v4.1.7 FTP Problem - Uploading large files :: Support Forum

private_meta
Joined:: 2008-10-24
Posts:: 11

v4.1.7 FTP Problem - Uploading large files

2008-10-24 14:41

Hi,

I'm using WinSCP Version 4.1.7 and I have a problem with the FTP-Protocol.
I think I have narrowed the problem down, it should be a bug (sorry if it's not).

When I am transferring rather large files via FTP and the session disconnects / you receive a session timeout, but the session still finishes uploading, then WinSCP tells me it needs to reconnect, does so automatically but does not see that the file is already uploaded. This way, if there are big files that provoke a session timeout when uploading, it re-uploads (or tries to do so) over and over again or just receives an error (cancelling the upload) if the file permissions are not set on overwrite/delete.

It seems the program does not check if the file is already downloaded. This happened to me on different servers I wanted to upload to and does not happen if the Session Timeout is set rather high or turned off.

cheers,
meta

Reply with quote

JohnusJamusLowus
Joined:: 2008-10-24
Posts:: 2
Location:: Chicago, Illinois, USA

I am getting exactly the same behaviour since upgrading.

2008-10-24 16:13

I am not able to download large files to either of my servers with WinSCP since upgrading to 4.1.7.

Reply with quote

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

Re: v4.1.7 FTP Problem - Uploading large files

2008-10-26

private_meta wrote:

I'm using WinSCP Version 4.1.7 and I have a problem with the FTP-Protocol.
I think I have narrowed the problem down, it should be a bug (sorry if it's not).

Thanks. Can you post a log file?

Reply with quote

martin◆ Site Admin

Re: I am getting exactly the same behaviour since upgrading.

2008-10-26

JohnusJamusLowus wrote:

I am not able to download large files to either of my servers with WinSCP since upgrading to 4.1.7.

What happens? What protocol?

Reply with quote

private_meta
Joined:: 2008-10-24
Posts:: 11

Re: v4.1.7 FTP Problem - Uploading large files

2008-10-26 18:13

martin wrote:

private_meta wrote:

I'm using WinSCP Version 4.1.7 and I have a problem with the FTP-Protocol.
I think I have narrowed the problem down, it should be a bug (sorry if it's not).
Thanks. Can you post a log file?

I'll have to get one for that. I'll post it as soon as I get one.

cheers,
meta

Reply with quote

JohnusJamusLowus
Joined:: 2008-10-24
Posts:: 2
Location:: Chicago, Illinois, USA

Re: I am getting exactly the same behaviour since upgrading.

2008-10-27 16:22

martin wrote:

JohnusJamusLowus wrote:

I am not able to download large files to either of my servers with WinSCP since upgrading to 4.1.7.
What happens? What protocol?

I am getting a "Confirm" window with the text
"Host has not answered for more 15 seconds. Still waiting

Warning: Aborting this operation will close connection."

and an abort button. I am including a log below

******************************************

Creating directory "New folder".
Canonifying: "New folder"
Getting real path for '/home/jjlow/New folder'
Type: SSH_FXP_REALPATH, Size: 31, Number: 145168
Type: SSH_FXP_STATUS, Size: 24, Number: 144644
Discarding reserved response
Type: SSH_FXP_STATUS, Size: 29, Number: 145168
Status/error code: 2, Message: 145168, Server: No such file, Language:
Getting real path for '/home/jjlow/'
Type: SSH_FXP_REALPATH, Size: 21, Number: 145424
Type: SSH_FXP_NAME, Size: 43, Number: 145424
Real path is '/home/jjlow'
Canonified: "/home/jjlow/New folder"
Type: SSH_FXP_MKDIR, Size: 35, Number: 144910
Type: SSH_FXP_STATUS, Size: 24, Number: 144910
Status/error code: 0
Listing directory "/home/jjlow".
Type: SSH_FXP_OPENDIR, Size: 20, Number: 145675
Type: SSH_FXP_HANDLE, Size: 13, Number: 145675
Type: SSH_FXP_READDIR, Size: 13, Number: 145932
Type: SSH_FXP_NAME, Size: 12498, Number: 145932
Type: SSH_FXP_READDIR, Size: 13, Number: 146188
Type: SSH_FXP_NAME, Size: 10425, Number: 146188
Type: SSH_FXP_READDIR, Size: 13, Number: 146444
Type: SSH_FXP_STATUS, Size: 28, Number: 146444
Status/error code: 1
Type: SSH_FXP_CLOSE, Size: 13, Number: 146692
Creating directory "pedone".
Canonifying: "pedone"
Getting real path for '/home/jjlow/pedone'
Type: SSH_FXP_REALPATH, Size: 27, Number: 147216
Type: SSH_FXP_STATUS, Size: 24, Number: 146692
Discarding reserved response
Type: SSH_FXP_STATUS, Size: 29, Number: 147216
Status/error code: 2, Message: 147216, Server: No such file, Language:
Getting real path for '/home/jjlow/'
Type: SSH_FXP_REALPATH, Size: 21, Number: 147472
Type: SSH_FXP_NAME, Size: 43, Number: 147472
Real path is '/home/jjlow'
Canonified: "/home/jjlow/pedone"
Type: SSH_FXP_MKDIR, Size: 31, Number: 146958
Type: SSH_FXP_STATUS, Size: 24, Number: 146958
Status/error code: 0
Listing directory "/home/jjlow".
Type: SSH_FXP_OPENDIR, Size: 20, Number: 147723
Type: SSH_FXP_HANDLE, Size: 13, Number: 147723
Type: SSH_FXP_READDIR, Size: 13, Number: 147980
Type: SSH_FXP_NAME, Size: 12498, Number: 147980
Type: SSH_FXP_READDIR, Size: 13, Number: 148236
Type: SSH_FXP_NAME, Size: 10533, Number: 148236
Type: SSH_FXP_READDIR, Size: 13, Number: 148492
Type: SSH_FXP_STATUS, Size: 28, Number: 148492
Status/error code: 1
Type: SSH_FXP_CLOSE, Size: 13, Number: 148740
Changing directory to "/home/jjlow/pedone".
Getting real path for '/home/jjlow/pedone'
Type: SSH_FXP_REALPATH, Size: 27, Number: 149008
Type: SSH_FXP_STATUS, Size: 24, Number: 148740
Discarding reserved response
Type: SSH_FXP_NAME, Size: 57, Number: 149008
Real path is '/home/jjlow/pedone'
Trying to open directory "/home/jjlow/pedone".
Type: SSH_FXP_LSTAT, Size: 27, Number: 149255
Type: SSH_FXP_ATTRS, Size: 37, Number: 149255
Getting current directory name.
Listing directory "/home/jjlow/pedone".
Type: SSH_FXP_OPENDIR, Size: 27, Number: 149515
Type: SSH_FXP_HANDLE, Size: 13, Number: 149515
Type: SSH_FXP_READDIR, Size: 13, Number: 149772
Type: SSH_FXP_NAME, Size: 207, Number: 149772
Type: SSH_FXP_READDIR, Size: 13, Number: 150028
Type: SSH_FXP_STATUS, Size: 28, Number: 150028
Status/error code: 1
Type: SSH_FXP_CLOSE, Size: 13, Number: 150276
Copying 1 files/directories to remote directory "/home/jjlow/pedone/"
PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 01; Resume: S (102400); CalcS: Yes; Mask: *.*
TM: M; ClAr: No; CPS: 0; ExclM(No):
AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
File: "D:\Documents and Settings\E341104\Desktop\2x2x2 supercell.car"
Copying "D:\Documents and Settings\E341104\Desktop\2x2x2 supercell.car" to remote directory started.
Binary transfer mode selected.
Checking existence of file.
Type: SSH_FXP_LSTAT, Size: 47, Number: 150535
Type: SSH_FXP_STATUS, Size: 24, Number: 150276
Discarding reserved response
Type: SSH_FXP_STATUS, Size: 29, Number: 150535
Status/error code: 2
Checking existence of partially transfered file.
Type: SSH_FXP_LSTAT, Size: 56, Number: 150791
Type: SSH_FXP_STATUS, Size: 29, Number: 150791
Status/error code: 2
Opening remote file.
Type: SSH_FXP_OPEN, Size: 72, Number: 151043
Type: SSH_FXP_HANDLE, Size: 13, Number: 151043
Type: SSH_FXP_WRITE, Size: 4121, Number: 151558
55 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
Type: SSH_FXP_CLOSE, Size: 13, Number: 159236
Type: SSH_FXP_STATUS, Size: 24, Number: 158214
Type: SSH_FXP_STATUS, Size: 24, Number: 158470
Type: SSH_FXP_STATUS, Size: 24, Number: 158726
Type: SSH_FXP_STATUS, Size: 24, Number: 158982
Type: SSH_FXP_STATUS, Size: 24, Number: 159236
Status/error code: 0
Type: SSH_FXP_RENAME, Size: 98, Number: 159506
Type: SSH_FXP_STATUS, Size: 24, Number: 159506
Status/error code: 0
Type: SSH_FXP_SETSTAT, Size: 59, Number: 151305
Type: SSH_FXP_STATUS, Size: 24, Number: 151305
Status/error code: 0
Listing directory "/home/jjlow/pedone".
Type: SSH_FXP_OPENDIR, Size: 27, Number: 159755
Type: SSH_FXP_HANDLE, Size: 13, Number: 159755
Type: SSH_FXP_READDIR, Size: 13, Number: 160012
Type: SSH_FXP_NAME, Size: 341, Number: 160012
Type: SSH_FXP_READDIR, Size: 13, Number: 160268
Type: SSH_FXP_STATUS, Size: 28, Number: 160268
Status/error code: 1
Type: SSH_FXP_CLOSE, Size: 13, Number: 160516
Initiating key re-exchange (timeout)
Doing Diffie-Hellman group exchange
Doing Diffie-Hellman key exchange with hash SHA-1
Initialised AES-256 SDCTR client->server encryption
Initialised HMAC-SHA1 client->server MAC algorithm
Initialised AES-256 SDCTR server->client encryption
Initialised HMAC-SHA1 server->client MAC algorithm
Copying 1 files/directories to remote directory "/home/jjlow/pedone/"
PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 01; Resume: S (102400); CalcS: Yes; Mask: *.*
TM: M; ClAr: No; CPS: 0; ExclM(No):
AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
File: "D:\Documents and Settings\E341104\Desktop\2x2x2 supercell.car"
Copying "D:\Documents and Settings\E341104\Desktop\2x2x2 supercell.car" to remote directory started.
Binary transfer mode selected.
Checking existence of file.
Type: SSH_FXP_LSTAT, Size: 47, Number: 160775
Type: SSH_FXP_STATUS, Size: 24, Number: 160516
Discarding reserved response
Type: SSH_FXP_ATTRS, Size: 37, Number: 160775
Checking existence of partially transfered file.
Type: SSH_FXP_LSTAT, Size: 56, Number: 161031
Type: SSH_FXP_STATUS, Size: 29, Number: 161031
Status/error code: 2
Asking user:
Remote file '2x2x2 supercell.car' already exists. Overwrite?

New: 618250 bytes, 10/27/2008 10:06:34 AM
Existing: 441008 bytes, 10/27/2008 9:58:26 AM ()
Opening remote file.
Type: SSH_FXP_OPEN, Size: 72, Number: 161283
Type: SSH_FXP_HANDLE, Size: 13, Number: 161283
Type: SSH_FXP_WRITE, Size: 4121, Number: 161798
Waiting for dispatching send buffer timed out, asking user what to do.
Asking user:
Host has not answered for 15 seconds.

Wait for another 15 seconds? ()
84 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
Type: SSH_FXP_CLOSE, Size: 13, Number: 173828
Type: SSH_FXP_STATUS, Size: 24, Number: 171526
Type: SSH_FXP_STATUS, Size: 24, Number: 171782
Type: SSH_FXP_STATUS, Size: 24, Number: 172038
Type: SSH_FXP_STATUS, Size: 24, Number: 172294
Type: SSH_FXP_STATUS, Size: 24, Number: 172550
Type: SSH_FXP_STATUS, Size: 24, Number: 172806
Type: SSH_FXP_STATUS, Size: 24, Number: 173062
Type: SSH_FXP_STATUS, Size: 24, Number: 173318
Type: SSH_FXP_STATUS, Size: 24, Number: 173574
Type: SSH_FXP_STATUS, Size: 24, Number: 173828
Status/error code: 0
Type: SSH_FXP_REMOVE, Size: 47, Number: 174093
Type: SSH_FXP_STATUS, Size: 24, Number: 174093
Status/error code: 0
Type: SSH_FXP_RENAME, Size: 98, Number: 174354
Type: SSH_FXP_STATUS, Size: 24, Number: 174354
Status/error code: 0
Type: SSH_FXP_SETSTAT, Size: 63, Number: 161545
Type: SSH_FXP_STATUS, Size: 24, Number: 161545
Status/error code: 0
Listing directory "/home/jjlow/pedone".
Type: SSH_FXP_OPENDIR, Size: 27, Number: 174603
Type: SSH_FXP_HANDLE, Size: 13, Number: 174603
Type: SSH_FXP_READDIR, Size: 13, Number: 174860
Type: SSH_FXP_NAME, Size: 341, Number: 174860
Type: SSH_FXP_READDIR, Size: 13, Number: 175116
Type: SSH_FXP_STATUS, Size: 28, Number: 175116
Status/error code: 1
Type: SSH_FXP_CLOSE, Size: 13, Number: 175364
Changing directory to "JohnusJamusLowus".
Getting real path for '/home/jjlow/pedone/JohnusJamusLowus'
Type: SSH_FXP_REALPATH, Size: 44, Number: 175632
Type: SSH_FXP_STATUS, Size: 24, Number: 175364
Discarding reserved response
Type: SSH_FXP_STATUS, Size: 29, Number: 175632
Status/error code: 2, Message: 175632, Server: No such file, Language:
(ECommand) Error changing directory to 'JohnusJamusLowus'.
Cannot get real path for '/home/jjlow/pedone/JohnusJamusLowus'.
No such file or directory.
Error code: 2
Error message from server: No such file
Request code: 16
Copying 1 files/directories to remote directory "/home/jjlow/pedone/"
PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 01; Resume: S (102400); CalcS: Yes; Mask: *.*
TM: M; ClAr: No; CPS: 0; ExclM(No):
AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
File: "D:\Documents and Settings\E341104\Desktop\2x2x2 supercell.car"
Copying "D:\Documents and Settings\E341104\Desktop\2x2x2 supercell.car" to remote directory started.
Binary transfer mode selected.
Checking existence of file.
Type: SSH_FXP_LSTAT, Size: 47, Number: 175879
Type: SSH_FXP_ATTRS, Size: 37, Number: 175879
Checking existence of partially transfered file.
Type: SSH_FXP_LSTAT, Size: 56, Number: 176135
Type: SSH_FXP_STATUS, Size: 29, Number: 176135
Status/error code: 2
Asking user:
Remote file '2x2x2 supercell.car' already exists. Overwrite?

New: 618250 bytes, 10/27/2008 10:06:34 AM
Existing: 618250 bytes, 10/27/2008 10:06:34 AM ()
Checking existence of new file.
Type: SSH_FXP_LSTAT, Size: 32, Number: 176391
Type: SSH_FXP_STATUS, Size: 29, Number: 176391
Status/error code: 2
Opening remote file.
Type: SSH_FXP_OPEN, Size: 57, Number: 176643
Type: SSH_FXP_HANDLE, Size: 13, Number: 176643
Type: SSH_FXP_WRITE, Size: 4121, Number: 177158
40 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
Type: SSH_FXP_CLOSE, Size: 13, Number: 182788
Waiting for data timed out, asking user what to do.
Asking user:
Host has not answered for 15 seconds.

Wait for another 15 seconds? ()
Attempt to close connection due to fatal exception:
Terminated by user.
Closing connection.
Sending special code: 12
Sent EOF message
Fatal error while disposing the SFTP queue.
Connection was lost, asking what to do.
Asking user:
Terminated by user. ()
Looking up host "sgics1"
Connecting to 138.90.50.86 port 22
Server version: SSH-2.0-OpenSSH_4.3
We claim version: SSH-2.0-WinSCP_release_4.1.7
SSPI: acquired credentials for: e341104@GLOBAL.DS.HONEYWELL.COM
Constructed service principal name 'host/sgics1'
GSSKEX disabled: The specified target is unknown or unreachable
Using SSH protocol version 2
Doing Diffie-Hellman group exchange
Doing Diffie-Hellman key exchange with hash SHA-1
Host key fingerprint is:
ssh-rsa 2048 5c:6b:25:14:25:70:80:6d:7d:da:b4:8a:93:62:20:91
Initialised AES-256 SDCTR client->server encryption
Initialised HMAC-SHA1 client->server MAC algorithm
Initialised AES-256 SDCTR server->client encryption
Initialised HMAC-SHA1 server->client MAC algorithm
Using username "jjlow".
Prompt (6, SSH password, , &Password: )
Using stored password.
Sent password
Access granted
Opened channel for session
Started a shell/command
Doing startup conversation with host.
Type: SSH_FXP_INIT, Size: 5, Number: -1
Type: SSH_FXP_VERSION, Size: 5, Number: -1
SFTP version 3 negotiated.
We believe the server has signed timestamps bug
We will use UTF-8 strings for status messages only
Limiting packet size to OpenSSH sftp-server limit of 262148 bytes
Changing directory to "/home/jjlow/pedone".
Getting real path for '/home/jjlow/pedone'
Type: SSH_FXP_REALPATH, Size: 27, Number: 183056
Type: SSH_FXP_NAME, Size: 57, Number: 183056
Real path is '/home/jjlow/pedone'
Trying to open directory "/home/jjlow/pedone".
Type: SSH_FXP_LSTAT, Size: 27, Number: 183303
Type: SSH_FXP_ATTRS, Size: 37, Number: 183303
Getting current directory name.
Startup conversation with host finished.
File: "D:\Documents and Settings\E341104\Desktop\2x2x2 supercell.car"
Copying "D:\Documents and Settings\E341104\Desktop\2x2x2 supercell.car" to remote directory started.
Binary transfer mode selected.
Checking existence of file.
Type: SSH_FXP_LSTAT, Size: 47, Number: 183559
Type: SSH_FXP_ATTRS, Size: 37, Number: 183559
Checking existence of partially transfered file.
Type: SSH_FXP_LSTAT, Size: 56, Number: 183815
Type: SSH_FXP_STATUS, Size: 29, Number: 183815
Status/error code: 2
Opening remote file.
Type: SSH_FXP_OPEN, Size: 72, Number: 184067
Type: SSH_FXP_HANDLE, Size: 13, Number: 184067
Type: SSH_FXP_WRITE, Size: 4121, Number: 184582
37 skipped SSH_FXP_WRITE, SSH_FXP_READ, SSH_FXP_DATA and SSH_FXP_STATUS packets.
Type: SSH_FXP_CLOSE, Size: 13, Number: 189956
Type: SSH_FXP_STATUS, Size: 24, Number: 188934
Type: SSH_FXP_STATUS, Size: 24, Number: 189190
Type: SSH_FXP_STATUS, Size: 24, Number: 189446
Type: SSH_FXP_STATUS, Size: 24, Number: 189702
Type: SSH_FXP_STATUS, Size: 24, Number: 189956
Status/error code: 0
Type: SSH_FXP_REMOVE, Size: 47, Number: 190221
Type: SSH_FXP_STATUS, Size: 24, Number: 190221
Status/error code: 0
Type: SSH_FXP_RENAME, Size: 98, Number: 190482
Type: SSH_FXP_STATUS, Size: 24, Number: 190482
Status/error code: 0
Type: SSH_FXP_SETSTAT, Size: 63, Number: 184329
Type: SSH_FXP_STATUS, Size: 24, Number: 184329
Status/error code: 0
Listing directory "/home/jjlow/pedone".
Type: SSH_FXP_OPENDIR, Size: 27, Number: 190731
Type: SSH_FXP_HANDLE, Size: 13, Number: 190731
Type: SSH_FXP_READDIR, Size: 13, Number: 190988
Type: SSH_FXP_NAME, Size: 463, Number: 190988
Type: SSH_FXP_READDIR, Size: 13, Number: 191244
Type: SSH_FXP_STATUS, Size: 28, Number: 191244
Status/error code: 1
Type: SSH_FXP_CLOSE, Size: 13, Number: 191492

Reply with quote

private_meta
Joined:: 2008-10-24
Posts:: 11

Re: v4.1.7 FTP Problem - Uploading large files

2008-10-27 19:57

martin wrote:

private_meta wrote:

I'm using WinSCP Version 4.1.7 and I have a problem with the FTP-Protocol.
I think I have narrowed the problem down, it should be a bug (sorry if it's not).
Thanks. Can you post a log file?

Hi again.

I fetched a log file. I stripped it from some personal information. As far as I can see, the information necessary should be in there. If it's not enough, tell me again :-)

cheers,
meta

. 2008-10-27 19:34:29.203 Got reply 1 to the command 4
. 2008-10-27 19:34:29.203 File: "PATH_AND_FILENAME_OF_BIG_FILE"
. 2008-10-27 19:34:29.218 Copying "PATH_AND_FILENAME_OF_BIG_FILE" to remote directory started.
. 2008-10-27 19:34:29.218 Binary transfer mode selected.
. 2008-10-27 19:34:29.218 Starting upload of PATH_AND_FILENAME_OF_BIG_FILE
> 2008-10-27 19:34:29.218 TYPE I
< 2008-10-27 19:34:29.218 200 Type set to I.
> 2008-10-27 19:34:29.218 PORT 192,168,0,120,4,90
< 2008-10-27 19:34:29.234 200 Port command successful.
> 2008-10-27 19:34:29.234 STOR FILENAME_OF_BIG_FILE
< 2008-10-27 19:34:29.234 150 Opening data connection for FILENAME_OF_BIG_FILE
. 2008-10-27 19:34:29.375 Session upkeep
...--------------------- repeated session upkeep for 377 lines ---------------------...
. 2008-10-27 19:37:38.796 Session upkeep
. 2008-10-27 19:37:53.921 Timeout detected.
. 2008-10-27 19:37:53.937 Copying files to remote side failed.
. 2008-10-27 19:37:53.937 Got reply 1004 to the command 4
. 2008-10-27 19:37:53.968 Connection was lost, asking what to do.
. 2008-10-27 19:37:53.968 Asking user:
. 2008-10-27 19:37:53.968 Lost connection. ("Timeout detected.","Copying files to remote side failed.","Opening data connection for FILENAME_OF_BIG_FILE")
. 2008-10-27 19:37:59.265 Connecting to SERVERNAME ...
. 2008-10-27 19:38:00.359 Connected with SERVERNAME. Waiting for welcome message...
< 2008-10-27 19:38:00.359 220 Gene6 FTP Server v3.6.0  (Build 23) ready...
> 2008-10-27 19:38:00.359 USER USERNAME
< 2008-10-27 19:38:00.359 331 Password required for USERNAME.
> 2008-10-27 19:38:00.359 PASS *******
< 2008-10-27 19:38:00.375 230 User USERNAME logged in.
> 2008-10-27 19:38:00.375 SYST
< 2008-10-27 19:38:00.375 215 UNIX Type: L8
> 2008-10-27 19:38:00.375 FEAT
< 2008-10-27 19:38:00.375 211-Extensions supported:
< 2008-10-27 19:38:00.375  AUTH TLS
< 2008-10-27 19:38:00.375  CCC
< 2008-10-27 19:38:00.375  CLNT
< 2008-10-27 19:38:00.375  CPSV
< 2008-10-27 19:38:00.375  EPRT
< 2008-10-27 19:38:00.375  EPSV
< 2008-10-27 19:38:00.375  MDTM
< 2008-10-27 19:38:00.375  MLST type*;size*;created;modify*;
< 2008-10-27 19:38:00.375  PASV
< 2008-10-27 19:38:00.375  PBSZ
< 2008-10-27 19:38:00.375  PROT
< 2008-10-27 19:38:00.375  REST STREAM
< 2008-10-27 19:38:00.375  SIZE
< 2008-10-27 19:38:00.375  SSCN
< 2008-10-27 19:38:00.375  TVFS
< 2008-10-27 19:38:00.375  UTF8
< 2008-10-27 19:38:00.375  XCRC "filename" SP EP
< 2008-10-27 19:38:00.375  XMD5 "filename" SP EP
< 2008-10-27 19:38:00.375 211 End.
> 2008-10-27 19:38:00.375 CLNT FileZilla
< 2008-10-27 19:38:00.390 200 Noted.
> 2008-10-27 19:38:00.390 OPTS UTF8 ON
< 2008-10-27 19:38:00.390 200 UTF8 OPTS ON
. 2008-10-27 19:38:00.390 Connected
. 2008-10-27 19:38:00.390 Got reply 1 to the command 1
. 2008-10-27 19:38:00.421 Doing startup conversation with host.
. 2008-10-27 19:38:00.421 Changing directory to "REMOTE_FOLDER".
. 2008-10-27 19:38:00.421 Synchronizing current directory "REMOTE_FOLDER".
> 2008-10-27 19:38:00.421 CWD REMOTE_FOLDER
< 2008-10-27 19:38:00.421 250 CWD command successful. "REMOTE_FOLDER" is current directory.
. 2008-10-27 19:38:00.421 Got reply 1 to the command 16
> 2008-10-27 19:38:00.421 CWD REMOTE_FOLDER
< 2008-10-27 19:38:00.421 250 CWD command successful. "REMOTE_FOLDER" is current directory.
. 2008-10-27 19:38:00.421 Got reply 1 to the command 16
. 2008-10-27 19:38:00.421 Getting current directory name.
> 2008-10-27 19:38:00.421 PWD
< 2008-10-27 19:38:00.421 257 "REMOTE_FOLDER" is current directory.
. 2008-10-27 19:38:00.421 Got reply 1 to the command 16
. 2008-10-27 19:38:00.421 Startup conversation with host finished.
. 2008-10-27 19:38:00.453 Session upkeep
. 2008-10-27 19:38:00.453 File: "PATH_AND_FILENAME_OF_BIG_FILE"
. 2008-10-27 19:38:00.453 Copying "PATH_AND_FILENAME_OF_BIG_FILE" to remote directory started.
. 2008-10-27 19:38:00.453 Binary transfer mode selected.
. 2008-10-27 19:38:00.453 Starting upload of PATH_AND_FILENAME_OF_BIG_FILE
> 2008-10-27 19:38:00.453 CWD REMOTE_FOLDER/UPLOAD_FOLDER/
< 2008-10-27 19:38:00.453 250 CWD command successful. "REMOTE_FOLDER/UPLOAD_FOLDER" is current directory.
> 2008-10-27 19:38:00.453 PWD
< 2008-10-27 19:38:00.453 257 "REMOTE_FOLDER/UPLOAD_FOLDER" is current directory.
> 2008-10-27 19:38:00.468 TYPE A
< 2008-10-27 19:38:00.468 200 Type set to A.
> 2008-10-27 19:38:00.484 PORT 192,168,0,120,4,95
< 2008-10-27 19:38:00.484 200 Port command successful.
> 2008-10-27 19:38:00.484 LIST -a
< 2008-10-27 19:38:00.484 150 Opening data connection for directory list.
. 2008-10-27 19:38:00.484 drw-rw-rw-   1 user     group           0 Oct 27 19:34 .
. 2008-10-27 19:38:00.484 drw-rw-rw-   1 user     group           0 Oct 27 19:34 ..
. 2008-10-27 19:38:00.484 -rw-rw-rw-   1 user     group   1073739776 Oct 27 19:37 FILENAME_OF_BIG_FILE
< 2008-10-27 19:38:00.484 226 Transfer ok.
> 2008-10-27 19:38:00.484 TYPE I
< 2008-10-27 19:38:00.484 200 Type set to I.
> 2008-10-27 19:38:00.500 PORT 192,168,0,120,4,97
< 2008-10-27 19:38:00.500 200 Port command successful.
> 2008-10-27 19:38:00.500 STOR FILENAME_OF_BIG_FILE
< 2008-10-27 19:38:00.562 150 Opening data connection for FILENAME_OF_BIG_FILE
. 2008-10-27 19:38:00.921 Session upkeep
...--------------------- repeating session upkeep for 385 lines ---------------------...
. 2008-10-27 19:41:14.437 Session upkeep
. 2008-10-27 19:41:29.906 Timeout detected.
. 2008-10-27 19:41:29.906 Copying files to remote side failed.
. 2008-10-27 19:41:29.906 Got reply 1004 to the command 4
. 2008-10-27 19:41:29.906 Connection was lost, asking what to do.
. 2008-10-27 19:41:29.906 Asking user:
. 2008-10-27 19:41:29.906 Lost connection. ("Timeout detected.","Copying files to remote side failed.","Opening data connection for FILENAME_OF_BIG_FILE")
. 2008-10-27 19:41:34.921 Connecting to SERVERNAME ...
. 2008-10-27 19:41:35.109 Connected with SERVERNAME. Waiting for welcome message...
< 2008-10-27 19:41:35.109 220 Gene6 FTP Server v3.6.0  (Build 23) ready...
> 2008-10-27 19:41:35.109 USER USERNAME
< 2008-10-27 19:41:35.109 331 Password required for USERNAME.
> 2008-10-27 19:41:35.109 PASS *******
< 2008-10-27 19:41:35.125 230 User USERNAME logged in.
> 2008-10-27 19:41:35.125 SYST
< 2008-10-27 19:41:35.125 215 UNIX Type: L8
> 2008-10-27 19:41:35.125 FEAT
< 2008-10-27 19:41:35.125 211-Extensions supported:
< 2008-10-27 19:41:35.125  AUTH TLS
< 2008-10-27 19:41:35.125  CCC
< 2008-10-27 19:41:35.125  CLNT
< 2008-10-27 19:41:35.125  CPSV
< 2008-10-27 19:41:35.125  EPRT
< 2008-10-27 19:41:35.125  EPSV
< 2008-10-27 19:41:35.125  MDTM
< 2008-10-27 19:41:35.125  MLST type*;size*;created;modify*;
< 2008-10-27 19:41:35.125  PASV
< 2008-10-27 19:41:35.125  PBSZ
< 2008-10-27 19:41:35.125  PROT
< 2008-10-27 19:41:35.125  REST STREAM
< 2008-10-27 19:41:35.125  SIZE
< 2008-10-27 19:41:35.125  SSCN
< 2008-10-27 19:41:35.125  TVFS
< 2008-10-27 19:41:35.125  UTF8
< 2008-10-27 19:41:35.125  XCRC "filename" SP EP
< 2008-10-27 19:41:35.125  XMD5 "filename" SP EP
< 2008-10-27 19:41:35.125 211 End.
> 2008-10-27 19:41:35.125 CLNT FileZilla
< 2008-10-27 19:41:35.125 200 Noted.
> 2008-10-27 19:41:35.125 OPTS UTF8 ON
< 2008-10-27 19:41:35.125 200 UTF8 OPTS ON
. 2008-10-27 19:41:35.125 Connected
. 2008-10-27 19:41:35.125 Got reply 1 to the command 1
. 2008-10-27 19:41:35.125 Doing startup conversation with host.
. 2008-10-27 19:41:35.140 Changing directory to "REMOTE_FOLDER".
. 2008-10-27 19:41:35.140 Synchronizing current directory "REMOTE_FOLDER".
> 2008-10-27 19:41:35.140 CWD REMOTE_FOLDER
< 2008-10-27 19:41:35.140 250 CWD command successful. "REMOTE_FOLDER" is current directory.
. 2008-10-27 19:41:35.140 Got reply 1 to the command 16
> 2008-10-27 19:41:35.140 CWD REMOTE_FOLDER
< 2008-10-27 19:41:35.140 250 CWD command successful. "REMOTE_FOLDER" is current directory.
. 2008-10-27 19:41:35.140 Got reply 1 to the command 16
. 2008-10-27 19:41:35.140 Getting current directory name.
> 2008-10-27 19:41:35.140 PWD
< 2008-10-27 19:41:35.140 257 "REMOTE_FOLDER" is current directory.
. 2008-10-27 19:41:35.140 Got reply 1 to the command 16
. 2008-10-27 19:41:35.140 Startup conversation with host finished.
. 2008-10-27 19:41:35.156 File: "PATH_AND_FILENAME_OF_BIG_FILE"
. 2008-10-27 19:41:35.156 Copying "PATH_AND_FILENAME_OF_BIG_FILE" to remote directory started.
. 2008-10-27 19:41:35.156 Binary transfer mode selected.
. 2008-10-27 19:41:35.156 Starting upload of PATH_AND_FILENAME_OF_BIG_FILE
> 2008-10-27 19:41:35.156 CWD REMOTE_FOLDER/UPLOAD_FOLDER/
< 2008-10-27 19:41:35.171 250 CWD command successful. "REMOTE_FOLDER/UPLOAD_FOLDER" is current directory.
> 2008-10-27 19:41:35.171 PWD
< 2008-10-27 19:41:35.171 257 "REMOTE_FOLDER/UPLOAD_FOLDER" is current directory.
> 2008-10-27 19:41:35.171 TYPE A
< 2008-10-27 19:41:35.171 200 Type set to A.
> 2008-10-27 19:41:35.171 PORT 192,168,0,120,4,102
< 2008-10-27 19:41:35.171 200 Port command successful.
> 2008-10-27 19:41:35.171 LIST -a
< 2008-10-27 19:41:35.171 150 Opening data connection for directory list.
. 2008-10-27 19:41:35.171 drw-rw-rw-   1 user     group           0 Oct 27 19:34 .
. 2008-10-27 19:41:35.171 drw-rw-rw-   1 user     group           0 Oct 27 19:34 ..
. 2008-10-27 19:41:35.171 -rw-rw-rw-   1 user     group   1073739776 Oct 27 19:41 FILENAME_OF_BIG_FILE
< 2008-10-27 19:41:35.187 226 Transfer ok.
> 2008-10-27 19:41:35.187 TYPE I
< 2008-10-27 19:41:35.187 200 Type set to I.
> 2008-10-27 19:41:35.187 PORT 192,168,0,120,4,104
< 2008-10-27 19:41:35.187 200 Port command successful.
> 2008-10-27 19:41:35.187 STOR FILENAME_OF_BIG_FILE
< 2008-10-27 19:41:35.234 150 Opening data connection for FILENAME_OF_BIG_FILE
. 2008-10-27 19:41:35.375 Session upkeep
...--------------------- repeating session upkeep 368 lines ---------------------...
. 2008-10-27 19:44:40.296 Session upkeep
. 2008-10-27 19:44:55.906 Timeout detected.
. 2008-10-27 19:44:55.906 Copying files to remote side failed.
. 2008-10-27 19:44:55.906 Got reply 1004 to the command 4
. 2008-10-27 19:44:55.906 Connection was lost, asking what to do.
. 2008-10-27 19:44:55.906 Asking user:
. 2008-10-27 19:44:55.906 Lost connection. ("Timeout detected.","Copying files to remote side failed.","Opening data connection for FILENAME_OF_BIG_FILE")
. 2008-10-27 19:44:58.312 Connection was lost, asking what to do.
. 2008-10-27 19:44:58.312 Asking user:
. 2008-10-27 19:44:58.312 Lost connection. ("Timeout detected.","Copying files to remote side failed.","Opening data connection for FILENAME_OF_BIG_FILE")
* 2008-10-27 19:44:59.187 (ESshFatal) Lost connection.
* 2008-10-27 19:44:59.187 Timeout detected.
* 2008-10-27 19:44:59.187 Copying files to remote side failed.
* 2008-10-27 19:44:59.187 Opening data connection for FILENAME_OF_BIG_FILE
* 2008-10-27 19:44:59.187 Copying files to remote side failed.

Reply with quote

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

Re: v4.1.7 FTP Problem - Uploading large files

2008-10-29

private_meta wrote:

I fetched a log file. I stripped it from some personal information. As far as I can see, the information necessary should be in there. If it's not enough, tell me again :-)

Thanks.
May I have a test account on your server?

Reply with quote

private_meta
Joined:: 2008-10-24
Posts:: 11

Re: v4.1.7 FTP Problem - Uploading large files

2008-10-29 09:34

martin wrote:

private_meta wrote:

I fetched a log file. I stripped it from some personal information. As far as I can see, the information necessary should be in there. If it's not enough, tell me again :-)
Thanks.
May I have a test account on your server?

Sorry, but that's not possible (technically).

The FTP-Server I have tried it on runs the GeneFTP 6 Software, maybe there is an open server somewhere that uses it, but it might be possible to get the same problem on any server software if the timeout is very small.

Reply with quote

martin◆ Site Admin

Re: v4.1.7 FTP Problem - Uploading large files

2008-10-31

OK. Would setting longer timeout resolve the issue?

Reply with quote

private_meta
Joined:: 2008-10-24
Posts:: 11

Re: v4.1.7 FTP Problem - Uploading large files

2008-10-31 09:39

martin wrote:

OK. Would setting longer timeout resolve the issue?

I didn't think of that. I don't have time right now to test it, but I'll test it as soon as possible and come back here with the result.

Reply with quote

martin◆ Site Admin

Re: v4.1.7 FTP Problem - Uploading large files

2008-10-31

private_meta wrote:

I didn't think of that. I don't have time right now to test it, but I'll test it as soon as possible and come back here with the result.

Thanks in advance.

Reply with quote

private_meta
Joined:: 2008-10-24
Posts:: 11

Re: v4.1.7 FTP Problem - Uploading large files

2009-01-19 19:21

martin wrote:

private_meta wrote:

I didn't think of that. I don't have time right now to test it, but I'll test it as soon as possible and come back here with the result.
Thanks in advance.

Sorry in Advance, I thought I had answered to this post already.

I tried to play with the timeout settings, but alas it did not work. However, I found a workaround (although I don't like it very much). I installed FileZilla because I had to upload a lot of large files. The initial start of the program told me that FileZilla, in combination with the started service "Application Layer Gateway" (alg.exe), leads to problems with large files. I deactivated the service and tried to upload the large files with WinSCP again. Now, the upload seems to work perfectly.
It seems the problem lies with the Application Layer Gateway itself. I don't want to keep it deactivated forever, but it is a small workaround and maybe you can do something with that information.

Cheers,
meta

Reply with quote