SMTP Mail Versand funktioniert nicht

[Lightstorm]

Seit heute funktioniert der SMTP Versand nicht mehr auf meinem Server. Ich habe echt keine Ahnung woran es liegt.

In der Syslog gibt es nur diese Meldung ständig wenn ich versuche Email zu senden:

Sep 13 13:18:35 vs000000 postfix/smtpd[22448]: connect from p54875C66.dip.t-dialin.net[84.135.92.102]
Sep 13 13:18:52 vs000000 postfix/smtpd[22448]: lost connection after UNKNOWN from p54875C66.dip.t-dialin.net[84.135.92.102]
Sep 13 13:18:52 vs000000 postfix/smtpd[22448]: disconnect from p54875C66.dip.t-dialin.net[84.135.92.102]

Das sagt mir leider nichts, wieso kommt die Verbindung nicht zustande?
Es läuft über SSL aber auch ohne geht es nicht. IMAP funktioniert dagegen weiterhin.

Habt ihr eine Idee oder hilfreiche Anhaltspunkte wo ich nachschauen könnte?

Was ich gestern und heute verändert habe war nur das virtual_alias Datei in postfix, die Zuordnung von Benutzern und Email Namen. Habe auch postmap drüber laufen lassen und postfix neu gestartet, auch den ganzen Server neu gestartet.
Weiß nur nicht ob dieser Fehler durch diese Veränderung entstanden ist oder schon vorher da war.

 

[rikola]

Setz doch mal den Loglevel sowohl beim Server als auch beim Klienten hoch und schau dann in den Log-Dateien beider Rechner nach, was dort steht.
Kannst Du die Veraenderung nicht rueckgaengig machen, um rauszufinden, ob sie die Ursache dieses Fehlers ist?

 

[Lightstorm]

Hier smtpd läuft im verbose Modus, in der Sylog gibs dann diese Meldungen:

Sep 13 16:01:39 vs000000 imapd-ssl: Connection, ip=[::ffff:84.135.68.170]
Sep 13 16:01:39 vs000000 imapd-ssl: LOGIN, user=administrator, ip=[::ffff:84.135.68.170], port=[61371], protocol=IMAP
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: connection established
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: master_notify: status 0
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: name_mask: resource
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: name_mask: software
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: xsasl_cyrus_server_create: SASL service=smtp, realm=(null)
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: name_mask: noanonymous
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: connect from p548744AA.dip.t-dialin.net[84.135.68.170]
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_list_match: p548744AA.dip.t-dialin.net: no match
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_list_match: 84.135.68.170: no match
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_list_match: p548744AA.dip.t-dialin.net: no match
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_list_match: 84.135.68.170: no match
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_hostname: p548744AA.dip.t-dialin.net ~? 127.0.0.0/8
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_hostaddr: 84.135.68.170 ~? 127.0.0.0/8
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_hostname: p548744AA.dip.t-dialin.net ~? 192.168.1.0/24
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_hostaddr: 84.135.68.170 ~? 192.168.1.0/24
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_list_match: p548744AA.dip.t-dialin.net: no match
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_list_match: 84.135.68.170: no match
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: send attr request = connect
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: send attr ident = smtp:84.135.68.170
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: private/anvil: wanted attribute: status
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: input attribute name: status
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: input attribute value: 0
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: private/anvil: wanted attribute: count
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: input attribute name: count
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: input attribute value: 1
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: private/anvil: wanted attribute: rate
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: input attribute name: rate
Sep 13 16:02:55 vs000000postfix/smtpd[23729]: input attribute value: 1
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: private/anvil: wanted attribute: (list terminator)
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: input attribute name: (end)
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: > p548744AA.dip.t-dialin.net[84.135.68.170]: 220 vs000000.vserver.de ESMTP Postfix (Debian/GNU)
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: < p548744AA.dip.t-dialin.net[84.135.68.170]: ?U??
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_string: ?U?? ~? CONNECT
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_string: ?U?? ~? GET
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_string: ?U?? ~? POST
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: match_list_match: ?U??: no match
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: > p548744AA.dip.t-dialin.net[84.135.68.170]: 502 5.5.2 Error: command not recognized
Sep 13 16:03:00 vs000000 postfix/smtpd[9860]: idle timeout -- exiting
Sep 13 16:03:00 vs000000 postfix/smtpd[23731]: proxymap stream disconnect
Sep 13 16:03:00 vs000000 postfix/smtpd[23731]: idle timeout -- exiting
Sep 13 16:03:00 vs000000 postfix/smtpd[23737]: proxymap stream disconnect
Sep 13 16:03:00 vs000000 postfix/smtpd[23737]: idle timeout -- exiting
Sep 13 16:03:00 vs000000 postfix/smtpd[23733]: proxymap stream disconnect
Sep 13 16:03:00 vs000000 postfix/smtpd[23733]: idle timeout -- exiting
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: smtp_get: EOF
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: match_hostname: p548744AA.dip.t-dialin.net ~? 127.0.0.0/8
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: match_hostaddr: 84.135.68.170 ~? 127.0.0.0/8
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: match_hostname: p548744AA.dip.t-dialin.net ~? 192.168.1.0/24
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: match_hostaddr: 84.135.68.170 ~? 192.168.1.0/24
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: match_list_match: p548744AA.dip.t-dialin.net: no match
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: match_list_match: 84.135.68.170: no match
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: send attr request = disconnect
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: send attr ident = smtp:84.135.68.170
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: private/anvil: wanted attribute: status
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: input attribute name: status
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: input attribute value: 0
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: private/anvil: wanted attribute: (list terminator)
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: input attribute name: (end)
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: lost connection after UNKNOWN from p548744AA.dip.t-dialin.net[84.135.68.170]
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: disconnect from p548744AA.dip.t-dialin.net[84.135.68.170]
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: master_notify: status 1
Sep 13 16:04:40 vs000000 postfix/smtpd[23729]: connection closed

