WinSCP 5.9.1 breaks connection during file copy :: Support Forum

miso
Joined:: 2016-08-25
Posts:: 3

WinSCP 5.9.1 breaks connection during file copy

2016-08-25 12:09

Hi,
Previous version of WinSCP 5.1.5 works correctly. With new version 5.9.1 I have problem with file copy.

Generally problem depends on file size. Smaller files under 30KB are copied OK, bigger over 38KB broke connection with message in attached file.

Log is as follow:

2016-08-25 13:04:30.137 WinSCP: this is begin-of-file
. 2016-08-25 13:04:30.160 SCP remote side confirmation (0)
. 2016-08-25 13:04:30.171 Asking user:
. 2016-08-25 13:04:30.171 **Overwrite remote file 'zprae_nm.csv'?**
. 2016-08-25 13:04:30.171 
. 2016-08-25 13:04:30.171 Destination directory already contains file 'zprae_nm.csv'.
. 2016-08-25 13:04:30.171 Choose, if you want to overwrite the file or skip this transfer and keep existing file.
. 2016-08-25 13:04:30.171  
. 2016-08-25 13:04:30.171 New:         29 828 bytes, 12.01.2016 15:06:30
. 2016-08-25 13:04:30.171 Existing:    24 952 bytes, 19.08.2015 13:24:21 ()
. 2016-08-25 13:04:31.166 File: "E:\Workplace\ICD\SZR9\zprae_nm.csv"
. 2016-08-25 13:04:31.167 File: 'E:\Workplace\ICD\SZR9\zprae_nm.csv' [2016-01-12T14:06:30.997Z] [29828]
. 2016-08-25 13:04:31.167 Copying "E:\Workplace\ICD\SZR9\zprae_nm.csv" to remote directory started.
. 2016-08-25 13:04:31.167 Binary transfer mode selected.
> 2016-08-25 13:04:31.181 T1452607590 0 1438952469 0
. 2016-08-25 13:04:31.183 SCP remote side confirmation (0)
> 2016-08-25 13:04:31.183 C0644 29828 zprae_nm.csv
. 2016-08-25 13:04:31.187 SCP remote side confirmation (0)
. 2016-08-25 13:04:31.188 Sending BINARY data (first block, 29828 bytes)
. 2016-08-25 13:04:31.188 Sending NULL.
. 2016-08-25 13:04:31.220 SCP remote side confirmation (0)
. 2016-08-25 13:04:31.220 Transfer done: 'E:\Workplace\ICD\SZR9\zprae_nm.csv' [29828]
. 2016-08-25 13:04:31.220 Copying "E:\Workplace\ICD\SZR9\zprae_nm.csv" to remote directory finished.
> 2016-08-25 13:04:31.220 E
. 2016-08-25 13:04:31.222 SCP remote side confirmation (0)
< 2016-08-25 13:04:31.225 WinSCP: this is end-of-file:0
. 2016-08-25 13:04:31.225 Listing current directory.
> 2016-08-25 13:04:31.225 ls -la --full-time ; echo "WinSCP: this is end-of-file:$?"
< 2016-08-25 13:04:31.291 total 56
< 2016-08-25 13:04:31.291 drwxr-xr-x 2 iec iec   140 2016-08-25 13:03:08.069591735 +0200 .
< 2016-08-25 13:04:31.291 drwxr-xr-x 3 iec iec   200 2016-08-25 13:02:33.479542425 +0200 ..
< 2016-08-25 13:04:31.291 -rw-r--r-- 1 iec iec  1305 2015-04-14 13:54:34.000000000 +0200 goose_time_mlb2.xml
< 2016-08-25 13:04:31.291 -rw-r--r-- 1 iec iec     0 2016-08-25 13:02:39.459550951 +0200 zprae.icd
< 2016-08-25 13:04:31.291 -rw-r--r-- 1 iec iec 10012 2016-05-04 12:24:57.000000000 +0200 zprae_dm.csv
< 2016-08-25 13:04:31.291 -rw-r--r-- 1 iec iec  8192 2015-08-19 13:24:21.000000000 +0200 zprae_im.csv
< 2016-08-25 13:04:31.291 -rw-r--r-- 1 iec iec 29828 2016-01-12 15:06:30.000000000 +0100 zprae_nm.csv
< 2016-08-25 13:04:31.293 WinSCP: this is end-of-file:0
. 2016-08-25 13:04:31.293 ..;d;0;2016-08-25T11:02:33.000Z;3;"iec" [0];"iec" [0];rwxr-xr-x;0
. 2016-08-25 13:04:31.293 goose_time_mlb2.xml;-;1305;2015-04-14T11:54:34.000Z;3;"iec" [0];"iec" [0];rw-r--r--;0
. 2016-08-25 13:04:31.293 zprae.icd;-;0;2016-08-25T11:02:39.000Z;3;"iec" [0];"iec" [0];rw-r--r--;0
. 2016-08-25 13:04:31.294 zprae_dm.csv;-;10012;2016-05-04T10:24:57.000Z;3;"iec" [0];"iec" [0];rw-r--r--;0
. 2016-08-25 13:04:31.294 zprae_im.csv;-;8192;2015-08-19T11:24:21.000Z;3;"iec" [0];"iec" [0];rw-r--r--;0
. 2016-08-25 13:04:31.294 zprae_nm.csv;-;29828;2016-01-12T14:06:30.000Z;3;"iec" [0];"iec" [0];rw-r--r--;0
. 2016-08-25 13:04:36.429 Copying 1 files/directories to remote directory "/home/iec/icd/SZR9/nowy/"
. 2016-08-25 13:04:36.429   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: Yes; Mask: *.*
. 2016-08-25 13:04:36.429   TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; InclM: ; ResumeL: 0
. 2016-08-25 13:04:36.429   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
> 2016-08-25 13:04:36.429 echo "WinSCP: this is begin-of-file" ; scp -r  -d -t "/home/iec/icd/SZR9/nowy" ; echo "WinSCP: this is end-of-file:$?"
< 2016-08-25 13:04:36.436 WinSCP: this is begin-of-file
. 2016-08-25 13:04:36.463 SCP remote side confirmation (0)
. 2016-08-25 13:04:36.463 Asking user:
. 2016-08-25 13:04:36.463 **Overwrite remote file 'zprae_dm.csv'?**
. 2016-08-25 13:04:36.463 
. 2016-08-25 13:04:36.463 Destination directory already contains file 'zprae_dm.csv'.
. 2016-08-25 13:04:36.463 Choose, if you want to overwrite the file or skip this transfer and keep existing file.
. 2016-08-25 13:04:36.463  
. 2016-08-25 13:04:36.463 New:         38 808 bytes, 12.01.2016 15:06:30
. 2016-08-25 13:04:36.463 Existing:    10 012 bytes, 04.05.2016 12:24:57 ()
. 2016-08-25 13:04:37.279 File: "E:\Workplace\ICD\SZR9\zprae_dm.csv"
. 2016-08-25 13:04:37.280 File: 'E:\Workplace\ICD\SZR9\zprae_dm.csv' [2016-01-12T14:06:30.994Z] [38808]
. 2016-08-25 13:04:37.280 Copying "E:\Workplace\ICD\SZR9\zprae_dm.csv" to remote directory started.
. 2016-08-25 13:04:37.280 Binary transfer mode selected.
> 2016-08-25 13:04:37.280 T1452607590 0 1448447552 0
. 2016-08-25 13:04:37.282 SCP remote side confirmation (0)
> 2016-08-25 13:04:37.282 C0644 38808 zprae_dm.csv
. 2016-08-25 13:04:37.285 SCP remote side confirmation (0)
. 2016-08-25 13:04:37.285 Sending BINARY data (first block, 32768 bytes)
. 2016-08-25 13:04:37.286 Sending NULL.
. 2016-08-25 13:04:37.326 Network error: Software caused connection abort
* 2016-08-25 13:04:37.332 (ESshFatal) Network error: Software caused connection abort
* 2016-08-25 13:04:37.332 Copying file 'E:\Workplace\ICD\SZR9\zprae_dm.csv' fatally failed.
* 2016-08-25 13:04:37.332 Copying files to remote side failed.

