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: AUTH 1 PLAIN
service=imap secured session=xoAIwYr6WQB/AAAB lip=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: AUTH 1 PLAIN
service=imap secured session=TjU5wYr6XwB/AAAB lip=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 mail=maildir:/var/mail/ardito
2014-05-29 16:29:23 imap-login: Info: Login: user=<ardito>,
method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=25390, secured,
session=<TjU5wYr6XwB/AAAB>
2014-05-29 16:29:23 imap(ardito): Info: Disconnected: Logged out in=51
out=498
2014-05-29 16:29:23 auth: Debug: auth client connected (pid=25391)
2014-05-29 16:29:23 auth: Debug: client in: AUTH 1 PLAIN
service=imap secured session=YupcwYr6YgB/AAAB lip=127.0.0.1
rip=127.0.0.1 lport=143 rport=40290 resp=<hidden>
2014-05-29 16:29:23 auth-worker(25377): Debug: pam(ardito,127.0.0.1):
lookup service=dovecot
2014-05-29 16:29:23 auth-worker(25377): Debug: pam(ardito,127.0.0.1):
#1/1 style=1 msg=Password:
2014-05-29 16:29:25 auth-worker(25377): Info: pam(ardito,127.0.0.1):
pam_authenticate() failed: Authentication failure (password mismatch?)
2014-05-29 16:29:25 auth: Debug:
cache(ardito,127.0.0.1,<YupcwYr6YgB/AAAB>): hit: <hidden> user=ardito
2014-05-29 16:29:25 auth: Debug: client passdb out: OK 1 user=ardito
2014-05-29 16:29:25 auth: Debug: master in: REQUEST 3315073025
25391 1 5b2b45f82074db122b4ac3b96e40d290
2014-05-29 16:29:25 auth: Debug:
userdb-cache(ardito,127.0.0.1,<YupcwYr6YgB/AAAB>): miss
2014-05-29 16:29:25 auth-worker(25377): Debug: passwd(ardito,127.0.0.1):
lookup
2014-05-29 16:29:25 auth-worker(25377): Info: passwd(ardito,127.0.0.1):
unknown user
2014-05-29 16:29:25 auth: Debug:
userdb-cache(ardito,127.0.0.1,<YupcwYr6YgB/AAAB>): hit: ardito
mail=maildir:/var/mail/ardito
2014-05-29 16:29:25 auth: Debug: master userdb out: USER
3315073025 ardito mail=maildir:/var/mail/ardito
2014-05-29 16:29:25 imap-login: Info: Login: user=<ardito>,
method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=25395, secured,
session=<YupcwYr6YgB/AAAB>
2014-05-29 16:29:25 imap(ardito): Info: Disconnected: Logged out in=91
out=548
2014-05-29 16:30:07 master: Warning: Killed with signal 15 (by pid=25462
uid=0 code=kill)
Thanks,
Marco
-------------------
[Ai sensi e per gli effetti della Legge sulla tutela della privacy
(L. 196/2003), questa mail รจ destinata unicamente alle persone sopra
indicate e le informazioni in essa contenute sono da considerarsi
strettamente riservate. E' proibito leggere, copiare, usare o
diffondere il contenuto della presente mail senza autorizzazione.
Se avete ricevuto questo messaggio per errore, siete pregati di
rispedire la stessa al mittente. Grazie]
--
[email protected]
https://inverse.ca/sogo/lists
