Forum » Support and Bug Reports »

Post a reply

Before posting, please read how to report bug or request support effectively.

Bug reports without an attached log file are usually useless.

Username

Subject

Message body

Font colour:

Font size:

[quote="Hubbel"]Good morning,

thanks a lot for the dev-version. Now the failed [m]LIST[/m] 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):
[code]@sessionlog@
. 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.
[/code]
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:
[code]@sessionlog@
. 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
[/code]
So even when [m]LIS[/m]T-problem using test-IIS is solved there's still a [m]PWD[/m] 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:[/quote]

Options

Disable BBCode in this post

Notify me when a reply is posted (registered users only)

Add an Attachment

If you do not want to add an Attachment to your Post, please leave the Fields blank.

Filename

(maximum 10 MB; please compress large files; only common media, archive, text and programming file formats are allowed)

File Comment

Options

Private file (available to author and site moderators only)

Topic review

martin

Re: Works under other clients...

2018-02-08

menezesj wrote:

The issue started occuring when the host changed permissions on the server to be a write-only drop box. Prior to this change, we had not been having any issues uploading.
Here are the logs from Filezilla uploading to the same site/account.

Please double check that you are really using the same credentials with WinSCP as with FileZilla.
As you can see in the log, WinSCP is doing the same command sequence as FileZilla, yet the server responds differently.
Also did you check server-side FTP log file or server-side Windows event log?

menezesj

Works under other clients...

2018-02-05 17:59

The issue started occuring when the host changed permissions on the server to be a write-only drop box. Prior to this change, we had not been having any issues uploading.
Here are the logs from FileZilla uploading to the same site/account.

Status:   Resolving address of swaftp1.schoolwires.com

Status:   Connecting to 52.206.191.229:21...

Status:   Connection established, waiting for welcome message...

Response:   220 Microsoft FTP Service

Command:   AUTH TLS

Response:   234 AUTH command ok. Expecting TLS Negotiation.

Status:   Initializing TLS...

Status:   Verifying certificate...

Status:   TLS connection established.

Command:   USER CA01*******

Response:   331 Password required

Command:   PASS ****************

Response:   230 User logged in.

Command:   OPTS UTF8 ON

Response:   200 OPTS UTF8 command successful - UTF8 encoding now ON.

Command:   PBSZ 0

Response:   200 PBSZ command successful.

Command:   PROT P

Response:   200 PROT command successful.

Status:   Logged in

Status:   Retrieving directory listing...

Command:   PWD

Response:   257 "/" is current directory.

Command:   TYPE I

Response:   200 Type set to I.

Command:   PASV

Response:   227 Entering Passive Mode (52,206,191,229,214,220).

Command:   LIST

Response:   550 Access is denied. 

Error:   Failed to retrieve directory listing

Status:   Starting upload of \\server\FTPFiles\SchoolWires\sw_user_import.csv

Status:   Retrieving directory listing of "/"...

Command:   PASV

Response:   227 Entering Passive Mode (52,206,191,229,214,219).

Command:   LIST

Response:   550 Access is denied. 

Command:   SIZE sw_user_import.csv

Response:   550 Access is denied. 

Command:   PASV

Response:   227 Entering Passive Mode (52,206,191,229,214,218).

Command:   STOR sw_user_import.csv

Response:   150 Opening BINARY mode data connection.

Response:   226 Transfer complete.

Status:   File transfer successful, transferred 172,485 bytes in 1 second

Status:   Retrieving directory listing of "/"...

Command:   PASV

Response:   227 Entering Passive Mode (52,206,191,229,214,222).

Command:   LIST

Response:   550 Access is denied. 

Error:   Failed to retrieve directory listing

martin

2018-01-11

menezesj wrote:

Hello,
I am also running into the same issue when uploading to one of our vendor's FTP servers. The account is configured with no read permissions to the directory, only as a drop box. I have upgraded to the latest version, 5.11.3 in the hopes it would resolve the issue, to no avail.

This does not look related to directory permissions.
Why do you think so? Can you upload to other folders?
Please start a new thread, as this seems unrelated.
Can you upload files to that folder using any other FTP client? If you can, post its log file.

menezesj

2018-01-08 21:41

