[Dovecot] Pop3 hanging up after login.

Sat Aug 27 21:13:39 EEST 2011

Hi,

I'm trying to implement a second dovecot server on a second machine to 
try to load balance IMAP/POP3 services.
The first machine is already running and is working pretty well with 
dovecot-pop3d 1:1.0.15-2.3+lenny1. The
new machine is running dovecot-pop3d 1:1.2.15-7 and accessing the 
existing maildirs over a NFS share on /mnt/mail.

When I try to telnet localhost 110 on the new machine it just freezes 
after the "pass MySecret", like this:
# telnet localhost 110
Trying 127.0.0.1...
Connected to localhost.
Escape character is '^]'.
+OK Bem Vindo.
user my.user at mydomain.com.br
+OK
pass MySecret
... (Nothing else happens)

If I try to stop Dovecot with /etc/init.d/dovecot stop it stops, but the 
pop3 process keep running and the only
way to stop it is with kill -9.

On my log I only can see this:
Aug 27 15:03:40 radamante dovecot: auth(default): client in: 
AUTH#0112#011PLAIN#011service=pop3#011secured#011lip=127.0.0.1#011rip=127.0.0.1#011lport=110#011rport=60421#011resp=<hidden>
Aug 27 15:03:40 radamante dovecot: auth-worker(default): 
pam(my.user at mydomain.com.br,127.0.0.1): lookup service=dovecot
Aug 27 15:03:40 radamante dovecot: auth-worker(default): 
pam(my.user at mydomain.com.br,127.0.0.1): #1/1 style=1 msg=Password:
Aug 27 15:03:42 radamante dovecot: auth-worker(default): 
pam(my.user at mydomain.com.br,127.0.0.1): pam_authenticate() failed: 
Authentication failure (password mismatch?)
Aug 27 15:03:42 radamante dovecot: auth(default): 
cache(my.user at mydomain.com.br,127.0.0.1): miss
Aug 27 15:03:42 radamante dovecot: auth(default): 
ldap(my.user at mydomain.com.br,127.0.0.1): pass search: base=ou=Users, 
dc=mydomain, dc=com, dc=br scope=subtree 
filter=(&(mail=my.user at mydomain.com.br)) fields=mail,userPassword
Aug 27 15:03:42 radamante dovecot: auth(default): 
ldap(my.user at mydomain.com.br,127.0.0.1): result: 
mail(user at domain)=my.user at mydomain.com.br userPassword(password)=<hidden>
Aug 27 15:03:42 radamante dovecot: auth(default): client out: 
OK#0112#011user=my.user at mydomain.com.br#011user at domain=my.user at mydomain.com.br
Aug 27 15:03:42 radamante dovecot: auth(default): master in: 
REQUEST#0111#01130330#0112
Aug 27 15:03:42 radamante dovecot: auth(default): 
passwd(my.user at mydomain.com.br,127.0.0.1): lookup
Aug 27 15:03:42 radamante dovecot: auth(default): 
passwd(my.user at mydomain.com.br,127.0.0.1): unknown user
Aug 27 15:03:42 radamante dovecot: auth(default): 
ldap(my.user at mydomain.com.br,127.0.0.1): user search: base=ou=Users, 
dc=mydomain, dc=com, dc=br scope=subtree 
filter=(&(mail=my.user at mydomain.com.br)) 
fields=homeDirectory,uidNumber,gidNumber
Aug 27 15:03:42 radamante dovecot: auth(default): 
ldap(my.user at mydomain.com.br,127.0.0.1): result: 
uidNumber(uidNumber)=3478 gidNumber(gidNumber)=513 
homeDirectory(homeDirectory)=/home/samba/my.user
Aug 27 15:03:42 radamante dovecot: auth(default): master out: 
USER#0111#011my.user at mydomain.com.br#011uidNumber=3478#011gidNumber=513#011homeDirectory=/home/samba/my.user
Aug 27 15:03:42 radamante dovecot: pop3-login: Login: 
user=<my.user at mydomain.com.br>, method=PLAIN, rip=127.0.0.1, 
lip=127.0.0.1, secured
Aug 27 15:03:42 radamante dovecot: auth(default): new auth connection: 
pid=30330
Aug 27 15:03:42 radamante dovecot: POP3(my.user at mydomain.com.br): 
Effective uid=8, gid=8, home=(none)
Aug 27 15:03:42 radamante dovecot: POP3(my.user at mydomain.com.br): 
maildir: data=/mnt/mail/mydomain.com.br/my.user/Maildir
Aug 27 15:03:42 radamante dovecot: POP3(my.user at mydomain.com.br): 
maildir++: root=/mnt/mail/mydomain.com.br/my.user/Maildir, index=, 
control=, inbox=/mnt/mail/mydomain.com.br/my.user/Maildir
Aug 27 15:03:42 radamante dovecot: POP3(my.user at mydomain.com.br): 
Namespace : Using permissions from 
/mnt/mail/mydomain.com.br/my.user/Maildir: mode=0700 gid=-1

