With regard to
http://www.mail-archive.com/postfix-users@postfix.org/msg44135.html
I am on the Exim end of this problem, or at least some of the servers I
use are, and I'm having trouble convincing the admins for these Exim
servers that it is a problem they need to fix.
In an attempt to do this convincing, I spun up an Ubuntu 12.04 instance
on Amazon EC2, and installed postfix and mailutils to it, set the
debugging config for the serverm and did a test. But, I'm having
trouble seeing what exactly postfix thinks the problem is, perhaps
somebody more familiar with Postfix (or SMTP in general) can shed some
light on this for me, here's the debug with a few redactions to protect
the guilty...
Jun 8 07:10:03 ip-10-160-125-11 postfix/pickup[4728]: A821B22FF7:
uid=1000 from=<ubuntu>
Jun 8 07:10:03 ip-10-160-125-11 postfix/cleanup[4733]: A821B22FF7:
message-id=<20120608071003.a821b22...@ip-10-160-125-11.us-west-1.compute.internal>
Jun 8 07:10:03 ip-10-160-125-11 postfix/qmgr[4729]: A821B22FF7:
from=<ubu...@ec2-50-18-91-139.us-west-1.compute.amazonaws.com>,
size=436, nrcpt=1 (queue active)
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]:
smtp_stream_setup: maxtime=300 enable_deadline=0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 220-xxxxxxx.elinuxservers.com ESMTP Exim
4.77 #2 Fri, 08 Jun 2012 00:10:03 -0700
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 220-We do not authorize the use of this
system to transport unsolicited,
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 220 and/or bulk e-mail.
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: >
example.com[72.34.48.xxx]:25: EHLO
ip-10-160-125-11.us-west-1.compute.internal
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250-xxxxxxx.elinuxservers.com Hello
ip-10-160-125-11.us-west-1.compute.internal [50.18.91.139]
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250-SIZE 52428800
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250-PIPELINING
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250-AUTH PLAIN LOGIN
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250-STARTTLS
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250 HELP
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: server features:
0x101d size 52428800
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: Using ESMTP
PIPELINING, TCP send buffer size is 24040, PIPELINING buffer size is 4096
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]:
smtp_stream_setup: maxtime=300 enable_deadline=0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: >
example.com[72.34.48.xxx]:25: MAIL
FROM:<ubu...@ec2-50-18-91-139.us-west-1.compute.amazonaws.com>
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: >
example.com[72.34.48.xxx]:25: RCPT TO:<ja...@example.com>
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: >
example.com[72.34.48.xxx]:25: DATA
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]:
smtp_stream_setup: maxtime=300 enable_deadline=0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250 OK
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]:
smtp_stream_setup: maxtime=300 enable_deadline=0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250 OK
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]:
smtp_stream_setup: maxtime=120 enable_deadline=0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250 OK
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: connect to
subsystem private/bounce
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
nrequest = 0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr flags = 0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
queue_id = A821B22FF7
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
original_recipient = ja...@example.com
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
recipient = ja...@example.com
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr offset
= 670
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
dsn_orig_rcpt =
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
notify_flags = 0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr status
= 5.5.0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
diag_type = smtp
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
diag_text = 250 OK
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
mta_type = dns
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr
mta_mname = example.com
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr action
= failed
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: send attr reason
= Protocol error: host example.com[72.34.48.xxx] said: 250 OK (in reply
to DATA command)
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: private/bounce
socket: wanted attribute: status
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: input attribute
name: status
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: input attribute
value: 0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: private/bounce
socket: wanted attribute: (list terminator)
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: input attribute
name: (end)
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: A821B22FF7:
to=<ja...@example.com>, relay=example.com[72.34.48.xxx]:25, delay=0.11,
delays=0.04/0.01/0.04/0.03, dsn=5.5.0, status=bounced (Protocol error:
host example.com[72.34.48.xxx] said: 250 OK (in reply to DATA command))
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: >
example.com[72.34.48.xxx]:25: RSET
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: >
example.com[72.34.48.xxx]:25: QUIT
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]:
smtp_stream_setup: maxtime=20 enable_deadline=0
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: <
example.com[72.34.48.xxx]:25: 250 OK
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: name_mask: resource
Jun 8 07:10:03 ip-10-160-125-11 postfix/smtp[4735]: name_mask: software
Jun 8 07:10:03 ip-10-160-125-11 postfix/cleanup[4733]: C426F22FF8:
message-id=<20120608071003.c426f22...@ip-10-160-125-11.us-west-1.compute.internal>
Jun 8 07:10:03 ip-10-160-125-11 postfix/qmgr[4729]: C426F22FF8:
from=<>, size=2613, nrcpt=1 (queue active)
Jun 8 07:10:03 ip-10-160-125-11 postfix/bounce[4736]: A821B22FF7:
sender non-delivery notification: C426F22FF8
Jun 8 07:10:03 ip-10-160-125-11 postfix/qmgr[4729]: A821B22FF7: removed
Jun 8 07:10:03 ip-10-160-125-11 postfix/local[4738]: C426F22FF8:
to=<ubu...@ec2-50-18-91-139.us-west-1.compute.amazonaws.com>,
relay=local, delay=0.03, delays=0.01/0.01/0/0.01, dsn=2.0.0, status=sent
(delivered to mailbox)
Jun 8 07:10:03 ip-10-160-125-11 postfix/qmgr[4729]: C426F22FF8: removed
