Cannot download from specific FTP server with WinSCP.com :: Support Forum

eldnumh
Joined:: 2014-06-02
Posts:: 4

Cannot download from specific FTP server with WinSCP.com

2014-06-02 10:14

I want to download a file from an FTP server owned by a huge European agency. A few month ago it work perfectly, but now, the download fails with:
Could not retrieve file information
Command was blocked. Contact admin.

The commands I am using are

"\Program Files (x86)\WinSCP\WinSCP.com" /log="winscp.log"
open ftp://...
get /dsf/fr/y20...

For me it seem, that WinSCP.com calls MLST before actually retrieving the file. Is this correct, why is this required? It it possible to disable that?

If I call stat, I get the same error.

If I use the GUI, I can download the file without error.

The log was created with WinSCP v5.1.7, but I tested with 5.5.3 either, this version shows the same error message.

Win7 64bit
FTP
no GUI

. 2014-06-02 10:20:50.985 --------------------------------------------------------------------------
. 2014-06-02 10:20:50.986 WinSCP Version 5.1.7 (Build 3446) (OS 6.1.7601 Service Pack 1)
. 2014-06-02 10:20:50.986 Configuration: HKEY_CURRENT_USER\Software\Martin Prikryl\WinSCP 2\
. 2014-06-02 10:20:50.987 Local account: IXENXOM\hmu
. 2014-06-02 10:20:50.987 Working directory: C:\Users\hmu.IXENXOM
. 2014-06-02 10:20:50.987 Process ID: 5092
. 2014-06-02 10:20:50.987 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" /console=517 /consoleinstance=_4916_111 "/log=winscp.log" 
. 2014-06-02 10:20:50.987 Time zone: Current: GMT+2, Standard: GMT+1, DST: GMT+2, DST Start: 30.03.2014, DST End: 26.10.2014
. 2014-06-02 10:20:50.987 Login time: Montag, 2. Juni 2014 10:20:50
. 2014-06-02 10:20:50.987 --------------------------------------------------------------------------
. 2014-06-02 10:20:50.987 Session name: EXittXann@ftparch.giove.xxx.int (Modified stored session)
. 2014-06-02 10:20:50.987 Host name: ftparch.giove.xxx.int (Port: 21)
. 2014-06-02 10:20:50.987 User name: EXittXann (Password: Yes, Key file: No)
. 2014-06-02 10:20:50.987 Tunnel: No
. 2014-06-02 10:20:50.987 Transfer Protocol: FTP
. 2014-06-02 10:20:50.987 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2014-06-02 10:20:50.987 Proxy: none
. 2014-06-02 10:20:50.987 FTP: FTPS: None; Passive: Yes [Force IP: A]; List all: A
. 2014-06-02 10:20:50.987 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2014-06-02 10:20:50.987 Cache directory changes: Yes, Permanent: Yes
. 2014-06-02 10:20:50.987 DST mode: 1; Timezone offset: 0h 0m
. 2014-06-02 10:20:50.987 --------------------------------------------------------------------------
. 2014-06-02 10:20:50.996 Connecting to ftparch.giove.xxx.int ...
. 2014-06-02 10:20:51.047 Connected with ftparch.giove.xxx.int. Waiting for welcome message...
< 2014-06-02 10:20:51.074 220 Check Point FireWall-1 Secure FTP server running on FW-ESA-01
> 2014-06-02 10:20:51.075 USER EXittXann
< 2014-06-02 10:20:51.104 331 password: you can use password@password
> 2014-06-02 10:20:51.104 PASS *****************
< 2014-06-02 10:20:51.334 230-User EXittXann authenticated by FireWall-1 authentication
< 2014-06-02 10:20:51.334 230-Connected to server. Logging in...
< 2014-06-02 10:20:51.344 230-220 ProFTPD 1.3.4c Server (GPCARC ProFTPD Server) [172.17.0.10]
< 2014-06-02 10:20:51.344 230-331 Password required for EXittXann
< 2014-06-02 10:20:51.344 230-230-*******************************************************************************
< 2014-06-02 10:20:51.344 230-   IT IS AN OFFENCE TO CONTINUE WITHOUT PROPER AUTHORISATION. This system is
< 2014-06-02 10:20:51.344 230-   restricted to authorised users .
< 2014-06-02 10:20:51.344 230-   Individuals attempting unauthorised access will be prosecuted.
< 2014-06-02 10:20:51.344 230- *******************************************************************************
< 2014-06-02 10:20:51.344 230 230 User EXittXann logged in
> 2014-06-02 10:20:51.344 SYST
< 2014-06-02 10:20:51.366 215 UNIX Type: L8
> 2014-06-02 10:20:51.366 FEAT
< 2014-06-02 10:20:51.396 211-Features:
< 2014-06-02 10:20:51.632  MDTM
< 2014-06-02 10:20:51.632  MFMT
< 2014-06-02 10:20:51.632  TVFS
< 2014-06-02 10:20:51.632  MFF modify;UNIX.group;UNIX.mode;
< 2014-06-02 10:20:51.632  MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.mode*;UNIX.owner*;
< 2014-06-02 10:20:51.632  REST STREAM
< 2014-06-02 10:20:51.632  SIZE
< 2014-06-02 10:20:51.632 211 End
. 2014-06-02 10:20:51.633 Connected
. 2014-06-02 10:20:51.633 --------------------------------------------------------------------------
. 2014-06-02 10:20:51.634 Using FTP protocol.
. 2014-06-02 10:20:51.634 Doing startup conversation with host.
> 2014-06-02 10:20:51.635 PWD
< 2014-06-02 10:20:51.664 257 "/" is the current directory
. 2014-06-02 10:20:51.665 Getting current directory name.
. 2014-06-02 10:20:51.666 Startup conversation with host finished.
< 2014-06-02 10:20:51.666 Script: Active session: [1] EXittXann@ftparch.giove.xxx.int
> 2014-06-02 10:22:16.808 Script: get /dsf/fr/y2013/m06/d30/h07/GNA/GNARNAVS999913181HMRAWF000.xml.gz
. 2014-06-02 10:22:16.809 Listing file "/dsf/fr/y2013/m06/d30/h07/GNA/GNARNAVS999913181HMRAWF000.xml.gz".
> 2014-06-02 10:22:16.810 REST 0
< 2014-06-02 10:22:16.810 502 Security server inhibited REST command
> 2014-06-02 10:22:16.810 PWD
< 2014-06-02 10:22:16.810 257 "/" is the current directory
. 2014-06-02 10:22:16.810 Retrieving file information...
> 2014-06-02 10:22:16.810 MLST /dsf/fr/y2013/m06/d30/h07/GNA/GNARNAVS999913181HMRAWF000.xml.gz
< 2014-06-02 10:22:16.854 505 Command was blocked. Contact admin.
. 2014-06-02 10:22:16.854 Could not retrieve file information
< 2014-06-02 10:22:16.855 Script: Can't get attributes of file '/dsf/fr/y2013/m06/d30/h07/GNA/GNARNAVS999913181HMRAWF000.xml.gz'.
< 2014-06-02 10:22:16.856 Script: Could not retrieve file information
< 2014-06-02 10:22:16.857 Command was blocked. Contact admin.
. 2014-06-02 10:22:16.858 Script: Failed
> 2014-06-02 10:22:22.039 Script: bye
. 2014-06-02 10:22:22.040 Script: Exit code: 1
. 2014-06-02 10:22:22.041 Disconnected from server

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 41,440
Location:: Prague, Czechia

