[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