the application.cfm, or cached template, is called for every
template in the request. i'd say that the starttickcount was
initialized at the beginning of the last template called in the
request; the onrequestend.cfm template.
putting the starttickcount at the top the first template called
should get an accurate time, i expect within 10ms or so anyway.
dave swift
[EMAIL PROTECTED]
icq 1863609
aim swiftdave
-----Original Message-----
From: Michael Evans [mailto:[EMAIL PROTECTED]]
Sent: Thursday, February 22, 2001 12:21 PM
To: CF-Server
Subject: Performance analysis
Hi all,
Here's a weird one: as I understand it, if I have startTickCount =
GetTickCount() as the first line of Application.cfm, and endTickCount at the
end of OnRequestEnd.cfm, and I take the difference between them, I should
have the total page execution time in milliseconds, right?
Can anyone explain this sort of behaviour then:
Total page timing: 125 ms
----------------------------------------------------------------------------
----
Execution Time
1266 milliseconds
47 ms D:\CFUSION\CUSTOMTAGS\LESSWHITESPACE.CFM
15 ms D:\PROGRAM
FILES\ALLAIRE\SPECTRA\CUSTOMTAGS\SYSTEM\COAPI\UTILS\CFA_GENERATEDCONTENTCACH
E.CFM
0 ms D:\PROGRAM
FILES\ALLAIRE\SPECTRA\CUSTOMTAGS\SYSTEM\COAPI\UTILS\CFA_SERVERSETTINGSGET.CF
M
0 ms D:\PROGRAM
FILES\ALLAIRE\SPECTRA\CUSTOMTAGS\SYSTEM\COAPI\UTILS\CFA_STRUCTGET.CFM
0 ms D:\PROGRAM
FILES\ALLAIRE\SPECTRA\CUSTOMTAGS\SYSTEM\TIER1\APPLICATION\CFA_APPLICATIONINI
TIALIZE.CFM
16 ms D:\PROGRAM
FILES\ALLAIRE\SPECTRA\CUSTOMTAGS\SYSTEM\TIER1\SITEMODELING\CFA_PAGE.CFM
47 ms D:\PROGRAM
FILES\ALLAIRE\SPECTRA\CUSTOMTAGS\SYSTEM\TIER1\UTILS\CFA_GLOBALSETTINGS.CFM
0 ms E:\INETPUB\WWWROOT\APPLICATION.CFM
16 ms E:\INETPUB\WWWROOT\FEATURES\INDEX.CFM
15 ms E:\INETPUB\WWWROOT\FORUMS\GLOBAL.CFM
0 ms E:\INETPUB\WWWROOT\INCLUDES\COMMON\BUTTONADCODE.CFM
1110 ms E:\INETPUB\WWWROOT\INCLUDES\COMMON\FOOTER.CFM
0 ms E:\INETPUB\WWWROOT\INCLUDES\COMMON\MARGIN_R_AD.CFM
0 ms E:\INETPUB\WWWROOT\INCLUDES\COMMON\MARGIN_R_SEARCHSTORE.CFM
0 ms E:\INETPUB\WWWROOT\INCLUDES\COMMON\SET_TEMP_SECTION.CFM
0 ms E:\INETPUB\WWWROOT\INCLUDES\STORE\PROCESS_BASKET_STATE.CFM
0 ms E:\INETPUB\WWWROOT\STICKY.CFM
0 ms E:\INETPUB\WWWROOT\ONREQUESTEND.CFM
0 ms STARTUP, PARSING, & SHUTDOWN
In this example the total page timing shown is the difference between the
tick counts as described above. So why does this not match CF's execution
time in the debug trace? Either something horrendous is happening in
footer.cfm, but somehow it's not reflected in the difference of the tick
counts, or the CF timing is all wrong... does anyone understand how these
numbers are generated?
Also, if client vars are being stored in an external datasource, where does
the time taken to access the variables get taken into account?
Any help would be great!
Cheers,
Mike
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
Structure your ColdFusion code with Fusebox. Get the official book at
http://www.fusionauthority.com/bkinfo.cfm
------------------------------------------------------------------------------
To unsubscribe, send a message to [EMAIL PROTECTED] with
'unsubscribe' in the body or visit the list page at www.houseoffusion.com
