[rt-users] Performance issues with RT3 (all versions)

[Ruslan U. Zakirov]

Jon Masters wrote:
> Hi all,
> 

[snip]

> Cheers,
> 
> Jon.
> 
> --- Specification ---
> 
> Linux kernel 2.6.6 (Not stock, Debian kernel release 2.6.6-1-686) 
> running on an Intel Celeron 600MHz CPU. This test box is not the fastest 
> machine in the world but it runs nothing else other than RT and a few 
> general system daemons. No windowing system cruft. System has been tuned 
> using sysctls and ulimits adjusted all in vain, IDE DMA is on, etc. etc. 
> The production machine exhibits the same problems and is much faster.
> 
> (So please assume that the base Debian is configured correctly).
so as I understand no memory presure.

> 
> To the system has been added MySQL version 4.0.18 (Debian 4.0.18-5).
Turn on log of slow queries with limit 1 second.
http://wiki.bestpractical.com/?Debug
Also can you create full SQL log for one request to Ticket/Display page?

> 
> I have also installed RT 3.2.0 as well as other versions.
Does it make any sense if you switch to 3.0.x.

> 
> I have performed database OPTIMIZE, ANALYZE, etc. etc.
It doesn't help if you are on InnoDB. Are you?

> 
> etc. etc. etc. etc.
> 
You didn't report what versions of modules and perl do you have?
mod_perl 1.x or 2.x? if 1.x then DSO or Static?

This output isn't normal
> --- dprofpp output ---
> 
> Total Elapsed Time = 196.2333 Seconds
>   User+System Time = 72.21333 Seconds
> Exclusive Times
> %Time ExclSec CumulS #Calls sec/call Csec/c  Name
>  40.4   29.24 29.418   4647   0.0063 0.0063 
> DBIx::SearchBuilder::Record::__ANO
>                                              N__
>  9.20   6.641 71.487   2297   0.0029 0.0311  HTML::Mason::Request::comp
>  8.09   5.844  5.844  10197   0.0006 0.0006  HTML::Mason::Request::print
>  6.79   4.902  4.974     52   0.0943 0.0957 
> RT::Transaction::BriefDescription
>  6.58   4.753  4.805     41   0.1159 0.1172  RT::Attachment::ContentLength
>  4.35   3.139  3.178     25   0.1256 0.1271  RT::Attachment::Content
>  3.52   2.539  2.639     25   0.1016 0.1055  Text::Quoted::extract
>  2.28   1.644  1.658     41   0.0401 0.0404  RT::Attachment::Headers
>  1.65   1.195  1.211     53   0.0226 0.0228  RT::Transactions::Next
>  1.64   1.186  1.182     52   0.0228 0.0227  RT::Record::CreatedAsString
>  1.52   1.095  1.119     25   0.0438 0.0448  RT::Transaction::IsInbound
>  1.37   0.986  1.011     42   0.0235 0.0241  RT::Attachments::Next
>  0.97   0.697  0.695     50   0.0139 0.0139  RT::Transaction::TicketObj
>  0.87   0.628  0.635     52   0.0121 0.0122  RT::Record::CreatorObj
>  0.51   0.369  0.367     20   0.0184 0.0184 
> DBIx::SearchBuilder::Record::AUTOL
>                                              OAD
dprofpp: Ticket/Display for ticket with 141 attachments.
Total Elapsed Time = 116.6960 Seconds
   User+System Time = 37.64604 Seconds
Exclusive Times
%Time ExclSec CumulS #Calls sec/call Csec/c  Name
  8.22   3.095  5.953  80847   0.0000 0.0001 
DBIx::SearchBuilder::Record::Cachable::_gen_primary_cache_key
  8.17   3.077  3.077   9457   0.0003 0.0003  RT::User::_ClassAccessible
  6.77   2.547 12.232  80251   0.0000 0.0002  RT::Record::__Value
  5.86   2.206  8.807  80251   0.0000 0.0001 
DBIx::SearchBuilder::Record::Cachable::__Value
  4.92   1.853 11.355   9264   0.0002 0.0012  RT::Principal::HasRight
  4.86   1.831  8.322  44702   0.0000 0.0002 
DBIx::SearchBuilder::Record::Cachable::LoadByCols
  4.55   1.714  2.936  44702   0.0000 0.0001 
DBIx::SearchBuilder::Record::Cachable::_lookup_primary_cache_key
  4.30   1.617  1.911  44702   0.0000 0.0000 
DBIx::SearchBuilder::Record::Cachable::_gen_alternate_cache_key
  3.99   1.503  1.503 402077   0.0000 0.0000 
DBIx::SearchBuilder::Record::Cachable::_RecordCache
  3.97   1.494 10.414  44702   0.0000 0.0002  RT::Record::LoadByCols
  3.82   1.439  9.439  37661   0.0000 0.0003 
DBIx::SearchBuilder::Record::new
  3.45   1.298  1.298   9263   0.0001 0.0001  RT::Queue::_ClassAccessible
  3.43   1.293 11.158  37661   0.0000 0.0003 
DBIx::SearchBuilder::Record::Cachable::new
  3.31   1.245  1.245 184179   0.0000 0.0000 
DBIx::SearchBuilder::Record::id
  3.27   1.232  1.307  86734   0.0000 0.0000 
DBIx::SearchBuilder::Record::__Value