[Dovecot] Dovecot 2 fails after correct login
Frank Walter
francwalter at gmx.net
Sun May 20 14:12:00 EEST 2012
Hello
I installed dovecot 2.0.9 (and dovecot-mysql!) on my Ubuntu 12.04 LTS root-server to work together with Postfix 2.9.1-4
After some struggle, I got Postfix's auth working, using dovecot for smtp-authentification with mysql.
But dovecot himself is not working properly. When I connect, after giving plaintext password I read the error in the client (I connect for debug reasons with a dos-box on windows):
telnet example.org 110
+OK Dovecot ready.
USER me at example.org
+OK
PASS myplainpassword
-ERR [IN-USE] Internal error occurred. Refer to server log for more information.
If I do:
...
PASS thisisthewrongpassword
I get:
-ERR Authentication failed.
And this is the debug-log (I changed IP and names, server to 1.2.3.4 me to 5.6.7.8 server-name to example.org, my email to me at example.org):
May 20 12:14:54 auth: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
May 20 12:14:54 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
May 20 12:14:54 auth: Debug: auth client connected (pid=25337)
May 20 12:14:57 auth: Debug: client in: AUTH 1 PLAIN service=pop3 lip=1.2.3.4 rip=5.6.7.8 lport=110 rport=1731 resp=AGZyYW5jQGV3Ni5vcmcAMzE0MTU=
May 20 12:14:57 auth-worker: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
May 20 12:14:57 auth-worker: Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
May 20 12:14:57 auth-worker: Debug: pam(me at example.org,5.6.7.8): lookup service=dovecot
May 20 12:14:57 auth-worker: Debug: pam(me at example.org,5.6.7.8): #1/1 style=1 msg=Password:
May 20 12:15:00 auth-worker: Debug: sql(me at example.org,5.6.7.8): query: SELECT email as user, password FROM view_users WHERE email='me at example.org';
May 20 12:15:00 auth: Debug: client out: OK 1 user=me at example.org
May 20 12:15:00 auth: Debug: master in: REQUEST 4018667521 25337 1 ccd274c0359454ad3beae53ccb1cc03b
May 20 12:15:00 auth: Debug: passwd(me at example.org,5.6.7.8): lookup
May 20 12:15:00 auth: Debug: prefetch(me at example.org,5.6.7.8): passdb didn't return userdb entries, trying the next userdb
May 20 12:15:00 auth-worker: Debug: sql(me at example.org,5.6.7.8): SELECT home, uid, gid FROM users WHERE username = 'me' AND domain = 'example.org'
May 20 12:15:00 auth: Debug: master out: FAIL 4018667521
And with wrong password:
...
May 20 12:42:51 auth-worker: Debug: sql(me at example.org,5.6.7.8): query: SELECT email as user, password FROM view_users WHERE email='me at example.org';
May 20 12:42:51 auth-worker: Debug: sql(me at example.org,5.6.7.8): PLAIN-MD5(thisisthewrongpassword) != '7d7ba8201b765ffd4b212268b8c34d1c'
May 20 12:42:53 auth: Debug: client out: FAIL 1 user=me at example.org
I don't understand the error messages (yet), could somebody please give me a hint?
My dovecot.conf:
passdb {
driver = sql
args = /etc/dovecot/dovecot-sql.conf
}
userdb {
driver = prefetch
}
userdb {
driver = sql
args = /etc/dovecot/dovecot-sql.conf
}
dovecot-sql.conf:
driver = mysql
connect = host=127.0.0.1 dbname=maildb user=mailuser password=mailpassword
default_pass_scheme = PLAIN-MD5
password_query = SELECT email as user, password FROM view_users WHERE email='%u';
10-auth.conf:
disable_plaintext_auth = no
auth_mechanisms = plain
!include auth-system.conf.ext
and in my 10-master.conf I have:
service imap-login {
inet_listener imap {
}
inet_listener imaps {
}
}
service pop3-login {
inet_listener pop3 {
}
inet_listener pop3s {
}
}
service lmtp {
unix_listener lmtp {
}
}
service imap {
}
service pop3 {
}
service auth {
unix_listener auth-userdb {
mode = 0660
}
unix_listener /var/spool/postfix/private/auth {
mode = 0666
user = postfix
group = postfix
}
}
service auth-worker {
}
service dict {
unix_listener dict {
}
}
I enabled logging in 10-logging.conf, enabled ssl in 10-ssl.conf and put the path in 10-mail.conf. Beside this I didn't change the defaults.
Thank you for help!
frank
More information about the dovecot
mailing list