eZ debug - slow runtime

Next topic

Author	Message
Stéphane Cloutier	Friday 16 July 2004 6:52:52 am I'm having speed issues. At first install of 3.4 and 3.4.1, a few weeks ago, my site runtime was between 0.4 to 0.6 secdonds. Now it is from 1.2 to 1.4 seconds. What could have caused this situation ? I cleared cache and recompile the templates. Still same results. Would this have an impact : strpos(): Empty delimiter. in /var/www/html/ez34/var/corporate/cache/template/compiled/node_list-e75210f10929c05db3aee13537a9f3de.php on line 30 eZ debug Timing: Jul 16 2004 09:38:27 Script start Timing: Jul 16 2004 09:38:27 Module start 'content' Timing: Jul 16 2004 09:38:27 Module end 'content' Warning: PHP Jul 16 2004 09:38:27 strpos(): Empty delimiter. in /var/www/html/ez34/var/corporate/cache/template/compiled/node_list-e75210f10929c05db3aee13537a9f3de.php on line 30 Timing: Jul 16 2004 09:38:27 End Timing points: Checkpoint Elapsed Rel. Elapsed Memory Rel. Memory Script start 0.0000 sec 0.3130 sec 0.0000KB 0.0000KB Module start 'content' 0.3130 sec 0.2214 sec 0.0000KB 0.0000KB Module end 'content' 0.5344 sec 0.8059 sec 0.0000KB 0.0000KB End 1.3402 sec 0.0000KB 0.0000KB Total runtime: 1.3418 sec Time accumulators: Accumulator Elapsed Percent Count Average ini_load Load cache 0.2476 sec 18.0855% 7 0.0354 sec Mysql Total Mysql_queries 0.0143 sec 1.0411% 2 0.0071 sec Looping result 0.0009 sec 0.0661% 2 0.0005 sec Template Total 0.7812 sec 57.1% 1 0.7812 sec Template load 0.1983 sec 14.4836% 1 0.1983 sec Template processing 0.5806 sec 42.4033% 1 0.5806 sec override Cache load 0.1416 sec 10.3407% 1 0.1416 sec Total script time: 1.3692 sec Qupanuaq - Snow bunting
Björn Dieding@xrow.de	Friday 16 July 2004 9:08:42 am /var/www/html/ez34/var/corporate/cache/template/compiled/node_list-e75210f10929c05db3aee13537a9f3de.php fix the error in the compiled template, just for testing Turn off debugging/logging. You might have a slow disk. Looking for a new job? http://www.xrow.com/xrow-GmbH/Jobs Looking for hosting? http://hostingezpublish.com ----------------------------------------------------------------------------- GMT +01:00 Hannover, Germany Web: http://www.xrow.com/
Stéphane Cloutier	Sunday 18 July 2004 7:54:57 pm I tried fixing the template warning. I even applied the template compile compression patch, but still no improvement. I don't know what's happening. When I installed ezp 3.4, it was working very fast. I applied 3.4.1 and was still very good. Everything changed only a few days ago. I`m running apache 1.3, mysql 4.1.0, php 4.3.4. It looks like the cache is being re-created every so often someone visits my site, although the content doesn't change that much. Is there a tool I could run on my server so I can watch what processes ezp involves and how much CPU/memory it uses? Qupanuaq - Snow bunting

Author

Message

Friday 16 July 2004 6:52:52 am

I'm having speed issues. At first install of 3.4 and 3.4.1, a few weeks ago, my site runtime was between 0.4 to 0.6 secdonds. Now it is from 1.2 to 1.4 seconds. What could have caused this situation ? I cleared cache and recompile the templates. Still same results.

Would this have an impact :
strpos(): Empty delimiter. in /var/www/html/ez34/var/corporate/cache/template/compiled/node_list-e75210f10929c05db3aee13537a9f3de.php on line 30

eZ debug
Timing: Jul 16 2004 09:38:27

Script start

Timing: Jul 16 2004 09:38:27

Module start 'content'

Timing: Jul 16 2004 09:38:27

Module end 'content'

Warning: PHP Jul 16 2004 09:38:27

strpos(): Empty delimiter. in /var/www/html/ez34/var/corporate/cache/template/compiled/node_list-e75210f10929c05db3aee13537a9f3de.php on line 30

Timing: Jul 16 2004 09:38:27

End

Timing points:
Checkpoint Elapsed Rel. Elapsed Memory Rel. Memory
Script start 0.0000 sec 0.3130 sec 0.0000KB 0.0000KB
Module start 'content' 0.3130 sec 0.2214 sec 0.0000KB 0.0000KB
Module end 'content' 0.5344 sec 0.8059 sec 0.0000KB 0.0000KB
End 1.3402 sec 0.0000KB 0.0000KB
Total runtime: 1.3418 sec

Time accumulators:
Accumulator Elapsed Percent Count Average
ini_load
Load cache 0.2476 sec 18.0855% 7 0.0354 sec
Mysql Total
Mysql_queries 0.0143 sec 1.0411% 2 0.0071 sec
Looping result 0.0009 sec 0.0661% 2 0.0005 sec
Template Total 0.7812 sec 57.1% 1 0.7812 sec
Template load 0.1983 sec 14.4836% 1 0.1983 sec
Template processing 0.5806 sec 42.4033% 1 0.5806 sec
override
Cache load 0.1416 sec 10.3407% 1 0.1416 sec
Total script time: 1.3692 sec

Qupanuaq - Snow bunting

Björn Dieding@xrow.de

Friday 16 July 2004 9:08:42 am

/var/www/html/ez34/var/corporate/cache/template/compiled/node_list-e75210f10929c05db3aee13537a9f3de.php
fix the error in the compiled template, just for testing

Turn off debugging/logging.
You might have a slow disk.

Looking for a new job? http://www.xrow.com/xrow-GmbH/Jobs
Looking for hosting? http://hostingezpublish.com
-----------------------------------------------------------------------------
GMT +01:00 Hannover, Germany
Web: http://www.xrow.com/

Stéphane Cloutier

Sunday 18 July 2004 7:54:57 pm

I tried fixing the template warning. I even applied the template compile compression patch, but still no improvement. I don't know what's happening. When I installed ezp 3.4, it was working very fast. I applied 3.4.1 and was still very good. Everything changed only a few days ago. I`m running apache 1.3, mysql 4.1.0, php 4.3.4.

