Any patches or updates
dmd1214
Posts: 20
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:
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()
Comments
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.