Possible IMAP IDLE bug in Dovecot 2.2.18 and 2.2.25

2016-08-24 Thread cleber-lis...@inetweb.com.br 
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 
"cle...@testdomain.com.br" 
16:08:09 [192.168.202.11][21425199] cle...@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

Possible IMAP IDLE bug in Dovecot 2.2.18 and 2.2.25

2016-08-23 Thread cleber-lis...@inetweb.com.br 
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 
"cle...@testdomain.com.br" 
16:08:09 [192.168.202.11][21425199] cle...@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