Performance again

bodikp · January 10, 2008, 10:59am

Hello List,

I’m having some issues with a radiant install that I can;t really
understand. the site (www.ffinfo.nl) is hosted on a slicehost 512 slice.
There are 6 mongrel instances running. http server is nginx.

Small part of the log file:
Processing SiteController#show_page (for 212.178.112.58 at 2008-01-10
09:51:05) [GET]
Parameters: {“url”=>“/”, “action”=>“show_page”, “controller”=>“site”}
Completed in 0.08576 (11 reqs/sec) | DB: 0.02466 (28%) | 302 Found [
http://www.ffinfo.nl/]

Processing SiteController#show_page (for 212.178.112.58 at 2008-01-10
09:51:05) [GET]
Parameters: {“url”=>[“nl”], “action”=>“show_page”,
“controller”=>“site”}
Completed in 5.12782 (0 reqs/sec) | DB: 1.01139 (19%) | 200 OK [
TransIP - Reserved domain]

Processing SiteController#show_page (for 212.178.112.58 at 2008-01-10
09:51:11) [GET]
Parameters: {“url”=>[“frontpage.css”], “action”=>“show_page”,
“controller”=>“site”}
Completed in 0.17383 (5 reqs/sec) | DB: 0.30654 (176%) | 200 OK [
TransIP - Reserved domain]

Processing SiteController#show_page (for 212.178.112.58 at 2008-01-10
09:51:11) [GET]
Parameters: {“url”=>[“sifr.js”], “action”=>“show_page”,
“controller”=>“site”}
Completed in 0.09413 (10 reqs/sec) | DB: 0.01478 (15%) | 200 OK [
TransIP - Reserved domain]

I really don’t like the numbers here.

Any1 with some idea’s where to look / tweak ?

Thanks.

–
met vriendelijke groet,

Peter B.

bodikp · January 10, 2008, 8:56pm

On 1/10/08, Peter B. [email protected] wrote:

I’m having some issues with a radiant install that I can;t really
understand.

…

I really don’t like the numbers here.

Is the app running in production mode? If not, the pages are not being
cached so it probably will be slow.

What happens when you request a page that has been cached? You should
see the numbers improve quite a bit on the second request.

Are the pages complicated? How does a simple “Hello World!” example
render?

–
John L.
http://wiseheartdesign.com

bodikp · January 10, 2008, 9:12pm

It’s production mode and I think it’s quite complex. I will try a simple
hello world page.

Thanks

Peter

On Jan 10, 2008 8:55 PM, John L. [email protected] wrote:

Radiant mailing list
Post: [email protected]
Search: http://radiantcms.org/mailing-list/search/
Site: http://lists.radiantcms.org/mailman/listinfo/radiant

–
met vriendelijke groet,

Peter B.

bodikp · January 10, 2008, 10:29pm

Hello,

I;ve just logged in to the admin and this is in de log:

Processing WelcomeController#index (for 217.120.155.28 at 2008-01-10
21:25:44) [GET]
Session ID: fc1b241ee9c237aa602c4ec81911811a
Parameters: {“action”=>“index”, “controller”=>“admin/welcome”}
Redirected to TransIP - Reserved domain
Completed in 0.00085 (1179 reqs/sec) | DB: 0.00000 (0%) | 302 Found [
TransIP - Reserved domain]

Processing PageController#index (for 217.120.155.28 at 2008-01-10
21:25:44)
[GET]
Session ID: fc1b241ee9c237aa602c4ec81911811a
Parameters: {“action”=>“index”, “controller”=>“admin/page”}
Redirected to TransIP - Reserved domain
Filter chain halted as
[#<ActionController::Filters::ClassMethods::SymbolFilter:0x2aab0ec23088
@filter=:authenticate>] returned_false.
Completed in 0.00140 (714 reqs/sec) | DB: 0.00000 (0%) | 302 Found [
TransIP - Reserved domain]

