2012-01-20T18:59:30.99150Z,1327085970.9915 [NAL9601](IMPORTANT): GPS fix at: 1327085936 2012-01-20T18:59:31.00510Z,1327085971.0051 [Default:GPS:Read_GPS] Stopped 2012-01-20T18:59:31.00550Z,1327085971.0055 [Default:GPS](INFO): Completed Default:GPS 2012-01-20T18:59:31.00560Z,1327085971.0056 [Default:GPS] Stopped 2012-01-20T18:59:31.00570Z,1327085971.0057 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-01-20T18:59:31.00580Z,1327085971.0058 [Default:GPS:A.SetSpeed] Stopped 2012-01-20T18:59:31.00590Z,1327085971.0059 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-01-20T18:59:31.00600Z,1327085971.006 [Default:Iridium] Running Loop=1 2012-01-20T18:59:31.00610Z,1327085971.0061 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-01-20T18:59:31.00620Z,1327085971.0062 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-01-20T18:59:31.00630Z,1327085971.0063 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-01-20T18:59:31.00650Z,1327085971.0065 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-01-20T18:59:31.00650Z,1327085971.0065 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T18:59:31.33710Z,1327085971.3371 [Default:Iridium:B.GoToSurface] Stopped 2012-01-20T18:59:31.33720Z,1327085971.3372 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T18:59:31.33730Z,1327085971.3373 [Default:Iridium:Read_Iridium] Running Loop=1 2012-01-20T18:59:48.40660Z,1327085988.4066 [NAL9601](INFO): SBD MO Status=1, MOMSN=42179, MT Status=0, MTMSN=0 2012-01-20T18:59:48.60330Z,1327085988.6033 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T181903/shore0008.lzma 2012-01-20T18:59:48.60350Z,1327085988.6035 [NAL9601](INFO): Packets left to send: 2 2012-01-20T18:59:48.60450Z,1327085988.6045 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000138 2012-01-20T19:00:00.10650Z,1327086000.1065 [NAL9601](INFO): SBD MO Status=1, MOMSN=42180, MT Status=0, MTMSN=0 2012-01-20T19:00:00.28340Z,1327086000.2834 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T181903/shore0008.lzma 2012-01-20T19:00:00.28360Z,1327086000.2836 [NAL9601](INFO): Packets left to send: 1 2012-01-20T19:00:00.28460Z,1327086000.2846 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000139 2012-01-20T19:00:07.43850Z,1327086007.4385 [NAL9601](INFO): SBD MO Status=1, MOMSN=42181, MT Status=0, MTMSN=0 2012-01-20T19:00:07.57540Z,1327086007.5754 [NAL9601](INFO): Sent 46 bytes from file Logs/20120120T181903/shore0008.lzma 2012-01-20T19:00:07.57560Z,1327086007.5756 [NAL9601](INFO): Packets left to send: 0 2012-01-20T19:00:07.57660Z,1327086007.5766 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000140 2012-01-20T19:00:19.08180Z,1327086019.0818 [NAL9601](INFO): SBD MO Status=1, MOMSN=42182, MT Status=0, MTMSN=0 2012-01-20T19:00:19.25530Z,1327086019.2553 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0000.lzma 2012-01-20T19:00:19.25560Z,1327086019.2556 [NAL9601](INFO): Packets left to send: 2 2012-01-20T19:00:19.25660Z,1327086019.2566 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000141 2012-01-20T19:00:31.56170Z,1327086031.5617 [NAL9601](INFO): SBD MO Status=1, MOMSN=42183, MT Status=0, MTMSN=0 2012-01-20T19:00:31.73530Z,1327086031.7353 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0000.lzma 2012-01-20T19:00:31.73550Z,1327086031.7355 [NAL9601](INFO): Packets left to send: 1 2012-01-20T19:00:31.73660Z,1327086031.7366 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000142 2012-01-20T19:00:42.43260Z,1327086042.4326 [NAL9601](INFO): SBD MO Status=1, MOMSN=42184, MT Status=0, MTMSN=0 2012-01-20T19:00:42.61930Z,1327086042.6193 [NAL9601](INFO): Sent 194 bytes from file Logs/20120120T185928/shore0000.lzma 2012-01-20T19:00:42.61950Z,1327086042.6195 [NAL9601](INFO): Packets left to send: 0 2012-01-20T19:00:42.62060Z,1327086042.6206 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000143 2012-01-20T19:00:46.43380Z,1327086046.4338 [NAL9601](INFO): SBD MO Status=0, MOMSN=42185, MT Status=0, MTMSN=0 2012-01-20T19:00:46.64830Z,1327086046.6483 [Default:Iridium:Read_Iridium] Stopped 2012-01-20T19:00:46.64870Z,1327086046.6487 [Default:Iridium](INFO): Completed Default:Iridium 2012-01-20T19:00:46.64880Z,1327086046.6488 [Default:Iridium] Stopped 2012-01-20T19:00:46.64890Z,1327086046.6489 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-01-20T19:00:46.64900Z,1327086046.649 [Default:Iridium:A.SetSpeed] Stopped 2012-01-20T19:00:46.64910Z,1327086046.6491 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-01-20T19:00:46.64920Z,1327086046.6492 [Default:G.Wait] Running Loop=1 2012-01-20T19:00:46.64930Z,1327086046.6493 [Default:G.Wait](DEBUG): Initialize Wait Component. 2012-01-20T19:00:57.08360Z,1327086057.0836 [NAL9601](INFO): Powering down 2012-01-20T19:05:47.02820Z,1327086347.0282 [Default:CallIridium] Running Loop=1 2012-01-20T19:05:47.02840Z,1327086347.0284 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-01-20T19:05:47.02850Z,1327086347.0285 [Default:CallIridium:A] Running Loop=1 2012-01-20T19:05:47.02870Z,1327086347.0287 [Default:CallIridium:A](INFO): Aggregate::initialize Default:CallIridium:A 2012-01-20T19:05:47.02880Z,1327086347.0288 [Default:CallGPS] Running Loop=1 2012-01-20T19:05:47.02890Z,1327086347.0289 [Default:CallGPS](INFO): Aggregate::initialize Default:CallGPS 2012-01-20T19:05:47.02900Z,1327086347.029 [Default:CallGPS:A] Running Loop=1 2012-01-20T19:05:47.02920Z,1327086347.0292 [Default:CallGPS:A](INFO): Aggregate::initialize Default:CallGPS:A 2012-01-20T19:05:52.04550Z,1327086352.0455 [Default:Iridium] Running Loop=1 2012-01-20T19:05:52.04570Z,1327086352.0457 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-01-20T19:05:52.04580Z,1327086352.0458 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-01-20T19:05:52.04590Z,1327086352.0459 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-01-20T19:05:52.04600Z,1327086352.046 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-01-20T19:05:52.04610Z,1327086352.0461 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T19:05:52.04670Z,1327086352.0467 [Default:Iridium:B.GoToSurface] Stopped 2012-01-20T19:05:52.04680Z,1327086352.0468 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T19:05:52.04700Z,1327086352.047 [Default:Iridium:Read_Iridium] Running Loop=1 2012-01-20T19:05:52.04730Z,1327086352.0473 [Default:GPS] Running Loop=1 2012-01-20T19:05:52.04740Z,1327086352.0474 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-01-20T19:05:52.04750Z,1327086352.0475 [Default:GPS:A.SetSpeed] Running Loop=1 2012-01-20T19:05:52.04760Z,1327086352.0476 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-01-20T19:05:52.04770Z,1327086352.0477 [Default:GPS:B.GoToSurface] Running Loop=1 2012-01-20T19:05:52.04780Z,1327086352.0478 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T19:05:52.04840Z,1327086352.0484 [Default:GPS:B.GoToSurface] Stopped 2012-01-20T19:05:52.04850Z,1327086352.0485 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T19:05:52.04870Z,1327086352.0487 [Default:GPS:Read_GPS] Running Loop=1 2012-01-20T19:05:52.73940Z,1327086352.7394 [NAL9601](INFO): Powering up 2012-01-20T19:06:58.45140Z,1327086418.4514 [NAL9601](INFO): NAL9601 initialized 2012-01-20T19:07:17.89860Z,1327086437.8986 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42186, MT Status=1, MTMSN=3480 2012-01-20T19:07:18.11530Z,1327086438.1153 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0001.lzma 2012-01-20T19:07:18.11550Z,1327086438.1155 [NAL9601](INFO): Packets left to send: 3 2012-01-20T19:07:18.11650Z,1327086438.1165 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000144 2012-01-20T19:07:18.51620Z,1327086438.5162 [NAL9601](INFO): Received command:load Engineering/Homing_altitude.xml;set homing_altitude.Altitude 9 meter;set homing_altitude.dockBearing 330 degree;set homing_altitude.finalApproach 0.1 meter;run 2012-01-20T19:07:18.53420Z,1327086438.5342 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/Homing_altitude.xml 2012-01-20T19:07:18.53450Z,1327086438.5345 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/Homing_altitude.xml 2012-01-20T19:07:18.58910Z,1327086438.5891 [MissionManager](INFO): DefineArg homing_altitude.Wpt1Lat = 36.823 arcdeg 2012-01-20T19:07:18.59140Z,1327086438.5914 [MissionManager](INFO): DefineArg homing_altitude.Wpt1Lon = -121.83 arcdeg 2012-01-20T19:07:18.59360Z,1327086438.5936 [MissionManager](INFO): DefineArg homing_altitude.Altitude = 10 m 2012-01-20T19:07:18.59580Z,1327086438.5958 [MissionManager](INFO): DefineArg homing_altitude.Speed = 1 m/s 2012-01-20T19:07:18.59800Z,1327086438.598 [MissionManager](INFO): DefineArg homing_altitude.MassDefault = nan n/a 2012-01-20T19:07:18.60020Z,1327086438.6002 [MissionManager](INFO): DefineArg homing_altitude.BuoyancyNeutral = nan n/a 2012-01-20T19:07:18.60240Z,1327086438.6024 [MissionManager](INFO): DefineArg homing_altitude.dockBearing = 10 arcdeg 2012-01-20T19:07:18.60460Z,1327086438.6046 [MissionManager](INFO): DefineArg homing_altitude.inTrkOffset = 0 m 2012-01-20T19:07:18.60670Z,1327086438.6067 [MissionManager](INFO): DefineArg homing_altitude.xTrkOffset = 0 m 2012-01-20T19:07:18.60890Z,1327086438.6089 [MissionManager](INFO): DefineArg homing_altitude.finalApproach = 200 m 2012-01-20T19:07:18.61110Z,1327086438.6111 [MissionManager](INFO): DefineArg homing_altitude.clusterRadius = 20 m 2012-01-20T19:07:18.61340Z,1327086438.6134 [MissionManager](INFO): DefineArg homing_altitude.SampleNeilBrown = 1 n/a 2012-01-20T19:07:18.61560Z,1327086438.6156 [MissionManager](INFO): DefineArg homing_altitude.TimeoutDuration = 40 min 2012-01-20T19:07:18.61770Z,1327086438.6177 [MissionManager](INFO): DefineArg homing_altitude.kpAltitude = -0.08 n/a 2012-01-20T19:07:18.61990Z,1327086438.6199 [MissionManager](INFO): DefineArg homing_altitude.kiAltitude = 0 n/a 2012-01-20T19:07:18.62190Z,1327086438.6219 [MissionManager](INFO): DefineArg homing_altitude.kdAltitude = 0 n/a 2012-01-20T19:07:18.62340Z,1327086438.6234 [homing_altitude:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-01-20T19:07:18.62820Z,1327086438.6282 [homing_altitude:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-01-20T19:07:18.63650Z,1327086438.6365 [homing_altitude:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-01-20T19:07:18.64160Z,1327086438.6416 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-01-20T19:07:18.65940Z,1327086438.6594 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-01-20T19:07:18.66140Z,1327086438.6614 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-01-20T19:07:18.66360Z,1327086438.6636 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfaceSpeed = 0.5 m/s 2012-01-20T19:07:18.66570Z,1327086438.6657 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.IridiumTimeout = 30 min 2012-01-20T19:07:18.66660Z,1327086438.6666 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-01-20T19:07:18.67530Z,1327086438.6753 [homing_altitude:WaypointOne:B.Pitch](DEBUG): Construct. 2012-01-20T19:07:18.67980Z,1327086438.6798 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Construct Buoyancy. 2012-01-20T19:07:18.68220Z,1327086438.6822 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Construct. 2012-01-20T19:07:18.68460Z,1327086438.6846 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Construct. 2012-01-20T19:07:18.69100Z,1327086438.691 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Construct Waypoint. 2012-01-20T19:07:18.69360Z,1327086438.6936 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Construct Homing. 2012-01-20T19:07:18.73830Z,1327086438.7383 [homing_altitude:H.Execute](DEBUG): Construct Execute. 2012-01-20T19:07:18.75900Z,1327086438.759 [MissionManager](DEBUG): 36.823 -121.83 10 1 10 0 0 200 20 Set either this or PeakDetectChlActive to True in order to sample from the Neil Brown CTD. Maximum length of mission 40 PID loop parameter for following the bottom. -0.08 PID loop parameter for following the bottom. 0 PID loop parameter for following the bottom. 0 7 0 37 2.0 1 -1 restart logs 2012-01-20T19:07:18.75950Z,1327086438.7595 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/Homing_altitude.xml 2012-01-20T19:07:18.95730Z,1327086438.9573 [CommandLine](IMPORTANT): got command set homing_altitude.Altitude 9 meter 2012-01-20T19:07:19.17750Z,1327086439.1775 [CommandLine](IMPORTANT): got command set homing_altitude.dockBearing 330 degree 2012-01-20T19:07:19.41520Z,1327086439.4152 [CommandLine](IMPORTANT): got command set homing_altitude.finalApproach 0.1000000015 meter 2012-01-20T19:07:19.41650Z,1327086439.4165 [CommandLine](IMPORTANT): got command run 2012-01-20T19:07:19.41670Z,1327086439.4167 [CommandLine](IMPORTANT): Running 2012-01-20T19:07:19.46350Z,1327086439.4635 [Default] Stopped 2012-01-20T19:07:19.46360Z,1327086439.4636 [Default](INFO): Aggregate::uninitialize Default 2012-01-20T19:07:19.46370Z,1327086439.4637 [Default:GPS] Stopped 2012-01-20T19:07:19.46380Z,1327086439.4638 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-01-20T19:07:19.46390Z,1327086439.4639 [Default:GPS:A.SetSpeed] Stopped 2012-01-20T19:07:19.46400Z,1327086439.464 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-01-20T19:07:19.46410Z,1327086439.4641 [Default:GPS:Read_GPS] Stopped 2012-01-20T19:07:19.46410Z,1327086439.4641 [Default:Iridium] Stopped 2012-01-20T19:07:19.46420Z,1327086439.4642 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-01-20T19:07:19.46430Z,1327086439.4643 [Default:Iridium:A.SetSpeed] Stopped 2012-01-20T19:07:19.46440Z,1327086439.4644 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-01-20T19:07:19.46440Z,1327086439.4644 [Default:Iridium:Read_Iridium] Stopped 2012-01-20T19:07:19.46450Z,1327086439.4645 [Default:CallGPS] Stopped 2012-01-20T19:07:19.46460Z,1327086439.4646 [Default:CallGPS](INFO): Aggregate::uninitialize Default:CallGPS 2012-01-20T19:07:19.46470Z,1327086439.4647 [Default:CallGPS:A] Stopped 2012-01-20T19:07:19.46480Z,1327086439.4648 [Default:CallGPS:A](INFO): Aggregate::uninitialize Default:CallGPS:A 2012-01-20T19:07:19.46490Z,1327086439.4649 [Default:CallIridium] Stopped 2012-01-20T19:07:19.46500Z,1327086439.465 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-01-20T19:07:19.46510Z,1327086439.4651 [Default:CallIridium:A] Stopped 2012-01-20T19:07:19.46520Z,1327086439.4652 [Default:CallIridium:A](INFO): Aggregate::uninitialize Default:CallIridium:A 2012-01-20T19:07:19.46530Z,1327086439.4653 [Default:E.SetSpeed] Stopped 2012-01-20T19:07:19.46530Z,1327086439.4653 [Default:E.SetSpeed](DEBUG): Uninitialize. 2012-01-20T19:07:19.46540Z,1327086439.4654 [Default:F.GoToSurface] Stopped 2012-01-20T19:07:19.46550Z,1327086439.4655 [Default:F.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T19:07:19.46550Z,1327086439.4655 [Default:G.Wait] Stopped 2012-01-20T19:07:19.46560Z,1327086439.4656 [Default:G.Wait](DEBUG): Uninitialize Wait Component. 2012-01-20T19:07:19.46580Z,1327086439.4658 [MissionManager](IMPORTANT): Started mission homing_altitude 2012-01-20T19:07:19.46590Z,1327086439.4659 [homing_altitude] Running Loop=1 2012-01-20T19:07:19.46600Z,1327086439.466 [homing_altitude](INFO): Aggregate::initialize homing_altitude 2012-01-20T19:07:19.46610Z,1327086439.4661 [homing_altitude:A.AltitudeEnvelope] Running Loop=1 2012-01-20T19:07:19.46620Z,1327086439.4662 [homing_altitude:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-01-20T19:07:19.46630Z,1327086439.4663 [homing_altitude:B.DepthEnvelope] Running Loop=1 2012-01-20T19:07:19.46640Z,1327086439.4664 [homing_altitude:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-01-20T19:07:19.46700Z,1327086439.467 [homing_altitude:C.OffshoreEnvelope] Running Loop=1 2012-01-20T19:07:19.46710Z,1327086439.4671 [homing_altitude:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-01-20T19:07:19.46720Z,1327086439.4672 [homing_altitude:D] Running Loop=1 2012-01-20T19:07:19.46730Z,1327086439.4673 [homing_altitude:SURFACECOMMS] Running Loop=1 2012-01-20T19:07:19.46750Z,1327086439.4675 [homing_altitude:SURFACECOMMS](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS 2012-01-20T19:07:19.46760Z,1327086439.4676 [homing_altitude:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T19:07:19.46760Z,1327086439.4676 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T19:07:19.46920Z,1327086439.4692 [homing_altitude:SURFACECOMMS:B] Running Loop=1 2012-01-20T19:07:19.46940Z,1327086439.4694 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS:B 2012-01-20T19:07:19.46950Z,1327086439.4695 [homing_altitude:SURFACECOMMS:B:A] Running Loop=1 2012-01-20T19:07:19.46960Z,1327086439.4696 [homing_altitude:D] Running Loop=1 2012-01-20T19:07:19.47010Z,1327086439.4701 [homing_altitude:D](DEBUG): Initialize ReadDataComponent to sense platform_orientation 2012-01-20T19:07:19.47090Z,1327086439.4709 [homing_altitude:C.OffshoreEnvelope] Running Loop=1 2012-01-20T19:07:19.47190Z,1327086439.4719 [homing_altitude:B.DepthEnvelope] Running Loop=1 2012-01-20T19:07:19.47280Z,1327086439.4728 [homing_altitude:A.AltitudeEnvelope] Running Loop=1 2012-01-20T19:07:20.14690Z,1327086440.1469 [homing_altitude:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense time_fix 2012-01-20T19:09:02.14830Z,1327086542.1483 [NAL9601](IMPORTANT): GPS fix at: 1327086508 2012-01-20T19:09:02.16150Z,1327086542.1615 [homing_altitude:SURFACECOMMS:B:A] Stopped 2012-01-20T19:09:02.16170Z,1327086542.1617 [homing_altitude:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T19:09:02.53720Z,1327086542.5372 [homing_altitude:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-01-20T19:09:20.46190Z,1327086560.4619 [NAL9601](INFO): SBD MO Status=1, MOMSN=42187, MT Status=0, MTMSN=0 2012-01-20T19:09:20.61930Z,1327086560.6193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0001.lzma 2012-01-20T19:09:20.61960Z,1327086560.6196 [NAL9601](INFO): Packets left to send: 2 2012-01-20T19:09:20.62060Z,1327086560.6206 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000145 2012-01-20T19:09:29.36020Z,1327086569.3602 [NAL9601](INFO): SBD MO Status=1, MOMSN=42188, MT Status=0, MTMSN=0 2012-01-20T19:09:29.50730Z,1327086569.5073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0001.lzma 2012-01-20T19:09:29.50750Z,1327086569.5075 [NAL9601](INFO): Packets left to send: 1 2012-01-20T19:09:29.50930Z,1327086569.5093 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000146 2012-01-20T19:09:37.87250Z,1327086577.8725 [NAL9601](INFO): SBD MO Status=1, MOMSN=42189, MT Status=0, MTMSN=0 2012-01-20T19:09:37.99130Z,1327086577.9913 [NAL9601](INFO): Sent 121 bytes from file Logs/20120120T185928/shore0001.lzma 2012-01-20T19:09:37.99150Z,1327086577.9915 [NAL9601](INFO): Packets left to send: 0 2012-01-20T19:09:37.99250Z,1327086577.9925 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000147 2012-01-20T19:09:45.07370Z,1327086585.0737 [NAL9601](INFO): SBD MO Status=0, MOMSN=42190, MT Status=0, MTMSN=0 2012-01-20T19:09:57.78700Z,1327086597.787 [NAL9601](INFO): SBD MO Status=1, MOMSN=42191, MT Status=0, MTMSN=0 2012-01-20T19:09:57.95940Z,1327086597.9594 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0002.lzma 2012-01-20T19:09:57.95960Z,1327086597.9596 [NAL9601](INFO): Packets left to send: 4 2012-01-20T19:09:57.96070Z,1327086597.9607 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000148 2012-01-20T19:10:09.48470Z,1327086609.4847 [NAL9601](INFO): SBD MO Status=1, MOMSN=42192, MT Status=0, MTMSN=0 2012-01-20T19:10:09.64330Z,1327086609.6433 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0002.lzma 2012-01-20T19:10:09.64350Z,1327086609.6435 [NAL9601](INFO): Packets left to send: 3 2012-01-20T19:10:09.64460Z,1327086609.6446 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000149 2012-01-20T19:10:22.02610Z,1327086622.0261 [NAL9601](INFO): SBD MO Status=1, MOMSN=42193, MT Status=0, MTMSN=0 2012-01-20T19:10:22.22730Z,1327086622.2273 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0002.lzma 2012-01-20T19:10:22.22750Z,1327086622.2275 [NAL9601](INFO): Packets left to send: 2 2012-01-20T19:10:22.22940Z,1327086622.2294 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000150 2012-01-20T19:10:30.52110Z,1327086630.5211 [NAL9601](INFO): SBD MO Status=1, MOMSN=42194, MT Status=0, MTMSN=0 2012-01-20T19:10:30.71130Z,1327086630.7113 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0002.lzma 2012-01-20T19:10:30.71160Z,1327086630.7116 [NAL9601](INFO): Packets left to send: 1 2012-01-20T19:10:30.71260Z,1327086630.7126 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000151 2012-01-20T19:10:41.20970Z,1327086641.2097 [NAL9601](INFO): SBD MO Status=1, MOMSN=42195, MT Status=0, MTMSN=0 2012-01-20T19:10:41.39540Z,1327086641.3954 [NAL9601](INFO): Sent 105 bytes from file Logs/20120120T185928/shore0002.lzma 2012-01-20T19:10:41.39560Z,1327086641.3956 [NAL9601](INFO): Packets left to send: 0 2012-01-20T19:10:41.39670Z,1327086641.3967 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000152 2012-01-20T19:10:45.20600Z,1327086645.206 [NAL9601](INFO): SBD MO Status=0, MOMSN=42196, MT Status=0, MTMSN=0 2012-01-20T19:10:45.40080Z,1327086645.4008 [homing_altitude:SURFACECOMMS:B:B] Stopped 2012-01-20T19:10:45.40100Z,1327086645.401 [homing_altitude:SURFACECOMMS:B:C] Running Loop=1 2012-01-20T19:10:45.65700Z,1327086645.657 [homing_altitude:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2012-01-20T19:10:47.60710Z,1327086647.6071 [NAL9601](IMPORTANT): GPS fix at: 1327086614 2012-01-20T19:10:47.62060Z,1327086647.6206 [homing_altitude:SURFACECOMMS:B:C] Stopped 2012-01-20T19:10:47.62080Z,1327086647.6208 [homing_altitude:SURFACECOMMS:B](INFO): Completed homing_altitude:SURFACECOMMS:B 2012-01-20T19:10:47.62090Z,1327086647.6209 [homing_altitude:SURFACECOMMS:B] Stopped 2012-01-20T19:10:47.62100Z,1327086647.621 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS:B 2012-01-20T19:10:47.62150Z,1327086647.6215 [homing_altitude:SURFACECOMMS](INFO): Completed homing_altitude:SURFACECOMMS 2012-01-20T19:10:47.62150Z,1327086647.6215 [homing_altitude:SURFACECOMMS] Stopped 2012-01-20T19:10:47.62160Z,1327086647.6216 [homing_altitude:SURFACECOMMS](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS 2012-01-20T19:10:47.62170Z,1327086647.6217 [homing_altitude:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T19:10:47.62180Z,1327086647.6218 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T19:10:47.62190Z,1327086647.6219 [homing_altitude:WaypointOne] Running Loop=1 2012-01-20T19:10:47.62210Z,1327086647.6221 [homing_altitude:WaypointOne](INFO): Aggregate::initialize homing_altitude:WaypointOne 2012-01-20T19:10:47.62220Z,1327086647.6222 [homing_altitude:WaypointOne:A] Running Loop=1 2012-01-20T19:10:47.62220Z,1327086647.6222 [homing_altitude:WaypointOne:B.Pitch] Running Loop=1 2012-01-20T19:10:47.62230Z,1327086647.6223 [homing_altitude:WaypointOne:B.Pitch](DEBUG): Initialize. 2012-01-20T19:10:47.62250Z,1327086647.6225 [homing_altitude:WaypointOne:C.Buoyancy] Running Loop=1 2012-01-20T19:10:47.62260Z,1327086647.6226 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-01-20T19:10:47.62300Z,1327086647.623 [homing_altitude:WaypointOne:D.SetSpeed] Running Loop=1 2012-01-20T19:10:47.62310Z,1327086647.6231 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Initialize. 2012-01-20T19:10:47.62320Z,1327086647.6232 [homing_altitude:WaypointOne:E.PitchServo] Running Loop=1 2012-01-20T19:10:47.62330Z,1327086647.6233 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Initialize. 2012-01-20T19:10:47.62360Z,1327086647.6236 [homing_altitude:WaypointOne:E.PitchServo](INFO): Initialize with holdValue=height_above_sea_floor 2012-01-20T19:10:47.62440Z,1327086647.6244 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint] Running Loop=1 2012-01-20T19:10:47.62450Z,1327086647.6245 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Initialize HomingComponent. 2012-01-20T19:10:47.62460Z,1327086647.6246 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Initialize WaypointComponent. 2012-01-20T19:10:48.00880Z,1327086648.0088 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon is in the DAT FOV with dtw = 924.06. Turning DAT on. 2012-01-20T19:10:48.01050Z,1327086648.0105 [homing_altitude:WaypointOne:E.PitchServo] Running Loop=1 2012-01-20T19:10:48.01140Z,1327086648.0114 [homing_altitude:WaypointOne:D.SetSpeed] Running Loop=1 2012-01-20T19:10:48.01220Z,1327086648.0122 [homing_altitude:WaypointOne:C.Buoyancy] Running Loop=1 2012-01-20T19:10:48.01310Z,1327086648.0131 [homing_altitude:WaypointOne:B.Pitch] Running Loop=1 2012-01-20T19:10:48.01390Z,1327086648.0139 [homing_altitude:WaypointOne:A] Running Loop=1 2012-01-20T19:10:48.01440Z,1327086648.0144 [homing_altitude:WaypointOne:A](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-01-20T19:10:48.01490Z,1327086648.0149 [homing_altitude:WaypointOne:A](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-01-20T19:10:48.41390Z,1327086648.4139 [DAT](INFO): Powering up 2012-01-20T19:10:53.95180Z,1327086653.9518 [NAL9601](INFO): Powering down 2012-01-20T19:11:08.32500Z,1327086668.325 [Radio_Freewave](INFO): Powering down 2012-01-20T19:11:15.97490Z,1327086675.9749 [DAT](ERROR): No response from remote modem 2012-01-20T19:11:21.11940Z,1327086681.1194 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 1 transponder hits. 2012-01-20T19:11:31.59610Z,1327086691.5961 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 2 transponder hits. 2012-01-20T19:11:33.55550Z,1327086693.5555 [Radio_Freewave](INFO): Powering up 2012-01-20T19:11:36.70340Z,1327086696.7034 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 3 transponder hits. 2012-01-20T19:11:41.87820Z,1327086701.8782 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 4 transponder hits. 2012-01-20T19:11:51.03720Z,1327086711.0372 [Radio_Freewave](INFO): Powering down 2012-01-20T19:11:54.37090Z,1327086714.3709 [DAT](ERROR): No response from remote modem 2012-01-20T19:11:59.50820Z,1327086719.5082 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 5 transponder hits; the filter is now engaged. 2012-01-20T19:11:59.50850Z,1327086719.5085 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The vehicle lat,lon is (36.8309, -121.8313) with dtw = 900.7 2012-01-20T19:11:59.50940Z,1327086719.5094 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Pure pursuit active. dtw = 900.4 m. 2012-01-20T19:12:01.94360Z,1327086721.9436 [Radio_Freewave](INFO): Powering up 2012-01-20T19:12:10.61130Z,1327086730.6113 [AHRS_sp3003D](FAULT): Read Acceleration Vector Failure. 2012-01-20T19:12:10.61140Z,1327086730.6114 [AHRS_sp3003D] Communications Fault, FailCount= 1 2012-01-20T19:12:10.61140Z,1327086730.6114 [AHRS_sp3003D](ERROR): Communications Fault 2012-01-20T19:12:10.80530Z,1327086730.8053 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D 2012-01-20T19:12:10.92830Z,1327086730.9283 [AHRS_sp3003D](INFO): Powering down 2012-01-20T19:12:11.14870Z,1327086731.1487 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D 2012-01-20T19:12:11.14880Z,1327086731.1488 [AHRS_sp3003D] No Fault, FailCount= 1 2012-01-20T19:12:12.46530Z,1327086732.4653 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-01-20T19:12:18.11500Z,1327086738.115 [DAT](ERROR): No response from remote modem 2012-01-20T19:12:23.23250Z,1327086743.2325 [Radio_Freewave](INFO): Powering down 2012-01-20T19:12:23.65320Z,1327086743.6532 [Radio_Freewave](INFO): Powering up 2012-01-20T19:12:35.82300Z,1327086755.823 [DAT](ERROR): No response from remote modem 2012-01-20T19:12:48.61890Z,1327086768.6189 [DAT](ERROR): No response from remote modem 2012-01-20T19:13:06.56300Z,1327086786.563 [DAT](ERROR): No response from remote modem 2012-01-20T19:13:29.49890Z,1327086809.4989 [DAT](ERROR): No response from remote modem 2012-01-20T19:13:58.21770Z,1327086838.2177 [Radio_Freewave](INFO): Powering down 2012-01-20T19:14:01.37920Z,1327086841.3792 [Radio_Freewave](INFO): Powering up 2012-01-20T19:14:24.30690Z,1327086864.3069 [DAT](ERROR): No response from remote modem 2012-01-20T19:14:58.16050Z,1327086898.1605 [Radio_Freewave](INFO): Powering down 2012-01-20T19:14:59.94000Z,1327086899.94 [Radio_Freewave](INFO): Powering up 2012-01-20T19:15:04.20820Z,1327086904.2082 [Radio_Freewave](INFO): Powering down 2012-01-20T19:15:09.07670Z,1327086909.0767 [Radio_Freewave](INFO): Powering up 2012-01-20T19:15:11.95890Z,1327086911.9589 [DAT](ERROR): No response from remote modem 2012-01-20T19:15:16.98070Z,1327086916.9807 [CTD_NeilBrown](ERROR): Salinity reading out of range: 25.07777977 psu 2012-01-20T19:15:19.76920Z,1327086919.7692 [Radio_Freewave](INFO): Powering down 2012-01-20T19:15:26.58320Z,1327086926.5832 [Radio_Freewave](INFO): Powering up 2012-01-20T19:15:32.28050Z,1327086932.2805 [Radio_Freewave](INFO): Powering down 2012-01-20T19:15:35.05840Z,1327086935.0584 [Radio_Freewave](INFO): Powering up 2012-01-20T19:16:08.06710Z,1327086968.0671 [DAT](ERROR): No response from remote modem 2012-01-20T19:16:18.73310Z,1327086978.7331 [Radio_Freewave](INFO): Powering down 2012-01-20T19:16:25.14350Z,1327086985.1435 [Radio_Freewave](INFO): Powering up 2012-01-20T19:16:25.60690Z,1327086985.6069 [DAT](ERROR): No response from remote modem 2012-01-20T19:16:39.01730Z,1327086999.0173 [Radio_Freewave](INFO): Powering down 2012-01-20T19:16:41.82340Z,1327087001.8234 [Radio_Freewave](INFO): Powering up 2012-01-20T19:16:45.83700Z,1327087005.837 [Radio_Freewave](INFO): Powering down 2012-01-20T19:16:49.45090Z,1327087009.4509 [Radio_Freewave](INFO): Powering up 2012-01-20T19:16:50.66310Z,1327087010.6631 [CTD_NeilBrown](ERROR): Salinity reading out of range: 27.27346039 psu 2012-01-20T19:16:55.50910Z,1327087015.5091 [Radio_Freewave](INFO): Powering down 2012-01-20T19:16:56.48530Z,1327087016.4853 [Radio_Freewave](INFO): Powering up 2012-01-20T19:17:06.96690Z,1327087026.9669 [DAT](ERROR): No response from remote modem 2012-01-20T19:17:24.27490Z,1327087044.2749 [DAT](ERROR): No response from remote modem 2012-01-20T19:17:34.78990Z,1327087054.7899 [Radio_Freewave](INFO): Powering down 2012-01-20T19:17:35.19810Z,1327087055.1981 [Radio_Freewave](INFO): Powering up 2012-01-20T19:17:51.66690Z,1327087071.6669 [DAT](ERROR): No response from remote modem 2012-01-20T19:18:28.09490Z,1327087108.0949 [DAT](ERROR): No response from remote modem 2012-01-20T19:19:18.83490Z,1327087158.8349 [DAT](ERROR): No response from remote modem 2012-01-20T19:20:11.24340Z,1327087211.2434 [Radio_Freewave](INFO): Powering down 2012-01-20T19:20:14.84560Z,1327087214.8456 [Radio_Freewave](INFO): Powering up 2012-01-20T19:21:33.71740Z,1327087293.7174 [Radio_Freewave](INFO): Powering down 2012-01-20T19:21:37.32340Z,1327087297.3234 [Radio_Freewave](INFO): Powering up 2012-01-20T19:21:40.92800Z,1327087300.928 [Radio_Freewave](INFO): Powering down 2012-01-20T19:21:45.72060Z,1327087305.7206 [CTD_NeilBrown](ERROR): Salinity reading out of range: 23.16613007 psu 2012-01-20T19:21:46.17650Z,1327087306.1765 [Radio_Freewave](INFO): Powering up 2012-01-20T19:22:01.75670Z,1327087321.7567 [Radio_Freewave](INFO): Powering down 2012-01-20T19:22:02.55660Z,1327087322.5566 [Radio_Freewave](INFO): Powering up 2012-01-20T19:24:12.60030Z,1327087452.6003 [Radio_Freewave](INFO): Powering down 2012-01-20T19:24:15.36130Z,1327087455.3613 [Radio_Freewave](INFO): Powering up 2012-01-20T19:24:16.54830Z,1327087456.5483 [DAT](ERROR): Unexpected response:20:24:19.5682 , LVL= 32752, 32305, 32754, 32755, AGC= 63, IDX= 66-0.08,-2.579, |v|= 0.739, RAW= 277.9, 0.3, CAL= 289.2,- 0.5, ROT= 8.2,- 0.5 Compass 346.6 Range 1 to 0 : 362.8 m 2012-01-20T19:25:14.70670Z,1327087514.7067 [Radio_Freewave](INFO): Powering down 2012-01-20T19:25:18.27460Z,1327087518.2746 [DAT](ERROR): Unexpected response:20:25:21.2630 , LVL= 32752, 32753, 32754, 32755, AGC= 63, IDX= 66 0.04, 1.899, |v|= 0.738, RAW= 280.9,- 2.1, CAL= 293.8,- 3.2, ROT= 12.8,- 3.2 Compass 356.8 Range 1 to 0 : 318.4 m 2012-01-20T19:25:18.68990Z,1327087518.6899 [Radio_Freewave](INFO): Powering up 2012-01-20T19:26:39.00100Z,1327087599.001 [Radio_Freewave](INFO): Powering down 2012-01-20T19:26:41.42590Z,1327087601.4259 [Radio_Freewave](INFO): Powering up 2012-01-20T19:26:43.33240Z,1327087603.3324 [DAT](ERROR): Unexpected response:20:26:46.2382 , LVL= 32752, 32753, 32402, 32755, AGC= 57, IDX= 14-0.13,-2.715, |v|= 0.735, RAW= 279.1, 0.5, CAL= 290.9,- 0.4, ROT= 9.9,- 0.4 Compass 1.9 Range 1 to 0 : 258.3 m 2012-01-20T19:27:00.86880Z,1327087620.8688 [DAT](ERROR): Unexpected response:20:27:04.1204 , LVL= 32752, 29393, 31346, 32755, AGC= 62, IDX= 66 0.18, 2.772, |v|= 0.725, RAW= 282.7,- 1.0, CAL= 296.4,- 1.8, ROT= 15.4,- 1.8 Compass 355.8 Range 1 to 0 : 244.1 m 2012-01-20T19:27:31.77840Z,1327087651.7784 [Radio_Freewave](INFO): Powering down 2012-01-20T19:27:33.76430Z,1327087653.7643 [Radio_Freewave](INFO): Powering up 2012-01-20T19:27:55.77560Z,1327087675.7756 [DAT](ERROR): Unexpected response:20:27:59.2727 , LVL= 32752, 32753, 32754, 32755, AGC= 56, IDX= 2 0.25,-1.822, |v|= 0.791, RAW= 295.7,- 5.5, CAL= 311.4,- 6.4, ROT= 30.4,- 6.4 Compass 349.6 Range 1 to 0 : 205.6 m 2012-01-20T19:28:17.50150Z,1327087697.5015 [DAT](ERROR): Unexpected response:20:28:20.7031 , LVL= 32752, 32753, 32754, 32755, AGC= 57, IDX= 66 0.10,-1.131, |v|= 0.783, RAW= 291.5,- 2.1, CAL= 306.9,- 2.0, ROT= 25.9,- 2.0 Compass 347.7 Range 1 to 0 : 188.7 m 2012-01-20T19:28:21.46100Z,1327087701.461 [DAT](ERROR): Unexpected response:20:28:24.9982 , LVL= 32752, 31393, 32754, 32755, AGC= 60, IDX= 66 0.35,-0.017, |v|= 0.785, RAW= 291.9,- 0.7, CAL= 307.1,- 0.2, ROT= 26.1,- 0.2 Compass 344.8 Range 1 to 0 : 186.1 m 2012-01-20T19:28:21.46430Z,1327087701.4643 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.17959976 psu 2012-01-20T19:28:23.86450Z,1327087703.8645 [Radio_Freewave](INFO): Powering down 2012-01-20T19:28:29.52080Z,1327087709.5208 [Radio_Freewave](INFO): Powering up 2012-01-20T19:28:29.89250Z,1327087709.8925 [DAT](ERROR): Unexpected response:20:28:33.3974 , LVL= 32752, 32753, 32754, 32755, AGC= 58, IDX= 66-0.16,-0.305, |v|= 0.675, RAW= 285.2,- 3.7, CAL= 300.1,- 4.6, ROT= 19.1,- 4.6 Compass 338.4 Range 1 to 0 : 185.1 m 2012-01-20T19:28:42.42560Z,1327087722.4256 [DAT](ERROR): Unexpected response:20:28:45.8829 , LVL= 32752, 28769, 29026, 32755, AGC= 59, IDX= 34-0.20, 0.007, |v|= 0.692, RAW= 285.9,- 0.4, CAL= 300.7,- 0.8, ROT= 19.7,- 0.8 Compass 334.3 Range 1 to 0 : 173.6 m 2012-01-20T19:28:46.93710Z,1327087726.9371 [DAT](ERROR): Unexpected response:20:28:50.4824 , LVL= 32752, 32753, 32754, 32755, AGC= 58, IDX= 66-0.14, 0.400, |v|= 0.645, RAW= 289.9,- 0.8, CAL= 305.0,- 0.6, ROT= 24.0,- 0.6 Compass 338.7 Range 1 to 0 : 172.9 m 2012-01-20T19:28:55.71470Z,1327087735.7147 [Radio_Freewave](INFO): Powering down 2012-01-20T19:28:58.14710Z,1327087738.1471 [Radio_Freewave](INFO): Powering up 2012-01-20T19:28:59.72860Z,1327087739.7286 [DAT](ERROR): Unexpected response:20:29:02.9719 , LVL= 32752, 32753, 32754, 32755, AGC= 59, IDX= 66 0.08,-1.749, |v|= 0.765, RAW= 287.5,- 0.9, CAL= 302.5,- 1.2, ROT= 21.5,- 1.2 Compass 340.6 Range 1 to 0 : 164.5 m 2012-01-20T19:29:16.46960Z,1327087756.4696 [DAT](ERROR): Unexpected response:20:29:20.0496 , LVL= 32752, 25073, 32754, 32755, AGC= 58, IDX=152-0.30,-2.999, |v|= 0.738, RAW= 292.9,- 1.5, CAL= 308.3,- 1.0, ROT= 27.3,- 1.0 Compass 336.9 Range 1 to 0 : 146.9 m 2012-01-20T19:29:28.79470Z,1327087768.7947 [DAT](ERROR): Unexpected response:20:29:32.3899 , LVL= 32752, 32753, 32754, 32755, AGC= 59, IDX= 64 0.39,-2.887, |v|= 0.726, RAW= 294.1,- 3.5, CAL= 309.7,- 3.5, ROT= 28.7,- 3.5 Compass 328.6 Range 1 to 0 : 139.1 m 2012-01-20T19:29:57.58690Z,1327087797.5869 [DAT](ERROR): Unexpected response:20:30:01.2667 , LVL= 32752, 21073, 32754, 32755, AGC= 54, IDX= 66-0.40,-1.033, |v|= 0.753, RAW= 304.8,- 3.2, CAL= 319.9,- 4.5, ROT= 38.9,- 4.5 Compass 335.1 Range 1 to 0 : 120.3 m 2012-01-20T19:30:02.38200Z,1327087802.382 [Radio_Freewave](INFO): Powering down 2012-01-20T19:30:03.20900Z,1327087803.209 [Radio_Freewave](INFO): Powering up 2012-01-20T19:30:06.05760Z,1327087806.0576 [DAT](ERROR): Unexpected response:20:30:09.3602 , LVL= 32752, 20657, 32754, 32755, AGC= 50, IDX= 64 0.31, 1.504, |v|= 0.734, RAW= 295.1,- 3.7, CAL= 310.7,- 3.8, ROT= 29.7,- 3.8 Compass 338.8 Range 1 to 0 : 115.0 m 2012-01-20T19:30:10.08900Z,1327087810.089 [DAT](ERROR): Unexpected response:20:30:13.5569 , LVL= 32752, 25073, 31282, 32755, AGC= 49, IDX= 32 0.15,-0.930, |v|= 0.771, RAW= 296.4,- 8.6, CAL= 312.3,-10.8, ROT= 31.3,-10.8 Compass 336.7 Range 1 to 0 : 112.3 m 2012-01-20T19:30:12.47790Z,1327087812.4779 [Radio_Freewave](INFO): Powering down 2012-01-20T19:30:14.49000Z,1327087814.49 [Radio_Freewave](INFO): Powering up 2012-01-20T19:30:18.56070Z,1327087818.5607 [DAT](ERROR): Unexpected response:20:30:22.0997 , LVL= 32752, 27169, 32754, 32755, AGC= 55, IDX= 66-0.15,-2.585, |v|= 0.768, RAW= 298.5,- 2.6, CAL= 313.9,- 2.8, ROT= 32.9,- 2.8 Compass 347.3 Range 1 to 0 : 106.5 m 2012-01-20T19:30:38.67350Z,1327087838.6735 [DAT](ERROR): Unexpected response:20:30:42.4345 , LVL= 32752, 32497, 32754, 32755, AGC= 49, IDX= 32 0.32,-1.600, |v|= 0.782, RAW= 318.8,- 9.2, CAL= 336.1,-13.6, ROT= 55.1,-13.6 Compass 339.4 Range 1 to 0 : 94.2 m 2012-01-20T19:30:42.56860Z,1327087842.5686 [DAT](ERROR): Unexpected response:20:30:46.2818 , LVL= 32752, 28529, 32754, 32755, AGC= 54, IDX= 66 0.22, 1.509, |v|= 0.739, RAW= 318.7,- 3.8, CAL= 337.2,- 7.9, ROT= 56.2,- 7.9 Compass 346.9 Range 1 to 0 : 92.0 m 2012-01-20T19:30:44.56170Z,1327087844.5617 [Radio_Freewave](INFO): Powering down 2012-01-20T19:30:46.57240Z,1327087846.5724 [Radio_Freewave](INFO): Powering up 2012-01-20T19:30:54.90800Z,1327087854.908 [DAT](ERROR): Unexpected response:20:30:58.6729 , LVL= 32752, 27425, 32754, 32755, AGC= 56, IDX= 66 0.36,-1.101, |v|= 0.726, RAW= 316.1,- 0.2, CAL= 335.1,- 3.1, ROT= 54.1,- 3.1 Compass 335.0 Range 1 to 0 : 84.7 m 2012-01-20T19:30:57.71540Z,1327087857.7154 [Radio_Freewave](INFO): Powering down 2012-01-20T19:30:59.38980Z,1327087859.3898 [Radio_Freewave](INFO): Powering up 2012-01-20T19:31:32.68020Z,1327087892.6802 [Radio_Freewave](INFO): Powering down 2012-01-20T19:31:34.30150Z,1327087894.3015 [Radio_Freewave](INFO): Powering up 2012-01-20T19:31:49.67710Z,1327087909.6771 [Radio_Freewave](INFO): Powering down 2012-01-20T19:31:53.30050Z,1327087913.3005 [Radio_Freewave](INFO): Powering up 2012-01-20T19:32:39.12310Z,1327087959.1231 [Radio_Freewave](INFO): Powering down 2012-01-20T19:32:43.13600Z,1327087963.136 [Radio_Freewave](INFO): Powering up 2012-01-20T19:32:57.58400Z,1327087977.584 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](IMPORTANT): Homing:: Terminating now. dtw = -0.2 m, DATRange = 37.5 m. 2012-01-20T19:32:57.58460Z,1327087977.5846 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](INFO): Homing::Reached Waypoint 36.82319799,-121.8296114 2012-01-20T19:32:57.58480Z,1327087977.5848 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint] Stopped 2012-01-20T19:32:57.58490Z,1327087977.5849 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Uninitialize HomingComponent. 2012-01-20T19:32:57.58630Z,1327087977.5863 [homing_altitude:WaypointOne](INFO): Completed homing_altitude:WaypointOne 2012-01-20T19:32:57.58640Z,1327087977.5864 [homing_altitude:WaypointOne] Stopped 2012-01-20T19:32:57.58660Z,1327087977.5866 [homing_altitude:WaypointOne](INFO): Aggregate::uninitialize homing_altitude:WaypointOne 2012-01-20T19:32:57.58660Z,1327087977.5866 [homing_altitude:WaypointOne:A] Stopped 2012-01-20T19:32:57.58670Z,1327087977.5867 [homing_altitude:WaypointOne:B.Pitch] Stopped 2012-01-20T19:32:57.58670Z,1327087977.5867 [homing_altitude:WaypointOne:C.Buoyancy] Stopped 2012-01-20T19:32:57.58700Z,1327087977.587 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-01-20T19:32:57.58710Z,1327087977.5871 [homing_altitude:WaypointOne:D.SetSpeed] Stopped 2012-01-20T19:32:57.58710Z,1327087977.5871 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Uninitialize. 2012-01-20T19:32:57.58720Z,1327087977.5872 [homing_altitude:WaypointOne:E.PitchServo] Stopped 2012-01-20T19:32:57.58730Z,1327087977.5873 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Uninitialize. 2012-01-20T19:32:57.58740Z,1327087977.5874 [homing_altitude:PHONEHOMEWPT1] Running Loop=1 2012-01-20T19:32:57.58750Z,1327087977.5875 [homing_altitude:PHONEHOMEWPT1](INFO): Aggregate::initialize homing_altitude:PHONEHOMEWPT1 2012-01-20T19:32:57.98640Z,1327087977.9864 [homing_altitude:SURFACECOMMS] Running Loop=1 2012-01-20T19:32:57.98660Z,1327087977.9866 [homing_altitude:SURFACECOMMS](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS 2012-01-20T19:32:57.98670Z,1327087977.9867 [homing_altitude:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T19:32:57.98690Z,1327087977.9869 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T19:32:57.98810Z,1327087977.9881 [homing_altitude:SURFACECOMMS:B] Running Loop=1 2012-01-20T19:32:57.98820Z,1327087977.9882 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS:B 2012-01-20T19:32:57.98840Z,1327087977.9884 [homing_altitude:SURFACECOMMS:B:A] Running Loop=1 2012-01-20T19:32:58.44930Z,1327087978.4493 [DAT](INFO): Powering down 2012-01-20T19:32:59.45170Z,1327087979.4517 [NAL9601](INFO): Powering up 2012-01-20T19:34:05.06350Z,1327088045.0635 [NAL9601](INFO): NAL9601 initialized 2012-01-20T19:34:06.31180Z,1327088046.3118 [NAL9601](IMPORTANT): GPS fix at: 1327088014 2012-01-20T19:34:06.32540Z,1327088046.3254 [homing_altitude:SURFACECOMMS:B:A] Stopped 2012-01-20T19:34:06.32570Z,1327088046.3257 [homing_altitude:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T19:34:22.56050Z,1327088062.5605 [NAL9601](INFO): SBD MO Status=1, MOMSN=42197, MT Status=0, MTMSN=0 2012-01-20T19:34:22.72330Z,1327088062.7233 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:34:22.72350Z,1327088062.7235 [NAL9601](INFO): Packets left to send: 36 2012-01-20T19:34:22.72790Z,1327088062.7279 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000153 2012-01-20T19:34:29.85290Z,1327088069.8529 [NAL9601](INFO): SBD MO Status=1, MOMSN=42198, MT Status=0, MTMSN=0 2012-01-20T19:34:30.01530Z,1327088070.0153 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:34:30.01550Z,1327088070.0155 [NAL9601](INFO): Packets left to send: 35 2012-01-20T19:34:30.01660Z,1327088070.0166 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000154 2012-01-20T19:34:39.54420Z,1327088079.5442 [NAL9601](INFO): SBD MO Status=1, MOMSN=42199, MT Status=0, MTMSN=0 2012-01-20T19:34:39.69930Z,1327088079.6993 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:34:39.69960Z,1327088079.6996 [NAL9601](INFO): Packets left to send: 34 2012-01-20T19:34:39.70060Z,1327088079.7006 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000155 2012-01-20T19:34:46.95240Z,1327088086.9524 [NAL9601](INFO): SBD MO Status=1, MOMSN=42200, MT Status=0, MTMSN=0 2012-01-20T19:34:47.08730Z,1327088087.0873 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:34:47.08760Z,1327088087.0876 [NAL9601](INFO): Packets left to send: 33 2012-01-20T19:34:47.08870Z,1327088087.0887 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000156 2012-01-20T19:34:54.38580Z,1327088094.3858 [NAL9601](INFO): SBD MO Status=1, MOMSN=42201, MT Status=0, MTMSN=0 2012-01-20T19:34:54.57530Z,1327088094.5753 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:34:54.57550Z,1327088094.5755 [NAL9601](INFO): Packets left to send: 32 2012-01-20T19:34:54.57660Z,1327088094.5766 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000157 2012-01-20T19:35:01.72040Z,1327088101.7204 [NAL9601](INFO): SBD MO Status=1, MOMSN=42202, MT Status=0, MTMSN=0 2012-01-20T19:35:01.86330Z,1327088101.8633 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:35:01.86350Z,1327088101.8635 [NAL9601](INFO): Packets left to send: 31 2012-01-20T19:35:01.86460Z,1327088101.8646 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000158 2012-01-20T19:35:09.51500Z,1327088109.515 [NAL9601](INFO): SBD MO Status=1, MOMSN=42203, MT Status=0, MTMSN=0 2012-01-20T19:35:09.65130Z,1327088109.6513 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:35:09.65150Z,1327088109.6515 [NAL9601](INFO): Packets left to send: 30 2012-01-20T19:35:09.65260Z,1327088109.6526 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000159 2012-01-20T19:35:18.05500Z,1327088118.055 [NAL9601](INFO): SBD MO Status=1, MOMSN=42204, MT Status=0, MTMSN=0 2012-01-20T19:35:18.23950Z,1327088118.2395 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:35:18.23970Z,1327088118.2397 [NAL9601](INFO): Packets left to send: 29 2012-01-20T19:35:18.24080Z,1327088118.2408 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000160 2012-01-20T19:35:26.54590Z,1327088126.5459 [NAL9601](INFO): SBD MO Status=1, MOMSN=42205, MT Status=0, MTMSN=0 2012-01-20T19:35:26.72730Z,1327088126.7273 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:35:26.72750Z,1327088126.7275 [NAL9601](INFO): Packets left to send: 28 2012-01-20T19:35:26.72860Z,1327088126.7286 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000161 2012-01-20T19:35:35.53310Z,1327088135.5331 [NAL9601](INFO): SBD MO Status=1, MOMSN=42206, MT Status=0, MTMSN=0 2012-01-20T19:35:35.71140Z,1327088135.7114 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:35:35.71160Z,1327088135.7116 [NAL9601](INFO): Packets left to send: 27 2012-01-20T19:35:35.71270Z,1327088135.7127 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000162 2012-01-20T19:35:44.49390Z,1327088144.4939 [NAL9601](INFO): SBD MO Status=1, MOMSN=42207, MT Status=0, MTMSN=0 2012-01-20T19:35:44.69940Z,1327088144.6994 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:35:44.69960Z,1327088144.6996 [NAL9601](INFO): Packets left to send: 26 2012-01-20T19:35:44.70070Z,1327088144.7007 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000163 2012-01-20T19:35:53.01460Z,1327088153.0146 [NAL9601](INFO): SBD MO Status=1, MOMSN=42208, MT Status=0, MTMSN=0 2012-01-20T19:35:53.18340Z,1327088153.1834 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:35:53.18360Z,1327088153.1836 [NAL9601](INFO): Packets left to send: 25 2012-01-20T19:35:53.18470Z,1327088153.1847 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000164 2012-01-20T19:36:02.76900Z,1327088162.769 [NAL9601](INFO): SBD MO Status=1, MOMSN=42209, MT Status=0, MTMSN=0 2012-01-20T19:36:02.96730Z,1327088162.9673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:36:02.96760Z,1327088162.9676 [NAL9601](INFO): Packets left to send: 24 2012-01-20T19:36:02.96870Z,1327088162.9687 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000165 2012-01-20T19:36:10.88590Z,1327088170.8859 [NAL9601](INFO): SBD MO Status=1, MOMSN=42210, MT Status=0, MTMSN=0 2012-01-20T19:36:11.05940Z,1327088171.0594 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:36:11.05960Z,1327088171.0596 [NAL9601](INFO): Packets left to send: 23 2012-01-20T19:36:11.06070Z,1327088171.0607 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000166 2012-01-20T19:36:18.59100Z,1327088178.591 [NAL9601](INFO): SBD MO Status=1, MOMSN=42211, MT Status=0, MTMSN=0 2012-01-20T19:36:18.74330Z,1327088178.7433 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:36:18.74350Z,1327088178.7435 [NAL9601](INFO): Packets left to send: 22 2012-01-20T19:36:18.74480Z,1327088178.7448 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000167 2012-01-20T19:36:27.09650Z,1327088187.0965 [NAL9601](INFO): SBD MO Status=1, MOMSN=42212, MT Status=0, MTMSN=0 2012-01-20T19:36:27.23130Z,1327088187.2313 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:36:27.23150Z,1327088187.2315 [NAL9601](INFO): Packets left to send: 21 2012-01-20T19:36:27.23260Z,1327088187.2326 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000168 2012-01-20T19:36:35.66950Z,1327088195.6695 [NAL9601](INFO): SBD MO Status=1, MOMSN=42213, MT Status=0, MTMSN=0 2012-01-20T19:36:35.81930Z,1327088195.8193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:36:35.81960Z,1327088195.8196 [NAL9601](INFO): Packets left to send: 20 2012-01-20T19:36:35.82090Z,1327088195.8209 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000169 2012-01-20T19:36:47.32430Z,1327088207.3243 [NAL9601](INFO): SBD MO Status=1, MOMSN=42214, MT Status=0, MTMSN=0 2012-01-20T19:36:47.49930Z,1327088207.4993 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:36:47.49950Z,1327088207.4995 [NAL9601](INFO): Packets left to send: 19 2012-01-20T19:36:47.54170Z,1327088207.5417 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000170 2012-01-20T19:36:59.11390Z,1327088219.1139 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42215, MT Status=1, MTMSN=3481 2012-01-20T19:36:59.27930Z,1327088219.2793 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:36:59.27960Z,1327088219.2796 [NAL9601](INFO): Packets left to send: 18 2012-01-20T19:36:59.28070Z,1327088219.2807 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000171 2012-01-20T19:36:59.75660Z,1327088219.7566 [NAL9601](INFO): Received command:load Transport/transit_3km.xml;set transit_3km.Wpt1Lat 36.828 degree;set transit_3km.Wpt1Lon -121.831 degree;run 2012-01-20T19:36:59.76890Z,1327088219.7689 [CommandLine](IMPORTANT): got command load ./Missions/Transport/transit_3km.xml 2012-01-20T19:36:59.76910Z,1327088219.7691 [homing_altitude] Stopped 2012-01-20T19:36:59.76920Z,1327088219.7692 [homing_altitude](INFO): Aggregate::uninitialize homing_altitude 2012-01-20T19:36:59.76930Z,1327088219.7693 [homing_altitude:A.AltitudeEnvelope] Stopped 2012-01-20T19:36:59.76940Z,1327088219.7694 [homing_altitude:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-01-20T19:36:59.76940Z,1327088219.7694 [homing_altitude:B.DepthEnvelope] Stopped 2012-01-20T19:36:59.76950Z,1327088219.7695 [homing_altitude:B.DepthEnvelope](DEBUG): Uninitialize. 2012-01-20T19:36:59.76960Z,1327088219.7696 [homing_altitude:C.OffshoreEnvelope] Stopped 2012-01-20T19:36:59.76960Z,1327088219.7696 [homing_altitude:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-01-20T19:36:59.76970Z,1327088219.7697 [homing_altitude:D] Stopped 2012-01-20T19:36:59.76980Z,1327088219.7698 [homing_altitude:SURFACECOMMS] Stopped 2012-01-20T19:36:59.76990Z,1327088219.7699 [homing_altitude:SURFACECOMMS](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS 2012-01-20T19:36:59.77000Z,1327088219.77 [homing_altitude:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T19:36:59.77000Z,1327088219.77 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T19:36:59.77010Z,1327088219.7701 [homing_altitude:SURFACECOMMS:B] Stopped 2012-01-20T19:36:59.77020Z,1327088219.7702 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS:B 2012-01-20T19:36:59.77030Z,1327088219.7703 [homing_altitude:SURFACECOMMS:B:B] Stopped 2012-01-20T19:36:59.77040Z,1327088219.7704 [homing_altitude:PHONEHOMEWPT1] Stopped 2012-01-20T19:36:59.77050Z,1327088219.7705 [homing_altitude:PHONEHOMEWPT1](INFO): Aggregate::uninitialize homing_altitude:PHONEHOMEWPT1 2012-01-20T19:36:59.78030Z,1327088219.7803 [MissionManager](INFO): Loading Mission: ./Missions/Transport/transit_3km.xml 2012-01-20T19:36:59.81380Z,1327088219.8138 [MissionManager](INFO): DefineArg transit_3km.ApproachDepth = 10 m 2012-01-20T19:36:59.81610Z,1327088219.8161 [MissionManager](INFO): DefineArg transit_3km.Wpt1Lat = 36.806966 arcdeg 2012-01-20T19:36:59.81840Z,1327088219.8184 [MissionManager](INFO): DefineArg transit_3km.Wpt1Lon = -121.824326 arcdeg 2012-01-20T19:36:59.82050Z,1327088219.8205 [MissionManager](INFO): DefineArg transit_3km.Speed = 1 m/s 2012-01-20T19:36:59.82260Z,1327088219.8226 [MissionManager](INFO): DefineArg transit_3km.MaxDepth = 20 m 2012-01-20T19:36:59.82410Z,1327088219.8241 [transit_3km:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-01-20T19:36:59.82880Z,1327088219.8288 [transit_3km:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-01-20T19:36:59.83400Z,1327088219.834 [transit_3km:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-01-20T19:36:59.83880Z,1327088219.8388 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-01-20T19:36:59.84210Z,1327088219.8421 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Construct. 2012-01-20T19:36:59.85080Z,1327088219.8508 [transit_3km:WaypointOne:A.Pitch](DEBUG): Construct. 2012-01-20T19:36:59.85550Z,1327088219.8555 [transit_3km:WaypointOne:B.SetSpeed](DEBUG): Construct. 2012-01-20T19:36:59.85770Z,1327088219.8577 [transit_3km:WaypointOne:WaypointW1.Waypoint](DEBUG): Construct Waypoint. 2012-01-20T19:36:59.86620Z,1327088219.8662 [MissionManager](DEBUG): 10.0 36.806966 -121.824326 1 Maximum depth for the entire mission. 20 7 1 0 35 2012-01-20T19:36:59.86660Z,1327088219.8666 [CommandLine](IMPORTANT): Loaded ./Missions/Transport/transit_3km.xml 2012-01-20T19:36:59.95110Z,1327088219.9511 [CommandLine](IMPORTANT): got command set transit_3km.Wpt1Lat 36.82799911 degree 2012-01-20T19:37:00.05630Z,1327088220.0563 [CommandLine](IMPORTANT): got command set transit_3km.Wpt1Lon -121.8310013 degree 2012-01-20T19:37:00.05760Z,1327088220.0576 [CommandLine](IMPORTANT): got command run 2012-01-20T19:37:00.05780Z,1327088220.0578 [CommandLine](IMPORTANT): Running 2012-01-20T19:37:00.07090Z,1327088220.0709 [MissionManager](IMPORTANT): Started mission transit_3km 2012-01-20T19:37:00.07100Z,1327088220.071 [transit_3km] Running Loop=1 2012-01-20T19:37:00.07110Z,1327088220.0711 [transit_3km](INFO): Aggregate::initialize transit_3km 2012-01-20T19:37:00.07120Z,1327088220.0712 [transit_3km:A.AltitudeEnvelope] Running Loop=1 2012-01-20T19:37:00.07130Z,1327088220.0713 [transit_3km:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-01-20T19:37:00.07140Z,1327088220.0714 [transit_3km:B.DepthEnvelope] Running Loop=1 2012-01-20T19:37:00.07150Z,1327088220.0715 [transit_3km:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-01-20T19:37:00.07210Z,1327088220.0721 [transit_3km:C.OffshoreEnvelope] Running Loop=1 2012-01-20T19:37:00.07220Z,1327088220.0722 [transit_3km:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-01-20T19:37:00.07240Z,1327088220.0724 [transit_3km:SURFACECOMMS] Running Loop=1 2012-01-20T19:37:00.07250Z,1327088220.0725 [transit_3km:SURFACECOMMS](INFO): Aggregate::initialize transit_3km:SURFACECOMMS 2012-01-20T19:37:00.07260Z,1327088220.0726 [transit_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T19:37:00.07270Z,1327088220.0727 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T19:37:00.07420Z,1327088220.0742 [transit_3km:SURFACECOMMS:B] Running Loop=1 2012-01-20T19:37:00.07440Z,1327088220.0744 [transit_3km:SURFACECOMMS:B](INFO): Aggregate::initialize transit_3km:SURFACECOMMS:B 2012-01-20T19:37:00.07450Z,1327088220.0745 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-01-20T19:37:00.07450Z,1327088220.0745 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-01-20T19:37:00.07470Z,1327088220.0747 [transit_3km:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T19:37:00.07480Z,1327088220.0748 [transit_3km:C.OffshoreEnvelope] Running Loop=1 2012-01-20T19:37:00.07570Z,1327088220.0757 [transit_3km:B.DepthEnvelope] Running Loop=1 2012-01-20T19:37:00.07670Z,1327088220.0767 [transit_3km:A.AltitudeEnvelope] Running Loop=1 2012-01-20T19:37:00.28280Z,1327088220.2828 [transit_3km:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-01-20T19:37:00.28370Z,1327088220.2837 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-01-20T19:37:02.25130Z,1327088222.2513 [NAL9601](IMPORTANT): GPS fix at: 1327088191 2012-01-20T19:37:02.26530Z,1327088222.2653 [transit_3km:SURFACECOMMS:B:B] Stopped 2012-01-20T19:37:02.26540Z,1327088222.2654 [transit_3km:SURFACECOMMS:B:C] Running Loop=1 2012-01-20T19:37:02.67020Z,1327088222.6702 [transit_3km:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-01-20T19:37:14.61370Z,1327088234.6137 [NAL9601](INFO): SBD MO Status=1, MOMSN=42216, MT Status=0, MTMSN=0 2012-01-20T19:37:14.75940Z,1327088234.7594 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:37:14.75960Z,1327088234.7596 [NAL9601](INFO): Packets left to send: 17 2012-01-20T19:37:14.76070Z,1327088234.7607 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000172 2012-01-20T19:37:35.51070Z,1327088255.5107 [NAL9601](INFO): SBD MO Status=2, MOMSN=42217, MT Status=2, MTMSN=0 2012-01-20T19:37:35.51110Z,1327088255.5111 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-01-20T19:37:58.99780Z,1327088278.9978 [NAL9601](INFO): SBD MO Status=1, MOMSN=42217, MT Status=0, MTMSN=0 2012-01-20T19:37:59.18740Z,1327088279.1874 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:37:59.18760Z,1327088279.1876 [NAL9601](INFO): Packets left to send: 16 2012-01-20T19:37:59.18870Z,1327088279.1887 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000173 2012-01-20T19:38:07.49080Z,1327088287.4908 [NAL9601](INFO): SBD MO Status=1, MOMSN=42218, MT Status=0, MTMSN=0 2012-01-20T19:38:07.67530Z,1327088287.6753 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:38:07.67550Z,1327088287.6755 [NAL9601](INFO): Packets left to send: 15 2012-01-20T19:38:07.77710Z,1327088287.7771 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000174 2012-01-20T19:38:20.36170Z,1327088300.3617 [NAL9601](INFO): SBD MO Status=1, MOMSN=42219, MT Status=0, MTMSN=0 2012-01-20T19:38:20.55530Z,1327088300.5553 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:38:20.55560Z,1327088300.5556 [NAL9601](INFO): Packets left to send: 14 2012-01-20T19:38:20.55670Z,1327088300.5567 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000175 2012-01-20T19:38:31.24990Z,1327088311.2499 [NAL9601](INFO): SBD MO Status=1, MOMSN=42220, MT Status=0, MTMSN=0 2012-01-20T19:38:31.43930Z,1327088311.4393 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:38:31.43950Z,1327088311.4395 [NAL9601](INFO): Packets left to send: 13 2012-01-20T19:38:31.44070Z,1327088311.4407 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000176 2012-01-20T19:38:41.37050Z,1327088321.3705 [NAL9601](INFO): SBD MO Status=1, MOMSN=42221, MT Status=0, MTMSN=0 2012-01-20T19:38:41.52330Z,1327088321.5233 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:38:41.52350Z,1327088321.5235 [NAL9601](INFO): Packets left to send: 12 2012-01-20T19:38:41.52480Z,1327088321.5248 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000177 2012-01-20T19:38:51.14190Z,1327088331.1419 [NAL9601](INFO): SBD MO Status=1, MOMSN=42222, MT Status=0, MTMSN=0 2012-01-20T19:38:51.31130Z,1327088331.3113 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:38:51.31150Z,1327088331.3115 [NAL9601](INFO): Packets left to send: 11 2012-01-20T19:38:51.31260Z,1327088331.3126 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000178 2012-01-20T19:39:06.43470Z,1327088346.4347 [NAL9601](INFO): SBD MO Status=1, MOMSN=42223, MT Status=0, MTMSN=0 2012-01-20T19:39:06.58340Z,1327088346.5834 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:39:06.58360Z,1327088346.5836 [NAL9601](INFO): Packets left to send: 10 2012-01-20T19:39:06.58480Z,1327088346.5848 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000179 2012-01-20T19:39:16.11660Z,1327088356.1166 [NAL9601](INFO): SBD MO Status=1, MOMSN=42224, MT Status=0, MTMSN=0 2012-01-20T19:39:16.26730Z,1327088356.2673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:39:16.26750Z,1327088356.2675 [NAL9601](INFO): Packets left to send: 9 2012-01-20T19:39:16.26870Z,1327088356.2687 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000180 2012-01-20T19:39:29.04980Z,1327088369.0498 [NAL9601](INFO): SBD MO Status=1, MOMSN=42225, MT Status=0, MTMSN=0 2012-01-20T19:39:29.24730Z,1327088369.2473 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:39:29.24750Z,1327088369.2475 [NAL9601](INFO): Packets left to send: 8 2012-01-20T19:39:29.28670Z,1327088369.2867 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000181 2012-01-20T19:39:37.52880Z,1327088377.5288 [NAL9601](INFO): SBD MO Status=1, MOMSN=42226, MT Status=0, MTMSN=0 2012-01-20T19:39:37.73540Z,1327088377.7354 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:39:37.73560Z,1327088377.7356 [NAL9601](INFO): Packets left to send: 7 2012-01-20T19:39:37.73680Z,1327088377.7368 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000182 2012-01-20T19:39:48.03780Z,1327088388.0378 [NAL9601](INFO): SBD MO Status=1, MOMSN=42227, MT Status=0, MTMSN=0 2012-01-20T19:39:48.21930Z,1327088388.2193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:39:48.21960Z,1327088388.2196 [NAL9601](INFO): Packets left to send: 6 2012-01-20T19:39:48.22080Z,1327088388.2208 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000183 2012-01-20T19:39:55.75050Z,1327088395.7505 [NAL9601](INFO): SBD MO Status=1, MOMSN=42228, MT Status=0, MTMSN=0 2012-01-20T19:39:55.90730Z,1327088395.9073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:39:55.90750Z,1327088395.9075 [NAL9601](INFO): Packets left to send: 5 2012-01-20T19:39:55.90870Z,1327088395.9087 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000184 2012-01-20T19:40:03.42180Z,1327088403.4218 [NAL9601](INFO): SBD MO Status=1, MOMSN=42229, MT Status=0, MTMSN=0 2012-01-20T19:40:03.59530Z,1327088403.5953 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:40:03.59560Z,1327088403.5956 [NAL9601](INFO): Packets left to send: 4 2012-01-20T19:40:03.59670Z,1327088403.5967 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000185 2012-01-20T19:40:12.34250Z,1327088412.3425 [NAL9601](INFO): SBD MO Status=1, MOMSN=42230, MT Status=0, MTMSN=0 2012-01-20T19:40:12.48330Z,1327088412.4833 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:40:12.48350Z,1327088412.4835 [NAL9601](INFO): Packets left to send: 3 2012-01-20T19:40:12.48540Z,1327088412.4854 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000186 2012-01-20T19:40:24.80470Z,1327088424.8047 [NAL9601](INFO): SBD MO Status=1, MOMSN=42231, MT Status=0, MTMSN=0 2012-01-20T19:40:24.96570Z,1327088424.9657 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:40:24.96590Z,1327088424.9659 [NAL9601](INFO): Packets left to send: 2 2012-01-20T19:40:24.97930Z,1327088424.9793 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000187 2012-01-20T19:40:32.88590Z,1327088432.8859 [NAL9601](INFO): SBD MO Status=1, MOMSN=42232, MT Status=0, MTMSN=0 2012-01-20T19:40:33.05130Z,1327088433.0513 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:40:33.05150Z,1327088433.0515 [NAL9601](INFO): Packets left to send: 1 2012-01-20T19:40:33.05270Z,1327088433.0527 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000188 2012-01-20T19:40:42.61120Z,1327088442.6112 [NAL9601](INFO): SBD MO Status=1, MOMSN=42233, MT Status=0, MTMSN=0 2012-01-20T19:40:42.73530Z,1327088442.7353 [NAL9601](INFO): Sent 61 bytes from file Logs/20120120T185928/shore0003.lzma 2012-01-20T19:40:42.73550Z,1327088442.7355 [NAL9601](INFO): Packets left to send: 0 2012-01-20T19:40:42.73670Z,1327088442.7367 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000189 2012-01-20T19:40:47.78170Z,1327088447.7817 [NAL9601](INFO): SBD MO Status=0, MOMSN=42234, MT Status=0, MTMSN=0 2012-01-20T19:41:08.52170Z,1327088468.5217 [NAL9601](INFO): SBD MO Status=1, MOMSN=42235, MT Status=0, MTMSN=0 2012-01-20T19:41:08.69540Z,1327088468.6954 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0004.lzma 2012-01-20T19:41:08.69560Z,1327088468.6956 [NAL9601](INFO): Packets left to send: 7 2012-01-20T19:41:08.69700Z,1327088468.697 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000190 2012-01-20T19:41:16.31380Z,1327088476.3138 [NAL9601](INFO): SBD MO Status=1, MOMSN=42236, MT Status=0, MTMSN=0 2012-01-20T19:41:16.48330Z,1327088476.4833 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0004.lzma 2012-01-20T19:41:16.48350Z,1327088476.4835 [NAL9601](INFO): Packets left to send: 6 2012-01-20T19:41:16.48470Z,1327088476.4847 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000191 2012-01-20T19:41:28.39770Z,1327088488.3977 [NAL9601](INFO): SBD MO Status=1, MOMSN=42237, MT Status=0, MTMSN=0 2012-01-20T19:41:28.56330Z,1327088488.5633 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0004.lzma 2012-01-20T19:41:28.56360Z,1327088488.5636 [NAL9601](INFO): Packets left to send: 5 2012-01-20T19:41:28.56470Z,1327088488.5647 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000192 2012-01-20T19:41:41.48050Z,1327088501.4805 [NAL9601](INFO): SBD MO Status=1, MOMSN=42238, MT Status=0, MTMSN=0 2012-01-20T19:41:41.64340Z,1327088501.6434 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0004.lzma 2012-01-20T19:41:41.64360Z,1327088501.6436 [NAL9601](INFO): Packets left to send: 4 2012-01-20T19:41:41.64480Z,1327088501.6448 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000193 2012-01-20T19:41:54.76170Z,1327088514.7617 [NAL9601](INFO): SBD MO Status=1, MOMSN=42239, MT Status=0, MTMSN=0 2012-01-20T19:41:54.92330Z,1327088514.9233 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0004.lzma 2012-01-20T19:41:54.92350Z,1327088514.9235 [NAL9601](INFO): Packets left to send: 3 2012-01-20T19:41:54.92470Z,1327088514.9247 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000194 2012-01-20T19:42:05.64980Z,1327088525.6498 [NAL9601](INFO): SBD MO Status=1, MOMSN=42240, MT Status=0, MTMSN=0 2012-01-20T19:42:05.81130Z,1327088525.8113 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0004.lzma 2012-01-20T19:42:05.81150Z,1327088525.8115 [NAL9601](INFO): Packets left to send: 2 2012-01-20T19:42:05.81270Z,1327088525.8127 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000195 2012-01-20T19:42:13.74650Z,1327088533.7465 [NAL9601](INFO): SBD MO Status=1, MOMSN=42241, MT Status=0, MTMSN=0 2012-01-20T19:42:13.89540Z,1327088533.8954 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0004.lzma 2012-01-20T19:42:13.89560Z,1327088533.8956 [NAL9601](INFO): Packets left to send: 1 2012-01-20T19:42:13.89670Z,1327088533.8967 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000196 2012-01-20T19:42:20.31600Z,1327088540.316 [NAL9601](INFO): SBD MO Status=1, MOMSN=42242, MT Status=0, MTMSN=0 2012-01-20T19:42:20.48330Z,1327088540.4833 [NAL9601](INFO): Sent 160 bytes from file Logs/20120120T185928/shore0004.lzma 2012-01-20T19:42:20.48350Z,1327088540.4835 [NAL9601](INFO): Packets left to send: 0 2012-01-20T19:42:20.52070Z,1327088540.5207 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000197 2012-01-20T19:42:31.09050Z,1327088551.0905 [NAL9601](INFO): SBD MO Status=0, MOMSN=42243, MT Status=0, MTMSN=0 2012-01-20T19:42:31.28010Z,1327088551.2801 [transit_3km:SURFACECOMMS:B:C] Stopped 2012-01-20T19:42:31.28030Z,1327088551.2803 [transit_3km:SURFACECOMMS:B:D] Running Loop=1 2012-01-20T19:42:31.50630Z,1327088551.5063 [transit_3km:SURFACECOMMS:B:D](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-01-20T19:42:33.47520Z,1327088553.4752 [NAL9601](IMPORTANT): GPS fix at: 1327088522 2012-01-20T19:42:33.48860Z,1327088553.4886 [transit_3km:SURFACECOMMS:B:D] Stopped 2012-01-20T19:42:33.48900Z,1327088553.489 [transit_3km:SURFACECOMMS:B](INFO): Completed transit_3km:SURFACECOMMS:B 2012-01-20T19:42:33.48910Z,1327088553.4891 [transit_3km:SURFACECOMMS:B] Stopped 2012-01-20T19:42:33.48920Z,1327088553.4892 [transit_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize transit_3km:SURFACECOMMS:B 2012-01-20T19:42:33.48930Z,1327088553.4893 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-01-20T19:42:33.48940Z,1327088553.4894 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-01-20T19:42:33.48990Z,1327088553.4899 [transit_3km:SURFACECOMMS](INFO): Completed transit_3km:SURFACECOMMS 2012-01-20T19:42:33.49000Z,1327088553.49 [transit_3km:SURFACECOMMS] Stopped 2012-01-20T19:42:33.49010Z,1327088553.4901 [transit_3km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_3km:SURFACECOMMS 2012-01-20T19:42:33.49020Z,1327088553.4902 [transit_3km:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T19:42:33.49020Z,1327088553.4902 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T19:42:33.49040Z,1327088553.4904 [transit_3km:WaypointOne] Running Loop=1 2012-01-20T19:42:33.49050Z,1327088553.4905 [transit_3km:WaypointOne](INFO): Aggregate::initialize transit_3km:WaypointOne 2012-01-20T19:42:33.49060Z,1327088553.4906 [transit_3km:WaypointOne:A.Pitch] Running Loop=1 2012-01-20T19:42:33.49070Z,1327088553.4907 [transit_3km:WaypointOne:A.Pitch](DEBUG): Initialize. 2012-01-20T19:42:33.49090Z,1327088553.4909 [transit_3km:WaypointOne:B.SetSpeed] Running Loop=1 2012-01-20T19:42:33.49100Z,1327088553.491 [transit_3km:WaypointOne:B.SetSpeed](DEBUG): Initialize. 2012-01-20T19:42:33.49120Z,1327088553.4912 [transit_3km:WaypointOne:WaypointW1.Waypoint] Running Loop=1 2012-01-20T19:42:33.49130Z,1327088553.4913 [transit_3km:WaypointOne:WaypointW1.Waypoint](DEBUG): Initialize WaypointComponent. 2012-01-20T19:42:34.01520Z,1327088554.0152 [transit_3km:WaypointOne:B.SetSpeed] Running Loop=1 2012-01-20T19:42:34.01600Z,1327088554.016 [transit_3km:WaypointOne:A.Pitch] Running Loop=1 2012-01-20T19:42:39.97210Z,1327088559.9721 [NAL9601](INFO): Powering down 2012-01-20T19:42:54.79550Z,1327088574.7955 [Radio_Freewave](INFO): Powering down 2012-01-20T19:43:00.43210Z,1327088580.4321 [Radio_Freewave](INFO): Powering up 2012-01-20T19:43:57.28160Z,1327088637.2816 [Radio_Freewave](INFO): Powering down 2012-01-20T19:53:53.90130Z,1327089233.9013 [transit_3km:WaypointOne:WaypointW1.Waypoint](INFO): Reached Waypoint: 36.82799751,-121.8309995 2012-01-20T19:53:53.90160Z,1327089233.9016 [transit_3km:WaypointOne:WaypointW1.Waypoint] Stopped 2012-01-20T19:53:53.90170Z,1327089233.9017 [transit_3km:WaypointOne:WaypointW1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-01-20T19:53:53.90180Z,1327089233.9018 [transit_3km:WaypointOne:PHONEHOMEWPT1] Running Loop=1 2012-01-20T19:53:53.90200Z,1327089233.902 [transit_3km:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::initialize transit_3km:WaypointOne:PHONEHOMEWPT1 2012-01-20T19:53:54.32100Z,1327089234.321 [transit_3km:SURFACECOMMS] Running Loop=1 2012-01-20T19:53:54.32120Z,1327089234.3212 [transit_3km:SURFACECOMMS](INFO): Aggregate::initialize transit_3km:SURFACECOMMS 2012-01-20T19:53:54.32130Z,1327089234.3213 [transit_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T19:53:54.32140Z,1327089234.3214 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T19:56:47.86510Z,1327089407.8651 [Radio_Freewave](INFO): Powering up 2012-01-20T19:56:47.87910Z,1327089407.8791 [transit_3km:SURFACECOMMS:B] Running Loop=1 2012-01-20T19:56:47.87930Z,1327089407.8793 [transit_3km:SURFACECOMMS:B](INFO): Aggregate::initialize transit_3km:SURFACECOMMS:B 2012-01-20T19:56:47.87940Z,1327089407.8794 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-01-20T19:56:47.87940Z,1327089407.8794 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-01-20T19:56:47.87960Z,1327089407.8796 [transit_3km:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T19:56:48.62370Z,1327089408.6237 [NAL9601](INFO): Powering up 2012-01-20T19:57:54.33540Z,1327089474.3354 [NAL9601](INFO): NAL9601 initialized 2012-01-20T19:57:55.44410Z,1327089475.4441 [NAL9601](IMPORTANT): GPS fix at: 1327089446 2012-01-20T19:57:55.45740Z,1327089475.4574 [transit_3km:SURFACECOMMS:B:B] Stopped 2012-01-20T19:57:55.45760Z,1327089475.4576 [transit_3km:SURFACECOMMS:B:C] Running Loop=1 2012-01-20T19:58:10.99390Z,1327089490.9939 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42244, MT Status=1, MTMSN=3482 2012-01-20T19:58:11.20330Z,1327089491.2033 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T19:58:11.20350Z,1327089491.2035 [NAL9601](INFO): Packets left to send: 8 2012-01-20T19:58:11.20470Z,1327089491.2047 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000198 2012-01-20T19:58:11.61220Z,1327089491.6122 [NAL9601](INFO): Received command:restart logs 2012-01-20T19:58:11.67530Z,1327089491.6753 [CommandLine](IMPORTANT): got command restart logs