Forums / General / admin interface / content publishing very slow

admin interface / content publishing very slow

Author Message

Andrew Kelly

Wednesday 26 October 2005 5:52:31 am

Hi all,
forgive me if the answer to my problem is in the forums somewhere already. I haven't been able to find it if it is.

I have a rather fresh installation and have only recently begun adding content. At the moment the content consists entirely of instances of the folder class, basically just to sketch out a navigation structure. There are less than 300 nodes in place, and the deepest point in the tree is only 4 levels down. The host is a dual proc 1,5 Ghz with 2GB of RAM.
Publishing a new or edited content item (simple folder) has been taking increasingly longer, and now takes between 18 and 21 seconds to complete.

I cannot believe this is correct behavior, becuase at this rate it'll take several minutes to add an item soon.

What is going on, and how can I make it go away?
Can anybody help?

Andy

Kåre Køhler Høvik

Thursday 27 October 2005 12:00:42 am

Hi

Please post some more info about OS, DB, PHP, PHP accelerator, etc. ( with version numbers )

Kåre Høvik

Andrew Kelly

Thursday 27 October 2005 12:23:05 am

Yes, of course, sorry about that.

As I said, the hardware is rather tough enough, and bandwidth is massive. For right now, the machine doesn't do anything other than play secondary DNS for several unused domains. Basically idle, except the devel on the site I'm talking about.

Debian (Stable) Linux
PostgreSQL, 7.4.7
Apache 1.3.33
PHP 4.3.10(-16)
accelerator APC 3.0.6

I really appreciate any help you can provide and offer my great thanks in advance.

Andy

Kåre Køhler Høvik

Thursday 27 October 2005 12:39:34 am

Hi

Try to put this php script on your server : http://ez.no/content/download/101238/438979/file/apc.php , press the "View Host Stats", and check how much memory is available, and cache hit/miss ratio.

Kåre Høvik

Andrew Kelly

Thursday 27 October 2005 12:59:29 am

If I'm reading things correctly, Memory usage is:
Free: 90.0 MBytes (100.0%)
Used: 8.7 KBytes (0.0%)

and the hit and miss details are:
Hits: 1 (0.0%)
Misses: 4935 (100.0%)

Looks ugly, huh?

Andy

Andrew Kelly

Monday 31 October 2005 12:57:53 am

Hmm.. Have I just given an answer that means there's no help for me?
I really don't know what to infer from silence; can I please have some kind of follow-up response?

Thanks.

Andy

Kåre Køhler Høvik

Monday 31 October 2005 1:15:13 am

Hi

Your APC does not seem to be working. If it was, it should have been using at least 30 MB for eZ publish.

I suggest you try to either reinstall it, or try another PHP accellerator.

Kåre Høvik

Xavier Dutoit

Monday 31 October 2005 2:50:16 am

Switch on the debug mode, see if you have error messages and post the timing result. It shouldn't be that long at all.

X+

http://www.sydesy.com

Andrew Kelly

Monday 31 October 2005 3:53:13 am

Please forgive the length of this reply.

Kåre:
Thanks for the tip. I admit I've never used APC before. My only experience with an accelerator has been with ioncube running on RedHat 7.3 (currently in full production, with an uptime over 600 days, by the way).
I only installed APC because it seems to be the one recommended by eZ staff. I'll see if I can find any obvious mistakes.

Xavier:
I've turned on debugging in the admin interface, and performed 3 simple tasks:
-- Logged in
-- selected 'edit' for a content item (simple folder at top of tree)
-- made a spelling change and selected 'send for publishing'

The timing information follows in code blocks.

LOGIN:
[Actual elapsed time before continuing was possible: 11.5 seconds]

Timing points:
Checkpoint              Elapsed Rel.    Elapsed         Memory          Rel. Memory
Module start 'content'  0.0000 sec      0.5653 sec      5,841.4844KB    4,411.3203KB
Module end 'content'    0.5653 sec      0.2060 sec      10,252.8047KB   1,081.4688KB
End                     0.7713 sec                      11,334.2734KB   0.0010KB
Total runtime:  2.0248 sec

Time accumulators:
Accumulator     Elapsed         Percent         Count   Average
ini_load
Load cache      0.0344 sec      1.5829%         16      0.0022 sec
Template Total  0.7218 sec      33.2%           3       0.2406 sec
Template load   0.0201 sec      0.9242%         3       0.0067 sec
Template
processing      0.7008 sec      32.2226%        3       0.2336 sec

override
Cache load      0.0117 sec      0.5392%         3       0.0039 sec
class_abstraction
Instantiating
content class
attribute       0.0104 sec      0.4801%         5       0.0021 sec
Total script time:      2.1747 sec

EDIT:
[]Actual elapsed time before continuing was possible: 4.8 seconds

Timing points:
Checkpoint      Elapsed Rel.    Elapsed         Memory          Rel. Memory
Module start
'content'       0.0000 sec      0.4175 sec      5,748.8828KB    5,963.0703KB
Module end
'content'       0.4175 sec      0.1580 sec      11,711.9531KB   616.3438KB
End             0.5755 sec                      12,328.2969KB   0.6019KB
Total runtime:  0.8509 sec

Time accumulators:
Accumulator     Elapsed         Percent         Count   Average
ini_load
Load cache      0.0377 sec      3.7795%         18      0.0021 sec
Template Total  0.4996 sec      50.1%           3       0.1665 sec
Template load   0.0202 sec      2.0212%         3       0.0067 sec
Template
processing      0.4786 sec      47.9445%        3       0.1595 sec

override
Cache load      0.0119 sec      1.1876%         3       0.0040 sec
Sytem overhead
Fetch class
attribute
is_required     0.0106 sec      1.0622%         5       0.0021 sec
Fetch class
attribute name  0.0009 sec      0.0884%         5       0.0002 sec

class_abstraction
Instantiating
content class
attribute       0.0002 sec      0.0236%         3       0.0001 sec

General
String
conversion      0.0001 sec      0.0140%         2       0.0001 sec
Total script time:      0.9981 sec

PUBLISH:
[]Actual elapsed time before continuing was possible: 19.6 seconds

Timing points:
Checkpoint      Elapsed Rel.    Elapsed         Memory          Rel. Memory
Module start
'content'       0.0000 sec      0.4207 sec      5,735.0547KB    4,089.8516KB
Module end
'content'       0.4207 sec      5.8524 sec      9,824.9063KB    1,195.4297KB
End             6.2730 sec                      11,020.3359KB   0.0010KB
Total runtime:  7.3306 sec

Time accumulators:
Accumulator     Elapsed         Percent         Count   Average
ini_load
Load cache      0.0345 sec      0.4619%         16      0.0022 sec
Template Total  6.2497 sec      83.6%           3       2.0832 sec
Template load   0.0228 sec      0.3054%         3       0.0076 sec
Template
processing      6.2257 sec      83.2660%        3       2.0752 sec

override
Cache load      0.0132 sec      0.1763%         3       0.0044 sec

class_abstraction
Instantiating
content class
attribute       0.0104 sec      0.1393%         5       0.0021 sec
Total script time:      7.4769 sec

Also, selecting 'discard draft' rather than sending for publishing results in simliar times.

Thanks for helping out with this.

Andy