[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