mina-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From David Latorre <dvl...@gmail.com>
Subject Re: Apache FTP Server - Strange Behavior when passive port specification is explicit
Date Wed, 03 Apr 2013 09:17:09 GMT
Hello  Jagtar,


 Please, use the ftpserver-users mailing list for these questions.

 Your log file says that the remote peer (either your FTP client or some
network appliance)  closed the connection.  It is possible that the IP
address from the PASV response is incorrect:

Entering Passive Mode (ex,te,rnl,ip,203,135)

Is ex,te,rnl,ip,  the correct IP address the client should connect to?  You
said the FTP client never got this response so a firewall might be killing
the connection for any reason. Check your FTP client (just in case) and
Firewall log files.



In Active Mode,  the ports should be opened in the FTP Client end;  the
ftpserver only needs port 21 and outgoing connections from port 20 (or the
one you have configured in the XML), this is why it works.  Active mode is
'troublesome' when the port the FTP client opened in its local computer is
not available to the FTP server (ie,  the FTP client is behind a firewall
or in a NATed environment).





2013/3/31 Jagtar Singh <jsmundey@hotmail.com>

> Hi,
> We configured apache ftp server (v 1.0.6) for passive mode operation.
> Server is behind firewall so we specified port range (50000-55000) asking
> firewall admin to open the same range.On client side (connecting via
> external ip) we don't get response to PASV command (when doing dir). On
> server side we get this exception in log (excerpt below). We thought it's a
> firewall issue.
> Strangely, we removed passive configuration from xml and everything
> started to work. Ports being used were 60000+ which we didn't ask admins to
> open.
> Also from internal IP, it never gave any issue.
> Please explain the behavior because mostly we need passive port
> specification due to restrictive firewalls. I'm afraid this might cause
> problem.
> Thanks in advance.J.--[ INFO] 2013-03-28 04:40:53,825 [admin]
> [][cl.ie.nt.ip] RECEIVED: PASV[DEBUG] 2013-03-28 04:40:53,825 [admin]
> [][cl.ie.nt.ip] Initiating passive data connection[DEBUG] 2013-03-28
> 04:40:53,826 [admin] [][cl.ie.nt.ip] Opening passive data connection on
> address "/int.ern.al.ip" and port 52103[DEBUG] 2013-03-28 04:40:53,826
> [admin] [][cl.ie.nt.ip] Passive data connection created on address
> "/int.ern.al.ip" and port 52103[DEBUG] 2013-03-28 04:40:53,827 [admin]
> [][cl.ie.nt.ip] Firing a WRITE event for session 2[DEBUG] 2013-03-28
> 04:40:53,828 [admin] [][cl.ie.nt.ip] Firing a WRITE event for session
> 2[DEBUG] 2013-03-28 04:40:53,828 [admin] [][cl.ie.nt.ip] Event WRITE has
> been fired for session 2[DEBUG] 2013-03-28 04:40:53,828 [admin]
> [][cl.ie.nt.ip] Firing a WRITE event for session 2[DEBUG] 2013-03-28
> 04:40:53,829 [admin] [][cl.ie.nt.ip] Firing a MESSAGE_SENT event for
> session 2[DEBUG] 2013-03-28 04:40:53,829 [admin] [][cl.ie.nt.ip] Adding
> event MESSAGE_SENT to session 2Queue : [MESSAGE_SENT, ]
> [DEBUG] 2013-03-28 04:40:53,829 [admin] [][cl.ie.nt.ip] Event MESSAGE_SENT
> has been fired for session 2[DEBUG] 2013-03-28 04:40:53,829 [admin]
> [][cl.ie.nt.ip] Firing a EXCEPTION_CAUGHT event for session 2[DEBUG]
> 2013-03-28 04:40:53,830 [admin] [][cl.ie.nt.ip] Adding event
> EXCEPTION_CAUGHT to session 2Queue : [MESSAGE_SENT, , EXCEPTION_CAUGHT, ]
> [DEBUG] 2013-03-28 04:40:53,830 [admin] [][cl.ie.nt.ip] Event
> EXCEPTION_CAUGHT has been fired for session 2[DEBUG] 2013-03-28
> 04:40:53,830 [admin] [][cl.ie.nt.ip] Firing a SESSION_CLOSED event for
> session 2[DEBUG] 2013-03-28 04:40:53,830 [admin] [][cl.ie.nt.ip] Adding
> event SESSION_CLOSED to session 2Queue : [MESSAGE_SENT, , EXCEPTION_CAUGHT,
> , SESSION_CLOSED, ]
> [DEBUG] 2013-03-28 04:40:53,830 [admin] [][cl.ie.nt.ip] Event
> SESSION_CLOSED has been fired for session 2[DEBUG] 2013-03-28 04:40:53,830
> [admin] [][cl.ie.nt.ip] Event WRITE has been fired for session 2[DEBUG]
> 2013-03-28 04:40:53,831 [admin] [][cl.ie.nt.ip] Event WRITE has been fired
> for session 2[DEBUG] 2013-03-28 04:40:53,831 [admin] [][cl.ie.nt.ip] Event
> MESSAGE_RECEIVED has been fired for session 2[DEBUG] 2013-03-28
> 04:40:53,831 [] [][] Event MESSAGE_RECEIVED has been fired for session
> 2[DEBUG] 2013-03-28 04:40:53,831 [] [][] Firing a MESSAGE_SENT event for
> session 2[DEBUG] 2013-03-28 04:40:53,831 [admin] [][cl.ie.nt.ip] Firing a
> MESSAGE_SENT event for session 2[ INFO] 2013-03-28 04:40:53,831 [admin]
> [][cl.ie.nt.ip] SENT: 227 Entering Passive Mode (ex,te,rnl,ip,203,135)
> [DEBUG] 2013-03-28 04:40:53,831 [admin] [][cl.ie.nt.ip] Event MESSAGE_SENT
> has been fired for session 2[DEBUG] 2013-03-28 04:40:53,831 [] [][] Event
> MESSAGE_SENT has been fired for session 2[DEBUG] 2013-03-28 04:40:53,831 []
> [][] Firing a EXCEPTION_CAUGHT event for session 2[DEBUG] 2013-03-28
> 04:40:53,831 [admin] [][cl.ie.nt.ip] Firing a EXCEPTION_CAUGHT event for
> session 2[ WARN] 2013-03-28 04:40:53,833 [admin] [][cl.ie.nt.ip] EXCEPTION
> :java.io.IOException: An existing connection was forcibly closed by the
> remote host   at sun.nio.ch.SocketDispatcher.read0(Native Method)     at
> sun.nio.ch.SocketDispatcher.read(Unknown Source)     at
> sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source)       at
> sun.nio.ch.IOUtil.read(Unknown Source)       at
> sun.nio.ch.SocketChannelImpl.read(Unknown Source)    at
> org.apache.mina.transport.socket.nio.NioProcessor.read(NioProcessor.java:280)
>        at
> org.apache.mina.transport.socket.nio.NioProcessor.read(NioProcessor.java:44)
> at
> org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:695)
>    at
> org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:668)
> at
> org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:657)
> at
> org.apache.mina.core.polling.AbstractPollingIoProcessor.access$600(AbstractPollingIoProcessor.java:68)
>       at
> org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1141)
>  at
> org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
>      at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
>  at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)   at
> java.lang.Thread.run(Unknown Source)[ERROR] 2013-03-28 04:40:53,835 [admin]
> [][cl.ie.nt.ip] Exception caught, closing sessionjava.io.IOException: An
> existing connection was forcibly closed by the remote host      at
> sun.nio.ch.SocketDispatcher.read0(Native Method)     at
> sun.nio.ch.SocketDispatcher.read(Unknown Source)     at
> sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source)       at
> sun.nio.ch.IOUtil.read(Unknown Source)       at
> sun.nio.ch.SocketChannelImpl.read(Unknown Source)    at
> org.apache.mina.transport.socket.nio.NioProcessor.read(NioProcessor.java:280)
>        at
> org.apache.mina.transport.socket.nio.NioProcessor.read(NioProcessor.java:44)
> at
> org.apache.mina.core.polling.AbstractPollingIoProcessor.read(AbstractPollingIoProcessor.java:695)
>    at
> org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:668)
> at
> org.apache.mina.core.polling.AbstractPollingIoProcessor.process(AbstractPollingIoProcessor.java:657)
> at
> org.apache.mina.core.polling.AbstractPollingIoProcessor.access$600(AbstractPollingIoProcessor.java:68)
>       at
> org.apache.mina.core.polling.AbstractPollingIoProcessor$Processor.run(AbstractPollingIoProcessor.java:1141)
>  at
> org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)
>      at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
>  at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)   at
> java.lang.Thread.run(Unknown Source)[DEBUG] 2013-03-28 04:40:53,836 [admin]
> [][cl.ie.nt.ip] Event EXCEPTION_CAUGHT has been fired for session 2[DEBUG]
> 2013-03-28 04:40:53,836 [] [][] Event EXCEPTION_CAUGHT has been fired for
> session 2[DEBUG] 2013-03-28 04:40:53,836 [] [][] Firing a SESSION_CLOSED
> event for session 2[DEBUG] 2013-03-28 04:40:53,836 [admin] [][cl.ie.nt.ip]
> Firing a SESSION_CLOSED event for session 2[ INFO] 2013-03-28 04:40:53,836
> [admin] [][cl.ie.nt.ip] CLOSED--

Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message