Mailing-List: contact dev-help@mina.apache.org; run by ezmlm
Precedence: bulk
Reply-To: dev@mina.apache.org
Message-ID: <105375645.1257355652990.JavaMail.jira@brutus>
Date: Wed, 4 Nov 2009 17:27:32 +0000 (UTC)
From: "Nick Padgett (JIRA)" <jira@apache.org>
To: dev@mina.apache.org
Subject: [jira] Created: (FTPSERVER-339) Commands Dropped After Successful
 File Upload
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable

Commands Dropped After Successful File Upload
---------------------------------------------

                 Key: FTPSERVER-339
                 URL: https://issues.apache.org/jira/browse/FTPSERVER-339
             Project: FtpServer
          Issue Type: Bug
    Affects Versions: 1.0.3
            Reporter: Nick Padgett
            Priority: Critical


After my ftp client successfully uploads a file, it sends a QUIT request.  =
This QUIT request doesn't always appear to be logged by the FtpServer which=
 results in the connection idling and the FtpServer subsequently closes the=
 connection.  We experience this issue often when uploading large files (2+=
GB) or multiple medium size files (~1 GB).  When the QUIT is received befor=
e the FtpServer issues a 226 transfer complete message, the server sends a =
226 transfer complete message , the a 221 goodbye message before closing th=
e connection.  When the QUIT is received after the FtpServer issues a 226 t=
ransfer complete message, sometimes the QUIT is processed on the existing t=
hread and sometimes it is processed on a new thread.  In either case, the s=
erver sends a 221 goodbye message and closed the connection.  This leads me=
 to believe that a QUIT message could be sent to the server in the time bet=
ween when the first thread is being closed and the second thread is being o=
pened.  Below are logs from my FtpServer that illustrate all of these scena=
rios.

This issue occurs very frequently and is resulting in the user believing th=
eir uploads failed.

QUIT received before 226 transfer complete sent:
2009-11-04 14:32:31,936 [pool-4-thread-8] INFO org.apache.ftpserver.command=
.impl.STOR  - File uploaded /1024/2009/11/02/7558_7W5oJqfN_event.wmv
2009-11-04 14:32:31,937 [pool-4-thread-8] INFO org.apache.ftpserver.listene=
r.nio.FtpLoggingFilter  - SENT: 150 File status okay; about to open data co=
nnection.
2009-11-04 14:32:32,104 [pool-4-thread-8] INFO org.apache.ftpserver.listene=
r.nio.FtpLoggingFilter  - RECEIVED: QUIT
2009-11-04 14:32:32,104 [pool-4-thread-8] INFO org.apache.ftpserver.listene=
r.nio.FtpLoggingFilter  - SENT: 226 Transfer complete.
2009-11-04 14:32:32,105 [pool-4-thread-8] INFO org.apache.ftpserver.listene=
r.nio.FtpLoggingFilter  - SENT: 221 Goodbye.
2009-11-04 14:32:32,105 [pool-4-thread-8] INFO org.apache.ftpserver.listene=
r.nio.FtpLoggingFilter  - CLOSED

QUIT received after 226 transfer complete sent on the same thread:
2009-11-03 19:25:23,958 [pool-4-thread-697] INFO org.apache.ftpserver.comma=
nd.impl.STOR  - File uploaded /1017/2009/10/09/7235_xVJpQ8tT_event.wmv
2009-11-03 19:25:23,958 [pool-4-thread-697] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - SENT: 150 File status okay; about to open data =
connection.
2009-11-03 19:25:23,968 [pool-4-thread-697] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - SENT: 226 Transfer complete.
2009-11-03 19:25:23,991 [pool-4-thread-697] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - RECEIVED: QUIT
2009-11-03 19:25:23,992 [pool-4-thread-697] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - SENT: 221 Goodbye.
2009-11-03 19:25:23,992 [pool-4-thread-697] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - CLOSED

QUIT received after the 226 transfer complete sent on a new thread:
2009-11-03 04:43:16,551 [pool-4-thread-662] INFO org.apache.ftpserver.comma=
nd.impl.STOR  - File uploaded /1030/2009/11/02/7580_GxDwum7M_event.wmv
2009-11-03 04:43:16,552 [pool-4-thread-662] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - SENT: 150 File status okay; about to open data =
connection.
2009-11-03 04:43:16,552 [pool-4-thread-662] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - SENT: 226 Transfer complete.
2009-11-03 04:43:16,595 [pool-4-thread-667] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - RECEIVED: QUIT
2009-11-03 04:43:16,598 [pool-4-thread-667] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - SENT: 221 Goodbye.
2009-11-03 04:43:16,598 [pool-4-thread-667] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - CLOSED

QUIT NOT received because the FtpServer is between threads:
2009-11-04 02:05:18,328 [pool-4-thread-16] INFO org.apache.ftpserver.comman=
d.impl.STOR  - File uploaded /1051/2008/11/15/7400_NHftLRzu_event.mp4
2009-11-04 02:05:18,329 [pool-4-thread-16] INFO org.apache.ftpserver.listen=
er.nio.FtpLoggingFilter  - SENT: 150 File status okay; about to open data c=
onnection.
2009-11-04 02:05:18,397 [pool-4-thread-16] INFO org.apache.ftpserver.listen=
er.nio.FtpLoggingFilter  - SENT: 226 Transfer complete.
2009-11-04 02:08:18,394 [pool-4-thread-106] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - IDLE
2009-11-04 02:08:18,394 [pool-4-thread-106] INFO org.apache.ftpserver.impl.=
DefaultFtpHandler  - Session idle, closing
2009-11-04 02:08:18,395 [pool-4-thread-106] INFO org.apache.ftpserver.liste=
ner.nio.FtpLoggingFilter  - CLOSED


--=20
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.