[Dovecot] dovecot from debian lenny-backports does not accept connections
hi,
dovecot from the debian lenny backports does not successfully accept incomming imap or imaps connections; when the client connect i can see the clients ip address in an imap-process but the client cannot succesfully connect and retries this several times where an new imap-process is bound until the maximum connections per client is reached; when the client is closed the imap-processes on the server stay opened, even when dovecot will be restarted or stopped - the processes needs to killed manually;
--- snip ---
2010-05-14 02:24:42 auth(default): Info: client in: AUTH 1
PLAIN service=imap secured lip=y.y.y.y rip=x.x.x.x
lport=993 rport=1411
2010-05-14 02:24:42 auth(default): Info: client out: CONT 1
2010-05-14 02:24:42 auth(default): Info: client in: CONT 1
AGFsZXhAdXZzdCrhdAB5YWxpbWVybw==
2010-05-14 02:24:42 auth-worker(default): Info:
sql(alex@domain.tld,x.x.x.x): query: SELECT username AS user, password,
'/var/vmail/domain.tld/alex' AS userdb_home,
'maildir:/var/vmail/domain.tld/alex' AS userdb_mail, 150 AS userdb_uid,
8 AS userdb_gid FROM mailbox WHERE username = 'alex@domain.tld' AND
active = '1'
2010-05-14 02:24:42 auth(default): Info: client out: OK 1
user=alex@domain.tld
2010-05-14 02:24:42 auth(default): Info: master in: REQUEST 2
2177 1
2010-05-14 02:24:42 auth(default): Info:
prefetch(alex@domain.tld,x.x.x.x): success
2010-05-14 02:24:42 auth(default): Info: master out: USER 2
alex@domain.tld home=/var/vmail/domain.tld/alex
mail=maildir:/var/vmail/domain.tld/alex uid=150 gid=8
2010-05-14 02:24:42 imap-login: Info: Login: user=alex@domain.tld,
method=PLAIN, rip=x.x.x.x, lip=y.y.y.y, TLS
2010-05-14 02:24:42 IMAP(alex@domain.tld): Info: Loading modules from
directory: /usr/lib/dovecot/modules/imap
2010-05-14 02:24:42 IMAP(alex@domain.tld): Info: Module loaded:
/usr/lib/dovecot/modules/imap/lib10_quota_plugin.so
2010-05-14 02:24:42 IMAP(alex@domain.tld): Info: Module loaded:
/usr/lib/dovecot/modules/imap/lib11_imap_quota_plugin.so
2010-05-14 02:24:42 IMAP(alex@domain.tld): Info: Effective uid=150,
gid=8, home=/var/vmail/domain.tld/alex
2010-05-14 02:24:42 IMAP(alex@domain.tld): Info: Quota root:
name=storage=102400 backend=maildir args=
2010-05-14 02:24:42 IMAP(alex@domain.tld): Info: maildir:
data=/var/vmail/domain.tld/alex
2010-05-14 02:24:42 IMAP(alex@domain.tld): Info: maildir++:
root=/var/vmail/domain.tld/alex, index=, control=,
inbox=/var/vmail/domain.tld/alex
2010-05-14 02:24:42 auth(default): Info: new auth connection: pid=2186
2010-05-14 02:24:45 auth(default): Info: client in: AUTH 1
PLAIN service=imap secured lip=y.y.y.y rip=x.x.x.x
lport=993 rport=1412
2010-05-14 02:24:45 auth(default): Info: client out: CONT 1
2010-05-14 02:24:45 auth(default): Info: client in: CONT 1
AGFsZXhAdXZzdCrhdAB5YWxpbWVybw==
2010-05-14 02:24:45 auth-worker(default): Info:
sql(alex@domain.tld,x.x.x.x): query: SELECT username AS user, password,
'/var/vmail/domain.tld/alex' AS userdb_home,
'maildir:/var/vmail/domain.tld/alex' AS userdb_mail, 150 AS userdb_uid,
8 AS userdb_gid FROM mailbox WHERE username = 'alex@domain.tld' AND
active = '1'
2010-05-14 02:24:45 auth(default): Info: client out: OK 1
user=alex@domain.tld
2010-05-14 02:24:45 auth(default): Info: master in: REQUEST 3
2075 1
2010-05-14 02:24:45 auth(default): Info:
prefetch(alex@domain.tld,x.x.x.x): success
2010-05-14 02:24:45 auth(default): Info: master out: USER 3
alex@domain.tld home=/var/vmail/domain.tld/alex
mail=maildir:/var/vmail/domain.tld/alex uid=150 gid=8
2010-05-14 02:24:45 imap-login: Info: Login: user=alex@domain.tld,
method=PLAIN, rip=x.x.x.x, lip=y.y.y.y, TLS
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Loading modules from
directory: /usr/lib/dovecot/modules/imap
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Module loaded:
/usr/lib/dovecot/modules/imap/lib10_quota_plugin.so
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Module loaded:
/usr/lib/dovecot/modules/imap/lib11_imap_quota_plugin.so
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Effective uid=150,
gid=8, home=/var/vmail/domain.tld/alex
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Quota root:
name=storage=102400 backend=maildir args=
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: maildir:
data=/var/vmail/domain.tld/alex
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: maildir++:
root=/var/vmail/domain.tld/alex, index=, control=,
inbox=/var/vmail/domain.tld/alex
2010-05-14 02:24:45 auth(default): Info: new auth connection: pid=2189
2010-05-14 02:24:45 auth(default): Info: new auth connection: pid=2188
2010-05-14 02:24:45 auth(default): Info: client in: AUTH 1
PLAIN service=imap secured lip=y.y.y.y rip=x.x.x.x
lport=993 rport=1413
2010-05-14 02:24:45 auth(default): Info: client out: CONT 1
2010-05-14 02:24:45 auth(default): Info: client in: CONT 1
AGFsZXhAdXZzdC5hdABrYWxpbWVybw==
2010-05-14 02:24:45 auth-worker(default): Info:
sql(alex@domain.tld,x.x.x.x): query: SELECT username AS user, password,
'/var/vmail/domain.tld/alex' AS userdb_home,
'maildir:/var/vmail/domain.tld/alex' AS userdb_mail, 150 AS userdb_uid,
8 AS userdb_gid FROM mailbox WHERE username = 'alex@domain.tld' AND
active = '1'
2010-05-14 02:24:45 auth(default): Info: client out: OK 1
user=alex@domain.tld
2010-05-14 02:24:45 auth(default): Info: master in: REQUEST 4
2186 1
2010-05-14 02:24:45 auth(default): Info:
prefetch(alex@domain.tld,x.x.x.x): success
2010-05-14 02:24:45 auth(default): Info: master out: USER 4
alex@domain.tld home=/var/vmail/domain.tld/alex
mail=maildir:/var/vmail/domain.tld/alex uid=150 gid=8
2010-05-14 02:24:45 imap-login: Info: Login: user=alex@domain.tld,
method=PLAIN, rip=x.x.x.x, lip=y.y.y.y, TLS
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Loading modules from
directory: /usr/lib/dovecot/modules/imap
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Module loaded:
/usr/lib/dovecot/modules/imap/lib10_quota_plugin.so
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Module loaded:
/usr/lib/dovecot/modules/imap/lib11_imap_quota_plugin.so
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Effective uid=150,
gid=8, home=/var/vmail/domain.tld/alex
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Quota root:
name=storage=102400 backend=maildir args=
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: maildir:
data=/var/vmail/domain.tld/alex
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: maildir++:
root=/var/vmail/domain.tld/alex, index=, control=,
inbox=/var/vmail/domain.tld/alex
2010-05-14 02:24:45 auth(default): Info: client in: AUTH 1
PLAIN service=imap secured lip=y.y.y.y rip=x.x.x.x
lport=993 rport=1414
2010-05-14 02:24:45 auth(default): Info: client out: CONT 1
2010-05-14 02:24:45 auth(default): Info: client in: CONT 1
AGFsZXhAdXZzdC5hdABrYWxpbWVybw==
2010-05-14 02:24:45 auth-worker(default): Info:
sql(alex@domain.tld,x.x.x.x): query: SELECT username AS user, password,
'/var/vmail/domain.tld/alex' AS userdb_home,
'maildir:/var/vmail/domain.tld/alex' AS userdb_mail, 150 AS userdb_uid,
8 AS userdb_gid FROM mailbox WHERE username = 'alex@domain.tld' AND
active = '1'
2010-05-14 02:24:45 auth(default): Info: client out: OK 1
user=alex@domain.tld
2010-05-14 02:24:45 auth(default): Info: master in: REQUEST 5
2188 1
2010-05-14 02:24:45 auth(default): Info:
prefetch(alex@domain.tld,x.x.x.x): success
2010-05-14 02:24:45 auth(default): Info: master out: USER 5
alex@domain.tld home=/var/vmail/domain.tld/alex
mail=maildir:/var/vmail/domain.tld/alex uid=150 gid=8
2010-05-14 02:24:45 imap-login: Info: Login: user=alex@domain.tld,
method=PLAIN, rip=x.x.x.x, lip=y.y.y.y, TLS
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Loading modules from
directory: /usr/lib/dovecot/modules/imap
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Module loaded:
/usr/lib/dovecot/modules/imap/lib10_quota_plugin.so
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Module loaded:
/usr/lib/dovecot/modules/imap/lib11_imap_quota_plugin.so
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Effective uid=150,
gid=8, home=/var/vmail/domain.tld/alex
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: Quota root:
name=storage=102400 backend=maildir args=
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: maildir:
data=/var/vmail/domain.tld/alex
2010-05-14 02:24:45 IMAP(alex@domain.tld): Info: maildir++:
root=/var/vmail/domain.tld/alex, index=, control=,
inbox=/var/vmail/domain.tld/alex
2010-05-14 02:24:46 auth(default): Info: new auth connection: pid=2192
--- snip ---
i have a working installation from the stable repository with the same settings - but i need the newer dovecot for the nfs feature; i know there was a kernel bug prior 2.6.27 which also causes such a problem, but i have now the 2.6.32 running and still having this problem
- no client connection could be established....
has anyone a idea or a solution for me?
thx, alex
Joelly Alexander put forth on 5/14/2010 2:18 PM:
hi,
dovecot from the debian lenny backports does not successfully accept incomming imap or imaps connections; when the client connect i can see the clients ip address in an imap-process but the client cannot succesfully connect and retries this several times where an new imap-process is bound until the maximum connections per client is reached; when the client is closed the imap-processes on the server stay opened, even when dovecot will be restarted or stopped - the processes needs to killed manually;
I'm running the 1.2.11 Lenny backport as well and I've never seen the problem you describe. I'm using a custom rolled 2.6.32.9 from kernel.org source. I'm not using iptables or selinux. I've been troubleshooting some performance issues likely related to mbox processing/indexing but I've had nothing like the problem you describe. I'm using Unix user accounts and PAM authentication instead of virtual users, so there are substantial differences between our setups.
However, I don't think Dovecot is the source of your problem. From your description, it seems likely that return packets aren't making it to the clients. This would explain why they keep reconnecting, and would also explain why the imap process is never closed, i.e. the client doesn't think it ever connected successfully, so it never sends a logout command.
Something is preventing your return packets from reaching the clients. Check/disable your local iptables rules and any firewalls/routers between you and the clients to see where the outbound traffic is being blocked.
Package: dovecot-imapd State: installed Automatically installed: no Version: 1:1.2.11-1~bpo50+2 Priority: optional Section: mail Maintainer: Dovecot Maintainers jaldhar-dovecot@debian.org Uncompressed Size: 1823k Architecture: i386 Compressed Size: 1119k Filename: pool/main/d/dovecot/dovecot-imapd_1.2.11-1~bpo50+2_i386.deb MD5sum: fadfa84d8f07163ab7a2e9b32e39e056 Archive: lenny-backports, now
-- Stan
participants (2)
-
Joelly Alexander
-
Stan Hoeppner