Re: Cannot download from specific FTP server with WinSCP.com

2014-06-02

In scripting WinSCP uses MLST command to find out if the path points to a file or directory. In GUI, WinSCP already knows that from directory listing. I just wonder if MLSD works (as listing in GUI obviously works). But, I do not understand what's would be a point of disabling MLST, but allowing MLSD.

Anyway, obvious problem is that your server announces support for MLST command, although it actually does not allow it.

As a workaround you can:

Configure WinSCP to fallback to LIST (with all its disadvantages):
https://winscp.net/eng/docs/ui_login_ftp
https://winscp.net/eng/docs/rawsettings
Use file mask instead of specific path, i.e. get /dsf/fr/y2013/m06/d30/h07/GNA/GNARNAVS999913181HMRAWF000.xml.gz* (note the asterisk).
This makes WinSCP use MLSD to get list of all files to search for names matching mask. I.e. effectively doing what GUI does.

Reply with quote

eldnumh
Joined:: 2014-06-02
Posts:: 4

Re: Cannot download from specific FTP server with WinSCP.com

2014-06-04 13:35

martin wrote:

In scripting WinSCP uses MLST command to find out if the path points to a file or directory. In GUI, WinSCP already knows that from directory listing. I just wonder if MLSD works (as listing in GUI obviously works). But, I do not understand what's would be a point of disabling MLST, but allowing MLSD.

MLSD works...

Anyway, obvious problem is that your server announces support for MLST command, although it actually does not allow it.

Currently I am trying to get this changed on the FTP server, but it is a huge European agency, therefore this will probably take months...

As a workaround you can:

Configure WinSCP to fallback to LIST (with all its disadvantages):
https://winscp.net/eng/docs/ui_login_ftp
https://winscp.net/eng/docs/rawsettings

This workaround does not help, it only changes the behaviour for ls from MLSD to LIST -a, but stat or get still uses MLST.

See the following logfile:

. 2014-06-04 14:11:37.765 --------------------------------------------------------------------------
. 2014-06-04 14:11:37.765 WinSCP Version 5.5.3 (Build 4214) (OS 6.1.7601 Service Pack 1 - Windows 7 Professional)
. 2014-06-04 14:11:37.765 Configuration: nul
. 2014-06-04 14:11:37.765 Local account: UPN2\UMF-Admin1
. 2014-06-04 14:11:37.765 Working directory: C:\Users\UMF-Admin1
. 2014-06-04 14:11:37.765 Process ID: 1280
. 2014-06-04 14:11:37.765 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" /console=553 /consoleinstance=_4968_516 "/ini=nul" "/log=winscp.log" "/command" "open ftp://EXittXann:xxx@ftparch.giove.xxx.int/dsf/fr/ -rawsettings FtpUseMlsd=1" "ls" 
. 2014-06-04 14:11:37.765 Time zone: Current: GMT+2, Standard: GMT+1 (W. Europe Standard Time), DST: GMT+2 (W. Europe Daylight Time), DST Start: 3/30/2014, DST End: 10/26/2014
. 2014-06-04 14:11:37.765 Login time: Wednesday, June 04, 2014 2:11:37 PM
. 2014-06-04 14:11:37.765 --------------------------------------------------------------------------
. 2014-06-04 14:11:37.765 Script: Retrospectively logging previous script records:
> 2014-06-04 14:11:37.765 Script: open ftp://EXittXann:***@ftparch.giove.xxx.int/dsf/fr/ -rawsettings FtpUseMlsd=1
. 2014-06-04 14:11:37.765 --------------------------------------------------------------------------
. 2014-06-04 14:11:37.765 Session name: EXittXann@ftparch.giove.xxx.int (Ad-Hoc site)
. 2014-06-04 14:11:37.765 Host name: ftparch.giove.xxx.int (Port: 21)
. 2014-06-04 14:11:37.765 User name: EXittXann (Password: Yes, Key file: No)
. 2014-06-04 14:11:37.765 Tunnel: No
. 2014-06-04 14:11:37.765 Transfer Protocol: FTP
. 2014-06-04 14:11:37.765 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2014-06-04 14:11:37.765 Proxy: none
. 2014-06-04 14:11:37.765 Send buffer: 262144
. 2014-06-04 14:11:37.765 FTP: FTPS: None; Passive: Yes [Force IP: A]; MLSD: - [List all: A]
. 2014-06-04 14:11:37.765 Local directory: default, Remote directory: /dsf/fr/, Update: Yes, Cache: Yes
. 2014-06-04 14:11:37.765 Cache directory changes: Yes, Permanent: Yes
. 2014-06-04 14:11:37.765 DST mode: 1; Timezone offset: 0h 0m
. 2014-06-04 14:11:37.765 --------------------------------------------------------------------------
. 2014-06-04 14:11:37.766 Connecting to ftparch.giove.xxx.int ...
. 2014-06-04 14:11:37.796 Connected with ftparch.giove.xxx.int. Waiting for welcome message...
< 2014-06-04 14:11:37.821 220 Check Point FireWall-1 Secure FTP server running on FW-ESA-01
> 2014-06-04 14:11:37.821 USER EXittXann
...
< 2014-06-04 14:11:37.889 230 230 User EXittXann logged in
> 2014-06-04 14:11:37.889 SYST
< 2014-06-04 14:11:37.919 215 UNIX Type: L8
> 2014-06-04 14:11:37.919 FEAT
< 2014-06-04 14:11:37.941 211-Features:
< 2014-06-04 14:11:38.161  MDTM
< 2014-06-04 14:11:38.162  MFMT
< 2014-06-04 14:11:38.162  TVFS
< 2014-06-04 14:11:38.162  MFF modify;UNIX.group;UNIX.mode;
< 2014-06-04 14:11:38.162  MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.mode*;UNIX.owner*;
< 2014-06-04 14:11:38.162  REST STREAM
< 2014-06-04 14:11:38.162  SIZE
< 2014-06-04 14:11:38.162 211 End
. 2014-06-04 14:11:38.162 Connected
. 2014-06-04 14:11:38.162 --------------------------------------------------------------------------
. 2014-06-04 14:11:38.162 Using FTP protocol.
. 2014-06-04 14:11:38.162 Doing startup conversation with host.
...
> 2014-06-04 14:11:38.233 Script: ls
. 2014-06-04 14:11:38.233 Retrieving directory listing...
> 2014-06-04 14:11:38.233 TYPE A
< 2014-06-04 14:11:38.259 200 Type set to A
> 2014-06-04 14:11:38.260 PASV
< 2014-06-04 14:11:38.281 227 Entering Passive Mode (195,169,140,174,210,188)
> 2014-06-04 14:11:38.281 LIST -a
< 2014-06-04 14:11:38.339 150 Opening ASCII mode data connection for file list
. 2014-06-04 14:11:38.346 drwxrwxr-x  21 root     root           21 Feb 11 13:00 .
. 2014-06-04 14:11:38.346 drwxr-xr-x  14 root     root           16 Jun  4 05:00 ..
...
. 2014-06-04 14:11:38.347 drwxr-xr-x   8 root     root            8 Jun  1 00:15 y2014
< 2014-06-04 14:11:38.561 226 Transfer complete
. 2014-06-04 14:11:38.561 Directory listing successful
. 2014-06-04 14:11:38.561 ..;D;0;1899-12-30T01:00:00.000Z;"" [0];"" [0];---------;0
....
. 2014-06-04 14:11:38.561 y2014;D;8;2014-05-31T22:15:00.000Z;"root" [0];"root" [0];rwxr-xr-x;0
< 2014-06-04 14:11:38.561 Script: D---------   0                           0              ..
...
< 2014-06-04 14:11:38.568 Script: Drwxr-xr-x   0 root     root             8 Jun  1  0:15 y2014
> 2014-06-04 14:12:25.265 Script: stat /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz
. 2014-06-04 14:12:25.266 Listing file "/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz".
> 2014-06-04 14:12:25.266 TYPE I
< 2014-06-04 14:12:25.266 200 Type set to I
. 2014-06-04 14:12:25.266 Retrieving file information...
> 2014-06-04 14:12:25.266 MLST /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz
< 2014-06-04 14:12:25.287 505 Command was blocked. Contact admin.
. 2014-06-04 14:12:25.287 Could not retrieve file information
< 2014-06-04 14:12:25.288 Script: Can't get attributes of file '/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz'.
< 2014-06-04 14:12:25.288 Script: Could not retrieve file information
< 2014-06-04 14:12:25.288 Command was blocked. Contact admin.
. 2014-06-04 14:12:25.289 Script: Failed
> 2014-06-04 14:12:33.681 Script: get /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz
. 2014-06-04 14:12:33.681 Listing file "/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz".
. 2014-06-04 14:12:33.681 Retrieving file information...
> 2014-06-04 14:12:33.681 MLST /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz
< 2014-06-04 14:12:33.703 505 Command was blocked. Contact admin.
. 2014-06-04 14:12:33.703 Could not retrieve file information
< 2014-06-04 14:12:33.703 Script: Can't get attributes of file '/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz'.
< 2014-06-04 14:12:33.703 Script: Could not retrieve file information
< 2014-06-04 14:12:33.703 Command was blocked. Contact admin.
. 2014-06-04 14:12:33.704 Script: Failed
> 2014-06-04 14:12:47.985 Script: get /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz*
. 2014-06-04 14:12:47.985 Retrieving directory listing...
> 2014-06-04 14:12:47.985 CWD /dsf/fr/y2014/m03/d15/h13/OSP/
< 2014-06-04 14:12:48.007 250 CWD command successful
> 2014-06-04 14:12:48.007 PWD
< 2014-06-04 14:12:48.027 257 "/dsf/fr/y2014/m03/d15/h13/OSP" is the current directory
> 2014-06-04 14:12:48.027 TYPE A
< 2014-06-04 14:12:48.049 200 Type set to A
> 2014-06-04 14:12:48.049 PASV
< 2014-06-04 14:12:48.071 227 Entering Passive Mode (195,169,140,174,137,95)
> 2014-06-04 14:12:48.071 LIST -a
< 2014-06-04 14:12:48.121 150 Opening ASCII mode data connection for file list
. 2014-06-04 14:12:48.127 drwxr-xr-x   2 root     root           45 Mar 17 14:00 .
. 2014-06-04 14:12:48.127 drwxrwxr-x   6 root     root          189 Apr  3 15:06 ..
. 2014-06-04 14:12:48.127 -rw-r--r--   1 root     root         5595 Mar 17 14:00 OSPRIONO999914074NASEDF000.xml.gz
...
. 2014-06-04 14:12:48.128 -rw-r--r--   1 root     root         6204 Mar 17 14:00 OSPROSRT999914074NVSISA000.xml.gz
< 2014-06-04 14:12:48.345 226 Transfer complete
. 2014-06-04 14:12:48.345 Directory listing successful
. 2014-06-04 14:12:48.345 ..;D;0;1899-12-30T01:00:00.000Z;"" [0];"" [0];---------;0
. 2014-06-04 14:12:48.345 OSPRIONO999914074NASEDF000.xml.gz;-;5595;2014-03-17T13:00:00.000Z;"root" [0];"root" [0];rw-r--r--;0
...
. 2014-06-04 14:12:48.345 OSPROSRT999914074NVSISA000.xml.gz;-;6204;2014-03-17T13:00:00.000Z;"root" [0];"root" [0];rw-r--r--;0
. 2014-06-04 14:12:48.346 File: '/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz' [2014-03-17T13:00:00.000Z] [2578]
. 2014-06-04 14:12:48.346 Copying "/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz" to local directory started.
. 2014-06-04 14:12:48.346 Binary transfer mode selected.
. 2014-06-04 14:12:48.347 Starting download of /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz
> 2014-06-04 14:12:48.348 TYPE I
< 2014-06-04 14:12:48.369 200 Type set to I
> 2014-06-04 14:12:48.369 PASV
< 2014-06-04 14:12:48.391 227 Entering Passive Mode (195,169,140,174,239,7)
> 2014-06-04 14:12:48.391 RETR OSPRIONO999914074NIDFSS000.xml.gz
< 2014-06-04 14:12:48.451 150 Opening BINARY mode data connection for OSPRIONO999914074NIDFSS000.xml.gz (2578 bytes)
< 2014-06-04 14:12:48.675 226 Transfer complete
. 2014-06-04 14:12:48.675 Download successful
> 2014-06-04 14:12:51.825 Script: exit
. 2014-06-04 14:12:51.825 Script: Exit code: 1
. 2014-06-04 14:12:51.825 Disconnected from server

