[imp] Very slow login times IMP-CVS+Cyrus+MySQL

[Josh Endries]

Hello,

I installed the CVS version of Horde and IMP on a server and Horde is 
taking a very long time (~1.5 minutes) to log in to the Cyrus IMAP 
server (IMP does the auth). The server also takes a very long time to 
compose/send email after submitting. I can use a normal IMAP client like 
Thunderbird just fine with no delays, and can login manually over telnet 
without problems... I'm currently using imapproxyd to pool the IMAP 
connections, but removing that doesn't change what happens (I tried). It 
doesn't take long to login when using IMP's test.php, and the logs make 
it look like it logs in okay right away, then it just sits there doing 
nothing for a minute, then PHP realized it logged in and comes alive again.

This is all the logging I can think of grabbing (mysql, horde, and syslog):

040822 15:51:03    6732 Connect     horde at 127.0.0.1 on
                    6732 Init DB     horde
Aug 22 15:51:04 HORDE [debug] [imp] SQL Query by Prefs_sql::retrieve(): 
SELECT pref_scope, pref_name, pref_value FROM horde_prefs WHERE pref_uid 
= 'josh' AND (pref_scope = 'imp' OR pref_scope = 'horde') ORDER BY 
pref_scope [on line 147 of "/usr/local/lib/php/Horde/Prefs/sql.php"]
040822 15:51:04    6732 Init DB     horde
                    6732 Init DB     horde
                    6732 Query       SELECT pref_scope, pref_name, 
pref_value FROM horde_prefs WHERE pref_uid = 'josh' AND (pref_scope = 
'imp' OR pref_scope = 'horde') ORDER BY pref_scope
Aug 22 15:51:04 www master[5906]: about to exec /usr/local/cyrus/bin/imapd
Aug 22 15:51:04 www imap[5906]: executed
Aug 22 15:51:04 www imap[5906]: accepted connection
Aug 22 15:51:04 www imap[5906]: login: localhost [127.0.0.1] josh 
plaintext User logged in
Aug 22 15:51:04 www in.imapproxyd[555]: LOGIN: 'josh' (127.0.0.1:52402) 
on new sd [10]
Aug 22 15:52:24 HORDE [notice] [imp] Login success for josh 
[68.172.187.152] to {127.0.0.1:10143} [on line 117 of 
"/var/www/www.endries.org/horde/imp/redirect.php"]
Aug 22 15:52:24 www in.imapproxyd[555]: LOGOUT: 'josh' from server sd [10]
Aug 22 15:52:24 www in.imapproxyd[555]: LOGIN: 'josh' (127.0.0.1:52406) 
on existing sd [10]
Aug 22 15:52:24 www imap[5906]: seen_db: user josh opened 
/var/imap/user/j/josh.seen
Aug 22 15:52:24 www in.imapproxyd[555]: LOGOUT: 'josh' from server sd [10]
040822 15:52:24    6732 Init DB     horde
                    6732 Query       SELECT 1 FROM horde_prefs WHERE 
pref_uid = 'josh' AND pref_name = 'last_login' AND (pref_scope = 'horde' 
OR pref_scope = 'horde')
                    6732 Init DB     horde
                    6732 Query       UPDATE horde_prefs SET pref_value = 
'a:2:{s:4:\"time\";i:1093204264;s:4:\"host\";s:14:\"68.172.187.152\";}' 
WHERE pref_uid = 'josh' AND pref_name = 'last_login' AND pref_scope = 
'horde'
                    6732 Quit

Josh
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/x-pkcs7-signature
Size: 3166 bytes
Desc: S/MIME Cryptographic Signature
Url : http://lists.horde.org/archives/imp/attachments/20040822/796cdf73/smime.bin