✔ Postfix Fehler 250

Bratkartoffel · 6. Juli 2016

Hi,

Code:

Jul  6 15:34:10 utopano dovecot: auth-worker(12806): pam(support@utopano.de,meineIp): pam_authenticate() failed: Authentication failure (password mismatch?)
Jul  6 15:34:10 utopano dovecot: auth-worker(12806): pam(support@utopano.de,meineIp): unknown user

Der Dovecot versucht die Authentifizierung über PAM (also über das System). Kann hier aber den Benutzer nicht finden, ist auch verständlich. Du musst Dovecot noch so einrichten, dass dieser ebenfalls über MySQL die Infos holt und das Passwort prüft.

http://wiki2.dovecot.org/AuthDatabase/SQL

Grüsse,
BK

rob_utopano · 6. Juli 2016

Das Problem ist dass es schon damit verbunden ist !
@Bratkartoffel

Bratkartoffel · 7. Juli 2016

Hi,

wie sieht die dovecot config aus?

Grüsse,
BK

rob_utopano · 7. Juli 2016

@Bratkartoffel

Code:

dict {
  #quota = mysql:/etc/dovecot/dovecot-dict-sql.conf.ext
  #expire = sqlite:/etc/dovecot/dovecot-dict-sql.conf.ext
}

# Most of the actual configuration gets included below. The filenames are
# first sorted by their ASCII value and parsed in that order. The 00-prefixes
# in filenames are intended to make it easier to understand the ordering.
!include conf.d/*.conf

# A config file can also tried to be included without giving an error if
# it's not found:
!include_try local.conf

auth_mechanisms = plain login
log_timestamp = "%Y-%m-%d %H:%M:%S "
passdb {
  args = /etc/dovecot/dovecot-mysql.conf
  driver = sql
}
protocols = imap pop3
service auth {
  unix_listener /var/spool/postfix/private/auth_dovecot {
    group = postfix
    mode = 0660
    user = postfix
  }
  unix_listener auth-master {
    mode = 0600
    user = vmail
  }
  user = root
}
ssl = yes
ssl_cert = <***
ssl_key = <***
userdb {
  args = /etc/dovecot/dovecot-mysql.conf
  driver = sql
}
protocol pop3 {
  pop3_uidl_format = %08Xu%08Xv
  pop3_client_workarounds = oe-ns-eoh
#   pop3_uidl_format = %v.%u
}
protocol lda {
  auth_socket_path = /var/run/dovecot/auth-master
  postmaster_address = admin@utopano.de
}
auth_verbose=yes

Bratkartoffel · 7. Juli 2016

Hi,

Deine Config sieht auf den ersten Blick richtig aus, kann hier keinen offensichtlichen Fehler entdecken.
Tausche mal bitte das "auth_verbose=yes" durch ein "auth_debug=yes" aus. Danach Dovecot neustarten, neu testen und Log hier posten.

Grüsse,
BK

rob_utopano · 9. Juli 2016

Hey @Bratkartoffel

hier ist es

Code:

Jul  9 08:47:55 utopano dovecot: auth: Debug: auth client connected (pid=29573)
Jul  9 08:47:55 utopano dovecot: auth: Debug: client in: AUTH#0111#011PLAIN#011service=imap#011secured#011session=ld+BTC43lAAFAVIp#011lip=meineIP#011rip=meineIP#011lport=143#011rport=53908#011resp=<hidden>
Jul  9 08:47:55 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): lookup service=dovecot
Jul  9 08:47:55 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): #1/1 style=1 msg=Password:
Jul  9 08:47:56 utopano dovecot: auth-worker(29477): pam(support@utopano.de,meineIP): pam_authenticate() failed: Authentication failure (password mismatch?)
Jul  9 08:47:56 utopano dovecot: auth-worker(29477): Debug: sql(support@utopano.de,meineIP): query: SELECT password FROM mailbox WHERE username = 'support@utopano.de'
Jul  9 08:47:56 utopano dovecot: auth: Debug: client passdb out: OK#0111#011user=support@utopano.de
Jul  9 08:47:56 utopano dovecot: auth: Debug: master in: REQUEST#0113242983425#01129573#0111#01176e7dc227b23c737cb99932dcdccab68#011session_pid=29574#011request_auth_token
Jul  9 08:47:56 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): lookup
Jul  9 08:47:56 utopano dovecot: auth-worker(29477): pam(support@utopano.de,meineIP): unknown user
Jul  9 08:47:56 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): SELECT CONCAT('maildir:/var/vmail/',maildir) AS mail, 6000 AS uid, 6000 AS gid FROM mailbox WHERE username = 'support@utopano.de'
Jul  9 08:47:56 utopano dovecot: auth: Debug: master userdb out: USER#0113242983425#011support@utopano.de#011mail=maildir:/var/vmail/utopano.de/support/#011uid=6000#011gid=6000#011auth_token=70ff4285200bc9ba7fc653036c511ba95d8502b5
Jul  9 08:47:56 utopano dovecot: imap-login: Login: user=<support@utopano.de>, method=PLAIN, rip=meineIP, lip=meineIP, mpid=29574, secured, session=<ld+BTC43lAAFAVIp>
Jul  9 08:47:56 utopano dovecot: imap(support@utopano.de): Disconnected: Logged out in=82 out=568
Jul  9 08:47:56 utopano dovecot: auth: Debug: auth client connected (pid=29575)
Jul  9 08:47:56 utopano dovecot: auth: Debug: client in: AUTH#0111#011PLAIN#011service=imap#011secured#011session=6HCYTC43uAAFAVIp#011lip=meineIP#011rip=meineIP#011lport=143#011rport=53944#011resp=<hidden>
Jul  9 08:47:56 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): lookup service=dovecot
Jul  9 08:47:56 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): #1/1 style=1 msg=Password:
Jul  9 08:47:58 utopano dovecot: auth-worker(29477): pam(support@utopano.de,meineIP): pam_authenticate() failed: Authentication failure (password mismatch?)
Jul  9 08:47:58 utopano dovecot: auth-worker(29477): Debug: sql(support@utopano.de,meineIP): query: SELECT password FROM mailbox WHERE username = 'support@utopano.de'
Jul  9 08:47:58 utopano dovecot: auth: Debug: client passdb out: OK#0111#011user=support@utopano.de
Jul  9 08:47:58 utopano dovecot: auth: Debug: master in: REQUEST#0112275672065#01129575#0111#0111d91b880d0f3e1849c5f3af41af3c3ce#011session_pid=29582#011request_auth_token
Jul  9 08:47:58 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): lookup
Jul  9 08:47:58 utopano dovecot: auth-worker(29477): pam(support@utopano.de,meineIP): unknown user
Jul  9 08:47:58 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): SELECT CONCAT('maildir:/var/vmail/',maildir) AS mail, 6000 AS uid, 6000 AS gid FROM mailbox WHERE username = 'support@utopano.de'
Jul  9 08:47:58 utopano dovecot: auth: Debug: master userdb out: USER#0112275672065#011support@utopano.de#011mail=maildir:/var/vmail/utopano.de/support/#011uid=6000#011gid=6000#011auth_token=dfc2b2a32b09aa94d5f4eb853d21935143c3c8d1
Jul  9 08:47:58 utopano dovecot: imap-login: Login: user=<support@utopano.de>, method=PLAIN, rip=5.1.82.41, lip=5.1.82.41, mpid=29582, secured, session=<6HCYTC43uAAFAVIp>
Jul  9 08:47:58 utopano dovecot: imap(support@utopano.de): Disconnected: Logged out in=44 out=492
Jul  9 08:47:59 utopano dovecot: auth: Debug: auth client connected (pid=29583)
Jul  9 08:47:59 utopano dovecot: auth: Debug: client in: AUTH#0111#011PLAIN#011service=imap#011secured#011session=MgrETC431wAFAVIp#011lip=meineIP#011rip=meineIP#011lport=143#011rport=53975#011resp=<hidden>
Jul  9 08:47:59 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP: lookup service=dovecot
Jul  9 08:47:59 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): #1/1 style=1 msg=Password:
Jul  9 08:47:59 utopano dovecot: auth: Debug: auth client connected (pid=29584)
Jul  9 08:47:59 utopano dovecot: auth: Debug: client in: AUTH#0111#011PLAIN#011service=imap#011secured#011session=bUDETC432AAFAVIp#011lip=meineIP#011rip=meineIP#011lport=143#011rport=53976#011resp=<hidden>
Jul  9 08:47:59 utopano dovecot: auth-worker(29496): Debug: pam(support@utopano.de,meineIP): lookup service=dovecot
Jul  9 08:47:59 utopano dovecot: auth-worker(29496): Debug: pam(support@utopano.de,meineIP): #1/1 style=1 msg=Password:
Jul  9 08:48:01 utopano postfix/pickup[29517]: 5326C10201B7: uid=0 from=<root>
Jul  9 08:48:01 utopano postfix/cleanup[27110]: 5326C10201B7: message-id=<20160709064801.5326C10201B7@mx.utopano.de>
Jul  9 08:48:01 utopano postfix/qmgr[575]: 5326C10201B7: from=<root@mx.utopano.de>, size=654, nrcpt=1 (queue active)
Jul  9 08:48:01 utopano postfix/pickup[29517]: 5596D10201AD: uid=0 from=<root>
Jul  9 08:48:01 utopano postfix/cleanup[25842]: 5596D10201AD: message-id=<20160709064801.5596D10201AD@mx.utopano.de>
Jul  9 08:48:01 utopano postfix/qmgr[575]: 5596D10201AD: from=<root@mx.utopano.de>, size=632, nrcpt=1 (queue active)
Jul  9 08:48:01 utopano postfix/virtual[24115]: 5326C10201B7: to=<root@mx.utopano.de>, orig_to=<root>, relay=virtual, delay=0.03, delays=0.02/0/0/0, dsn=5.1.1, status=bounced (unknown user: "root@mx.utopano.de")
Jul  9 08:48:01 utopano postfix/virtual[25185]: 5596D10201AD: to=<root@mx.utopano.de>, orig_to=<root>, relay=virtual, delay=0.01, delays=0/0/0/0, dsn=5.1.1, status=bounced (unknown user: "root@mx.utopano.de")
Jul  9 08:48:01 utopano postfix/cleanup[25842]: 573E51020399: message-id=<20160709064801.573E51020399@mx.utopano.de>
Jul  9 08:48:01 utopano postfix/cleanup[27110]: 5713A1020396: message-id=<20160709064801.5713A1020396@mx.utopano.de>
Jul  9 08:48:01 utopano postfix/qmgr[575]: 573E51020399: from=<>, size=2487, nrcpt=1 (queue active)
Jul  9 08:48:01 utopano postfix/bounce[27730]: 5596D10201AD: sender non-delivery notification: 573E51020399
Jul  9 08:48:01 utopano postfix/bounce[29006]: 5326C10201B7: sender non-delivery notification: 5713A1020396
Jul  9 08:48:01 utopano postfix/qmgr[575]: 5596D10201AD: removed
Jul  9 08:48:01 utopano postfix/qmgr[575]: 5326C10201B7: removed
Jul  9 08:48:01 utopano postfix/qmgr[575]: 5713A1020396: from=<>, size=2509, nrcpt=1 (queue active)
Jul  9 08:48:01 utopano postfix/virtual[25185]: 5713A1020396: to=<root@mx.utopano.de>, relay=virtual, delay=0, delays=0/0/0/0, dsn=5.1.1, status=bounced (unknown user: "root@mx.utopano.de")
Jul  9 08:48:01 utopano postfix/qmgr[575]: 5713A1020396: removed
Jul  9 08:48:01 utopano postfix/virtual[27731]: 573E51020399: to=<root@mx.utopano.de>, relay=virtual, delay=0, delays=0/0/0/0, dsn=5.1.1, status=bounced (unknown user: "root@mx.utopano.de")
Jul  9 08:48:01 utopano postfix/qmgr[575]: 573E51020399: removed
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): pam(support@utopano.de,meineIP): pam_authenticate() failed: Authentication failure (password mismatch?)
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): Debug: sql(support@utopano.de,meineIP): query: SELECT password FROM mailbox WHERE username = 'support@utopano.de'
Jul  9 08:48:02 utopano dovecot: auth: Debug: client passdb out: OK#0111#011user=support@utopano.de
Jul  9 08:48:02 utopano dovecot: auth: Debug: master in: REQUEST#0112586050561#01129583#0111#01163fd77af2ab9ab3968ed62ce78c4e991#011session_pid=29604#011request_auth_token
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): lookup
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): pam(support@utopano.de,meineIP): unknown user
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): SELECT CONCAT('maildir:/var/vmail/',maildir) AS mail, 6000 AS uid, 6000 AS gid FROM mailbox WHERE username = 'support@utopano.de'
Jul  9 08:48:02 utopano dovecot: auth: Debug: master userdb out: USER#0112586050561#011support@utopano.de#011mail=maildir:/var/vmail/utopano.de/support/#011uid=6000#011gid=6000#011auth_token=f5de940e3e14abc460c6dda1455ce4d666583475
Jul  9 08:48:02 utopano dovecot: imap-login: Login: user=<support@utopano.de>, method=PLAIN, rip=meineIP, lip=meineIP, mpid=29604, secured, session=<MgrETC431wAFAVIp>
Jul  9 08:48:02 utopano dovecot: auth-worker(29496): pam(support@utopano.de,meineIP): pam_authenticate() failed: Authentication failure (password mismatch?)
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): Debug: sql(support@utopano.demeineIP): query: SELECT password FROM mailbox WHERE username = 'support@utopano.de'
Jul  9 08:48:02 utopano dovecot: auth: Debug: client passdb out: OK#0111#011user=support@utopano.de
Jul  9 08:48:02 utopano dovecot: auth: Debug: master in: REQUEST#0113913547777#01129584#0111#0119dab40812d99a0e77453a1f1cd590a93#011session_pid=29605#011request_auth_token
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,meineIP): lookup
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): pam(support@utopano.de,meineIP): unknown user
Jul  9 08:48:02 utopano dovecot: auth-worker(29477): Debug: pam(support@utopano.de,5.1.82.41): SELECT CONCAT('maildir:/var/vmail/',maildir) AS mail, 6000 AS uid, 6000 AS gid FROM mailbox WHERE username = 'support@utopano.de'
Jul  9 08:48:02 utopano dovecot: auth: Debug: master userdb out: USER#0113913547777#011support@utopano.de#011mail=maildir:/var/vmail/utopano.de/support/#011uid=6000#011gid=6000#011auth_token=b3a3a8ca17b1457d4ed5bb86483a4c523261463f
Jul  9 08:48:02 utopano dovecot: imap-login: Login: user=<support@utopano.de>, method=PLAIN, rip=meineIP, lip=meineIP mpid=29605, secured, session=<bUDETC432AAFAVIp>
Jul  9 08:48:02 utopano dovecot: imap(support@utopano.de): Disconnected: Logged out in=50 out=469
Jul  9 08:48:02 utopano dovecot: imap(support@utopano.de): Disconnected: Logged out in=157 out=689

Bratkartoffel · 11. Juli 2016

Hi,

sieht so aus, als ob dein konfigurierter MySQL Benutzer keinen Zugriff auf das Passwort des Users hat:

Jul 9 08:47:56 utopano dovecot: auth-worker(29477): Debug: sql(support@utopano.de,meineIP): query: SELECT password FROM mailbox WHERE username = 'support@utopano.de'
Jul 9 08:47:56 utopano dovecot: auth: Debug: client passdb out: OK#0111#011user=support@utopano.de

Die erste Query im Log liefert kein Ergebnis wenn ich das richtig sehe. Da die zweite Query im obigen Log das richtige Ergebnis liefert sieht es nach einem Rechteproblem aus. Kannst du dich mal mit dem Dovecot-MySQL-User an der DB anmelden und folgende Queries schicken?

SQL:

SELECT password FROM mailbox WHERE username = 'support@utopano.de'
SELECT CONCAT('maildir:/var/vmail/',maildir) AS mail, 6000 AS uid, 6000 AS gid FROM mailbox WHERE username = 'support@utopano.de'

Grüsse,
BK

rob_utopano · 11. Juli 2016

Hallo @Bratkartoffel

Code:

#1064 - You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near 'SELECT CONCAT('maildir:/var/vmail/',maildir) AS mail, 6000 AS uid, 6000 AS gid F' at line 2

Bratkartoffel · 11. Juli 2016

Hi,

sorry, etwas ungenau von mir beschrieben. Das oben sind zwei separate SQL Queries, also bitte einzeln ausführen.

Grüsse,
BK

rob_utopano · 11. Juli 2016

@Bratkartoffel
1.Querie:
password
passwortausgabe

2.Querie:
mail uid gid
maildir:/var/vmail/utopano.de/support/ 6000 6000

✔ Postfix Fehler 250

Bratkartoffel

gebratene Kartoffel

rob_utopano

Mitglied

Bratkartoffel

gebratene Kartoffel

rob_utopano

Mitglied

Bratkartoffel

gebratene Kartoffel

rob_utopano

Mitglied

Bratkartoffel

gebratene Kartoffel

rob_utopano

Mitglied

Bratkartoffel

gebratene Kartoffel

rob_utopano

Mitglied

Neue Beiträge