Hello,
I am also running into the same issue when uploading to one of our vendor's FTP servers. The account is configured with no read permissions to the directory, only as a drop box. I have upgraded to the latest version, 5.11.3 in the hopes it would resolve the issue, to no avail.
This is the logs we get from our end:

Connecting to swaftp1.schoolwires.com ...

TLS connection established. Waiting for welcome message...

Connected

Starting the session...

Session started.

Active session: [1] USERAccount@swaftp1.schoolwires.com

/

e:\...\sw_user_import.csv |            0 B |    0.0 KB/s | binary |   0%

Error transferring file 'e:\ftpfiles\SchoolWires\sw_user_import.csv'.

Using TLSv1.2, cipher TLSv1/SSLv3: ECDHE-RSA-AES256-SHA384, 2048 bit RSA, ECDHE-

RSA-AES256-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AES(256)  Mac=SHA384

Copying files to remote side failed.

The specified network name is no longer available.

(A)bort, (R)etry, (S)kip, Ski(p) all: Abort

Uploading using the GUI yields a "Copying files to remote side failed. Access is denied." error.

Any help would be greatly appreciated.

Edit: Below is the detailed log we get:

. 2018-01-08 12:46:52.520 --------------------------------------------------------------------------
. 2018-01-08 12:46:52.520 WinSCP Version 5.11.3 (Build 7995) (OS 6.3.9600 - Windows Server 2012 R2 Standard)
. 2018-01-08 12:46:52.520 Configuration: HKCU\Software\Martin Prikryl\WinSCP 2\
. 2018-01-08 12:46:52.520 Log level: Normal
. 2018-01-08 12:46:52.520 Local account: CVUSD\LocalWindowsUserAcct
. 2018-01-08 12:46:52.521 Working directory: C:\Users\LocalWindowsUserAcct
. 2018-01-08 12:46:52.521 Process ID: 14564
. 2018-01-08 12:46:52.521 Command-line: "c:\Program Files (x86)\WinSCP\WinSCP.exe" /console=5.11.3 /consoleinstance=_12580_780 "/script=e:\FTPFiles\SchoolWires\SWFTP.txt" "/log=c:\users\LocalWindowsUserAcct\documents\sw.log" 
. 2018-01-08 12:46:52.521 Time zone: Current: GMT-8, Standard: GMT-8 (Pacific Standard Time), DST: GMT-7 (Pacific Daylight Time), DST Start: 3/11/2018, DST End: 11/4/2018
. 2018-01-08 12:46:52.521 Login time: Monday, January 8, 2018 12:46:52 PM
. 2018-01-08 12:46:52.521 --------------------------------------------------------------------------
. 2018-01-08 12:46:52.521 Script: Retrospectively logging previous script records:
> 2018-01-08 12:46:52.521 Script: open ftps://<UserAccount>:***@swaftp1.schoolwires.com:21/ -explicit
. 2018-01-08 12:46:52.521 --------------------------------------------------------------------------
. 2018-01-08 12:46:52.521 Session name: <UserAccount>@swaftp1.schoolwires.com (Ad-Hoc site)
. 2018-01-08 12:46:52.521 Host name: swaftp1.schoolwires.com (Port: 21)
. 2018-01-08 12:46:52.521 User name: <UserAccount> (Password: Yes, Key file: No, Passphrase: No)
. 2018-01-08 12:46:52.521 Transfer Protocol: FTP
. 2018-01-08 12:46:52.521 Ping type: Dummy, Ping interval: 30 sec; Timeout: 15 sec
. 2018-01-08 12:46:52.521 Disable Nagle: No
. 2018-01-08 12:46:52.521 Proxy: None
. 2018-01-08 12:46:52.521 Send buffer: 262144
. 2018-01-08 12:46:52.521 UTF: Auto
. 2018-01-08 12:46:52.521 FTPS: Explicit TLS/SSL [Client certificate: No]
. 2018-01-08 12:46:52.521 FTP: Passive: Yes [Force IP: Auto]; MLSD: Auto [List all: Auto]; HOST: Auto
. 2018-01-08 12:46:52.521 Session reuse: Yes
. 2018-01-08 12:46:52.521 TLS/SSL versions: TLSv1.0-TLSv1.2
. 2018-01-08 12:46:52.521 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2018-01-08 12:46:52.521 Cache directory changes: Yes, Permanent: Yes
. 2018-01-08 12:46:52.521 Recycle bin: Delete to: No, Overwritten to: No, Bin path: 
. 2018-01-08 12:46:52.521 Timezone offset: 0h 0m
. 2018-01-08 12:46:52.521 --------------------------------------------------------------------------
. 2018-01-08 12:46:52.522 Connecting to swaftp1.schoolwires.com ...
. 2018-01-08 12:46:52.525 Connected with swaftp1.schoolwires.com, negotiating TLS connection...
< 2018-01-08 12:46:52.677 220 Microsoft FTP Service
> 2018-01-08 12:46:52.678 AUTH TLS
< 2018-01-08 12:46:52.754 234 AUTH command ok. Expecting TLS Negotiation.
. 2018-01-08 12:46:53.028 Verifying certificate for "Schoolwires, Inc." with fingerprint 9b:47:ac:eb:4f:c2:a6:91:ea:f6:f3:51:39:1d:e0:5c:72:f1:25:cb and 20 failures
. 2018-01-08 12:46:53.028 Certificate common name "*.schoolwires.com" matches hostname
. 2018-01-08 12:46:53.056 Certificate verified against Windows certificate store
. 2018-01-08 12:46:53.057 Using TLSv1.2, cipher TLSv1/SSLv3: ECDHE-RSA-AES256-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AES(256)  Mac=SHA384
. 2018-01-08 12:46:53.057 TLS connection established. Waiting for welcome message...
> 2018-01-08 12:46:53.057 USER <UserAccount>
< 2018-01-08 12:46:53.133 331 Password required
> 2018-01-08 12:46:53.133 PASS ****************
< 2018-01-08 12:46:53.210 230 User logged in.
> 2018-01-08 12:46:53.210 SYST
. 2018-01-08 12:46:53.286 The server is probably running Windows, assuming that directory listing timestamps are affected by DST.
< 2018-01-08 12:46:53.286 215 Windows_NT
> 2018-01-08 12:46:53.286 FEAT
< 2018-01-08 12:46:53.363 211-Extended features supported:
< 2018-01-08 12:46:53.363  LANG EN*
< 2018-01-08 12:46:53.363  UTF8
< 2018-01-08 12:46:53.363  AUTH TLS;TLS-C;SSL;TLS-P;
< 2018-01-08 12:46:53.363  PBSZ
< 2018-01-08 12:46:53.363  PROT C;P;
< 2018-01-08 12:46:53.363  CCC
< 2018-01-08 12:46:53.363  HOST
< 2018-01-08 12:46:53.363  SIZE
< 2018-01-08 12:46:53.363  MDTM
< 2018-01-08 12:46:53.363  REST STREAM
< 2018-01-08 12:46:53.363 211 END
> 2018-01-08 12:46:53.364 OPTS UTF8 ON
< 2018-01-08 12:46:53.439 200 OPTS UTF8 command successful - UTF8 encoding now ON.
> 2018-01-08 12:46:53.439 PBSZ 0
< 2018-01-08 12:46:53.516 200 PBSZ command successful.
> 2018-01-08 12:46:53.516 PROT P
< 2018-01-08 12:46:53.592 200 PROT command successful.
. 2018-01-08 12:46:53.592 Connected
. 2018-01-08 12:46:53.592 --------------------------------------------------------------------------
. 2018-01-08 12:46:53.592 Using FTP protocol.
. 2018-01-08 12:46:53.592 Doing startup conversation with host.
> 2018-01-08 12:46:53.592 PWD
< 2018-01-08 12:46:53.669 257 "/" is current directory.
. 2018-01-08 12:46:53.669 Getting current directory name.
. 2018-01-08 12:46:53.669 Startup conversation with host finished.
< 2018-01-08 12:46:53.669 Script: Active session: [1] <UserAccount>@swaftp1.schoolwires.com
> 2018-01-08 12:46:53.670 Script: cd /
. 2018-01-08 12:46:53.670 Changing directory to "/".
> 2018-01-08 12:46:53.670 CWD /
< 2018-01-08 12:46:53.747 250 CWD command successful.
. 2018-01-08 12:46:53.747 Getting current directory name.
> 2018-01-08 12:46:53.747 PWD
< 2018-01-08 12:46:53.823 257 "/" is current directory.
< 2018-01-08 12:46:53.823 Script: /
> 2018-01-08 12:46:53.825 Script: put e:\ftpfiles\SchoolWires\sw_user_import.csv
. 2018-01-08 12:46:53.825 Copying 1 files/directories to remote directory "/" - total size: 170,102
. 2018-01-08 12:46:53.825   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 
. 2018-01-08 12:46:53.825   TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; InclM: ; ResumeL: 0
. 2018-01-08 12:46:53.825   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2018-01-08 12:46:53.826 File: 'e:\ftpfiles\SchoolWires\sw_user_import.csv' [2018-01-08T07:50:01.048Z] [170102]
. 2018-01-08 12:46:53.826 Copying "e:\ftpfiles\SchoolWires\sw_user_import.csv" to remote directory started.
. 2018-01-08 12:46:53.826 Binary transfer mode selected.
. 2018-01-08 12:46:53.826 Starting upload of e:\ftpfiles\SchoolWires\sw_user_import.csv
> 2018-01-08 12:46:53.826 TYPE I
< 2018-01-08 12:46:53.903 200 Type set to I.
> 2018-01-08 12:46:53.903 PASV
< 2018-01-08 12:46:53.980 227 Entering Passive Mode (52,206,191,229,214,221).
> 2018-01-08 12:46:53.980 STOR sw_user_import.csv
. 2018-01-08 12:46:53.980 Connecting to 52.206.191.229:55005 ...
< 2018-01-08 12:46:54.057 150 Opening BINARY mode data connection.
. 2018-01-08 12:46:54.141 Session ID reused
. 2018-01-08 12:46:54.141 Using TLSv1.2, cipher TLSv1/SSLv3: ECDHE-RSA-AES256-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AES(256)  Mac=SHA384
. 2018-01-08 12:46:54.141 TLS connection established
. 2018-01-08 12:46:54.142 Data connection closed
< 2018-01-08 12:46:54.217 550 The specified network name is no longer available. 
. 2018-01-08 12:46:54.217 Copying files to remote side failed.
* 2018-01-08 12:46:54.218 (ExtException) **Using TLSv1.2, cipher TLSv1/SSLv3: ECDHE-RSA-AES256-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AES(256)  Mac=SHA384
* 2018-01-08 12:46:54.218 **
* 2018-01-08 12:46:54.218 Copying files to remote side failed.
* 2018-01-08 12:46:54.218 The specified network name is no longer available. 
. 2018-01-08 12:46:54.218 Asking user:
. 2018-01-08 12:46:54.218 Error transferring file 'e:\ftpfiles\SchoolWires\sw_user_import.csv'. ("Using TLSv1.2, cipher TLSv1/SSLv3: ECDHE-RSA-AES256-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AES(256)  Mac=SHA384
. 2018-01-08 12:46:54.218 ","Copying files to remote side failed.","The specified network name is no longer available. ")
< 2018-01-08 12:46:54.218 Script: Error transferring file 'e:\ftpfiles\SchoolWires\sw_user_import.csv'.
< 2018-01-08 12:46:54.218 Script: Using TLSv1.2, cipher TLSv1/SSLv3: ECDHE-RSA-AES256-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AES(256)  Mac=SHA384
< 2018-01-08 12:46:54.218 
 
< 2018-01-08 12:46:54.218 Copying files to remote side failed.
 
< 2018-01-08 12:46:54.218 The specified network name is no longer available.
* 2018-01-08 12:46:54.219 (EScpSkipFile) Error transferring file 'e:\ftpfiles\SchoolWires\sw_user_import.csv'.
* 2018-01-08 12:46:54.219 Using TLSv1.2, cipher TLSv1/SSLv3: ECDHE-RSA-AES256-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AES(256)  Mac=SHA384
* 2018-01-08 12:46:54.219 
* 2018-01-08 12:46:54.219 Copying files to remote side failed.
* 2018-01-08 12:46:54.219 The specified network name is no longer available. 
. 2018-01-08 12:46:54.219 Copying finished: Transferred: 16,384, Elapsed: 0:00:00, CPS: 105,025/s
. 2018-01-08 12:46:54.219 Script: Failed
. 2018-01-08 12:46:54.219 Script: Exit code: 1
. 2018-01-08 12:46:54.220 Disconnected from server

Hubbel

Re: Problem still exists

2016-02-29 11:42

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:

martin

Re: Problem still exists

2016-02-28

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.

martin

Re: Problem still exists

2016-02-25

Thanks for your report. I will look into it and will contact you once I have anything to test.

Hubbel

Problem still exists

2016-02-24 13:26

Hi there,

I'm experiencing the same problem – WinSCP tries to list the directory it should upload to without being instructed to list. As read access is forbidden (I can't influence that decision) it fails to upload. I checked it vice versa on a test system where I'm in control of the FTP-Server and gave the read-permission – it works great.

