thr3ads.net - dovecot - User doesn't exist [Aug 2020]

If this information is useful, please help other people find it:
Share via:

Bruno Kiste

2020-Aug-14 09:45 UTC

User doesn't exist

I try to use dovecot as a MTA with postfix.
But it does not accept incoming mails.
Why does it not work?

[root at localhost bruno]# dovecot --version
2.3.8 (9df20d2db)

/var/log/maillog
Aug 14 11:03:32 localhost dovecot[566097]: lmtp(566373): Connect from local
Aug 14 11:03:32 localhost dovecot[566097]:
lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
Debug: auth-master: userdb lookup(bruno at 2c2axxx.online-server.cloud):
Started userdb lookup
Aug 14 11:03:32 localhost dovecot[566097]:
lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb: Connecting
Aug 14 11:03:32 localhost dovecot[566097]:
lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb: Client
connected (fd=16)
Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug: master in:
USER#0111#011bruno at 2c2axxx.online-server.cloud#011service=lmtp
Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
passwd(bruno at 2c2axxx.online-server.cloud): Performing userdb lookup
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
Loading modules from directory: /usr/lib64/dovecot/auth
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
Module loaded: /usr/lib64/dovecot/auth/lib20_auth_var_expand_crypt.so
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
Module loaded: /usr/lib64/dovecot/auth/libdriver_sqlite.so
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
passwd-file /etc/dovecot/users: Read 4 users in 0 secs
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug: conn
unix:auth-worker (pid=566367,uid=97): Server accepted connection (fd=14)
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug: conn
unix:auth-worker (pid=566367,uid=97): Sending version handshake
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug: conn
unix:auth-worker (pid=566367,uid=97): auth-worker<1>: Handling USER
request
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
passwd(bruno at 2c2axxx.online-server.cloud): Performing userdb lookup
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
passwd(bruno at 2c2axxx.online-server.cloud): lookup
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374):
passwd(bruno at 2c2axxx.online-server.cloud): unknown user  - trying the next
userdb
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
passwd(bruno at 2c2axxx.online-server.cloud): Finished userdb lookup
Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug: conn
unix:auth-worker (pid=566367,uid=97): auth-worker<1>: Finished
Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
passwd(bruno at 2c2axxx.online-server.cloud): Finished userdb lookup
Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
passwd-file(bruno at 2c2axxx.online-server.cloud): Performing userdb lookup
Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
passwd-file(bruno at 2c2axxx.online-server.cloud): lookup:
user=bruno at 2c2axxx.online-server.cloud file=/etc/dovecot/users
Aug 14 11:03:32 localhost dovecot[566097]: auth:
passwd-file(bruno at 2c2axxx.online-server.cloud): unknown user
Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
passwd-file(bruno at 2c2axxx.online-server.cloud): Finished userdb lookup
Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug: userdb out:
NOTFOUND#0111
Aug 14 11:03:32 localhost dovecot[566097]:
lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
Debug: auth-master: userdb lookup(bruno at 2c2axxx.online-server.cloud): auth
USER input:
Aug 14 11:03:32 localhost dovecot[566097]:
lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
Debug: auth-master: userdb lookup(bruno at 2c2axxx.online-server.cloud):
Userdb lookup failed
Aug 14 11:03:32 localhost postfix/lmtp[566372]: 4E2127802973:
to=<bruno at 2c2axxx.online-server.cloud>,
relay=2c2axxx.online-server.cloud[private/dovecot-lmtp], delay=0.26,
delays=0.19/0.02/0.02/0.02, dsn=5.1.1, status=bounced (host
2c2axxx.online-server.cloud[private/dovecot-lmtp] said: 550 5.1.1
<bruno at 2c2axxx.online-server.cloud> User doesn't exist:
bruno at 2c2axxx.online-server.cloud (in reply to RCPT TO command))

[root at localhost bruno]# cat /etc/dovecot/users
#user:password:uid:gid:(gecos):home:(shell):extra_fields
bruno:{CRAM-MD5}88XXXee:5001:5001::/home/bruno::
mail:{CRAM-MD5}88XXXee:5001:5001::/home/mail::
nas:{CRAM-MD5}8eXXX40:5001:5001::/home/nas
asys:{CRAM-MD5}99XXX09:5001:5001::/home/asys
-------------- next part --------------
An HTML attachment was scrubbed...
URL:
<https://dovecot.org/pipermail/dovecot/attachments/20200814/0b086467/attachment.html>

J. Echter

2020-Aug-14 09:50 UTC

head link

User doesn't exist

