IISState log help

From: Doug Surfleet (Surfleet_at_discussions.microsoft.com)
Date: 08/11/04 

Date: Wed, 11 Aug 2004 15:13:03 -0700

Could someone help me read this log file? The main issue is that we are
seeing the CPU hit 100% under low usage. This only started a few weeks ago.

I do notice that quite a few threads have this message: "Thread is waiting
for a lock to be released. Looking for lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state" What does this mean, other than it is
waiting for a lock?

Thanks

NOTE: I had to remove some of the threads to make the post shorter. There
were originally 110 threads, with about 60-70 locked waiting either for
thread bc7 to finish or the lock was in a 'convoy' state.

***********************
Starting new log output
IISState version 3.3.1
Wed Aug 11 11:48:21 2004
OS = Windows 2000
Executable: dllhost.exe
PID = 592
Note: Thread times are formatted as HH:MM:SS.ms
***********************
Thread ID: 15
System Thread ID: 8a8
Kernel Time: 0:0:12.390
User Time: 0:1:25.734
*** ERROR: Symbol file could not be found. Defaulted to export symbols for
-
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
Owning thread System ID: b7c
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 0218df28 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 0218df9c 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 0218dfa4 749f48da ntdll!RtlEnterCriticalSection+0x46
03 0218dfd0 749f46b2 asp!CScriptManager::GetEngine+0x41
04 0218e028 749f4539 asp!AllocAndLoadEngines+0x171
05 0218e064 749f43ef asp!ExecuteRequest+0x147
06 0218e0ac 74a16f7e asp!Execute+0x17e
07 0218e10c 74a23e71 asp!CHitObj::ExecuteChildRequest+0x12f
08 0218e334 779d7d5d asp!CServer::Execute+0x96
09 0218e350 77a2310e OLEAUT32!DispCallFunc+0x15d
0a 0218e3e0 74a2407d OLEAUT32!CTypeInfo2::Invoke+0x244
0b 0218e408 6b614279 asp!CDispatchImpl<IServer>::Invoke+0x4b
WARNING: Stack unwind information not available. Following frames may be
wrong.
0c 0218e484 6b61866f vbscript!DllCanUnloadNow+0x8e24
0d 00000000 00000000 vbscript!DllCanUnloadNow+0xd21a

Thread ID: 17
System Thread ID: 8b4
Kernel Time: 0:0:17.796
User Time: 0:1:33.46
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: Unable to locate ASP page

 # ChildEBP RetAddr
00 0224f950 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 0224f9c4 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 0224f9cc 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 0224fa6c 78001e00 ntdll!RtlFreeHeap+0x11b
04 0224fab4 6b615935 MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 00000000 00000000 vbscript!DllCanUnloadNow+0xa4e0

Thread ID: 18
System Thread ID: 8b8
Kernel Time: 0:0:11.375
User Time: 0:1:20.406
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
Owning thread System ID: b7c
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 0228df78 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 0228dfec 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 0228dff4 749f5a60 ntdll!RtlEnterCriticalSection+0x46
03 0228e014 749f597d asp!CScriptManager::ReturnEngineToCache+0xf8
04 0228e030 749f45b0 asp!DeAllocAndFreeEngines+0x35
05 0228e064 749f43ef asp!ExecuteRequest+0x278
06 0228e0ac 74a16f7e asp!Execute+0x17e
07 0228e10c 74a23e71 asp!CHitObj::ExecuteChildRequest+0x12f
08 0228e334 779d7d5d asp!CServer::Execute+0x96
09 0228e350 77a2310e OLEAUT32!DispCallFunc+0x15d
0a 0228e3e0 74a2407d OLEAUT32!CTypeInfo2::Invoke+0x244
0b 0228e408 6b614279 asp!CDispatchImpl<IServer>::Invoke+0x4b
WARNING: Stack unwind information not available. Following frames may be
wrong.
0c 0228e484 6b61866f vbscript!DllCanUnloadNow+0x8e24
0d 00000000 00000000 vbscript!DllCanUnloadNow+0xd21a

