Link to home
Start Free TrialLog in
Avatar of bassaddkt
bassaddkt

asked on

Error Reading from Socket

Can someone shed some light on why I am getting 'Error reading from socket!'


Mon 2007-11-26 19:19:20: ----------
Mon 2007-11-26 19:19:46: Session 5043; child 1; thread 908
Mon 2007-11-26 19:19:25: Accepting SMTP connection from [17.148.16.81 : 60661]
Mon 2007-11-26 19:19:25: Performing PTR lookup (81.16.148.17.IN-ADDR.ARPA)
Mon 2007-11-26 19:19:25: *  D=81.16.148.17.IN-ADDR.ARPA TTL=(568) PTR=[smtpoutm.mac.com]
Mon 2007-11-26 19:19:25: *  Gathering A records...
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.68]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.69]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.70]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.71]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.72]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.73]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.74]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.75]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.76]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.77]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.78]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.79]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.80]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.81]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.82]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.83]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.64]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.65]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.66]
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.67]
Mon 2007-11-26 19:19:25: ---- End PTR results
Mon 2007-11-26 19:19:25: --> 220 yann-server.com ESMTP MDaemon 8.0.1; Mon, 26 Nov 2007 19:19:25 -0800
Mon 2007-11-26 19:19:25: <-- EHLO smtpoutm.mac.com
Mon 2007-11-26 19:19:25: Performing IP lookup (smtpoutm.mac.com)
Mon 2007-11-26 19:19:25: *  D=smtpoutm.mac.com TTL=(60) A=[17.148.16.67]
Mon 2007-11-26 19:19:25: ---- End IP lookup results
Mon 2007-11-26 19:19:25: --> 250-yann-server.com Hello smtpoutm.mac.com (may be forged), pleased to meet you
Mon 2007-11-26 19:19:25: --> 250-ETRN
Mon 2007-11-26 19:19:25: --> 250-AUTH=LOGIN
Mon 2007-11-26 19:19:25: --> 250-AUTH LOGIN CRAM-MD5
Mon 2007-11-26 19:19:25: --> 250-8BITMIME
Mon 2007-11-26 19:19:25: --> 250 SIZE 0
Mon 2007-11-26 19:19:26: <-- MAIL From:<crispinspaeth@mac.com> SIZE=3901
Mon 2007-11-26 19:19:26: Performing IP lookup (mac.com)
Mon 2007-11-26 19:19:26: *  D=mac.com TTL=(118) A=[17.250.248.32]
Mon 2007-11-26 19:19:26: *  P=010 D=mac.com TTL=(68) MX=[smtp-mx3.mac.com]
Mon 2007-11-26 19:19:26: *  P=010 D=mac.com TTL=(68) MX=[smtp-mx2.mac.com]
Mon 2007-11-26 19:19:26: *  P=010 D=mac.com TTL=(68) MX=[smtp-mx1.mac.com]
Mon 2007-11-26 19:19:26: *  D=mac.com TTL=(118) A=[17.250.248.32]
Mon 2007-11-26 19:19:26: *  D=mac.com TTL=(118) A=[17.250.248.32]
Mon 2007-11-26 19:19:26: *  D=mac.com TTL=(118) A=[17.250.248.32]
Mon 2007-11-26 19:19:26: ---- End IP lookup results
Mon 2007-11-26 19:19:26: Performing DNS-BL lookup (17.148.16.81 - connecting IP)
Mon 2007-11-26 19:19:26: *  sbl-xbl.spamhaus.org - passed
Mon 2007-11-26 19:19:26: *  opm.blitzed.org - passed
Mon 2007-11-26 19:19:46: *  relays.ordb.org - timed out (10 second wait)
Mon 2007-11-26 19:19:46: *  bl.spamcop.net - passed
Mon 2007-11-26 19:19:46: ---- End DNS-BL results
Mon 2007-11-26 19:19:46: --> 250 <crispinspaeth@mac.com>, Sender ok
Mon 2007-11-26 19:19:46: Error reading from socket!
Mon 2007-11-26 19:19:46: Unexpected socket closure
Mon 2007-11-26 19:19:46: SMTP session terminated (Bytes in/out: 68/272)
Mon 2007-11-26 19:19:46: ----------

Open in new window

Avatar of FilipZahradnik
FilipZahradnik
Flag of Australia image
This is what happens when the other end stops responding for some reason (maybe timeout). It appears that the sender mailserver is not happy to wait for 20 seconds for your mailserver to send Recipient ok response.

Here's what happens:

Step 1:  Sending mailserver sends MAIL FROM command
Mon 2007-11-26 19:19:26: <-- MAIL From:<crispinspaeth@mac.com> SIZE=3901

Step 2: Your servers does DNSBL lookups. It takes 20 seconds.

Step 3: Your mailserver sends  Sender ok response
Mon 2007-11-26 19:19:46: --> 250 <crispinspaeth@mac.com>, Sender ok

Step 4: Your mailserver discovers that the connection has been terminated.
Mon 2007-11-26 19:19:46: Error reading from socket!

Basically, some time during the 20 secods it took your server to do the DNSBL lookup, the other site closed the connection. RFC2821 says that the connection should stay open fot 10 minutes, but some mail servers are not RFC-compliant.

Questions&Suggestions:
- Does the error affect all incoming emails or only some? Is it linked only to certain mailservers?
- Try temporarily disabling DNSBL lookups to see if the error goes away.
- Try whitelisting domains/IP that cause this problem from DNSBL




ASKER CERTIFIED SOLUTION
Avatar of FilipZahradnik
FilipZahradnik
Flag of Australia image
Link to home
membership
This solution is only available to members.
To access this solution, you must be a member of Experts Exchange.
Start Free Trial