Protocol violation at login phase

Advertisement

alexs
Joined:
Posts:
2

Protocol violation at login phase

Hi
I could not login to my SSH server using WinScp.
Error: "SFTP protocol violation: Invalid message..."

At the same time I've successfully used FileZilla 3.0.11 and CuteFtpPro 8.2.0 (they showed me the content of /)

Details:
Version of WinSCP: 4.1.5 build 410
Transfer protocol: SFTP
Interface style: Norton commander

Log file (Debug2 level)

. 2008-07-22 16:59:45.734 --------------------------------------------------------------------------
. 2008-07-22 16:59:45.734 WinSCP Version 4.1.5 (Build 410) (OS 5.1.2600 Service Pack 2)
. 2008-07-22 16:59:45.734 Login time: 22 Липень 2008 р. 16:59:45
. 2008-07-22 16:59:45.734 --------------------------------------------------------------------------
. 2008-07-22 16:59:45.734 Session name: 172.99.99.51
. 2008-07-22 16:59:45.734 Host name: 172.99.99.51 (Port: 22)
. 2008-07-22 16:59:45.734 User name: (Password: No, Key file: No)
. 2008-07-22 16:59:45.734 Tunnel: No
. 2008-07-22 16:59:45.734 Transfer Protocol: SFTP
. 2008-07-22 16:59:45.734 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2008-07-22 16:59:45.734 Proxy: none
. 2008-07-22 16:59:45.734 SSH protocol version: 2; Compression: No
. 2008-07-22 16:59:45.734 Bypass authentication: No
. 2008-07-22 16:59:45.734 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2008-07-22 16:59:45.734 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2008-07-22 16:59:45.734 SSH Bugs: -,-,-,-,-,-,-,-
. 2008-07-22 16:59:45.734 SFTP Bugs: -,-
. 2008-07-22 16:59:45.734 Return code variable: Autodetect; Lookup user groups: Yes
. 2008-07-22 16:59:45.734 Shell: default, EOL: 0
. 2008-07-22 16:59:45.734 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2008-07-22 16:59:45.734 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2008-07-22 16:59:45.734 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2008-07-22 16:59:45.734 Cache directory changes: Yes, Permanent: Yes
. 2008-07-22 16:59:45.734 DST mode: 1
. 2008-07-22 16:59:45.734 --------------------------------------------------------------------------
. 2008-07-22 16:59:45.765 Looking up host "172.99.99.51"
. 2008-07-22 16:59:45.765 Connecting to 172.99.99.51 port 22
. 2008-07-22 16:59:45.765 Selecting events 63 for socket 672
. 2008-07-22 16:59:45.765 Waiting for the server to continue with the initialisation
. 2008-07-22 16:59:45.765 Looking for incoming data
. 2008-07-22 16:59:45.765 Looking for network events
. 2008-07-22 16:59:45.765 Detected network event
. 2008-07-22 16:59:45.765 Enumerating network events for socket 672
. 2008-07-22 16:59:45.765 Enumerated 18 network events making 18 cumulative events for socket 672
. 2008-07-22 16:59:45.765 Handling network write event on socket 672 with error 0
. 2008-07-22 16:59:45.765 Handling network connect event on socket 672 with error 0
. 2008-07-22 16:59:45.765 Looking for network events
. 2008-07-22 16:59:45.765 Detected network event
. 2008-07-22 16:59:45.765 Enumerating network events for socket 672
. 2008-07-22 16:59:45.765 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 16:59:45.765 Handling network read event on socket 672 with error 0
. 2008-07-22 16:59:45.765 Server version: SSH-2.0-1.0
. 2008-07-22 16:59:45.765 We claim version: SSH-2.0-WinSCP_release_4.1.5
. 2008-07-22 16:59:45.765 SSPI: acquired credentials for: alexs@PC20877
. 2008-07-22 16:59:45.765 Cannot convert IP address to SPN: DNS is not trusted
. 2008-07-22 16:59:45.765 GSSKEX disabled: The operation completed successfully.

