[Postfixbuch-users] warning: timeout talking toproxy localhost:10024

[Uwe Driessen]

Sandy Drobic schrieb:
> 
> Das einzige, was sichtbar ist, das ist der Timeout von 300 Sekunden. Ich
> nehme an, dass der Client schon erheblich vorher aufgegeben hat. Setze
> doch mal
> debug_peer_list = 217.111.27.18
> debug_peer_level = 3

Das Produziert einen haufen Informationen *gg ich habe die ab der Übergabe an Amavis
drangehängt.

Jul 17 19:06:18 fblan postfix/smtpd[27663]: trying... [127.0.0.1]
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 220 [127.0.0.1] ESMTP
amavisd-new service ready
Jul 17 19:06:18 fblan postfix/smtpd[27663]: > localhost:10024: EHLO mail.fblan.de
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250-[127.0.0.1]
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250-VRFY
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250-PIPELINING
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250-SIZE
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250-ENHANCEDSTATUSCODES
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250-8BITMIME
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250-DSN
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250 XFORWARD NAME ADDR
PROTO HELO
Jul 17 19:06:18 fblan postfix/smtpd[27663]: > localhost:10024: XFORWARD
NAME=vmail1.regtp.de ADDR=217.111.27.18 HELO=vmail1.bnetza.de PROTO=SMTP
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250 2.5.0 Ok XFORWARD
Jul 17 19:06:18 fblan postfix/smtpd[27663]: > localhost:10024: MAIL
From:<dialer.postfach at BNetzA.de>
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250 2.1.0 Sender
dialer.postfach at BNetzA.de OK
Jul 17 19:06:18 fblan postfix/smtpd[27663]: NOQUEUE: client=vmail1.regtp.de[217.111.27.18]
Jul 17 19:06:18 fblan postfix/smtpd[27663]: > localhost:10024: RCPT To:<driessen at fblan.de>
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 250 2.1.5 Recipient
driessen at fblan.de OK
Jul 17 19:06:18 fblan postfix/smtpd[27663]: > vmail1.regtp.de[217.111.27.18]: 250 2.1.5 Ok
Jul 17 19:06:18 fblan postfix/smtpd[27663]: watchdog_pat: 0x80b3428
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < vmail1.regtp.de[217.111.27.18]: DATA
Jul 17 19:06:18 fblan postfix/smtpd[27663]: > localhost:10024: DATA
Jul 17 19:06:18 fblan postfix/smtpd[27663]: < localhost:10024: 354 End data with
<CR><LF>.<CR><LF>
Jul 17 19:06:18 fblan postfix/smtpd[27663]: > vmail1.regtp.de[217.111.27.18]: 354 End data
with <CR><LF>.<CR><LF>
Jul 17 19:06:18 fblan postfix/smtpd[27663]: > localhost:10024: .
Jul 17 19:06:19 fblan postfix/smtpd[27668]: connect from localhost.localdomain[127.0.0.1]
Jul 17 19:06:19 fblan postfix/smtpd[27668]: 28F824DCABF:
client=localhost.localdomain[127.0.0.1]
Jul 17 19:06:19 fblan postfix/cleanup[27669]: 28F824DCABF:
message-id=<C42E412AEE0932458D2A9FF129F43BBA036AE9 at itmede01c012.mede01.intern.adns>
Jul 17 19:06:19 fblan postfix/qmgr[27401]: 28F824DCABF: from=<dialer.postfach at BNetzA.de>,
size=16087, nrcpt=1 (queue active)
Jul 17 19:06:19 fblan amavis[27225]: (27225-01) Passed,<dialer.postfach at BNetzA.de> ->
<driessen at fblan.de>, quarantine OgsBht0Hcy78, Message-ID:
<C42E412AEE0932458D2A9FF129F43BBA036AE9 at itmede01c012.mede01.intern.adns>, Hits: 0.175
Jul 17 19:06:19 fblan postfix/smtpd[27663]: < localhost:10024: 250 2.6.0 Ok, id=27225-01,
from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 28F824DCABF
Jul 17 19:06:19 fblan postfix/smtpd[27663]: > vmail1.regtp.de[217.111.27.18]: 250 2.6.0
Ok, id=27225-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 28F824DCABF
Jul 17 19:06:19 fblan postfix/smtpd[27663]: > localhost:10024: QUIT
Jul 17 19:06:19 fblan postfix/smtpd[27663]: watchdog_pat: 0x80b3428
Jul 17 19:06:19 fblan postfix/smtpd[27668]: disconnect from
localhost.localdomain[127.0.0.1]
Jul 17 19:06:19 fblan postfix/virtual[27670]: 28F824DCABF: to=<driessen at fblan.de>,
relay=virtual, delay=0.13, delays=0.08/0.02/0/0.02, dsn=2.0.0, status=sent (delivered to
maildir)
Jul 17 19:06:19 fblan postfix/qmgr[27401]: 28F824DCABF: removed
Jul 17 19:06:24 fblan postfix/smtpd[27663]: < vmail1.regtp.de[217.111.27.18]: QUIT
Jul 17 19:06:24 fblan postfix/smtpd[27663]: > vmail1.regtp.de[217.111.27.18]: 221 2.0.0
Bye