Here's the log (with some anonymisation):


. 2016-02-24 11:54:42.618 Using FTP protocol.

. 2016-02-24 11:54:42.618 Doing startup conversation with host.

> 2016-02-24 11:54:42.633 PWD

< 2016-02-24 11:54:42.633 257 "/" is current directory.

. 2016-02-24 11:54:42.633 Getting current directory name.

. 2016-02-24 11:54:42.633 Startup conversation with host finished.

< 2016-02-24 11:54:42.633 Script: Active session: [1] ftpuser@127.0.0.1

> 2016-02-24 11:55:00.508 Script: bin

< 2016-02-24 11:55:00.508 Script: transfer        binary    

> 2016-02-24 11:55:12.165 Script: cd TEST_FTP

. 2016-02-24 11:55:12.165 Cached directory change via "TEST_FTP" to "/TEST_FTP".

. 2016-02-24 11:55:12.165 Getting current directory name.

< 2016-02-24 11:55:12.165 Script: /TEST_FTP

> 2016-02-24 11:55:19.212 Script: lcd C:\UploadDir\

< 2016-02-24 11:55:19.212 Script: C:\UploadDir

. 2016-02-24 11:55:19.212 Sending dummy command to keep session alive.

> 2016-02-24 11:55:19.212 TYPE A

