CPU 100%, IIS using ASP application

CPU 100%, IIS using ASP application

am 12.07.2007 21:24:01 von Chetan

We are doing performance testing & CPU spikes on the webserver with 200 or
more users accessing, took the log from debug diag & am posting it. Debug
diag gives an issue with blocking but dosent elaborate on where it happens,
as in the call stack comes up empty for the blocked threads, i checked this
setting in debug diag-->Options & Settings-->Preferences, checked the
"Include source and line information in analysis reports", debug symbols for
Win2K3SP1 are on the server as well. Is there any other setting which i need
to enable to get some more info on the problem. Threads 51 & 54 are blocking
other ASP requests. Any help is appreciated ...

Env: Win2k3 SP1, IIS 6, webserver updated with patches, 2GB Ram, 2CPU

Debug diag report:

------------------------------------------------------------ ----------------------------
Analysis Summary
Type Description Recommendation
Warning The following threads in
w3wp.exe__BManage__PID__2616__Date__07_09_2007__Time_04_37_0 4PM__421__Manual
Dump.dmp are waiting on data to be returned from another server via WinSock.

The call to WinSock originated from dbnetlib!ConnectionRead+3b6

( 51 )

50.00% of executing ASP Requests blocked

1.15% of threads blocked


Ensure that any remote server this application may be calling is
functioning properly and there are no network issues between the two servers.
If the problem continues, please contact the application vendor for further
assistance


Information The following threads in
w3wp.exe__BManage__PID__2616__Date__07_09_2007__Time_04_37_0 4PM__421__Manual
Dump.dmp are processing a client request and is/are not fully resolved.
Further analysis of these threads is recommended in order to determine what
may be blocking the request(s).

( 54 )


50.00% of executing ASP Requests blocked

1.15% of threads blocked


Analysis Details
Table Of Contents

w3wp.exe__BManage__PID__2616__Date__07_09_2007__Time_04_37_0 4PM__421__Manual
Dump.dmp

Top 5 threads by CPU time

Thread report

COM+ STA ThreadPool Report

Well-Known COM STA Threads Report

HTTP Report

ASP Report

Report for
w3wp.exe__BManage__PID__2616__Date__07_09_2007__Time_04_37_0 4PM__421__Manual
Dump.dmp
Type of Analysis Performed Hang Analysis
Machine Name PERFBOXSQL6
Operating System Windows Server 2003 Service Pack 1
Number Of Processors 2
Process ID 2616
Process Image c:\WINDOWS\system32\inetsrv\w3wp.exe
System Up-Time 10 day(s) 03:06:17
Process Up-Time 0 day(s) 00:36:59

Top 5 Threads by CPU time
Note - Times include both user mode and kernel mode for each thread Thread
ID: 56 Total CPU Time: 0 day(s) 00:00:59.937 Entry Point for Thread:
msvcrt!_endthreadex+2f
Thread ID: 69 Total CPU Time: 0 day(s) 00:00:56.671 Entry Point for
Thread: msvcrt!_endthreadex+2f
Thread ID: 18 Total CPU Time: 0 day(s) 00:00:55.0 Entry Point for
Thread: msvcrt!_endthreadex+2f
Thread ID: 31 Total CPU Time: 0 day(s) 00:00:52.640 Entry Point for
Thread: msvcrt!_endthreadex+2f
Thread ID: 63 Total CPU Time: 0 day(s) 00:00:51.671 Entry Point for
Thread: msvcrt!_endthreadex+2f

Thread report


....
....

Thread 51 - System ID 840
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:02:26 PM
Time spent in user mode 0 Days 0:0:39.453
Time spent in kernel mode 0 Days 0:0:4.125


This thread is waiting on data to be returned from another server via WinSock.


The call to WinSock originated from dbnetlib!ConnectionRead+3b6


Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForSingleObject+c
mswsock!SockWaitForSingleObject+3a
mswsock!WSPRecv+203
ws2_32!WSARecv+77
wsock32!recv+31
dbnetlib!ConnectionRead+3b6
sqlsrv32!getbytes+1fa
sqlsrv32!ProcessTDSStream+204
sqlsrv32!SQLExecDirectW+e46
odbc32!SQLExecute+26f
odbc32!SQLExecDirectW+3b
msdasql!CUtlParam30::ExecuteWithParameters+37b
msdasql!CImpICommandText::ExecuteHelper+1ee
msdasql!CImpICommandText::Execute+673
msado15!CConnection::Execute+9d
msado15!_ExecuteAsync+19f
msado15!ExecuteAsync+23
msado15!CQuery::Execute+a5e
msado15!CCommand::_Execute+153
msado15!CCommand::Execute+4e2
msado15!CCommand::Invoke+81e
jscript!DllRegisterServer+45f6
jscript!DllCanUnloadNow+f774
jscript!DllGetClassObject+6421
jscript!DllGetClassObject+63a2
jscript!DllCanUnloadNow+6301
jscript+5750
jscript+4fe0
jscript+6dd9
jscript+38aa
jscript+5079
jscript+4fff
jscript+6f34
jscript+5750
jscript+4fe0
jscript+6dd9
jscript+38aa
jscript+5cb0
jscript+4fff
jscript!DllCanUnloadNow+6d2
jscript+38aa
jscript+5347
jscript!DllGetClassObject+589
jscript!DllGetClassObject+8b8
asp!CActiveScriptEngine::TryCall+19
asp!CActiveScriptEngine::Call+31
asp!CallScriptFunctionOfEngine+5b
asp!ExecuteRequest+17e
asp!Execute+249
asp!CHitObj::ViperAsyncCallback+3e8
asp!CViperAsyncRequest::OnCall+92
comsvcs!CSTAActivityWork::STAActivityWorkHelper+32
ole32!EnterForCallback+c4
ole32!SwitchForCallback+1a3
ole32!PerformCallback+54
ole32!CObjectContext::InternalContextCallback+159
ole32!CObjectContext::DoCallback+1c
comsvcs!CSTAActivityWork::DoWork+12d
comsvcs!CSTAThread::DoWork+18
comsvcs!CSTAThread::ProcessQueueWork+37
comsvcs!CSTAThread::WorkerLoop+17c
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34


