mail_version = 2.5.5
mail_release_date = 20080902

Last week I worked for some hours to resolve a problem receiving mail from 
puremail.com.  I have a short term workaround now, but for the long term I 
want to understand the problem and obviate the need for the workaround.

The mail from puremail was sent from ip address 66.81.101.50, which reverses 
to 'mx.puremail.com'.  A forward lookup on 'mx.puremail.com' results in a 
truncated DNS result and TCP retry, returning 23 ip addresses.

From the remote end's view the 220 return message is delayed by minutes; that 
is how I isolated it to a DNS resolution problem.  Running postfix debug on 
that ip address results in the log entries at the end of this mail.  The only 
redaction in the log is to remove the specific matches for my local networks.

It appears from the log that the 220 tried to be sent, but the "smtp_get: EOF" 
line would seem to indicate that a connection was not open, or had been 
closed, or something like that; in any case concurrent packet sniffs confirm 
that the 220 never made it to the network.

I implemented a short term workaround by defining 66.81.101.50 as 
mx.puremail.com in my local /etc/hosts; that at least got mail coming 
through.

I found this post referencing the postfix changelog entry 20050622 that 
mentions that only the 'best' five MX records are retained from long name 
server replies.  I don't know if this factors into this problem or not.

http://archives.neohapsis.com/archives/postfix/2007-11/0032.html

I am not having any other problems with slow connects (at least none that have 
been reported.)  DNS is run from two other servers on the local network, and 
those have been working well for a long time.

If someone can give me an idea if this is a configuration problem on my side 
or something on the puremail side I would very much appreciate it, and TIA.

   -- Michael

===========================
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: connect from 
mx.puremail.com[66.81.101.50]
... removed various match_hostaddr for local network ...
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: match_list_match: mx.puremail.com: 
no match
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: match_list_match: 66.81.101.50: no 
match
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: send attr request = connect
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: send attr ident = smtp:66.81.101.50
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: vstream_fflush_some: fd 23 flush 41
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: vstream_buf_get_ready: fd 23 got 25
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: private/anvil: wanted attribute: 
status
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute name: status
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute value: 0
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: private/anvil: wanted attribute: 
count
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute name: count
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute value: 1
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: private/anvil: wanted attribute: 
rate
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute name: rate
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute value: 1
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: private/anvil: wanted attribute: 
(list terminator)
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute name: (end)
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: > mx.puremail.com[66.81.101.50]: 
220 srmail2.fni.com ESMTP UCE subject to blockage without notice
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: vstream_fflush_some: fd 11 flush 66
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: watchdog_pat: 0x57a980
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: smtp_get: EOF
... removed various match_hostaddr for local network ...
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: match_list_match: mx.puremail.com: 
no match
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: match_list_match: 66.81.101.50: no 
match
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: send attr request = disconnect
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: send attr ident = smtp:66.81.101.50
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: vstream_fflush_some: fd 23 flush 44
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: vstream_buf_get_ready: fd 23 got 10
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: private/anvil: wanted attribute: 
status
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute name: status
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute value: 0
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: private/anvil: wanted attribute: 
(list terminator)
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: input attribute name: (end)
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: lost connection after CONNECT from 
mx.puremail.com[66.81.101.50]
Dec  4 18:18:27 bilbo postfix/smtpd[3919]: disconnect from 
mx.puremail.com[66.81.101.50]
===========================

Attachment: signature.asc
Description: This is a digitally signed message part.

Reply via email to