< 2016-02-24 11:55:19.212 200 Type set to A.

> 2016-02-24 11:55:27.102 Script: mput *

. 2016-02-24 11:55:27.102 Copying 1 files/directories to remote directory "/TEST_FTP"

. 2016-02-24 11:55:27.102   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 

. 2016-02-24 11:55:27.102   TM: B; ClAr: No; RemEOF: No; RemBOM: No; CPS: 0; NewerOnly: No; InclM: ; ResumeL: 0

. 2016-02-24 11:55:27.102   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml

. 2016-02-24 11:55:27.102 File: 'Testfile' [2016-02-09T09:22:35.298Z] [3007005]

. 2016-02-24 11:55:27.102 Copying "Testfile" to remote directory started.

. 2016-02-24 11:55:27.102 Binary transfer mode selected.

. 2016-02-24 11:55:27.102 Starting upload of Testfile

> 2016-02-24 11:55:27.102 CWD /TEST_FTP/

< 2016-02-24 11:55:27.102 250 CWD command successful.

> 2016-02-24 11:55:27.102 PWD

< 2016-02-24 11:55:27.102 257 "/TEST_FTP" is current directory.

> 2016-02-24 11:55:27.102 TYPE A

< 2016-02-24 11:55:27.102 200 Type set to A.

