2012-01-20T19:58:24.68330Z,1327089504.6833 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42245, MT Status=1, MTMSN=3483 2012-01-20T19:58:24.87540Z,1327089504.8754 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T19:58:24.87560Z,1327089504.8756 [NAL9601](INFO): Packets left to send: 7 2012-01-20T19:58:24.87660Z,1327089504.8766 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000199 2012-01-20T19:58:25.38420Z,1327089505.3842 [NAL9601](INFO): Received command:load Engineering/Homing_altitude.xml;set homing_altitude.Altitude 9 meter;set homing_altitude.dockBearing 335 degree;set homing_altitude.finalApproach 0.01 meter;run 2012-01-20T19:58:25.40230Z,1327089505.4023 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/Homing_altitude.xml 2012-01-20T19:58:25.40240Z,1327089505.4024 [transit_3km] Stopped 2012-01-20T19:58:25.40250Z,1327089505.4025 [transit_3km](INFO): Aggregate::uninitialize transit_3km 2012-01-20T19:58:25.40260Z,1327089505.4026 [transit_3km:A.AltitudeEnvelope] Stopped 2012-01-20T19:58:25.40270Z,1327089505.4027 [transit_3km:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-01-20T19:58:25.40280Z,1327089505.4028 [transit_3km:B.DepthEnvelope] Stopped 2012-01-20T19:58:25.40290Z,1327089505.4029 [transit_3km:B.DepthEnvelope](DEBUG): Uninitialize. 2012-01-20T19:58:25.40300Z,1327089505.403 [transit_3km:C.OffshoreEnvelope] Stopped 2012-01-20T19:58:25.40300Z,1327089505.403 [transit_3km:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-01-20T19:58:25.40310Z,1327089505.4031 [transit_3km:SURFACECOMMS] Stopped 2012-01-20T19:58:25.40320Z,1327089505.4032 [transit_3km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_3km:SURFACECOMMS 2012-01-20T19:58:25.40330Z,1327089505.4033 [transit_3km:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T19:58:25.40340Z,1327089505.4034 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T19:58:25.40350Z,1327089505.4035 [transit_3km:SURFACECOMMS:B] Stopped 2012-01-20T19:58:25.40360Z,1327089505.4036 [transit_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize transit_3km:SURFACECOMMS:B 2012-01-20T19:58:25.40360Z,1327089505.4036 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-01-20T19:58:25.40370Z,1327089505.4037 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-01-20T19:58:25.40380Z,1327089505.4038 [transit_3km:SURFACECOMMS:B:C] Stopped 2012-01-20T19:58:25.40380Z,1327089505.4038 [transit_3km:WaypointOne] Stopped 2012-01-20T19:58:25.40400Z,1327089505.404 [transit_3km:WaypointOne](INFO): Aggregate::uninitialize transit_3km:WaypointOne 2012-01-20T19:58:25.40400Z,1327089505.404 [transit_3km:WaypointOne:A.Pitch] Stopped 2012-01-20T19:58:25.40410Z,1327089505.4041 [transit_3km:WaypointOne:B.SetSpeed] Stopped 2012-01-20T19:58:25.40410Z,1327089505.4041 [transit_3km:WaypointOne:B.SetSpeed](DEBUG): Uninitialize. 2012-01-20T19:58:25.40420Z,1327089505.4042 [transit_3km:WaypointOne:PHONEHOMEWPT1] Stopped 2012-01-20T19:58:25.40430Z,1327089505.4043 [transit_3km:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize transit_3km:WaypointOne:PHONEHOMEWPT1 2012-01-20T19:58:25.41030Z,1327089505.4103 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/Homing_altitude.xml 2012-01-20T19:58:25.46590Z,1327089505.4659 [MissionManager](INFO): DefineArg homing_altitude.Wpt1Lat = 36.823 arcdeg 2012-01-20T19:58:25.46810Z,1327089505.4681 [MissionManager](INFO): DefineArg homing_altitude.Wpt1Lon = -121.83 arcdeg 2012-01-20T19:58:25.47030Z,1327089505.4703 [MissionManager](INFO): DefineArg homing_altitude.Altitude = 10 m 2012-01-20T19:58:25.47260Z,1327089505.4726 [MissionManager](INFO): DefineArg homing_altitude.Speed = 1 m/s 2012-01-20T19:58:25.47490Z,1327089505.4749 [MissionManager](INFO): DefineArg homing_altitude.MassDefault = nan n/a 2012-01-20T19:58:25.47700Z,1327089505.477 [MissionManager](INFO): DefineArg homing_altitude.BuoyancyNeutral = nan n/a 2012-01-20T19:58:25.47930Z,1327089505.4793 [MissionManager](INFO): DefineArg homing_altitude.dockBearing = 10 arcdeg 2012-01-20T19:58:25.48140Z,1327089505.4814 [MissionManager](INFO): DefineArg homing_altitude.inTrkOffset = 0 m 2012-01-20T19:58:25.48360Z,1327089505.4836 [MissionManager](INFO): DefineArg homing_altitude.xTrkOffset = 0 m 2012-01-20T19:58:25.48580Z,1327089505.4858 [MissionManager](INFO): DefineArg homing_altitude.finalApproach = 200 m 2012-01-20T19:58:25.48810Z,1327089505.4881 [MissionManager](INFO): DefineArg homing_altitude.clusterRadius = 20 m 2012-01-20T19:58:25.49040Z,1327089505.4904 [MissionManager](INFO): DefineArg homing_altitude.SampleNeilBrown = 1 n/a 2012-01-20T19:58:25.49280Z,1327089505.4928 [MissionManager](INFO): DefineArg homing_altitude.TimeoutDuration = 40 min 2012-01-20T19:58:25.49510Z,1327089505.4951 [MissionManager](INFO): DefineArg homing_altitude.kpAltitude = -0.08 n/a 2012-01-20T19:58:25.49720Z,1327089505.4972 [MissionManager](INFO): DefineArg homing_altitude.kiAltitude = 0 n/a 2012-01-20T19:58:25.49940Z,1327089505.4994 [MissionManager](INFO): DefineArg homing_altitude.kdAltitude = 0 n/a 2012-01-20T19:58:25.50080Z,1327089505.5008 [homing_altitude:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-01-20T19:58:25.50560Z,1327089505.5056 [homing_altitude:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-01-20T19:58:25.51140Z,1327089505.5114 [homing_altitude:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-01-20T19:58:25.51640Z,1327089505.5164 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-01-20T19:58:25.53360Z,1327089505.5336 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-01-20T19:58:25.53570Z,1327089505.5357 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-01-20T19:58:25.53780Z,1327089505.5378 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfaceSpeed = 0.5 m/s 2012-01-20T19:58:25.53990Z,1327089505.5399 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.IridiumTimeout = 30 min 2012-01-20T19:58:25.54080Z,1327089505.5408 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-01-20T19:58:25.54950Z,1327089505.5495 [homing_altitude:WaypointOne:B.Pitch](DEBUG): Construct. 2012-01-20T19:58:25.55410Z,1327089505.5541 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Construct Buoyancy. 2012-01-20T19:58:25.55650Z,1327089505.5565 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Construct. 2012-01-20T19:58:25.55890Z,1327089505.5589 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Construct. 2012-01-20T19:58:25.56510Z,1327089505.5651 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Construct Waypoint. 2012-01-20T19:58:25.56790Z,1327089505.5679 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Construct Homing. 2012-01-20T19:58:25.57630Z,1327089505.5763 [homing_altitude:H.Execute](DEBUG): Construct Execute. 2012-01-20T19:58:25.58490Z,1327089505.5849 [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:58:25.58540Z,1327089505.5854 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/Homing_altitude.xml 2012-01-20T19:58:25.68830Z,1327089505.6883 [CommandLine](IMPORTANT): got command set homing_altitude.Altitude 9 meter 2012-01-20T19:58:25.81930Z,1327089505.8193 [CommandLine](IMPORTANT): got command set homing_altitude.dockBearing 335 degree 2012-01-20T19:58:25.93550Z,1327089505.9355 [CommandLine](IMPORTANT): got command set homing_altitude.finalApproach 0.009999999776 meter 2012-01-20T19:58:25.93690Z,1327089505.9369 [CommandLine](IMPORTANT): got command run 2012-01-20T19:58:25.93700Z,1327089505.937 [CommandLine](IMPORTANT): Running 2012-01-20T19:58:25.95040Z,1327089505.9504 [MissionManager](IMPORTANT): Started mission homing_altitude 2012-01-20T19:58:25.95050Z,1327089505.9505 [homing_altitude] Running Loop=1 2012-01-20T19:58:25.95070Z,1327089505.9507 [homing_altitude](INFO): Aggregate::initialize homing_altitude 2012-01-20T19:58:25.95070Z,1327089505.9507 [homing_altitude:A.AltitudeEnvelope] Running Loop=1 2012-01-20T19:58:25.95090Z,1327089505.9509 [homing_altitude:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-01-20T19:58:25.95100Z,1327089505.951 [homing_altitude:B.DepthEnvelope] Running Loop=1 2012-01-20T19:58:25.95110Z,1327089505.9511 [homing_altitude:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-01-20T19:58:25.95170Z,1327089505.9517 [homing_altitude:C.OffshoreEnvelope] Running Loop=1 2012-01-20T19:58:25.95170Z,1327089505.9517 [homing_altitude:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-01-20T19:58:25.95180Z,1327089505.9518 [homing_altitude:D] Running Loop=1 2012-01-20T19:58:25.95200Z,1327089505.952 [homing_altitude:SURFACECOMMS] Running Loop=1 2012-01-20T19:58:25.95210Z,1327089505.9521 [homing_altitude:SURFACECOMMS](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS 2012-01-20T19:58:25.95220Z,1327089505.9522 [homing_altitude:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T19:58:25.95230Z,1327089505.9523 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T19:58:25.95390Z,1327089505.9539 [homing_altitude:SURFACECOMMS:B] Running Loop=1 2012-01-20T19:58:25.95410Z,1327089505.9541 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS:B 2012-01-20T19:58:25.95420Z,1327089505.9542 [homing_altitude:SURFACECOMMS:B:A] Running Loop=1 2012-01-20T19:58:25.95430Z,1327089505.9543 [homing_altitude:D] Running Loop=1 2012-01-20T19:58:25.95500Z,1327089505.955 [homing_altitude:D](DEBUG): Initialize ReadDataComponent to sense platform_orientation 2012-01-20T19:58:25.95580Z,1327089505.9558 [homing_altitude:C.OffshoreEnvelope] Running Loop=1 2012-01-20T19:58:25.95680Z,1327089505.9568 [homing_altitude:B.DepthEnvelope] Running Loop=1 2012-01-20T19:58:25.95790Z,1327089505.9579 [homing_altitude:A.AltitudeEnvelope] Running Loop=1 2012-01-20T19:58:26.68010Z,1327089506.6801 [homing_altitude:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense time_fix 2012-01-20T19:58:28.65420Z,1327089508.6542 [NAL9601](IMPORTANT): GPS fix at: 1327089479 2012-01-20T19:58:28.66750Z,1327089508.6675 [homing_altitude:SURFACECOMMS:B:A] Stopped 2012-01-20T19:58:28.66770Z,1327089508.6677 [homing_altitude:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T19:58:29.07760Z,1327089509.0776 [homing_altitude:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-01-20T19:58:45.01520Z,1327089525.0152 [NAL9601](INFO): SBD MO Status=1, MOMSN=42246, MT Status=0, MTMSN=0 2012-01-20T19:58:45.14730Z,1327089525.1473 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T19:58:45.14750Z,1327089525.1475 [NAL9601](INFO): Packets left to send: 6 2012-01-20T19:58:45.14860Z,1327089525.1486 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000200 2012-01-20T19:58:53.92660Z,1327089533.9266 [NAL9601](INFO): SBD MO Status=1, MOMSN=42247, MT Status=0, MTMSN=0 2012-01-20T19:58:54.13130Z,1327089534.1313 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T19:58:54.13150Z,1327089534.1315 [NAL9601](INFO): Packets left to send: 5 2012-01-20T19:58:54.13260Z,1327089534.1326 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000201 2012-01-20T19:59:04.02560Z,1327089544.0256 [NAL9601](INFO): SBD MO Status=1, MOMSN=42248, MT Status=0, MTMSN=0 2012-01-20T19:59:04.21530Z,1327089544.2153 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T19:59:04.21550Z,1327089544.2155 [NAL9601](INFO): Packets left to send: 4 2012-01-20T19:59:04.21660Z,1327089544.2166 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000202 2012-01-20T19:59:13.77510Z,1327089553.7751 [NAL9601](INFO): SBD MO Status=1, MOMSN=42249, MT Status=0, MTMSN=0 2012-01-20T19:59:13.89930Z,1327089553.8993 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T19:59:13.89950Z,1327089553.8995 [NAL9601](INFO): Packets left to send: 3 2012-01-20T19:59:13.90060Z,1327089553.9006 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000203 2012-01-20T19:59:39.92200Z,1327089579.922 [NAL9601](INFO): SBD MO Status=2, MOMSN=42250, MT Status=0, MTMSN=0 2012-01-20T19:59:39.92220Z,1327089579.9222 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-01-20T19:59:55.39270Z,1327089595.3927 [NAL9601](INFO): SBD MO Status=1, MOMSN=42250, MT Status=0, MTMSN=0 2012-01-20T19:59:55.54550Z,1327089595.5455 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T19:59:55.54570Z,1327089595.5457 [NAL9601](INFO): Packets left to send: 2 2012-01-20T19:59:55.54680Z,1327089595.5468 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000204 2012-01-20T20:00:05.56910Z,1327089605.5691 [NAL9601](INFO): SBD MO Status=1, MOMSN=42251, MT Status=0, MTMSN=0 2012-01-20T20:00:05.71930Z,1327089605.7193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T20:00:05.71950Z,1327089605.7195 [NAL9601](INFO): Packets left to send: 1 2012-01-20T20:00:05.72060Z,1327089605.7206 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000205 2012-01-20T20:00:13.25240Z,1327089613.2524 [NAL9601](INFO): SBD MO Status=1, MOMSN=42252, MT Status=0, MTMSN=0 2012-01-20T20:00:13.40730Z,1327089613.4073 [NAL9601](INFO): Sent 265 bytes from file Logs/20120120T185928/shore0005.lzma 2012-01-20T20:00:13.40750Z,1327089613.4075 [NAL9601](INFO): Packets left to send: 0 2012-01-20T20:00:13.40860Z,1327089613.4086 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000206 2012-01-20T20:00:21.34490Z,1327089621.3449 [NAL9601](INFO): SBD MO Status=1, MOMSN=42253, MT Status=0, MTMSN=0 2012-01-20T20:00:21.49530Z,1327089621.4953 [NAL9601](INFO): Sent 329 bytes from file Logs/20120120T185928/shore0006.lzma 2012-01-20T20:00:21.49550Z,1327089621.4955 [NAL9601](INFO): Packets left to send: 0 2012-01-20T20:00:21.49660Z,1327089621.4966 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000207 2012-01-20T20:00:28.14470Z,1327089628.1447 [NAL9601](INFO): SBD MO Status=0, MOMSN=42254, MT Status=0, MTMSN=0 2012-01-20T20:00:40.89680Z,1327089640.8968 [NAL9601](INFO): SBD MO Status=1, MOMSN=42255, MT Status=0, MTMSN=0 2012-01-20T20:00:41.06330Z,1327089641.0633 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0000.lzma 2012-01-20T20:00:41.06350Z,1327089641.0635 [NAL9601](INFO): Packets left to send: 5 2012-01-20T20:00:41.06460Z,1327089641.0646 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000208 2012-01-20T20:00:48.60970Z,1327089648.6097 [NAL9601](INFO): SBD MO Status=1, MOMSN=42256, MT Status=0, MTMSN=0 2012-01-20T20:00:48.75530Z,1327089648.7553 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0000.lzma 2012-01-20T20:00:48.75560Z,1327089648.7556 [NAL9601](INFO): Packets left to send: 4 2012-01-20T20:00:48.75660Z,1327089648.7566 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000209 2012-01-20T20:00:57.92550Z,1327089657.9255 [NAL9601](INFO): SBD MO Status=1, MOMSN=42257, MT Status=0, MTMSN=0 2012-01-20T20:00:58.13940Z,1327089658.1394 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0000.lzma 2012-01-20T20:00:58.13960Z,1327089658.1396 [NAL9601](INFO): Packets left to send: 3 2012-01-20T20:00:58.14060Z,1327089658.1406 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000210 2012-01-20T20:01:10.47030Z,1327089670.4703 [NAL9601](INFO): SBD MO Status=1, MOMSN=42258, MT Status=0, MTMSN=0 2012-01-20T20:01:10.62330Z,1327089670.6233 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0000.lzma 2012-01-20T20:01:10.62350Z,1327089670.6235 [NAL9601](INFO): Packets left to send: 2 2012-01-20T20:01:10.62460Z,1327089670.6246 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000211 2012-01-20T20:01:22.97660Z,1327089682.9766 [NAL9601](INFO): SBD MO Status=1, MOMSN=42259, MT Status=0, MTMSN=0 2012-01-20T20:01:23.09950Z,1327089683.0995 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0000.lzma 2012-01-20T20:01:23.09970Z,1327089683.0997 [NAL9601](INFO): Packets left to send: 1 2012-01-20T20:01:23.10080Z,1327089683.1008 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000212 2012-01-20T20:01:31.14680Z,1327089691.1468 [NAL9601](INFO): SBD MO Status=1, MOMSN=42260, MT Status=0, MTMSN=0 2012-01-20T20:01:31.28730Z,1327089691.2873 [NAL9601](INFO): Sent 155 bytes from file Logs/20120120T195811/shore0000.lzma 2012-01-20T20:01:31.28750Z,1327089691.2875 [NAL9601](INFO): Packets left to send: 0 2012-01-20T20:01:31.28860Z,1327089691.2886 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000213 2012-01-20T20:01:39.06550Z,1327089699.0655 [NAL9601](INFO): SBD MO Status=0, MOMSN=42261, MT Status=0, MTMSN=0 2012-01-20T20:01:39.28870Z,1327089699.2887 [homing_altitude:SURFACECOMMS:B:B] Stopped 2012-01-20T20:01:39.28890Z,1327089699.2889 [homing_altitude:SURFACECOMMS:B:C] Running Loop=1 2012-01-20T20:01:39.46580Z,1327089699.4658 [homing_altitude:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2012-01-20T20:01:41.45360Z,1327089701.4536 [NAL9601](IMPORTANT): GPS fix at: 1327089672 2012-01-20T20:01:41.48780Z,1327089701.4878 [homing_altitude:SURFACECOMMS:B:C] Stopped 2012-01-20T20:01:41.48810Z,1327089701.4881 [homing_altitude:SURFACECOMMS:B](INFO): Completed homing_altitude:SURFACECOMMS:B 2012-01-20T20:01:41.48820Z,1327089701.4882 [homing_altitude:SURFACECOMMS:B] Stopped 2012-01-20T20:01:41.48830Z,1327089701.4883 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS:B 2012-01-20T20:01:41.48880Z,1327089701.4888 [homing_altitude:SURFACECOMMS](INFO): Completed homing_altitude:SURFACECOMMS 2012-01-20T20:01:41.48890Z,1327089701.4889 [homing_altitude:SURFACECOMMS] Stopped 2012-01-20T20:01:41.48900Z,1327089701.489 [homing_altitude:SURFACECOMMS](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS 2012-01-20T20:01:41.48910Z,1327089701.4891 [homing_altitude:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T20:01:41.48920Z,1327089701.4892 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T20:01:41.48930Z,1327089701.4893 [homing_altitude:WaypointOne] Running Loop=1 2012-01-20T20:01:41.48940Z,1327089701.4894 [homing_altitude:WaypointOne](INFO): Aggregate::initialize homing_altitude:WaypointOne 2012-01-20T20:01:41.48950Z,1327089701.4895 [homing_altitude:WaypointOne:A] Running Loop=1 2012-01-20T20:01:41.48960Z,1327089701.4896 [homing_altitude:WaypointOne:B.Pitch] Running Loop=1 2012-01-20T20:01:41.48960Z,1327089701.4896 [homing_altitude:WaypointOne:B.Pitch](DEBUG): Initialize. 2012-01-20T20:01:41.48980Z,1327089701.4898 [homing_altitude:WaypointOne:C.Buoyancy] Running Loop=1 2012-01-20T20:01:41.48990Z,1327089701.4899 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-01-20T20:01:41.49030Z,1327089701.4903 [homing_altitude:WaypointOne:D.SetSpeed] Running Loop=1 2012-01-20T20:01:41.49040Z,1327089701.4904 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Initialize. 2012-01-20T20:01:41.49050Z,1327089701.4905 [homing_altitude:WaypointOne:E.PitchServo] Running Loop=1 2012-01-20T20:01:41.49060Z,1327089701.4906 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Initialize. 2012-01-20T20:01:41.49090Z,1327089701.4909 [homing_altitude:WaypointOne:E.PitchServo](INFO): Initialize with holdValue=height_above_sea_floor 2012-01-20T20:01:41.49180Z,1327089701.4918 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint] Running Loop=1 2012-01-20T20:01:41.49180Z,1327089701.4918 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Initialize HomingComponent. 2012-01-20T20:01:41.49190Z,1327089701.4919 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Initialize WaypointComponent. 2012-01-20T20:01:41.90650Z,1327089701.9065 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon is in the DAT FOV with dtw = 691.71. Turning DAT on. 2012-01-20T20:01:41.90820Z,1327089701.9082 [homing_altitude:WaypointOne:E.PitchServo] Running Loop=1 2012-01-20T20:01:41.90910Z,1327089701.9091 [homing_altitude:WaypointOne:D.SetSpeed] Running Loop=1 2012-01-20T20:01:41.90990Z,1327089701.9099 [homing_altitude:WaypointOne:C.Buoyancy] Running Loop=1 2012-01-20T20:01:41.91080Z,1327089701.9108 [homing_altitude:WaypointOne:B.Pitch] Running Loop=1 2012-01-20T20:01:41.91170Z,1327089701.9117 [homing_altitude:WaypointOne:A] Running Loop=1 2012-01-20T20:01:41.91220Z,1327089701.9122 [homing_altitude:WaypointOne:A](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-01-20T20:01:41.91260Z,1327089701.9126 [homing_altitude:WaypointOne:A](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-01-20T20:01:42.26330Z,1327089702.2633 [DAT](INFO): Powering up 2012-01-20T20:01:47.86060Z,1327089707.8606 [NAL9601](INFO): Powering down 2012-01-20T20:01:54.94950Z,1327089714.9495 [Radio_Freewave](INFO): Powering down 2012-01-20T20:01:55.33300Z,1327089715.333 [Radio_Freewave](INFO): Powering up 2012-01-20T20:01:57.38340Z,1327089717.3834 [Radio_Freewave](INFO): Powering down 2012-01-20T20:02:01.75160Z,1327089721.7516 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 1 transponder hits. 2012-01-20T20:02:06.54640Z,1327089726.5464 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 2 transponder hits. 2012-01-20T20:02:11.36900Z,1327089731.369 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 3 transponder hits. 2012-01-20T20:02:16.70410Z,1327089736.7041 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 4 transponder hits. 2012-01-20T20:02:18.69760Z,1327089738.6976 [Radio_Freewave](INFO): Powering up 2012-01-20T20:02:26.28010Z,1327089746.2801 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 5 transponder hits; the filter is now engaged. 2012-01-20T20:02:26.28050Z,1327089746.2805 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The vehicle lat,lon is (36.8290, -121.8304) with dtw = 674.8 2012-01-20T20:02:26.28140Z,1327089746.2814 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Pure pursuit active. dtw = 674.5 m. 2012-01-20T20:03:50.23650Z,1327089830.2365 [Radio_Freewave](INFO): Powering down 2012-01-20T20:03:54.60060Z,1327089834.6006 [Radio_Freewave](INFO): Powering up 2012-01-20T20:04:13.92320Z,1327089853.9232 [Radio_Freewave](INFO): Powering down 2012-01-20T20:04:17.51810Z,1327089857.5181 [Radio_Freewave](INFO): Powering up 2012-01-20T20:04:26.55320Z,1327089866.5532 [Radio_Freewave](INFO): Powering down 2012-01-20T20:04:27.35270Z,1327089867.3527 [Radio_Freewave](INFO): Powering up 2012-01-20T20:06:03.82470Z,1327089963.8247 [Radio_Freewave](INFO): Powering down 2012-01-20T20:06:05.83950Z,1327089965.8395 [Radio_Freewave](INFO): Powering up 2012-01-20T20:06:58.78490Z,1327090018.7849 [Radio_Freewave](INFO): Powering down 2012-01-20T20:06:59.22350Z,1327090019.2235 [Radio_Freewave](INFO): Powering up 2012-01-20T20:07:00.70500Z,1327090020.705 [DAT](ERROR): Unexpected response:21:07:07.6887 , LVL= 32752, 32753, 32754, 32755, AGC= 65, IDX= 62 0.16,-1.146, |v|= 0.829, RAW= 269.2,- 0.6, CAL= 278.7,- 0.5, ROT= 357.7,- 0.5 Compass 340.4 Range 1 to 0 : 465.9 m 2012-01-20T20:07:13.03400Z,1327090033.034 [Radio_Freewave](INFO): Powering down 2012-01-20T20:07:16.22260Z,1327090036.2226 [Radio_Freewave](INFO): Powering up 2012-01-20T20:07:16.59650Z,1327090036.5965 [CTD_NeilBrown](ERROR): Salinity reading out of range: 21.9616909 psu 2012-01-20T20:07:59.18840Z,1327090079.1884 [Radio_Freewave](INFO): Powering down 2012-01-20T20:08:00.43270Z,1327090080.4327 [Radio_Freewave](INFO): Powering up 2012-01-20T20:09:45.33370Z,1327090185.3337 [Radio_Freewave](INFO): Powering down 2012-01-20T20:09:48.54140Z,1327090188.5414 [Radio_Freewave](INFO): Powering up 2012-01-20T20:09:52.54200Z,1327090192.542 [Radio_Freewave](INFO): Powering down 2012-01-20T20:09:58.15770Z,1327090198.1577 [Radio_Freewave](INFO): Powering up 2012-01-20T20:09:58.16570Z,1327090198.1657 [DAT](ERROR): Unexpected response:21:10:05.3791 , LVL= 32752, 32753, 32754, 32755, AGC= 63, IDX= 62 0.23, 0.995, |v|= 0.754, RAW= 269.1,- 8.0, CAL= 278.8,- 9.5, ROT= 357.8,- 9.5 Compass 356.2 Range 1 to 0 : 334.9 m 2012-01-20T20:09:58.17150Z,1327090198.1715 [CTD_NeilBrown](ERROR): Salinity reading out of range: 24.83250046 psu 2012-01-20T20:11:12.98030Z,1327090272.9803 [Radio_Freewave](INFO): Powering down 2012-01-20T20:11:14.98550Z,1327090274.9855 [Radio_Freewave](INFO): Powering up 2012-01-20T20:11:22.61380Z,1327090282.6138 [Radio_Freewave](INFO): Powering down 2012-01-20T20:11:28.15570Z,1327090288.1557 [Radio_Freewave](INFO): Powering up 2012-01-20T20:11:54.97130Z,1327090314.9713 [Radio_Freewave](INFO): Powering down 2012-01-20T20:11:58.16800Z,1327090318.168 [Radio_Freewave](INFO): Powering up 2012-01-20T20:12:09.72290Z,1327090329.7229 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure. 2012-01-20T20:12:09.72300Z,1327090329.723 [AHRS_sp3003D] Communications Fault, FailCount= 1 2012-01-20T20:12:09.72310Z,1327090329.7231 [AHRS_sp3003D](ERROR): Communications Fault 2012-01-20T20:12:10.00930Z,1327090330.0093 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D 2012-01-20T20:12:10.06600Z,1327090330.066 [AHRS_sp3003D](INFO): Powering down 2012-01-20T20:12:10.29980Z,1327090330.2998 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D 2012-01-20T20:12:10.29990Z,1327090330.2999 [AHRS_sp3003D] No Fault, FailCount= 1 2012-01-20T20:12:11.26420Z,1327090331.2642 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-01-20T20:12:31.88990Z,1327090351.8899 [Radio_Freewave](INFO): Powering down 2012-01-20T20:12:34.30790Z,1327090354.3079 [Radio_Freewave](INFO): Powering up 2012-01-20T20:12:48.59700Z,1327090368.597 [DAT](ERROR): Unexpected response:21:12:56.0175 , LVL= 32752, 31601, 32322, 32755, AGC= 61, IDX= 62-0.06,-2.805, |v|= 0.774, RAW= 273.6,- 3.7, CAL= 283.9,- 4.8, ROT= 2.9,- 4.8 Compass 357.1 Range 1 to 0 : 204.7 m 2012-01-20T20:12:51.99680Z,1327090371.9968 [Radio_Freewave](INFO): Powering down 2012-01-20T20:12:57.08580Z,1327090377.0858 [Radio_Freewave](INFO): Powering up 2012-01-20T20:13:12.41030Z,1327090392.4103 [Radio_Freewave](INFO): Powering down 2012-01-20T20:13:13.60730Z,1327090393.6073 [Radio_Freewave](INFO): Powering up 2012-01-20T20:14:28.58340Z,1327090468.5834 [Radio_Freewave](INFO): Powering down 2012-01-20T20:14:31.00850Z,1327090471.0085 [Radio_Freewave](INFO): Powering up 2012-01-20T20:14:56.68600Z,1327090496.686 [DAT](ERROR): Unexpected response:21:15:04.4435 , LVL= 32752, 32753, 32754, 32755, AGC= 56, IDX= 62-0.14,-1.616, |v|= 0.730, RAW= 275.0,- 7.3, CAL= 286.0,- 9.2, ROT= 5.0,- 9.2 Compass 354.1 Range 1 to 0 : 105.3 m 2012-01-20T20:15:08.74110Z,1327090508.7411 [DAT](ERROR): Unexpected response:21:15:16.4323 , LVL= 32752, 29857, 29010, 32755, AGC= 55, IDX= 60 0.32, 2.895, |v|= 0.776, RAW= 261.7,- 8.2, CAL= 272.0,- 8.6, ROT= 351.0,- 8.6 Compass 351.0 Range 1 to 0 : 96.3 m 2012-01-20T20:15:22.34230Z,1327090522.3423 [Radio_Freewave](INFO): Powering down 2012-01-20T20:15:22.72550Z,1327090522.7255 [Radio_Freewave](INFO): Powering up 2012-01-20T20:15:29.34580Z,1327090529.3458 [DAT](ERROR): Unexpected response:21:15:37.1132 , LVL= 32752, 32753, 32754, 32755, AGC= 53, IDX= 64 0.10,-2.868, |v|= 0.898, RAW= 272.7,- 1.1, CAL= 282.5,- 1.5, ROT= 1.5,- 1.5 Compass 1.0 Range 1 to 0 : 81.1 m 2012-01-20T20:15:33.29000Z,1327090533.29 [DAT](ERROR): Unexpected response:21:15:41.0599 , LVL= 32752, 32753, 32754, 32755, AGC= 55, IDX= 64 0.16,-0.817, |v|= 0.908, RAW= 271.3,- 0.3, CAL= 280.5,- 0.3, ROT= 359.5,- 0.3 Compass 357.8 Range 1 to 0 : 78.5 m 2012-01-20T20:15:37.30010Z,1327090537.3001 [DAT](ERROR): Unexpected response:21:15:45.2560 , LVL= 32752, 32753, 32754, 32755, AGC= 54, IDX= 30 0.13, 1.243, |v|= 0.728, RAW= 270.7,- 8.4, CAL= 280.4,-10.1, ROT= 359.4,-10.1 Compass 357.4 Range 1 to 0 : 75.3 m 2012-01-20T20:17:18.10860Z,1327090638.1086 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](IMPORTANT): Homing:: Terminating now. dtw = -0.3 m, DATRange = 33.7 m. 2012-01-20T20:17:18.10930Z,1327090638.1093 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](INFO): Homing::Reached Waypoint 36.82315615,-121.8290538 2012-01-20T20:17:18.10970Z,1327090638.1097 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint] Stopped 2012-01-20T20:17:18.10970Z,1327090638.1097 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Uninitialize HomingComponent. 2012-01-20T20:17:18.11170Z,1327090638.1117 [homing_altitude:WaypointOne](INFO): Completed homing_altitude:WaypointOne 2012-01-20T20:17:18.11180Z,1327090638.1118 [homing_altitude:WaypointOne] Stopped 2012-01-20T20:17:18.11190Z,1327090638.1119 [homing_altitude:WaypointOne](INFO): Aggregate::uninitialize homing_altitude:WaypointOne 2012-01-20T20:17:18.11200Z,1327090638.112 [homing_altitude:WaypointOne:A] Stopped 2012-01-20T20:17:18.11200Z,1327090638.112 [homing_altitude:WaypointOne:B.Pitch] Stopped 2012-01-20T20:17:18.11210Z,1327090638.1121 [homing_altitude:WaypointOne:C.Buoyancy] Stopped 2012-01-20T20:17:18.11210Z,1327090638.1121 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-01-20T20:17:18.11220Z,1327090638.1122 [homing_altitude:WaypointOne:D.SetSpeed] Stopped 2012-01-20T20:17:18.11250Z,1327090638.1125 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Uninitialize. 2012-01-20T20:17:18.11260Z,1327090638.1126 [homing_altitude:WaypointOne:E.PitchServo] Stopped 2012-01-20T20:17:18.11270Z,1327090638.1127 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Uninitialize. 2012-01-20T20:17:18.11280Z,1327090638.1128 [homing_altitude:PHONEHOMEWPT1] Running Loop=1 2012-01-20T20:17:18.11300Z,1327090638.113 [homing_altitude:PHONEHOMEWPT1](INFO): Aggregate::initialize homing_altitude:PHONEHOMEWPT1 2012-01-20T20:17:18.43710Z,1327090638.4371 [homing_altitude:SURFACECOMMS] Running Loop=1 2012-01-20T20:17:18.43740Z,1327090638.4374 [homing_altitude:SURFACECOMMS](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS 2012-01-20T20:17:18.43750Z,1327090638.4375 [homing_altitude:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T20:17:18.43760Z,1327090638.4376 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T20:17:18.43890Z,1327090638.4389 [homing_altitude:SURFACECOMMS:B] Running Loop=1 2012-01-20T20:17:18.43910Z,1327090638.4391 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS:B 2012-01-20T20:17:18.43920Z,1327090638.4392 [homing_altitude:SURFACECOMMS:B:A] Running Loop=1 2012-01-20T20:17:18.85120Z,1327090638.8512 [DAT](INFO): Powering down 2012-01-20T20:17:19.23550Z,1327090639.2355 [NAL9601](INFO): Powering up 2012-01-20T20:18:24.84740Z,1327090704.8474 [NAL9601](INFO): NAL9601 initialized 2012-01-20T20:18:26.06670Z,1327090706.0667 [NAL9601](IMPORTANT): GPS fix at: 1327090678 2012-01-20T20:18:26.08010Z,1327090706.0801 [homing_altitude:SURFACECOMMS:B:A] Stopped 2012-01-20T20:18:26.08040Z,1327090706.0804 [homing_altitude:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T20:18:41.92870Z,1327090721.9287 [NAL9601](INFO): SBD MO Status=1, MOMSN=42262, MT Status=0, MTMSN=0 2012-01-20T20:18:42.10730Z,1327090722.1073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:18:42.10750Z,1327090722.1075 [NAL9601](INFO): Packets left to send: 28 2012-01-20T20:18:42.10860Z,1327090722.1086 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000214 2012-01-20T20:18:55.53700Z,1327090735.537 [NAL9601](INFO): SBD MO Status=1, MOMSN=42263, MT Status=0, MTMSN=0 2012-01-20T20:18:55.68740Z,1327090735.6874 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:18:55.68760Z,1327090735.6876 [NAL9601](INFO): Packets left to send: 27 2012-01-20T20:18:55.68870Z,1327090735.6887 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000215 2012-01-20T20:19:10.41310Z,1327090750.4131 [NAL9601](INFO): SBD MO Status=1, MOMSN=42264, MT Status=0, MTMSN=0 2012-01-20T20:19:10.56730Z,1327090750.5673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:19:10.56750Z,1327090750.5675 [NAL9601](INFO): Packets left to send: 26 2012-01-20T20:19:10.56860Z,1327090750.5686 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000216 2012-01-20T20:19:45.73380Z,1327090785.7338 [NAL9601](INFO): SBD MO Status=2, MOMSN=42265, MT Status=2, MTMSN=0 2012-01-20T20:19:45.73400Z,1327090785.734 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-01-20T20:20:20.92820Z,1327090820.9282 [NAL9601](INFO): SBD MO Status=2, MOMSN=42265, MT Status=0, MTMSN=0 2012-01-20T20:20:20.92850Z,1327090820.9285 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-01-20T20:20:38.46470Z,1327090838.4647 [NAL9601](INFO): SBD MO Status=1, MOMSN=42265, MT Status=0, MTMSN=0 2012-01-20T20:20:38.62970Z,1327090838.6297 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:20:38.62990Z,1327090838.6299 [NAL9601](INFO): Packets left to send: 25 2012-01-20T20:20:38.63100Z,1327090838.631 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000217 2012-01-20T20:20:48.59780Z,1327090848.5978 [NAL9601](INFO): SBD MO Status=2, MOMSN=42266, MT Status=2, MTMSN=0 2012-01-20T20:20:48.59810Z,1327090848.5981 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-01-20T20:21:08.11730Z,1327090868.1173 [NAL9601](INFO): SBD MO Status=1, MOMSN=42266, MT Status=0, MTMSN=0 2012-01-20T20:21:08.28330Z,1327090868.2833 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:21:08.28350Z,1327090868.2835 [NAL9601](INFO): Packets left to send: 24 2012-01-20T20:21:08.28480Z,1327090868.2848 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000218 2012-01-20T20:21:20.67780Z,1327090880.6778 [NAL9601](INFO): SBD MO Status=1, MOMSN=42267, MT Status=0, MTMSN=0 2012-01-20T20:21:20.86340Z,1327090880.8634 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:21:20.86360Z,1327090880.8636 [NAL9601](INFO): Packets left to send: 23 2012-01-20T20:21:20.86480Z,1327090880.8648 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000219 2012-01-20T20:21:29.20430Z,1327090889.2043 [NAL9601](INFO): SBD MO Status=1, MOMSN=42268, MT Status=0, MTMSN=0 2012-01-20T20:21:29.35130Z,1327090889.3513 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:21:29.35160Z,1327090889.3516 [NAL9601](INFO): Packets left to send: 22 2012-01-20T20:21:29.35280Z,1327090889.3528 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000220 2012-01-20T20:21:39.33470Z,1327090899.3347 [NAL9601](INFO): SBD MO Status=1, MOMSN=42269, MT Status=0, MTMSN=0 2012-01-20T20:21:39.53530Z,1327090899.5353 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:21:39.53550Z,1327090899.5355 [NAL9601](INFO): Packets left to send: 21 2012-01-20T20:21:39.53660Z,1327090899.5366 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000221 2012-01-20T20:21:49.97480Z,1327090909.9748 [NAL9601](INFO): SBD MO Status=1, MOMSN=42270, MT Status=0, MTMSN=0 2012-01-20T20:21:50.11930Z,1327090910.1193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:21:50.11950Z,1327090910.1195 [NAL9601](INFO): Packets left to send: 20 2012-01-20T20:21:50.12060Z,1327090910.1206 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000222 2012-01-20T20:22:02.35370Z,1327090922.3537 [NAL9601](INFO): SBD MO Status=1, MOMSN=42271, MT Status=0, MTMSN=0 2012-01-20T20:22:02.49930Z,1327090922.4993 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:22:02.49950Z,1327090922.4995 [NAL9601](INFO): Packets left to send: 19 2012-01-20T20:22:02.50060Z,1327090922.5006 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000223 2012-01-20T20:22:12.11950Z,1327090932.1195 [NAL9601](INFO): SBD MO Status=2, MOMSN=42272, MT Status=2, MTMSN=0 2012-01-20T20:22:12.11970Z,1327090932.1197 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-01-20T20:22:27.23020Z,1327090947.2302 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42272, MT Status=1, MTMSN=3484 2012-01-20T20:22:27.36330Z,1327090947.3633 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:22:27.36350Z,1327090947.3635 [NAL9601](INFO): Packets left to send: 18 2012-01-20T20:22:27.71690Z,1327090947.7169 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000224 2012-01-20T20:22:28.12850Z,1327090948.1285 [NAL9601](INFO): Received command:load Transport/transit_3km.xml;set transit_3km.Wpt1Lat 36.829 degree;set transit_3km.Wpt1Lon -121.831 degree;run 2012-01-20T20:22:28.14110Z,1327090948.1411 [CommandLine](IMPORTANT): got command load ./Missions/Transport/transit_3km.xml 2012-01-20T20:22:28.14120Z,1327090948.1412 [homing_altitude] Stopped 2012-01-20T20:22:28.14130Z,1327090948.1413 [homing_altitude](INFO): Aggregate::uninitialize homing_altitude 2012-01-20T20:22:28.14140Z,1327090948.1414 [homing_altitude:A.AltitudeEnvelope] Stopped 2012-01-20T20:22:28.14150Z,1327090948.1415 [homing_altitude:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-01-20T20:22:28.14160Z,1327090948.1416 [homing_altitude:B.DepthEnvelope] Stopped 2012-01-20T20:22:28.14160Z,1327090948.1416 [homing_altitude:B.DepthEnvelope](DEBUG): Uninitialize. 2012-01-20T20:22:28.14170Z,1327090948.1417 [homing_altitude:C.OffshoreEnvelope] Stopped 2012-01-20T20:22:28.14180Z,1327090948.1418 [homing_altitude:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-01-20T20:22:28.14180Z,1327090948.1418 [homing_altitude:D] Stopped 2012-01-20T20:22:28.14190Z,1327090948.1419 [homing_altitude:SURFACECOMMS] Stopped 2012-01-20T20:22:28.14200Z,1327090948.142 [homing_altitude:SURFACECOMMS](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS 2012-01-20T20:22:28.14210Z,1327090948.1421 [homing_altitude:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T20:22:28.14220Z,1327090948.1422 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T20:22:28.14220Z,1327090948.1422 [homing_altitude:SURFACECOMMS:B] Stopped 2012-01-20T20:22:28.14240Z,1327090948.1424 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS:B 2012-01-20T20:22:28.14240Z,1327090948.1424 [homing_altitude:SURFACECOMMS:B:B] Stopped 2012-01-20T20:22:28.14250Z,1327090948.1425 [homing_altitude:PHONEHOMEWPT1] Stopped 2012-01-20T20:22:28.14260Z,1327090948.1426 [homing_altitude:PHONEHOMEWPT1](INFO): Aggregate::uninitialize homing_altitude:PHONEHOMEWPT1 2012-01-20T20:22:28.15230Z,1327090948.1523 [MissionManager](INFO): Loading Mission: ./Missions/Transport/transit_3km.xml 2012-01-20T20:22:28.18600Z,1327090948.186 [MissionManager](INFO): DefineArg transit_3km.ApproachDepth = 10 m 2012-01-20T20:22:28.18830Z,1327090948.1883 [MissionManager](INFO): DefineArg transit_3km.Wpt1Lat = 36.806966 arcdeg 2012-01-20T20:22:28.19050Z,1327090948.1905 [MissionManager](INFO): DefineArg transit_3km.Wpt1Lon = -121.824326 arcdeg 2012-01-20T20:22:28.19270Z,1327090948.1927 [MissionManager](INFO): DefineArg transit_3km.Speed = 1 m/s 2012-01-20T20:22:28.19470Z,1327090948.1947 [MissionManager](INFO): DefineArg transit_3km.MaxDepth = 20 m 2012-01-20T20:22:28.19630Z,1327090948.1963 [transit_3km:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-01-20T20:22:28.20110Z,1327090948.2011 [transit_3km:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-01-20T20:22:28.20630Z,1327090948.2063 [transit_3km:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-01-20T20:22:28.21120Z,1327090948.2112 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-01-20T20:22:28.21440Z,1327090948.2144 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Construct. 2012-01-20T20:22:28.22690Z,1327090948.2269 [transit_3km:WaypointOne:A.Pitch](DEBUG): Construct. 2012-01-20T20:22:28.23140Z,1327090948.2314 [transit_3km:WaypointOne:B.SetSpeed](DEBUG): Construct. 2012-01-20T20:22:28.23360Z,1327090948.2336 [transit_3km:WaypointOne:WaypointW1.Waypoint](DEBUG): Construct Waypoint. 2012-01-20T20:22:28.24220Z,1327090948.2422 [MissionManager](DEBUG): 10.0 36.806966 -121.824326 1 Maximum depth for the entire mission. 20 7 1 0 35 2012-01-20T20:22:28.24260Z,1327090948.2426 [CommandLine](IMPORTANT): Loaded ./Missions/Transport/transit_3km.xml 2012-01-20T20:22:28.32950Z,1327090948.3295 [CommandLine](IMPORTANT): got command set transit_3km.Wpt1Lat 36.82899857 degree 2012-01-20T20:22:28.41340Z,1327090948.4134 [CommandLine](IMPORTANT): got command set transit_3km.Wpt1Lon -121.8310013 degree 2012-01-20T20:22:28.41470Z,1327090948.4147 [CommandLine](IMPORTANT): got command run 2012-01-20T20:22:28.41490Z,1327090948.4149 [CommandLine](IMPORTANT): Running 2012-01-20T20:22:28.42780Z,1327090948.4278 [MissionManager](IMPORTANT): Started mission transit_3km 2012-01-20T20:22:28.42790Z,1327090948.4279 [transit_3km] Running Loop=1 2012-01-20T20:22:28.42810Z,1327090948.4281 [transit_3km](INFO): Aggregate::initialize transit_3km 2012-01-20T20:22:28.42820Z,1327090948.4282 [transit_3km:A.AltitudeEnvelope] Running Loop=1 2012-01-20T20:22:28.42820Z,1327090948.4282 [transit_3km:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-01-20T20:22:28.42840Z,1327090948.4284 [transit_3km:B.DepthEnvelope] Running Loop=1 2012-01-20T20:22:28.42840Z,1327090948.4284 [transit_3km:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-01-20T20:22:28.42910Z,1327090948.4291 [transit_3km:C.OffshoreEnvelope] Running Loop=1 2012-01-20T20:22:28.42910Z,1327090948.4291 [transit_3km:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-01-20T20:22:28.42930Z,1327090948.4293 [transit_3km:SURFACECOMMS] Running Loop=1 2012-01-20T20:22:28.42940Z,1327090948.4294 [transit_3km:SURFACECOMMS](INFO): Aggregate::initialize transit_3km:SURFACECOMMS 2012-01-20T20:22:28.42950Z,1327090948.4295 [transit_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T20:22:28.42960Z,1327090948.4296 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T20:22:28.43130Z,1327090948.4313 [transit_3km:SURFACECOMMS:B] Running Loop=1 2012-01-20T20:22:28.43140Z,1327090948.4314 [transit_3km:SURFACECOMMS:B](INFO): Aggregate::initialize transit_3km:SURFACECOMMS:B 2012-01-20T20:22:28.43150Z,1327090948.4315 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-01-20T20:22:28.43160Z,1327090948.4316 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-01-20T20:22:28.43180Z,1327090948.4318 [transit_3km:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T20:22:28.43180Z,1327090948.4318 [transit_3km:C.OffshoreEnvelope] Running Loop=1 2012-01-20T20:22:28.43270Z,1327090948.4327 [transit_3km:B.DepthEnvelope] Running Loop=1 2012-01-20T20:22:28.43380Z,1327090948.4338 [transit_3km:A.AltitudeEnvelope] Running Loop=1 2012-01-20T20:22:28.64850Z,1327090948.6485 [transit_3km:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-01-20T20:22:28.64940Z,1327090948.6494 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-01-20T20:22:30.69880Z,1327090950.6988 [NAL9601](IMPORTANT): GPS fix at: 1327090956 2012-01-20T20:22:30.71210Z,1327090950.7121 [transit_3km:SURFACECOMMS:B:B] Stopped 2012-01-20T20:22:30.71220Z,1327090950.7122 [transit_3km:SURFACECOMMS:B:C] Running Loop=1 2012-01-20T20:22:31.03950Z,1327090951.0395 [transit_3km:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-01-20T20:22:49.82280Z,1327090969.8228 [NAL9601](INFO): SBD MO Status=1, MOMSN=42273, MT Status=0, MTMSN=0 2012-01-20T20:22:50.02330Z,1327090970.0233 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:22:50.02350Z,1327090970.0235 [NAL9601](INFO): Packets left to send: 17 2012-01-20T20:22:50.02470Z,1327090970.0247 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000225 2012-01-20T20:23:03.54260Z,1327090983.5426 [NAL9601](INFO): SBD MO Status=1, MOMSN=42274, MT Status=0, MTMSN=0 2012-01-20T20:23:03.70330Z,1327090983.7033 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:23:03.70360Z,1327090983.7036 [NAL9601](INFO): Packets left to send: 16 2012-01-20T20:23:03.70550Z,1327090983.7055 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000226 2012-01-20T20:23:15.64120Z,1327090995.6412 [NAL9601](INFO): SBD MO Status=1, MOMSN=42275, MT Status=0, MTMSN=0 2012-01-20T20:23:15.78330Z,1327090995.7833 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:23:15.78350Z,1327090995.7835 [NAL9601](INFO): Packets left to send: 15 2012-01-20T20:23:15.78470Z,1327090995.7847 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000227 2012-01-20T20:23:28.51790Z,1327091008.5179 [NAL9601](INFO): SBD MO Status=1, MOMSN=42276, MT Status=0, MTMSN=0 2012-01-20T20:23:28.66730Z,1327091008.6673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:23:28.66750Z,1327091008.6675 [NAL9601](INFO): Packets left to send: 14 2012-01-20T20:23:28.66860Z,1327091008.6686 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000228 2012-01-20T20:23:40.63440Z,1327091020.6344 [NAL9601](INFO): SBD MO Status=1, MOMSN=42277, MT Status=0, MTMSN=0 2012-01-20T20:23:40.84330Z,1327091020.8433 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:23:40.84350Z,1327091020.8435 [NAL9601](INFO): Packets left to send: 13 2012-01-20T20:23:40.84460Z,1327091020.8446 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000229 2012-01-20T20:23:52.69690Z,1327091032.6969 [NAL9601](INFO): SBD MO Status=1, MOMSN=42278, MT Status=0, MTMSN=0 2012-01-20T20:23:52.82740Z,1327091032.8274 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:23:52.82760Z,1327091032.8276 [NAL9601](INFO): Packets left to send: 12 2012-01-20T20:23:52.92740Z,1327091032.9274 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000230 2012-01-20T20:24:00.39070Z,1327091040.3907 [NAL9601](INFO): SBD MO Status=1, MOMSN=42279, MT Status=0, MTMSN=0 2012-01-20T20:24:00.51540Z,1327091040.5154 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:24:00.51560Z,1327091040.5156 [NAL9601](INFO): Packets left to send: 11 2012-01-20T20:24:00.51670Z,1327091040.5167 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000231 2012-01-20T20:24:08.48580Z,1327091048.4858 [NAL9601](INFO): SBD MO Status=1, MOMSN=42280, MT Status=0, MTMSN=0 2012-01-20T20:24:08.70330Z,1327091048.7033 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:24:08.70350Z,1327091048.7035 [NAL9601](INFO): Packets left to send: 10 2012-01-20T20:24:08.70460Z,1327091048.7046 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000232 2012-01-20T20:24:22.16580Z,1327091062.1658 [NAL9601](INFO): SBD MO Status=1, MOMSN=42281, MT Status=0, MTMSN=0 2012-01-20T20:24:22.37950Z,1327091062.3795 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:24:22.37970Z,1327091062.3797 [NAL9601](INFO): Packets left to send: 9 2012-01-20T20:24:22.38080Z,1327091062.3808 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000233 2012-01-20T20:24:32.25390Z,1327091072.2539 [NAL9601](INFO): SBD MO Status=1, MOMSN=42282, MT Status=0, MTMSN=0 2012-01-20T20:24:32.47130Z,1327091072.4713 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:24:32.47150Z,1327091072.4715 [NAL9601](INFO): Packets left to send: 8 2012-01-20T20:24:32.47260Z,1327091072.4726 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000234 2012-01-20T20:24:40.72990Z,1327091080.7299 [NAL9601](INFO): SBD MO Status=1, MOMSN=42283, MT Status=0, MTMSN=0 2012-01-20T20:24:40.85130Z,1327091080.8513 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:24:40.85150Z,1327091080.8515 [NAL9601](INFO): Packets left to send: 7 2012-01-20T20:24:40.85270Z,1327091080.8527 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000235 2012-01-20T20:24:47.71430Z,1327091087.7143 [NAL9601](INFO): SBD MO Status=2, MOMSN=42284, MT Status=2, MTMSN=0 2012-01-20T20:24:47.71450Z,1327091087.7145 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-01-20T20:25:00.04050Z,1327091100.0405 [NAL9601](INFO): SBD MO Status=1, MOMSN=42284, MT Status=0, MTMSN=0 2012-01-20T20:25:00.23940Z,1327091100.2394 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:25:00.23960Z,1327091100.2396 [NAL9601](INFO): Packets left to send: 6 2012-01-20T20:25:00.24070Z,1327091100.2407 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000236 2012-01-20T20:25:10.87680Z,1327091110.8768 [NAL9601](INFO): SBD MO Status=1, MOMSN=42285, MT Status=0, MTMSN=0 2012-01-20T20:25:11.00730Z,1327091111.0073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:25:11.00760Z,1327091111.0076 [NAL9601](INFO): Packets left to send: 5 2012-01-20T20:25:11.00870Z,1327091111.0087 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000237 2012-01-20T20:25:19.76190Z,1327091119.7619 [NAL9601](INFO): SBD MO Status=1, MOMSN=42286, MT Status=0, MTMSN=0 2012-01-20T20:25:19.89130Z,1327091119.8913 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:25:19.89150Z,1327091119.8915 [NAL9601](INFO): Packets left to send: 4 2012-01-20T20:25:19.89280Z,1327091119.8928 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000238 2012-01-20T20:25:27.51140Z,1327091127.5114 [NAL9601](INFO): SBD MO Status=1, MOMSN=42287, MT Status=0, MTMSN=0 2012-01-20T20:25:27.67930Z,1327091127.6793 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:25:27.67950Z,1327091127.6795 [NAL9601](INFO): Packets left to send: 3 2012-01-20T20:25:27.71820Z,1327091127.7182 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000239 2012-01-20T20:25:37.14980Z,1327091137.1498 [NAL9601](INFO): SBD MO Status=1, MOMSN=42288, MT Status=0, MTMSN=0 2012-01-20T20:25:37.36330Z,1327091137.3633 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:25:37.36350Z,1327091137.3635 [NAL9601](INFO): Packets left to send: 2 2012-01-20T20:25:37.36470Z,1327091137.3647 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000240 2012-01-20T20:25:46.03780Z,1327091146.0378 [NAL9601](INFO): SBD MO Status=1, MOMSN=42289, MT Status=0, MTMSN=0 2012-01-20T20:25:46.25530Z,1327091146.2553 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:25:46.25550Z,1327091146.2555 [NAL9601](INFO): Packets left to send: 1 2012-01-20T20:25:46.25670Z,1327091146.2567 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000241 2012-01-20T20:25:56.57380Z,1327091156.5738 [NAL9601](INFO): SBD MO Status=1, MOMSN=42290, MT Status=0, MTMSN=0 2012-01-20T20:25:56.73530Z,1327091156.7353 [NAL9601](INFO): Sent 7 bytes from file Logs/20120120T195811/shore0001.lzma 2012-01-20T20:25:56.73550Z,1327091156.7355 [NAL9601](INFO): Packets left to send: 0 2012-01-20T20:25:56.73670Z,1327091156.7367 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000242 2012-01-20T20:26:06.59440Z,1327091166.5944 [NAL9601](INFO): SBD MO Status=0, MOMSN=42291, MT Status=0, MTMSN=0 2012-01-20T20:26:20.45850Z,1327091180.4585 [NAL9601](INFO): SBD MO Status=1, MOMSN=42292, MT Status=0, MTMSN=0 2012-01-20T20:26:20.59530Z,1327091180.5953 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:26:20.59550Z,1327091180.5955 [NAL9601](INFO): Packets left to send: 8 2012-01-20T20:26:20.59670Z,1327091180.5967 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000243 2012-01-20T20:26:28.98570Z,1327091188.9857 [NAL9601](INFO): SBD MO Status=1, MOMSN=42293, MT Status=0, MTMSN=0 2012-01-20T20:26:29.18330Z,1327091189.1833 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:26:29.18360Z,1327091189.1836 [NAL9601](INFO): Packets left to send: 7 2012-01-20T20:26:29.18470Z,1327091189.1847 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000244 2012-01-20T20:26:37.48570Z,1327091197.4857 [NAL9601](INFO): SBD MO Status=1, MOMSN=42294, MT Status=0, MTMSN=0 2012-01-20T20:26:37.67140Z,1327091197.6714 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:26:37.67160Z,1327091197.6716 [NAL9601](INFO): Packets left to send: 6 2012-01-20T20:26:37.67270Z,1327091197.6727 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000245 2012-01-20T20:26:50.01290Z,1327091210.0129 [NAL9601](INFO): SBD MO Status=1, MOMSN=42295, MT Status=0, MTMSN=0 2012-01-20T20:26:50.15130Z,1327091210.1513 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:26:50.15150Z,1327091210.1515 [NAL9601](INFO): Packets left to send: 5 2012-01-20T20:26:50.15270Z,1327091210.1527 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000246 2012-01-20T20:26:59.29460Z,1327091219.2946 [NAL9601](INFO): SBD MO Status=1, MOMSN=42296, MT Status=0, MTMSN=0 2012-01-20T20:26:59.43930Z,1327091219.4393 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:26:59.43950Z,1327091219.4395 [NAL9601](INFO): Packets left to send: 4 2012-01-20T20:26:59.44070Z,1327091219.4407 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000247 2012-01-20T20:27:07.03210Z,1327091227.0321 [NAL9601](INFO): SBD MO Status=1, MOMSN=42297, MT Status=0, MTMSN=0 2012-01-20T20:27:07.22740Z,1327091227.2274 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:27:07.22760Z,1327091227.2276 [NAL9601](INFO): Packets left to send: 3 2012-01-20T20:27:07.22880Z,1327091227.2288 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000248 2012-01-20T20:27:14.80290Z,1327091234.8029 [NAL9601](INFO): SBD MO Status=1, MOMSN=42298, MT Status=0, MTMSN=0 2012-01-20T20:27:15.01530Z,1327091235.0153 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:27:15.01550Z,1327091235.0155 [NAL9601](INFO): Packets left to send: 2 2012-01-20T20:27:15.01680Z,1327091235.0168 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000249 2012-01-20T20:27:23.72490Z,1327091243.7249 [NAL9601](INFO): SBD MO Status=1, MOMSN=42299, MT Status=0, MTMSN=0 2012-01-20T20:27:23.89930Z,1327091243.8993 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:27:23.89950Z,1327091243.8995 [NAL9601](INFO): Packets left to send: 1 2012-01-20T20:27:23.90070Z,1327091243.9007 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000250 2012-01-20T20:27:30.62920Z,1327091250.6292 [NAL9601](INFO): SBD MO Status=1, MOMSN=42300, MT Status=0, MTMSN=0 2012-01-20T20:27:30.78730Z,1327091250.7873 [NAL9601](INFO): Sent 4 bytes from file Logs/20120120T195811/shore0002.lzma 2012-01-20T20:27:30.78750Z,1327091250.7875 [NAL9601](INFO): Packets left to send: 0 2012-01-20T20:27:30.78870Z,1327091250.7887 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000251 2012-01-20T20:27:39.40870Z,1327091259.4087 [NAL9601](INFO): SBD MO Status=0, MOMSN=42301, MT Status=0, MTMSN=0 2012-01-20T20:27:39.60110Z,1327091259.6011 [transit_3km:SURFACECOMMS:B:C] Stopped 2012-01-20T20:27:39.60120Z,1327091259.6012 [transit_3km:SURFACECOMMS:B:D] Running Loop=1 2012-01-20T20:27:39.83910Z,1327091259.8391 [transit_3km:SURFACECOMMS:B:D](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-01-20T20:27:41.81540Z,1327091261.8154 [NAL9601](IMPORTANT): GPS fix at: 1327091268 2012-01-20T20:27:41.82880Z,1327091261.8288 [transit_3km:SURFACECOMMS:B:D] Stopped 2012-01-20T20:27:41.82920Z,1327091261.8292 [transit_3km:SURFACECOMMS:B](INFO): Completed transit_3km:SURFACECOMMS:B 2012-01-20T20:27:41.82930Z,1327091261.8293 [transit_3km:SURFACECOMMS:B] Stopped 2012-01-20T20:27:41.82940Z,1327091261.8294 [transit_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize transit_3km:SURFACECOMMS:B 2012-01-20T20:27:41.82950Z,1327091261.8295 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-01-20T20:27:41.82960Z,1327091261.8296 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-01-20T20:27:41.83000Z,1327091261.83 [transit_3km:SURFACECOMMS](INFO): Completed transit_3km:SURFACECOMMS 2012-01-20T20:27:41.83010Z,1327091261.8301 [transit_3km:SURFACECOMMS] Stopped 2012-01-20T20:27:41.83030Z,1327091261.8303 [transit_3km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_3km:SURFACECOMMS 2012-01-20T20:27:41.83030Z,1327091261.8303 [transit_3km:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T20:27:41.83040Z,1327091261.8304 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T20:27:41.83050Z,1327091261.8305 [transit_3km:WaypointOne] Running Loop=1 2012-01-20T20:27:41.83070Z,1327091261.8307 [transit_3km:WaypointOne](INFO): Aggregate::initialize transit_3km:WaypointOne 2012-01-20T20:27:41.83090Z,1327091261.8309 [transit_3km:WaypointOne:A.Pitch] Running Loop=1 2012-01-20T20:27:41.83100Z,1327091261.831 [transit_3km:WaypointOne:A.Pitch](DEBUG): Initialize. 2012-01-20T20:27:41.83120Z,1327091261.8312 [transit_3km:WaypointOne:B.SetSpeed] Running Loop=1 2012-01-20T20:27:41.83130Z,1327091261.8313 [transit_3km:WaypointOne:B.SetSpeed](DEBUG): Initialize. 2012-01-20T20:27:41.83160Z,1327091261.8316 [transit_3km:WaypointOne:WaypointW1.Waypoint] Running Loop=1 2012-01-20T20:27:41.83160Z,1327091261.8316 [transit_3km:WaypointOne:WaypointW1.Waypoint](DEBUG): Initialize WaypointComponent. 2012-01-20T20:27:42.23500Z,1327091262.235 [transit_3km:WaypointOne:B.SetSpeed] Running Loop=1 2012-01-20T20:27:42.23590Z,1327091262.2359 [transit_3km:WaypointOne:A.Pitch] Running Loop=1 2012-01-20T20:27:48.25130Z,1327091268.2513 [NAL9601](INFO): Powering down 2012-01-20T20:28:09.68800Z,1327091289.688 [Radio_Freewave](INFO): Powering down 2012-01-20T20:28:10.50320Z,1327091290.5032 [Radio_Freewave](INFO): Powering up 2012-01-20T20:28:12.05780Z,1327091292.0578 [Radio_Freewave](INFO): Powering down 2012-01-20T20:38:31.14550Z,1327091911.1455 [Batt_Ocean_Server](INFO): Over Temperature Alarm! Battery Bank #4 STATUS: 5911 2012-01-20T20:38:31.14590Z,1327091911.1459 [Batt_Ocean_Server](INFO): Not Initialized - Battery Bank #4 STATUS: 5911 2012-01-20T20:42:04.34560Z,1327092124.3456 [transit_3km:WaypointOne:WaypointW1.Waypoint](INFO): Reached Waypoint: 36.82899814,-121.8309995 2012-01-20T20:42:04.34590Z,1327092124.3459 [transit_3km:WaypointOne:WaypointW1.Waypoint] Stopped 2012-01-20T20:42:04.34590Z,1327092124.3459 [transit_3km:WaypointOne:WaypointW1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-01-20T20:42:04.34610Z,1327092124.3461 [transit_3km:WaypointOne:PHONEHOMEWPT1] Running Loop=1 2012-01-20T20:42:04.34620Z,1327092124.3462 [transit_3km:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::initialize transit_3km:WaypointOne:PHONEHOMEWPT1 2012-01-20T20:42:04.69040Z,1327092124.6904 [transit_3km:SURFACECOMMS] Running Loop=1 2012-01-20T20:42:04.69060Z,1327092124.6906 [transit_3km:SURFACECOMMS](INFO): Aggregate::initialize transit_3km:SURFACECOMMS 2012-01-20T20:42:04.69070Z,1327092124.6907 [transit_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T20:42:04.69070Z,1327092124.6907 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T20:45:00.82810Z,1327092300.8281 [Radio_Freewave](INFO): Powering up 2012-01-20T20:45:00.83820Z,1327092300.8382 [transit_3km:SURFACECOMMS:B] Running Loop=1 2012-01-20T20:45:00.83830Z,1327092300.8383 [transit_3km:SURFACECOMMS:B](INFO): Aggregate::initialize transit_3km:SURFACECOMMS:B 2012-01-20T20:45:00.83840Z,1327092300.8384 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-01-20T20:45:00.83850Z,1327092300.8385 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-01-20T20:45:00.83870Z,1327092300.8387 [transit_3km:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T20:45:01.55720Z,1327092301.5572 [NAL9601](INFO): Powering up 2012-01-20T20:46:07.27440Z,1327092367.2744 [NAL9601](INFO): NAL9601 initialized 2012-01-20T20:46:08.34250Z,1327092368.3425 [NAL9601](IMPORTANT): GPS fix at: 1327092376 2012-01-20T20:46:08.35610Z,1327092368.3561 [transit_3km:SURFACECOMMS:B:B] Stopped 2012-01-20T20:46:08.35620Z,1327092368.3562 [transit_3km:SURFACECOMMS:B:C] Running Loop=1 2012-01-20T20:46:25.51170Z,1327092385.5117 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42302, MT Status=1, MTMSN=3485 2012-01-20T20:46:25.72730Z,1327092385.7273 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:46:25.72760Z,1327092385.7276 [NAL9601](INFO): Packets left to send: 8 2012-01-20T20:46:25.72870Z,1327092385.7287 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000252 2012-01-20T20:46:26.04030Z,1327092386.0403 [NAL9601](INFO): Received command:restart logs 2012-01-20T20:46:26.08730Z,1327092386.0873 [CommandLine](IMPORTANT): got command restart logs