Any patches or updates

dmd1214

Hi Folks,

I just started performance profiling my app for the first time with 5.1. I have been using memory profiler extensively. This might be newbe type stuff, but I'm encountering what seem to be a lot of random bugs. For example:

1. the ants hangs when changing the time bar
2. I got a blue screen of death at one point
3. some method times report negitive miliseconds (some in the thousands)
4. In the code view not all lines of code have metrics (might be a compile issue on my side?)
5. after I save results ants still warns me that results are not saved before exiting
6. etc.

I have noticed some out of memory exceptions being logged to the perf profiler log folder

So I was wondering if you all had any patch releases ready or if you had any coming.


Here's a example of one of the performance profiler logs:

25 Sep 2009 11:36:13.135 [1] INFO  RedGate.Profiler.Logging.LogService - Starting new ANTS Performance Profiler 5 session
25 Sep 2009 11:36:13.135 [1] INFO  RedGate.Profiler.Logging.LogService - Assembly version: 5.1.0.14
25 Sep 2009 11:36:34.149 [1] WARN  RedGate.Profiler.OS.Local.LocalWindowsVersionInspector - Detected Windows version: Windows XP Professional (5.1 32-bit)
25 Sep 2009 11:36:34.149 [1] WARN  RedGate.Profiler.IISSupport.Local.LocalIisInspector - IIS is supported on installed OS version
25 Sep 2009 11:36:34.430 [1] WARN  RedGate.Profiler.IISSupport.Local.LocalIisInspector - Result of check to see if inetinfo.exe running: True
25 Sep 2009 11:36:34.430 [1] WARN  RedGate.Profiler.IISSupport.Local.LocalIisInspector - Checks confirm that IIS is running.
25 Sep 2009 11:36:34.430 [1] WARN  RedGate.Profiler.IISSupport.Local.LocalIisInspector - Checks confirm that IIS is enabled.
25 Sep 2009 11:36:34.446 [1] WARN  RedGate.Profiler.IISSupport.Local.LocalIisInspector - Successfully detected IIS installation: IIS 5.1 (Auto-detected)
25 Sep 2009 11:36:34.446 [1] WARN  RedGate.Profiler.IISSupport.Local.LocalIisInspector - Checks confirm that IIS is installed.
25 Sep 2009 11:38:11.847 [25] WARN  RedGate.Profiler.Engine.Reports.Basic.ProfileRange - Range has a tick rate of 1235894.77932577KHz with a standard deviation of 122511.643363942KHz. The deviance in clock speed seems high and may be causing a synchronisation issue.
25 Sep 2009 11:38:42.752 [ConfigurableThreadPool thread] WARN  RedGate.Profiler.Engine.Reports.Basic.ProfileRange - Range has a tick rate of 1235894.77932577KHz with a standard deviation of 122511.643363946KHz. The deviance in clock speed seems high and may be causing a synchronisation issue.
25 Sep 2009 11:39:36.421 [ConfigurableThreadPool thread] WARN  RedGate.Profiler.Engine.Reports.Basic.ProfileRange - Range has a tick rate of 1235894.77932577KHz with a standard deviation of 122511.643363946KHz. The deviance in clock speed seems high and may be causing a synchronisation issue.
25 Sep 2009 12:16:01.795 [1] WARN  RedGate.Profiler.Engine.Startup.IIS.IIS5Utilities - Using default .NET 2.0 credentials
25 Sep 2009 12:16:01.966 [ConfigurableThreadPool thread] WARN  RedGate.Profiler.Engine.Startup.Sessions.IISSession - Couldn't find w3wp.exe in C:\WINDOWS\system32\inetsrv
25 Sep 2009 12:16:47.120 [Profiler pipe monitor] WARN  RedGate.Profiler.Engine.Startup.Sessions.IISSession - The local app data path for IIS is C:\Documents and Settings\HNRDXPLTL3W8898\ASPNET\Local Settings\Application Data, so ANTS will use C:\Documents and Settings\HNRDXPLTL3W8898\ASPNET\Local Settings\Application Data\Red Gate\ANTS Profiler to store results
25 Sep 2009 12:20:41.828 [Profiler pipe monitor] WARN  RedGate.Profiler.Engine.Startup.Basic.PipeConnection - Pipe has been closed; most likely the process being profiled has terminated.
25 Sep 2009 12:20:42.187 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - Process 6088 is apparently not running: Process with an Id of 6088 is not running.
25 Sep 2009 12:20:42.187 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - Process with an Id of 6088 is not running.
25 Sep 2009 12:20:42.187 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - System.ArgumentException
25 Sep 2009 12:20:42.765 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory -    at System.Diagnostics.Process.GetProcessById(Int32 processId, String machineName)
   at System.Diagnostics.Process.GetProcessById(Int32 processId)
   at RedGate.Profiler.Monitoring.PerformanceCounterFactory.GetInstanceNameForProcessID(Int32 processID)
