FTP timeouts

Hi,

I'm undergoing constant timeouts on FTP connections using WinSCP Beta 4.0.2. Though I've lowered timeout an keepalive settings to fairly minimum values, I'm still getting timeout messages, as follows:

"Timeout detected.
Type set to A."

or

"Timeout detected.
Type set to I."

or

"Timeout detected.
Restarting at 0. Send STORE or RETRIEVE to initiate transfer."

Using, for instance CuteFTP Pro, I was completely unaware of this issue. I don't know how it manage to maintain stable connections transparently, but I think WinSCP can do better than all other commercial programs.


Blessings,

Bar.

Please post a log file.

Hi,

Here you are my log file showing timeouts issues:

*************************************************************

. 2007-07-02 09:51:27.294 [Background 1] Session upkeep
. 2007-07-02 09:51:27.794 Session upkeep
> 2007-07-02 09:51:27.794 PWD
. 2007-07-02 09:51:27.794 Timeout detected.
. 2007-07-02 09:51:27.794 FtpControlSocket.cpp(1199): DoClose(0) OpMode=0 OpState=-1 caller=0x01d29564
. 2007-07-02 09:51:27.794 FtpControlSocket.cpp(4180): ResetOperation(4100) OpMode=0 OpState=-1 caller=0x01d29564
. 2007-07-02 09:51:27.794 Got reply 1000 to the command 0
* 2007-07-02 09:51:27.794 (ESshFatal) Lost connection.
* 2007-07-02 09:51:27.794 Timeout detected.
* 2007-07-02 09:51:27.794 Type set to A.
. 2007-07-02 09:51:37.341 Connecting to 172.18.237.192 ...
. 2007-07-02 09:51:37.341 FtpControlSocket.cpp(1056): OnConnect(0) OpMode=1 OpState=-1 caller=0x01d29564
. 2007-07-02 09:51:37.356 Connected with 172.18.237.192. Waiting for welcome message...
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=-1 caller=0x01d29564
< 2007-07-02 09:51:37.356 220 nocPM02 FTP server (Digital UNIX Version 5.60) ready.
> 2007-07-02 09:51:37.356 USER xmate
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=0 caller=0x01d29564
< 2007-07-02 09:51:37.356 331 Password required for xmate.
> 2007-07-02 09:51:37.356 PASS **********
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=3 caller=0x01d29564
< 2007-07-02 09:51:37.356 230 User xmate logged in.
> 2007-07-02 09:51:37.356 SYST
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=-14 caller=0x01d29564
< 2007-07-02 09:51:37.356 215 UNIX Type: L8 Version: Digital UNIX V4.0 (Rev. 1229)
> 2007-07-02 09:51:37.356 FEAT
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=-13 caller=0x01d29564
< 2007-07-02 09:51:37.356 500 'FEAT': command not understood.
. 2007-07-02 09:51:37.356 Connected
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(4180): ResetOperation(1) OpMode=1 OpState=-13 caller=0x01d29564
. 2007-07-02 09:51:37.356 Got reply 1 to the command 1
. 2007-07-02 09:51:37.356 Doing startup conversation with host.
. 2007-07-02 09:51:37.356 Changing directory to "/usr/temip_homes/xmate".
. 2007-07-02 09:51:37.356 Synchronizing current directory "/usr/temip_homes/xmate".
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(1269): FtpCommand(CWD /usr/temip_homes/xmate) OpMode=0 OpState=-1 caller=0x01d29564
> 2007-07-02 09:51:37.356 CWD /usr/temip_homes/xmate
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=2 OpState=-1 caller=0x01d29564
< 2007-07-02 09:51:37.356 250 CWD command successful.
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(4180): ResetOperation(1) OpMode=2 OpState=-1 caller=0x01d29564
. 2007-07-02 09:51:37.356 Got reply 1 to the command 16
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(1269): FtpCommand(CWD /usr/temip_homes/xmate) OpMode=0 OpState=-1 caller=0x01d29564
> 2007-07-02 09:51:37.356 CWD /usr/temip_homes/xmate
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=2 OpState=-1 caller=0x01d29564
< 2007-07-02 09:51:37.356 250 CWD command successful.
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(4180): ResetOperation(1) OpMode=2 OpState=-1 caller=0x01d29564
. 2007-07-02 09:51:37.356 Got reply 1 to the command 16
. 2007-07-02 09:51:37.356 Getting current directory name.
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(1269): FtpCommand(PWD) OpMode=0 OpState=-1 caller=0x01d29564
> 2007-07-02 09:51:37.356 PWD
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=2 OpState=-1 caller=0x01d29564
< 2007-07-02 09:51:37.356 257 "/usr/temip_homes/xmate" is current directory.
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(4180): ResetOperation(1) OpMode=2 OpState=-1 caller=0x01d29564
. 2007-07-02 09:51:37.356 Got reply 1 to the command 16
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(1276): List(FALSE,0,"/usr/temip_homes/xmate/","",1) OpMode=0 OpState=-1 caller=0x01d29564
. 2007-07-02 09:51:37.356 Retrieving directory listing...
> 2007-07-02 09:51:37.356 TYPE A
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(878): OnReceive(0) OpMode=4 OpState=8 caller=0x01d29564
< 2007-07-02 09:51:37.356 200 Type set to A.
. 2007-07-02 09:51:37.356 FtpControlSocket.cpp(1276): List(FALSE,0,"","",0) OpMode=4 OpState=8 caller=0x01d29564
> 2007-07-02 09:51:47.465 PASV
. 2007-07-02 09:51:47.465 FtpControlSocket.cpp(878): OnReceive(0) OpMode=4 OpState=7 caller=0x01d29564
< 2007-07-02 09:51:47.465 227 Entering Passive Mode (172,18,237,192,11,184)
. 2007-07-02 09:51:47.465 FtpControlSocket.cpp(1276): List(FALSE,0,"","",0) OpMode=4 OpState=7 caller=0x01d29564
. 2007-07-02 09:51:47.465 TransferSocket.cpp(597): SetActive() caller=0x11fe2764
> 2007-07-02 09:51:47.465 LIST -a
. 2007-07-02 09:51:47.465 TransferSocket.cpp(481): OnConnect(0) caller=0x11fe2764
. 2007-07-02 09:51:47.465 FtpControlSocket.cpp(878): OnReceive(0) OpMode=4 OpState=9 caller=0x01d29564
< 2007-07-02 09:51:47.465 150 Opening ASCII mode data connection for /bin/ls (0.0.0.0,0).
. 2007-07-02 09:51:47.465 FtpControlSocket.cpp(1276): List(FALSE,0,"","",0) OpMode=4 OpState=9 caller=0x01d29564
. 2007-07-02 09:51:47.622 TransferSocket.cpp(557): OnClose(0) caller=0x11fe2764
. 2007-07-02 09:51:47.622 TransferSocket.cpp(1179): Close() caller=0x11fe2764
. 2007-07-02 09:51:47.622 TransferSocket.cpp(1179): Close() caller=0x11fe2764
. 2007-07-02 09:51:47.637 FtpControlSocket.cpp(2070): TransferEnd(4) OpMode=4 OpState=10 caller=0x01d29564
. 2007-07-02 09:51:47.637 FtpControlSocket.cpp(1276): List(TRUE,0,"","",0) OpMode=4 OpState=10 caller=0x01d29564

