Version 5.5.1

We are hosting a ws-ftp server (Ipswitch) and recently we upgraded from version 7.7.0 to 8.5. After the upgrade we got calls from clients complaining they can't connect anymore.

These clients are using WinSCP 5.5.1. I tested it myself with version 5.1.3 and have no issues so I tried WinSCP 5.5.1 and I cannot login anymore. The error I see on the server is Invalid Public Key, but the authentication method is username and password.

Last thing I did was try to use the most recent version of WinSCP 5.11.2 and there the issue has gone again, I can login.

Any idea what's going on?

So use the latest version. It's the right thing to do in any case.

If you want us to investigate this any further, we need to see WinSCP log files form all three versions.

. 2017-10-23 13:19:47.702 --------------------------------------------------------------------------
. 2017-10-23 13:19:47.702 WinSCP Version 5.5.1 (Build 3970) (OS 6.3.9600 - Windows 8.1)
. 2017-10-23 13:19:47.702 Configuration: HKEY_CURRENT_USER\Software\Martin Prikryl\WinSCP 2\
. 2017-10-23 13:19:47.702 Local account: xxxxxxxxxx\xxx
. 2017-10-23 13:19:47.702 Working directory: D:\!\winscp551
. 2017-10-23 13:19:47.702 Process ID: 10544
. 2017-10-23 13:19:47.702 Command-line: "D:\!\winscp551\WinSCP.exe"
. 2017-10-23 13:19:47.702 Time zone: Current: GMT+2, Standard: GMT+1, DST: GMT+2, DST Start: 26/03/17, DST End: 29/10/17
. 2017-10-23 13:19:47.702 Login time: maandag 23 oktober 2017 13:19:47
. 2017-10-23 13:19:47.702 --------------------------------------------------------------------------
. 2017-10-23 13:19:47.702 Session name: testuser@db.workbee.eu (Site)
. 2017-10-23 13:19:47.702 Host name: db.workbee.eu (Port: 22)
. 2017-10-23 13:19:47.702 User name: testuser (Password: Yes, Key file: No)
. 2017-10-23 13:19:47.702 Tunnel: No
. 2017-10-23 13:19:47.702 Transfer Protocol: SFTP (SCP)
. 2017-10-23 13:19:47.702 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2017-10-23 13:19:47.702 Proxy: none
. 2017-10-23 13:19:47.702 Send buffer: 262144
. 2017-10-23 13:19:47.702 SSH protocol version: 2; Compression: No
. 2017-10-23 13:19:47.702 Bypass authentication: No
. 2017-10-23 13:19:47.702 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2017-10-23 13:19:47.702 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2017-10-23 13:19:47.702 SSH Bugs: A,A,A,A,A,A,A,A,A,A
. 2017-10-23 13:19:47.702 Simple channel: Yes
. 2017-10-23 13:19:47.702 Return code variable: Autodetect; Lookup user groups: A
. 2017-10-23 13:19:47.702 Shell: default
. 2017-10-23 13:19:47.702 EOL: 0, UTF: 2
. 2017-10-23 13:19:47.702 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2017-10-23 13:19:47.702 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2017-10-23 13:19:47.702 SFTP Bugs: A,A
. 2017-10-23 13:19:47.702 SFTP Server: default
. 2017-10-23 13:19:47.702 Local directory: C:\Users\Rob van Olst\Documents, Remote directory: /, Update: Yes, Cache: Yes
. 2017-10-23 13:19:47.702 Cache directory changes: Yes, Permanent: Yes
. 2017-10-23 13:19:47.702 DST mode: 1; Timezone offset: 0h 0m
. 2017-10-23 13:19:47.702 --------------------------------------------------------------------------
. 2017-10-23 13:19:47.764 Looking up host "db.workbee.eu"
. 2017-10-23 13:19:47.780 Connecting to 84.241.179.138 port 22
. 2017-10-23 13:19:47.796 Selecting events 63 for socket 792
. 2017-10-23 13:19:47.827 Waiting for the server to continue with the initialization
. 2017-10-23 13:19:47.827 Looking for incoming data
. 2017-10-23 13:19:47.827 Looking for network events
. 2017-10-23 13:19:47.827 Detected network event
. 2017-10-23 13:19:47.827 Enumerating network events for socket 792
. 2017-10-23 13:19:47.827 Enumerated 19 network events making 19 cumulative events for socket 792
. 2017-10-23 13:19:47.827 Handling network read event on socket 792 with error 0
. 2017-10-23 13:19:47.827 Server version: SSH-2.0-WS_FTP-SSH_8.5.0
. 2017-10-23 13:19:47.827 Using SSH protocol version 2
. 2017-10-23 13:19:47.827 We claim version: SSH-2.0-WinSCP_release_5.5.1
. 2017-10-23 13:19:47.827 Handling network write event on socket 792 with error 0
. 2017-10-23 13:19:47.827 Handling network connect event on socket 792 with error 0
. 2017-10-23 13:19:47.827 Waiting for the server to continue with the initialization
. 2017-10-23 13:19:47.827 Looking for incoming data
. 2017-10-23 13:19:47.827 Looking for network events
. 2017-10-23 13:19:47.827 Detected network event
. 2017-10-23 13:19:47.827 Enumerating network events for socket 792
. 2017-10-23 13:19:47.827 Enumerated 1 network events making 1 cumulative events for socket 792
. 2017-10-23 13:19:47.827 Handling network read event on socket 792 with error 0
. 2017-10-23 13:19:47.827 Doing Diffie-Hellman group exchange
. 2017-10-23 13:19:47.827 Waiting for the server to continue with the initialization
. 2017-10-23 13:19:47.827 Looking for incoming data
. 2017-10-23 13:19:47.827 Looking for network events
. 2017-10-23 13:19:47.921 Detected network event
. 2017-10-23 13:19:47.921 Enumerating network events for socket 792
. 2017-10-23 13:19:47.921 Enumerated 32 network events making 32 cumulative events for socket 792
. 2017-10-23 13:19:47.921 Handling network close event on socket 792 with error 0
. 2017-10-23 13:19:47.921 Selecting events 0 for socket 792
. 2017-10-23 13:19:47.921 Server unexpectedly closed network connection
* 2017-10-23 13:19:47.952 (EFatal) Server unexpectedly closed network connection.

