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.

Options
Add an Attachment

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

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

Options

Topic review

martin

Re: Testing with 5.1.8 - Worked for a time, then same error

I do not think you understand me. Please read my suggestion once again.

Run your script using the latest version of WinSCP on a different machine (where the latest version can run). If there's a real problem with your script, the script will fail too, but the latest version will give you more detailed error report. That should allow you to fix the problem on your legacy system. If the script works with the latest version, your problem is likely due to a bug or an incompatibility of the ancient version of WinSCP you are using.
jspetit

Re: Testing with 5.1.8 - Worked for a time, then same error

Can't do that, my target is a corporate server and they said no to a big project to replace it.

Besides, I know it works on another server with the latest version. What I want is to make it work on that server with that version. I want to fix the problem causing it and currently the error message (or absence of) doesn't help me.

What is the trigger for the "Script Failed" message?
martin

Re: Testing with 5.1.8 - Worked for a time, then same error

I wrote:
Test your script using the latest version of WinSCP on modern version of Windows, to see the problem details.
jspetit

Re: Testing with 5.1.8 - Worked for a time, then same error

I can't, this server is a Windows 2003, and the latest version just hang there, its process cannot be killed, and locks the executable, preventing a rollback. And doesn't writes logs either, so we don't know why.

What I need to know is what is the real error. What is going on when the scripting initialize, what is the condition for what writes "Script Failed".

The test is simple: call the winscp.com with a session name, and a simple "Exit" script. That's it.
martin

Re: Testing with 5.1.8 - Worked for a time, then same error

The latest version of WinSCP will probably be more informative about the problem. Test your script using the latest version of WinSCP on modern version of Windows, to see the problem details.
jspetit

Testing with 5.1.8 - Worked for a time, then same error

We upgraded a month ago to 5.1.8, and it seemed to have fixed the problem.

