Forum » Support and Bug Reports »

Post a reply

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

Bug reports without an attached log file are usually useless.

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

Guest

Re: Cant connect to SFTP-Server - old winscp version is working!

martin 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!
martin

Re: Cant connect to SFTP-Server - old winscp version is working!

Please post the same log for 4.1.9.
ciaccom

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

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). 

[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): 

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

Documentation

Support

Associations

Follow Us