Thread ID: 19
System Thread ID: 8c0
Kernel Time: 0:0:16.421
User Time: 0:1:18.218
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
Owning thread System ID: b7c
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 0230df78 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 0230dfec 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 0230dff4 749f5a60 ntdll!RtlEnterCriticalSection+0x46
03 0230e014 749f597d asp!CScriptManager::ReturnEngineToCache+0xf8
04 0230e030 749f45b0 asp!DeAllocAndFreeEngines+0x35
05 0230e064 749f43ef asp!ExecuteRequest+0x278
06 0230e0ac 74a16f7e asp!Execute+0x17e
07 0230e10c 74a23e71 asp!CHitObj::ExecuteChildRequest+0x12f
08 0230e334 779d7d5d asp!CServer::Execute+0x96
09 0230e350 77a2310e OLEAUT32!DispCallFunc+0x15d
0a 0230e3e0 74a2407d OLEAUT32!CTypeInfo2::Invoke+0x244
0b 0230e408 6b614279 asp!CDispatchImpl<IServer>::Invoke+0x4b
WARNING: Stack unwind information not available. Following frames may be
wrong.
0c 0230e484 6b61866f vbscript!DllCanUnloadNow+0x8e24
0d 00000000 00000000 vbscript!DllCanUnloadNow+0xd21a

Thread ID: 31
System Thread ID: a5c
Kernel Time: 0:0:8.93
User Time: 0:0:41.796
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 2139dd9c 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 2139de10 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 2139de18 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 2139deb8 78001e00 ntdll!RtlFreeHeap+0x11b
04 2139df00 6b61b3d9 MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 00000000 00000000 vbscript!DllCanUnloadNow+0xff84

Thread ID: 32
System Thread ID: ad4
Kernel Time: 0:0:8.265
User Time: 0:0:33.546
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 37dbddac 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 37dbde20 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 37dbde28 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 37dbdec8 78001e00 ntdll!RtlFreeHeap+0x11b
04 37dbdf10 6b60d20e MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 00000000 00000000 vbscript!DllCanUnloadNow+0x1db9

Thread ID: 33
System Thread ID: b4c
Kernel Time: 0:0:1.437
User Time: 0:0:7.796
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 1a85dd94 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 1a85de08 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 1a85de10 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 1a85deb0 78001e00 ntdll!RtlFreeHeap+0x11b
04 1a85def8 6b62d8ab MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 00000000 00000000 vbscript!DllCanUnloadNow+0x22456

Thread ID: 34
System Thread ID: 64c
Kernel Time: 0:0:0.765
User Time: 0:0:6.656
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
Owning thread System ID: b7c
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 0276df78 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 0276dfec 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 0276dff4 749f5a60 ntdll!RtlEnterCriticalSection+0x46
03 0276e014 749f597d asp!CScriptManager::ReturnEngineToCache+0xf8
04 0276e030 749f45b0 asp!DeAllocAndFreeEngines+0x35
05 0276e064 749f43ef asp!ExecuteRequest+0x278
06 0276e0ac 74a16f7e asp!Execute+0x17e
07 0276e10c 74a23e71 asp!CHitObj::ExecuteChildRequest+0x12f
08 0276e334 779d7d5d asp!CServer::Execute+0x96
09 0276e350 77a2310e OLEAUT32!DispCallFunc+0x15d
0a 0276e3e0 74a2407d OLEAUT32!CTypeInfo2::Invoke+0x244
0b 0276e408 6b614279 asp!CDispatchImpl<IServer>::Invoke+0x4b
WARNING: Stack unwind information not available. Following frames may be
wrong.
0c 0276e484 6b61866f vbscript!DllCanUnloadNow+0x8e24
0d 00000000 00000000 vbscript!DllCanUnloadNow+0xd21a

