2012-01-20T23:03:12.71520Z,1327100592.7152 [NAL9601](IMPORTANT): GPS fix at: 1327100580
2012-01-20T23:03:12.72890Z,1327100592.7289 [Default:GPS:Read_GPS] Stopped
2012-01-20T23:03:12.72930Z,1327100592.7293 [Default:GPS](INFO): Completed Default:GPS
2012-01-20T23:03:12.72940Z,1327100592.7294 [Default:GPS] Stopped
2012-01-20T23:03:12.72950Z,1327100592.7295 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-20T23:03:12.72960Z,1327100592.7296 [Default:GPS:A.SetSpeed] Stopped
2012-01-20T23:03:12.72970Z,1327100592.7297 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-20T23:03:12.72980Z,1327100592.7298 [Default:Iridium] Running Loop=1
2012-01-20T23:03:12.72990Z,1327100592.7299 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-01-20T23:03:12.73000Z,1327100592.73 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-20T23:03:12.73010Z,1327100592.7301 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-01-20T23:03:12.73030Z,1327100592.7303 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-01-20T23:03:12.73030Z,1327100592.7303 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:03:13.12080Z,1327100593.1208 [Default:Iridium:B.GoToSurface] Stopped
2012-01-20T23:03:13.12090Z,1327100593.1209 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:03:13.12100Z,1327100593.121 [Default:Iridium:Read_Iridium] Running Loop=1
2012-01-20T23:03:29.80890Z,1327100609.8089 [NAL9601](INFO): SBD MO Status=1, MOMSN=42470, MT Status=0, MTMSN=0
2012-01-20T23:03:29.98730Z,1327100609.9873 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:03:29.98760Z,1327100609.9876 [NAL9601](INFO): Packets left to send: 9
2012-01-20T23:03:29.98860Z,1327100609.9886 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000408
2012-01-20T23:03:42.29120Z,1327100622.2912 [NAL9601](INFO): SBD MO Status=1, MOMSN=42471, MT Status=0, MTMSN=0
2012-01-20T23:03:42.46730Z,1327100622.4673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:03:42.46750Z,1327100622.4675 [NAL9601](INFO): Packets left to send: 8
2012-01-20T23:03:42.46860Z,1327100622.4686 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000409
2012-01-20T23:03:55.17360Z,1327100635.1736 [NAL9601](INFO): SBD MO Status=1, MOMSN=42472, MT Status=0, MTMSN=0
2012-01-20T23:03:55.34730Z,1327100635.3473 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:03:55.34750Z,1327100635.3475 [NAL9601](INFO): Packets left to send: 7
2012-01-20T23:03:55.34860Z,1327100635.3486 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000410
2012-01-20T23:04:08.04980Z,1327100648.0498 [NAL9601](INFO): SBD MO Status=1, MOMSN=42473, MT Status=0, MTMSN=0
2012-01-20T23:04:08.22730Z,1327100648.2273 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:04:08.22750Z,1327100648.2275 [NAL9601](INFO): Packets left to send: 6
2012-01-20T23:04:08.22860Z,1327100648.2286 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000411
2012-01-20T23:04:16.14980Z,1327100656.1498 [NAL9601](INFO): SBD MO Status=1, MOMSN=42474, MT Status=0, MTMSN=0
2012-01-20T23:04:16.31530Z,1327100656.3153 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:04:16.31550Z,1327100656.3155 [NAL9601](INFO): Packets left to send: 5
2012-01-20T23:04:16.31650Z,1327100656.3165 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000412
2012-01-20T23:04:28.66990Z,1327100668.6699 [NAL9601](INFO): SBD MO Status=1, MOMSN=42475, MT Status=0, MTMSN=0
2012-01-20T23:04:28.79930Z,1327100668.7993 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:04:28.79950Z,1327100668.7995 [NAL9601](INFO): Packets left to send: 4
2012-01-20T23:04:28.80060Z,1327100668.8006 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000413
2012-01-20T23:04:38.78190Z,1327100678.7819 [NAL9601](INFO): SBD MO Status=1, MOMSN=42476, MT Status=0, MTMSN=0
2012-01-20T23:04:38.97940Z,1327100678.9794 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:04:38.97960Z,1327100678.9796 [NAL9601](INFO): Packets left to send: 3
2012-01-20T23:04:38.98080Z,1327100678.9808 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000414
2012-01-20T23:04:47.72990Z,1327100687.7299 [NAL9601](INFO): SBD MO Status=1, MOMSN=42477, MT Status=0, MTMSN=0
2012-01-20T23:04:47.86330Z,1327100687.8633 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:04:47.86350Z,1327100687.8635 [NAL9601](INFO): Packets left to send: 2
2012-01-20T23:04:47.86450Z,1327100687.8645 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000415
2012-01-20T23:04:58.99780Z,1327100698.9978 [NAL9601](INFO): SBD MO Status=1, MOMSN=42478, MT Status=0, MTMSN=0
2012-01-20T23:04:59.14730Z,1327100699.1473 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:04:59.14750Z,1327100699.1475 [NAL9601](INFO): Packets left to send: 1
2012-01-20T23:04:59.14860Z,1327100699.1486 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000416
2012-01-20T23:05:07.89370Z,1327100707.8937 [NAL9601](INFO): SBD MO Status=1, MOMSN=42479, MT Status=0, MTMSN=0
2012-01-20T23:05:08.03530Z,1327100708.0353 [NAL9601](INFO): Sent 235 bytes from file Logs/20120120T211834/shore0009.lzma
2012-01-20T23:05:08.03560Z,1327100708.0356 [NAL9601](INFO): Packets left to send: 0
2012-01-20T23:05:08.03660Z,1327100708.0366 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000417
2012-01-20T23:05:15.61370Z,1327100715.6137 [NAL9601](INFO): SBD MO Status=1, MOMSN=42480, MT Status=0, MTMSN=0
2012-01-20T23:05:15.82330Z,1327100715.8233 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0000.lzma
2012-01-20T23:05:15.82350Z,1327100715.8235 [NAL9601](INFO): Packets left to send: 2
2012-01-20T23:05:15.82460Z,1327100715.8246 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000418
2012-01-20T23:05:23.29790Z,1327100723.2979 [NAL9601](INFO): SBD MO Status=1, MOMSN=42481, MT Status=0, MTMSN=0
2012-01-20T23:05:23.50730Z,1327100723.5073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0000.lzma
2012-01-20T23:05:23.50750Z,1327100723.5075 [NAL9601](INFO): Packets left to send: 1
2012-01-20T23:05:23.50860Z,1327100723.5086 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000419
2012-01-20T23:05:30.57270Z,1327100730.5727 [NAL9601](INFO): SBD MO Status=1, MOMSN=42482, MT Status=0, MTMSN=0
2012-01-20T23:05:30.69950Z,1327100730.6995 [NAL9601](INFO): Sent 209 bytes from file Logs/20120120T230309/shore0000.lzma
2012-01-20T23:05:30.69970Z,1327100730.6997 [NAL9601](INFO): Packets left to send: 0
2012-01-20T23:05:30.70070Z,1327100730.7007 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000420
2012-01-20T23:05:37.41450Z,1327100737.4145 [NAL9601](INFO): SBD MO Status=0, MOMSN=42483, MT Status=0, MTMSN=0
2012-01-20T23:05:37.62610Z,1327100737.6261 [Default:Iridium:Read_Iridium] Stopped
2012-01-20T23:05:37.62660Z,1327100737.6266 [Default:Iridium](INFO): Completed Default:Iridium
2012-01-20T23:05:37.62670Z,1327100737.6267 [Default:Iridium] Stopped
2012-01-20T23:05:37.63500Z,1327100737.635 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-01-20T23:05:37.63510Z,1327100737.6351 [Default:Iridium:A.SetSpeed] Stopped
2012-01-20T23:05:37.63520Z,1327100737.6352 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-01-20T23:05:37.63530Z,1327100737.6353 [Default:G.Wait] Running Loop=1
2012-01-20T23:05:37.63540Z,1327100737.6354 [Default:G.Wait](DEBUG): Initialize Wait Component.
2012-01-20T23:05:48.12050Z,1327100748.1205 [NAL9601](INFO): Powering down
2012-01-20T23:10:37.95420Z,1327101037.9542 [Default:CallIridium] Running Loop=1
2012-01-20T23:10:37.95430Z,1327101037.9543 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-01-20T23:10:37.95450Z,1327101037.9545 [Default:CallIridium:A] Running Loop=1
2012-01-20T23:10:37.95460Z,1327101037.9546 [Default:CallIridium:A](INFO): Aggregate::initialize Default:CallIridium:A
2012-01-20T23:10:37.95480Z,1327101037.9548 [Default:CallGPS] Running Loop=1
2012-01-20T23:10:37.95490Z,1327101037.9549 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-20T23:10:37.95510Z,1327101037.9551 [Default:CallGPS:A] Running Loop=1
2012-01-20T23:10:37.95520Z,1327101037.9552 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-20T23:10:42.96180Z,1327101042.9618 [Default:Iridium] Running Loop=1
2012-01-20T23:10:42.96190Z,1327101042.9619 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-01-20T23:10:42.96200Z,1327101042.962 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-20T23:10:42.96210Z,1327101042.9621 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-01-20T23:10:42.96230Z,1327101042.9623 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-01-20T23:10:42.96240Z,1327101042.9624 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:10:42.96310Z,1327101042.9631 [Default:Iridium:B.GoToSurface] Stopped
2012-01-20T23:10:42.96320Z,1327101042.9632 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:10:42.96330Z,1327101042.9633 [Default:Iridium:Read_Iridium] Running Loop=1
2012-01-20T23:10:42.96370Z,1327101042.9637 [Default:GPS] Running Loop=1
2012-01-20T23:10:42.96380Z,1327101042.9638 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-20T23:10:42.96390Z,1327101042.9639 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-20T23:10:42.96390Z,1327101042.9639 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-20T23:10:42.96410Z,1327101042.9641 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-20T23:10:42.96420Z,1327101042.9642 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:10:42.96480Z,1327101042.9648 [Default:GPS:B.GoToSurface] Stopped
2012-01-20T23:10:42.96490Z,1327101042.9649 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:10:42.96500Z,1327101042.965 [Default:GPS:Read_GPS] Running Loop=1
2012-01-20T23:10:43.57160Z,1327101043.5716 [NAL9601](INFO): Powering up
2012-01-20T23:11:49.28580Z,1327101109.2858 [NAL9601](INFO): NAL9601 initialized
2012-01-20T23:12:06.69780Z,1327101126.6978 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42484, MT Status=1, MTMSN=3491
2012-01-20T23:12:06.84730Z,1327101126.8473 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0001.lzma
2012-01-20T23:12:06.84750Z,1327101126.8475 [NAL9601](INFO): Packets left to send: 3
2012-01-20T23:12:06.84860Z,1327101126.8486 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000421
2012-01-20T23:12:07.38840Z,1327101127.3884 [NAL9601](INFO): Received command:load Transport/transit_1km.xml;set transit_1km.Wpt1Lon -121.807 degree;run 
2012-01-20T23:12:07.40130Z,1327101127.4013 [CommandLine](IMPORTANT): got command load ./Missions/Transport/transit_1km.xml
2012-01-20T23:12:07.40160Z,1327101127.4016 [MissionManager](INFO): Loading Mission: ./Missions/Transport/transit_1km.xml
2012-01-20T23:12:07.42970Z,1327101127.4297 [MissionManager](INFO): DefineArg transit_1km.ApproachDepth = 10 m
2012-01-20T23:12:07.43270Z,1327101127.4327 [MissionManager](INFO): DefineArg transit_1km.Wpt1Lat = 36.8156 arcdeg
2012-01-20T23:12:07.43580Z,1327101127.4358 [MissionManager](INFO): DefineArg transit_1km.Wpt1Lon = -121.806 arcdeg
2012-01-20T23:12:07.43870Z,1327101127.4387 [MissionManager](INFO): DefineArg transit_1km.MaxDepth = 20 m
2012-01-20T23:12:07.44040Z,1327101127.4404 [transit_1km:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-01-20T23:12:07.44740Z,1327101127.4474 [transit_1km:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-01-20T23:12:07.45480Z,1327101127.4548 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-01-20T23:12:07.47290Z,1327101127.4729 [MissionManager](INFO): DefineArg transit_1km:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-01-20T23:12:07.47580Z,1327101127.4758 [MissionManager](INFO): DefineArg transit_1km:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-01-20T23:12:07.47870Z,1327101127.4787 [MissionManager](INFO): DefineArg transit_1km:SURFACECOMMS.SurfaceSpeed = 0.5 m/s
2012-01-20T23:12:07.48170Z,1327101127.4817 [MissionManager](INFO): DefineArg transit_1km:SURFACECOMMS.IridiumTimeout = 30 min
2012-01-20T23:12:07.48290Z,1327101127.4829 [transit_1km:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-01-20T23:12:07.49540Z,1327101127.4954 [transit_1km:WaypointOne:A.Pitch](DEBUG): Construct.
2012-01-20T23:12:07.50190Z,1327101127.5019 [transit_1km:WaypointOne:B.SetSpeed](DEBUG): Construct.
2012-01-20T23:12:07.50500Z,1327101127.505 [transit_1km:WaypointOne:WaypointW1.Waypoint](DEBUG): Construct Waypoint.
2012-01-20T23:12:07.51470Z,1327101127.5147 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="transit_1km">

<!-- To be used within 6km of waypoint. No science collection.  -->

    <DefineArg Name="ApproachDepth"><Units:meter/><Value>10.0</Value></DefineArg>

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

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

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

    <Timeout Duration="P130M"/>

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

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

    <Insert Filename="Insert/Surface.xml" Id="SURFACECOMMS" />

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

    <Aggregate Id="NeedComms">

        <When>
            <Elapsed><Universal:time_fix/></Elapsed>
            <Gt><Units:minute/><Value>35</Value></Gt>
        </When>

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

    </Aggregate>

    <Aggregate Id="WaypointOne">

<!-- Go to Wpt1 -->

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

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

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

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

    </Aggregate>

</Mission>


2012-01-20T23:12:07.51520Z,1327101127.5152 [CommandLine](IMPORTANT): Loaded ./Missions/Transport/transit_1km.xml
2012-01-20T23:12:07.60100Z,1327101127.601 [CommandLine](IMPORTANT): got command set transit_1km.Wpt1Lon -121.8069992 degree
2012-01-20T23:12:07.60230Z,1327101127.6023 [CommandLine](IMPORTANT): got command run 
2012-01-20T23:12:07.60250Z,1327101127.6025 [CommandLine](IMPORTANT): Running 
2012-01-20T23:12:07.61510Z,1327101127.6151 [Default] Stopped
2012-01-20T23:12:07.61530Z,1327101127.6153 [Default](INFO): Aggregate::uninitialize Default
2012-01-20T23:12:07.61540Z,1327101127.6154 [Default:GPS] Stopped
2012-01-20T23:12:07.61550Z,1327101127.6155 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-20T23:12:07.61560Z,1327101127.6156 [Default:GPS:A.SetSpeed] Stopped
2012-01-20T23:12:07.61560Z,1327101127.6156 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-20T23:12:07.61570Z,1327101127.6157 [Default:GPS:Read_GPS] Stopped
2012-01-20T23:12:07.61580Z,1327101127.6158 [Default:Iridium] Stopped
2012-01-20T23:12:07.61590Z,1327101127.6159 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-01-20T23:12:07.61600Z,1327101127.616 [Default:Iridium:A.SetSpeed] Stopped
2012-01-20T23:12:07.61600Z,1327101127.616 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-01-20T23:12:07.61610Z,1327101127.6161 [Default:Iridium:Read_Iridium] Stopped
2012-01-20T23:12:07.61620Z,1327101127.6162 [Default:CallGPS] Stopped
2012-01-20T23:12:07.61630Z,1327101127.6163 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-20T23:12:07.61630Z,1327101127.6163 [Default:CallGPS:A] Stopped
2012-01-20T23:12:07.61650Z,1327101127.6165 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-20T23:12:07.61650Z,1327101127.6165 [Default:CallIridium] Stopped
2012-01-20T23:12:07.61670Z,1327101127.6167 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-01-20T23:12:07.61670Z,1327101127.6167 [Default:CallIridium:A] Stopped
2012-01-20T23:12:07.61690Z,1327101127.6169 [Default:CallIridium:A](INFO): Aggregate::uninitialize Default:CallIridium:A
2012-01-20T23:12:07.61690Z,1327101127.6169 [Default:E.SetSpeed] Stopped
2012-01-20T23:12:07.61700Z,1327101127.617 [Default:E.SetSpeed](DEBUG): Uninitialize.
2012-01-20T23:12:07.61710Z,1327101127.6171 [Default:F.GoToSurface] Stopped
2012-01-20T23:12:07.61710Z,1327101127.6171 [Default:F.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:12:07.61720Z,1327101127.6172 [Default:G.Wait] Stopped
2012-01-20T23:12:07.61730Z,1327101127.6173 [Default:G.Wait](DEBUG): Uninitialize Wait Component.
2012-01-20T23:12:07.61740Z,1327101127.6174 [MissionManager](IMPORTANT): Started mission transit_1km
2012-01-20T23:12:07.61750Z,1327101127.6175 [transit_1km] Running Loop=1
2012-01-20T23:12:07.61760Z,1327101127.6176 [transit_1km](INFO): Aggregate::initialize transit_1km
2012-01-20T23:12:07.61770Z,1327101127.6177 [transit_1km:A.AltitudeEnvelope] Running Loop=1
2012-01-20T23:12:07.61780Z,1327101127.6178 [transit_1km:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-01-20T23:12:07.61790Z,1327101127.6179 [transit_1km:B.DepthEnvelope] Running Loop=1
2012-01-20T23:12:07.61800Z,1327101127.618 [transit_1km:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-01-20T23:12:07.62140Z,1327101127.6214 [transit_1km:SURFACECOMMS] Running Loop=1
2012-01-20T23:12:07.62160Z,1327101127.6216 [transit_1km:SURFACECOMMS](INFO): Aggregate::initialize transit_1km:SURFACECOMMS
2012-01-20T23:12:07.62170Z,1327101127.6217 [transit_1km:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-01-20T23:12:07.62170Z,1327101127.6217 [transit_1km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:12:07.62710Z,1327101127.6271 [transit_1km:SURFACECOMMS:B] Running Loop=1
2012-01-20T23:12:07.62720Z,1327101127.6272 [transit_1km:SURFACECOMMS:B](INFO): Aggregate::initialize transit_1km:SURFACECOMMS:B
2012-01-20T23:12:07.62740Z,1327101127.6274 [transit_1km:SURFACECOMMS:B:A] Running Loop=1
2012-01-20T23:12:07.62750Z,1327101127.6275 [transit_1km:B.DepthEnvelope] Running Loop=1
2012-01-20T23:12:07.63240Z,1327101127.6324 [transit_1km:A.AltitudeEnvelope] Running Loop=1
2012-01-20T23:12:08.25320Z,1327101128.2532 [transit_1km:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense time_fix
2012-01-20T23:12:24.25780Z,1327101144.2578 [NAL9601](IMPORTANT): GPS fix at: 1327101133
2012-01-20T23:12:24.29430Z,1327101144.2943 [transit_1km:SURFACECOMMS:B:A] Stopped
2012-01-20T23:12:24.29460Z,1327101144.2946 [transit_1km:SURFACECOMMS:B:B] Running Loop=1
2012-01-20T23:12:24.66750Z,1327101144.6675 [transit_1km:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-01-20T23:12:40.12970Z,1327101160.1297 [NAL9601](INFO): SBD MO Status=1, MOMSN=42485, MT Status=0, MTMSN=0
2012-01-20T23:12:40.30730Z,1327101160.3073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0001.lzma
2012-01-20T23:12:40.30760Z,1327101160.3076 [NAL9601](INFO): Packets left to send: 2
2012-01-20T23:12:40.30860Z,1327101160.3086 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000422
2012-01-20T23:12:47.88160Z,1327101167.8816 [NAL9601](INFO): SBD MO Status=1, MOMSN=42486, MT Status=0, MTMSN=0
2012-01-20T23:12:48.07930Z,1327101168.0793 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0001.lzma
2012-01-20T23:12:48.07950Z,1327101168.0795 [NAL9601](INFO): Packets left to send: 1
2012-01-20T23:12:48.08060Z,1327101168.0806 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000423
2012-01-20T23:13:13.51270Z,1327101193.5127 [NAL9601](INFO): SBD MO Status=2, MOMSN=42487, MT Status=2, MTMSN=0
2012-01-20T23:13:13.51300Z,1327101193.513 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-01-20T23:13:36.31670Z,1327101216.3167 [NAL9601](INFO): SBD MO Status=2, MOMSN=42487, MT Status=2, MTMSN=0
2012-01-20T23:13:36.31690Z,1327101216.3169 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-01-20T23:15:14.89800Z,1327101314.898 [NAL9601](INFO): SBD MO Status=2, MOMSN=42487, MT Status=2, MTMSN=0
2012-01-20T23:15:14.89830Z,1327101314.8983 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-01-20T23:15:50.87670Z,1327101350.8767 [NAL9601](INFO): SBD MO Status=2, MOMSN=42487, MT Status=2, MTMSN=0
2012-01-20T23:15:50.87690Z,1327101350.8769 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-01-20T23:16:19.80580Z,1327101379.8058 [NAL9601](INFO): SBD MO Status=1, MOMSN=42487, MT Status=0, MTMSN=0
2012-01-20T23:16:19.95370Z,1327101379.9537 [NAL9601](INFO): Sent 326 bytes from file Logs/20120120T230309/shore0001.lzma
2012-01-20T23:16:19.95390Z,1327101379.9539 [NAL9601](INFO): Packets left to send: 0
2012-01-20T23:16:19.95500Z,1327101379.955 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000424
2012-01-20T23:16:28.63540Z,1327101388.6354 [NAL9601](INFO): SBD MO Status=0, MOMSN=42488, MT Status=0, MTMSN=0
2012-01-20T23:16:40.50860Z,1327101400.5086 [NAL9601](INFO): SBD MO Status=1, MOMSN=42489, MT Status=0, MTMSN=0
2012-01-20T23:16:40.71930Z,1327101400.7193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0002.lzma
2012-01-20T23:16:40.71960Z,1327101400.7196 [NAL9601](INFO): Packets left to send: 5
2012-01-20T23:16:40.72060Z,1327101400.7206 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000425
2012-01-20T23:16:48.24580Z,1327101408.2458 [NAL9601](INFO): SBD MO Status=1, MOMSN=42490, MT Status=0, MTMSN=0
2012-01-20T23:16:48.40740Z,1327101408.4074 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0002.lzma
2012-01-20T23:16:48.40760Z,1327101408.4076 [NAL9601](INFO): Packets left to send: 4
2012-01-20T23:16:48.40870Z,1327101408.4087 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000426
2012-01-20T23:16:55.94940Z,1327101415.9494 [NAL9601](INFO): SBD MO Status=1, MOMSN=42491, MT Status=0, MTMSN=0
2012-01-20T23:16:56.09540Z,1327101416.0954 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0002.lzma
2012-01-20T23:16:56.09560Z,1327101416.0956 [NAL9601](INFO): Packets left to send: 3
2012-01-20T23:16:56.09670Z,1327101416.0967 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000427
2012-01-20T23:17:06.10730Z,1327101426.1073 [NAL9601](INFO): SBD MO Status=1, MOMSN=42492, MT Status=0, MTMSN=0
2012-01-20T23:17:06.28150Z,1327101426.2815 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0002.lzma
2012-01-20T23:17:06.28180Z,1327101426.2818 [NAL9601](INFO): Packets left to send: 2
2012-01-20T23:17:06.28520Z,1327101426.2852 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000428
2012-01-20T23:17:13.38330Z,1327101433.3833 [NAL9601](INFO): SBD MO Status=1, MOMSN=42493, MT Status=0, MTMSN=0
2012-01-20T23:17:13.56730Z,1327101433.5673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0002.lzma
2012-01-20T23:17:13.56750Z,1327101433.5675 [NAL9601](INFO): Packets left to send: 1
2012-01-20T23:17:13.56940Z,1327101433.5694 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000429
2012-01-20T23:17:20.76170Z,1327101440.7617 [NAL9601](INFO): SBD MO Status=1, MOMSN=42494, MT Status=0, MTMSN=0
2012-01-20T23:17:20.95540Z,1327101440.9554 [NAL9601](INFO): Sent 106 bytes from file Logs/20120120T230309/shore0002.lzma
2012-01-20T23:17:20.95560Z,1327101440.9556 [NAL9601](INFO): Packets left to send: 0
2012-01-20T23:17:20.95660Z,1327101440.9566 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000430
2012-01-20T23:17:27.99940Z,1327101447.9994 [NAL9601](INFO): SBD MO Status=0, MOMSN=42495, MT Status=0, MTMSN=0
2012-01-20T23:17:28.16030Z,1327101448.1603 [transit_1km:SURFACECOMMS:B:B] Stopped
2012-01-20T23:17:28.16050Z,1327101448.1605 [transit_1km:SURFACECOMMS:B:C] Running Loop=1
2012-01-20T23:17:28.41130Z,1327101448.4113 [transit_1km:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2012-01-20T23:17:30.35810Z,1327101450.3581 [NAL9601](IMPORTANT): GPS fix at: 1327101439
2012-01-20T23:17:30.37360Z,1327101450.3736 [transit_1km:SURFACECOMMS:B:C] Stopped
2012-01-20T23:17:30.37390Z,1327101450.3739 [transit_1km:SURFACECOMMS:B](INFO): Completed transit_1km:SURFACECOMMS:B
2012-01-20T23:17:30.37390Z,1327101450.3739 [transit_1km:SURFACECOMMS:B] Stopped
2012-01-20T23:17:30.37410Z,1327101450.3741 [transit_1km:SURFACECOMMS:B](INFO): Aggregate::uninitialize transit_1km:SURFACECOMMS:B
2012-01-20T23:17:30.37450Z,1327101450.3745 [transit_1km:SURFACECOMMS](INFO): Completed transit_1km:SURFACECOMMS
2012-01-20T23:17:30.37460Z,1327101450.3746 [transit_1km:SURFACECOMMS] Stopped
2012-01-20T23:17:30.37470Z,1327101450.3747 [transit_1km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_1km:SURFACECOMMS
2012-01-20T23:17:30.37490Z,1327101450.3749 [transit_1km:SURFACECOMMS:A.GoToSurface] Stopped
2012-01-20T23:17:30.37500Z,1327101450.375 [transit_1km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:17:30.37520Z,1327101450.3752 [transit_1km:WaypointOne] Running Loop=1
2012-01-20T23:17:30.37530Z,1327101450.3753 [transit_1km:WaypointOne](INFO): Aggregate::initialize transit_1km:WaypointOne
2012-01-20T23:17:30.37540Z,1327101450.3754 [transit_1km:WaypointOne:A.Pitch] Running Loop=1
2012-01-20T23:17:30.37540Z,1327101450.3754 [transit_1km:WaypointOne:A.Pitch](DEBUG): Initialize.
2012-01-20T23:17:30.37570Z,1327101450.3757 [transit_1km:WaypointOne:B.SetSpeed] Running Loop=1
2012-01-20T23:17:30.37570Z,1327101450.3757 [transit_1km:WaypointOne:B.SetSpeed](DEBUG): Initialize.
2012-01-20T23:17:30.37600Z,1327101450.376 [transit_1km:WaypointOne:WaypointW1.Waypoint] Running Loop=1
2012-01-20T23:17:30.37610Z,1327101450.3761 [transit_1km:WaypointOne:WaypointW1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-01-20T23:17:30.80730Z,1327101450.8073 [transit_1km:WaypointOne:B.SetSpeed] Running Loop=1
2012-01-20T23:17:30.81170Z,1327101450.8117 [transit_1km:WaypointOne:A.Pitch] Running Loop=1
2012-01-20T23:17:36.99260Z,1327101456.9926 [NAL9601](INFO): Powering down
2012-01-20T23:19:13.29210Z,1327101553.2921 [Radio_Freewave](INFO): Powering down
2012-01-20T23:22:00.63930Z,1327101720.6393 [Batt_Ocean_Server](INFO): Over Temperature Alarm! Battery Bank #4 STATUS: 5911
2012-01-20T23:22:00.63960Z,1327101720.6396 [Batt_Ocean_Server](INFO): Not Initialized - Battery Bank #4 STATUS: 5911
2012-01-20T23:52:30.61070Z,1327103550.6107 [transit_1km:NeedComms] Running Loop=1
2012-01-20T23:52:30.61750Z,1327103550.6175 [transit_1km:NeedComms](INFO): Aggregate::initialize transit_1km:NeedComms
2012-01-20T23:52:30.61770Z,1327103550.6177 [transit_1km:NeedComms:NEEDCOMMS] Running Loop=1
2012-01-20T23:52:30.61780Z,1327103550.6178 [transit_1km:NeedComms:NEEDCOMMS](INFO): Aggregate::initialize transit_1km:NeedComms:NEEDCOMMS
2012-01-20T23:52:31.51810Z,1327103551.5181 [transit_1km:SURFACECOMMS] Running Loop=1
2012-01-20T23:52:31.51820Z,1327103551.5182 [transit_1km:SURFACECOMMS](INFO): Aggregate::initialize transit_1km:SURFACECOMMS
2012-01-20T23:52:31.51830Z,1327103551.5183 [transit_1km:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-01-20T23:52:31.51840Z,1327103551.5184 [transit_1km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:55:51.90500Z,1327103751.905 [Radio_Freewave](INFO): Powering up
2012-01-20T23:55:51.91510Z,1327103751.9151 [transit_1km:SURFACECOMMS:B] Running Loop=1
2012-01-20T23:55:51.91520Z,1327103751.9152 [transit_1km:SURFACECOMMS:B](INFO): Aggregate::initialize transit_1km:SURFACECOMMS:B
2012-01-20T23:55:51.91540Z,1327103751.9154 [transit_1km:SURFACECOMMS:B:A] Running Loop=1
2012-01-20T23:55:52.70050Z,1327103752.7005 [NAL9601](INFO): Powering up
2012-01-20T23:56:58.41140Z,1327103818.4114 [NAL9601](INFO): NAL9601 initialized
2012-01-20T23:56:59.52000Z,1327103819.52 [NAL9601](IMPORTANT): GPS fix at: 1327103812
2012-01-20T23:56:59.53350Z,1327103819.5335 [transit_1km:SURFACECOMMS:B:A] Stopped
2012-01-20T23:56:59.53380Z,1327103819.5338 [transit_1km:SURFACECOMMS:B:B] Running Loop=1
2012-01-20T23:57:16.26470Z,1327103836.2647 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42496, MT Status=1, MTMSN=3492
2012-01-20T23:57:16.47130Z,1327103836.4713 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:57:16.47150Z,1327103836.4715 [NAL9601](INFO): Packets left to send: 12
2012-01-20T23:57:16.47270Z,1327103836.4727 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000431
2012-01-20T23:57:16.88420Z,1327103836.8842 [NAL9601](INFO): Received command:stop
2012-01-20T23:57:16.92720Z,1327103836.9272 [CommandLine](IMPORTANT): got command stop
2012-01-20T23:57:17.07360Z,1327103837.0736 [transit_1km] Stopped
2012-01-20T23:57:17.07380Z,1327103837.0738 [transit_1km](INFO): Aggregate::uninitialize transit_1km
2012-01-20T23:57:17.07390Z,1327103837.0739 [transit_1km:A.AltitudeEnvelope] Stopped
2012-01-20T23:57:17.07400Z,1327103837.074 [transit_1km:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-01-20T23:57:17.07400Z,1327103837.074 [transit_1km:B.DepthEnvelope] Stopped
2012-01-20T23:57:17.07410Z,1327103837.0741 [transit_1km:B.DepthEnvelope](DEBUG): Uninitialize.
2012-01-20T23:57:17.07420Z,1327103837.0742 [transit_1km:SURFACECOMMS] Stopped
2012-01-20T23:57:17.07430Z,1327103837.0743 [transit_1km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_1km:SURFACECOMMS
2012-01-20T23:57:17.07440Z,1327103837.0744 [transit_1km:SURFACECOMMS:A.GoToSurface] Stopped
2012-01-20T23:57:17.07440Z,1327103837.0744 [transit_1km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:57:17.07450Z,1327103837.0745 [transit_1km:SURFACECOMMS:B] Stopped
2012-01-20T23:57:17.07460Z,1327103837.0746 [transit_1km:SURFACECOMMS:B](INFO): Aggregate::uninitialize transit_1km:SURFACECOMMS:B
2012-01-20T23:57:17.07470Z,1327103837.0747 [transit_1km:SURFACECOMMS:B:B] Stopped
2012-01-20T23:57:17.07480Z,1327103837.0748 [transit_1km:NeedComms] Stopped
2012-01-20T23:57:17.07490Z,1327103837.0749 [transit_1km:NeedComms](INFO): Aggregate::uninitialize transit_1km:NeedComms
2012-01-20T23:57:17.07500Z,1327103837.075 [transit_1km:NeedComms:NEEDCOMMS] Stopped
2012-01-20T23:57:17.07510Z,1327103837.0751 [transit_1km:NeedComms:NEEDCOMMS](INFO): Aggregate::uninitialize transit_1km:NeedComms:NEEDCOMMS
2012-01-20T23:57:17.07520Z,1327103837.0752 [transit_1km:WaypointOne] Stopped
2012-01-20T23:57:17.07530Z,1327103837.0753 [transit_1km:WaypointOne](INFO): Aggregate::uninitialize transit_1km:WaypointOne
2012-01-20T23:57:17.07540Z,1327103837.0754 [transit_1km:WaypointOne:A.Pitch] Stopped
2012-01-20T23:57:17.07540Z,1327103837.0754 [transit_1km:WaypointOne:B.SetSpeed] Stopped
2012-01-20T23:57:17.07550Z,1327103837.0755 [transit_1km:WaypointOne:B.SetSpeed](DEBUG): Uninitialize.
2012-01-20T23:57:17.07560Z,1327103837.0756 [transit_1km:WaypointOne:WaypointW1.Waypoint] Stopped
2012-01-20T23:57:17.07560Z,1327103837.0756 [transit_1km:WaypointOne:WaypointW1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-01-20T23:57:17.48970Z,1327103837.4897 [MissionManager](IMPORTANT): Started mission Default
2012-01-20T23:57:17.48980Z,1327103837.4898 [Default] Running Loop=1
2012-01-20T23:57:17.48990Z,1327103837.4899 [Default](INFO): Aggregate::initialize Default
2012-01-20T23:57:17.49000Z,1327103837.49 [Default:E.SetSpeed] Running Loop=1
2012-01-20T23:57:17.49010Z,1327103837.4901 [Default:E.SetSpeed](DEBUG): Initialize.
2012-01-20T23:57:17.49020Z,1327103837.4902 [Default:F.GoToSurface] Running Loop=1
2012-01-20T23:57:17.49030Z,1327103837.4903 [Default:F.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:57:17.49070Z,1327103837.4907 [Default:GPS] Running Loop=1
2012-01-20T23:57:17.49090Z,1327103837.4909 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-20T23:57:17.49100Z,1327103837.491 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-20T23:57:17.49100Z,1327103837.491 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-20T23:57:17.49120Z,1327103837.4912 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-20T23:57:17.49130Z,1327103837.4913 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:57:17.49270Z,1327103837.4927 [Default:CallIridium] Running Loop=1
2012-01-20T23:57:17.49280Z,1327103837.4928 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-01-20T23:57:17.49300Z,1327103837.493 [Default:CallIridium:A] Running Loop=1
2012-01-20T23:57:17.49310Z,1327103837.4931 [Default:CallIridium:A](INFO): Aggregate::initialize Default:CallIridium:A
2012-01-20T23:57:17.49320Z,1327103837.4932 [Default:CallGPS] Running Loop=1
2012-01-20T23:57:17.49340Z,1327103837.4934 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-20T23:57:17.49350Z,1327103837.4935 [Default:CallGPS:A] Running Loop=1
2012-01-20T23:57:17.49360Z,1327103837.4936 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-20T23:57:17.49390Z,1327103837.4939 [Default:GPS:B.GoToSurface] Stopped
2012-01-20T23:57:17.49400Z,1327103837.494 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:57:17.49420Z,1327103837.4942 [Default:GPS:Read_GPS] Running Loop=1
2012-01-20T23:57:17.87250Z,1327103837.8725 [Default:Iridium] Running Loop=1
2012-01-20T23:57:17.87270Z,1327103837.8727 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-01-20T23:57:17.87280Z,1327103837.8728 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-20T23:57:17.87290Z,1327103837.8729 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-01-20T23:57:17.87310Z,1327103837.8731 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-01-20T23:57:17.87310Z,1327103837.8731 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:57:17.87380Z,1327103837.8738 [Default:Iridium:B.GoToSurface] Stopped
2012-01-20T23:57:17.87390Z,1327103837.8739 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:57:17.87400Z,1327103837.874 [Default:Iridium:Read_Iridium] Running Loop=1
2012-01-20T23:57:19.84730Z,1327103839.8473 [NAL9601](IMPORTANT): GPS fix at: 1327103832
2012-01-20T23:57:19.88080Z,1327103839.8808 [Default:GPS:Read_GPS] Stopped
2012-01-20T23:57:19.88110Z,1327103839.8811 [Default:GPS](INFO): Completed Default:GPS
2012-01-20T23:57:19.88120Z,1327103839.8812 [Default:GPS] Stopped
2012-01-20T23:57:19.88130Z,1327103839.8813 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-20T23:57:19.88140Z,1327103839.8814 [Default:GPS:A.SetSpeed] Stopped
2012-01-20T23:57:19.88150Z,1327103839.8815 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-20T23:57:20.27280Z,1327103840.2728 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-20T23:57:20.27290Z,1327103840.2729 [Default:CallGPS:A] Stopped
2012-01-20T23:57:20.27310Z,1327103840.2731 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-20T23:57:20.27330Z,1327103840.2733 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-20T23:57:20.27330Z,1327103840.2733 [Default:CallGPS] Stopped
2012-01-20T23:57:20.27350Z,1327103840.2735 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-20T23:57:20.68050Z,1327103840.6805 [Default:CallGPS] Running Loop=1
2012-01-20T23:57:20.68070Z,1327103840.6807 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-20T23:57:20.68090Z,1327103840.6809 [Default:CallGPS:A] Running Loop=1
2012-01-20T23:57:20.68100Z,1327103840.681 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-20T23:57:21.15250Z,1327103841.1525 [Default:GPS] Running Loop=1
2012-01-20T23:57:21.15270Z,1327103841.1527 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-20T23:57:21.15280Z,1327103841.1528 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-20T23:57:21.15280Z,1327103841.1528 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-20T23:57:21.15300Z,1327103841.153 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-20T23:57:21.15310Z,1327103841.1531 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:57:21.15380Z,1327103841.1538 [Default:GPS:B.GoToSurface] Stopped
2012-01-20T23:57:21.15380Z,1327103841.1538 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:57:21.15400Z,1327103841.154 [Default:GPS:Read_GPS] Running Loop=1
2012-01-20T23:57:31.32180Z,1327103851.3218 [NAL9601](INFO): SBD MO Status=1, MOMSN=42497, MT Status=0, MTMSN=0
2012-01-20T23:57:31.45140Z,1327103851.4514 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:57:31.45170Z,1327103851.4517 [NAL9601](INFO): Packets left to send: 11
2012-01-20T23:57:31.45280Z,1327103851.4528 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000432
2012-01-20T23:57:43.40180Z,1327103863.4018 [NAL9601](INFO): SBD MO Status=1, MOMSN=42498, MT Status=0, MTMSN=0
2012-01-20T23:57:43.53530Z,1327103863.5353 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:57:43.53550Z,1327103863.5355 [NAL9601](INFO): Packets left to send: 10
2012-01-20T23:57:43.53740Z,1327103863.5374 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000433
2012-01-20T23:57:55.87780Z,1327103875.8778 [NAL9601](INFO): SBD MO Status=1, MOMSN=42499, MT Status=0, MTMSN=0
2012-01-20T23:57:56.01130Z,1327103876.0113 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:57:56.01160Z,1327103876.0116 [NAL9601](INFO): Packets left to send: 9
2012-01-20T23:57:56.01270Z,1327103876.0127 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000434
2012-01-20T23:58:08.79460Z,1327103888.7946 [NAL9601](INFO): SBD MO Status=1, MOMSN=42500, MT Status=0, MTMSN=0
2012-01-20T23:58:08.99140Z,1327103888.9914 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:58:08.99160Z,1327103888.9916 [NAL9601](INFO): Packets left to send: 8
2012-01-20T23:58:08.99270Z,1327103888.9927 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000435
2012-01-20T23:58:16.46160Z,1327103896.4616 [NAL9601](INFO): SBD MO Status=1, MOMSN=42501, MT Status=0, MTMSN=0
2012-01-20T23:58:16.67930Z,1327103896.6793 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:58:16.67950Z,1327103896.6795 [NAL9601](INFO): Packets left to send: 7
2012-01-20T23:58:16.68060Z,1327103896.6806 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000436
2012-01-20T23:58:28.57390Z,1327103908.5739 [NAL9601](INFO): SBD MO Status=1, MOMSN=42502, MT Status=0, MTMSN=0
2012-01-20T23:58:28.75940Z,1327103908.7594 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:58:28.75960Z,1327103908.7596 [NAL9601](INFO): Packets left to send: 6
2012-01-20T23:58:28.76080Z,1327103908.7608 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000437
2012-01-20T23:58:37.94540Z,1327103917.9454 [NAL9601](INFO): SBD MO Status=1, MOMSN=42503, MT Status=0, MTMSN=0
2012-01-20T23:58:38.14730Z,1327103918.1473 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:58:38.14750Z,1327103918.1475 [NAL9601](INFO): Packets left to send: 5
2012-01-20T23:58:38.14930Z,1327103918.1493 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000438
2012-01-20T23:58:45.59380Z,1327103925.5938 [NAL9601](INFO): SBD MO Status=1, MOMSN=42504, MT Status=0, MTMSN=0
2012-01-20T23:58:45.73540Z,1327103925.7354 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:58:45.73560Z,1327103925.7356 [NAL9601](INFO): Packets left to send: 4
2012-01-20T23:58:45.73680Z,1327103925.7368 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000439
2012-01-20T23:58:55.29270Z,1327103935.2927 [NAL9601](INFO): SBD MO Status=1, MOMSN=42505, MT Status=0, MTMSN=0
2012-01-20T23:58:55.41930Z,1327103935.4193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:58:55.41950Z,1327103935.4195 [NAL9601](INFO): Packets left to send: 3
2012-01-20T23:58:55.42070Z,1327103935.4207 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000440
2012-01-20T23:59:02.99380Z,1327103942.9938 [NAL9601](INFO): SBD MO Status=1, MOMSN=42506, MT Status=0, MTMSN=0
2012-01-20T23:59:03.20730Z,1327103943.2073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:59:03.20750Z,1327103943.2075 [NAL9601](INFO): Packets left to send: 2
2012-01-20T23:59:03.20860Z,1327103943.2086 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000441
2012-01-20T23:59:11.47380Z,1327103951.4738 [NAL9601](INFO): SBD MO Status=1, MOMSN=42507, MT Status=0, MTMSN=0
2012-01-20T23:59:11.59530Z,1327103951.5953 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:59:11.59550Z,1327103951.5955 [NAL9601](INFO): Packets left to send: 1
2012-01-20T23:59:11.59730Z,1327103951.5973 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000442
2012-01-20T23:59:19.95520Z,1327103959.9552 [NAL9601](INFO): SBD MO Status=1, MOMSN=42508, MT Status=0, MTMSN=0
2012-01-20T23:59:20.07930Z,1327103960.0793 [NAL9601](INFO): Sent 16 bytes from file Logs/20120120T230309/shore0003.lzma
2012-01-20T23:59:20.07950Z,1327103960.0795 [NAL9601](INFO): Packets left to send: 0
2012-01-20T23:59:20.08070Z,1327103960.0807 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000443
2012-01-20T23:59:27.65030Z,1327103967.6503 [NAL9601](INFO): SBD MO Status=1, MOMSN=42509, MT Status=0, MTMSN=0
2012-01-20T23:59:27.76730Z,1327103967.7673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0004.lzma
2012-01-20T23:59:27.76760Z,1327103967.7676 [NAL9601](INFO): Packets left to send: 1
2012-01-20T23:59:28.45550Z,1327103968.4555 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000444
2012-01-20T23:59:36.67860Z,1327103976.6786 [NAL9601](INFO): SBD MO Status=1, MOMSN=42510, MT Status=0, MTMSN=0
2012-01-20T23:59:36.85530Z,1327103976.8553 [NAL9601](INFO): Sent 77 bytes from file Logs/20120120T230309/shore0004.lzma
2012-01-20T23:59:36.85550Z,1327103976.8555 [NAL9601](INFO): Packets left to send: 0
2012-01-20T23:59:36.85660Z,1327103976.8566 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000445
2012-01-20T23:59:45.47450Z,1327103985.4745 [NAL9601](INFO): SBD MO Status=0, MOMSN=42511, MT Status=0, MTMSN=0
2012-01-20T23:59:46.87130Z,1327103986.8713 [NAL9601](IMPORTANT): GPS fix at: 1327103980
2012-01-20T23:59:46.88470Z,1327103986.8847 [Default:GPS:Read_GPS] Stopped
2012-01-20T23:59:46.88500Z,1327103986.885 [Default:GPS](INFO): Completed Default:GPS
2012-01-20T23:59:46.88510Z,1327103986.8851 [Default:GPS] Stopped
2012-01-20T23:59:46.88520Z,1327103986.8852 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-20T23:59:46.88530Z,1327103986.8853 [Default:GPS:A.SetSpeed] Stopped
2012-01-20T23:59:46.88540Z,1327103986.8854 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-20T23:59:47.27680Z,1327103987.2768 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-20T23:59:47.27690Z,1327103987.2769 [Default:CallGPS:A] Stopped
2012-01-20T23:59:47.27700Z,1327103987.277 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-20T23:59:47.27720Z,1327103987.2772 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-20T23:59:47.27730Z,1327103987.2773 [Default:CallGPS] Stopped
2012-01-20T23:59:47.27740Z,1327103987.2774 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-20T23:59:47.68610Z,1327103987.6861 [Default:CallGPS] Running Loop=1
2012-01-20T23:59:47.68620Z,1327103987.6862 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-20T23:59:47.68640Z,1327103987.6864 [Default:CallGPS:A] Running Loop=1
2012-01-20T23:59:47.68650Z,1327103987.6865 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-20T23:59:48.09850Z,1327103988.0985 [Default:GPS] Running Loop=1
2012-01-20T23:59:48.09860Z,1327103988.0986 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-20T23:59:48.09870Z,1327103988.0987 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-20T23:59:48.09880Z,1327103988.0988 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-20T23:59:48.09900Z,1327103988.099 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-20T23:59:48.09910Z,1327103988.0991 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-20T23:59:48.09970Z,1327103988.0997 [Default:GPS:B.GoToSurface] Stopped
2012-01-20T23:59:48.09980Z,1327103988.0998 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-20T23:59:48.09990Z,1327103988.0999 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:00:03.56980Z,1327104003.5698 [NAL9601](INFO): SBD MO Status=1, MOMSN=42512, MT Status=0, MTMSN=0
2012-01-21T00:00:03.71530Z,1327104003.7153 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0005.lzma
2012-01-21T00:00:03.71550Z,1327104003.7155 [NAL9601](INFO): Packets left to send: 2
2012-01-21T00:00:03.74160Z,1327104003.7416 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000446
2012-01-21T00:00:11.24990Z,1327104011.2499 [NAL9601](INFO): SBD MO Status=1, MOMSN=42513, MT Status=0, MTMSN=0
2012-01-21T00:00:11.39930Z,1327104011.3993 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0005.lzma
2012-01-21T00:00:11.39950Z,1327104011.3995 [NAL9601](INFO): Packets left to send: 1
2012-01-21T00:00:11.40070Z,1327104011.4007 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000447
2012-01-21T00:00:19.72980Z,1327104019.7298 [NAL9601](INFO): SBD MO Status=1, MOMSN=42514, MT Status=0, MTMSN=0
2012-01-21T00:00:19.88730Z,1327104019.8873 [NAL9601](INFO): Sent 161 bytes from file Logs/20120120T230309/shore0005.lzma
2012-01-21T00:00:19.88750Z,1327104019.8875 [NAL9601](INFO): Packets left to send: 0
2012-01-21T00:00:19.88870Z,1327104019.8887 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000448
2012-01-21T00:00:24.52980Z,1327104024.5298 [NAL9601](INFO): SBD MO Status=0, MOMSN=42515, MT Status=0, MTMSN=0
2012-01-21T00:00:24.69250Z,1327104024.6925 [Default:Iridium:Read_Iridium] Stopped
2012-01-21T00:00:24.69290Z,1327104024.6929 [Default:Iridium](INFO): Completed Default:Iridium
2012-01-21T00:00:24.69300Z,1327104024.693 [Default:Iridium] Stopped
2012-01-21T00:00:24.69310Z,1327104024.6931 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-01-21T00:00:24.69320Z,1327104024.6932 [Default:Iridium:A.SetSpeed] Stopped
2012-01-21T00:00:24.69320Z,1327104024.6932 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:00:24.69340Z,1327104024.6934 [Default:G.Wait] Running Loop=1
2012-01-21T00:00:24.69350Z,1327104024.6935 [Default:G.Wait](DEBUG): Initialize Wait Component.
2012-01-21T00:00:24.93610Z,1327104024.9361 [Default:CallIridium:A](INFO): Completed Default:CallIridium:A
2012-01-21T00:00:24.93620Z,1327104024.9362 [Default:CallIridium:A] Stopped
2012-01-21T00:00:24.93630Z,1327104024.9363 [Default:CallIridium:A](INFO): Aggregate::uninitialize Default:CallIridium:A
2012-01-21T00:00:24.93650Z,1327104024.9365 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-01-21T00:00:24.93660Z,1327104024.9366 [Default:CallIridium] Stopped
2012-01-21T00:00:24.93670Z,1327104024.9367 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-01-21T00:00:25.73120Z,1327104025.7312 [NAL9601](IMPORTANT): GPS fix at: 1327104019
2012-01-21T00:00:25.74500Z,1327104025.745 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:00:25.74540Z,1327104025.7454 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:00:25.74550Z,1327104025.7455 [Default:GPS] Stopped
2012-01-21T00:00:25.74560Z,1327104025.7456 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:00:25.74570Z,1327104025.7457 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:00:25.74580Z,1327104025.7458 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:00:26.15950Z,1327104026.1595 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:00:26.15960Z,1327104026.1596 [Default:CallGPS:A] Stopped
2012-01-21T00:00:26.15970Z,1327104026.1597 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:00:26.15990Z,1327104026.1599 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:00:26.16000Z,1327104026.16 [Default:CallGPS] Stopped
2012-01-21T00:00:26.16010Z,1327104026.1601 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:00:46.26810Z,1327104046.2681 [NAL9601](INFO): Powering down
2012-01-21T00:03:31.23290Z,1327104211.2329 [Batt_Ocean_Server](INFO): Over Temperature Alarm! Battery Bank #3 STATUS: 5911
2012-01-21T00:03:31.23320Z,1327104211.2332 [Batt_Ocean_Server](INFO): Not Initialized - Battery Bank #3 STATUS: 5911
2012-01-21T00:05:26.28540Z,1327104326.2854 [Default:CallIridium] Running Loop=1
2012-01-21T00:05:26.28560Z,1327104326.2856 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-01-21T00:05:26.28580Z,1327104326.2858 [Default:CallIridium:A] Running Loop=1
2012-01-21T00:05:26.28590Z,1327104326.2859 [Default:CallIridium:A](INFO): Aggregate::initialize Default:CallIridium:A
2012-01-21T00:05:26.28600Z,1327104326.286 [Default:CallGPS] Running Loop=1
2012-01-21T00:05:26.28610Z,1327104326.2861 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:05:26.28630Z,1327104326.2863 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:05:26.28640Z,1327104326.2864 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:05:31.31780Z,1327104331.3178 [Default:Iridium] Running Loop=1
2012-01-21T00:05:31.31790Z,1327104331.3179 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-01-21T00:05:31.31800Z,1327104331.318 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-21T00:05:31.31810Z,1327104331.3181 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:05:31.31830Z,1327104331.3183 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-01-21T00:05:31.31840Z,1327104331.3184 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:05:31.31910Z,1327104331.3191 [Default:Iridium:B.GoToSurface] Stopped
2012-01-21T00:05:31.31920Z,1327104331.3192 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:05:31.31930Z,1327104331.3193 [Default:Iridium:Read_Iridium] Running Loop=1
2012-01-21T00:05:31.31960Z,1327104331.3196 [Default:GPS] Running Loop=1
2012-01-21T00:05:31.31970Z,1327104331.3197 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:05:31.31980Z,1327104331.3198 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:05:31.31990Z,1327104331.3199 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:05:31.32000Z,1327104331.32 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:05:31.32010Z,1327104331.3201 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:05:31.32070Z,1327104331.3207 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:05:31.32080Z,1327104331.3208 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:05:31.32100Z,1327104331.321 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:05:32.19940Z,1327104332.1994 [NAL9601](INFO): Powering up
2012-01-21T00:06:37.91140Z,1327104397.9114 [NAL9601](INFO): NAL9601 initialized
2012-01-21T00:06:39.00330Z,1327104399.0033 [NAL9601](IMPORTANT): GPS fix at: 1327104393
2012-01-21T00:06:39.06150Z,1327104399.0615 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:06:39.06180Z,1327104399.0618 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:06:39.06190Z,1327104399.0619 [Default:GPS] Stopped
2012-01-21T00:06:39.06210Z,1327104399.0621 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:06:39.06210Z,1327104399.0621 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:06:39.06220Z,1327104399.0622 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:06:39.46520Z,1327104399.4652 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:06:39.46530Z,1327104399.4653 [Default:CallGPS:A] Stopped
2012-01-21T00:06:39.46540Z,1327104399.4654 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:06:39.46560Z,1327104399.4656 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:06:39.46570Z,1327104399.4657 [Default:CallGPS] Stopped
2012-01-21T00:06:39.46580Z,1327104399.4658 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:06:39.82690Z,1327104399.8269 [Default:CallGPS] Running Loop=1
2012-01-21T00:06:39.82700Z,1327104399.827 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:06:39.82720Z,1327104399.8272 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:06:39.82740Z,1327104399.8274 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:06:40.28490Z,1327104400.2849 [Default:GPS] Running Loop=1
2012-01-21T00:06:40.28510Z,1327104400.2851 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:06:40.28510Z,1327104400.2851 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:06:40.28520Z,1327104400.2852 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:06:40.28540Z,1327104400.2854 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:06:40.28550Z,1327104400.2855 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:06:40.28620Z,1327104400.2862 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:06:40.28620Z,1327104400.2862 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:06:40.28640Z,1327104400.2864 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:06:55.31790Z,1327104415.3179 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42516, MT Status=1, MTMSN=3493
2012-01-21T00:06:55.47130Z,1327104415.4713 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0006.lzma
2012-01-21T00:06:55.47160Z,1327104415.4716 [NAL9601](INFO): Packets left to send: 2
2012-01-21T00:06:55.47270Z,1327104415.4727 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000449
2012-01-21T00:06:55.88420Z,1327104415.8842 [NAL9601](INFO): Received command:run Default.xml
2012-01-21T00:06:55.89930Z,1327104415.8993 [CommandLine](IMPORTANT): got command run ./Missions/Default.xml
2012-01-21T00:06:55.89960Z,1327104415.8996 [MissionManager](INFO): Loading Mission: ./Missions/Default.xml
2012-01-21T00:06:55.95020Z,1327104415.9502 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-01-21T00:06:55.95270Z,1327104415.9527 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-01-21T00:06:55.96080Z,1327104415.9608 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-01-21T00:06:55.96740Z,1327104415.9674 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-01-21T00:06:55.97550Z,1327104415.9755 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-01-21T00:06:55.99200Z,1327104415.992 [Default:E.SetSpeed](DEBUG): Construct.
2012-01-21T00:06:55.99440Z,1327104415.9944 [Default:F.GoToSurface](DEBUG): Construct GoToSurface.
2012-01-21T00:06:55.99710Z,1327104415.9971 [Default:G.Wait](DEBUG): Construct Wait.
2012-01-21T00:06:56.00160Z,1327104416.0016 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Default">

    <Aggregate Id="GPS" >

<!--    Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!--    Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!--    Get GPS fix -->

        <ReadDatum Id="Read_GPS"><Universal:latitude_fix/>
        </ReadDatum>

    </Aggregate>

    <Aggregate Id="Iridium" >

<!--    Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!--    Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!--    Do data comms -->

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute RunIn="Sequence">
                    <Setting>
                    <Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped drop weight due to communications timeout</Syslog>
            </Timeout><Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

    <Aggregate Id="CallGPS" >

        <When>
            <Elapsed><Universal:time_fix/></Elapsed>
            <Gt><Units:hour/><Value>1.0</Value></Gt>
            <Or>
            <Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:minute/><Value>5.0</Value></Gt><And><Universal:platform_conversation/><Eq><True/></Eq></And></Or>
            <Or>
            <Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

        <Call RefId="GPS"/>

    </Aggregate>

    <Aggregate Id="CallIridium" >

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or>
            <Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

        <Call RefId="Iridium"/>

    </Aggregate>

<!-- Wait forever at the surface-->

<!-- Run at low speed -->

    <Guidance:SetSpeed RunIn="Parallel">
        <Setting><Guidance:SetSpeed.period/><Units:second/><Value>5</Value></Setting>
    </Guidance:SetSpeed>

    <Guidance:GoToSurface RunIn="Parallel"/>

    <Guidance:Wait RunIn="Sequence"></Guidance:Wait>

</Mission>


2012-01-21T00:06:56.00200Z,1327104416.002 [CommandLine](IMPORTANT): Running ./Missions/Default.xml
2012-01-21T00:06:56.12870Z,1327104416.1287 [Default] Stopped
2012-01-21T00:06:56.12890Z,1327104416.1289 [Default](INFO): Aggregate::uninitialize Default
2012-01-21T00:06:56.12900Z,1327104416.129 [Default:GPS] Stopped
2012-01-21T00:06:56.12910Z,1327104416.1291 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:06:56.12920Z,1327104416.1292 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:06:56.12930Z,1327104416.1293 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:06:56.12930Z,1327104416.1293 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:06:56.12940Z,1327104416.1294 [Default:Iridium] Stopped
2012-01-21T00:06:56.12950Z,1327104416.1295 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-01-21T00:06:56.12960Z,1327104416.1296 [Default:Iridium:A.SetSpeed] Stopped
2012-01-21T00:06:56.12970Z,1327104416.1297 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:06:56.12970Z,1327104416.1297 [Default:Iridium:Read_Iridium] Stopped
2012-01-21T00:06:56.12980Z,1327104416.1298 [Default:CallGPS] Stopped
2012-01-21T00:06:56.12990Z,1327104416.1299 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:06:56.13000Z,1327104416.13 [Default:CallGPS:A] Stopped
2012-01-21T00:06:56.13020Z,1327104416.1302 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:06:56.13020Z,1327104416.1302 [Default:CallIridium] Stopped
2012-01-21T00:06:56.13040Z,1327104416.1304 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-01-21T00:06:56.13040Z,1327104416.1304 [Default:CallIridium:A] Stopped
2012-01-21T00:06:56.13060Z,1327104416.1306 [Default:CallIridium:A](INFO): Aggregate::uninitialize Default:CallIridium:A
2012-01-21T00:06:56.13060Z,1327104416.1306 [Default:E.SetSpeed] Stopped
2012-01-21T00:06:56.13070Z,1327104416.1307 [Default:E.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:06:56.13090Z,1327104416.1309 [Default:F.GoToSurface] Stopped
2012-01-21T00:06:56.13100Z,1327104416.131 [Default:F.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:06:56.13100Z,1327104416.131 [Default:G.Wait] Stopped
2012-01-21T00:06:56.13110Z,1327104416.1311 [Default:G.Wait](DEBUG): Uninitialize Wait Component.
2012-01-21T00:06:56.13130Z,1327104416.1313 [MissionManager](IMPORTANT): Started mission Default
2012-01-21T00:06:56.13130Z,1327104416.1313 [Default] Running Loop=1
2012-01-21T00:06:56.13150Z,1327104416.1315 [Default](INFO): Aggregate::initialize Default
2012-01-21T00:06:56.13160Z,1327104416.1316 [Default:E.SetSpeed] Running Loop=1
2012-01-21T00:06:56.13160Z,1327104416.1316 [Default:E.SetSpeed](DEBUG): Initialize.
2012-01-21T00:06:56.13180Z,1327104416.1318 [Default:F.GoToSurface] Running Loop=1
2012-01-21T00:06:56.13180Z,1327104416.1318 [Default:F.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:06:56.13230Z,1327104416.1323 [Default:GPS] Running Loop=1
2012-01-21T00:06:56.13240Z,1327104416.1324 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:06:56.13250Z,1327104416.1325 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:06:56.13260Z,1327104416.1326 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:06:56.13290Z,1327104416.1329 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:06:56.13300Z,1327104416.133 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:06:56.13390Z,1327104416.1339 [Default:F.GoToSurface] Running Loop=1
2012-01-21T00:06:56.13520Z,1327104416.1352 [Default:E.SetSpeed] Running Loop=1
2012-01-21T00:06:56.13610Z,1327104416.1361 [Default:CallIridium] Running Loop=1
2012-01-21T00:06:56.13620Z,1327104416.1362 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-01-21T00:06:56.13640Z,1327104416.1364 [Default:CallIridium:A] Running Loop=1
2012-01-21T00:06:56.13650Z,1327104416.1365 [Default:CallIridium:A](INFO): Aggregate::initialize Default:CallIridium:A
2012-01-21T00:06:56.13660Z,1327104416.1366 [Default:CallGPS] Running Loop=1
2012-01-21T00:06:56.13670Z,1327104416.1367 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:06:56.13700Z,1327104416.137 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:06:56.13720Z,1327104416.1372 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:06:56.13820Z,1327104416.1382 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:06:56.13820Z,1327104416.1382 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:06:56.13850Z,1327104416.1385 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:06:56.13860Z,1327104416.1386 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:06:56.56600Z,1327104416.566 [Default:Iridium] Running Loop=1
2012-01-21T00:06:56.56610Z,1327104416.5661 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-01-21T00:06:56.56620Z,1327104416.5662 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-21T00:06:56.56630Z,1327104416.5663 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:06:56.56650Z,1327104416.5665 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-01-21T00:06:56.56660Z,1327104416.5666 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:06:56.56780Z,1327104416.5678 [Default:Iridium:B.GoToSurface] Stopped
2012-01-21T00:06:56.56790Z,1327104416.5679 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:06:56.56810Z,1327104416.5681 [Default:Iridium:Read_Iridium] Running Loop=1
2012-01-21T00:06:56.56810Z,1327104416.5681 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-21T00:06:56.56930Z,1327104416.5693 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-01-21T00:06:56.91920Z,1327104416.9192 [NAL9601](IMPORTANT): GPS fix at: 1327104410
2012-01-21T00:06:56.93300Z,1327104416.933 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-01-21T00:06:56.93420Z,1327104416.9342 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:06:56.93460Z,1327104416.9346 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:06:56.93470Z,1327104416.9347 [Default:GPS] Stopped
2012-01-21T00:06:56.93480Z,1327104416.9348 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:06:56.93490Z,1327104416.9349 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:06:56.93500Z,1327104416.935 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:06:57.32890Z,1327104417.3289 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:06:57.32900Z,1327104417.329 [Default:CallGPS:A] Stopped
2012-01-21T00:06:57.32910Z,1327104417.3291 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:06:57.32930Z,1327104417.3293 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:06:57.32940Z,1327104417.3294 [Default:CallGPS] Stopped
2012-01-21T00:06:57.32950Z,1327104417.3295 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:06:57.81840Z,1327104417.8184 [Default:CallGPS] Running Loop=1
2012-01-21T00:06:57.81860Z,1327104417.8186 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:06:57.81870Z,1327104417.8187 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:06:57.81900Z,1327104417.819 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:06:58.12490Z,1327104418.1249 [Default:GPS] Running Loop=1
2012-01-21T00:06:58.12500Z,1327104418.125 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:06:58.12510Z,1327104418.1251 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:06:58.12520Z,1327104418.1252 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:06:58.12540Z,1327104418.1254 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:06:58.12550Z,1327104418.1255 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:06:58.12610Z,1327104418.1261 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:06:58.12610Z,1327104418.1261 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:06:58.12630Z,1327104418.1263 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:07:07.62990Z,1327104427.6299 [NAL9601](INFO): SBD MO Status=1, MOMSN=42517, MT Status=0, MTMSN=0
2012-01-21T00:07:07.75530Z,1327104427.7553 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0006.lzma
2012-01-21T00:07:07.75550Z,1327104427.7555 [NAL9601](INFO): Packets left to send: 1
2012-01-21T00:07:07.75660Z,1327104427.7566 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000450
2012-01-21T00:07:14.55030Z,1327104434.5503 [NAL9601](INFO): SBD MO Status=1, MOMSN=42518, MT Status=0, MTMSN=0
2012-01-21T00:07:14.74330Z,1327104434.7433 [NAL9601](INFO): Sent 268 bytes from file Logs/20120120T230309/shore0006.lzma
2012-01-21T00:07:14.74350Z,1327104434.7435 [NAL9601](INFO): Packets left to send: 0
2012-01-21T00:07:14.74470Z,1327104434.7447 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000451
2012-01-21T00:07:19.71380Z,1327104439.7138 [NAL9601](INFO): SBD MO Status=0, MOMSN=42519, MT Status=0, MTMSN=0
2012-01-21T00:07:20.91550Z,1327104440.9155 [NAL9601](IMPORTANT): GPS fix at: 1327104434
2012-01-21T00:07:20.92950Z,1327104440.9295 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:07:20.92980Z,1327104440.9298 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:07:20.92990Z,1327104440.9299 [Default:GPS] Stopped
2012-01-21T00:07:20.93000Z,1327104440.93 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:07:20.93010Z,1327104440.9301 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:07:20.93020Z,1327104440.9302 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:07:21.34210Z,1327104441.3421 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:07:21.34220Z,1327104441.3422 [Default:CallGPS:A] Stopped
2012-01-21T00:07:21.34230Z,1327104441.3423 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:07:21.34250Z,1327104441.3425 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:07:21.34260Z,1327104441.3426 [Default:CallGPS] Stopped
2012-01-21T00:07:21.34270Z,1327104441.3427 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:07:21.72960Z,1327104441.7296 [Default:CallGPS] Running Loop=1
2012-01-21T00:07:21.72980Z,1327104441.7298 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:07:21.72990Z,1327104441.7299 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:07:21.73010Z,1327104441.7301 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:07:22.12040Z,1327104442.1204 [Default:GPS] Running Loop=1
2012-01-21T00:07:22.12060Z,1327104442.1206 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:07:22.12070Z,1327104442.1207 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:07:22.12070Z,1327104442.1207 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:07:22.12090Z,1327104442.1209 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:07:22.12100Z,1327104442.121 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:07:22.12170Z,1327104442.1217 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:07:22.12170Z,1327104442.1217 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:07:22.12190Z,1327104442.1219 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:07:37.61380Z,1327104457.6138 [NAL9601](INFO): SBD MO Status=1, MOMSN=42520, MT Status=0, MTMSN=0
2012-01-21T00:07:37.81550Z,1327104457.8155 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0007.lzma
2012-01-21T00:07:37.81570Z,1327104457.8157 [NAL9601](INFO): Packets left to send: 1
2012-01-21T00:07:37.81690Z,1327104457.8169 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000452
2012-01-21T00:07:42.51350Z,1327104462.5135 [Radio_Freewave](INFO): Powering down
2012-01-21T00:07:47.64130Z,1327104467.6413 [Radio_Freewave](INFO): Powering up
2012-01-21T00:07:48.81770Z,1327104468.8177 [NAL9601](INFO): SBD MO Status=2, MOMSN=42521, MT Status=2, MTMSN=0
2012-01-21T00:07:48.81790Z,1327104468.8179 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-01-21T00:07:50.01950Z,1327104470.0195 [NAL9601](IMPORTANT): GPS fix at: 1327104464
2012-01-21T00:07:50.03360Z,1327104470.0336 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:07:50.03390Z,1327104470.0339 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:07:50.03400Z,1327104470.034 [Default:GPS] Stopped
2012-01-21T00:07:50.03410Z,1327104470.0341 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:07:50.03420Z,1327104470.0342 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:07:50.03430Z,1327104470.0343 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:07:50.44740Z,1327104470.4474 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:07:50.44750Z,1327104470.4475 [Default:CallGPS:A] Stopped
2012-01-21T00:07:50.44760Z,1327104470.4476 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:07:50.44780Z,1327104470.4478 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:07:50.44790Z,1327104470.4479 [Default:CallGPS] Stopped
2012-01-21T00:07:50.44800Z,1327104470.448 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:07:50.86970Z,1327104470.8697 [Default:CallGPS] Running Loop=1
2012-01-21T00:07:50.86990Z,1327104470.8699 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:07:50.87000Z,1327104470.87 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:07:50.87020Z,1327104470.8702 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:07:51.22860Z,1327104471.2286 [Default:GPS] Running Loop=1
2012-01-21T00:07:51.22870Z,1327104471.2287 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:07:51.22880Z,1327104471.2288 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:07:51.22890Z,1327104471.2289 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:07:51.22910Z,1327104471.2291 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:07:51.22920Z,1327104471.2292 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:07:51.22980Z,1327104471.2298 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:07:51.22990Z,1327104471.2299 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:07:51.23010Z,1327104471.2301 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:08:11.41680Z,1327104491.4168 [NAL9601](INFO): SBD MO Status=1, MOMSN=42521, MT Status=0, MTMSN=0
2012-01-21T00:08:11.55530Z,1327104491.5553 [NAL9601](INFO): Sent 189 bytes from file Logs/20120120T230309/shore0007.lzma
2012-01-21T00:08:11.55550Z,1327104491.5555 [NAL9601](INFO): Packets left to send: 0
2012-01-21T00:08:11.55670Z,1327104491.5567 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000453
2012-01-21T00:08:28.62470Z,1327104508.6247 [NAL9601](INFO): SBD MO Status=0, MOMSN=42522, MT Status=0, MTMSN=0
2012-01-21T00:08:28.83980Z,1327104508.8398 [Default:Iridium:Read_Iridium] Stopped
2012-01-21T00:08:28.84020Z,1327104508.8402 [Default:Iridium](INFO): Completed Default:Iridium
2012-01-21T00:08:28.84030Z,1327104508.8403 [Default:Iridium] Stopped
2012-01-21T00:08:28.84040Z,1327104508.8404 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-01-21T00:08:28.84050Z,1327104508.8405 [Default:Iridium:A.SetSpeed] Stopped
2012-01-21T00:08:28.84050Z,1327104508.8405 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:08:28.84070Z,1327104508.8407 [Default:G.Wait] Running Loop=1
2012-01-21T00:08:28.84080Z,1327104508.8408 [Default:G.Wait](DEBUG): Initialize Wait Component.
2012-01-21T00:08:29.01260Z,1327104509.0126 [Default:CallIridium:A](INFO): Completed Default:CallIridium:A
2012-01-21T00:08:29.01270Z,1327104509.0127 [Default:CallIridium:A] Stopped
2012-01-21T00:08:29.01280Z,1327104509.0128 [Default:CallIridium:A](INFO): Aggregate::uninitialize Default:CallIridium:A
2012-01-21T00:08:29.01300Z,1327104509.013 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-01-21T00:08:29.01310Z,1327104509.0131 [Default:CallIridium] Stopped
2012-01-21T00:08:29.01320Z,1327104509.0132 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-01-21T00:08:29.80710Z,1327104509.8071 [NAL9601](IMPORTANT): GPS fix at: 1327104503
2012-01-21T00:08:29.82070Z,1327104509.8207 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:08:29.82120Z,1327104509.8212 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:08:29.82130Z,1327104509.8213 [Default:GPS] Stopped
2012-01-21T00:08:29.82140Z,1327104509.8214 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:08:29.82150Z,1327104509.8215 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:08:29.82160Z,1327104509.8216 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:08:30.21270Z,1327104510.2127 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:08:30.21280Z,1327104510.2128 [Default:CallGPS:A] Stopped
2012-01-21T00:08:30.21290Z,1327104510.2129 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:08:30.21310Z,1327104510.2131 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:08:30.21320Z,1327104510.2132 [Default:CallGPS] Stopped
2012-01-21T00:08:30.21330Z,1327104510.2133 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:08:50.41570Z,1327104530.4157 [NAL9601](INFO): Powering down
2012-01-21T00:13:30.44460Z,1327104810.4446 [Default:CallIridium] Running Loop=1
2012-01-21T00:13:30.44480Z,1327104810.4448 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-01-21T00:13:30.44500Z,1327104810.445 [Default:CallIridium:A] Running Loop=1
2012-01-21T00:13:30.44510Z,1327104810.4451 [Default:CallIridium:A](INFO): Aggregate::initialize Default:CallIridium:A
2012-01-21T00:13:30.44520Z,1327104810.4452 [Default:CallGPS] Running Loop=1
2012-01-21T00:13:30.44530Z,1327104810.4453 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:13:30.44550Z,1327104810.4455 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:13:30.44560Z,1327104810.4456 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:13:35.41970Z,1327104815.4197 [Default:Iridium] Running Loop=1
2012-01-21T00:13:35.41990Z,1327104815.4199 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-01-21T00:13:35.41990Z,1327104815.4199 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-21T00:13:35.42000Z,1327104815.42 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:13:35.42020Z,1327104815.4202 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-01-21T00:13:35.42030Z,1327104815.4203 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:13:35.42090Z,1327104815.4209 [Default:Iridium:B.GoToSurface] Stopped
2012-01-21T00:13:35.42100Z,1327104815.421 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:13:35.42110Z,1327104815.4211 [Default:Iridium:Read_Iridium] Running Loop=1
2012-01-21T00:13:35.42140Z,1327104815.4214 [Default:GPS] Running Loop=1
2012-01-21T00:13:35.42150Z,1327104815.4215 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:13:35.42160Z,1327104815.4216 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:13:35.42170Z,1327104815.4217 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:13:35.42190Z,1327104815.4219 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:13:35.42190Z,1327104815.4219 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:13:35.42260Z,1327104815.4226 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:13:35.42260Z,1327104815.4226 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:13:35.42280Z,1327104815.4228 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:13:35.99940Z,1327104815.9994 [NAL9601](INFO): Powering up
2012-01-21T00:14:41.71140Z,1327104881.7114 [NAL9601](INFO): NAL9601 initialized
2012-01-21T00:14:58.33380Z,1327104898.3338 [NAL9601](INFO): SBD MO Status=1, MOMSN=42523, MT Status=0, MTMSN=0
2012-01-21T00:14:58.47140Z,1327104898.4714 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0008.lzma
2012-01-21T00:14:58.47160Z,1327104898.4716 [NAL9601](INFO): Packets left to send: 3
2012-01-21T00:14:58.47350Z,1327104898.4735 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000454
2012-01-21T00:15:08.47300Z,1327104908.473 [NAL9601](INFO): SBD MO Status=1, MOMSN=42524, MT Status=0, MTMSN=0
2012-01-21T00:15:08.65540Z,1327104908.6554 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0008.lzma
2012-01-21T00:15:08.65560Z,1327104908.6556 [NAL9601](INFO): Packets left to send: 2
2012-01-21T00:15:08.65680Z,1327104908.6568 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000455
2012-01-21T00:15:11.80930Z,1327104911.8093 [Batt_Ocean_Server](INFO): Over Temperature Alarm! Battery Bank #4 STATUS: 5911
2012-01-21T00:15:11.80960Z,1327104911.8096 [Batt_Ocean_Server](INFO): Not Initialized - Battery Bank #4 STATUS: 5911
2012-01-21T00:15:17.34910Z,1327104917.3491 [NAL9601](INFO): SBD MO Status=1, MOMSN=42525, MT Status=0, MTMSN=0
2012-01-21T00:15:17.54330Z,1327104917.5433 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0008.lzma
2012-01-21T00:15:17.54360Z,1327104917.5436 [NAL9601](INFO): Packets left to send: 1
2012-01-21T00:15:17.54470Z,1327104917.5447 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000456
2012-01-21T00:15:26.02090Z,1327104926.0209 [NAL9601](INFO): SBD MO Status=1, MOMSN=42526, MT Status=0, MTMSN=0
2012-01-21T00:15:26.24320Z,1327104926.2432 [NAL9601](INFO): Sent 164 bytes from file Logs/20120120T230309/shore0008.lzma
2012-01-21T00:15:26.24350Z,1327104926.2435 [NAL9601](INFO): Packets left to send: 0
2012-01-21T00:15:26.24460Z,1327104926.2446 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000457
2012-01-21T00:15:34.02580Z,1327104934.0258 [NAL9601](INFO): SBD MO Status=0, MOMSN=42527, MT Status=0, MTMSN=0
2012-01-21T00:15:34.25380Z,1327104934.2538 [Default:Iridium:Read_Iridium] Stopped
2012-01-21T00:15:34.25420Z,1327104934.2542 [Default:Iridium](INFO): Completed Default:Iridium
2012-01-21T00:15:34.25430Z,1327104934.2543 [Default:Iridium] Stopped
2012-01-21T00:15:34.25440Z,1327104934.2544 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-01-21T00:15:34.25450Z,1327104934.2545 [Default:Iridium:A.SetSpeed] Stopped
2012-01-21T00:15:34.25460Z,1327104934.2546 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:15:34.43320Z,1327104934.4332 [Default:CallIridium:A](INFO): Completed Default:CallIridium:A
2012-01-21T00:15:34.43330Z,1327104934.4333 [Default:CallIridium:A] Stopped
2012-01-21T00:15:34.43340Z,1327104934.4334 [Default:CallIridium:A](INFO): Aggregate::uninitialize Default:CallIridium:A
2012-01-21T00:15:34.43360Z,1327104934.4336 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-01-21T00:15:34.43370Z,1327104934.4337 [Default:CallIridium] Stopped
2012-01-21T00:15:34.43380Z,1327104934.4338 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-01-21T00:15:35.24380Z,1327104935.2438 [NAL9601](IMPORTANT): GPS fix at: 1327104930
2012-01-21T00:15:35.25770Z,1327104935.2577 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:15:35.25810Z,1327104935.2581 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:15:35.25820Z,1327104935.2582 [Default:GPS] Stopped
2012-01-21T00:15:35.25830Z,1327104935.2583 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:15:35.25840Z,1327104935.2584 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:15:35.25850Z,1327104935.2585 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:15:35.64180Z,1327104935.6418 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:15:35.64190Z,1327104935.6419 [Default:CallGPS:A] Stopped
2012-01-21T00:15:35.64200Z,1327104935.642 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:15:35.64220Z,1327104935.6422 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:15:35.64230Z,1327104935.6423 [Default:CallGPS] Stopped
2012-01-21T00:15:35.64240Z,1327104935.6424 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:15:55.80250Z,1327104955.8025 [NAL9601](INFO): Powering down
2012-01-21T00:20:35.79610Z,1327105235.7961 [Default:CallIridium] Running Loop=1
2012-01-21T00:20:35.79630Z,1327105235.7963 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-01-21T00:20:35.79640Z,1327105235.7964 [Default:CallIridium:A] Running Loop=1
2012-01-21T00:20:35.79650Z,1327105235.7965 [Default:CallIridium:A](INFO): Aggregate::initialize Default:CallIridium:A
2012-01-21T00:20:35.79670Z,1327105235.7967 [Default:CallGPS] Running Loop=1
2012-01-21T00:20:35.79680Z,1327105235.7968 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:20:35.79700Z,1327105235.797 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:20:35.79710Z,1327105235.7971 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:20:40.84890Z,1327105240.8489 [Default:Iridium] Running Loop=1
2012-01-21T00:20:40.84910Z,1327105240.8491 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-01-21T00:20:40.84920Z,1327105240.8492 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-21T00:20:40.84920Z,1327105240.8492 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:20:40.84940Z,1327105240.8494 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-01-21T00:20:40.84950Z,1327105240.8495 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:20:40.85010Z,1327105240.8501 [Default:Iridium:B.GoToSurface] Stopped
2012-01-21T00:20:40.85020Z,1327105240.8502 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:20:40.85030Z,1327105240.8503 [Default:Iridium:Read_Iridium] Running Loop=1
2012-01-21T00:20:40.85060Z,1327105240.8506 [Default:GPS] Running Loop=1
2012-01-21T00:20:40.85070Z,1327105240.8507 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:20:40.85090Z,1327105240.8509 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:20:40.85100Z,1327105240.851 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:20:40.85110Z,1327105240.8511 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:20:40.85120Z,1327105240.8512 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:20:40.85180Z,1327105240.8518 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:20:40.85190Z,1327105240.8519 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:20:40.85200Z,1327105240.852 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:20:41.42060Z,1327105241.4206 [NAL9601](INFO): Powering up
2012-01-21T00:20:59.08260Z,1327105259.0826 [Batt_Ocean_Server](INFO): Over Temperature Alarm! Battery Bank #7 STATUS: 5911
2012-01-21T00:20:59.08300Z,1327105259.083 [Batt_Ocean_Server](INFO): Not Initialized - Battery Bank #7 STATUS: 5911
2012-01-21T00:21:47.13140Z,1327105307.1314 [NAL9601](INFO): NAL9601 initialized
2012-01-21T00:22:26.48870Z,1327105346.4887 [NAL9601](INFO): SBD MO Status=1, MOMSN=42528, MT Status=0, MTMSN=0
2012-01-21T00:22:26.65970Z,1327105346.6597 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0009.lzma
2012-01-21T00:22:26.65990Z,1327105346.6599 [NAL9601](INFO): Packets left to send: 3
2012-01-21T00:22:26.66110Z,1327105346.6611 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000458
2012-01-21T00:22:41.40170Z,1327105361.4017 [NAL9601](INFO): SBD MO Status=2, MOMSN=42529, MT Status=2, MTMSN=0
2012-01-21T00:22:41.40200Z,1327105361.402 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-01-21T00:22:42.60730Z,1327105362.6073 [NAL9601](IMPORTANT): GPS fix at: 1327105358
2012-01-21T00:22:42.62360Z,1327105362.6236 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:22:42.62390Z,1327105362.6239 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:22:42.62400Z,1327105362.624 [Default:GPS] Stopped
2012-01-21T00:22:42.62410Z,1327105362.6241 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:22:42.62420Z,1327105362.6242 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:22:42.62430Z,1327105362.6243 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:22:43.01310Z,1327105363.0131 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:22:43.01320Z,1327105363.0132 [Default:CallGPS:A] Stopped
2012-01-21T00:22:43.01330Z,1327105363.0133 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:22:43.01350Z,1327105363.0135 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:22:43.01360Z,1327105363.0136 [Default:CallGPS] Stopped
2012-01-21T00:22:43.01370Z,1327105363.0137 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:22:43.45770Z,1327105363.4577 [Default:CallGPS] Running Loop=1
2012-01-21T00:22:43.45780Z,1327105363.4578 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:22:43.45800Z,1327105363.458 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:22:43.45810Z,1327105363.4581 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:22:43.81250Z,1327105363.8125 [Default:GPS] Running Loop=1
2012-01-21T00:22:43.81260Z,1327105363.8126 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:22:43.81270Z,1327105363.8127 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:22:43.81280Z,1327105363.8128 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:22:43.81300Z,1327105363.813 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:22:43.81310Z,1327105363.8131 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:22:43.81370Z,1327105363.8137 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:22:43.81380Z,1327105363.8138 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:22:43.81400Z,1327105363.814 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:23:10.07830Z,1327105390.0783 [NAL9601](INFO): SBD MO Status=1, MOMSN=42529, MT Status=0, MTMSN=0
2012-01-21T00:23:10.29530Z,1327105390.2953 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0009.lzma
2012-01-21T00:23:10.29550Z,1327105390.2955 [NAL9601](INFO): Packets left to send: 2
2012-01-21T00:23:10.29750Z,1327105390.2975 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000459
2012-01-21T00:23:19.00470Z,1327105399.0047 [NAL9601](INFO): SBD MO Status=2, MOMSN=42530, MT Status=2, MTMSN=0
2012-01-21T00:23:19.00500Z,1327105399.005 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-01-21T00:23:20.21120Z,1327105400.2112 [NAL9601](IMPORTANT): GPS fix at: 1327105395
2012-01-21T00:23:20.22450Z,1327105400.2245 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:23:20.22490Z,1327105400.2249 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:23:20.22500Z,1327105400.225 [Default:GPS] Stopped
2012-01-21T00:23:20.22510Z,1327105400.2251 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:23:20.22520Z,1327105400.2252 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:23:20.22530Z,1327105400.2253 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:23:20.61890Z,1327105400.6189 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:23:20.61900Z,1327105400.619 [Default:CallGPS:A] Stopped
2012-01-21T00:23:20.61910Z,1327105400.6191 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:23:20.61930Z,1327105400.6193 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:23:20.61940Z,1327105400.6194 [Default:CallGPS] Stopped
2012-01-21T00:23:20.61950Z,1327105400.6195 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:23:21.02020Z,1327105401.0202 [Default:CallGPS] Running Loop=1
2012-01-21T00:23:21.02030Z,1327105401.0203 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:23:21.02050Z,1327105401.0205 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:23:21.02060Z,1327105401.0206 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:23:21.42140Z,1327105401.4214 [Default:GPS] Running Loop=1
2012-01-21T00:23:21.42160Z,1327105401.4216 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:23:21.42170Z,1327105401.4217 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:23:21.42180Z,1327105401.4218 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:23:21.42200Z,1327105401.422 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:23:21.42200Z,1327105401.422 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:23:21.42270Z,1327105401.4227 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:23:21.42270Z,1327105401.4227 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:23:21.42290Z,1327105401.4229 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:23:37.29360Z,1327105417.2936 [NAL9601](INFO): SBD MO Status=1, MOMSN=42530, MT Status=0, MTMSN=0
2012-01-21T00:23:37.45530Z,1327105417.4553 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0009.lzma
2012-01-21T00:23:37.45550Z,1327105417.4555 [NAL9601](INFO): Packets left to send: 1
2012-01-21T00:23:37.45680Z,1327105417.4568 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000460
2012-01-21T00:23:43.43050Z,1327105423.4305 [NAL9601](INFO): SBD MO Status=1, MOMSN=42531, MT Status=0, MTMSN=0
2012-01-21T00:23:43.63930Z,1327105423.6393 [NAL9601](INFO): Sent 15 bytes from file Logs/20120120T230309/shore0009.lzma
2012-01-21T00:23:43.63950Z,1327105423.6395 [NAL9601](INFO): Packets left to send: 0
2012-01-21T00:23:43.64070Z,1327105423.6407 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000461
2012-01-21T00:23:51.83010Z,1327105431.8301 [NAL9601](INFO): SBD MO Status=0, MOMSN=42532, MT Status=0, MTMSN=0
2012-01-21T00:23:52.04050Z,1327105432.0405 [Default:Iridium:Read_Iridium] Stopped
2012-01-21T00:23:52.04090Z,1327105432.0409 [Default:Iridium](INFO): Completed Default:Iridium
2012-01-21T00:23:52.04100Z,1327105432.041 [Default:Iridium] Stopped
2012-01-21T00:23:52.04110Z,1327105432.0411 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-01-21T00:23:52.04120Z,1327105432.0412 [Default:Iridium:A.SetSpeed] Stopped
2012-01-21T00:23:52.04130Z,1327105432.0413 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:23:52.26330Z,1327105432.2633 [Default:CallIridium:A](INFO): Completed Default:CallIridium:A
2012-01-21T00:23:52.26340Z,1327105432.2634 [Default:CallIridium:A] Stopped
2012-01-21T00:23:52.26350Z,1327105432.2635 [Default:CallIridium:A](INFO): Aggregate::uninitialize Default:CallIridium:A
2012-01-21T00:23:52.26390Z,1327105432.2639 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-01-21T00:23:52.26400Z,1327105432.264 [Default:CallIridium] Stopped
2012-01-21T00:23:52.26410Z,1327105432.2641 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-01-21T00:23:53.01120Z,1327105433.0112 [NAL9601](IMPORTANT): GPS fix at: 1327105428
2012-01-21T00:23:53.06930Z,1327105433.0693 [Default:GPS:Read_GPS] Stopped
2012-01-21T00:23:53.06970Z,1327105433.0697 [Default:GPS](INFO): Completed Default:GPS
2012-01-21T00:23:53.06980Z,1327105433.0698 [Default:GPS] Stopped
2012-01-21T00:23:53.06990Z,1327105433.0699 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-01-21T00:23:53.07000Z,1327105433.07 [Default:GPS:A.SetSpeed] Stopped
2012-01-21T00:23:53.07010Z,1327105433.0701 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-01-21T00:23:53.46210Z,1327105433.4621 [Default:CallGPS:A](INFO): Completed Default:CallGPS:A
2012-01-21T00:23:53.46220Z,1327105433.4622 [Default:CallGPS:A] Stopped
2012-01-21T00:23:53.46230Z,1327105433.4623 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A
2012-01-21T00:23:53.46250Z,1327105433.4625 [Default:CallGPS](INFO): Completed Default:CallGPS
2012-01-21T00:23:53.46260Z,1327105433.4626 [Default:CallGPS] Stopped
2012-01-21T00:23:53.46270Z,1327105433.4627 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS
2012-01-21T00:24:13.65350Z,1327105453.6535 [NAL9601](INFO): Powering down
2012-01-21T00:28:53.59630Z,1327105733.5963 [Default:CallIridium] Running Loop=1
2012-01-21T00:28:53.59650Z,1327105733.5965 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-01-21T00:28:53.59670Z,1327105733.5967 [Default:CallIridium:A] Running Loop=1
2012-01-21T00:28:53.59680Z,1327105733.5968 [Default:CallIridium:A](INFO): Aggregate::initialize Default:CallIridium:A
2012-01-21T00:28:53.59690Z,1327105733.5969 [Default:CallGPS] Running Loop=1
2012-01-21T00:28:53.59700Z,1327105733.597 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS
2012-01-21T00:28:53.59720Z,1327105733.5972 [Default:CallGPS:A] Running Loop=1
2012-01-21T00:28:53.59730Z,1327105733.5973 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A
2012-01-21T00:28:58.70070Z,1327105738.7007 [Default:Iridium] Running Loop=1
2012-01-21T00:28:58.70090Z,1327105738.7009 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-01-21T00:28:58.70100Z,1327105738.701 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-01-21T00:28:58.70110Z,1327105738.7011 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:28:58.70130Z,1327105738.7013 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-01-21T00:28:58.70130Z,1327105738.7013 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:28:58.70200Z,1327105738.702 [Default:Iridium:B.GoToSurface] Stopped
2012-01-21T00:28:58.70210Z,1327105738.7021 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:28:58.70230Z,1327105738.7023 [Default:Iridium:Read_Iridium] Running Loop=1
2012-01-21T00:28:58.70260Z,1327105738.7026 [Default:GPS] Running Loop=1
2012-01-21T00:28:58.70290Z,1327105738.7029 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-01-21T00:28:58.70300Z,1327105738.703 [Default:GPS:A.SetSpeed] Running Loop=1
2012-01-21T00:28:58.70310Z,1327105738.7031 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-01-21T00:28:58.70330Z,1327105738.7033 [Default:GPS:B.GoToSurface] Running Loop=1
2012-01-21T00:28:58.70330Z,1327105738.7033 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-01-21T00:28:58.70390Z,1327105738.7039 [Default:GPS:B.GoToSurface] Stopped
2012-01-21T00:28:58.70400Z,1327105738.704 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-01-21T00:28:58.70420Z,1327105738.7042 [Default:GPS:Read_GPS] Running Loop=1
2012-01-21T00:28:59.23420Z,1327105739.2342 [NAL9601](INFO): Powering up
2012-01-21T00:30:04.94750Z,1327105804.9475 [NAL9601](INFO): NAL9601 initialized
2012-01-21T00:30:22.73780Z,1327105822.7378 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42533, MT Status=1, MTMSN=3494
2012-01-21T00:30:22.91780Z,1327105822.9178 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T230309/shore0010.lzma
2012-01-21T00:30:22.91800Z,1327105822.918 [NAL9601](INFO): Packets left to send: 3
2012-01-21T00:30:22.91940Z,1327105822.9194 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000462
2012-01-21T00:30:23.32030Z,1327105823.3203 [NAL9601](INFO): Received command:restart logs
2012-01-21T00:30:23.41520Z,1327105823.4152 [CommandLine](IMPORTANT): got command restart logs