martin wrote:

So use the latest version. It's the right thing to do in any case.

If you want us to investigate this any further, we need to see WinSCP log files form all three versions.

I could not agree more, next challenge is to tell this to our clients ;-)

. 2017-10-23 13:22:00.553 --------------------------------------------------------------------------
. 2017-10-23 13:22:00.553 WinSCP Version 5.1.3 (Build 2881) (OS 6.2.9200)
. 2017-10-23 13:22:00.553 Configuration: HKEY_CURRENT_USER\Software\Martin Prikryl\WinSCP 2\
. 2017-10-23 13:22:00.553 Local account: xxxxxxxxxx\xxx
. 2017-10-23 13:22:00.553 Working directory: C:\Program Files (x86)\WinSCP
. 2017-10-23 13:22:00.553 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe"
. 2017-10-23 13:22:00.553 Time zone: Current: GMT+2, Standard: GMT+1, DST: GMT+2, DST Start: 26/03/17, DST End: 29/10/17
. 2017-10-23 13:22:00.553 Login time: maandag 23 oktober 2017 13:22:00
. 2017-10-23 13:22:00.553 --------------------------------------------------------------------------
. 2017-10-23 13:22:00.553 Session name: testuser@db.workbee.eu (Stored session)
. 2017-10-23 13:22:00.553 Host name: db.workbee.eu (Port: 22)
. 2017-10-23 13:22:00.553 User name: testuser (Password: Yes, Key file: No)
. 2017-10-23 13:22:00.553 Tunnel: No
. 2017-10-23 13:22:00.553 Transfer Protocol: SFTP (SCP)
. 2017-10-23 13:22:00.553 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2017-10-23 13:22:00.553 Proxy: none
. 2017-10-23 13:22:00.553 SSH protocol version: 2; Compression: No
. 2017-10-23 13:22:00.553 Bypass authentication: No
. 2017-10-23 13:22:00.553 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2017-10-23 13:22:00.553 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2017-10-23 13:22:00.553 SSH Bugs: A,A,A,A,A,A,A,A,A,A
. 2017-10-23 13:22:00.553 SFTP Bugs: A,A
. 2017-10-23 13:22:00.553 Return code variable: Autodetect; Lookup user groups: A
. 2017-10-23 13:22:00.553 Shell: default
. 2017-10-23 13:22:00.553 EOL: 0, UTF: 2
. 2017-10-23 13:22:00.553 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2017-10-23 13:22:00.553 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2017-10-23 13:22:00.553 Local directory: C:\Users\Rob van Olst\Documents, Remote directory: /, Update: Yes, Cache: Yes
. 2017-10-23 13:22:00.553 Cache directory changes: Yes, Permanent: Yes
. 2017-10-23 13:22:00.553 DST mode: 1
. 2017-10-23 13:22:00.553 --------------------------------------------------------------------------
. 2017-10-23 13:22:00.617 Looking up host "db.workbee.eu"
. 2017-10-23 13:22:00.648 Connecting to 84.241.179.138 port 22
. 2017-10-23 13:22:00.664 Selecting events 63 for socket 1028
. 2017-10-23 13:22:00.679 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:00.679 Looking for incoming data
. 2017-10-23 13:22:00.679 Looking for network events
. 2017-10-23 13:22:00.679 Detected network event
. 2017-10-23 13:22:00.679 Enumerating network events for socket 1028
. 2017-10-23 13:22:00.679 Enumerated 19 network events making 19 cumulative events for socket 1028
. 2017-10-23 13:22:00.679 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:00.679 Server version: SSH-2.0-WS_FTP-SSH_8.5.0
. 2017-10-23 13:22:00.679 Using SSH protocol version 2
. 2017-10-23 13:22:00.679 We claim version: SSH-2.0-WinSCP_release_5.1.3
. 2017-10-23 13:22:00.679 Handling network write event on socket 1028 with error 0
. 2017-10-23 13:22:00.679 Handling network connect event on socket 1028 with error 0
. 2017-10-23 13:22:00.679 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:00.679 Looking for incoming data
. 2017-10-23 13:22:00.679 Looking for network events
. 2017-10-23 13:22:00.679 Detected network event
. 2017-10-23 13:22:00.679 Enumerating network events for socket 1028
. 2017-10-23 13:22:00.679 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:00.679 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:00.679 Using Diffie-Hellman with standard group "group14"
. 2017-10-23 13:22:00.679 Doing Diffie-Hellman key exchange with hash SHA-1
. 2017-10-23 13:22:00.742 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:00.742 Looking for incoming data
. 2017-10-23 13:22:00.742 Looking for network events
. 2017-10-23 13:22:00.773 Detected network event
. 2017-10-23 13:22:00.773 Enumerating network events for socket 1028
. 2017-10-23 13:22:00.773 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:00.773 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:00.843 Verifying host key rsa2 0x23,0xbc199ce0c5250b3b6452df1cdeca36d0c4a06eca705bed3e83ef43f1a84c46a4c95ac1b0c3f954f3d1463b0de45b0f1b28d449ff4619829562373cb366f2b6f31b034875b01f40d6a0dc5c225773d41f55333094113ab7c74762a4f08b989891f2e9c26daef84a2fc7eb62d8b2b271045127584baedaa93155f007a759bbeb31 with fingerprint ssh-rsa 1024 26:37:1e:b5:93:12:57:a8:68:cc:f6:97:69:dd:b9:5b
. 2017-10-23 13:22:00.860 Host key matches cached key
. 2017-10-23 13:22:00.860 Selecting events 63 for socket 1028
. 2017-10-23 13:22:00.860 Host key fingerprint is:
. 2017-10-23 13:22:00.860 ssh-rsa 1024 26:37:1e:b5:93:12:57:a8:68:cc:f6:97:69:dd:b9:5b
. 2017-10-23 13:22:00.860 Initialised AES-256 SDCTR client->server encryption
. 2017-10-23 13:22:00.860 Initialised HMAC-SHA1 client->server MAC algorithm
. 2017-10-23 13:22:00.860 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:00.860 Looking for incoming data
. 2017-10-23 13:22:00.860 Looking for network events
. 2017-10-23 13:22:00.860 Detected network event
. 2017-10-23 13:22:00.860 Enumerating network events for socket 1028
. 2017-10-23 13:22:00.860 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:00.860 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:00.860 Initialised AES-256 SDCTR server->client encryption
. 2017-10-23 13:22:00.860 Initialised HMAC-SHA1 server->client MAC algorithm
. 2017-10-23 13:22:00.860 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:00.860 Looking for incoming data
. 2017-10-23 13:22:00.860 Looking for network events
. 2017-10-23 13:22:00.923 Detected network event
. 2017-10-23 13:22:00.923 Enumerating network events for socket 1028
. 2017-10-23 13:22:00.923 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:00.923 Handling network read event on socket 1028 with error 0
! 2017-10-23 13:22:00.923 Using username "testuser".
. 2017-10-23 13:22:00.985 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:00.985 Looking for incoming data
. 2017-10-23 13:22:00.985 Looking for network events
. 2017-10-23 13:22:01.001 Detected network event
. 2017-10-23 13:22:01.001 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.001 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.001 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.001 Prompt (7, SSH password, , &Password: )
. 2017-10-23 13:22:01.001 Using stored password.
. 2017-10-23 13:22:01.048 Sent password
. 2017-10-23 13:22:01.048 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:01.048 Looking for incoming data
. 2017-10-23 13:22:01.048 Looking for network events
. 2017-10-23 13:22:01.063 Detected network event
. 2017-10-23 13:22:01.063 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.063 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.063 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.063 Access granted
. 2017-10-23 13:22:01.063 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:01.063 Looking for incoming data
. 2017-10-23 13:22:01.063 Looking for network events
. 2017-10-23 13:22:01.063 Detected network event
. 2017-10-23 13:22:01.063 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.063 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.063 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.063 Opened channel for session
. 2017-10-23 13:22:01.063 Waiting for the server to continue with the initialisation
. 2017-10-23 13:22:01.063 Looking for incoming data
. 2017-10-23 13:22:01.063 Looking for network events
. 2017-10-23 13:22:01.157 Detected network event
. 2017-10-23 13:22:01.157 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.157 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.157 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.157 Started a shell/command
. 2017-10-23 13:22:01.173 --------------------------------------------------------------------------
. 2017-10-23 13:22:01.173 Using SFTP protocol.
. 2017-10-23 13:22:01.173 Doing startup conversation with host.
. 2017-10-23 13:22:01.173 Session upkeep
. 2017-10-23 13:22:01.173 Looking for network events
. 2017-10-23 13:22:01.173 Timeout waiting for network events
> 2017-10-23 13:22:01.227 Type: SSH_FXP_INIT, Size: 5, Number: -1
> 2017-10-23 13:22:01.227 01,00,00,00,05,
. 2017-10-23 13:22:01.227 Sent 9 bytes
. 2017-10-23 13:22:01.227 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:22:01.227 Looking for network events
. 2017-10-23 13:22:01.227 Timeout waiting for network events
. 2017-10-23 13:22:01.227 Waiting for another 4 bytes
. 2017-10-23 13:22:01.227 Looking for incoming data
. 2017-10-23 13:22:01.227 Looking for network events
. 2017-10-23 13:22:01.227 Detected network event
. 2017-10-23 13:22:01.227 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.227 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.227 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.227 Received 9 bytes (0)
. 2017-10-23 13:22:01.227 Read 4 bytes (5 pending)
. 2017-10-23 13:22:01.227 Read 5 bytes (0 pending)
< 2017-10-23 13:22:01.227 Type: SSH_FXP_VERSION, Size: 5, Number: -1
< 2017-10-23 13:22:01.227 02,00,00,00,04,
. 2017-10-23 13:22:01.227 SFTP version 4 negotiated.
. 2017-10-23 13:22:01.227 We will use UTF-8 strings when appropriate
. 2017-10-23 13:22:01.289 Changing directory to "/".
. 2017-10-23 13:22:01.289 Getting real path for '/'
> 2017-10-23 13:22:01.289 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
> 2017-10-23 13:22:01.289 10,00,00,00,10,00,00,00,01,2F,
. 2017-10-23 13:22:01.289 Sent 14 bytes
. 2017-10-23 13:22:01.289 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:22:01.289 Looking for network events
. 2017-10-23 13:22:01.289 Timeout waiting for network events
. 2017-10-23 13:22:01.289 Waiting for another 4 bytes
. 2017-10-23 13:22:01.289 Looking for incoming data
. 2017-10-23 13:22:01.289 Looking for network events
. 2017-10-23 13:22:01.305 Detected network event
. 2017-10-23 13:22:01.305 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.305 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.305 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.305 Received 27 bytes (0)
. 2017-10-23 13:22:01.305 Read 4 bytes (23 pending)
. 2017-10-23 13:22:01.305 Read 23 bytes (0 pending)
< 2017-10-23 13:22:01.305 Type: SSH_FXP_NAME, Size: 23, Number: 16
< 2017-10-23 13:22:01.305 68,00,00,00,10,00,00,00,01,00,00,00,01,2F,00,00,00,04,02,00,00,01,E8,
. 2017-10-23 13:22:01.305 Real path is '/'
. 2017-10-23 13:22:01.305 Trying to open directory "/".
> 2017-10-23 13:22:01.305 Type: SSH_FXP_LSTAT, Size: 14, Number: 263
> 2017-10-23 13:22:01.305 07,00,00,01,07,00,00,00,01,2F,00,00,00,AD,
. 2017-10-23 13:22:01.305 Sent 18 bytes
. 2017-10-23 13:22:01.305 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:22:01.305 Looking for network events
. 2017-10-23 13:22:01.305 Timeout waiting for network events
. 2017-10-23 13:22:01.305 Waiting for another 4 bytes
. 2017-10-23 13:22:01.305 Looking for incoming data
. 2017-10-23 13:22:01.305 Looking for network events
. 2017-10-23 13:22:01.305 Detected network event
. 2017-10-23 13:22:01.305 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.305 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.305 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.305 Received 42 bytes (0)
. 2017-10-23 13:22:01.305 Read 4 bytes (38 pending)
. 2017-10-23 13:22:01.305 Read 38 bytes (0 pending)
< 2017-10-23 13:22:01.305 Type: SSH_FXP_ATTRS, Size: 38, Number: 263
< 2017-10-23 13:22:01.305 69,00,00,01,07,00,00,00,3C,02,00,00,01,E8,00,00,00,00,59,E8,C2,0C,00,00,00,
< 2017-10-23 13:22:01.305 00,55,CB,5A,26,00,00,00,00,59,E8,C2,0C,
. 2017-10-23 13:22:01.305 Getting current directory name.
. 2017-10-23 13:22:01.477 Listing directory "/".
> 2017-10-23 13:22:01.477 Type: SSH_FXP_OPENDIR, Size: 10, Number: 523
> 2017-10-23 13:22:01.477 0B,00,00,02,0B,00,00,00,01,2F,
. 2017-10-23 13:22:01.477 Sent 14 bytes
. 2017-10-23 13:22:01.477 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:22:01.477 Looking for network events
. 2017-10-23 13:22:01.477 Timeout waiting for network events
. 2017-10-23 13:22:01.477 Waiting for another 4 bytes
. 2017-10-23 13:22:01.477 Looking for incoming data
. 2017-10-23 13:22:01.477 Looking for network events
. 2017-10-23 13:22:01.477 Detected network event
. 2017-10-23 13:22:01.477 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.477 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.477 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.477 Received 15 bytes (0)
. 2017-10-23 13:22:01.477 Read 4 bytes (11 pending)
. 2017-10-23 13:22:01.477 Read 11 bytes (0 pending)
< 2017-10-23 13:22:01.477 Type: SSH_FXP_HANDLE, Size: 11, Number: 523
< 2017-10-23 13:22:01.477 66,00,00,02,0B,00,00,00,02,32,30,
> 2017-10-23 13:22:01.477 Type: SSH_FXP_READDIR, Size: 11, Number: 780
> 2017-10-23 13:22:01.477 0C,00,00,03,0C,00,00,00,02,32,30,
. 2017-10-23 13:22:01.477 Sent 15 bytes
. 2017-10-23 13:22:01.477 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:22:01.477 Looking for network events
. 2017-10-23 13:22:01.477 Timeout waiting for network events
. 2017-10-23 13:22:01.477 Waiting for another 4 bytes
. 2017-10-23 13:22:01.477 Looking for incoming data
. 2017-10-23 13:22:01.477 Looking for network events
. 2017-10-23 13:22:01.492 Detected network event
. 2017-10-23 13:22:01.492 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.492 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.492 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.492 Received 90 bytes (0)
. 2017-10-23 13:22:01.492 Read 4 bytes (86 pending)
. 2017-10-23 13:22:01.492 Read 86 bytes (0 pending)
< 2017-10-23 13:22:01.492 Type: SSH_FXP_NAME, Size: 86, Number: 780
< 2017-10-23 13:22:01.492 68,00,00,03,0C,00,00,00,02,00,00,00,01,2E,00,00,00,3C,02,00,00,01,E8,00,00,
< 2017-10-23 13:22:01.492 00,00,59,E8,C2,0C,00,00,00,00,55,CB,5A,26,00,00,00,00,59,E8,C2,0C,00,00,00,
< 2017-10-23 13:22:01.492 02,2E,2E,00,00,00,3C,02,00,00,01,E8,00,00,00,00,59,B7,E9,0A,00,00,00,00,55,
< 2017-10-23 13:22:01.492 CA,05,FA,00,00,00,00,59,B7,E9,0A,
> 2017-10-23 13:22:01.492 Type: SSH_FXP_READDIR, Size: 11, Number: 1036
> 2017-10-23 13:22:01.492 0C,00,00,04,0C,00,00,00,02,32,30,
. 2017-10-23 13:22:01.492 Sent 15 bytes
. 2017-10-23 13:22:01.492 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:22:01.492 Looking for network events
. 2017-10-23 13:22:01.492 Timeout waiting for network events
. 2017-10-23 13:22:01.492 Read file '.' from listing
. 2017-10-23 13:22:01.492 Read file '..' from listing
. 2017-10-23 13:22:01.492 Waiting for another 4 bytes
. 2017-10-23 13:22:01.492 Looking for incoming data
. 2017-10-23 13:22:01.492 Looking for network events
. 2017-10-23 13:22:01.508 Detected network event
. 2017-10-23 13:22:01.508 Enumerating network events for socket 1028
. 2017-10-23 13:22:01.508 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:01.508 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:01.508 Received 32 bytes (0)
. 2017-10-23 13:22:01.508 Read 4 bytes (28 pending)
. 2017-10-23 13:22:01.508 Read 28 bytes (0 pending)
< 2017-10-23 13:22:01.508 Type: SSH_FXP_STATUS, Size: 28, Number: 1036
< 2017-10-23 13:22:01.508 65,00,00,04,0C,00,00,00,01,00,00,00,0B,45,6E,64,20,6F,66,20,46,69,6C,65,00,
< 2017-10-23 13:22:01.508 00,00,00,
< 2017-10-23 13:22:01.508 Status code: 1
> 2017-10-23 13:22:01.508 Type: SSH_FXP_CLOSE, Size: 11, Number: 1284
> 2017-10-23 13:22:01.508 04,00,00,05,04,00,00,00,02,32,30,
. 2017-10-23 13:22:01.508 Sent 15 bytes
. 2017-10-23 13:22:01.508 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:22:01.508 Looking for network events
. 2017-10-23 13:22:01.508 Timeout waiting for network events
. 2017-10-23 13:22:01.508 ..;D;0;2017-09-12T14:02:50.000Z;"" [0];"" [0];rwxr-x---;0
. 2017-10-23 13:22:01.602 Startup conversation with host finished.
. 2017-10-23 13:22:02.336 Session upkeep
. 2017-10-23 13:22:02.336 Looking for network events
. 2017-10-23 13:22:02.336 Detected network event
. 2017-10-23 13:22:02.336 Enumerating network events for socket 1028
. 2017-10-23 13:22:02.336 Enumerated 1 network events making 1 cumulative events for socket 1028
. 2017-10-23 13:22:02.336 Handling network read event on socket 1028 with error 0
. 2017-10-23 13:22:02.336 Received 21 bytes (0)
. 2017-10-23 13:22:02.642 Closing connection.
. 2017-10-23 13:22:02.642 Sending special code: 12
. 2017-10-23 13:22:02.642 Sent EOF message
. 2017-10-23 13:22:02.642 Selecting events 0 for socket 1028

