postfix 2.8 and upper don't close connection with smtpd_proxy_filter

Mon, 22 Apr 2013 07:41:35 -0700 

Hi,
Since i have upgrade my postfix from 2.6.x to 2.8.x or 2.10.x postfix don't send the command QUIT after the response (code 250) to END-OF-MESSAGE. 

Debug :

Before with postfix 2.6.18 :
----------------------------
...

Apr 22 14:36:47 dedi dkimproxy.in[18373]: DKIM verify - none; 
from=<[email protected]>
Apr 22 14:36:47 dedi postfix/cleanup[4973]: B2FCF261729: 
message-id=<[email protected]>
Apr 22 14:36:47 dedi postfix/qmgr[4966]: B2FCF261729: 
from=<[email protected]>, size=651, nrcpt=1 (queue active)
Apr 22 14:36:47 dedi postfix/smtpd[4972]: public/cleanup socket: wanted 
attribute: status

Apr 22 14:36:47 dedi postfix/smtpd[4972]: input attribute name: status
Apr 22 14:36:47 dedi postfix/smtpd[4972]: input attribute value: 0

Apr 22 14:36:47 dedi postfix/smtpd[4972]: public/cleanup socket: wanted 
attribute: reason

Apr 22 14:36:47 dedi postfix/smtpd[4972]: input attribute name: reason
Apr 22 14:36:47 dedi postfix/smtpd[4972]: input attribute value: (end)

Apr 22 14:36:47 dedi postfix/smtpd[4972]: public/cleanup socket: wanted 
attribute: (list terminator)

Apr 22 14:36:47 dedi postfix/smtpd[4972]: input attribute name: (end)

Apr 22 14:36:47 dedi postfix/smtpd[4972]: > 
dedi.ludosoft.org[127.0.0.1]: 250 2.0.0 Ok: queued as B2FCF261729

Apr 22 14:36:47 dedi postfix/smtpd[4972]: watchdog_pat: 0x8d721b8

Apr 22 14:36:47 dedi postfix/smtpd[4968]: < 127.0.0.1:10025: 250 2.0.0 
Ok: queued as B2FCF261729
Apr 22 14:36:47 dedi postfix/smtpd[4968]: > 
dedi.ludosoft.org[127.0.0.1]: 250 2.0.0 Ok: queued as B2FCF261729

Apr 22 14:36:47 dedi postfix/smtpd[4968]: > 127.0.0.1:10025: QUIT
Apr 22 14:36:47 dedi postfix/smtpd[4968]: watchdog_pat: 0x9fea028

Apr 22 14:36:47 dedi postfix/smtpd[4972]: < 
dedi.ludosoft.org[127.0.0.1]: QUIT
Apr 22 14:36:47 dedi postfix/smtpd[4972]: > 
dedi.ludosoft.org[127.0.0.1]: 221 2.0.0 Bye
Apr 22 14:36:47 dedi postfix/smtpd[4972]: match_hostname: 
dedi.ludosoft.org ~? 127.0.0.0/8
Apr 22 14:36:47 dedi postfix/smtpd[4972]: match_hostaddr: 127.0.0.1 ~? 
127.0.0.0/8
Apr 22 14:36:47 dedi postfix/smtpd[4972]: disconnect from 
dedi.ludosoft.org[127.0.0.1]
Apr 22 14:36:48 dedi postfix/smtp[4974]: B2FCF261729: 
to=<[email protected]>, relay=filenet.ludosoft.org[82.236.203.193]:25, 
delay=17, delays=16/0.08/0.27/0.52, dsn=2.0.0, status=sent (250 2.0.0 
Ok: queued as 9F6BE122798B)

Apr 22 14:36:48 dedi postfix/qmgr[4966]: B2FCF261729: removed

Apr 22 14:36:52 dedi postfix/smtpd[4968]: < 
dedi.ludosoft.org[127.0.0.1]: QUIT
Apr 22 14:36:52 dedi postfix/smtpd[4968]: > 
dedi.ludosoft.org[127.0.0.1]: 221 2.0.0 Bye
Apr 22 14:36:52 dedi postfix/smtpd[4968]: match_hostname: 
dedi.ludosoft.org ~? 127.0.0.0/8
Apr 22 14:36:52 dedi postfix/smtpd[4968]: match_hostaddr: 127.0.0.1 ~? 
127.0.0.0/8
Apr 22 14:36:52 dedi postfix/smtpd[4968]: disconnect from 
dedi.ludosoft.org[127.0.0.1]



Now with postfix 2.10.0 :
----------------------------
...

Apr 22 14:15:08 dedi dkimproxy.in[1459]: DKIM verify - none; 
from=<[email protected]>
Apr 22 14:15:08 dedi postfix/cleanup[2975]: C0BBC261729: 
message-id=<[email protected]>
Apr 22 14:15:08 dedi postfix/qmgr[2966]: C0BBC261729: 
from=<[email protected]>, size=651, nrcpt=1 (queue active)
Apr 22 14:15:08 dedi postfix/smtpd[2974]: public/cleanup socket: wanted 
attribute: status

