[Postfixbuch-users] Doppelte Mailzustellung
Stefan Förster
cite+postfix-buch at incertum.net
Sa Okt 25 12:28:11 CEST 2008
Hallo Welt,
wie ich hier gerade die Nachrichten lese, die im Laufe des letzten
Tages aufgeschlagen sind, fällt mir auf, daß ich in letzter Zeit des
öfteren bei diversen Mailinglisten (neben dieser hier u.a. auch
amavis-users und die deutsche Debian-Benutzer-Mailingliste) Mails
doppelt empfange. Als Beispiel hier einmal diese Nachricht von Ralf
Hildebrandt:
https://listi.jpberlin.de/pipermail/postfixbuch-users/2008-October/046349.html
Ich habe die das erste Mal mit folgenden Headern bekommen (ich zitiere
mal nur diejenigen, bei denen man einen Unterschied bemerkt,
alles "darunter" ist gleich):
Return-Path: postfixbuch-users-bounces at listen.jpberlin.de
X-Original-To: cite+postfix-buch at incertum.net
Delivered-To: cite+postfix-buch at incertum.net
Received: from localhost (localhost [127.0.0.1])
by mail.incertum.net (Postfix) with ESMTP id 927DB10003
for <cite+postfix-buch at incertum.net>; Fri, 24 Oct 2008 23:17:29 +0200 (CEST)
X-Relay-Countries: DE ** DE DE ** DE
X-Virus-Scanned: amavisd-new at mail.incertum.net
X-Spam-Flag: NO
X-Spam-Score: -3.589
X-Spam-Level:
X-Spam-Status: No, score=-3.589 required=6.31 tests=[AWL=-0.990,
BAYES_00=-2.599]
Received: from mail.incertum.net ([127.0.0.1])
by localhost (mail.incertum.net [127.0.0.1]) (amavisd-new, port 10023)
with ESMTP id Z7TT7gA2g1tV for <cite+postfix-buch at incertum.net>;
Fri, 24 Oct 2008 23:17:27 +0200 (CEST)
X-nemea-policyd-weight: using cached result; rate:hard: -8.1
Received: from ilpostino.jpberlin.de (ilpostino.jpberlin.de [213.203.238.6])
by mail.incertum.net (Postfix) with ESMTP
for <cite+postfix-buch at incertum.net>; Fri, 24 Oct 2008 23:17:27 +0200 (CEST)
Received: from ilpostino.jpberlin.de (localhost [127.0.0.1])
by ilpostino.jpberlin.de (Postfix) with ESMTP id C1F13164014;
Fri, 24 Oct 2008 23:17:24 +0200 (CEST)
X-Original-To: postfixbuch-users at listi.jpberlin.de
Delivered-To: postfixbuch-users at listi.jpberlin.de
Received: from ilpostino.jpberlin.de ([213.203.238.6])
by localhost (hopfen.heinlein-support.de [213.203.238.118])
(amavisd-new, port 10024)
with ESMTP id 3v4z8zG+Uau6 for <postfixbuch-users at listi.jpberlin.de>;
Fri, 24 Oct 2008 23:17:19 +0200 (CEST)
X-policyd-weight: using cached result; rate: -7.6
Received: from mail-ausfall.charite.de (mail-ausfall.charite.de
[193.175.70.131])
by ilpostino.jpberlin.de (Postfix) with ESMTP id 357D6164013
for <postfixbuch-users at listen.jpberlin.de>;
Fri, 24 Oct 2008 23:17:18 +0200 (CEST)
Received: from mail-ausfall.charite.de ([127.0.0.1])
by localhost (mail-ausfall.charite.de [127.0.0.1]) (amavisd-new,
port 10025)
with ESMTP id 08kacCygaYs2; Fri, 24 Oct 2008 23:17:18 +0200 (CEST)
und einmal mit diesen:
Return-Path: postfixbuch-users-bounces at listen.jpberlin.de
X-Original-To: cite+postfix-buch at incertum.net
Delivered-To: cite+postfix-buch at incertum.net
Received: from localhost (localhost [127.0.0.1])
by mail.incertum.net (Postfix) with ESMTP id 69EAD10003
for <cite+postfix-buch at incertum.net>; Fri, 24 Oct 2008 23:17:37 +0200 (CEST)
X-Virus-Scanned: amavisd-new at mail.incertum.net
X-Spam-Flag: NO
X-Spam-Score: -3.589
X-Spam-Level:
X-Spam-Status: No, score=-3.589 required=6.31 tests=[AWL=-0.990,
BAYES_00=-2.599]
Received: from mail.incertum.net ([127.0.0.1])
by localhost (mail.incertum.net [127.0.0.1]) (amavisd-new, port 10023)
with ESMTP id 3hCzESn6dIKn for <cite+postfix-buch at incertum.net>;
Fri, 24 Oct 2008 23:17:37 +0200 (CEST)
X-nemea-policyd-weight: using cached result; rate:hard: -8.1
Received: from ilpostino.jpberlin.de (ilpostino.jpberlin.de [213.203.238.6])
by mail.incertum.net (Postfix) with ESMTP
for <cite+postfix-buch at incertum.net>; Fri, 24 Oct 2008 23:17:37 +0200 (CEST)
Received: from ilpostino.jpberlin.de (localhost [127.0.0.1])
by ilpostino.jpberlin.de (Postfix) with ESMTP id 52841164013;
Fri, 24 Oct 2008 23:17:33 +0200 (CEST)
X-Original-To: postfixbuch-users at listi.jpberlin.de
Delivered-To: postfixbuch-users at listi.jpberlin.de
Received: from ilpostino.jpberlin.de ([213.203.238.6])
by localhost (hopfen.heinlein-support.de [213.203.238.118])
(amavisd-new, port 10024)
with ESMTP id RzNfB4ohF3AE for <postfixbuch-users at listi.jpberlin.de>;
Fri, 24 Oct 2008 23:17:19 +0200 (CEST)
X-policyd-weight: using cached result; rate: -7.6
Received: from mail-ausfall.charite.de (mail-ausfall.charite.de
[193.175.70.131])
by ilpostino.jpberlin.de (Postfix) with ESMTP id 33B02164011
for <postfixbuch-users at listi.jpberlin.de>;
Fri, 24 Oct 2008 23:17:18 +0200 (CEST)
Received: from mail-ausfall.charite.de ([127.0.0.1])
by localhost (mail-ausfall.charite.de [127.0.0.1]) (amavisd-new,
port 10025)
with ESMTP id 08kacCygaYs2; Fri, 24 Oct 2008 23:17:18 +0200 (CEST)
Wie man sieht, unterscheiden sich die Header ab der Wiedereinspeisung
von amavisd-new zu Postfix auf ilpostino.jpberlin.de - möchte man
sagen, wenn man nur kurz hinschaut. Und obwohl das auf den ersten
Blick schlüssig aussieht, wundert mich doch, daß ich das selbe
Verhalten auf so vielen Mailinglisten erlebe. Man bemerkt auch, daß
bei mir anscheinend der "X-Relay-Countries"-Header in der zweiten Mail
fehlt (ich erzeuge den mittels
http://www.incertum.net/~cite/60-custom-package da der ja nicht
eingefügt wird, wenn amavisd-new den SA aufruft). Das Problem ist, daß
ich bei mir keine Auffälligkeiten in den Logs habe:
#v+
Oct 24 23:17:27 nemea postfix/smtpd[17927]: connect from ilpostino.jpberlin.de[213.203.238.6]
Oct 24 23:17:27 nemea postfix/policyd-weight[6398]: decided action=PREPEND X-nemea-policyd-weight: using cached result; rate:hard: -8.1; <client=213.203.238.
6> <helo=ilpostino.jpberlin.de> <from=postfixbuch-users-bounces at listen.jpberlin.de> <to=cite+postfix-buch at incertum.net>; delay: 0s
Oct 24 23:17:27 nemea postfix/smtpd[17927]: NOQUEUE: client=ilpostino.jpberlin.de[213.203.238.6]
Oct 24 23:17:29 nemea postfix/smtpd[17935]: connect from localhost[127.0.0.1]
Oct 24 23:17:29 nemea postfix/smtpd[17935]: 927DB10003: client=ilpostino.jpberlin.de[213.203.238.6]
Oct 24 23:17:29 nemea postfix/cleanup[17938]: 927DB10003: message-id=<20081024211717.GC28819 at charite.de>
Oct 24 23:17:29 nemea postfix/qmgr[10495]: 927DB10003: from=<postfixbuch-users-bounces at listen.jpberlin.de>, size=4940, nrcpt=1 (queue active)
Oct 24 23:17:29 nemea postfix/smtpd[17935]: disconnect from localhost[127.0.0.1]
Oct 24 23:17:29 nemea amavis[19818]: (19818-02) Passed CLEAN, PREQ [213.203.238.6] [141.42.4.250] <postfixbuch-users-bounces at listen.jpberlin.de> -> <cite+pos
tfix-buch at incertum.net>, Message-ID: <20081024211717.GC28819 at charite.de>, mail_id: Z7TT7gA2g1tV, Hits: -3.589, size: 4362, queued_as: 927DB10003, 2531 ms
Oct 24 23:17:29 nemea postfix/smtpd[17927]: disconnect from ilpostino.jpberlin.de[213.203.238.6]
Oct 24 23:17:30 nemea deliver(cite at incertum.net): msgid=<20081024211717.GC28819 at charite.de>: saved mail to postfix-buch
Oct 24 23:17:30 nemea postfix/pipe[17939]: 927DB10003: to=<cite+postfix-buch at incertum.net>, relay=dovecot, delay=0.61, delays=0.17/0/0/0.44, dsn=2.0.0, statu
s=sent (delivered via dovecot service)
Oct 24 23:17:30 nemea postfix/qmgr[10495]: 927DB10003: removed
#v-
#v+
Oct 24 23:17:37 nemea postfix/smtpd[17921]: connect from ilpostino.jpberlin.de[213.203.238.6]
Oct 24 23:17:37 nemea postfix/policyd-weight[6398]: decided action=PREPEND X-nemea-policyd-weight: using cached result; rate:hard: -8.1; <client=213.203.238.
6> <helo=ilpostino.jpberlin.de> <from=postfixbuch-users-bounces at listen.jpberlin.de> <to=cite+postfix-buch at incertum.net>; delay: 0s
Oct 24 23:17:37 nemea postfix/smtpd[17921]: NOQUEUE: client=ilpostino.jpberlin.de[213.203.238.6]
Oct 24 23:17:37 nemea postfix/smtpd[17935]: connect from localhost[127.0.0.1]
Oct 24 23:17:37 nemea postfix/smtpd[17935]: 69EAD10003: client=ilpostino.jpberlin.de[213.203.238.6]
Oct 24 23:17:37 nemea postfix/cleanup[17938]: 69EAD10003: message-id=<20081024211717.GC28819 at charite.de>
Oct 24 23:17:37 nemea postfix/qmgr[10495]: 69EAD10003: from=<postfixbuch-users-bounces at listen.jpberlin.de>, size=4901, nrcpt=1 (queue active)
Oct 24 23:17:37 nemea postfix/smtpd[17935]: disconnect from localhost[127.0.0.1]
Oct 24 23:17:37 nemea amavis[19843]: (19843-02) Passed CLEAN, PREQ [213.203.238.6] [141.42.4.250] <postfixbuch-users-bounces at listen.jpberlin.de> -> <cite+pos
tfix-buch at incertum.net>, Message-ID: <20081024211717.GC28819 at charite.de>, mail_id: 3hCzESn6dIKn, Hits: -3.589, size: 4361, queued_as: 69EAD10003, 522 ms
Oct 24 23:17:37 nemea postfix/smtpd[17921]: disconnect from ilpostino.jpberlin.de[213.203.238.6]
Oct 24 23:17:38 nemea deliver(cite at incertum.net): msgid=<20081024211717.GC28819 at charite.de>: saved mail to postfix-buch
Oct 24 23:17:38 nemea postfix/pipe[17939]: 69EAD10003: to=<cite+postfix-buch at incertum.net>, relay=dovecot, delay=0.65, delays=0.17/0/0/0.48, dsn=2.0.0, statu
s=sent (delivered via dovecot service)
Oct 24 23:17:38 nemea postfix/qmgr[10495]: 69EAD10003: removed
#v-
Aber offensichtlich ist die erste Einlieferung um 23:17:27 irgendwie
fehlgeschlagen, sonst hätte der Server die Mail ja kaum ein zweites
mal eingeliefert. Kann ich hier ohne die Logs der Gegenseite zu sehen
und ohne den einliefernden Host in debug_peer_list aufzunehmen bzw.
tcpdump zu bemühen noch weiter debuggen?
Ich bin natürlich gerne beereit, postconf -n beizubringen, falls das
notwendig ist (müsste dann aber master.cf auch beifügen) oder die
amavisd-new-Debug-Logs, aber ihr dürft mir glauben daß sich da
ebenfalls nicht auffälliges findet (und ich verstehe trotzdem, wenn
ihr nachgucken wollt *g*).
Ciao
Stefan
--
Stefan Förster http://www.incertum.net/ Public Key: 0xBBE2A9E9
FdI #51: Version x.5 - Mit neuen Icons! (Kristian Köhntopp)
Mehr Informationen über die Mailingliste Postfixbuch-users