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.150I looked at my maillog again and found the point where dovecot "crashed": Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 262144 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'uidlist record_pool' with: 524288 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 32768 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 65536 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 131072 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 262144 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 32768 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 65536 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'uidlist record_pool' with: 524288 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 32768 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 65536 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 131072 Aug 23 00:29:27 palantir dovecot: IMAP(ts at dreamcoder.dk): Sending log messages too fast, throttling.. Aug 23 00:29:28 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 262144 Aug 23 00:29:32 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 32768 Aug 23 00:29:32 palantir dovecot: IMAP(ts at dreamcoder.dk): Growing pool 'maildir_uidlist_sync' with: 65536 Aug 23 00:29:32 palantir dovecot: IMAP(ts at 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