PutFile treats source file as a directory :: Support Forum

jamesmc
Joined:: 2017-06-29
Posts:: 2

PutFile treats source file as a directory

2017-06-29 09:48

Hi,

I have an interesting problem. I have copied some parts of a log file below, after the intialisation lines I have copied a successful transfer and a failed transfer for reference. On the failed transfer (for file 276962_205558567.tif) it seems that the FTP process is treating the source file as a folder and attempting to get a file list. Interestingly, this doesn't always fail.

I would appreciate any help anyone could provide. I am aware there are newer versions of the assembly available and I am happy to try it but I would like to know what the cause is and that it is addressed in a newer version.

Thanks.

. 2017-06-27 18:29:29.447 --------------------------------------------------------------------------
. 2017-06-27 18:29:29.447 WinSCP Version 5.8.4 RC (Build 6736) (OS 6.1.7601 Service Pack 1 - Windows Server 2008 R2 Standard)
. 2017-06-27 18:29:29.447 Configuration: nul
. 2017-06-27 18:29:29.447 Log level: Normal
. 2017-06-27 18:29:29.447 Local account: ***********
. 2017-06-27 18:29:29.447 Working directory: e:\carltonlive\v10\spclient\common\assemblies
. 2017-06-27 18:29:29.447 Process ID: 239896
. 2017-06-27 18:29:29.447 Command-line: "e:\carltonlive\v10\spclient\common\assemblies\winscp.exe" /xmllog="C:\Users\******\AppData\Local\Temp\4\wscp3AE60.032F0169.tmp" /xmlgroups /nointeractiveinput /dotnet=584 /ini=nul /log="\\ukdews309\usw\live\Vision\**************\FTPOut/ServerLog-20170627-183001.txt" /console /consoleinstance=_241248_31421019_789
. 2017-06-27 18:29:29.447 Time zone: Current: GMT+1, Standard: GMT+0 (GMT Standard Time), DST: GMT+1 (GMT Daylight Time), DST Start: 26/03/2017, DST End: 29/10/2017
. 2017-06-27 18:29:29.447 Login time: 27 June 2017 18:29:29
. 2017-06-27 18:29:29.447 --------------------------------------------------------------------------
. 2017-06-27 18:29:29.447 Script: Retrospectively logging previous script records:
> 2017-06-27 18:29:29.447 Script: option batch on
< 2017-06-27 18:29:29.447 Script: batch on
< 2017-06-27 18:29:29.447 Script: reconnecttime 120
> 2017-06-27 18:29:29.447 Script: option confirm off
< 2017-06-27 18:29:29.447 Script: confirm off
> 2017-06-27 18:29:29.447 Script: option reconnecttime 120
< 2017-06-27 18:29:29.447 Script: reconnecttime 120
> 2017-06-27 18:29:29.447 Script: open ftp://****************** -passive=1 -timeout=15
. 2017-06-27 18:29:29.447 --------------------------------------------------------------------------
. 2017-06-27 18:29:29.447 Session name: **************** (Ad-Hoc site)
. 2017-06-27 18:29:29.447 Host name: *************** (Port: 21)
. 2017-06-27 18:29:29.447 User name: ****** (Password: Yes, Key file: No)
. 2017-06-27 18:29:29.447 Transfer Protocol: FTP
. 2017-06-27 18:29:29.447 Ping type: Dummy, Ping interval: 30 sec; Timeout: 15 sec
. 2017-06-27 18:29:29.447 Disable Nagle: No
. 2017-06-27 18:29:29.447 Proxy: None
. 2017-06-27 18:29:29.447 Send buffer: 262144
. 2017-06-27 18:29:29.447 UTF: Auto
. 2017-06-27 18:29:29.447 FTPS: None [Client certificate: No]
. 2017-06-27 18:29:29.447 FTP: Passive: Yes [Force IP: Auto]; MLSD: Auto [List all: Auto]; HOST: Auto
. 2017-06-27 18:29:29.447 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2017-06-27 18:29:29.447 Cache directory changes: Yes, Permanent: Yes
. 2017-06-27 18:29:29.447 Recycle bin: Delete to: No, Overwritten to: No, Bin path:
. 2017-06-27 18:29:29.447 Timezone offset: 0h 0m
. 2017-06-27 18:29:29.447 --------------------------------------------------------------------------
. 2017-06-27 18:29:29.447 Connecting to *************** ...
. 2017-06-27 18:29:29.572 Connected with **************. Waiting for welcome message...
< 2017-06-27 18:29:30.040 220 ProFTPD Server
> 2017-06-27 18:29:30.040 USER ******
< 2017-06-27 18:29:30.164 331 Password required for *******
> 2017-06-27 18:29:30.164 PASS *****
< 2017-06-27 18:29:30.289 230 User ***** logged in
> 2017-06-27 18:29:30.289 SYST
< 2017-06-27 18:29:30.414 215 UNIX Type: L8
> 2017-06-27 18:29:30.414 FEAT
< 2017-06-27 18:29:30.539 211-Features:
< 2017-06-27 18:29:30.539 AUTH TLS
< 2017-06-27 18:29:30.539 CCC
< 2017-06-27 18:29:30.539 CLNT
< 2017-06-27 18:29:30.539 EPRT
< 2017-06-27 18:29:30.539 EPSV
< 2017-06-27 18:29:30.539 HOST
< 2017-06-27 18:29:30.539 MDTM
< 2017-06-27 18:29:30.539 MFF modify;UNIX.group;UNIX.mode;
< 2017-06-27 18:29:30.539 MFMT
< 2017-06-27 18:29:30.664 MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.groupname*;UNIX.mode*;UNIX.owner*;UNIX.ownername*;
< 2017-06-27 18:29:30.664 PBSZ
< 2017-06-27 18:29:30.664 PROT
< 2017-06-27 18:29:30.664 REST STREAM
< 2017-06-27 18:29:30.664 SIZE
< 2017-06-27 18:29:30.664 SSCN
< 2017-06-27 18:29:30.664 TVFS
< 2017-06-27 18:29:30.664 211 End
> 2017-06-27 18:29:30.664 CLNT WinSCP-release-5.8.4
< 2017-06-27 18:29:30.788 200 OK
> 2017-06-27 18:29:30.788 OPTS UTF8 ON
< 2017-06-27 18:29:30.913 500 OPTS UTF8 not understood
. 2017-06-27 18:29:30.913 Connected
. 2017-06-27 18:29:30.913 --------------------------------------------------------------------------
. 2017-06-27 18:29:30.913 Using FTP protocol.
. 2017-06-27 18:29:30.913 Doing startup conversation with host.
> 2017-06-27 18:29:30.913 PWD
< 2017-06-27 18:29:31.038 257 "/" is the current directory
. 2017-06-27 18:29:31.038 Getting current directory name.
. 2017-06-27 18:29:31.038 Startup conversation with host finished.
< 2017-06-27 18:29:31.038 Script: Active session: [1] ******@************
> 2017-06-27 18:29:31.085 Script: pwd
< 2017-06-27 18:29:31.085 Script: /

