I don't know much about profiling, but I would guess that when one method calls another the time spent in the 2nd method is credited to both methods. That double counting gives totals of more than 100%.

On 20 April 2010 13:51, Sam.Sexton@thomsonreuters.com wrote:

Hi - I've just implemented profiling on 1.13.0 to see where the time goes and I'm just a little confused:

Here's a clip of what profileinfo.php shows me:

-total     100%

MediaWiki::initialize [+]                62.14%

Parser::parse [+]              60.5%

MediaWiki::performAction          60.35%

Article::view       58.87%

Parser::internalParse     46.76%

Parser::replaceVariables               30.62%

Parser::braceSubstitution [+]     18.86%

MediaWiki::finalCleanup              16.74%

OutputPage::output       16.05%

Output-skin        15.96%

SkinTemplate::outputPage [+]   15.95%

AutoLoader::autoload   15.71%

Now, perhaps I'm out of touch and this is a quantum mechanics matter that's beyond me, but in the olden days, there were only 100 percentage points - but  the first two entries alone claim to be taking >120% of the time - so I'd be grateful if this could be explained to me, please!

Thanks,

/Sam

Sam Sexton Provisioning Team Leader

Thomson Reuters

sam.sexton@thomsonreuters.com thomsonreuters.com

This email was sent to you by Thomson Reuters, the global news and information company. Any views expressed in this message are those of the individual sender, except where the sender specifically states them to be the views of Thomson Reuters.

---

MediaWiki-l mailing list MediaWiki-l@lists.wikimedia.org https://lists.wikimedia.org/mailman/listinfo/mediawiki-l

I said:

Hi - I've just implemented profiling on 1.13.0 to see where the time

goes and I'm just a little confused:

Here's a clip of what profileinfo.php shows me:

-total 100%

MediaWiki::initialize [+] 62.14%

Parser::parse [+] 60.5%

MediaWiki::performAction 60.35%

Article::view 58.87%

Parser::internalParse 46.76%

Parser::replaceVariables 30.62%

Parser::braceSubstitution [+] 18.86%

MediaWiki::finalCleanup 16.74%

OutputPage::output 16.05%

Output-skin 15.96%

SkinTemplate::outputPage [+] 15.95%

AutoLoader::autoload 15.71%

Now, perhaps I'm out of touch and this is a quantum mechanics matter

that's beyond me, but in the olden days, there were only 100 percentage points - but the first two entries alone claim to be taking >120% of the time - so I'd be grateful if this could be explained to me, please!

Thanks,

/Sam

============

What would also help is a sample of what a well-performing system would show - this stuff ain't intuitive - to me, at least!

This email was sent to you by Thomson Reuters, the global news and information company. Any views expressed in this message are those of the individual sender, except where the sender specifically states them to be the views of Thomson Reuters.