2012-06-22T04:53:10.782Z,1340340790.782 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10605, MT Status=1, MTMSN=2781 2012-06-22T04:53:10.971Z,1340340790.971 [NAL9601](INFO): Sent 196 bytes from file Logs/20120622T014020/shore0009.lzma 2012-06-22T04:53:10.971Z,1340340790.971 [NAL9601](INFO): Packets left to send: 0 2012-06-22T04:53:10.973Z,1340340790.973 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0009.lzma.parts/0000.sbd 2012-06-22T04:53:11.488Z,1340340791.488 [NAL9601](INFO): Received command:load Engineering/triangle_control_speed_onelap.xml;set triangle_control_speed_onelap.MassDefault -0.85 centimeter;set triangle_control_speed_onelap.StopDepthExcursion 15 meter;run 2012-06-22T04:53:11.825Z,1340340791.825 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/triangle_control_speed_onelap.xml 2012-06-22T04:53:11.825Z,1340340791.825 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/triangle_control_speed_onelap.xml 2012-06-22T04:53:12.175Z,1340340792.175 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt1Lat = 36.815000 arcdeg 2012-06-22T04:53:12.177Z,1340340792.177 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt1Lon = -121.869000 arcdeg 2012-06-22T04:53:12.180Z,1340340792.180 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed1 = 1.000000 m/s 2012-06-22T04:53:12.182Z,1340340792.182 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt2Lat = 36.829000 arcdeg 2012-06-22T04:53:12.185Z,1340340792.185 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt2Lon = -121.886000 arcdeg 2012-06-22T04:53:12.187Z,1340340792.187 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed2 = 0.750000 m/s 2012-06-22T04:53:12.189Z,1340340792.189 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt3Lat = 36.816000 arcdeg 2012-06-22T04:53:12.191Z,1340340792.191 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt3Lon = -121.883000 arcdeg 2012-06-22T04:53:12.193Z,1340340792.194 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed3 = 0.600000 m/s 2012-06-22T04:53:12.196Z,1340340792.196 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MaxDepth = 37.000000 m 2012-06-22T04:53:12.198Z,1340340792.198 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MinAltitude = 7.000000 m 2012-06-22T04:53:12.201Z,1340340792.201 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.DepthShallow = 15.000000 m 2012-06-22T04:53:12.203Z,1340340792.203 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.DepthDeep = 32.000000 m 2012-06-22T04:53:12.205Z,1340340792.205 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.NeedCommsTime = 50.000000 min 2012-06-22T04:53:12.207Z,1340340792.207 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.TimeoutDuration = 250.000000 min 2012-06-22T04:53:12.209Z,1340340792.209 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MassHold = 1 bool 2012-06-22T04:53:12.212Z,1340340792.212 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MassDefault = -0.007500 n/a 2012-06-22T04:53:12.214Z,1340340792.214 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.massDeadband = 0.000000 mm 2012-06-22T04:53:12.216Z,1340340792.216 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.BuoyancyHold = 1 bool 2012-06-22T04:53:12.218Z,1340340792.218 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.BuoyancyNeutral = 0.000505 n/a 2012-06-22T04:53:12.220Z,1340340792.220 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevatorHold = 0 bool 2012-06-22T04:53:12.222Z,1340340792.222 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevatorDefault = 0.000000 arcdeg 2012-06-22T04:53:12.224Z,1340340792.224 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.elevDeadband = 0.000000 arcdeg 2012-06-22T04:53:12.226Z,1340340792.226 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevIntInDepthMode = 0 bool 2012-06-22T04:53:12.229Z,1340340792.229 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.StopDepthExcursion = 5.000000 n/a 2012-06-22T04:53:12.231Z,1340340792.231 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kdPitchMass = 0.000000 n/a 2012-06-22T04:53:12.234Z,1340340792.234 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kiDepthOff = 0.100000 n/a 2012-06-22T04:53:12.236Z,1340340792.236 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kiPitchMass = 0.001500 n/a 2012-06-22T04:53:12.238Z,1340340792.238 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kpPitchMass = 0.010000 n/a 2012-06-22T04:53:12.241Z,1340340792.241 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.maxDiveAccel = 0.001000 n/a 2012-06-22T04:53:12.242Z,1340340792.242 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-06-22T04:53:12.247Z,1340340792.247 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-06-22T04:53:12.252Z,1340340792.252 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-06-22T04:53:12.258Z,1340340792.258 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-06-22T04:53:12.261Z,1340340792.261 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Construct. 2012-06-22T04:53:12.270Z,1340340792.270 [triangle_control_speed_onelap:WaypointOne:A.Pitch](DEBUG): Construct. 2012-06-22T04:53:12.274Z,1340340792.274 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Construct. 2012-06-22T04:53:12.276Z,1340340792.276 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Construct Waypoint. 2012-06-22T04:53:12.283Z,1340340792.283 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Construct Buoyancy. 2012-06-22T04:53:12.286Z,1340340792.286 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch](DEBUG): Construct. 2012-06-22T04:53:12.290Z,1340340792.290 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch](DEBUG): Construct. 2012-06-22T04:53:12.308Z,1340340792.308 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Construct. 2012-06-22T04:53:12.310Z,1340340792.310 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch](DEBUG): Construct. 2012-06-22T04:53:12.314Z,1340340792.314 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Construct Waypoint. 2012-06-22T04:53:12.319Z,1340340792.319 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Construct. 2012-06-22T04:53:12.321Z,1340340792.321 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch](DEBUG): Construct. 2012-06-22T04:53:12.324Z,1340340792.325 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Construct Waypoint. 2012-06-22T04:53:12.330Z,1340340792.330 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Construct. 2012-06-22T04:53:12.332Z,1340340792.332 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch](DEBUG): Construct. 2012-06-22T04:53:12.335Z,1340340792.335 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2012-06-22T04:53:12.352Z,1340340792.352 [MissionManager](DEBUG): 36.815 -121.869 Speed for leg 1 1 36.829 -121.886 Speed for leg 2 .75 36.816 -121.883 Speed for leg 3 .60 Maximum depth for the entire mission. 37 Minimum height above the sea floor for the entire mission. 7.0 Depth for transit 15 Depth for test 32 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications 50 Maximum length of mission 250 Set to True in order to hold mass at user defined position, False to allow mass to run on its own. Static setting for mass when the mass position is held. Sets the value of VerticalControl.massDeadband 0.0 Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. Buoyancy bladder position when buoyancy is held. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. Set to True in order to hold the elevators at neutral position, False to allow them to run on their own. Static setting for elevator if elevator position is held. 0.0 Sets the value of VerticalControl.elevDeadband 0.0 Sets VerticalControl.useElevIntInDepthMode value Sets VerticalControl.stopDepthExcursion within the triangle, but not during the transit. Sets the value of VerticalControl.kdPitchMass Sets the value of VerticalControl.kiDepthOff Sets the value of VerticalControl.kiPitchMass Sets the value of VerticalControl.kpPitchMass Sets the value of VerticalControl.maxDiveAccel 1 0 1 2012-06-22T04:53:12.353Z,1340340792.353 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/triangle_control_speed_onelap.xml 2012-06-22T04:53:12.512Z,1340340792.512 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.MassDefault -0.850000 centimeter 2012-06-22T04:53:12.700Z,1340340792.700 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.StopDepthExcursion 15.000000 meter 2012-06-22T04:53:12.701Z,1340340792.701 [CommandLine](IMPORTANT): got command run 2012-06-22T04:53:12.701Z,1340340792.701 [CommandLine](IMPORTANT): Running 2012-06-22T04:53:12.715Z,1340340792.716 [Default] Stopped 2012-06-22T04:53:12.716Z,1340340792.716 [Default](INFO): Aggregate::uninitialize Default 2012-06-22T04:53:12.716Z,1340340792.716 [Default:Iridium] Stopped 2012-06-22T04:53:12.716Z,1340340792.716 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-06-22T04:53:12.716Z,1340340792.716 [Default:Iridium:A.SetSpeed] Stopped 2012-06-22T04:53:12.716Z,1340340792.716 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T04:53:12.716Z,1340340792.716 [Default:Iridium:Read_Iridium] Stopped 2012-06-22T04:53:12.716Z,1340340792.716 [Default:CallIridium] Stopped 2012-06-22T04:53:12.716Z,1340340792.716 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-06-22T04:53:12.716Z,1340340792.716 [Default:CallIridium:B] Stopped 2012-06-22T04:53:12.716Z,1340340792.717 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-06-22T04:53:12.716Z,1340340792.717 [Default:D.SetSpeed] Stopped 2012-06-22T04:53:12.717Z,1340340792.717 [Default:D.SetSpeed](DEBUG): Uninitialize. 2012-06-22T04:53:12.717Z,1340340792.717 [Default:E.GoToSurface] Stopped 2012-06-22T04:53:12.717Z,1340340792.717 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T04:53:12.717Z,1340340792.717 [Default:F.Wait] Stopped 2012-06-22T04:53:12.717Z,1340340792.717 [Default:F.Wait](DEBUG): Uninitialize Wait Component. 2012-06-22T04:53:12.717Z,1340340792.717 [MissionManager](IMPORTANT): Started mission triangle_control_speed_onelap 2012-06-22T04:53:12.717Z,1340340792.717 [triangle_control_speed_onelap] Running Loop=1 2012-06-22T04:53:12.717Z,1340340792.717 [triangle_control_speed_onelap](INFO): Aggregate::initialize triangle_control_speed_onelap 2012-06-22T04:53:12.717Z,1340340792.717 [triangle_control_speed_onelap:A.AltitudeEnvelope] Running Loop=1 2012-06-22T04:53:12.717Z,1340340792.717 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-06-22T04:53:12.718Z,1340340792.718 [triangle_control_speed_onelap:B.DepthEnvelope] Running Loop=1 2012-06-22T04:53:12.718Z,1340340792.718 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-06-22T04:53:12.719Z,1340340792.719 [triangle_control_speed_onelap:C.OffshoreEnvelope] Running Loop=1 2012-06-22T04:53:12.719Z,1340340792.719 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-06-22T04:53:12.719Z,1340340792.719 [triangle_control_speed_onelap:D] Running Loop=1 2012-06-22T04:53:12.719Z,1340340792.719 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-22T04:53:12.719Z,1340340792.719 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T04:53:12.719Z,1340340792.719 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-22T04:53:12.719Z,1340340792.719 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T04:53:12.721Z,1340340792.721 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-22T04:53:12.721Z,1340340792.721 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T04:53:12.721Z,1340340792.721 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T04:53:12.721Z,1340340792.721 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-22T04:53:12.722Z,1340340792.722 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-22T04:53:12.722Z,1340340792.722 [triangle_control_speed_onelap:D] Running Loop=1 2012-06-22T04:53:12.722Z,1340340792.722 [triangle_control_speed_onelap:D](DEBUG): Initialize ReadDataComponent to sense platform_orientation 2012-06-22T04:53:12.723Z,1340340792.723 [triangle_control_speed_onelap:C.OffshoreEnvelope] Running Loop=1 2012-06-22T04:53:12.724Z,1340340792.724 [triangle_control_speed_onelap:B.DepthEnvelope] Running Loop=1 2012-06-22T04:53:12.725Z,1340340792.725 [triangle_control_speed_onelap:A.AltitudeEnvelope] Running Loop=1 2012-06-22T04:53:13.463Z,1340340793.463 [triangle_control_speed_onelap:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-06-22T04:53:13.463Z,1340340793.463 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T04:53:15.422Z,1340340795.422 [NAL9601](IMPORTANT): GPS fix at: 1340341001.00 2012-06-22T04:53:15.437Z,1340340795.437 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-22T04:53:15.437Z,1340340795.437 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-22T04:53:15.834Z,1340340795.834 [triangle_control_speed_onelap:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-06-22T04:53:29.275Z,1340340809.275 [NAL9601](INFO): SBD MO Status=0, MOMSN=10606, MT Status=0, MTMSN=0 2012-06-22T04:53:48.402Z,1340340828.402 [NAL9601](INFO): SBD MO Status=1, MOMSN=10607, MT Status=0, MTMSN=0 2012-06-22T04:53:48.615Z,1340340828.615 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0000.lzma 2012-06-22T04:53:48.615Z,1340340828.615 [NAL9601](INFO): Packets left to send: 2 2012-06-22T04:53:48.617Z,1340340828.617 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0000.lzma.parts/0002.sbd 2012-06-22T04:54:00.081Z,1340340840.081 [NAL9601](INFO): SBD MO Status=1, MOMSN=10608, MT Status=0, MTMSN=0 2012-06-22T04:54:00.295Z,1340340840.295 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0000.lzma 2012-06-22T04:54:00.295Z,1340340840.295 [NAL9601](INFO): Packets left to send: 1 2012-06-22T04:54:00.296Z,1340340840.296 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0000.lzma.parts/0001.sbd 2012-06-22T04:54:13.781Z,1340340853.781 [NAL9601](INFO): SBD MO Status=1, MOMSN=10609, MT Status=0, MTMSN=0 2012-06-22T04:54:13.975Z,1340340853.975 [NAL9601](INFO): Sent 162 bytes from file Logs/20120622T045247/shore0000.lzma 2012-06-22T04:54:13.975Z,1340340853.975 [NAL9601](INFO): Packets left to send: 0 2012-06-22T04:54:13.976Z,1340340853.976 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0000.lzma.parts/0000.sbd 2012-06-22T04:54:25.781Z,1340340865.782 [NAL9601](INFO): SBD MO Status=0, MOMSN=10610, MT Status=0, MTMSN=0 2012-06-22T04:54:25.972Z,1340340865.972 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T04:54:25.972Z,1340340865.972 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T04:54:26.209Z,1340340866.209 [triangle_control_speed_onelap:SURFACECOMMS:B:D](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-06-22T04:54:28.178Z,1340340868.178 [NAL9601](IMPORTANT): GPS fix at: 1340341074.00 2012-06-22T04:54:28.193Z,1340340868.193 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T04:54:28.194Z,1340340868.194 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T04:54:28.194Z,1340340868.194 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T04:54:28.194Z,1340340868.194 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T04:54:28.194Z,1340340868.194 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T04:54:28.194Z,1340340868.194 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T04:54:28.195Z,1340340868.195 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T04:54:28.195Z,1340340868.195 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T04:54:28.196Z,1340340868.195 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T04:54:28.196Z,1340340868.195 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T04:54:28.196Z,1340340868.196 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T04:54:28.196Z,1340340868.196 [triangle_control_speed_onelap:WaypointOne] Running Loop=1 2012-06-22T04:54:28.196Z,1340340868.196 [triangle_control_speed_onelap:WaypointOne](INFO): Aggregate::initialize triangle_control_speed_onelap:WaypointOne 2012-06-22T04:54:28.196Z,1340340868.196 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Running Loop=1 2012-06-22T04:54:28.196Z,1340340868.196 [triangle_control_speed_onelap:WaypointOne:A.Pitch](DEBUG): Initialize. 2012-06-22T04:54:28.196Z,1340340868.196 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Running Loop=1 2012-06-22T04:54:28.196Z,1340340868.196 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Initialize. 2012-06-22T04:54:28.197Z,1340340868.197 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint] Running Loop=1 2012-06-22T04:54:28.197Z,1340340868.197 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Initialize WaypointComponent. 2012-06-22T04:54:28.597Z,1340340868.597 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Running Loop=1 2012-06-22T04:54:28.598Z,1340340868.598 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Running Loop=1 2012-06-22T04:54:34.588Z,1340340874.588 [NAL9601](INFO): Powering down 2012-06-22T04:54:47.475Z,1340340887.475 [Radio_Freewave](INFO): Powering down 2012-06-22T04:54:55.398Z,1340340895.398 [Radio_Freewave](INFO): Powering up 2012-06-22T04:55:11.793Z,1340340911.793 [Radio_Freewave](INFO): Powering down 2012-06-22T04:55:20.593Z,1340340920.593 [Radio_Freewave](INFO): Powering up 2012-06-22T04:55:33.605Z,1340340933.605 [Radio_Freewave](INFO): Powering down 2012-06-22T04:55:35.623Z,1340340935.623 [Radio_Freewave](INFO): Powering up 2012-06-22T04:55:46.443Z,1340340946.443 [Radio_Freewave](INFO): Powering down 2012-06-22T04:55:57.605Z,1340340957.605 [Radio_Freewave](INFO): Powering up 2012-06-22T04:56:00.832Z,1340340960.832 [Radio_Freewave](INFO): Powering down 2012-06-22T04:57:08.522Z,1340341028.523 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000 2012-06-22T04:57:08.523Z,1340341028.523 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint] Stopped 2012-06-22T04:57:08.523Z,1340341028.523 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-06-22T04:57:08.523Z,1340341028.523 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1] Running Loop=1 2012-06-22T04:57:08.523Z,1340341028.523 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1 2012-06-22T04:57:08.923Z,1340341028.923 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-22T04:57:08.923Z,1340341028.923 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T04:57:08.923Z,1340341028.923 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-22T04:57:08.923Z,1340341028.923 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T04:59:25.613Z,1340341165.613 [Radio_Freewave](INFO): Powering up 2012-06-22T04:59:25.624Z,1340341165.624 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-22T04:59:25.624Z,1340341165.624 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T04:59:25.624Z,1340341165.624 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T04:59:25.624Z,1340341165.624 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-22T04:59:25.624Z,1340341165.624 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-22T04:59:26.494Z,1340341166.494 [NAL9601](INFO): Powering up 2012-06-22T05:00:31.919Z,1340341231.919 [NAL9601](INFO): NAL9601 initialized 2012-06-22T05:00:55.066Z,1340341255.066 [NAL9601](IMPORTANT): GPS fix at: 1340341455.00 2012-06-22T05:00:55.081Z,1340341255.081 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-22T05:00:55.081Z,1340341255.081 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-22T05:01:18.242Z,1340341278.242 [NAL9601](INFO): SBD MO Status=1, MOMSN=10611, MT Status=0, MTMSN=0 2012-06-22T05:01:18.435Z,1340341278.435 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0001.lzma 2012-06-22T05:01:18.435Z,1340341278.435 [NAL9601](INFO): Packets left to send: 3 2012-06-22T05:01:18.437Z,1340341278.437 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0001.lzma.parts/0003.sbd 2012-06-22T05:01:37.961Z,1340341297.961 [NAL9601](INFO): SBD MO Status=1, MOMSN=10612, MT Status=0, MTMSN=0 2012-06-22T05:01:38.107Z,1340341298.107 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0001.lzma 2012-06-22T05:01:38.107Z,1340341298.107 [NAL9601](INFO): Packets left to send: 2 2012-06-22T05:01:38.108Z,1340341298.108 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0001.lzma.parts/0002.sbd 2012-06-22T05:01:58.500Z,1340341318.500 [NAL9601](INFO): SBD MO Status=1, MOMSN=10613, MT Status=0, MTMSN=0 2012-06-22T05:01:58.671Z,1340341318.671 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0001.lzma 2012-06-22T05:01:58.671Z,1340341318.671 [NAL9601](INFO): Packets left to send: 1 2012-06-22T05:01:58.672Z,1340341318.672 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0001.lzma.parts/0001.sbd 2012-06-22T05:02:16.262Z,1340341336.261 [NAL9601](INFO): SBD MO Status=2, MOMSN=10614, MT Status=2, MTMSN=0 2012-06-22T05:02:16.262Z,1340341336.262 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-06-22T05:02:27.047Z,1340341347.047 [NAL9601](INFO): SBD MO Status=1, MOMSN=10614, MT Status=0, MTMSN=0 2012-06-22T05:02:27.227Z,1340341347.227 [NAL9601](INFO): Sent 16 bytes from file Logs/20120622T045247/shore0001.lzma 2012-06-22T05:02:27.227Z,1340341347.227 [NAL9601](INFO): Packets left to send: 0 2012-06-22T05:02:27.228Z,1340341347.228 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0001.lzma.parts/0000.sbd 2012-06-22T05:02:37.447Z,1340341357.447 [NAL9601](INFO): SBD MO Status=0, MOMSN=10615, MT Status=0, MTMSN=0 2012-06-22T05:02:37.625Z,1340341357.625 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T05:02:37.625Z,1340341357.625 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T05:02:39.803Z,1340341359.803 [NAL9601](IMPORTANT): GPS fix at: 1340341560.00 2012-06-22T05:02:39.817Z,1340341359.818 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T05:02:39.818Z,1340341359.818 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T05:02:39.818Z,1340341359.818 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T05:02:39.818Z,1340341359.818 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T05:02:39.818Z,1340341359.819 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T05:02:39.819Z,1340341359.819 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T05:02:39.819Z,1340341359.819 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T05:02:39.820Z,1340341359.819 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T05:02:39.820Z,1340341359.820 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T05:02:39.820Z,1340341359.820 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T05:02:39.820Z,1340341359.820 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T05:02:40.219Z,1340341360.219 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1 2012-06-22T05:02:40.219Z,1340341360.219 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1] Stopped 2012-06-22T05:02:40.219Z,1340341360.219 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1 2012-06-22T05:02:40.220Z,1340341360.220 [triangle_control_speed_onelap:WaypointOne](INFO): Completed triangle_control_speed_onelap:WaypointOne 2012-06-22T05:02:40.221Z,1340341360.220 [triangle_control_speed_onelap:WaypointOne] Stopped 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:WaypointOne](INFO): Aggregate::uninitialize triangle_control_speed_onelap:WaypointOne 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Stopped 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Stopped 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Uninitialize. 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:ThreeLegs] Running Loop=1 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:ThreeLegs](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Running Loop=1 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:BuoyancyHold 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Running Loop=1 2012-06-22T05:02:40.221Z,1340341360.221 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-06-22T05:02:40.222Z,1340341360.222 [triangle_control_speed_onelap:ThreeLegs:MassHold] Running Loop=1 2012-06-22T05:02:40.222Z,1340341360.222 [triangle_control_speed_onelap:ThreeLegs:MassHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:MassHold 2012-06-22T05:02:40.222Z,1340341360.222 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Running Loop=1 2012-06-22T05:02:40.222Z,1340341360.222 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch](DEBUG): Initialize. 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:ElevatorHold 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch](DEBUG): Initialize. 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:D] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:E] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:F] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:G] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:H] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:I] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.223 [triangle_control_speed_onelap:ThreeLegs:J] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:K] Running Loop=1 2012-06-22T05:02:40.223Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:L] Running Loop=1 2012-06-22T05:02:40.224Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive] Running Loop=1 2012-06-22T05:02:40.224Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive 2012-06-22T05:02:40.224Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1] Running Loop=1 2012-06-22T05:02:40.224Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1 2012-06-22T05:02:40.224Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Running Loop=1 2012-06-22T05:02:40.224Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Initialize. 2012-06-22T05:02:40.224Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Running Loop=1 2012-06-22T05:02:40.224Z,1340341360.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch](DEBUG): Initialize. 2012-06-22T05:02:40.225Z,1340341360.225 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint] Running Loop=1 2012-06-22T05:02:40.225Z,1340341360.225 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent. 2012-06-22T05:02:40.635Z,1340341360.635 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Running Loop=1 2012-06-22T05:02:40.636Z,1340341360.636 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Running Loop=1 2012-06-22T05:02:40.637Z,1340341360.637 [triangle_control_speed_onelap:ThreeLegs:L] Running Loop=1 2012-06-22T05:02:40.638Z,1340341360.638 [triangle_control_speed_onelap:ThreeLegs:K] Running Loop=1 2012-06-22T05:02:40.639Z,1340341360.639 [triangle_control_speed_onelap:ThreeLegs:J] Running Loop=1 2012-06-22T05:02:40.640Z,1340341360.640 [triangle_control_speed_onelap:ThreeLegs:I] Running Loop=1 2012-06-22T05:02:40.641Z,1340341360.641 [triangle_control_speed_onelap:ThreeLegs:H] Running Loop=1 2012-06-22T05:02:40.642Z,1340341360.642 [triangle_control_speed_onelap:ThreeLegs:G] Running Loop=1 2012-06-22T05:02:40.643Z,1340341360.643 [triangle_control_speed_onelap:ThreeLegs:F] Running Loop=1 2012-06-22T05:02:40.644Z,1340341360.644 [triangle_control_speed_onelap:ThreeLegs:E] Running Loop=1 2012-06-22T05:02:40.645Z,1340341360.645 [triangle_control_speed_onelap:ThreeLegs:D] Running Loop=1 2012-06-22T05:02:40.646Z,1340341360.645 [triangle_control_speed_onelap:ThreeLegs:MassHold] Running Loop=1 2012-06-22T05:02:40.646Z,1340341360.645 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Running Loop=1 2012-06-22T05:02:40.646Z,1340341360.646 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Running Loop=1 2012-06-22T05:02:40.646Z,1340341360.647 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Running Loop=1 2012-06-22T05:02:46.235Z,1340341366.235 [NAL9601](INFO): Powering down 2012-06-22T05:03:00.641Z,1340341380.641 [Radio_Freewave](INFO): Powering down 2012-06-22T05:03:13.041Z,1340341393.041 [Radio_Freewave](INFO): Powering up 2012-06-22T05:03:46.673Z,1340341426.673 [Radio_Freewave](INFO): Powering down 2012-06-22T05:33:45.988Z,1340343225.987 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:33:48.385Z,1340343228.385 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:33:50.785Z,1340343230.785 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:33:53.185Z,1340343233.185 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:39:16.119Z,1340343556.119 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](INFO): Reached Waypoint: 36.829000,-121.886000 2012-06-22T05:39:16.119Z,1340343556.119 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint] Stopped 2012-06-22T05:39:16.119Z,1340343556.119 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-06-22T05:39:16.119Z,1340343556.119 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2] Running Loop=1 2012-06-22T05:39:16.119Z,1340343556.119 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2 2012-06-22T05:39:16.540Z,1340343556.540 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-22T05:39:16.540Z,1340343556.540 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T05:39:16.540Z,1340343556.540 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-22T05:39:16.540Z,1340343556.540 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T05:44:42.233Z,1340343882.233 [Radio_Freewave](INFO): Powering up 2012-06-22T05:44:42.248Z,1340343882.248 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-22T05:44:42.248Z,1340343882.248 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T05:44:42.248Z,1340343882.248 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T05:44:42.248Z,1340343882.248 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-22T05:44:42.248Z,1340343882.248 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-22T05:44:43.023Z,1340343883.023 [NAL9601](INFO): Powering up 2012-06-22T05:45:48.547Z,1340343948.547 [NAL9601](INFO): NAL9601 initialized 2012-06-22T05:45:49.675Z,1340343949.675 [NAL9601](IMPORTANT): GPS fix at: 1340344153.00 2012-06-22T05:45:49.707Z,1340343949.707 [Navigation](IMPORTANT): Error 7.3 %DT at bearing -164.4 deg., over the last 2291.1 m. 2012-06-22T05:45:49.707Z,1340343949.707 [Navigation](IMPORTANT): Distance made good 2432.4 m, at (36.8311, -121.8848). 2012-06-22T05:45:49.708Z,1340343949.708 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-22T05:45:49.708Z,1340343949.708 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-22T05:46:10.971Z,1340343970.971 [NAL9601](INFO): SBD MO Status=1, MOMSN=10616, MT Status=0, MTMSN=0 2012-06-22T05:46:11.103Z,1340343971.103 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:46:11.103Z,1340343971.103 [NAL9601](INFO): Packets left to send: 9 2012-06-22T05:46:11.105Z,1340343971.105 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0009.sbd 2012-06-22T05:46:23.449Z,1340343983.449 [NAL9601](INFO): SBD MO Status=1, MOMSN=10617, MT Status=0, MTMSN=0 2012-06-22T05:46:23.591Z,1340343983.591 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:46:23.591Z,1340343983.591 [NAL9601](INFO): Packets left to send: 8 2012-06-22T05:46:23.592Z,1340343983.592 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0008.sbd 2012-06-22T05:46:42.409Z,1340344002.409 [NAL9601](INFO): SBD MO Status=2, MOMSN=10618, MT Status=2, MTMSN=0 2012-06-22T05:46:42.410Z,1340344002.409 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-06-22T05:47:01.522Z,1340344021.522 [NAL9601](INFO): SBD MO Status=1, MOMSN=10618, MT Status=0, MTMSN=0 2012-06-22T05:47:01.723Z,1340344021.723 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:47:01.723Z,1340344021.723 [NAL9601](INFO): Packets left to send: 7 2012-06-22T05:47:01.724Z,1340344021.724 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0007.sbd 2012-06-22T05:47:27.670Z,1340344047.670 [NAL9601](INFO): SBD MO Status=2, MOMSN=10619, MT Status=2, MTMSN=0 2012-06-22T05:47:27.670Z,1340344047.670 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-06-22T05:47:44.960Z,1340344064.960 [NAL9601](INFO): SBD MO Status=1, MOMSN=10619, MT Status=0, MTMSN=0 2012-06-22T05:47:45.155Z,1340344065.155 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:47:45.155Z,1340344065.155 [NAL9601](INFO): Packets left to send: 6 2012-06-22T05:47:45.156Z,1340344065.156 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0006.sbd 2012-06-22T05:47:57.389Z,1340344077.389 [NAL9601](INFO): SBD MO Status=1, MOMSN=10620, MT Status=0, MTMSN=0 2012-06-22T05:47:57.539Z,1340344077.539 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:47:57.539Z,1340344077.539 [NAL9601](INFO): Packets left to send: 5 2012-06-22T05:47:57.540Z,1340344077.540 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0005.sbd 2012-06-22T05:48:07.886Z,1340344087.886 [NAL9601](INFO): SBD MO Status=1, MOMSN=10621, MT Status=0, MTMSN=0 2012-06-22T05:48:08.019Z,1340344088.019 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:48:08.019Z,1340344088.019 [NAL9601](INFO): Packets left to send: 4 2012-06-22T05:48:08.020Z,1340344088.020 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0004.sbd 2012-06-22T05:48:29.272Z,1340344109.272 [NAL9601](INFO): SBD MO Status=1, MOMSN=10622, MT Status=0, MTMSN=0 2012-06-22T05:48:29.487Z,1340344109.487 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:48:29.487Z,1340344109.487 [NAL9601](INFO): Packets left to send: 3 2012-06-22T05:48:29.488Z,1340344109.488 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0003.sbd 2012-06-22T05:48:45.049Z,1340344125.049 [NAL9601](INFO): SBD MO Status=2, MOMSN=10623, MT Status=2, MTMSN=0 2012-06-22T05:48:45.049Z,1340344125.049 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-06-22T05:49:05.356Z,1340344145.356 [NAL9601](INFO): SBD MO Status=1, MOMSN=10623, MT Status=0, MTMSN=0 2012-06-22T05:49:05.531Z,1340344145.531 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:49:05.531Z,1340344145.531 [NAL9601](INFO): Packets left to send: 2 2012-06-22T05:49:05.533Z,1340344145.533 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0002.sbd 2012-06-22T05:49:17.646Z,1340344157.645 [NAL9601](INFO): SBD MO Status=1, MOMSN=10624, MT Status=0, MTMSN=0 2012-06-22T05:49:17.815Z,1340344157.815 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:49:17.815Z,1340344157.815 [NAL9601](INFO): Packets left to send: 1 2012-06-22T05:49:17.816Z,1340344157.816 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0001.sbd 2012-06-22T05:49:29.823Z,1340344169.823 [NAL9601](INFO): SBD MO Status=1, MOMSN=10625, MT Status=0, MTMSN=0 2012-06-22T05:49:29.991Z,1340344169.991 [NAL9601](INFO): Sent 157 bytes from file Logs/20120622T045247/shore0002.lzma 2012-06-22T05:49:29.991Z,1340344169.991 [NAL9601](INFO): Packets left to send: 0 2012-06-22T05:49:29.992Z,1340344169.992 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0002.lzma.parts/0000.sbd 2012-06-22T05:49:38.987Z,1340344178.987 [NAL9601](INFO): SBD MO Status=0, MOMSN=10626, MT Status=0, MTMSN=0 2012-06-22T05:49:39.210Z,1340344179.210 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T05:49:39.210Z,1340344179.210 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T05:49:41.378Z,1340344181.378 [NAL9601](IMPORTANT): GPS fix at: 1340344386.00 2012-06-22T05:49:41.394Z,1340344181.394 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T05:49:41.394Z,1340344181.394 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T05:49:41.394Z,1340344181.394 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T05:49:41.394Z,1340344181.394 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T05:49:41.394Z,1340344181.394 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T05:49:41.394Z,1340344181.395 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T05:49:41.395Z,1340344181.395 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T05:49:41.395Z,1340344181.395 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T05:49:41.396Z,1340344181.396 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T05:49:41.396Z,1340344181.396 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T05:49:41.396Z,1340344181.396 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T05:49:41.846Z,1340344181.846 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2 2012-06-22T05:49:41.846Z,1340344181.846 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2] Stopped 2012-06-22T05:49:41.846Z,1340344181.846 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2 2012-06-22T05:49:41.847Z,1340344181.847 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1 2012-06-22T05:49:41.847Z,1340344181.847 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1] Stopped 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Stopped 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Stopped 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2] Running Loop=1 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Running Loop=1 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Initialize. 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Running Loop=1 2012-06-22T05:49:41.848Z,1340344181.848 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch](DEBUG): Initialize. 2012-06-22T05:49:41.849Z,1340344181.849 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint] Running Loop=1 2012-06-22T05:49:41.849Z,1340344181.849 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Initialize WaypointComponent. 2012-06-22T05:49:42.223Z,1340344182.223 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Running Loop=1 2012-06-22T05:49:42.224Z,1340344182.224 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Running Loop=1 2012-06-22T05:49:47.775Z,1340344187.776 [NAL9601](INFO): Powering down 2012-06-22T05:50:07.365Z,1340344207.365 [Radio_Freewave](INFO): Powering down 2012-06-22T05:54:05.217Z,1340344445.217 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:07.987Z,1340344447.987 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:10.419Z,1340344450.419 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:12.772Z,1340344452.772 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:15.172Z,1340344455.172 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:17.571Z,1340344457.571 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:19.974Z,1340344459.974 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:22.371Z,1340344462.371 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:24.772Z,1340344464.772 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:27.171Z,1340344467.171 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:29.571Z,1340344469.571 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:31.974Z,1340344471.974 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:34.372Z,1340344474.372 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:36.772Z,1340344476.772 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:39.172Z,1340344479.172 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:41.617Z,1340344481.617 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:43.974Z,1340344483.974 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T05:54:46.372Z,1340344486.372 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535 2012-06-22T06:03:40.052Z,1340345020.052 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:03:42.855Z,1340345022.855 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:03:45.252Z,1340345025.252 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:03:47.652Z,1340345027.652 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:03:50.052Z,1340345030.052 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:03:52.452Z,1340345032.452 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:03:54.899Z,1340345034.899 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:03:57.251Z,1340345037.251 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:03:59.651Z,1340345039.651 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:04:02.051Z,1340345042.051 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T06:12:05.629Z,1340345525.629 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#2 STATUS: 65535 2012-06-22T06:32:12.209Z,1340346732.209 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](INFO): Reached Waypoint: 36.816000,-121.883000 2012-06-22T06:32:12.209Z,1340346732.209 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint] Stopped 2012-06-22T06:32:12.209Z,1340346732.209 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-06-22T06:32:12.209Z,1340346732.209 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3] Running Loop=1 2012-06-22T06:32:12.209Z,1340346732.209 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3 2012-06-22T06:32:12.599Z,1340346732.599 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-22T06:32:12.599Z,1340346732.599 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T06:32:12.599Z,1340346732.599 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-22T06:32:12.599Z,1340346732.599 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T06:36:39.148Z,1340346999.148 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#5 STATUS: 65535 2012-06-22T06:37:25.562Z,1340347045.562 [Radio_Freewave](INFO): Powering up 2012-06-22T06:37:25.576Z,1340347045.576 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-22T06:37:25.576Z,1340347045.576 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T06:37:25.576Z,1340347045.576 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T06:37:25.576Z,1340347045.576 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-22T06:37:25.577Z,1340347045.577 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-22T06:37:26.316Z,1340347046.316 [NAL9601](INFO): Powering up 2012-06-22T06:38:31.839Z,1340347111.839 [NAL9601](INFO): NAL9601 initialized 2012-06-22T06:38:32.947Z,1340347112.947 [NAL9601](IMPORTANT): GPS fix at: 1340347322.00 2012-06-22T06:38:32.977Z,1340347112.977 [Navigation](IMPORTANT): Error 4.6 %DT at bearing -53.0 deg., over the last 1881.9 m. 2012-06-22T06:38:32.978Z,1340347112.978 [Navigation](IMPORTANT): Distance made good 1779.4 m, at (36.8152, -121.8835). 2012-06-22T06:38:32.980Z,1340347112.980 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-22T06:38:32.980Z,1340347112.980 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-22T06:38:56.834Z,1340347136.834 [NAL9601](INFO): SBD MO Status=1, MOMSN=10627, MT Status=0, MTMSN=0 2012-06-22T06:38:56.991Z,1340347136.991 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:38:56.991Z,1340347136.991 [NAL9601](INFO): Packets left to send: 12 2012-06-22T06:38:56.993Z,1340347136.993 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0012.sbd 2012-06-22T06:39:16.925Z,1340347156.925 [NAL9601](INFO): SBD MO Status=1, MOMSN=10628, MT Status=0, MTMSN=0 2012-06-22T06:39:17.071Z,1340347157.071 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:39:17.071Z,1340347157.071 [NAL9601](INFO): Packets left to send: 11 2012-06-22T06:39:17.072Z,1340347157.072 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0011.sbd 2012-06-22T06:39:33.435Z,1340347173.435 [NAL9601](INFO): SBD MO Status=1, MOMSN=10629, MT Status=0, MTMSN=0 2012-06-22T06:39:33.635Z,1340347173.635 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:39:33.635Z,1340347173.635 [NAL9601](INFO): Packets left to send: 10 2012-06-22T06:39:33.637Z,1340347173.637 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0010.sbd 2012-06-22T06:39:47.178Z,1340347187.178 [NAL9601](INFO): SBD MO Status=1, MOMSN=10630, MT Status=0, MTMSN=0 2012-06-22T06:39:47.311Z,1340347187.311 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:39:47.311Z,1340347187.311 [NAL9601](INFO): Packets left to send: 9 2012-06-22T06:39:47.312Z,1340347187.312 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0009.sbd 2012-06-22T06:39:58.957Z,1340347198.957 [NAL9601](INFO): SBD MO Status=1, MOMSN=10631, MT Status=0, MTMSN=0 2012-06-22T06:39:59.095Z,1340347199.095 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:39:59.095Z,1340347199.095 [NAL9601](INFO): Packets left to send: 8 2012-06-22T06:39:59.096Z,1340347199.096 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0008.sbd 2012-06-22T06:40:09.068Z,1340347209.068 [NAL9601](INFO): SBD MO Status=2, MOMSN=10632, MT Status=2, MTMSN=0 2012-06-22T06:40:09.068Z,1340347209.068 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-06-22T06:40:23.023Z,1340347223.023 [NAL9601](INFO): SBD MO Status=1, MOMSN=10632, MT Status=0, MTMSN=0 2012-06-22T06:40:23.159Z,1340347223.159 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:40:23.159Z,1340347223.159 [NAL9601](INFO): Packets left to send: 7 2012-06-22T06:40:23.160Z,1340347223.160 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0007.sbd 2012-06-22T06:40:35.109Z,1340347235.109 [NAL9601](INFO): SBD MO Status=1, MOMSN=10633, MT Status=0, MTMSN=0 2012-06-22T06:40:35.243Z,1340347235.243 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:40:35.243Z,1340347235.243 [NAL9601](INFO): Packets left to send: 6 2012-06-22T06:40:35.244Z,1340347235.244 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0006.sbd 2012-06-22T06:40:49.237Z,1340347249.237 [NAL9601](INFO): SBD MO Status=1, MOMSN=10634, MT Status=0, MTMSN=0 2012-06-22T06:40:49.415Z,1340347249.415 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:40:49.415Z,1340347249.415 [NAL9601](INFO): Packets left to send: 5 2012-06-22T06:40:49.416Z,1340347249.416 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0005.sbd 2012-06-22T06:41:00.942Z,1340347260.942 [NAL9601](INFO): SBD MO Status=1, MOMSN=10635, MT Status=0, MTMSN=0 2012-06-22T06:41:01.099Z,1340347261.099 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:41:01.099Z,1340347261.099 [NAL9601](INFO): Packets left to send: 4 2012-06-22T06:41:01.100Z,1340347261.100 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0004.sbd 2012-06-22T06:41:14.234Z,1340347274.234 [NAL9601](INFO): SBD MO Status=1, MOMSN=10636, MT Status=0, MTMSN=0 2012-06-22T06:41:14.375Z,1340347274.375 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:41:14.375Z,1340347274.375 [NAL9601](INFO): Packets left to send: 3 2012-06-22T06:41:14.376Z,1340347274.376 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0003.sbd 2012-06-22T06:41:24.020Z,1340347284.020 [NAL9601](INFO): SBD MO Status=1, MOMSN=10637, MT Status=0, MTMSN=0 2012-06-22T06:41:24.163Z,1340347284.163 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:41:24.163Z,1340347284.163 [NAL9601](INFO): Packets left to send: 2 2012-06-22T06:41:24.164Z,1340347284.164 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0002.sbd 2012-06-22T06:41:36.062Z,1340347296.062 [NAL9601](INFO): SBD MO Status=1, MOMSN=10638, MT Status=0, MTMSN=0 2012-06-22T06:41:36.247Z,1340347296.247 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:41:36.247Z,1340347296.247 [NAL9601](INFO): Packets left to send: 1 2012-06-22T06:41:36.248Z,1340347296.248 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0001.sbd 2012-06-22T06:41:48.168Z,1340347308.168 [NAL9601](INFO): SBD MO Status=1, MOMSN=10639, MT Status=0, MTMSN=0 2012-06-22T06:41:48.323Z,1340347308.323 [NAL9601](INFO): Sent 148 bytes from file Logs/20120622T045247/shore0003.lzma 2012-06-22T06:41:48.323Z,1340347308.323 [NAL9601](INFO): Packets left to send: 0 2012-06-22T06:41:48.324Z,1340347308.324 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0003.lzma.parts/0000.sbd 2012-06-22T06:42:00.149Z,1340347320.149 [NAL9601](INFO): SBD MO Status=0, MOMSN=10640, MT Status=0, MTMSN=0 2012-06-22T06:42:00.321Z,1340347320.321 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T06:42:00.321Z,1340347320.321 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T06:42:02.550Z,1340347322.550 [NAL9601](IMPORTANT): GPS fix at: 1340347532.00 2012-06-22T06:42:02.564Z,1340347322.565 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T06:42:02.565Z,1340347322.565 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T06:42:02.565Z,1340347322.565 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T06:42:02.565Z,1340347322.565 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T06:42:02.565Z,1340347322.565 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T06:42:02.565Z,1340347322.565 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T06:42:02.566Z,1340347322.566 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T06:42:02.566Z,1340347322.566 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T06:42:02.567Z,1340347322.566 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T06:42:02.567Z,1340347322.566 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T06:42:02.567Z,1340347322.567 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T06:42:02.978Z,1340347322.978 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3 2012-06-22T06:42:02.978Z,1340347322.978 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3] Stopped 2012-06-22T06:42:02.978Z,1340347322.978 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3 2012-06-22T06:42:02.979Z,1340347322.979 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2 2012-06-22T06:42:02.979Z,1340347322.979 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2] Stopped 2012-06-22T06:42:02.979Z,1340347322.979 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2 2012-06-22T06:42:02.979Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Stopped 2012-06-22T06:42:02.980Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T06:42:02.980Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Stopped 2012-06-22T06:42:02.980Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3] Running Loop=1 2012-06-22T06:42:02.980Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3 2012-06-22T06:42:02.980Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Running Loop=1 2012-06-22T06:42:02.980Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Initialize. 2012-06-22T06:42:02.980Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Running Loop=1 2012-06-22T06:42:02.980Z,1340347322.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch](DEBUG): Initialize. 2012-06-22T06:42:02.981Z,1340347322.981 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint] Running Loop=1 2012-06-22T06:42:02.981Z,1340347322.981 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2012-06-22T06:42:03.372Z,1340347323.372 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Running Loop=1 2012-06-22T06:42:03.373Z,1340347323.373 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Running Loop=1 2012-06-22T06:42:08.959Z,1340347328.959 [NAL9601](INFO): Powering down 2012-06-22T06:45:32.790Z,1340347532.790 [Radio_Freewave](INFO): Powering down 2012-06-22T07:21:22.166Z,1340349682.166 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000 2012-06-22T07:21:22.166Z,1340349682.166 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint] Stopped 2012-06-22T07:21:22.166Z,1340349682.166 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-06-22T07:21:22.166Z,1340349682.166 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1] Running Loop=1 2012-06-22T07:21:22.166Z,1340349682.166 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1 2012-06-22T07:21:22.586Z,1340349682.586 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-22T07:21:22.587Z,1340349682.587 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T07:21:22.587Z,1340349682.587 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-22T07:21:22.587Z,1340349682.587 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:26:47.079Z,1340350007.079 [Radio_Freewave](INFO): Powering up 2012-06-22T07:26:47.093Z,1340350007.093 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-22T07:26:47.094Z,1340350007.093 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T07:26:47.094Z,1340350007.094 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T07:26:47.094Z,1340350007.094 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:26:47.094Z,1340350007.094 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-22T07:26:47.855Z,1340350007.855 [NAL9601](INFO): Powering up 2012-06-22T07:27:53.479Z,1340350073.479 [NAL9601](INFO): NAL9601 initialized 2012-06-22T07:27:54.588Z,1340350074.588 [NAL9601](IMPORTANT): GPS fix at: 1340350288.00 2012-06-22T07:27:54.602Z,1340350074.602 [Navigation](IMPORTANT): Error 5.3 %DT at bearing -38.3 deg., over the last 1516.9 m. 2012-06-22T07:27:54.603Z,1340350074.603 [Navigation](IMPORTANT): Distance made good 1350.8 m, at (36.8136, -121.8684). 2012-06-22T07:27:54.604Z,1340350074.603 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-22T07:27:54.604Z,1340350074.604 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-22T07:28:14.479Z,1340350094.479 [NAL9601](INFO): SBD MO Status=1, MOMSN=10641, MT Status=0, MTMSN=0 2012-06-22T07:28:14.635Z,1340350094.635 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:28:14.635Z,1340350094.635 [NAL9601](INFO): Packets left to send: 12 2012-06-22T07:28:14.637Z,1340350094.637 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0012.sbd 2012-06-22T07:28:24.613Z,1340350104.612 [NAL9601](INFO): SBD MO Status=1, MOMSN=10642, MT Status=0, MTMSN=0 2012-06-22T07:28:24.819Z,1340350104.819 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:28:24.819Z,1340350104.819 [NAL9601](INFO): Packets left to send: 11 2012-06-22T07:28:24.820Z,1340350104.820 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0011.sbd 2012-06-22T07:28:38.796Z,1340350118.796 [NAL9601](INFO): SBD MO Status=1, MOMSN=10643, MT Status=0, MTMSN=0 2012-06-22T07:28:38.999Z,1340350118.999 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:28:38.999Z,1340350118.999 [NAL9601](INFO): Packets left to send: 10 2012-06-22T07:28:39.000Z,1340350119.000 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0010.sbd 2012-06-22T07:28:53.421Z,1340350133.421 [NAL9601](INFO): SBD MO Status=1, MOMSN=10644, MT Status=0, MTMSN=0 2012-06-22T07:28:53.575Z,1340350133.575 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:28:53.575Z,1340350133.575 [NAL9601](INFO): Packets left to send: 9 2012-06-22T07:28:53.576Z,1340350133.576 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0009.sbd 2012-06-22T07:29:09.935Z,1340350149.935 [NAL9601](INFO): SBD MO Status=1, MOMSN=10645, MT Status=0, MTMSN=0 2012-06-22T07:29:10.151Z,1340350150.151 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:29:10.151Z,1340350150.151 [NAL9601](INFO): Packets left to send: 8 2012-06-22T07:29:10.152Z,1340350150.152 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0008.sbd 2012-06-22T07:29:24.033Z,1340350164.033 [NAL9601](INFO): SBD MO Status=1, MOMSN=10646, MT Status=0, MTMSN=0 2012-06-22T07:29:24.231Z,1340350164.231 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:29:24.231Z,1340350164.231 [NAL9601](INFO): Packets left to send: 7 2012-06-22T07:29:24.232Z,1340350164.232 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0007.sbd 2012-06-22T07:29:35.292Z,1340350175.292 [NAL9601](INFO): SBD MO Status=1, MOMSN=10647, MT Status=0, MTMSN=0 2012-06-22T07:29:35.411Z,1340350175.411 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:29:35.411Z,1340350175.411 [NAL9601](INFO): Packets left to send: 6 2012-06-22T07:29:35.412Z,1340350175.412 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0006.sbd 2012-06-22T07:29:47.366Z,1340350187.365 [NAL9601](INFO): SBD MO Status=1, MOMSN=10648, MT Status=0, MTMSN=0 2012-06-22T07:29:47.491Z,1340350187.491 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:29:47.491Z,1340350187.491 [NAL9601](INFO): Packets left to send: 5 2012-06-22T07:29:47.492Z,1340350187.492 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0005.sbd 2012-06-22T07:29:59.111Z,1340350199.111 [NAL9601](INFO): SBD MO Status=1, MOMSN=10649, MT Status=0, MTMSN=0 2012-06-22T07:29:59.275Z,1340350199.275 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:29:59.275Z,1340350199.275 [NAL9601](INFO): Packets left to send: 4 2012-06-22T07:29:59.276Z,1340350199.276 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0004.sbd 2012-06-22T07:30:20.177Z,1340350220.177 [NAL9601](INFO): SBD MO Status=1, MOMSN=10650, MT Status=0, MTMSN=0 2012-06-22T07:30:20.339Z,1340350220.339 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:30:20.339Z,1340350220.339 [NAL9601](INFO): Packets left to send: 3 2012-06-22T07:30:20.340Z,1340350220.340 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0003.sbd 2012-06-22T07:30:31.557Z,1340350231.557 [NAL9601](INFO): SBD MO Status=1, MOMSN=10651, MT Status=0, MTMSN=0 2012-06-22T07:30:31.723Z,1340350231.723 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:30:31.723Z,1340350231.723 [NAL9601](INFO): Packets left to send: 2 2012-06-22T07:30:31.724Z,1340350231.724 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0002.sbd 2012-06-22T07:30:44.136Z,1340350244.136 [NAL9601](INFO): SBD MO Status=1, MOMSN=10652, MT Status=0, MTMSN=0 2012-06-22T07:30:44.303Z,1340350244.303 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:30:44.303Z,1340350244.303 [NAL9601](INFO): Packets left to send: 1 2012-06-22T07:30:44.304Z,1340350244.304 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0001.sbd 2012-06-22T07:30:56.637Z,1340350256.637 [NAL9601](INFO): SBD MO Status=1, MOMSN=10653, MT Status=0, MTMSN=0 2012-06-22T07:30:56.787Z,1340350256.787 [NAL9601](INFO): Sent 263 bytes from file Logs/20120622T045247/shore0004.lzma 2012-06-22T07:30:56.787Z,1340350256.787 [NAL9601](INFO): Packets left to send: 0 2012-06-22T07:30:56.788Z,1340350256.788 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0004.lzma.parts/0000.sbd 2012-06-22T07:31:07.847Z,1340350267.847 [NAL9601](INFO): SBD MO Status=0, MOMSN=10654, MT Status=0, MTMSN=0 2012-06-22T07:31:08.010Z,1340350268.010 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T07:31:08.010Z,1340350268.010 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T07:31:10.272Z,1340350270.272 [NAL9601](IMPORTANT): GPS fix at: 1340350484.00 2012-06-22T07:31:10.289Z,1340350270.289 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T07:31:10.290Z,1340350270.290 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T07:31:10.290Z,1340350270.290 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T07:31:10.290Z,1340350270.290 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T07:31:10.290Z,1340350270.290 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T07:31:10.290Z,1340350270.290 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:31:10.291Z,1340350270.291 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T07:31:10.291Z,1340350270.291 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T07:31:10.292Z,1340350270.292 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T07:31:10.292Z,1340350270.292 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T07:31:10.292Z,1340350270.292 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:31:10.659Z,1340350270.659 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1 2012-06-22T07:31:10.659Z,1340350270.659 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1] Stopped 2012-06-22T07:31:10.659Z,1340350270.659 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1 2012-06-22T07:31:10.660Z,1340350270.660 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3 2012-06-22T07:31:10.660Z,1340350270.660 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3] Stopped 2012-06-22T07:31:10.661Z,1340350270.661 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3 2012-06-22T07:31:10.661Z,1340350270.661 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Stopped 2012-06-22T07:31:10.661Z,1340350270.661 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:31:10.661Z,1340350270.661 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Stopped 2012-06-22T07:31:10.661Z,1340350270.661 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive 2012-06-22T07:31:10.661Z,1340350270.661 [triangle_control_speed_onelap:ThreeLegs:TestDrive] Stopped 2012-06-22T07:31:10.661Z,1340350270.661 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive 2012-06-22T07:31:10.665Z,1340350270.665 [triangle_control_speed_onelap:ThreeLegs](INFO): Completed triangle_control_speed_onelap:ThreeLegs 2012-06-22T07:31:10.665Z,1340350270.665 [triangle_control_speed_onelap:ThreeLegs] Stopped 2012-06-22T07:31:10.665Z,1340350270.665 [triangle_control_speed_onelap:ThreeLegs](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs 2012-06-22T07:31:10.665Z,1340350270.665 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Stopped 2012-06-22T07:31:10.665Z,1340350270.665 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:BuoyancyHold 2012-06-22T07:31:10.665Z,1340350270.665 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Stopped 2012-06-22T07:31:10.665Z,1340350270.665 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-06-22T07:31:10.665Z,1340350270.665 [triangle_control_speed_onelap:ThreeLegs:MassHold] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:MassHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:MassHold 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:ElevatorHold 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:D] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:E] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:F] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:G] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:H] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:I] Stopped 2012-06-22T07:31:10.666Z,1340350270.666 [triangle_control_speed_onelap:ThreeLegs:J] Stopped 2012-06-22T07:31:10.666Z,1340350270.667 [triangle_control_speed_onelap:ThreeLegs:K] Stopped 2012-06-22T07:31:10.666Z,1340350270.667 [triangle_control_speed_onelap:ThreeLegs:L] Stopped 2012-06-22T07:31:10.668Z,1340350270.668 [triangle_control_speed_onelap](INFO): Completed triangle_control_speed_onelap 2012-06-22T07:31:10.668Z,1340350270.668 [triangle_control_speed_onelap] Stopped 2012-06-22T07:31:10.668Z,1340350270.668 [triangle_control_speed_onelap](INFO): Aggregate::uninitialize triangle_control_speed_onelap 2012-06-22T07:31:10.668Z,1340350270.668 [triangle_control_speed_onelap:A.AltitudeEnvelope] Stopped 2012-06-22T07:31:10.669Z,1340350270.668 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-06-22T07:31:10.669Z,1340350270.668 [triangle_control_speed_onelap:B.DepthEnvelope] Stopped 2012-06-22T07:31:10.669Z,1340350270.669 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Uninitialize. 2012-06-22T07:31:10.669Z,1340350270.669 [triangle_control_speed_onelap:C.OffshoreEnvelope] Stopped 2012-06-22T07:31:10.669Z,1340350270.669 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-06-22T07:31:10.669Z,1340350270.669 [triangle_control_speed_onelap:D] Stopped 2012-06-22T07:31:11.057Z,1340350271.057 [MissionManager](IMPORTANT): Started mission Default 2012-06-22T07:31:11.057Z,1340350271.057 [Default] Running Loop=1 2012-06-22T07:31:11.057Z,1340350271.057 [Default](INFO): Aggregate::initialize Default 2012-06-22T07:31:11.057Z,1340350271.057 [Default:D.SetSpeed] Running Loop=1 2012-06-22T07:31:11.057Z,1340350271.057 [Default:D.SetSpeed](DEBUG): Initialize. 2012-06-22T07:31:11.057Z,1340350271.057 [Default:E.GoToSurface] Running Loop=1 2012-06-22T07:31:11.057Z,1340350271.057 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:31:11.058Z,1340350271.058 [Default:Iridium] Running Loop=1 2012-06-22T07:31:11.058Z,1340350271.058 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T07:31:11.058Z,1340350271.058 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T07:31:11.058Z,1340350271.058 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:31:11.058Z,1340350271.058 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T07:31:11.058Z,1340350271.058 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:31:11.060Z,1340350271.060 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T07:31:11.060Z,1340350271.060 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:31:11.060Z,1340350271.060 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T07:31:35.821Z,1340350295.821 [NAL9601](INFO): SBD MO Status=1, MOMSN=10655, MT Status=0, MTMSN=0 2012-06-22T07:31:36.023Z,1340350296.023 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T045247/shore0005.lzma 2012-06-22T07:31:36.023Z,1340350296.023 [NAL9601](INFO): Packets left to send: 1 2012-06-22T07:31:36.025Z,1340350296.025 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0005.lzma.parts/0001.sbd 2012-06-22T07:31:47.156Z,1340350307.157 [NAL9601](INFO): SBD MO Status=1, MOMSN=10656, MT Status=0, MTMSN=0 2012-06-22T07:31:47.307Z,1340350307.307 [NAL9601](INFO): Sent 188 bytes from file Logs/20120622T045247/shore0005.lzma 2012-06-22T07:31:47.307Z,1340350307.307 [NAL9601](INFO): Packets left to send: 0 2012-06-22T07:31:47.308Z,1340350307.308 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0005.lzma.parts/0000.sbd 2012-06-22T07:31:54.734Z,1340350314.734 [NAL9601](INFO): SBD MO Status=0, MOMSN=10657, MT Status=0, MTMSN=0 2012-06-22T07:31:54.910Z,1340350314.910 [Default:Iridium:Read_Iridium] Stopped 2012-06-22T07:31:54.911Z,1340350314.911 [Default:Iridium](INFO): Completed Default:Iridium 2012-06-22T07:31:54.911Z,1340350314.911 [Default:Iridium] Stopped 2012-06-22T07:31:54.911Z,1340350314.911 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-06-22T07:31:54.911Z,1340350314.911 [Default:Iridium:A.SetSpeed] Stopped 2012-06-22T07:31:54.911Z,1340350314.911 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:31:54.911Z,1340350314.911 [Default:F.Wait] Running Loop=1 2012-06-22T07:31:54.911Z,1340350314.911 [Default:F.Wait](DEBUG): Initialize Wait Component. 2012-06-22T07:32:05.350Z,1340350325.350 [NAL9601](INFO): Powering down 2012-06-22T07:36:55.419Z,1340350615.420 [Default:CallIridium] Running Loop=1 2012-06-22T07:36:55.420Z,1340350615.420 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-06-22T07:36:55.420Z,1340350615.420 [Default:CallIridium:A] Running Loop=1 2012-06-22T07:36:55.420Z,1340350615.420 [Default:CallIridium:A] Stopped 2012-06-22T07:36:55.420Z,1340350615.420 [Default:CallIridium:B] Running Loop=1 2012-06-22T07:36:55.420Z,1340350615.420 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-06-22T07:37:00.475Z,1340350620.475 [Default:Iridium] Running Loop=1 2012-06-22T07:37:00.475Z,1340350620.475 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T07:37:00.475Z,1340350620.475 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T07:37:00.475Z,1340350620.475 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:37:00.475Z,1340350620.475 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T07:37:00.475Z,1340350620.475 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:37:00.476Z,1340350620.476 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T07:37:00.476Z,1340350620.476 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:37:00.476Z,1340350620.476 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T07:37:00.477Z,1340350620.477 [Default:GPS] Running Loop=1 2012-06-22T07:37:00.477Z,1340350620.477 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-06-22T07:37:00.477Z,1340350620.477 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T07:37:00.477Z,1340350620.477 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:37:00.477Z,1340350620.477 [Default:GPS:B.GoToSurface] Running Loop=1 2012-06-22T07:37:00.477Z,1340350620.477 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:37:00.478Z,1340350620.478 [Default:GPS:B.GoToSurface] Stopped 2012-06-22T07:37:00.478Z,1340350620.478 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:37:00.478Z,1340350620.478 [Default:GPS:Read_GPS] Running Loop=1 2012-06-22T07:37:01.000Z,1340350621.000 [NAL9601](INFO): Powering up 2012-06-22T07:38:06.723Z,1340350686.723 [NAL9601](INFO): NAL9601 initialized 2012-06-22T07:38:22.496Z,1340350702.497 [NAL9601](INFO): SBD MO Status=1, MOMSN=10658, MT Status=0, MTMSN=0 2012-06-22T07:38:22.687Z,1340350702.687 [NAL9601](INFO): Sent 100 bytes from file Logs/20120622T045247/shore0006.lzma 2012-06-22T07:38:22.687Z,1340350702.687 [NAL9601](INFO): Packets left to send: 0 2012-06-22T07:38:22.689Z,1340350702.689 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0006.lzma.parts/0000.sbd 2012-06-22T07:38:31.701Z,1340350711.701 [NAL9601](INFO): SBD MO Status=0, MOMSN=10659, MT Status=0, MTMSN=0 2012-06-22T07:38:31.890Z,1340350711.890 [Default:Iridium:Read_Iridium] Stopped 2012-06-22T07:38:31.891Z,1340350711.891 [Default:Iridium](INFO): Completed Default:Iridium 2012-06-22T07:38:31.891Z,1340350711.891 [Default:Iridium] Stopped 2012-06-22T07:38:31.891Z,1340350711.891 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-06-22T07:38:31.891Z,1340350711.891 [Default:Iridium:A.SetSpeed] Stopped 2012-06-22T07:38:31.891Z,1340350711.891 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:38:32.110Z,1340350712.110 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2012-06-22T07:38:32.110Z,1340350712.110 [Default:CallIridium:B] Stopped 2012-06-22T07:38:32.111Z,1340350712.111 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-06-22T07:38:32.111Z,1340350712.111 [Default:CallIridium](INFO): Completed Default:CallIridium 2012-06-22T07:38:32.111Z,1340350712.111 [Default:CallIridium] Stopped 2012-06-22T07:38:32.111Z,1340350712.111 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-06-22T07:40:40.967Z,1340350840.967 [NAL9601](IMPORTANT): GPS fix at: 1340351056.00 2012-06-22T07:40:40.984Z,1340350840.984 [Default:GPS:Read_GPS] Stopped 2012-06-22T07:40:40.984Z,1340350840.984 [Default:GPS:D] Running Loop=1 2012-06-22T07:40:41.376Z,1340350841.376 [Default:GPS:D] Stopped 2012-06-22T07:40:41.376Z,1340350841.376 [Default:GPS](INFO): Completed Default:GPS 2012-06-22T07:40:41.376Z,1340350841.376 [Default:GPS] Stopped 2012-06-22T07:40:41.377Z,1340350841.376 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-06-22T07:40:41.377Z,1340350841.377 [Default:GPS:A.SetSpeed] Stopped 2012-06-22T07:40:41.377Z,1340350841.377 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:40:57.008Z,1340350857.008 [NAL9601](INFO): Powering down 2012-06-22T07:43:31.947Z,1340351011.947 [Default:CallIridium] Running Loop=1 2012-06-22T07:43:31.948Z,1340351011.948 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-06-22T07:43:31.948Z,1340351011.948 [Default:CallIridium:A] Running Loop=1 2012-06-22T07:43:31.948Z,1340351011.948 [Default:CallIridium:A] Stopped 2012-06-22T07:43:31.948Z,1340351011.948 [Default:CallIridium:B] Running Loop=1 2012-06-22T07:43:31.948Z,1340351011.948 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-06-22T07:43:36.905Z,1340351016.905 [Default:Iridium] Running Loop=1 2012-06-22T07:43:36.905Z,1340351016.905 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T07:43:36.905Z,1340351016.905 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T07:43:36.905Z,1340351016.905 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:43:36.905Z,1340351016.905 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T07:43:36.905Z,1340351016.905 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:43:36.906Z,1340351016.906 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T07:43:36.906Z,1340351016.907 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:43:36.907Z,1340351016.907 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T07:43:36.907Z,1340351016.907 [Default:GPS] Running Loop=1 2012-06-22T07:43:36.907Z,1340351016.907 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-06-22T07:43:36.907Z,1340351016.907 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T07:43:36.907Z,1340351016.907 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:43:36.908Z,1340351016.908 [Default:GPS:B.GoToSurface] Running Loop=1 2012-06-22T07:43:36.908Z,1340351016.908 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:43:36.908Z,1340351016.908 [Default:GPS:B.GoToSurface] Stopped 2012-06-22T07:43:36.908Z,1340351016.908 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:43:36.909Z,1340351016.908 [Default:GPS:Read_GPS] Running Loop=1 2012-06-22T07:43:37.556Z,1340351017.556 [NAL9601](INFO): Powering up 2012-06-22T07:44:43.285Z,1340351083.285 [NAL9601](INFO): NAL9601 initialized 2012-06-22T07:45:03.846Z,1340351103.846 [NAL9601](INFO): SBD MO Status=1, MOMSN=10660, MT Status=0, MTMSN=0 2012-06-22T07:45:04.035Z,1340351104.035 [NAL9601](INFO): Sent 164 bytes from file Logs/20120622T045247/shore0007.lzma 2012-06-22T07:45:04.035Z,1340351104.035 [NAL9601](INFO): Packets left to send: 0 2012-06-22T07:45:04.037Z,1340351104.037 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0007.lzma.parts/0000.sbd 2012-06-22T07:45:12.250Z,1340351112.250 [NAL9601](INFO): SBD MO Status=0, MOMSN=10661, MT Status=0, MTMSN=0 2012-06-22T07:45:12.451Z,1340351112.451 [Default:Iridium:Read_Iridium] Stopped 2012-06-22T07:45:12.451Z,1340351112.451 [Default:Iridium](INFO): Completed Default:Iridium 2012-06-22T07:45:12.451Z,1340351112.451 [Default:Iridium] Stopped 2012-06-22T07:45:12.451Z,1340351112.451 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-06-22T07:45:12.451Z,1340351112.451 [Default:Iridium:A.SetSpeed] Stopped 2012-06-22T07:45:12.451Z,1340351112.451 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:45:12.711Z,1340351112.711 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2012-06-22T07:45:12.711Z,1340351112.711 [Default:CallIridium:B] Stopped 2012-06-22T07:45:12.711Z,1340351112.711 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-06-22T07:45:12.712Z,1340351112.712 [Default:CallIridium](INFO): Completed Default:CallIridium 2012-06-22T07:45:12.712Z,1340351112.712 [Default:CallIridium] Stopped 2012-06-22T07:45:12.712Z,1340351112.712 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-06-22T07:45:38.730Z,1340351138.730 [NAL9601](IMPORTANT): GPS fix at: 1340351354.00 2012-06-22T07:45:38.745Z,1340351138.745 [Default:GPS:Read_GPS] Stopped 2012-06-22T07:45:38.745Z,1340351138.745 [Default:GPS:D] Running Loop=1 2012-06-22T07:45:39.186Z,1340351139.186 [Default:GPS:D] Stopped 2012-06-22T07:45:39.186Z,1340351139.186 [Default:GPS](INFO): Completed Default:GPS 2012-06-22T07:45:39.186Z,1340351139.187 [Default:GPS] Stopped 2012-06-22T07:45:39.187Z,1340351139.187 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-06-22T07:45:39.187Z,1340351139.187 [Default:GPS:A.SetSpeed] Stopped 2012-06-22T07:45:39.187Z,1340351139.187 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:45:54.696Z,1340351154.696 [NAL9601](INFO): Powering down 2012-06-22T07:50:14.719Z,1340351414.719 [Default:CallIridium] Running Loop=1 2012-06-22T07:50:14.719Z,1340351414.719 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-06-22T07:50:14.719Z,1340351414.719 [Default:CallIridium:A] Running Loop=1 2012-06-22T07:50:14.719Z,1340351414.719 [Default:CallIridium:A] Stopped 2012-06-22T07:50:14.719Z,1340351414.719 [Default:CallIridium:B] Running Loop=1 2012-06-22T07:50:14.720Z,1340351414.720 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-06-22T07:50:19.674Z,1340351419.674 [Default:Iridium] Running Loop=1 2012-06-22T07:50:19.674Z,1340351419.674 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T07:50:19.675Z,1340351419.675 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T07:50:19.675Z,1340351419.675 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:50:19.675Z,1340351419.675 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T07:50:19.675Z,1340351419.675 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:50:19.676Z,1340351419.676 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T07:50:19.676Z,1340351419.676 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:50:19.676Z,1340351419.676 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T07:50:19.676Z,1340351419.676 [Default:GPS] Running Loop=1 2012-06-22T07:50:19.676Z,1340351419.676 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-06-22T07:50:19.677Z,1340351419.677 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T07:50:19.677Z,1340351419.677 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:50:19.677Z,1340351419.677 [Default:GPS:B.GoToSurface] Running Loop=1 2012-06-22T07:50:19.677Z,1340351419.677 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:50:19.678Z,1340351419.678 [Default:GPS:B.GoToSurface] Stopped 2012-06-22T07:50:19.678Z,1340351419.678 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:50:19.678Z,1340351419.678 [Default:GPS:Read_GPS] Running Loop=1 2012-06-22T07:50:20.379Z,1340351420.378 [NAL9601](INFO): Powering up 2012-06-22T07:51:26.003Z,1340351486.003 [NAL9601](INFO): NAL9601 initialized 2012-06-22T07:51:40.242Z,1340351500.241 [NAL9601](INFO): SBD MO Status=1, MOMSN=10662, MT Status=0, MTMSN=0 2012-06-22T07:51:40.371Z,1340351500.371 [NAL9601](INFO): Sent 151 bytes from file Logs/20120622T045247/shore0008.lzma 2012-06-22T07:51:40.371Z,1340351500.371 [NAL9601](INFO): Packets left to send: 0 2012-06-22T07:51:40.373Z,1340351500.373 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0008.lzma.parts/0000.sbd 2012-06-22T07:51:49.857Z,1340351509.857 [NAL9601](INFO): SBD MO Status=0, MOMSN=10663, MT Status=0, MTMSN=0 2012-06-22T07:51:50.069Z,1340351510.069 [Default:Iridium:Read_Iridium] Stopped 2012-06-22T07:51:50.070Z,1340351510.070 [Default:Iridium](INFO): Completed Default:Iridium 2012-06-22T07:51:50.070Z,1340351510.070 [Default:Iridium] Stopped 2012-06-22T07:51:50.070Z,1340351510.070 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-06-22T07:51:50.070Z,1340351510.070 [Default:Iridium:A.SetSpeed] Stopped 2012-06-22T07:51:50.070Z,1340351510.070 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:51:50.264Z,1340351510.264 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2012-06-22T07:51:50.264Z,1340351510.264 [Default:CallIridium:B] Stopped 2012-06-22T07:51:50.264Z,1340351510.264 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-06-22T07:51:50.264Z,1340351510.264 [Default:CallIridium](INFO): Completed Default:CallIridium 2012-06-22T07:51:50.264Z,1340351510.264 [Default:CallIridium] Stopped 2012-06-22T07:51:50.264Z,1340351510.264 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-06-22T07:51:51.073Z,1340351511.073 [NAL9601](IMPORTANT): GPS fix at: 1340351727.00 2012-06-22T07:51:51.089Z,1340351511.089 [Default:GPS:Read_GPS] Stopped 2012-06-22T07:51:51.089Z,1340351511.089 [Default:GPS:D] Running Loop=1 2012-06-22T07:51:51.463Z,1340351511.463 [Default:GPS:D] Stopped 2012-06-22T07:51:51.464Z,1340351511.464 [Default:GPS](INFO): Completed Default:GPS 2012-06-22T07:51:51.464Z,1340351511.464 [Default:GPS] Stopped 2012-06-22T07:51:51.464Z,1340351511.464 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-06-22T07:51:51.464Z,1340351511.464 [Default:GPS:A.SetSpeed] Stopped 2012-06-22T07:51:51.464Z,1340351511.464 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:52:06.967Z,1340351526.967 [NAL9601](INFO): Powering down 2012-06-22T07:56:52.022Z,1340351812.022 [Default:CallIridium] Running Loop=1 2012-06-22T07:56:52.022Z,1340351812.022 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-06-22T07:56:52.022Z,1340351812.022 [Default:CallIridium:A] Running Loop=1 2012-06-22T07:56:52.023Z,1340351812.023 [Default:CallIridium:A] Stopped 2012-06-22T07:56:52.023Z,1340351812.023 [Default:CallIridium:B] Running Loop=1 2012-06-22T07:56:52.023Z,1340351812.023 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-06-22T07:56:57.004Z,1340351817.004 [Default:Iridium] Running Loop=1 2012-06-22T07:56:57.004Z,1340351817.004 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T07:56:57.004Z,1340351817.004 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T07:56:57.004Z,1340351817.004 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:56:57.004Z,1340351817.004 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T07:56:57.004Z,1340351817.004 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:56:57.005Z,1340351817.005 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T07:56:57.005Z,1340351817.005 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:56:57.005Z,1340351817.005 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T07:56:57.006Z,1340351817.006 [Default:GPS] Running Loop=1 2012-06-22T07:56:57.006Z,1340351817.006 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-06-22T07:56:57.006Z,1340351817.006 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T07:56:57.006Z,1340351817.006 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-06-22T07:56:57.006Z,1340351817.006 [Default:GPS:B.GoToSurface] Running Loop=1 2012-06-22T07:56:57.006Z,1340351817.006 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T07:56:57.007Z,1340351817.007 [Default:GPS:B.GoToSurface] Stopped 2012-06-22T07:56:57.007Z,1340351817.007 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T07:56:57.007Z,1340351817.007 [Default:GPS:Read_GPS] Running Loop=1 2012-06-22T07:56:57.712Z,1340351817.712 [NAL9601](INFO): Powering up 2012-06-22T07:56:58.878Z,1340351818.878 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#4 STATUS: 65535 2012-06-22T07:58:03.335Z,1340351883.335 [NAL9601](INFO): NAL9601 initialized 2012-06-22T07:58:04.439Z,1340351884.439 [NAL9601](IMPORTANT): GPS fix at: 1340352101.00 2012-06-22T07:58:04.464Z,1340351884.464 [Default:GPS:Read_GPS] Stopped 2012-06-22T07:58:04.464Z,1340351884.464 [Default:GPS:D] Running Loop=1 2012-06-22T07:58:04.908Z,1340351884.908 [Default:GPS:D] Stopped 2012-06-22T07:58:04.908Z,1340351884.908 [Default:GPS](INFO): Completed Default:GPS 2012-06-22T07:58:04.908Z,1340351884.908 [Default:GPS] Stopped 2012-06-22T07:58:04.908Z,1340351884.908 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-06-22T07:58:04.909Z,1340351884.908 [Default:GPS:A.SetSpeed] Stopped 2012-06-22T07:58:04.909Z,1340351884.908 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:58:27.269Z,1340351907.269 [NAL9601](INFO): SBD MO Status=1, MOMSN=10664, MT Status=0, MTMSN=0 2012-06-22T07:58:27.387Z,1340351907.387 [NAL9601](INFO): Sent 187 bytes from file Logs/20120622T045247/shore0009.lzma 2012-06-22T07:58:27.387Z,1340351907.387 [NAL9601](INFO): Packets left to send: 0 2012-06-22T07:58:27.389Z,1340351907.389 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0009.lzma.parts/0000.sbd 2012-06-22T07:58:36.874Z,1340351916.874 [NAL9601](INFO): SBD MO Status=0, MOMSN=10665, MT Status=0, MTMSN=0 2012-06-22T07:58:37.090Z,1340351917.090 [Default:Iridium:Read_Iridium] Stopped 2012-06-22T07:58:37.091Z,1340351917.091 [Default:Iridium](INFO): Completed Default:Iridium 2012-06-22T07:58:37.091Z,1340351917.091 [Default:Iridium] Stopped 2012-06-22T07:58:37.091Z,1340351917.091 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-06-22T07:58:37.091Z,1340351917.091 [Default:Iridium:A.SetSpeed] Stopped 2012-06-22T07:58:37.091Z,1340351917.091 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T07:58:37.286Z,1340351917.286 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2012-06-22T07:58:37.286Z,1340351917.286 [Default:CallIridium:B] Stopped 2012-06-22T07:58:37.286Z,1340351917.286 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-06-22T07:58:37.286Z,1340351917.286 [Default:CallIridium](INFO): Completed Default:CallIridium 2012-06-22T07:58:37.287Z,1340351917.286 [Default:CallIridium] Stopped 2012-06-22T07:58:37.287Z,1340351917.287 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-06-22T07:58:47.464Z,1340351927.464 [NAL9601](INFO): Powering down 2012-06-22T08:03:37.419Z,1340352217.419 [Default:CallIridium] Running Loop=1 2012-06-22T08:03:37.419Z,1340352217.419 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-06-22T08:03:37.419Z,1340352217.420 [Default:CallIridium:A] Running Loop=1 2012-06-22T08:03:37.420Z,1340352217.420 [Default:CallIridium:A] Stopped 2012-06-22T08:03:37.420Z,1340352217.420 [Default:CallIridium:B] Running Loop=1 2012-06-22T08:03:37.420Z,1340352217.420 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-06-22T08:03:42.456Z,1340352222.456 [Default:Iridium] Running Loop=1 2012-06-22T08:03:42.456Z,1340352222.456 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T08:03:42.456Z,1340352222.456 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T08:03:42.456Z,1340352222.456 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T08:03:42.456Z,1340352222.456 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T08:03:42.456Z,1340352222.456 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T08:03:42.457Z,1340352222.457 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T08:03:42.457Z,1340352222.457 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T08:03:42.457Z,1340352222.457 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T08:03:42.458Z,1340352222.458 [Default:GPS] Running Loop=1 2012-06-22T08:03:42.458Z,1340352222.458 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-06-22T08:03:42.458Z,1340352222.458 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T08:03:42.458Z,1340352222.458 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-06-22T08:03:42.458Z,1340352222.458 [Default:GPS:B.GoToSurface] Running Loop=1 2012-06-22T08:03:42.458Z,1340352222.458 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T08:03:42.459Z,1340352222.459 [Default:GPS:B.GoToSurface] Stopped 2012-06-22T08:03:42.459Z,1340352222.459 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T08:03:42.459Z,1340352222.459 [Default:GPS:Read_GPS] Running Loop=1 2012-06-22T08:03:43.068Z,1340352223.068 [NAL9601](INFO): Powering up 2012-06-22T08:04:48.791Z,1340352288.791 [NAL9601](INFO): NAL9601 initialized 2012-06-22T08:04:49.883Z,1340352289.884 [NAL9601](IMPORTANT): GPS fix at: 1340352507.00 2012-06-22T08:04:49.899Z,1340352289.899 [Default:GPS:Read_GPS] Stopped 2012-06-22T08:04:49.900Z,1340352289.900 [Default:GPS:D] Running Loop=1 2012-06-22T08:04:50.314Z,1340352290.314 [Default:GPS:D] Stopped 2012-06-22T08:04:50.315Z,1340352290.315 [Default:GPS](INFO): Completed Default:GPS 2012-06-22T08:04:50.315Z,1340352290.315 [Default:GPS] Stopped 2012-06-22T08:04:50.315Z,1340352290.315 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-06-22T08:04:50.315Z,1340352290.315 [Default:GPS:A.SetSpeed] Stopped 2012-06-22T08:04:50.315Z,1340352290.315 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T08:05:05.366Z,1340352305.366 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10666, MT Status=1, MTMSN=2782 2012-06-22T08:05:05.555Z,1340352305.555 [NAL9601](INFO): Sent 160 bytes from file Logs/20120622T045247/shore0010.lzma 2012-06-22T08:05:05.555Z,1340352305.555 [NAL9601](INFO): Packets left to send: 0 2012-06-22T08:05:05.557Z,1340352305.557 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0010.lzma.parts/0000.sbd 2012-06-22T08:05:05.967Z,1340352305.967 [NAL9601](INFO): Received command:restart logs 2012-06-22T08:05:05.983Z,1340352305.983 [CommandLine](IMPORTANT): got command restart logs