> 2017-06-27 18:29:33.706 Script: put -delete -nopermissions -preservetime -transfer="ascii" -- "\\ukdews309\usw\live\Vision\**************\FTPOut\1017561.properties" "/5749201/TargetDir/1017561.properties"
. 2017-06-27 18:29:33.706 Copying 1 files/directories to remote directory "/5749201/TargetDir/"
. 2017-06-27 18:29:33.706 PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 1017561.properties
. 2017-06-27 18:29:33.706 TM: A; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; InclM: ; ResumeL: 0
. 2017-06-27 18:29:33.706 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2017-06-27 18:29:33.706 File: '\\ukdews309\usw\live\Vision\**************\FTPOut\1017561.properties' [2017-06-27T17:29:12.879Z] [790]
. 2017-06-27 18:29:33.706 Copying "\\ukdews309\usw\live\Vision\**************\FTPOut\1017561.properties" to remote directory started.
. 2017-06-27 18:29:33.706 Ascii transfer mode selected.
. 2017-06-27 18:29:33.706 Starting upload of \\ukdews309\usw\live\Vision\**************\FTPOut\1017561.properties
> 2017-06-27 18:29:33.706 TYPE A
< 2017-06-27 18:29:33.830 200 Type set to A
> 2017-06-27 18:29:33.830 PASV
< 2017-06-27 18:29:33.955 227 Entering Passive Mode (**************,199,120)
> 2017-06-27 18:29:33.955 STOR 1017561.properties
< 2017-06-27 18:29:34.080 150 Opening ASCII mode data connection for 1017561.properties
< 2017-06-27 18:29:34.345 226 Transfer complete
> 2017-06-27 18:29:34.345 MFMT 20170627172912 1017561.properties
< 2017-06-27 18:29:34.470 213 Modify=20170627172912; 1017561.properties
. 2017-06-27 18:29:34.470 Upload successful
. 2017-06-27 18:29:34.470 Transfer done: '\\ukdews309\usw\live\Vision\**************\FTPOut\1017561.properties' [790]

