[horde] only RSET accepted now

Michael J Rubinsky mrubinsk at horde.org
Wed Dec 21 16:27:43 UTC 2016 

Quoting Twilek <twilek at gmx.de>:

>> Sounds like an inquiry with the exim folks would be your best bet.
>
> Hi Mike,
>
> I just did that and asked on their mailing list (they are really  
> nice guys). They showed me how to do an extended log file and after  
> analysing that, saw that somehow an error happens counting bytes.  
> They finally where not absolutely sure whether this was a horde or  
> an exim problem and said that I might show you their results as well.
> The whole discussion can be read via  
> https://lists.exim.org/lurker/thread/20161220.233107.7b48433a.en.html
>
> Jeremy Harris said:
>
> That is a error for smtp-command-sequence problems,
> involving BDAT (the CHUNKING variant of DATA).
>
> Something is out-of-sync and the state-machine
> needs an SMTP RSET command to get synch'd up again.
>
>> 17155 SMTP>> 250 1048576 byte chunk received
>> 17155 SMTP<<
>> 17155 LOG: smtp_protocol_error MAIN
>> 17155 SMTP protocol error in "" H=localhost (mydomain.de) [127.0.0.1]
>> only BDAT permissible after non-LAST BDAT
>
> They sent us an empty command; not a legal SMTP thing at all.
>
>
> Heiko Schlittmann said:
>
>> 17155 Data file name: /var/spool/exim4//input//1cJQWY-0004Sh-L1-D
>> 17155 SMTP>> 250 1048576 byte chunk received
>
> Exim responds after 1048576 bytes - the end of the current chung.
>
>> 17155 SMTP<<
>> 17155 LOG: smtp_protocol_error MAIN
>
> But they (Horde) don't stop sending.
>
>> 17155   SMTP protocol error in "" H=localhost (mydomain.de) [127.0.0.1]
>> only BDAT permissible after non-LAST BDAT
>> 17155 SMTP>> 503 only BDAT permissible after non-LAST BDAT
>> 17155 SMTP<< BDAT 1048576
>
> But the next BDAT seems to announce come as the very next item, I'd say,
> there is just a "off by one" error, on Exim's or Horde's side.
>
>> 17155 LOG: smtp_protocol_error MAIN
>> 17155   SMTP protocol error in "BDAT 1048576" H=localhost (mydomain.de)
>> [127.0.0.1] only RSET accepted now
>
>> 17155 SMTP>> 503 only RSET accepted now
>> 17155 SMTP<< 3dUFxtO0/wzyT2yLntnW6mtiIUQj5Vr8SfEHjv4d+D/hX0LzB0
>
> And additionally they do not seem to be interested in the responses to
> the data they send.
>
>
> Those are just excerpts.
>
> The original log I sent was:
>
> 17155 Process 17155 is handling incoming connection from [127.0.0.1]
> 17155 SMTP>> 220 www.mydomain.de ESMTP Exim 4.88 Tue, 20 Dec 2016  
> 20:56:34 +0100
> 17155 Process 17155 is ready for new message
> 17155 smtp_setup_msg entered
> 17155 SMTP<< EHLO mydomain.de
> 17155 SMTP>> 250-www.mydomain.de Hello localhost [127.0.0.1]
> 17155 250-SIZE 52428800
> 17155 250-8BITMIME
> 17155 250-PIPELINING
> 17155 250-CHUNKING
> 17155 250-STARTTLS
> 17155 250 HELP
> 17155 SMTP<< STARTTLS
> 17155 SMTP>> 220 TLS go ahead
> 17155 SMTP<< EHLO mydomain.de
> 17155 SMTP>> 250-www.mydomain.de Hello localhost [127.0.0.1]
> 17155 250-SIZE 52428800
> 17155 250-8BITMIME
> 17155 250-PIPELINING
> 17155 250-AUTH PLAIN
> 17155 250-CHUNKING
> 17155 250 HELP
> 17155 SMTP<< AUTH PLAIN xxx
> 17155 SMTP>> 235 Authentication succeeded
> 17155 SMTP<< MAIL FROM:<Myname at mydomain.de> SIZE=7324213
> 17155 spool directory space = 203300768K inodes = 28578772  
> check_space = 10240K inodes = 100 msg_size = 7329213
> 17155 log directory space = 203300768K inodes = 28578772 check_space  
> = 10240K inodes = 100
> 17155 SMTP>> 250 OK
> 17155 SMTP<< RCPT TO:<name at targetdomain.de>
> 17155 SMTP>> 250 Accepted
> 17155 DSN: orcpt: NULL  flags: 0
> 17155 SMTP<< BDAT 1048576
> 17155 chunking state 1
> 17155 >>Headers received:
> 17155 Received: from ip-x-x-x-x.x.x.de
> 17155  (ip-x-x-x-x.x.x.de [x.x.x.x]) by
> 17155  www.mydomain.de (Horde Framework) with HTTPS; Tue, 20 Dec  
> 2016 19:56:34 +0000
> 17155 Date: Tue, 20 Dec 2016 19:56:33 +0000
> 17155 Message-ID:  
> <20161220195633.Horde.ax0ygXDTPkyHHWCkG9KemlG at www.mydomain.de>
> 17155 From: Myname mydomain <Myname at mydomain.de>
> 17155 To: name at targetdomain.de
> 17155 Subject: Test
> 17155 User-Agent: Horde Application Framework 5
> 17155 Accept-Language: de,en,fr
> 17155 Content-Type: multipart/mixed; boundary="=_Y-k5koZvyf0yykhXd5xQJA0"
> 17155 MIME-Version: 1.0
> 17155
> 17155 rewritten sender = Myname at mydomain.de
> 17155 >>Headers after rewriting and local additions:
> 17155 P Received: from ip-x-x-x-x.x.x.x
> 17155  (ip-x-x-x-x.x.x.x [x.x.x.x]) by
> 17155  www.mydomain.de (Horde Framework) with HTTPS; Tue, 20 Dec  
> 2016 19:56:34 +0000
> 17155   Date: Tue, 20 Dec 2016 19:56:33 +0000
> 17155 I Message-ID:  
> <20161220195633.Horde.ax0ygXDTPkyHHWCkG9KemlG at www.mydomain.de>
> 17155 F From: Myname mydomain <Myname at mydomain.de>
> 17155 T To: name at targetdomain.de
> 17155   Subject: Test
> 17155   User-Agent: Horde Application Framework 5
> 17155   Accept-Language: de,en,fr
> 17155   Content-Type: multipart/mixed; boundary="=_Y-k5koZvyf0yykhXd5xQJA0"
> 17155   MIME-Version: 1.0
> 17155
> 17155 Data file name: /var/spool/exim4//input//1cJQWY-0004Sh-L1-D
> 17155 SMTP>> 250 1048576 byte chunk received
> 17155 SMTP<<
> 17155 LOG: smtp_protocol_error MAIN
> 17155   SMTP protocol error in "" H=localhost (mydomain.de)  
> [127.0.0.1] only BDAT permissible after non-LAST BDAT
> 17155 SMTP>> 503 only BDAT permissible after non-LAST BDAT
> 17155 SMTP<< BDAT 1048576
> 17155 LOG: smtp_protocol_error MAIN
> 17155   SMTP protocol error in "BDAT 1048576" H=localhost  
> (mydomain.de) [127.0.0.1] only RSET accepted now
> 17155 SMTP>> 503 only RSET accepted now
> 17155 SMTP<< 3dUFxtO0/wzyT2yLntnW6mtiIUQj5Vr8SfEHjv4d+D/hX0LzB0
> 17155 LOG: smtp_protocol_error MAIN
> 17155   SMTP protocol error in  
> "3dUFxtO0/wzyT2yLntnW6mtiIUQj5Vr8SfEHjv4d+D/hX0LzB0" H=localhost  
> (mydomain.de) [127.0.0.1] only RSET accepted now
> 17155 SMTP>> 503 only RSET accepted now
> 17155 SMTP<<  
> uCYK9NTSDzK6d5WsWuwBRN+ASBi9/b9P+naFGnpPxFVN3OXhK7flj8Hqq1FpUOLtriPot+GT4FUv
> 17155 LOG: smtp_protocol_error MAIN
> 17155   SMTP protocol error in  
> "uCYK9NTSDzK6d5WsWuwBRN+ASBi9/b9P+naFGnpPxFVN3OXhK7flj8Hqq1FpUOLtriPot+GT4FUv" H=localhost (mydomain.de) [127.0.0.1] only RSET accepted  
> now
> 17155 LOG: MAIN REJECT
> 17155   SMTP call from localhost (mydomain.de) [127.0.0.1] dropped:  
> too many syntax or protocol errors (last command was  
> "uCYK9NTSDzK6d5WsWuwBRN+ASBi9/b9P+naFGnpPxFVN3OXhK7flj8Hqq1FpUOLtriPot+GT4FUv")
> 17155 SMTP>> 503-only RSET accepted now
> 17155 SMTP>> 503 Too many syntax or protocol errors
> 17155 LOG: MAIN
> 17155   SMTP connection from localhost (mydomain.de) [127.0.0.1]  
> lost while reading message data
> 17155 SMTP>> 421 Lost incoming connection
> 17121 child 17155 ended: status=0x0
> 17121   normal exit, 0
> 17121 0 SMTP accept processes now running
> 17121 Listening...
>
> Maybe you can see what´s going wrong...

Exactly what I needed. Fixed in Horde_Smtp package. Release being  
uploaded as we speak.


> Regards
>
> Henning
>
> -- 
> Horde mailing list
> Frequently Asked Questions: http://horde.org/faq/
> To unsubscribe, mail: horde-unsubscribe at lists.horde.org



-- 
mike
The Horde Project
http://www.horde.org
https://www.facebook.com/hordeproject
https://www.twitter.com/hordeproject
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 2007 bytes
Desc: S/MIME Signature
URL: <https://lists.horde.org/archives/horde/attachments/20161221/5bef9db5/attachment.bin>

More information about the horde mailing list