Thread ID: 36
System Thread ID: b7c
Kernel Time: 0:0:0.93
User Time: 0:0:0.390
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 07c7ddd8 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 07c7de4c 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 07c7de54 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 07c7def4 78001e00 ntdll!RtlFreeHeap+0x11b
04 07c7df3c 6b602567 MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 07c7dfdc 74a2277c vbscript!DllRegisterServer+0x91a
06 07c7dfe8 749f5cfa asp!CScriptManager::CheckFSQLRU+0x56
07 07c7dff4 749f5a84 asp!CScriptManager::AddToFSQ+0x5c
08 07c7e014 749f597d asp!CScriptManager::ReturnEngineToCache+0x12a
09 07c7e030 749f45b0 asp!DeAllocAndFreeEngines+0x35
0a 07c7e064 749f43ef asp!ExecuteRequest+0x278
0b 07c7e0ac 74a16f7e asp!Execute+0x17e
0c 07c7e10c 74a23e71 asp!CHitObj::ExecuteChildRequest+0x12f
0d 07c7e334 779d7d5d asp!CServer::Execute+0x96
0e 07c7e350 77a2310e OLEAUT32!DispCallFunc+0x15d
0f 07c7e3e0 74a2407d OLEAUT32!CTypeInfo2::Invoke+0x244
10 07c7e408 6b614279 asp!CDispatchImpl<IServer>::Invoke+0x4b
11 07c7e484 6b61866f vbscript!DllCanUnloadNow+0x8e24
12 00000000 00000000 vbscript!DllCanUnloadNow+0xd21a

Thread ID: 43
System Thread ID: b84
Kernel Time: 0:0:0.15
User Time: 0:0:0.31
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 009cdd94 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 009cde08 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 009cde10 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 009cdeb0 78001e00 ntdll!RtlFreeHeap+0x11b
04 009cdef8 6b62d8ab MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 00000000 00000000 vbscript!DllCanUnloadNow+0x22456

Thread ID: 46
System Thread ID: c20
Kernel Time: 0:0:0.0
User Time: 0:0:0.109
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 0208dd94 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 0208de08 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 0208de10 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 0208deb0 78001e00 ntdll!RtlFreeHeap+0x11b
04 0208def8 6b62d8ab MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 00000000 00000000 vbscript!DllCanUnloadNow+0x22456

Thread ID: 49
System Thread ID: b80
Kernel Time: 0:0:0.15
User Time: 0:0:0.125
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\PORTAL\NUGGETS\SIDEBARPUBLIC\DEFAULT.ASP

 # ChildEBP RetAddr
00 022cd500 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 022cd574 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 022cd57c 77fcc21a ntdll!RtlEnterCriticalSection+0x46
03 022cd754 78001532 ntdll!RtlAllocateHeap+0x177
04 022cd794 780014cf MSVCRT!_heap_alloc+0xeb
05 022cd79c 780197b2 MSVCRT!_nh_malloc+0x10
06 022cd7a8 6b615a0e MSVCRT!operator new+0xb
WARNING: Stack unwind information not available. Following frames may be
wrong.
07 022cd7c4 6b60d3f3 vbscript!DllCanUnloadNow+0xa5b9
08 17cf7a78 00000000 vbscript!DllCanUnloadNow+0x1f9e

Thread ID: 50
System Thread ID: 7a8
Kernel Time: 0:0:0.31
User Time: 0:0:0.46
*** WARNING: Unable to verify checksum for
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\PORTAL\NUGGETS\SIDEBARPUBLIC\DEFAULT.ASP

 # ChildEBP RetAddr
