Uploads from FireFTP failing with "java.io.EOFException: Unexpected end of ZLIB
input stream"
---------------------------------------------------------------------------------------------
Key: FTPSERVER-319
URL: https://issues.apache.org/jira/browse/FTPSERVER-319
Project: FtpServer
Issue Type: Bug
Components: Core, Server
Affects Versions: 1.0.2
Environment: Server:
- OS X 10.5.7
- Java(TM) 2 Runtime Environment, Standard Edition (build 1.5.0_19-b02-304)
- Clean extract of Apache FtpServer 1.0.2 Release, started via 'bin/ftpd.sh
res/conf/ftpd-typical.xml'
Client:
- FireFTP 1.0.5 on Firefox 3.5.1 on OS X 10.4.11
- Connecting over LAN
Reporter: Roger Marshall
Priority: Minor
Uploads from FireFTP are failing frequently for me with "java.io.EOFException:
Unexpected end of ZLIB input stream".
FireFTP then stalls for about 30 seconds before disconnecting, reconnecting,
and retrying the upload (this time via 'APPE' rather than 'STOR'), which then
works.
Transmit and Interarchy clients for OS X seem to work fine, but they do not
appear to send 'MODE Z'.
Server Log:
[ INFO] 2009-07-28 18:03:52,251 [] [] Refreshing
org.springframework.context.support.filesystemxmlapplicationcont...@724a9d:
display name
[org.springframework.context.support.filesystemxmlapplicationcont...@724a9d];
startup date [Tue Jul 28 18:03:52 BST 2009]; root of context hierarchy
[ INFO] 2009-07-28 18:03:52,324 [] [] Loading XML bean definitions from file
[/Users/roger/Projects/apache-ftpserver-1.0.2/res/conf/ftpd-typical.xml]
[ INFO] 2009-07-28 18:03:52,699 [] [] Bean factory for application context
[org.springframework.context.support.filesystemxmlapplicationcont...@724a9d]:
org.springframework.beans.factory.support.defaultlistablebeanfact...@b0d33c
[ INFO] 2009-07-28 18:03:52,719 [] [] Pre-instantiating singletons in
org.springframework.beans.factory.support.defaultlistablebeanfact...@b0d33c:
defining beans
[org.apache.ftpserver.listener.ListenerFactory#0,org.apache.ftpserver.usermanager.PropertiesUserManagerFactory#0,org.apache.ftpserver.FtpServerFactory#0,myServer];
root of factory hierarchy
[ INFO] 2009-07-28 18:03:52,996 [] [] FTP server started
[ INFO] 2009-07-28 18:04:03,449 [] [192.168.2.120] CREATED
[ INFO] 2009-07-28 18:04:03,458 [] [192.168.2.120] OPENED
[ INFO] 2009-07-28 18:04:03,479 [] [192.168.2.120] SENT: 220 Service ready for
new user.
[ INFO] 2009-07-28 18:04:03,484 [] [192.168.2.120] RECEIVED: USER admin
[ INFO] 2009-07-28 18:04:03,491 [admin] [192.168.2.120] SENT: 331 User name
okay, need password for admin.
[ INFO] 2009-07-28 18:04:03,581 [admin] [192.168.2.120] RECEIVED: PASS *****
[ INFO] 2009-07-28 18:04:03,602 [admin] [192.168.2.120] Login success - admin
[ INFO] 2009-07-28 18:04:03,603 [admin] [192.168.2.120] SENT: 230 User logged
in, proceed.
[ INFO] 2009-07-28 18:04:03,603 [admin] [192.168.2.120] RECEIVED: FEAT
[ INFO] 2009-07-28 18:04:03,605 [admin] [192.168.2.120] SENT: 211-Extensions
supported
SIZE
MDTM
REST STREAM
LANG en;zh-tw;ja;is
MLST Size;Modify;Type;Perm
AUTH SSL
AUTH TLS
MODE Z
UTF8
TVFS
MD5
MMD5
MFMT
211 End
[ INFO] 2009-07-28 18:04:03,606 [admin] [192.168.2.120] RECEIVED: PWD
[ INFO] 2009-07-28 18:04:03,607 [admin] [192.168.2.120] SENT: 257 "/" is
current directory.
[ INFO] 2009-07-28 18:04:08,593 [admin] [192.168.2.120] RECEIVED: TYPE I
[ INFO] 2009-07-28 18:04:08,596 [admin] [192.168.2.120] SENT: 200 Command TYPE
okay.
[ INFO] 2009-07-28 18:04:08,674 [admin] [192.168.2.120] RECEIVED: MODE Z
[ INFO] 2009-07-28 18:04:08,680 [admin] [192.168.2.120] SENT: 200 Command MODE
okay.
[ INFO] 2009-07-28 18:04:08,680 [admin] [192.168.2.120] RECEIVED: PASV
[ INFO] 2009-07-28 18:04:08,684 [admin] [192.168.2.120] SENT: 227 Entering
Passive Mode (192,168,2,111,242,122)
[ INFO] 2009-07-28 18:04:08,687 [admin] [192.168.2.120] RECEIVED: STOR
some_file.flv
[ INFO] 2009-07-28 18:04:10,593 [admin] [192.168.2.120] File uploaded
/some_file.flv
[ INFO] 2009-07-28 18:04:10,594 [admin] [192.168.2.120] SENT: 150 File status
okay; about to open data connection.
[ INFO] 2009-07-28 18:04:10,594 [admin] [192.168.2.120] SENT: 226 Transfer
complete.
.
.
.
[ INFO] 2009-07-28 18:05:14,886 [admin] [192.168.2.120] RECEIVED: TYPE I
[ INFO] 2009-07-28 18:05:14,887 [admin] [192.168.2.120] SENT: 200 Command TYPE
okay.
[ INFO] 2009-07-28 18:05:14,888 [admin] [192.168.2.120] RECEIVED: PASV
[ INFO] 2009-07-28 18:05:14,889 [admin] [192.168.2.120] SENT: 227 Entering
Passive Mode (192,168,2,111,242,132)
[ INFO] 2009-07-28 18:05:14,892 [admin] [192.168.2.120] RECEIVED: STOR WM
Components 2.2.2.3.dmg
[ WARN] 2009-07-28 18:06:14,893 [admin] [192.168.2.120] Exception during data
transfer, closing data connection socket
java.io.EOFException: Unexpected end of ZLIB input stream
at java.util.zip.InflaterInputStream.fill(InflaterInputStream.java:216)
at java.util.zip.InflaterInputStream.read(InflaterInputStream.java:134)
at java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
at java.io.BufferedInputStream.read1(BufferedInputStream.java:256)
at java.io.BufferedInputStream.read(BufferedInputStream.java:313)
at java.io.FilterInputStream.read(FilterInputStream.java:90)
at
org.apache.ftpserver.impl.IODataConnection.transfer(IODataConnection.java:243)
at
org.apache.ftpserver.impl.IODataConnection.transferFromClient(IODataConnection.java:136)
at org.apache.ftpserver.command.impl.STOR.execute(STOR.java:146)
at
org.apache.ftpserver.impl.DefaultFtpHandler.messageReceived(DefaultFtpHandler.java:210)
at
org.apache.ftpserver.listener.nio.FtpHandlerAdapter.messageReceived(FtpHandlerAdapter.java:61)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$TailFilter.messageReceived(DefaultIoFilterChain.java:721)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:433)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:801)
at
org.apache.ftpserver.listener.nio.FtpLoggingFilter.messageReceived(FtpLoggingFilter.java:85)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:433)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:801)
at
org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:71)
at
org.apache.mina.filter.logging.MdcInjectionFilter.filter(MdcInjectionFilter.java:136)
at
org.apache.mina.filter.util.CommonEventFilter.messageReceived(CommonEventFilter.java:70)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:433)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:801)
at
org.apache.mina.filter.codec.ProtocolCodecFilter$ProtocolDecoderOutputImpl.flush(ProtocolCodecFilter.java:375)
at
org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:229)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:433)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:47)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:801)
at
org.apache.mina.core.filterchain.IoFilterEvent.fire(IoFilterEvent.java:71)
at org.apache.mina.core.session.IoEvent.run(IoEvent.java:63)
at
org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTask(OrderedThreadPoolExecutor.java:801)
at
org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTasks(OrderedThreadPoolExecutor.java:793)
at
org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.run(OrderedThreadPoolExecutor.java:735)
at java.lang.Thread.run(Thread.java:613)
[ INFO] 2009-07-28 18:06:14,897 [admin] [192.168.2.120] SENT: 150 File status
okay; about to open data connection.
[ INFO] 2009-07-28 18:06:14,897 [admin] [192.168.2.120] RECEIVED: QUIT
[ INFO] 2009-07-28 18:06:14,898 [admin] [192.168.2.120] CLOSED
[ INFO] 2009-07-28 18:06:24,896 [] [192.168.2.120] CREATED
[ INFO] 2009-07-28 18:06:24,896 [admin] [192.168.2.120] OPENED
[ INFO] 2009-07-28 18:06:24,897 [admin] [192.168.2.120] SENT: 220 Service ready
for new user.
[ INFO] 2009-07-28 18:06:24,901 [] [192.168.2.120] RECEIVED: USER admin
[ INFO] 2009-07-28 18:06:24,902 [admin] [192.168.2.120] SENT: 331 User name
okay, need password for admin.
[ INFO] 2009-07-28 18:06:24,935 [admin] [192.168.2.120] RECEIVED: PASS *****
[ INFO] 2009-07-28 18:06:24,936 [admin] [192.168.2.120] Login success - admin
[ INFO] 2009-07-28 18:06:24,944 [admin] [192.168.2.120] SENT: 230 User logged
in, proceed.
[ INFO] 2009-07-28 18:06:24,967 [admin] [192.168.2.120] RECEIVED: CWD /
[ INFO] 2009-07-28 18:06:24,968 [admin] [192.168.2.120] SENT: 250 Directory
changed to /
[ INFO] 2009-07-28 18:06:25,067 [admin] [192.168.2.120] RECEIVED: TYPE I
[ INFO] 2009-07-28 18:06:25,068 [admin] [192.168.2.120] SENT: 200 Command TYPE
okay.
[ INFO] 2009-07-28 18:06:25,072 [admin] [192.168.2.120] RECEIVED: SIZE WM
Components 2.2.2.3.dmg
[ INFO] 2009-07-28 18:06:25,073 [admin] [192.168.2.120] SENT: 213 10104832
[ INFO] 2009-07-28 18:06:25,087 [admin] [192.168.2.120] RECEIVED: MODE Z
[ INFO] 2009-07-28 18:06:25,088 [admin] [192.168.2.120] SENT: 200 Command MODE
okay.
[ INFO] 2009-07-28 18:06:25,091 [admin] [192.168.2.120] RECEIVED: PASV
[ INFO] 2009-07-28 18:06:25,093 [admin] [192.168.2.120] SENT: 227 Entering
Passive Mode (192,168,2,111,242,133)
[ INFO] 2009-07-28 18:06:25,095 [admin] [192.168.2.120] RECEIVED: APPE WM
Components 2.2.2.3.dmg
[ INFO] 2009-07-28 18:06:25,168 [admin] [192.168.2.120] File uploaded /WM
Components 2.2.2.3.dmg
[ INFO] 2009-07-28 18:06:25,168 [admin] [192.168.2.120] SENT: 150 File status
okay; about to open data connection.
[ INFO] 2009-07-28 18:06:25,169 [admin] [192.168.2.120] SENT: 226 Transfer
complete.
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.