martin wrote:

Use file mask instead of specific path, i.e. get /dsf/fr/y2013/m06/d30/h07/GNA/GNARNAVS999913181HMRAWF000.xml.gz* (note the asterisk).
This makes WinSCP use MLSD to get list of all files to search for names matching mask. I.e. effectively doing what GUI does.

Thanks, this one is working fine :-)

Regards Heiko

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 41,440
Location:: Prague, Czechia

Re: Cannot download from specific FTP server with WinSCP.com

2014-06-10

eldnumh wrote:

This workaround does not help, it only changes the behaviour for ls from MLSD to LIST -a, but stat or get still uses MLST.

You are right. Added to tracker:
Issue 1181 – Make "Use MLSD command for directory listing" affect also use of MLST command

I'll send you a dev build with the fix.

Reply with quote

eldnumh
Joined:: 2014-06-02
Posts:: 4

Re: Cannot download from specific FTP server with WinSCP.com

2014-06-17 13:45

Thanks for the fix, today I could test it successfully. Here is a part of the log file:
First with calling stat before get

. 2014-06-17 14:24:43.585 WinSCP Version 5.6 (Dev Build 4425 2014-06-10) - Do NOT distribute (OS 6.1.7601 Service Pack 1 - Windows 7 Professional)
...
. 2014-06-17 14:24:43.585 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" /console=560 /consoleinstance=_3992_955 "/ini=nul" "/log=winscp.log" "/command" "open ftp://EXittXann:xxx@ftparch.giove.esa.int/dsf/fr/ -rawsettings FtpUseMlsd=1" "ls" 
...
. 2014-06-17 14:24:43.585 FTP: FTPS: None; Passive: Yes [Force IP: A]; MLSD: - [List all: A]
...
> 2014-06-17 14:24:47.837 Script: stat /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz 
. 2014-06-17 14:24:47.838 Listing file "/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz".
. 2014-06-17 14:24:47.838 Retrieving directory listing...
> 2014-06-17 14:24:47.838 CWD /dsf/fr/y2014/m03/d15/h13/OSP/
< 2014-06-17 14:24:47.928 250 CWD command successful
> 2014-06-17 14:24:47.928 PWD
< 2014-06-17 14:24:47.948 257 "/dsf/fr/y2014/m03/d15/h13/OSP" is the current directory
> 2014-06-17 14:24:47.949 TYPE A
< 2014-06-17 14:24:47.969 200 Type set to A
> 2014-06-17 14:24:47.969 PASV
< 2014-06-17 14:24:47.989 227 Entering Passive Mode (195,169,140,174,212,100)
> 2014-06-17 14:24:47.989 LIST -a
< 2014-06-17 14:24:48.033 150 Opening ASCII mode data connection for file list
. 2014-06-17 14:24:48.047 drwxr-xr-x   2 root     root           45 Mar 17 14:00 .
. 2014-06-17 14:24:48.047 drwxrwxr-x   6 root     root          189 Apr  3 15:06 ..
. 2014-06-17 14:24:48.047 -rw-r--r--   1 root     root         5595 Mar 17 14:00 OSPRIONO999914074NASEDF000.xml.gz
. 2014-06-17 14:24:48.047 -rw-r--r--   1 root     root         4139 Mar 17 14:00 OSPRIONO999914074NCONFG000.xml.gz
...
. 2014-06-17 14:24:48.047 -rw-r--r--   1 root     root         5043 Mar 17 14:00 OSPROSRT999914074NSSISA000.xml.gz
. 2014-06-17 14:24:48.047 -rw-r--r--   1 root     root         6204 Mar 17 14:00 OSPROSRT999914074NVSISA000.xml.gz
< 2014-06-17 14:24:48.257 226 Transfer complete
. 2014-06-17 14:24:48.257 Directory listing successful
. 2014-06-17 14:24:48.257 OSPRIONO999914074NIDFSS000.xml.gz;-;2578;2014-03-17T13:00:00.000Z;"root" [0];"root" [0];rw-r--r--;0
< 2014-06-17 14:24:48.257 Script: -rw-r--r--   0 root     root          2578 Mar 17 14:00 OSPRIONO999914074NIDFSS000.xml.gz
> 2014-06-17 14:24:51.725 Script: get /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz 
. 2014-06-17 14:24:51.725 Listing file "/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz".
. 2014-06-17 14:24:51.725 OSPRIONO999914074NIDFSS000.xml.gz;-;2578;2014-03-17T13:00:00.000Z;"root" [0];"root" [0];rw-r--r--;0
. 2014-06-17 14:24:51.725 File: '/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz' [2014-03-17T13:00:00.000Z] [2578]
. 2014-06-17 14:24:51.726 Copying "/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz" to local directory started.
. 2014-06-17 14:24:51.726 Binary transfer mode selected.
. 2014-06-17 14:24:51.726 Starting download of /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz
. 2014-06-17 14:24:51.726 Asking user:
. 2014-06-17 14:24:51.726 **Overwrite local file 'OSPRIONO999914074NIDFSS000.xml.gz'?**
. 2014-06-17 14:24:51.726 
. 2014-06-17 14:24:51.726 Destination directory already contains file 'OSPRIONO999914074NIDFSS000.xml.gz'.
. 2014-06-17 14:24:51.726 Choose, if you want to overwrite the file or skip this transfer and keep existing file.
. 2014-06-17 14:24:51.726  
. 2014-06-17 14:24:51.726 New:         2,578 bytes, 3/17/2014 2:00 PM
. 2014-06-17 14:24:51.726 Existing:    2,578 bytes, 3/17/2014 2:00:00 PM ()
< 2014-06-17 14:24:51.726 Script: Overwrite local file 'OSPRIONO999914074NIDFSS000.xml.gz'?
< 2014-06-17 14:24:51.726 
< 2014-06-17 14:24:51.726 Destination directory already contains file 'OSPRIONO999914074NIDFSS000.xml.gz'.
< 2014-06-17 14:24:51.726 Choose, if you want to overwrite the file or skip this transfer and keep existing file.
< 2014-06-17 14:24:51.726  
< 2014-06-17 14:24:51.726 New:       2,578 bytes, 3/17/2014 2:00 PM
< 2014-06-17 14:24:51.726 Existing:  2,578 bytes, 3/17/2014 2:00:00 PM
> 2014-06-17 14:25:02.829 TYPE I
< 2014-06-17 14:25:02.849 200 Type set to I
> 2014-06-17 14:25:02.849 PASV
< 2014-06-17 14:25:02.869 227 Entering Passive Mode (195,169,140,174,211,61)
> 2014-06-17 14:25:02.869 RETR OSPRIONO999914074NIDFSS000.xml.gz
< 2014-06-17 14:25:02.917 150 Opening BINARY mode data connection for OSPRIONO999914074NIDFSS000.xml.gz (2578 bytes)
< 2014-06-17 14:25:03.139 226 Transfer complete
. 2014-06-17 14:25:03.139 Download successful
> 2014-06-17 14:25:08.078 Script: exit
. 2014-06-17 14:25:08.078 Script: Exit code: 0
. 2014-06-17 14:25:08.080 Disconnected from server

