[Dovecot] Can't get authentication for masterusers on Mac OS X Server 10.6.8

[Gilles Celli]

Ok I've enabled dovecot's "auth_verbose" and "auth_debug" mode along with syslog facility to debug mode, so here's the output:

1. So first when trying to login with "myusername*master":

#telnet mailserv.example.com 143
Connected to mailserv.example.com.
Escape character is '^]'.
* OK Dovecot ready.
1 login myusername*master myMasterPassword
1 NO Authentication failed.
1 logout
* BYE Logging out
1 OK Logout completed.
Connection closed by foreign host.

It fails....here's then the debug logout:

Mar  4 16:09:14 mailserv dovecot[9253]: auth(default): client in: AUTH	14	PLAIN	service=imap	secured	lip=127.0.0.1	rip=127.0.0.1	lport=143	rport=63994	resp=AGdpbGxlcyplY2dzYWRtaW4AdGVzdA==
Mar  4 16:09:14 mailserv dovecot[9253]: auth(default): passwd-file(master,127.0.0.1,master): lookup: user=master file=/etc/dovecot/passwd.masterusers
Mar  4 16:09:14 mailserv dovecot[9253]: auth(default): passdb(master,127.0.0.1,master): Master user logging in as myusername
Mar  4 16:09:14 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): mail SACL is enabled; overriding settings in user record
Mar  4 16:09:14 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): found user in local table: user=myusername
Mar  4 16:09:14 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): Credentials could not be verified username or password is invalid.
Mar  4 16:09:16 mailserv dovecot[9253]: auth(default): client out: FAIL	14	user=myusername
Mar  4 16:09:24 mailserv dovecot[9253]: imap-login: Aborted login (auth failed, 1 attempts): user=<myusername>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, secured
Mar  4 16:09:24 mailserv dovecot[9253]: auth(default): new auth connection: pid=9278

2. Well here's with "myusername" login, which is succesful:

#telnet mailserv.example.com 143
Connected to mailserv.example.com.
Escape character is '^]'.
* OK Dovecot ready.
1 login myusername myPassword
1 OK Logged in.
1 logout
* BYE Logging out
1 OK Logout completed.
Connection closed by foreign host.


Logout:

Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): client in: AUTH	65	PLAIN	service=imap	secured	lip=127.0.0.1	rip=127.0.0.1	lport=143	rport=64184	resp=AGdpbGxlcwB0PWcxbGwzc3B3IQ==
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): mail SACL is enabled; overriding settings in user record
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): found user in local table: user=myusername
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): client out: OK	65	user=myusername
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): master in: REQUEST	80	9276	65
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): lookup user=myusername
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): found user in local table: user=myusername
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): record name=myusername, uid=1030, gid=20
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): user=myusername, quota=*:storage=10240000
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): od(myusername,127.0.0.1): data store location=maildir:/var/spool/imap/dovecot/mail/396B158B-27A9-4827-99AF-CBF65F85C407
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): master out: USER	80	myusername	uid=1030	gid=20	quota=maildir:User quota:noenforcing	quota_rule=*:storage=10240000	mail=maildir:/var/spool/imap/dovecot/mail/396B158B-27A9-4827-99AF-CBF65F85C407	mail_location=maildir:/var/spool/imap/dovecot/mail/396B158B-27A9-4827-99AF-CBF65F85C407	sieve=/var/spool/imap/dovecot/sieve-scripts/396B158B-27A9-4827-99AF-CBF65F85C407/dovecot.sieve	sieve_dir=/var/spool/imap/dovecot/sieve-scripts/396B158B-27A9-4827-99AF-CBF65F85C407	sieve_storage=/var/spool/imap/dovecot/sieve-scripts/396B158B-27A9-4827-99AF-CBF65F85C407
Mar  4 16:22:42 mailserv dovecot[9253]: imap-login: Login: user=<myusername>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, secured
Mar  4 16:22:42 mailserv dovecot[9253]: auth(default): new auth connection: pid=9276
Mar  4 16:22:46 mailserv dovecot[9253]: IMAP(*): User myusername: Disconnected: Logged out bytes=8/43

Any thoughts ?

Cheers,

Gilles



On 03 Mar 2014, at 21:48, list at grootstyr.eu wrote:

> Try getting more verbose logs using dovecot's logging mechanisms.
>    auth_verbose=yes
>    auth_debug=yes
> It seems that you aren't authenticating your master users against your 
> passwd file, instead you are authenticating against your OpenDirectory.