ASP request executing on thread 51
GET request for /BManage/lang-en/management/LMS_Training.asp
Request alive for 0 day(s) 00:00:16
QueryString UserMode=0&Mode=4
Request mapped to
c:\inetpub\wwwroot\BManage\lang-en\management\LMS_Training.a sp
ASP Application /LM/W3SVC/1/Root/BManage
ASP Template C:\INETPUB\WWWROOT\BManage\LANG-EN\MANAGEMENT\LMS_TRAINING.A SP


Script call stack for thread 51

Function Scope Line Of Code Source File Line Number


Thread 52 - System ID 3892
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:02:29 PM
Time spent in user mode 0 Days 0:0:40.875
Time spent in kernel mode 0 Days 0:0:6.406


Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34


Thread 53 - System ID 2528
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:02:36 PM
Time spent in user mode 0 Days 0:0:45.515
Time spent in kernel mode 0 Days 0:0:4.921

Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34


Thread 54 - System ID 2872
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:02:39 PM
Time spent in user mode 0 Days 0:0:37.656
Time spent in kernel mode 0 Days 0:0:5.656

This thread is processing a client request and is/are not fully resolved.
Further analysis of these threads is recommended in order to determine what
may be blocking the request(s).


Function Source
ntdll!RtlEnterCriticalSection+6
jscript+1590
jscript+1604
jscript!DllGetClassObject+4aa7
jscript!DllGetClassObject+4ab6
jscript!DllGetClassObject+5821
asp!CScriptingNamespace::UnInit+93
asp!ExecuteRequest+3c0
asp!Execute+249
asp!CHitObj::ViperAsyncCallback+3e8
asp!CViperAsyncRequest::OnCall+92
comsvcs!CSTAActivityWork::STAActivityWorkHelper+32
ole32!EnterForCallback+c4
ole32!SwitchForCallback+1a3
ole32!PerformCallback+54
ole32!CObjectContext::InternalContextCallback+159
ole32!CObjectContext::DoCallback+1c
comsvcs!CSTAActivityWork::DoWork+12d
comsvcs!CSTAThread::DoWork+18
comsvcs!CSTAThread::ProcessQueueWork+37
comsvcs!CSTAThread::WorkerLoop+17c
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34


ASP request executing on thread 54
GET request for /BManage/lang-en/management/LMS_LearnerHomePageData.asp
Request alive for 0 day(s) 00:00:16
QueryString
objPrefArrayInd=16&arrIViewPrefInd=1&arrMaxRowsInd=0&iIndext emp=1&iIndex=3&UserMode=0
Request mapped to
c:\inetpub\wwwroot\BManage\lang-en\management\LMS_LearnerHom ePageData.asp
ASP Application /LM/W3SVC/1/Root/BManage
ASP Template
C:\INETPUB\WWWROOT\BManage\LANG-EN\MANAGEMENT\LMS_LEARNERHOM EPAGEDATA.ASP


Script call stack for thread 54

Function Scope Line Of Code Source File Line Number


Thread 55 - System ID 1092
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:02:46 PM
Time spent in user mode 0 Days 0:0:24.421
Time spent in kernel mode 0 Days 0:0:2.859

Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 56 - System ID 1992
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:02:49 PM
Time spent in user mode 0 Days 0:0:52.359
Time spent in kernel mode 0 Days 0:0:7.578




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 57 - System ID 3456
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:02:56 PM
Time spent in user mode 0 Days 0:0:36.171
Time spent in kernel mode 0 Days 0:0:4.265




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 58 - System ID 484
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:02:59 PM
Time spent in user mode 0 Days 0:0:27.843
Time spent in kernel mode 0 Days 0:0:3.953




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 59 - System ID 2440
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:06 PM
Time spent in user mode 0 Days 0:0:23.687
Time spent in kernel mode 0 Days 0:0:2.578




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 60 - System ID 2744
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:09 PM
Time spent in user mode 0 Days 0:0:36.328
Time spent in kernel mode 0 Days 0:0:3.546




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 61 - System ID 2516
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:16 PM
Time spent in user mode 0 Days 0:0:18.578
Time spent in kernel mode 0 Days 0:0:2.656




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 62 - System ID 4076
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:19 PM
Time spent in user mode 0 Days 0:0:41.796
Time spent in kernel mode 0 Days 0:0:4.671




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 63 - System ID 1284
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:19 PM
Time spent in user mode 0 Days 0:0:44.968
Time spent in kernel mode 0 Days 0:0:6.703




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 64 - System ID 344
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:29 PM
Time spent in user mode 0 Days 0:0:40.890
Time spent in kernel mode 0 Days 0:0:6.62




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 65 - System ID 2928
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:36 PM
Time spent in user mode 0 Days 0:0:39.734
Time spent in kernel mode 0 Days 0:0:4.562




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 66 - System ID 1900
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:37 PM
Time spent in user mode 0 Days 0:0:37.609
Time spent in kernel mode 0 Days 0:0:7.234




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 67 - System ID 2604
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:42 PM
Time spent in user mode 0 Days 0:0:25.796
Time spent in kernel mode 0 Days 0:0:2.718




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 68 - System ID 704
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:49 PM
Time spent in user mode 0 Days 0:0:40.296
Time spent in kernel mode 0 Days 0:0:5.984




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 69 - System ID 3752
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:56 PM
Time spent in user mode 0 Days 0:0:51.437
Time spent in kernel mode 0 Days 0:0:5.234




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 70 - System ID 1212
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:03:59 PM
Time spent in user mode 0 Days 0:0:33.484
Time spent in kernel mode 0 Days 0:0:5.390




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 71 - System ID 2144
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:04:04 PM
Time spent in user mode 0 Days 0:0:26.906
Time spent in kernel mode 0 Days 0:0:3.187




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 72 - System ID 2384
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:04:09 PM
Time spent in user mode 0 Days 0:0:26.906
Time spent in kernel mode 0 Days 0:0:4.31




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 73 - System ID 1360
Entry point msvcrt!_endthreadex+2f
Create time 7/9/2007 4:04:17 PM
Time spent in user mode 0 Days 0:0:31.140
Time spent in kernel mode 0 Days 0:0:3.390




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtWaitForMultipleObjects+c
kernel32!WaitForMultipleObjectsEx+11a
user32!RealMsgWaitForMultipleObjectsEx+141
user32!MsgWaitForMultipleObjects+1f
comsvcs!CSTAThread::WorkerLoop+1e5
msvcrt!_endthreadex+a3
kernel32!BaseThreadStart+34