Apr 22 14:15:08 dedi postfix/smtpd[2974]: input attribute name: status
Apr 22 14:15:08 dedi postfix/smtpd[2974]: input attribute value: 0

Apr 22 14:15:08 dedi postfix/smtpd[2974]: public/cleanup socket: wanted 
attribute: reason

Apr 22 14:15:08 dedi postfix/smtpd[2974]: input attribute name: reason
Apr 22 14:15:08 dedi postfix/smtpd[2974]: input attribute value: (end)

Apr 22 14:15:08 dedi postfix/smtpd[2974]: public/cleanup socket: wanted 
attribute: (list terminator)

Apr 22 14:15:08 dedi postfix/smtpd[2974]: input attribute name: (end)

Apr 22 14:15:08 dedi postfix/smtpd[2974]: > 
dedi.ludosoft.org[127.0.0.1]: 250 2.0.0 Ok: queued as C0BBC261729

Apr 22 14:15:08 dedi postfix/smtpd[2974]: watchdog_pat: 0x8e10870

Apr 22 14:15:08 dedi postfix/smtpd[2970]: < 127.0.0.1:10025: 250 2.0.0 
Ok: queued as C0BBC261729
Apr 22 14:15:08 dedi postfix/smtpd[2970]: > 
dedi.ludosoft.org[127.0.0.1]: 250 2.0.0 Ok: queued as C0BBC261729
Apr 22 14:15:08 dedi postfix/smtpd[2970]: proxy-accept: END-OF-MESSAGE: 
250 2.0.0 Ok: queued as C0BBC261729; from=<[email protected]> 
to=<[email protected]> proto=ESMTP helo=<dedi.ludosoft.org>

Apr 22 14:15:08 dedi postfix/smtpd[2970]: watchdog_pat: 0x839f800

Apr 22 14:15:09 dedi postfix/smtp[2976]: C0BBC261729: 
to=<[email protected]>, relay=filenet.ludosoft.org[82.236.203.193]:25, 
delay=13, delays=12/0.08/0.25/0.78, dsn=2.0.0, status=sent (250 2.0.0 
Ok: queued as BAC10122195B)

Apr 22 14:15:09 dedi postfix/qmgr[2966]: C0BBC261729: removed

Apr 22 14:15:11 dedi postfix/smtpd[2970]: < 
dedi.ludosoft.org[127.0.0.1]: QUIT
Apr 22 14:15:11 dedi postfix/smtpd[2970]: > 
dedi.ludosoft.org[127.0.0.1]: 221 2.0.0 Bye
Apr 22 14:15:11 dedi postfix/smtpd[2970]: match_hostname: 
dedi.ludosoft.org ~? 127.0.0.0/8
Apr 22 14:15:11 dedi postfix/smtpd[2970]: match_hostaddr: 127.0.0.1 ~? 
127.0.0.0/8
Apr 22 14:15:11 dedi postfix/smtpd[2970]: disconnect from 
dedi.ludosoft.org[127.0.0.1]

Apr 22 14:20:08 dedi postfix/smtpd[2974]: smtp_get: timeout

Apr 22 14:20:08 dedi postfix/smtpd[2974]: > 
dedi.ludosoft.org[127.0.0.1]: 421 4.4.2 dedi.ludosoft.org Error: timeout 
exceeded
Apr 22 14:20:08 dedi postfix/smtpd[2974]: match_hostname: 
dedi.ludosoft.org ~? 127.0.0.0/8
Apr 22 14:20:08 dedi postfix/smtpd[2974]: match_hostaddr: 127.0.0.1 ~? 
127.0.0.0/8
Apr 22 14:20:08 dedi postfix/smtpd[2974]: timeout after END-OF-MESSAGE 
from dedi.ludosoft.org[127.0.0.1]
Apr 22 14:20:08 dedi postfix/smtpd[2974]: disconnect from 
dedi.ludosoft.org[127.0.0.1]




My master.cf :

#
# Postfix master process configuration file.  For details on the format
# of the file, see the Postfix master(5) manual page.
#
# ==========================================================================
# service type  private unpriv  chroot  wakeup  maxproc command + args
#               (yes)   (yes)   (yes)   (never) (100)
# ==========================================================================
#smtp      inet  n       -       n       -       -       smtpd
smtp      inet  n       -       n       -       20      smtpd
    -o smtpd_proxy_filter=127.0.0.1:10025
    -o smtpd_client_connection_count_limit=20
    -o smtpd_proxy_timeout=600s
    -o smtp_connection_cache_on_demand=no

...


I use DKIMproxy like filter.

You can see the investigation here :

https://sourceforge.net/tracker/?func=detail&atid=1001852&aid=3534386&group_id=207482


Thank's.

Ludovic.







Attachment:
smime.p7s

Description: Signature cryptographique S/MIME






















					Reply via email to