[linux-l] Postfix-Problem: Smtp-Auth
Sebastian Andres
sebastian at sebastianandres.de
Do Okt 27 23:41:34 CEST 2005
Hi!
Habe folgendes Problem mit Postfix:
Verwende ein Debian Sarge. Habe die beiden Pakete Postfix und
postfix-tls mittels apt-get installiert.
Postfix soll sich jetzt als Client an einem Server authentifizieren
mittels smtp-Auth und die Mails weiterleiten. Unter Windows lassen
sich die Mails problemlos an den Server schicken. Hier einmal der
Auszug aus der datei /var/log/mail.info:
Oct 27 23:16:50 server postfix/qmgr[2388]: 79A5B880EB:
from=<sa at server.local>, size=635, nrcpt=1 (queue active)
Oct 27 23:16:50 server postfix/smtp[3114]: name_mask: dns
Oct 27 23:16:50 server postfix/smtp[3114]: host name lookup methods:
dns
Oct 27 23:16:50 server postfix/smtp[3114]: dict_open:
hash:/etc/postfix/smtp_auth
Oct 27 23:16:50 server postfix/smtp[3114]: match_string:
fast_flush_domains ~? debug_peer_list
Oct 27 23:16:50 server postfix/smtp[3114]: match_string:
fast_flush_domains ~? fast_flush_domains
Oct 27 23:16:50 server postfix/smtp[3114]: connection established
Oct 27 23:16:50 server postfix/smtp[3114]: master_notify: status 0
Oct 27 23:16:50 server postfix/smtp[3114]: deliver_request_initial:
send initial status
Oct 27 23:16:50 server postfix/smtp[3114]: send attr status = 0
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: flags
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name: flags
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value: 3
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: queue_name
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
queue_name
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
active
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: queue_id
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
queue_id
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
79A5B880EB
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: offset
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
offset
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value: 170
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: size
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name: size
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value: 635
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: nexthop
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
nexthop
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
sebastianandres.de
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: encoding
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
encoding
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
(end)
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: sender
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
sender
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
sa at server.local
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: errors-to
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
errors-to
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
sa at server.local
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: return-receipt
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
return-receipt
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
(end)
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: time
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name: time
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
1130447810
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: client_name
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
client_name
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
(end)
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: client_address
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
client_address
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
(end)
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: protocol_name
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
protocol_name
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
(end)
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: helo_name
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
helo_name
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
(end)
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: offset
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
offset
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value: 151
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: original_recipient
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
original_recipient
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
s_andres at web.de
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: recipient
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
recipient
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value:
s_andres at web.de
Oct 27 23:16:50 server postfix/smtp[3114]: smtp socket: wanted
attribute: offset
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute name:
offset
Oct 27 23:16:50 server postfix/smtp[3114]: input attribute value: 0
Oct 27 23:16:50 server postfix/smtp[3114]: deliver_request_get: file
active/7/79A5B880EB
Oct 27 23:16:50 server postfix/smtp[3114]: deliver_message: from
sa at server.local
Oct 27 23:16:50 server postfix/smtp[3114]: smtp_parse_destination:
sebastianandres.de smtp
Oct 27 23:16:50 server postfix/smtp[3114]: connecting to
sebastianandres.de port 25
Oct 27 23:16:50 server postfix/smtp[3114]: dns_query:
sebastianandres.de (MX): OK
Oct 27 23:16:50 server postfix/smtp[3114]: dns_get_answer: type MX for
sebastianandres.de
Oct 27 23:16:50 server postfix/smtp[3114]: dns_get_answer: type MX for
sebastianandres.de
Oct 27 23:16:50 server postfix/smtp[3114]: smtp_addr_one: host
sebastianandres.de
Oct 27 23:16:50 server postfix/smtp[3114]: smtp_addr_one: host
mx01.alturo.net
Oct 27 23:16:51 server postfix/smtp[3114]: begin sebastianandres.de
address list
Oct 27 23:16:51 server postfix/smtp[3114]: pref 10 host
sebastianandres.de/82.165.25.176
Oct 27 23:16:51 server postfix/smtp[3114]: pref 20 host
mx01.alturo.net/212.227.15.134
Oct 27 23:16:51 server postfix/smtp[3114]: pref 20 host
mx01.alturo.net/212.227.15.150
Oct 27 23:16:51 server postfix/smtp[3114]: pref 20 host
mx01.alturo.net/212.227.15.169
Oct 27 23:16:51 server postfix/smtp[3114]: pref 20 host
mx01.alturo.net/212.227.15.186
Oct 27 23:16:51 server postfix/smtp[3114]: end sebastianandres.de
address list
Oct 27 23:16:51 server postfix/smtp[3114]: inet_addr_local: configured
2 IPv4 addresses
Oct 27 23:16:51 server postfix/smtp[3114]: warning:
inet_addr_local[procnet_ifinet6]: Couldn't open /proc/net/if_inet6 for
reading: No such file or directory
Oct 27 23:16:51 server postfix/smtp[3114]: inet_addr_local: configured
0 IPv6 addresses
Oct 27 23:16:51 server postfix/smtp[3114]: smtp_find_self: not found
Oct 27 23:16:51 server postfix/smtp[3114]: smtp_connect_addr: trying:
sebastianandres.de[82.165.25.176] port 25...
Oct 27 23:16:51 server postfix/smtp[3114]: maps_find:
smtp_tls_per_site: sebastianandres.de: not found
Oct 27 23:16:51 server postfix/smtp[3114]: maps_find:
smtp_tls_per_site: sebastianandres.de: not found
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 220 a15171661.alturo-server.de
ESMTP Postfix
Oct 27 23:16:51 server postfix/smtp[3114]: >
sebastianandres.de[82.165.25.176]: EHLO server
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250-a15171661.alturo-server.de
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250-PIPELINING
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250-SIZE 10240000
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250-VRFY
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250-ETRN
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250-STARTTLS
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250-AUTH LOGIN
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250-AUTH=LOGIN
Oct 27 23:16:51 server postfix/smtp[3114]: <
sebastianandres.de[82.165.25.176]: 250 8BITMIME
Oct 27 23:16:51 server postfix/smtp[3114]: server features: 0x3f size
10240000
Oct 27 23:16:51 server postfix/smtp[3114]: maps_find:
smtp_sasl_passwd: hash:/etc/postfix/smtp_auth(0,100):
sebastianandres.de = username:meinpw
Oct 27 23:16:51 server postfix/smtp[3114]: smtp_sasl_passwd_lookup:
host `sebastianandres.de' user `username' pass `'Meinpw'
Oct 27 23:16:51 server postfix/smtp[3114]: starting new SASL client
Oct 27 23:16:51 server postfix/smtp[3114]: name_mask: noanonymous
Oct 27 23:16:51 server postfix/smtp[3114]: smtp_sasl_authenticate:
sebastianandres.de[82.165.25.176]: SASL mechanisms LOGIN
Oct 27 23:16:51 server postfix/smtp[3114]: warning: SASL
authentication failure: No worthy mechs found
Oct 27 23:16:51 server postfix/smtp[3114]: 79A5B880EB: Authentication
failed: cannot SASL authenticate to server
sebastianandres.de[82.165.25.176]: no mechanism available
Oct 27 23:16:51 server postfix/smtp[3114]: name_mask: resource
Oct 27 23:16:51 server postfix/smtp[3114]: name_mask: software
Oct 27 23:16:51 server postfix/smtp[3114]: disposing SASL state
information
Oct 27 23:16:51 server postfix/smtp[3114]: smtp_connect_addr: trying:
mx01.alturo.net[212.227.15.150] port 25...
Oct 27 23:16:51 server postfix/smtp[3114]: maps_find:
smtp_tls_per_site: mx01.alturo.net: not found
Oct 27 23:16:51 server postfix/smtp[3114]: maps_find:
smtp_tls_per_site: mx01.alturo.net: not found
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 220 mx-b.kundenserver.de (mxeu0)
Welcome to Nemesis ESMTP server
Oct 27 23:16:51 server postfix/smtp[3114]: >
mx01.alturo.net[212.227.15.150]: EHLO server
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 250-mxeu0.kundenserver.de pleased to
meet you
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 250-PIPELINING
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 250-8BITMIME
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 250-SIZE 20971520
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 250 HELP
Oct 27 23:16:51 server postfix/smtp[3114]: server features: 0xf size
20971520
Oct 27 23:16:51 server postfix/smtp[3114]: Using ESMTP PIPELINING, TCP
send buffer size is 4096
Oct 27 23:16:51 server postfix/smtp[3114]: >
mx01.alturo.net[212.227.15.150]: MAIL FROM:<sa at server.local> SIZE=635
Oct 27 23:16:51 server postfix/smtp[3114]: >
mx01.alturo.net[212.227.15.150]: RCPT TO:<s_andres at web.de>
Oct 27 23:16:51 server postfix/smtp[3114]: >
mx01.alturo.net[212.227.15.150]: DATA
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 250 mail from: <sa at server.local> ok
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 550 won't relay to s_andres at web.de
Oct 27 23:16:51 server postfix/smtp[3114]: connect to subsystem
private/bounce
Oct 27 23:16:51 server postfix/smtp[3114]: send attr nrequest = 0
Oct 27 23:16:51 server postfix/smtp[3114]: send attr flags = 0
Oct 27 23:16:51 server postfix/smtp[3114]: send attr queue_id =
79A5B880EB
Oct 27 23:16:51 server postfix/smtp[3114]: send attr
original_recipient = s_andres at web.de
Oct 27 23:16:51 server postfix/smtp[3114]: send attr recipient =
s_andres at web.de
Oct 27 23:16:51 server postfix/smtp[3114]: send attr offset = 151
Oct 27 23:16:51 server postfix/smtp[3114]: send attr status = 5.0.0
Oct 27 23:16:51 server postfix/smtp[3114]: send attr action = failed
Oct 27 23:16:51 server postfix/smtp[3114]: send attr reason = host
mx01.alturo.net[212.227.15.150] said: 550 won't relay to
s_andres at web.de (in reply to RCPT TO command)
Oct 27 23:16:51 server postfix/smtp[3114]: private/bounce socket:
wanted attribute: status
Oct 27 23:16:51 server postfix/smtp[3114]: input attribute name:
status
Oct 27 23:16:51 server postfix/smtp[3114]: input attribute value: 0
Oct 27 23:16:51 server postfix/smtp[3114]: private/bounce socket:
wanted attribute: (list terminator)
Oct 27 23:16:51 server postfix/smtp[3114]: input attribute name: (end)
Oct 27 23:16:51 server postfix/smtp[3114]: 79A5B880EB:
to=<s_andres at web.de>, relay=mx01.alturo.net[212.227.15.150], delay=1,
status=bounced (host mx01.alturo.net[212.227.15.150] said: 550 won't
relay to s_andres at web.de (in reply to RCPT TO command))
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 554 no valid recipients
Oct 27 23:16:51 server postfix/smtp[3114]: >
mx01.alturo.net[212.227.15.150]: RSET
Oct 27 23:16:51 server postfix/smtp[3114]: >
mx01.alturo.net[212.227.15.150]: QUIT
Oct 27 23:16:51 server postfix/smtp[3114]: <
mx01.alturo.net[212.227.15.150]: 250 OK
Oct 27 23:16:51 server postfix/smtp[3114]: name_mask: resource
Oct 27 23:16:51 server postfix/smtp[3114]: name_mask: software
Oct 27 23:16:51 server postfix/smtp[3114]: deliver_request_final:
send: "" 0
Oct 27 23:16:51 server postfix/smtp[3114]: send attr reason =
Oct 27 23:16:51 server postfix/smtp[3114]: send attr status = 0
Oct 27 23:16:51 server postfix/smtp[3114]: master_notify: status 1
Oct 27 23:16:51 server postfix/smtp[3114]: connection closed
Oct 27 23:16:51 server postfix/smtp[3114]: watchdog_stop: 0x80666f0
Oct 27 23:16:51 server postfix/smtp[3114]: watchdog_start: 0x80666f0
Oct 27 23:16:51 server postfix/cleanup[3112]: E4B0B880EE:
message-id=<20051027211651.E4B0B880EE at server>
Oct 27 23:16:51 server postfix/qmgr[2388]: E4B0B880EE: from=<>,
size=2344, nrcpt=1 (queue active)
Oct 27 23:16:51 server postfix/qmgr[2388]: 79A5B880EB: removed
Oct 27 23:16:51 server postfix/local[3116]: E4B0B880EE:
to=<sa at server.local>, relay=local, delay=0, status=sent (delivered to
command: /usr/bin/procmail)
Oct 27 23:16:51 server postfix/qmgr[2388]: E4B0B880EE: removed
Oct 27 23:18:31 server postfix/smtp[3114]: idle timeout -- exiting
Hier die Datei /etc/postfix/main.cf:
# See /usr/share/postfix/main.cf.dist for a commented, more complete
version
smtpd_banner = $myhostname ESMTP $mail_name (Debian/GNU)
biff = no
# appending .domain is the MUA's job.
append_dot_mydomain = no
# Uncomment the next line to generate "delayed mail" warnings
#delay_warning_time = 4h
myhostname = server
alias_maps = hash:/etc/aliases
alias_database = hash:/etc/aliases
myorigin = /etc/mailname
mydestination = server.local, localhost.local, localhost
relayhost = sebastianandres.de
mynetworks = 127.0.0.0/8
mailbox_command = procmail -a "$EXTENSION"
mailbox_size_limit = 0
recipient_delimiter = +
inet_interfaces = all
# neu!
smtpd_sender_restrictions = hash:/etc/postfix/access
smtpd_helo_required = no
strict_rfc821_envelopes = no
smtpd_recipient_restrictions = permit_mynetworks,check_relay_domains
smtp_sasl_auth_enable = yes
# akktiviert die authentifizierung mittels smtp ist am besten, da
sonnst
# vorher ein abholen erzwungen werden muss, um authorisiert zu sein.
smtp_sasl_password_maps = hash:/etc/postfix/smtp_auth
# hier ist das passwort fuer die anmeldung am smtp server gespeichert.
smtp_sasl_security_options = noanonymous
# verhindert anonymes anmelden am smtp-server
Die Datei /etc/postfix/smtp_auth existiert! Auch als .db-File!
Mfg. Sebastian andres
Mehr Informationen über die Mailingliste linux-l