Thread 74 - System ID 2276
Entry point rpcrt4!ThreadStartRoutine
Create time 7/9/2007 4:06:09 PM
Time spent in user mode 0 Days 0:0:0.0
Time spent in kernel mode 0 Days 0:0:0.0




Function Source
ntdll!KiFastSystemCallRet
ntdll!ZwReplyWaitReceivePortEx+c
rpcrt4!LRPC_ADDRESS::ReceiveLotsaCalls+198
rpcrt4!RecvLotsaCallsWrapper+d
rpcrt4!BaseCachedThreadRoutine+9d
rpcrt4!ThreadStartRoutine+1b
kernel32!BaseThreadStart+34







Thread 75 - System ID 2984
Entry point rpcrt4!ThreadStartRoutine
Create time 7/9/2007 4:06:09 PM
Time spent in user mode 0 Days 0:0:0.0
Time spent in kernel mode 0 Days 0:0:0.0




Function Source
ntdll!KiFastSystemCallRet
ntdll!ZwReplyWaitReceivePortEx+c
rpcrt4!LRPC_ADDRESS::ReceiveLotsaCalls+198
rpcrt4!RecvLotsaCallsWrapper+d
rpcrt4!BaseCachedThreadRoutine+9d
rpcrt4!ThreadStartRoutine+1b
kernel32!BaseThreadStart+34







Thread 76 - System ID 3928
Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
Create time 7/9/2007 4:11:12 PM
Time spent in user mode 0 Days 0:0:0.390
Time spent in kernel mode 0 Days 0:0:2.875




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtRemoveIoCompletion+c
kernel32!GetQueuedCompletionStatus+29
w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
w3tp!THREAD_MANAGER::ThreadManagerThread+39
kernel32!BaseThreadStart+34







Thread 77 - System ID 3684
Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
Create time 7/9/2007 4:11:32 PM
Time spent in user mode 0 Days 0:0:1.187
Time spent in kernel mode 0 Days 0:0:3.984




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtRemoveIoCompletion+c
kernel32!GetQueuedCompletionStatus+29
w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
w3tp!THREAD_MANAGER::ThreadManagerThread+39
kernel32!BaseThreadStart+34







Thread 78 - System ID 1524
Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
Create time 7/9/2007 4:14:53 PM
Time spent in user mode 0 Days 0:0:0.703
Time spent in kernel mode 0 Days 0:0:3.343




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtRemoveIoCompletion+c
kernel32!GetQueuedCompletionStatus+29
w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
w3tp!THREAD_MANAGER::ThreadManagerThread+39
kernel32!BaseThreadStart+34







Thread 79 - System ID 228
Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
Create time 7/9/2007 4:19:01 PM
Time spent in user mode 0 Days 0:0:1.234
Time spent in kernel mode 0 Days 0:0:3.531




Function Source
ntdll!KiFastSystemCallRet
ntdll!NtRemoveIoCompletion+c
kernel32!GetQueuedCompletionStatus+29
w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
w3tp!THREAD_MANAGER::ThreadManagerThread+39
kernel32!BaseThreadStart+34







Thread 80 - System ID 3004
Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
Create time 7/9/2007 4:21:35 PM
Time spent in user mode 0 Days 0:0:0.703
Time spent in kernel mode 0 Days 0:0:3.31

Function Source
ntdll!KiFastSystemCallRet
ntdll!NtRemoveIoCompletion+c
kernel32!GetQueuedCompletionStatus+29
w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
w3tp!THREAD_MANAGER::ThreadManagerThread+39
kernel32!BaseThreadStart+34


Thread 81 - System ID 2864
Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
Create time 7/9/2007 4:29:30 PM
Time spent in user mode 0 Days 0:0:0.312
Time spent in kernel mode 0 Days 0:0:2.296

Function Source
ntdll!KiFastSystemCallRet
ntdll!NtRemoveIoCompletion+c
kernel32!GetQueuedCompletionStatus+29
w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
w3tp!THREAD_MANAGER::ThreadManagerThread+39
kernel32!BaseThreadStart+34

COM+ STA ThreadPool Report
Max STA Threads 50
Min STA Threads 4
Current STA Threads 50
g_activitiesPerThread 1
EmulateMTSBehavior False
STA Threads In-Call 2 (4%)


All threads other simliair to Threads 52/53

STA Thread Activity Count Thread Status Call Status
51 4 In-Call (ASP) waiting on data to be returned from another server via
WinSock
52 4 Idle (N/A)
53 4 Idle (N/A)
54 4 In-Call (ASP) processing a client request and is/are not fully
resolved. Further analysis of these threads is recommended in order to
determine what may be blocking the request(s).




Well-Known COM STA Threads Report
STA Name Thread ID Thread Status Call Status

Main STA 15 Idle (N/A)
Apartment-threaded host for MTA clients 33 Idle (N/A)



HTTP report
IIS ATQ worker thread count 11 Thread(s)
Available ATQ worker thread count 11 Thread(s)
Active client connections 151 client connection(s)



Client Connections
Client connection from 10.100.4.213:14960 to 10.100.4.251:80
Host Header PERFBOXSQL6:80
GET request for
/BManage/lang-en/management/LMS_Training.asp?UserMode=0&Mode =4
Mapped To URL /BManage/lang-en/management/LMS_Training.asp
HTTP Version HTTP/1.1
SSL Request False
Time alive 0 day(s) 00:00:16
QueryString UserMode=0&Mode=4
Request mapped to
HTTP Request State HTR_READING_CLIENT_REQUEST
Native Request State NREQ_STATE_PROCESS




