[Dovecot] Problem with AUTH causes serverside lockup

[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 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