Google Groups no longer supports new Usenet posts or subscriptions. Historical content remains viewable.
Dismiss

Negative SMTP response to data-dot and Blocked MTA-BLOCKED

965 views
Skip to first unread message

Varghese, Daniel

unread,
Jul 7, 2011, 10:42:20 AM7/7/11
to
Hi All,
 
We have postfix node in which we configured amavisdfeed (in main.cf) as content_filter=amavisfeed:[127.0.0.1]:10024  the amavisd version we use is amavisd-new-2.6.4 (20090625)
 
In Amavisd.conf the re-injection of mail to POSTFIX configured as
 
$notify_method  = 'smtp:[127.0.0.1]:10025';
$forward_method = 'smtp:[127.0.0.1]:10030'; 
 
Many of the mails get stuck in the deferred Q, in the maillog file I have following errors for such mails, can someone can help to understand why this error occurs intermittently?
 
=================================================== Maillog ========================================================================================
Jul  5 22:22:26 myHostMachine postfix/smtpd[26330]: connect from myclinet.mydomain.com[XX.XX.XX.XX]]
Jul  5 22:22:26 myHostMachine postfix/smtpd[26330]: lost connection after CONNECT from myclinet.mydomain.com[XX.XX.XX.XX]]
Jul  5 22:22:26 myHostMachine postfix/smtpd[26330]: disconnect from myclinet.mydomain.com[XX.XX.XX.XX]]
Jul  5 22:22:27 myHostMachine amavis[24264]: (24264-18) Negative SMTP response to data-dot (<mydest...@mydomain.com>):
Jul  5 22:22:27 myHostMachine amavis[24264]: (24264-18) (!)FWD via SMTP: <mycl...@mydomain.com> -> <mydest...@mydomain.com>,BODY=7BIT 451 4.5.0 No resp. to data-dot, id=24264-18, from MTA([127.0.0.1]:10030):
Jul  5 22:22:27 myHostMachine amavis[24264]: (24264-18) Blocked MTA-BLOCKED, [XX.XX.XX.XX] [XXX.XXX.XXX.XX] <mycl...@mydomain.com> -> <mydest...@mydomain.com>, Message-ID: <7FF49AA3B28B4D29...@mydomain.local>, mail_id: CdCVBbn1GM5a, Hits: -1.901, size: 464708, 6848 ms
Jul  5 22:22:27 myHostMachine postfix/lmtp[26326]: C469D10C082: to=<mydest...@mydomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=8.7, delays=1.8/0/0.01/6.9, dsn=4.5.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot, id=24264-18, from MTA([127.0.0.1]:10030):  (in reply to end of DATA command))
Jul  5 22:22:29 myHostMachine postfix/smtpd[26418]: connect from myclinet.mydomain.com[XX.XX.XX.XX]
Jul  5 22:22:29 myHostMachine postfix/smtpd[26418]: lost connection after CONNECT from myclinet.mydomain.com[XX.XX.XX.XX]
====================================================================================================================================================
Regards,
Daniel Varghese
HP, HSSL Bangalore
( +91 9448481973
+ daniel....@hp.com
The information in this email is confidential and may be legally privileged. It is intended exclusively for the use of the individual(s) to whom it is addressed.
If you are not the intended recipient, please notify the sender immediately and destroy this email. You are hereby advised that any unauthorized copying, disclosure, distribution, or action taken or omitted to be taken in reliance on the information in this email is strictly forbidden and may be unlawful.
 
 
 

Patrick Domack

unread,
Jul 7, 2011, 7:27:09 PM7/7/11
to
I would guess you only configured postfix to listen on 10025 and not
on 10030, or did you want something else to listen on 10030?


Quoting "Varghese, Daniel" <daniel....@hp.com>:

> * +91 9448481973
> * daniel....@hp.com

Mark Martinec

unread,
Jul 8, 2011, 6:22:16 AM7/8/11
to
Daniel,

>> Jul 5 22:22:26 myHostMachine postfix/smtpd[26330]: connect from
>> myclinet.mydomain.com[XX.XX.XX.XX]]
>> Jul 5 22:22:26 myHostMachine postfix/smtpd[26330]: lost connection
>> after CONNECT from myclinet.mydomain.com[XX.XX.XX.XX]]
>> Jul 5 22:22:26 myHostMachine postfix/smtpd[26330]: disconnect from
>> myclinet.mydomain.com[XX.XX.XX.XX]]
>> Jul 5 22:22:27 myHostMachine amavis[24264]: (24264-18) Negative SMTP
>> response to data-dot (<mydest...@mydomain.com>):
>> Jul 5 22:22:27 myHostMachine amavis[24264]: (24264-18) (!)FWD via
>> SMTP: <mycl...@mydomain.com> ->
>> <mydest...@mydomain.com>,BODY=7BIT 451 4.5.0 No resp. to data-dot,
>> id=24264-18, from MTA([127.0.0.1]:10030):
>> Jul 5 22:22:27 myHostMachine amavis[24264]: (24264-18) Blocked
>> MTA-BLOCKED, [XX.XX.XX.XX] [XXX.XXX.XXX.XX]

