2012-01-20T20:46:40.56350Z,1327092400.5635 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=42303, MT Status=1, MTMSN=3486 2012-01-20T20:46:40.70730Z,1327092400.7073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:46:40.70760Z,1327092400.7076 [NAL9601](INFO): Packets left to send: 7 2012-01-20T20:46:40.70850Z,1327092400.7085 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000253 2012-01-20T20:46:41.22800Z,1327092401.228 [NAL9601](INFO): Received command:load Engineering/Homing_altitude.xml;set homing_altitude.Altitude 9 meter;set homing_altitude.dockBearing 350 degree;set homing_altitude.finalApproach 0.1 meter;set homing_altitude.clusterRadius 75 meter;run 2012-01-20T20:46:41.24630Z,1327092401.2463 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/Homing_altitude.xml 2012-01-20T20:46:41.24640Z,1327092401.2464 [transit_3km] Stopped 2012-01-20T20:46:41.24660Z,1327092401.2466 [transit_3km](INFO): Aggregate::uninitialize transit_3km 2012-01-20T20:46:41.24660Z,1327092401.2466 [transit_3km:A.AltitudeEnvelope] Stopped 2012-01-20T20:46:41.24670Z,1327092401.2467 [transit_3km:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-01-20T20:46:41.24700Z,1327092401.247 [transit_3km:B.DepthEnvelope] Stopped 2012-01-20T20:46:41.24710Z,1327092401.2471 [transit_3km:B.DepthEnvelope](DEBUG): Uninitialize. 2012-01-20T20:46:41.24720Z,1327092401.2472 [transit_3km:C.OffshoreEnvelope] Stopped 2012-01-20T20:46:41.24730Z,1327092401.2473 [transit_3km:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-01-20T20:46:41.24730Z,1327092401.2473 [transit_3km:SURFACECOMMS] Stopped 2012-01-20T20:46:41.24750Z,1327092401.2475 [transit_3km:SURFACECOMMS](INFO): Aggregate::uninitialize transit_3km:SURFACECOMMS 2012-01-20T20:46:41.24760Z,1327092401.2476 [transit_3km:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T20:46:41.24760Z,1327092401.2476 [transit_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T20:46:41.24770Z,1327092401.2477 [transit_3km:SURFACECOMMS:B] Stopped 2012-01-20T20:46:41.24780Z,1327092401.2478 [transit_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize transit_3km:SURFACECOMMS:B 2012-01-20T20:46:41.24790Z,1327092401.2479 [transit_3km:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-01-20T20:46:41.24800Z,1327092401.248 [transit_3km:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-01-20T20:46:41.24800Z,1327092401.248 [transit_3km:SURFACECOMMS:B:C] Stopped 2012-01-20T20:46:41.24810Z,1327092401.2481 [transit_3km:WaypointOne] Stopped 2012-01-20T20:46:41.24820Z,1327092401.2482 [transit_3km:WaypointOne](INFO): Aggregate::uninitialize transit_3km:WaypointOne 2012-01-20T20:46:41.24830Z,1327092401.2483 [transit_3km:WaypointOne:A.Pitch] Stopped 2012-01-20T20:46:41.24830Z,1327092401.2483 [transit_3km:WaypointOne:B.SetSpeed] Stopped 2012-01-20T20:46:41.24840Z,1327092401.2484 [transit_3km:WaypointOne:B.SetSpeed](DEBUG): Uninitialize. 2012-01-20T20:46:41.24850Z,1327092401.2485 [transit_3km:WaypointOne:PHONEHOMEWPT1] Stopped 2012-01-20T20:46:41.24860Z,1327092401.2486 [transit_3km:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize transit_3km:WaypointOne:PHONEHOMEWPT1 2012-01-20T20:46:41.25510Z,1327092401.2551 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/Homing_altitude.xml 2012-01-20T20:46:41.31350Z,1327092401.3135 [MissionManager](INFO): DefineArg homing_altitude.Wpt1Lat = 36.823 arcdeg 2012-01-20T20:46:41.31570Z,1327092401.3157 [MissionManager](INFO): DefineArg homing_altitude.Wpt1Lon = -121.83 arcdeg 2012-01-20T20:46:41.31800Z,1327092401.318 [MissionManager](INFO): DefineArg homing_altitude.Altitude = 10 m 2012-01-20T20:46:41.32010Z,1327092401.3201 [MissionManager](INFO): DefineArg homing_altitude.Speed = 1 m/s 2012-01-20T20:46:41.32230Z,1327092401.3223 [MissionManager](INFO): DefineArg homing_altitude.MassDefault = nan n/a 2012-01-20T20:46:41.32460Z,1327092401.3246 [MissionManager](INFO): DefineArg homing_altitude.BuoyancyNeutral = nan n/a 2012-01-20T20:46:41.32670Z,1327092401.3267 [MissionManager](INFO): DefineArg homing_altitude.dockBearing = 10 arcdeg 2012-01-20T20:46:41.32890Z,1327092401.3289 [MissionManager](INFO): DefineArg homing_altitude.inTrkOffset = 0 m 2012-01-20T20:46:41.33100Z,1327092401.331 [MissionManager](INFO): DefineArg homing_altitude.xTrkOffset = 0 m 2012-01-20T20:46:41.33310Z,1327092401.3331 [MissionManager](INFO): DefineArg homing_altitude.finalApproach = 200 m 2012-01-20T20:46:41.33530Z,1327092401.3353 [MissionManager](INFO): DefineArg homing_altitude.clusterRadius = 20 m 2012-01-20T20:46:41.33760Z,1327092401.3376 [MissionManager](INFO): DefineArg homing_altitude.SampleNeilBrown = 1 n/a 2012-01-20T20:46:41.33970Z,1327092401.3397 [MissionManager](INFO): DefineArg homing_altitude.TimeoutDuration = 40 min 2012-01-20T20:46:41.34180Z,1327092401.3418 [MissionManager](INFO): DefineArg homing_altitude.kpAltitude = -0.08 n/a 2012-01-20T20:46:41.34390Z,1327092401.3439 [MissionManager](INFO): DefineArg homing_altitude.kiAltitude = 0 n/a 2012-01-20T20:46:41.34590Z,1327092401.3459 [MissionManager](INFO): DefineArg homing_altitude.kdAltitude = 0 n/a 2012-01-20T20:46:41.34740Z,1327092401.3474 [homing_altitude:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-01-20T20:46:41.35210Z,1327092401.3521 [homing_altitude:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-01-20T20:46:41.35780Z,1327092401.3578 [homing_altitude:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-01-20T20:46:41.36290Z,1327092401.3629 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml 2012-01-20T20:46:41.37940Z,1327092401.3794 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfaceDepthRate = nan m/s 2012-01-20T20:46:41.38140Z,1327092401.3814 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfacePitch = nan arcdeg 2012-01-20T20:46:41.38360Z,1327092401.3836 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.SurfaceSpeed = 0.5 m/s 2012-01-20T20:46:41.38570Z,1327092401.3857 [MissionManager](INFO): DefineArg homing_altitude:SURFACECOMMS.IridiumTimeout = 30 min 2012-01-20T20:46:41.38660Z,1327092401.3866 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-01-20T20:46:41.39540Z,1327092401.3954 [homing_altitude:WaypointOne:B.Pitch](DEBUG): Construct. 2012-01-20T20:46:41.39990Z,1327092401.3999 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Construct Buoyancy. 2012-01-20T20:46:41.40230Z,1327092401.4023 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Construct. 2012-01-20T20:46:41.40470Z,1327092401.4047 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Construct. 2012-01-20T20:46:41.41110Z,1327092401.4111 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Construct Waypoint. 2012-01-20T20:46:41.41380Z,1327092401.4138 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Construct Homing. 2012-01-20T20:46:41.42220Z,1327092401.4222 [homing_altitude:H.Execute](DEBUG): Construct Execute. 2012-01-20T20:46:41.43080Z,1327092401.4308 [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-20T20:46:41.43130Z,1327092401.4313 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/Homing_altitude.xml 2012-01-20T20:46:41.53190Z,1327092401.5319 [CommandLine](IMPORTANT): got command set homing_altitude.Altitude 9 meter 2012-01-20T20:46:41.66570Z,1327092401.6657 [CommandLine](IMPORTANT): got command set homing_altitude.dockBearing 350 degree 2012-01-20T20:46:41.78210Z,1327092401.7821 [CommandLine](IMPORTANT): got command set homing_altitude.finalApproach 0.1000000015 meter 2012-01-20T20:46:41.89820Z,1327092401.8982 [CommandLine](IMPORTANT): got command set homing_altitude.clusterRadius 75 meter 2012-01-20T20:46:41.89960Z,1327092401.8996 [CommandLine](IMPORTANT): got command run 2012-01-20T20:46:41.89980Z,1327092401.8998 [CommandLine](IMPORTANT): Running 2012-01-20T20:46:41.91320Z,1327092401.9132 [MissionManager](IMPORTANT): Started mission homing_altitude 2012-01-20T20:46:41.91340Z,1327092401.9134 [homing_altitude] Running Loop=1 2012-01-20T20:46:41.91350Z,1327092401.9135 [homing_altitude](INFO): Aggregate::initialize homing_altitude 2012-01-20T20:46:41.91360Z,1327092401.9136 [homing_altitude:A.AltitudeEnvelope] Running Loop=1 2012-01-20T20:46:41.91370Z,1327092401.9137 [homing_altitude:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-01-20T20:46:41.91380Z,1327092401.9138 [homing_altitude:B.DepthEnvelope] Running Loop=1 2012-01-20T20:46:41.91390Z,1327092401.9139 [homing_altitude:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-01-20T20:46:41.91450Z,1327092401.9145 [homing_altitude:C.OffshoreEnvelope] Running Loop=1 2012-01-20T20:46:41.91460Z,1327092401.9146 [homing_altitude:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-01-20T20:46:41.91470Z,1327092401.9147 [homing_altitude:D] Running Loop=1 2012-01-20T20:46:41.91490Z,1327092401.9149 [homing_altitude:SURFACECOMMS] Running Loop=1 2012-01-20T20:46:41.91500Z,1327092401.915 [homing_altitude:SURFACECOMMS](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS 2012-01-20T20:46:41.91510Z,1327092401.9151 [homing_altitude:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T20:46:41.91520Z,1327092401.9152 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T20:46:41.91680Z,1327092401.9168 [homing_altitude:SURFACECOMMS:B] Running Loop=1 2012-01-20T20:46:41.91690Z,1327092401.9169 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS:B 2012-01-20T20:46:41.91710Z,1327092401.9171 [homing_altitude:SURFACECOMMS:B:A] Running Loop=1 2012-01-20T20:46:41.91710Z,1327092401.9171 [homing_altitude:D] Running Loop=1 2012-01-20T20:46:41.91760Z,1327092401.9176 [homing_altitude:D](DEBUG): Initialize ReadDataComponent to sense platform_orientation 2012-01-20T20:46:41.91840Z,1327092401.9184 [homing_altitude:C.OffshoreEnvelope] Running Loop=1 2012-01-20T20:46:41.91930Z,1327092401.9193 [homing_altitude:B.DepthEnvelope] Running Loop=1 2012-01-20T20:46:41.92040Z,1327092401.9204 [homing_altitude:A.AltitudeEnvelope] Running Loop=1 2012-01-20T20:46:42.56520Z,1327092402.5652 [homing_altitude:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense time_fix 2012-01-20T20:46:44.52630Z,1327092404.5263 [NAL9601](IMPORTANT): GPS fix at: 1327092412 2012-01-20T20:46:44.53970Z,1327092404.5397 [homing_altitude:SURFACECOMMS:B:A] Stopped 2012-01-20T20:46:44.53990Z,1327092404.5399 [homing_altitude:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T20:46:44.99360Z,1327092404.9936 [homing_altitude:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-01-20T20:46:58.08530Z,1327092418.0853 [NAL9601](INFO): SBD MO Status=1, MOMSN=42304, MT Status=0, MTMSN=0 2012-01-20T20:46:58.27930Z,1327092418.2793 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:46:58.27950Z,1327092418.2795 [NAL9601](INFO): Packets left to send: 6 2012-01-20T20:46:59.27590Z,1327092419.2759 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000254 2012-01-20T20:47:07.90960Z,1327092427.9096 [NAL9601](INFO): SBD MO Status=1, MOMSN=42305, MT Status=0, MTMSN=0 2012-01-20T20:47:08.07840Z,1327092428.0784 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:47:08.07860Z,1327092428.0786 [NAL9601](INFO): Packets left to send: 5 2012-01-20T20:47:08.07960Z,1327092428.0796 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000255 2012-01-20T20:47:18.80880Z,1327092438.8088 [NAL9601](INFO): SBD MO Status=1, MOMSN=42306, MT Status=0, MTMSN=0 2012-01-20T20:47:18.94730Z,1327092438.9473 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:47:18.94760Z,1327092438.9476 [NAL9601](INFO): Packets left to send: 4 2012-01-20T20:47:18.94860Z,1327092438.9486 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000256 2012-01-20T20:47:27.77910Z,1327092447.7791 [NAL9601](INFO): SBD MO Status=1, MOMSN=42307, MT Status=0, MTMSN=0 2012-01-20T20:47:27.93130Z,1327092447.9313 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:47:27.93150Z,1327092447.9315 [NAL9601](INFO): Packets left to send: 3 2012-01-20T20:47:27.93260Z,1327092447.9326 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000257 2012-01-20T20:47:36.64810Z,1327092456.6481 [NAL9601](INFO): SBD MO Status=1, MOMSN=42308, MT Status=0, MTMSN=0 2012-01-20T20:47:36.81930Z,1327092456.8193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:47:36.81950Z,1327092456.8195 [NAL9601](INFO): Packets left to send: 2 2012-01-20T20:47:36.82060Z,1327092456.8206 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000258 2012-01-20T20:47:56.74590Z,1327092476.7459 [NAL9601](INFO): SBD MO Status=1, MOMSN=42309, MT Status=0, MTMSN=0 2012-01-20T20:47:56.88740Z,1327092476.8874 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:47:56.88760Z,1327092476.8876 [NAL9601](INFO): Packets left to send: 1 2012-01-20T20:47:56.88870Z,1327092476.8887 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000259 2012-01-20T20:48:08.06740Z,1327092488.0674 [NAL9601](INFO): SBD MO Status=1, MOMSN=42310, MT Status=0, MTMSN=0 2012-01-20T20:48:08.26740Z,1327092488.2674 [NAL9601](INFO): Sent 245 bytes from file Logs/20120120T195811/shore0003.lzma 2012-01-20T20:48:08.26760Z,1327092488.2676 [NAL9601](INFO): Packets left to send: 0 2012-01-20T20:48:08.26870Z,1327092488.2687 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000260 2012-01-20T20:48:14.94110Z,1327092494.9411 [NAL9601](INFO): SBD MO Status=2, MOMSN=42311, MT Status=2, MTMSN=0 2012-01-20T20:48:14.94130Z,1327092494.9413 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-01-20T20:48:32.91650Z,1327092512.9165 [NAL9601](INFO): SBD MO Status=1, MOMSN=42311, MT Status=0, MTMSN=0 2012-01-20T20:48:33.13130Z,1327092513.1313 [NAL9601](INFO): Sent 291 bytes from file Logs/20120120T195811/shore0004.lzma 2012-01-20T20:48:33.13150Z,1327092513.1315 [NAL9601](INFO): Packets left to send: 0 2012-01-20T20:48:33.27950Z,1327092513.2795 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000261 2012-01-20T20:48:39.82510Z,1327092519.8251 [NAL9601](INFO): SBD MO Status=0, MOMSN=42312, MT Status=0, MTMSN=0 2012-01-20T20:48:59.92200Z,1327092539.922 [NAL9601](INFO): SBD MO Status=1, MOMSN=42313, MT Status=0, MTMSN=0 2012-01-20T20:49:00.10730Z,1327092540.1073 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0000.lzma 2012-01-20T20:49:00.10760Z,1327092540.1076 [NAL9601](INFO): Packets left to send: 5 2012-01-20T20:49:00.29420Z,1327092540.2942 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000262 2012-01-20T20:49:13.72920Z,1327092553.7292 [NAL9601](INFO): SBD MO Status=1, MOMSN=42314, MT Status=0, MTMSN=0 2012-01-20T20:49:13.86730Z,1327092553.8673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0000.lzma 2012-01-20T20:49:13.86750Z,1327092553.8675 [NAL9601](INFO): Packets left to send: 4 2012-01-20T20:49:13.86850Z,1327092553.8685 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000263 2012-01-20T20:49:25.06530Z,1327092565.0653 [NAL9601](INFO): SBD MO Status=1, MOMSN=42315, MT Status=0, MTMSN=0 2012-01-20T20:49:25.25130Z,1327092565.2513 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0000.lzma 2012-01-20T20:49:25.25150Z,1327092565.2515 [NAL9601](INFO): Packets left to send: 3 2012-01-20T20:49:25.25260Z,1327092565.2526 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000264 2012-01-20T20:49:35.13260Z,1327092575.1326 [NAL9601](INFO): SBD MO Status=1, MOMSN=42316, MT Status=0, MTMSN=0 2012-01-20T20:49:35.33530Z,1327092575.3353 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0000.lzma 2012-01-20T20:49:35.33560Z,1327092575.3356 [NAL9601](INFO): Packets left to send: 2 2012-01-20T20:49:35.33660Z,1327092575.3366 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000265 2012-01-20T20:49:48.10690Z,1327092588.1069 [NAL9601](INFO): SBD MO Status=1, MOMSN=42317, MT Status=0, MTMSN=0 2012-01-20T20:49:48.31540Z,1327092588.3154 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0000.lzma 2012-01-20T20:49:48.31560Z,1327092588.3156 [NAL9601](INFO): Packets left to send: 1 2012-01-20T20:49:48.31680Z,1327092588.3168 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000266 2012-01-20T20:49:57.42910Z,1327092597.4291 [NAL9601](INFO): SBD MO Status=1, MOMSN=42318, MT Status=0, MTMSN=0 2012-01-20T20:49:57.59930Z,1327092597.5993 [NAL9601](INFO): Sent 29 bytes from file Logs/20120120T204626/shore0000.lzma 2012-01-20T20:49:57.59950Z,1327092597.5995 [NAL9601](INFO): Packets left to send: 0 2012-01-20T20:49:57.60060Z,1327092597.6006 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000267 2012-01-20T20:50:05.82570Z,1327092605.8257 [NAL9601](INFO): SBD MO Status=0, MOMSN=42319, MT Status=0, MTMSN=0 2012-01-20T20:50:06.00000Z,1327092606 [homing_altitude:SURFACECOMMS:B:B] Stopped 2012-01-20T20:50:06.00020Z,1327092606.0002 [homing_altitude:SURFACECOMMS:B:C] Running Loop=1 2012-01-20T20:50:06.24180Z,1327092606.2418 [homing_altitude:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2012-01-20T20:50:08.22330Z,1327092608.2233 [NAL9601](IMPORTANT): GPS fix at: 1327092616 2012-01-20T20:50:08.23680Z,1327092608.2368 [homing_altitude:SURFACECOMMS:B:C] Stopped 2012-01-20T20:50:08.23700Z,1327092608.237 [homing_altitude:SURFACECOMMS:B](INFO): Completed homing_altitude:SURFACECOMMS:B 2012-01-20T20:50:08.23710Z,1327092608.2371 [homing_altitude:SURFACECOMMS:B] Stopped 2012-01-20T20:50:08.23720Z,1327092608.2372 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS:B 2012-01-20T20:50:08.23770Z,1327092608.2377 [homing_altitude:SURFACECOMMS](INFO): Completed homing_altitude:SURFACECOMMS 2012-01-20T20:50:08.23780Z,1327092608.2378 [homing_altitude:SURFACECOMMS] Stopped 2012-01-20T20:50:08.23790Z,1327092608.2379 [homing_altitude:SURFACECOMMS](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS 2012-01-20T20:50:08.23800Z,1327092608.238 [homing_altitude:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T20:50:08.23810Z,1327092608.2381 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T20:50:08.23820Z,1327092608.2382 [homing_altitude:WaypointOne] Running Loop=1 2012-01-20T20:50:08.23830Z,1327092608.2383 [homing_altitude:WaypointOne](INFO): Aggregate::initialize homing_altitude:WaypointOne 2012-01-20T20:50:08.23840Z,1327092608.2384 [homing_altitude:WaypointOne:A] Running Loop=1 2012-01-20T20:50:08.23850Z,1327092608.2385 [homing_altitude:WaypointOne:B.Pitch] Running Loop=1 2012-01-20T20:50:08.23850Z,1327092608.2385 [homing_altitude:WaypointOne:B.Pitch](DEBUG): Initialize. 2012-01-20T20:50:08.23870Z,1327092608.2387 [homing_altitude:WaypointOne:C.Buoyancy] Running Loop=1 2012-01-20T20:50:08.23900Z,1327092608.239 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-01-20T20:50:08.23940Z,1327092608.2394 [homing_altitude:WaypointOne:D.SetSpeed] Running Loop=1 2012-01-20T20:50:08.23950Z,1327092608.2395 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Initialize. 2012-01-20T20:50:08.23960Z,1327092608.2396 [homing_altitude:WaypointOne:E.PitchServo] Running Loop=1 2012-01-20T20:50:08.23970Z,1327092608.2397 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Initialize. 2012-01-20T20:50:08.24000Z,1327092608.24 [homing_altitude:WaypointOne:E.PitchServo](INFO): Initialize with holdValue=height_above_sea_floor 2012-01-20T20:50:08.24080Z,1327092608.2408 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint] Running Loop=1 2012-01-20T20:50:08.24090Z,1327092608.2409 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Initialize HomingComponent. 2012-01-20T20:50:08.24100Z,1327092608.241 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Initialize WaypointComponent. 2012-01-20T20:50:08.64420Z,1327092608.6442 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon has exited the DAT FOV with dtw = 851.65. Turning DAT off. 2012-01-20T20:50:08.64590Z,1327092608.6459 [homing_altitude:WaypointOne:E.PitchServo] Running Loop=1 2012-01-20T20:50:08.64680Z,1327092608.6468 [homing_altitude:WaypointOne:D.SetSpeed] Running Loop=1 2012-01-20T20:50:08.64770Z,1327092608.6477 [homing_altitude:WaypointOne:C.Buoyancy] Running Loop=1 2012-01-20T20:50:08.64860Z,1327092608.6486 [homing_altitude:WaypointOne:B.Pitch] Running Loop=1 2012-01-20T20:50:08.64940Z,1327092608.6494 [homing_altitude:WaypointOne:A] Running Loop=1 2012-01-20T20:50:08.64980Z,1327092608.6498 [homing_altitude:WaypointOne:A](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2012-01-20T20:50:08.65030Z,1327092608.6503 [homing_altitude:WaypointOne:A](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2012-01-20T20:50:14.56400Z,1327092614.564 [NAL9601](INFO): Powering down 2012-01-20T20:50:27.64110Z,1327092627.6411 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon is in the DAT FOV with dtw = 856.23. Turning DAT on. 2012-01-20T20:50:28.07190Z,1327092628.0719 [DAT](INFO): Powering up 2012-01-20T20:50:42.66640Z,1327092642.6664 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 1 transponder hits. 2012-01-20T20:50:43.00130Z,1327092643.0013 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon has exited the DAT FOV with dtw = 33.68. Turning DAT off. 2012-01-20T20:50:43.77090Z,1327092643.7709 [DAT](INFO): Powering down 2012-01-20T20:50:50.80940Z,1327092650.8094 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon is in the DAT FOV with dtw = 35.95. Turning DAT on. 2012-01-20T20:50:51.51790Z,1327092651.5179 [DAT](INFO): Powering up 2012-01-20T20:51:06.11130Z,1327092666.1113 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 2 transponder hits. 2012-01-20T20:51:06.86960Z,1327092666.8696 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon has exited the DAT FOV with dtw = 33.69. Turning DAT off. 2012-01-20T20:51:07.71850Z,1327092667.7185 [DAT](INFO): Powering down 2012-01-20T20:51:19.17630Z,1327092679.1763 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon is in the DAT FOV with dtw = 38.14. Turning DAT on. 2012-01-20T20:51:19.54480Z,1327092679.5448 [DAT](INFO): Powering up 2012-01-20T20:51:39.44170Z,1327092699.4417 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 3 transponder hits. 2012-01-20T20:51:54.72410Z,1327092714.7241 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 4 transponder hits. 2012-01-20T20:51:59.98620Z,1327092719.9862 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The Usbl has 5 transponder hits; the filter is now engaged. 2012-01-20T20:51:59.98650Z,1327092719.9865 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: The vehicle lat,lon is (36.8306, -121.8307) with dtw = 852.3 2012-01-20T20:51:59.98770Z,1327092719.9877 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Pure pursuit active. dtw = 852.1 m. 2012-01-20T20:52:34.21090Z,1327092754.2109 [DAT](ERROR): No response from remote modem 2012-01-20T20:53:26.87090Z,1327092806.8709 [DAT](ERROR): No response from remote modem 2012-01-20T20:54:05.42700Z,1327092845.427 [DAT](ERROR): No response from remote modem 2012-01-20T20:54:42.41130Z,1327092882.4113 [Radio_Freewave](INFO): Powering down 2012-01-20T20:54:44.38150Z,1327092884.3815 [Radio_Freewave](INFO): Powering up 2012-01-20T20:55:19.16290Z,1327092919.1629 [DAT](ERROR): No response from remote modem 2012-01-20T20:56:31.71900Z,1327092991.719 [DAT](ERROR): No response from remote modem 2012-01-20T21:03:54.04500Z,1327093434.045 [Radio_Freewave](INFO): Powering down 2012-01-20T21:03:55.66100Z,1327093435.661 [Radio_Freewave](INFO): Powering up 2012-01-20T21:04:00.83940Z,1327093440.8394 [Radio_Freewave](INFO): Powering down 2012-01-20T21:04:04.04120Z,1327093444.0412 [Radio_Freewave](INFO): Powering up 2012-01-20T21:05:15.67480Z,1327093515.6748 [DAT](ERROR): Unexpected response:22:05:27.9911 , LVL= 32752, 32753, 32754, 32755, AGC= 63, IDX= 10 0.11, 0.979, |v|= 1.045, RAW= 268.8,- 1.4, CAL= 278.5,- 1.4, ROT= 357.5,- 1.4 Compass 47.5 Range 1 to 0 : 261.6 m 2012-01-20T21:05:55.24490Z,1327093555.2449 [Radio_Freewave](INFO): Powering down 2012-01-20T21:05:58.06250Z,1327093558.0625 [Radio_Freewave](INFO): Powering up 2012-01-20T21:05:58.85660Z,1327093558.8566 [DAT](ERROR): Unexpected response:22:06:11.3015 , LVL= 32752, 30401, 28514, 32755, AGC= 62, IDX= 2 0.21,-3.065, |v|= 0.939, RAW= 269.0,- 7.9, CAL= 278.7,- 9.4, ROT= 357.7,- 9.4 Compass 58.2 Range 1 to 0 : 228.8 m 2012-01-20T21:06:05.19740Z,1327093565.1974 [Batt_Ocean_Server](INFO): Over Temperature Alarm! Battery Bank #4 STATUS: 5911 2012-01-20T21:06:05.19770Z,1327093565.1977 [Batt_Ocean_Server](INFO): Not Initialized - Battery Bank #4 STATUS: 5911 2012-01-20T21:06:51.13710Z,1327093611.1371 [DAT](ERROR): Unexpected response:22:07:03.1008 , LVL= 32752, 32753, 32754, 32755, AGC= 60, IDX=132 0.32,-0.846, |v|= 0.866, RAW= 267.4,- 0.0, CAL= 277.5, 0.5, ROT= 356.5, 0.5 Compass 63.4 Range 1 to 0 : 189.6 m 2012-01-20T21:08:05.10330Z,1327093685.1033 [DAT](ERROR): Unexpected response:22:08:17.6289 , LVL= 32752, 31649, 32754, 31219, AGC= 56, IDX= 60 0.01, 1.293, |v|= 0.760, RAW= 265.8,- 9.6, CAL= 275.7,-10.9, ROT= 354.7,-10.9 Compass 65.2 Range 1 to 0 : 132.2 m 2012-01-20T21:08:25.33430Z,1327093705.3343 [DAT](ERROR): Unexpected response:22:08:38.0595 , LVL= 32752, 32753, 32754, 32755, AGC= 56, IDX= 60 0.47,-1.799, |v|= 0.774, RAW= 270.6,- 5.4, CAL= 280.1,- 6.5, ROT= 359.1,- 6.5 Compass 68.6 Range 1 to 0 : 116.8 m 2012-01-20T21:08:37.26790Z,1327093717.2679 [DAT](ERROR): Unexpected response:22:08:50.0989 , LVL= 32752, 32753, 32754, 32755, AGC= 56, IDX= 62 0.11, 0.511, |v|= 0.846, RAW= 271.3,- 3.1, CAL= 280.8,- 3.7, ROT= 359.8,- 3.7 Compass 65.4 Range 1 to 0 : 108.3 m 2012-01-20T21:09:17.31950Z,1327093757.3195 [DAT](ERROR): Unexpected response:22:09:30.2644 , LVL= 32752, 32753, 32754, 32755, AGC= 57, IDX= 62-0.17, 0.464, |v|= 0.673, RAW= 277.8,- 2.8, CAL= 289.3,- 4.2, ROT= 8.3,- 4.2 Compass 66.4 Range 1 to 0 : 79.5 m 2012-01-20T21:09:21.31020Z,1327093761.3102 [DAT](ERROR): Unexpected response:22:09:34.0590 , LVL= 32752, 32753, 32754, 32755, AGC= 58, IDX= 32 0.15, 2.720, |v|= 0.774, RAW= 279.2,- 0.8, CAL= 291.1,- 1.9, ROT= 10.1,- 1.9 Compass 67.1 Range 1 to 0 : 76.4 m 2012-01-20T21:09:25.32910Z,1327093765.3291 [DAT](ERROR): Unexpected response:22:09:38.1557 , LVL= 32752, 32753, 32754, 32755, AGC= 55, IDX= 64-0.14, 0.131, |v|= 0.819, RAW= 272.9,- 6.3, CAL= 283.2,- 7.8, ROT= 2.2,- 7.8 Compass 64.3 Range 1 to 0 : 73.8 m 2012-01-20T21:09:29.30990Z,1327093769.3099 [DAT](ERROR): Unexpected response:22:09:42.1025 , LVL= 32752, 32753, 32754, 32755, AGC= 54, IDX= 30 0.03,-1.996, |v|= 0.795, RAW= 274.9,- 0.2, CAL= 285.3,- 0.8, ROT= 4.3,- 0.8 Compass 62.0 Range 1 to 0 : 71.1 m 2012-01-20T21:10:58.35090Z,1327093858.3509 [DAT](ERROR): No response from remote modem 2012-01-20T21:11:10.68320Z,1327093870.6832 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon has exited the DAT FOV with dtw = 1.91. Turning DAT off. 2012-01-20T21:11:10.68410Z,1327093870.6841 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Cross-track control active. dtw = 2.1 m. 2012-01-20T21:11:11.44460Z,1327093871.4446 [DAT](INFO): Powering down 2012-01-20T21:11:17.18450Z,1327093877.1845 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Beacon is in the DAT FOV with dtw = 3.12. Turning DAT on. 2012-01-20T21:11:17.18510Z,1327093877.1851 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Pure pursuit active. dtw = 3.1 m. 2012-01-20T21:11:17.54240Z,1327093877.5424 [DAT](INFO): Powering up 2012-01-20T21:11:25.89460Z,1327093885.8946 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Homing:: Cross-track control active. dtw = 0.1 m. 2012-01-20T21:11:26.24020Z,1327093886.2402 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](IMPORTANT): Homing:: Terminating now. dtw = -0.1 m, DATRange = nan m. 2012-01-20T21:11:26.24080Z,1327093886.2408 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](INFO): Homing::Reached Waypoint 36.82318767,-121.8292875 2012-01-20T21:11:26.24110Z,1327093886.2411 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint] Stopped 2012-01-20T21:11:26.24120Z,1327093886.2412 [homing_altitude:WaypointOne:WaypointW1.HomingWaypoint](DEBUG): Uninitialize HomingComponent. 2012-01-20T21:11:26.24240Z,1327093886.2424 [homing_altitude:WaypointOne](INFO): Completed homing_altitude:WaypointOne 2012-01-20T21:11:26.24250Z,1327093886.2425 [homing_altitude:WaypointOne] Stopped 2012-01-20T21:11:26.24260Z,1327093886.2426 [homing_altitude:WaypointOne](INFO): Aggregate::uninitialize homing_altitude:WaypointOne 2012-01-20T21:11:26.24270Z,1327093886.2427 [homing_altitude:WaypointOne:A] Stopped 2012-01-20T21:11:26.24270Z,1327093886.2427 [homing_altitude:WaypointOne:B.Pitch] Stopped 2012-01-20T21:11:26.24290Z,1327093886.2429 [homing_altitude:WaypointOne:C.Buoyancy] Stopped 2012-01-20T21:11:26.24290Z,1327093886.2429 [homing_altitude:WaypointOne:C.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-01-20T21:11:26.24300Z,1327093886.243 [homing_altitude:WaypointOne:D.SetSpeed] Stopped 2012-01-20T21:11:26.24310Z,1327093886.2431 [homing_altitude:WaypointOne:D.SetSpeed](DEBUG): Uninitialize. 2012-01-20T21:11:26.24310Z,1327093886.2431 [homing_altitude:WaypointOne:E.PitchServo] Stopped 2012-01-20T21:11:26.24320Z,1327093886.2432 [homing_altitude:WaypointOne:E.PitchServo](DEBUG): Uninitialize. 2012-01-20T21:11:26.24340Z,1327093886.2434 [homing_altitude:PHONEHOMEWPT1] Running Loop=1 2012-01-20T21:11:26.24350Z,1327093886.2435 [homing_altitude:PHONEHOMEWPT1](INFO): Aggregate::initialize homing_altitude:PHONEHOMEWPT1 2012-01-20T21:11:26.68780Z,1327093886.6878 [homing_altitude:SURFACECOMMS] Running Loop=1 2012-01-20T21:11:26.68800Z,1327093886.688 [homing_altitude:SURFACECOMMS](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS 2012-01-20T21:11:26.68810Z,1327093886.6881 [homing_altitude:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-01-20T21:11:26.68810Z,1327093886.6881 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T21:11:26.68930Z,1327093886.6893 [homing_altitude:SURFACECOMMS:B] Running Loop=1 2012-01-20T21:11:26.68940Z,1327093886.6894 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::initialize homing_altitude:SURFACECOMMS:B 2012-01-20T21:11:26.68960Z,1327093886.6896 [homing_altitude:SURFACECOMMS:B:A] Running Loop=1 2012-01-20T21:11:27.40800Z,1327093887.408 [NAL9601](INFO): Powering up 2012-01-20T21:11:33.06910Z,1327093893.0691 [DAT](INFO): Powering down 2012-01-20T21:12:33.11940Z,1327093953.1194 [NAL9601](INFO): NAL9601 initialized 2012-01-20T21:12:34.25020Z,1327093954.2502 [NAL9601](IMPORTANT): GPS fix at: 1327093932 2012-01-20T21:12:34.26360Z,1327093954.2636 [homing_altitude:SURFACECOMMS:B:A] Stopped 2012-01-20T21:12:34.26380Z,1327093954.2638 [homing_altitude:SURFACECOMMS:B:B] Running Loop=1 2012-01-20T21:12:59.98490Z,1327093979.9849 [NAL9601](INFO): SBD MO Status=1, MOMSN=42320, MT Status=0, MTMSN=0 2012-01-20T21:13:00.16730Z,1327093980.1673 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:13:00.16760Z,1327093980.1676 [NAL9601](INFO): Packets left to send: 35 2012-01-20T21:13:00.16860Z,1327093980.1686 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000268 2012-01-20T21:13:11.32450Z,1327093991.3245 [NAL9601](INFO): SBD MO Status=1, MOMSN=42321, MT Status=0, MTMSN=0 2012-01-20T21:13:11.44730Z,1327093991.4473 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:13:11.44750Z,1327093991.4475 [NAL9601](INFO): Packets left to send: 34 2012-01-20T21:13:11.44860Z,1327093991.4486 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000269 2012-01-20T21:13:26.21290Z,1327094006.2129 [NAL9601](INFO): SBD MO Status=1, MOMSN=42322, MT Status=0, MTMSN=0 2012-01-20T21:13:26.42330Z,1327094006.4233 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:13:26.42350Z,1327094006.4235 [NAL9601](INFO): Packets left to send: 33 2012-01-20T21:13:26.42460Z,1327094006.4246 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000270 2012-01-20T21:13:34.81480Z,1327094014.8148 [NAL9601](INFO): SBD MO Status=1, MOMSN=42323, MT Status=0, MTMSN=0 2012-01-20T21:13:35.01160Z,1327094015.0116 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:13:35.01180Z,1327094015.0118 [NAL9601](INFO): Packets left to send: 32 2012-01-20T21:13:35.01280Z,1327094015.0128 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000271 2012-01-20T21:13:45.32580Z,1327094025.3258 [NAL9601](INFO): SBD MO Status=1, MOMSN=42324, MT Status=0, MTMSN=0 2012-01-20T21:13:45.49540Z,1327094025.4954 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:13:45.49560Z,1327094025.4956 [NAL9601](INFO): Packets left to send: 31 2012-01-20T21:13:45.49670Z,1327094025.4967 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000272 2012-01-20T21:13:55.81790Z,1327094035.8179 [NAL9601](INFO): SBD MO Status=1, MOMSN=42325, MT Status=0, MTMSN=0 2012-01-20T21:13:55.98330Z,1327094035.9833 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:13:55.98350Z,1327094035.9835 [NAL9601](INFO): Packets left to send: 30 2012-01-20T21:13:55.98460Z,1327094035.9846 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000273 2012-01-20T21:14:07.91380Z,1327094047.9138 [NAL9601](INFO): SBD MO Status=1, MOMSN=42326, MT Status=0, MTMSN=0 2012-01-20T21:14:08.06330Z,1327094048.0633 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:14:08.06350Z,1327094048.0635 [NAL9601](INFO): Packets left to send: 29 2012-01-20T21:14:08.06460Z,1327094048.0646 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000274 2012-01-20T21:14:16.43270Z,1327094056.4327 [NAL9601](INFO): SBD MO Status=1, MOMSN=42327, MT Status=0, MTMSN=0 2012-01-20T21:14:16.64740Z,1327094056.6474 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:14:16.64760Z,1327094056.6476 [NAL9601](INFO): Packets left to send: 28 2012-01-20T21:14:16.84540Z,1327094056.8454 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000275 2012-01-20T21:14:25.05780Z,1327094065.0578 [NAL9601](INFO): SBD MO Status=1, MOMSN=42328, MT Status=0, MTMSN=0 2012-01-20T21:14:25.23540Z,1327094065.2354 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:14:25.23560Z,1327094065.2356 [NAL9601](INFO): Packets left to send: 27 2012-01-20T21:14:25.23670Z,1327094065.2367 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000276 2012-01-20T21:14:35.15780Z,1327094075.1578 [NAL9601](INFO): SBD MO Status=1, MOMSN=42329, MT Status=0, MTMSN=0 2012-01-20T21:14:35.31980Z,1327094075.3198 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:14:35.32000Z,1327094075.32 [NAL9601](INFO): Packets left to send: 26 2012-01-20T21:14:35.32110Z,1327094075.3211 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000277 2012-01-20T21:14:45.65770Z,1327094085.6577 [NAL9601](INFO): SBD MO Status=1, MOMSN=42330, MT Status=0, MTMSN=0 2012-01-20T21:14:45.80330Z,1327094085.8033 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:14:45.80350Z,1327094085.8035 [NAL9601](INFO): Packets left to send: 25 2012-01-20T21:14:45.80460Z,1327094085.8046 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000278 2012-01-20T21:14:53.37320Z,1327094093.3732 [NAL9601](INFO): SBD MO Status=1, MOMSN=42331, MT Status=0, MTMSN=0 2012-01-20T21:14:53.59130Z,1327094093.5913 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:14:53.59150Z,1327094093.5915 [NAL9601](INFO): Packets left to send: 24 2012-01-20T21:14:53.59280Z,1327094093.5928 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000279 2012-01-20T21:15:05.87220Z,1327094105.8722 [NAL9601](INFO): SBD MO Status=1, MOMSN=42332, MT Status=0, MTMSN=0 2012-01-20T21:15:06.07130Z,1327094106.0713 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:15:06.07150Z,1327094106.0715 [NAL9601](INFO): Packets left to send: 23 2012-01-20T21:15:06.07270Z,1327094106.0727 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000280 2012-01-20T21:15:13.99660Z,1327094113.9966 [NAL9601](INFO): SBD MO Status=1, MOMSN=42333, MT Status=0, MTMSN=0 2012-01-20T21:15:14.15930Z,1327094114.1593 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:15:14.15950Z,1327094114.1595 [NAL9601](INFO): Packets left to send: 22 2012-01-20T21:15:14.16060Z,1327094114.1606 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000281 2012-01-20T21:15:23.30570Z,1327094123.3057 [NAL9601](INFO): SBD MO Status=1, MOMSN=42334, MT Status=0, MTMSN=0 2012-01-20T21:15:23.44380Z,1327094123.4438 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:15:23.44400Z,1327094123.444 [NAL9601](INFO): Packets left to send: 21 2012-01-20T21:15:23.44510Z,1327094123.4451 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000282 2012-01-20T21:15:31.49460Z,1327094131.4946 [NAL9601](INFO): SBD MO Status=1, MOMSN=42335, MT Status=0, MTMSN=0 2012-01-20T21:15:31.63130Z,1327094131.6313 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:15:31.63160Z,1327094131.6316 [NAL9601](INFO): Packets left to send: 20 2012-01-20T21:15:31.63260Z,1327094131.6326 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000283 2012-01-20T21:15:39.93740Z,1327094139.9374 [NAL9601](INFO): SBD MO Status=1, MOMSN=42336, MT Status=0, MTMSN=0 2012-01-20T21:15:40.11930Z,1327094140.1193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:15:40.11950Z,1327094140.1195 [NAL9601](INFO): Packets left to send: 19 2012-01-20T21:15:40.12060Z,1327094140.1206 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000284 2012-01-20T21:15:48.43290Z,1327094148.4329 [NAL9601](INFO): SBD MO Status=1, MOMSN=42337, MT Status=0, MTMSN=0 2012-01-20T21:15:48.60330Z,1327094148.6033 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:15:48.60350Z,1327094148.6035 [NAL9601](INFO): Packets left to send: 18 2012-01-20T21:15:48.60470Z,1327094148.6047 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000285 2012-01-20T21:16:01.03260Z,1327094161.0326 [NAL9601](INFO): SBD MO Status=1, MOMSN=42338, MT Status=0, MTMSN=0 2012-01-20T21:16:01.18330Z,1327094161.1833 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:16:01.18350Z,1327094161.1835 [NAL9601](INFO): Packets left to send: 17 2012-01-20T21:16:01.18460Z,1327094161.1846 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000286 2012-01-20T21:16:09.55370Z,1327094169.5537 [NAL9601](INFO): SBD MO Status=1, MOMSN=42339, MT Status=0, MTMSN=0 2012-01-20T21:16:09.67330Z,1327094169.6733 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:16:09.67350Z,1327094169.6735 [NAL9601](INFO): Packets left to send: 16 2012-01-20T21:16:09.67460Z,1327094169.6746 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000287 2012-01-20T21:16:20.87090Z,1327094180.8709 [NAL9601](INFO): SBD MO Status=1, MOMSN=42340, MT Status=0, MTMSN=0 2012-01-20T21:16:21.05540Z,1327094181.0554 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:16:21.05560Z,1327094181.0556 [NAL9601](INFO): Packets left to send: 15 2012-01-20T21:16:21.05670Z,1327094181.0567 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000288 2012-01-20T21:16:28.61370Z,1327094188.6137 [NAL9601](INFO): SBD MO Status=1, MOMSN=42341, MT Status=0, MTMSN=0 2012-01-20T21:16:28.74330Z,1327094188.7433 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:16:28.74360Z,1327094188.7436 [NAL9601](INFO): Packets left to send: 14 2012-01-20T21:16:28.74470Z,1327094188.7447 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000289 2012-01-20T21:16:36.16170Z,1327094196.1617 [NAL9601](INFO): SBD MO Status=1, MOMSN=42342, MT Status=0, MTMSN=0 2012-01-20T21:16:36.33130Z,1327094196.3313 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:16:36.33150Z,1327094196.3315 [NAL9601](INFO): Packets left to send: 13 2012-01-20T21:16:36.33270Z,1327094196.3327 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000290 2012-01-20T21:16:43.58320Z,1327094203.5832 [NAL9601](INFO): SBD MO Status=1, MOMSN=42343, MT Status=0, MTMSN=0 2012-01-20T21:16:43.71930Z,1327094203.7193 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:16:43.71950Z,1327094203.7195 [NAL9601](INFO): Packets left to send: 12 2012-01-20T21:16:43.72070Z,1327094203.7207 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000291 2012-01-20T21:16:51.24580Z,1327094211.2458 [NAL9601](INFO): SBD MO Status=1, MOMSN=42344, MT Status=0, MTMSN=0 2012-01-20T21:16:51.40740Z,1327094211.4074 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:16:51.40760Z,1327094211.4076 [NAL9601](INFO): Packets left to send: 11 2012-01-20T21:16:51.52140Z,1327094211.5214 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000292 2012-01-20T21:17:02.22880Z,1327094222.2288 [NAL9601](INFO): SBD MO Status=1, MOMSN=42345, MT Status=0, MTMSN=0 2012-01-20T21:17:02.39140Z,1327094222.3914 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:17:02.39160Z,1327094222.3916 [NAL9601](INFO): Packets left to send: 10 2012-01-20T21:17:02.39290Z,1327094222.3929 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000293 2012-01-20T21:17:09.62940Z,1327094229.6294 [NAL9601](INFO): SBD MO Status=1, MOMSN=42346, MT Status=0, MTMSN=0 2012-01-20T21:17:09.77940Z,1327094229.7794 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:17:09.77960Z,1327094229.7796 [NAL9601](INFO): Packets left to send: 9 2012-01-20T21:17:09.78080Z,1327094229.7808 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000294 2012-01-20T21:17:18.53780Z,1327094238.5378 [NAL9601](INFO): SBD MO Status=1, MOMSN=42347, MT Status=0, MTMSN=0 2012-01-20T21:17:18.66330Z,1327094238.6633 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:17:18.66350Z,1327094238.6635 [NAL9601](INFO): Packets left to send: 8 2012-01-20T21:17:18.66550Z,1327094238.6655 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000295 2012-01-20T21:17:27.03820Z,1327094247.0382 [NAL9601](INFO): SBD MO Status=1, MOMSN=42348, MT Status=0, MTMSN=0 2012-01-20T21:17:27.25130Z,1327094247.2513 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:17:27.25150Z,1327094247.2515 [NAL9601](INFO): Packets left to send: 7 2012-01-20T21:17:27.25280Z,1327094247.2528 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000296 2012-01-20T21:17:36.71380Z,1327094256.7138 [NAL9601](INFO): SBD MO Status=1, MOMSN=42349, MT Status=0, MTMSN=0 2012-01-20T21:17:36.83840Z,1327094256.8384 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:17:36.83860Z,1327094256.8386 [NAL9601](INFO): Packets left to send: 6 2012-01-20T21:17:36.83980Z,1327094256.8398 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000297 2012-01-20T21:17:44.11590Z,1327094264.1159 [NAL9601](INFO): SBD MO Status=1, MOMSN=42350, MT Status=0, MTMSN=0 2012-01-20T21:17:44.32730Z,1327094264.3273 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:17:44.32750Z,1327094264.3275 [NAL9601](INFO): Packets left to send: 5 2012-01-20T21:17:44.32870Z,1327094264.3287 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000298 2012-01-20T21:17:52.61380Z,1327094272.6138 [NAL9601](INFO): SBD MO Status=1, MOMSN=42351, MT Status=0, MTMSN=0 2012-01-20T21:17:52.81140Z,1327094272.8114 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:17:52.81160Z,1327094272.8116 [NAL9601](INFO): Packets left to send: 4 2012-01-20T21:17:52.81270Z,1327094272.8127 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000299 2012-01-20T21:18:01.55870Z,1327094281.5587 [NAL9601](INFO): SBD MO Status=1, MOMSN=42352, MT Status=0, MTMSN=0 2012-01-20T21:18:01.69930Z,1327094281.6993 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:18:01.69950Z,1327094281.6995 [NAL9601](INFO): Packets left to send: 3 2012-01-20T21:18:01.70070Z,1327094281.7007 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000300 2012-01-20T21:18:10.11150Z,1327094290.1115 [NAL9601](INFO): SBD MO Status=1, MOMSN=42353, MT Status=0, MTMSN=0 2012-01-20T21:18:10.28340Z,1327094290.2834 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:18:10.28360Z,1327094290.2836 [NAL9601](INFO): Packets left to send: 2 2012-01-20T21:18:10.28470Z,1327094290.2847 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000301 2012-01-20T21:18:19.80730Z,1327094299.8073 [NAL9601](INFO): SBD MO Status=1, MOMSN=42354, MT Status=0, MTMSN=0 2012-01-20T21:18:19.97130Z,1327094299.9713 [NAL9601](INFO): Sent 332 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:18:19.97150Z,1327094299.9715 [NAL9601](INFO): Packets left to send: 1 2012-01-20T21:18:19.97270Z,1327094299.9727 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000302 2012-01-20T21:18:26.20590Z,1327094306.2059 [NAL9601](INFO): SBD MO Status=1, MOMSN=42355, MT Status=0, MTMSN=0 2012-01-20T21:18:26.35930Z,1327094306.3593 [NAL9601](INFO): Sent 87 bytes from file Logs/20120120T204626/shore0001.lzma 2012-01-20T21:18:26.35950Z,1327094306.3595 [NAL9601](INFO): Packets left to send: 0 2012-01-20T21:18:26.36070Z,1327094306.3607 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000303 2012-01-20T21:18:31.02540Z,1327094311.0254 [NAL9601](INFO): SBD MO Status=0, MOMSN=42356, MT Status=0, MTMSN=0 2012-01-20T21:18:31.16430Z,1327094311.1643 [homing_altitude:SURFACECOMMS:B:B] Stopped 2012-01-20T21:18:31.16450Z,1327094311.1645 [homing_altitude:SURFACECOMMS:B:C] Running Loop=1 2012-01-20T21:18:33.42440Z,1327094313.4244 [NAL9601](IMPORTANT): GPS fix at: 1327094291 2012-01-20T21:18:33.43750Z,1327094313.4375 [homing_altitude:SURFACECOMMS:B:C] Stopped 2012-01-20T21:18:33.43780Z,1327094313.4378 [homing_altitude:SURFACECOMMS:B](INFO): Completed homing_altitude:SURFACECOMMS:B 2012-01-20T21:18:33.43780Z,1327094313.4378 [homing_altitude:SURFACECOMMS:B] Stopped 2012-01-20T21:18:33.43800Z,1327094313.438 [homing_altitude:SURFACECOMMS:B](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS:B 2012-01-20T21:18:33.43850Z,1327094313.4385 [homing_altitude:SURFACECOMMS](INFO): Completed homing_altitude:SURFACECOMMS 2012-01-20T21:18:33.43860Z,1327094313.4386 [homing_altitude:SURFACECOMMS] Stopped 2012-01-20T21:18:33.43870Z,1327094313.4387 [homing_altitude:SURFACECOMMS](INFO): Aggregate::uninitialize homing_altitude:SURFACECOMMS 2012-01-20T21:18:33.43880Z,1327094313.4388 [homing_altitude:SURFACECOMMS:A.GoToSurface] Stopped 2012-01-20T21:18:33.43890Z,1327094313.4389 [homing_altitude:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T21:18:33.82190Z,1327094313.8219 [homing_altitude:PHONEHOMEWPT1](INFO): Completed homing_altitude:PHONEHOMEWPT1 2012-01-20T21:18:33.82200Z,1327094313.822 [homing_altitude:PHONEHOMEWPT1] Stopped 2012-01-20T21:18:33.82220Z,1327094313.8222 [homing_altitude:PHONEHOMEWPT1](INFO): Aggregate::uninitialize homing_altitude:PHONEHOMEWPT1 2012-01-20T21:18:33.82230Z,1327094313.8223 [homing_altitude:H.Execute] Running Loop=1 2012-01-20T21:18:34.34740Z,1327094314.3474 [homing_altitude:H.Execute](INFO): Executing command restart logs 2012-01-20T21:18:34.34900Z,1327094314.349 [homing_altitude:H.Execute] Stopped 2012-01-20T21:18:34.35040Z,1327094314.3504 [homing_altitude](INFO): Completed homing_altitude 2012-01-20T21:18:34.35050Z,1327094314.3505 [homing_altitude] Stopped 2012-01-20T21:18:34.35060Z,1327094314.3506 [homing_altitude](INFO): Aggregate::uninitialize homing_altitude 2012-01-20T21:18:34.35070Z,1327094314.3507 [homing_altitude:A.AltitudeEnvelope] Stopped 2012-01-20T21:18:34.35070Z,1327094314.3507 [homing_altitude:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-01-20T21:18:34.35090Z,1327094314.3509 [homing_altitude:B.DepthEnvelope] Stopped 2012-01-20T21:18:34.35090Z,1327094314.3509 [homing_altitude:B.DepthEnvelope](DEBUG): Uninitialize. 2012-01-20T21:18:34.35100Z,1327094314.351 [homing_altitude:C.OffshoreEnvelope] Stopped 2012-01-20T21:18:34.35110Z,1327094314.3511 [homing_altitude:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-01-20T21:18:34.35110Z,1327094314.3511 [homing_altitude:D] Stopped 2012-01-20T21:18:34.39530Z,1327094314.3953 [CommandLine](IMPORTANT): got command restart logs 2012-01-20T21:18:34.67120Z,1327094314.6712 [MissionManager](IMPORTANT): Started mission Default 2012-01-20T21:18:34.67130Z,1327094314.6713 [Default] Running Loop=1 2012-01-20T21:18:34.67140Z,1327094314.6714 [Default](INFO): Aggregate::initialize Default 2012-01-20T21:18:34.67150Z,1327094314.6715 [Default:E.SetSpeed] Running Loop=1 2012-01-20T21:18:34.67160Z,1327094314.6716 [Default:E.SetSpeed](DEBUG): Initialize. 2012-01-20T21:18:34.67170Z,1327094314.6717 [Default:F.GoToSurface] Running Loop=1 2012-01-20T21:18:34.67180Z,1327094314.6718 [Default:F.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T21:18:34.67220Z,1327094314.6722 [Default:GPS] Running Loop=1 2012-01-20T21:18:34.67240Z,1327094314.6724 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-01-20T21:18:34.67240Z,1327094314.6724 [Default:GPS:A.SetSpeed] Running Loop=1 2012-01-20T21:18:34.67250Z,1327094314.6725 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-01-20T21:18:34.67270Z,1327094314.6727 [Default:GPS:B.GoToSurface] Running Loop=1 2012-01-20T21:18:34.67270Z,1327094314.6727 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-01-20T21:18:34.67440Z,1327094314.6744 [Default:GPS:B.GoToSurface] Stopped 2012-01-20T21:18:34.67440Z,1327094314.6744 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-01-20T21:18:34.67460Z,1327094314.6746 [Default:GPS:Read_GPS] Running Loop=1