Topic "Cant connect to SFTP-Server - old winscp version is working!"

Author Message
ciaccom
[View user's profile]

Joined: 2011-12-12
Posts: 1
hello,

strange problem: using winscp v4.1.9 (build416) to connect to NullFTP-Server (v1.0.3.3) is working fine with SFTP (fallback SCP).

with winscp v4.3.5 (build1463) the client connects to the SFTP server and refuses with following log (debug mode 2).

Code:
[size=12]. 2011-12-12 11:11:05.305 --------------------------------------------------------------------------
. 2011-12-12 11:11:05.305 WinSCP Version 4.3.5 (Build 1463) (OS 6.1.7601 Service Pack 1)
. 2011-12-12 11:11:05.305 Configuration: C:\Users\admin\Desktop\WinSCP.ini
. 2011-12-12 11:11:05.305 Local account: D86002E1\admin
. 2011-12-12 11:11:05.305 Login time: Montag, 12. Dezember 2011 11:11:05
. 2011-12-12 11:11:05.305 --------------------------------------------------------------------------
. 2011-12-12 11:11:05.305 Session name: walm@10.192.37.225 (Ad-Hoc session)
. 2011-12-12 11:11:05.305 Host name: 10.192.37.225 (Port: 2200)
. 2011-12-12 11:11:05.305 User name: walm (Password: Yes, Key file: No)
. 2011-12-12 11:11:05.305 Tunnel: No
. 2011-12-12 11:11:05.305 Transfer Protocol: SFTP (SCP)
. 2011-12-12 11:11:05.305 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2011-12-12 11:11:05.305 Proxy: none
. 2011-12-12 11:11:05.305 SSH protocol version: 2; Compression: No
. 2011-12-12 11:11:05.305 Bypass authentication: No
. 2011-12-12 11:11:05.305 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2011-12-12 11:11:05.305 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2011-12-12 11:11:05.305 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2011-12-12 11:11:05.305 SFTP Bugs: -,-
. 2011-12-12 11:11:05.305 Return code variable: Autodetect; Lookup user groups: Yes
. 2011-12-12 11:11:05.305 Shell: default
. 2011-12-12 11:11:05.305 EOL: 0, UTF: 2
. 2011-12-12 11:11:05.305 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2011-12-12 11:11:05.305 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2011-12-12 11:11:05.305 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2011-12-12 11:11:05.305 Cache directory changes: Yes, Permanent: Yes
. 2011-12-12 11:11:05.305 DST mode: 1
. 2011-12-12 11:11:05.305 --------------------------------------------------------------------------
. 2011-12-12 11:11:05.321 Looking up host "10.192.37.225"
. 2011-12-12 11:11:05.321 Connecting to 10.192.37.225 port 2200
. 2011-12-12 11:11:05.321 Selecting events 63 for socket 748
. 2011-12-12 11:11:05.321 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.321 Looking for incoming data
. 2011-12-12 11:11:05.321 Looking for network events
. 2011-12-12 11:11:05.321 Detected network event
. 2011-12-12 11:11:05.321 Enumerating network events for socket 748
. 2011-12-12 11:11:05.321 Enumerated 19 network events making 19 cumulative events for socket 748
. 2011-12-12 11:11:05.321 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.321 Server version: SSH-2.0-1.82 sshlib: NullShare SFTP Server
. 2011-12-12 11:11:05.321 We believe remote version has SSH-2 ignore bug
. 2011-12-12 11:11:05.321 Using SSH protocol version 2
. 2011-12-12 11:11:05.321 We claim version: SSH-2.0-WinSCP_release_4.3.5
. 2011-12-12 11:11:05.321 Handling network write event on socket 748 with error 0
. 2011-12-12 11:11:05.321 Handling network connect event on socket 748 with error 0
. 2011-12-12 11:11:05.321 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.321 Looking for incoming data
. 2011-12-12 11:11:05.321 Looking for network events
. 2011-12-12 11:11:05.321 Detected network event
. 2011-12-12 11:11:05.321 Enumerating network events for socket 748
. 2011-12-12 11:11:05.321 Enumerated 1 network events making 1 cumulative events for socket 748
. 2011-12-12 11:11:05.321 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.321 Doing Diffie-Hellman group exchange
. 2011-12-12 11:11:05.321 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.321 Looking for incoming data
. 2011-12-12 11:11:05.321 Looking for network events
. 2011-12-12 11:11:05.321 Detected network event
. 2011-12-12 11:11:05.321 Enumerating network events for socket 748
. 2011-12-12 11:11:05.321 Enumerated 1 network events making 1 cumulative events for socket 748
. 2011-12-12 11:11:05.321 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.321 Doing Diffie-Hellman key exchange with hash SHA-1
. 2011-12-12 11:11:05.399 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.399 Looking for incoming data
. 2011-12-12 11:11:05.399 Looking for network events
. 2011-12-12 11:11:05.414 Detected network event
. 2011-12-12 11:11:05.414 Enumerating network events for socket 748
. 2011-12-12 11:11:05.414 Enumerated 1 network events making 1 cumulative events for socket 748
. 2011-12-12 11:11:05.414 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.492 Selecting events 63 for socket 748
. 2011-12-12 11:11:05.492 Host key fingerprint is:
. 2011-12-12 11:11:05.492 ssh-rsa 1024 77:42:b2:ce:6a:73:99:e5:b1:1f:3f:28:98:2c:26:b9
. 2011-12-12 11:11:05.492 Initialised AES-256 CBC client->server encryption
. 2011-12-12 11:11:05.492 Initialised HMAC-SHA1 client->server MAC algorithm
. 2011-12-12 11:11:05.492 Initialised AES-256 CBC server->client encryption
. 2011-12-12 11:11:05.492 Initialised HMAC-SHA1 server->client MAC algorithm
. 2011-12-12 11:11:05.492 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.492 Looking for incoming data
. 2011-12-12 11:11:05.492 Looking for network events
. 2011-12-12 11:11:05.492 Detected network event
. 2011-12-12 11:11:05.492 Enumerating network events for socket 748
. 2011-12-12 11:11:05.492 Enumerated 1 network events making 1 cumulative events for socket 748
. 2011-12-12 11:11:05.492 Handling network read event on socket 748 with error 0
! 2011-12-12 11:11:05.492 Using username "walm".
. 2011-12-12 11:11:05.492 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.492 Looking for incoming data
. 2011-12-12 11:11:05.492 Looking for network events
. 2011-12-12 11:11:05.492 Detected network event
. 2011-12-12 11:11:05.492 Enumerating network events for socket 748
. 2011-12-12 11:11:05.492 Enumerated 1 network events making 1 cumulative events for socket 748
. 2011-12-12 11:11:05.492 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.492 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.492 Looking for incoming data
. 2011-12-12 11:11:05.492 Looking for network events
. 2011-12-12 11:11:05.492 Detected network event
. 2011-12-12 11:11:05.492 Enumerating network events for socket 748
. 2011-12-12 11:11:05.492 Enumerated 1 network events making 1 cumulative events for socket 748
. 2011-12-12 11:11:05.492 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.492 Keyboard-interactive authentication refused
. 2011-12-12 11:11:05.492 Prompt (7, SSH password, , &Password: )
. 2011-12-12 11:11:05.492 Using stored password.
. 2011-12-12 11:11:05.492 Sent password
. 2011-12-12 11:11:05.492 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.492 Looking for incoming data
. 2011-12-12 11:11:05.492 Looking for network events
. 2011-12-12 11:11:05.492 Detected network event
. 2011-12-12 11:11:05.492 Enumerating network events for socket 748
. 2011-12-12 11:11:05.492 Enumerated 1 network events making 1 cumulative events for socket 748
. 2011-12-12 11:11:05.492 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.492 Access granted
. 2011-12-12 11:11:05.492 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.492 Looking for incoming data
. 2011-12-12 11:11:05.492 Looking for network events
. 2011-12-12 11:11:05.492 Detected network event
. 2011-12-12 11:11:05.492 Enumerating network events for socket 748
. 2011-12-12 11:11:05.492 Enumerated 1 network events making 1 cumulative events for socket 748
. 2011-12-12 11:11:05.492 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.492 Opened channel for session
. 2011-12-12 11:11:05.492 Waiting for the server to continue with the initialisation
. 2011-12-12 11:11:05.492 Looking for incoming data
. 2011-12-12 11:11:05.492 Looking for network events
. 2011-12-12 11:11:05.492 Detected network event
. 2011-12-12 11:11:05.492 Enumerating network events for socket 748
. 2011-12-12 11:11:05.492 Enumerated 33 network events making 33 cumulative events for socket 748
. 2011-12-12 11:11:05.492 Handling network read event on socket 748 with error 0
. 2011-12-12 11:11:05.492 Received disconnect message (protocol error)
. 2011-12-12 11:11:05.492 Disconnection message text: unexpected end of packet
. 2011-12-12 11:11:05.492 Selecting events 0 for socket 748
. 2011-12-12 11:11:05.492 Server sent disconnect message
. 2011-12-12 11:11:05.492 type 2 (protocol error):
. 2011-12-12 11:11:05.492 "unexpected end of packet"
* 2011-12-12 11:11:05.492 (ESshFatal) Server sent disconnect message
* 2011-12-12 11:11:05.492 type 2 (protocol error):
* 2011-12-12 11:11:05.492 "unexpected end of packet"
* 2011-12-12 11:11:05.492 Authentication log (see session log for details):
* 2011-12-12 11:11:05.492 Using username "walm".
* 2011-12-12 11:11:05.492
* 2011-12-12 11:11:05.492 Authentication failed.
. 2011-12-12 11:11:10.578 --------------------------------------------------------------------------[/size]


on the server side it looks like this (nothing special):

Code:
2011-12-12 11:11:05.492 : User walm logged in
2011-12-12 11:11:05.492 : Initial directory for user 'walm' is F:\Queue_CZ\/
2011-12-12 11:11:05.492 : User walm has disconnected from Secure FTP server


the connection attempt was done locally on a win 2008R2 server with NullFTP bind to the 10.192.37.225 IP / interface.


can anyone help - i really don't know where to dig up.

thanks in advance, ciaccom
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 24552
Location: Prague, Czechia
Please post the same log for 4.1.9.
_________________
Martin Prikryl
Guest




prikryl wrote:
Please post the same log for 4.1.9.


thanks for your reply prikryl! just half a hour ago i figured out, that NullFTP-Server is buggy! so the problem is not behind winscp!
Advertisements

You can post new topics in this forum






Search Site

What is WinSCP?

It is award-winning SFTP client, SCP client, FTPS client and FTP client integrated into one software program for file transfer to FTP server or secure SFTP server. [More]

And it's free!

Donate

About donations

$9   $19   $49   $99

About donations

Recommend

WinSCP Privacy Policy

WinSCP License