It looks like the cache is being re-created every so often someone visits my site, although the content doesn't change that much.

Is there a tool I could run on my server so I can watch what processes ezp involves and how much CPU/memory it uses?

Qupanuaq - Snow bunting

eZ debug

Timing:	Jan 18 2025 22:37:32
Script start
Timing:	Jan 18 2025 22:37:32
Module start 'layout'
Timing:	Jan 18 2025 22:37:32
Module start 'content'
Timing:	Jan 18 2025 22:37:33
Module end 'content'
Timing:	Jan 18 2025 22:37:33
Script end

Main resources:

Total runtime	0.7630 sec
Peak memory usage	4,096.0000 KB
Database Queries	57

Timing points:

Checkpoint	Start (sec)	Duration (sec)	Memory at start (KB)	Memory used (KB)
Script start	0.0000	0.0056	587.9141	152.6250
Module start 'layout'	0.0056	0.0032	740.5391	39.4609
Module start 'content'	0.0088	0.7526	780.0000	552.8125
Module end 'content'	0.7614	0.0016	1,332.8125	12.1719
Script end	0.7630		1,344.9844

Time accumulators:

Accumulator	Duration (sec)	Duration (%)	Count	Average (sec)
Ini load
Load cache	0.0035	0.4644	16	0.0002
Check MTime	0.0015	0.1987	16	0.0001
Mysql Total
Database connection	0.0008	0.1056	1	0.0008
Mysqli_queries	0.7013	91.9133	57	0.0123
Looping result	0.0007	0.0855	55	0.0000
Template Total	0.7243	94.9	2	0.3621
Template load	0.0022	0.2885	2	0.0011
Template processing	0.7220	94.6271	2	0.3610
Template load and register function	0.0001	0.0146	1	0.0001
states
state_id_array	0.0014	0.1797	1	0.0014
state_identifier_array	0.0017	0.2250	2	0.0009
Override
Cache load	0.0019	0.2539	50	0.0000
Sytem overhead
Fetch class attribute can translate value	0.0007	0.0967	2	0.0004
Fetch class attribute name	0.0018	0.2332	5	0.0004
XML
Image XML parsing	0.0012	0.1526	2	0.0006
class_abstraction
Instantiating content class attribute	0.0000	0.0016	6	0.0000
General
dbfile	0.0010	0.1316	22	0.0000
String conversion	0.0000	0.0013	4	0.0000
Note: percentages do not add up to 100% because some accumulators overlap

Templates used to render the page:

Usage	Requested template	Template	Template loaded
1	node/view/full.tpl	full/forum_topic.tpl	extension/sevenx/design/simple/override/templates/full/forum_topic.tpl
3	content/datatype/view/ezimage.tpl	<No override>	extension/sevenx/design/simple/templates/content/datatype/view/ezimage.tpl
3	content/datatype/view/ezxmltext.tpl	<No override>	extension/community_design/design/suncana/templates/content/datatype/view/ezxmltext.tpl
8	content/datatype/view/ezxmltags/paragraph.tpl	<No override>	extension/ezwebin/design/ezwebin/templates/content/datatype/view/ezxmltags/paragraph.tpl
6	content/datatype/view/ezxmltags/line.tpl	<No override>	design/standard/templates/content/datatype/view/ezxmltags/line.tpl
1	print_pagelayout.tpl	<No override>	extension/community/design/community/templates/print_pagelayout.tpl
Number of times templates used: 22 Number of unique templates used: 6

Time used to render debug report: 0.0001 secs