New subject: [Mediawiki-l] [Upgrade 1.9.3 -> 1.11.0] Slow performance

1 Oct 2007

Hi all,

I just upgraded my mediawiki installation from version 1.9.3 to 1.11.0. 
The upgrade went smoothly (including the maintenance/update.php part) but 
now page delivery from the server is incredibly slow (about 40s for each 
page). 

For testing purposes I also did a fresh install of version 1.11.0 using a 
copy of the original database. This one is running smoothly (2s per page) 
although the LocalSettings.php are identical (except the necessary 
differences; checked with diff). 

I turned on profiling and it seems that the server is waiting for a 
memcached-server (see below):

--------------------------------------------------------------------------------------------------------------------------
Start request
GET /wiki/index.php5/Hauptseite
Accept: */*
Referer: http://XXXXX/er_page/
Accept-Language: de
Accept-Encoding: gzip, deflate
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; SV1; .NET 
CLR 1.1.4322; .NET CLR 2.0.50727)
Host: dephd0080.rockwellcollins.com
Connection: Keep-Alive
Cookie: wikidbUserID=fDSY6tzj5mRmRSbScc7kkfb5MVNtrdUpbtn-YKamQeI.; 
wikidbUserName=BU6md5v7QoBjaPWgjiDFKQ6zUFA6qcnb5kDAN4bLZDo.; 
wikidbToken=WCECG_8rs-DpCfE9biU2YMIQQNwjsrbzvdiSwJTLAaFHDMZ-wNU4NaARwKVGXgY-; 
wikidb_session=Ly7ZvAQ1kMeToIurVQUAbzTyN6aG7WmzRCC7RkbP7VY9AztrT_pPCzTpmDsuTMwQ

Main cache: FakeMemCachedClient
Message cache: MediaWikiBagOStuff
Parser cache: MediaWikiBagOStuff
Unstubbing $wgParser on call of $wgParser->setHook from efSubpageList
Fully initialised
Unstubbing $wgContLang on call of $wgContLang->checkTitleEncoding from 
WebRequest::getGPCVal
Language::loadLocalisation(): got localisation for de from source
Language::loadLocalisation(): got localisation for en from source
Unstubbing $wgUser on call of $wgUser->isAllowed from Title::userCanRead
Cache miss for user 14
Unstubbing $wgLoadBalancer on call of $wgLoadBalancer->getConnection from 
wfGetDB
Logged in from session
Unstubbing $wgOut on call of $wgOut->setSquidMaxage from 
MediaWiki::performAction
Unstubbing $wgLang on call of $wgLang->getCode from 
User::getPageRenderingHash
OutputPage::checkLastModified: client did not send If-Modified-Since 
header
Article::tryFileCache(): not cacheable
Article::view using parser cache: yes
Trying parser cache wikidb:pcache:idhash:1-0!1!0!!de!2
Parser cache miss.
Unstubbing $wgMessageCache on call of $wgMessageCache->getTransform from 
wfMsgGetKey
MessageCache::load(): cache is empty
MessageCache::load(): loading all messages from DB
MemCached set error in MessageCache: restart memcached server!
Saved in parser cache with key wikidb:pcache:idhash:1-0!1!0!!de!2 and 
timestamp 20071001120757
OutputPage::sendCacheControl: private caching; Mon, 01 Oct 2007 11:55:50 
GMT **
20071001120757  39.736  /wiki/index.php5/Hauptseite
Request ended normally
------------------------------------------------------------------------------------------------------------------------------------

Debug output from the new and clean installation shows no MemCached 
errors:

---------------------------------------------------------------------------------------------------------------------------------
Start request
GET /php/mediawiki-1.11.0rc1/index.php5/Proteus/WCA
Host: dephd0080
User-Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1; de; rv:1.8.1.7) 
Gecko/20070914 Firefox/2.0.0.7
Accept: 
text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
Accept-Language: de-de,de;q=0.8,en-us;q=0.5,en;q=0.3
Accept-Encoding: gzip,deflate
Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
Keep-Alive: 300
Connection: keep-alive
Referer: 
http://XXX/php/mediawiki-1.11.0rc1/index.php5/E3000/6%2C8V_Z-Diode
Cookie: 
wikidbToken=nDjM5UyIQUUrFVQDEvMVp378QyY9dVNkGXwLioUKkJ82A9PBsrJwRAZlYN_KkGCL; 
wikidbUserName=i4l5AWOwFjyqUIzh1KW-WCmn0vNuECv_8dkZvn9lkWE.; 
wikidbUserID=kbm8jn7GNRYpai9dEy3RqN6bZJcItgKdkOjHvMhKjsc.; 
wikidb_session=R4qkwULU20-Ez1DiDK6vt7fkZ1jkl1zHlJcpJJ3IzIXb9UcGbKYUVBe67S8w5d40

Main cache: FakeMemCachedClient
Message cache: MediaWikiBagOStuff
Parser cache: MediaWikiBagOStuff
Unstubbing $wgParser on call of $wgParser->setHook from efSubpageList
Fully initialised
Unstubbing $wgContLang on call of $wgContLang->checkTitleEncoding from 
WebRequest::getGPCVal
Language::loadLocalisation(): got localisation for de from source
Language::loadLocalisation(): got localisation for en from source
Unstubbing $wgUser on call of $wgUser->isAllowed from Title::userCanRead
Unstubbing $wgLoadBalancer on call of $wgLoadBalancer->getConnection from 
wfGetDB
IP: XXX.XXX.XXX.XXX
Unstubbing $wgOut on call of $wgOut->setSquidMaxage from 
MediaWiki::performAction
Unstubbing $wgLang on call of $wgLang->getCode from 
User::getPageRenderingHash
Unstubbing $wgMessageCache on call of $wgMessageCache->loadAllMessages 
from User::getGroupName
MessageCache::load(): got from global cache
OutputPage::checkLastModified: client did not send If-Modified-Since 
header
Article::tryFileCache(): not cacheable
Article::view using parser cache: yes
Trying parser cache wikidb_TEST:pcache:idhash:1786-0!1!0!!de!2!edit=0
Parser cache miss.
Saved in parser cache with key 
wikidb_TEST:pcache:idhash:1786-0!1!0!!de!2!edit=0 and timestamp 
20071001105101
OutputPage::sendCacheControl: private caching; Mon, 01 Oct 2007 10:04:50 
GMT **
Request ended normally
-------------------------------------------------------------------------------------------------------------------------------

I don't know why MemCache shows up in the debug output, since it is 
disabled in my LocalSettings.php:

---------------------------------------------------------------------
## Shared memory settings
$wgMainCacheType = CACHE_NONE;
$wgMemCachedServers = array();
---------------------------------------------------------------------

Any advices how to solve this?!

Thanks in advance,

        Arnd