Client connection from 10.100.4.137:47468 to 10.100.4.240:80
Host Header PERFBOXSQL6:80
GET request for
/BManage/lang-en/management/LMS_LearnerHomePageData.asp?objP refArrayInd=16&arrIViewPrefInd=1&arrMaxRowsInd=0&iIndextemp= 1&iIndex=3&UserMode=0
Mapped To URL /BManage/lang-en/management/LMS_LearnerHomePageData.asp
HTTP Version HTTP/1.1
SSL Request False
Time alive 0 day(s) 00:00:16
QueryString
objPrefArrayInd=16&arrIViewPrefInd=1&arrMaxRowsInd=0&iIndext emp=1&iIndex=3&UserMode=0
Request mapped to
HTTP Request State HTR_READING_CLIENT_REQUEST
Native Request State NREQ_STATE_PROCESS




Client connection from 10.100.4.137:46858 to 10.100.4.240:80
Host Header PERFBOXSQL6:80
GET request for
/BManage/uploads/courses/wll_test/one_attempt_test_02/media/ 2b.jpg
HTTP Version HTTP/1.1
SSL Request False
Time alive 0 day(s) 00:00:16
QueryString
Request mapped to
HTTP Request State HTR_READING_CLIENT_REQUEST
Native Request State NREQ_STATE_PROCESS




Client connection from 10.100.4.213:15156 to 10.100.4.251:80
Host Header PERFBOXSQL6:80
POST request for /BManage/lang-default/SYS_login.asp
HTTP Version HTTP/1.1
SSL Request False
Time alive 0 day(s) 00:00:16
QueryString
Request mapped to
HTTP Request State HTR_READING_CLIENT_REQUEST
Native Request State NREQ_STATE_PROCESS




Client connection from 10.100.4.213:15155 to 10.100.4.251:80
Host Header PERFBOXSQL6:80
GET request for /BManage/lang-default/css/default_st700/SYS_base.css
HTTP Version HTTP/1.1
SSL Request False
Time alive 0 day(s) 00:00:16
QueryString
Request mapped to
HTTP Request State HTR_READING_CLIENT_REQUEST
Native Request State NREQ_STATE_PROCESS




Client connection from 10.100.4.213:15154 to 10.100.4.251:80
Host Header PERFBOXSQL6:80
GET request for /BManage/core/client_scripts/SYS_util.js
HTTP Version HTTP/1.1
SSL Request False
Time alive 0 day(s) 00:00:16
QueryString
Request mapped to
HTTP Request State HTR_READING_CLIENT_REQUEST
Native Request State NREQ_STATE_PROCESS




145 connection(s) waiting for the next request.
ASP report
Executing ASP requests 2 Request(s)
ASP templates cached 23 Template(s)
ASP template cache size 56.98 MBytes
Loaded ASP applications 1 Application(s)
ASP.DLL Version 6.0.3790.1830



ASP application report
ASP application metabase key /LM/W3SVC/1/Root/BManage
Physical Path C:\INETPUB\WWWROOT\BManage\
Virtual Root /BManage
Session Count 211 Session(s)
Request Count 3 Request(s)
Session Timeout 360 minutes(s)
Path to Global.asa C:\INETPUB\WWWROOT\BManage\GLOBAL.ASA
Server side script debugging enabled False
Client side script debugging enabled False
Out of process COM servers allowed False
Session state turned on True
Write buffering turned on True
Application restart enabled True
Parent paths enabled False
ASP Script error messages will be sent to browser True



Current ASP Request report
ASP request executing on thread 51
GET request for /BManage/lang-en/management/LMS_Training.asp
Request alive for 0 day(s) 00:00:16
QueryString UserMode=0&Mode=4
Request mapped to
c:\inetpub\wwwroot\BManage\lang-en\management\LMS_Training.a sp
ASP Application /LM/W3SVC/1/Root/BManage
ASP Template C:\INETPUB\WWWROOT\BManage\LANG-EN\MANAGEMENT\LMS_TRAINING.A SP




ASP request executing on thread 54
GET request for /BManage/lang-en/management/LMS_LearnerHomePageData.asp
Request alive for 0 day(s) 00:00:16
QueryString
objPrefArrayInd=16&arrIViewPrefInd=1&arrMaxRowsInd=0&iIndext emp=1&iIndex=3&UserMode=0
Request mapped to
c:\inetpub\wwwroot\BManage\lang-en\management\LMS_LearnerHom ePageData.asp
ASP Application /LM/W3SVC/1/Root/BManage
ASP Template
C:\INETPUB\WWWROOT\BManage\LANG-EN\MANAGEMENT\LMS_LEARNERHOM EPAGEDATA.ASP



Script Summary
Script Name Status Error Code Error Source Error Description Source Line
IISAnalysis.asp Completed
------------------------------------------------------------ ----------------------------

Re: CPU 100%, IIS using ASP application

am 13.07.2007 19:58:38 von patfilot

1) A thread that is in a 'receive' state has already sent the query to the
DB and is waiting for the DB to return. So, either the query is inefficient
or the DB is being saturated (needs more IO, etc.).

2) Perf testing can be tricky. Often what many people are actually doing is
launching a DoS attack. If you have 200 clients, you should see 0 idle
threads, but in this case only a couple of pages are actually executing.
Most likely the stress test looks like the same client all hitting the box -
this could be b/c they are using duplicate cookies or some other
serialization cause.

3) You are using ODBC to connect to SQL. OLEDB is a bit more performant -
may not make a big difference here. To switch, just change the connection
string.


Pat