> 2016-02-24 11:55:27.102 PORT 1,0,0,1,170,168

< 2016-02-24 11:55:27.102 200 PORT command successful.

> 2016-02-24 11:55:27.102 LIST

< 2016-02-24 11:55:27.102 150 Opening ASCII mode data connection for /bin/ls.

< 2016-02-24 11:55:27.102 550 .: Access is denied. 

> 2016-02-24 11:55:27.102 SIZE Testfile

. 2016-02-24 11:55:27.102 Copying files to remote side failed.

* 2016-02-24 11:55:27.102 (ExtException) **Copying files to remote side failed.**

* 2016-02-24 11:55:27.102 .: Access is denied. 

. 2016-02-24 11:55:27.102 Asking user:

. 2016-02-24 11:55:27.102 Error transferring file 'Testfile'. ("Copying files to remote side failed.",".: Access is denied. ")

< 2016-02-24 11:55:27.102 Script: Error transferring file 'Testfile'.

< 2016-02-24 11:55:27.118 Script: Copying files to remote side failed.

< 2016-02-24 11:55:27.118 .: Access is denied.

I can't find a way to prevent the LIST-command, my idea for a workaround is to ignore server responses (as the pure upload with ftp.exe works), but I can't figure out how. Any hint for me?

martin

Re: Putting files without read access in the ftp server

