[Postfixbuch-users] Postfix stellt Mails sehr langsam zu

Matthias Haegele mhaegele at linuxrocks.dyndns.org
Fr Dez 21 12:11:21 CET 2007


HAZOTH MICHAEL schrieb:
> 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
							^^^^^^^^^^
scnr: Netter Typo ;-).

> 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.

Hmm. Übersehe ich da nur was (hab nur 250 OK entdeckt), hast du was in 
deferred?

Das kenn ich nicht:
Dec 21 09:36:34 pf2 amavis[14163]: (14163-01) rw_loop send 474
									^^^^^^^^^^^^^^
aber weiss nicht ob es überhaupt relevant ist.

Du sagtest er stellt Mails nur langsam zu afair gibt es da immer wieder 
periodisch diese "Anvil-Meldungen".
Bzw. kannst du eine Mail mittels telnet servername 25 erfolgreich absetzen?

> Danke und Gruß
> 
> Michael


-- 
Gruesse/Greetings
MH


Dont send mail to: ubecatcher at linuxrocks.dyndns.org
--




Mehr Informationen über die Mailingliste Postfixbuch-users