. 2008-07-22 16:59:45.765 Using SSH protocol version 2
. 2008-07-22 16:59:45.765 Waiting for the server to continue with the initialisation
. 2008-07-22 16:59:45.765 Looking for incoming data
. 2008-07-22 16:59:45.765 Looking for network events
. 2008-07-22 16:59:45.765 Detected network event
. 2008-07-22 16:59:45.765 Enumerating network events for socket 672
. 2008-07-22 16:59:45.765 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 16:59:45.765 Handling network read event on socket 672 with error 0
. 2008-07-22 16:59:45.765 Using Diffie-Hellman with standard group "group1"
. 2008-07-22 16:59:45.765 Doing Diffie-Hellman key exchange with hash SHA-1
. 2008-07-22 16:59:45.781 Waiting for the server to continue with the initialisation
. 2008-07-22 16:59:45.781 Looking for incoming data
. 2008-07-22 16:59:45.781 Looking for network events
. 2008-07-22 16:59:56.765 Detected network event
. 2008-07-22 16:59:56.765 Enumerating network events for socket 672
. 2008-07-22 16:59:56.765 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 16:59:56.765 Handling network read event on socket 672 with error 0
. 2008-07-22 16:59:56.796 Selecting events 63 for socket 672
. 2008-07-22 16:59:56.796 Host key fingerprint is:
. 2008-07-22 16:59:56.796 ssh-dss 1024 09:43:4a:45:8f:60:84:aa:e9:6f:ff:db:f0:49:5a:3b
. 2008-07-22 16:59:56.796 Initialised triple-DES CBC client->server encryption
. 2008-07-22 16:59:56.796 Initialised HMAC-SHA1 client->server MAC algorithm
. 2008-07-22 16:59:56.796 Waiting for the server to continue with the initialisation
. 2008-07-22 16:59:56.796 Looking for incoming data
. 2008-07-22 16:59:56.796 Looking for network events
. 2008-07-22 16:59:56.796 Detected network event
. 2008-07-22 16:59:56.796 Enumerating network events for socket 672
. 2008-07-22 16:59:56.796 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 16:59:56.796 Handling network read event on socket 672 with error 0
. 2008-07-22 16:59:56.796 Initialised triple-DES CBC server->client encryption
. 2008-07-22 16:59:56.796 Initialised HMAC-SHA1 server->client MAC algorithm
. 2008-07-22 16:59:56.796 Waiting for the server to continue with the initialisation
. 2008-07-22 16:59:56.796 Looking for incoming data
. 2008-07-22 16:59:56.796 Looking for network events
. 2008-07-22 16:59:56.812 Detected network event
. 2008-07-22 16:59:56.812 Enumerating network events for socket 672
. 2008-07-22 16:59:56.812 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 16:59:56.812 Handling network read event on socket 672 with error 0
. 2008-07-22 16:59:56.812 Prompt (1, SSH login name, , login as: )
. 2008-07-22 17:00:05.125 Waiting for the server to continue with the initialisation
. 2008-07-22 17:00:05.125 Looking for incoming data
. 2008-07-22 17:00:05.125 Looking for network events
. 2008-07-22 17:00:05.140 Detected network event
. 2008-07-22 17:00:05.140 Enumerating network events for socket 672
. 2008-07-22 17:00:05.140 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:05.140 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:05.140 Prompt (6, SSH password, , &Password: )
. 2008-07-22 17:00:07.234 Sent password
. 2008-07-22 17:00:07.234 Waiting for the server to continue with the initialisation
. 2008-07-22 17:00:07.234 Looking for incoming data
. 2008-07-22 17:00:07.234 Looking for network events
. 2008-07-22 17:00:07.250 Detected network event
. 2008-07-22 17:00:07.250 Enumerating network events for socket 672
. 2008-07-22 17:00:07.250 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.250 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.250 Access granted
. 2008-07-22 17:00:07.250 Waiting for the server to continue with the initialisation
. 2008-07-22 17:00:07.250 Looking for incoming data
. 2008-07-22 17:00:07.250 Looking for network events
. 2008-07-22 17:00:07.265 Detected network event
. 2008-07-22 17:00:07.265 Enumerating network events for socket 672
. 2008-07-22 17:00:07.265 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.265 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.265 Opened channel for session
. 2008-07-22 17:00:07.265 Waiting for the server to continue with the initialisation
. 2008-07-22 17:00:07.265 Looking for incoming data
. 2008-07-22 17:00:07.265 Looking for network events
. 2008-07-22 17:00:07.265 Detected network event
. 2008-07-22 17:00:07.265 Enumerating network events for socket 672
. 2008-07-22 17:00:07.265 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.265 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.265 Waiting for the server to continue with the initialisation
. 2008-07-22 17:00:07.265 Looking for incoming data
. 2008-07-22 17:00:07.265 Looking for network events
. 2008-07-22 17:00:07.468 Detected network event
. 2008-07-22 17:00:07.468 Enumerating network events for socket 672
. 2008-07-22 17:00:07.468 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.468 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.468 Started a shell/command
. 2008-07-22 17:00:07.468 --------------------------------------------------------------------------
. 2008-07-22 17:00:07.468 Using SFTP protocol.
. 2008-07-22 17:00:07.468 Doing startup conversation with host.
> 2008-07-22 17:00:07.468 Type: SSH_FXP_INIT, Size: 5, Number: -1
> 2008-07-22 17:00:07.468 01,00,00,00,05,
. 2008-07-22 17:00:07.468 Sent 9 bytes
. 2008-07-22 17:00:07.468 There are 0 bytes remaining in the send buffer
. 2008-07-22 17:00:07.468 Looking for network events
. 2008-07-22 17:00:07.468 Timeout waiting for network events
. 2008-07-22 17:00:07.468 Waiting for another 4 bytes
. 2008-07-22 17:00:07.468 Looking for incoming data
. 2008-07-22 17:00:07.468 Looking for network events
. 2008-07-22 17:00:07.484 Detected network event
. 2008-07-22 17:00:07.484 Enumerating network events for socket 672
. 2008-07-22 17:00:07.484 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.484 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.484 Received 9 bytes (0)
. 2008-07-22 17:00:07.484 Read 4 bytes (5 pending)
. 2008-07-22 17:00:07.484 Read 5 bytes (0 pending)
< 2008-07-22 17:00:07.484 Type: SSH_FXP_VERSION, Size: 5, Number: -1
< 2008-07-22 17:00:07.484 02,00,00,00,03,
. 2008-07-22 17:00:07.484 SFTP version 3 negotiated.
. 2008-07-22 17:00:07.484 We believe the server has signed timestamps bug
. 2008-07-22 17:00:07.484 We will use UTF-8 strings for status messages only
. 2008-07-22 17:00:07.484 Getting current directory name.
. 2008-07-22 17:00:07.484 Getting real path for '.'
> 2008-07-22 17:00:07.484 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
> 2008-07-22 17:00:07.484 10,00,00,00,10,00,00,00,01,2E,
. 2008-07-22 17:00:07.484 Sent 14 bytes
. 2008-07-22 17:00:07.484 There are 0 bytes remaining in the send buffer
. 2008-07-22 17:00:07.484 Looking for network events
. 2008-07-22 17:00:07.484 Timeout waiting for network events
. 2008-07-22 17:00:07.484 Waiting for another 4 bytes
. 2008-07-22 17:00:07.484 Looking for incoming data
. 2008-07-22 17:00:07.484 Looking for network events
. 2008-07-22 17:00:07.500 Detected network event
. 2008-07-22 17:00:07.500 Enumerating network events for socket 672
. 2008-07-22 17:00:07.500 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.500 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.500 Received 27 bytes (0)
. 2008-07-22 17:00:07.500 Read 4 bytes (23 pending)
. 2008-07-22 17:00:07.500 Read 23 bytes (0 pending)
< 2008-07-22 17:00:07.500 Type: SSH_FXP_NAME, Size: 23, Number: 16
< 2008-07-22 17:00:07.500 68,00,00,00,10,00,00,00,01,00,00,00,01,2F,00,00,00,01,2F,00,00,00,00,
. 2008-07-22 17:00:07.500 Real path is '/'
. 2008-07-22 17:00:07.500 Listing directory "/".
> 2008-07-22 17:00:07.500 Type: SSH_FXP_OPENDIR, Size: 10, Number: 267
> 2008-07-22 17:00:07.500 0B,00,00,01,0B,00,00,00,01,2F,
. 2008-07-22 17:00:07.500 Sent 14 bytes
. 2008-07-22 17:00:07.500 There are 0 bytes remaining in the send buffer
. 2008-07-22 17:00:07.500 Looking for network events
. 2008-07-22 17:00:07.500 Timeout waiting for network events
. 2008-07-22 17:00:07.500 Waiting for another 4 bytes
. 2008-07-22 17:00:07.500 Looking for incoming data
. 2008-07-22 17:00:07.500 Looking for network events
. 2008-07-22 17:00:07.500 Detected network event
. 2008-07-22 17:00:07.500 Enumerating network events for socket 672
. 2008-07-22 17:00:07.500 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.500 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.500 Received 17 bytes (0)
. 2008-07-22 17:00:07.500 Read 4 bytes (13 pending)
. 2008-07-22 17:00:07.500 Read 13 bytes (0 pending)
< 2008-07-22 17:00:07.500 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
< 2008-07-22 17:00:07.500 66,00,00,01,0B,00,00,00,04,00,00,00,01,
> 2008-07-22 17:00:07.500 Type: SSH_FXP_READDIR, Size: 13, Number: 524
> 2008-07-22 17:00:07.500 0C,00,00,02,0C,00,00,00,04,00,00,00,01,
. 2008-07-22 17:00:07.500 Sent 17 bytes
. 2008-07-22 17:00:07.500 There are 0 bytes remaining in the send buffer
. 2008-07-22 17:00:07.500 Looking for network events
. 2008-07-22 17:00:07.500 Timeout waiting for network events
. 2008-07-22 17:00:07.500 Waiting for another 4 bytes
. 2008-07-22 17:00:07.500 Looking for incoming data
. 2008-07-22 17:00:07.500 Looking for network events
. 2008-07-22 17:00:07.640 Detected network event
. 2008-07-22 17:00:07.640 Enumerating network events for socket 672
. 2008-07-22 17:00:07.640 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.640 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.640 Received 1077 bytes (0)
. 2008-07-22 17:00:07.640 Read 4 bytes (1073 pending)
. 2008-07-22 17:00:07.640 Read 1073 bytes (0 pending)
< 2008-07-22 17:00:07.640 Type: SSH_FXP_NAME, Size: 1073, Number: 524
< 2008-07-22 17:00:07.640 68,00,00,02,0C,00,00,00,16,00,00,00,0A,65,72,72,5F,66,61,2E,6C,6F,67,00,00,
< 2008-07-22 17:00:07.640 00,0A,65,72,72,5F,66,61,2E,6C,6F,67,00,00,00,05,00,00,00,00,00,00,01,50,00,
< 2008-07-22 17:00:07.640 00,83,82,00,00,00,1A,47,61,74,65,77,61,79,55,6D,62,72,65,6C,6C,61,56,65,72,
< 2008-07-22 17:00:07.640 73,69,6F,6E,2E,74,78,74,00,00,00,1A,47,61,74,65,77,61,79,55,6D,62,72,65,6C,
< 2008-07-22 17:00:07.640 6C,61,56,65,72,73,69,6F,6E,2E,74,78,74,00,00,00,05,00,00,00,00,00,00,00,A7,
< 2008-07-22 17:00:07.640 00,00,83,82,00,00,00,0B,70,31,30,5F,31,32,2E,73,72,65,63,00,00,00,0B,70,31,
< 2008-07-22 17:00:07.640 30,5F,31,32,2E,73,72,65,63,00,00,00,05,00,00,00,00,00,02,EF,BB,00,00,83,82,
< 2008-07-22 17:00:07.640 00,00,00,0B,70,31,32,5F,31,30,2E,73,72,65,63,00,00,00,0B,70,31,32,5F,31,30,
< 2008-07-22 17:00:07.640 2E,73,72,65,63,00,00,00,05,00,00,00,00,00,02,EF,BB,00,00,83,82,00,00,00,11,
< 2008-07-22 17:00:07.640 47,61,74,65,77,61,79,43,6F,6E,66,69,67,2E,74,78,74,00,00,00,11,47,61,74,65,
< 2008-07-22 17:00:07.640 77,61,79,43,6F,6E,66,69,67,2E,74,78,74,00,00,00,05,00,00,00,00,00,00,0A,BD,
< 2008-07-22 17:00:07.640 00,00,83,82,00,00,00,0C,4D,47,5F,30,32,2E,34,35,2E,68,65,78,00,00,00,0C,4D,
< 2008-07-22 17:00:07.640 47,5F,30,32,2E,34,35,2E,68,65,78,00,00,00,05,00,00,00,00,02,10,54,60,00,00,
< 2008-07-22 17:00:07.640 83,82,00,00,00,0C,70,31,30,5F,31,30,32,2E,73,72,65,63,00,00,00,0C,70,31,30,
< 2008-07-22 17:00:07.640 5F,31,30,32,2E,73,72,65,63,00,00,00,05,00,00,00,00,00,02,EF,BB,00,00,83,82,
< 2008-07-22 17:00:07.640 00,00,00,08,77,67,65,74,2E,65,78,65,00,00,00,08,77,67,65,74,2E,65,78,65,00,
< 2008-07-22 17:00:07.640 00,00,05,00,00,00,00,00,02,7C,00,00,00,83,82,00,00,00,0E,58,74,65,72,6D,4C,
< 2008-07-22 17:00:07.640 6F,67,2E,30,33,35,37,32,00,00,00,0E,58,74,65,72,6D,4C,6F,67,2E,30,33,35,37,
< 2008-07-22 17:00:07.640 32,00,00,00,05,00,00,00,00,00,12,14,68,00,00,83,82,00,00,00,08,31,2E,35,34,
< 2008-07-22 17:00:07.640 2E,72,61,72,00,00,00,08,31,2E,35,34,2E,72,61,72,00,00,00,05,00,00,00,00,00,
< 2008-07-22 17:00:07.640 74,A1,14,00,00,83,82,00,00,00,14,45,78,65,63,43,6F,6E,66,69,67,4F,75,74,70,
< 2008-07-22 17:00:07.640 75,74,2E,74,78,74,00,00,00,14,45,78,65,63,43,6F,6E,66,69,67,4F,75,74,70,75,
< 2008-07-22 17:00:07.640 74,2E,74,78,74,00,00,00,05,00,00,00,00,00,00,00,00,00,00,83,82,00,00,00,0F,
< 2008-07-22 17:00:07.640 31,32,75,70,54,6F,47,41,31,30,2E,73,72,65,63,00,00,00,0F,31,32,75,70,54,6F,
< 2008-07-22 17:00:07.640 47,41,31,30,2E,73,72,65,63,00,00,00,05,00,00,00,00,00,02,EF,BB,00,00,83,82,
< 2008-07-22 17:00:07.640 00,00,00,10,63,75,62,61,5F,63,6F,75,6E,74,72,79,2E,6D,66,63,00,00,00,10,63,
< 2008-07-22 17:00:07.640 75,62,61,5F,63,6F,75,6E,74,72,79,2E,6D,66,63,00,00,00,05,00,00,00,00,00,00,
< 2008-07-22 17:00:07.640 01,D9,00,00,83,82,00,00,00,16,30,31,30,30,30,30,30,30,5F,30,31,30,30,30,32,
< 2008-07-22 17:00:07.640 30,30,2E,73,72,65,63,00,00,00,16,30,31,30,30,30,30,30,30,5F,30,31,30,30,30,
< 2008-07-22 17:00:07.640 32,30,30,2E,73,72,65,63,00,00,00,05,00,00,00,00,00,02,EF,BB,00,00,83,82,00,
< 2008-07-22 17:00:07.640 00,00,16,30,31,30,30,30,32,30,30,5F,30,31,30,30,30,30,30,30,2E,73,72,65,63,
< 2008-07-22 17:00:07.640 00,00,00,16,30,31,30,30,30,32,30,30,5F,30,31,30,30,30,30,30,30,2E,73,72,65,
< 2008-07-22 17:00:07.640 63,00,00,00,05,00,00,00,00,00,02,EF,BB,00,00,83,82,00,00,00,06,76,31,2E,74,
< 2008-07-22 17:00:07.640 78,74,00,00,00,06,76,31,2E,74,78,74,00,00,00,05,00,00,00,00,00,00,00,4F,00,
< 2008-07-22 17:00:07.640 00,83,82,00,00,00,06,76,32,2E,74,78,74,00,00,00,06,76,32,2E,74,78,74,00,00,
< 2008-07-22 17:00:07.640 00,05,00,00,00,00,00,00,00,50,00,00,83,82,00,00,00,05,76,2E,74,78,74,00,00,
< 2008-07-22 17:00:07.640 00,05,76,2E,74,78,74,00,00,00,05,00,00,00,00,00,00,00,4D,00,00,83,82,00,00,
< 2008-07-22 17:00:07.640 00,06,76,33,2E,74,78,74,00,00,00,06,76,33,2E,74,78,74,00,00,00,05,00,00,00,
< 2008-07-22 17:00:07.640 00,00,00,00,4E,00,00,83,82,00,00,00,07,72,72,72,2E,74,78,74,00,00,00,07,72,
< 2008-07-22 17:00:07.640 72,72,2E,74,78,74,00,00,00,05,00,00,00,00,00,00,02,FF,00,00,83,82,00,00,00,
< 2008-07-22 17:00:07.640 07,31,31,31,2E,63,66,67,00,00,00,07,31,31,31,2E,63,66,67,00,00,00,05,00,00,
< 2008-07-22 17:00:07.640 00,00,00,00,03,D3,00,00,83,82,00,00,00,07,32,32,32,2E,63,66,67,00,00,00,07,
< 2008-07-22 17:00:07.640 32,32,32,2E,63,66,67,00,00,00,05,00,00,00,00,00,00,03,D3,00,00,83,82,
> 2008-07-22 17:00:07.640 Type: SSH_FXP_READDIR, Size: 13, Number: 780
> 2008-07-22 17:00:07.640 0C,00,00,03,0C,00,00,00,04,00,00,00,01,
. 2008-07-22 17:00:07.640 Sent 17 bytes
. 2008-07-22 17:00:07.640 There are 0 bytes remaining in the send buffer
. 2008-07-22 17:00:07.640 Looking for network events
. 2008-07-22 17:00:07.640 Timeout waiting for network events
. 2008-07-22 17:00:07.640 Read file 'err_fa.log' from listing
. 2008-07-22 17:00:07.640 Read file 'GatewayUmbrellaVersion.txt' from listing
. 2008-07-22 17:00:07.640 Read file 'p10_12.srec' from listing
. 2008-07-22 17:00:07.640 Read file 'p12_10.srec' from listing
. 2008-07-22 17:00:07.640 Read file 'GatewayConfig.txt' from listing
. 2008-07-22 17:00:07.640 Read file 'MG_02.45.hex' from listing
. 2008-07-22 17:00:07.640 Read file 'p10_102.srec' from listing
. 2008-07-22 17:00:07.656 Read file 'wget.exe' from listing
. 2008-07-22 17:00:07.656 Read file 'XtermLog.03572' from listing
. 2008-07-22 17:00:07.656 Read file '1.54.rar' from listing
. 2008-07-22 17:00:07.656 Read file 'ExecConfigOutput.txt' from listing
. 2008-07-22 17:00:07.656 Read file '12upToGA10.srec' from listing
. 2008-07-22 17:00:07.656 Read file 'cuba_country.mfc' from listing
. 2008-07-22 17:00:07.656 Read file '01000000_01000200.srec' from listing
. 2008-07-22 17:00:07.656 Read file '01000200_01000000.srec' from listing
. 2008-07-22 17:00:07.656 Read file 'v1.txt' from listing
. 2008-07-22 17:00:07.656 Read file 'v2.txt' from listing
. 2008-07-22 17:00:07.656 Read file 'v.txt' from listing
. 2008-07-22 17:00:07.656 Read file 'v3.txt' from listing
. 2008-07-22 17:00:07.656 Read file 'rrr.txt' from listing
. 2008-07-22 17:00:07.656 Read file '111.cfg' from listing
. 2008-07-22 17:00:07.656 Read file '222.cfg' from listing
. 2008-07-22 17:00:07.656 Waiting for another 4 bytes
. 2008-07-22 17:00:07.656 Looking for incoming data
. 2008-07-22 17:00:07.656 Looking for network events
. 2008-07-22 17:00:07.656 Detected network event
. 2008-07-22 17:00:07.656 Enumerating network events for socket 672
. 2008-07-22 17:00:07.656 Enumerated 1 network events making 1 cumulative events for socket 672
. 2008-07-22 17:00:07.656 Handling network read event on socket 672 with error 0
. 2008-07-22 17:00:07.656 Received 32 bytes (0)
. 2008-07-22 17:00:07.656 Read 4 bytes (28 pending)
. 2008-07-22 17:00:07.656 Read 28 bytes (0 pending)
< 2008-07-22 17:00:07.656 Type: SSH_FXP_STATUS, Size: 28, Number: 524
< 2008-07-22 17:00:07.656 65,00,00,02,0C,00,00,00,01,00,00,00,0B,45,6E,64,20,6F,66,20,66,69,6C,65,00,
< 2008-07-22 17:00:07.656 00,00,00,
. 2008-07-22 17:00:07.656 Attempt to close connection due to fatal exception:
* 2008-07-22 17:00:07.656 SFTP protocol violation: Invalid message number 524 (expected 780).
. 2008-07-22 17:00:07.656 Closing connection.
. 2008-07-22 17:00:07.656 Sending special code: 12
. 2008-07-22 17:00:07.656 Sent EOF message
. 2008-07-22 17:00:07.656 Selecting events 0 for socket 672
* 2008-07-22 17:00:07.656 (ESshFatal) SFTP protocol violation: Invalid message number 524 (expected 780).
* 2008-07-22 17:00:07.656 Error listing directory '/'.



