Postfix Fehler 250


Bratkartoffel

gebratene Kartoffel
Premium-User
#21
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
 
#24
@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

gebratene Kartoffel
Premium-User
#25
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
 
#26
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
 
Zuletzt bearbeitet:

Bratkartoffel

gebratene Kartoffel
Premium-User
#27
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
 
#28
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

gebratene Kartoffel
Premium-User
#31
Hi,

jetzt bin ich langsam auch ratlos. Habe gerade in meine Dovecot Config geschaut und mit dir verglichen. Den einzigen relevanten Unterschied, den ich zu dir habe liegt in der password_query:
Code:
password_query = \
  SELECT \
        username AS user, \
        password
  FROM nss_passwd \
  WHERE username = '%n'
Wie du siehst selektiere ich nicht nur das Passwort, sondern auch den Usernamen. Wenn das jetzt auch nichts hilft, dann muss ich leider hier aufgeben.

Grüsse,
BK
 
#32
Hallo @Bratkartoffel

ich habe wahrscheinlich den Fehler gefunden, in der Postfix Configuration gab es einen Schreibfehler der verursachte das das SSL Zertifikat nicht geladen werden konnte bzw. der Path war nicht vorhanden. Ich hab dies behoben, zwar geht Roundcube nicht mehr aus unerklärlichen Gründen aber über jedenen anderen Client funktioniert es wieder. Vielen Dank für deine Hilfe !

Liebe Grüße
Robert