. 2017-10-23 13:25:28.205 --------------------------------------------------------------------------
. 2017-10-23 13:25:28.205 WinSCP Version 5.11.2 (Build 7781) (OS 6.3.9600 - Windows 8.1)
. 2017-10-23 13:25:28.205 Configuration: HKCU\Software\Martin Prikryl\WinSCP 2\
. 2017-10-23 13:25:28.205 Log level: Debug 2, Logging passwords
. 2017-10-23 13:25:28.205 Local account: xxxxxxxxx\xxx
. 2017-10-23 13:25:28.205 Working directory: D:\!\winscp5112
. 2017-10-23 13:25:28.205 Process ID: 6328
. 2017-10-23 13:25:28.205 Command-line: "D:\!\winscp5112\WinSCP.exe"
. 2017-10-23 13:25:28.205 Time zone: Current: GMT+2, Standard: GMT+1 (W. Europe Standard Time), DST: GMT+2 (W. Europe Summer Time), DST Start: 26/03/17, DST End: 29/10/17
. 2017-10-23 13:25:28.205 Login time: maandag 23 oktober 2017 13:25:28
. 2017-10-23 13:25:28.205 --------------------------------------------------------------------------
. 2017-10-23 13:25:28.205 Session name: testuser@db.workbee.eu (Site)
. 2017-10-23 13:25:28.205 Host name: db.workbee.eu (Port: 22)
. 2017-10-23 13:25:28.205 User name: testuser (Password: xxxxxxxxxx, Key file: No, Passphrase: No)
. 2017-10-23 13:25:28.205 Tunnel: No
. 2017-10-23 13:25:28.205 Transfer Protocol: SFTP (SCP)
. 2017-10-23 13:25:28.205 Ping type: Off, Ping interval: 30 sec; Timeout: 15 sec
. 2017-10-23 13:25:28.205 Disable Nagle: No
. 2017-10-23 13:25:28.205 Proxy: None
. 2017-10-23 13:25:28.205 Send buffer: 262144
. 2017-10-23 13:25:28.205 SSH protocol version: 2; Compression: No
. 2017-10-23 13:25:28.205 Bypass authentication: No
. 2017-10-23 13:25:28.205 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: Yes
. 2017-10-23 13:25:28.205 GSSAPI: Forwarding: No
. 2017-10-23 13:25:28.205 Ciphers: aes,chacha20,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2017-10-23 13:25:28.205 KEX: ecdh,dh-gex-sha1,dh-group14-sha1,rsa,WARN,dh-group1-sha1
. 2017-10-23 13:25:28.205 SSH Bugs: Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto,Auto
. 2017-10-23 13:25:28.205 Simple channel: Yes
. 2017-10-23 13:25:28.205 Return code variable: Autodetect; Lookup user groups: Auto
. 2017-10-23 13:25:28.205 Shell: default
. 2017-10-23 13:25:28.205 EOL: LF, UTF: Auto
. 2017-10-23 13:25:28.205 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes; Follow directory symlinks: No
. 2017-10-23 13:25:28.205 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2017-10-23 13:25:28.205 SFTP Bugs: Auto,Auto
. 2017-10-23 13:25:28.205 SFTP Server: default
. 2017-10-23 13:25:28.205 Local directory: C:\Users\Rob van Olst\Documents, Remote directory: /, Update: Yes, Cache: Yes
. 2017-10-23 13:25:28.205 Cache directory changes: Yes, Permanent: Yes
. 2017-10-23 13:25:28.205 Recycle bin: Delete to: No, Overwritten to: No, Bin path:
. 2017-10-23 13:25:28.205 DST mode: Unix
. 2017-10-23 13:25:28.205 --------------------------------------------------------------------------
. 2017-10-23 13:25:28.221 Looking up host "db.workbee.eu" for SSH connection
. 2017-10-23 13:25:28.252 Connecting to 84.241.179.138 port 22
. 2017-10-23 13:25:28.252 Selecting events 63 for socket 1140
. 2017-10-23 13:25:28.252 We claim version: SSH-2.0-WinSCP_release_5.11.2
. 2017-10-23 13:25:28.267 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.267 Looking for incoming data
. 2017-10-23 13:25:28.267 Looking for network events
. 2017-10-23 13:25:28.267 Detected network event
. 2017-10-23 13:25:28.267 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.267 Enumerated 18 network events making 18 cumulative events for socket 1140
. 2017-10-23 13:25:28.267 Handling network write event on socket 1140 with error 0
. 2017-10-23 13:25:28.267 Handling network connect event on socket 1140 with error 0
. 2017-10-23 13:25:28.267 Looking for network events
. 2017-10-23 13:25:28.267 Detected network event
. 2017-10-23 13:25:28.267 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.267 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.267 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.267 Server version: SSH-2.0-WS_FTP-SSH_8.5.0
. 2017-10-23 13:25:28.267 Using SSH protocol version 2
. 2017-10-23 13:25:28.267 Have a known host key of type rsa2
. 2017-10-23 13:25:28.267 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.267 Looking for incoming data
. 2017-10-23 13:25:28.267 Looking for network events
. 2017-10-23 13:25:28.330 Detected network event
. 2017-10-23 13:25:28.330 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.330 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.330 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.330 Doing Diffie-Hellman group exchange
. 2017-10-23 13:25:28.330 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.330 Looking for incoming data
. 2017-10-23 13:25:28.330 Looking for network events
. 2017-10-23 13:25:28.392 Detected network event
. 2017-10-23 13:25:28.392 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.392 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.392 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.392 Doing Diffie-Hellman key exchange with hash SHA-256
. 2017-10-23 13:25:28.471 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.471 Looking for incoming data
. 2017-10-23 13:25:28.471 Looking for network events
. 2017-10-23 13:25:28.502 Detected network event
. 2017-10-23 13:25:28.502 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.502 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.502 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.580 Server also has ssh-dss host key, but we don't know it
. 2017-10-23 13:25:28.580 Host key fingerprint is:
. 2017-10-23 13:25:28.580 ssh-rsa 1024 26:37:1e:b5:93:12:57:a8:68:cc:f6:97:69:dd:b9:5b
. 2017-10-23 13:25:28.580 Verifying host key rsa2 0x23,0xbc199ce0c5250b3b 6452df1cdeca36d0 c4a06eca705bed3e 83ef43f1a84c46a4 c95ac1b0c3f954f3 d1463b0de45b0f1b 28d449ff46198295 62373cb366f2b6f3 1b034875b01f40d6 a0dc5c225773d41f 55333094113ab7c7 4762a4f08b989891 f2e9c26daef84a2f c7eb62d8b2b27104 5127584baedaa931 55f007a759bbeb31 with fingerprint ssh-rsa 1024 26:37:1e:b5:93:12:57:a8:68:cc:f6:97:69:dd:b9:5b
. 2017-10-23 13:25:28.642 Host key matches cached key
. 2017-10-23 13:25:28.642 Selecting events 63 for socket 1140
. 2017-10-23 13:25:28.642 Initialised AES-256 SDCTR client->server encryption
. 2017-10-23 13:25:28.642 Initialised HMAC-SHA-256 client->server MAC algorithm
. 2017-10-23 13:25:28.642 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.642 Looking for incoming data
. 2017-10-23 13:25:28.642 Looking for network events
. 2017-10-23 13:25:28.642 Detected network event
. 2017-10-23 13:25:28.642 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.642 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.642 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.642 Initialised AES-256 SDCTR server->client encryption
. 2017-10-23 13:25:28.642 Initialised HMAC-SHA-256 server->client MAC algorithm
. 2017-10-23 13:25:28.642 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.642 Looking for incoming data
. 2017-10-23 13:25:28.642 Looking for network events
. 2017-10-23 13:25:28.721 Detected network event
. 2017-10-23 13:25:28.721 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.721 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.721 Handling network read event on socket 1140 with error 0
! 2017-10-23 13:25:28.721 Using username "testuser".
. 2017-10-23 13:25:28.767 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.767 Looking for incoming data
. 2017-10-23 13:25:28.767 Looking for network events
. 2017-10-23 13:25:28.799 Detected network event
. 2017-10-23 13:25:28.799 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.799 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.799 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.799 Server offered these authentication methods: publickey,password
. 2017-10-23 13:25:28.799 Prompt (password, "SSH password", <no instructions>, "&Password: ")
. 2017-10-23 13:25:28.799 Using stored password.
. 2017-10-23 13:25:28.814 Sent password
. 2017-10-23 13:25:28.814 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.814 Looking for incoming data
. 2017-10-23 13:25:28.814 Looking for network events
. 2017-10-23 13:25:28.830 Detected network event
. 2017-10-23 13:25:28.830 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.830 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.830 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.830 Access granted
. 2017-10-23 13:25:28.830 Opening session as main channel
. 2017-10-23 13:25:28.830 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.830 Looking for incoming data
. 2017-10-23 13:25:28.830 Looking for network events
. 2017-10-23 13:25:28.846 Detected network event
. 2017-10-23 13:25:28.846 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.846 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.846 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.846 Opened main channel
. 2017-10-23 13:25:28.846 Waiting for the server to continue with the initialization
. 2017-10-23 13:25:28.846 Looking for incoming data
. 2017-10-23 13:25:28.846 Looking for network events
. 2017-10-23 13:25:28.924 Detected network event
. 2017-10-23 13:25:28.924 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.924 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.924 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.924 Started a shell/command
. 2017-10-23 13:25:28.939 --------------------------------------------------------------------------
. 2017-10-23 13:25:28.939 Using SFTP protocol.
. 2017-10-23 13:25:28.939 Doing startup conversation with host.
. 2017-10-23 13:25:28.939 Session upkeep
. 2017-10-23 13:25:28.939 Looking for network events
. 2017-10-23 13:25:28.939 Timeout waiting for network events
> 2017-10-23 13:25:28.955 Type: SSH_FXP_INIT, Size: 5, Number: -1
> 2017-10-23 13:25:28.955 01,00,00,00,06,
. 2017-10-23 13:25:28.955 Sent 9 bytes
. 2017-10-23 13:25:28.955 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:25:28.955 Looking for network events
. 2017-10-23 13:25:28.955 Timeout waiting for network events
. 2017-10-23 13:25:28.955 Waiting for another 4 bytes
. 2017-10-23 13:25:28.955 Looking for incoming data
. 2017-10-23 13:25:28.955 Looking for network events
. 2017-10-23 13:25:28.955 Detected network event
. 2017-10-23 13:25:28.955 Enumerating network events for socket 1140
. 2017-10-23 13:25:28.955 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:28.955 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:28.955 Received 9 bytes (0)
. 2017-10-23 13:25:28.955 Read 4 bytes (5 pending)
. 2017-10-23 13:25:28.955 Read 5 bytes (0 pending)
< 2017-10-23 13:25:28.955 Type: SSH_FXP_VERSION, Size: 5, Number: -1
< 2017-10-23 13:25:28.955 02,00,00,00,04,
. 2017-10-23 13:25:28.955 SFTP version 4 negotiated.
. 2017-10-23 13:25:28.955 We will use UTF-8 strings as it is mandatory with SFTP version 4 and newer
. 2017-10-23 13:25:28.955 Changing directory to "/".
. 2017-10-23 13:25:28.955 Getting real path for '/'
> 2017-10-23 13:25:28.955 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
> 2017-10-23 13:25:28.955 10,00,00,00,10,00,00,00,01,2F,
. 2017-10-23 13:25:28.955 Sent 14 bytes
. 2017-10-23 13:25:28.955 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:25:28.955 Looking for network events
. 2017-10-23 13:25:28.955 Timeout waiting for network events
. 2017-10-23 13:25:28.955 Waiting for another 4 bytes
. 2017-10-23 13:25:28.955 Looking for incoming data
. 2017-10-23 13:25:28.955 Looking for network events
. 2017-10-23 13:25:29.002 Detected network event
. 2017-10-23 13:25:29.002 Enumerating network events for socket 1140
. 2017-10-23 13:25:29.002 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:29.002 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:29.002 Received 27 bytes (0)
. 2017-10-23 13:25:29.002 Read 4 bytes (23 pending)
. 2017-10-23 13:25:29.002 Read 23 bytes (0 pending)
< 2017-10-23 13:25:29.002 Type: SSH_FXP_NAME, Size: 23, Number: 16
< 2017-10-23 13:25:29.002 68,00,00,00,10,00,00,00,01,00,00,00,01,2F,00,00,00,04,02,00,00,01,E8,
. 2017-10-23 13:25:29.002 Real path is '/'
. 2017-10-23 13:25:29.002 Trying to open directory "/".
> 2017-10-23 13:25:29.002 Type: SSH_FXP_LSTAT, Size: 14, Number: 263
> 2017-10-23 13:25:29.002 07,00,00,01,07,00,00,00,01,2F,00,00,00,AD,
. 2017-10-23 13:25:29.002 Sent 18 bytes
. 2017-10-23 13:25:29.002 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:25:29.002 Looking for network events
. 2017-10-23 13:25:29.002 Timeout waiting for network events
. 2017-10-23 13:25:29.002 Waiting for another 4 bytes
. 2017-10-23 13:25:29.002 Looking for incoming data
. 2017-10-23 13:25:29.002 Looking for network events
. 2017-10-23 13:25:29.017 Detected network event
. 2017-10-23 13:25:29.017 Enumerating network events for socket 1140
. 2017-10-23 13:25:29.017 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:29.017 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:29.017 Received 42 bytes (0)
. 2017-10-23 13:25:29.017 Read 4 bytes (38 pending)
. 2017-10-23 13:25:29.017 Read 38 bytes (0 pending)
< 2017-10-23 13:25:29.017 Type: SSH_FXP_ATTRS, Size: 38, Number: 263
< 2017-10-23 13:25:29.017 69,00,00,01,07,00,00,00,3C,02,00,00,01,E8,00,00,00,00,59,E8,C2,0C,00,00,00,
< 2017-10-23 13:25:29.017 00,55,CB,5A,26,00,00,00,00,59,E8,C2,0C,
. 2017-10-23 13:25:29.017 Getting current directory name.
. 2017-10-23 13:25:29.080 Listing directory "/".
> 2017-10-23 13:25:29.080 Type: SSH_FXP_OPENDIR, Size: 10, Number: 523
> 2017-10-23 13:25:29.080 0B,00,00,02,0B,00,00,00,01,2F,
. 2017-10-23 13:25:29.080 Sent 14 bytes
. 2017-10-23 13:25:29.080 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:25:29.080 Looking for network events
. 2017-10-23 13:25:29.080 Timeout waiting for network events
. 2017-10-23 13:25:29.080 Waiting for another 4 bytes
. 2017-10-23 13:25:29.080 Looking for incoming data
. 2017-10-23 13:25:29.080 Looking for network events
. 2017-10-23 13:25:29.080 Detected network event
. 2017-10-23 13:25:29.080 Enumerating network events for socket 1140
. 2017-10-23 13:25:29.080 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:29.080 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:29.080 Received 15 bytes (0)
. 2017-10-23 13:25:29.080 Read 4 bytes (11 pending)
. 2017-10-23 13:25:29.080 Read 11 bytes (0 pending)
< 2017-10-23 13:25:29.080 Type: SSH_FXP_HANDLE, Size: 11, Number: 523
< 2017-10-23 13:25:29.080 66,00,00,02,0B,00,00,00,02,32,31,
> 2017-10-23 13:25:29.080 Type: SSH_FXP_READDIR, Size: 11, Number: 780
> 2017-10-23 13:25:29.080 0C,00,00,03,0C,00,00,00,02,32,31,
. 2017-10-23 13:25:29.080 Sent 15 bytes
. 2017-10-23 13:25:29.080 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:25:29.080 Looking for network events
. 2017-10-23 13:25:29.080 Timeout waiting for network events
. 2017-10-23 13:25:29.080 Waiting for another 4 bytes
. 2017-10-23 13:25:29.080 Looking for incoming data
. 2017-10-23 13:25:29.080 Looking for network events
. 2017-10-23 13:25:29.096 Detected network event
. 2017-10-23 13:25:29.096 Enumerating network events for socket 1140
. 2017-10-23 13:25:29.096 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:29.096 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:29.096 Received 90 bytes (0)
. 2017-10-23 13:25:29.096 Read 4 bytes (86 pending)
. 2017-10-23 13:25:29.096 Read 86 bytes (0 pending)
< 2017-10-23 13:25:29.096 Type: SSH_FXP_NAME, Size: 86, Number: 780
< 2017-10-23 13:25:29.096 68,00,00,03,0C,00,00,00,02,00,00,00,01,2E,00,00,00,3C,02,00,00,01,E8,00,00,
< 2017-10-23 13:25:29.096 00,00,59,E8,C2,0C,00,00,00,00,55,CB,5A,26,00,00,00,00,59,E8,C2,0C,00,00,00,
< 2017-10-23 13:25:29.096 02,2E,2E,00,00,00,3C,02,00,00,01,E8,00,00,00,00,59,B7,E9,0A,00,00,00,00,55,
< 2017-10-23 13:25:29.096 CA,05,FA,00,00,00,00,59,B7,E9,0A,
> 2017-10-23 13:25:29.096 Type: SSH_FXP_READDIR, Size: 11, Number: 1036
> 2017-10-23 13:25:29.096 0C,00,00,04,0C,00,00,00,02,32,31,
. 2017-10-23 13:25:29.096 Sent 15 bytes
. 2017-10-23 13:25:29.096 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:25:29.096 Looking for network events
. 2017-10-23 13:25:29.096 Timeout waiting for network events
. 2017-10-23 13:25:29.096 Read file '.' from listing
. 2017-10-23 13:25:29.096 Read file '..' from listing
. 2017-10-23 13:25:29.096 Waiting for another 4 bytes
. 2017-10-23 13:25:29.096 Looking for incoming data
. 2017-10-23 13:25:29.096 Looking for network events
. 2017-10-23 13:25:29.111 Detected network event
. 2017-10-23 13:25:29.111 Enumerating network events for socket 1140
. 2017-10-23 13:25:29.111 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:29.111 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:29.111 Received 32 bytes (0)
. 2017-10-23 13:25:29.111 Read 4 bytes (28 pending)
. 2017-10-23 13:25:29.111 Read 28 bytes (0 pending)
< 2017-10-23 13:25:29.111 Type: SSH_FXP_STATUS, Size: 28, Number: 1036
< 2017-10-23 13:25:29.111 65,00,00,04,0C,00,00,00,01,00,00,00,0B,45,6E,64,20,6F,66,20,46,69,6C,65,00,
< 2017-10-23 13:25:29.111 00,00,00,
< 2017-10-23 13:25:29.111 Status code: 1
> 2017-10-23 13:25:29.111 Type: SSH_FXP_CLOSE, Size: 11, Number: 1284
> 2017-10-23 13:25:29.111 04,00,00,05,04,00,00,00,02,32,31,
. 2017-10-23 13:25:29.111 Sent 15 bytes
. 2017-10-23 13:25:29.111 There are 0 bytes remaining in the send buffer
. 2017-10-23 13:25:29.111 Looking for network events
. 2017-10-23 13:25:29.111 Timeout waiting for network events
. 2017-10-23 13:25:29.111 ..;D;0;2017-09-12T14:02:50.000Z;3;"" [0];"" [0];rwxr-x---;0
. 2017-10-23 13:25:29.111 Session upkeep
. 2017-10-23 13:25:29.111 Looking for network events
. 2017-10-23 13:25:29.111 Timeout waiting for network events
. 2017-10-23 13:25:29.149 Startup conversation with host finished.
. 2017-10-23 13:25:29.586 Session upkeep
. 2017-10-23 13:25:29.586 Looking for network events
. 2017-10-23 13:25:29.586 Detected network event
. 2017-10-23 13:25:29.586 Enumerating network events for socket 1140
. 2017-10-23 13:25:29.586 Enumerated 1 network events making 1 cumulative events for socket 1140
. 2017-10-23 13:25:29.586 Handling network read event on socket 1140 with error 0
. 2017-10-23 13:25:29.586 Received 21 bytes (0)
. 2017-10-23 13:25:30.082 Session upkeep
. 2017-10-23 13:25:30.082 Looking for network events
. 2017-10-23 13:25:30.082 Timeout waiting for network events
. 2017-10-23 13:25:30.606 Closing connection.
. 2017-10-23 13:25:30.606 Sending special code: 12
. 2017-10-23 13:25:30.606 Sent EOF message
. 2017-10-23 13:25:30.606 Selecting events 0 for socket 1140

