WinSCP & SFTP Problem (only with new versions)

Advertisement

iZ
Guest

WinSCP & SFTP Problem (only with new versions)

Hi,

I´m using WinSCP portable 4.1.9 and 5.5.4

I´m having problems with the 5.5.4 version to connect to a SFTP Server,

. 2014-06-13 15:51:19.791 --------------------------------------------------------------------------
. 2014-06-13 15:51:19.854 Looking up host "[i]XXXXXXXXXXXXXX[/i]"
. 2014-06-13 15:51:19.854 Connecting to [i]XXXXXXXXXXXXXXXXXX [/i]port 22
. 2014-06-13 15:51:20.182 Selecting events 63 for socket 472
. 2014-06-13 15:51:20.213 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:20.213 Looking for incoming data
. 2014-06-13 15:51:20.213 Looking for network events
. 2014-06-13 15:51:20.213 Detected network event
. 2014-06-13 15:51:20.213 Enumerating network events for socket 472
. 2014-06-13 15:51:20.213 Enumerated 18 network events making 18 cumulative events for socket 472
. 2014-06-13 15:51:20.213 Handling network write event on socket 472 with error 0
. 2014-06-13 15:51:20.213 Handling network connect event on socket 472 with error 0
. 2014-06-13 15:51:20.213 Looking for network events
. 2014-06-13 15:51:20.510 Detected network event
. 2014-06-13 15:51:20.510 Enumerating network events for socket 472
. 2014-06-13 15:51:20.510 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:20.510 Handling network read event on socket 472 with error 0
. 2014-06-13 15:51:20.510 Server version: SSH-2.0-SysaxSSH_1.0
. 2014-06-13 15:51:20.510 Using SSH protocol version 2
. 2014-06-13 15:51:20.510 We claim version: SSH-2.0-WinSCP_release_5.5.4
. 2014-06-13 15:51:20.510 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:20.510 Looking for incoming data
. 2014-06-13 15:51:20.510 Looking for network events
. 2014-06-13 15:51:20.854 Detected network event
. 2014-06-13 15:51:20.854 Enumerating network events for socket 472
. 2014-06-13 15:51:20.854 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:20.854 Handling network read event on socket 472 with error 0
. 2014-06-13 15:51:20.854 Doing Diffie-Hellman group exchange
. 2014-06-13 15:51:20.854 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:20.854 Looking for incoming data
. 2014-06-13 15:51:20.854 Looking for network events
. 2014-06-13 15:51:21.182 Detected network event
. 2014-06-13 15:51:21.182 Enumerating network events for socket 472
. 2014-06-13 15:51:21.182 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:21.182 Handling network read event on socket 472 with error 0
. 2014-06-13 15:51:21.182 Doing Diffie-Hellman key exchange with hash SHA-1
. 2014-06-13 15:51:21.307 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:21.307 Looking for incoming data
. 2014-06-13 15:51:21.307 Looking for network events
. 2014-06-13 15:51:21.666 Detected network event
. 2014-06-13 15:51:21.666 Enumerating network events for socket 472
. 2014-06-13 15:51:21.666 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:21.666 Handling network read event on socket 472 with error 0
. 2014-06-13 15:51:21.807 Verifying host key rsa2 [i]XXXXXXXXXXXX  [/i]with fingerprint ssh-rsa 1024 [i]XXXXXXXXXXX[/i]
. 2014-06-13 15:51:21.838 Host key matches cached key
. 2014-06-13 15:51:21.838 Selecting events 63 for socket 472
. 2014-06-13 15:51:21.838 Host key fingerprint is:
. 2014-06-13 15:51:21.838 ssh-rsa 1024 f9:13:eb:fe:9e:2d:2e:53:41:87:1c:9f:12:a3:9e:02
. 2014-06-13 15:51:21.838 Initialised AES-256 SDCTR client->server encryption
. 2014-06-13 15:51:21.838 Initialised HMAC-SHA1 client->server MAC algorithm
. 2014-06-13 15:51:21.838 Initialised AES-256 SDCTR server->client encryption
. 2014-06-13 15:51:21.838 Initialised HMAC-SHA1 server->client MAC algorithm
. 2014-06-13 15:51:21.838 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:21.838 Looking for incoming data
. 2014-06-13 15:51:21.838 Looking for network events
. 2014-06-13 15:51:22.713 Detected network event
. 2014-06-13 15:51:22.713 Enumerating network events for socket 472
. 2014-06-13 15:51:22.713 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:22.713 Handling network read event on socket 472 with error 0
! 2014-06-13 15:51:22.713 Using username "bshftp".
. 2014-06-13 15:51:22.744 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:22.744 Looking for incoming data
. 2014-06-13 15:51:22.744 Looking for network events
. 2014-06-13 15:51:23.088 Detected network event
. 2014-06-13 15:51:23.088 Enumerating network events for socket 472
. 2014-06-13 15:51:23.088 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:23.088 Handling network read event on socket 472 with error 0
. 2014-06-13 15:51:23.088 Prompt (7, SSH password, , &Password: )
. 2014-06-13 15:51:23.088 Using stored password.
. 2014-06-13 15:51:23.104 Sent password
. 2014-06-13 15:51:23.104 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:23.104 Looking for incoming data
. 2014-06-13 15:51:23.104 Looking for network events
. 2014-06-13 15:51:23.432 Detected network event
. 2014-06-13 15:51:23.432 Enumerating network events for socket 472
. 2014-06-13 15:51:23.432 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:23.432 Handling network read event on socket 472 with error 0
. 2014-06-13 15:51:23.432 Access granted
. 2014-06-13 15:51:23.432 Opening session as main channel
. 2014-06-13 15:51:23.432 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:23.432 Looking for incoming data
. 2014-06-13 15:51:23.432 Looking for network events
. 2014-06-13 15:51:23.776 Detected network event
. 2014-06-13 15:51:23.776 Enumerating network events for socket 472
. 2014-06-13 15:51:23.776 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:23.776 Handling network read event on socket 472 with error 0
. 2014-06-13 15:51:23.776 Opened main channel
. 2014-06-13 15:51:23.776 Waiting for the server to continue with the initialization
. 2014-06-13 15:51:23.776 Looking for incoming data
. 2014-06-13 15:51:23.776 Looking for network events
. 2014-06-13 15:51:24.104 Detected network event
. 2014-06-13 15:51:24.104 Enumerating network events for socket 472
. 2014-06-13 15:51:24.104 Enumerated 1 network events making 1 cumulative events for socket 472
. 2014-06-13 15:51:24.104 Handling network read event on socket 472 with error 0
. 2014-06-13 15:51:24.104 Received disconnect message (protocol error)
. 2014-06-13 15:51:24.104 Disconnection message text: disconnecting
. 2014-06-13 15:51:24.104 Selecting events 0 for socket 472
. 2014-06-13 15:51:24.104 Server sent disconnect message
. 2014-06-13 15:51:24.104 type 2 (protocol error):
. 2014-06-13 15:51:24.104 "disconnecting"
* 2014-06-13 15:51:24.119 (EFatal) Server sent disconnect message
* 2014-06-13 15:51:24.119 type 2 (protocol error):
* 2014-06-13 15:51:24.119 "disconnecting"
* 2014-06-13 15:51:24.119 Authentication log (see session log for details):
* 2014-06-13 15:51:24.119 Using username "bshftp".
* 2014-06-13 15:51:24.119 
* 2014-06-13 15:51:24.119 Authentication failed.

