loading of user acount administration page extremely slow after update to trac 0.12

[Maarten Derickx]

Dear readers of the trac users mailinglist (with cc to sage-devel),

Not so long ago we upgraded our server at http://trac.sagemath.org to trac 0.12 and after this upgrade loading the page http://trac.sagemath.org/sage_trac/admin/accounts/users sudenly has become extremely slow (loading takes between 3 to 5 minutes). I found no strange error messages in the log files, so I have no clue where to look for the cause of this slowness. Or is it just a regression in the trac codebase? Do you have any hints on where to start looking?

Thanks in advance,

Maarten Derickx

[RjOllos]

On Apr 2, 1:20 pm, Maarten Derickx <m.derickx.stud...@gmail.com>
wrote:
> Not so long ago we upgraded our server athttp://trac.sagemath.orgto trac
> 0.12 and after this upgrade loading the pagehttp://trac.sagemath.org/sage_trac/admin/accounts/userssudenly has become

> extremely slow (loading takes between 3 to 5 minutes).

That page is provided by the AccountManagerPlugin, so you might try
looking at the open bug reports
http://trac-hacks.org/wiki/AccountManagerPlugin

I'd also suggest setting the log level to debug and try to figure out
where it is spending time:

http://trac.edgewall.org/wiki/TracLogging

You'll probably get better feedback if you post more information about
your install: web server, authentication mechanism, etc ...

I've had some problems with slow ticket load times recently due to the
PrivateTicketsPlugin and using the restrict_owner option. If you have
a large number of users on your system, you may want to follow this
ticket:
http://trac.edgewall.org/ticket/4245

[Maarten Derickx]

Thanks for the hints on where to start.

 
On Tuesday, April 3, 2012 7:49:19 PM UTC+2, RjOllos wrote:

That page is provided by the AccountManagerPlugin, so you might try
looking at the open bug reports
http://trac-hacks.org/wiki/AccountManagerPlugin

Tnx, I browesd trough the tickets, and none seemed to have something related to any kind of slowness in their title.

 

I'd also suggest setting the log level to debug and try to figure out
where it is spending time:

http://trac.edgewall.org/wiki/TracLogging

Ok, I've set it to debug and pasted the relevant part of the logs on

http://pastebin.com/77KAvdVi

The appache logs showed.

66.249.67.106 - - [06/Apr/2012:07:21:49 -0700] "GET /sage_trac/ticket/7492?version=9 HTTP/1.1" 200 4959
128.208.160.197 - - [06/Apr/2012:07:21:52 -0700] "GET /sage_trac/ticket/76?format=rss HTTP/1.1" 200 5842
194.171.106.2 - - [06/Apr/2012:07:17:38 -0700] "GET /sage_trac/admin/accounts/users HTTP/1.1" 200 34371
194.171.106.2 - - [06/Apr/2012:07:21:53 -0700] "GET /sage_trac/chrome/acct_mgr/acct_mgr.css HTTP/1.1" 304 -
66.249.67.106 - - [06/Apr/2012:07:21:54 -0700] "GET /sage_trac/query?component=website%2Fwiki&milestone=sage-4.1&group=status&order=time HTTP/1.1" 200 6504
66.249.67.106 - - [06/Apr/2012:07:21:56 -0700] "GET /sage_trac/timeline?from=2008-06-24T18%3A56%3A46-07%3A00&precision=second HTTP/1.1" 200 24870
66.249.67.106 - - [06/Apr/2012:07:21:59 -0700] "GET /sage_trac/query?component=website%2Fwiki&milestone=sage-4.1&group=status&order=type HTTP/1.1" 200 6502

So I included only the time between  07:17:38 and 07:22:00 since that is the time where I guess all the relevant thing happened.

The most interesting thing in there is probably that the following two lines start to appear after 07:17:38

2012-04-06 07:17:40,474 Trac[perm] DEBUG: No policy allowed anonymous performing ACCTMGR_CONFIG_ADMIN on None
2012-04-06 07:17:40,474 Trac[perm] DEBUG: No policy allowed anonymous performing ACCTMGR_USER_ADMIN on None 

They both appear 87 times with an avarage time between occurances of 2 to 3 seconds. (we have 830 about users so this is called once per 10 users)

You'll probably get better feedback if you post more information about
your install: web server, authentication mechanism, etc ...

 

We have trac 0.12 and version 3.2 of the account manager plugin and use python 2.5. Our trac server is running under apache2 using WSGI. The os is ubuntu.

[account-manager]
account_changes_notify_addresses = i_think_the_exact...@relevant.org
authentication_url = http://trac.sagemath.org/sage_trac/login
force_passwd_change = false
generated_password_length = 8
htdigest_realm = sage.math.washington.edu
notify_actions = new
password_file = /var/trac/sage_trac/conf/passwd
password_store = HtDigestStore,HttpAuthStore


 

I've had some problems with slow ticket load times recently due to the
PrivateTicketsPlugin and using the restrict_owner option. If you have
a large number of users on your system, you may want to follow this
ticket:
http://trac.edgewall.org/ticket/4245

I don't know what is a lot, but we have about 800 users. The 3 minutes seems way longer then timings shown at that ticket while those people have more users.  Is there a quick way to determine wether that code is also executed when loading the user admin page?

[Maarten Derickx]

 

We have trac 0.12 and version 3.2 of the account manager plugin and use python 2.5. Our trac server is running under apache2 using WSGI. The os is ubuntu.

That should be version 0.3.2  of the account manager plugin. Sorry for the noise