Currently running SME Server 7.5.1 as a dedicated email server. Right now I am having a problem with one outside contact who generates invoices with a scanner and then at a certain time of the day the invoice is emailed to our accounts payable dpt email address. We probably receive about 6 emails every week from this contact and only recently started getting this issue were the client is getting a bounce back. Below you will find the exact error produced within the qpsmptd log file. I have already white listed the ip address of the sender, the sender @addresss and I still cannot receive emails from this automated generated email for invoices. We can send emails back and forth its only with this automated email from MTARBOX@METALPLATE.COM going to accountspayable@ourdomain do we have an issue.
012-05-01 13:07:51.709977500 24832 Accepted connection 0/40 from 67.220.101.207 / vsmail.digitrends.com
2012-05-01 13:07:51.709978500 24832 Connection from vsmail.digitrends.com [67.220.101.207]
2012-05-01 13:07:51.709980500 24832 running plugin (set_hooks): peers
2012-05-01 13:07:51.714970500 24832 peers hooking valid_auth
2012-05-01 13:07:51.714972500 24832 peers hooking set_hooks
2012-05-01 13:07:51.714973500 24832 logging::logterse hooking queue
2012-05-01 13:07:51.714975500 24832 logging::logterse hooking deny
2012-05-01 13:07:51.714976500 24832 tls plugin (init): ciphers: ALL:!aNULL:!ADH:!eNULL:!LOW:!EXP:RC4+RSA:+HIGH:+MEDIUM
2012-05-01 13:07:51.714977500 24832 tls hooking connect
2012-05-01 13:07:51.714979500 24832 tls hooking ehlo
2012-05-01 13:07:51.714980500 24832 tls hooking helo
2012-05-01 13:07:51.714981500 24832 tls hooking rcpt
2012-05-01 13:07:51.714990500 24832 tls hooking mail
2012-05-01 13:07:51.714991500 24832 tls hooking data
2012-05-01 13:07:51.714992500 24832 tls hooking post-connection
2012-05-01 13:07:51.714994500 24832 tls hooking unrecognized_command
2012-05-01 13:07:51.714995500 24832 check_earlytalker hooking connect
2012-05-01 13:07:51.714996500 24832 check_earlytalker hooking data
2012-05-01 13:07:51.714997500 24832 whitelist_soft hooking connect
2012-05-01 13:07:51.714999500 24832 whitelist_soft hooking helo
2012-05-01 13:07:51.715002500 24832 whitelist_soft hooking ehlo
2012-05-01 13:07:51.715004500 24832 whitelist_soft hooking mail
2012-05-01 13:07:51.715005500 24832 whitelist_soft hooking rcpt
2012-05-01 13:07:51.715006500 24832 count_unrecognized_commands hooking connect
2012-05-01 13:07:51.715008500 24832 count_unrecognized_commands hooking unrecognized_command
2012-05-01 13:07:51.715009500 24832 check_relay hooking connect
2012-05-01 13:07:51.715010500 24832 check_norelay hooking connect
2012-05-01 13:07:51.715014500 24832 require_resolvable_fromhost hooking mail
2012-05-01 13:07:51.715015500 24832 check_basicheaders hooking data_post
2012-05-01 13:07:51.715017500 24832 check_badcountries hooking connect
2012-05-01 13:07:51.715018500 24832 rhsbl hooking rcpt
2012-05-01 13:07:51.715024500 24832 rhsbl hooking mail
2012-05-01 13:07:51.715025500 24832 rhsbl hooking disconnect
2012-05-01 13:07:51.715026500 24832 dnsbl hooking connect
2012-05-01 13:07:51.715027500 24832 dnsbl hooking rcpt
2012-05-01 13:07:51.715029500 24832 dnsbl hooking disconnect
2012-05-01 13:07:51.715030500 24832 check_badmailfrom hooking rcpt
2012-05-01 13:07:51.715031500 24832 check_badmailfrom hooking mail
2012-05-01 13:07:51.715032500 24832 check_badrcptto_patterns hooking rcpt
2012-05-01 13:07:51.715036500 24832 check_badrcptto hooking rcpt
2012-05-01 13:07:51.717101500 24832 check_spamhelo hooking ehlo
2012-05-01 13:07:51.717103500 24832 check_spamhelo hooking helo
2012-05-01 13:07:51.717104500 24832 check_goodrcptto hooking rcpt
2012-05-01 13:07:51.717106500 24832 rcpt_ok hooking rcpt
2012-05-01 13:07:51.717107500 24832 virus::pattern_filter hooking data_post
2012-05-01 13:07:51.717108500 24832 tnef2mime hooking data_post
2012-05-01 13:07:51.717109500 24832 spamassassin hooking data_post
2012-05-01 13:07:51.717111500 24832 spamassassin hooking data_post
2012-05-01 13:07:51.717112500 24832 spamassassin hooking data_post
2012-05-01 13:07:51.717120500 24832 virus::clamav hooking data_post
2012-05-01 13:07:51.717121500 24832 queue::qmail_2dqueue hooking queue
2012-05-01 13:07:51.717123500 24832 Plugin peers, hook set_hooks returned DECLINED,
2012-05-01 13:07:51.717124500 24832 running plugin (connect): tls
2012-05-01 13:07:51.717125500 24832 Plugin tls, hook connect returned DECLINED,
2012-05-01 13:07:51.717127500 24832 running plugin (connect): check_earlytalker
2012-05-01 13:07:52.719962500 24832 check_earlytalker plugin (connect): remote host said nothing spontaneous, proceeding
2012-05-01 13:07:52.719965500 24832 Plugin check_earlytalker, hook connect returned DECLINED,
2012-05-01 13:07:52.719966500 24832 running plugin (connect): whitelist_soft
2012-05-01 13:07:52.719968500 24832 trying to get config for whitelisthosts
2012-05-01 13:07:52.719969500 24832 Plugin whitelist_soft, hook connect returned DECLINED,
2012-05-01 13:07:52.719970500 24832 running plugin (connect): count_unrecognized_commands
2012-05-01 13:07:52.719978500 24832 Plugin count_unrecognized_commands, hook connect returned DECLINED,
2012-05-01 13:07:52.719979500 24832 running plugin (connect): check_relay
2012-05-01 13:07:52.719981500 24832 trying to get config for relayclients
2012-05-01 13:07:52.719982500 24832 trying to get config for morerelayclients
2012-05-01 13:07:52.719983500 24832 Plugin check_relay, hook connect returned DECLINED,
2012-05-01 13:07:52.719985500 24832 running plugin (connect): check_norelay
2012-05-01 13:07:52.719999500 24832 trying to get config for norelayclients
2012-05-01 13:07:52.720000500 24832 Plugin check_norelay, hook connect returned DECLINED,
2012-05-01 13:07:52.720001500 24832 running plugin (connect): check_badcountries
2012-05-01 13:07:52.720003500 24832 check_badcountries plugin (connect): GeoIP Country: US
2012-05-01 13:07:52.720004500 24832 trying to get config for badcountries
2012-05-01 13:07:52.720005500 24832 Plugin check_badcountries, hook connect returned DECLINED,
2012-05-01 13:07:52.720009500 24832 running plugin (connect): dnsbl
2012-05-01 13:07:52.720015500 24832 dnsbl plugin (connect): RBLSMTPD not set for 67.220.101.207
2012-05-01 13:07:52.720017500 24832 trying to get config for dnsbl_allow
2012-05-01 13:07:52.720018500 24832 trying to get config for dnsbl_zones
2012-05-01 13:07:52.720020500 24832 dnsbl plugin (connect): Checking 207.101.220.67.zen.spamhaus.org for TXT record in the background
2012-05-01 13:07:52.721334500 24832 dnsbl plugin (connect): Checking 207.101.220.67.dnsbl.njabl.org for TXT record in the background
2012-05-01 13:07:52.721336500 24832 dnsbl plugin (connect): Checking 207.101.220.67.whois.rfc-ignorant.org for TXT record in the background
2012-05-01 13:07:52.721338500 24832 Plugin dnsbl, hook connect returned DECLINED,
2012-05-01 13:07:52.721341500 24832 trying to get config for smtpgreeting
2012-05-01 13:07:52.721343500 24832 220 mail.ourdomain.com ESMTP
2012-05-01 13:07:52.721344500 24832 trying to get config for timeoutsmtpd
2012-05-01 13:07:52.755442500 24832 dispatching EHLO vsmail.digitrends.com
2012-05-01 13:07:52.755443500 24832 running plugin (ehlo): tls
2012-05-01 13:07:52.755447500 24832 Plugin tls, hook ehlo returned DECLINED,
2012-05-01 13:07:52.755448500 24832 running plugin (ehlo): whitelist_soft
2012-05-01 13:07:52.755449500 24832 trying to get config for whitelisthelo
2012-05-01 13:07:52.755451500 24832 Plugin whitelist_soft, hook ehlo returned DECLINED,
2012-05-01 13:07:52.755452500 24832 running plugin (ehlo): check_spamhelo
2012-05-01 13:07:52.755453500 24832 trying to get config for badhelo
2012-05-01 13:07:52.755460500 24832 Plugin check_spamhelo, hook ehlo returned DECLINED,
2012-05-01 13:07:52.755462500 24832 trying to get config for tls_before_auth
2012-05-01 13:07:52.755463500 24832 trying to get config for me
2012-05-01 13:07:52.755464500 24832 trying to get config for databytes
2012-05-01 13:07:52.755466500 24832 250-ourdomain Hi vsmail.digitrends.com [67.220.101.207]
2012-05-01 13:07:52.755467500 24832 250-PIPELINING
2012-05-01 13:07:52.755468500 24832 250-8BITMIME
2012-05-01 13:07:52.755472500 24832 250-SIZE 20000000
2012-05-01 13:07:52.755473500 24832 250 STARTTLS
2012-05-01 13:07:52.813952500 24832 dispatching MAIL FROM:<MTARBOX@METALPLATE.COM> SIZE=58693
2012-05-01 13:07:52.813954500 24832 full from_parameter: FROM:<MTARBOX@METALPLATE.COM> SIZE=58693
2012-05-01 13:07:52.813956500 24832 from email address : [<MTARBOX@METALPLATE.COM>]
2012-05-01 13:07:52.813957500 24832 running plugin (mail): tls
2012-05-01 13:07:52.813958500 24832 Plugin tls, hook mail returned DECLINED,
2012-05-01 13:07:52.813960500 24832 running plugin (mail): whitelist_soft
2012-05-01 13:07:52.813961500 24832 trying to get config for whitelistsenders
2012-05-01 13:07:52.813970500 24832 Plugin whitelist_soft, hook mail returned DECLINED,
2012-05-01 13:07:52.813971500 24832 running plugin (mail): require_resolvable_fromhost
2012-05-01 13:07:52.813972500 24832 trying to get config for invalid_resolvable_fromhost
2012-05-01 13:07:52.813974500 24832 trying to get config for require_resolvable_fromhost
2012-05-01 13:07:52.973740500 24832 Plugin require_resolvable_fromhost, hook mail returned DECLINED,
2012-05-01 13:07:52.973771500 24832 running plugin (mail): rhsbl
2012-05-01 13:07:52.973855500 24832 trying to get config for rhsbl_zones
2012-05-01 13:07:52.974005500 24832 rhsbl plugin (mail): Checking METALPLATE.COM.dsn.rfc-ignorant.org for TXT record in the background
2012-05-01 13:07:52.974618500 24832 Plugin rhsbl, hook mail returned DECLINED,
2012-05-01 13:07:52.974646500 24832 running plugin (mail): check_badmailfrom
2012-05-01 13:07:52.974685500 24832 trying to get config for badmailfrom
2012-05-01 13:07:52.975152500 24832 Plugin check_badmailfrom, hook mail returned DECLINED,
2012-05-01 13:07:52.975223500 24832 getting mail from <MTARBOX@METALPLATE.COM>
2012-05-01 13:07:52.975263500 24832 250 <MTARBOX@METALPLATE.COM>, sender OK - how exciting to get mail from you!
2012-05-01 13:07:53.010897500 24832 dispatching RCPT To:<ACCOUNTSPAYABLE@OURDOMAIN>
2012-05-01 13:07:53.010899500 24832 to email address : [<ACCOUNTSPAYABLE@OURDOMAIN>]
2012-05-01 13:07:53.010900500 24832 running plugin (rcpt): tls
2012-05-01 13:07:53.010902500 24832 Plugin tls, hook rcpt returned DECLINED,
2012-05-01 13:07:53.010903500 24832 running plugin (rcpt): whitelist_soft
2012-05-01 13:07:53.010904500 24832 trying to get config for whitelistrcpt
2012-05-01 13:07:53.010906500 24832 Plugin whitelist_soft, hook rcpt returned DECLINED,
2012-05-01 13:07:53.010915500 24832 running plugin (rcpt): rhsbl
2012-05-01 13:07:53.010916500 24832 rhsbl plugin (rcpt): waiting for rhsbl dns
2012-05-01 13:07:54.114931500 24832 rhsbl plugin (rcpt): DONE waiting for rhsbl dns, got 1 answers ...
2012-05-01 13:07:54.114933500 24832 Plugin rhsbl, hook rcpt returned DECLINED,
2012-05-01 13:07:54.114935500 24832 running plugin (rcpt): dnsbl
2012-05-01 13:07:54.114936500 24832 dnsbl plugin (rcpt): waiting for dnsbl dns
2012-05-01 13:07:54.114937500 24832 dnsbl plugin (rcpt): DONE waiting for dnsbl dns, got 3 answers ...
2012-05-01 13:07:54.114939500 24832 Plugin dnsbl, hook rcpt returned DECLINED,
2012-05-01 13:07:54.114940500 24832 running plugin (rcpt): check_badmailfrom
2012-05-01 13:07:54.114948500 24832 Plugin check_badmailfrom, hook rcpt returned DECLINED,
2012-05-01 13:07:54.114949500 24832 running plugin (rcpt): check_badrcptto_patterns
2012-05-01 13:07:54.114951500 24832 trying to get config for badrcptto_patterns
2012-05-01 13:07:54.114952500 24832 Plugin check_badrcptto_patterns, hook rcpt returned DECLINED,
2012-05-01 13:07:54.114954500 24832 running plugin (rcpt): check_badrcptto
2012-05-01 13:07:54.114955500 24832 trying to get config for badrcptto
2012-05-01 13:07:54.114959500 24832 Plugin check_badrcptto, hook rcpt returned DECLINED,
2012-05-01 13:07:54.114960500 24832 running plugin (rcpt): check_goodrcptto
2012-05-01 13:07:54.114961500 24832 check_goodrcptto plugin (rcpt): stripping '-' extensions
2012-05-01 13:07:54.114963500 24832 trying to get config for goodrcptto
2012-05-01 13:07:54.114964500 24832 check_goodrcptto plugin (rcpt): address includes extn '-', checking users: accountspayable
2012-05-01 13:07:54.114966500 24832 Plugin check_goodrcptto, hook rcpt returned DECLINED,
2012-05-01 13:07:54.114972500 24832 running plugin (rcpt): rcpt_ok
2012-05-01 13:07:54.114973500 24832 trying to get config for rcpthosts
2012-05-01 13:07:54.114974500 24832 Plugin rcpt_ok, hook rcpt returned OK,
2012-05-01 13:07:54.115035500 24832 250 <ACCOUNTSPAYABLE@OURDOMAIN>, recipient ok
2012-05-01 13:07:54.147749500 24832 dispatching DATA
2012-05-01 13:07:54.147751500 24832 running plugin (data): tls
2012-05-01 13:07:54.147752500 24832 Plugin tls, hook data returned DECLINED,
2012-05-01 13:07:54.147754500 24832 running plugin (data): check_earlytalker
2012-05-01 13:07:54.147755500 24832 Plugin check_earlytalker, hook data returned DECLINED,
2012-05-01 13:07:54.147757500 24832 354 go ahead
2012-05-01 13:07:54.147758500 24832 max_size: 20000000 / size: 0
2012-05-01 13:07:54.147759500 24832 trying to get config for timeout
2012-05-01 13:07:54.180451500 24832 spooling message to disk
2012-05-01 13:07:55.396912500 24832 max_size: 20000000 / size: 57920
2012-05-01 13:07:55.396913500 24832 running plugin (data_post): check_basicheaders
2012-05-01 13:07:55.396915500 24832 Plugin check_basicheaders, hook data_post returned DECLINED,
2012-05-01 13:07:55.396916500 24832 running plugin (data_post): virus::pattern_filter
2012-05-01 13:07:55.396918500 24832 trying to get config for pattern_filter
2012-05-01 13:07:55.396919500 24832 trying to get config for signatures_patterns
2012-05-01 13:07:55.396921500 24832 Plugin virus::pattern_filter, hook data_post returned DECLINED,
2012-05-01 13:07:55.396938500 24832 running plugin (data_post): tnef2mime
2012-05-01 13:07:55.406911500 24832 Plugin tnef2mime, hook data_post returned DECLINED,
2012-05-01 13:07:55.406913500 24832 running plugin (data_post): spamassassin
2012-05-01 13:07:55.406915500 24832 spamassassin plugin (data_post): check_spam
2012-05-01 13:07:55.406916500 24832 spamassassin plugin (data_post): check_spam: connected to spamd
2012-05-01 13:07:55.418910500 24832 spamassassin plugin (data_post): check_spam: finished sending to spamd
2012-05-01 13:08:00.634592500 24832 spamassassin plugin (data_post): check_spam: spamd: SPAMD/1.1 0 EX_OK
2012-05-01 13:08:00.634746500 24832 spamassassin plugin (data_post): check_spam: spamd: Content-length: 166
2012-05-01 13:08:00.634748500 24832 spamassassin plugin (data_post): check_spam: spamd: Spam: True ; 6.0 / 5.0
2012-05-01 13:08:00.634749500 24832 spamassassin plugin (data_post): check_spam: spamd:
2012-05-01 13:08:00.637106500 24832 spamassassin plugin (data_post): check_spam: finished reading from spamd
2012-05-01 13:08:00.637108500 24832 spamassassin plugin (data_post): check_spam: Yes, hits=6.0, required=5.0, tests=DATE_IN_PAST_03_06,HTML_IMAGE_ONLY_04,HTML_MESSAGE,HTML_MIME_NO_HTML_TAG,MIME_HTML_ONLY,RCVD_IN_DNSWL_NONE,SPF_HELO_PASS,SUBJ_ALL_CAPS,T_REMOTE_IMAGE,UPPERCASE_75_100
2012-05-01 13:08:00.637111500 24832 Plugin spamassassin, hook data_post returned DECLINED,
2012-05-01 13:08:00.637112500 24832 running plugin (data_post): spamassassin
2012-05-01 13:08:00.637119500 24832 spamassassin plugin (data_post): check_spam_reject: reject_threshold=6
2012-05-01 13:08:00.637120500 24832 spamassassin plugin (data_post): check_spam_reject: score=6.0
2012-05-01 13:08:00.637122500 24832 Plugin spamassassin, hook data_post returned DENY, spam score exceeded threshold (#5.6.1)
2012-05-01 13:08:00.637124500 24832 logging::logterse plugin (deny): ` 67.220.101.207 vsmail.digitrends.com vsmail.digitrends.com <MTARBOX@METALPLATE.COM> <ACCOUNTSPAYABLE@OURDOMAIN> spamassassin 901 spam score exceeded threshold (#5.6.1) Yes, hits=6.0 required=5.0_
2012-05-01 13:08:00.637129500 24832 552 spam score exceeded threshold (#5.6.1)
2012-05-01 13:08:00.672830500 24832 dispatching QUIT
2012-05-01 13:08:00.672832500 24832 221 ourdomain closing connection. Have a wonderful day.
2012-05-01 13:08:00.672834500 24832 click, disconnecting
2012-05-01 13:08:00.672835500 24832 running plugin (disconnect): rhsbl
2012-05-01 13:08:00.672837500 24832 Plugin rhsbl, hook disconnect returned DECLINED,
2012-05-01 13:08:00.672838500 24832 running plugin (disconnect): dnsbl
2012-05-01 13:08:00.672839500 24832 Plugin dnsbl, hook disconnect returned DECLINED,
2012-05-01 13:08:00.672841500 24832 running plugin (post-connection): tls
2012-05-01 13:08:00.672858500 24832 Plugin tls, hook post-connection returned DECLINED,