Topic "Timeout after 15 seconds when transferring large files"

Author Message
thorpen

Guest


Hi,

We are running version 3.5 (can't go any higher due to customer restraints).

We we attempt a small file transfer it works fine. On large files, we keep getting the 15 second time out message and have to abort.

Log file contents below, any help would be apppreciated.

. --------------------------------------------------------------------------
. WinSCP Version 3.5.0 (Build 204)
. Login time: 02 September 2009 11:02:03
. --------------------------------------------------------------------------
. Session name: UAT
. Host name:
. User name:
. Transfer Protocol: SFTP
. SSH protocol version: 2; Compression: Yes
. Agent forwarding: No; TIS/CryptoCard: No; KI: Yes
. Ciphers: aes,blowfish,3des,WARN,des; Ssh2DES: No
. Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. SSH Bugs: -,-,-,-,-,-,-,-,
. Proxy: none
. Return code variable: Autodetect; Lookup user groups: Yes
. Shell: default, EOL: 0
. Local directory: C:\UAT, Remote directory: /, 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 "195.99.138.110"
. Connecting to 195.99.138.110 port 443
. Server version: SSH-2.0-0.0
. We claim version: SSH-2.0-WinSCP-release-3.5
. Using SSH protocol version 2
. Doing Diffie-Hellman group exchange
. Doing Diffie-Hellman key exchange
. Host key fingerprint is:
. ssh-dss 1024 0b:07:9d:a3:17:27:bf:95:ac:d3:1e:88:9f:a9:1c:7a
. Initialised AES-256 client->server encryption
. Initialised AES-256 server->client encryption
. Initialised zlib (RFC1950) compression
. Initialised zlib (RFC1950) decompression
! Using username "atostcqt".
. Reading private key file "C:\testprivatekey.PPK"
! |-----------------------------------------------------------------|
! | This system is for the use of authorized users only. |
! | Individuals using this computer system without authority, or in |
! | excess of their authority, are subject to having all of their |
! | activities on this system monitored and recorded by system |
! | personnel. |
! | |
! | In the course of monitoring individuals improperly using this |
! | system, or in the course of system maintenance, the activities |
! | of authorized users may also be monitored. |
! | |
! | Anyone using this system expressly consents to such monitoring |
! | and is advised that if such monitoring reveals possible |
! | evidence of criminal activity, system personnel may provide the |
! | evidence of such monitoring to law enforcement officials. |
! |-----------------------------------------------------------------|
. Offered public key
. Offer of public key accepted
! Authenticating with public key "dsa-key-20090813"
. Access granted
. Opened channel for session
. Started a shell/command
. --------------------------------------------------------------------------
. Using SFTP protocol.
. Doing startup conversation with host.
> Type: SSH_FXP_INIT, Size: 5, Number: -1
< Type: SSH_FXP_VERSION, Size: 677, Number: -1
. SFTP version 4 negotiated.
. Unknown server extension newline@vandyke.com=0x000000010A
. Server requests EOL sequence 0x0A.
. Unknown server extension default-fs-attribs@vandyke.com=0x00000003000000012F00000000
. Unknown server extension posix-rename@openssh.com=1
. Unknown server extension supported=0xFFFFFF800000FFFFFFAD000000000000003F001F01FFFFFFFF0000FFFFFF800000000018706F7369782D72656E616D65406F70656E7373682E636F6D00000009746578742D7365656B0000000976656E646F722D69640000000E76657273696F6E2D73656C6563740000000E686F6D652D6469726563746F727900000009636F70792D66696C650000000F6765742D74656D702D666F6C646572000000106D616B652D74656D702D666F6C646572
. Unknown server extension supported2=0xFFFFFF800000FFFFFFAD000000000000003F000000000000FFFFFF800000000000000000000000000800000018706F7369782D72656E616D65406F70656E7373682E636F6D00000009746578742D7365656B0000000976656E646F722D69640000000E76657273696F6E2D73656C6563740000000E686F6D652D6469726563746F727900000009636F70792D66696C650000000F6765742D74656D702D666F6C646572000000106D616B652D74656D702D666F6C646572
. Unknown server extension vendor-id=0x0000001656616E44796B6520536F6674776172652C20496E632E00000006565368656C6C00000011332E352E3220286275696C64203432332900030005000001FFFFFFA7
. Unknown server extension versions=3,4,5,6,draft-ietf-secsh-filexfer-11@vandyke.com,partial-v6@vandyke.com
> Type: SSH_FXP_EXTENDED, Size: 38, Number: 200
< Type: SSH_FXP_STATUS, Size: 96, Number: 200
< Status/error code: 8
. Server does not recognise WinSCP.
. Cached directory change via "/" to "/".
. Getting current directory name.
. Listing directory "/".
> Type: SSH_FXP_OPENDIR, Size: 10, Number: 267
< Type: SSH_FXP_HANDLE, Size: 13, Number: 267
> Type: SSH_FXP_READDIR, Size: 13, Number: 524
< Type: SSH_FXP_NAME, Size: 642, Number: 524
> Type: SSH_FXP_READDIR, Size: 13, Number: 780
< Type: SSH_FXP_STATUS, Size: 44, Number: 780
< Status/error code: 1
> Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
< Type: SSH_FXP_STATUS, Size: 34, Number: 1028
< Status/error code: 0
. Startup conversation with host finished.
. File: "/test-pdf.pdf"
. Copying "/test-pdf.pdf" to local directory started.
. Binary transfer mode selected.
. Checking existence of partially transfered file.
. Asking user:
. Target directory contains partially transfered file 'test-pdf.pdf'. Do you want to resume file transfer? Note: Answering 'No' would delete partially transfered file and restart transfer. ()
. Opening remote file.
> Type: SSH_FXP_OPEN, Size: 31, Number: 1283
< Type: SSH_FXP_HANDLE, Size: 13, Number: 1283
> Type: SSH_FXP_READ, Size: 25, Number: 1541
. Waiting for data timed out, asking user what to do.
. Asking user:
. Host hasn't answered for 15 seconds.
.
. Wait for another 15 seconds? Pressing 'Abort' button will close session. ()
. Attempt to close connection due to fatal exception:
* Terminated by user.
. Closing connection.
Advertisements
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
I'm sorry, but I cannot provide support for 3.5.
_________________
Martin Prikryl
Guest




I have now tried this with the latest version 4.1.9 and still get the same issue
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 25015
Location: Prague, Czechia
Please send me a Debug 2 level log file.

You will find my address (if you log in) in my forum profile.
_________________
Martin Prikryl
GUDDU

Guest


HOW TO CONTINUE UPLOADING WITH OUT ANY WARNING MESSAGE LIKE "UPDATE" AND "KEY".....IF I M NOT THERE THE HOW TO IGNORE THESE MESSAGE AND RESUME UPLOADING CONTINUE
PLS HELP ME
GUDDU

Guest


I HAVE ALSO SAME PROBLEM SEARCHING HOST AND.......RECONNECT .....I M USING WINSCP 4.3.2
steve3
[View user's profile]

Joined: 2011-03-22
Posts: 1
prikryl wrote:
Please send me a Debug 2 level log file.

You will find my address (if you log in) in my forum profile.


tried to transfer 2GB file from server to win-client using scp

winscp 3.2.1 (Build 174) => no problem
winscp 4.3.2 (Build 1201) => always timeout after receiving almost 500MB

Excerpt Debug2 Protocol (complete Protocol available, if needed):

. 2011-03-22 11:19:59.218 --------------------------------------------------------------------------
. 2011-03-22 11:19:59.218 WinSCP Version 4.3.2 (Build 1201) (OS 5.1.2600 Service Pack 2)
. 2011-03-22 11:19:59.218 Login time: Dienstag, 22. Mrz 2011 11:19:59
. 2011-03-22 11:19:59.218 --------------------------------------------------------------------------
. 2011-03-22 11:19:59.218 Session name: ackermann.vm
. 2011-03-22 11:19:59.218 Host name: 192.168.1.181 (Port: 22)
. 2011-03-22 11:19:59.218 User name: root (Password: Yes, Key file: No)
. 2011-03-22 11:19:59.218 Tunnel: No
. 2011-03-22 11:19:59.218 Transfer Protocol: SCP
. 2011-03-22 11:19:59.218 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2011-03-22 11:19:59.218 Proxy: none
. 2011-03-22 11:19:59.218 SSH protocol version: 2; Compression: No
. 2011-03-22 11:19:59.218 Bypass authentication: No
. 2011-03-22 11:19:59.218 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2011-03-22 11:19:59.218 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2011-03-22 11:19:59.218 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2011-03-22 11:19:59.218 SFTP Bugs: -,-
. 2011-03-22 11:19:59.218 Return code variable: Autodetect; Lookup user groups: Yes
. 2011-03-22 11:19:59.218 Shell: default
. 2011-03-22 11:19:59.218 EOL: 0, UTF: 2
. 2011-03-22 11:19:59.218 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2011-03-22 11:19:59.218 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2011-03-22 11:19:59.218 Local directory: C:\Backup, Remote directory: /vmfs/volumes/datastore1, Update: Yes, Cache: Yes
. 2011-03-22 11:19:59.218 Cache directory changes: Yes, Permanent: Yes
. 2011-03-22 11:19:59.218 DST mode: 1
. 2011-03-22 11:19:59.218 --------------------------------------------------------------------------
. 2011-03-22 11:19:59.249 Looking up host "192.168.1.181"
. 2011-03-22 11:19:59.249 Connecting to 192.168.1.181 port 22
. 2011-03-22 11:19:59.296 Server version: SSH-2.0-dropbear_0.52
. 2011-03-22 11:19:59.296 We believe remote version has SSH-2 ignore bug
. 2011-03-22 11:19:59.296 Using SSH protocol version 2
. 2011-03-22 11:19:59.296 We claim version: SSH-2.0-WinSCP_release_4.3.2
. 2011-03-22 11:19:59.312 Using Diffie-Hellman with standard group "group1"
. 2011-03-22 11:19:59.312 Doing Diffie-Hellman key exchange with hash SHA-1
. 2011-03-22 11:19:59.499 Host key fingerprint is:
. 2011-03-22 11:19:59.608 ssh-rsa 1040 37:c2:5d:11:ab:8a:15:b0:4a:67:8c:d0:da:4d:b3:25
. 2011-03-22 11:19:59.608 Initialised AES-256 SDCTR client->server encryption
. 2011-03-22 11:19:59.608 Initialised HMAC-SHA1 client->server MAC algorithm
. 2011-03-22 11:19:59.608 Initialised AES-256 SDCTR server->client encryption
. 2011-03-22 11:19:59.608 Initialised HMAC-SHA1 server->client MAC algorithm
! 2011-03-22 11:19:59.718 Using username "root".
. 2011-03-22 11:19:59.733 Prompt (6, SSH password, , &Passwort:)
. 2011-03-22 11:19:59.733 Using stored password.
. 2011-03-22 11:19:59.733 Sent password
. 2011-03-22 11:19:59.733 Access granted
. 2011-03-22 11:19:59.733 Opened channel for session
. 2011-03-22 11:19:59.843 Started a shell/command
. 2011-03-22 11:19:59.952 --------------------------------------------------------------------------
. 2011-03-22 11:19:59.952 Using SCP protocol.
. 2011-03-22 11:19:59.968 Doing startup conversation with host.
. 2011-03-22 11:19:59.968 Skipping host startup message (if any).
> 2011-03-22 11:19:59.968 echo "WinSCP: this is end-of-file:0"
< 2011-03-22 11:20:00.077 WinSCP: this is end-of-file:0
. 2011-03-22 11:20:00.077 Detecting variable containing return code of last command.
. 2011-03-22 11:20:00.077 Trying "$status".
> 2011-03-22 11:20:00.077 echo "$status" ; echo "WinSCP: this is end-of-file:0"
< 2011-03-22 11:20:00.280 WinSCP: this is end-of-file:0
. 2011-03-22 11:20:00.280 The response is not numerical exit code
. 2011-03-22 11:20:00.280 Trying "$?".
> 2011-03-22 11:20:00.280 echo "$?" ; echo "WinSCP: this is end-of-file:0"
< 2011-03-22 11:20:00.390 0
< 2011-03-22 11:20:00.390 WinSCP: this is end-of-file:0
. 2011-03-22 11:20:00.390 Return code variable "$?" selected.
. 2011-03-22 11:20:00.390 Clearing all aliases.
.....
. 2011-03-22 11:32:43.368 Timeout waiting for network events
. 2011-03-22 11:32:43.899 Session upkeep
. 2011-03-22 11:32:43.899 Looking for network events
. 2011-03-22 11:32:43.899 Timeout waiting for network events
. 2011-03-22 11:32:43.899 Copying 1 files/directories to local directory "C:\Backup\"
. 2011-03-22 11:32:43.899 PrTime: Yes; PrRO: Yes; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 01; Resume: S (10240); CalcS: Yes; Mask: *.*
. 2011-03-22 11:32:43.899 TM: M; ClAr: No; CPS: 0; ExclM(No):
. 2011-03-22 11:32:43.899 AscM: *.*htm*; *.txt; *.php*; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2011-03-22 11:32:43.899 Sent 119 bytes
. 2011-03-22 11:32:43.899 There are 0 bytes remaining in the send buffer
. 2011-03-22 11:32:43.899 Looking for network events
. 2011-03-22 11:32:43.899 Timeout waiting for network events
. 2011-03-22 11:32:43.899 Sent 1 bytes
. 2011-03-22 11:32:43.899 There are 0 bytes remaining in the send buffer
. 2011-03-22 11:32:43.899 Looking for network events
. 2011-03-22 11:32:43.899 Timeout waiting for network events
> 2011-03-22 11:32:43.899 echo "WinSCP: this is begin-of-file" ; scp -r -p -d -f "kdnu8pg7_ackermann.tgz" ; echo "WinSCP: this is end-of-file:$?"
. 2011-03-22 11:32:43.899 Waiting for another 1 bytes
. 2011-03-22 11:32:43.899 Looking for incoming data
. 2011-03-22 11:32:43.899 Looking for network events
. 2011-03-22 11:32:44.008 Detected network event
. 2011-03-22 11:32:44.008 Enumerating network events for socket 464
. 2011-03-22 11:32:44.008 Enumerated 1 network events making 1 cumulative events for socket 464
. 2011-03-22 11:32:44.008 Handling network read event on socket 464 with error 0
. 2011-03-22 11:32:44.008 Received 30 bytes (0)
. 2011-03-22 11:32:44.008 Read 1 bytes (29 pending)
. 2011-03-22 11:32:44.008 Read 29 bytes (0 pending)
< 2011-03-22 11:32:44.008 WinSCP: this is begin-of-file
. 2011-03-22 11:32:44.008 Sending NULL.
. 2011-03-22 11:32:44.008 Sent 1 bytes
. 2011-03-22 11:32:44.008 There are 0 bytes remaining in the send buffer
. 2011-03-22 11:32:44.008 Looking for network events
. 2011-03-22 11:32:44.008 Timeout waiting for network events
. 2011-03-22 11:32:44.008 Waiting for another 1 bytes
. 2011-03-22 11:32:44.008 Looking for incoming data
. 2011-03-22 11:32:44.008 Looking for network events
. 2011-03-22 11:32:44.055 Detected network event
. 2011-03-22 11:32:44.055 Enumerating network events for socket 464
. 2011-03-22 11:32:44.055 Enumerated 1 network events making 1 cumulative events for socket 464
. 2011-03-22 11:32:44.055 Handling network read event on socket 464 with error 0
. 2011-03-22 11:32:44.055 Received 27 bytes (0)
. 2011-03-22 11:32:44.055 Read 1 bytes (26 pending)
. 2011-03-22 11:32:44.055 Read 26 bytes (0 pending)
< 2011-03-22 11:32:44.055 T1300720124 0 1300789249 0
. 2011-03-22 11:32:44.055 Sending NULL.
. 2011-03-22 11:32:44.055 Sent 1 bytes
. 2011-03-22 11:32:44.055 There are 0 bytes remaining in the send buffer
. 2011-03-22 11:32:44.055 Looking for network events
. 2011-03-22 11:32:44.055 Timeout waiting for network events
. 2011-03-22 11:32:44.055 Waiting for another 1 bytes
. 2011-03-22 11:32:44.055 Looking for incoming data
. 2011-03-22 11:32:44.055 Looking for network events
. 2011-03-22 11:32:44.055 Detected network event
. 2011-03-22 11:32:44.055 Enumerating network events for socket 464
. 2011-03-22 11:32:44.055 Enumerated 1 network events making 1 cumulative events for socket 464
. 2011-03-22 11:32:44.055 Handling network read event on socket 464 with error 0
. 2011-03-22 11:32:44.055 Received 40 bytes (0)
. 2011-03-22 11:32:44.055 Read 1 bytes (39 pending)
. 2011-03-22 11:32:44.055 Read 39 bytes (0 pending)
< 2011-03-22 11:32:44.055 C0644 2123267350 kdnu8pg7_ackermann.tgz
. 2011-03-22 11:32:44.055 Asking user:
. 2011-03-22 11:32:44.055 Die lokale Datei 'kdnu8pg7_ackermann.tgz' existiert bereits. berschreiben?
. 2011-03-22 11:32:44.055
. 2011-03-22 11:32:44.055 Neu: 2123267350 bytes, 21.03.2011 16:08:44
. 2011-03-22 11:32:44.055 Vorhanden: 499994624 bytes, 22.03.2011 11:23:26 ()
. 2011-03-22 11:32:44.368 Session upkeep
. 2011-03-22 11:32:44.368 Looking for network events
. 2011-03-22 11:32:44.368 Timeout waiting for network events
. 2011-03-22 11:32:44.868 Session upkeep
. 2011-03-22 11:32:44.868 Looking for network events
. 2011-03-22 11:32:44.868 Timeout waiting for network events
. 2011-03-22 11:32:45.196 Sending NULL.
. 2011-03-22 11:32:45.196 Sent 1 bytes
. 2011-03-22 11:32:45.196 There are 0 bytes remaining in the send buffer
. 2011-03-22 11:32:45.196 Looking for network events
. 2011-03-22 11:32:45.196 Timeout waiting for network events
. 2011-03-22 11:32:45.196 Binary transfer mode selected.
. 2011-03-22 11:32:45.196 Waiting for another 4096 bytes
. 2011-03-22 11:32:45.196 Looking for incoming data
. 2011-03-22 11:32:45.196 Looking for network events
. 2011-03-22 11:32:45.212 Detected network event
. 2011-03-22 11:32:45.212 Enumerating network events for socket 464
. 2011-03-22 11:32:45.212 Enumerated 1 network events making 1 cumulative events for socket 464
. 2011-03-22 11:32:45.212 Handling network read event on socket 464 with error 0
. 2011-03-22 11:32:45.212 Received 4096 bytes (0)
. 2011-03-22 11:32:45.212 Received 4096 bytes (0)
. 2011-03-22 11:32:45.212 Read 4096 bytes (4096 pending)
. 2011-03-22 11:32:45.212 Read 4096 bytes (0 pending)
....
. 2011-03-22 11:36:35.475 Waiting for another 4096 bytes
. 2011-03-22 11:36:35.475 Looking for incoming data
. 2011-03-22 11:36:35.475 Looking for network events
. 2011-03-22 11:36:35.475 Detected network event
. 2011-03-22 11:36:35.475 Enumerating network events for socket 464
. 2011-03-22 11:36:35.475 Enumerated 1 network events making 1 cumulative events for socket 464
. 2011-03-22 11:36:35.475 Handling network read event on socket 464 with error 0
. 2011-03-22 11:36:35.491 Received 4096 bytes (0)
. 2011-03-22 11:36:35.491 Received 4096 bytes (0)
. 2011-03-22 11:36:35.491 Received 4096 bytes (0)
. 2011-03-22 11:36:35.491 Received 734 bytes (0)
. 2011-03-22 11:36:35.491 Read 4096 bytes (8926 pending)
. 2011-03-22 11:36:35.491 Read 4096 bytes (4830 pending)
. 2011-03-22 11:36:35.491 Read 4096 bytes (734 pending)
. 2011-03-22 11:36:35.491 Waiting for another 3362 bytes
. 2011-03-22 11:36:35.491 Looking for incoming data
. 2011-03-22 11:36:35.491 Looking for network events
. 2011-03-22 11:36:50.490 Timeout waiting for network events
. 2011-03-22 11:36:50.490 Waiting for data timed out, asking user what to do.
. 2011-03-22 11:36:50.490 Asking user:
. 2011-03-22 11:36:50.490 Der entfernte Rechner kommuniziert seit 15 Sekunden nicht.
. 2011-03-22 11:36:50.490
. 2011-03-22 11:36:50.490 Weitere 15 Sekunden warten? ()
. 2011-03-22 11:36:50.506 Session upkeep
. 2011-03-22 11:36:50.506 Looking for network events
. 2011-03-22 11:36:50.506 Timeout waiting for network events
. 2011-03-22 11:36:50.881 Session upkeep
. 2011-03-22 11:36:50.881 Looking for network events
. 2011-03-22 11:36:50.881 Timeout waiting for network events
. 2011-03-22 11:36:50.990 Pooling for data in case they finally arrives
. 2011-03-22 11:36:50.990 Looking for network events
. 2011-03-22 11:36:50.990 Timeout waiting for network events
.....
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