25 Sep 2009 12:20:42.781 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - Process 6088 is apparently not running: Process with an Id of 6088 is not running.
25 Sep 2009 12:20:42.781 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - Process with an Id of 6088 is not running.
25 Sep 2009 12:20:42.781 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - System.ArgumentException
25 Sep 2009 12:20:42.781 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory -    at System.Diagnostics.Process.GetProcessById(Int32 processId, String machineName)
   at System.Diagnostics.Process.GetProcessById(Int32 processId)
   at RedGate.Profiler.Monitoring.PerformanceCounterFactory.GetInstanceNameForProcessID(Int32 processID)
25 Sep 2009 12:20:42.797 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - Process 6088 is apparently not running: Process with an Id of 6088 is not running.
25 Sep 2009 12:20:42.797 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - Process with an Id of 6088 is not running.
25 Sep 2009 12:20:42.797 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory - System.ArgumentException
25 Sep 2009 12:20:42.797 [25] WARN  RedGate.Profiler.Monitoring.PerformanceCounterFactory -    at System.Diagnostics.Process.GetProcessById(Int32 processId, String machineName)
   at System.Diagnostics.Process.GetProcessById(Int32 processId)
   at RedGate.Profiler.Monitoring.PerformanceCounterFactory.GetInstanceNameForProcessID(Int32 processID)
25 Sep 2009 12:21:21.170 [Profiler pipe monitor] WARN  RedGate.Profiler.Engine.Startup.Sessions.IISSession - The local app data path for IIS is C:\Documents and Settings\HNRDXPLTL3W8898\ASPNET\Local Settings\Application Data, so ANTS will use C:\Documents and Settings\HNRDXPLTL3W8898\ASPNET\Local Settings\Application Data\Red Gate\ANTS Profiler to store results
25 Sep 2009 12:21:21.779 [Profiler pipe monitor] WARN  RedGate.Profiler.Engine.Startup.Basic.PipeConnection - Pipe has been closed; most likely the process being profiled has terminated.
25 Sep 2009 12:25:22.956 [Profiler pipe monitor] WARN  RedGate.Profiler.Engine.Startup.Sessions.IISSession - The local app data path for IIS is C:\Documents and Settings\HNRDXPLTL3W8898\ASPNET\Local Settings\Application Data, so ANTS will use C:\Documents and Settings\HNRDXPLTL3W8898\ASPNET\Local Settings\Application Data\Red Gate\ANTS Profiler to store results
25 Sep 2009 12:25:23.409 [Profiler pipe monitor] WARN  RedGate.Profiler.Engine.Startup.Basic.PipeConnection - Pipe has been closed; most likely the process being profiled has terminated.
25 Sep 2009 12:31:39.247 [25] WARN  RedGate.Profiler.Monitoring.PerformanceDataAdapter - Invalid operation exception occurred whilst trying to retrieve counter sample: Instance 'aspnet_wp' does not exist in the specified Category.
System.InvalidOperationException: Instance 'aspnet_wp' does not exist in the specified Category.
   at System.Diagnostics.CounterDefinitionSample.GetInstanceValue(String instanceName)
   at System.Diagnostics.PerformanceCounter.NextSample()
   at RedGate.Profiler.Monitoring.PerformanceDataAdapter.a(Object , UpdateTimerElapsedEventArgs )
25 Sep 2009 12:31:39.262 [25] WARN  RedGate.Profiler.Monitoring.PerformanceDataAdapter - Invalid operation exception occurred whilst trying to retrieve counter sample: Instance 'aspnet_wp' does not exist in the specified Category.
System.InvalidOperationException: Instance 'aspnet_wp' does not exist in the specified Category.
   at System.Diagnostics.CounterDefinitionSample.GetInstanceValue(String instanceName)
   at System.Diagnostics.PerformanceCounter.NextSample()
   at RedGate.Profiler.Monitoring.PerformanceDataAdapter.a(Object , UpdateTimerElapsedEventArgs )
25 Sep 2009 12:31:39.262 [25] WARN  RedGate.Profiler.Monitoring.PerformanceDataAdapter - Invalid operation exception occurred whilst trying to retrieve counter sample: Instance 'aspnet_wp' does not exist in the specified Category.
System.InvalidOperationException: Instance 'aspnet_wp' does not exist in the specified Category.
   at System.Diagnostics.CounterDefinitionSample.GetInstanceValue(String instanceName)
   at System.Diagnostics.PerformanceCounter.NextSample()
   at RedGate.Profiler.Monitoring.PerformanceDataAdapter.a(Object , UpdateTimerElapsedEventArgs )
