Re: 4.8 mit postfix

On Tue, Aug 05, 2003 at 04:00:38PM +02009423, Jan Siml wrote:
> Der gegenüberliegende Server hat ein Problem mit Deiner Authentifizierung.
> Passwort und Benutzernamen nochmal überpüft?

Yep, habe ich gemacht. Sollte passen.
Ich habe im Passwort auch einen Punkt mit drin(Bsp.: .geheim), das
sollte allerdings wohl keine Probleme geben.

> > relayhost = provider.hostname.de <-- hier steht ein richtiger Eintrag
>
> Du solltest
>
> relayhost = [provider.hostname.de]
>
> schreiben.

Habe ich geändert, allerdings hatte es keine Auswirkungen.

> >> Hast Du denn auch alle Parameter, die SASL betreffen die main.cf von
> >> Postfix gestopft?
> >
> > Aus /usr/local/etc/postfic/main.cf:
> >
> > ...
> >
> > #SMTP mit SASL-Authentification verwenden
> > smtp_sasl_auth_enable = yes
> > #Die Paßwörter stehen in der Datei /etc/postfix/smtp_auth
> > smtp_sasl_password_maps = hash:/usr/local/etc/postfix/smtp_auth
>
> Das ist alles richtig. Hast Du das mit postconf überprüft oder ist das aus
> der datei kopiert? Nur die Angaben die postconf aufwirft, sind korrekt.

Aus main.cf habe ich rauskopiert.
Die Ausgabe von postconf habe ich hier gepostet.

> > #Zusatz-Optionen: Keine anonyme-Anmeldung verwenden
> > smtp_sasl_security_options = noanonymous
> >
> > Ich habe auch das file smtp_auth erzeugt und via postmap smtp_auth
> > umgewandelt.
>
> Das wollte ich gerade fragen. Ist die Datei auch für Postfix lesbar (644)?

-rw-r--r-- 1 root wheel 39 5 Aug 16:15 smtp_auth
Sollte passen.

>
> Könntest Du mal das Verbose-Logging für smtp (smtp unix - - - - - smtp -v)
> in der master.cf aktivieren und dann nochmal den entsprechenden Auszug aus
> dem Logfile posten?

Hier das Log(sorry ist etwas lang):

