AW: [Postfixbuch-users] Fragen zu postfix+amavisd-new
Lorenz Meis, E3T IT-Systems GmbH
l.meis at e3t.net
Mi Apr 16 23:38:36 CEST 2003
... kannst du die mail irgendwie aus der sendqueue löschen? (notfalls mit pop2web) und das gleiche prob von einem "hübschen" sender nachvollziehen?
Lorenz
-----Ursprüngliche Nachricht-----
Von: Wolfram Greinert [mailto:greinert at rz.uni-leipzig.de]
Gesendet: Mittwoch, 16. April 2003 23:26
An: postfixbuch-users at listi.jpberlin.de
Betreff: Re: [Postfixbuch-users] Fragen zu postfix+amavisd-new
Halloechen,
Ralf Hildebrandt writes:
> * Wolfram Greinert <greinert at rz.uni-leipzig.de>:
> > Hallo,
> >
> > ich habe beim Zusammenspiel von postfix 2.0.7 mit amavisd-new-20030314 (unter
> > perl 5.8) 2 kleine Probleme.
> >
> > Im logfile sehe ich haeufig solche Zeilen:
> >
> > Apr 16 15:24:34 v3 postfix/smtp[8996]: 6BA0CB5: to=<aaa at bbb.tld>, relay=127.0.0.1[127.0.0.1], delay=-460, status=deferred (lost connection with 127.0.0.1[127.0.0.1] while sending end of data -- message may be sent more than once)
> >
> > (notfalls koennte ich dazu auch die debug-Ausgaben liefern :-)
> >
> > Die mails bleiben dann in der queue und liefern immer wieder diesen Fehler.
> > Kann mir vielleicht jemand sagen, was die Zeile bedeutet und wie ich das
> > Problem beseitigen kann ???
>
> amavis muesste fluchen und zetern!
hm, ich sehe leider nichts :-(
> Mal den debuglevel hochdrehen und gucken
habe ich gemacht, hier ist das Ergebnis, aber wo ist der Fehler ????
Kannst Du mir uebrigens was zu per_recip_whitelist_sender_lookup_tables sagen ?? :-)
-----------------------------------------------------------------------------
Apr 16 23:02:07 v2 postfix/smtp[7383]: < 127.0.0.1[127.0.0.1]: 250-[127.0.0.1]
Apr 16 23:02:07 v2 postfix/smtp[7383]: < 127.0.0.1[127.0.0.1]: 250-PIPELINING
Apr 16 23:02:07 v2 postfix/smtp[7383]: < 127.0.0.1[127.0.0.1]: 250-SIZE
Apr 16 23:02:07 v2 postfix/smtp[7383]: < 127.0.0.1[127.0.0.1]: 250-8BITMIME
Apr 16 23:02:07 v2 postfix/smtp[7383]: < 127.0.0.1[127.0.0.1]: 250 ENHANCEDSTATUSCODES
Apr 16 23:02:07 v2 postfix/smtp[7383]: server features: 0xf size 0
Apr 16 23:02:07 v2 postfix/smtp[7383]: Using ESMTP PIPELINING, TCP send buffer size is 4096
Apr 16 23:02:07 v2 postfix/smtp[7383]: > 127.0.0.1[127.0.0.1]: MAIL FROM:<tdpkjz5mjxv at hotmail.com> SIZE=8307
Apr 16 23:02:07 v2 postfix/smtp[7383]: > 127.0.0.1[127.0.0.1]: RCPT TO:<xxxxxxxxx at uni-leipzig.de>
Apr 16 23:02:07 v2 postfix/smtp[7383]: > 127.0.0.1[127.0.0.1]: DATA
Apr 16 23:02:07 v2 amavis[7360]: (07360-01) prolong_timer after reading SMTP command: remaining time = 300 s
Apr 16 23:02:07 v2 amavis[7360]: (07360-01) ESMTP< MAIL FROM:<tdpkjz5mjxv at hotmail.com> SIZE=8307\r\n
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) lookup_acl: key="tdpkjz5mjxv at hotmail.com", no match
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) ESMTP> 250 2.1.0 Sender tdpkjz5mjxv at hotmail.com OK
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) prolong_timer after reading SMTP command: remaining time = 300 s
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) ESMTP< RCPT TO:<xxxxxxxxx at uni-leipzig.de>\r\n
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) ESMTP> 250 2.1.5 Recipient xxxxxxxxx at uni-leipzig.de OK
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) prolong_timer after reading SMTP command: remaining time = 300 s
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) ESMTP< DATA\r\n
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) ESMTP:10024 /var/amavis/amavis-20030416T230114-07360: <tdpkjz5mjxv at hotmail.com> -> <xxxxxxxxx at uni-leipzig.de> Received: SIZE=8307 from v2.rz.uni-leipzig.de ([127.0.0.1]) by localhost (v2 [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 07360-02 for <xxxxxxxxx at uni-leipzig.de>; Wed, 16 Apr 2003 23:02:07 +0200 (CEST)
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) ESMTP> 354 End data with <CR><LF>.<CR><LF>
Apr 16 23:02:07 v2 postfix/smtp[7383]: < 127.0.0.1[127.0.0.1]: 250 2.1.0 Sender tdpkjz5mjxv at hotmail.com OK
Apr 16 23:02:07 v2 postfix/smtp[7383]: < 127.0.0.1[127.0.0.1]: 250 2.1.5 Recipient xxxxxxxxx at uni-leipzig.de OK
Apr 16 23:02:07 v2 postfix/smtp[7383]: < 127.0.0.1[127.0.0.1]: 354 End data with <CR><LF>.<CR><LF>
Apr 16 23:02:07 v2 postfix/smtp[7383]: > 127.0.0.1[127.0.0.1]: .
Apr 16 23:02:07 v2 postfix/smtp[7383]: > 127.0.0.1[127.0.0.1]: QUIT
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) ESMTP< .\r\n
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) body hash: f74d231f36574a67b793b9c374931cb5
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) Original mail size: 8175; quota set to: 4087500 bytes
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) Checking: <tdpkjz5mjxv at hotmail.com> -> <xxxxxxxxx at uni-leipzig.de>
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) Extracting mime components
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) Issued a new file name: part-00001
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) mime_decode_preamble: 2 lines
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) mime_decode: Content-type: multipart/alternative
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) mime_decode_epilogue: 1 lines
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) mime_decode: Content-type: text/html, name:
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) Charging 6485 bytes to remaining quota 4087500 (out of 4087500, (0%)) - by mime_decode
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) prolong_timer after mime_decode: remaining time = 300 s
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) Checking for banned MIME types and names
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) check_for_banned - mime-type: multipart/alternative
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) lookup_RE: key="multipart/alternative", no match
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) check_for_banned - mime-type: text/html
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) lookup_RE: key="text/html", no match
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) lookup_acl: key="xxxxxxxxx at uni-leipzig.de", no match
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) decode_parts: level=1, #parts=1 : part-00001
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) run_command: [7430] /opt/file/bin/file /var/amavis/amavis-20030416T230114-07360/parts/part-00001 </dev/null 2>/dev/null
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) File-type of part-00001: ASCII English text, with very long lines; (.asc)
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) Checking for banned (contents-based) file types, 1 parts
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) check_for_banned (part-00001) - file type: .asc
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) lookup_RE: key=".asc", no match
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) check_for_banned (part-00001) - file type: ASCII English text, with very long lines
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) lookup_RE: key="ASCII English text, with very long lines", no match
Apr 16 23:02:07 v2 amavis[7360]: (07360-02) do_ascii: Decoding part part-00001 (0 items)
Apr 16 23:02:07 v2 postfix/smtp[7383]: smtp_get: EOF
Apr 16 23:02:07 v2 postfix/smtp[7383]: connect to subsystem private/defer
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr nrequest = 0
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr flags = 0
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr queue_id = 3B82AA5
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr original_recipient = xxxxxxxxx at uni-leipzig.de
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr recipient = xxxxxxxxx at uni-leipzig.de
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr reason = lost connection with 127.0.0.1[127.0.0.1] while sending end of data -- message may be sent more than once
Apr 16 23:02:07 v2 postfix/smtp[7383]: private/defer socket: wanted attribute: status
Apr 16 23:02:07 v2 postfix/smtp[7383]: input attribute name: status
Apr 16 23:02:07 v2 postfix/smtp[7383]: input attribute value: 0
Apr 16 23:02:07 v2 postfix/smtp[7383]: private/defer socket: wanted attribute: (list terminator)
Apr 16 23:02:07 v2 postfix/smtp[7383]: input attribute name: (end)
Apr 16 23:02:07 v2 postfix/smtp[7383]: 3B82AA5: to=<xxxxxxxxx at uni-leipzig.de>, relay=127.0.0.1[127.0.0.1], delay=-284, status=deferred (lost connection with 127.0.0.1[127.0.0.1] while sending end of data -- message may be sent more than once)
Apr 16 23:02:07 v2 postfix/smtp[7383]: flush_add: site uni-leipzig.de id 3B82AA5
Apr 16 23:02:07 v2 postfix/smtp[7383]: connect to subsystem public/flush
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr request = add
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr site = uni-leipzig.de
Apr 16 23:02:07 v2 postfix/smtp[7383]: send attr queue_id = 3B82AA5
Apr 16 23:02:07 v2 postfix/smtp[7383]: public/flush socket: wanted attribute: status
Apr 16 23:02:07 v2 postfix/smtp[7383]: input attribute name: status
Apr 16 23:02:07 v2 postfix/smtp[7383]: input attribute value: 0
Apr 16 23:02:07 v2 postfix/smtp[7383]: public/flush socket: wanted attribute: (list terminator)
Apr 16 23:02:07 v2 postfix/smtp[7383]: input attribute name: (end)
Apr 16 23:02:07 v2 postfix/smtp[7383]: flush_add: site uni-leipzig.de id 3B82AA5 status 0
Apr 16 23:02:07 v2 postfix/smtp[7383]: name_mask: resource
Apr 16 23:02:07 v2 postfix/smtp[7383]: name_mask: software
-----------------------------------------------------------------------------
>
> --
> Ralf Hildebrandt (Im Auftrag des Referat V a) Ralf.Hildebrandt at charite.de
> Charite Campus Mitte Tel. +49 (0)30-450 570-155
> Referat V a - Kommunikationsnetze - Fax. +49 (0)30-450 570-916
> AIM: ralfpostfix
> --
> _______________________________________________
> Postfixbuch-users mailingliste
> JPBerlin - Mailbox und Politischer Provider
> Postfixbuch-users at listi.jpberlin.de
> http://listi.jpberlin.de/mailman/listinfo/postfixbuch-users
--
###########################################
# Wolfram Greinert #
# URZ der Uni Leipzig, Abteilung Netze #
# 04109 Leipzig, Augustusplatz 10/11 #
# Tel.: +(0341) 97-33325 #
# email: greinert at rz.uni-leipzig.de #
###########################################
Mehr Informationen über die Mailingliste Postfixbuch-users