*************************************************************

. 2007-07-02 09:51:48.122 [Background 1] Session upkeep
> 2007-07-02 09:51:48.122 [Background 1] TYPE I
. 2007-07-02 09:51:48.122 [Background 1] Timeout detected.
. 2007-07-02 09:51:48.122 [Background 1] FtpControlSocket.cpp(1199): DoClose(0) OpMode=0 OpState=-1 caller=0x01d80830
. 2007-07-02 09:51:48.122 [Background 1] FtpControlSocket.cpp(4180): ResetOperation(4100) OpMode=0 OpState=-1 caller=0x01d80830
. 2007-07-02 09:51:48.122 [Background 1] Got reply 1000 to the command 0
. 2007-07-02 09:51:48.294 Session upkeep

*************************************************************

. 2007-07-02 09:52:02.293 Session upkeep
> 2007-07-02 09:52:02.293 REST 0
. 2007-07-02 09:52:02.293 Timeout detected.
. 2007-07-02 09:52:02.293 FtpControlSocket.cpp(1199): DoClose(0) OpMode=0 OpState=-1 caller=0x01d29564
. 2007-07-02 09:52:02.293 FtpControlSocket.cpp(4180): ResetOperation(4100) OpMode=0 OpState=-1 caller=0x01d29564
. 2007-07-02 09:52:02.293 Got reply 1000 to the command 0
* 2007-07-02 09:52:02.293 (ESshFatal) Lost connection.
* 2007-07-02 09:52:02.293 Timeout detected.
. 2007-07-02 09:52:05.840 Connecting to 172.18.237.192 ...
. 2007-07-02 09:52:05.840 FtpControlSocket.cpp(1056): OnConnect(0) OpMode=1 OpState=-1 caller=0x01d29564
. 2007-07-02 09:52:05.855 Connected with 172.18.237.192. Waiting for welcome message...
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=-1 caller=0x01d29564
< 2007-07-02 09:52:05.855 220 nocPM02 FTP server (Digital UNIX Version 5.60) ready.
> 2007-07-02 09:52:05.855 USER xmate
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=0 caller=0x01d29564
< 2007-07-02 09:52:05.855 331 Password required for xmate.
> 2007-07-02 09:52:05.855 PASS **********
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=3 caller=0x01d29564
< 2007-07-02 09:52:05.855 230 User xmate logged in.
> 2007-07-02 09:52:05.855 SYST
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=-14 caller=0x01d29564
< 2007-07-02 09:52:05.855 215 UNIX Type: L8 Version: Digital UNIX V4.0 (Rev. 1229)
> 2007-07-02 09:52:05.855 FEAT
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(878): OnReceive(0) OpMode=1 OpState=-13 caller=0x01d29564
< 2007-07-02 09:52:05.855 500 'FEAT': command not understood.
. 2007-07-02 09:52:05.855 Connected
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(4180): ResetOperation(1) OpMode=1 OpState=-13 caller=0x01d29564
. 2007-07-02 09:52:05.855 Got reply 1 to the command 1
. 2007-07-02 09:52:05.855 Doing startup conversation with host.
. 2007-07-02 09:52:05.855 Changing directory to "/usr/temip_homes/xmate".
. 2007-07-02 09:52:05.855 Synchronizing current directory "/usr/temip_homes/xmate".
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(1269): FtpCommand(CWD /usr/temip_homes/xmate) OpMode=0 OpState=-1 caller=0x01d29564
> 2007-07-02 09:52:05.855 CWD /usr/temip_homes/xmate
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(878): OnReceive(0) OpMode=2 OpState=-1 caller=0x01d29564
< 2007-07-02 09:52:05.855 250 CWD command successful.
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(4180): ResetOperation(1) OpMode=2 OpState=-1 caller=0x01d29564
. 2007-07-02 09:52:05.855 Got reply 1 to the command 16
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(1269): FtpCommand(CWD /usr/temip_homes/xmate) OpMode=0 OpState=-1 caller=0x01d29564
> 2007-07-02 09:52:05.855 CWD /usr/temip_homes/xmate
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(878): OnReceive(0) OpMode=2 OpState=-1 caller=0x01d29564
< 2007-07-02 09:52:05.855 250 CWD command successful.
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(4180): ResetOperation(1) OpMode=2 OpState=-1 caller=0x01d29564
. 2007-07-02 09:52:05.855 Got reply 1 to the command 16
. 2007-07-02 09:52:05.855 Getting current directory name.
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(1269): FtpCommand(PWD) OpMode=0 OpState=-1 caller=0x01d29564
> 2007-07-02 09:52:05.855 PWD
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(878): OnReceive(0) OpMode=2 OpState=-1 caller=0x01d29564
< 2007-07-02 09:52:05.855 257 "/usr/temip_homes/xmate" is current directory.
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(4180): ResetOperation(1) OpMode=2 OpState=-1 caller=0x01d29564
. 2007-07-02 09:52:05.855 Got reply 1 to the command 16
. 2007-07-02 09:52:05.855 FtpControlSocket.cpp(1276): List(FALSE,0,"/usr/temip_homes/xmate/","",1) OpMode=0 OpState=-1 caller=0x01d29564
. 2007-07-02 09:52:05.855 Retrieving directory listing...
> 2007-07-02 09:52:05.855 TYPE A
. 2007-07-02 09:52:05.871 FtpControlSocket.cpp(878): OnReceive(0) OpMode=4 OpState=8 caller=0x01d29564
< 2007-07-02 09:52:05.871 200 Type set to A.
. 2007-07-02 09:52:05.871 FtpControlSocket.cpp(1276): List(FALSE,0,"","",0) OpMode=4 OpState=8 caller=0x01d29564
> 2007-07-02 09:52:05.871 PASV
. 2007-07-02 09:52:05.871 FtpControlSocket.cpp(878): OnReceive(0) OpMode=4 OpState=7 caller=0x01d29564
< 2007-07-02 09:52:05.871 227 Entering Passive Mode (172,18,237,192,11,187)
. 2007-07-02 09:52:05.871 FtpControlSocket.cpp(1276): List(FALSE,0,"","",0) OpMode=4 OpState=7 caller=0x01d29564
. 2007-07-02 09:52:05.871 TransferSocket.cpp(597): SetActive() caller=0x12484690
> 2007-07-02 09:52:05.871 LIST -a
. 2007-07-02 09:52:05.886 TransferSocket.cpp(481): OnConnect(0) caller=0x12484690
. 2007-07-02 09:52:05.886 FtpControlSocket.cpp(878): OnReceive(0) OpMode=4 OpState=9 caller=0x01d29564
< 2007-07-02 09:52:05.886 150 Opening ASCII mode data connection for /bin/ls (0.0.0.0,0).
. 2007-07-02 09:52:05.886 FtpControlSocket.cpp(1276): List(FALSE,0,"","",0) OpMode=4 OpState=9 caller=0x01d29564
. 2007-07-02 09:52:05.886 TransferSocket.cpp(557): OnClose(0) caller=0x12484690
. 2007-07-02 09:52:05.933 TransferSocket.cpp(1179): Close() caller=0x12484690
. 2007-07-02 09:52:05.980 TransferSocket.cpp(1179): Close() caller=0x12484690
. 2007-07-02 09:52:05.980 FtpControlSocket.cpp(878): OnReceive(0) OpMode=4 OpState=10 caller=0x01d29564
< 2007-07-02 09:52:05.980 226 Transfer complete.
. 2007-07-02 09:52:05.980 FtpControlSocket.cpp(2070): TransferEnd(4) OpMode=4 OpState=10 caller=0x01d29564
. 2007-07-02 09:52:05.980 FtpControlSocket.cpp(1276): List(TRUE,0,"","",0) OpMode=4 OpState=10 caller=0x01d29564

*************************************************************


Blessings,

Bar.

Can you try to disable keepalives?

would you kindly tell me prikryl how can i disable keepalives?
cheers.

Please read documentation:
https://winscp.net/eng/docs/ui_login_connection