Aug 5 16:25:35 baldur postfix/postfix-script: refreshing the Postfix mail system
Aug 5 16:25:35 baldur postfix/master[34034]: reload configuration
Aug 5 16:26:02 baldur postfix/pickup[35766]: E4398102: uid=1001 from=<usenet(at)esct.org>
Aug 5 16:26:02 baldur postfix/cleanup[35778]: E4398102: message-id=<20030805142602.GR13484(at)baldur.asgard>
Aug 5 16:26:02 baldur postfix/qmgr[35767]: E4398102: from=<usenet(at)esct.org>, size=405, nrcpt=1 (queue active)
Aug 5 16:26:02 baldur postfix/smtp[35780]: name_mask: subnet
Aug 5 16:26:02 baldur postfix/smtp[35780]: mynetworks: 192.168.100.0/24 127.0.0.0/8
Aug 5 16:26:02 baldur postfix/smtp[35780]: maps_append: hash:/usr/local/etc/postfix/smtp_auth
Aug 5 16:26:02 baldur postfix/smtp[35780]: dict_open: hash:/usr/local/etc/postfix/smtp_auth
Aug 5 16:26:02 baldur postfix/smtp[35780]: name_mask: noanonymous
Aug 5 16:26:02 baldur postfix/smtp[35780]: watchdog_create: 0x8070c48 18000
Aug 5 16:26:02 baldur postfix/smtp[35780]: watchdog_stop: 0x8070c48
Aug 5 16:26:02 baldur postfix/smtp[35780]: watchdog_start: 0x8070c48
Aug 5 16:26:02 baldur postfix/smtp[35780]: connection established
Aug 5 16:26:02 baldur postfix/smtp[35780]: master_notify: status 0
Aug 5 16:26:02 baldur postfix/smtp[35780]: deliver_request_initial: send initial status
Aug 5 16:26:02 baldur postfix/smtp[35780]: send attr status = 0
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: flags
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: flags
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: 3
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: queue_name
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: queue_name
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: active
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: queue_id
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: queue_id
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: E4398102
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: offset
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: offset
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: 151
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: size
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: size
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: 405
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: nexthop
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: nexthop
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: [provider]
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: encoding
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: encoding
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: (end)
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: sender
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: sender
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: usenet(at)esct.org
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: errors-to
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: errors-to
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: usenet(at)esct.org
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: return-receipt
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: return-receipt
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: (end)
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: time
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: time
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: 1060093562
Aug 5 16:26:02 baldur postfix/smtp[35780]: smtp socket: wanted attribute: offset
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: offset
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute value: 117
Aug 5 16:26:02 baldur postfix/smtp[35780]: input attribute name: recipient
Aug 5 16:26:02 baldur postfix/smtp[35780]: dns_query: provider (A): OK
Aug 5 16:26:02 baldur postfix/smtp[35780]: dns_get_answer: type A for provider
Aug 5 16:26:02 baldur postfix/smtp[35780]: begin provider address list
Aug 5 16:26:02 baldur postfix/smtp[35780]: pref 0 host provider/provider.ip
Aug 5 16:26:02 baldur postfix/smtp[35780]: end provider address list
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 220 mail.q-space.de ESMTP
Aug 5 16:26:03 baldur postfix/smtp[35780]: > provider[provider.ip]: EHLO baldur.asgard
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-cwnet5.q-space.de
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-PIPELINING
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-SIZE 20480000
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-VRFY
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-ETRN
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-STARTTLS
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-AUTH CRAM-MD5 DIGEST-MD5 LOGIN PLAIN
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-AUTH=CRAM-MD5 DIGEST-MD5 LOGIN PLAIN
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250-XVERP
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 250 8BITMIME
Aug 5 16:26:03 baldur postfix/smtp[35780]: server features: 0x2f size 20480000
Aug 5 16:26:03 baldur postfix/smtp[35780]: maps_find: smtp_sasl_passwd: hash:/usr/local/etc/postfix/smtp_auth: provider = username:geheim
Aug 5 16:26:03 baldur postfix/smtp[35780]: smtp_sasl_passwd_lookup: host `provider' user `username' pass `geheim'
Aug 5 16:26:03 baldur postfix/smtp[35780]: starting new SASL client
Aug 5 16:26:03 baldur postfix/smtp[35780]: smtp_sasl_authenticate: provider[provider.ip]: SASL mechanisms CRAM-MD5 DIGEST-MD5 LOGIN PLAIN
Aug 5 16:26:03 baldur postfix/smtp[35780]: > provider[provider.ip]: AUTH DIGEST-MD5
Aug 5 16:26:03 baldur postfix/smtp[35780]: < provider[provider.ip]: 334 cmVhbG09ImN3bmV0NSIsbm9uY2U9IjNHVGpvUzF2N3dacFp3ZS8zRTNxT0N2aTNNKzYzczk4b3hKRWVmL29NWFE9Iixxb3A9ImF1dGgsYXV0aC1pbnQiLGNoYXJzZXQ9dXRmLTgsYWxnb3JpdGhtPW1kNS1zZXNz
Aug 5 16:26:03 baldur postfix/smtp[35780]: smtp_sasl_authenticate: provider[provider.ip]: decoded challenge: realm="cwnet5",nonce="3GTjoS1v7wZpZwe/3E3qOCvi3M+63s98oxJEef/oMXQ=",qop="auth,auth-int",charset=utf-8,algorithm=md5-sess
Aug 5 16:26:03 baldur postfix/smtp[35780]: smtp_sasl_get_user: username
Aug 5 16:26:03 baldur postfix/smtp[35780]: smtp_sasl_get_user: username
Aug 5 16:26:03 baldur postfix/smtp[35780]: smtp_sasl_get_passwd: geheim
Aug 5 16:26:03 baldur postfix/smtp[35780]: smtp_sasl_authenticate: provider[provider.ip]: uncoded client response username="username",realm="cwnet5",nonce="3GTjoS1v7wZpZwe/3E3qOCvi3M+63s98oxJEef/oMXQ=",cnonce="hvwt00uhyKDYAVUhApXhazyY4ONRMqIjQU0uehUMnoc=",nc=00000001,qop=auth,digest-uri="smtp/provider",response=6408c91d78cda21e4bfd558fbcd24a99
Aug 5 16:26:03 baldur postfix/smtp[35780]: > provider[provider.ip]: dXNlcm5hbWU9ImVzY3QzIixyZWFsbT0iY3duZXQ1Iixub25jZT0iM0dUam9TMXY3d1pwWndlLzNFM3FPQ3ZpM00rNjNzOThveEpFZWYvb01YUT0iLGNub25jZT0iaHZ3dDAwdWh5S0RZQVZVaEFwWGhhenlZNE9OUk1xSWpRVTB1ZWhVTW5vYz0iLG5jPTAwMDAwMDAxLHFvcD1hdXRoLGRpZ2VzdC11cmk9InNtdHAvY3duZXQ1LndpcmVsZXNzbmV0LmRlIixyZXNwb25zZT02NDA4YzkxZDc4Y2RhMjFlNGJmZDU1OGZiY2QyNGE5OQ==
Aug 5 16:26:08 baldur postfix/smtp[35780]: < provider[provider.ip]: 535 Error: authentication failed
Aug 5 16:26:08 baldur postfix/smtp[35780]: connect to subsystem private/defer
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr nrequest = 0
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr flags = 0
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr queue_id = E4398102
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr original_recipient = usenet(at)esct.org
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr recipient = usenet(at)esct.org
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr reason = Authentication failed: SASL authentication failed; server provider[provider.ip] said: 535 Error: authentication failed
Aug 5 16:26:08 baldur postfix/smtp[35780]: private/defer socket: wanted attribute: status
Aug 5 16:26:08 baldur postfix/smtp[35780]: input attribute name: status
Aug 5 16:26:08 baldur postfix/smtp[35780]: input attribute value: 0
Aug 5 16:26:08 baldur postfix/smtp[35780]: private/defer socket: wanted attribute: (list terminator)
Aug 5 16:26:08 baldur postfix/smtp[35780]: input attribute name: (end)
Aug 5 16:26:08 baldur postfix/smtp[35780]: E4398102: to=<usenet(at)esct.org>, relay=provider[provider.ip], delay=6, status=deferred (Authentication failed: SASL authentication failed; server provider[provider.ip] said: 535 Error: authentication failed)
Aug 5 16:26:08 baldur postfix/smtp[35780]: flush_add: site esct.org id E4398102
Aug 5 16:26:08 baldur postfix/smtp[35780]: connect to subsystem public/flush
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr request = add
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr site = esct.org
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr queue_id = E4398102
Aug 5 16:26:08 baldur postfix/smtp[35780]: public/flush socket: wanted attribute: status
Aug 5 16:26:08 baldur postfix/smtp[35780]: input attribute name: status
Aug 5 16:26:08 baldur postfix/smtp[35780]: input attribute value: 4
Aug 5 16:26:08 baldur postfix/smtp[35780]: public/flush socket: wanted attribute: (list terminator)
Aug 5 16:26:08 baldur postfix/smtp[35780]: input attribute name: (end)
Aug 5 16:26:08 baldur postfix/smtp[35780]: flush_add: site esct.org id E4398102 status 4
Aug 5 16:26:08 baldur postfix/smtp[35780]: name_mask: resource
Aug 5 16:26:08 baldur postfix/smtp[35780]: name_mask: software
Aug 5 16:26:08 baldur postfix/smtp[35780]: disposing SASL state information
Aug 5 16:26:08 baldur postfix/smtp[35780]: deliver_request_final: send: "Authentication failed: SASL authentication failed; server provider[provider.ip] said: 535 Error: authentication failed" -1
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr reason = Authentication failed: SASL authentication failed; server provider[provider.ip] said: 535 Error: authentication failed
Aug 5 16:26:08 baldur postfix/smtp[35780]: send attr status = 4294967295
Aug 5 16:26:08 baldur postfix/smtp[35780]: master_notify: status 1
Aug 5 16:26:08 baldur postfix/smtp[35780]: connection closed
Aug 5 16:26:08 baldur postfix/smtp[35780]: watchdog_stop: 0x8070c48
Aug 5 16:26:08 baldur postfix/smtp[35780]: watchdog_start: 0x8070c48

Ufff, das war es ...

Andreas -

To Unsubscribe: send mail to majordomo.FreeBSD.org
with "unsubscribe de-bsd-questions" in the body of the message
Received on Tue 05 Aug 2003 - 17:15:00 CEST