Topic "Binary transfers using SCP in version 3.7.3"

Author Message
mdoran
[View user's profile]

Joined: 2005-01-06
Posts: 8
When trying to transfer any file in binary and connected with scp you recieve the "Host has not answered for more than 15 seconds" box. Then you click abort and recieve "copy files to remote side failed" "copying file *filename* fatally failed. Terminated by user. When connected with sftp it seems to work just fine. I went back and tried version 3.4.2 and that version works no problem. In 3.7.3 I tried transfering files as little as 100kb and as large as 10meg, both small and large files both fail with the errors above. Hope this helps, please let me know if you need any more information.
Advertisements
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
Does it happen when you tranfer the files on foreground or in background queue? What is your connection speed?
_________________
Martin Prikryl
knight

Guest


I'm facing the same issuse. scp stalls and the only thing to do is cancel the session
reverted back to 3.7.1 and no problems anymore
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
knight wrote:
I'm facing the same issuse. scp stalls and the only thing to do is cancel the session
reverted back to 3.7.1 and no problems anymore

OK, that's what I know already, but I'm interested in the answers to my questions.
_________________
Martin Prikryl
knight

Guest


prikryl wrote:
knight wrote:
I'm facing the same issuse. scp stalls and the only thing to do is cancel the session
reverted back to 3.7.1 and no problems anymore

OK, that's what I know already, but I'm interested in the answers to my questions.

Sorry,

doing transfer in foreground on a 10 Mbps LAN
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
Please can anyone of you download debug version of WinSCP (WinSCP3log.exe), enable low-level protocol logging, try to reproduce the problem and send me the log file? Then please try it again with WinSCP3fix.exe and let me know if it changes anything.
mdoran
[View user's profile]

Joined: 2005-01-06
Posts: 8
prikryl wrote:
Does it happen when you tranfer the files on foreground or in background queue? What is your connection speed?


It happens with the transfer of the files in the foreground and my connection speed is 100Mbps.

Last edited by mdoran on 2005-02-08 15:25; edited 1 time in total
mdoran
[View user's profile]

Joined: 2005-01-06
Posts: 8
prikryl wrote:
Please can anyone of you download debug version of WinSCP (WinSCP3log.exe), enable low-level protocol logging, try to reproduce the problem and send me the log file? Then please try it again with WinSCP3fix.exe and let me know if it changes anything.


It appears the WinSCP3fix.exe does not correct the problem. Here is the log you would like to see.

. 2005-02-08 09:16:15.935 --------------------------------------------------------------------------
. 2005-02-08 09:16:15.935 WinSCP Version 3.7.3 (Build 266) (OS 5.1.2600 Service Pack 2)
. 2005-02-08 09:16:15.935 Login time: Tuesday, February 08, 2005 9:16:15 AM
. 2005-02-08 09:16:15.935 --------------------------------------------------------------------------
. 2005-02-08 09:16:15.935 Session name: sessionname
. 2005-02-08 09:16:15.935 Host name: servername (Port: 22)
. 2005-02-08 09:16:15.935 User name: username (Password: Yes, Key file: No)
. 2005-02-08 09:16:15.935 Transfer Protocol: SCP
. 2005-02-08 09:16:15.935 SSH protocol version: 2; Compression: No
. 2005-02-08 09:16:15.935 Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2005-02-08 09:16:15.935 Ciphers: aes,blowfish,3des,WARN,des; Ssh2DES: No
. 2005-02-08 09:16:15.935 Ping type: -, Ping interval: 60 sec; Timeout: 15 sec
. 2005-02-08 09:16:15.935 SSH Bugs: -,-,-,-,-,-,-,-
. 2005-02-08 09:16:15.935 SFTP Bugs: -,-
. 2005-02-08 09:16:15.935 Proxy: none
. 2005-02-08 09:16:15.935 Return code variable: Autodetect; Lookup user groups: Yes
. 2005-02-08 09:16:15.935 Shell: default, EOL: 0
. 2005-02-08 09:16:15.935 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2005-02-08 09:16:15.935 Cache directory changes: Yes, Permanent: Yes
. 2005-02-08 09:16:15.935 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2005-02-08 09:16:15.935 Alias LS: No, Ign LS warn: Yes, Scp1 Comp: No
. 2005-02-08 09:16:15.935 --------------------------------------------------------------------------
. 2005-02-08 09:16:15.935 Looking up host "servername"
. 2005-02-08 09:16:15.945 Connecting to 10.48.2.104 port 22
. 2005-02-08 09:16:15.976 Waiting for the server to continue with the initialisation
. 2005-02-08 09:16:15.976 Server version: SSH-2.0-Sun_SSH_1.0.1
. 2005-02-08 09:16:15.976 We believe remote version has SSH2 rekey bug
. 2005-02-08 09:16:15.976 We claim version: SSH-2.0-WinSCP_release_3.7.3
. 2005-02-08 09:16:15.976 Using SSH protocol version 2
. 2005-02-08 09:16:15.976 Waiting for the server to continue with the initialisation
. 2005-02-08 09:16:15.976 Using Diffie-Hellman with standard group "group1"
. 2005-02-08 09:16:15.976 Doing Diffie-Hellman key exchange
. 2005-02-08 09:16:16.066 Waiting for the server to continue with the initialisation
. 2005-02-08 09:16:16.186 Host key fingerprint is:
. 2005-02-08 09:16:16.186 ssh-rsa 1024 8d:94:db:50:15:06:52:93:3f:c0:a0:f8:5f:f7:9b:8e
. 2005-02-08 09:16:16.186 Initialised AES-128 client->server encryption

. 2005-02-08 09:16:16.186 Initialised HMAC-SHA1 client->server MAC algorithm
. 2005-02-08 09:16:16.186 Waiting for the server to continue with the initialisation
. 2005-02-08 09:16:16.186 Initialised AES-128 server->client encryption
. 2005-02-08 09:16:16.186 Initialised HMAC-SHA1 server->client MAC algorithm
. 2005-02-08 09:16:16.186 Waiting for the server to continue with the initialisation
! 2005-02-08 09:16:16.186 Using username "username".
. 2005-02-08 09:16:16.186 Waiting for the server to continue with the initialisation
. 2005-02-08 09:16:16.196 Session password prompt (username@servername's password: )
. 2005-02-08 09:16:16.196 Using stored password.
. 2005-02-08 09:16:16.196 Sent password
. 2005-02-08 09:16:16.196 Waiting for the server to continue with the initialisation
. 2005-02-08 09:16:16.206 Access granted
. 2005-02-08 09:16:16.206 Waiting for the server to continue with the initialisation
. 2005-02-08 09:16:16.206 Opened channel for session
. 2005-02-08 09:16:16.206 Waiting for the server to continue with the initialisation
. 2005-02-08 09:16:16.236 Started a shell/command
. 2005-02-08 09:16:16.236 --------------------------------------------------------------------------
. 2005-02-08 09:16:16.236 Using SCP protocol.
. 2005-02-08 09:16:16.236 Doing startup conversation with host.
. 2005-02-08 09:16:16.236 Skipping host startup message (if any).
. 2005-02-08 09:16:16.236 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:16.236 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:16.236 echo "WinSCP: this is end-of-file:0"
. 2005-02-08 09:16:16.236 Waiting for another 1 bytes
< 2005-02-08 09:16:16.326 WinSCP: this is end-of-file:0
. 2005-02-08 09:16:16.326 Detecting variable containing return code of last command.
. 2005-02-08 09:16:16.326 Trying "$status".
. 2005-02-08 09:16:16.326 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:16.326 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:16.326 echo "$status" ; echo "WinSCP: this is end-of-file:0"
. 2005-02-08 09:16:16.326 Waiting for another 1 bytes
< 2005-02-08 09:16:16.427 WinSCP: this is end-of-file:0
. 2005-02-08 09:16:16.427 Trying "$?".
. 2005-02-08 09:16:16.427 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:16.427 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:16.427 echo "$?" ; echo "WinSCP: this is end-of-file:0"
. 2005-02-08 09:16:16.427 Waiting for another 1 bytes
< 2005-02-08 09:16:16.527 0
< 2005-02-08 09:16:16.527 WinSCP: this is end-of-file:0
. 2005-02-08 09:16:16.527 Return code variable "$?" selected.
. 2005-02-08 09:16:16.527 Clearing all aliases.
. 2005-02-08 09:16:16.527 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:16.527 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:16.527 unalias "echo" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:16.527 Waiting for another 1 bytes
! 2005-02-08 09:16:16.627 -bash: unalias: `echo': not an alias
< 2005-02-08 09:16:16.627 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:16.627 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:16.627 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:16.627 unalias "pwd" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:16.627 Waiting for another 1 bytes
! 2005-02-08 09:16:16.727 -bash: unalias: `pwd': not an alias
< 2005-02-08 09:16:16.727 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:16.727 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:16.727 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:16.727 unalias "cd" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:16.727 Waiting for another 1 bytes
! 2005-02-08 09:16:16.828 -bash: unalias: `cd': not an alias
< 2005-02-08 09:16:16.828 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:16.828 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:16.828 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:16.828 unalias "ls" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:16.828 Waiting for another 1 bytes
! 2005-02-08 09:16:16.928 -bash: unalias: `ls': not an alias
< 2005-02-08 09:16:16.928 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:16.928 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:16.928 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:16.928 unalias "groups" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:16.928 Waiting for another 1 bytes
! 2005-02-08 09:16:17.028 -bash: unalias: `groups': not an alias
< 2005-02-08 09:16:17.028 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.028 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.028 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.028 unalias "scp" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.028 Waiting for another 1 bytes
! 2005-02-08 09:16:17.198 -bash: unalias: `scp': not an alias
< 2005-02-08 09:16:17.198 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.198 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.198 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.198 unalias "rm" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.198 Waiting for another 1 bytes
! 2005-02-08 09:16:17.289 -bash: unalias: `rm': not an alias
< 2005-02-08 09:16:17.289 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.289 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.289 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.289 unalias "mv" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.289 Waiting for another 1 bytes
! 2005-02-08 09:16:17.389 -bash: unalias: `mv': not an alias
< 2005-02-08 09:16:17.389 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.389 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.389 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.389 unalias "mkdir" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.389 Waiting for another 1 bytes
! 2005-02-08 09:16:17.489 -bash: unalias: `mkdir': not an alias
< 2005-02-08 09:16:17.489 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.489 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.489 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.489 unalias "chmod" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.489 Waiting for another 1 bytes
! 2005-02-08 09:16:17.589 -bash: unalias: `chmod': not an alias
< 2005-02-08 09:16:17.589 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.589 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.589 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.589 unalias "chgrp" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.589 Waiting for another 1 bytes
! 2005-02-08 09:16:17.690 -bash: unalias: `chgrp': not an alias
< 2005-02-08 09:16:17.690 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.690 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.690 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.690 unalias "chown" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.690 Waiting for another 1 bytes
! 2005-02-08 09:16:17.790 -bash: unalias: `chown': not an alias
< 2005-02-08 09:16:17.790 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.790 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.790 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.790 unalias "unset" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.790 Waiting for another 1 bytes
! 2005-02-08 09:16:17.890 -bash: unalias: `unset': not an alias
< 2005-02-08 09:16:17.890 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.890 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.890 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.890 unalias "unalias" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.890 Waiting for another 1 bytes
! 2005-02-08 09:16:17.990 -bash: unalias: `unalias': not an alias
< 2005-02-08 09:16:17.990 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:17.990 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:17.990 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:17.990 unalias "alias" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:17.990 Waiting for another 1 bytes
! 2005-02-08 09:16:18.091 -bash: unalias: `alias': not an alias
< 2005-02-08 09:16:18.091 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.091 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.091 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.091 unalias "ln" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.091 Waiting for another 1 bytes
! 2005-02-08 09:16:18.191 -bash: unalias: `ln': not an alias
< 2005-02-08 09:16:18.191 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.191 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.191 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.191 unalias "cp" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.191 Waiting for another 1 bytes
! 2005-02-08 09:16:18.291 -bash: unalias: `cp': not an alias
< 2005-02-08 09:16:18.291 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.291 Clearing national user variables.
. 2005-02-08 09:16:18.291 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.291 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.291 unset "LANG" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.291 Waiting for another 1 bytes
< 2005-02-08 09:16:18.391 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.391 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.391 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.391 unset "LANGUAGE" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.391 Waiting for another 1 bytes
< 2005-02-08 09:16:18.492 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.492 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.492 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.492 unset "LC_CTYPE" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.492 Waiting for another 1 bytes
< 2005-02-08 09:16:18.592 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.592 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.592 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.592 unset "LC_COLLATE" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.592 Waiting for another 1 bytes
< 2005-02-08 09:16:18.692 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.692 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.692 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.692 unset "LC_MONETARY" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.692 Waiting for another 1 bytes
< 2005-02-08 09:16:18.792 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.792 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.792 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.792 unset "LC_NUMERIC" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.792 Waiting for another 1 bytes
< 2005-02-08 09:16:18.893 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.893 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.893 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.893 unset "LC_TIME" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.893 Waiting for another 1 bytes
< 2005-02-08 09:16:18.993 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:18.993 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:18.993 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:18.993 unset "LC_MESSAGES" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:18.993 Waiting for another 1 bytes
< 2005-02-08 09:16:19.093 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:19.093 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:19.093 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:19.093 unset "LC_ALL" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:19.093 Waiting for another 1 bytes
< 2005-02-08 09:16:19.193 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:19.193 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:19.193 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:19.193 unset "HUMAN_BLOCKS" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:19.193 Waiting for another 1 bytes
< 2005-02-08 09:16:19.294 WinSCP: this is end-of-file:1
. 2005-02-08 09:16:19.294 Looking up groups and users.
. 2005-02-08 09:16:19.294 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:19.294 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:19.294 groups ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:19.294 Waiting for another 1 bytes
< 2005-02-08 09:16:19.404 websvc other
. 2005-02-08 09:16:19.404 Waiting for another 1 bytes
< 2005-02-08 09:16:19.574 WinSCP: this is end-of-file:0
. 2005-02-08 09:16:19.574 Following groups found:
. 2005-02-08 09:16:19.574 websvc
. 2005-02-08 09:16:19.574 other
. 2005-02-08 09:16:19.574 No users found.
. 2005-02-08 09:16:19.574 Getting current directory name.
. 2005-02-08 09:16:19.574 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:19.574 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:19.574 pwd ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:19.574 Waiting for another 1 bytes
< 2005-02-08 09:16:19.664 /home/username
. 2005-02-08 09:16:19.664 Waiting for another 1 bytes
< 2005-02-08 09:16:19.775 WinSCP: this is end-of-file:0
. 2005-02-08 09:16:19.775 Listing current directory.
. 2005-02-08 09:16:19.775 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:19.775 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:19.775 ls -la --full-time ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:19.775 Waiting for another 1 bytes
! 2005-02-08 09:16:19.865 ls: illegal option -- -
! 2005-02-08 09:16:19.865 ls: illegal option -- -
! 2005-02-08 09:16:19.865 ls: illegal option -- e
! 2005-02-08 09:16:19.865 usage: ls -1RaAdCxmnlhogrtucpFbqisfL@ [files]
. 2005-02-08 09:16:19.865 Waiting for another 1 bytes
< 2005-02-08 09:16:19.975 WinSCP: this is end-of-file:2
* 2005-02-08 09:16:19.975 (ETerminal) Command 'ls -la --full-time'
* 2005-02-08 09:16:19.975 failed with return code 2 and error message
* 2005-02-08 09:16:19.975 ls: illegal option -- -
* 2005-02-08 09:16:19.975 ls: illegal option -- -
* 2005-02-08 09:16:19.975 ls: illegal option -- e
* 2005-02-08 09:16:19.975 usage: ls -1RaAdCxmnlhogrtucpFbqisfL@ [files].
. 2005-02-08 09:16:19.975 Directory listing with --full-time failed, try again regular listing.
. 2005-02-08 09:16:19.975 Listing current directory.
. 2005-02-08 09:16:19.975 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:19.975 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:19.975 ls -la ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:19.975 Waiting for another 1 bytes
< 2005-02-08 09:16:20.096 total 1227424
< 2005-02-08 09:16:20.096 drwxr-xr-x 5 username websvc 2048 Feb 8 09:12 .
< 2005-02-08 09:16:20.096 drwxr-xr-x 56 root users 3072 Feb 1 09:02 ..
< 2005-02-08 09:16:20.096 -rwxr-x--- 1 username websvc 13010 Feb 7 13:37 .bash_history
< 2005-02-08 09:16:20.096 -rwxr-x--- 1 username websvc 24 Dec 17 15:39 .bash_logout
< 2005-02-08 09:16:20.096 -rwxr-x--- 1 username websvc 191 Dec 17 15:39 .bash_profile
< 2005-02-08 09:16:20.096 -rwxr-x--- 1 username websvc 124 Dec 17 15:39 .bashrc
< 2005-02-08 09:16:20.096 -rwxr-x--- 1 username websvc 1024 Jan 11 11:09 .rnd
< 2005-02-08 09:16:20.096 -rw------- 1 root root 689 Jan 14 11:15 .viminfo
< 2005-02-08 09:16:20.096 -rw-r--r-- 1 username websvc 24576 Feb 8 09:12 amcms.war
< 2005-02-08 09:16:20.096 -rw-r--r-- 1 username websvc 7014 Feb 3 14:15 config.reg
< 2005-02-08 09:16:20.096 -rw------- 1 root other 9835696 Jan 27 12:27 core
< 2005-02-08 09:16:20.096 -rwxr-xr-x 1 username websvc 307239320 Jan 26 16:34 server814_linux32.bin
< 2005-02-08 09:16:20.096 -rwxrwxrwx 1 username websvc 308324072 Jan 18 16:23 server814_solaris32.bin
< 2005-02-08 09:16:20.096 -rw-rw-r-- 1 username websvc 670 Jan 28 16:41 startserver.class
< 2005-02-08 09:16:20.096 -rw-r--r-- 1 username websvc 2505630 Jan 26 13:06 token-closet.war
. 2005-02-08 09:16:20.096 Waiting for another 1 bytes
< 2005-02-08 09:16:20.096 WinSCP: this is end-of-file:0
. 2005-02-08 09:16:20.096 Startup conversation with host finished.
. 2005-02-08 09:16:20.697 Session upkeep
. 2005-02-08 09:16:21.198 Session upkeep
. 2005-02-08 09:16:21.699 Session upkeep
. 2005-02-08 09:16:22.201 Session upkeep
. 2005-02-08 09:16:22.702 Session upkeep
. 2005-02-08 09:16:23.203 Session upkeep
. 2005-02-08 09:16:23.704 Session upkeep
. 2005-02-08 09:16:24.206 Session upkeep
. 2005-02-08 09:16:24.707 Session upkeep
. 2005-02-08 09:16:25.148 Copying 1 files/directories to remote directory "/home/username/"
. 2005-02-08 09:16:25.148 PrTime: No; PrRO: Yes; Rght: rw-r--r--; PrR: No; FnCs: N; RIC: Yes; Resume: S (102400); CalcS: Yes; Mask: *.*
. 2005-02-08 09:16:25.148 TM: M; ClAr: No; ExclM:
. 2005-02-08 09:16:25.148 AscM: *.*htm*; *.txt; *.php*; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2005-02-08 09:16:25.148 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:25.148 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:25.148 echo "WinSCP: this is begin-of-file" ; scp -r -d -t "/home/username" ; echo "WinSCP: this is end-of-file:$?"
. 2005-02-08 09:16:25.148 Waiting for another 1 bytes
< 2005-02-08 09:16:25.248 WinSCP: this is begin-of-file
. 2005-02-08 09:16:25.248 Waiting for another 1 bytes
. 2005-02-08 09:16:25.388 SCP remote side confirmation (0)
. 2005-02-08 09:16:25.388 Session upkeep
. 2005-02-08 09:16:25.388 Asking user:
. 2005-02-08 09:16:25.388 Remote file 'amcms.war' already exists. Overwrite?
. 2005-02-08 09:16:25.388
. 2005-02-08 09:16:25.388 New: 489927 bytes, 9/1/2004 1:03:48 AM
. 2005-02-08 09:16:25.388 Existing: 24576 bytes, 2/8/2005 10:12:00 AM ()
. 2005-02-08 09:16:25.709 Session upkeep
. 2005-02-08 09:16:26.210 Session upkeep
. 2005-02-08 09:16:26.712 Session upkeep
. 2005-02-08 09:16:27.213 Session upkeep
. 2005-02-08 09:16:27.714 Session upkeep
. 2005-02-08 09:16:28.215 Session upkeep
. 2005-02-08 09:16:28.296 File: "C:\Download\Host On-Demand v9\apps\wel\amcms.war"
. 2005-02-08 09:16:28.306 Copying "C:\Download\Host On-Demand v9\apps\wel\amcms.war" to remote directory started.
. 2005-02-08 09:16:28.306 Binary transfer mode selected.
. 2005-02-08 09:16:28.306 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:28.306 There is 0 bytes remaining in the send buffer
> 2005-02-08 09:16:28.306 C0644 489927 amcms.war
. 2005-02-08 09:16:28.306 Waiting for another 1 bytes
. 2005-02-08 09:16:28.406 SCP remote side confirmation (0)
. 2005-02-08 09:16:28.406 Sending BINARY data (first block, 4096 bytes)
. 2005-02-08 09:16:28.406 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:28.406 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.406 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:28.406 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.406 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:28.406 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.416 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:28.416 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.416 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:28.416 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.416 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:28.416 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.416 There is 0 bytes remaining in the send buffer
. 2005-02-08 09:16:28.416 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.416 There is 1978 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.426 There is 6074 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.426 There is 10170 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.426 There is 14266 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.426 There is 18362 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.426 There is 22458 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.426 There is 26554 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.426 There is 30650 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 Sending BINARY data (4096 bytes)
. 2005-02-08 09:16:28.426 There is 34746 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 There is 34746 bytes remaining in the send buffer, need to send at least another 1978 bytes
. 2005-02-08 09:16:28.426 There is 34746 bytes remaining in the send buffer
. 2005-02-08 09:16:28.426 There is 34746 bytes remaining in the send buffer, need to send at least another 1978 bytes
. 2005-02-08 09:16:43.442 Waiting for data timed out, asking user what to do.
. 2005-02-08 09:16:43.442 Asking user:
. 2005-02-08 09:16:43.442 Host has not answered for 15 seconds.
. 2005-02-08 09:16:43.442
. 2005-02-08 09:16:43.442 Wait for another 15 seconds? Pressing 'Abort' button will close session. ()
. 2005-02-08 09:16:43.523 Session upkeep
. 2005-02-08 09:16:43.753 Session upkeep
. 2005-02-08 09:16:44.254 Session upkeep
. 2005-02-08 09:16:44.475 Attempt to close connection due to fatal exception:
* 2005-02-08 09:16:44.475 Terminated by user.
. 2005-02-08 09:16:44.475 Closing connection.
Guest




to confirm the symptoms of the previous posts
3.7.3 fails everytime,
3.7.2 is fine......
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
mdoran wrote:
It appears the WinSCP3fix.exe does not correct the problem. Here is the log you would like to see.
...

Thanks for the log, I'm going to investigate it.
_________________
Martin Prikryl
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
Quote:
to confirm the symptoms of the previous posts
3.7.3 fails everytime,
3.7.2 is fine......

Are you sure that 3.7.2 has worked? So far I've believed that the problem was introduced in 3.7.2 and 3.7.1 was the last one working. If you are right, it changes things Smile Please confirm ASAP.
_________________
Martin Prikryl
mdoran
[View user's profile]

Joined: 2005-01-06
Posts: 8
prikryl wrote:
Quote:
to confirm the symptoms of the previous posts
3.7.3 fails everytime,
3.7.2 is fine......

Are you sure that 3.7.2 has worked? So far I've believed that the problem was introduced in 3.7.2 and 3.7.1 was the last one working. If you are right, it changes things Smile Please confirm ASAP.


I just tried this in 3.7.1 and 3.7.2 and the transfers work everytime. Hope this helps.
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
mdoran wrote:
I just tried this in 3.7.1 and 3.7.2 and the transfers work everytime. Hope this helps.

Thanks. Please try my another attempt to fix this. If it fails again, post a log file.
slaukas

Guest


I have tested your new Fix2 (for the scp transfer Problem with the version 3.7.3) and it's looks ok !!

Without the Fix-release, the error is only with scp not with sftp.
knight

Guest


slaukas wrote:
I have tested your new Fix2 (for the scp transfer Problem with the version 3.7.3) and it's looks ok !!

Without the Fix-release, the error is only with scp not with sftp.


Yep, the fix2 did the job...
transfer using scp OK
knight

Guest


knight wrote:
slaukas wrote:
I have tested your new Fix2 (for the scp transfer Problem with the version 3.7.3) and it's looks ok !!

Without the Fix-release, the error is only with scp not with sftp.


Yep, the fix2 did the job...
transfer using scp OK


Oops too optmistic,it stops again transferring files..
transfer stops at random places.
I tried to transfer a file of 50MB and it halts at eg. 14% 20% 51%..
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
knight wrote:
Oops too optmistic,it stops again transferring files..
transfer stops at random places.
I tried to transfer a file of 50MB and it halts at eg. 14% 20% 51%..

OK, I believe that this time it is the problem that I've thought was introduced in 3.7.2 already. Can you verify it? In all cases please (you or anyone else) post a log file (from "fix2" version). Thanks again for your cooperation. I appreciate it.
_________________
Martin Prikryl
slaukas

Guest


I have tested the fix2 under heavy load with files with different size. The First 20-40 MB run's very well, but after this the Transfer stop's with the Message: Host has not answered for 15 seconds. and the session is broken.
With sftp i have no problems with the same Files.

You can see the Log-File:
-------------------------
. 2005-02-09 14:20:19.427 --------------------------------------------------------------------------
. 2005-02-09 14:20:19.427 WinSCP Version 3.7.3 (Build 266) (OS 5.1.2600 Service Pack 2)
. 2005-02-09 14:20:19.427 Login time: Wednesday, February 09, 2005 2:20:19 PM
. 2005-02-09 14:20:19.427 --------------------------------------------------------------------------
. 2005-02-09 14:20:19.427 Session name: scpdemo@srvewokp10
. 2005-02-09 14:20:19.427 Host name: srvewokp10 (Port: 22)
. 2005-02-09 14:20:19.427 User name: scpdemo (Password: No, Key file: No)
. 2005-02-09 14:20:19.427 Transfer Protocol: SCP
. 2005-02-09 14:20:19.427 SSH protocol version: 2; Compression: Yes
. 2005-02-09 14:20:19.427 Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2005-02-09 14:20:19.427 Ciphers: aes,blowfish,3des,WARN,des; Ssh2DES: No
. 2005-02-09 14:20:19.427 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2005-02-09 14:20:19.427 SSH Bugs: -,-,-,-,-,-,-,-
. 2005-02-09 14:20:19.427 SFTP Bugs: -,-,-
. 2005-02-09 14:20:19.427 Proxy: none
. 2005-02-09 14:20:19.427 Return code variable: Autodetect; Lookup user groups: Yes
. 2005-02-09 14:20:19.427 Shell: default, EOL: 0
. 2005-02-09 14:20:19.427 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2005-02-09 14:20:19.427 Cache directory changes: Yes, Permanent: Yes
. 2005-02-09 14:20:19.427 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2005-02-09 14:20:19.427 Alias LS: No, Ign LS warn: Yes, Scp1 Comp: No
. 2005-02-09 14:20:19.427 --------------------------------------------------------------------------
. 2005-02-09 14:20:19.458 Looking up host "srvewokp10"
. 2005-02-09 14:20:19.458 Connecting to 192.168.2.1 port 22
. 2005-02-09 14:20:19.490 Server version: SSH-2.0-OpenSSH_3.6.1p2
. 2005-02-09 14:20:19.490 We claim version: SSH-2.0-WinSCP_release_3.7.3
. 2005-02-09 14:20:19.490 Using SSH protocol version 2
. 2005-02-09 14:20:19.568 Doing Diffie-Hellman group exchange
. 2005-02-09 14:20:19.646 Doing Diffie-Hellman key exchange
. 2005-02-09 14:20:20.458 Host key fingerprint is:
. 2005-02-09 14:20:20.458 ssh-rsa 1024 25:59:02:02:c8:28:5e:95:56:70:40:c7:d6:8e:3d:95
. 2005-02-09 14:20:20.458 Initialised AES-256 client->server encryption
. 2005-02-09 14:20:20.458 Initialised HMAC-SHA1 client->server MAC algorithm
. 2005-02-09 14:20:20.458 Initialised zlib (RFC1950) compression
. 2005-02-09 14:20:20.458 Initialised AES-256 server->client encryption
. 2005-02-09 14:20:20.458 Initialised HMAC-SHA1 server->client MAC algorithm
. 2005-02-09 14:20:20.458 Initialised zlib (RFC1950) decompression
! 2005-02-09 14:20:20.458 Using username "scpdemo".
. 2005-02-09 14:20:20.521 Keyboard-interactive authentication refused
. 2005-02-09 14:20:20.521 Session password prompt (scpdemo@srvewokp10's password: )
. 2005-02-09 14:20:20.521 Asking user for password.
. 2005-02-09 14:20:27.193 Sent password
! 2005-02-09 14:20:29.630 Access denied
. 2005-02-09 14:20:29.630 Access denied
. 2005-02-09 14:20:29.630 Session password prompt (scpdemo@srvewokp10's password: )
. 2005-02-09 14:20:29.630 Asking user for password.
. 2005-02-09 14:20:35.036 Sent password
. 2005-02-09 14:20:35.036 Access granted
. 2005-02-09 14:20:35.099 Opened channel for session
. 2005-02-09 14:20:35.161 Started a shell/command
. 2005-02-09 14:20:35.161 --------------------------------------------------------------------------
. 2005-02-09 14:20:35.161 Using SCP protocol.
. 2005-02-09 14:20:35.161 Doing startup conversation with host.
. 2005-02-09 14:20:35.177 Skipping host startup message (if any).
> 2005-02-09 14:20:35.177 echo "WinSCP: this is end-of-file:0"
< 2005-02-09 14:20:35.302 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:35.302 Detecting variable containing return code of last command.
. 2005-02-09 14:20:35.302 Trying "$status".
> 2005-02-09 14:20:35.302 echo "$status" ; echo "WinSCP: this is end-of-file:0"
< 2005-02-09 14:20:35.318 0
< 2005-02-09 14:20:35.318 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:35.318 Return code variable "$status" selected.
. 2005-02-09 14:20:35.318 Clearing all aliases.
> 2005-02-09 14:20:35.318 unalias "echo" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.349 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.349 unalias "pwd" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.396 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.396 unalias "cd" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.427 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.427 unalias "ls" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.474 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.474 unalias "groups" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.521 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.521 unalias "scp" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.552 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.552 unalias "rm" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.599 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.599 unalias "mv" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.630 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.630 unalias "mkdir" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.677 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.677 unalias "chmod" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.708 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.708 unalias "chgrp" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.755 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.755 unalias "chown" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.802 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.802 unalias "unset" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.833 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.833 unalias "unalias" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.880 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.880 unalias "alias" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.911 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.911 unalias "ln" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.958 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:35.958 unalias "cp" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:35.989 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:35.989 Clearing national user variables.
> 2005-02-09 14:20:35.989 unset "LANG" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.036 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.036 unset "LANGUAGE" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.068 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.068 unset "LC_CTYPE" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.114 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.114 unset "LC_COLLATE" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.161 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.161 unset "LC_MONETARY" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.193 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.193 unset "LC_NUMERIC" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.239 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.239 unset "LC_TIME" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.271 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.271 unset "LC_MESSAGES" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.318 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.318 unset "LC_ALL" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.349 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:36.349 unset "HUMAN_BLOCKS" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.396 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:36.396 Looking up groups and users.
> 2005-02-09 14:20:36.396 groups ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.693 root users
< 2005-02-09 14:20:36.849 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:36.849 Following groups found:
. 2005-02-09 14:20:36.849 root
. 2005-02-09 14:20:36.849 users
. 2005-02-09 14:20:36.849 No users found.
. 2005-02-09 14:20:36.849 Getting current directory name.
> 2005-02-09 14:20:36.849 pwd ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:36.864 /
< 2005-02-09 14:20:37.068 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:37.068 Listing current directory.
> 2005-02-09 14:20:37.068 ls -la --full-time ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:37.271 total 11
< 2005-02-09 14:20:37.271 drwxr-xr-x 7 0 0 1024 2005-02-04 07:56:36.091856298 +0000 .
< 2005-02-09 14:20:37.271 drwxr-xr-x 7 0 0 1024 2005-02-04 07:56:36.091856298 +0000 ..
< 2005-02-09 14:20:37.271 drwxr-xr-x 2 0 0 1024 2005-02-09 09:28:14.000000000 +0000 bin
< 2005-02-09 14:20:37.271 drwxr-xr-x 2 0 0 1024 2005-02-04 10:15:14.000000000 +0000 etc
< 2005-02-09 14:20:37.271 drwxr-xr-x 2 scpdemo scpdemo 1024 2005-02-09 09:31:22.000000000 +0000 incoming
< 2005-02-09 14:20:37.271 drwxr-xr-x 3 0 0 1024 2005-02-04 07:56:35.000000000 +0000 lib
< 2005-02-09 14:20:37.286 drwxr-xr-x 5 0 0 1024 2005-02-04 07:56:35.631838125 +0000 usr
< 2005-02-09 14:20:37.396 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:37.396 Directory listing with --full-time succeed, next time all errors during directory listing will be displayed immediatelly.
. 2005-02-09 14:20:37.396 Startup conversation with host finished.
. 2005-02-09 14:20:39.193 Cached directory change via "incoming" to "/incoming".
. 2005-02-09 14:20:39.193 Getting current directory name.
. 2005-02-09 14:20:39.193 Listing current directory.
. 2005-02-09 14:20:39.193 Locating to cached directory "/incoming".
> 2005-02-09 14:20:39.193 cd "/incoming" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:39.193 WinSCP: this is end-of-file:0
> 2005-02-09 14:20:39.193 ls -la --full-time ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:39.239 total 2333
< 2005-02-09 14:20:39.239 drwxr-xr-x 2 scpdemo scpdemo 1024 2005-02-09 09:31:22.000000000 +0000 .
< 2005-02-09 14:20:39.239 drwxr-xr-x 7 0 0 1024 2005-02-04 07:56:36.091856298 +0000 ..
< 2005-02-09 14:20:39.239 -rw-r--r-- 1 scpdemo scpdemo 2120050 2005-01-28 15:52:29.000000000 +0000 CD NIGG Anrufe.zip
< 2005-02-09 14:20:39.239 -rw-r--r-- 1 scpdemo scpdemo 126976 2005-02-09 09:29:28.000000000 +0000 data2.cab
< 2005-02-09 14:20:39.239 -rw-r--r-- 1 scpdemo scpdemo 126976 2005-02-09 09:26:28.000000000 +0000 winscp373fix2.zip
< 2005-02-09 14:20:39.364 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:43.833 Deleting file "CD NIGG Anrufe.zip".
> 2005-02-09 14:20:43.833 rm -f -r "CD NIGG Anrufe.zip" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:44.036 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:44.036 Deleting file "data2.cab".
> 2005-02-09 14:20:44.036 rm -f -r "data2.cab" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:44.052 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:44.052 Deleting file "winscp373fix2.zip".
> 2005-02-09 14:20:44.052 rm -f -r "winscp373fix2.zip" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:44.083 WinSCP: this is end-of-file:0
. 2005-02-09 14:20:44.083 Listing current directory.
> 2005-02-09 14:20:44.083 ls -la --full-time ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:20:44.130 total 2
< 2005-02-09 14:20:44.130 drwxr-xr-x 2 scpdemo scpdemo 1024 2005-02-09 13:20:43.928351261 +0000 .
< 2005-02-09 14:20:44.130 drwxr-xr-x 7 0 0 1024 2005-02-04 07:56:36.091856298 +0000 ..
< 2005-02-09 14:20:44.286 WinSCP: this is end-of-file:0
. 2005-02-09 14:21:06.614 Copying 10 files/directories to remote directory "/incoming/"
. 2005-02-09 14:21:06.614 PrTime: Yes; PrRO: Yes; Rght: rw-r--r--; PrR: No; FnCs: N; RIC: Yes; Resume: S (102400); CalcS: Yes; Mask: *.*
. 2005-02-09 14:21:06.614 TM: M; ClAr: No; ExclM:
. 2005-02-09 14:21:06.614 AscM: *.*html; *.htm; *.txt; *.php*; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
> 2005-02-09 14:21:06.614 echo "WinSCP: this is begin-of-file" ; scp -r -d -t "/incoming" ; echo "WinSCP: this is end-of-file:$status"
< 2005-02-09 14:21:06.661 WinSCP: this is begin-of-file
. 2005-02-09 14:21:06.895 SCP remote side confirmation (0)
. 2005-02-09 14:21:06.895 File: "C:\Downloads\winscp373fix2.zip"
. 2005-02-09 14:21:06.911 Copying "C:\Downloads\winscp373fix2.zip" to remote directory started.
. 2005-02-09 14:21:06.911 Binary transfer mode selected.
> 2005-02-09 14:21:06.911 T1107940973 0 1107955204 0
. 2005-02-09 14:21:06.942 SCP remote side confirmation (0)
> 2005-02-09 14:21:06.942 C0644 1366796 winscp373fix2.zip
. 2005-02-09 14:21:06.973 SCP remote side confirmation (0)
. 2005-02-09 14:21:06.973 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:07.067 Detected incoming data while idle
. 2005-02-09 14:21:07.567 Detected incoming data while idle
. 2005-02-09 14:21:08.067 Detected incoming data while idle
. 2005-02-09 14:21:08.098 Sending NULL.
. 2005-02-09 14:21:08.098 SCP remote side confirmation (0)
. 2005-02-09 14:21:08.098 Copying "C:\Downloads\winscp373fix2.zip" to remote directory finished.
. 2005-02-09 14:21:08.098 File: "C:\Downloads\winscp373setupintl.exe"
. 2005-02-09 14:21:08.098 Copying "C:\Downloads\winscp373setupintl.exe" to remote directory started.
. 2005-02-09 14:21:08.098 Binary transfer mode selected.
> 2005-02-09 14:21:08.098 T1107940899 0 1107941036 0
. 2005-02-09 14:21:08.130 SCP remote side confirmation (0)
> 2005-02-09 14:21:08.130 C0644 1624128 winscp373setupintl.exe
. 2005-02-09 14:21:08.177 SCP remote side confirmation (0)
. 2005-02-09 14:21:08.177 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:08.567 Detected incoming data while idle
. 2005-02-09 14:21:09.067 Detected incoming data while idle
. 2005-02-09 14:21:09.520 Sending NULL.
. 2005-02-09 14:21:09.520 SCP remote side confirmation (0)
. 2005-02-09 14:21:09.520 Copying "C:\Downloads\winscp373setupintl.exe" to remote directory finished.
. 2005-02-09 14:21:09.520 File: "C:\Downloads\winscp373scpproblem.zip"
. 2005-02-09 14:21:09.520 Copying "C:\Downloads\winscp373scpproblem.zip" to remote directory started.
. 2005-02-09 14:21:09.520 Binary transfer mode selected.
> 2005-02-09 14:21:09.520 T1107940167 0 1107940505 0
. 2005-02-09 14:21:09.552 SCP remote side confirmation (0)
> 2005-02-09 14:21:09.552 C0644 2728808 winscp373scpproblem.zip
. 2005-02-09 14:21:09.598 SCP remote side confirmation (0)
. 2005-02-09 14:21:09.598 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:10.567 Detected incoming data while idle
. 2005-02-09 14:21:11.067 Detected incoming data while idle
. 2005-02-09 14:21:11.567 Detected incoming data while idle
. 2005-02-09 14:21:11.833 Sending NULL.
. 2005-02-09 14:21:11.864 SCP remote side confirmation (0)
. 2005-02-09 14:21:11.864 Copying "C:\Downloads\winscp373scpproblem.zip" to remote directory finished.
. 2005-02-09 14:21:11.864 File: "C:\Downloads\SkypeSetup.exe"
. 2005-02-09 14:21:11.864 Copying "C:\Downloads\SkypeSetup.exe" to remote directory started.
. 2005-02-09 14:21:11.864 Binary transfer mode selected.
> 2005-02-09 14:21:11.864 T1107765316 0 1107941182 0
. 2005-02-09 14:21:11.895 SCP remote side confirmation (0)
> 2005-02-09 14:21:11.895 C0644 5909216 SkypeSetup.exe
. 2005-02-09 14:21:11.942 SCP remote side confirmation (0)
. 2005-02-09 14:21:11.942 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:13.067 Detected incoming data while idle
. 2005-02-09 14:21:13.567 Detected incoming data while idle
. 2005-02-09 14:21:14.067 Detected incoming data while idle
. 2005-02-09 14:21:15.067 Detected incoming data while idle
. 2005-02-09 14:21:15.567 Detected incoming data while idle
. 2005-02-09 14:21:16.067 Detected incoming data while idle
. 2005-02-09 14:21:16.755 Sending NULL.
. 2005-02-09 14:21:16.755 SCP remote side confirmation (0)
. 2005-02-09 14:21:16.755 Copying "C:\Downloads\SkypeSetup.exe" to remote directory finished.
. 2005-02-09 14:21:16.755 File: "C:\Downloads\squid-2.5.STABLE8-RC4-20050207.tar.gz"
. 2005-02-09 14:21:16.755 Copying "C:\Downloads\squid-2.5.STABLE8-RC4-20050207.tar.gz" to remote directory started.
. 2005-02-09 14:21:16.755 Binary transfer mode selected.
> 2005-02-09 14:21:16.770 T1107765095 0 1107765113 0
. 2005-02-09 14:21:16.801 SCP remote side confirmation (0)
> 2005-02-09 14:21:16.801 C0644 1371360 squid-2.5.STABLE8-RC4-20050207.tar.gz
. 2005-02-09 14:21:16.848 SCP remote side confirmation (0)
. 2005-02-09 14:21:16.848 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:17.567 Detected incoming data while idle
. 2005-02-09 14:21:17.958 Sending NULL.
. 2005-02-09 14:21:18.005 SCP remote side confirmation (0)
. 2005-02-09 14:21:18.005 Copying "C:\Downloads\squid-2.5.STABLE8-RC4-20050207.tar.gz" to remote directory finished.
. 2005-02-09 14:21:18.005 File: "C:\Downloads\samba-3.0.11.tar.gz"
. 2005-02-09 14:21:18.005 Copying "C:\Downloads\samba-3.0.11.tar.gz" to remote directory started.
. 2005-02-09 14:21:18.005 Binary transfer mode selected.
> 2005-02-09 14:21:18.020 T1107761102 0 1107762701 0
. 2005-02-09 14:21:18.083 SCP remote side confirmation (0)
> 2005-02-09 14:21:18.083 C0644 15372539 samba-3.0.11.tar.gz
. 2005-02-09 14:21:18.130 SCP remote side confirmation (0)
. 2005-02-09 14:21:18.130 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:19.067 Detected incoming data while idle
. 2005-02-09 14:21:19.567 Detected incoming data while idle
. 2005-02-09 14:21:20.067 Detected incoming data while idle
. 2005-02-09 14:21:20.567 Detected incoming data while idle
. 2005-02-09 14:21:21.067 Detected incoming data while idle
. 2005-02-09 14:21:22.067 Detected incoming data while idle
. 2005-02-09 14:21:22.567 Detected incoming data while idle
. 2005-02-09 14:21:23.067 Detected incoming data while idle
. 2005-02-09 14:21:23.567 Detected incoming data while idle
. 2005-02-09 14:21:24.567 Detected incoming data while idle
. 2005-02-09 14:21:25.067 Detected incoming data while idle
. 2005-02-09 14:21:25.567 Detected incoming data while idle
. 2005-02-09 14:21:26.567 Detected incoming data while idle
. 2005-02-09 14:21:27.067 Detected incoming data while idle
. 2005-02-09 14:21:28.567 Detected incoming data while idle
. 2005-02-09 14:21:29.567 Detected incoming data while idle
. 2005-02-09 14:21:30.067 Detected incoming data while idle
. 2005-02-09 14:21:30.567 Detected incoming data while idle
. 2005-02-09 14:21:31.067 Detected incoming data while idle
. 2005-02-09 14:21:31.098 Sending NULL.
. 2005-02-09 14:21:31.129 SCP remote side confirmation (0)
. 2005-02-09 14:21:31.129 Copying "C:\Downloads\samba-3.0.11.tar.gz" to remote directory finished.
. 2005-02-09 14:21:31.145 File: "C:\Downloads\winpt-0.5.5-exe.zip"
. 2005-02-09 14:21:31.192 Copying "C:\Downloads\winpt-0.5.5-exe.zip" to remote directory started.
. 2005-02-09 14:21:31.208 Binary transfer mode selected.
> 2005-02-09 14:21:31.208 T1107525827 0 1107955291 0
. 2005-02-09 14:21:31.239 SCP remote side confirmation (0)
> 2005-02-09 14:21:31.239 C0644 229019 winpt-0.5.5-exe.zip
. 2005-02-09 14:21:31.270 SCP remote side confirmation (0)
. 2005-02-09 14:21:31.270 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:31.489 Sending NULL.
. 2005-02-09 14:21:31.520 SCP remote side confirmation (0)
. 2005-02-09 14:21:31.520 Copying "C:\Downloads\winpt-0.5.5-exe.zip" to remote directory finished.
. 2005-02-09 14:21:31.520 File: "C:\Downloads\gnupg-w32cli-2.4.0a.zip"
. 2005-02-09 14:21:31.598 Copying "C:\Downloads\gnupg-w32cli-2.4.0a.zip" to remote directory started.
. 2005-02-09 14:21:31.598 Binary transfer mode selected.
> 2005-02-09 14:21:31.598 T1107525593 0 1107955291 0
. 2005-02-09 14:21:31.629 SCP remote side confirmation (0)
> 2005-02-09 14:21:31.629 C0644 1666418 gnupg-w32cli-2.4.0a.zip
. 2005-02-09 14:21:31.676 SCP remote side confirmation (0)
. 2005-02-09 14:21:31.676 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:32.067 Detected incoming data while idle
. 2005-02-09 14:21:33.067 Detected incoming data while idle
. 2005-02-09 14:21:33.129 Sending NULL.
. 2005-02-09 14:21:33.161 SCP remote side confirmation (0)
. 2005-02-09 14:21:33.161 Copying "C:\Downloads\gnupg-w32cli-2.4.0a.zip" to remote directory finished.
. 2005-02-09 14:21:33.161 File: "C:\Downloads\anycom-bluetooth-usb120-240-windows-v1-4-3-4sp1.exe"
. 2005-02-09 14:21:33.161 Copying "C:\Downloads\anycom-bluetooth-usb120-240-windows-v1-4-3-4sp1.exe" to remote directory started.
. 2005-02-09 14:21:33.176 Binary transfer mode selected.
> 2005-02-09 14:21:33.176 T1107180066 0 1107941184 0
. 2005-02-09 14:21:33.207 SCP remote side confirmation (0)
> 2005-02-09 14:21:33.207 C0644 21856801 anycom-bluetooth-usb120-240-windows-v1-4-3-4sp1.exe
. 2005-02-09 14:21:33.254 SCP remote side confirmation (0)
. 2005-02-09 14:21:33.254 Sending BINARY data (first block, 4096 bytes)
. 2005-02-09 14:21:33.567 Detected incoming data while idle
. 2005-02-09 14:21:34.067 Detected incoming data while idle
. 2005-02-09 14:21:34.567 Detected incoming data while idle
. 2005-02-09 14:21:35.067 Detected incoming data while idle
. 2005-02-09 14:21:35.567 Detected incoming data while idle
. 2005-02-09 14:21:36.067 Detected incoming data while idle
. 2005-02-09 14:21:36.567 Detected incoming data while idle
. 2005-02-09 14:21:37.067 Detected incoming data while idle
. 2005-02-09 14:21:37.567 Detected incoming data while idle
. 2005-02-09 14:21:38.567 Detected incoming data while idle
. 2005-02-09 14:21:39.067 Detected incoming data while idle
. 2005-02-09 14:21:39.567 Detected incoming data while idle
. 2005-02-09 14:21:40.067 Detected incoming data while idle
. 2005-02-09 14:21:40.567 Detected incoming data while idle
. 2005-02-09 14:21:41.067 Detected incoming data while idle
. 2005-02-09 14:21:43.067 Detected incoming data while idle
. 2005-02-09 14:21:43.567 Detected incoming data while idle
. 2005-02-09 14:21:44.067 Detected incoming data while idle
. 2005-02-09 14:21:44.567 Detected incoming data while idle
. 2005-02-09 14:21:45.567 Detected incoming data while idle
. 2005-02-09 14:21:46.067 Detected incoming data while idle
. 2005-02-09 14:21:46.567 Detected incoming data while idle
. 2005-02-09 14:21:47.567 Detected incoming data while idle
. 2005-02-09 14:21:48.067 Detected incoming data while idle
. 2005-02-09 14:21:48.567 Detected incoming data while idle
. 2005-02-09 14:21:49.567 Detected incoming data while idle
. 2005-02-09 14:21:50.067 Detected incoming data while idle
. 2005-02-09 14:21:50.567 Detected incoming data while idle
. 2005-02-09 14:21:51.067 Detected incoming data while idle
. 2005-02-09 14:22:07.129 Waiting for data timed out, asking user what to do.
. 2005-02-09 14:22:07.129 Asking user:
. 2005-02-09 14:22:07.129 Host has not answered for 15 seconds.
. 2005-02-09 14:22:07.129
. 2005-02-09 14:22:07.129 Wait for another 15 seconds? Pressing 'Abort' button will close session. ()
. 2005-02-09 14:22:09.441 Attempt to close connection due to fatal exception:
* 2005-02-09 14:22:09.441 Terminated by user.
. 2005-02-09 14:22:09.441 Closing connection.
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
slaukas wrote:
I have tested the fix2 under heavy load with files with different size. The First 20-40 MB run's very well, but after this the Transfer stop's with the Message: Host has not answered for 15 seconds. and the session is broken.
With sftp i have no problems with the same Files.

Thanks. While I have not mentioned it, I wanted the log with low-level logging enabled. As I expect that the log will be very large, please mail it to me zipped.

Also I would like to know if you can reproduce the same problem with older version of WinSCP, particularly 3.7.2 and 3.7.1.

Again, reply from anyone, not only slaukas, is appreciated.
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
Thanks goes to Knight for the log.

So here comes yet another attempt to fix this. If it fails again, post a log file (again the low-level one).

Sorry for the hassle, but this bug it really tough one.

Also I'm still curious in you can reproduce this in 3.7.1.
pbelb

Guest


I was using 3.7.1 without problems, and jumped to 3.7.3, and immediately saw the same problems others did with sending a binary file from my pc to another machine using scp.

Interestingly, the problem doesn't seem to affect receiving a file from the distant system.

I'm using 100MB Full Duplex, talking to a solaris 9 or 10 machine on the other end.

Neither distant machine was a problem with 3.7.1.

Went back to 3.7.1, and all is as it was before.....
pbelb

Guest


I downloaded fix 4, and it seems to be working...... yay! Thank you!
mdoran
[View user's profile]

Joined: 2005-01-06
Posts: 8
prikryl wrote:
Thanks goes to Knight for the log.

So here comes yet another attempt to fix this. If it fails again, post a log file (again the low-level one).

Sorry for the hassle, but this bug it really tough one.

Also I'm still curious in you can reproduce this in 3.7.1.


I used fix4 and uploaded a 300meg file no problem, that seems to fix the issue.
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
Thanks all for the logs and help.

I've found myself some another small problem. And as I'm bit paranoic to make fixes to SCP code not to introduce new problem (that's the way our bug was introduced), I would like to ask you for one more (the last) test. Download the version to test. If it works, no need to send logs anymore. Also this time please test download too.

Thanks again.
mdoran
[View user's profile]

Joined: 2005-01-06
Posts: 8
prikryl wrote:
Thanks all for the logs and help.

I've found myself some another small problem. And as I'm bit paranoic to make fixes to SCP code not to introduce new problem (that's the way our bug was introduced), I would like to ask you for one more (the last) test. Download the version to test. If it works, no need to send logs anymore. Also this time please test download too.

Thanks again.


I just tried fix5 and I was able to upload and download a 300Meg file with no trouble at all. Looks like you got the problem fixed, thanks for all your hard work and the great product.
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
mdoran wrote:
I just tried fix5 and I was able to upload and download a 300Meg file with no trouble at all. Looks like you got the problem fixed, thanks for all your hard work and the great product.

Thank you too.
_________________
Martin Prikryl
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