2009-06-03

This issue is being tracked here.

qwerty1

Same issue.

2009-06-02 18:53

I have the same issue with identical logs. What was the solution?

Bascy

2008-07-03 08:48

sending you the log file

martin

2008-07-02

I'm sending you a debug version.

Bascy

2008-07-02 13:43

. 2008-07-02 14:41:02.181 --------------------------------------------------------------------------
. 2008-07-02 14:41:02.181 WinSCP Version 4.0.7 (Build 370) (OS 5.2.3790 Service Pack 2)
. 2008-07-02 14:41:02.181 Login time: woensdag 2 juli 2008 14:41:02
. 2008-07-02 14:41:02.181 --------------------------------------------------------------------------
. 2008-07-02 14:41:02.181 Session name: www.x.com
. 2008-07-02 14:41:02.181 Host name: www.x.com (Port: 21)
. 2008-07-02 14:41:02.181 User name:  (Password: No, Key file: No)
. 2008-07-02 14:41:02.181 Tunnel: No
. 2008-07-02 14:41:02.181 Transfer Protocol: FTP
. 2008-07-02 14:41:02.181 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2008-07-02 14:41:02.181 Proxy: none
. 2008-07-02 14:41:02.181 FTP: Passive: No
. 2008-07-02 14:41:02.181 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2008-07-02 14:41:02.181 Cache directory changes: Yes, Permanent: Yes
. 2008-07-02 14:41:02.181 DST mode: 1
. 2008-07-02 14:41:02.181 --------------------------------------------------------------------------
. 2008-07-02 14:41:02.181 Username prompt (no username provided)
. 2008-07-02 14:41:04.806 Password prompt (no password provided or last login attempt failed)
. 2008-07-02 14:41:08.681 Connecting to www.x.com ...
. 2008-07-02 14:41:08.697 Connected with www.x.com. Waiting for welcome message...
< 2008-07-02 14:41:09.275 220 Microsoft FTP Service
> 2008-07-02 14:41:09.275 USER anonymous\
< 2008-07-02 14:41:09.572 331 Password required for anonymous\.
> 2008-07-02 14:41:09.572 PASS ********
< 2008-07-02 14:41:12.416 530 User anonymous\ cannot log in.
. 2008-07-02 14:41:12.416 Connection failed.
. 2008-07-02 14:41:12.416 Username prompt (no username provided)
. 2008-07-02 14:41:16.681 Password prompt (no password provided or last login attempt failed)
. 2008-07-02 14:41:19.572 Connecting to www.x.com ...
. 2008-07-02 14:41:19.572 Connected with www.x.com. Waiting for welcome message...
< 2008-07-02 14:41:19.822 220 Microsoft FTP Service
> 2008-07-02 14:41:19.822 USER anonymous
< 2008-07-02 14:41:20.041 331 Anonymous access allowed, send identity (e-mail name) as password.
> 2008-07-02 14:41:20.041 PASS ************
< 2008-07-02 14:41:20.260 230 Anonymous user logged in.
> 2008-07-02 14:41:20.260 SYST
< 2008-07-02 14:41:20.431 215 Windows_NT
> 2008-07-02 14:41:20.431 FEAT
< 2008-07-02 14:41:20.572 211-FEAT
< 2008-07-02 14:41:20.744     SIZE
< 2008-07-02 14:41:20.744     MDTM
< 2008-07-02 14:41:20.744 211 END
. 2008-07-02 14:41:20.744 Connected
. 2008-07-02 14:41:20.744 --------------------------------------------------------------------------
. 2008-07-02 14:41:20.744 Using FTP protocol.
. 2008-07-02 14:41:20.744 Doing startup conversation with host.
> 2008-07-02 14:41:20.744 PWD
< 2008-07-02 14:41:20.822 257 "/" is current directory.
. 2008-07-02 14:41:20.822 Getting current directory name.
. 2008-07-02 14:41:20.822 Startup conversation with host finished.
. 2008-07-02 14:41:22.853 Changing directory to "uoload".
> 2008-07-02 14:41:22.853 CWD uoload
< 2008-07-02 14:41:22.900 550 uoload: The system cannot find the file specified. 
* 2008-07-02 14:41:22.900 (ECommand) Error changing directory to 'uoload'.
* 2008-07-02 14:41:22.900 uoload: The system cannot find the file specified. 
. 2008-07-02 14:41:24.056 Retrieving directory listing...
> 2008-07-02 14:41:24.056 TYPE A
< 2008-07-02 14:41:24.244 200 Type set to A.
> 2008-07-02 14:41:24.244 PORT 10,53,47,6,14,129
< 2008-07-02 14:41:24.369 200 PORT command successful.
> 2008-07-02 14:41:24.369 LIST -a
< 2008-07-02 14:41:24.416 150 Opening ASCII mode data connection for /bin/ls.
< 2008-07-02 14:41:24.619 226 Transfer complete.
. 2008-07-02 14:41:24.619 Directory listing successful
. 2008-07-02 14:41:28.353 Cached directory change via "upload" to "/upload".
. 2008-07-02 14:41:28.353 Getting current directory name.
. 2008-07-02 14:41:31.072 Retrieving directory listing...
> 2008-07-02 14:41:31.088 CWD /upload/
< 2008-07-02 14:41:31.119 250 CWD command successful.
> 2008-07-02 14:41:31.119 PWD
< 2008-07-02 14:41:31.166 257 "/upload" is current directory.
> 2008-07-02 14:41:31.166 TYPE A
< 2008-07-02 14:41:31.275 200 Type set to A.
> 2008-07-02 14:41:31.275 PORT 10,53,47,6,14,131
< 2008-07-02 14:41:31.400 200 PORT command successful.
> 2008-07-02 14:41:31.400 LIST -a
< 2008-07-02 14:41:31.525 150 Opening ASCII mode data connection for /bin/ls.
< 2008-07-02 14:41:31.744 550 -a: Access is denied. 
. 2008-07-02 14:41:31.744 Could not retrieve directory listing
. 2008-07-02 14:41:31.744 Asking user:
. 2008-07-02 14:41:31.744 Error listing directory '/upload'. ("Could not retrieve directory listing","-a: Access is denied. ")