Perhaps you have a loop: forgot to disable content filtering
on port 10030 ?

Mark

Varghese, Daniel

unread,
Jul 8, 2011, 7:44:18 AM7/8/11
to
Yes, we have a custom program that listen to 10030 which consume the messages.


-----Original Message-----
From: amavis-users-bounces+daniel.varghese=hp....@amavis.org [mailto:amavis-users-bounces+daniel.varghese=hp....@amavis.org] On Behalf Of Patrick Domack
Sent: Friday, July 08, 2011 4:57 AM
To: amavis...@amavis.org
Subject: Re: Negative SMTP response to data-dot and Blocked MTA-BLOCKED

I would guess you only configured postfix to listen on 10025 and not
on 10030, or did you want something else to listen on 10030?


Quoting "Varghese, Daniel" <daniel....@hp.com>:

> Hi All,
>
> We have postfix node in which we configured amavisdfeed (in main.cf)
> as content_filter=amavisfeed:[127.0.0.1]:10024 the amavisd version
> we use is amavisd-new-2.6.4 (20090625)
>
> In Amavisd.conf the re-injection of mail to POSTFIX configured as
>
> $notify_method = 'smtp:[127.0.0.1]:10025';
> $forward_method = 'smtp:[127.0.0.1]:10030';
>
> Many of the mails get stuck in the deferred Q, in the maillog file I
> have following errors for such mails, can someone can help to
> understand why this error occurs intermittently?
>
> =================================================== Maillog
> ========================================================================================

> MTA-BLOCKED, [XX.XX.XX.XX] [XXX.XXX.XXX.XX] <mycl...@mydomain.com>
> -> <mydest...@mydomain.com>, Message-ID:
> <7FF49AA3B28B4D29...@mydomain.local>, mail_id:
> CdCVBbn1GM5a, Hits: -1.901, size: 464708, 6848 ms
> Jul 5 22:22:27 myHostMachine postfix/lmtp[26326]: C469D10C082:
> to=<mydest...@mydomain.com>, relay=127.0.0.1[127.0.0.1]:10024,
> delay=8.7, delays=1.8/0/0.01/6.9, dsn=4.5.0, status=deferred (host
> 127.0.0.1[127.0.0.1] said: 451 4.5.0 No resp. to data-dot,
> id=24264-18, from MTA([127.0.0.1]:10030): (in reply to end of DATA
> command))

> Jul 5 22:22:29 myHostMachine postfix/smtpd[26418]: connect from
> myclinet.mydomain.com[XX.XX.XX.XX]
> Jul 5 22:22:29 myHostMachine postfix/smtpd[26418]: lost connection

> after CONNECT from myclinet.mydomain.com[XX.XX.XX.XX]

Varghese, Daniel

unread,
Aug 1, 2011, 11:21:19 PM8/1/11
to
Hi Guys!

Please help me to understand what are the various reasons for this error.

In our infrastructure, only few mails get stuck in deferred Q and it remains in the Q even after 2/3 days.

Regards,
Daniel

Mark Martinec

unread,
Aug 3, 2011, 12:22:38 PM8/3/11
to
Daniel,

> Please help me to understand what are the various reasons for this error.
>
> In our infrastructure, only few mails get stuck in deferred Q and it
> remains in the Q even after 2/3 days.

> We have postfix node in which we configured amavisdfeed (in main.cf)


> as content_filter=amavisfeed:[127.0.0.1]:10024
> the amavisd version we use is amavisd-new-2.6.4 (20090625)

> $notify_method = 'smtp:[127.0.0.1]:10025';


> $forward_method = 'smtp:[127.0.0.1]:10030';
>

> Jul 5 22:22:27 myHostMachine amavis[24264]: (24264-18)
> Negative SMTP response to data-dot (<mydest...@mydomain.com>):
> Jul 5 22:22:27 myHostMachine amavis[24264]: (24264-18)
> (!)FWD via SMTP: <mycl...@mydomain.com> -> <mydest...@mydomain.com>,BODY=7BIT
> 451 4.5.0 No resp. to data-dot, id=24264-18, from MTA([127.0.0.1]:10030):

> Yes, we have a custom program that listen to 10030 which consume the messages.

Looks like you custom program on port 10030 is not responding
(or not responding in time) after being fed a message and a
terminating dot.

If you have such messages stuck in a queue (so this problem
is repeatable at will by a 'postfix flush'), then either enable
debugging in your custom program, or capture a TCP traffic
(tcpdump -i lo0 -s 0 -w 0.log 'tcp port 10030') and examine
what happened in an SMTP session with wireshark.

Mark

0 new messages