I have gleamed an additional semi-useless gem from the logs this morning! 31/01/06 00:06:44 DEBUG smtpserver: Watchdog default Worker #11 has time to sleep 360000 31/01/06 00:06:44 DEBUG smtpserver: Command received: EHLO zproxy.gmail.com 31/01/06 00:06:44 DEBUG smtpserver: Sent: 250-SIZE 20480000 31/01/06 00:06:44 DEBUG smtpserver: Sent: 250-Tonegawa Hello zproxy.gmail.com (zproxy.gmail.com [64.233.162.204]) 31/01/06 00:06:44 DEBUG smtpserver: Sent: 250-AUTH LOGIN PLAIN 31/01/06 00:06:44 DEBUG smtpserver: Sent: 250 AUTH=LOGIN PLAIN 31/01/06 00:06:44 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:06:44 DEBUG smtpserver: Command received: MAIL FROM:<[EMAIL PROTECTED]> 31/01/06 00:06:44 DEBUG smtpserver: Sent: 250 Sender <[EMAIL PROTECTED]> OK 31/01/06 00:06:44 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:06:45 DEBUG smtpserver: Command received: RCPT TO:<[EMAIL PROTECTED]> 31/01/06 00:06:45 DEBUG smtpserver: Sent: 250 Recipient <[EMAIL PROTECTED]> OK 31/01/06 00:06:45 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:06:45 DEBUG smtpserver: Command received: DATA 31/01/06 00:06:45 DEBUG smtpserver: Sent: 354 Ok Send data ending with <CRLF>.<CRLF> 31/01/06 00:06:45 DEBUG smtpserver: Using SizeLimitedInputStream with max message size: 20480000 31/01/06 00:06:45 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:06:45 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:06:45 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:06:45 DEBUG smtpserver: Calling reset() default Worker #11 <snip> resets to worker #11 continue as expected 31/01/06 00:07:55 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:07:55 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:07:56 DEBUG smtpserver: Calling reset() default Worker #11 31/01/06 00:07:57 DEBUG smtpserver: Calling disposeWatchdog() default Worker #11 31/01/06 00:07:57 DEBUG smtpserver: Returning SMTPHandler to pool. 31/01/06 00:07:57 DEBUG smtpserver: Returning a org.apache.james.smtpserver.SMTPHandler to the pool 31/01/06 00:07:57 DEBUG smtpserver: Watchdog default Worker #11 is exiting run().
The same 8 MB attachment from Gmail causes this issue to occur, but with some rest and a fresh look I realize now that the transactions in the log file are completely mixed together - reading down a bit I can see that Worker #11 is actually reclaimed at some point, just not with the result that I had been hoping to see. I'm considering investing some time in adding additional debug output to my version of SMTPHandler.java in order to see if I can get a better grasp of what exactly is happening when the connection is lost, but I wonder if there isn't a better approach? Thanks! Kyle W. Cartmell Sr. Software Developer LAP Holdings, LLC dba First Finance Toll Free: (800) 355-3135 [EMAIL PROTECTED] www.efirstfinance.com -----Original Message----- From: Kyle W. Cartmell [mailto:[EMAIL PROTECTED] Sent: Tuesday, January 31, 2006 8:28 AM To: James Users List Subject: RE: server closes connection when receiving message larger than ~8MB Please excuse my poor threading, technical difficulties, yadda yadda! 1. The connection is closed by the JAMES server. 2. I personally am using Microsoft Outlook 2000, although I have been able to reproduce this issue using Mozilla Thunderbird, Micorosoft Outlook Express, and Microsoft Outlook 2003 on Windows 2000 as well as Windows XP. 3. I am using JAMES version 2.2.0 4. The server hosting JAMES is running Debian Linux 2.4.27-2-386 using JVM version 1.5.0_06. -----Original Message----- From: Kyle W. Cartmell [mailto:[EMAIL PROTECTED] Sent: Monday, January 30, 2006 7:26 PM To: James Users List Subject: RE: server closes connection when receiving message larger than ~8MB I have discovered the debug loglevel and have some new information to share. The following exerpt from my smtplog has been quite enlightening: 30/01/06 19:06:11 DEBUG smtpserver: Retrieving a org.apache.james.smtpserver.SMTPHandler from the pool 30/01/06 19:06:11 DEBUG smtpserver: Getting SMTPHandler from pool. 30/01/06 19:06:11 INFO smtpserver: Connection from 192.168.1.132 (192.168.1.132) 30/01/06 19:06:11 DEBUG smtpserver: Sent: 220 Tonegawa SMTP Server (JAMES SMTP Server 2.2.0) ready Mon, 30 Jan 2006 19:06:11 -0700 (MST) 30/01/06 19:06:11 DEBUG smtpserver: Calling start() 30/01/06 19:06:11 DEBUG smtpserver: Watchdog default Worker #51 has time to sleep 360000 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #13 30/01/06 19:06:12 DEBUG smtpserver: Command received: EHLO [192.168.1.132] 30/01/06 19:06:12 DEBUG smtpserver: Sent: 250-SIZE 20480000 30/01/06 19:06:12 DEBUG smtpserver: Sent: 250 Tonegawa Hello [192.168.1.132] (192.168.1.132 [192.168.1.132]) 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:12 DEBUG smtpserver: Command received: MAIL FROM:<[EMAIL PROTECTED]> SIZE=10838375 30/01/06 19:06:12 DEBUG smtpserver: MAIL command option SIZE received with value 10838375. 30/01/06 19:06:12 DEBUG smtpserver: Sent: 250 Sender <[EMAIL PROTECTED]> OK 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:12 DEBUG smtpserver: Command received: RCPT TO:<[EMAIL PROTECTED]> 30/01/06 19:06:12 DEBUG smtpserver: Sent: 250 Recipient <[EMAIL PROTECTED]> OK 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:12 DEBUG smtpserver: Command received: DATA 30/01/06 19:06:12 DEBUG smtpserver: Sent: 354 Ok Send data ending with <CRLF>.<CRLF> 30/01/06 19:06:12 DEBUG smtpserver: Using SizeLimitedInputStream with max message size: 20480000 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #39 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #25 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #35 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #27 30/01/06 19:06:12 DEBUG smtpserver: Calling reset() default Worker #29 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #22 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #49 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #23 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #9 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 30/01/06 19:06:13 DEBUG smtpserver: Calling reset() default Worker #51 ... you get the idea, this continues for quite a while, mostly referencing worker #51, but the occasional non-51 does appear from time to time. At 19:06:16, there is no further information for this transaction and the next SMTP connection comes in and begins to dump information into the log. Still researching this on my own, but once again I would greatly appreciate a nudge in the correct direction. Thanks!! Kyle W. Cartmell Sr. Software Developer LAP Holdings, LLC dba First Finance Toll Free: (800) 355-3135 [EMAIL PROTECTED] www.efirstfinance.com --------------------------------------------------------------------- To unsubscribe, e-mail: [EMAIL PROTECTED] For additional commands, e-mail: [EMAIL PROTECTED] --------------------------------------------------------------------- To unsubscribe, e-mail: [EMAIL PROTECTED] For additional commands, e-mail: [EMAIL PROTECTED] --------------------------------------------------------------------- To unsubscribe, e-mail: [EMAIL PROTECTED] For additional commands, e-mail: [EMAIL PROTECTED]