But with the old version 4.1.9 works,


. 2014-06-13 15:54:40.061 --------------------------------------------------------------------------
. 2014-06-13 15:54:40.076 Looking up host "[i]XXXXXXXXXXXXXXXXXXX[/i]"
. 2014-06-13 15:54:40.076 Connecting to [i]XXXXXXXXXXXXXXXXXXX [/i]port 22
. 2014-06-13 15:54:40.404 Selecting events 63 for socket 548
. 2014-06-13 15:54:40.404 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:40.404 Looking for incoming data
. 2014-06-13 15:54:40.404 Looking for network events
. 2014-06-13 15:54:40.404 Detected network event
. 2014-06-13 15:54:40.404 Enumerating network events for socket 548
. 2014-06-13 15:54:40.404 Enumerated 18 network events making 18 cumulative events for socket 548
. 2014-06-13 15:54:40.404 Handling network write event on socket 548 with error 0
. 2014-06-13 15:54:40.404 Handling network connect event on socket 548 with error 0
. 2014-06-13 15:54:40.404 Looking for network events
. 2014-06-13 15:54:40.733 Detected network event
. 2014-06-13 15:54:40.733 Enumerating network events for socket 548
. 2014-06-13 15:54:40.733 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:40.733 Handling network read event on socket 548 with error 0
. 2014-06-13 15:54:40.733 Server version: SSH-2.0-SysaxSSH_1.0
. 2014-06-13 15:54:40.733 We claim version: SSH-2.0-WinSCP_release_4.1.9
. 2014-06-13 15:54:40.733 SSPI: acquired credentials for: [i]XXXXXXXXX@XXXXXXXXXXXX[/i]
. 2014-06-13 15:54:40.733 Cannot convert IP address to SPN: DNS is not trusted
. 2014-06-13 15:54:40.733 GSSKEX disabled: The operation completed successfully.

