nobody?
I enabled auth_debug=yes to trace auth problems, in dovecot.log, going
from
- service start to
- sogo login and
- mail page activation (only 1 message in in box), then
- service stop:
I see several cache miss and pam authentication failure (password
mismatch?)
If I get it right, dovecot tries to auth on PAM, fails, then looks
cache, fails, then ask auth to LDAP server
I thought to have
1) only LDAP as auth, not PAM ?
2) enbled user cache...
probably something in my config is wrong, but can't see any complaint by
dovecot about the config... ?
I try to follow wiki and other sources, but can't figure it out...
I'm stuck...
2014-05-29 16:28:53 master: Info: Dovecot v2.1.17 starting up (core
dumps disabled)
2014-05-29 16:29:17 auth: Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth
2014-05-29 16:29:17 auth: Debug: Loading modules from directory:
/usr/lib/dovecot/modules/auth
2014-05-29 16:29:17 auth: Debug: Module loaded:
/usr/lib/dovecot/modules/auth/libauthdb_ldap.so
2014-05-29 16:29:17 auth: Debug: auth client connected (pid=25375)
2014-05-29 16:29:17 auth: Debug: client in: AUTH1 PLAIN
service=imapsecured session=xoAIwYr6WQB/AAABlip=127.0.0.1
rip=127.0.0.1 lport=143 rport=40281 resp=hidden
2014-05-29 16:29:17 auth-worker(25377): Debug: Loading modules from
directory: /usr/lib/dovecot/modules/auth
2014-05-29 16:29:17 auth-worker(25377): Debug: Loading modules from
directory: /usr/lib/dovecot/modules/auth
2014-05-29 16:29:17 auth-worker(25377): Debug: Module loaded:
/usr/lib/dovecot/modules/auth/libauthdb_ldap.so
2014-05-29 16:29:17 auth-worker(25377): Debug: pam(ardito,127.0.0.1):
lookup service=dovecot
2014-05-29 16:29:17 auth-worker(25377): Debug: pam(ardito,127.0.0.1):
#1/1 style=1 msg=Password:
2014-05-29 16:29:20 auth-worker(25377): Info: pam(ardito,127.0.0.1):
pam_authenticate() failed: Authentication failure (password mismatch?)
2014-05-29 16:29:20 auth: Debug:
cache(ardito,127.0.0.1,xoAIwYr6WQB/AAAB): miss
2014-05-29 16:29:20 auth: Debug:
ldap(ardito,127.0.0.1,xoAIwYr6WQB/AAAB): bind search:
base=OU=utenti,OU=uffici,OU=sede,DC=apiform,DC=to,DC=it
filter=((objectClass=user)(sAMAccountName=ardito))
2014-05-29 16:29:20 auth: Debug:
ldap(ardito,127.0.0.1,xoAIwYr6WQB/AAAB): result: sAMAccountName=ardito
2014-05-29 16:29:20 auth: Debug: client passdb out: OK 1 user=ardito
2014-05-29 16:29:20 auth: Debug: master in: REQUEST 3486777345
25375 1 fdc4a0770d615a4d58ae4ef41176935d
2014-05-29 16:29:20 auth: Debug:
userdb-cache(ardito,127.0.0.1,xoAIwYr6WQB/AAAB): miss
2014-05-29 16:29:20 auth-worker(25377): Debug: passwd(ardito,127.0.0.1):
lookup
2014-05-29 16:29:20 auth-worker(25377): Info: passwd(ardito,127.0.0.1):
unknown user
2014-05-29 16:29:20 auth: Debug:
userdb-cache(ardito,127.0.0.1,xoAIwYr6WQB/AAAB): miss
2014-05-29 16:29:20 auth: Debug:
ldap(ardito,127.0.0.1,xoAIwYr6WQB/AAAB): user search:
base=OU=utenti,OU=uffici,OU=sede,DC=apiform,DC=to,DC=it scope=subtree
filter=((objectClass=user)(sAMAccountName=ardito))
fields=sAMAccountName,userPassword
2014-05-29 16:29:20 auth: Debug:
ldap(ardito,127.0.0.1,xoAIwYr6WQB/AAAB): result:
sAMAccountName=ardito; userPassword missing
2014-05-29 16:29:20 auth: Debug: master userdb out: USER
3486777345 ardito mail=maildir:/var/mail/ardito
2014-05-29 16:29:20 imap-login: Info: Login: user=ardito,
method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=25381, secured,
session=xoAIwYr6WQB/AAAB
2014-05-29 16:29:20 imap(ardito): Info: Disconnected: Logged out in=180
out=1013
2014-05-29 16:29:20 auth: Debug: auth client connected (pid=25385)
2014-05-29 16:29:21 auth: Debug: client in: AUTH1 PLAIN
service=imapsecured session=TjU5wYr6XwB/AAABlip=127.0.0.1
rip=127.0.0.1 lport=143 rport=40287 resp=hidden
2014-05-29 16:29:21 auth-worker(25377): Debug: pam(ardito,127.0.0.1):
lookup service=dovecot
2014-05-29 16:29:21 auth-worker(25377): Debug: pam(ardito,127.0.0.1):
#1/1 style=1 msg=Password:
2014-05-29 16:29:23 auth-worker(25377): Info: pam(ardito,127.0.0.1):
pam_authenticate() failed: Authentication failure (password mismatch?)
2014-05-29 16:29:23 auth: Debug:
cache(ardito,127.0.0.1,TjU5wYr6XwB/AAAB): hit: hidden user=ardito
2014-05-29 16:29:23 auth: Debug: client passdb out: OK 1 user=ardito
2014-05-29 16:29:23 auth: Debug: master in: REQUEST 2534539265
25385 1 8c2601ee2ef7e94ff9275cfc0d72adce
2014-05-29 16:29:23 auth: Debug:
userdb-cache(ardito,127.0.0.1,TjU5wYr6XwB/AAAB): miss
2014-05-29 16:29:23 auth-worker(25377): Debug: passwd(ardito,127.0.0.1):
lookup
2014-05-29 16:29:23 auth-worker(25377): Info: passwd(ardito,127.0.0.1):
unknown user
2014-05-29 16:29:23 auth: Debug:
userdb-cache(ardito,127.0.0.1,TjU5wYr6XwB/AAAB): hit: ardito
mail=maildir:/var/mail/ardito
2014-05-29 16:29:23 auth: Debug: master userdb out: USER
2534539265 ardito