But today:
. 2021-09-21 10:05:08.574 --------------------------------------------------------------------------
. 2021-09-21 10:05:08.590 WinSCP Version 5.1.8 (Build 3799) (OS 5.2.3790 Service Pack 2 - Microsoft Windows Server 2003)
. 2021-09-21 10:05:08.606 Configuration: D:\Data\Path\Path\Settings.ini
. 2021-09-21 10:05:08.606 Local account: DOMAIN\Account
. 2021-09-21 10:05:08.606 Working directory: D:\WIN32app\CTMAG
. 2021-09-21 10:05:08.606 Process ID: 4844
. 2021-09-21 10:05:08.606 Command-line: "D:\win32app\WinSCP3\WinSCP.exe" /console=518 /consoleinstance=_7724_379 "SESSION_NAME" "/ini=D:\Data\Path\Path\Settings.ini" "/command" "LCD \\W2K8\Path\Path\Path\Report\" "LPWD" "CD REMOTE_PATH:/././POLLABLE" "PWD" "DIR" "BINARY" "GET *" "EXIT" "/Log=D:\Data\Path\Logs\!s_!y-!m-!d.log" "/Loglevel=1"
. 2021-09-21 10:05:08.606 Time zone: Current: GMT-4, Standard: GMT-5, DST: GMT-4, DST Start: 3/14/2021, DST End: 11/7/2021
. 2021-09-21 10:05:08.606 Login time: Tuesday, September 21, 2021 10:05:08 AM
. 2021-09-21 10:05:08.606 --------------------------------------------------------------------------
. 2021-09-21 10:05:08.606 Session name: SESSION_NAME(Stored session)
. 2021-09-21 10:05:08.606 Host name: remote_hostname (Port: 22)
. 2021-09-21 10:05:08.606 User name: USER_NAME: (Password: Yes, Key file: No)
. 2021-09-21 10:05:08.606 Tunnel: No
. 2021-09-21 10:05:08.606 Transfer Protocol: SFTP
. 2021-09-21 10:05:08.606 Ping type: N, Ping interval: 30 sec; Timeout: 120 sec
. 2021-09-21 10:05:08.606 Proxy: SOCKS5
. 2021-09-21 10:05:08.606 HostName: proxy_url (Port: 1080); Username: ; Passwd: No
. 2021-09-21 10:05:08.606 Send buffer: 262144
. 2021-09-21 10:05:08.606 SSH protocol version: 2; Compression: No
. 2021-09-21 10:05:08.606 Bypass authentication: No
. 2021-09-21 10:05:08.606 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: No; GSSAPI: No
. 2021-09-21 10:05:08.606 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2021-09-21 10:05:08.606 SSH Bugs: A,A,A,A,A,A,A,A,A,A
. 2021-09-21 10:05:08.606 Simple channel: Yes
. 2021-09-21 10:05:08.606 Return code variable: Autodetect; Lookup user groups: A
. 2021-09-21 10:05:08.606 Shell: default
. 2021-09-21 10:05:08.606 EOL: 0, UTF: 2
. 2021-09-21 10:05:08.606 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2021-09-21 10:05:08.606 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2021-09-21 10:05:08.606 Local directory: default, Remote directory: /USER_NAME:/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE, Update: Yes, Cache: Yes
. 2021-09-21 10:05:08.606 Cache directory changes: Yes, Permanent: Yes
. 2021-09-21 10:05:08.606 DST mode: 1; Timezone offset: 0h 0m
. 2021-09-21 10:05:08.606 --------------------------------------------------------------------------
. 2021-09-21 10:05:08.606 Looking up host "remote_hostname "
. 2021-09-21 10:05:08.652 Connecting to proxy_ip port 1080
. 2021-09-21 10:05:09.012 Server version: SSH-2.0-GXSSSHD_Comments
. 2021-09-21 10:05:09.012 Using SSH protocol version 2
. 2021-09-21 10:05:09.012 We claim version: SSH-2.0-WinSCP_release_5.1.8
. 2021-09-21 10:05:09.074 Doing Diffie-Hellman group exchange
. 2021-09-21 10:05:09.277 Doing Diffie-Hellman key exchange with hash SHA-1
. 2021-09-21 10:05:09.559 Verifying host key rsa2 keyvalue
. 2021-09-21 10:05:09.574 Host key matches cached key
. 2021-09-21 10:05:09.574 Host key fingerprint is:
. 2021-09-21 10:05:09.574 ssh-rsa 1024 value
. 2021-09-21 10:05:09.574 Initialised AES-256 SDCTR client->server encryption
. 2021-09-21 10:05:09.574 Initialised HMAC-SHA1 client->server MAC algorithm
. 2021-09-21 10:05:09.574 Initialised AES-256 SDCTR server->client encryption
. 2021-09-21 10:05:09.574 Initialised HMAC-SHA1 server->client MAC algorithm
! 2021-09-21 10:05:09.793 Using username "USER_NAME:".
. 2021-09-21 10:05:09.856 Prompt (7, SSH password, , &Password: )
. 2021-09-21 10:05:09.856 Using stored password.
. 2021-09-21 10:05:09.856 Sent password
. 2021-09-21 10:05:09.934 Access granted
. 2021-09-21 10:05:10.012 Opened channel for session
. 2021-09-21 10:05:10.215 Started a shell/command
. 2021-09-21 10:05:10.215 --------------------------------------------------------------------------
. 2021-09-21 10:05:10.215 Using SFTP protocol.
. 2021-09-21 10:05:10.324 Doing startup conversation with host.
> 2021-09-21 10:05:10.324 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2021-09-21 10:05:10.387 Type: SSH_FXP_VERSION, Size: 21, Number: -1
. 2021-09-21 10:05:10.387 SFTP version 4 negotiated.
. 2021-09-21 10:05:10.387 Server requests EOL sequence "\n".
. 2021-09-21 10:05:10.387 We will use UTF-8 strings when appropriate
. 2021-09-21 10:05:10.387 Changing directory to "/USER_NAME:/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE".
. 2021-09-21 10:05:10.387 Getting real path for '/USER_NAME:/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE'
> 2021-09-21 10:05:10.387 Type: SSH_FXP_REALPATH, Size: 2375, Number: 16
< 2021-09-21 10:05:10.481 Type: SSH_FXP_NAME, Size: 2404, Number: 16
. 2021-09-21 10:05:10.481 Real path is '/USER_NAME:/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE'
. 2021-09-21 10:05:10.481 Trying to open directory "/USER_NAME:/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE/XXXXXX-SEND:/././POLLABLE".
> 2021-09-21 10:05:10.481 Type: SSH_FXP_LSTAT, Size: 2379, Number: 263
< 2021-09-21 10:05:10.574 Type: SSH_FXP_ATTRS, Size: 30, Number: 263. 2021-09-21 10:05:10.574 Getting current directory name.
. 2021-09-21 10:05:10.574 Startup conversation with host finished.. 2021-09-21 10:05:10.574 Startup conversation with host finished.
< 2021-09-21 10:05:10.574 Script: Active session: [1] SESSION_NAME
. 2021-09-21 10:05:10.574 Script: Failed
> 2021-09-21 10:05:10.590 Script: LCD \\SrvW2K8\Path\Path\Path\Path\