. 2014-06-13 15:54:40.733 Using SSH protocol version 2
. 2014-06-13 15:54:40.733 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:40.733 Looking for incoming data
. 2014-06-13 15:54:40.733 Looking for network events
. 2014-06-13 15:54:41.061 Detected network event
. 2014-06-13 15:54:41.061 Enumerating network events for socket 548
. 2014-06-13 15:54:41.061 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:41.061 Handling network read event on socket 548 with error 0
. 2014-06-13 15:54:41.061 Doing Diffie-Hellman group exchange
. 2014-06-13 15:54:41.061 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:41.061 Looking for incoming data
. 2014-06-13 15:54:41.061 Looking for network events
. 2014-06-13 15:54:41.404 Detected network event
. 2014-06-13 15:54:41.404 Enumerating network events for socket 548
. 2014-06-13 15:54:41.404 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:41.404 Handling network read event on socket 548 with error 0
. 2014-06-13 15:54:41.404 Doing Diffie-Hellman key exchange with hash SHA-1
. 2014-06-13 15:54:41.498 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:41.498 Looking for incoming data
. 2014-06-13 15:54:41.498 Looking for network events
. 2014-06-13 15:54:41.842 Detected network event
. 2014-06-13 15:54:41.842 Enumerating network events for socket 548
. 2014-06-13 15:54:41.842 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:41.842 Handling network read event on socket 548 with error 0
. 2014-06-13 15:54:41.936 Selecting events 63 for socket 548
. 2014-06-13 15:54:41.936 Host key fingerprint is:
. 2014-06-13 15:54:41.936 ssh-rsa 1024 [i]XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX[/i]
. 2014-06-13 15:54:41.936 Initialised AES-256 SDCTR client->server encryption
. 2014-06-13 15:54:41.936 Initialised HMAC-SHA1 client->server MAC algorithm
. 2014-06-13 15:54:41.951 Initialised AES-256 SDCTR server->client encryption
. 2014-06-13 15:54:41.951 Initialised HMAC-SHA1 server->client MAC algorithm
. 2014-06-13 15:54:41.951 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:41.951 Looking for incoming data
. 2014-06-13 15:54:41.951 Looking for network events
. 2014-06-13 15:54:42.764 Detected network event
. 2014-06-13 15:54:42.764 Enumerating network events for socket 548
. 2014-06-13 15:54:42.764 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:42.764 Handling network read event on socket 548 with error 0
! 2014-06-13 15:54:42.764 Using username "blabla".
. 2014-06-13 15:54:42.764 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:42.764 Looking for incoming data
. 2014-06-13 15:54:42.764 Looking for network events
. 2014-06-13 15:54:43.108 Detected network event
. 2014-06-13 15:54:43.108 Enumerating network events for socket 548
. 2014-06-13 15:54:43.108 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:43.108 Handling network read event on socket 548 with error 0
. 2014-06-13 15:54:43.108 Prompt (6, SSH password, , &Password: )
. 2014-06-13 15:54:43.108 Using stored password.
. 2014-06-13 15:54:43.108 Sent password
. 2014-06-13 15:54:43.108 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:43.108 Looking for incoming data
. 2014-06-13 15:54:43.108 Looking for network events
. 2014-06-13 15:54:43.436 Detected network event
. 2014-06-13 15:54:43.436 Enumerating network events for socket 548
. 2014-06-13 15:54:43.436 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:43.436 Handling network read event on socket 548 with error 0
. 2014-06-13 15:54:43.436 Access granted
. 2014-06-13 15:54:43.436 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:43.436 Looking for incoming data
. 2014-06-13 15:54:43.436 Looking for network events
. 2014-06-13 15:54:43.764 Detected network event
. 2014-06-13 15:54:43.764 Enumerating network events for socket 548
. 2014-06-13 15:54:43.764 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:43.764 Handling network read event on socket 548 with error 0
. 2014-06-13 15:54:43.764 Opened channel for session
. 2014-06-13 15:54:43.764 Waiting for the server to continue with the initialisation
. 2014-06-13 15:54:43.764 Looking for incoming data
. 2014-06-13 15:54:43.764 Looking for network events
. 2014-06-13 15:54:44.092 Detected network event
. 2014-06-13 15:54:44.092 Enumerating network events for socket 548
. 2014-06-13 15:54:44.092 Enumerated 1 network events making 1 cumulative events for socket 548
. 2014-06-13 15:54:44.092 Handling network read event on socket 548 with error 0
. 2014-06-13 15:54:44.092 Started a shell/command
. 2014-06-13 15:54:44.092 --------------------------------------------------------------------------
.................................
.......................
.............

