Possible IMAP IDLE bug in Dovecot 2.2.18 and 2.2.25

cleber-listas at inetweb.com.br cleber-listas at inetweb.com.br
Tue Aug 23 12:08:26 UTC 2016 

Hello Guys,
 I guess that I found a bug in Dovecot 2.2.18 and 2.2.25 versions. The 
problem it's when I try to connect in a Dovecot used a proxy to another 
e-mail server (in our case it's a Smartermail Server) the DoveCot send a 
lot of IDLE commands to the destination server. With that, the LOG files 
grow and grow and grow :(
  
 I make a test with 1 connection only and if you see the log file in the 
same second the dovecot send 25, 40 IDLE commands. But, when I disconnect 
the client (outlook 2013) the dovecot continue to send the IDLE command for 
some seconds.
  
 If I try to connect directly to Smartermail with Outlook this don't 
occurrs.
  
 Bellow the LOG FILE:
  
 16:08:08 [192.168.202.11][21425199] connected at 22/08/2016 16:08:08
16:08:08 [192.168.202.11][21425199] command: C CAPABILITY
16:08:09 [192.168.202.11][21425199] command: L LOGIN 
"cleber at testdomain.com.br" XXXX
16:08:09 [192.168.202.11][21425199] cleber at testdomain.com.br logged in
16:08:09 [192.168.202.11][21425199] command: 36mc IDLE
16:08:09 [192.168.202.11][21425199] command: 2x04 ID ("name" "Microsoft 
Outlook" "version" "15.0.4849.1000")
16:08:09 [192.168.202.11][21425199] response: 2x04 BAD Command does not 
exist or is not implemented
16:08:09 [192.168.202.11][21425199] command: g4zp LSUB "" "*"
16:08:09 [192.168.202.11][21425199] command: pv81 IDLE
16:08:09 [192.168.202.11][21425199] command: 2it1 SELECT "INBOX"
16:08:09 [192.168.202.11][21425199] response: * 912 EXISTS
16:08:09 [192.168.202.11][21425199] response: * 0 RECENT
16:08:09 [192.168.202.11][21425199] response: * OK [UNSEEN 904] Message 904 
is first unseen
16:08:09 [192.168.202.11][21425199] response: * OK [UIDVALIDITY 1] UIDs 
valid
16:08:09 [192.168.202.11][21425199] response: * OK [UIDNEXT 26830] 
Predicted next UID
16:08:09 [192.168.202.11][21425199] response: * FLAGS (\Answered \Flagged 
\Deleted \Seen \Draft)
16:08:09 [192.168.202.11][21425199] response: * OK [PERMANENTFLAGS 
(\Answered \Flagged \Deleted \Seen \Draft)]
16:08:09 [192.168.202.11][21425199] response: 2it1 OK [READ-WRITE] SELECT 
completed
16:08:09 [192.168.202.11][21425199] command: a6tj IDLE
16:08:09 [192.168.202.11][21425199] command: bn09 FETCH 912 (UID)
16:08:09 [192.168.202.11][21425199] command: 78b6 IDLE
16:08:09 [192.168.202.11][21425199] command: q13w UID FETCH 1:26829 (UID 
FLAGS)
16:08:09 [192.168.202.11][21425199] command: p02s IDLE
16:08:09 [192.168.202.11][21425199] command: 9de9 IDLE
16:08:09 [192.168.202.11][21425199] command: xjyf IDLE
16:08:09 [192.168.202.11][21425199] command: holb IDLE
16:08:09 [192.168.202.11][21425199] command: sfbq IDLE
16:08:09 [192.168.202.11][21425199] command: tuvb IDLE
16:08:09 [192.168.202.11][21425199] command: hv13 IDLE
16:08:09 [192.168.202.11][21425199] command: ctgw IDLE
16:08:09 [192.168.202.11][21425199] command: s00g IDLE
16:08:09 [192.168.202.11][21425199] command: 9zx1 IDLE
16:08:09 [192.168.202.11][21425199] command: dtu2 IDLE
16:08:09 [192.168.202.11][21425199] command: 1brp IDLE
16:08:09 [192.168.202.11][21425199] command: vhds IDLE
16:08:09 [192.168.202.11][21425199] command: cp9s IDLE
16:08:09 [192.168.202.11][21425199] command: hx1b IDLE
16:08:09 [192.168.202.11][21425199] command: 6thy IDLE
16:08:09 [192.168.202.11][21425199] command: 4ert IDLE
16:08:09 [192.168.202.11][21425199] command: cy32 IDLE
16:08:09 [192.168.202.11][21425199] command: z7ku IDLE
16:08:09 [192.168.202.11][21425199] command: xeqd IDLE
16:08:09 [192.168.202.11][21425199] command: gqis IDLE
16:08:09 [192.168.202.11][21425199] command: bdz2 IDLE
16:08:09 [192.168.202.11][21425199] command: zzyq IDLE
16:08:09 [192.168.202.11][21425199] command: 41do IDLE
16:08:09 [192.168.202.11][21425199] command: 7k73 IDLE
16:08:10 [192.168.202.11][21425199] command: 5989 IDLE
16:08:10 [192.168.202.11][21425199] command: lyt4 IDLE
16:08:10 [192.168.202.11][21425199] command: 8ji4 IDLE
16:08:10 [192.168.202.11][21425199] command: pt5b IDLE
16:08:10 [192.168.202.11][21425199] command: ha2s IDLE
16:08:10 [192.168.202.11][21425199] command: ay0x IDLE
16:08:10 [192.168.202.11][21425199] command: t66x IDLE
16:08:10 [192.168.202.11][21425199] command: pf4j IDLE
16:08:10 [192.168.202.11][21425199] command: 2q5x IDLE
16:08:10 [192.168.202.11][21425199] command: wdup IDLE
16:08:10 [192.168.202.11][21425199] command: ii7b IDLE
16:08:10 [192.168.202.11][21425199] command: 2g4k IDLE
16:08:10 [192.168.202.11][21425199] command: lxxg IDLE
16:08:10 [192.168.202.11][21425199] command: vilu IDLE
16:08:10 [192.168.202.11][21425199] command: ms05 IDLE
16:08:10 [192.168.202.11][21425199] command: 8ugb IDLE
16:08:10 [192.168.202.11][21425199] command: 1vfo IDLE
16:08:10 [192.168.202.11][21425199] command: s78s IDLE
16:08:10 [192.168.202.11][21425199] command: 7h2k IDLE
16:08:10 [192.168.202.11][21425199] command: 0het IDLE
16:08:10 [192.168.202.11][21425199] command: rgj7 IDLE
16:08:10 [192.168.202.11][21425199] command: myx3 IDLE
16:08:10 [192.168.202.11][21425199] command: oi5h IDLE
16:08:10 [192.168.202.11][21425199] command: p4mr IDLE
16:08:10 [192.168.202.11][21425199] command: z0dn IDLE
16:08:10 [192.168.202.11][21425199] command: ivj4 IDLE
16:08:10 [192.168.202.11][21425199] command: vxt4 IDLE
16:08:10 [192.168.202.11][21425199] command: mj9c IDLE
16:08:10 [192.168.202.11][21425199] command: 0cf3 IDLE
16:08:10 [192.168.202.11][21425199] command: u215 IDLE
16:08:10 [192.168.202.11][21425199] command: czw6 IDLE
16:08:10 [192.168.202.11][21425199] command: t3nw IDLE
16:08:10 [192.168.202.11][21425199] command: ixmt IDLE
16:08:10 [192.168.202.11][21425199] command: o3qi IDLE
16:08:10 [192.168.202.11][21425199] command: ca1l IDLE
16:08:10 [192.168.202.11][21425199] command: 3r67 IDLE
16:08:10 [192.168.202.11][21425199] command: 8rmq IDLE
16:08:10 [192.168.202.11][21425199] command: yi76 IDLE
16:08:10 [192.168.202.11][21425199] command: 7doe IDLE
16:08:10 [192.168.202.11][21425199] command: 1xzf IDLE
16:08:10 [192.168.202.11][21425199] command: y78i IDLE
16:08:10 [192.168.202.11][21425199] command: 7g2f IDLE
16:08:10 [192.168.202.11][21425199] command: vmvn IDLE
16:08:10 [192.168.202.11][21425199] command: m9z5 IDLE
16:08:10 [192.168.202.11][21425199] command: cq7q IDLE
16:08:11 [192.168.202.11][21425199] command: 3c54 IDLE
16:08:11 [192.168.202.11][21425199] command: otih IDLE
16:08:11 [192.168.202.11][21425199] command: uuhy IDLE
16:08:11 [192.168.202.11][21425199] command: 7dya IDLE
16:08:11 [192.168.202.11][21425199] command: i2rb IDLE
16:08:11 [192.168.202.11][21425199] command: 13kl IDLE
16:08:11 [192.168.202.11][21425199] command: mbxe IDLE
16:08:11 [192.168.202.11][21425199] command: 0oz5 IDLE
16:08:11 [192.168.202.11][21425199] command: ymn7 IDLE
16:08:11 [192.168.202.11][21425199] command: vavf IDLE
16:08:11 [192.168.202.11][21425199] command: 3vdh IDLE
16:08:11 [192.168.202.11][21425199] command: rnci IDLE
16:08:11 [192.168.202.11][21425199] command: n3gu IDLE
16:08:11 [192.168.202.11][21425199] command: gu0f IDLE
16:08:11 [192.168.202.11][21425199] command: gewp IDLE
16:08:11 [192.168.202.11][21425199] command: vu84 IDLE
16:08:11 [192.168.202.11][21425199] command: d1rz IDLE
16:08:11 [192.168.202.11][21425199] command: 7nz9 IDLE
16:08:11 [192.168.202.11][21425199] command: trs9 IDLE
16:08:11 [192.168.202.11][21425199] command: yqlo IDLE
16:08:11 [192.168.202.11][21425199] command: 2jtj IDLE
16:08:11 [192.168.202.11][21425199] command: 085e IDLE
16:08:11 [192.168.202.11][21425199] command: 2jyw IDLE
16:08:11 [192.168.202.11][21425199] command: ycwo IDLE
16:08:11 [192.168.202.11][21425199] command: iemo IDLE
16:08:11 [192.168.202.11][21425199] command: eien IDLE
16:08:11 [192.168.202.11][21425199] command: lxi2 IDLE
16:08:11 [192.168.202.11][21425199] command: uhug IDLE
16:08:11 [192.168.202.11][21425199] command: jabp IDLE
16:08:11 [192.168.202.11][21425199] command: v1hf IDLE
16:08:11 [192.168.202.11][21425199] command: s0pp IDLE
16:08:11 [192.168.202.11][21425199] command: i4qi IDLE
16:08:11 [192.168.202.11][21425199] command: bc7b IDLE
16:08:11 [192.168.202.11][21425199] command: 6kxh IDLE
16:08:11 [192.168.202.11][21425199] command: bd0a IDLE
16:08:11 [192.168.202.11][21425199] command: i578 IDLE
16:08:11 [192.168.202.11][21425199] command: 4fb8 IDLE
16:08:11 [192.168.202.11][21425199] command: y259 IDLE
16:08:11 [192.168.202.11][21425199] command: tftj IDLE
16:08:11 [192.168.202.11][21425199] command: vm65 IDLE
16:08:11 [192.168.202.11][21425199] command: 2seb IDLE
16:08:11 [192.168.202.11][21425199] command: 2tu4 IDLE
16:08:11 [192.168.202.11][21425199] command: 7fq3 IDLE
16:08:11 [192.168.202.11][21425199] command: nydu IDLE
16:08:11 [192.168.202.11][21425199] command: 5na0 IDLE
  
 My dovecot -n config:
  
 # 2.2.25 (7be1766): /usr/local/etc/dovecot/dovecot.conf
# OS: Linux 2.6.32-642.1.1.el6.x86_64 x86_64 CentOS release 6.8 (Final)
auth_cache_negative_ttl = 0
auth_cache_ttl = 0
auth_mechanisms = plain login
auth_verbose = yes
auth_verbose_passwords = plain
base_dir = /var/run/dovecot/
debug_log_path = /var/log/dovecot/debug.log
disable_plaintext_auth = no
info_log_path = /var/log/dovecot/info.log
log_path = /var/log/dovecot/dovecot.log
passdb {
  args = /usr/local/etc/dovecot/conf.d/10-mysql.conf
  driver = sql
}
protocols = pop3 imap
service imap-login {
  service_count = 0
  vsz_limit = 512 M
}
service pop3-login {
  process_min_avail = 1
  service_count = 0
}
ssl_ca = </usr/local/etc/dovecot/RapidSSL_CA_G3.pem
ssl_cert = </usr/local/etc/dovecot/mailserverpro.pem
ssl_key = </usr/local/etc/dovecot/mailserverpro.pem
userdb {
  driver = prefetch
}
  
 My MYSQL connection config:
  
 password_query = SELECT NULL AS password, NULL as user, NULL AS destuser, 
host, 'Y' AS nologin, 'Y' as nodelay, 'Y' AS nopassword, 'Y' AS proxy, 'Y' 
as proxy_nopipelining, 120 AS proxy_timeout FROM dovecot_domains WHERE 
domain = '%d'
  
 Anybody can help?
  
 Best Regards, Cleber

More information about the dovecot mailing list