00 0234c950 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 0234c9c4 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 0234c9cc 77fcc21a ntdll!RtlEnterCriticalSection+0x46
03 0234cba4 78001532 ntdll!RtlAllocateHeap+0x177
04 0234cbe4 780014cf MSVCRT!_heap_alloc+0xeb
05 0234cbec 780197b2 MSVCRT!_nh_malloc+0x10
06 0234cbf8 6b80876f MSVCRT!operator new+0xb
07 0234cc04 6b8087c3 scrrun!VBADictionary::Init+0xf
08 0234cc0c 6b80a4c6 scrrun!VBADictionary::Create+0x23
09 0234cc20 77ac1362 scrrun!CClassFactory::CreateInstance+0xa6
0a 0234cc40 77ac1077 ole32!CreateInprocInstanceHelper+0x1a
0b 0234ccfc 77a6c1da ole32!ICoCreateInstanceEx+0x134
0c 0234cd24 77a6c1a5 ole32!CoCreateInstanceEx+0x2b
0d 0234cd54 74a2e6a2 ole32!CoCreateInstance+0x35
0e 0234cd6c 74a0bfc0 asp!ViperCreateInstance+0x16
0f 0234cd90 74a0bee2 asp!CComponentObject::TryInstantiate+0x76
10 0234cdd8 74a0d26d asp!CComponentObject::Instantiate+0x42
11 0234cdf0 74a2cde1
asp!CPageComponentManager::AddScopedUnnamedInstantiated+0x58
12 0234ce38 74a23657 asp!CTypelibCache::CreateComponent+0xce
13 0234ce6c 779d7d5d asp!CServer::CreateObject+0x5c
14 0234ce8c 77a2310e OLEAUT32!DispCallFunc+0x15d
15 0234cf1c 74a2407d OLEAUT32!CTypeInfo2::Invoke+0x244
16 0234cf44 6b614279 asp!CDispatchImpl<IServer>::Invoke+0x4b
WARNING: Stack unwind information not available. Following frames may be
wrong.
17 0234cfc0 6b61866f vbscript!DllCanUnloadNow+0x8e24
18 0234d6a0 0234d6e0 vbscript!DllCanUnloadNow+0xd21a
19 079b0000 0c6a0000 0x234d6e0
1a 0bc20000 079b0000 0xc6a0000
1b 0c790000 0bc20000 0x79b0000
1c 06e00000 0c790000 0xbc20000
1d 0d1c0000 06e00000 0xc790000
1e 0d340000 0d1c0000 0x6e00000
1f 023f0000 0d340000 0xd1c0000
20 05880000 023f0000 0xd340000
21 08970000 05880000 0x23f0000
22 09e20000 08970000 0x5880000
23 0b010000 09e20000 0x8970000
24 0be60000 0b010000 0x9e20000
25 04650000 0be60000 0xb010000
26 05980000 04650000 0xbe60000
27 05410000 05980000 0x4650000
28 0a6e0000 05410000 0x5980000
29 051b0000 0a6e0000 0x5410000
2a 05b10000 051b0000 0xa6e0000
2b 03e90000 05b10000 0x51b0000
2c 06520000 03e90000 0x5b10000
2d 00400050 06520000 0x3e90000
2e 03d10000 00400050 0x6520000
2f 05e00000 03d10000 0x400050
30 097f0000 05e00000 0x3d10000
31 10d40000 097f0000 0x5e00000

Thread ID: 51
System Thread ID: 7c8
Kernel Time: 0:0:0.0
User Time: 0:0:0.109
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\SIDEBAR.ASP

 # ChildEBP RetAddr
00 023add9c 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 023ade10 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 023ade18 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 023adeb8 78001e00 ntdll!RtlFreeHeap+0x11b
04 023adf00 6b615935 MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 00000000 00000000 vbscript!DllCanUnloadNow+0xa4e0

Thread ID: 52
System Thread ID: adc
Kernel Time: 0:0:0.15
User Time: 0:0:0.93
Other information: Thread is waiting for a lock to be released. Looking for
lock owner.
The owning thread is 0. This usually indicates that the lock has either
been leaked or is in a 'convoy' state
Thread Type: ASP
Executing Page: E:\INETPUB\ANGELROOT\HOME.ASP

 # ChildEBP RetAddr
00 0272dd9c 77f8822a ntdll!ZwWaitForSingleObject+0xb
01 0272de10 77f8819b ntdll!RtlpWaitForCriticalSection+0x9e
02 0272de18 77fcc74d ntdll!RtlEnterCriticalSection+0x46
03 0272deb8 78001e00 ntdll!RtlFreeHeap+0x11b
04 0272df00 6b615935 MSVCRT!free+0xda
WARNING: Stack unwind information not available. Following frames may be
wrong.
05 00000000 00000000 vbscript!DllCanUnloadNow+0xa4e0

Relevant Pages