By now I have no clue whats going on, any help is appreciated. I don't 
understand if the problem is the NFS share or if it's something with the 
new version of Dovecot on Debian.

Some more info:

# dovecot -n
# 1.2.15: /etc/dovecot/dovecot.conf
# OS: Linux 2.6.32-5-amd64 x86_64 Debian 6.0.2 nfs
log_timestamp: %Y-%m-%d %H:%M:%S
protocols: imap pop3
ssl: no
disable_plaintext_auth: no
login_dir: /var/run/dovecot/login
login_executable(default): /usr/lib/dovecot/imap-login
login_executable(imap): /usr/lib/dovecot/imap-login
login_executable(pop3): /usr/lib/dovecot/pop3-login
login_greeting: Bem Vindo.
login_process_per_connection: no
login_chroot: no
login_process_size: 128
login_processes_count: 8
max_mail_processes: 2048
mail_max_userip_connections(default): 100
mail_max_userip_connections(imap): 100
mail_max_userip_connections(pop3): 10
verbose_proctitle: yes
first_valid_uid: 1
last_valid_uid: 9999
last_valid_gid: 9999
mail_privileged_group: mail
mail_uid: mail
mail_gid: mail
mail_location: maildir:/mnt/mail/%Ld/%Ln/Maildir
mail_debug: yes
mmap_disable: yes
mail_nfs_storage: yes
mail_nfs_index: yes
maildir_copy_preserve_filename: yes
mbox_write_locks: fcntl dotlock
mail_executable(default): /usr/lib/dovecot/imap
mail_executable(imap): /usr/lib/dovecot/imap
mail_executable(pop3): /usr/lib/dovecot/pop3
mail_process_size: 512
mail_plugin_dir(default): /usr/lib/dovecot/modules/imap
mail_plugin_dir(imap): /usr/lib/dovecot/modules/imap
mail_plugin_dir(pop3): /usr/lib/dovecot/modules/pop3
imap_client_workarounds(default): delay-newmail
imap_client_workarounds(imap): delay-newmail
imap_client_workarounds(pop3):
imap_idle_notify_interval(default): 30
imap_idle_notify_interval(imap): 30
imap_idle_notify_interval(pop3): 120
pop3_client_workarounds(default):
pop3_client_workarounds(imap):
pop3_client_workarounds(pop3): outlook-no-nuls oe-ns-eoh
auth default:
   default_realm: mydomain.com.br
   cache_size: 1024
   cache_negative_ttl: 0
   username_format: %Lu
   failure_delay: 3
   debug: yes
   passdb:
     driver: pam
   passdb:
     driver: ldap
     args: /etc/dovecot/dovecot-ldap.conf
   userdb:
     driver: passwd
   userdb:
     driver: ldap
     args: /etc/dovecot/dovecot-ldap.conf

root at radamante:/etc/dovecot# egrep -v '^#|^$' dovecot-ldap.conf
hosts = 192.168.5.37 192.168.5.124
sasl_bind = no
tls = no
auth_bind = no
ldap_version = 3
base = ou=Users, dc=mydomain, dc=com, dc=br
deref = never
scope = subtree
user_attrs = 
homeDirectory=homeDirectory,uidNumber=uidNumber,gidNumber=gidNumber
user_filter = (&(mail=%u))
pass_attrs = mail=user at domain,userPassword=password
pass_filter = (&(mail=%u))
default_pass_scheme = CRYPT

Thanks in advance.