[Dovecot] Problem with AUTH causes serverside lockup
Hello all,
I am running Dovecot alpha1, and after about 24 hours of the server working fine, it starts to lock up when I open Thunderbird to check mail.
Basically I can see new mail, but when I click it, it just hangs at "Loading message...".
I get the following in my maillog:
Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1 PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1 PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1 PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: CONT 1 [BASE64 SUM] Aug 23 09:25:28 palantir last message repeated 2 times Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1 PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1 PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: CONT 1 [BASE64 SUM]
And then it just hangs untill it finally says
Aug 23 09:26:17 palantir dovecot: imap-login: Disconnected: Inactivity: method=plain, rip=80.197.147.147, lip=194.192.14.150
When I do: # killall dovecot # dovecot (restarting dovecot)
then it works fine again.
-- Tom Sommer
Tom Sommer wrote:
I am running Dovecot alpha1, and after about 24 hours of the server working fine, it starts to lock up when I open Thunderbird to check mail.
Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1
PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1
PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1
PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: CONT 1
[BASE64 SUM] Aug 23 09:25:28 palantir last message repeated 2 times Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1
PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: AUTH 1
PLAIN service=IMAP lip=194.192.14.150 rip=80.197.147.147 Aug 23 09:25:28 palantir dovecot: auth(default): client out: CONT 1 Aug 23 09:25:28 palantir dovecot: auth(default): client in: CONT 1
[BASE64 SUM]And then it just hangs untill it finally says
Aug 23 09:26:17 palantir dovecot: imap-login: Disconnected: Inactivity: method=plain, rip=80.197.147.147, lip=194.192.14.150
I looked at my maillog again and found the point where dovecot "crashed":
Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 262144 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'uidlist record_pool' with: 524288 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 32768 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 65536 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 131072 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 262144 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 32768 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 65536 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'uidlist record_pool' with: 524288 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 32768 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 65536 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 131072 Aug 23 00:29:27 palantir dovecot: IMAP(ts@dreamcoder.dk): Sending log messages too fast, throttling.. Aug 23 00:29:28 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 262144 Aug 23 00:29:32 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 32768 Aug 23 00:29:32 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 65536 Aug 23 00:29:32 palantir dovecot: IMAP(ts@dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 131072 Aug 23 00:29:32 palantir dovecot: auth(default): client in: CONT 1 3 logout Aug 23 00:29:32 palantir dovecot: auth(default): client out: FAIL 1 reason=Unexpected continuation
-- Tom Sommer
participants (1)
-
Tom Sommer