martin wrote:
This issue has been added to the tracker:
https://winscp.net/tracker/1403
I'm sending you an email with a development version of WinSCP to the address you have used to register on this forum.
Good morning,
thanks a lot for the dev-version. Now the failed LIST does not cancel the connection at the same step as before (see 11:02:15.230), but it fails at a later step (see 11:02:55.245):
. 2016-02-29 11:01:36.167 --------------------------------------------------------------------------
. 2016-02-29 11:01:36.167 WinSCP Version 5.8.2 (Dev Build 6237 2016-02-28) - Do NOT distribute (OS 5.2.3790 Service Pack 2 - Microsoft Windows Server 2003)
. 2016-02-29 11:01:36.167 Configuration: E:\temp\WinSCP.ini
. 2016-02-29 11:01:36.167 Log level: Normal
. 2016-02-29 11:01:36.167 Local account: SomeMaschine\SomeUser
. 2016-02-29 11:01:36.167 Working directory: E:\temp
. 2016-02-29 11:01:36.167 Process ID: 4084
. 2016-02-29 11:01:36.167 Command-line: "E:\temp\WinSCP.exe" /console=582 /consoleinstance=_6468_928 "/log=winscp.log"
. 2016-02-29 11:01:36.167 Time zone: Current: GMT+1, Standard: GMT+1 (W. Europe Standard Time), DST: GMT+2 (W. Europe Daylight Time), DST Start: 27.03.2016, DST End: 30.10.2016
. 2016-02-29 11:01:36.167 Login time: Monday, 29. February 2016 11:01:36
. 2016-02-29 11:01:36.167 --------------------------------------------------------------------------
. 2016-02-29 11:01:36.167 Script: Retrospectively logging previous script records:
> 2016-02-29 11:01:36.167 Script: open ftp://ftpuser:***@127.0.0.1/
. 2016-02-29 11:01:36.167 --------------------------------------------------------------------------
. 2016-02-29 11:01:36.167 Session name: ftpuser@127.0.0.1 (Ad-Hoc site)
. 2016-02-29 11:01:36.167 Host name: 127.0.0.1 (Port: 21)
. 2016-02-29 11:01:36.167 User name: ftpuser (Password: Yes, Key file: No)
. 2016-02-29 11:01:36.167 Transfer Protocol: FTP
. 2016-02-29 11:01:36.167 Ping type: Dummy, Ping interval: 30 sec; Timeout: 15 sec
. 2016-02-29 11:01:36.167 Disable Nagle: No
. 2016-02-29 11:01:36.167 Proxy: None
. 2016-02-29 11:01:36.167 Send buffer: 262144
. 2016-02-29 11:01:36.167 UTF: Auto
. 2016-02-29 11:01:36.167 FTP: FTPS: None [Client certificate: No]; Passive: Yes [Force IP: Auto]; MLSD: Auto [List all: Auto]
. 2016-02-29 11:01:36.167 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2016-02-29 11:01:36.167 Cache directory changes: Yes, Permanent: Yes
. 2016-02-29 11:01:36.167 Recycle bin: Delete to: No, Overwritten to: No, Bin path:
. 2016-02-29 11:01:36.167 Timezone offset: 0h 0m
. 2016-02-29 11:01:36.167 --------------------------------------------------------------------------
. 2016-02-29 11:01:36.183 Connecting to 127.0.0.1 ...
. 2016-02-29 11:01:36.183 Connected with 127.0.0.1. Waiting for welcome message...
< 2016-02-29 11:01:36.183 220 Microsoft FTP Service
> 2016-02-29 11:01:36.183 USER ftpuser
< 2016-02-29 11:01:36.183 331 Password required for ftpuser.
> 2016-02-29 11:01:36.183 PASS ********
< 2016-02-29 11:01:36.183 230 User ftpuser logged in.
> 2016-02-29 11:01:36.183 SYST
. 2016-02-29 11:01:36.183 The server is probably running Windows, assuming that directory listing timestamps are affected by DST.
< 2016-02-29 11:01:36.183 215 Windows_NT
> 2016-02-29 11:01:36.183 FEAT
< 2016-02-29 11:01:36.183 211-FEAT
< 2016-02-29 11:01:36.183 SIZE
< 2016-02-29 11:01:36.183 MDTM
< 2016-02-29 11:01:36.183 211 END
. 2016-02-29 11:01:36.198 Connected
. 2016-02-29 11:01:36.198 --------------------------------------------------------------------------
. 2016-02-29 11:01:36.198 Using FTP protocol.
. 2016-02-29 11:01:36.198 Doing startup conversation with host.
> 2016-02-29 11:01:36.198 PWD
< 2016-02-29 11:01:36.198 257 "/" is current directory.
. 2016-02-29 11:01:36.214 Getting current directory name.
. 2016-02-29 11:01:36.214 Startup conversation with host finished.
< 2016-02-29 11:01:36.214 Script: Active session: [1] ftpuser@127.0.0.1
> 2016-02-29 11:02:13.792 Script: cd TEST_FTP
. 2016-02-29 11:02:13.792 Cached directory change via "TEST_FTP" to "/TEST_FTP".
. 2016-02-29 11:02:13.792 Getting current directory name.
< 2016-02-29 11:02:13.792 Script: /TEST_FTP
. 2016-02-29 11:02:13.808 Sending dummy command to keep session alive.
> 2016-02-29 11:02:13.808 PWD
< 2016-02-29 11:02:13.808 257 "/" is current directory.
> 2016-02-29 11:02:15.214 Script: ls
. 2016-02-29 11:02:15.214 Retrieving directory listing...
> 2016-02-29 11:02:15.214 CWD /TEST_FTP/
< 2016-02-29 11:02:15.214 250 CWD command successful.
> 2016-02-29 11:02:15.214 PWD
< 2016-02-29 11:02:15.214 257 "/TEST_FTP" is current directory.
> 2016-02-29 11:02:15.214 TYPE A
< 2016-02-29 11:02:15.214 200 Type set to A.
> 2016-02-29 11:02:15.230 PASV
< 2016-02-29 11:02:15.230 227 Entering Passive Mode (127,0,0,1,180,132).
> 2016-02-29 11:02:15.230 LIST -a
. 2016-02-29 11:02:15.230 Connecting to 127.0.0.1:46212 ...
< 2016-02-29 11:02:15.230 125 Data connection already open; Transfer starting.
< 2016-02-29 11:02:15.230 550 -a: Access is denied.
. 2016-02-29 11:02:15.230 Could not retrieve directory listing
. 2016-02-29 11:02:15.230 LIST with -a failed, will try pure LIST
. 2016-02-29 11:02:15.230 Retrieving directory listing...
> 2016-02-29 11:02:15.230 TYPE A
< 2016-02-29 11:02:15.230 200 Type set to A.
> 2016-02-29 11:02:15.230 PASV
< 2016-02-29 11:02:15.230 227 Entering Passive Mode (194,64,221,246,180,134).
> 2016-02-29 11:02:15.230 LIST
. 2016-02-29 11:02:15.230 Connecting to 127.0.0.1:46214 ...
< 2016-02-29 11:02:15.230 125 Data connection already open; Transfer starting.
< 2016-02-29 11:02:15.230 550 .: Access is denied.
. 2016-02-29 11:02:15.230 Could not retrieve directory listing
. 2016-02-29 11:02:15.230 Asking user:
. 2016-02-29 11:02:15.230 Error listing directory '/TEST_FTP'. ("Could not retrieve directory listing",".: Access is denied. ")
< 2016-02-29 11:02:15.230 Script: Error listing directory '/TEST_FTP'.
< 2016-02-29 11:02:15.245 Script: Could not retrieve directory listing
< 2016-02-29 11:02:15.245 .: Access is denied.
> 2016-02-29 11:02:41.745 Script: lcd C:\UploadDir
< 2016-02-29 11:02:41.745 Script: C:\UploadDir
> 2016-02-29 11:02:44.402 Script: bin
< 2016-02-29 11:02:44.402 Script: transfer binary
> 2016-02-29 11:02:55.214 Script: put Testfile
. 2016-02-29 11:02:55.230 Copying 1 files/directories to remote directory "/TEST_FTP"
. 2016-02-29 11:02:55.230 PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask:
. 2016-02-29 11:02:55.230 TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; InclM: ; ResumeL: 0
. 2016-02-29 11:02:55.230 AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2016-02-29 11:02:55.230 File: 'Testfile' [2016-02-18T12:38:00.000Z] [7415197]
. 2016-02-29 11:02:55.245 Copying "Testfile" to remote directory started.
. 2016-02-29 11:02:55.245 Binary transfer mode selected.
. 2016-02-29 11:02:55.245 Sending dummy command to keep session alive.
> 2016-02-29 11:02:55.245 REST 0
< 2016-02-29 11:02:55.245 350 Restarting at 0.
. 2016-02-29 11:02:55.245 Starting upload of Testfile
> 2016-02-29 11:02:55.245 TYPE A
< 2016-02-29 11:02:55.245 200 Type set to A.
> 2016-02-29 11:02:55.245 PASV
< 2016-02-29 11:02:55.245 227 Entering Passive Mode (194,64,221,246,180,135).
> 2016-02-29 11:02:55.245 LIST
< 2016-02-29 11:02:55.245 125 Data connection already open; Transfer starting.
< 2016-02-29 11:02:55.245 550 .: Access is denied.
> 2016-02-29 11:02:55.245 SIZE Testfile
. 2016-02-29 11:02:55.245 Copying files to remote side failed.
* 2016-02-29 11:02:55.245 (ExtException) **Copying files to remote side failed.**
* 2016-02-29 11:02:55.245 .: Access is denied.
. 2016-02-29 11:02:55.245 Asking user:
. 2016-02-29 11:02:55.245 Error transferring file 'Testfile'. ("Copying files to remote side failed.",".: Access is denied. ")
< 2016-02-29 11:02:55.245 Script: Error transferring file 'Testfile'.
< 2016-02-29 11:02:55.261 Script: Copying files to remote side failed.
< 2016-02-29 11:02:55.261 .: Access is denied.
As told before I'm using two FTP-server to test, the logs above are from IIS, but my true transfer-destination is another maschine which seems to be even more touchy than the IIS. See below:
. 2016-02-29 11:23:18.201 --------------------------------------------------------------------------
. 2016-02-29 11:23:18.201 WinSCP Version 5.8.2 (Dev Build 6237 2016-02-28) - Do NOT distribute (OS 5.2.3790 Service Pack 2 - Microsoft Windows Server 2003)
. 2016-02-29 11:23:18.201 Configuration: E:\temp\WinSCP.ini
. 2016-02-29 11:23:18.201 Log level: Normal
. 2016-02-29 11:23:18.201 Local account: SomeMaschine\SomeUser
. 2016-02-29 11:23:18.201 Working directory: E:\temp
. 2016-02-29 11:23:18.201 Process ID: 500
. 2016-02-29 11:23:18.201 Command-line: "E:\temp\WinSCP.exe" /console=582 /consoleinstance=_4284_900 "/log=winscp.log"
. 2016-02-29 11:23:18.201 Time zone: Current: GMT+1, Standard: GMT+1 (W. Europe Standard Time), DST: GMT+2 (W. Europe Daylight Time), DST Start: 27.03.2016, DST End: 30.10.2016
. 2016-02-29 11:23:18.201 Login time: Monday, 29. February 2016 11:23:18
. 2016-02-29 11:23:18.201 --------------------------------------------------------------------------
. 2016-02-29 11:23:18.201 Script: Retrospectively logging previous script records:
> 2016-02-29 11:23:18.201 Script: open ftp://ftpuser:***@SomeOtherHost/
. 2016-02-29 11:23:18.201 --------------------------------------------------------------------------
. 2016-02-29 11:23:18.201 Session name: ftpuser@SomeOtherHost (Ad-Hoc site)
. 2016-02-29 11:23:18.201 Host name: SomeOtherHost (Port: 21)
. 2016-02-29 11:23:18.201 User name: ftpuser (Password: Yes, Key file: No)
. 2016-02-29 11:23:18.201 Transfer Protocol: FTP
. 2016-02-29 11:23:18.201 Ping type: Dummy, Ping interval: 30 sec; Timeout: 15 sec
. 2016-02-29 11:23:18.201 Disable Nagle: No
. 2016-02-29 11:23:18.201 Proxy: None
. 2016-02-29 11:23:18.201 Send buffer: 262144
. 2016-02-29 11:23:18.201 UTF: Auto
. 2016-02-29 11:23:18.201 FTP: FTPS: None [Client certificate: No]; Passive: Yes [Force IP: Auto]; MLSD: Auto [List all: Auto]
. 2016-02-29 11:23:18.201 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2016-02-29 11:23:18.201 Cache directory changes: Yes, Permanent: Yes
. 2016-02-29 11:23:18.201 Recycle bin: Delete to: No, Overwritten to: No, Bin path:
. 2016-02-29 11:23:18.201 Timezone offset: 0h 0m
. 2016-02-29 11:23:18.201 --------------------------------------------------------------------------
. 2016-02-29 11:23:18.217 Connecting to SomeOtherHost ...
. 2016-02-29 11:23:18.342 Connected with SomeOtherHost. Waiting for welcome message...
< 2016-02-29 11:23:18.389 220-FTPD1 IBM FTP CS V1R13 at SomeOtherHost, 11:23:18 on 2016-02-29.
< 2016-02-29 11:23:18.389 220 Connection will close if idle for more than 60 minutes.
> 2016-02-29 11:23:18.389 USER ftpuser
< 2016-02-29 11:23:18.529 331 Send password please.
> 2016-02-29 11:23:18.529 PASS ******
< 2016-02-29 11:23:18.654 230 ftpuser is logged on. Working directory is "/User/Test/Incoming/Destination".
> 2016-02-29 11:23:18.654 SYST
< 2016-02-29 11:23:18.670 500 User Exit denies Userid 'ftpuser' from using Command 'SYST'.
> 2016-02-29 11:23:18.670 FEAT
< 2016-02-29 11:23:18.670 500 User Exit denies Userid 'ftpuser' from using Command 'FEAT'.
. 2016-02-29 11:23:18.670 Connected
. 2016-02-29 11:23:18.670 --------------------------------------------------------------------------
. 2016-02-29 11:23:18.670 Using FTP protocol.
. 2016-02-29 11:23:18.670 Doing startup conversation with host.
> 2016-02-29 11:23:18.685 PWD
< 2016-02-29 11:23:18.685 500 User Exit denies Userid 'ftpuser' from using Command 'PWD'.
. 2016-02-29 11:23:18.685 Getting current directory name.
> 2016-02-29 11:23:18.685 PWD
< 2016-02-29 11:23:18.685 500 User Exit denies Userid 'ftpuser' from using Command 'PWD'.
* 2016-02-29 11:23:18.685 (ECommand) Error getting name of current remote directory.
* 2016-02-29 11:23:18.685 User Exit denies Userid 'ftpuser' from using Command 'PWD'.
. 2016-02-29 11:23:18.701 Attempt to close connection due to fatal exception:
* 2016-02-29 11:23:18.701 (ExtException) **User Exit denies Userid 'ftpuser' from using Command 'PWD'.**
. 2016-02-29 11:23:18.717 Disconnected from server
So even when LIST-problem using test-IIS is solved there's still a PWD Problem with the real target. @11:23:18.685 WinSCP queries the remote working directory, but this Information was already received @11:23:18.654.
Maybe for environments like mine an "autistic-mode" would be of great use, which must not necessarily be limited to the FTP-Client :wink: