[Postfixbuch-users] Konflikt in spampd bei Mails, die von fetchmail eingeliefert werden

[Achim Lammerts]

Hallo Liste,

seit ein paar Tagen habe ich ein Problem, bei dem ich die Ursache nicht 
finde. Für einen Kunden lasse ich seine Mails mit fetchmail von web.de 
abholen und in sein lokales Postfach liefern. Die Mails werden wie alle 
anderen Einlieferungen zuerst von amavisd geprüft, Postfix nimmt sie 
zurück und gibt sie dann an spampd weiter. Und hier reisst mir bei den 
abgeholten Mails die Verbindung ab. Das Logfile gibt in der Sache nicht 
viel her oder ich sehe den Fehler nicht. Bei externen Einlieferungen 
läuft alles wie geschmiert. Hat jemand eine Idee, was die Ursache sein kann?


Danke für die Hilfe!

Achim


Hier ein Auszug aus dem Log:


Dec  5 09:46:02 hostname postfix/smtpd[19643]: connect from 
localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname policyd: connection from: 127.0.0.1 port: 59835 
slots: 2 of 4096 used
Dec  5 09:46:02 hostname postfix/smtpd[19643]: 85AC680CC82: 
client=localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname postfix/cleanup[19620]: 85AC680CC82: 
message-id=<457531A5.7020805 at hotmail.com>
Dec  5 09:46:02 hostname postfix/qmgr[9744]: 85AC680CC82: 
from=<ta at hotmail.com>, size=1599, nrcpt=1 (queue active)
Dec  5 09:46:02 hostname amavis[17839]: (17839-08) ESMTP::10024 
/var/spool/amavis/amavis-20061205T084323-17839: <ta at hotmail.com> -> 
<user at hostname.domain.tld> Received: SIZE=1599 BODY=7BIT from 
hostname.domain.tld ([127.0.0.1]) by localhost (hostname [127.0.0.1]) 
(amavisd-new, port 10024) with ESMTP id 17839-08 for 
<user at hostname.domain.tld>; Tue,  5 Dec 2006 09:46:02 +0100 (CET)
Dec  5 09:46:02 hostname amavis[17839]: (17839-08) Checking: 
<ta at hotmail.com> -> <user at hostname.domain.tld>
Dec  5 09:46:02 hostname postfix/smtpd[19617]: disconnect from 
localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname postfix/smtpd[19643]: disconnect from 
localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname amavis[17839]: (17839-08) FWD via SMTP: 
[127.0.0.1]:10025 <ta at hotmail.com> -> <user at hostname.domain.tld>
Dec  5 09:46:02 hostname postfix/smtpd[19624]: connect from 
localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname postfix/smtpd[19624]: E3B8C80CC85: 
client=localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname postfix/cleanup[19620]: E3B8C80CC85: 
message-id=<457531A5.7020805 at hotmail.com>
Dec  5 09:46:02 hostname postfix/qmgr[9744]: E3B8C80CC85: 
from=<ta at hotmail.com>, size=2071, nrcpt=1 (queue active)
Dec  5 09:46:02 hostname spampd[19586]: 2006/12/05-09:46:02 CONNECT TCP 
Peer: "127.0.0.1:59838" Local: "127.0.0.1:10026" 
Dec  5 09:46:02 hostname spampd[19586]: Initiated Server
Dec  5 09:46:02 hostname spampd[19586]: Initiated Client
Dec  5 09:46:02 hostname postfix/smtpd[19624]: disconnect from 
localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname postfix/smtpd[19626]: connect from 
localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname spampd[19586]: smtp_server state: 'started'
Dec  5 09:46:02 hostname spampd[19586]: smtp_server state: 'EHLO 
hostname.domain.tld'
Dec  5 09:46:02 hostname spampd[19586]: Destination response: 
'250-hostname.domain.tld^M 250-PIPELINING^M 250-SIZE 25600000^M 
250-ETRN^M 250-STARTTLS^M 250-AUTH LOGIN^M 250-AUTH=LOGIN^M 250 8BITMIME'
Dec  5 09:46:02 hostname spampd[19586]: smtp_server state: 'MAIL 
FROM:<ta at hotmail.com> SIZE=2071'
Dec  5 09:46:02 hostname amavis[17839]: (17839-08) Passed, 
<ta at hotmail.com> -> <user at hostname.domain.tld>, Message-ID: 
<457531A5.7020805 at hotmail.com>, Hits: -
Dec  5 09:46:02 hostname spampd[19586]: Destination response: '250 Ok'
Dec  5 09:46:02 hostname spampd[19586]: smtp_server state: 'RCPT 
TO:<user at hostname.domain.tld>'
Dec  5 09:46:02 hostname amavis[17839]: (17839-08) TIMING [total 393 ms] 
- SMTP EHLO: 1 (0%), SMTP pre-MAIL: 0 (0%), SMTP pre-DATA-flush: 2 (1%), 
SMTP DATA: 40 (10%), body hash: 0 (0%), mime_decode: 11 (3%), 
get-file-type: 21 (5%), decompose_part: 1 (0%), parts: 0 (0%), 
AV-scan-1: 254 (64%), fwd-connect: 6 (2%), fwd-mail-from: 1 (0%), 
fwd-rcpt-to: 2 (0%), write-header: 3 (1%), fwd-data: 0 (0%), 
fwd-data-end: 41 (10%), fwd-rundown: 4 (1%), unlink-1-files: 4 (1%), 
rundown: 0 (0%)
Dec  5 09:46:02 hostname postfix/smtpd[19626]: F110480CC86: 
client=localhost.localdomain[127.0.0.1]
Dec  5 09:46:02 hostname postfix/smtp[19621]: 85AC680CC82: 
to=<user at hostname.domain.tld>, relay=127.0.0.1[127.0.0.1], delay=0, 
status=sent (250 2.6.0 Ok, id=17839-08, from MTA: 250 Ok: queued as 
E3B8C80CC85)
Dec  5 09:46:02 hostname postfix/qmgr[9744]: 85AC680CC82: removed
Dec  5 09:46:02 hostname spampd[19586]: Destination response: '250 Ok'
Dec  5 09:46:02 hostname spampd[19586]: smtp_server state: 'DATA'
Dec  5 09:46:02 hostname spampd[19586]: Destination response: '354 End 
data with <CR><LF>.<CR><LF>'
Dec  5 09:46:03 hostname spampd[19586]: smtp_server state: '.'
Dec  5 09:46:03 hostname spampd[19586]: processing message 
<457531A5.7020805 at hotmail.com> for <user at hostname.domain.tld>
Dec  5 09:46:03 hostname spampd[19586]: Exiting child process after 
handling 1 requests
Dec  5 09:46:03 hostname postfix/smtpd[19626]: lost connection after 
DATA from localhost.localdomain[127.0.0.1]
Dec  5 09:46:03 hostname postfix/smtpd[19626]: disconnect from 
localhost.localdomain[127.0.0.1]
Dec  5 09:46:03 hostname postfix/smtp[19625]: E3B8C80CC85: 
to=<user at hostname.domain.tld>, relay=127.0.0.1[127.0.0.1], delay=1, 
status=deferred (lost connection with 127.0.0.1[127.0.0.1] while sending 
end of data -- message may be sent more than once)
Dec  5 09:46:03 hostname spampd[19585]: Starting "1" children
Dec  5 09:46:03 hostname spampd[19647]: Child Preforked (19647)