Problem: Unusual SMTP behavior

["Peter M. Goldstein" <pe...@yahoo.com>]

Hi all,

I'm observing some unusual SMTP behavior with James and I was wondering
if anyone else had seen something similar or had any thoughts as to the
cause.

I have the following setup:

aristotle:

A Win2k box on which I'm running James 2.1a1 with a Java 1.4.0.1 Java
installation.  The James installation is essentially out of the box,
with configuration modifications to handle mail for a particular domain
name, accept mail from clients on machines other than localhost, and
listing of valid DNS servers.  I have also made some code modifications
to improve logging of SMTP responses from the server.  These code
changes are logging only and shouldn't affect the behavior.

This same box has a Microsoft Outlook XP client.

leibniz:

A Windows XP box with a Microsoft Outlook XP client.

On aristotle everything seems to work fine.  I can send and receive
mail, and the "Test Account Settings" button on the email account
configuration works fine.  When sending mail through James and when
using the "Test Account Settings" I get a log segment that looks like
this (Domains, IPs, and Email Addresses have been changed to protect the
innocent):

INFO    2002-07-09 10:31:14.993 [smtpserv] (): Hello Name is: aristotle
DEBUG   2002-07-09 10:31:15.013 [smtpserv] (): Max message size is: 0
INFO    2002-07-09 10:31:15.023 [smtpserv] (): Connection from
somemachine.com (1.2.3.4)
INFO    2002-07-09 10:31:15.033 [smtpserv] (): Command received: HELO
aristotle
DEBUG   2002-07-09 10:31:15.033 [smtpserv] (): Sent: 250 aristotle Hello
somemachine.com (1.2.3.4)
INFO    2002-07-09 10:31:15.043 [smtpserv] (): Command received: MAIL
FROM: <fo...@foo.com>
DEBUG   2002-07-09 10:31:15.043 [smtpserv] (): Sent 250 Sender
<fo...@foo.com> OK
INFO    2002-07-09 10:31:15.053 [smtpserv] (): Command received: RCPT
TO: <fo...@foo.com>
DEBUG   2002-07-09 10:31:15.053 [smtpserv] (): 250 Recipient
<fo...@foo.com> OK
INFO    2002-07-09 10:31:15.063 [smtpserv] (): Command received: DATA
INFO    2002-07-09 10:31:15.393 [smtpserv] (): Mail sent to Mail Server
INFO    2002-07-09 10:31:15.403 [smtpserv] (): Command received: QUIT
DEBUG   2002-07-09 10:31:15.403 [smtpserv] (): Sent 221 aristotle
Service closing transmission channel

For leibniz the story is a little different.  I can receive mail just
fine from a variety of sources.  I can send mail, but the log segment
looks a little different (Domains, IPs, and Email Addresses have been
changed to protect the innocent):
  
INFO    2002-07-09 12:08:14.851 [smtpserv] (): Hello Name is: aristotle
DEBUG   2002-07-09 12:08:14.861 [smtpserv] (): Max message size is: 0
INFO    2002-07-09 12:08:14.881 [smtpserv] (): Connection from
somemachine.com (1.2.3.4)
INFO    2002-07-09 12:08:14.891 [smtpserv] (): Command received: EHLO
leibniz
INFO    2002-07-09 12:08:14.901 [smtpserv] (): Command received: MAIL
FROM: <fo...@foo.com>
DEBUG   2002-07-09 12:08:14.901 [smtpserv] (): Sent 250 Sender
<fo...@foo.com> OK
INFO    2002-07-09 12:08:14.911 [smtpserv] (): Command received: RCPT
TO: <ba...@bar.com>
DEBUG   2002-07-09 12:08:14.911 [smtpserv] (): 250 Recipient
<ba...@bar.com> OK
INFO    2002-07-09 12:08:19.949 [smtpserv] (): Command received: RSET
DEBUG   2002-07-09 12:08:19.949 [smtpserv] (): 250 OK
INFO    2002-07-09 12:08:23.053 [smtpserv] (): Command received: RSET
DEBUG   2002-07-09 12:08:23.063 [smtpserv] (): 250 OK
INFO    2002-07-09 12:08:23.063 [smtpserv] (): Command received: MAIL
FROM: <fo...@foo.com>
DEBUG   2002-07-09 12:08:23.063 [smtpserv] (): Sent 250 Sender
<fo...@foo.com> OK
INFO    2002-07-09 12:08:23.073 [smtpserv] (): Command received: RCPT
TO: <ba...@bar.com>
DEBUG   2002-07-09 12:08:23.073 [smtpserv] (): 250 Recipient
<ba...@bar.com> OK
INFO    2002-07-09 12:08:23.083 [smtpserv] (): Command received: DATA
INFO    2002-07-09 12:08:23.183 [smtpserv] (): Mail sent to Mail Server
INFO    2002-07-09 12:08:23.293 [smtpserv] (): Command received: QUIT
DEBUG   2002-07-09 12:08:23.293 [smtpserv] (): Sent 221 aristotle
Service closing transmission channel

Note the RSETs.  Not sure why this would be happening.  And when I try
and "Test Account Settings", everything just hangs.  The test eventually
times out, never having completed successfully.  The log segment is

INFO    2002-07-09 12:07:32.390 [smtpserv] (): Hello Name is: aristotle
DEBUG   2002-07-09 12:07:32.390 [smtpserv] (): Max message size is: 0
INFO    2002-07-09 12:07:32.400 [smtpserv] (): Connection from
somemachine.com (1.2.3.4)
INFO    2002-07-09 12:07:32.400 [smtpserv] (): Command received: EHLO
leibniz
INFO    2002-07-09 12:07:32.410 [smtpserv] (): Command received: MAIL
FROM: <fo...@foo.com>
DEBUG   2002-07-09 12:07:32.410 [smtpserv] (): Sent 250 Sender
<fo...@foo.com> OK

This is especially odd considering that an actual send succeeds.

I haven't had a chance to figure out if this is an XP specific problem
or one that occurs for any client not on the server machine in an O/S
independent fashion.  That's my next step.

I observed the same behavior when using James 2.0a3 and the snapshot in
the "latest" directory from 5/30/02.

Any thoughts?  Any suggestions would be appreciated.  Thanks.

--Peter

Peter M. Goldstein
farsight@alum.mit.edu




--
To unsubscribe, e-mail:   <ma...@jakarta.apache.org>
For additional commands, e-mail: <ma...@jakarta.apache.org>