Am 14.08.20 um 11:45 schrieb Bruno Kiste:> I try to use dovecot as a MTA with postfix.
> But it does not accept incoming mails.
> Why does it not work?
>
> [root at localhost bruno]# dovecot --version
> 2.3.8 (9df20d2db)
>
> /var/log/maillog
> Aug 14 11:03:32 localhost dovecot[566097]: lmtp(566373): Connect from
> local
> Aug 14 11:03:32 localhost dovecot[566097]:
> lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
> Debug: auth-master: userdb lookup(bruno at 2c2axxx.online-server.cloud):
> Started userdb lookup
> Aug 14 11:03:32 localhost dovecot[566097]:
> lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
> Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb: Connecting
> Aug 14 11:03:32 localhost dovecot[566097]:
> lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
> Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb: Client
> connected (fd=16)
> Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug: master in:
> USER#0111#011bruno at 2c2axxx.online-server.cloud#011service=lmtp
> Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
> passwd(bruno at 2c2axxx.online-server.cloud): Performing userdb lookup
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> Loading modules from directory: /usr/lib64/dovecot/auth
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> Module loaded: /usr/lib64/dovecot/auth/lib20_auth_var_expand_crypt.so
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> Module loaded: /usr/lib64/dovecot/auth/libdriver_sqlite.so
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> passwd-file /etc/dovecot/users: Read 4 users in 0 secs
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> conn unix:auth-worker (pid=566367,uid=97): Server accepted connection
> (fd=14)
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> conn unix:auth-worker (pid=566367,uid=97): Sending version handshake
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> conn unix:auth-worker (pid=566367,uid=97): auth-worker<1>: Handling
> USER request
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> passwd(bruno at 2c2axxx.online-server.cloud): Performing userdb lookup
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> passwd(bruno at 2c2axxx.online-server.cloud): lookup
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374):
> passwd(bruno at 2c2axxx.online-server.cloud): unknown user ?- trying the
> next userdb
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> passwd(bruno at 2c2axxx.online-server.cloud): Finished userdb lookup
> Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
> conn unix:auth-worker (pid=566367,uid=97): auth-worker<1>: Finished
> Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
> passwd(bruno at 2c2axxx.online-server.cloud): Finished userdb lookup
> Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
> passwd-file(bruno at 2c2axxx.online-server.cloud): Performing userdb lookup
> Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
> passwd-file(bruno at 2c2axxx.online-server.cloud): lookup:
> user=bruno at 2c2axxx.online-server.cloud file=/etc/dovecot/users
> Aug 14 11:03:32 localhost dovecot[566097]: auth:
> passwd-file(bruno at 2c2axxx.online-server.cloud): unknown user
> Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
> passwd-file(bruno at 2c2axxx.online-server.cloud): Finished userdb lookup
> Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug: userdb out:
> NOTFOUND#0111
> Aug 14 11:03:32 localhost dovecot[566097]:
> lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
> Debug: auth-master: userdb lookup(bruno at 2c2axxx.online-server.cloud):
> auth USER input:
> Aug 14 11:03:32 localhost dovecot[566097]:
> lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
> Debug: auth-master: userdb lookup(bruno at 2c2axxx.online-server.cloud):
> Userdb lookup failed
> Aug 14 11:03:32 localhost postfix/lmtp[566372]: 4E2127802973:
> to=<bruno at 2c2axxx.online-server.cloud>,
> relay=2c2axxx.online-server.cloud[private/dovecot-lmtp], delay=0.26,
> delays=0.19/0.02/0.02/0.02, dsn=5.1.1, status=bounced (host
> 2c2axxx.online-server.cloud[private/dovecot-lmtp] said: 550 5.1.1
> <bruno at 2c2axxx.online-server.cloud> User doesn't exist:
> bruno at 2c2axxx.online-server.cloud (in reply to RCPT TO command))
>
> [root at localhost bruno]# cat /etc/dovecot/users
> #user:password:uid:gid:(gecos):home:(shell):extra_fields
> bruno:{CRAM-MD5}88XXXee:5001:5001::/home/bruno::
> mail:{CRAM-MD5}88XXXee:5001:5001::/home/mail::
> nas:{CRAM-MD5}8eXXX40:5001:5001::/home/nas
> asys:{CRAM-MD5}99XXX09:5001:5001::/home/asys

Hi,

it seems to look for user bruno at 2c2axxx.online-server.cloud which
doesn't exist.

Bruno Kiste

2020-Aug-14 21:29 UTC

head link

User doesn't exist

Hello,

well, the user "bruno" does exist.
Can I make it ignore the hostname part or accept the user "bruno" as
"bruno at 2c2axxx.online-server.cloud"?
Can I change the lookup in dovecot or do I have to edit the postfix
configuration?

Best wishes,

Bruno

