You are viewing a plain text version of this content. The canonical link for it is here.
Posted to server-user@james.apache.org by bill page <bi...@snakeriversoftware.com> on 2004/05/14 18:00:34 UTC
Nulls hanging up James?
I have two questions regarding the smtpserver. Below is the debugging log
and the outlook retrieved email headers. I believe these are for the same
message. This is still James 2.1.3?
1)
How do I connect the log with a particular email. I would assume that:
14/05/04 11:30:46 DEBUG smtpserver: Successfully spooled
mail )Mail1084548496221-249 from touring-bounces@phred.org for
[billp@snakeriversoftware.com]
contains the id to match with, but con't find that in the email.
2)
The smtp log shows over 2 minutes from the 354 response. Talking to the
list owner, it looks like his server is timing out and then james is sending
the 250. It also appears that the original poster has nulls in his message
(004E0). I thought smtp was supposed to be all ascii but could James be
hanging up?
004B0: 3D 0D 0A 75 6E 64 65 72 77 65 61 72 3F 0D 0A 0D =..underwear?...
004C0: 0A 53 68 6F 63 6B 69 6E 67 2E 0D 0A 0D 0A 4F 6C .Shocking.....Ol
004D0: 6F 66 20 69 6E 20 55 70 70 73 61 6C 61 0D 0A 00 of in Uppsala...
004E0: 00 00 00 00 0D 0A 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F ......__________
004F0: 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F ________________
00500: 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F 5F ________________
00510: 5F 5F 5F 5F 5F 0D 0A 54 6F 75 72 69 6E 67 20 6D _____..Touring m
00520: 61 69 6C 69 6E 67 20 6C 69 73 74 0D 0A 54 6F 75 ailing list..Tou
00530: 72 69 6E 67 40 70 68 72 65 64 2E 6F 72 67 0D 0A ring@phred.org..
00540: 42 72 6F 77 73 65 20 61 6E 64 20 73 65 61 72 63 Browse and searc
00550: 68 20 74 68 65 20 61 72 63 68 69 76 65 73 3A 20 h the archives:
----------------------------------------------------------------------------
---
14/05/04 11:28:09 DEBUG smtpserver: Calling start()
14/05/04 11:28:09 DEBUG smtpserver: Watchdog default Worker #47 has time to
sleep 359990
14/05/04 11:28:10 DEBUG smtpserver: Command received: EHLO
catfood.nt.phred.org
14/05/04 11:28:10 DEBUG smtpserver: Sent: 250 leela Hello
catfood.nt.phred.org (nt.phred.org [216.254.27.122])
14/05/04 11:28:10 DEBUG smtpserver: Calling reset() default Worker #47
14/05/04 11:28:10 DEBUG smtpserver: Command received: MAIL
FROM:<to...@phred.org>
14/05/04 11:28:10 DEBUG smtpserver: Sent: 250 Sender
<to...@phred.org> OK
14/05/04 11:28:10 DEBUG smtpserver: Calling reset() default Worker #47
14/05/04 11:28:11 DEBUG smtpserver: Command received: RCPT
TO:<bi...@snakeriversoftware.com>
14/05/04 11:28:11 DEBUG smtpserver: Sent: 250 Recipient
<bi...@snakeriversoftware.com> OK
14/05/04 11:28:11 DEBUG smtpserver: Calling reset() default Worker #47
14/05/04 11:28:11 DEBUG smtpserver: Command received: DATA
14/05/04 11:28:11 DEBUG smtpserver: Sent: 354 Ok Send data ending with
<CRLF>.<CRLF>
14/05/04 11:30:46 DEBUG smtpserver: Successfully spooled
mail )Mail1084548496221-249 from touring-bounces@phred.org for
[billp@snakeriversoftware.com]
14/05/04 11:30:46 DEBUG smtpserver: Sent: 250 Message received
14/05/04 11:30:46 DEBUG smtpserver: Calling reset() default Worker #47
14/05/04 11:30:46 DEBUG smtpserver: Calling stop() default Worker #47
Return-Path: <ol...@stroh.nu>
Received: from nt.phred.org ([216.254.27.122])
by leela (JAMES SMTP Server 2.1.3) with SMTP ID 389
for <bi...@snakeriversoftware.com>;
Fri, 14 May 2004 11:28:16 -0400 (EDT)
Received: from phred.org ([172.16.1.2]) by catfood.nt.phred.org with
Microsoft SMTPSVC(6.0.3790.0);
Wed, 12 May 2004 09:50:27 -0700
Received: from catfood.nt.phred.org (catfood.nt.phred.org [172.16.1.3])
by phred.org (Postfix) with ESMTP id 28B9A5E71
for <to...@phred.org>; Wed, 12 May 2004 09:50:09 -0700 (PDT)
Received: from av1-2-sn4.m-sp.skanova.net ([81.228.10.115]) by
catfood.nt.phred.org with Microsoft SMTPSVC(6.0.3790.0);
Wed, 12 May 2004 09:50:24 -0700
Received: by av1-2-sn4.m-sp.skanova.net (Postfix, from userid 502)
id 3303637F7A; Wed, 12 May 2004 18:50:08 +0200 (CEST)
Received: from smtp2-2-sn4.m-sp.skanova.net (smtp2-2-sn4.m-sp.skanova.net
[81.228.10.182]) by av1-2-sn4.m-sp.skanova.net (Postfix) with ESMTP
id 23AC937F34; Wed, 12 May 2004 18:50:08 +0200 (CEST)
Received: from salong (h42n2fls33o1121.telia.com [217.211.80.42])
by smtp2-2-sn4.m-sp.skanova.net (Postfix) with SMTP
id 960EA37E5B; Wed, 12 May 2004 18:49:58 +0200 (CEST)
Message-ID: <00...@salong>
From: "Olof Stroh" <ol...@stroh.nu>
To: "Jim Foreman" <ji...@icon.net>,
"Phred Touring List" <to...@phred.org>
References: <00...@jim>
Date: Wed, 12 May 2004 18:49:54 +0200
MIME-Version: 1.0
X-Priority: 3
X-MSMail-Priority: Normal
X-Mailer: Microsoft Outlook Express 6.00.2800.1409
X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2800.1409
X-OriginalArrivalTime: 12 May 2004 16:50:25.0068 (UTC)
FILETIME=[38D43EC0:01C43841]
Content-Transfer-Encoding: quoted-printable
Content-Type: text/plain;charset="iso-8859-1"
Subject: Re: Underwear, was Helmets
X-BeenThere: touring@phred.org
X-Mailman-Version: 2.1
Precedence: list
Reply-To: Olof Stroh <ol...@stroh.nu>
Sender: touring-bounces@phred.org
Delivered-To: billp@snakeriversoftware.com
Bill Page
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
RE: Nulls hanging up James?
Posted by "Noel J. Bergman" <no...@devtech.com>.
Bill,
I found, and patched, a bug where a socket disconnection prior to the socket
timeout would be viewed by the SMTP handler, in DATA state, as a successful
completion of the data transfer. With that patch, if the client
disconnects, there will be an exception.
Not so sure about this "NULL" character issue, though, but the patch will
enforce the <CRLF>.<CRLF> requirement.
If you are around, I would be happy to send you a test build. Actually, I
will review our current situation, and may post RC4 this weekend, since the
initial reports are that we've successfully addressed the incompatible JDBC
functionality issues between some of the databases.
--- Noel
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
RE: Nulls hanging up James?
Posted by bill page <bi...@snakeriversoftware.com>.
Noel, I've been up on 2.2.0 RC3 for a few days now as you suggested. I'm
still having a problem with timeouts(?). To refresh your memory:
This is coming from a list. The message in question apparently has nulls at
the end. I think four, but I don't get to see them because of the problem.
I believe that James is hanging up when it encounters the nulls (you can see
a couple minutes go by below right after the 354 response). What I do know
is that the list puts a footer on the message that I don't see (because I
think James is hung up at that point). The list owner says that he is
timing out on his side. It appears that the sender breaking connection is
what gets James going again.
20/05/04 10:44:30 DEBUG smtpserver: Getting SMTPHandler from pool.
20/05/04 10:44:30 INFO smtpserver: Connection from nt.phred.org
(216.254.27.122)
20/05/04 10:44:30 DEBUG smtpserver: Sent: 220 leela SMTP Server (JAMES SMTP
Server 2.2.0RC3) ready Thu, 20 May 2004 10:44:30 -0400 (EDT)
20/05/04 10:44:30 DEBUG smtpserver: Calling start()
20/05/04 10:44:30 DEBUG smtpserver: Watchdog default Worker #14 has time to
sleep 360000
20/05/04 10:44:30 DEBUG smtpserver: Command received: EHLO
catfood.nt.phred.org
20/05/04 10:44:30 DEBUG smtpserver: Sent: 250 leela Hello
catfood.nt.phred.org (nt.phred.org [216.254.27.122])
20/05/04 10:44:30 DEBUG smtpserver: Calling reset() default Worker #14
20/05/04 10:44:30 DEBUG smtpserver: Command received: MAIL
FROM:<to...@phred.org>
20/05/04 10:44:30 DEBUG smtpserver: Sent: 250 Sender
<to...@phred.org> OK
20/05/04 10:44:30 DEBUG smtpserver: Calling reset() default Worker #14
20/05/04 10:44:30 DEBUG smtpserver: Command received: RCPT
TO:<bi...@snakeriversoftware.com>
20/05/04 10:44:30 DEBUG smtpserver: Sent: 250 Recipient
<bi...@snakeriversoftware.com> OK
20/05/04 10:44:30 DEBUG smtpserver: Calling reset() default Worker #14
20/05/04 10:44:30 DEBUG smtpserver: Command received: DATA
20/05/04 10:44:30 DEBUG smtpserver: Sent: 354 Ok Send data ending with
<CRLF>.<CRLF>
20/05/04 10:46:59 INFO smtpserver: Successfully spooled mail
Mail1085064270933-1002 from touring-bounces@phred.org for
[billp@snakeriversoftware.com]
20/05/04 10:46:59 DEBUG smtpserver: Sent: 250 Message received
20/05/04 10:47:00 DEBUG smtpserver: Calling reset() default Worker #14
20/05/04 10:47:00 DEBUG smtpserver: Calling stop() default Worker #14
20/05/04 10:47:00 DEBUG smtpserver: Closing socket.
20/05/04 10:47:00 DEBUG smtpserver: Calling disposeWatchdog() default Worker
#14
20/05/04 10:47:00 DEBUG smtpserver: Watchdog default Worker #14 is exiting
run().
20/05/04 10:47:00 DEBUG smtpserver: Returning SMTPHandler to pool.
20/05/04 10:47:00 DEBUG smtpserver: Returning a
org.apache.james.smtpserver.SMTPHandler to the pool
> -----Original Message-----
> From: Noel J. Bergman [mailto:noel@devtech.com]
> Sent: Friday, May 14, 2004 1:19 PM
> To: James Users List; billp@snakeriversoftware.com
> Subject: RE: Nulls hanging up James?
>
>
>
> > The smtp log shows over 2 minutes from the 354 response. Talking to
> > the list owner, it looks like his server is timing out and then
> > james is sending the 250.
>
> That's odd. James should catch an error if there is a
> timeout. It should
> need to see the <CRLF>.<CRLF>. I just did a test where I
> started a message,
> and then left it hanging in the middle of the DATA command.
> Please test
> with 2.2.0RC3. If necessary, we can do a special build with
> some extra
> debugging code.
>
> --- Noel
>
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org
RE: Nulls hanging up James?
Posted by "Noel J. Bergman" <no...@devtech.com>.
> How do I connect the log with a particular email.
"Successfully spooled mail Mail1084548496221-249 from ..."
That is the key. In the database repository, that would be the key in the
table. In the file system, the name is hexified. That is a legacy from
Avalon code, but we have control over it, and will consider changing it in a
future release.
> The smtp log shows over 2 minutes from the 354 response. Talking to
> the list owner, it looks like his server is timing out and then
> james is sending the 250.
That's odd. James should catch an error if there is a timeout. It should
need to see the <CRLF>.<CRLF>. I just did a test where I started a message,
and then left it hanging in the middle of the DATA command. Please test
with 2.2.0RC3. If necessary, we can do a special build with some extra
debugging code.
--- Noel
---------------------------------------------------------------------
To unsubscribe, e-mail: server-user-unsubscribe@james.apache.org
For additional commands, e-mail: server-user-help@james.apache.org