Is there any way to beef up the logs to know what the Script: Failed means?

The code runs on a Windows 2003, and I've tried installing the latest version, and WinSCP is crashing so badly that I can't kill it's process, and I had to reboot the server because the exe and com were locked.

5.1.8 is the only one that works, and I can't hardly test every other iterations.
jspetit

Re: WinSCP.com 4.4: Script: Failed right after connection causes Return Code 1

We were under the impression that WinSCP 5 did not support W2K, we will do a test with 5.1.8
martin

Re: WinSCP.com 4.4: Script: Failed right after connection causes Return Code 1

OK, but you will hardly get any help with WinSCP 4.4.
At least post both logs. Though I'm not sure it will get us anywhere.
Did you try upgrading to the latest version of WinSCP that supports Windows 2000 – WinSCP 5.1.8?
vazan

WinSCP.com 4.4: Script: Failed right after connection causes Return Code 1

Hi i'm his colleague,
We can't use the last version for this task due to the fact it's an old server (windows server 2000) until out client want to upgrade we are unable to use more recent thing.

That why we are stuck with WinSCP 4.4.
martin

Re: WinSCP.com 4.4: Script: Failed right after connection causes Return Code 1

Please post session log files from both scenarios using the latest version of WinSCP.

WinSCP 4.4 is over 8 years old.
jspetit

WinSCP.com 4.4: Script: Failed right after connection causes Return Code 1

I have this simple test:
winscp.com /console MY_SESSION /command "exit" /ini:options.ini /log:d:\temp\test.txt

1. Open the session
2. Exit

This is a subset of the log:
< 2021-07-22 14:55:42.787 Script: Active session: [1] MY_SESSION
. 2021-07-22 14:55:42.787 Script: Failed
> 2021-07-22 14:55:42.787 Script: exit
. 2021-07-22 14:55:42.787 Session upkeep
. 2021-07-22 14:55:42.787 Looking for network events
. 2021-07-22 14:55:42.787 Timeout waiting for network events
. 2021-07-22 14:55:42.787 Script: Exit code: 1
. 2021-07-22 14:55:42.787 Closing connection.
. 2021-07-22 14:55:42.787 Sending special code: 12
. 2021-07-22 14:55:42.787 Sent EOF message
. 2021-07-22 14:55:42.787 Selecting events 0 for socket 488

We can't figure out what is the problem here.

The same test, without the EXIT command, does the same.

What is puzzling is that running the ini with winscp.exe /console with the same params doesn't trigger the failure nor the return code 1, and after that, winscp.com doesn't fail anymore!

During our tests, we replaced winscp.ini with our ini, opened the session in WinSCP, save, them used it with success.

The only differences were cosmetic, and some extra characters in the password saved in the session.

And it worked.

Our ini worked yesterday, and stopped working today.

What is causing this, and how can I debug this.