I think we can stop looking, this is what I got back from the guys that bring you ws-ftp:

I've been doing some testing against my WS_FTP Server 8.5 with various versions of WinSCP.

I've found that any version between 5.5.1 and 5.7.4 will fail to connect to our server. Looking through my verbose server logs I can see that all those versions are attempting to use the Diffie-Hellman-group-exchange-sha256 key exchange algorithm.

From WinSCP 5.7.5 upwards it is using the same algorithm but will allow the connection to go through and be successful. With this particular version they implemented the following.

Supporting the RFC 4419 revision to Diffie-Hellman group exchange.

This was to fix an issue connecting to OpenSSH 6.9 servers using the same algorithm as described in the link below.

https://winscp.net/forum/viewtopic.php?t=15626

The reason this never affected them before was that we only added Diffie-Hellman-group-exchange-sha256 into WS_FTP Server 8.5, it was never present in 7.7. However our implementation of it as it is very recent will adhere to RFC 4419.

in addition the reason why your original version of WinSCP 5.1.3 was working is that it doesn't support Diffie-Hellman-group-exchange-sha256. In testing with that version against my server it was using Diffie-Hellman-group14-sha1.

So in short this is a client side issue which to address they will need to upgrade to at least WinSCP version 5.7.5.

Robocop wrote:

martin wrote:

So use the latest version. It's the right thing to do in any case.

If you want us to investigate this any further, we need to see WinSCP log files form all three versions.

I could not agree more, next challenge is to tell this to our clients ;-)

Thanks for sharing your solution!