GET -DELETE fails on 2nd file every time. 550 File doesn't exist

Hi!,

I have a weird problem with a simple GET -DELETE * command. It works only for the first file, then i get a 550 File doesn't exist error on the next. If i manually delete the first file in the list and run the script again, the previous failed one will download and delete with no problems, but the next in line will fail again. I've tested on two machines with different versions of WinSCP, and the same problem occurs. I suspect the remote server has some issues, but I can't be sure.

If I don't use the -DELETE switch, the command works flawlessly and gets all the files.

Any help much appreciated! Thanks

. 2017-10-31 12:33:47.877 --------------------------------------------------------------------------
. 2017-10-31 12:33:47.877 WinSCP Version 5.1.3 (Build 2881) (OS 6.0.6002 Service Pack 2)
. 2017-10-31 12:33:47.878 Configuration: c:\program files\winscp\WinSCP.ini
. 2017-10-31 12:33:47.880 Local account: *masked*
. 2017-10-31 12:33:47.880 Working directory: *masked*
. 2017-10-31 12:33:47.880 Command-line: "c:\program files\winscp\WinSCP.exe" /console=513 /consoleinstance=_17832_469 "*masked*" "/command" "GET -DELETE *.xml c:\Inbox\" "/log=c:\winscp.log"
. 2017-10-31 12:33:47.880 Time zone: Current: GMT+1, Standard: GMT+1, DST: GMT+2, DST Start: 3/26/2017, DST End: 10/29/2017
. 2017-10-31 12:33:47.880 Login time: Tuesday, October 31, 2017 12:33:47 PM
. 2017-10-31 12:33:47.880 --------------------------------------------------------------------------
. 2017-10-31 12:33:47.880 Session name: *masked* (Stored session)
. 2017-10-31 12:33:47.880 Host name: *masked* (Port: 21)
. 2017-10-31 12:33:47.880 User name: *masked* (Password: Yes, Key file: No)
. 2017-10-31 12:33:47.880 Tunnel: No
. 2017-10-31 12:33:47.881 Transfer Protocol: FTP
. 2017-10-31 12:33:47.881 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2017-10-31 12:33:47.881 Proxy: none
. 2017-10-31 12:33:47.881 FTP: FTPS: None; Passive: Yes [Force IP: A]
. 2017-10-31 12:33:47.881 Local directory: *masked*, Remote directory: *masked*, Update: No, Cache: No
. 2017-10-31 12:33:47.881 Cache directory changes: No, Permanent: Yes
. 2017-10-31 12:33:47.881 DST mode: 1
. 2017-10-31 12:33:47.881 --------------------------------------------------------------------------
. 2017-10-31 12:33:47.884 Connecting to *masked* ...
. 2017-10-31 12:33:47.900 Connected with *masked*. Waiting for welcome message...
< 2017-10-31 12:33:47.907 220 Blue Goat 8100-2 FTP Service
> 2017-10-31 12:33:47.907 USER *masked*
< 2017-10-31 12:33:47.984 331 User *masked* logged in, needs password
> 2017-10-31 12:33:47.984 PASS *masked*
< 2017-10-31 12:33:48.035 230 Password ok, FTP server ready
> 2017-10-31 12:33:48.036 SYST
< 2017-10-31 12:33:48.067 215 UNIX Type: L8
> 2017-10-31 12:33:48.067 FEAT
< 2017-10-31 12:33:48.096 211 No features
. 2017-10-31 12:33:48.097 Connected
. 2017-10-31 12:33:48.097 --------------------------------------------------------------------------
. 2017-10-31 12:33:48.097 Using FTP protocol.
. 2017-10-31 12:33:48.097 Doing startup conversation with host.
> 2017-10-31 12:33:48.097 PWD
< 2017-10-31 12:33:48.137 257 "/" PWD Successful.
. 2017-10-31 12:33:48.137 Changing directory to "*masked*".
> 2017-10-31 12:33:48.137 CWD *masked*
< 2017-10-31 12:33:48.171 250 CWD command successful.
. 2017-10-31 12:33:48.171 Getting current directory name.
> 2017-10-31 12:33:48.171 PWD
< 2017-10-31 12:33:48.212 257 "/receive" PWD Successful.
. 2017-10-31 12:33:48.212 Startup conversation with host finished.
< 2017-10-31 12:33:48.213 Script: Active session: [1] *masked*
> 2017-10-31 12:33:48.214 Script: GET -DELETE *.xml C:\Inbox\
. 2017-10-31 12:33:48.214 Retrieving directory listing...
> 2017-10-31 12:33:48.214 TYPE A
< 2017-10-31 12:33:48.244 200 Command okay.
> 2017-10-31 12:33:48.244 PASV
< 2017-10-31 12:33:48.255 227 Entering Passive Mode (10,11,73,235,222,82)
> 2017-10-31 12:33:48.255 LIST -a
< 2017-10-31 12:33:48.330 150 File status okay; about to open data connection.
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 08:37 invoice_300307507_2017-10-31-08-37-09{399d4ed6-3431-48ba-9b67-ae3dff5fab5e}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 08:37 invoice_300307510_2017-10-31-08-37-10{7fd2a437-0286-4807-b817-d54ba1c74293}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 08:37 invoice_300307509_2017-10-31-08-37-10{d280bd76-8fe7-4cf3-a7b9-a089637c53ba}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 08:46 invoice_56612_2017-10-31-08-46-52{413872df-dbda-49cd-ba5b-82a73480a5de}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 08:57 invoice_100032505_2017-10-31-08-57-35{113f40ea-32cd-4764-aa7c-4e91fa326ba5}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 08:57 invoice_100032506_2017-10-31-08-57-36{27ebe978-8d66-49b3-9e35-5a762414a602}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 09:31 invoice_006150_2017-10-31-09-31-34{9f188196-0b17-4b51-a21e-3e18c2716ad0}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 09:33 invoice_006157_2017-10-31-09-33-51{eab8303d-7169-43b4-ada0-4c437eaa191d}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 09:36 invoice_006160_2017-10-31-09-36-02{ed9b07fe-f947-420d-9873-0e28a084d8f1}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 09:38 invoice_006163_2017-10-31-09-38-43{13fa6d44-96b0-4921-b355-462766988ed4}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 09:40 invoice_006164_2017-10-31-09-40-22{37977088-ab7f-4e61-a424-a5b7985b46ed}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 10:09 invoice_787464_2017-10-31-10-09-20{79112cc1-a031-4175-8574-eafeaea55bda}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 10:28 invoice_416408_2017-10-31-10-28-47{9f8dfb6a-441e-4c94-8376-10257bfb47ff}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 10:52 invoice_1817_2017-10-31-10-52-34{c12c6796-29d3-4a9e-bd1e-a4ce88fc6c2c}.xml
. 2017-10-31 12:33:48.429 -rw-r--r-- 1 owner group 1 Oct 31 11:04 invoice_81338424_2017-10-31-11-04-48{3cf787ee-260c-4551-95a1-0e88b1eb0ebf}.xml
< 2017-10-31 12:33:48.523 226 Transfer complete, closing data connection.
. 2017-10-31 12:33:48.523 Directory listing successful
. 2017-10-31 12:33:48.523 File: "/receive/invoice_300307507_2017-10-31-08-37-09{399d4ed6-3431-48ba-9b67-ae3dff5fab5e}.xml"
. 2017-10-31 12:33:48.524 Copying "/receive/invoice_300307507_2017-10-31-08-37-09{399d4ed6-3431-48ba-9b67-ae3dff5fab5e}.xml" to local directory started.
. 2017-10-31 12:33:48.524 Binary transfer mode selected.
. 2017-10-31 12:33:48.530 Starting download of /receive/invoice_300307507_2017-10-31-08-37-09{399d4ed6-3431-48ba-9b67-ae3dff5fab5e}.xml
> 2017-10-31 12:33:48.595 TYPE I
< 2017-10-31 12:33:48.648 200 Command okay.
> 2017-10-31 12:33:48.648 PASV
< 2017-10-31 12:33:48.656 227 Entering Passive Mode (10,11,73,235,220,149)
> 2017-10-31 12:33:48.663 RETR invoice_300307507_2017-10-31-08-37-09{399d4ed6-3431-48ba-9b67-ae3dff5fab5e}.xml
< 2017-10-31 12:33:49.784 150 File status okay; about to open data connection.
< 2017-10-31 12:33:49.993 226 Transfer complete, closing data connection.
. 2017-10-31 12:33:50.005 Download successful
. 2017-10-31 12:33:50.005 Deleting file "/receive/invoice_300307507_2017-10-31-08-37-09{399d4ed6-3431-48ba-9b67-ae3dff5fab5e}.xml".
> 2017-10-31 12:33:50.006 DELE /receive/invoice_300307507_2017-10-31-08-37-09{399d4ed6-3431-48ba-9b67-ae3dff5fab5e}.xml
< 2017-10-31 12:33:50.611 250 File deleted successfully
. 2017-10-31 12:33:50.611 File: "/receive/invoice_300307510_2017-10-31-08-37-10{7fd2a437-0286-4807-b817-d54ba1c74293}.xml"
. 2017-10-31 12:33:50.612 Copying "/receive/invoice_300307510_2017-10-31-08-37-10{7fd2a437-0286-4807-b817-d54ba1c74293}.xml" to local directory started.
. 2017-10-31 12:33:50.612 Binary transfer mode selected.
. 2017-10-31 12:33:50.619 Starting download of /receive/invoice_300307510_2017-10-31-08-37-10{7fd2a437-0286-4807-b817-d54ba1c74293}.xml
> 2017-10-31 12:33:50.661 TYPE I
< 2017-10-31 12:33:50.691 200 Command okay.
> 2017-10-31 12:33:50.691 PASV
< 2017-10-31 12:33:50.697 227 Entering Passive Mode (10,11,73,235,209,32)
> 2017-10-31 12:33:50.705 RETR invoice_300307510_2017-10-31-08-37-10{7fd2a437-0286-4807-b817-d54ba1c74293}.xml
< 2017-10-31 12:33:50.814 550 File doesn't exist
. 2017-10-31 12:33:50.815 Copying files from remote side failed.
* 2017-10-31 12:33:50.815 (ExtException) Copying files from remote side failed.
* 2017-10-31 12:33:50.815 File doesn't exist
. 2017-10-31 12:33:50.815 Asking user:

While you should definitely try to upgrade to the latest version of WinSCP, I actually do not see anything wrong on WinSCP side.

I have the same error ... second file is always transfer with size kb 0 and then it stops..

Deleting the 0 byte file from my pc and then start again then the file is transferred but the next one fails..

its only when i use "get -Delete"

ravenweb wrote:

I have the same error ... second file is always transfer with size kb 0 and then it stops..

Deleting the 0 byte file from my pc and then start again then the file is transferred but the next one fails..

its only when i use "get -Delete"

Please attach a full session log file showing the problem (using the latest version of WinSCP).

Here is the log file

Thanks for the log.
But I seems that on WinSCP side everything works as it should. Do you have any reason to believe that's a problem with WinSCP?

So you think that the problem could be the ftp server

ravenweb wrote:

So you think that the problem could be the ftp server

Yes, it looks like that.