Post a reply

Before posting, please read how to report bug or request support effectively.

Bug reports without an attached log file are usually useless.

Username

Subject

Message body

Font colour:

Font size:

[quote="dweste"]When transferring large files to a FileZilla FTPS (Implicit TLS/SSL) server, and the transfer is 99% complete, the control connection times out and the transfer aborts. Small files work like a charm.

I tried about everything I could find on the WinSCP site:
– Increased all timeouts, client and serverside
– Tested other versions of WinSCP (5.76, 5.77, and the latest beta)
– rawsettings: [m]SendBuf=0 SshSimple=0 PingType=2 PingIntervalSecs=15[/m]
– Disabled Application Gateway Layer service on the server

Nothing worked so far. After reading [url=https://lists.apache.org/thread/9nb1gc07ybkq56mh2574wo9r00g55fm4]this topic[/url], I suspect the control connection is not being kept alive by WinSCP. The large file takes too much time (about 15 minutes), and the control connection times out.

I setup a small test with only the large file, to reduce unnecessary clutter in the logfile. I attached the complete logfile, and this is the part where the problem occurs: 
[code]@sessionlog@
. 2016-03-14 10:19:57.807 Data connection opened
. 2016-03-14 10:19:57.807 Trying reuse main TLS session ID
. 2016-03-14 10:19:57.807 TLS layer changed state from none to connected
< 2016-03-14 10:19:57.811 150 Opening data channel for directory listing of "/"
. 2016-03-14 10:19:57.812 Session ID reused
. 2016-03-14 10:19:57.813 TLS connection established
. 2016-03-14 10:19:57.819 type=file;modify=20160314091944;size=346072288; Vitrite.rar
< 2016-03-14 10:19:57.819 226 Successfully transferred "/"
. 2016-03-14 10:19:57.819 Directory listing successful
. 2016-03-14 10:19:57.819 Got reply 1 to the command 2
. 2016-03-14 10:19:57.819 ..;D;0;1899-12-30T01:00:00.000Z;"" [0];"" [0];---------;0
. 2016-03-14 10:19:57.819 Vitrite.rar;-;346072288;2016-03-14T09:19:44.000Z;"" [0];"" [0];---------;0
. 2016-03-14 10:19:58.059 Local file 'D:\Backup\Vitrite.rar' [2009-03-03T19:12:26.312Z] [689869262] is modified comparing to remote file '/Vitrite.rar' [2016-03-14T09:19:44.000Z] [346072288]
< 2016-03-14 10:19:58.059 Script: Synchronizing...
. 2016-03-14 10:19:58.060 Synchronizing local directory 'D:\Backup\' with remote directory '/', params = 0x1002 (NoConfirmation, Mirror)
. 2016-03-14 10:19:58.060 Copying 1 files/directories to remote directory "/"
. 2016-03-14 10:19:58.060   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: N (102400); CalcS: No; Mask: *.*
. 2016-03-14 10:19:58.060   TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; InclM: ; ResumeL: 0
. 2016-03-14 10:19:58.060   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2016-03-14 10:19:58.060 File: 'D:\Backup\Vitrite.rar' [2009-03-03T19:12:26.312Z] [689869262]
< 2016-03-14 10:19:58.061 Script: Local 'D:\Backup' => Remote '/'
. 2016-03-14 10:19:58.061 Copying "D:\Backup\Vitrite.rar" to remote directory started.
. 2016-03-14 10:19:58.061 Binary transfer mode selected.
. 2016-03-14 10:19:58.062 Starting upload of D:\Backup\Vitrite.rar
> 2016-03-14 10:19:58.062 TYPE I
< 2016-03-14 10:19:58.066 200 Type set to I
> 2016-03-14 10:19:58.066 PASV
< 2016-03-14 10:19:58.070 227 Entering Passive Mode (xxx,xxx,xxx,xxx,x,xxx)
> 2016-03-14 10:19:58.071 STOR Vitrite.rar
. 2016-03-14 10:19:58.076 Data connection opened
< 2016-03-14 10:19:58.123 150 Opening data channel for file upload to server of "/Vitrite.rar"
. 2016-03-14 10:19:58.123 Data connection opened
. 2016-03-14 10:19:58.123 Trying reuse main TLS session ID
. 2016-03-14 10:19:58.123 TLS layer changed state from none to connected
. 2016-03-14 10:19:58.128 Session ID reused
. 2016-03-14 10:19:58.129 TLS connection established
. 2016-03-14 10:36:09.552 Timeout detected. (control connection)
. 2016-03-14 10:36:09.552 Copying files to remote side failed.
. 2016-03-14 10:36:09.552 Got reply 1004 to the command 4
. 2016-03-14 10:36:09.553 Connection was lost, asking what to do.
. 2016-03-14 10:36:09.553 Asking user:
. 2016-03-14 10:36:09.553 Lost connection. ("Timeout detected. (control connection)","Copying files to remote side failed.")
< 2016-03-14 10:36:09.553 Script: Lost connection.
< 2016-03-14 10:36:09.553 Script: Timeout detected. (control connection)
< 2016-03-14 10:36:09.553 Copying files to remote side failed.
[/code]
Any thoughts?[/quote]

Options

Disable BBCode in this post

Notify me when a reply is posted (registered users only)

Add an Attachment

If you do not want to add an Attachment to your Post, please leave the Fields blank.

Filename

(maximum 10 MB; please compress large files; only common media, archive, text and programming file formats are allowed)

File Comment

Options

Private file (available to author and site moderators only)

Topic review

martin

Re: FTPS large files Timeout detected (control connection)

2016-03-17

Does the file keep uploading? Or dies it hang and then timeout? Do you have the same problem with other FTP clients?

If the first, the problem is typically a broken firewall/proxy/NAT that aborts the FTP control connection as it sees no traffic during the file transfer. There's no way to keep the control connection alive during transfer. It would violate the FTP protocol specification.

dweste

FTPS large files Timeout detected (control connection)

2016-03-14 12:02

When transferring large files to a FileZilla FTPS (Implicit TLS/SSL) server, and the transfer is 99% complete, the control connection times out and the transfer aborts. Small files work like a charm.

I tried about everything I could find on the WinSCP site:
– Increased all timeouts, client and serverside
– Tested other versions of WinSCP (5.76, 5.77, and the latest beta)
– rawsettings: SendBuf=0 SshSimple=0 PingType=2 PingIntervalSecs=15
– Disabled Application Gateway Layer service on the server

Nothing worked so far. After reading this topic, I suspect the control connection is not being kept alive by WinSCP. The large file takes too much time (about 15 minutes), and the control connection times out.

I setup a small test with only the large file, to reduce unnecessary clutter in the logfile. I attached the complete logfile, and this is the part where the problem occurs:

. 2016-03-14 10:19:57.807 Data connection opened
. 2016-03-14 10:19:57.807 Trying reuse main TLS session ID
. 2016-03-14 10:19:57.807 TLS layer changed state from none to connected
< 2016-03-14 10:19:57.811 150 Opening data channel for directory listing of "/"
. 2016-03-14 10:19:57.812 Session ID reused
. 2016-03-14 10:19:57.813 TLS connection established
. 2016-03-14 10:19:57.819 type=file;modify=20160314091944;size=346072288; Vitrite.rar
< 2016-03-14 10:19:57.819 226 Successfully transferred "/"
. 2016-03-14 10:19:57.819 Directory listing successful
. 2016-03-14 10:19:57.819 Got reply 1 to the command 2
. 2016-03-14 10:19:57.819 ..;D;0;1899-12-30T01:00:00.000Z;"" [0];"" [0];---------;0
. 2016-03-14 10:19:57.819 Vitrite.rar;-;346072288;2016-03-14T09:19:44.000Z;"" [0];"" [0];---------;0
. 2016-03-14 10:19:58.059 Local file 'D:\Backup\Vitrite.rar' [2009-03-03T19:12:26.312Z] [689869262] is modified comparing to remote file '/Vitrite.rar' [2016-03-14T09:19:44.000Z] [346072288]
< 2016-03-14 10:19:58.059 Script: Synchronizing...
. 2016-03-14 10:19:58.060 Synchronizing local directory 'D:\Backup\' with remote directory '/', params = 0x1002 (NoConfirmation, Mirror)
. 2016-03-14 10:19:58.060 Copying 1 files/directories to remote directory "/"
. 2016-03-14 10:19:58.060   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: N (102400); CalcS: No; Mask: *.*
. 2016-03-14 10:19:58.060   TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; InclM: ; ResumeL: 0
. 2016-03-14 10:19:58.060   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2016-03-14 10:19:58.060 File: 'D:\Backup\Vitrite.rar' [2009-03-03T19:12:26.312Z] [689869262]
< 2016-03-14 10:19:58.061 Script: Local 'D:\Backup' => Remote '/'
. 2016-03-14 10:19:58.061 Copying "D:\Backup\Vitrite.rar" to remote directory started.
. 2016-03-14 10:19:58.061 Binary transfer mode selected.
. 2016-03-14 10:19:58.062 Starting upload of D:\Backup\Vitrite.rar
> 2016-03-14 10:19:58.062 TYPE I
< 2016-03-14 10:19:58.066 200 Type set to I
> 2016-03-14 10:19:58.066 PASV
< 2016-03-14 10:19:58.070 227 Entering Passive Mode (xxx,xxx,xxx,xxx,x,xxx)
> 2016-03-14 10:19:58.071 STOR Vitrite.rar
. 2016-03-14 10:19:58.076 Data connection opened
< 2016-03-14 10:19:58.123 150 Opening data channel for file upload to server of "/Vitrite.rar"
. 2016-03-14 10:19:58.123 Data connection opened
. 2016-03-14 10:19:58.123 Trying reuse main TLS session ID
. 2016-03-14 10:19:58.123 TLS layer changed state from none to connected
. 2016-03-14 10:19:58.128 Session ID reused
. 2016-03-14 10:19:58.129 TLS connection established
. 2016-03-14 10:36:09.552 Timeout detected. (control connection)
. 2016-03-14 10:36:09.552 Copying files to remote side failed.
. 2016-03-14 10:36:09.552 Got reply 1004 to the command 4
. 2016-03-14 10:36:09.553 Connection was lost, asking what to do.
. 2016-03-14 10:36:09.553 Asking user:
. 2016-03-14 10:36:09.553 Lost connection. ("Timeout detected. (control connection)","Copying files to remote side failed.")
< 2016-03-14 10:36:09.553 Script: Lost connection.
< 2016-03-14 10:36:09.553 Script: Timeout detected. (control connection)
< 2016-03-14 10:36:09.553 Copying files to remote side failed.

Any thoughts?

Post a reply

Topic review

Re: FTPS large files Timeout detected (control connection)

FTPS large files Timeout detected (control connection)

Documentation

Support

Associations

Follow Us