3.8: 10 additional seconds delay on any page of my new site

Author Message

Massimiliano Bariola

Wednesday 28 June 2006 5:08:50 am

Hi,

I just installed 3.8.1 version 15972 using the install wizard. The webserver is dedicated to this site, while DB is shared. Unfortunately, even after the caches have been generated, the system runs awfully (and strangely) slow.

Debug Output on pages, both admin and public siteaccess, shows the following:

Timing points:
Checkpoint	Elapsed	Rel. Elapsed	Memory	Rel. Memory
Module start 'content'	0.0000 sec	0.0244 sec	881.8125KB	345.1641KB
Module end 'content'	0.0244 sec	0.1240 sec	1,226.9766KB	741.1563KB
End	0.1485 sec	 	1,968.1328KB	0.7238KB
Total runtime:	0.2372 sec	
Time accumulators:
 Accumulator	 Elapsed	 Percent	 Count	 Average
ini_load				
Load cache	0.0081 sec	0.0782%	10	0.0008 sec
FindInputFiles	0.0049 sec	0.0480%	10	0.0005 sec
Mysql Total				
Mysql_queries	0.0013 sec	0.0128%	1	0.0013 sec
Looping result	0.0001 sec	0.0005%	1	0.0001 sec
TS translator				
TS init	0.0308 sec	0.2988%	7	0.0044 sec
TS cache load	0.0204 sec	0.1978%	7	0.0029 sec
TS context load	0.0167 sec	0.1622%	7	0.0024 sec
Template Total	0.2069 sec	2.0%	2	0.1035 sec
Template load	0.0132 sec	0.1281%	2	0.0066 sec
Template processing	0.1933 sec	1.8777%	2	0.0966 sec
override				
Cache load	0.0099 sec	0.0965%	2	0.0050 sec
General				
dbfile	0.0012 sec	0.0118%	11	0.0001 sec
Total script time:	10.2931 sec	

thus there is a huge difference between total runtime (0.2) and total script time (10.2)

can anyone help me out?

info on the system:

eZp 3.8.1

primary language IT

additional languages en-EN

running eAccelerator 0.9.4 (detected by eZp)

PHP 4.4.2 with extensions: xml, tokenizer, standard, session, posix, overload, mysql, mbstring, gd, curl, ctype, zlib, pcre, apache, eAccelerator

180 seconds max runtime, script mem limit 384 MB

DB: mySQL, InnoDB, all tables InnoDB, uft8_general_ci, unicode support enabled during eZp setup wizard

