Topic: Rejected external mail

[Paperguides]

I have a customer with a server only SME setup and some external email is being rejected.

The sender gets:

To: adam@xxxx.co.uk
Subject: Mail delivery failed: returning message to sender

This message was created automatically by mail delivery software.

A message that you sent could not be delivered to one or more of its
recipients. This is a permanent error. The following address(es) failed:

  julia.smith@yyyy.org
    SMTP error from remote mail server after RCPT
TO:<julia.smith@ebmind.org>:
    host mail.yyyy.org [217.146.nnn.138]: 550 1216554304

Having scanned the log I discovered the following but I cannot understand what SME Server doesn't like.  Can anyone interpret for me?

2008-08-06 12:10:59.272744500 4719 250 <adam@xxxx.co.uk>, sender OK - how exciting to get mail from you!
2008-08-06 12:10:59.273115500 4719 dispatching RCPT TO:<julia.smith@yyyy.org>
2008-08-06 12:10:59.273645500 4719 to email address : [<julia.smith@yyyy.org>]
2008-08-06 12:10:59.274129500 4719 running plugin (rcpt): rhsbl
2008-08-06 12:10:59.274549500 4719 rhsbl plugin: waiting for rhsbl dns
2008-08-06 12:10:59.605675500 4719 rhsbl plugin: DONE waiting for rhsbl dns, got  1  answers ...
2008-08-06 12:10:59.606557500 4719 Plugin rhsbl, hook rcpt returned DECLINED,
2008-08-06 12:10:59.606866500 4719 running plugin (rcpt): dnsbl
2008-08-06 12:10:59.607232500 4719 trying to get config for dnsbl_zones
2008-08-06 12:10:59.607651500 4719 dnsbl plugin: waiting for dnsbl dns
2008-08-06 12:10:59.607977500 4719 dnsbl plugin: DONE waiting for dnsbl dns, got  3  answers ...
2008-08-06 12:10:59.609369500 4719 Plugin dnsbl, hook rcpt returned DECLINED,
2008-08-06 12:10:59.609681500 4719 running plugin (rcpt): check_badmailfrom
2008-08-06 12:10:59.610020500 4719 Plugin check_badmailfrom, hook rcpt returned DECLINED,
2008-08-06 12:10:59.610317500 4719 running plugin (rcpt): check_badrcptto_patterns
2008-08-06 12:10:59.610656500 4719 trying to get config for badrcptto_patterns
2008-08-06 12:10:59.611313500 4719 Plugin check_badrcptto_patterns, hook rcpt returned DECLINED,
2008-08-06 12:10:59.611623500 4719 running plugin (rcpt): check_badrcptto
2008-08-06 12:10:59.611966500 4719 trying to get config for badrcptto
2008-08-06 12:10:59.612782500 4719 Plugin check_badrcptto, hook rcpt returned DECLINED,
2008-08-06 12:10:59.613081500 4719 running plugin (rcpt): rcpt_ok
2008-08-06 12:10:59.613409500 4719 trying to get config for me
2008-08-06 12:10:59.613741500 4719 trying to get config for rcpthosts
2008-08-06 12:10:59.614321500 4719 Plugin rcpt_ok, hook rcpt returned OK,
2008-08-06 12:10:59.614700500 4719 250 <julia.smith@yyyy.org>, recipient ok
2008-08-06 12:10:59.615061500 4719 dispatching DATA
2008-08-06 12:10:59.615492500 4719 354 go ahead
2008-08-06 12:10:59.615810500 4719 trying to get config for databytes
2008-08-06 12:10:59.616111500 4719 max_size: 15000000 / size: 0
2008-08-06 12:10:59.616536500 4719 trying to get config for timeout
2008-08-06 12:10:59.657746500 4719 spooling message to disk
2008-08-06 12:10:59.720552500 4719 max_size: 15000000 / size: 16198
2008-08-06 12:10:59.721013500 4719 trying to get config for me
2008-08-06 12:10:59.721668500 4719 running plugin (data_post): check_basicheaders
2008-08-06 12:10:59.722171500 4719 Plugin check_basicheaders, hook data_post returned DECLINED,
2008-08-06 12:10:59.722486500 4719 running plugin (data_post): virus::pattern_filter
2008-08-06 12:10:59.722837500 4719 trying to get config for pattern_filter
2008-08-06 12:10:59.723240500 4719 trying to get config for signatures_patterns
2008-08-06 12:10:59.728945500 4719 Plugin virus::pattern_filter, hook data_post returned DECLINED,
2008-08-06 12:10:59.729246500 4719 running plugin (data_post): tnef2mime
2008-08-06 12:10:59.742280500 4719 Plugin tnef2mime, hook data_post returned DECLINED,
2008-08-06 12:10:59.742598500 4719 running plugin (data_post): spamassassin
2008-08-06 12:10:59.742941500 4719 spamassassin plugin: check_spam
2008-08-06 12:10:59.743986500 4719 spamassassin plugin: check_spam: connected to spamd
2008-08-06 12:10:59.757034500 4719 spamassassin plugin: check_spam: finished sending to spamd
2008-08-06 12:11:06.620953500 4719 spamassassin plugin: check_spam: spamd: SPAMD/1.1 0 EX_OK
2008-08-06 12:11:06.620960500
2008-08-06 12:11:06.620964500 4719 trying to get config for me
2008-08-06 12:11:06.620970500 4719 spamassassin plugin: check_spam: spamd: Content-length: 0
2008-08-06 12:11:06.620975500
2008-08-06 12:11:06.620980500 4719 spamassassin plugin: check_spam: spamd: Spam: False ; 0.0 / 4.0
2008-08-06 12:11:06.620986500
2008-08-06 12:11:06.620990500 4719 spamassassin plugin: check_spam: spamd:
2008-08-06 12:11:06.620996500
2008-08-06 12:11:06.621032500 4719 spamassassin plugin: check_spam: finished reading from spamd
2008-08-06 12:11:06.621037500 4719 spamassassin plugin: check_spam: No, hits=0.0, required=4.0, tests=
2008-08-06 12:11:06.621043500 4719 Plugin spamassassin, hook data_post returned DECLINED,
2008-08-06 12:11:06.627013500 4719 running plugin (data_post): spamassassin
2008-08-06 12:11:06.627019500 4719 spamassassin plugin: check_spam_reject: reject_threshold=12
2008-08-06 12:11:06.627025500 4719 spamassassin plugin: check_spam_reject: score=0.0
2008-08-06 12:11:06.627030500 4719 spamassassin plugin: check_spam_reject: passed
2008-08-06 12:11:06.627035500 4719 Plugin spamassassin, hook data_post returned DECLINED,
2008-08-06 12:11:06.627041500 4719 running plugin (data_post): spamassassin
2008-08-06 12:11:06.627046500 4719 Plugin spamassassin, hook data_post returned DECLINED,
2008-08-06 12:11:06.627070500 4719 running plugin (data_post): virus::clamav
2008-08-06 12:11:06.627075500 4719 virus::clamav plugin: Changing permissions on file to permit scanner access
2008-08-06 12:11:06.627081500 4719 virus::clamav plugin: Running: /usr/bin/clamdscan --stdout  --config-file=/etc/clamd.conf --disable-summary /var/spool/qpsmtpd/1218021059:4719:0 2>&1
2008-08-06 12:11:06.698936500 4719 virus::clamav plugin: clamscan results: /var/spool/qpsmtpd/1218021059:4719:0: OK
2008-08-06 12:11:06.698944500 4719 trying to get config for me
2008-08-06 12:11:06.708885500 4719 Plugin virus::clamav, hook data_post returned DECLINED,
2008-08-06 12:11:06.708892500 4719 running plugin (queue): logging::logterse
2008-08-06 12:11:06.708897500 4719 logging::logterse plugin: ` 212.53.95.208   customermail2.easily.co.uk   customermail2.easily.co.uk   <adam@xxxx.co.uk>   <julia.smith@yyyy.org>   queued      <000801c8f7b5$1863e400$492bac00$@co.uk>   No, hits=0.0 required=4.0_
2008-08-06 12:11:06.708905500 4719 Plugin logging::logterse, hook queue returned DECLINED,
2008-08-06 12:11:06.708910500 4719 running plugin (queue): queue::qmail_2dqueue
2008-08-06 12:11:06.708938500 4736 queue::qmail_2dqueue plugin: (for 4719 ) Queuing qp 4736 to /var/qmail/bin/qmail-queue
2008-08-06 12:11:06.950935500 4719 Plugin queue::qmail_2dqueue, hook queue returned OK, Queued! 1218021066 qp 4736 <000801c8f7b5$1863e400$492bac00$@co.uk>
2008-08-06 12:11:06.951354500 4719 250 Queued! 1218021066 qp 4736 <000801c8f7b5$1863e400$492bac00$@co.uk>
2008-08-06 12:11:06.973562500 4719 dispatching QUIT
2008-08-06 12:11:06.974016500 4719 trying to get config for me
2008-08-06 12:11:06.974319500 4719 221 yyyy.org closing connection. Have a wonderful day.
2008-08-06 12:11:06.974642500 4719 click, disconnecting


My customer is understandably more than a little concerned so any help would be most welcome.

Thanks

Tony Smith-Crallan

[mmccarn]

The denial message you quote shows a result of 550, but the exmple extract from /var/log/qpsmtpd/current shows a result of 250.  Are you sure you're showing us the log extract for the denied message?

You should be able to get all of the info you need from the lines in /var/log/qpsmtpd/current that mention "logterse".  The following command will show you all of the "logterse" log entries that do NOT contain the word "queued" - that is, the log entries for all denied messages.

Code: [Select]

grep logterse /var/log/qpsmtpd/* | grep -v queued | less