Processing WelcomeController#login (for 217.120.155.28 at 2008-01-10
21:25:44) [GET]
Session ID: fc1b241ee9c237aa602c4ec81911811a
Parameters: {“action”=>“login”, “controller”=>“admin/welcome”}
Rendering within layouts/application
Rendering admin/welcome/login
Completed in 0.02869 (34 reqs/sec) | Rendering: 0.02625 (91%) | DB:
0.00082(2%) | 200 OK [
TransIP - Reserved domain]

Seems and looks fast, loggin in needs some time, but not that much. So I
think I’ll have to take a look at my cms usage.

Peter.

On Jan 10, 2008 9:11 PM, Peter B. [email protected]
wrote:

On 1/10/08, Peter B. < [email protected]> wrote:
What happens when you request a page that has been cached? You should
Post: [email protected]

–
met vriendelijke groet,

Peter B.

bodikp · January 10, 2008, 10:36pm

Sorry for this spam-like mailing…

simple layout (just <r:content />)

Processing SiteController#show_page (for 217.120.155.28 at 2008-01-10
21:34:33) [GET]
Parameters: {“url”=>[“nl”, “test”], “action”=>“show_page”,
“controller”=>“site”}
Completed in 0.05132 (19 reqs/sec) | DB: 0.00329 (6%) | 200 OK [
TransIP - Reserved domain]

Ã§ached version:
Processing SiteController#show_page (for 217.120.155.28 at 2008-01-10
21:35:01) [GET]
Parameters: {“url”=>[“nl”, “test”], “action”=>“show_page”,
“controller”=>“site”}
Completed in 0.00211 (473 reqs/sec) | DB: 0.00021 (9%) | 304 Not
Modified [
TransIP - Reserved domain]

Soooooo, back to the drawing board I suppose.

On Jan 10, 2008 10:33 PM, Peter B.
[email protected]
wrote:

21:25:44) [GET]
Processing WelcomeController#login (for 217.120.155.28 at 2008-01-10

Thanks

…

Site: http://lists.radiantcms.org/mailman/listinfo/radiant

met vriendelijke groet,

Peter B.

–
met vriendelijke groet,

Peter B.

bodikp · January 10, 2008, 10:33pm

And a simple hello world / test page with a normal used layout

Processing SiteController#show_page (for 217.120.155.28 at 2008-01-10
21:30:57) [GET]
Parameters: {“url”=>[“nl”, “test”], “action”=>“show_page”,
“controller”=>“site”}
Completed in 4.86826 (0 reqs/sec) | DB: 0.87199 (17%) | 200 OK [
TransIP - Reserved domain]

I will take a closer look at my layouts…

Will be continued.

On Jan 10, 2008 10:29 PM, Peter B.
[email protected]
wrote:

Completed in 0.00085 (1179 reqs/sec) | DB: 0.00000 (0%) | 302 Found [
@filter=:authenticate>] returned_false.
Completed in 0.02869 (34 reqs/sec) | Rendering: 0.02625 (91%) | DB:
wrote:

Radiant mailing list
Peter B.

–
met vriendelijke groet,

Peter B.

–
met vriendelijke groet,

Peter B.

bodikp · January 10, 2008, 10:43pm

Are you by any chance running the navigation plugin
(http://svn.artofmission.com/svn/plugins/radiant/extensions/navigation_tags/
) AND using the <r:nav /> tag AND have lots of nested pages? If so,
try removing that tag and compare performance. That tag has a bug that
can cause slowdowns like the ones you’re describing.

bodikp · January 10, 2008, 10:50pm

Hi Ryan,

I don’t use that extension, but I do have a lot of nested pages with a
recursive menu snippet.
So I removed the recursion and added the list of sub-pages in het
content of
the parent page.

It’s blazing fast again.

Peter.

On Jan 10, 2008 10:42 PM, Ryan H. [email protected] wrote:

Processing SiteController#show_page (for 217.120.155.28 at 2008-01-10
On Jan 10, 2008 10:33 PM, Peter B. <[email protected]

