[Postfixbuch-users] Postfix stellt Mails sehr langsam zu

Fr Dez 21 10:35:59 CET 2007

Hallo Leute,

ich hoffe mein Problem ist nicht OT aber bisher hab ich noch keine Lösung in der Liste gefunden.

Ich betreibe den Postfix als Mail-Relay in der DMZ. Die Aufstellung ist eigentlich ganz normal:

Postfix -> Amavisd-New -> Clam-AV & McAffe -> Spamassassin -> Postfix

Seit gestern schickt der Postfix kaum noch Mails an externe Adressen... höchsten 10 in der Stunde -> die MailQ wächst und wächst also.

Mails die von extern zu einer internen Adresse kommen werden ohne Probleme zugestellt. So wie sonst auch.

Im LOG taucht nix auf, und das ist es was mich verwundert.

---schnipp master.cf ---

localhost:10025 inet    n       -       n       -       -       smtpd

        -o content_filter=

        -o smtpd_delay_reject=no

        -o smtpd_client_restrictions=permit_mynetworks,reject

        -o smtpd_helo_restrictions=

        -o smtpd_sender_restrictions=

        -o smtpd_recipient_restrictions=permit_mynetworks,reject

        -o smtpd_data_restrictions=reject_unauth_pipelining

        -o smtpd_end_of_data_restrictions=

        -o smtpd_restriction_classes=

        -o mynetworks=127.0.0.0/8

        -o smtpd_error_sleep_time=0

        -o smtpd_soft_error_limit=1001

        -o smtpd_hard_error_limit=1000

        -o smtpd_client_connection_count_limit=0

        -o smtpd_client_connection_rate_limit=0

        -o receive_override_options=no_header_body_checks,no_unknown_recipient_checks,no_milters

        -o local_header_rewrite_clients=

smtp-amavis unix        -       -       n       -       10      smtp

        -o smtp_data_done_timeout=1800

        -o disable_dns_lookups=yes

        -o max_use=20

--- schnipp ---

---schnipp log---

Dec 21 09:36:31 pf2 amavis[14163]: (14163-01) ESMTP::10024 /var/amavis/tmp/amavis-20071221T093631-14163: <m.hazoth at hatz-diesel.de> -> <empfaenger at xxx.xy> SIZE=1362918 Received: from mail.hatz-diesel.de ([127.0.0.1]) by localhost (pf2.hatz-diesel.local [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <empfaenger at xxx.xy>; Fri, 21 Dec 2007 09:36:31 +0100 (CET)

Dec 21 09:36:31 pf2 amavis[14163]: (14163-01) body hash: ac8de633be4ebeaed8f12756f5fc3de6

Dec 21 09:36:31 pf2 amavis[14163]: (14163-01) Checking: fjI77ybsWsrX <m.hazoth at hatz-diesel.de> -> <empfaenger at xxx.xy>

Dec 21 09:36:31 pf2 amavis[14163]: (14163-01) 2822.From: <m.hazoth at hatz-diesel.de>

Dec 21 09:36:31 pf2 postfix/smtpd[12534]: timeout after MAIL from MTA002E.interbusiness.it[88.44.62.2]

Dec 21 09:36:31 pf2 postfix/smtpd[12534]: disconnect from MTA002E.interbusiness.it[88.44.62.2]

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p005 1 Content-Type: multipart/mixed

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p006 1/1 Content-Type: multipart/alternative

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p001 1/1/1 Content-Type: text/plain, size: 50 B, name:

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p002 1/1/2 Content-Type: text/html, size: 1825 B, name:

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p003 1/2 Content-Type: application/octet-stream, size: 12424 B, name: denk_mit_glas.pdf

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p004 1/3 Content-Type: application/octet-stream, size: 980609 B, name: Microsoft Word - Windows Desktop Search Administration Guide 3 revb.pdf

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) Checking for banned types and filenames

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) collect banned table[0]: empfaenger at xxx.xy, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x94603b0)

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p.path empfaenger at xxx.xy: "P=p005,L=1,M=multipart/mixed | P=p003,L=1/2,M=application/octet-stream,T=pdf,N=denk_mit_glas.pdf"

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p.path empfaenger at xxx.xy: "P=p005,L=1,M=multipart/mixed | P=p004,L=1/3,M=application/octet-stream,T=pdf,N=Microsoft Word - Windows Desktop Search Administration Guide 3 revb.pdf"

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p.path empfaenger at xxx.xy: "P=p005,L=1,M=multipart/mixed | P=p006,L=1/1,M=multipart/alternative | P=p001,L=1/1/1,M=text/plain,T=asc"

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) p.path empfaenger at xxx.xy: "P=p005,L=1,M=multipart/mixed | P=p006,L=1/1,M=multipart/alternative | P=p002,L=1/1/2,M=text/html,T=html"

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) Using ClamAV-clamd: (built-in interface)

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) Using (ClamAV-clamd) on dir: CONTSCAN /var/amavis/tmp/amavis-20071221T093631-14163/parts\n

