Problems Downloading large File (SFTP) - abort after 60 min

Advertisement

philipp_nbg
Guest

Problems Downloading large File (SFTP) - abort after 60 min

Hi,

since two days we are fighting against the following problem.
A 680 MB file should be downloaded from an SFTP-Server.

The commnandline-script runs on a windows xp sp3 machine.
We use also the resume-function and the temp-file-function.

The Problem:
After starting the download we get a growing-up local temp-file.
After exactly 60 min the download aborts.
the result ist, that the temp-file will be deleted and it starts from begin.

It's working like a hamster-wheel... :-)

I hope anyone could help us solving the problem.

This is our script:

winscp.com -transfer=binary -resumesupport /log="c:\temp\winscp.log" /script="c:\temp\download.ftp"

Content of "download.ftp":

# Automatically abort script on errors
option batch abort
# Disable overwrite confirmations that conflict with the previous
option confirm off
# Connect to SFTP server using a password
open sftp://user:password@XX.XXX.X.XXX -hostkey="ssh-rsa 1024 dd:f4:13:4f:77:9c:0d:88:3c:f9:3f:2b:2b:b9:ea:2e"
# Download file
get update_1493/*.* c:\temp\update_1493_DL\
# Disconnect
close
# Exit WinSCP
exit

Reply with quote

Advertisement

philipp_nbg
Guest

Here is the winscp-logfile

. 2015-02-05 21:04:38.734 --------------------------------------------------------------------------
. 2015-02-05 21:04:38.734 WinSCP Version 5.5.6 (Build 4746) (OS 5.1.2600 Service Pack 3 - Microsoft Windows XP)
. 2015-02-05 21:04:38.734 Configuration: c:\norma\tools\WinSCP\WinSCP.ini
. 2015-02-05 21:04:38.734 Local account: WORKGROUP\N02153-DB4CY2J$
. 2015-02-05 21:04:38.734 Working directory: C:\Dokumente und Einstellungen\All Users\Dell\KACE\kbots_cache\packages\kbots\133
. 2015-02-05 21:04:38.734 Process ID: 7984
. 2015-02-05 21:04:38.734 Command-line: "c:\norma\tools\WinSCP\WinSCP.exe" /console=556 /consoleinstance=_20208_652 "-transfer=binary" "-resumesupport" "/log=c:\NORMA\LOGFILES\WINSCP_20150205_210436.LOG" "/script=c:\temp\update_1493_DL\NCR_1493_update.ftp"
. 2015-02-05 21:04:38.734 Time zone: Current: GMT+1, Standard: GMT+1 (Westeuropäische Normalzeit), DST: GMT+2 (Westeuropäische Sommerzeit), DST Start: 29.03.2015, DST End: 25.10.2015
. 2015-02-05 21:04:38.734 Login time: Donnerstag, 5. Februar 2015 21:04:38
. 2015-02-05 21:04:38.734 --------------------------------------------------------------------------
. 2015-02-05 21:04:38.734 Script: Retrospectively logging previous script records:
> 2015-02-05 21:04:38.734 Script: option batch abort
< 2015-02-05 21:04:38.734 Script: batch abort
> 2015-02-05 21:04:38.734 Script: option confirm off
< 2015-02-05 21:04:38.734 Script: confirm off
> 2015-02-05 21:04:38.734 Script: open sftp://normasoft:***@xx.xxx.x.xxx -hostkey="ssh-rsa 1024 dd:f4:13:4f:77:9c:0d:88:3c:f9:3f:2b:2b:b9:ea:2e"
. 2015-02-05 21:04:38.734 --------------------------------------------------------------------------
. 2015-02-05 21:04:38.734 Session name: user@xx.xxx.x.xxx (Ad-Hoc site)
. 2015-02-05 21:04:38.734 Host name: xx.xxx.x.xxx (Port: 22)
. 2015-02-05 21:04:38.734 User name: user (Password: Yes, Key file: No)
. 2015-02-05 21:04:38.734 Tunnel: No
. 2015-02-05 21:04:38.734 Transfer Protocol: SFTP
. 2015-02-05 21:04:38.734 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2015-02-05 21:04:38.734 Proxy: none
. 2015-02-05 21:04:38.734 Send buffer: 262144
. 2015-02-05 21:04:38.734 SSH protocol version: 2; Compression: No
. 2015-02-05 21:04:38.734 Bypass authentication: No
. 2015-02-05 21:04:38.734 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2015-02-05 21:04:38.734 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2015-02-05 21:04:38.734 SSH Bugs: A,A,A,A,A,A,A,A,A,A
. 2015-02-05 21:04:38.734 Simple channel: Yes
. 2015-02-05 21:04:38.734 Return code variable: Autodetect; Lookup user groups: A
. 2015-02-05 21:04:38.734 Shell: default
. 2015-02-05 21:04:38.734 EOL: 0, UTF: 2
. 2015-02-05 21:04:38.734 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2015-02-05 21:04:38.734 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2015-02-05 21:04:38.734 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2015-02-05 21:04:38.734 Cache directory changes: Yes, Permanent: Yes
. 2015-02-05 21:04:38.734 DST mode: 1; Timezone offset: 0h 0m
. 2015-02-05 21:04:38.734 --------------------------------------------------------------------------
. 2015-02-05 21:04:38.734 Looking up host "xx.xxx.x.xxx"
. 2015-02-05 21:04:38.734 Connecting to xx.xxx.x.xxx port 22
. 2015-02-05 21:04:38.875 Server version: SSH-2.0-OpenSSH_5.5
. 2015-02-05 21:04:38.875 Using SSH protocol version 2
. 2015-02-05 21:04:38.875 We claim version: SSH-2.0-WinSCP_release_5.5.6
. 2015-02-05 21:04:38.953 Doing Diffie-Hellman group exchange
. 2015-02-05 21:04:39.156 Doing Diffie-Hellman key exchange with hash SHA-256
. 2015-02-05 21:04:41.031 Verifying host key rsa2 0x23,0xd0c42816196232e1 ad00132516539058 8471c0ddcb9a4a14 f5ffc1afbbfff702 44e3e6d317ae0f50 91d25af5183383c4 9d6904b6cab5dfb4 84e9d4a34480d408 7170a6bc39225a30 78bbf8d95004bed0 6870c6a7134d012e 6180a3e6841f745b 3627504b78ad13bd d1b8bb6f75f5e374 c2a6996b9b363e56 4fa6ad4e59f3a9e1 with fingerprint ssh-rsa 1024 dd:f4:13:4f:77:9c:0d:88:3c:f9:3f:2b:2b:b9:ea:2e
. 2015-02-05 21:04:41.031 Host key matches configured key
. 2015-02-05 21:04:41.031 Host key fingerprint is:
. 2015-02-05 21:04:41.031 ssh-rsa 1024 dd:f4:13:4f:77:9c:0d:88:3c:f9:3f:2b:2b:b9:ea:2e
. 2015-02-05 21:04:41.031 Initialised AES-256 SDCTR client->server encryption
. 2015-02-05 21:04:41.031 Initialised HMAC-SHA1 client->server MAC algorithm
. 2015-02-05 21:04:41.031 Initialised AES-256 SDCTR server->client encryption
. 2015-02-05 21:04:41.031 Initialised HMAC-SHA1 server->client MAC algorithm
! 2015-02-05 21:04:41.203 Using username "normasoft".
. 2015-02-05 21:04:41.343 Attempting keyboard-interactive authentication
. 2015-02-05 21:04:41.406 Server refused keyboard-interactive authentication
. 2015-02-05 21:04:41.406 Prompt (7, SSH password, , &Password: )
. 2015-02-05 21:04:41.406 Using stored password.
. 2015-02-05 21:04:41.406 Sent password
. 2015-02-05 21:04:41.578 Access granted
. 2015-02-05 21:04:41.578 Opening session as main channel
. 2015-02-05 21:04:41.640 Opened main channel
. 2015-02-05 21:04:41.828 Started a shell/command
. 2015-02-05 21:04:41.828 --------------------------------------------------------------------------
. 2015-02-05 21:04:41.843 Using SFTP protocol.
. 2015-02-05 21:04:41.843 Doing startup conversation with host.
> 2015-02-05 21:04:41.843 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2015-02-05 21:04:41.921 Type: SSH_FXP_VERSION, Size: 95, Number: -1
. 2015-02-05 21:04:41.921 SFTP version 3 negotiated.
. 2015-02-05 21:04:41.921 Unknown server extension posix-rename@openssh.com="1"
. 2015-02-05 21:04:41.921 Supports statvfs@openssh.com extension version "2"
. 2015-02-05 21:04:41.921 Unknown server extension fstatvfs@openssh.com="2"
. 2015-02-05 21:04:41.921 We believe the server has signed timestamps bug
. 2015-02-05 21:04:41.921 We will use UTF-8 strings when appropriate
. 2015-02-05 21:04:41.921 Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
. 2015-02-05 21:04:41.921 Getting current directory name.
. 2015-02-05 21:04:41.921 Getting real path for '.'
> 2015-02-05 21:04:41.921 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2015-02-05 21:04:41.984 Type: SSH_FXP_NAME, Size: 43, Number: 16
. 2015-02-05 21:04:41.984 Real path is '/home/norma'
. 2015-02-05 21:04:41.984 Startup conversation with host finished.
< 2015-02-05 21:04:41.984 Script: Active session: [1] user@xx.xxx.x.xxx
> 2015-02-05 21:04:41.984 Script: get update_1493/*.* c:\temp\update_1493_DL\
. 2015-02-05 21:04:41.984 Listing directory "/home/norma/update_1493".
> 2015-02-05 21:04:41.984 Type: SSH_FXP_OPENDIR, Size: 32, Number: 267
< 2015-02-05 21:04:42.062 Type: SSH_FXP_HANDLE, Size: 13, Number: 267
> 2015-02-05 21:04:42.062 Type: SSH_FXP_READDIR, Size: 13, Number: 524
< 2015-02-05 21:04:42.125 Type: SSH_FXP_NAME, Size: 472, Number: 524
> 2015-02-05 21:04:42.125 Type: SSH_FXP_READDIR, Size: 13, Number: 780
< 2015-02-05 21:04:42.203 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2015-02-05 21:04:42.203 Status code: 1
> 2015-02-05 21:04:42.203 Type: SSH_FXP_CLOSE, Size: 13, Number: 1028
. 2015-02-05 21:04:42.203 .;d;4096;2014-11-21T14:07:33.000Z;"softadmin" [2567];"users" [100];rwxr-xr-x;2
. 2015-02-05 21:04:42.203 update_1493.zip;-;706073610;2014-11-21T14:10:34.000Z;"softadmin" [2567];"users" [100];rw-r--r--;0
. 2015-02-05 21:04:42.203 ..;d;4096;2015-02-05T17:08:16.000Z;"softadmin" [2567];"users" [100];rwxr-x---;0
. 2015-02-05 21:04:42.203 update_1493.zip.md5;-;33;2014-11-21T14:07:33.000Z;"softadmin" [2567];"users" [100];rw-r--r--;0
. 2015-02-05 21:04:42.203 File: '/home/norma/update_1493/update_1493.zip' [2014-11-21T14:10:34.000Z] [706073610]
. 2015-02-05 21:04:42.203 Copying "/home/norma/update_1493/update_1493.zip" to local directory started.
. 2015-02-05 21:04:42.203 Binary transfer mode selected.
. 2015-02-05 21:04:42.203 Checking existence of partially transfered file.
. 2015-02-05 21:04:42.203 Opening remote file.
> 2015-02-05 21:04:42.203 Type: SSH_FXP_OPEN, Size: 56, Number: 1283
< 2015-02-05 21:04:42.281 Type: SSH_FXP_STATUS, Size: 24, Number: 1028
. 2015-02-05 21:04:42.281 Discarding reserved response
< 2015-02-05 21:04:42.343 Type: SSH_FXP_HANDLE, Size: 13, Number: 1283
> 2015-02-05 21:04:42.343 Type: SSH_FXP_FSTAT, Size: 13, Number: 1544
< 2015-02-05 21:04:42.421 Type: SSH_FXP_ATTRS, Size: 37, Number: 1544
> 2015-02-05 21:04:42.421 Type: SSH_FXP_READ, Size: 25, Number: 1797

Reply with quote

martin
Site Admin
martin avatar
Joined:
Posts:
41,517
Location:
Prague, Czechia

Re: Problems Downloading large File (SFTP) - abort after 60 min

I do not see any disconnect in the log. Can you attach a log with disconnect + reconnect?

Reply with quote

Advertisement

You can post new topics in this forum