Could be something related with... ?,

. 2014-06-13 15:54:40.733 SSPI: acquired credentials for:

Should I change something on the parameters?

Reply with quote

Advertisement

iZ
Guest

GSSAPI is disabled in both versions. Activating it doesn´t solve the Error message :/

What do you mean with "you have excluded session parameters from the log"? The IPs ?

Reply with quote

martin
Site Admin
martin avatar
Joined:
Posts:
40,476
Location:
Prague, Czechia

Do you have an access to server log? As it's the server who interrupts the connection, so there can be something useful in the server log.

. 2014-06-13 15:51:24.104 Received disconnect message (protocol error)
. 2014-06-13 15:51:24.104 Disconnection message text: disconnecting

Reply with quote

Advertisement

Anonymous_Engineer
Guest

FINALLY THE SOLUTION

Error of Winscp logs:
. 2018-01-25 17:54:29.796 [Shell] Handling network write event on socket 1008 with error 0

Reason: The server rsa2 fingerprint is not being inserted in command line

Solution commands:
cd C:\Program Files\WinSCP
winscp.exe /command "open sftp://USERNAME@YOURIPADDRESS -hostkey=""ssh-rsa 2048 XX:XX:XX:XX:XX:XX:XX:XX:XX:XX:XX:XX:XX:XX:XX:XX""" "exit"

How to get rsa in a simple way?
Enter username and ip and port 22 in WinSCP. It will prompt a window with the ssh-rsa of the server

Reply with quote

Advertisement

You can post new topics in this forum