[rt-users] Slow Query when Displaying "Owners" dropdown (Query Builder, Reply, etc.)

mark matyas mark at library.ucsc.edu
Mon Jan 2 17:02:12 EST 2006


Hello RT Users, Ruslan, Jesse,
Sorry for taking a few days to reply (went on vacation). I'm sending you 
the requested stack traces from the error logs regarding my problem. To 
recap this was my issue: I was using 3.4.4 and had peformance issues 
with the Query Builder page (we have a large database). So I upgraded to 
3.5.6 which fixed the problem (I really like 3.5.6 btw). Although there 
are no visible errors while using RT, I notice some errors in the apache 
and rt logs. It only seems to happen when a user logs out of RT, 
otherwise 3.5.6 is working out well.
My specs again:
RT 3.5.6 + apache2 + fastcgi + mysql 4.1 + HTML::Mason v1.3101 + DBI 
v1.48 + DBIx::SearchBuilder v1.36;

Log files are appended at the end of this email and attached.
thanks!
Mark Matyas
UCSC University Library


___________________________________________________
#### The following happens when I'm already logged in to RT (doesn't 
happen when logging in).
####I restarted apache right before I did this. I get this in apache 
error_log immediately after I LOGOUT:
------------------------------------------------


[Mon Jan 02 13:42:06 2006] [warn] FastCGI: server 
"/opt/rt3/bin/mason_handler.fcgi" started (pid 13365)
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: [Mon Jan  2 21:42:50 
2006] [err]: Couldn't load  from the users database. 
(/opt/rt3/lib/RT/CurrentUser.pm:146)
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: Stack trace:
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::CurrentUser::UserObj() called at /opt/rt3/lib/RT/CurrentUser.pm:340
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::CurrentUser::HasRight() called at 
/opt/rt3/local/html/Elements/Header:88
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1255
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \t(eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp() called at 
/opt/rt3/share/html/Elements/Login:68
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1255
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \t(eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp() called at /opt/rt3/share/html/autohandler:253
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1250
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \t(eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:461
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \t(eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:461
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \t(eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:413
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:192
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \t(eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:192
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::CGI::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Interp.pm:323
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Interp::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:123
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \t(eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:123
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::CGIHandler::_handler() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:73
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::CGIHandler::handle_cgi_object() called at 
/opt/rt3/bin/mason_handler.fcgi:76
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \t(eval)() called at 
/opt/rt3/bin/mason_handler.fcgi:76
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: Use of uninitialized 
value in concatenation (.) or string at 
/opt/rt3/lib/RT/Principal_Overlay.pm line 425.
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: DBD::mysql::st execute 
failed: You have an error in your SQL syntax; check the manual that 
corresponds to your MySQL server version for the right syntax to use 
near 'AND ((ACL.ObjectType = 'RT::System' AND ACL.ObjectId = 1) OR 
(ACL.ObjectType = '' at line 1 at 
/usr/lib/perl5/site_perl/5.8.5/DBIx/SearchBuilder/Handle.pm line 493.
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
RT::Handle=HASH(0x9f687f4) couldn't execute the query 'SELECT ACL.id 
from ACL, Groups, Principals, CachedGroupMembers WHERE  (ACL.RightName = 
'SuperUser' OR  ACL.RightName = 'ModifySelf') AND Principals.Disabled = 
0 AND CachedGroupMembers.Disabled = 0 AND Principals.id = Groups.id AND 
Principals.PrincipalType = 'Group' AND Principals.id = 
CachedGroupMembers.GroupId AND CachedGroupMembers.MemberId =  AND 
((ACL.ObjectType = 'RT::System' AND ACL.ObjectId = 1) OR (ACL.ObjectType 
= 'RT::System')) AND ACL.PrincipalId = Principals.id AND 
ACL.PrincipalType = 'Group'  LIMIT 1' at 
/usr/lib/perl5/site_perl/5.8.5/DBIx/SearchBuilder/Handle.pm line 506
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tDBIx::SearchBuilder::Handle::SimpleQuery('RT::Handle=HASH(0x9f687f4)', 
'SELECT ACL.id from ACL, Groups, Principals, CachedGroupMember...') 
called at /usr/lib/perl5/site_perl/5.8.5/DBIx/SearchBuilder/Handle.pm 
line 538
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tDBIx::SearchBuilder::Handle::FetchResult('RT::Handle=HASH(0x9f687f4)', 
'SELECT ACL.id from ACL, Groups, Principals, CachedGroupMember...') 
called at /opt/rt3/lib/RT/Principal_Overlay.pm line 458
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::Principal::_HasRight('RT::Principal=HASH(0xa331620)', 'Right', 
'ModifySelf', 'EquivObjects', 'ARRAY(0xa331494)', 'Object', 
'RT::System=HASH(0x9fc38dc)') called at 
/opt/rt3/lib/RT/Principal_Overlay.pm line 369
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::Principal::HasRight('RT::Principal=HASH(0xa331620)', 'Right', 
'ModifySelf', 'Object', 'RT::System=HASH(0x9fc38dc)') called at 
/opt/rt3/lib/RT/User_Overlay.pm line 1570
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::User::HasRight('RT::User=HASH(0xa33be2c)', 'Right', 'ModifySelf', 
'Object', 'RT::System=HASH(0x9fc38dc)') called at 
/opt/rt3/lib/RT/CurrentUser.pm line 340
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::CurrentUser::HasRight('RT::CurrentUser=HASH(0xa328e80)', 'Right', 
'ModifySelf', 'Object', 'RT::System=HASH(0x9fc38dc)') called at 
/opt/rt3/local/html/Elements/Header line 88
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__('Title', 'Login', 'Focus', 'user') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm line 135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run('HTML::Mason::Component::FileBased=HASH(0xa31d600)', 
'Title', 'Login', 'Focus', 'user') called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1255
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp('undef', 'undef', 'Title', 'Login', 
'Focus', 'user') called at /opt/rt3/share/html/Elements/Login line 68
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm line 135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run('HTML::Mason::Component::FileBased=HASH(0xa2fa57c)') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1255
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp('undef', 'undef') called at 
/opt/rt3/share/html/autohandler line 253
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm line 135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run('HTML::Mason::Component::FileBased=HASH(0xa24ebec)') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1250
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp('undef', 'undef', 'undef') called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 461
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 461
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 413
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::exec('HTML::Mason::Request::CGI=HASH(0xa25e1a8)') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 192
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 192
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::CGI::exec('HTML::Mason::Request::CGI=HASH(0xa25e1a8)') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Interp.pm line 323
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Interp::exec('undef', 'undef') called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 123
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 123
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::CGIHandler::_handler('HTML::Mason::CGIHandler=HASH(0x9f4c60c)', 
'HASH(0xa2d0b6c)') called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 73
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::CGIHandler::handle_cgi_object('HTML::Mason::CGIHandler=HASH(0x9f4c60c)', 
'CGI::Fast=HASH(0xa2314b8)') called at /opt/rt3/bin/mason_handler.fcgi 
line 76
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/opt/rt3/bin/mason_handler.fcgi line 76
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: DBD::mysql::st execute 
failed: You have an error in your SQL syntax; check the manual that 
corresponds to your MySQL server version for the right syntax to use 
near 'AND ((ACL.ObjectType = 'RT::System' AND ACL.ObjectId = 1) OR 
(ACL.ObjectType = '' at line 1 at 
/usr/lib/perl5/site_perl/5.8.5/DBIx/SearchBuilder/Handle.pm line 493.
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
RT::Handle=HASH(0x9f687f4) couldn't execute the query 'SELECT ACL.id 
from ACL, Groups, Principals, CachedGroupMembers WHERE  (ACL.RightName = 
'SuperUser' OR  ACL.RightName = 'ModifySelf') AND Principals.Disabled = 
0 AND CachedGroupMembers.Disabled = 0 AND Principals.id = Groups.id AND 
Principals.PrincipalType = 'Group' AND Principals.id = 
CachedGroupMembers.GroupId AND CachedGroupMembers.MemberId =  AND 
((ACL.ObjectType = 'RT::System' AND ACL.ObjectId = 1) OR (ACL.ObjectType 
= 'RT::System')) AND ACL.PrincipalType = Groups.Type AND ((Groups.Domain 
= 'RT::System-Role' AND Groups.Instance = '1') OR (Groups.Domain = 
'RT::System-Role'))  LIMIT 1' at 
/usr/lib/perl5/site_perl/5.8.5/DBIx/SearchBuilder/Handle.pm line 506
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tDBIx::SearchBuilder::Handle::SimpleQuery('RT::Handle=HASH(0x9f687f4)', 
'SELECT ACL.id from ACL, Groups, Principals, CachedGroupMember...') 
called at /usr/lib/perl5/site_perl/5.8.5/DBIx/SearchBuilder/Handle.pm 
line 538
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tDBIx::SearchBuilder::Handle::FetchResult('RT::Handle=HASH(0x9f687f4)', 
'SELECT ACL.id from ACL, Groups, Principals, CachedGroupMember...') 
called at /opt/rt3/lib/RT/Principal_Overlay.pm line 467
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::Principal::_HasRight('RT::Principal=HASH(0xa331620)', 'Right', 
'ModifySelf', 'EquivObjects', 'ARRAY(0xa331494)', 'Object', 
'RT::System=HASH(0x9fc38dc)') called at 
/opt/rt3/lib/RT/Principal_Overlay.pm line 369
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::Principal::HasRight('RT::Principal=HASH(0xa331620)', 'Right', 
'ModifySelf', 'Object', 'RT::System=HASH(0x9fc38dc)') called at 
/opt/rt3/lib/RT/User_Overlay.pm line 1570
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::User::HasRight('RT::User=HASH(0xa33be2c)', 'Right', 'ModifySelf', 
'Object', 'RT::System=HASH(0x9fc38dc)') called at 
/opt/rt3/lib/RT/CurrentUser.pm line 340
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tRT::CurrentUser::HasRight('RT::CurrentUser=HASH(0xa328e80)', 'Right', 
'ModifySelf', 'Object', 'RT::System=HASH(0x9fc38dc)') called at 
/opt/rt3/local/html/Elements/Header line 88
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__('Title', 'Login', 'Focus', 'user') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm line 135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run('HTML::Mason::Component::FileBased=HASH(0xa31d600)', 
'Title', 'Login', 'Focus', 'user') called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1255
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp('undef', 'undef', 'Title', 'Login', 
'Focus', 'user') called at /opt/rt3/share/html/Elements/Login line 68
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm line 135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run('HTML::Mason::Component::FileBased=HASH(0xa2fa57c)') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1255
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp('undef', 'undef') called at 
/opt/rt3/share/html/autohandler line 253
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm line 135
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Component::run('HTML::Mason::Component::FileBased=HASH(0xa24ebec)') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1250
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 1249
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::comp('undef', 'undef', 'undef') called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 461
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 461
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm line 413
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::exec('HTML::Mason::Request::CGI=HASH(0xa25e1a8)') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 192
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 192
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Request::CGI::exec('HTML::Mason::Request::CGI=HASH(0xa25e1a8)') 
called at /usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Interp.pm line 323
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::Interp::exec('undef', 'undef') called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 123
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 123
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::CGIHandler::_handler('HTML::Mason::CGIHandler=HASH(0x9f4c60c)', 
'HASH(0xa2d0b6c)') called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm line 73
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: 
\tHTML::Mason::CGIHandler::handle_cgi_object('HTML::Mason::CGIHandler=HASH(0x9f4c60c)', 
'CGI::Fast=HASH(0xa2314b8)') called at /opt/rt3/bin/mason_handler.fcgi 
line 76
[Mon Jan 02 13:42:50 2006] [error] [client 128.114.238.30] FastCGI: 
server "/opt/rt3/bin/mason_handler.fcgi" stderr: \teval {...} called at 
/opt/rt3/bin/mason_handler.fcgi line 76


--------------------------------------
#####I get this in rt.log (with stack trace):
##### FYI: the time in RT log is messed up and doesn't sync with apache 
error log
--------------------------------------

Stack trace:
         RT::CurrentUser::UserObj() called at 
/opt/rt3/lib/RT/CurrentUser.pm:340
         RT::CurrentUser::HasRight() called at 
/opt/rt3/local/html/Elements/Header:88
         HTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
         HTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1255
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
         HTML::Mason::Request::comp() called at 
/opt/rt3/share/html/Elements/Login:68
         HTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
         HTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1255
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
         HTML::Mason::Request::comp() called at 
/opt/rt3/share/html/autohandler:253
         HTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
         HTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1250
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
         HTML::Mason::Request::comp() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:461
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:461
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:413
         HTML::Mason::Request::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:192
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:192
         HTML::Mason::Request::CGI::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Interp.pm:323
         HTML::Mason::Interp::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:123
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:123
         HTML::Mason::CGIHandler::_handler() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:73
         HTML::Mason::CGIHandler::handle_cgi_object() called at 
/opt/rt3/bin/mason_handler.fcgi:76
         (eval)() called at /opt/rt3/bin/mason_handler.fcgi:76
[Mon Jan  2 21:42:50 2006] [err]: Couldn't load  from the users 
database. (/opt/rt3/lib/RT/CurrentUser.pm:146)

Stack trace:
         RT::CurrentUser::UserObj() called at 
/opt/rt3/lib/RT/CurrentUser.pm:340
         RT::CurrentUser::HasRight() called at 
/opt/rt3/local/html/Elements/Header:88
         HTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
         HTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1255
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
         HTML::Mason::Request::comp() called at 
/opt/rt3/share/html/Elements/Login:68
         HTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
         HTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1255
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
         HTML::Mason::Request::comp() called at 
/opt/rt3/share/html/autohandler:253
         HTML::Mason::Commands::__ANON__() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Component.pm:135
         HTML::Mason::Component::run() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1250
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:1249
         HTML::Mason::Request::comp() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:461
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:461
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Request.pm:413
         HTML::Mason::Request::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:192
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:192
         HTML::Mason::Request::CGI::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/Interp.pm:323
         HTML::Mason::Interp::exec() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:123
         (eval)() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:123
         HTML::Mason::CGIHandler::_handler() called at 
/usr/lib/perl5/site_perl/5.8.5/HTML/Mason/CGIHandler.pm:73
         HTML::Mason::CGIHandler::handle_cgi_object() called at 
/opt/rt3/bin/mason_handler.fcgi:76
         (eval)() called at /opt/rt3/bin/mason_handler.fcgi:76









Ruslan Zakirov wrote:
> On 12/30/05, mark matyas <mark at library.ucsc.edu> wrote:
> 
>>Hi Jesse,
>>Thanks for responding. Before I got your email I decided to go ahead and
>>try 3.5.6 for the heck of it, and it seems to have fixed the problem. I
>>really like 3.5.6 actually  especially the WebUI and Simple Search. I
>>have another problem now though.
>>Myspecs: RT 3.5.6 + apache 2 + fastcgi + + mysql 4.1 + HTML::Mason
>>v1.3101 + DBI v1.48 + DBIx::SearchBuilder v1.36;
>>The only problem now though is that I get alot of the following errors
>>in my apache log:
>>[Thu Dec 29 23:12:13 2005] [error] [client 67.180.231.50] FastCGI:
>>server "/opt/rt3/bin/mason_handler.fcgi" stderr: [Fri Dec 30 07:12:13
>>2005] [err]: Couldn't load  from the users database.
> 
> I saw this sveral times too. I find it harmless, but anyway it should
> be debugged.
> 
> 
>>(/opt/rt3/lib/RT/CurrentUser.pm:146)
>>[Thu Dec 29 23:12:13 2005] [error] [client 67.180.231.50] FastCGI:
>>server "/opt/rt3/bin/mason_handler.fcgi" stderr: Use of uninitialized
>>value in concatenation (.) or string at
>>/opt/rt3/lib/RT/Principal_Overlay.pm line 425.
>>[Thu Dec 29 23:12:13 2005] [error] [client 64.233.172.40] FastCGI:
>>server "/opt/rt3/bin/mason_handler.fcgi" stderr: [Fri Dec 30 07:12:13
>>2005] [err]: Couldn't load  from the users database.
>>(/opt/rt3/lib/RT/CurrentUser.pm:146)
>>[Thu Dec 29 23:12:13 2005] [error] [client 64.233.172.40] FastCGI:
>>server "/opt/rt3/bin/mason_handler.fcgi" stderr: Use of uninitialized
>>value in concatenation (.) or string at
>>/opt/rt3/lib/RT/Principal_Overlay.pm line 425.
>>[Thu Dec 29 23:12:13 2005] [error] [client 67.180.231.50] FastCGI:
>>server "/opt/rt3/bin/mason_handler.fcgi" stderr: DBD::mysql::st execute
>>failed: You have an error in your SQL syntax; check the manual that
>>corresponds to your MySQL server version for the right syntax to use
>>near 'AND ((ACL.ObjectType = 'RT::System' AND ACL.ObjectId = 1) OR
>>(ACL.ObjectType = '' at line 1 at
>>/usr/lib/perl5/site_perl/5.8.5/DBIx/SearchBuilder/Handle.pm line 493.
>>[Thu Dec 29 23:12:13 2005] [error] [client 67.180.231.50] FastCGI:
>>server "/opt/rt3/bin/mason_handler.fcgi" stderr:
>>RT::Handle=HASH(0xaabfb6c) couldn't execute the query 'SELECT ACL.id
>>from ACL, Groups, Principals, CachedGroupMembers WHERE  (ACL.RightName =
>>'SuperUser' OR  ACL.RightName = 'ModifySelf') AND Principals.Disabled =
>>0 AND CachedGroupMembers.Disabled = 0 AND Principals.id = Groups.id AND
>>Principals.PrincipalType = 'Group' AND Principals.id =
>>CachedGroupMembers.GroupId AND CachedGroupMembers.MemberId =  AND
>>((ACL.ObjectType = 'RT::System' AND ACL.ObjectId = 1) OR (ACL.ObjectType
>>= 'RT::System')) AND ACL.PrincipalId = Principals.id AND
>>ACL.PrincipalType = 'Group'  LIMIT 1' at
>>/usr/lib/perl5/site_perl/5.8.5/DBIx/SearchBuilder/Handle.pm line 506
> 
> This is bad.
> "AND CachedGroupMembers.MemberId =  AND"
>                                                           ^^^^^ here
> Could turn on stack traces logging in RT config:
> Set($LogToFile, 'error');
> Set($LogStackTraces, 1);
> Then reproduce this query error and send us stack trace from log file,
> note that stacktraces are not logged via syslog.
> 
> 
>>
>>what could this be? should I downgrade or upgrade a certain perl module?
>>I would really like to keep using 3.5.6 if possible. should i downgrade RT?
>>Thanks for your help,
>>mark matyas
>>
>>Jesse Vincent wrote:
>>
>>>
>>>On Wed, Dec 28, 2005 at 03:16:09PM -0800, mark matyas wrote:
>>>
>>>
>>>>Hello RT Users,
>>>>
>>>>We just upgraded from RT 3.0 to 3.4.4 and everything went fine expect
>>>>for one thing:
>>>>There is a specific query that takes more than 50 Seconds and is
>>>>executed frequently as the results of the Query seems to be the list of
>>>>Owners in the Drop down menu for the Query Builder page or When
>>>>Selecting Reply, etc. So when a user clicks on the Query Builder it
>>>>takes 50 seconds, making it very hard to use sometimes.
>>>
>>>
>>>
>>>Mark,
>>>
>>>Can you please test out RT 3.4.5RC2 to see if it solves your performance
>>>problem? We spent about half a man week working on just that for this
>>>release.
>>>
>>>Jesse
>>
>>_______________________________________________
>>http://lists.bestpractical.com/cgi-bin/mailman/listinfo/rt-users
>>
>>Be sure to check out the RT Wiki at http://wiki.bestpractical.com
>>
>>Download a free sample chapter of RT Essentials from O'Reilly Media at http://rtbook.bestpractical.com
>>
>>WE'RE COMING TO YOUR TOWN SOON - RT Training in Amsterdam, Boston and
>>San Francisco - Find out more at http://bestpractical.com/services/training.html
>>
> 
> 
> 
> --
> Best regards, Ruslan.



More information about the rt-users mailing list