TransIP - Reserved domain]

Completed in 0.00085 (1179 reqs/sec) | DB: 0.00000 (0%) | 302
[#<ActionController::Filters::ClassMethods::SymbolFilter:
Rendering within layouts/application

…

Search: http://radiantcms.org/mailing-list/search/

–
Peter B.
Site: http://lists.radiantcms.org/mailman/listinfo/radiant

–
met vriendelijke groet,

Peter B.

bodikp · January 11, 2008, 9:39am

Hi John,

Yes, I understand. It was on my mind all the time, but I never thought
about
the menu. I also use the aggregate extension with alot of nesting so I
started to look there first.

I will try to make a blog post about this experience this weekend.

Peter.

On Jan 11, 2008 12:16 AM, John L. [email protected] wrote:

Would you mind doing a write up about your experience?

–
John L.
http://wiseheartdesign.com

Radiant mailing list
Post: [email protected]
Search: http://radiantcms.org/mailing-list/search/
Site: http://lists.radiantcms.org/mailman/listinfo/radiant

–
met vriendelijke groet,

Peter B.

bodikp · January 11, 2008, 12:16am

On 1/10/08, Peter B. [email protected] wrote:

I do have a lot of nested pages with a recursive menu snippet.
So I removed the recursion and added the list of sub-pages in het content of
the parent page. It’s blazing fast again.

On performance the thing to keep in mind is that the more pages
Radiant needs to access in order to render longer it will take. As you
discovered navigation can be choke point for this.

Would you mind doing a write up about your experience?

–
John L.
http://wiseheartdesign.com

bodikp · January 11, 2008, 4:49pm

I’m using the aggregate extension now and it is fast! So no problems
there.

Peter.

On Jan 11, 2008 4:45 PM, Ryan H. [email protected] wrote:

started to look there first.

content of
John L.
–

Radiant mailing list
Post: [email protected]
Search: http://radiantcms.org/mailing-list/search/
Site: http://lists.radiantcms.org/mailman/listinfo/radiant

–
met vriendelijke groet,

Peter B.

bodikp · January 24, 2008, 10:52pm

It took some time, but I wrote a (small) blog post this evening about
the
performance ‘issues’ that I had and how I solved them,

You can find it here :
http://peterberkenbosch.wordpress.com/2008/01/24/radiant-performance-issue-and-how-i-fixed-it/

Peter.

On Jan 11, 2008 4:49 PM, Peter B. [email protected]
wrote:

I will try to make a blog post about this experience this weekend.

Post: [email protected]

–
met vriendelijke groet,

Peter B.

bodikp · January 11, 2008, 4:46pm

Did you find that the aggregate extension sped things up?

bodikp · January 28, 2008, 5:10pm

Peter B. wrote:

It took some time, but I wrote a (small) blog post this evening about the
performance ‘issues’ that I had and how I solved them,

You can find it here :
http://peterberkenbosch.wordpress.com/2008/01/24/radiant-performance-issue-and-how-i-fixed-it/

Peter.

Hi Peter,

Thanks! Looks good!

By the way, what kinds of size was the site (in terms of number of
pages, depth and width)? It would give an idea of when to start
worrying!

Cheers,
Mohit.
1/29/2008 | 12:09 AM.

bodikp · January 28, 2008, 7:26pm

Hi Mohit,

At this moment we have 148 pages, the max depth is 5 levels deep, but
there
are only 2 pages that are that deep. Most of it is 3 levels deep.

From google analytics: from 28 dec 2007 - 17 jan 2008 : 3412 visits and
15104 pagevies

Peter.

On Jan 28, 2008 5:10 PM, Mohit S. [email protected] wrote:

1/29/2008 | 12:09 AM.

Radiant mailing list
Post: [email protected]
Search: http://radiantcms.org/mailing-list/search/
Site: http://lists.radiantcms.org/mailman/listinfo/radiant

–
met vriendelijke groet,

Peter B.