You are viewing a plain text version of this content. The canonical link for it is here.
Posted to ftpserver-users@mina.apache.org by Pierre Etienne <pi...@gmail.com> on 2011/06/06 22:22:36 UTC
timeout problem in SSL (explicit) mode
Hello, (sorryfor my English)
I come towards you because I have a problem at the time of establishing an
explicit connection SSL or even implicit. (No problem in normal mode (not
SSL) ).
Indeed, when I am under FileZilla at no time me see the list of files and I
have a timeout which arrives.
Here are logs FileZilla:
Statut : Résolution de l'adresse de hinka.org
Statut : Connexion à 91.121.145.174:21...
Statut : Connexion établie, attente du message d'accueil...
Réponse : 220 Service ready for new user.
Commande : AUTH TLS
Réponse : 234 Command AUTH okay; starting TLS connection.
Statut : Initialisation de TLS...
Statut : Vérification du certificat...
Commande : USER admin
Statut : Connexion TLS/SSL établie.
Réponse : 331 User name okay, need password for admin.
Commande : PASS *****
Réponse : 230 User logged in, proceed.
Commande : SYST
Réponse : 215 UNIX Type: Apache FtpServer
Commande : FEAT
Réponse : 211-Extensions supported
Réponse : SIZE
Réponse : MDTM
Réponse : REST STREAM
Réponse : LANG en;zh-tw;ja;is
Réponse : MLST Size;Modify;Type;Perm
Réponse : AUTH SSL
Réponse : AUTH TLS
Réponse : MODE Z
Réponse : UTF8
Réponse : TVFS
Réponse : MD5
Réponse : MMD5
Réponse : MFMT
Réponse : 211 End
Commande : OPTS UTF8 ON
Réponse : 200 Command OPTS okay.
Commande : PBSZ 0
Réponse : 200 Command PBSZ okay.
Commande : PROT P
Réponse : 200 Command PROT okay.
Commande : OPTS MLST size;modify;type;
Réponse : 501 Syntax error in parameters or arguments.
Statut : Connecté
Statut : Récupération du contenu du dossier...
Commande : PWD
Réponse : 257 "/" is current directory.
Commande : TYPE I
Réponse : 200 Command TYPE okay.
Commande : PASV
Réponse : 227 Entering Passive Mode (91,121,145,174,150,100)
Commande : MLSD
Réponse : 150 File status okay; about to open data connection.
Erreur : Délai d'attente expiré
Erreur : Impossible de récupérer le contenu du dossier
Here are logs side (server):
[ INFO] 2011-06-06 22:16:55,569 [] [88.171.186.112] CREATED
[ INFO] 2011-06-06 22:16:55,570 [] [88.171.186.112] OPENED
[ INFO] 2011-06-06 22:16:55,571 [] [88.171.186.112] SENT: 220 Service ready
for new user.
[ INFO] 2011-06-06 22:16:55,616 [] [88.171.186.112] RECEIVED: AUTH TLS
[ INFO] 2011-06-06 22:16:55,761 [] [88.171.186.112] SENT: 234 Command AUTH
okay; starting TLS connection.
[ INFO] 2011-06-06 22:16:56,059 [] [88.171.186.112] RECEIVED: USER admin
[ INFO] 2011-06-06 22:16:56,060 [admin] [88.171.186.112] SENT: 331 User name
okay, need password for admin.
[ INFO] 2011-06-06 22:16:56,108 [admin] [88.171.186.112] RECEIVED: PASS
*****
[ INFO] 2011-06-06 22:16:56,108 [admin] [88.171.186.112] Login success -
admin
[ INFO] 2011-06-06 22:16:56,109 [admin] [88.171.186.112] SENT: 230 User
logged in, proceed.
[ INFO] 2011-06-06 22:16:56,158 [admin] [88.171.186.112] RECEIVED: SYST
[ INFO] 2011-06-06 22:16:56,159 [admin] [88.171.186.112] SENT: 215 UNIX
Type: Apache FtpServer
[ INFO] 2011-06-06 22:16:56,209 [admin] [88.171.186.112] RECEIVED: FEAT
[ INFO] 2011-06-06 22:16:56,209 [admin] [88.171.186.112] 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] 2011-06-06 22:16:56,257 [admin] [88.171.186.112] RECEIVED: OPTS UTF8
ON
[ INFO] 2011-06-06 22:16:56,257 [admin] [88.171.186.112] SENT: 200 Command
OPTS okay.
[ INFO] 2011-06-06 22:16:56,303 [admin] [88.171.186.112] RECEIVED: PBSZ 0
[ INFO] 2011-06-06 22:16:56,303 [admin] [88.171.186.112] SENT: 200 Command
PBSZ okay.
[ INFO] 2011-06-06 22:16:56,350 [admin] [88.171.186.112] RECEIVED: PROT P
[ INFO] 2011-06-06 22:16:56,351 [admin] [88.171.186.112] SENT: 200 Command
PROT okay.
[ INFO] 2011-06-06 22:16:56,397 [admin] [88.171.186.112] RECEIVED: OPTS MLST
size;modify;type;
[ INFO] 2011-06-06 22:16:56,397 [admin] [88.171.186.112] SENT: 501 Syntax
error in parameters or arguments.
[ INFO] 2011-06-06 22:16:56,445 [admin] [88.171.186.112] RECEIVED: PWD
[ INFO] 2011-06-06 22:16:56,446 [admin] [88.171.186.112] SENT: 257 "/" is
current directory.
[ INFO] 2011-06-06 22:16:56,495 [admin] [88.171.186.112] RECEIVED: TYPE I
[ INFO] 2011-06-06 22:16:56,496 [admin] [88.171.186.112] SENT: 200 Command
TYPE okay.
[ INFO] 2011-06-06 22:16:56,541 [admin] [88.171.186.112] RECEIVED: PASV
[ INFO] 2011-06-06 22:16:56,541 [admin] [88.171.186.112] SENT: 227 Entering
Passive Mode (91,121,145,174,150,100)
[ INFO] 2011-06-06 22:16:56,586 [admin] [88.171.186.112] RECEIVED: MLSD
[ WARN] 2011-06-06 22:21:56,662 [admin] [88.171.186.112]
FtpDataConnection.getDataSocket()
java.net.SocketTimeoutException: Accept timed out
at java.net.PlainSocketImpl.socketAccept(Native Method)
at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:408)
at java.net.ServerSocket.implAccept(ServerSocket.java:462)
at java.net.ServerSocket.accept(ServerSocket.java:430)
at
org.apache.ftpserver.impl.IODataConnectionFactory.createDataSocket(IODataConnectionFactory.java:334)
at
org.apache.ftpserver.impl.IODataConnectionFactory.openConnection(IODataConnectionFactory.java:259)
at org.apache.ftpserver.command.impl.MLSD.execute(MLSD.java:96)
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:713)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
at
org.apache.ftpserver.listener.nio.FtpLoggingFilter.messageReceived(FtpLoggingFilter.java:85)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
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:434)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
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:434)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
at
org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
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:780)
at
org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTasks(OrderedThreadPoolExecutor.java:772)
at
org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.run(OrderedThreadPoolExecutor.java:714)
at java.lang.Thread.run(Thread.java:662)
[ INFO] 2011-06-06 22:21:56,662 [admin] [88.171.186.112] SENT: 150 File
status okay; about to open data connection.
[ INFO] 2011-06-06 22:21:56,662 [admin] [88.171.186.112] CLOSED
thx for your help.
Re: timeout problem in SSL (explicit) mode
Posted by David Latorre <dv...@gmail.com>.
Hello,
It seems that your client is not initiating the connection... most
probably, the returned IP address is not correct. Can you provide the
log file for a successful list command/ file transfer in non-SSL mode?
You didn't specify the FTPServer version you're using though.
2011/6/6 Pierre Etienne <pi...@gmail.com>:
> Hello, (sorryfor my English)
>
> I come towards you because I have a problem at the time of establishing an
> explicit connection SSL or even implicit. (No problem in normal mode (not
> SSL) ).
>
> Indeed, when I am under FileZilla at no time me see the list of files and I
> have a timeout which arrives.
>
> Here are logs FileZilla:
>
> Statut : Résolution de l'adresse de hinka.org
> Statut : Connexion à 91.121.145.174:21...
> Statut : Connexion établie, attente du message d'accueil...
> Réponse : 220 Service ready for new user.
> Commande : AUTH TLS
> Réponse : 234 Command AUTH okay; starting TLS connection.
> Statut : Initialisation de TLS...
> Statut : Vérification du certificat...
> Commande : USER admin
> Statut : Connexion TLS/SSL établie.
> Réponse : 331 User name okay, need password for admin.
> Commande : PASS *****
> Réponse : 230 User logged in, proceed.
> Commande : SYST
> Réponse : 215 UNIX Type: Apache FtpServer
> Commande : FEAT
> Réponse : 211-Extensions supported
> Réponse : SIZE
> Réponse : MDTM
> Réponse : REST STREAM
> Réponse : LANG en;zh-tw;ja;is
> Réponse : MLST Size;Modify;Type;Perm
> Réponse : AUTH SSL
> Réponse : AUTH TLS
> Réponse : MODE Z
> Réponse : UTF8
> Réponse : TVFS
> Réponse : MD5
> Réponse : MMD5
> Réponse : MFMT
> Réponse : 211 End
> Commande : OPTS UTF8 ON
> Réponse : 200 Command OPTS okay.
> Commande : PBSZ 0
> Réponse : 200 Command PBSZ okay.
> Commande : PROT P
> Réponse : 200 Command PROT okay.
> Commande : OPTS MLST size;modify;type;
> Réponse : 501 Syntax error in parameters or arguments.
> Statut : Connecté
> Statut : Récupération du contenu du dossier...
> Commande : PWD
> Réponse : 257 "/" is current directory.
> Commande : TYPE I
> Réponse : 200 Command TYPE okay.
> Commande : PASV
> Réponse : 227 Entering Passive Mode (91,121,145,174,150,100)
> Commande : MLSD
> Réponse : 150 File status okay; about to open data connection.
> Erreur : Délai d'attente expiré
> Erreur : Impossible de récupérer le contenu du dossier
>
>
> Here are logs side (server):
>
> [ INFO] 2011-06-06 22:16:55,569 [] [88.171.186.112] CREATED
> [ INFO] 2011-06-06 22:16:55,570 [] [88.171.186.112] OPENED
> [ INFO] 2011-06-06 22:16:55,571 [] [88.171.186.112] SENT: 220 Service ready
> for new user.
>
> [ INFO] 2011-06-06 22:16:55,616 [] [88.171.186.112] RECEIVED: AUTH TLS
> [ INFO] 2011-06-06 22:16:55,761 [] [88.171.186.112] SENT: 234 Command AUTH
> okay; starting TLS connection.
>
> [ INFO] 2011-06-06 22:16:56,059 [] [88.171.186.112] RECEIVED: USER admin
> [ INFO] 2011-06-06 22:16:56,060 [admin] [88.171.186.112] SENT: 331 User name
> okay, need password for admin.
>
> [ INFO] 2011-06-06 22:16:56,108 [admin] [88.171.186.112] RECEIVED: PASS
> *****
> [ INFO] 2011-06-06 22:16:56,108 [admin] [88.171.186.112] Login success -
> admin
> [ INFO] 2011-06-06 22:16:56,109 [admin] [88.171.186.112] SENT: 230 User
> logged in, proceed.
>
> [ INFO] 2011-06-06 22:16:56,158 [admin] [88.171.186.112] RECEIVED: SYST
> [ INFO] 2011-06-06 22:16:56,159 [admin] [88.171.186.112] SENT: 215 UNIX
> Type: Apache FtpServer
>
> [ INFO] 2011-06-06 22:16:56,209 [admin] [88.171.186.112] RECEIVED: FEAT
> [ INFO] 2011-06-06 22:16:56,209 [admin] [88.171.186.112] 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] 2011-06-06 22:16:56,257 [admin] [88.171.186.112] RECEIVED: OPTS UTF8
> ON
> [ INFO] 2011-06-06 22:16:56,257 [admin] [88.171.186.112] SENT: 200 Command
> OPTS okay.
>
> [ INFO] 2011-06-06 22:16:56,303 [admin] [88.171.186.112] RECEIVED: PBSZ 0
> [ INFO] 2011-06-06 22:16:56,303 [admin] [88.171.186.112] SENT: 200 Command
> PBSZ okay.
>
> [ INFO] 2011-06-06 22:16:56,350 [admin] [88.171.186.112] RECEIVED: PROT P
> [ INFO] 2011-06-06 22:16:56,351 [admin] [88.171.186.112] SENT: 200 Command
> PROT okay.
>
> [ INFO] 2011-06-06 22:16:56,397 [admin] [88.171.186.112] RECEIVED: OPTS MLST
> size;modify;type;
> [ INFO] 2011-06-06 22:16:56,397 [admin] [88.171.186.112] SENT: 501 Syntax
> error in parameters or arguments.
>
> [ INFO] 2011-06-06 22:16:56,445 [admin] [88.171.186.112] RECEIVED: PWD
> [ INFO] 2011-06-06 22:16:56,446 [admin] [88.171.186.112] SENT: 257 "/" is
> current directory.
>
> [ INFO] 2011-06-06 22:16:56,495 [admin] [88.171.186.112] RECEIVED: TYPE I
> [ INFO] 2011-06-06 22:16:56,496 [admin] [88.171.186.112] SENT: 200 Command
> TYPE okay.
>
> [ INFO] 2011-06-06 22:16:56,541 [admin] [88.171.186.112] RECEIVED: PASV
> [ INFO] 2011-06-06 22:16:56,541 [admin] [88.171.186.112] SENT: 227 Entering
> Passive Mode (91,121,145,174,150,100)
>
> [ INFO] 2011-06-06 22:16:56,586 [admin] [88.171.186.112] RECEIVED: MLSD
> [ WARN] 2011-06-06 22:21:56,662 [admin] [88.171.186.112]
> FtpDataConnection.getDataSocket()
> java.net.SocketTimeoutException: Accept timed out
> at java.net.PlainSocketImpl.socketAccept(Native Method)
> at java.net.PlainSocketImpl.accept(PlainSocketImpl.java:408)
> at java.net.ServerSocket.implAccept(ServerSocket.java:462)
> at java.net.ServerSocket.accept(ServerSocket.java:430)
> at
> org.apache.ftpserver.impl.IODataConnectionFactory.createDataSocket(IODataConnectionFactory.java:334)
> at
> org.apache.ftpserver.impl.IODataConnectionFactory.openConnection(IODataConnectionFactory.java:259)
> at org.apache.ftpserver.command.impl.MLSD.execute(MLSD.java:96)
> 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:713)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
> at
> org.apache.ftpserver.listener.nio.FtpLoggingFilter.messageReceived(FtpLoggingFilter.java:85)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain.callNextMessageReceived(DefaultIoFilterChain.java:434)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
> 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:434)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
> 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:434)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain.access$1200(DefaultIoFilterChain.java:46)
> at
> org.apache.mina.core.filterchain.DefaultIoFilterChain$EntryImpl$1.messageReceived(DefaultIoFilterChain.java:793)
> 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:780)
> at
> org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.runTasks(OrderedThreadPoolExecutor.java:772)
> at
> org.apache.mina.filter.executor.OrderedThreadPoolExecutor$Worker.run(OrderedThreadPoolExecutor.java:714)
> at java.lang.Thread.run(Thread.java:662)
> [ INFO] 2011-06-06 22:21:56,662 [admin] [88.171.186.112] SENT: 150 File
> status okay; about to open data connection.
>
> [ INFO] 2011-06-06 22:21:56,662 [admin] [88.171.186.112] CLOSED
>
>
>
> thx for your help.
>