Das einzige was mir komisch wirkt ist das dick unterstrichene, die Error Meldung, sagt mir aber leider auch nichts.

 

[saeckereier]

Was bekommste denn für eine Fehlermeldung? Verbinde dich mal mit telnet server 25 auf deinen Server und sende eine Mail per Hand:

EHLO xxx
MAIL FROM: <quell-adresse>
RCPT TO: <ziel-adresse>
DATA
Subject: test

testmail
.
QUIT

hierbei quell- und ziel-adresse ersetzen, die spitzen Klammern gehören mit zur Syntax, nicht entfernen. Poste mal den Mitschnitt.

 

[Lightstorm]

Edit: Ich habe das Problem jetzt gelöst. Es lag doch an der SSL Verbindung, in der main.cf von Postfix musste ich TLS aktivieren und die Pfade zum Zertifikat und privaten Schlüssel angeben. Jetzt funktioniert alles wieder.
Ein hilfreicher Link: http://www.postfix.org/TLS_README.html

 

[saeckereier]

Ich wollte gerade sagen, nach deiner Log sah es so aus, als ob dein Mailclient es mit SSL versucht hat, aber TLS erwartet wurde..

 

[saeckereier]

Für die Zukunft, im Logfile stehen die Kommandos, die Server und Client miteinander austauschen. Das entscheidende waren diese Zeilen, die hab ich aber leider übersehen:

Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: [B]>[/B] p548744AA.dip.t-dialin.net[84.135.68.170]: 220 vs000000.vserver.de ESMTP Postfix (Debian/GNU)
Sep 13 16:02:55 vs000000 postfix/smtpd[23729]: [B]<[/B] p548744AA.dip.t-dialin.net[84.135.68.170]: ?U??

Hier sendet ( > ) der Server seine Standard-Begrüßung (220 ist sowas wie Hallo bei SMTP) und er empfängt ( < ) ?U??. Wie du bei dem Telnetbeispiel gesehen hast, ist das nicht das was kommen sollte, ein Client würde mit EHLO oder HELO antworten. So sieht es das Protokoll vor. Dieses Kauderwelsch siehst du aber, wenn jemand versucht eine SSL-Verschlüsselung aufzubauen. Das bietet Postfix aber nur auf einem separaten Port an. (Nicht zu verwechseln mit TLS)