Topic "Transfer errors with FTPes over Socks Proxy"

Author Message
hermeticus
[View user's profile]

Joined: 2011-09-01
Posts: 4
Environment:
- WinSCP Version: 4.3.4 (Build 1340) (5.0.2 and 4.3.3 also show the same behaviour)
- OS: Windows XP SP3
- Protocol: FTP with explicit TLS over Socks5 Proxy (passive FTP)
- Error occours both in GUI and commandline/scripting mode
- Server Software as reported by server: Gene6 FTP Server v.3.10.0 (Build 2)

Hello,

when working in this rather exotic setup (FTPes over Socks5), i encountered strange errors.
Some transfers work fine.

But when changing to some directories on the server i get:
"Could not retrieve directory listing. Transfer ok."

And while downloading files i sometimes get the error:
"Copying files from remote side failed. File sent ok."

When using a different FTP Client i could not reproduce this errors.
I also tried connecting to ftp.secureftp-test.com. There were no errors.
It looks like the problem only exists for this WinSCP+Server+FTPes+Socks5 combination.

Here are log extracts
(shortened and sensitive information is replaced (IPs, Filenames, Directories, ...):

---LOG-Extract: Directory Listing Error---
. 2011-09-14 10:20:38.120 --------------------------------------------------------------------------
. 2011-09-14 10:20:38.120 WinSCP Version 4.3.3 (Build 1340) (OS 5.1.2600 Service Pack 3)
. 2011-09-14 10:20:38.120 Configuration: ***
. 2011-09-14 10:20:38.120 Login time: Mittwoch, 14. September 2011 10:20:38
. 2011-09-14 10:20:38.120 --------------------------------------------------------------------------
. 2011-09-14 10:20:38.120 Session name: *** (Stored session)
. 2011-09-14 10:20:38.120 Host name: *** (Port: 21)
. 2011-09-14 10:20:38.120 User name: *** (Password: Yes, Key file: No)
. 2011-09-14 10:20:38.120 Tunnel: No
. 2011-09-14 10:20:38.120 Transfer Protocol: FTP
. 2011-09-14 10:20:38.120 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2011-09-14 10:20:38.120 Proxy: SOCKS5
. 2011-09-14 10:20:38.120 HostName: *** (Port: ***); Username: ; Passwd: No
. 2011-09-14 10:20:38.136 FTP: FTPS: Explicit TLS; Passive: Yes [Force IP: No]
. 2011-09-14 10:20:38.136 Local directory: default, Remote directory: home, Update: No, Cache: No
. 2011-09-14 10:20:38.136 Cache directory changes: No, Permanent: Yes
. 2011-09-14 10:20:38.136 DST mode: 1
. 2011-09-14 10:20:38.136 --------------------------------------------------------------------------
. 2011-09-14 10:20:38.167 Connecting to *** ...
. 2011-09-14 10:20:38.292 Connected with ***, negotiating SSL connection...
< 2011-09-14 10:20:38.495 220 Gene6 FTP Server v3.10.0 (Build 2) ready...
> 2011-09-14 10:20:38.495 AUTH TLS
< 2011-09-14 10:20:38.511 234 AUTH command ok; starting SSL connection.
. 2011-09-14 10:20:38.902 SSL connection established. Waiting for welcome message...
> 2011-09-14 10:20:38.902 USER ***
< 2011-09-14 10:20:38.917 331 Password required for ***.
> 2011-09-14 10:20:38.917 PASS ********
< 2011-09-14 10:20:38.949 230 User *** logged in.
> 2011-09-14 10:20:38.949 SYST
< 2011-09-14 10:20:38.964 215 UNIX Type: L8
> 2011-09-14 10:20:38.964 FEAT
< 2011-09-14 10:20:38.980 211-Extensions supported:
< 2011-09-14 10:20:38.980 AUTH TLS
< 2011-09-14 10:20:38.980 CCC
< 2011-09-14 10:20:38.980 CLNT
< 2011-09-14 10:20:38.980 CPSV
< 2011-09-14 10:20:38.980 EPRT
< 2011-09-14 10:20:38.980 EPSV
< 2011-09-14 10:20:38.980 MDTM
< 2011-09-14 10:20:38.980 MFCT
< 2011-09-14 10:20:38.980 MFMT
< 2011-09-14 10:20:38.980 MLST type*;size*;create;modify*;
< 2011-09-14 10:20:38.980 MODE Z
< 2011-09-14 10:20:38.980 PASV
< 2011-09-14 10:20:38.980 PBSZ
< 2011-09-14 10:20:38.980 PROT
< 2011-09-14 10:20:38.980 REST STREAM
< 2011-09-14 10:20:38.980 SIZE
< 2011-09-14 10:20:38.980 SSCN
< 2011-09-14 10:20:38.995 TVFS
< 2011-09-14 10:20:38.995 UTF8
< 2011-09-14 10:20:38.995 XCRC "filename" SP EP
< 2011-09-14 10:20:38.995 XMD5 "filename" SP EP
< 2011-09-14 10:20:38.995 XSHA1 "filename" SP EP
< 2011-09-14 10:20:38.995 211 End.
> 2011-09-14 10:20:38.995 CLNT FileZilla
< 2011-09-14 10:20:38.995 200 Noted.
> 2011-09-14 10:20:38.995 OPTS UTF8 ON
< 2011-09-14 10:20:39.027 200 UTF8 OPTS ON
> 2011-09-14 10:20:39.027 PBSZ 0
< 2011-09-14 10:20:39.074 200 PBSZ=0
> 2011-09-14 10:20:39.074 PROT P
< 2011-09-14 10:20:39.089 200 PROT command successful.
. 2011-09-14 10:20:39.089 Connected
. 2011-09-14 10:20:39.089 Got reply 1 to the command 1
. 2011-09-14 10:20:39.089 --------------------------------------------------------------------------
. 2011-09-14 10:20:39.089 Using FTP protocol.
. 2011-09-14 10:20:39.089 Doing startup conversation with host.
> 2011-09-14 10:20:39.089 PWD
< 2011-09-14 10:20:39.105 257 "/" is current directory.
. 2011-09-14 10:20:39.105 Got reply 1 to the command 16
. 2011-09-14 10:20:39.120 Getting current directory name.
. 2011-09-14 10:20:39.120 Retrieving directory listing...
> 2011-09-14 10:20:39.120 TYPE A
< 2011-09-14 10:20:39.136 200 Type set to A.
> 2011-09-14 10:20:39.136 PASV
< 2011-09-14 10:20:39.152 227 Entering Passive Mode (*,*,*,*,8,179)
> 2011-09-14 10:20:39.152 LIST -a
< 2011-09-14 10:20:39.183 150 Data connection accepted from *.*.*.*:1686; transfer starting.
. 2011-09-14 10:20:39.214 SSL connection established
. 2011-09-14 10:20:39.214 drwxrwxrwx 1 user group 0 May 12 11:46 repldir1
. 2011-09-14 10:20:39.230 drwxrwxrwx 1 user group 0 Feb 08 2010 repldir2
. 2011-09-14 10:20:39.230 drwxrwxrwx 1 user group 0 Aug 31 16:21 repldir3
< 2011-09-14 10:20:39.370 226 Transfer ok.
. 2011-09-14 10:20:39.370 Directory listing successful
. 2011-09-14 10:20:39.370 Got reply 1 to the command 2
. 2011-09-14 10:20:39.370 Startup conversation with host finished.
2011-09-14 10:20:43.589 Changing directory to "repldir1".
> 2011-09-14 10:20:43.589 CWD repldir1
< 2011-09-14 10:20:43.620 250 CWD command successful. "/repldir1" is current directory.
. 2011-09-14 10:20:43.620 Got reply 1 to the command 16
. 2011-09-14 10:20:43.620 Getting current directory name.
> 2011-09-14 10:20:43.620 PWD
< 2011-09-14 10:20:43.636 257 "/repldir1" is current directory.
. 2011-09-14 10:20:43.636 Got reply 1 to the command 16
. 2011-09-14 10:20:43.636 Retrieving directory listing...
> 2011-09-14 10:20:43.636 TYPE A
< 2011-09-14 10:20:43.652 200 Type set to A.
> 2011-09-14 10:20:43.652 PASV
< 2011-09-14 10:20:43.667 227 Entering Passive Mode (*,*,*,*,8,179)
> 2011-09-14 10:20:43.667 LIST -a
< 2011-09-14 10:20:43.699 150 Data connection accepted from *.*.*.*:1687; transfer starting.
. 2011-09-14 10:20:43.730 SSL connection established
. 2011-09-14 10:20:43.730 Could not retrieve directory listing
. 2011-09-14 10:20:43.730 Got reply 4 to the command 2
< 2011-09-14 10:20:43.855 226 Transfer ok.
* 2011-09-14 10:20:43.855 (ECommand) Error listing directory '/repldir1'.
* 2011-09-14 10:20:43.855 Could not retrieve directory listing
* 2011-09-14 10:20:43.855 Transfer ok.
. 2011-09-14 10:20:47.902 Got reply 1004 to the command 2
. 2011-09-14 10:20:47.902 Disconnected from server
[/code]


---LOG-Extract: File Copy Error---
. 2011-08-22 06:55:10.121 --------------------------------------------------------------------------
. 2011-08-22 06:55:10.121 WinSCP Version 4.3.4 (Build 1428) (OS 5.1.2600 Service Pack 3)
. 2011-08-22 06:55:10.121 Configuration: HKEY_CURRENT_USER\Software\Martin Prikryl\WinSCP 2\
. 2011-08-22 06:55:10.121 Login time: Montag, 22. August 2011 06:55:10
. 2011-08-22 06:55:10.121 --------------------------------------------------------------------------
. 2011-08-22 06:55:10.121 Session name: *** (Modified stored session)
. 2011-08-22 06:55:10.121 Host name: *** (Port: 21)
. 2011-08-22 06:55:10.121 User name: *** (Password: Yes, Key file: No)
. 2011-08-22 06:55:10.121 Tunnel: No
. 2011-08-22 06:55:10.121 Transfer Protocol: FTP
. 2011-08-22 06:55:10.121 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2011-08-22 06:55:10.121 Proxy: SOCKS5
. 2011-08-22 06:55:10.121 HostName: *** (Port: ***); Username: ; Passwd: No
. 2011-08-22 06:55:10.121 FTP: FTPS: Explicit TLS; Passive: Yes [Force IP: No]
. 2011-08-22 06:55:10.121 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2011-08-22 06:55:10.121 Cache directory changes: Yes, Permanent: Yes
. 2011-08-22 06:55:10.121 DST mode: 1
. 2011-08-22 06:55:10.121 --------------------------------------------------------------------------
. 2011-08-22 06:55:10.137 Connecting to *** ...
. 2011-08-22 06:55:10.153 Connected with ***, negotiating SSL connection...
< 2011-08-22 06:55:10.309 220 Gene6 FTP Server v3.10.0 (Build 2) ready...
> 2011-08-22 06:55:10.309 AUTH TLS
< 2011-08-22 06:55:10.309 234 AUTH command ok; starting SSL connection.
. 2011-08-22 06:55:10.465 SSL connection established. Waiting for welcome message...
> 2011-08-22 06:55:10.465 USER ***
< 2011-08-22 06:55:10.481 331 Password required for ***.
> 2011-08-22 06:55:10.481 PASS ********
< 2011-08-22 06:55:10.496 230 User *** logged in.
> 2011-08-22 06:55:10.496 SYST
< 2011-08-22 06:55:10.512 215 UNIX Type: L8
> 2011-08-22 06:55:10.512 FEAT
< 2011-08-22 06:55:10.528 211-Extensions supported:
< 2011-08-22 06:55:10.528 AUTH TLS
< 2011-08-22 06:55:10.528 CCC
< 2011-08-22 06:55:10.528 CLNT
< 2011-08-22 06:55:10.528 CPSV
< 2011-08-22 06:55:10.528 EPRT
< 2011-08-22 06:55:10.528 EPSV
< 2011-08-22 06:55:10.528 MDTM
< 2011-08-22 06:55:10.528 MFCT
< 2011-08-22 06:55:10.528 MFMT
< 2011-08-22 06:55:10.528 MLST type*;size*;create;modify*;
< 2011-08-22 06:55:10.528 MODE Z
< 2011-08-22 06:55:10.528 PASV
< 2011-08-22 06:55:10.528 PBSZ
< 2011-08-22 06:55:10.528 PROT
< 2011-08-22 06:55:10.528 REST STREAM
< 2011-08-22 06:55:10.528 SIZE
< 2011-08-22 06:55:10.528 SSCN
< 2011-08-22 06:55:10.528 TVFS
< 2011-08-22 06:55:10.528 UTF8
< 2011-08-22 06:55:10.528 XCRC "filename" SP EP
< 2011-08-22 06:55:10.528 XMD5 "filename" SP EP
< 2011-08-22 06:55:10.528 XSHA1 "filename" SP EP
< 2011-08-22 06:55:10.528 211 End.
> 2011-08-22 06:55:10.528 CLNT FileZilla
< 2011-08-22 06:55:10.543 200 Noted.
> 2011-08-22 06:55:10.543 OPTS UTF8 ON
< 2011-08-22 06:55:10.559 200 UTF8 OPTS ON
> 2011-08-22 06:55:10.559 PBSZ 0
< 2011-08-22 06:55:10.575 200 PBSZ=0
> 2011-08-22 06:55:10.575 PROT P
< 2011-08-22 06:55:10.590 200 PROT command successful.
. 2011-08-22 06:55:10.590 Connected
. 2011-08-22 06:55:10.590 --------------------------------------------------------------------------
. 2011-08-22 06:55:10.590 Using FTP protocol.
. 2011-08-22 06:55:10.590 Doing startup conversation with host.
> 2011-08-22 06:55:10.590 PWD
< 2011-08-22 06:55:10.606 257 "/" is current directory.
. 2011-08-22 06:55:10.606 Getting current directory name.
. 2011-08-22 06:55:10.606 Retrieving directory listing...
> 2011-08-22 06:55:10.606 TYPE A
< 2011-08-22 06:55:10.621 200 Type set to A.
> 2011-08-22 06:55:10.621 PASV
< 2011-08-22 06:55:10.653 227 Entering Passive Mode (*,*,*,*,8,179)
> 2011-08-22 06:55:10.653 LIST -a
< 2011-08-22 06:55:10.684 150 Data connection accepted from *.*.*.*:2984; transfer starting.
. 2011-08-22 06:55:10.700 SSL connection established
< 2011-08-22 06:55:10.856 226 Transfer ok.
. 2011-08-22 06:55:10.856 Directory listing successful
. 2011-08-22 06:55:10.856 Startup conversation with host finished.
. 2011-08-22 06:55:14.184 Cached directory change via "subdir1" to "/subdir1".
. 2011-08-22 06:55:14.184 Getting current directory name.
. 2011-08-22 06:55:14.184 Retrieving directory listing...
> 2011-08-22 06:55:14.184 CWD /subdir1/
< 2011-08-22 06:55:14.200 250 CWD command successful. "/subdir1" is current directory.
> 2011-08-22 06:55:14.200 PWD
< 2011-08-22 06:55:14.215 257 "/subdir1" is current directory.
> 2011-08-22 06:55:14.215 TYPE A
< 2011-08-22 06:55:14.231 200 Type set to A.
> 2011-08-22 06:55:14.231 PASV
< 2011-08-22 06:55:14.246 227 Entering Passive Mode (*,*,*,*,8,182)
> 2011-08-22 06:55:14.246 LIST -a
< 2011-08-22 06:55:14.278 150 Data connection accepted from *.*.*.*:2986; transfer starting.
. 2011-08-22 06:55:14.309 SSL connection established
< 2011-08-22 06:55:14.465 226 Transfer ok.
. 2011-08-22 06:55:14.465 Directory listing successful
. 2011-08-22 06:55:15.778 Cached directory change via "subdir2" to "/subdir1/subdir2".
. 2011-08-22 06:55:15.778 Getting current directory name.
. 2011-08-22 06:55:15.778 Retrieving directory listing...
> 2011-08-22 06:55:15.778 CWD /subdir1/subdir2/
< 2011-08-22 06:55:15.793 250 CWD command successful. "/subdir1/subdir2" is current directory.
> 2011-08-22 06:55:15.793 PWD
< 2011-08-22 06:55:15.809 257 "/subdir1/subdir2" is current directory.
> 2011-08-22 06:55:15.809 TYPE A
< 2011-08-22 06:55:15.825 200 Type set to A.
> 2011-08-22 06:55:15.825 PASV
< 2011-08-22 06:55:15.840 227 Entering Passive Mode (*,*,*,*,8,178)
> 2011-08-22 06:55:15.840 LIST -a
< 2011-08-22 06:55:15.887 150 Data connection accepted from *.*.*.*:2987; transfer starting.
. 2011-08-22 06:55:15.903 SSL connection established
< 2011-08-22 06:55:16.106 226 Transfer ok.
. 2011-08-22 06:55:16.106 Directory listing successful
. 2011-08-22 06:55:16.325 Disconnected from server
> 2011-08-22 06:55:46.434 REST 0
< 2011-08-22 06:55:46.434 350 Restarting at 0. Send STORE or RETRIEVE.

. 2011-08-22 06:56:06.043 File: "/subdir1/subdir2/file3.txt"
. 2011-08-22 06:56:06.043 Copying "/subdir1/subdir2/file3.txt" to local directory started.
. 2011-08-22 06:56:06.043 Ascii transfer mode selected.
. 2011-08-22 06:56:06.059 Starting download of /subdir1/subdir2/file3.txt
> 2011-08-22 06:56:06.059 TYPE A
< 2011-08-22 06:56:06.059 200 Type set to A.
> 2011-08-22 06:56:06.059 PASV
< 2011-08-22 06:56:06.075 227 Entering Passive Mode (*,*,*,*,8,182)
> 2011-08-22 06:56:06.090 RETR file3.txt
< 2011-08-22 06:56:06.309 150 Data connection accepted from *.*.*.*:2993; transfer starting for /subdir1/subdir2/file3.txt (3613 bytes)
. 2011-08-22 06:56:06.340 SSL connection established
. 2011-08-22 06:56:06.356 Copying files from remote side failed.
< 2011-08-22 06:56:06.528 226 File sent ok.
* 2011-08-22 06:56:06.528 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:56:06.528 File sent ok.
. 2011-08-22 06:56:06.528 Asking user:
. 2011-08-22 06:56:06.528 Error transferring file '/subdir1/subdir2/file3.txt'. ("Copying files from remote side failed.","File sent ok.")
> 2011-08-22 06:56:36.434 TYPE I
< 2011-08-22 06:56:36.434 200 Type set to I.
. 2011-08-22 06:56:49.950 Starting download of /subdir1/subdir2/file3.txt
. 2011-08-22 06:56:49.950 Asking user:
. 2011-08-22 06:56:49.950 Local file 'file3.txt' already exists. Overwrite?
. 2011-08-22 06:56:49.950
. 2011-08-22 06:56:49.950 New: 3613 bytes, 18.08.2011 23:25
. 2011-08-22 06:56:49.950 Existing: 3613 bytes, 22.08.2011 06:56:06 ()
> 2011-08-22 06:56:52.356 TYPE A
< 2011-08-22 06:56:52.371 200 Type set to A.
> 2011-08-22 06:56:52.371 PASV
< 2011-08-22 06:56:52.387 227 Entering Passive Mode (*,*,*,*,8,179)
> 2011-08-22 06:56:52.387 RETR file3.txt
< 2011-08-22 06:56:52.418 150 Data connection accepted from *.*.*.*:2994; transfer starting for /subdir1/subdir2/file3.txt (3613 bytes)
. 2011-08-22 06:56:52.465 SSL connection established
. 2011-08-22 06:56:52.465 Copying files from remote side failed.
< 2011-08-22 06:56:52.481 226 File sent ok.
* 2011-08-22 06:56:52.481 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:56:52.481 File sent ok.
. 2011-08-22 06:56:52.481 Asking user:
. 2011-08-22 06:56:52.481 Error transferring file '/subdir1/subdir2/file3.txt'. ("Copying files from remote side failed.","File sent ok.")
. 2011-08-22 06:56:55.496 Starting download of /subdir1/subdir2/file3.txt
> 2011-08-22 06:56:55.496 TYPE A
< 2011-08-22 06:56:55.512 200 Type set to A.
> 2011-08-22 06:56:55.512 PASV
< 2011-08-22 06:56:55.528 227 Entering Passive Mode (*,*,*,*,8,175)
> 2011-08-22 06:56:55.528 RETR file3.txt
< 2011-08-22 06:56:55.559 150 Data connection accepted from *.*.*.*:2995; transfer starting for /subdir1/subdir2/file3.txt (3613 bytes)
. 2011-08-22 06:56:55.590 SSL connection established
. 2011-08-22 06:56:55.606 Copying files from remote side failed.
< 2011-08-22 06:56:55.746 226 File sent ok.
* 2011-08-22 06:56:55.746 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:56:55.746 File sent ok.
. 2011-08-22 06:56:55.746 Asking user:
. 2011-08-22 06:56:55.746 Error transferring file '/subdir1/subdir2/file3.txt'. ("Copying files from remote side failed.","File sent ok.")
. 2011-08-22 06:56:56.637 Starting download of /subdir1/subdir2/file3.txt
> 2011-08-22 06:56:56.637 TYPE A
< 2011-08-22 06:56:56.653 200 Type set to A.
> 2011-08-22 06:56:56.653 PASV
< 2011-08-22 06:56:56.668 227 Entering Passive Mode (*,*,*,*,8,180)
> 2011-08-22 06:56:56.668 RETR file3.txt
< 2011-08-22 06:56:56.700 150 Data connection accepted from *.*.*.*:2996; transfer starting for /subdir1/subdir2/file3.txt (3613 bytes)
. 2011-08-22 06:56:56.731 SSL connection established
. 2011-08-22 06:56:56.746 Copying files from remote side failed.
< 2011-08-22 06:56:56.840 226 File sent ok.
* 2011-08-22 06:56:56.840 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:56:56.840 File sent ok.
. 2011-08-22 06:56:56.840 Asking user:
. 2011-08-22 06:56:56.840 Error transferring file '/subdir1/subdir2/file3.txt'. ("Copying files from remote side failed.","File sent ok.")
. 2011-08-22 06:56:58.106 Starting download of /subdir1/subdir2/file3.txt
> 2011-08-22 06:56:58.106 TYPE A
< 2011-08-22 06:56:58.121 200 Type set to A.
> 2011-08-22 06:56:58.121 PASV
< 2011-08-22 06:56:58.137 227 Entering Passive Mode (*,*,*,*,8,174)
> 2011-08-22 06:56:58.137 RETR file3.txt
< 2011-08-22 06:56:58.168 150 Data connection accepted from *.*.*.*:2997; transfer starting for /subdir1/subdir2/file3.txt (3613 bytes)
. 2011-08-22 06:56:58.200 SSL connection established
. 2011-08-22 06:56:58.215 Copying files from remote side failed.
< 2011-08-22 06:56:58.371 226 File sent ok.
* 2011-08-22 06:56:58.371 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:56:58.371 File sent ok.
. 2011-08-22 06:56:58.371 Asking user:
. 2011-08-22 06:56:58.371 Error transferring file '/subdir1/subdir2/file3.txt'. ("Copying files from remote side failed.","File sent ok.")
. 2011-08-22 06:56:58.996 Starting download of /subdir1/subdir2/file3.txt
> 2011-08-22 06:56:58.996 TYPE A
< 2011-08-22 06:56:59.012 200 Type set to A.
> 2011-08-22 06:56:59.012 PASV
< 2011-08-22 06:56:59.028 227 Entering Passive Mode (*,*,*,*,8,175)
> 2011-08-22 06:56:59.028 RETR file3.txt
< 2011-08-22 06:56:59.059 150 Data connection accepted from *.*.*.*:2998; transfer starting for /subdir1/subdir2/file3.txt (3613 bytes)
. 2011-08-22 06:56:59.090 SSL connection established
. 2011-08-22 06:56:59.106 Copying files from remote side failed.
< 2011-08-22 06:56:59.246 226 File sent ok.
* 2011-08-22 06:56:59.246 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:56:59.246 File sent ok.
. 2011-08-22 06:56:59.246 Asking user:
. 2011-08-22 06:56:59.246 Error transferring file '/subdir1/subdir2/file3.txt'. ("Copying files from remote side failed.","File sent ok.")
* 2011-08-22 06:57:00.403 (EScpSkipFile) Error transferring file '/subdir1/subdir2/file3.txt'.
* 2011-08-22 06:57:00.403 Copying files from remote side failed.
* 2011-08-22 06:57:00.403 File sent ok.

. 2011-08-22 06:57:00.403 File: "/subdir1/subdir2/file2.pdf"
. 2011-08-22 06:57:00.403 Copying "/subdir1/subdir2/file2.pdf" to local directory started.
. 2011-08-22 06:57:00.403 Binary transfer mode selected.
. 2011-08-22 06:57:00.403 Starting download of /subdir1/subdir2/file2.pdf
> 2011-08-22 06:57:00.403 TYPE I
< 2011-08-22 06:57:00.418 200 Type set to I.
> 2011-08-22 06:57:00.418 PASV
< 2011-08-22 06:57:00.434 227 Entering Passive Mode (*,*,*,*,8,181)
> 2011-08-22 06:57:00.434 RETR file2.pdf
< 2011-08-22 06:57:00.840 150 Data connection accepted from *.*.*.*:2999; transfer starting for /subdir1/subdir2/file2.pdf (115096 bytes)
. 2011-08-22 06:57:00.871 SSL connection established
< 2011-08-22 06:57:01.012 226 File sent ok.
. 2011-08-22 06:57:01.012 Download successful

. 2011-08-22 06:57:01.012 File: "/subdir1/subdir2/file2.txt"
. 2011-08-22 06:57:01.012 Copying "/subdir1/subdir2/file2.txt" to local directory started.
. 2011-08-22 06:57:01.028 Ascii transfer mode selected.
. 2011-08-22 06:57:01.028 Starting download of /subdir1/subdir2/file2.txt
> 2011-08-22 06:57:01.028 TYPE A
< 2011-08-22 06:57:01.043 200 Type set to A.
> 2011-08-22 06:57:01.043 PASV
< 2011-08-22 06:57:01.059 227 Entering Passive Mode (*,*,*,*,8,176)
> 2011-08-22 06:57:01.059 RETR file2.txt
< 2011-08-22 06:57:02.090 150 Data connection accepted from *.*.*.*:35257; transfer starting for /subdir1/subdir2/file2.txt (1929 bytes)
. 2011-08-22 06:57:02.121 SSL connection established
. 2011-08-22 06:57:02.137 Copying files from remote side failed.
< 2011-08-22 06:57:02.309 226 File sent ok.
* 2011-08-22 06:57:02.309 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:57:02.309 File sent ok.
. 2011-08-22 06:57:02.309 Asking user:
. 2011-08-22 06:57:02.309 Error transferring file '/subdir1/subdir2/file2.txt'. ("Copying files from remote side failed.","File sent ok.")
* 2011-08-22 06:57:04.200 (EScpSkipFile) Error transferring file '/subdir1/subdir2/file2.txt'.
* 2011-08-22 06:57:04.200 Copying files from remote side failed.
* 2011-08-22 06:57:04.200 File sent ok.

. 2011-08-22 06:57:05.793 File: "/subdir1/subdir2/file4.txt"
. 2011-08-22 06:57:05.793 Copying "/subdir1/subdir2/file4.txt" to local directory started.
. 2011-08-22 06:57:05.793 Ascii transfer mode selected.
. 2011-08-22 06:57:05.793 Starting download of /subdir1/subdir2/file4.txt
> 2011-08-22 06:57:05.793 TYPE A
< 2011-08-22 06:57:05.809 200 Type set to A.
> 2011-08-22 06:57:05.809 PASV
< 2011-08-22 06:57:05.840 227 Entering Passive Mode (*,*,*,*,8,182)
> 2011-08-22 06:57:05.840 RETR file4.txt
< 2011-08-22 06:57:06.871 150 Data connection accepted from *.*.*.*:38301; transfer starting for /subdir1/subdir2/file4.txt (6812 bytes)
. 2011-08-22 06:57:06.903 SSL connection established
< 2011-08-22 06:57:07.121 226 File sent ok.
. 2011-08-22 06:57:07.121 Download successful

. 2011-08-22 06:57:08.778 File: "/subdir1/subdir2/file1.txt"
. 2011-08-22 06:57:08.778 Copying "/subdir1/subdir2/file1.txt" to local directory started.
. 2011-08-22 06:57:08.778 Ascii transfer mode selected.
. 2011-08-22 06:57:08.778 Starting download of /subdir1/subdir2/file1.txt
> 2011-08-22 06:57:08.778 TYPE A
< 2011-08-22 06:57:08.793 200 Type set to A.
> 2011-08-22 06:57:08.793 PASV
< 2011-08-22 06:57:08.809 227 Entering Passive Mode (*,*,*,*,8,175)
> 2011-08-22 06:57:08.809 RETR file1.txt
< 2011-08-22 06:57:09.840 150 Data connection accepted from *.*.*.*:50804; transfer starting for /subdir1/subdir2/file1.txt (2525 bytes)
. 2011-08-22 06:57:09.871 SSL connection established
. 2011-08-22 06:57:09.887 Copying files from remote side failed.
< 2011-08-22 06:57:09.965 226 File sent ok.
* 2011-08-22 06:57:09.965 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:57:09.965 File sent ok.
. 2011-08-22 06:57:09.965 Asking user:
. 2011-08-22 06:57:09.965 Error transferring file '/subdir1/subdir2/file1.txt'. ("Copying files from remote side failed.","File sent ok.")
* 2011-08-22 06:57:10.918 (EScpSkipFile) Error transferring file '/subdir1/subdir2/file1.txt'.
* 2011-08-22 06:57:10.918 Copying files from remote side failed.
* 2011-08-22 06:57:10.918 File sent ok.

. 2011-08-22 06:57:13.700 File: "/subdir1/subdir2/file61.txt"
. 2011-08-22 06:57:13.700 Copying "/subdir1/subdir2/file61.txt" to local directory started.
. 2011-08-22 06:57:13.700 Ascii transfer mode selected.
. 2011-08-22 06:57:13.700 Starting download of /subdir1/subdir2/file61.txt
> 2011-08-22 06:57:13.700 TYPE A
< 2011-08-22 06:57:13.715 200 Type set to A.
> 2011-08-22 06:57:13.715 PASV
< 2011-08-22 06:57:13.731 227 Entering Passive Mode (*,*,*,*,8,182)
> 2011-08-22 06:57:13.731 RETR file61.txt
< 2011-08-22 06:57:13.825 150 Data connection accepted from *.*.*.*:3009; transfer starting for /subdir1/subdir2/file61.txt (3646 bytes)
. 2011-08-22 06:57:13.856 SSL connection established
. 2011-08-22 06:57:13.871 Copying files from remote side failed.
< 2011-08-22 06:57:14.121 226 File sent ok.
* 2011-08-22 06:57:14.121 (ExtException) Copying files from remote side failed.
* 2011-08-22 06:57:14.121 File sent ok.
. 2011-08-22 06:57:14.121 Asking user:
. 2011-08-22 06:57:14.121 Error transferring file '/subdir1/subdir2/file61.txt'. ("Copying files from remote side failed.","File sent ok.")
* 2011-08-22 06:57:18.528 (EScpSkipFile) Error transferring file '/subdir1/subdir2/file61.txt'.
* 2011-08-22 06:57:18.528 Copying files from remote side failed.
* 2011-08-22 06:57:18.528 File sent ok.

. 2011-08-22 07:00:16.121 Retrieving directory listing...
> 2011-08-22 07:00:16.121 TYPE A
< 2011-08-22 07:00:16.137 200 Type set to A.
> 2011-08-22 07:00:16.137 PASV
< 2011-08-22 07:00:16.153 227 Entering Passive Mode (*,*,*,*,8,182)
> 2011-08-22 07:00:16.153 LIST -a
< 2011-08-22 07:00:16.184 150 Data connection accepted from *.*.*.*:3078; transfer starting.
. 2011-08-22 07:00:16.215 SSL connection established
< 2011-08-22 07:00:16.340 226 Transfer ok.
. 2011-08-22 07:00:16.340 Directory listing successful
* 2011-08-22 07:00:16.340 (EScpSkipFile) Error transferring file '/subdir1/subdir2/file1.txt'.
* 2011-08-22 07:00:16.340 Copying files from remote side failed.
* 2011-08-22 07:00:16.340 File sent ok.
[/code]

I haved searched the Forum, but could not find such a scenario.

Do you have any ideas what could cause this error?
Advertisements
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 24530
Location: Prague, Czechia
May I have a test account on your server?
_________________
Martin Prikryl
hermeticus
[View user's profile]

Joined: 2011-09-01
Posts: 4
prikryl wrote:
May I have a test account on your server?


Sadly, the server is not under my control (if it would be, i would have used SFTP in the first place Wink).

Anyway I will ask the server administrator if he is willing to create a test account for you.
I will mail you the details if i can convince him.
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 24530
Location: Prague, Czechia
OK, in that case, I'm sending you an email with link to a debug version.
_________________
Martin Prikryl
hermeticus
[View user's profile]

Joined: 2011-09-01
Posts: 4
Thank you for the debug version and please forgive me my late answer.

It was not possible for me to reproduce the error with the debug version.
The Debug version is running for several days now (scripted) without errors.

I hope you have changed something in the debug version? Otherwise it would be very strange.
If you have changed something in the debug version, then it was the right thing Wink


prikryl wrote:
OK, in that case, I'm sending you an email with link to a debug version.
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 24530
Location: Prague, Czechia
Hm, I do not think I did. Can you try 4.3.5?
_________________
Martin Prikryl
hermeticus
[View user's profile]

Joined: 2011-09-01
Posts: 4
Please forgive my extremely late answer.
I was out of the office for quite a while and could test 4.3.5 just this morning.

Unfortunately changing to one directory on the server results in the EDirListing Error all the time with 4.3.5, 4.3.3, 4.3.4, 5.0.1 and 5.0.2 but strangely not with the debug version.

I know how hard it is to hunt down this kind of hide-away-bugs.
Ill try to convince the server admin to create a test account for you.
But it will take a while and im not sure if ill succeed.


prikryl wrote:
Hm, I do not think I did. Can you try 4.3.5?
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 24530
Location: Prague, Czechia
hermeticus wrote:
I know how hard it is to hunt down this kind of hide-away-bugs.
Ill try to convince the server admin to create a test account for you.
But it will take a while and im not sure if ill succeed.

Thanks. Please try.
_________________
Martin Prikryl
phros
[View user's profile]

Joined: 2011-12-12
Posts: 1
Location: phradmin
Hello.

I hope I can give you a litle extra information to find the bug. Look in the attched word document.

Philip
WinScp-SocksProxy.doc (28.5 KB) [Download]

Description: Socks SSL

Advertisements

You can post new topics in this forum






Search Site

What is WinSCP?

It is award-winning SFTP client, SCP client, FTPS client and FTP client integrated into one software program for file transfer to FTP server or secure SFTP server. [More]

And it's free!

Donate

About donations

$9   $19   $49   $99

About donations

Recommend

WinSCP Privacy Policy

WinSCP License