Mailing List CGatePro@mail.stalker.com Message #91599
From: virt <virt1122@gmail.com>
Subject: Re[2]: failed to receive message body (xxx bytes). Error Code=read time-out
Date: Fri, 6 Jul 2007 11:22:11 +0400
To: CommuniGate Pro Discussions <CGatePro@mail.stalker.com>
This trouble becomes to be really disturbing. As I can see from the log the message begins to be transmitted, then the transmitting stops and in 5 minutes the connection breaks out due to time-out. At the same time, while the transmitting stops, the remote host is normally pinged, I can log in via telnet on 25th port etc. On the server where CGP spins works also apache and jabber server and there are no problems with net connections with them. Unfortunately I have failed to determine which messages are received normally and which - not (there are different clients and different sizes of letters everywhere. What else can be checked out in such a situation?



> Your server waited 5 minutes for a message body and none was sent.

> HTH,
> Bret
>  

>> -----Original Message-----
>> From: CommuniGate Pro Discussions
>> [mailto:CGatePro@mail.stalker.com] On Behalf Of virt
>> Sent: Thursday, July 05, 2007 6:25 AM
>> To: CommuniGate Pro Discussions
>> Subject: failed to receive message body (xxx bytes). Error
>> Code=read time-out

>> Hi,
>> What is wrong?

>> 10:38:29.07 4 SMTPI-13666([81.22.63.4]) got connection on
>> [xxx.xxx.xxx.xxx:25]
>> 10:38:29.07 4 SMTPI-13666([81.22.63.4]) rsp: 220 domain.ru
>> ESMTP 4.3.12
>> 10:38:29.09 4 SMTPI-13666([81.22.63.4]) cmd: EHLO mail.metromail.ru
>> 10:38:29.09 4 SMTPI-13666([81.22.63.4]) rsp: 250-domain.ru we
>> trust you mail.metromail.ru\r\n250-DSN\r\n250-SIZE
>> 31457280\r\n250-STARTTLS\r\n250-AUTH=LOGIN\r\n250-AUTH LOGIN
>> PLAIN CRAM-MD5
>> DIGEST-MD5\r\n250-ETRN\r\n250-TURN\r\n250-ATRN\r\n250-NO-SOLIC
>> ITING\r\n250-8BITMIME\r\n250-HELP\r\n250-PIPEL
>> 10:38:29.12 4 SMTPI-13666([81.22.63.4]) cmd: STARTTLS
>> 10:38:29.12 4 SMTPI-13666([81.22.63.4]) rsp: 220 please start
>> a TLS connection
>> 10:38:29.14 4 SMTPI-13666([81.22.63.4]) SSLv2 client hello as
>> TLSv1: method=DES3_SHA, session=10579 < 00 00 29 53 46 89 EE
>> E5 25 DB A0 CC 1D 18 BA B1 65 48 F9 1B AD 9A 0E 2C C5 05 C5
>> 98 87 8C 10 28>
>> 10:38:29.14 4 SMTPI-13666([81.22.63.4]) TLS handshake:
>> sending 'server_hello'
>> 10:38:29.14 4 SMTPI-13666([81.22.63.4]) TLS handshake:
>> sending the certificate
>> 10:38:29.14 4 SMTPI-13666([81.22.63.4]) TLS handshake:
>> sending 'hello_done'
>> 10:38:29.47 4 SMTPI-13666([81.22.63.4]) TLS client key
>> exchange processed
>> 10:38:29.47 4 SMTPI-13666([81.22.63.4]) security initiated
>> 10:38:29.47 4 SMTPI-13666([81.22.63.4]) TLS 'change cipher' processed
>> 10:38:29.47 4 SMTPI-13666([81.22.63.4]) TLS 'change cipher' sending
>> 10:38:29.47 4 SMTPI-13666([81.22.63.4]) TLS 'finish
>> handshake' processed
>> 10:38:29.47 4 SMTPI-13666([81.22.63.4]) TLS handshake:
>> sending 'finished'
>> 10:38:29.47 4 SMTPI-13666([81.22.63.4]) secure connection
>> (DES3_SHA) accepted, session 10579
>> 10:38:29.52 4 SMTPI-13666([81.22.63.4]) cmd: EHLO mail.metromail.ru
>> 10:38:29.52 4 SMTPI-13666([81.22.63.4]) rsp: 250-domain.ru we
>> trust you mail.metromail.ru\r\n250-DSN\r\n250-SIZE
>> 31457280\r\n250-AUTH=LOGIN\r\n250-AUTH LOGIN PLAIN CRAM-MD5
>> DIGEST-MD5\r\n250-ETRN\r\n250-TURN\r\n250-ATRN\r\n250-NO-SOLIC
>> ITING\r\n250-8BITMIME\r\n250-HELP\r\n250-PIPELINING\r\n250 EHL
>> 10:38:29.81 4 SMTPI-13666([81.22.63.4]) cmd: MAIL
>> FROM:<samara@vashdom.ru> SIZE=456535 BODY=8BITMIME
>> 10:38:29.81 4 SMTPI-13666([81.22.63.4]) rsp: 250
>> samara@vashdom.ru sender accepted
>> 10:38:29.81 4 SMTPI-13666([81.22.63.4]) cmd: RCPT
>> TO:<oleg.b@sama.domain.ru> ORCPT=rfc822;oleg.b@sama.domain.ru
>> 10:38:29.81 4 SMTPI-13666([81.22.63.4]) rsp: 250
>> oleg.b@sama.domain.ru will leave the Internet
>> 10:38:29.81 4 SMTPI-13666([81.22.63.4]) cmd: DATA
>> 10:38:29.81 4 SMTPI-13666([81.22.63.4]) rsp: 354 Enter mail,
>> end with "." on a line by itself
>> 10:43:29.82 3 SMTPI-13666([81.22.63.4]) failed to receive
>> message body (391 bytes). Error Code=read time-out
>> 10:43:29.82 4 SMTPI-13666([81.22.63.4]) TLS connection is closing
>> 10:43:29.82 4 SMTPI-13666([81.22.63.4]) closing connection
>> 10:43:29.82 4 SMTPI-13666([81.22.63.4]) releasing stream





>> logs tcpdump:
>> 10:38:29.057827 IP (tos 0x0, ttl 55, id 58894, offset 0,
>> flags [DF], proto: TCP (6), length: 64)
>> smtp.metromail.ru.58075 > domain.ru.smtp: S, cksum 0x4542
>>  (correct), 1164102708:1164102708(0) win 65535 <mss
>> 1360,nop,wscale 2,nop,nop,timestamp 3905674247 0,sackOK,eol>
>> 10:38:29.078441 IP (tos 0x0, ttl 55, id 58898, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x49c2
>>  (correct), ack 144389333 win 33026 <nop,nop,timestamp
>> 3905674268 131888244>
>> 10:38:29.098680 IP (tos 0x0, ttl 55, id 58903, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x4982
>>  (correct), ack 32 win 33018 <nop,nop,timestamp 3905674289 131888264>
>> 10:38:29.098930 IP (tos 0x0, ttl 55, id 58904, offset 0,
>> flags [DF], proto: TCP (6), length: 76)
>> smtp.metromail.ru.58075 > domain.ru.smtp: P, cksum 0x2b61
>>  (correct), 0:24(24) ack 32 win 33026 <nop,nop,timestamp
>> 3905674289 131888264>
>> 10:38:29.121917 IP (tos 0x0, ttl 55, id 58907, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x487c
>>  (correct), ack 280 win 32964 <nop,nop,timestamp 3905674312 131888285>
>> 10:38:29.122042 IP (tos 0x0, ttl 55, id 58908, offset 0,
>> flags [DF], proto: TCP (6), length: 62)
>> smtp.metromail.ru.58075 > domain.ru.smtp: P, cksum 0x05d4
>>  (correct), 24:34(10) ack 280 win 33026 <nop,nop,timestamp
>> 3905674312 131888285>
>> 10:38:29.141408 IP (tos 0x0, ttl 55, id 58910, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x47ef
>>  (correct), ack 315 win 33017 <nop,nop,timestamp 3905674332 131888308>
>> 10:38:29.143155 IP (tos 0x0, ttl 55, id 58911, offset 0,
>> flags [DF], proto: TCP (6), length: 181)
>> smtp.metromail.ru.58075 > domain.ru.smtp: P 34:163(129)
>> ack 315 win 33026 <nop,nop,timestamp 3905674333 131888308>
>> 10:38:29.163519 IP (tos 0x0, ttl 55, id 58912, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x4700
>>  (correct), ack 394 win 33006 <nop,nop,timestamp 3905674353 131888329>
>> 10:38:29.164644 IP (tos 0x0, ttl 55, id 58913, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x42f8
>>  (correct), ack 1742 win 32689 <nop,nop,timestamp 3905674354
>> 131888329>
>> 10:38:29.185258 IP (tos 0x0, ttl 55, id 58917, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x40d5
>>  (correct), ack 1966 win 32970 <nop,nop,timestamp 3905674375
>> 131888350>
>> 10:38:29.466227 IP (tos 0x0, ttl 55, id 58962, offset 0,
>> flags [DF], proto: TCP (6), length: 242)
>> smtp.metromail.ru.58075 > domain.ru.smtp: P 163:353(190)
>>  ack 1966 win 33026 <nop,nop,timestamp 3905674656 131888350>
>> 10:38:29.497836 IP (tos 0x0, ttl 55, id 58969, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x3d6a
>>  (correct), ack 1972 win 33024 <nop,nop,timestamp 3905674687
>> 131888663>
>> 10:38:29.518824 IP (tos 0x0, ttl 55, id 58971, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x3d1c
>>  (correct), ack 2017 win 33014 <nop,nop,timestamp 3905674709
>> 131888684>
>> 10:38:29.527818 IP (tos 0x0, ttl 55, id 58972, offset 0,
>> flags [DF], proto: TCP (6), length: 105)
>> smtp.metromail.ru.58075 > domain.ru.smtp: P 353:406(53)
>> ack 2017 win 33026 <nop,nop,timestamp 3905674718 131888684>
>> 10:38:29.548932 IP (tos 0x0, ttl 55, id 58976, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x3bdc
>>  (correct), ack 2278 win 32960 <nop,nop,timestamp 3905674739
>> 131888714>
>> 10:38:29.817909 IP (tos 0x0, ttl 55, id 59021, offset 0,
>> flags [DF], proto: TCP (6), length: 217)
>> smtp.metromail.ru.58075 > domain.ru.smtp: P 406:571(165)
>>  ack 2278 win 33026 <nop,nop,timestamp 3905675008 131888714>
>> 10:38:29.838273 IP (tos 0x0, ttl 55, id 59026, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x387f
>>  (correct), ack 2347 win 33008 <nop,nop,timestamp 3905675028
>> 131889004>
>> 10:38:29.858636 IP (tos 0x0, ttl 55, id 59031, offset 0,
>> flags [DF], proto: TCP (6), length: 52)
>> smtp.metromail.ru.58075 > domain.ru.smtp: ., cksum 0x37cc
>>  (correct), ack 2509 win 32985 <nop,nop,timestamp 3905675048
>> 131889024>
>> 10:43:29.843578 IP (tos 0x0, ttl 55, id 55155, offset 0,
>> flags [DF], proto: TCP (6), length: 40)
>> smtp.metromail.ru.58075 > domain.ru.smtp: R, cksum 0x8056
>>  (correct), 1164103280:1164103280(0) win 0

Subscribe (FEED) Subscribe (DIGEST) Subscribe (INDEX) Unsubscribe Mail to Listmaster