Exception while taking memory snapshot in ANTS Profiler 2.0
Alexander
Posts: 6
Hello, I am getting an exception on clicking 'snapshot' button, while profiling memory of ASP.NET application:
Couldn't read from data pipe (1450)
Server stack trace:
at [].[](IntPtr, Int32)
at [].[]()
at [].[]()
at [].[]()
at RedGate.Profiler.Transfer.PipeServer.GetRawState()
at RedGate.Profiler.Engine.ProfilingSession.[]()
....
Exception rethrown at [0]:
at System.Runtime.Remoting.Proxies.RealProxy.EndInvokeHelper(Message reqMsg, Boolean bProxyCase)
...
Here's data from profilerlog1416-2248.txt :
10:32:29 ANTS Profiler v2, Release log for native core2 created
10:32:29 CCoreProfilerCallback constructor
10:32:29 ProfilerBase::StartPipeClient
10:32:29 PipeClient::StartPipes
10:32:29 PipeClient::SendProcessInfo
10:32:29 Process C:\WINNT\Microsoft.NET\Framework\v1.1.4322\aspnet_wp.exe
10:32:29 Runtime version v1.1.4322
10:32:29 Transfer has validated the process info. We will profile this process
10:32:29 PhysMemInUse: 391 (Mb), VirtMemInUse: 31 (Mb), PhysMemAvail: 120 (Mb) - Memory profiler initialized
10:32:29
10:32:29 => ::THREAD.AddThread:: CLR Id: 1390488, OS Id: 2248
10:32:29 => ::THREAD.AddThread:: CLR Id: 1378080, OS Id: 1240
10:32:29
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 117 (Mb) - Start Garbage Collection Cycle
10:32:29 StartGarbageCollection (start)
10:32:29 after AddAllocatedObjects
10:32:29 after snapshot check
10:32:29 StartGarbageCollection (end)
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 117 (Mb) - EndGarbageCollection (start)
10:32:29 Objects: 4260
10:32:29 Classes: 696
10:32:29 Functions: 0
10:32:29 FunctionFlows: 0
10:32:29 CallStacks: 976
10:32:29 Strings: 699
10:32:29 AllocatedObjects: 0
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 117 (Mb) - EndGarbageCollection (end)
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 117 (Mb) - End Garbage Collection Cycle
10:32:29
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 116 (Mb) - Start Garbage Collection Cycle
10:32:29 StartGarbageCollection (start)
10:32:29 after AddAllocatedObjects
10:32:29 after snapshot check
10:32:29 StartGarbageCollection (end)
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 116 (Mb) - EndGarbageCollection (start)
10:32:29 Objects: 8522
10:32:29 Classes: 696
10:32:29 Functions: 0
10:32:29 FunctionFlows: 0
10:32:29 CallStacks: 976
10:32:29 Strings: 699
10:32:29 AllocatedObjects: 0
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 116 (Mb) - EndGarbageCollection (end)
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 116 (Mb) - End Garbage Collection Cycle
10:32:30 => ::THREAD.AddThread:: CLR Id: 1870160, OS Id: 1388
10:32:31
10:32:31 PhysMemInUse: 410 (Mb), VirtMemInUse: 107 (Mb), PhysMemAvail: 100 (Mb) - Start Garbage Collection Cycle
10:32:31 StartGarbageCollection (start)
10:32:31 after AddAllocatedObjects
10:32:31 after snapshot check
10:32:31 StartGarbageCollection (end)
10:32:31 MoveObjectInfos (start)
10:32:31 MoveObjectInfos (end)
10:32:31 PhysMemInUse: 413 (Mb), VirtMemInUse: 115 (Mb), PhysMemAvail: 98 (Mb) - EndGarbageCollection (start)
10:32:31 Objects: 46237
10:32:31 Classes: 1826
10:32:31 Functions: 0
10:32:31 FunctionFlows: 0
10:32:31 CallStacks: 10078
10:32:31 Strings: 1852
10:32:31 AllocatedObjects: 0
10:32:31 PhysMemInUse: 413 (Mb), VirtMemInUse: 115 (Mb), PhysMemAvail: 98 (Mb) - EndGarbageCollection (end)
10:32:31 PhysMemInUse: 413 (Mb), VirtMemInUse: 115 (Mb), PhysMemAvail: 98 (Mb) - End Garbage Collection Cycle
10:32:46
10:32:46 PhysMemInUse: 425 (Mb), VirtMemInUse: 122 (Mb), PhysMemAvail: 85 (Mb) - Start Garbage Collection Cycle
10:32:46 StartGarbageCollection (start)
10:32:46 after AddAllocatedObjects
10:32:46 after snapshot check
10:32:46 StartGarbageCollection (end)
10:32:46 MoveObjectInfos (start)
10:32:46 MoveObjectInfos (end)
10:32:46 PhysMemInUse: 434 (Mb), VirtMemInUse: 138 (Mb), PhysMemAvail: 77 (Mb) - EndGarbageCollection (start)
10:32:46 Objects: 184341
10:32:46 Classes: 2205
10:32:46 Functions: 0
10:32:46 FunctionFlows: 0
10:32:46 CallStacks: 15022
10:32:46 Strings: 2243
10:32:46 AllocatedObjects: 0
10:32:46 PhysMemInUse: 434 (Mb), VirtMemInUse: 138 (Mb), PhysMemAvail: 77 (Mb) - EndGarbageCollection (end)
10:32:46 PhysMemInUse: 434 (Mb), VirtMemInUse: 138 (Mb), PhysMemAvail: 77 (Mb) - End Garbage Collection Cycle
10:32:51 => ::THREAD.AddThread:: CLR Id: 66866408, OS Id: 1284
10:32:52
10:32:52 PhysMemInUse: 466 (Mb), VirtMemInUse: 186 (Mb), PhysMemAvail: 44 (Mb) - Start Garbage Collection Cycle
10:32:52 StartGarbageCollection (start)
10:32:53 after AddAllocatedObjects
10:32:53 after snapshot check
10:32:53 StartGarbageCollection (end)
10:32:53 MoveObjectInfos (start)
10:32:53 MoveObjectInfos (end)
10:32:53 PhysMemInUse: 477 (Mb), VirtMemInUse: 186 (Mb), PhysMemAvail: 33 (Mb) - EndGarbageCollection (start)
10:32:53 Objects: 326722
10:32:53 Classes: 2912
10:32:53 Functions: 0
10:32:53 FunctionFlows: 0
10:32:53 CallStacks: 30552
10:32:53 Strings: 2976
10:32:53 AllocatedObjects: 0
10:32:53 PhysMemInUse: 477 (Mb), VirtMemInUse: 186 (Mb), PhysMemAvail: 33 (Mb) - EndGarbageCollection (end)
10:32:53 PhysMemInUse: 477 (Mb), VirtMemInUse: 186 (Mb), PhysMemAvail: 33 (Mb) - End Garbage Collection Cycle
10:33:01 => ::PIPE CollectHeapState (start)
10:33:01
10:33:01 PhysMemInUse: 480 (Mb), VirtMemInUse: 194 (Mb), PhysMemAvail: 30 (Mb) - Start Garbage Collection Cycle
10:33:01 StartGarbageCollection (start)
10:33:01 after AddAllocatedObjects
10:33:01 after snapshot check
10:33:01 StartGarbageCollection (end)
10:33:01 MoveObjectInfos (start)
10:33:01 MoveObjectInfos (end)
10:33:01 PhysMemInUse: 488 (Mb), VirtMemInUse: 195 (Mb), PhysMemAvail: 22 (Mb) - EndGarbageCollection (start)
10:33:01 Objects: 414984
10:33:01 Classes: 3242
10:33:01 Functions: 0
10:33:01 FunctionFlows: 0
10:33:01 CallStacks: 36221
10:33:01 Strings: 3306
10:33:01 AllocatedObjects: 0
10:33:01 before RemoveNonAliveObjectInfos
10:33:01 before CheckObjectsOverflow
10:33:01 PhysMemInUse: 489 (Mb), VirtMemInUse: 195 (Mb), PhysMemAvail: 21 (Mb) - Taking Snapshot... (start)
10:33:01 PhysMemInUse: 489 (Mb), VirtMemInUse: 195 (Mb), PhysMemAvail: 21 (Mb) - before AddParentReferences
10:33:01 PhysMemInUse: 490 (Mb), VirtMemInUse: 195 (Mb), PhysMemAvail: 21 (Mb) - before AddAllFunctionDetails
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before AddAliveStatistics
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before CalculateFunctionFlowInfos
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before CalculateImportantObjects
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before CalculateImportantFunctions
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before CalculateObjectsIndexes12
10:33:06 PhysMemInUse: 494 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 17 (Mb) - before CalculateImportantCallStacks
10:33:06 PhysMemInUse: 494 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 17 (Mb) - before HeapStateToBuffer
10:33:07 PhysMemInUse: 497 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 13 (Mb) - after HeapStateToBuffer
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - after Reset
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - after MakeAllCreatedBeforeLastSnapshotTrue
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - Taking Snapshot... (end)
10:33:07 before parents remove
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - EndGarbageCollection (end)
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - End Garbage Collection Cycle
10:33:07 => ::THREAD.AddThread:: CLR Id: 81435912, OS Id: 2556
10:33:07 => ::THREAD.AddThread:: CLR Id: 180891944, OS Id: 2188
10:33:07 => ::PIPE CollectHeapState (end)
10:37:18 PIPE: Handles closed
10:37:20 => ::THREAD.AddThread:: CLR Id: 180946888, OS Id: 2316
10:37:21 => ::THREAD.RemoveThread:: CLR Id: 180946888, OS Id: 1440
10:37:21 => ::THREAD.AddThread:: CLR Id: 180948136, OS Id: 1440
10:37:21
10:37:21 PhysMemInUse: 485 (Mb), VirtMemInUse: 198 (Mb), PhysMemAvail: 26 (Mb) - Start Garbage Collection Cycle
10:37:21 StartGarbageCollection (start)
10:37:21 after AddAllocatedObjects
10:37:21 after snapshot check
10:37:21 StartGarbageCollection (end)
10:37:21 CCoreProfilerCallback::Shutdown (Memory start)
10:37:21 CCoreProfilerCallback::Shutdown (Memory end)
10:37:21 CCoreProfilerCallback::Shutdown
Couldn't read from data pipe (1450)
Server stack trace:
at [].[](IntPtr, Int32)
at [].[]()
at [].[]()
at [].[]()
at RedGate.Profiler.Transfer.PipeServer.GetRawState()
at RedGate.Profiler.Engine.ProfilingSession.[]()
....
Exception rethrown at [0]:
at System.Runtime.Remoting.Proxies.RealProxy.EndInvokeHelper(Message reqMsg, Boolean bProxyCase)
...
Here's data from profilerlog1416-2248.txt :
10:32:29 ANTS Profiler v2, Release log for native core2 created
10:32:29 CCoreProfilerCallback constructor
10:32:29 ProfilerBase::StartPipeClient
10:32:29 PipeClient::StartPipes
10:32:29 PipeClient::SendProcessInfo
10:32:29 Process C:\WINNT\Microsoft.NET\Framework\v1.1.4322\aspnet_wp.exe
10:32:29 Runtime version v1.1.4322
10:32:29 Transfer has validated the process info. We will profile this process
10:32:29 PhysMemInUse: 391 (Mb), VirtMemInUse: 31 (Mb), PhysMemAvail: 120 (Mb) - Memory profiler initialized
10:32:29
10:32:29 => ::THREAD.AddThread:: CLR Id: 1390488, OS Id: 2248
10:32:29 => ::THREAD.AddThread:: CLR Id: 1378080, OS Id: 1240
10:32:29
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 117 (Mb) - Start Garbage Collection Cycle
10:32:29 StartGarbageCollection (start)
10:32:29 after AddAllocatedObjects
10:32:29 after snapshot check
10:32:29 StartGarbageCollection (end)
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 117 (Mb) - EndGarbageCollection (start)
10:32:29 Objects: 4260
10:32:29 Classes: 696
10:32:29 Functions: 0
10:32:29 FunctionFlows: 0
10:32:29 CallStacks: 976
10:32:29 Strings: 699
10:32:29 AllocatedObjects: 0
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 117 (Mb) - EndGarbageCollection (end)
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 117 (Mb) - End Garbage Collection Cycle
10:32:29
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 116 (Mb) - Start Garbage Collection Cycle
10:32:29 StartGarbageCollection (start)
10:32:29 after AddAllocatedObjects
10:32:29 after snapshot check
10:32:29 StartGarbageCollection (end)
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 116 (Mb) - EndGarbageCollection (start)
10:32:29 Objects: 8522
10:32:29 Classes: 696
10:32:29 Functions: 0
10:32:29 FunctionFlows: 0
10:32:29 CallStacks: 976
10:32:29 Strings: 699
10:32:29 AllocatedObjects: 0
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 116 (Mb) - EndGarbageCollection (end)
10:32:29 PhysMemInUse: 394 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 116 (Mb) - End Garbage Collection Cycle
10:32:30 => ::THREAD.AddThread:: CLR Id: 1870160, OS Id: 1388
10:32:31
10:32:31 PhysMemInUse: 410 (Mb), VirtMemInUse: 107 (Mb), PhysMemAvail: 100 (Mb) - Start Garbage Collection Cycle
10:32:31 StartGarbageCollection (start)
10:32:31 after AddAllocatedObjects
10:32:31 after snapshot check
10:32:31 StartGarbageCollection (end)
10:32:31 MoveObjectInfos (start)
10:32:31 MoveObjectInfos (end)
10:32:31 PhysMemInUse: 413 (Mb), VirtMemInUse: 115 (Mb), PhysMemAvail: 98 (Mb) - EndGarbageCollection (start)
10:32:31 Objects: 46237
10:32:31 Classes: 1826
10:32:31 Functions: 0
10:32:31 FunctionFlows: 0
10:32:31 CallStacks: 10078
10:32:31 Strings: 1852
10:32:31 AllocatedObjects: 0
10:32:31 PhysMemInUse: 413 (Mb), VirtMemInUse: 115 (Mb), PhysMemAvail: 98 (Mb) - EndGarbageCollection (end)
10:32:31 PhysMemInUse: 413 (Mb), VirtMemInUse: 115 (Mb), PhysMemAvail: 98 (Mb) - End Garbage Collection Cycle
10:32:46
10:32:46 PhysMemInUse: 425 (Mb), VirtMemInUse: 122 (Mb), PhysMemAvail: 85 (Mb) - Start Garbage Collection Cycle
10:32:46 StartGarbageCollection (start)
10:32:46 after AddAllocatedObjects
10:32:46 after snapshot check
10:32:46 StartGarbageCollection (end)
10:32:46 MoveObjectInfos (start)
10:32:46 MoveObjectInfos (end)
10:32:46 PhysMemInUse: 434 (Mb), VirtMemInUse: 138 (Mb), PhysMemAvail: 77 (Mb) - EndGarbageCollection (start)
10:32:46 Objects: 184341
10:32:46 Classes: 2205
10:32:46 Functions: 0
10:32:46 FunctionFlows: 0
10:32:46 CallStacks: 15022
10:32:46 Strings: 2243
10:32:46 AllocatedObjects: 0
10:32:46 PhysMemInUse: 434 (Mb), VirtMemInUse: 138 (Mb), PhysMemAvail: 77 (Mb) - EndGarbageCollection (end)
10:32:46 PhysMemInUse: 434 (Mb), VirtMemInUse: 138 (Mb), PhysMemAvail: 77 (Mb) - End Garbage Collection Cycle
10:32:51 => ::THREAD.AddThread:: CLR Id: 66866408, OS Id: 1284
10:32:52
10:32:52 PhysMemInUse: 466 (Mb), VirtMemInUse: 186 (Mb), PhysMemAvail: 44 (Mb) - Start Garbage Collection Cycle
10:32:52 StartGarbageCollection (start)
10:32:53 after AddAllocatedObjects
10:32:53 after snapshot check
10:32:53 StartGarbageCollection (end)
10:32:53 MoveObjectInfos (start)
10:32:53 MoveObjectInfos (end)
10:32:53 PhysMemInUse: 477 (Mb), VirtMemInUse: 186 (Mb), PhysMemAvail: 33 (Mb) - EndGarbageCollection (start)
10:32:53 Objects: 326722
10:32:53 Classes: 2912
10:32:53 Functions: 0
10:32:53 FunctionFlows: 0
10:32:53 CallStacks: 30552
10:32:53 Strings: 2976
10:32:53 AllocatedObjects: 0
10:32:53 PhysMemInUse: 477 (Mb), VirtMemInUse: 186 (Mb), PhysMemAvail: 33 (Mb) - EndGarbageCollection (end)
10:32:53 PhysMemInUse: 477 (Mb), VirtMemInUse: 186 (Mb), PhysMemAvail: 33 (Mb) - End Garbage Collection Cycle
10:33:01 => ::PIPE CollectHeapState (start)
10:33:01
10:33:01 PhysMemInUse: 480 (Mb), VirtMemInUse: 194 (Mb), PhysMemAvail: 30 (Mb) - Start Garbage Collection Cycle
10:33:01 StartGarbageCollection (start)
10:33:01 after AddAllocatedObjects
10:33:01 after snapshot check
10:33:01 StartGarbageCollection (end)
10:33:01 MoveObjectInfos (start)
10:33:01 MoveObjectInfos (end)
10:33:01 PhysMemInUse: 488 (Mb), VirtMemInUse: 195 (Mb), PhysMemAvail: 22 (Mb) - EndGarbageCollection (start)
10:33:01 Objects: 414984
10:33:01 Classes: 3242
10:33:01 Functions: 0
10:33:01 FunctionFlows: 0
10:33:01 CallStacks: 36221
10:33:01 Strings: 3306
10:33:01 AllocatedObjects: 0
10:33:01 before RemoveNonAliveObjectInfos
10:33:01 before CheckObjectsOverflow
10:33:01 PhysMemInUse: 489 (Mb), VirtMemInUse: 195 (Mb), PhysMemAvail: 21 (Mb) - Taking Snapshot... (start)
10:33:01 PhysMemInUse: 489 (Mb), VirtMemInUse: 195 (Mb), PhysMemAvail: 21 (Mb) - before AddParentReferences
10:33:01 PhysMemInUse: 490 (Mb), VirtMemInUse: 195 (Mb), PhysMemAvail: 21 (Mb) - before AddAllFunctionDetails
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before AddAliveStatistics
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before CalculateFunctionFlowInfos
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before CalculateImportantObjects
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before CalculateImportantFunctions
10:33:06 PhysMemInUse: 492 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 19 (Mb) - before CalculateObjectsIndexes12
10:33:06 PhysMemInUse: 494 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 17 (Mb) - before CalculateImportantCallStacks
10:33:06 PhysMemInUse: 494 (Mb), VirtMemInUse: 201 (Mb), PhysMemAvail: 17 (Mb) - before HeapStateToBuffer
10:33:07 PhysMemInUse: 497 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 13 (Mb) - after HeapStateToBuffer
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - after Reset
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - after MakeAllCreatedBeforeLastSnapshotTrue
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - Taking Snapshot... (end)
10:33:07 before parents remove
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - EndGarbageCollection (end)
10:33:07 PhysMemInUse: 495 (Mb), VirtMemInUse: 205 (Mb), PhysMemAvail: 16 (Mb) - End Garbage Collection Cycle
10:33:07 => ::THREAD.AddThread:: CLR Id: 81435912, OS Id: 2556
10:33:07 => ::THREAD.AddThread:: CLR Id: 180891944, OS Id: 2188
10:33:07 => ::PIPE CollectHeapState (end)
10:37:18 PIPE: Handles closed
10:37:20 => ::THREAD.AddThread:: CLR Id: 180946888, OS Id: 2316
10:37:21 => ::THREAD.RemoveThread:: CLR Id: 180946888, OS Id: 1440
10:37:21 => ::THREAD.AddThread:: CLR Id: 180948136, OS Id: 1440
10:37:21
10:37:21 PhysMemInUse: 485 (Mb), VirtMemInUse: 198 (Mb), PhysMemAvail: 26 (Mb) - Start Garbage Collection Cycle
10:37:21 StartGarbageCollection (start)
10:37:21 after AddAllocatedObjects
10:37:21 after snapshot check
10:37:21 StartGarbageCollection (end)
10:37:21 CCoreProfilerCallback::Shutdown (Memory start)
10:37:21 CCoreProfilerCallback::Shutdown (Memory end)
10:37:21 CCoreProfilerCallback::Shutdown
Comments
Thanks for the profiler log. From looking at that, it could be that you are running out of memory and there is not enough available for Profiler to use to process the results. Does the Profiler still work if you do not perform as many operations with the software that you're profiling? This may be a sign that you simply need more memory.
Please let me know your thoughts on this.
No, I don't think this is memory problem. I tried to take snapshot
right after application start, on login dialog.
When exception is thrown, aspnet_wp process is taking 60 mbytes,
ui RedGate.Profile process - 38 mbytes, service RedGate.Profile process - 9 mbytes. I am running windows 2000 with 512 mb of memory.
One more error - if I close IE window after exception (instead of clicking profiler STOP button), dialog with following text show up -
Unable to create profiler results Index was outside the bounds of the array.
When you stopped profiling, ANTS Trace Log indicates that you have only 26MB of physical memory available. We're putting some code into the next version to handle these low-memory situations.
In version 2 of ANTS Profiler, the STOP button merely stops the profiling session without returning results as opposed to 1.x, which would cause results to be produced. So I can imagine that clicking STOP wouldn't cause the problem to surface.
How much free memory do I need to profile?
Here's another log:
14:19:59 ANTS Profiler v2, Release log for native core2 created
14:19:59 CCoreProfilerCallback constructor
14:19:59 ProfilerBase::StartPipeClient
14:19:59 PipeClient::StartPipes
14:19:59 PipeClient::SendProcessInfo
14:19:59 Process C:\WINNT\Microsoft.NET\Framework\v1.1.4322\aspnet_wp.exe
14:19:59 Runtime version v1.1.4322
14:19:59 Transfer has validated the process info. We will profile this process
14:19:59 PhysMemInUse: 283 (Mb), VirtMemInUse: 33 (Mb), PhysMemAvail: 228 (Mb) - Memory profiler initialized
14:19:59
14:19:59 => ::THREAD.AddThread:: CLR Id: 1406088, OS Id: 2684
14:19:59 => ::THREAD.AddThread:: CLR Id: 1408032, OS Id: 1476
14:20:00
14:20:00 PhysMemInUse: 287 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 224 (Mb) - Start Garbage Collection Cycle
14:20:00 StartGarbageCollection (start)
14:20:00 after AddAllocatedObjects
14:20:00 after snapshot check
14:20:00 StartGarbageCollection (end)
14:20:00 PhysMemInUse: 287 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 224 (Mb) - EndGarbageCollection (start)
14:20:00 Objects: 4260
14:20:00 Classes: 696
14:20:00 Functions: 0
14:20:00 FunctionFlows: 0
14:20:00 CallStacks: 976
14:20:00 Strings: 699
14:20:00 AllocatedObjects: 0
14:20:00 PhysMemInUse: 287 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 224 (Mb) - EndGarbageCollection (end)
14:20:00 PhysMemInUse: 287 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 224 (Mb) - End Garbage Collection Cycle
14:20:00
14:20:00 PhysMemInUse: 287 (Mb), VirtMemInUse: 82 (Mb), PhysMemAvail: 224 (Mb) - Start Garbage Collection Cycle
14:20:00 StartGarbageCollection (start)
14:20:00 after AddAllocatedObjects
14:20:00 after snapshot check
14:20:00 StartGarbageCollection (end)
14:20:00 PhysMemInUse: 287 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 223 (Mb) - EndGarbageCollection (start)
14:20:00 Objects: 8522
14:20:00 Classes: 696
14:20:00 Functions: 0
14:20:00 FunctionFlows: 0
14:20:00 CallStacks: 976
14:20:00 Strings: 699
14:20:00 AllocatedObjects: 0
14:20:00 PhysMemInUse: 287 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 223 (Mb) - EndGarbageCollection (end)
14:20:00 PhysMemInUse: 287 (Mb), VirtMemInUse: 84 (Mb), PhysMemAvail: 223 (Mb) - End Garbage Collection Cycle
14:20:02 => ::THREAD.AddThread:: CLR Id: 1850544, OS Id: 916
14:20:11
14:20:11 PhysMemInUse: 307 (Mb), VirtMemInUse: 109 (Mb), PhysMemAvail: 204 (Mb) - Start Garbage Collection Cycle
14:20:11 StartGarbageCollection (start)
14:20:11 after AddAllocatedObjects
14:20:11 after snapshot check
14:20:11 StartGarbageCollection (end)
14:20:11 MoveObjectInfos (start)
14:20:11 MoveObjectInfos (end)
14:20:11 PhysMemInUse: 310 (Mb), VirtMemInUse: 117 (Mb), PhysMemAvail: 201 (Mb) - EndGarbageCollection (start)
14:20:11 Objects: 40379
14:20:11 Classes: 1700
14:20:11 Functions: 0
14:20:11 FunctionFlows: 0
14:20:11 CallStacks: 10469
14:20:11 Strings: 1729
14:20:11 AllocatedObjects: 0
14:20:11 PhysMemInUse: 310 (Mb), VirtMemInUse: 117 (Mb), PhysMemAvail: 201 (Mb) - EndGarbageCollection (end)
14:20:11 PhysMemInUse: 310 (Mb), VirtMemInUse: 117 (Mb), PhysMemAvail: 201 (Mb) - End Garbage Collection Cycle
14:20:14
14:20:14 PhysMemInUse: 320 (Mb), VirtMemInUse: 124 (Mb), PhysMemAvail: 190 (Mb) - Start Garbage Collection Cycle
14:20:14 StartGarbageCollection (start)
14:20:14 after AddAllocatedObjects
14:20:14 after snapshot check
14:20:14 StartGarbageCollection (end)
14:20:14 MoveObjectInfos (start)
14:20:14 MoveObjectInfos (end)
14:20:14 PhysMemInUse: 329 (Mb), VirtMemInUse: 140 (Mb), PhysMemAvail: 181 (Mb) - EndGarbageCollection (start)
14:20:14 Objects: 190069
14:20:14 Classes: 2243
14:20:14 Functions: 0
14:20:14 FunctionFlows: 0
14:20:14 CallStacks: 16504
14:20:14 Strings: 2278
14:20:14 AllocatedObjects: 0
14:20:14 PhysMemInUse: 329 (Mb), VirtMemInUse: 140 (Mb), PhysMemAvail: 181 (Mb) - EndGarbageCollection (end)
14:20:14 PhysMemInUse: 329 (Mb), VirtMemInUse: 140 (Mb), PhysMemAvail: 181 (Mb) - End Garbage Collection Cycle
14:20:23 => ::PIPE CollectHeapState (start)
14:20:23
14:20:23 PhysMemInUse: 339 (Mb), VirtMemInUse: 145 (Mb), PhysMemAvail: 171 (Mb) - Start Garbage Collection Cycle
14:20:23 StartGarbageCollection (start)
14:20:23 after AddAllocatedObjects
14:20:23 after snapshot check
14:20:23 StartGarbageCollection (end)
14:20:23 MoveObjectInfos (start)
14:20:23 MoveObjectInfos (end)
14:20:23 PhysMemInUse: 346 (Mb), VirtMemInUse: 145 (Mb), PhysMemAvail: 164 (Mb) - EndGarbageCollection (start)
14:20:23 Objects: 264500
14:20:23 Classes: 2646
14:20:23 Functions: 0
14:20:23 FunctionFlows: 0
14:20:23 CallStacks: 21024
14:20:23 Strings: 2688
14:20:23 AllocatedObjects: 4
14:20:23 before RemoveNonAliveObjectInfos
14:20:23 before CheckObjectsOverflow
14:20:23 PhysMemInUse: 346 (Mb), VirtMemInUse: 145 (Mb), PhysMemAvail: 164 (Mb) - Taking Snapshot... (start)
14:20:23 PhysMemInUse: 346 (Mb), VirtMemInUse: 145 (Mb), PhysMemAvail: 164 (Mb) - before AddParentReferences
14:20:23 PhysMemInUse: 346 (Mb), VirtMemInUse: 145 (Mb), PhysMemAvail: 164 (Mb) - before AddAllFunctionDetails
14:20:25 PhysMemInUse: 348 (Mb), VirtMemInUse: 146 (Mb), PhysMemAvail: 163 (Mb) - before AddAliveStatistics
14:20:25 PhysMemInUse: 348 (Mb), VirtMemInUse: 146 (Mb), PhysMemAvail: 163 (Mb) - before CalculateFunctionFlowInfos
14:20:25 PhysMemInUse: 348 (Mb), VirtMemInUse: 146 (Mb), PhysMemAvail: 163 (Mb) - before CalculateImportantObjects
14:20:25 PhysMemInUse: 348 (Mb), VirtMemInUse: 146 (Mb), PhysMemAvail: 163 (Mb) - before CalculateImportantFunctions
14:20:25 PhysMemInUse: 348 (Mb), VirtMemInUse: 146 (Mb), PhysMemAvail: 163 (Mb) - before CalculateObjectsIndexes12
14:20:25 PhysMemInUse: 350 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 161 (Mb) - before CalculateImportantCallStacks
14:20:25 PhysMemInUse: 350 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 161 (Mb) - before HeapStateToBuffer
14:20:25 PhysMemInUse: 353 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 158 (Mb) - after HeapStateToBuffer
14:20:25 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 160 (Mb) - after Reset
14:20:25 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 160 (Mb) - after MakeAllCreatedBeforeLastSnapshotTrue
14:20:25 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 160 (Mb) - Taking Snapshot... (end)
14:20:25 before parents remove
14:20:25 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 160 (Mb) - EndGarbageCollection (end)
14:20:25 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 160 (Mb) - End Garbage Collection Cycle
14:20:25 => ::THREAD.AddThread:: CLR Id: 67114424, OS Id: 3088
14:20:25 => ::PIPE CollectHeapState (end)
14:20:33 => ::PIPE CollectHeapState (start)
14:20:33
14:20:33 PhysMemInUse: 350 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 160 (Mb) - Start Garbage Collection Cycle
14:20:33 StartGarbageCollection (start)
14:20:33 after AddAllocatedObjects
14:20:33 after snapshot check
14:20:33 StartGarbageCollection (end)
14:20:33 PhysMemInUse: 351 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 160 (Mb) - EndGarbageCollection (start)
14:20:33 Objects: 41956
14:20:33 Classes: 2646
14:20:33 Functions: 3971
14:20:33 FunctionFlows: 0
14:20:33 CallStacks: 21024
14:20:33 Strings: 7770
14:20:33 AllocatedObjects: 0
14:20:33 before RemoveNonAliveObjectInfos
14:20:33 before CheckObjectsOverflow
14:20:33 PhysMemInUse: 351 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 160 (Mb) - Taking Snapshot... (start)
14:20:33 PhysMemInUse: 351 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 160 (Mb) - before AddParentReferences
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 160 (Mb) - before AddAllFunctionDetails
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 161 (Mb) - before AddAliveStatistics
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 161 (Mb) - before CalculateFunctionFlowInfos
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 161 (Mb) - before CalculateImportantObjects
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 161 (Mb) - before CalculateImportantFunctions
14:20:34 PhysMemInUse: 348 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 163 (Mb) - before CalculateObjectsIndexes12
14:20:34 PhysMemInUse: 349 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 161 (Mb) - before CalculateImportantCallStacks
14:20:34 PhysMemInUse: 349 (Mb), VirtMemInUse: 178 (Mb), PhysMemAvail: 161 (Mb) - before HeapStateToBuffer
14:20:34 PhysMemInUse: 352 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 159 (Mb) - after HeapStateToBuffer
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 161 (Mb) - after Reset
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 161 (Mb) - after MakeAllCreatedBeforeLastSnapshotTrue
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 161 (Mb) - Taking Snapshot... (end)
14:20:34 before parents remove
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 161 (Mb) - EndGarbageCollection (end)
14:20:34 PhysMemInUse: 350 (Mb), VirtMemInUse: 181 (Mb), PhysMemAvail: 161 (Mb) - End Garbage Collection Cycle
14:20:34 => ::PIPE CollectHeapState (end)
14:20:34 PIPE: Handles closed
14:20:36 => ::THREAD.AddThread:: CLR Id: 79475752, OS Id: 1376
14:20:36 => ::THREAD.RemoveThread:: CLR Id: 79475752, OS Id: 1568
14:20:36 => ::THREAD.AddThread:: CLR Id: 79477352, OS Id: 1568
14:20:36
14:20:36 PhysMemInUse: 339 (Mb), VirtMemInUse: 177 (Mb), PhysMemAvail: 171 (Mb) - Start Garbage Collection Cycle
14:20:36 StartGarbageCollection (start)
14:20:36 after AddAllocatedObjects
14:20:36 after snapshot check
14:20:36 StartGarbageCollection (end)
14:20:36 CCoreProfilerCallback::Shutdown (Memory start)
14:20:36 CCoreProfilerCallback::Shutdown (Memory end)
14:20:36 CCoreProfilerCallback::Shutdown
The amount of memory you'll need depends on the size of the results. That's why it would be a good idea to try doing a bit less in the profiling session and taking a snapshot to see if it works with less data.