Am Fr., 14. Aug. 2020 um 11:50?Uhr schrieb J. Echter
<j.echter at echter-kuechen-elektro.de
<mailto:j.echter at echter-kuechen-elektro.de>>:

    Am 14.08.20 um 11:45 schrieb Bruno Kiste:
    > I try to use dovecot as a MTA with postfix.
    > But it does not accept incoming mails.
    > Why does it not work?
    >
    > [root at localhost bruno]# dovecot --version
    > 2.3.8 (9df20d2db)
    >
    > /var/log/maillog
    > Aug 14 11:03:32 localhost dovecot[566097]: lmtp(566373): Connect from
    > local
    > Aug 14 11:03:32 localhost dovecot[566097]:
    >
    lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
    > Debug: auth-master: userdb lookup(bruno at
2c2axxx.online-server.cloud):
    > Started userdb lookup
    > Aug 14 11:03:32 localhost dovecot[566097]:
    >
    lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
    > Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb: Connecting
    > Aug 14 11:03:32 localhost dovecot[566097]:
    >
    lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
    > Debug: auth-master: conn unix:/var/run/dovecot/auth-userdb: Client
    > connected (fd=16)
    > Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug: master in:
    > USER#0111#011bruno at 2c2axxx.online-server.cloud#011service=lmtp
    > Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
    > passwd(bruno at 2c2axxx.online-server.cloud): Performing userdb lookup
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > Loading modules from directory: /usr/lib64/dovecot/auth
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > Module loaded: /usr/lib64/dovecot/auth/lib20_auth_var_expand_crypt.so
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > Module loaded: /usr/lib64/dovecot/auth/libdriver_sqlite.so
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > passwd-file /etc/dovecot/users: Read 4 users in 0 secs
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > conn unix:auth-worker (pid=566367,uid=97): Server accepted connection
    > (fd=14)
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > conn unix:auth-worker (pid=566367,uid=97): Sending version handshake
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > conn unix:auth-worker (pid=566367,uid=97): auth-worker<1>:
Handling
    > USER request
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > passwd(bruno at 2c2axxx.online-server.cloud): Performing userdb lookup
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > passwd(bruno at 2c2axxx.online-server.cloud): lookup
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374):
    > passwd(bruno at 2c2axxx.online-server.cloud): unknown user ?- trying
the
    > next userdb
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > passwd(bruno at 2c2axxx.online-server.cloud): Finished userdb lookup
    > Aug 14 11:03:32 localhost dovecot[566097]: auth-worker(566374): Debug:
    > conn unix:auth-worker (pid=566367,uid=97): auth-worker<1>:
Finished
    > Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
    > passwd(bruno at 2c2axxx.online-server.cloud): Finished userdb lookup
    > Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
    > passwd-file(bruno at 2c2axxx.online-server.cloud): Performing userdb
    lookup
    > Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
    > passwd-file(bruno at 2c2axxx.online-server.cloud): lookup:
    > user=bruno at 2c2axxx.online-server.cloud file=/etc/dovecot/users
    > Aug 14 11:03:32 localhost dovecot[566097]: auth:
    > passwd-file(bruno at 2c2axxx.online-server.cloud): unknown user
    > Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug:
    > passwd-file(bruno at 2c2axxx.online-server.cloud): Finished userdb
lookup
    > Aug 14 11:03:32 localhost dovecot[566097]: auth: Debug: userdb out:
    > NOTFOUND#0111
    > Aug 14 11:03:32 localhost dovecot[566097]:
    >
    lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
    > Debug: auth-master: userdb lookup(bruno at
2c2axxx.online-server.cloud):
    > auth USER input:
    > Aug 14 11:03:32 localhost dovecot[566097]:
    >
    lmtp(bruno at
2c2axxx.online-server.cloud)<566373><0O+4HWRTNl9lpAgA0J78UA>:
    > Debug: auth-master: userdb lookup(bruno at
2c2axxx.online-server.cloud):
    > Userdb lookup failed
    > Aug 14 11:03:32 localhost postfix/lmtp[566372]: 4E2127802973:
    > to=<bruno at 2c2axxx.online-server.cloud>,
    > relay=2c2axxx.online-server.cloud[private/dovecot-lmtp], delay=0.26,
    > delays=0.19/0.02/0.02/0.02, dsn=5.1.1, status=bounced (host
    > 2c2axxx.online-server.cloud[private/dovecot-lmtp] said: 550 5.1.1
    > <bruno at 2c2axxx.online-server.cloud> User doesn't exist:
    > bruno at 2c2axxx.online-server.cloud (in reply to RCPT TO command))
    >
    > [root at localhost bruno]# cat /etc/dovecot/users
    > #user:password:uid:gid:(gecos):home:(shell):extra_fields
    > bruno:{CRAM-MD5}88XXXee:5001:5001::/home/bruno::
    > mail:{CRAM-MD5}88XXXee:5001:5001::/home/mail::
    > nas:{CRAM-MD5}8eXXX40:5001:5001::/home/nas
    > asys:{CRAM-MD5}99XXX09:5001:5001::/home/asys


    Hi,

    it seems to look for user bruno at 2c2axxx.online-server.cloud which
    doesn't exist.

Possibly Parallel Threads

Search for more possibly parallel threads

dovecot - Aug 2020 - User doesn't exist

User doesn't exist

User doesn't exist

User doesn't exist

Possibly Parallel Threads