Is this a known issue?

Regards
Mirek

winscp_error.jpg (25.92 KB)

Description: Error message

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 28,507
Location:: Prague, Czechia

Re: WinSCP 5.9.1 breaks connection during file copy

2016-08-28

Please attach a complete session log file from both versions.

Reply with quote

Guest

Re: WinSCP 5.9.1 breaks connection during file copy

2016-08-29 08:22

martin wrote:

Please attach a complete session log file from both versions.

I attach logs from:

System info:
local host: windows 10 or windows 7
remote host: linux arm5tejl, kernel 2.6.35, dropbear sshd v0.52

ver_5_9_1_192.168.4.18_ok.log (16.39 KB)

Description: 5.9.1 - OK file size 17KB

ver_5_9_1_192.168.4.18_err.log (15.23 KB)

Description: 5.9.1 - ERR file size 73KB

ver_5_7_7_192.168.4.18.log (15.33 KB)

Description: 5.7.7 - OK file size 73KB

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 28,507
Location:: Prague, Czechia

Re: WinSCP 5.9.1 breaks connection during file copy

2016-09-02

Thanks for the logs. Since 5.8 WinSCP uses a larger transfer buffer, what your embedded (?) SSH server may not be able cope with.
Did you try using SFTP protocol?

Reply with quote

miso
Joined:: 2016-08-25
Posts:: 3

Re: WinSCP 5.9.1 breaks connection during file copy

2016-09-12 13:10

martin wrote:

Thanks for the logs. Since 5.8 WinSCP uses a larger transfer buffer, what your embedded (?) SSH server may not be able cope with.
Did you try using SFTP protocol?

Our dropbear doesn't support sftp.

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 28,507
Location:: Prague, Czechia

Re: WinSCP 5.9.1 breaks connection during file copy

2016-09-12

OK, will see if more people have problems with this.

Reply with quote

miso
Joined:: 2016-08-25
Posts:: 3

Re: WinSCP 5.9.1 breaks connection during file copy

2016-09-12 13:55

martin wrote:

OK, will see if more people have problems with this.

Ok, I will wait too.

Thank you for answering to my posts.

regards
Mirek

Reply with quote

WinSCP 5.9.1 breaks connection during file copy