Topic "Connection dropping during transfers"

Author Message
ramius
[View user's profile]

Joined: 2009-10-15
Posts: 4
Location: DFW
Hello,

We are using WinSCP v4.2.3 (build 494) on Windows 2003 Standard SP2 (x86). The problem has appeared intermittently throughout the time we've been transferring files. The remote server in this case is running WS_FTP SSH v7.0. We are using scheduled jobs running with scripts.

The strange thing is, when I've asked the remote organization, they show their logs indicate our system reset the connection. Our firewall logs indicate the same thing.

Please let me know if any further information is needed.

Thank you!

-Matt

--------------------------------------------------------------


Command Line:
/console /script=C:\txfr\lib\FileTXFR_scriptname_DEST_winscp_script.txt /log=C:\txfr\log\scriptname\20091014_22.00_scriptname.log

Script for failed session:
opetion batch continue
option confirm off
option transfer automatic
option reconnecttime 60
open session_hostname.domain.com -hostkey <hostkey>
get -delete -nopreservetime /my_username/Out/* f:\txfr\In\
put -nopreservetime -nopermissions f:\txfr\Out\* /my_username/In/
close
exit

Console Output from failed session:
batch continue
confirm off
transfer automatic
reconnecttime 60
Searching for host...
Connecting to host...
Authenticating...
Using username "my_username".
Authenticating with pre-entered password.
Authenticated.
Starting the session...
Reading remote directory...
Session started.
Active session: [1] session_hostname.domain.com
myusername_HC_091013_Images.zip | 11527 KiB | 638.5 KiB/s | binary | 95%
No valid incoming packet found
(A)bort, (R)econnect (5 s): Abort
No valid incoming packet found
Copying files from remote side failed.
Session 'session_hostname.domain.com' closed.
No session.
No session.
No session.

Log Output from failed session:
. 2009-10-14 22:00:14.969 --------------------------------------------------------------------------
. 2009-10-14 22:00:14.969 WinSCP Version 4.2.3 (Build 494) (OS 5.2.3790 Service Pack 2)
. 2009-10-14 22:00:14.969 Login time: Wednesday, October 14, 2009 10:00:14 PM
. 2009-10-14 22:00:14.969 --------------------------------------------------------------------------
. 2009-10-14 22:00:14.969 Session name: session_hostname.domain.com
. 2009-10-14 22:00:14.969 Host name: hostname.domain.com (Port: 22)
. 2009-10-14 22:00:14.969 User name: my_username (Password: Yes, Key file: No)
. 2009-10-14 22:00:14.969 Tunnel: No
. 2009-10-14 22:00:14.969 Transfer Protocol: SFTP (SCP)
. 2009-10-14 22:00:14.969 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2009-10-14 22:00:14.969 Proxy: none
. 2009-10-14 22:00:14.969 SSH protocol version: 2; Compression: No
. 2009-10-14 22:00:14.969 Bypass authentication: No
. 2009-10-14 22:00:14.969 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2009-10-14 22:00:14.969 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2009-10-14 22:00:14.969 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2009-10-14 22:00:14.969 SFTP Bugs: -,-
. 2009-10-14 22:00:14.969 Return code variable: Autodetect; Lookup user groups: Yes
. 2009-10-14 22:00:14.969 Shell: default, EOL: 0
. 2009-10-14 22:00:14.969 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2009-10-14 22:00:14.969 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2009-10-14 22:00:14.969 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2009-10-14 22:00:14.969 Cache directory changes: Yes, Permanent: Yes
. 2009-10-14 22:00:14.969 DST mode: 1
. 2009-10-14 22:00:14.969 --------------------------------------------------------------------------
. 2009-10-14 22:00:14.969 Looking up host "hostname.domain.com"
. 2009-10-14 22:00:15.141 Connecting to 1.2.3.4 port 22
. 2009-10-14 22:00:15.235 Server version: SSH-2.0-WS_FTP-SSH_7.0
. 2009-10-14 22:00:15.235 Using SSH protocol version 2
. 2009-10-14 22:00:15.235 We claim version: SSH-2.0-WinSCP_release_4.2.3
. 2009-10-14 22:00:15.282 Using Diffie-Hellman with standard group "group14"
. 2009-10-14 22:00:15.282 Doing Diffie-Hellman key exchange with hash SHA-1
. 2009-10-14 22:00:15.672 Host key fingerprint is:
. 2009-10-14 22:00:15.688 ssh-rsa 2048 <key>
. 2009-10-14 22:00:15.688 Initialised AES-256 CBC client->server encryption
. 2009-10-14 22:00:15.688 Initialised HMAC-SHA1 client->server MAC algorithm
. 2009-10-14 22:00:15.719 Initialised AES-256 CBC server->client encryption
. 2009-10-14 22:00:15.719 Initialised HMAC-SHA1 server->client MAC algorithm
! 2009-10-14 22:00:15.766 Using username "my_username".
. 2009-10-14 22:00:15.829 Prompt (6, SSH password, , &Password: )
. 2009-10-14 22:00:15.829 Using stored password.
. 2009-10-14 22:00:15.829 Sent password
. 2009-10-14 22:00:15.891 Access granted
. 2009-10-14 22:00:15.922 Opened channel for session
. 2009-10-14 22:00:16.172 Started a shell/command
. 2009-10-14 22:00:16.172 --------------------------------------------------------------------------
. 2009-10-14 22:00:16.172 Using SFTP protocol.
. 2009-10-14 22:00:16.188 Doing startup conversation with host.
> 2009-10-14 22:00:16.188 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2009-10-14 22:00:16.219 Type: SSH_FXP_VERSION, Size: 5, Number: -1
. 2009-10-14 22:00:16.219 SFTP version 4 negotiated.
. 2009-10-14 22:00:16.219 We will use UTF-8 strings when appropriate
. 2009-10-14 22:00:16.219 Getting current directory name.
. 2009-10-14 22:00:16.219 Getting real path for '.'
> 2009-10-14 22:00:16.219 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2009-10-14 22:00:16.282 Type: SSH_FXP_NAME, Size: 29, Number: 16
. 2009-10-14 22:00:16.282 Real path is '/my_username'
. 2009-10-14 22:00:16.282 Startup conversation with host finished.
. 2009-10-14 22:00:16.282 Listing directory "/my_username/Out".
> 2009-10-14 22:00:16.282 Type: SSH_FXP_OPENDIR, Size: 20, Number: 267
< 2009-10-14 22:00:16.344 Type: SSH_FXP_HANDLE, Size: 12, Number: 267
> 2009-10-14 22:00:16.344 Type: SSH_FXP_READDIR, Size: 12, Number: 524
< 2009-10-14 22:00:16.391 Type: SSH_FXP_NAME, Size: 382, Number: 524
> 2009-10-14 22:00:16.391 Type: SSH_FXP_READDIR, Size: 12, Number: 780
< 2009-10-14 22:00:16.438 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2009-10-14 22:00:16.438 Status/error code: 1
> 2009-10-14 22:00:16.438 Type: SSH_FXP_CLOSE, Size: 12, Number: 1028
. 2009-10-14 22:00:16.438 File: "/my_username/Out/my_username_HC_091013_Images.zip"
. 2009-10-14 22:00:16.438 Copying "/my_username/Out/my_username_HC_091013_Images.zip" to local directory started.
. 2009-10-14 22:00:16.438 Binary transfer mode selected.
. 2009-10-14 22:00:16.438 Checking existence of partially transfered file.
. 2009-10-14 22:00:16.438 Opening remote file.
> 2009-10-14 22:00:16.438 Type: SSH_FXP_OPEN, Size: 57, Number: 1283
< 2009-10-14 22:00:16.485 Type: SSH_FXP_STATUS, Size: 17, Number: 1028
. 2009-10-14 22:00:16.485 Discarding reserved response
< 2009-10-14 22:00:16.532 Type: SSH_FXP_HANDLE, Size: 12, Number: 1283
> 2009-10-14 22:00:16.532 Type: SSH_FXP_READ, Size: 24, Number: 1797
. 2009-10-14 22:00:23.954 No valid incoming packet found
. 2009-10-14 22:00:24.875 Connection was lost, asking what to do.
. 2009-10-14 22:00:24.875 Asking user:
. 2009-10-14 22:00:24.875 No valid incoming packet found ()
. 2009-10-14 22:05:25.250 --------------------------------------------------------------------------
. 2009-10-14 22:05:25.250 WinSCP Version 4.2.3 (Build 494) (OS 5.2.3790 Service Pack 2)
. 2009-10-14 22:05:25.250 Login time: Wednesday, October 14, 2009 10:05:25 PM
. 2009-10-14 22:05:25.250 --------------------------------------------------------------------------
. 2009-10-14 22:05:25.250 Session name: session_hostname.domain.com
. 2009-10-14 22:05:25.250 Host name: hostname.domain.com (Port: 22)
. 2009-10-14 22:05:25.250 User name: my_username (Password: Yes, Key file: No)
. 2009-10-14 22:05:25.250 Tunnel: No
. 2009-10-14 22:05:25.250 Transfer Protocol: SFTP (SCP)
. 2009-10-14 22:05:25.250 Ping type: -, Ping interval: 30 sec; Timeout: 15 sec
. 2009-10-14 22:05:25.250 Proxy: none
. 2009-10-14 22:05:25.250 SSH protocol version: 2; Compression: No
. 2009-10-14 22:05:25.250 Bypass authentication: No
. 2009-10-14 22:05:25.250 Try agent: Yes; Agent forwarding: No; TIS/CryptoCard: No; KI: Yes; GSSAPI: No
. 2009-10-14 22:05:25.250 Ciphers: aes,blowfish,3des,WARN,arcfour,des; Ssh2DES: No
. 2009-10-14 22:05:25.250 SSH Bugs: -,-,-,-,-,-,-,-,-
. 2009-10-14 22:05:25.250 SFTP Bugs: -,-
. 2009-10-14 22:05:25.250 Return code variable: Autodetect; Lookup user groups: Yes
. 2009-10-14 22:05:25.250 Shell: default, EOL: 0
. 2009-10-14 22:05:25.250 Clear aliases: Yes, Unset nat.vars: Yes, Resolve symlinks: Yes
. 2009-10-14 22:05:25.250 LS: ls -la, Ign LS warn: Yes, Scp1 Comp: No
. 2009-10-14 22:05:25.250 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2009-10-14 22:05:25.250 Cache directory changes: Yes, Permanent: Yes
. 2009-10-14 22:05:25.250 DST mode: 1
. 2009-10-14 22:05:25.250 --------------------------------------------------------------------------
. 2009-10-14 22:05:25.250 Looking up host "hostname.domain.com"
. 2009-10-14 22:05:25.250 Connecting to 1.2.3.4 port 22
. 2009-10-14 22:05:25.344 Server version: SSH-2.0-WS_FTP-SSH_7.0
. 2009-10-14 22:05:25.344 Using SSH protocol version 2
. 2009-10-14 22:05:25.344 We claim version: SSH-2.0-WinSCP_release_4.2.3
. 2009-10-14 22:05:25.391 Using Diffie-Hellman with standard group "group14"
. 2009-10-14 22:05:25.391 Doing Diffie-Hellman key exchange with hash SHA-1
. 2009-10-14 22:05:25.860 Host key fingerprint is:
. 2009-10-14 22:05:25.860 ssh-rsa 2048 <key>
. 2009-10-14 22:05:25.875 Initialised AES-256 CBC client->server encryption
. 2009-10-14 22:05:25.875 Initialised HMAC-SHA1 client->server MAC algorithm
. 2009-10-14 22:05:25.922 Initialised AES-256 CBC server->client encryption
. 2009-10-14 22:05:25.922 Initialised HMAC-SHA1 server->client MAC algorithm
! 2009-10-14 22:05:25.954 Using username "my_username".
. 2009-10-14 22:05:26.032 Prompt (6, SSH password, , &Password: )
. 2009-10-14 22:05:26.032 Using stored password.
. 2009-10-14 22:05:26.032 Sent password
. 2009-10-14 22:05:26.079 Access granted
. 2009-10-14 22:05:26.125 Opened channel for session
. 2009-10-14 22:05:26.375 Started a shell/command
. 2009-10-14 22:05:26.375 --------------------------------------------------------------------------
. 2009-10-14 22:05:26.375 Using SFTP protocol.
. 2009-10-14 22:05:26.375 Doing startup conversation with host.
> 2009-10-14 22:05:26.375 Type: SSH_FXP_INIT, Size: 5, Number: -1
< 2009-10-14 22:05:26.422 Type: SSH_FXP_VERSION, Size: 5, Number: -1
. 2009-10-14 22:05:26.422 SFTP version 4 negotiated.
. 2009-10-14 22:05:26.422 We will use UTF-8 strings when appropriate
. 2009-10-14 22:05:26.422 Getting current directory name.
. 2009-10-14 22:05:26.422 Getting real path for '.'
> 2009-10-14 22:05:26.422 Type: SSH_FXP_REALPATH, Size: 10, Number: 16
< 2009-10-14 22:05:26.469 Type: SSH_FXP_NAME, Size: 29, Number: 16
. 2009-10-14 22:05:26.469 Real path is '/my_username'
. 2009-10-14 22:05:26.469 Startup conversation with host finished.
. 2009-10-14 22:05:26.469 Listing directory "/my_username/Out".
> 2009-10-14 22:05:26.469 Type: SSH_FXP_OPENDIR, Size: 20, Number: 267
< 2009-10-14 22:05:26.532 Type: SSH_FXP_HANDLE, Size: 12, Number: 267
> 2009-10-14 22:05:26.532 Type: SSH_FXP_READDIR, Size: 12, Number: 524
< 2009-10-14 22:05:26.579 Type: SSH_FXP_NAME, Size: 382, Number: 524
> 2009-10-14 22:05:26.579 Type: SSH_FXP_READDIR, Size: 12, Number: 780
< 2009-10-14 22:05:26.625 Type: SSH_FXP_STATUS, Size: 28, Number: 780
< 2009-10-14 22:05:26.625 Status/error code: 1
> 2009-10-14 22:05:26.625 Type: SSH_FXP_CLOSE, Size: 12, Number: 1028
. 2009-10-14 22:05:26.625 File: "/my_username/Out/my_username_HC_091013_Images.zip"
. 2009-10-14 22:05:26.625 Copying "/my_username/Out/my_username_HC_091013_Images.zip" to local directory started.
. 2009-10-14 22:05:26.625 Binary transfer mode selected.
. 2009-10-14 22:05:26.625 Checking existence of partially transfered file.
. 2009-10-14 22:05:26.625 Resuming file transfer.
. 2009-10-14 22:05:26.625 Opening remote file.
> 2009-10-14 22:05:26.625 Type: SSH_FXP_OPEN, Size: 57, Number: 1283
< 2009-10-14 22:05:26.672 Type: SSH_FXP_STATUS, Size: 17, Number: 1028
. 2009-10-14 22:05:26.672 Discarding reserved response
< 2009-10-14 22:05:26.719 Type: SSH_FXP_HANDLE, Size: 12, Number: 1283
> 2009-10-14 22:05:26.719 Type: SSH_FXP_READ, Size: 24, Number: 1797
. 2009-10-14 22:05:27.922 No valid incoming packet found
. 2009-10-14 22:05:28.954 Connection was lost, asking what to do.
. 2009-10-14 22:05:28.954 Asking user:
. 2009-10-14 22:05:28.954 No valid incoming packet found ()


._End_.
Advertisements
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 24991
Location: Prague, Czechia
The log indicates that WinSCP received too large SSH packet (though it may actually mean just random corruption of data stream). So it is quite probable that it was WinSCP that closed the connection.
_________________
Martin Prikryl
ramius
[View user's profile]

Joined: 2009-10-15
Posts: 4
Location: DFW
prikryl wrote:
The log indicates that WinSCP received too large SSH packet (though it may actually mean just random corruption of data stream). So it is quite probable that it was WinSCP that closed the connection.


Martin,

Is there any way to get it to retry automatically itself without aborting? I seem to have set every option I can find -- yet when this situation appears, "Abort" seems to always be the answer.

Thank you
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 24991
Location: Prague, Czechia
I've sent you an email with link to a debug version.
_________________
Martin Prikryl
ramius
[View user's profile]

Joined: 2009-10-15
Posts: 4
Location: DFW
Martin,

I actually use the WinSCP.com executable during scripting operations. Is there a debug version of that file?

Or, should I be using winscp.exe like this? Is there any difference, i.e., is one way better than the other?

winscp.exe [/console] [/script=script_file]

Thanks,

-Matt
martin
[View user's profile]
Site Admin
Joined: 2002-12-10
Posts: 24991
Location: Prague, Czechia
winscp.com just execute winscp.exe. So use winscp.com you have and replace the .exe with the once I've sent you.
_________________
Martin Prikryl
ramius
[View user's profile]

Joined: 2009-10-15
Posts: 4
Location: DFW
Very good -- it's in place. I'll let you know when I have a failure next time.

Thanks!

-Matt
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