Second without calling stat before get

. 2014-06-17 14:27:31.202 WinSCP Version 5.6 (Dev Build 4425 2014-06-10) - Do NOT distribute (OS 6.1.7601 Service Pack 1 - Windows 7 Professional)
...
. 2014-06-17 14:27:31.203 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" /console=560 /consoleinstance=_3992_955 "/ini=nul" "/log=winscp.log" "/command" "open ftp://EXittXann:xxx@ftparch.giove.esa.int/dsf/fr/ -rawsettings FtpUseMlsd=1" "ls" 
...
. 2014-06-17 14:27:31.203 FTP: FTPS: None; Passive: Yes [Force IP: A]; MLSD: - [List all: A]
...
> 2014-06-17 14:27:35.229 Script: get /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz 
. 2014-06-17 14:27:35.229 Listing file "/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz".
. 2014-06-17 14:27:35.229 Retrieving directory listing...
> 2014-06-17 14:27:35.229 CWD /dsf/fr/y2014/m03/d15/h13/OSP/
< 2014-06-17 14:27:35.248 250 CWD command successful
> 2014-06-17 14:27:35.248 PWD
< 2014-06-17 14:27:35.268 257 "/dsf/fr/y2014/m03/d15/h13/OSP" is the current directory
> 2014-06-17 14:27:35.268 TYPE A
< 2014-06-17 14:27:35.288 200 Type set to A
> 2014-06-17 14:27:35.289 PASV
< 2014-06-17 14:27:35.308 227 Entering Passive Mode (195,169,140,174,142,5)
> 2014-06-17 14:27:35.308 LIST -a
< 2014-06-17 14:27:35.350 150 Opening ASCII mode data connection for file list
< 2014-06-17 14:27:35.567 226 Transfer complete
. 2014-06-17 14:27:35.601 drwxr-xr-x   2 root     root           45 Mar 17 14:00 .
. 2014-06-17 14:27:35.601 drwxrwxr-x   6 root     root          189 Apr  3 15:06 ..
. 2014-06-17 14:27:35.601 -rw-r--r--   1 root     root         5595 Mar 17 14:00 OSPRIONO999914074NASEDF000.xml.gz
. 2014-06-17 14:27:35.601 -rw-r--r--   1 root     root         4139 Mar 17 14:00 OSPRIONO999914074NCONFG000.xml.gz
...
. 2014-06-17 14:27:35.601 -rw-r--r--   1 root     root         5043 Mar 17 14:00 OSPROSRT999914074NSSISA000.xml.gz
. 2014-06-17 14:27:35.601 -rw-r--r--   1 root     root         6204 Mar 17 14:00 OSPROSRT999914074NVSISA000.xml.gz
. 2014-06-17 14:27:35.601 Directory listing successful
. 2014-06-17 14:27:35.601 OSPRIONO999914074NIDFSS000.xml.gz;-;2578;2014-03-17T13:00:00.000Z;"root" [0];"root" [0];rw-r--r--;0
. 2014-06-17 14:27:35.602 File: '/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz' [2014-03-17T13:00:00.000Z] [2578]
. 2014-06-17 14:27:35.602 Copying "/dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz" to local directory started.
. 2014-06-17 14:27:35.602 Binary transfer mode selected.
. 2014-06-17 14:27:35.602 Starting download of /dsf/fr/y2014/m03/d15/h13/OSP/OSPRIONO999914074NIDFSS000.xml.gz
. 2014-06-17 14:27:35.602 Asking user:
. 2014-06-17 14:27:35.602 **Overwrite local file 'OSPRIONO999914074NIDFSS000.xml.gz'?**
. 2014-06-17 14:27:35.602 
. 2014-06-17 14:27:35.602 Destination directory already contains file 'OSPRIONO999914074NIDFSS000.xml.gz'.
. 2014-06-17 14:27:35.602 Choose, if you want to overwrite the file or skip this transfer and keep existing file.
. 2014-06-17 14:27:35.602  
. 2014-06-17 14:27:35.602 New:         2,578 bytes, 3/17/2014 2:00 PM
. 2014-06-17 14:27:35.602 Existing:    2,578 bytes, 3/17/2014 2:00:00 PM ()
< 2014-06-17 14:27:35.602 Script: Overwrite local file 'OSPRIONO999914074NIDFSS000.xml.gz'?
< 2014-06-17 14:27:35.602 
< 2014-06-17 14:27:35.602 Destination directory already contains file 'OSPRIONO999914074NIDFSS000.xml.gz'.
< 2014-06-17 14:27:35.602 Choose, if you want to overwrite the file or skip this transfer and keep existing file.
< 2014-06-17 14:27:35.603  
< 2014-06-17 14:27:35.603 New:       2,578 bytes, 3/17/2014 2:00 PM
< 2014-06-17 14:27:35.603 Existing:  2,578 bytes, 3/17/2014 2:00:00 PM
> 2014-06-17 14:27:37.255 TYPE I
< 2014-06-17 14:27:37.274 200 Type set to I
> 2014-06-17 14:27:37.274 PASV
< 2014-06-17 14:27:37.294 227 Entering Passive Mode (195,169,140,174,234,252)
> 2014-06-17 14:27:37.295 RETR OSPRIONO999914074NIDFSS000.xml.gz
< 2014-06-17 14:27:37.340 150 Opening BINARY mode data connection for OSPRIONO999914074NIDFSS000.xml.gz (2578 bytes)
< 2014-06-17 14:27:37.562 226 Transfer complete
. 2014-06-17 14:27:37.562 Download successful
> 2014-06-17 14:27:42.765 Script: exit
. 2014-06-17 14:27:42.766 Script: Exit code: 0
. 2014-06-17 14:27:42.767 Disconnected from server

