SCP fallback broken (3.7.5 beta)? :: Support Forum

Kreker Guest

SCP fallback broken (3.7.5 beta)?

2005-05-09 07:22

As a default I use SFTP (allow SCP fallback) in my settings. The server supports only SCP. This works flawlessly in version 3.7.4.
In version 3.7.5 beta it does not fall back to scp but tries sftp instead and fails. The logs seem to be identical up to "Started session" (only difference old: ssh1, new: ssh-1).

Log 3.7.5 beta:

. 2005-05-09 07:59:26.451 --------------------------------------------------------------------------
. 2005-05-09 07:59:26.451 WinSCP Version 3.7.5 (Build 294) (OS 5.1.2600 Service Pack 1)
. 2005-05-09 07:59:26.451 Login time: Montag, 9. Mai 2005 07:59:26
. 2005-05-09 07:59:26.451 --------------------------------------------------------------------------
. 2005-05-09 07:59:26.451 Session name: session 1
. 2005-05-09 07:59:26.451 Host name: xxx (Port: 22)
. 2005-05-09 07:59:26.461 User name: xxx (Password: No, Key file: No)
. 2005-05-09 07:59:26.461 Transfer Protocol: SFTP (SCP)
. 2005-05-09 07:59:26.461 SSH protocol version: 1 only; Compression: No
. 2005-05-09 07:59:26.461 Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2005-05-09 07:59:26.461 Ciphers: aes,blowfish,3des,WARN,des; Ssh2DES: No
. 2005-05-09 07:59:26.461 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2005-05-09 07:59:26.461 SSH Bugs: -,-,-,-,-,-,-,-
. 2005-05-09 07:59:26.461 SFTP Bugs: -,-,-
. 2005-05-09 07:59:26.461 Proxy: none
. 2005-05-09 07:59:26.461 Return code variable: Autodetect; Lookup user groups: Yes
. 2005-05-09 07:59:26.461 Shell: default, EOL: 0
. 2005-05-09 07:59:26.461 Local directory: C:\, Remote directory: /application/x, Update: No, Cache: Yes
. 2005-05-09 07:59:26.461 Cache directory changes: Yes, Permanent: Yes
. 2005-05-09 07:59:26.461 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2005-05-09 07:59:26.461 Alias LS: No, Ign LS warn: Yes, Scp1 Comp: No
. 2005-05-09 07:59:26.461 --------------------------------------------------------------------------
. 2005-05-09 07:59:26.461 Looking up host "xxx"
. 2005-05-09 07:59:26.471 Connecting to xxx port 22
. 2005-05-09 07:59:26.471 Waiting for the server to continue with the initialisation
. 2005-05-09 07:59:26.471 Looking for incoming data
. 2005-05-09 07:59:26.481 Select result is 1
. 2005-05-09 07:59:26.481 Server version: SSH-1.99-Sun_SSH_1.0.1
. 2005-05-09 07:59:26.481 We believe remote version has SSH-2 rekey bug
. 2005-05-09 07:59:26.481 We claim version: SSH-1.5-WinSCP_release_3.7.5
. 2005-05-09 07:59:26.481 Using SSH protocol version 1
. 2005-05-09 07:59:26.481 Waiting for the server to continue with the initialisation
. 2005-05-09 07:59:26.481 Looking for incoming data
. 2005-05-09 07:59:26.481 Select result is 1
. 2005-05-09 07:59:26.481 Received public keys
. 2005-05-09 07:59:26.481 Host key fingerprint is:
. 2005-05-09 07:59:26.481       1024 xxx
. 2005-05-09 07:59:26.481 Encrypted session key
. 2005-05-09 07:59:26.481 AES not supported in SSH-1, skipping
. 2005-05-09 07:59:26.481 Using Blowfish encryption
. 2005-05-09 07:59:26.481 Trying to enable encryption...
. 2005-05-09 07:59:26.481 Initialised Blowfish encryption
. 2005-05-09 07:59:26.481 Installing CRC compensation attack detector
. 2005-05-09 07:59:26.481 Waiting for the server to continue with the initialisation
. 2005-05-09 07:59:26.481 Looking for incoming data
. 2005-05-09 07:59:26.541 Select result is 1
. 2005-05-09 07:59:26.541 Successfully started encryption
. 2005-05-09 07:59:26.541 Sent username "xxx"
. 2005-05-09 07:59:26.541 Waiting for the server to continue with the initialisation
. 2005-05-09 07:59:26.541 Looking for incoming data
. 2005-05-09 07:59:26.551 Select result is 1
. 2005-05-09 07:59:26.551 Pageant is running. Requesting keys.
. 2005-05-09 07:59:26.551 Pageant has 0 SSH-1 keys
. 2005-05-09 07:59:26.551 Session password prompt (xxx@xxx's password: )
. 2005-05-09 07:59:26.551 Asking user for password.
. 2005-05-09 07:59:29.566 Sending password with camouflage packets
. 2005-05-09 07:59:29.566 Sent password
. 2005-05-09 07:59:29.566 Waiting for the server to continue with the initialisation
. 2005-05-09 07:59:29.566 Looking for incoming data
. 2005-05-09 07:59:29.576 Select result is 1
. 2005-05-09 07:59:29.576 Authentication successful
. 2005-05-09 07:59:29.576 Started session
. 2005-05-09 07:59:29.576 --------------------------------------------------------------------------
. 2005-05-09 07:59:29.576 Using SFTP protocol.
. 2005-05-09 07:59:29.576 Doing startup conversation with host.
> 2005-05-09 07:59:29.576 Type: SSH_FXP_INIT, Size: 5, Number: -1
> 2005-05-09 07:59:29.576 01,00,00,00,05,
. 2005-05-09 07:59:29.576 There are 0 bytes remaining in the send buffer
. 2005-05-09 07:59:29.576 Waiting for another 4 bytes
. 2005-05-09 07:59:29.576 Looking for incoming data
. 2005-05-09 07:59:29.596 Select result is 1
! 2005-05-09 07:59:29.596 usage: sftp [-1vC] [-osshopt = value] [user@]host
. 2005-05-09 07:59:29.596 Waiting for another 4 bytes
. 2005-05-09 07:59:29.596 Looking for incoming data
. 2005-05-09 07:59:29.606 Select result is 1
. 2005-05-09 07:59:29.606 Server sent command exit status 1
. 2005-05-09 07:59:29.606 Server closed network connection
. 2005-05-09 07:59:29.606 Waiting for another 4 bytes
. 2005-05-09 07:59:29.606 Looking for incoming data
* 2005-05-09 07:59:29.606 (ESshFatal) Cannot initialize SFTP protocol. Is the host running a SFTP server?
* 2005-05-09 07:59:29.606 Connection has been unexpectedly closed. Server sent command exit status 1.

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 41,517
Location:: Prague, Czechia

Re: SCP fallback broken (3.7.5 beta)?

2005-05-09

Please post the same log file for 3.7.4 anyway. Thanks.

Reply with quote

Guest

2005-05-09 08:07

There it is:

. 2005-05-09 08:04:53.267 --------------------------------------------------------------------------
. 2005-05-09 08:04:53.267 WinSCP Version 3.7.4 (Build 271) (OS 5.1.2600 Service Pack 1)
. 2005-05-09 08:04:53.267 Login time: Montag, 9. Mai 2005 08:04:53
. 2005-05-09 08:04:53.267 --------------------------------------------------------------------------
. 2005-05-09 08:04:53.267 Session name: xxx
. 2005-05-09 08:04:53.267 Host name: xxx (Port: 22)
. 2005-05-09 08:04:53.267 User name: xxx (Password: No, Key file: No)
. 2005-05-09 08:04:53.267 Transfer Protocol: SFTP (SCP)
. 2005-05-09 08:04:53.267 SSH protocol version: 1 only; Compression: No
. 2005-05-09 08:04:53.267 Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2005-05-09 08:04:53.267 Ciphers: aes,blowfish,3des,WARN,des; Ssh2DES: No
. 2005-05-09 08:04:53.267 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2005-05-09 08:04:53.267 SSH Bugs: -,-,-,-,-,-,-,-
. 2005-05-09 08:04:53.267 SFTP Bugs: -,-,-
. 2005-05-09 08:04:53.267 Proxy: none
. 2005-05-09 08:04:53.267 Return code variable: Autodetect; Lookup user groups: Yes
. 2005-05-09 08:04:53.267 Shell: default, EOL: 0
. 2005-05-09 08:04:53.267 Local directory: C:\, Remote directory: /application/x, Update: No, Cache: Yes
. 2005-05-09 08:04:53.267 Cache directory changes: Yes, Permanent: Yes
. 2005-05-09 08:04:53.267 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2005-05-09 08:04:53.267 Alias LS: No, Ign LS warn: Yes, Scp1 Comp: No
. 2005-05-09 08:04:53.267 --------------------------------------------------------------------------
. 2005-05-09 08:04:53.267 Looking up host "xxx"
. 2005-05-09 08:04:53.277 Connecting to xxx port 22
. 2005-05-09 08:04:53.277 Waiting for the server to continue with the initialisation
. 2005-05-09 08:04:53.277 Looking for incoming data
. 2005-05-09 08:04:53.287 Select result is 1
. 2005-05-09 08:04:53.287 Server version: SSH-1.99-Sun_SSH_1.0.1
. 2005-05-09 08:04:53.287 We believe remote version has SSH2 rekey bug
. 2005-05-09 08:04:53.287 We claim version: SSH-1.5-WinSCP_release_3.7.4
. 2005-05-09 08:04:53.287 Using SSH protocol version 1
. 2005-05-09 08:04:53.287 Waiting for the server to continue with the initialisation
. 2005-05-09 08:04:53.287 Looking for incoming data
. 2005-05-09 08:04:53.287 Select result is 1
. 2005-05-09 08:04:53.287 Received public keys
. 2005-05-09 08:04:53.287 Host key fingerprint is:
. 2005-05-09 08:04:53.287       1024 xxx
. 2005-05-09 08:04:53.287 Encrypted session key
. 2005-05-09 08:04:53.287 AES not supported in SSH1, skipping
. 2005-05-09 08:04:53.297 Using Blowfish encryption
. 2005-05-09 08:04:53.297 Trying to enable encryption...
. 2005-05-09 08:04:53.297 Initialised Blowfish encryption
. 2005-05-09 08:04:53.297 Installing CRC compensation attack detector
. 2005-05-09 08:04:53.297 Waiting for the server to continue with the initialisation
. 2005-05-09 08:04:53.297 Looking for incoming data
. 2005-05-09 08:04:53.337 Select result is 1
. 2005-05-09 08:04:53.337 Successfully started encryption
. 2005-05-09 08:04:53.337 Sent username "xxx"
. 2005-05-09 08:04:53.337 Waiting for the server to continue with the initialisation
. 2005-05-09 08:04:53.337 Looking for incoming data
. 2005-05-09 08:04:53.357 Select result is 1
. 2005-05-09 08:04:53.357 Pageant is running. Requesting keys.
. 2005-05-09 08:04:53.357 Pageant has 0 SSH1 keys
. 2005-05-09 08:04:53.357 Session password prompt (xxx@xxx's password: )
. 2005-05-09 08:04:53.357 Asking user for password.
. 2005-05-09 08:04:56.793 Sending password with camouflage packets
. 2005-05-09 08:04:56.793 Sent password
. 2005-05-09 08:04:56.793 Waiting for the server to continue with the initialisation
. 2005-05-09 08:04:56.793 Looking for incoming data
. 2005-05-09 08:04:56.813 Select result is 1
. 2005-05-09 08:04:56.813 Authentication successful
. 2005-05-09 08:04:56.813 Started session
. 2005-05-09 08:04:56.813 --------------------------------------------------------------------------
. 2005-05-09 08:04:56.813 Using SCP protocol.
. 2005-05-09 08:04:56.813 Doing startup conversation with host.
. 2005-05-09 08:04:56.813 Skipping host startup message (if any).
. 2005-05-09 08:04:56.813 There are 0 bytes remaining in the send buffer
. 2005-05-09 08:04:56.813 There are 0 bytes remaining in the send buffer
> 2005-05-09 08:04:56.813 echo "WinSCP: this is end-of-file:0"
. 2005-05-09 08:04:56.813 Waiting for another 1 bytes
. 2005-05-09 08:04:56.813 Looking for incoming data
. 2005-05-09 08:04:56.863 Select result is 1
< 2005-05-09 08:04:56.863 Sun Microsystems Inc.   SunOS 5.9   Generic   May 2002

Reply with quote

martin◆ Site Admin

2005-05-09

I can confirm that "SCP fallback" is broken in 3.7.5 :-( Workaround is to force SCP, if your server does not support SFTP.

Reply with quote

Guest

2005-05-15 14:20

Here you go......thanks for you help

--------------------------------------------------------------------------
WinSCP Version 3.7.5 (Build 294) (OS 5.1.2600 Service Pack 2)
Login time: Friday, May 13, 2005 3:25:54 PM
--------------------------------------------------------------------------
Session name: xxxxx@xxxxxx
Host name: 24.27.xxx.xxx (Port: 22)
User name: admin (Password: Yes, Key file: No)
Transfer Protocol: SCP
SSH protocol version: 2; Compression: No
Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
Ciphers: aes,blowfish,3des,WARN,des; Ssh2DES: No
Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
SSH Bugs: -,-,-,-,-,-,-,-
SFTP Bugs: -,-,-
Proxy: none
Return code variable: Autodetect; Lookup user groups: Yes
Shell: default, EOL: 0
Local directory: default, Remote directory: home, Update: No, Cache: Yes
Cache directory changes: Yes, Permanent: Yes
Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
Alias LS: No, Ign LS warn: Yes, Scp1 Comp: No
--------------------------------------------------------------------------
Looking up host "24.27.xxx.xxx"
Connecting to 24.27.xxx.xxx port 22
Server version: SSH-2.0-OpenSSH_3.7.1p1
We claim version: SSH-2.0-WinSCP_release_3.7.5
Using SSH protocol version 2
Doing Diffie-Hellman group exchange
Doing Diffie-Hellman key exchange
Host key fingerprint is:
ssh-rsa 1024 16:2d:33:00:ca:18:49:19:b3:97:24:xx:xx:xx:xx:xx
Initialised AES-256 client->server encryption
Initialised HMAC-SHA1 client->server MAC algorithm
Initialised AES-256 server->client encryption
Initialised HMAC-SHA1 server->client MAC algorithm
Using username "admin".
Session password prompt (admin@24.27.xxx.xxx's password: )
Using stored password.
Sent password
Access granted
Opened channel for session
Started a shell/command
--------------------------------------------------------------------------
Using SCP protocol.
Doing startup conversation with host.
Skipping host startup message (if any).
echo "WinSCP: this is end-of-file:0"
Can't redirect stdin: No such device or address
Server sent command exit status 6
All channels closed. Disconnecting
Server closed network connection
(ESshFatal) Error skipping startup message. Your shell is probably incompatible with the application (BASH is recommended).
Connection has been unexpectedly closed. Server sent command exit status 6.

Reply with quote