as expected, cause the user doesnt have read rights

martin

2008-07-02

Can you try doing ls before put? Please post a log file again then.

Bascy

2008-07-02 13:31

Logfile:

. 2008-07-02 14:29:42.510 Session name: www.x.com
. 2008-07-02 14:29:42.510 Host name: www.x.com (Port: 21)
. 2008-07-02 14:29:42.510 User name:  (Password: No, Key file: No)
. 2008-07-02 14:29:42.510 Tunnel: No
. 2008-07-02 14:29:42.510 Transfer Protocol: FTP
. 2008-07-02 14:29:42.510 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2008-07-02 14:29:42.510 Proxy: none
. 2008-07-02 14:29:42.510 FTP: Passive: No
. 2008-07-02 14:29:42.510 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2008-07-02 14:29:42.510 Cache directory changes: Yes, Permanent: Yes
. 2008-07-02 14:29:42.510 DST mode: 1
. 2008-07-02 14:29:42.510 --------------------------------------------------------------------------
. 2008-07-02 14:29:42.510 Username prompt (no username provided)
. 2008-07-02 14:29:45.572 Password prompt (no password provided or last login attempt failed)
. 2008-07-02 14:29:49.228 Connecting to www.x.com ...
. 2008-07-02 14:29:49.244 Connected with www.x.com. Waiting for welcome message...
< 2008-07-02 14:29:49.338 220 Microsoft FTP Service
> 2008-07-02 14:29:49.338 USER anonymous
< 2008-07-02 14:29:49.385 331 Anonymous access allowed, send identity (e-mail name) as password.
> 2008-07-02 14:29:49.385 PASS ************
< 2008-07-02 14:29:56.494 230 Anonymous user logged in.
> 2008-07-02 14:29:56.494 SYST
< 2008-07-02 14:29:56.541 215 Windows_NT
> 2008-07-02 14:29:56.541 FEAT
< 2008-07-02 14:29:56.556 211-FEAT
< 2008-07-02 14:29:56.791     SIZE
< 2008-07-02 14:29:56.791     MDTM
< 2008-07-02 14:29:56.791 211 END
. 2008-07-02 14:29:56.791 Connected
. 2008-07-02 14:29:56.791 --------------------------------------------------------------------------
. 2008-07-02 14:29:56.791 Using FTP protocol.
. 2008-07-02 14:29:56.791 Doing startup conversation with host.
> 2008-07-02 14:29:56.791 PWD
< 2008-07-02 14:29:56.822 257 "/" is current directory.
. 2008-07-02 14:29:56.822 Getting current directory name.
. 2008-07-02 14:29:56.822 Startup conversation with host finished.
. 2008-07-02 14:30:01.791 Cached directory change via "upload" to "/upload".
. 2008-07-02 14:30:01.791 Getting current directory name.
. 2008-07-02 14:30:09.619 Copying 1 files/directories to remote directory "/upload"
. 2008-07-02 14:30:09.619   PrTime: Yes; PrRO: Yes; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 01; Resume: S (102400); CalcS: No; Mask: 
. 2008-07-02 14:30:09.619   TM: M; ClAr: No; ExclM(No): 
. 2008-07-02 14:30:09.619   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2008-07-02 14:30:09.619 File: "licence"
. 2008-07-02 14:30:09.619 Copying "licence" to remote directory started.
. 2008-07-02 14:30:09.619 Binary transfer mode selected.
. 2008-07-02 14:30:09.619 Starting upload of licence
> 2008-07-02 14:30:09.619 CWD /upload/
< 2008-07-02 14:30:09.650 250 CWD command successful.
> 2008-07-02 14:30:09.650 PWD
< 2008-07-02 14:30:09.666 257 "/upload" is current directory.
> 2008-07-02 14:30:09.666 TYPE A
< 2008-07-02 14:30:09.713 200 Type set to A.
> 2008-07-02 14:30:09.713 PORT 10,53,47,6,14,17
< 2008-07-02 14:30:09.744 200 PORT command successful.
> 2008-07-02 14:30:09.744 LIST -a
< 2008-07-02 14:30:09.775 150 Opening ASCII mode data connection for /bin/ls.
< 2008-07-02 14:30:09.978 550 -a: Access is denied. 
. 2008-07-02 14:30:09.978 Copying files to remote side failed.
* 2008-07-02 14:30:09.978 (ExtException) Copying files to remote side failed.
* 2008-07-02 14:30:09.978 -a: Access is denied. 
. 2008-07-02 14:30:09.978 Asking user:
. 2008-07-02 14:30:09.978 Error transferring file 'licence'. ("Copying files to remote side failed.","-a: Access is denied. ")
* 2008-07-02 14:30:12.260 (EScpSkipFile) Error transferring file 'licence'.
* 2008-07-02 14:30:12.260 Copying files to remote side failed.
* 2008-07-02 14:30:12.260 -a: Access is denied. 
. 2008-07-02 14:30:14.447 Disconnected from server

BTW if i try this standard ftp from Windows it all works

martin

2008-07-02

Please post a log file.

Bascy

2008-07-02 12:19

winscp> cd upload

/upload

winscp> put licence

licence                   |          0 kB |    0,0 kB/s | binary |   0%

Error transferring file 'licence'.

Copying files to remote side failed.

-a: Access is denied.

(A)bort, (R)etry, (S)kip, Ski(p) all:

The upload dir is a virtual dir on an IIS6 server, with write access only.

martin

Re: Putting files without read access in the ftp server

2008-07-02

What does it do? Any error message?

Guest

Putting files without read access in the FTP server

2008-06-30 10:33

Hi all,

We're using a FTP server for a daily upload of a backup. Its using anonymous login, and the FTP server is configured to have no read access on the upload directory.

Using standard ftp everything works fine, but WinSCP seems to need read access to the directory. Is there a way to configure WinSCP to work in these conditions?