Rails, Passenger, FreeBSD, Production weird goings on

rubyonrails-talk
1

Hi,

Our production site is going very slow and i was just wondering if anyone has ever seen stuff like this before in the logs

One request returning four, notice the db times.

Processing ArticlesController#show (for 193.172.238.146 at 2010-11-18 16:26:03) [GET]   Session ID: d3ab8cc7b8bfc3154e1f3cdd11a79ec0   Parameters: {"action"=>"show", "id"=>"12", "controller"=>"education/articles"} Completed in 201.30773 (0 reqs/sec) | Rendering: 0.84850 (0%) | DB: 200.23051 (99%) | 200 OK [http://xx.xxx-xxxxxxxxxxx.com/education/articles/15\] Completed in 201.30773 (0 reqs/sec) | Rendering: 0.67231 (0%) | DB: 200.58331 (99%) | 200 OK [http://xx.xxx-xxxxxxxxxxx.com/education/articles/64\] Completed in 198.24940 (0 reqs/sec) | Rendering: 0.76880 (0%) | DB: 197.43730 (99%) | 200 OK [http://xx.xxx-xxxxxxxxxxx.com/education/articles/35\] Completed in 199.86349 (0 reqs/sec) | Rendering: 0.66750 (0%) | DB: 199.01294 (99%) | 200 OK [http://xx.xxx-xxxxxxxxxxx.com/education/articles/54\]

This looks unusual to me too, rendering the same views multiples of times.

Processing ArticlesController#show (for 200.35.190.97 at 2010-11-18 16:25:14) [GET]   Session ID: ffcb436fd7bc07baf925eb87ed069aa0   Parameters: {"action"=>"show", "id"=>"101", "controller"=>"education/articles"} Rendering within layouts/application Rendering education/articles/show Rendering within layouts/business/application Rendering within layouts/application Rendering education/articles/show Rendering within layouts/application Rendering education/articles/show Rendering within layouts/application Rendering education/articles/show Rendering within layouts/application Rendering education/articles/show Rendering within layouts/application Rendering within layouts/application Rendering education/articles/show Rendering education/articles/show Rendering business/articles/show Completed in 151.21412 (0 reqs/sec) | Rendering: 0.53775 (0%) | DB: 149.92393 (99%) | 200 OK [http://xx.xxx-xxxxxxxxxxx.com/business/articles/116\] Completed in 200.98208 (0 reqs/sec) | Rendering: 0.25980 (0%) | DB: 200.69937 (99%) | 200 OK [http://xx.xxx-xxxxxxxxxxxx.com/education/articles/299\]

The production site runs:

rails 2.1.0 postgres freebsd passenger

One thing i noticed is Passenger is referencing Ruby Enterprise but the app reports that it's runnign under FreeBSD's Ruby

This is a system we inherited, not our design or what we are used to.

Anyone shed any light?

JB

2

Hi,

Our production site is going very slow and i was just wondering if anyone has ever seen stuff like this before in the logs

Is that just multiple simultaneous requests writing to the log file at the same time, so all the results get interleaved?

If you suspect a database problem then turn on the slow query log (or even just sitting at the database console and asking it what it's doing (show processlist for mysql)) to see what queries are slow

Fred