"chetan" wrote in message
news:C5B4EC47-4A9D-45CF-BE14-6EDAA9D53E4D@microsoft.com...
> We are doing performance testing & CPU spikes on the webserver with 200 or
> more users accessing, took the log from debug diag & am posting it. Debug
> diag gives an issue with blocking but dosent elaborate on where it
> happens,
> as in the call stack comes up empty for the blocked threads, i checked
> this
> setting in debug diag-->Options & Settings-->Preferences, checked the
> "Include source and line information in analysis reports", debug symbols
> for
> Win2K3SP1 are on the server as well. Is there any other setting which i
> need
> to enable to get some more info on the problem. Threads 51 & 54 are
> blocking
> other ASP requests. Any help is appreciated ...
>
> Env: Win2k3 SP1, IIS 6, webserver updated with patches, 2GB Ram, 2CPU
>
> Debug diag report:
>
> ------------------------------------------------------------ ----------------------------
> Analysis Summary
> Type Description Recommendation
> Warning The following threads in
> w3wp.exe__BManage__PID__2616__Date__07_09_2007__Time_04_37_0 4PM__421__Manual
> Dump.dmp are waiting on data to be returned from another server via
> WinSock.
>
> The call to WinSock originated from dbnetlib!ConnectionRead+3b6
>
> ( 51 )
>
> 50.00% of executing ASP Requests blocked
>
> 1.15% of threads blocked
>
>
> Ensure that any remote server this application may be calling is
> functioning properly and there are no network issues between the two
> servers.
> If the problem continues, please contact the application vendor for
> further
> assistance
>
>
> Information The following threads in
> w3wp.exe__BManage__PID__2616__Date__07_09_2007__Time_04_37_0 4PM__421__Manual
> Dump.dmp are processing a client request and is/are not fully resolved.
> Further analysis of these threads is recommended in order to determine
> what
> may be blocking the request(s).
>
> ( 54 )
>
>
> 50.00% of executing ASP Requests blocked
>
> 1.15% of threads blocked
>
>
> Analysis Details
> Table Of Contents
>
> w3wp.exe__BManage__PID__2616__Date__07_09_2007__Time_04_37_0 4PM__421__Manual
> Dump.dmp
>
> Top 5 threads by CPU time
>
> Thread report
>
> COM+ STA ThreadPool Report
>
> Well-Known COM STA Threads Report
>
> HTTP Report
>
> ASP Report
>
> Report for
> w3wp.exe__BManage__PID__2616__Date__07_09_2007__Time_04_37_0 4PM__421__Manual
> Dump.dmp
> Type of Analysis Performed Hang Analysis
> Machine Name PERFBOXSQL6
> Operating System Windows Server 2003 Service Pack 1
> Number Of Processors 2
> Process ID 2616
> Process Image c:\WINDOWS\system32\inetsrv\w3wp.exe
> System Up-Time 10 day(s) 03:06:17
> Process Up-Time 0 day(s) 00:36:59
>
> Top 5 Threads by CPU time
> Note - Times include both user mode and kernel mode for each thread Thread
> ID: 56 Total CPU Time: 0 day(s) 00:00:59.937 Entry Point for
> Thread:
> msvcrt!_endthreadex+2f
> Thread ID: 69 Total CPU Time: 0 day(s) 00:00:56.671 Entry Point
> for
> Thread: msvcrt!_endthreadex+2f
> Thread ID: 18 Total CPU Time: 0 day(s) 00:00:55.0 Entry Point for
> Thread: msvcrt!_endthreadex+2f
> Thread ID: 31 Total CPU Time: 0 day(s) 00:00:52.640 Entry Point
> for
> Thread: msvcrt!_endthreadex+2f
> Thread ID: 63 Total CPU Time: 0 day(s) 00:00:51.671 Entry Point
> for
> Thread: msvcrt!_endthreadex+2f
>
> Thread report
>
>
> ...
> ...
>
> Thread 51 - System ID 840
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:02:26 PM
> Time spent in user mode 0 Days 0:0:39.453
> Time spent in kernel mode 0 Days 0:0:4.125
>
>
> This thread is waiting on data to be returned from another server via
> WinSock.
>
>
> The call to WinSock originated from dbnetlib!ConnectionRead+3b6
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForSingleObject+c
> mswsock!SockWaitForSingleObject+3a
> mswsock!WSPRecv+203
> ws2_32!WSARecv+77
> wsock32!recv+31
> dbnetlib!ConnectionRead+3b6
> sqlsrv32!getbytes+1fa
> sqlsrv32!ProcessTDSStream+204
> sqlsrv32!SQLExecDirectW+e46
> odbc32!SQLExecute+26f
> odbc32!SQLExecDirectW+3b
> msdasql!CUtlParam30::ExecuteWithParameters+37b
> msdasql!CImpICommandText::ExecuteHelper+1ee
> msdasql!CImpICommandText::Execute+673
> msado15!CConnection::Execute+9d
> msado15!_ExecuteAsync+19f
> msado15!ExecuteAsync+23
> msado15!CQuery::Execute+a5e
> msado15!CCommand::_Execute+153
> msado15!CCommand::Execute+4e2
> msado15!CCommand::Invoke+81e
> jscript!DllRegisterServer+45f6
> jscript!DllCanUnloadNow+f774
> jscript!DllGetClassObject+6421
> jscript!DllGetClassObject+63a2
> jscript!DllCanUnloadNow+6301
> jscript+5750
> jscript+4fe0
> jscript+6dd9
> jscript+38aa
> jscript+5079
> jscript+4fff
> jscript+6f34
> jscript+5750
> jscript+4fe0
> jscript+6dd9
> jscript+38aa
> jscript+5cb0
> jscript+4fff
> jscript!DllCanUnloadNow+6d2
> jscript+38aa
> jscript+5347
> jscript!DllGetClassObject+589
> jscript!DllGetClassObject+8b8
> asp!CActiveScriptEngine::TryCall+19
> asp!CActiveScriptEngine::Call+31
> asp!CallScriptFunctionOfEngine+5b
> asp!ExecuteRequest+17e
> asp!Execute+249
> asp!CHitObj::ViperAsyncCallback+3e8
> asp!CViperAsyncRequest::OnCall+92
> comsvcs!CSTAActivityWork::STAActivityWorkHelper+32
> ole32!EnterForCallback+c4
> ole32!SwitchForCallback+1a3
> ole32!PerformCallback+54
> ole32!CObjectContext::InternalContextCallback+159
> ole32!CObjectContext::DoCallback+1c
> comsvcs!CSTAActivityWork::DoWork+12d
> comsvcs!CSTAThread::DoWork+18
> comsvcs!CSTAThread::ProcessQueueWork+37
> comsvcs!CSTAThread::WorkerLoop+17c
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
> ASP request executing on thread 51
> GET request for /BManage/lang-en/management/LMS_Training.asp
> Request alive for 0 day(s) 00:00:16
> QueryString UserMode=0&Mode=4
> Request mapped to
> c:\inetpub\wwwroot\BManage\lang-en\management\LMS_Training.a sp
> ASP Application /LM/W3SVC/1/Root/BManage
> ASP Template
> C:\INETPUB\WWWROOT\BManage\LANG-EN\MANAGEMENT\LMS_TRAINING.A SP
>
>
> Script call stack for thread 51
>
> Function Scope Line Of Code Source File Line Number
>
>
> Thread 52 - System ID 3892
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:02:29 PM
> Time spent in user mode 0 Days 0:0:40.875
> Time spent in kernel mode 0 Days 0:0:6.406
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
> Thread 53 - System ID 2528
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:02:36 PM
> Time spent in user mode 0 Days 0:0:45.515
> Time spent in kernel mode 0 Days 0:0:4.921
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
> Thread 54 - System ID 2872
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:02:39 PM
> Time spent in user mode 0 Days 0:0:37.656
> Time spent in kernel mode 0 Days 0:0:5.656
>
> This thread is processing a client request and is/are not fully resolved.
> Further analysis of these threads is recommended in order to determine
> what
> may be blocking the request(s).
>
>
> Function Source
> ntdll!RtlEnterCriticalSection+6
> jscript+1590
> jscript+1604
> jscript!DllGetClassObject+4aa7
> jscript!DllGetClassObject+4ab6
> jscript!DllGetClassObject+5821
> asp!CScriptingNamespace::UnInit+93
> asp!ExecuteRequest+3c0
> asp!Execute+249
> asp!CHitObj::ViperAsyncCallback+3e8
> asp!CViperAsyncRequest::OnCall+92
> comsvcs!CSTAActivityWork::STAActivityWorkHelper+32
> ole32!EnterForCallback+c4
> ole32!SwitchForCallback+1a3
> ole32!PerformCallback+54
> ole32!CObjectContext::InternalContextCallback+159
> ole32!CObjectContext::DoCallback+1c
> comsvcs!CSTAActivityWork::DoWork+12d
> comsvcs!CSTAThread::DoWork+18
> comsvcs!CSTAThread::ProcessQueueWork+37
> comsvcs!CSTAThread::WorkerLoop+17c
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
> ASP request executing on thread 54
> GET request for /BManage/lang-en/management/LMS_LearnerHomePageData.asp
> Request alive for 0 day(s) 00:00:16
> QueryString
> objPrefArrayInd=16&arrIViewPrefInd=1&arrMaxRowsInd=0&iIndext emp=1&iIndex=3&UserMode=0
> Request mapped to
> c:\inetpub\wwwroot\BManage\lang-en\management\LMS_LearnerHom ePageData.asp
> ASP Application /LM/W3SVC/1/Root/BManage
> ASP Template
> C:\INETPUB\WWWROOT\BManage\LANG-EN\MANAGEMENT\LMS_LEARNERHOM EPAGEDATA.ASP
>
>
> Script call stack for thread 54
>
> Function Scope Line Of Code Source File Line Number
>
>
> Thread 55 - System ID 1092
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:02:46 PM
> Time spent in user mode 0 Days 0:0:24.421
> Time spent in kernel mode 0 Days 0:0:2.859
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 56 - System ID 1992
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:02:49 PM
> Time spent in user mode 0 Days 0:0:52.359
> Time spent in kernel mode 0 Days 0:0:7.578
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 57 - System ID 3456
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:02:56 PM
> Time spent in user mode 0 Days 0:0:36.171
> Time spent in kernel mode 0 Days 0:0:4.265
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 58 - System ID 484
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:02:59 PM
> Time spent in user mode 0 Days 0:0:27.843
> Time spent in kernel mode 0 Days 0:0:3.953
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 59 - System ID 2440
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:06 PM
> Time spent in user mode 0 Days 0:0:23.687
> Time spent in kernel mode 0 Days 0:0:2.578
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 60 - System ID 2744
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:09 PM
> Time spent in user mode 0 Days 0:0:36.328
> Time spent in kernel mode 0 Days 0:0:3.546
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 61 - System ID 2516
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:16 PM
> Time spent in user mode 0 Days 0:0:18.578
> Time spent in kernel mode 0 Days 0:0:2.656
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 62 - System ID 4076
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:19 PM
> Time spent in user mode 0 Days 0:0:41.796
> Time spent in kernel mode 0 Days 0:0:4.671
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 63 - System ID 1284
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:19 PM
> Time spent in user mode 0 Days 0:0:44.968
> Time spent in kernel mode 0 Days 0:0:6.703
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 64 - System ID 344
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:29 PM
> Time spent in user mode 0 Days 0:0:40.890
> Time spent in kernel mode 0 Days 0:0:6.62
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 65 - System ID 2928
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:36 PM
> Time spent in user mode 0 Days 0:0:39.734
> Time spent in kernel mode 0 Days 0:0:4.562
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 66 - System ID 1900
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:37 PM
> Time spent in user mode 0 Days 0:0:37.609
> Time spent in kernel mode 0 Days 0:0:7.234
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 67 - System ID 2604
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:42 PM
> Time spent in user mode 0 Days 0:0:25.796
> Time spent in kernel mode 0 Days 0:0:2.718
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 68 - System ID 704
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:49 PM
> Time spent in user mode 0 Days 0:0:40.296
> Time spent in kernel mode 0 Days 0:0:5.984
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 69 - System ID 3752
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:56 PM
> Time spent in user mode 0 Days 0:0:51.437
> Time spent in kernel mode 0 Days 0:0:5.234
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 70 - System ID 1212
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:03:59 PM
> Time spent in user mode 0 Days 0:0:33.484
> Time spent in kernel mode 0 Days 0:0:5.390
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 71 - System ID 2144
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:04:04 PM
> Time spent in user mode 0 Days 0:0:26.906
> Time spent in kernel mode 0 Days 0:0:3.187
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 72 - System ID 2384
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:04:09 PM
> Time spent in user mode 0 Days 0:0:26.906
> Time spent in kernel mode 0 Days 0:0:4.31
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 73 - System ID 1360
> Entry point msvcrt!_endthreadex+2f
> Create time 7/9/2007 4:04:17 PM
> Time spent in user mode 0 Days 0:0:31.140
> Time spent in kernel mode 0 Days 0:0:3.390
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtWaitForMultipleObjects+c
> kernel32!WaitForMultipleObjectsEx+11a
> user32!RealMsgWaitForMultipleObjectsEx+141
> user32!MsgWaitForMultipleObjects+1f
> comsvcs!CSTAThread::WorkerLoop+1e5
> msvcrt!_endthreadex+a3
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 74 - System ID 2276
> Entry point rpcrt4!ThreadStartRoutine
> Create time 7/9/2007 4:06:09 PM
> Time spent in user mode 0 Days 0:0:0.0
> Time spent in kernel mode 0 Days 0:0:0.0
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!ZwReplyWaitReceivePortEx+c
> rpcrt4!LRPC_ADDRESS::ReceiveLotsaCalls+198
> rpcrt4!RecvLotsaCallsWrapper+d
> rpcrt4!BaseCachedThreadRoutine+9d
> rpcrt4!ThreadStartRoutine+1b
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 75 - System ID 2984
> Entry point rpcrt4!ThreadStartRoutine
> Create time 7/9/2007 4:06:09 PM
> Time spent in user mode 0 Days 0:0:0.0
> Time spent in kernel mode 0 Days 0:0:0.0
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!ZwReplyWaitReceivePortEx+c
> rpcrt4!LRPC_ADDRESS::ReceiveLotsaCalls+198
> rpcrt4!RecvLotsaCallsWrapper+d
> rpcrt4!BaseCachedThreadRoutine+9d
> rpcrt4!ThreadStartRoutine+1b
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 76 - System ID 3928
> Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
> Create time 7/9/2007 4:11:12 PM
> Time spent in user mode 0 Days 0:0:0.390
> Time spent in kernel mode 0 Days 0:0:2.875
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtRemoveIoCompletion+c
> kernel32!GetQueuedCompletionStatus+29
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
> w3tp!THREAD_MANAGER::ThreadManagerThread+39
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 77 - System ID 3684
> Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
> Create time 7/9/2007 4:11:32 PM
> Time spent in user mode 0 Days 0:0:1.187
> Time spent in kernel mode 0 Days 0:0:3.984
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtRemoveIoCompletion+c
> kernel32!GetQueuedCompletionStatus+29
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
> w3tp!THREAD_MANAGER::ThreadManagerThread+39
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 78 - System ID 1524
> Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
> Create time 7/9/2007 4:14:53 PM
> Time spent in user mode 0 Days 0:0:0.703
> Time spent in kernel mode 0 Days 0:0:3.343
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtRemoveIoCompletion+c
> kernel32!GetQueuedCompletionStatus+29
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
> w3tp!THREAD_MANAGER::ThreadManagerThread+39
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 79 - System ID 228
> Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
> Create time 7/9/2007 4:19:01 PM
> Time spent in user mode 0 Days 0:0:1.234
> Time spent in kernel mode 0 Days 0:0:3.531
>
>
>
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtRemoveIoCompletion+c
> kernel32!GetQueuedCompletionStatus+29
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
> w3tp!THREAD_MANAGER::ThreadManagerThread+39
> kernel32!BaseThreadStart+34
>
>
>
>
>
>
>
> Thread 80 - System ID 3004
> Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
> Create time 7/9/2007 4:21:35 PM
> Time spent in user mode 0 Days 0:0:0.703
> Time spent in kernel mode 0 Days 0:0:3.31
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtRemoveIoCompletion+c
> kernel32!GetQueuedCompletionStatus+29
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
> w3tp!THREAD_MANAGER::ThreadManagerThread+39
> kernel32!BaseThreadStart+34
>
>
> Thread 81 - System ID 2864
> Entry point w3tp!THREAD_MANAGER::ThreadManagerThread
> Create time 7/9/2007 4:29:30 PM
> Time spent in user mode 0 Days 0:0:0.312
> Time spent in kernel mode 0 Days 0:0:2.296
>
> Function Source
> ntdll!KiFastSystemCallRet
> ntdll!NtRemoveIoCompletion+c
> kernel32!GetQueuedCompletionStatus+29
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+33
> w3tp!THREAD_POOL_DATA::ThreadPoolThread+24
> w3tp!THREAD_MANAGER::ThreadManagerThread+39
> kernel32!BaseThreadStart+34
>
> COM+ STA ThreadPool Report
> Max STA Threads 50
> Min STA Threads 4
> Current STA Threads 50
> g_activitiesPerThread 1
> EmulateMTSBehavior False
> STA Threads In-Call 2 (4%)
>
>
> All threads other simliair to Threads 52/53
>
> STA Thread Activity Count Thread Status Call Status
> 51 4 In-Call (ASP) waiting on data to be returned from another server
> via
> WinSock
> 52 4 Idle (N/A)
> 53 4 Idle (N/A)
> 54 4 In-Call (ASP) processing a client request and is/are not fully
> resolved. Further analysis of these threads is recommended in order to
> determine what may be blocking the request(s).
>
>
>
>
> Well-Known COM STA Threads Report
> STA Name Thread ID Thread Status Call Status
>
> Main STA 15 Idle (N/A)
> Apartment-threaded host for MTA clients 33 Idle (N/A)
>
>
>
> HTTP report
> IIS ATQ worker thread count 11 Thread(s)
> Available ATQ worker thread count 11 Thread(s)
> Active client connections 151 client connection(s)
>
>
>
> Client Connections
> Client connection from 10.100.4.213:14960 to 10.100.4.251:80
> Host Header PERFBOXSQL6:80
> GET request for
> /BManage/lang-en/management/LMS_Training.asp?UserMode=0&Mode =4
> Mapped To URL /BManage/lang-en/management/LMS_Training.asp
> HTTP Version HTTP/1.1
> SSL Request False
> Time alive 0 day(s) 00:00:16
> QueryString UserMode=0&Mode=4
> Request mapped to
> HTTP Request State HTR_READING_CLIENT_REQUEST
> Native Request State NREQ_STATE_PROCESS
>
>
>
>
> Client connection from 10.100.4.137:47468 to 10.100.4.240:80
> Host Header PERFBOXSQL6:80
> GET request for
> /BManage/lang-en/management/LMS_LearnerHomePageData.asp?objP refArrayInd=16&arrIViewPrefInd=1&arrMaxRowsInd=0&iIndextemp= 1&iIndex=3&UserMode=0
> Mapped To URL /BManage/lang-en/management/LMS_LearnerHomePageData.asp
> HTTP Version HTTP/1.1
> SSL Request False
> Time alive 0 day(s) 00:00:16
> QueryString
> objPrefArrayInd=16&arrIViewPrefInd=1&arrMaxRowsInd=0&iIndext emp=1&iIndex=3&UserMode=0
> Request mapped to
> HTTP Request State HTR_READING_CLIENT_REQUEST
> Native Request State NREQ_STATE_PROCESS
>
>
>
>
> Client connection from 10.100.4.137:46858 to 10.100.4.240:80
> Host Header PERFBOXSQL6:80
> GET request for
> /BManage/uploads/courses/wll_test/one_attempt_test_02/media/ 2b.jpg
> HTTP Version HTTP/1.1
> SSL Request False
> Time alive 0 day(s) 00:00:16
> QueryString
> Request mapped to
> HTTP Request State HTR_READING_CLIENT_REQUEST
> Native Request State NREQ_STATE_PROCESS
>
>
>
>
> Client connection from 10.100.4.213:15156 to 10.100.4.251:80
> Host Header PERFBOXSQL6:80
> POST request for /BManage/lang-default/SYS_login.asp
> HTTP Version HTTP/1.1
> SSL Request False
> Time alive 0 day(s) 00:00:16
> QueryString
> Request mapped to
> HTTP Request State HTR_READING_CLIENT_REQUEST
> Native Request State NREQ_STATE_PROCESS
>
>
>
>
> Client connection from 10.100.4.213:15155 to 10.100.4.251:80
> Host Header PERFBOXSQL6:80
> GET request for /BManage/lang-default/css/default_st700/SYS_base.css
> HTTP Version HTTP/1.1
> SSL Request False
> Time alive 0 day(s) 00:00:16
> QueryString
> Request mapped to
> HTTP Request State HTR_READING_CLIENT_REQUEST
> Native Request State NREQ_STATE_PROCESS
>
>
>
>
> Client connection from 10.100.4.213:15154 to 10.100.4.251:80
> Host Header PERFBOXSQL6:80
> GET request for /BManage/core/client_scripts/SYS_util.js
> HTTP Version HTTP/1.1
> SSL Request False
> Time alive 0 day(s) 00:00:16
> QueryString
> Request mapped to
> HTTP Request State HTR_READING_CLIENT_REQUEST
> Native Request State NREQ_STATE_PROCESS
>
>
>
>
> 145 connection(s) waiting for the next request.
> ASP report
> Executing ASP requests 2 Request(s)
> ASP templates cached 23 Template(s)
> ASP template cache size 56.98 MBytes
> Loaded ASP applications 1 Application(s)
> ASP.DLL Version 6.0.3790.1830
>
>
>
> ASP application report
> ASP application metabase key /LM/W3SVC/1/Root/BManage
> Physical Path C:\INETPUB\WWWROOT\BManage\
> Virtual Root /BManage
> Session Count 211 Session(s)
> Request Count 3 Request(s)
> Session Timeout 360 minutes(s)
> Path to Global.asa C:\INETPUB\WWWROOT\BManage\GLOBAL.ASA
> Server side script debugging enabled False
> Client side script debugging enabled False
> Out of process COM servers allowed False
> Session state turned on True
> Write buffering turned on True
> Application restart enabled True
> Parent paths enabled False
> ASP Script error messages will be sent to browser True
>
>
>
> Current ASP Request report
> ASP request executing on thread 51
> GET request for /BManage/lang-en/management/LMS_Training.asp
> Request alive for 0 day(s) 00:00:16
> QueryString UserMode=0&Mode=4
> Request mapped to
> c:\inetpub\wwwroot\BManage\lang-en\management\LMS_Training.a sp
> ASP Application /LM/W3SVC/1/Root/BManage
> ASP Template
> C:\INETPUB\WWWROOT\BManage\LANG-EN\MANAGEMENT\LMS_TRAINING.A SP
>
>
>
>
> ASP request executing on thread 54
> GET request for /BManage/lang-en/management/LMS_LearnerHomePageData.asp
> Request alive for 0 day(s) 00:00:16
> QueryString
> objPrefArrayInd=16&arrIViewPrefInd=1&arrMaxRowsInd=0&iIndext emp=1&iIndex=3&UserMode=0
> Request mapped to
> c:\inetpub\wwwroot\BManage\lang-en\management\LMS_LearnerHom ePageData.asp
> ASP Application /LM/W3SVC/1/Root/BManage
> ASP Template
> C:\INETPUB\WWWROOT\BManage\LANG-EN\MANAGEMENT\LMS_LEARNERHOM EPAGEDATA.ASP
>
>
>
> Script Summary
> Script Name Status Error Code Error Source Error Description Source Line
> IISAnalysis.asp Completed
> ------------------------------------------------------------ ----------------------------