[Postfixbuch-users] Amavis als smtpd-proxy, timeout warning und doppelte Mails
Kai Fürstenberg
kai_postfix at fuerstenberg.ws
Mi Aug 20 17:30:02 CEST 2008
Hallo Liste,
Ich habe seit gestern folgendes Phänomen:
Vereinzelte eingehende Mails werden doppelt zugestellt (sie sind
wirklich zwei mal im Imap-Ordner vorhanden). Das tritt immer dann auf,
wenn eine Timeout-warnung bei der Verbindung zum smtpd-proxy (Amavis)
auftritt. Lt. Log wurde die Mail aber nur ein mal zugestellt. Ausserdem
würde mich interessieren, wieso jetzt plötzlich ein Timeout auftritt, wo
doch gar nichts an der Konfig verändert wurde.
Gruß
Kai
--- maillog ---
Aug 20 12:06:07 mail postfix/smtpd[7640]: connect from
ilpostino.jpberlin.de[213.203.238.6]
Aug 20 12:06:08 mail postfix/policyd-weight[20099]: decided
action=PREPEND X-policyd-weight: using cached result; rate: -7.6; delay: 0s
Aug 20 12:06:08 mail postfix/smtpd[7640]: NOQUEUE:
client=ilpostino.jpberlin.de[213.203.238.6]
Aug 20 12:06:08 mail amavis[26212]: (26212-03) ESMTP::10024
/var/amavis/tmp/amavis-20080819T233437-26212:
<postfixbuch-users-bounces at listi.jpberlin.de> ->
<kai_postfix at fuerstenberg.ws> SIZE=3932 Received: from
mail.fuerstenberg.ws ([127.0.0.1]) by localhost (mail.fuerstenberg.ws
[127.0.0.1]) (amavisd-new, port 10024) with ESMTP for
<kai_postfix at fuerstenberg.ws>; Wed, 20 Aug 2008 12:06:08 +0200 (CEST)
Aug 20 12:06:08 mail amavis[26212]: (26212-03) Actual message size 4184
B greater than the declared 3932 B
Aug 20 12:06:08 mail amavis[26212]: (26212-03) Checking: jRFzMtTHTbIP
[213.203.238.6] <postfixbuch-users-bounces at listi.jpberlin.de> ->
<kai_postfix at fuerstenberg.ws>
Aug 20 12:06:08 mail amavis[26212]: (26212-03) p001 1 Content-Type:
text/plain, size: 1029 B, name:
Aug 20 12:07:48 mail postfix/smtpd[7640]: warning: timeout talking to
proxy 127.0.0.1:10024
Aug 20 12:08:20 mail postfix/cleanup[11471]: 5402D26C0050:
message-id=<20080820100748.5402D26C0050 at mail.fuerstenberg.ws>
Aug 20 12:08:26 mail amavis[26212]: (26212-03) SPAM-TAG,
<postfixbuch-users-bounces at listi.jpberlin.de> ->
<kai_postfix at fuerstenberg.ws>, No, score=1.83 required=6.31
tests=[RCVD_IN_BL_SPAMCOP_NET=1.832, SPF_HELO_PASS=-0.001, SPF_PASS=-0.001]
Aug 20 12:08:26 mail postfix/smtpd[11948]: connect from localhost[127.0.0.1]
Aug 20 12:08:26 mail postfix/smtpd[11948]: 0FE8A26C0052:
client=localhost[127.0.0.1]
Aug 20 12:08:26 mail postfix/cleanup[11952]: 0FE8A26C0052:
message-id=<200808201208.38992.p.heinlein at heinlein-support.de>
Aug 20 12:08:29 mail postfix/smtpd[7640]: disconnect from
ilpostino.jpberlin.de[213.203.238.6]
Aug 20 12:08:29 mail postfix/qmgr[23604]: 5402D26C0050:
from=<double-bounce at mail.fuerstenberg.ws>, size=1148, nrcpt=1 (queue active)
Aug 20 12:08:31 mail postfix/qmgr[23604]: 0FE8A26C0052:
from=<postfixbuch-users-bounces at listi.jpberlin.de>, size=4816, nrcpt=1
(queue active)
Aug 20 12:08:31 mail postfix/smtpd[11948]: disconnect from
localhost[127.0.0.1]
Aug 20 12:08:31 mail amavis[26212]: (26212-03) FWD via SMTP:
<postfixbuch-users-bounces at listi.jpberlin.de> ->
<kai_postfix at fuerstenberg.ws>, BODY=8BITMIME 250 2.6.0 Ok, id=26212-03,
from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 0FE8A26C0052
Aug 20 12:08:31 mail amavis[26212]: (26212-03) Passed CLEAN,
[213.203.238.6] [82.113.106.16]
<postfixbuch-users-bounces at listi.jpberlin.de> ->
<kai_postfix at fuerstenberg.ws>, Message-ID:
<200808201208.38992.p.heinlein at heinlein-support.de>, mail_id:
jRFzMtTHTbIP, Hits: 1.83, queued_as: 0FE8A26C0052, 143114 ms
Aug 20 12:08:31 mail amavis[26212]: (26212-03) TIMING [total 143126 ms]
- SMTP greeting: 2 (0%)0, SMTP EHLO: 0 (0%)0, SMTP pre-MAIL: 1 (0%)0,
SMTP pre-DATA-flush: 3 (0%)0, SMTP DATA: 72 (0%)0, check_init: 1 (0%)0,
digest_hdr: 1 (0%)0, digest_body: 0 (0%)0, gen_mail_id: 0 (0%)0,
mime_decode: 12 (0%)0, get-file-type1: 20 (0%)0, parts_decode: 0 (0%)0,
check_header: 4 (0%)0, AV-scan-1: 3 (0%)0, spam-wb-list: 2 (0%)0, SA msg
read: 1 (0%)0, SA parse: 4 (0%)0, SA check: 137556 (96%)96, SA finish: 5
(0%)96, update_cache: 2 (0%)96, decide_mail_destiny: 1 (0%)96,
fwd-connect: 34 (0%)96, fwd-mail-from: 5 (0%)96, fwd-rcpt-to: 653
(0%)97, fwd-data-cmd: 1 (0%)97, write-header: 2 (0%)97,
fwd-data-contents: 1 (0%)97, fwd-data-end: 4684 (3%)100, fwd-rundown: 12
(0%)100, prepare-dsn: 10 (0%)100, main_log_entry: 24 (0%)100,
update_snmp: 2 (0%)100, SMTP pre-response: 2 (0%)100, SMTP response: 1
(0%)100, unlink-1-files: 2 (0%)100, rundown: 2 (0%)100
Aug 20 12:08:33 mail postfix/virtual[12047]: 0FE8A26C0052:
to=<kai_postfix at fuerstenberg.ws>, relay=virtual, delay=7.8,
delays=3.4/2/0/2.4, dsn=2.0.0, status=sent (delivered to maildir)
Aug 20 12:08:33 mail postfix/virtual[12005]: 5402D26C0050:
to=<postmaster at fuerstenberg.ws>, orig_to=<postmaster>, relay=virtual,
delay=45, delays=41/2/0/2.4, dsn=2.0.0, status=sent (delivered to maildir)
Aug 20 12:08:33 mail postfix/qmgr[23604]: 5402D26C0050: removed
Aug 20 12:08:33 mail postfix/qmgr[23604]: 0FE8A26C0052: removed
--- Auszug aus ls -l cur --- Doppelte Mails ---
-rw------- 1 cyrus mail 5919 Aug 20 11:12
1219223579.V7bI8ca8120M198961.mail.fuerstenberg.ws:2,S
-rw------- 1 cyrus mail 5919 Aug 20 11:38
1219225102.V7bI8ca8122M999202.mail.fuerstenberg.ws:2,S
-rw------- 1 cyrus mail 6903 Aug 20 14:14
1219234481.V7bI8ca812cM29442.mail.fuerstenberg.ws:2,S
-rw------- 1 cyrus mail 6710 Aug 20 14:17
1219234677.V7bI8ca8130M806016.mail.fuerstenberg.ws:2,S
-rw------- 1 cyrus mail 6710 Aug 20 14:41
1219236100.V7bI8ca8134M588893.mail.fuerstenberg.ws:2,S
-rw------- 1 cyrus mail 6903 Aug 20 14:41
1219236100.V7bI8ca8136M599460.mail.fuerstenberg.ws:2,S
--- postconf -n ---
alias_database = /etc/aliases
alias_maps = hash:/etc/aliases
biff = no
bounce_size_limit = 100000
broken_sasl_auth_clients = yes
command_directory = /usr/sbin
config_directory = /etc/postfix
daemon_directory = /usr/libexec/postfix
data_directory = /var/lib/postfix
default_destination_recipient_limit = 1
disable_vrfy_command = yes
html_directory = no
inet_interfaces = all
local_destination_concurrency_limit = 1
local_transport = maildrop
mail_owner = postfix
mail_spool_directory = /var/courier/mail/
mailq_path = /usr/bin/mailq.postfix
manpage_directory = /usr/share/man
message_size_limit = 40000000
mydestination = localhost, localhost.localdomain
mydomain = fuerstenberg.ws
myhostname = mail.fuerstenberg.ws
mynetworks = 127.0.0.1
myorigin = $mydomain
newaliases_path = /usr/bin/newaliases.postfix
queue_directory = /var/spool/postfix
readme_directory = /usr/share/doc/postfix-2.1.5/README_FILES
recipient_delimiter = +
relay_clientcerts = mysql:/etc/postfix/sql/fingerprint.cf
sample_directory = /usr/share/doc/postfix-2.1.5/samples
sendmail_path = /usr/sbin/sendmail.postfix
setgid_group = postdrop
show_user_unknown_table_name = no
smtpd_data_restrictions = reject_unauth_pipelining
smtpd_delay_reject = yes
smtpd_error_sleep_time = 15s
smtpd_hard_error_limit = 6
smtpd_helo_required = yes
smtpd_recipient_restrictions = permit_tls_clientcerts
permit_sasl_authenticated permit_mynetworks
reject_unauth_destination reject_unlisted_recipient
check_helo_access hash:/etc/postfix/helo_checks check_recipient_access
hash:/etc/postfix/rcpt_checks/rcpt_check check_client_access
hash:/etc/postfix/client_access reject_unknown_sender_domain
reject_unknown_recipient_domain reject_non_fqdn_sender
reject_non_fqdn_recipient check_client_access
cidr:/etc/postfix/client_checks check_policy_service
inet:127.0.0.1:12525 reject_unknown_reverse_client_hostname
reject_invalid_helo_hostname
smtpd_reject_unlisted_recipient = yes
smtpd_restriction_classes = greylist, greylist_check, list_in_postfix,
list_in_postfixbuch, kai_rcpt_check
smtpd_sasl_auth_enable = yes
smtpd_sasl_local_domain =
smtpd_sasl_security_options = noanonymous
smtpd_soft_error_limit = 3
smtpd_tls_CAfile = /etc/postfix/certs/root.crt
smtpd_tls_ask_ccert = yes
smtpd_tls_cert_file = /etc/postfix/certs/servercert.pem
smtpd_tls_key_file = /etc/postfix/certs/cert.key
smtpd_tls_received_header = yes
smtpd_tls_session_cache_timeout = 3600s
smtpd_use_tls = yes
strict_rfc821_envelopes = yes
tls_random_source = dev:/dev/urandom
unknown_local_recipient_reject_code = 450
virtual_alias_maps = mysql:/etc/postfix/sql/recipients.cf
virtual_gid_maps = static:12
virtual_mailbox_base = /var/courier/mail
virtual_mailbox_domains = mxservices.de, zwergenwelt.com,
fuerstenberg.ws, raabennest.eu
virtual_mailbox_maps = mysql:/etc/postfix/sql/mailboxes.cf
virtual_minimum_uid = 50
virtual_uid_maps = static:76
--- master.cf ---
smtp inet n - n - - smtpd
-o smtpd_sasl_auth_enable=yes
-o smtpd_proxy_filter=127.0.0.1:10024
smtps inet n - n - - smtpd
-o smtpd_tls_wrappermode=yes -o smtpd_sasl_auth_enable=yes
submission inet n - n - - smtpd
-o smtpd_enforce_tls=yes
-o smtpd_sasl_auth_enable=yes
-o
smtpd_recipient_restrictions=permit_tls_clientcerts,permit_sasl_authenticated,reject
-o smtpd_proxy_filter=
pickup fifo n - n 60 1 pickup
cleanup unix n - n - 0 cleanup
qmgr fifo n - n 300 1 qmgr
rewrite unix - - n - - trivial-rewrite
bounce unix - - n - 0 bounce
defer unix - - n - 0 bounce
trace unix - - n - 0 bounce
verify unix - - n - 1 verify
flush unix n - n 1000? 0 flush
proxymap unix - - n - - proxymap
smtp unix - - n - - smtp
relay unix - - n - - smtp
showq unix n - n - - showq
error unix - - n - - error
local unix - n n - - local
virtual unix - n n - - virtual
lmtp unix - - n - - lmtp
anvil unix - - n - 1 anvil
maildrop unix - n n - - pipe
flags=DRhu user=cyrus argv=/usr/bin/maildrop
-d cyrus ${user} ${extension}
uucp unix - n n - - pipe
flags=Fqhu user=uucp argv=uux -r -n -z -a$sender - $nexthop!rmail
($recipient)
ifmail unix - n n - - pipe
flags=F user=ftn argv=/usr/lib/ifmail/ifmail -r $nexthop ($recipient)
bsmtp unix - n n - - pipe
flags=Fq. user=foo argv=/usr/local/sbin/bsmtp -f $sender $nexthop
$recipient
127.0.0.1:10025 inet n - n - -
smtpd
-o content_filter=
-o smtpd_proxy_filter=
smtp-amavis unix - - n - 8 smtp
-o smtp_data_done_timeout=1200s
-o disable_dns_lookups=yes
policy unix - n n -
- spawn
user=greylist argv=/usr/bin/perl /usr/libexec/postfix/greylist.pl
procmail unix - n n -
- pipe
flags=R user=cyrus argv=/usr/bin/procmail -p USER=${user}
EXTENSION=${extension} /etc/procmail/procmailrc
scache unix - - n -
1 scache
discard unix - - n -
- discard
tlsmgr unix - - n 1000?
1 tlsmgr
retry unix - - n -
- error
proxywrite unix - - n - 1 proxymap
Mehr Informationen über die Mailingliste Postfixbuch-users