[horde] only RSET accepted now
Twilek
twilek at gmx.de
Wed Dec 21 08:02:11 UTC 2016
> 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...
Regards
Henning
More information about the horde
mailing list