25 Sep 2009 12:31:41.309 [1] ERROR RedGate.Profiler.UI.Ignition - Block does not have a valid header.
25 Sep 2009 12:31:41.309 [1] ERROR RedGate.Profiler.UI.Ignition - RedGate.Profiler.Engine.Exceptions.BadLogFileException
25 Sep 2009 12:31:41.528 [1] ERROR RedGate.Profiler.UI.Ignition -    at am..ctor(ak log, Int32 partNum, Int32 offset)
   at am.get_NextBlock()
   at aF.RefreshLightweight()
   at aj.c(Graphics )
   at aj.OnPaint(PaintEventArgs e, Boolean isRadarPaint)
   at aj.OnPaint(PaintEventArgs e)
   at System.Windows.Forms.Control.PaintWithErrorHandling(PaintEventArgs e, Int16 layer, Boolean disposeEventArgs)
   at System.Windows.Forms.Control.WmPaint(Message& m)
   at System.Windows.Forms.Control.WndProc(Message& m)
   at System.Windows.Forms.ScrollableControl.WndProc(Message& m)
   at System.Windows.Forms.ContainerControl.WndProc(Message& m)
   at System.Windows.Forms.UserControl.WndProc(Message& m)
   at System.Windows.Forms.Control.ControlNativeWindow.OnMessage(Message& m)
   at System.Windows.Forms.Control.ControlNativeWindow.WndProc(Message& m)
   at System.Windows.Forms.NativeWindow.Callback(IntPtr hWnd, Int32 msg, IntPtr wparam, IntPtr lparam)
25 Sep 2009 12:31:49.106 [1] ERROR RedGate.Profiler.UI.Ignition - Error stopping SMTPSVC
25 Sep 2009 12:31:49.106 [1] ERROR RedGate.Profiler.UI.Ignition - RedGate.Profiler.Engine.Startup.IIS.IISException
25 Sep 2009 12:31:49.231 [1] ERROR RedGate.Profiler.UI.Ignition - 
Server stack trace: 
   at bo.b()
   at bo.StopProfilingIIS()
   at RedGate.Profiler.Engine.Startup.IIS.IISStarter`1.StopProfilingIIS()
   at System.Runtime.Remoting.Messaging.StackBuilderSink._PrivateProcessMessage(IntPtr md, Object[] args, Object server, Int32 methodPtr, Boolean fExecuteInContext, Object[]& outArgs)
   at System.Runtime.Remoting.Messaging.StackBuilderSink.PrivateProcessMessage(RuntimeMethodHandle md, Object[] args, Object server, Int32 methodPtr, Boolean fExecuteInContext, Object[]& outArgs)
   at System.Runtime.Remoting.Messaging.StackBuilderSink.SyncProcessMessage(IMessage msg, Int32 methodPtr, Boolean fExecuteInContext)

Exception rethrown at [0]: 
   at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessage(IMessage reqMsg, IMessage retMsg)
   at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(MessageData& msgData, Int32 type)
   at RedGate.Profiler.Engine.Startup.IIISActuator`1.StopProfilingIIS()
   at bQ.Stop()
   at RedGate.Profiler.Session.ProfilerSession.Stop()
   at aq.h()
   at aq.OnFormClosing(FormClosingEventArgs e)
   at System.Windows.Forms.Form.WmClose(Message& m)
   at System.Windows.Forms.Form.WndProc(Message& m)
   at System.Windows.Forms.Control.ControlNativeWindow.OnMessage(Message& m)
   at System.Windows.Forms.Control.ControlNativeWindow.WndProc(Message& m)
   at System.Windows.Forms.NativeWindow.Callback(IntPtr hWnd, Int32 msg, IntPtr wparam, IntPtr lparam)
25 Sep 2009 12:31:49.231 [1] ERROR RedGate.Profiler.UI.Ignition - Caused by:
25 Sep 2009 12:31:49.231 [1] ERROR RedGate.Profiler.UI.Ignition - Couldn't stop the 'SMTPSVC' service (1052)
25 Sep 2009 12:31:49.231 [1] ERROR RedGate.Profiler.UI.Ignition - RedGate.Profiler.Engine.Startup.IIS.ServiceUtilitiesException
25 Sep 2009 12:31:49.231 [1] ERROR RedGate.Profiler.UI.Ignition -    at bA.b(String )
   at bo.b()

Chris.Allen

Hi there - sounds like you are having a lot of known issues at once.

The negative times are a conseqeunce of very modern instruction pipelining and the effect it has on the algorithm we use to compensate for it. You can get more "real-world" times by de-selecting "Adjust timings to compensate for overhead added by the profiler". I recommend using the "fastest, most accurate" mode of profiling at the same time.

>>>4. In the code view not all lines of code have metrics (might be a compile issue on my side?)

Ensure that the pdb files from the debug build are synchronized with your source cdoe. If some lines are still not getting any timings- this is probably because they are not being run (at least as far as the "selected dataset" goes).

>>>5. after I save results ants still warns me that results are not saved before exiting

Is this reproducible - if so, can you write out the exact wording of the error message pelase (I'll check the source using that literal).

We haven't had a BSOD for over a year so, can I ask, have you had any hardware changes recently as, as far as trouble-shooting goes, that would be my first line of enquiry.