> 2017-06-27 18:29:34.548 Script: put -nopermissions -preservetime -transfer="binary" -- "\\ukdews603\Vision Art\Art\27\276962_205558567.tif" "/5749201/TargetDir/1017561.tif"
. 2017-06-27 18:29:34.548 Copying 1 files/directories to remote directory "/5749201/TargetDir/"
. 2017-06-27 18:29:34.548 PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 1017561.tif
. 2017-06-27 18:29:34.548 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; InclM: ; ResumeL: 0
. 2017-06-27 18:29:34.548 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2017-06-27 18:29:34.548 File: '\\ukdews603\Vision Art\Art\27\276962_205558567.tif' [2017-06-23T14:47:46.000Z] [12320500]
* 2017-06-27 18:29:34.564 (EOSExtException) Error retrieving file list for "\\ukdews603\Vision Art\Art\27\276962_205558567.tif\*.*".
* 2017-06-27 18:29:34.564 System Error. Code: 267.
* 2017-06-27 18:29:34.564 The directory name is invalid
. 2017-06-27 18:29:34.564 Asking user:
. 2017-06-27 18:29:34.564 Error listing directory '\\ukdews603\Vision Art\Art\27\276962_205558567.tif\'. ("Error retrieving file list for ""\\ukdews603\Vision Art\Art\27\276962_205558567.tif\*.*"".","System Error. Code: 267.","The directory name is invalid")
< 2017-06-27 18:29:34.564 Script: Error listing directory '\\ukdews603\Vision Art\Art\27\276962_205558567.tif\'.
< 2017-06-27 18:29:34.564 Script: Error retrieving file list for "\\ukdews603\Vision Art\Art\27\276962_205558567.tif\*.*".

< 2017-06-27 18:29:34.564 System Error. Code: 267.

< 2017-06-27 18:29:34.564 The directory name is invalid
* 2017-06-27 18:29:34.564 (EScpSkipFile) Error listing directory '\\ukdews603\Vision Art\Art\27\276962_205558567.tif\'.
* 2017-06-27 18:29:34.564 Error retrieving file list for "\\ukdews603\Vision Art\Art\27\276962_205558567.tif\*.*".
* 2017-06-27 18:29:34.564 System Error. Code: 267.
* 2017-06-27 18:29:34.564 The directory name is invalid
. 2017-06-27 18:29:34.564 Script: Failed

Reply with quote

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

Re: PutFile treats source file as a directory

2017-06-29

Thanks for your report.
I have sent you an email with a debug version of WinSCP to the address you have used to register on this forum.

Reply with quote

jamesmc
Joined:: 2017-06-29
Posts:: 2

2017-06-29 11:54

Thanks for your response. I don't use winscp standalone, I use it interactively in code via the accompanying .NET assembly. Can I do this with the debug version that you sent?

Also, these errors are intermittent and the process that suffers them runs in a live environment and I wont be able to incorporate a debug version into it and I would't know in advance if the error was going to occur to know if the debug version has fixed it.

What is causing the error? If I know this then I may be able to create a scenario that always creates the problem.

Thanks.

Reply with quote