------------------------------
Das verstehe ich nicht mehr so ganz warum das nachfolgende noch mal geprüft wird. 

Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: vmail1.regtp.de ~?
193.158.37.130/32
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostaddr: 217.111.27.18 ~?
193.158.37.130/32
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: vmail1.regtp.de ~? 127.0.0.0/8
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostaddr: 217.111.27.18 ~? 127.0.0.0/8
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: vmail1.regtp.de ~?
hash:/etc/postfix/vhcs2/aliases(0,lock|fold_fix)
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: lookup
hash:/etc/postfix/vhcs2/aliases.db vmail1.regtp.de: notfound
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: lookup
hash:/etc/postfix/vhcs2/aliases.db .regtp.de: notfound
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: lookup
hash:/etc/postfix/vhcs2/aliases.db .de: notfound
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostaddr: 217.111.27.18 ~?
hash:/etc/postfix/vhcs2/aliases(0,lock|fold_fix)
Jul 17 19:06:24 fblan postfix/smtpd[27663]: dict_lookup: 217.111.27.18 = (notfound)
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: vmail1.regtp.de ~?
hash:/etc/postfix/aliases(0,lock|fold_fix)
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: lookup
hash:/etc/postfix/aliases.db vmail1.regtp.de: notfound
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: lookup
hash:/etc/postfix/aliases.db .regtp.de: notfound
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: lookup
hash:/etc/postfix/aliases.db .de: notfound
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostaddr: 217.111.27.18 ~?
hash:/etc/postfix/aliases(0,lock|fold_fix)
Jul 17 19:06:24 fblan postfix/smtpd[27663]: dict_lookup: 217.111.27.18 = (notfound)
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: vmail1.regtp.de ~?
unix:passwd.byname(0,lock|fold_fix)
Jul 17 19:06:24 fblan postfix/smtpd[27663]: match_hostname: lookup unix:passwd.byname
vmail1.regtp.de: notfound
Jul 17 19:06:25 fblan postfix/smtpd[27663]: match_hostname: lookup unix:passwd.byname
.regtp.de: notfound
Jul 17 19:06:25 fblan postfix/smtpd[27663]: match_hostname: lookup unix:passwd.byname .de:
notfound
Jul 17 19:06:25 fblan postfix/smtpd[27663]: match_hostaddr: 217.111.27.18 ~?
unix:passwd.byname(0,lock|fold_fix)
Jul 17 19:06:25 fblan postfix/smtpd[27663]: dict_lookup: 217.111.27.18 = (notfound)
Jul 17 19:06:25 fblan postfix/smtpd[27663]: match_list_match: vmail1.regtp.de: no match
Jul 17 19:06:25 fblan postfix/smtpd[27663]: match_list_match: 217.111.27.18: no match
Jul 17 19:06:25 fblan postfix/smtpd[27663]: send attr request = disconnect
Jul 17 19:06:25 fblan postfix/smtpd[27663]: send attr ident = smtp:217.111.27.18
Jul 17 19:06:25 fblan postfix/smtpd[27663]: private/anvil: wanted attribute: status
Jul 17 19:06:25 fblan postfix/smtpd[27663]: input attribute name: status
Jul 17 19:06:25 fblan postfix/smtpd[27663]: input attribute value: 0
Jul 17 19:06:25 fblan postfix/smtpd[27663]: private/anvil: wanted attribute: (list
terminator)
Jul 17 19:06:25 fblan postfix/smtpd[27663]: input attribute name: (end)
Jul 17 19:06:25 fblan postfix/smtpd[27663]: disconnect from vmail1.regtp.de[217.111.27.18]



> 
> Du kannst auch mal versuchen, pipelining für den Client zu deaktivieren,
> das ist auch ab und zu mal ein Problem:
> 
> smtpd_discard_ehlo_keywords = hash:/etc/postfix/smtpd_discard_ehlo_keyword
> 
> /etc/postfix/smtpd_discard_ehlo_keyword:
> 217.111.27.18	pipelining

Das scheint es gewesen zu sein die letzte Mail kam dann sauber durch 


> 
> 


Mit freundlichen Grüßen

Drießen

-- 
Software & Computer
Uwe Drießen
Lembergstraße 33
67824 Feilbingert
Tel.: 06708 / 660045   Fax: 06708 / 661397