Das hier ist mehr oder weniger die Ausgabe des Debugs, während die Kombination Postfix Clamd, Amavisd und Spamassassin eine Mail empfängt. Ich kann nichts ungewöhnliches finden. Vielleicht kommt euch was spanisch vor und ihr habt einen Tip....
Jun 29 00:06:52 xxxx amavisd[2294]: (02294-01) ESMTP> 250 2.1.0 Sender mail@flo
rianzahn.de OK
Jun 29 00:06:52 xxxx amavisd[2294]: (02294-01) prolong_timer after reading SMTP
command: remaining time = 300 s
Jun 29 00:06:52 xxxx amavisd[2294]: (02294-01) ESMTP< RCPT TO:<
web1p1@xxxx.ser
verkompetenz.net>rn
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) ESMTP> 250 2.1.5 Recipient web1p
1@xxxx.serverkompetenz.net OK
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) prolong_timer after reading SMTP
command: remaining time = 300 s
Jun 29 00:06:52 xxxx amavisd[2294]: (02294-01) ESMTP< DATArn
Jun 29 00:06:52 xxxx amavisd[2294]: (02294-01) prolong_timer after DATA receive
d - timer reset: remaining time = 300 s
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) ESMTP::10024 /var/amavis/amavis-
20040629T000652-02294: <
mail@florianzahn.de> -> <
web1p1@xxxx.serverkompetenz.ne
t> Received: SIZE=13362 from hxxxx.serverkompetenz.net ([127.0.0.1]) by localhos
t (xxxx [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 02294-01 for <web1
p1@xxxx.serverkompetenz.net>; Tue, 29 Jun 2004 00:06:52 +0200 (CEST)
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) ESMTP> 354 End data with <CR><LF
>.<CR><LF>
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) ESMTP< .rn
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) body hash: 6903f398a38ab287fb0b7
c0559ccebd8
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Original mail size: 13123; quota
set to: 6561500 bytes
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Checking: <
mail@florianzahn.de>
-> <
web1p1@hxxxx.serverkompetenz.net>
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="
web1p1@hxxxx.se
rverkompetenz.net", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="
web1p1@hxxxx.se
rverkompetenz.net", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Extracting mime components
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Issued a new file name: part-000
01
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Issued a new file name: part-000
02
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) mime_decode_preamble: 3 lines
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) mime_decode: Content-type: multi
part/alternative
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) mime_decode_epilogue: 1 lines
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) mime_decode: Content-type: text/
plain, name:
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Charging 832 bytes to remaining
quota 6561500 (out of 6561500, (0%)) - by mime_decode
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) mime_decode: Content-type: text/
html, name:
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Charging 10392 bytes to remainin
g quota 6560668 (out of 6561500, (0%)) - by mime_decode
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) prolong_timer after mime_decode-
1: remaining time = 300 s
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Checking for banned MIME types a
nd names
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) check_for_banned - mime-type: mu
ltipart/alternative
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_RE: key="multipart/altern
ative", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) check_for_banned - mime-type: te
xt/plain
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_RE: key="text/plain", no
match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) check_for_banned - mime-type: te
xt/html
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_RE: key="text/html", no m
atch
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="
web1p1@hxxxx.se
rverkompetenz.net", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) decode_parts: level=1, #parts=2
: part-00001, part-00002
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) run_command: [2301] /usr/bin/fil
e /var/amavis/amavis-20040629T000652-02294/parts/part-00001 </dev/null
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) File-type of part-00001: ASCII E
nglish text; (.asc)
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) run_command: [2302] /usr/bin/fil
e /var/amavis/amavis-20040629T000652-02294/parts/part-00002 </dev/null
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) File-type of part-00002: HTML do
cument text; (.html)
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="
web1p1@hxxxx.se
rverkompetenz.net", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) Checking for banned (contents-ba
sed) file types, 2 parts
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) check_for_banned (part-00001) -
file type: .asc
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_RE: key=".asc", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) check_for_banned (part-00001) -
file type: ASCII English text
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_RE: key="ASCII English te
xt", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) check_for_banned (part-00002) -
file type: .html
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_RE: key=".html", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) check_for_banned (part-00002) -
file type: HTML document text
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) lookup_RE: key="HTML document te
xt", no match
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) do_ascii: Decoding part part-000
01 (0 items)
Jun 29 00:06:52 hxxxx amavisd[2294]: (02294-01) decompose_part: part-00001 - ato
mic
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) decompose_part: part-00002 - ato
mic
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) prolong_timer after decoding: re
maining time = 290 s
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_RE: key="MAIL", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) Using Clam Antivirus-clamd: (bui
lt-in interface)
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) Clam Antivirus-clamd: Connecting
to socket /var/run/clamav/clamd
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) Clam Antivirus-clamd: Sending CO
NTSCAN /var/amavis/amavis-20040629T000652-02294/partsn to UNIX socket /var/run/
clamav/clamd
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) Clam Antivirus-clamd result: /va
r/amavis/amavis-20040629T000652-02294/parts: OKn
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) prolong_timer after virus_scan:
remaining time = 290 s
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) white_black_list: checking sende
r <
mail@florianzahn.de>
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="mail@florianzah
n.de", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_RE: key="mail@florianzahn
.de", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_hash: key="mail@florianza
hn.de", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_hash: key="mail@", no mat
ch
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_hash: key="florianzahn.de
", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_hash: key=".florianzahn.d
e", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_hash: key=".de", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_hash: key=".", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="mail@florianzah
n.de", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="
web1p1@hxxxx.se
rverkompetenz.net", no match
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) calling SA parse, SA version 2.6
3
Jun 29 00:07:02 hxxxx amavisd[2294]: (02294-01) CALLING SA check
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) RETURNED FROM NoMailAudit::check
, time left: 25 s
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) prolong_timer after spam_scan_SA
: remaining time = 290 s
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) spam_scan: hits=5.416 tests=CLIC
K_BELOW_CAPS,HTML_70_80,HTML_LINK_CLICK_CAPS,HTML_LINK_CLICK_HERE,HTML_MESSAGE,H
TML_TAG_EXISTS_TBODY,HTML_WEB_BUGS,LINES_OF_YELLING,MORTGAGE_BEST,MORTGAGE_PITCH
,NO_REAL_NAME,RCVD_IN_SORBS
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) prolong_timer after spam_scan: r
emaining time = 290 s
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) lookup: (scalar) matches, result
="10"
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) header: Received: from hxxxx.ser
verkompetenz.net ([127.0.0.1])n by localhost (hxxxx [127.0.0.1]) (amavisd-new,
port 10024) with ESMTPn id 02294-01 for <
web1p1@hxxxx.serverkompetenz.net>;n T
ue, 29 Jun 2004 00:06:52 +0200 (CEST)n
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) header: X-Virus-Scanned: by amav
isd-new at florianzahn.den
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="
web1p1@hxxxx.se
rverkompetenz.net", no match
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) lookup_acl: key="
web1p1@hxxxx.se
rverkompetenz.net", no match
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) lookup: (scalar) matches, result
="-999"
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) lookup: (scalar) matches, result
="5"
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) headers CLUSTERING: NEW CLUSTER
<
web1p1@hxxxx.serverkompetenz.net>: hits=5.4, tag=0, tag2=0, subj=0, subj_u=0, l
ocal=0, bl=0
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) headers CLUSTERING: done all 1 r
ecips in one go
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) FWD via SMTP: [127.0.0.1]:10025
<
mail@florianzahn.de> -> <
web1p1@hxxxx.serverkompetenz.net>
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) Remote host introduces itself as
: hxxxx.serverkompetenz.net
Jun 29 00:07:07 hxxxx amavisd[2294]: (02294-01) prolong_timer after fwd-connect:
remaining time = 290 s
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) prolong_timer after fwd-mail-fro
m: remaining time = 290 s
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) prolong_timer after fwd-rcpt-to:
remaining time = 290 s
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) response to DATA: "354 End data
with <CR><LF>.<CR><LF>"
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) prolong_timer after fwd-data: re
maining time = 290 s
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) prolong_timer after fwd-data-end
: remaining time = 290 s
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) response to data end: "250 Ok: q
ueued as 1C444688094"
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) prolong_timer after fwd-rundown-
1: remaining time = 290 s
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) mail_via_smtp: 250 2.6.0 Ok, id=
02294-01, from MTA: 250 Ok: queued as 1C444688094
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) prolong_timer after forwarding:
remaining time = 290 s
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) one_response_for_all <mail@flori
anzahn.de>: success, dsn_needed=0, '250 2.6.0 Ok, id=02294-01, from MTA: 250 Ok:
queued as 1C444688094'
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) warnsender_with_pass=(,,,), dsn_
needed=0, exit=0, 250 2.6.0 Ok, id=02294-01, from MTA: 250 Ok: queued as 1C44468
8094
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) Passed, <
mail@florianzahn.de> ->
<
web1p1@hxxxx.serverkompetenz.net>, Message-ID: <00001FDF.40E0B2BC@florianzahn.
de>, Hits: 5.416
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) sending SMTP response: "250 2.6.
0 Ok, id=02294-01, from MTA: 250 Ok: queued as 1C444688094"
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) timer stopped after DATA end
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) rmdir_recursively: /var/amavis/a
mavis-20040629T000652-02294/parts, excl=1
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) TIMING [total 32876 ms] - SMTP E
HLO: 4 (0%), SMTP pre-MAIL: 1 (0%), mkdir tempdir: 0 (0%), create email.txt: 1 (
0%), SMTP pre-DATA-flush: 5 (0%), SMTP DATA: 77 (0%), body hash: 1 (0%), mkdir p
arts: 1 (0%), mime_decode: 20 (0%), get-file-type: 9 (0%), get-file-type: 6 (0%)
, decompose_part: 10131 (31%), decompose_part: 0 (0%), parts: 0 (0%), AV-scan-1:
6 (0%), SA msg read: 2 (0%), SA parse: 1 (0%), SA check: 5227 (16%), fwd-connec
t: 13 (0%), fwd-mail-from: 17328 (53%), fwd-rcpt-to: 2 (0%), write-header: 3 (0%
), fwd-data: 0 (0%), fwd-data-end: 33 (0%), fwd-rundown: 1 (0%), unlink-2-files:
3 (0%), rundown: 0 (0%)
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) ESMTP> 250 2.6.0 Ok, id=02294-01
, from MTA: 250 Ok: queued as 1C444688094
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) prolong_timer after reading SMTP
command: remaining time = 0 s
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) ESMTP< QUITrn
Jun 29 00:07:25 hxxxx amavisd[2294]: (02294-01) ESMTP> 221 2.0.0 [127.0.0.1] (am
avisd) closing transmission channel