Dec 21 09:36:32 pf2 amavis[14163]: (14163-01) ClamAV-clamd: Connecting to socket  /var/amavis/clamd.sock

Dec 21 09:36:33 pf2 amavis[14163]: (14163-01) ask_av (ClamAV-clamd): /var/amavis/tmp/amavis-20071221T093631-14163/parts CLEAN

Dec 21 09:36:33 pf2 amavis[14163]: (14163-01) ClamAV-clamd result: clean

Dec 21 09:36:33 pf2 amavis[14163]: (14163-01) Using (NAI McAfee AntiVirus (uvscan)): /usr/local/bin/uvscan --secure -rv --mime --summary --noboot - /var/amavis/tmp/amavis-20071221T093631-14163/parts

Dec 21 09:36:33 pf2 postfix/smtp[13985]: 2B1D87A61F6: lost connection with mx1.vgp.de[62.157.156.68] while sending message body

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) run_av: /usr/local/bin/uvscan exit 0, Scanning /var/amavis/tmp/amavis-20071221T093631-14163/parts/*\nScanning file /var/amavis/tmp/amavis-20071221T093631-14163/parts/p003\nScanning file /var/amavis/tmp/amavis-20071221T093631-14163/parts/p001\nScanning file /var/amavis/tmp/amavis-20071221T093631-14163/parts/p002\nScanning file /var/amavis/tmp/amavis-20071221T093631-14163/parts/p004\n\nSummary report on /var/amavis/tmp/amavis-20071221T093631-14163/parts/*\nFile(s)\n...Total files: ........... ...4\n...Clean: ................. ...4\n...Possibly Infected: ..... ...0

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) run_av (NAI McAfee AntiVirus (uvscan)): CLEAN

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) spam_scan: not wasting time on SA, message longer than 409600 bytes: 764+1344582

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) spam_scan: score= autolearn= tests=[]

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) do_notify_and_quar: ccat=Clean (1,0) ("1":Clean, "0":CatchAll) ccat_block=(), q_mth=, qar_mth=

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp creating socket by IO::Socket::INET: 127.0.0.1

Dec 21 09:36:34 pf2 postfix/smtpd[12965]: connect from localhost.localdomain[127.0.0.1]

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp resp to greeting: 220 ESMTP PF2

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp cmd> EHLO localhost

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp resp to EHLO: 250 mail.hatz-diesel.de\nPIPELINING\nSIZE 20480000\nETRN\nENHANCEDSTATUSCODES\n8BITMIME\nDSN

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) AUTH not needed, user='', MTA offers ''

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp cmd> MAIL FROM:<m.hazoth at hatz-diesel.de> BODY=7BIT

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp cmd> RCPT TO:<empfaenger at xxx.xy> ORCPT=rfc822;empfaenger at xxx.xy

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp cmd> DATA

Dec 21 09:36:34 pf2 postfix/smtpd[12965]: 85D187A6247: client=localhost.localdomain[127.0.0.1]

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp resp to MAIL (pip): 250 2.1.0 Ok

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp resp to RCPT (pip) (<empfaenger at xxx.xy>): 250 2.1.5 Ok, id=14163-01, from MTA([127.0.0.1]:10025): 250 2.1.5 Ok

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) smtp resp to DATA: 354 End data with <CR><LF>.<CR><LF>

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) rw_loop send 49152 (of 67547)> X-Virus-Scanned: amavisd-new at hatz-diesel.de\r\nReceived: from mail.hatz-diesel.de ([127.0.0.1])\r\n\tby localhost (pf2.hatz-diesel.local [127.0.0.1]) (amavisd-new, port 10024)\r\n\twith ESMTP id fjI77ybsWs [...]

Dec 21 09:36:34 pf2 postfix/cleanup[13565]: 85D187A6247: message-id=<E8A1792BCA2B3C40A9B33EEFAD453C7D9D54E9 at EXRU01.hatz-diesel.local>

Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) rw_loop send 47450 (of 66387)> 2XkDwargLKG43kt4wFSkgeDZeQPBouIXkwXEry\r\naLiE5NFwFVDeIJL3KWKBYpKHw8UkD4eLSR4Nl5A8HC4meThcBZQ3GslzPCMYUELyaLiE5NFwCcmD\r\n4VKSR8MlJI+Gq4DyRiN5A7bRICSPhktIHg2XkDwYLiV5NFxC8mi4CihvtFNBGWyjgUkeDheTPBwu\r\nJnk0 [...]

Dec 21 09:36:35 pf2 amavis[14163]: (14163-01) smtp cmd> QUIT

Dec 21 09:36:35 pf2 postfix/smtpd[12965]: disconnect from localhost.localdomain[127.0.0.1]

Dec 21 09:36:35 pf2 amavis[14163]: (14163-01) smtp resp to data-dot (<empfaenger at xxx.xy>): 250 2.0.0 Ok: queued as 85D187A6247

Dec 21 09:36:35 pf2 postfix/qmgr[11805]: 85D187A6247: from=<m.hazoth at hatz-diesel.de>, size=1363411, nrcpt=1 (queue active)

Dec 21 09:36:35 pf2 amavis[14163]: (14163-01) smtp resp to QUIT: 221 2.0.0 Bye

Dec 21 09:36:35 pf2 amavis[14163]: (14163-01) FWD via SMTP: <m.hazoth at hatz-diesel.de> -> <empfaenger at xxx.xy>,BODY=7BIT 250 2.6.0 Ok, id=14163-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 85D187A6247

Dec 21 09:36:35 pf2 amavis[14163]: (14163-01) Passed CLEAN, [10.94.10.10] <m.hazoth at hatz-diesel.de> -> <empfaenger at xxx.xy>, Message-ID: <E8A1792BCA2B3C40A9B33EEFAD453C7D9D54E9 at EXRU01.hatz-diesel.local>, mail_id: fjI77ybsWsrX, Hits: -, size: 1362918, queued_as: 85D187A6247, 3826 ms

Dec 21 09:36:35 pf2 postfix/smtp[14035]: 6B5237A6236: to=<empfaenger at xxx.xy>, relay=localhost[127.0.0.1]:10024, delay=4.2, delays=0.3/0/0.01/3.9, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 85D187A6247)

---schnipp---

wenn ich nicht komplett falsch liege dann siehts doch jetzt so aus: 

postfix bekommt die mail von amavis zurück über den port 10025. Und dann bleibt sie bei ihm liegen, an statt dass er sie an den eigentlichen Empfänger zustellt.

Nur, warum?

Seht ihr da vielleicht nen Fehler?

Ausgelastet ist der Server übrigens nicht. Er dümpelt so vor sich hin.

Ich hoff ihr könnt mir helfen.

Danke und Gruß

Michael

-------------- nächster Teil --------------
Ein Dateianhang mit HTML-Daten wurde abgetrennt...
URL: <https://listi.jpberlin.de/pipermail/postfixbuch-users/attachments/20071221/02501d04/attachment.html>