Amavis Mail Header Problem

[Günther J. Niederwimmer]

Hallo Leute,

Ich sage jetzt schon mal Danke, für jede Hilfe und Antwort.

Ich habe da ein "komisches" Problem mit einem Mailsender die Frage dabei ist, 
ist der andere schuld :-), oder bin ich es ?

bei meinem Hauptserver kommen diese Mails nicht an weil sich amavis 
anscheinend Aufhängt ?

Bei meinem uralten Server kommen die Mails durch ?
X-Virus-Scanned: amavisd-new at gjn.prv
X-Amavis-Alert: BAD HEADER SECTION, Improper folded header field made up
	entirely of whitespace (char 20 hex): X-Spam-Report: ...ehrt von
	"email.snw.at")\n \n Sollte die ei[...]

Beim neuen Server streikt amavis-new
obwohl ich in der amavis.conf
final_bad_header_destiny =>  D_PASS,  habe

irgend etwas stimmt mit meiner amavisd config nicht !

könnte man da mal die amavisd.conf posten damit Ihr Provis mal einen Blick 
darauf werfen könntet ?
Das Teil braucht auch ewig lange bis eine Mail durchkommt !

Aber zurück zu dem Problem hier sind die Logs vom neuen Server?

Sep 21 11:00:04 smtp postfix/smtpd[22382]: connect from 
email.snw.at[83.219.160.18]
Sep 21 11:00:04 smtp postfix/smtpd[22382]: Anonymous TLS connection established 
from email.snw.at[83.219.160.18]: TLSv1 with cipher DHE-RSA-AES256-SHA 
(256/256 bits)
Sep 21 11:00:05 smtp postfix/smtpd[22382]: 06323C1675C9: 
client=email.snw.at[83.219.160.18]
Sep 21 11:00:05 smtp postfix/cleanup[22354]: 06323C1675C9: message-
id=<004F47087AF8DA4A847FCD0923FFBBA7C6D482CA at Exchange.ug-wegscheider.local>
Sep 21 11:00:05 smtp opendkim[2700]: 06323C1675C9: email.snw.at 
[83.219.160.18] not internal
Sep 21 11:00:05 smtp opendkim[2700]: 06323C1675C9: not authenticated
Sep 21 11:00:05 smtp opendkim[2700]: 06323C1675C9: no signature data
Sep 21 11:00:05 smtp opendmarc[2697]: implicit authentication service: 
smtp.esslmaier.at
Sep 21 11:00:05 smtp opendmarc[2697]: 06323C1675C9: alois-mayr.at none
Sep 21 11:00:05 smtp amavis[8929]: loaded policy bank "AM.PDP-SOCK"
Sep 21 11:00:05 smtp amavis[8929]: process_request: fileno sock=14, STDIN=0, 
STDOUT=1
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: request=AM.PDP
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: queue_id=06323C1675C9
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: sender=<Ritsch at alois-
mayr.at>
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: 
recipient=<gjn at esslmaier.at>
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: 
tempdir=/var/spool/amavisd/tmp/afXXXXRznpko
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: tempdir_removed_by=client
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: 
mail_file=/var/spool/amavisd/tmp/afXXXXRznpko/email.txt
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: delivery_care_of=client
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: 
client_address=83.219.160.18
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: client_name=email.snw.at
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: helo_name=email.snw.at
Sep 21 11:00:05 smtp amavis[8929]: policy protocol: 
policy_bank=smtp.esslmaier.at
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) Request: AM.PDP  
/var/spool/amavisd/tmp/afXXXXRznpko: <Ritsch at alois-mayr.at> -> 
<gjn at esslmaier.at>
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) body hash: 
fc5e1a06b77c4e59decac978ed185074
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) trace: AM.PDP://x < 
83.219.160.18 < esmtpsa://83.219.166.2 < mapi://fe80::304d:25ce:74ef:6bb2
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) Checking: sdgiXaKsuh2z AM.PDP-
SOCK [83.219.160.18] <Ritsch at alois-mayr.at> -> <gjn at esslmaier.at>
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) 2822.From: <Ritsch at alois-
mayr.at>
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) p004 1 Content-Type: 
multipart/related
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) p005 1/1 Content-Type: 
multipart/alternative
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) p001 1/1/1 Content-Type: 
text/plain, size: 322 B, name:
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) p002 1/1/2 Content-Type: 
text/html, size: 5947 B, name:
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) p003 1/2 Content-Type: 
image/jpeg, size: 4873 B, name: image001.jpg
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) inspect_dsn: not a bounce
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) check_header: 4, Improper folded 
header field made up entirely of whitespace (char 20 hex): X-Spam-Report: 
...ehrt von "email.snw.at")\n \n Sollte die ei[...]
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) Checking for banned types and 
filenames
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) collect banned table[0]: 
gjn at esslmaier.at, tables: DEFAULT=>Amavis::Lookup::RE=ARRAY(0x25ab3e8)
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) p.path gjn at esslmaier.at: 
"P=p004,L=1,M=multipart/related | 
P=p003,L=1/2,M=image/jpeg,T=image,T=jpg,N=image001.jpg"
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) p.path gjn at esslmaier.at: 
"P=p004,L=1,M=multipart/related | P=p005,L=1/1,M=multipart/alternative | 
P=p001,L=1/1/1,M=text/plain,T=txt"
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) p.path gjn at esslmaier.at: 
"P=p004,L=1,M=multipart/related | P=p005,L=1/1,M=multipart/alternative | 
P=p002,L=1/1/2,M=text/html,T=asc"
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) presenting full original message 
to scanners as /var/spool/amavisd/tmp/afXXXXRznpko/parts/p006
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) run_av Using (ClamAV-clamd): 
(code) CONTSCAN /var/spool/amavisd/tmp/afXXXXRznpko/parts\n
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) ClamAV-clamd: Connecting to 
socket  /var/run/clamd.amavisd/clamd.sock
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) new socket by IO::Socket::UNIX 
to /var/run/clamd.amavisd/clamd.sock, timeout set to 10
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) ClamAV-clamd: Sending CONTSCAN 
/var/spool/amavisd/tmp/afXXXXRznpko/parts\n to socket 
/var/run/clamd.amavisd/clamd.sock
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) rw_loop read: got eof
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) run_av (ClamAV-clamd): CLEAN
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) run_av (ClamAV-clamd) result: 
clean
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) blocking ccat (4) differs from 
ccat_maj=4,4, gjn at esslmaier.at, final_destiny -2
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) do_notify_and_quar: 
ccat=BadHdrSpace (4,4) ("4,4":BadHdrSpace, "4":BadHdr, "1":Clean, 
"0":CatchAll) ccat_block=(4), qar_mth=
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) header_edits_for_quar: 
rec_bl_ccat=(4,0), ccat=(4,4) gjn at esslmaier.at
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) header_edits_for_quar: 
<Ritsch at alois-mayr.at> -> <gjn at esslmaier.at>, No, score=x tag=x tag2=x kill=x 
tests=[] autolearn=unavailable
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) skip local delivery(3): <> -> 
<bad-header-quarantine>
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) delivery method is 1, recips: 
gjn at esslmaier.at
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) DSN: NOTIFICATION: 
Action:failed, LOCAL 554 BadHdr, spam level 0.000, <Ritsch at alois-mayr.at> -> 
<gjn at esslmaier.at>
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) smtp session: setting up a new 
session
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) new socket using IO::Socket::IP 
to [127.0.0.1]:10025, timeout 35
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) (!)connect to 127.0.0.1:10025 
failed, attempt #1: Can't connect to socket 127.0.0.1:10025 using module 
IO::Socket::IP: Connection refused
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) mail_via_smtp: session failed: 
All attempts (1) failed connecting to smtp:127.0.0.1:10025
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) (!)XMtECE4sOZhI(sdgiXaKsuh2z) 
SEND from <> -> <Ritsch at alois-mayr.at>,  451 4.5.0 From MTA() during fwd-
connect (All attempts (1) failed connecting to smtp:127.0.0.1:10025): 
id=08929-01
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) (!!)TROUBLE in check_mail: 
delivery-notification FAILED: temporarily unable to send DSN to <Ritsch at alois-
mayr.at>: 451 4.5.0 From MTA() during fwd-connect (All attempts (1) failed 
connecting to smtp:127.0.0.1:10025): id=08929-01 at /usr/sbin/amavisd line 
15146.
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) mail checking ended: 
version_server=2\nlog_id=08929-01\nsetreply=451 4.5.0 Error%20in%20processing,
%20id=08929-01,%20delivery-notification%20FAILED:
%20temporarily%20unable%20to%20send%20DSN%20to%20<Ritsch at alois-mayr.at>:
%20451%204.5.0%20From%20MTA()%20during%20fwd-
connect%20(All%20attempts%20(1)%20failed%20connecting%20to%20smtp:127.0.0.1:10025):
%20id=08929-01%20at%20/usr/sbin/amavisd%20line%2015146.
\nreturn_value=tempfail\nexit_code=75
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) size: 17618, TIMING [total 108 
ms] - got data: 0.0 (0%)0, check_init: 4.1 (4%)4, digest_hdr: 1.4 (1%)5, 
digest_body: 0.4 (0%)6, collect_info: 3.2 (3%)8, mkdir parts: 1.4 (1%)10, 
mime_decode: 18 (16%)26, get-file-type3: 29 (26%)53, parts_decode: 0.2 (0%)53, 
check_header: 1.0 (1%)54, AV-scan-1: 24 (22%)76, decide_mail_destiny: 2.3 
(2%)78, notif-quar: 0.7 (1%)79, quar-hdrs: 1.6 (2%)80, save-to-local-mailbox: 
2.1 (2%)82, prepare-dsn: 13 (12%)94, fwd-end-chkpnt: 4.3 (4%)98, rundown: 2.1 
(2%)100
Sep 21 11:00:05 smtp postfix/cleanup[22354]: 06323C1675C9: milter-reject: END-
OF-MESSAGE from email.snw.at[83.219.160.18]: 4.5.0 Error in processing, 
id=08929-01, delivery-notification FAILED: temporarily unable to send DSN to 
<Ritsch at alois-mayr.at>: 451 4.5.0 From MTA() during fwd-connect (All attempts 
(1) failed connecting to smtp:127.0.0.1:10025): id=08929-01 at 
/usr/sbin/amavisd line 15146.; from=<Ritsch at alois-mayr.at> 
to=<gjn at esslmaier.at> proto=ESMTP helo=<email.snw.at>
Sep 21 11:00:05 smtp amavis[8929]: (08929-01) load: 100 %, total idle 0.000 s, 
busy 0.120 s
Sep 21 11:00:05 smtp postfix/smtpd[22382]: disconnect from 
email.snw.at[83.219.160.18]

-- 
mit freundlichen Grüßen / best regards,

  Günther J. Niederwimmer