Reply with quote

martin◆ Site Admin

Re: Cannot download from specific FTP server with WinSCP.com

2014-06-20

Thanks for your feedback!

Reply with quote

eldnumh
Joined:: 2014-06-02
Posts:: 4

Re: Cannot download from specific FTP server with WinSCP.com

2015-03-10 15:58

Today I tested this with the current version of WinSCP 5.7 again. Unfortunately it runs differently as with the WinSCP Version 5.6 (Dev Build 4425 2014-06-10).

. 2015-03-10 15:26:04.962 --------------------------------------------------------------------------
. 2015-03-10 15:26:04.962 WinSCP Version 5.7 (Build 5125) (OS 6.1.7601 Service Pack 1 - Windows 7 Professional)
. 2015-03-10 15:26:04.962 Configuration: nul
. 2015-03-10 15:26:04.962 Log level: Normal
. 2015-03-10 15:26:04.962 Local account: umf-adm1-PC\UMF-Admin1
. 2015-03-10 15:26:04.962 Working directory: C:\UMF\UMFBase\MMI\Sessions\20150690004\Downloads
. 2015-03-10 15:26:04.962 Process ID: 2740
. 2015-03-10 15:26:04.962 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" /console=570 /consoleinstance=_2196_591 "/log=winscp.log" "/ini=nul" "/command" "open ftp://EXittXann:xxx@ftparch.giove.esa.int/dsf/fr/ -rawsettings FtpUseMlsd=1" "ls" 
. 2015-03-10 15:26:04.962 Time zone: Current: GMT+1, Standard: GMT+1 (W. Europe Standard Time), DST: GMT+2 (W. Europe Daylight Time), DST Start: 29.03.2015, DST End: 25.10.2015
. 2015-03-10 15:26:04.962 Login time: Dienstag, 10. März 2015 15:26:04
. 2015-03-10 15:26:04.962 --------------------------------------------------------------------------
...
> 2015-03-10 15:26:05.159 SYST
< 2015-03-10 15:26:05.199 215 UNIX Type: L8
> 2015-03-10 15:26:05.199 FEAT
< 2015-03-10 15:26:05.237 211-Features:
< 2015-03-10 15:26:05.237  MDTM
< 2015-03-10 15:26:05.247  MFMT
< 2015-03-10 15:26:05.247  TVFS
< 2015-03-10 15:26:05.247  MFF modify;UNIX.group;UNIX.mode;
< 2015-03-10 15:26:05.247  MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.mode*;UNIX.owner*;
< 2015-03-10 15:26:05.247  REST STREAM
< 2015-03-10 15:26:05.247  SIZE
< 2015-03-10 15:26:05.247 211 End
. 2015-03-10 15:26:05.247 Connected
...
> 2015-03-10 15:26:11.232 Script: get /dsf/fr/y2014/m09/d25/h08/OSP/OSPROSRT999914268IRESOB000.sp3.gz Orbit\39d8_OSPROSRT999914268IRESOB000.sp3.gz
. 2015-03-10 15:26:11.232 Listing file "/dsf/fr/y2014/m09/d25/h08/OSP/OSPROSRT999914268IRESOB000.sp3.gz".
. 2015-03-10 15:26:11.232 Retrieving file information...
> 2015-03-10 15:26:11.232 PWD
< 2015-03-10 15:26:11.270 257 "/dsf/fr/y2014/m09/d21/OVF" is the current directory
> 2015-03-10 15:26:11.270 CWD /dsf/fr/y2014/m09/d25/h08/OSP/OSPROSRT999914268IRESOB000.sp3.gz
< 2015-03-10 15:26:11.311 550 /dsf/fr/y2014/m09/d25/h08/OSP/OSPROSRT999914268IRESOB000.sp3.gz: No such file or directory
> 2015-03-10 15:26:11.311 SIZE /dsf/fr/y2014/m09/d25/h08/OSP/OSPROSRT999914268IRESOB000.sp3.gz
< 2015-03-10 15:26:11.349 550 SIZE not allowed in ASCII mode
. 2015-03-10 15:26:11.349 Could not retrieve file information
< 2015-03-10 15:26:11.350 Script: Can't get attributes of file '/dsf/fr/y2014/m09/d25/h08/OSP/OSPROSRT999914268IRESOB000.sp3.gz'.
< 2015-03-10 15:26:11.350 Script: Could not retrieve file information
< 2015-03-10 15:26:11.350 SIZE not allowed in ASCII mode
. 2015-03-10 15:26:11.350 Script: Failed
> 2015-03-10 15:26:11.350 Script: pwd
< 2015-03-10 15:26:11.350 Script: /dsf/fr
...

Instead of the CWD on the folder, it does it with the file now. This returns with an 550 response, but the file definitely exists. Afterwards it does a SIZE, which was not called with the Dev Build. After this second 550 response, WinSCP seems to stop downloading. It this the intended behaviour?

Regards Heiko

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 41,440
Location:: Prague, Czechia

Re: Cannot download from specific FTP server with WinSCP.com

2015-03-12

eldnumh wrote:

Instead of the CWD on the folder, it does it with the file now. This returns with an 550 response, but the file definitely exists. Afterwards it does a SIZE, which was not called with the Dev Build. After this second 550 response, WinSCP seems to stop downloading. It this the intended behaviour?

This issue is tracked here:
Issue 1292 – Use FTP command SIZE in binary mode

Reply with quote