FTPS large files Timeout detected (control connection)
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:
– 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:
Any thoughts?
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.