2) a successfull to the same server using mentioned ver of Filzilla (sorry,but I dont know how to make same deep level using message numbers as WinScp has):

Status: Disconnected from server
Trace: CSftpControlSocket::ResetOperation(66)
Trace: CControlSocket::ResetOperation(66)
Status: Connecting to 172.99.99.51:22...
Trace: Going to execute C:\prg\FileZilla-3.0.11\fzsftp.exe
Response: fzSftp started
Trace: CSftpControlSocket::ConnectParseResponse(fzSftp started)
Trace: CSftpControlSocket::SendNextCommand()
Trace: CSftpControlSocket::ConnectSend()
Command: open "root@172.99.99.51" 22
Trace: Looking up host "172.99.99.51"
Trace: Connecting to 172.99.99.51 port 22
Trace: Server version: SSH-2.0-1.0
Trace: Using SSH protocol version 2
Trace: We claim version: SSH-2.0-PuTTY_Local:_Jun_15_2008_14:47:43
Trace: Using Diffie-Hellman with standard group "group1"
Trace: Doing Diffie-Hellman key exchange with hash SHA-1
Trace: Host key fingerprint is:
Trace: ssh-dss 1024 09:43:4a:45:8f:60:84:aa:e9:6f:ff:db:f0:49:5a:3b
Trace: Initialised triple-DES CBC client->server encryption
Trace: Initialised HMAC-SHA1 client->server MAC algorithm
Trace: Initialised triple-DES CBC server->client encryption
Trace: Initialised HMAC-SHA1 server->client MAC algorithm
Command: Pass: ********
Trace: Sent password
Trace: Access granted
Trace: Opened channel for session
Trace: Started a shell/command
Status: Connected to 172.99.99.51
Trace: CSftpControlSocket::ConnectParseResponse()
Trace: CSftpControlSocket::ResetOperation(0)
Trace: CControlSocket::ResetOperation(0)
Status: Retrieving directory listing...
Trace: CSftpControlSocket::SendNextCommand()
Trace: CSftpControlSocket::ChangeDirSend()
Command: pwd
Response: Current directory is: "/"
Trace: CSftpControlSocket::ResetOperation(0)
Trace: CControlSocket::ResetOperation(0)
Trace: CSftpControlSocket::ParseSubcommandResult(0)
Trace: CSftpControlSocket::ListSubcommandResult()
Trace: state = 1
Trace: CSftpControlSocket::ResetOperation(0)
Trace: CControlSocket::ResetOperation(0)
Status: Directory listing successful


Any help greatly appreciated.

Reply with quote

Advertisement

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

Re: Protocol violation at login phase

Obviously this is server-side error. I cannot tell why it works with other clients. Filezilla uses internally PSFTP. Maybe you can use PSFTP for testing as it allows more verbose logging.

Reply with quote

alexs
Joined:
Posts:
2

Hi, prikryl
actually you were right, this is a server-side issue (a bit tricky!)
I understand also why other clients worked (because they are more "tolerant" and for example Filezilla uses same MessageNumber (probably CuteFtp as well), but WinScp uses different for every sent packet.

Thanks again and good luck

P.S. I've found PSFTP app, but it is German only, so I was not able to turn on the verbose mode.... :)

Reply with quote

Advertisement

You can post new topics in this forum