server : Xeon dual processor, 3GHz, 1Gb RAM (I know it's too little, a mistake on shipping, but the machine is not swapping and there are a handful of MB free)

Tim Dickinson

Wednesday 28 June 2006 5:23:14 am

That is pretty strange.

Just to be doubley sure - you have all the cache options enabled and debug turned off and PHP isn't in safe mode or anything like that?

Tim

http://www.AdBalance.com - Ad network news and reviews
http://www.DevPublisher.com - Website publisher guides, tools and news
http://www.AudioMelody.com - Make better music

Bård Farstad

Wednesday 28 June 2006 5:57:28 am

Massimiliano, your actual script execution time is 0.1485 seconds. Counting the time it takes to empty the output buffer for transfering the contnet to the client you have 0.23 seconds.

It looks like the time is spent on rendering the debug output. Do you use debug output in a popup window? Can you try to disable the debug output and benchmark the page?

--bård

Documentation: http://ez.no/doc

Massimiliano Bariola

Wednesday 28 June 2006 6:32:47 am

Tim and Baard (sorry for not having norwegian keyboard),
@Tim: safe mode is off, here I copy my site.ini settings concerning caching

admin site:

[RegionalSettings]
Locale=ita-IT
ContentObjectLocale=ita-IT
ShowUntranslatedObjects=enabled
SiteLanguageList[]=ita-IT
TextTranslation=enabled

[FileSettings]
VarDir=var/plain_site

[ContentSettings]
CachedViewPreferences[full]=admin_navigation_content=0;admin_navigation_details=0;admin_navigation_languages=0;admin_navigation_locations=0;admin_navigation_relations=0;admin_navigation_roles=0;admin_navigation_policies=0;admin_navigation_content=0;admin_navigation_translations=0;admin_children_viewmode=list;admin_list_limit=1;admin_edit_show_locations=0;admin_leftmenu_width=10;admin_url_list_limit=10;admin_url_view_limit=10;admin_section_list_limit=1;admin_orderlist_sortfield=user_name;admin_orderlist_sortorder=desc;admin_search_stats_limit=1;admin_treemenu=1;admin_bookmarkmenu=1;admin_left_menu_width=13
TranslationList=
#ViewCaching=disabled
ViewCaching=enabled
#StaticCache=enabled
StaticCache=disabled

[TemplateSettings]
TemplateCache=enabled
#TemplateCache=disabled
TemplateCompile=enabled
#TemplateCompile=disabled
#ShowXHTMLCode=enabled
#Debug=enabled
Debug=disabled
ShowUsedTemplates=enabled
#ShowUsedTemplates=disabled

public site:

[RegionalSettings]
Locale=ita-IT
ContentObjectLocale=ita-IT
SiteLanguageList[]=ita-IT
TextTranslation=enabled

[FileSettings]
VarDir=var/plain_site

[ContentSettings]
TranslationList=

[TemplateSettings]
TemplateCache=enabled
#TemplateCache=disabled
TemplateCompile=enabled
#TemplateCompile=disabled
#ShowXHTMLCode=enabled
#Debug=enabled
Debug=disabled
ShowUsedTemplates=enabled
#ShowUsedTemplates=disabled
DevelopmentMode=enabled

@Baard: I usually debug inline and those are the results from the inline version. I tried to switch to popup, just to check, and it takes even longer, plus I get a kernel 20 error in the popup, saying "the module "var" which you requested was not found" instead of the debug output.
Trying to turn completely off debug strangely makes the public page load take a whopping 39 seconds to generate (taken by hand, I'll restart firefox now and use a specific tool for better checking, then I'll add more precise figures).

Massimiliano Bariola

Wednesday 28 June 2006 7:02:29 am

Ok here are the more accurate figures, taken with LORI FF extension. values are : click-to-first-byte sent back, and click-to-end-of-page-sending

home page public siteaccess: 10.344, 31.15 seconds
home page public siteaccess reload: 10.344, 31.16 seconds
home page public siteaccess reload: 10.328, 30.969 seconds
home page admin siteaccess: 10.313, 10.500 seconds,
21 seconds from successful login to content tree display 20.780, 20.781 secs
11 seconds from there to settings tab 10.750, 11.93 secs
11 seconds from settings tab to again content tree view 10.687, 11.218 seconds

Łukasz Serwatka

Wednesday 28 June 2006 12:01:49 pm

For better testing try to disable eAccelerator then you should get more "real" results in the debug output.

Where is your database server located? Is it on localhost or on independent machine? If on external server just for check try with

[DatabaseSettings]
UsePersistentConnection=enabled

Do you have any RSS imports via RSS feed template operator or fetching data from external sites?

BTW, without access to your machine it is difficult to find what cause the problem. Using support is good idea if still no success.

Personal website -> http://serwatka.net
Blog (about eZ Publish) -> http://serwatka.net/blog

Massimiliano Bariola

Friday 30 June 2006 2:55:27 am

Lukasz,

great, that made my day, now page serving time is back to normality. Now I guess my sysadmin will need to investigate why on this peculiar machine I need to keep persistent connections on for it to work well. I have done my part ;-)

Thank you again,

Max

Łukasz Serwatka

Friday 30 June 2006 5:23:31 am

Great ;) You are welcome Max! Share your information with other eZ users, if you will find someone with similar problem don't hesitate to post an answer ;)

Let us know what sys admin found.

Personal website -> http://serwatka.net
Blog (about eZ Publish) -> http://serwatka.net/blog

Massimiliano Bariola

Friday 30 June 2006 5:51:50 am

Lukasz,

I'll surely do so if I find someone in a similar predicament :-) and as soon as my sysadmin looks into this, I'll post the fix he did.

Ciao and have a nice weekend,

Max

Powered by eZ Publish™ CMS Open Source Web Content Management. Copyright © 1999-2014 eZ Systems AS (except where otherwise noted). All rights reserved.