File modified after FTP transfer problem :: Support Forum

labsy
Joined:: 2012-02-16
Posts:: 6
Location:: Slovenia

File modified after FTP transfer problem

2012-12-19 17:13

Hi all,

does anybody have an idea, why would an plain text .XML file get modified during transfer to FTP server?

I have made a script to connect to FTP server with options:

option batch abort 
option confirm off
option exclude "*.ftpquota"
open FTPConnection
put -resume transfer.lock ./
put -resume catalog.xml ./
rm transfer.lock
close
exit

This file catalog.xml is approx. 2.2 MB in size.
After it is transfered to FTP server, the last 20 or so lines in XML file are DUPLICATED and appended to the end of the file. So transfered file does not close XML syntax properly, and is not usable.

I was using 5.0.x version, but I have upgraded to latest - but still the same problem.
Tried to connect with ASCII or BINARY mode - still the same problem.
BUT when I transfer manually with WinSCP...well, in this case file is OK.

Any idea why?

** EDITED **
Maybe I found the solution!
I removed "-resume" option from .INI file, and first tests are promising.

Reply with quote

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

Re: File modified after FTP transfer problem

2012-12-26

Please attach a full log file showing the problem (=with -resume).

To generate log file, use /log=path_to_log_file command-line argument. Submit the log with your post as an attachment. Note that passwords and passphrases not stored in the log. You may want to remove other data you consider sensitive though, such as host names, IP addresses, account names or file names (unless they are relevant to the problem). If you do not want to post the log publicly, you may email it to me. You will find my address (if you log in) in my forum profile. Please include link back to this topic in your email. Also note in this topic that you have emailed the log.

Reply with quote

Guest

Re: File modified after FTP transfer problem

2012-12-28 11:17

Hi Martin,

thank you for reply!
Here are the LOGs, one with -resume option, and another without it. Hope it will come handy for debugging:

WITHOUT -resume:

. 2012-12-28 10:48:25.155 --------------------------------------------------------------------------
. 2012-12-28 10:48:25.155 WinSCP Version 5.1.2 (Build 2816) (OS 6.1.7601 Service Pack 1)
. 2012-12-28 10:48:25.155 Configuration: HKEY_CURRENT_USER\Software\Martin Prikryl\WinSCP 2\
. 2012-12-28 10:48:25.155 Local account: SERVER1\servis
. 2012-12-28 10:48:25.155 Working directory: C:\_export_Web_
. 2012-12-28 10:48:25.155 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" /console=512 /consoleinstance=_3704_120 "/script=upload.ini" 
. 2012-12-28 10:48:25.155 Time zone: Current: GMT+1, Standard: GMT+1, DST: GMT+2, DST Start: 25.3.2012, DST End: 28.10.2012
. 2012-12-28 10:48:25.155 Login time: 28. december 2012 10:48:25
. 2012-12-28 10:48:25.155 --------------------------------------------------------------------------
. 2012-12-28 10:48:25.156 Session name: vargalant (Stored session)
. 2012-12-28 10:48:25.156 Host name: ftp.company.com (Port: 21)
. 2012-12-28 10:48:25.156 User name: xml@company.com (Password: Yes, Key file: No)
. 2012-12-28 10:48:25.156 Tunnel: No
. 2012-12-28 10:48:25.156 Transfer Protocol: FTP
. 2012-12-28 10:48:25.156 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2012-12-28 10:48:25.156 Proxy: none
. 2012-12-28 10:48:25.156 FTP: FTPS: None; Passive: Yes [Force IP: A]
. 2012-12-28 10:48:25.156 Local directory: C:\_export_Web_, Remote directory: /, Update: No, Cache: Yes
. 2012-12-28 10:48:25.156 Cache directory changes: Yes, Permanent: Yes
. 2012-12-28 10:48:25.156 DST mode: 1
. 2012-12-28 10:48:25.156 --------------------------------------------------------------------------
. 2012-12-28 10:48:25.158 Connecting to ftp.company.com ...
. 2012-12-28 10:48:25.172 Connected with ftp.company.com. Waiting for welcome message...
< 2012-12-28 10:48:25.182 220 ProFTPD 1.3.0 Server ready.
> 2012-12-28 10:48:25.183 USER xml@company.com
< 2012-12-28 10:48:25.198 331 Password required for xml@company.com.
> 2012-12-28 10:48:25.198 PASS ********
< 2012-12-28 10:48:27.386 230 User xml@company.com logged in.
> 2012-12-28 10:48:27.386 SYST
< 2012-12-28 10:48:27.397 215 UNIX Type: L8
> 2012-12-28 10:48:27.397 FEAT
< 2012-12-28 10:48:27.407 211-Features:
< 2012-12-28 10:48:27.408  MDTM
< 2012-12-28 10:48:27.408  REST STREAM
< 2012-12-28 10:48:27.408  SIZE
< 2012-12-28 10:48:27.408 211 End
. 2012-12-28 10:48:27.408 Connected
. 2012-12-28 10:48:27.408 --------------------------------------------------------------------------
. 2012-12-28 10:48:27.408 Using FTP protocol.
. 2012-12-28 10:48:27.408 Doing startup conversation with host.
> 2012-12-28 10:48:27.408 PWD
< 2012-12-28 10:48:27.422 257 "/" is current directory.
. 2012-12-28 10:48:27.422 Changing directory to "/".
> 2012-12-28 10:48:27.422 CWD /
< 2012-12-28 10:48:27.431 250 CWD command successful
. 2012-12-28 10:48:27.431 Getting current directory name.
> 2012-12-28 10:48:27.431 PWD
< 2012-12-28 10:48:27.444 257 "/" is current directory.
. 2012-12-28 10:48:27.444 Startup conversation with host finished.
< 2012-12-28 10:48:27.444 Script: Active session: [1] vargalant
> 2012-12-28 10:48:27.444 Script: put -resume transfer.lock ./
. 2012-12-28 10:48:27.445 Copying 1 files/directories to remote directory "./"
. 2012-12-28 10:48:27.445   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 
. 2012-12-28 10:48:27.445   TM: B; ClAr: No; CPS: 0; InclM: |*.ftpquota
. 2012-12-28 10:48:27.445   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2012-12-28 10:48:27.445 File: "transfer.lock"
. 2012-12-28 10:48:27.445 Copying "transfer.lock" to remote directory started.
. 2012-12-28 10:48:27.445 Binary transfer mode selected.
. 2012-12-28 10:48:27.445 Starting upload of transfer.lock
> 2012-12-28 10:48:27.446 TYPE A
< 2012-12-28 10:48:27.456 200 Type set to A
> 2012-12-28 10:48:27.456 PASV
< 2012-12-28 10:48:27.469 227 Entering Passive Mode (51,165,179,39,179,170).
> 2012-12-28 10:48:27.470 LIST
< 2012-12-28 10:48:27.492 150 Opening ASCII mode data connection for file list
. 2012-12-28 10:48:27.493 drwxr-xr-x   2 ftp      ftp          4096 Dec 27 18:50 .
. 2012-12-28 10:48:27.493 drwxr-xr-x   2 ftp      ftp          4096 Dec 27 18:50 ..
. 2012-12-28 10:48:27.493 -rw-r--r--   1 ftp      ftp       2260223 Dec 19 16:08 catalog - Copy.xml
. 2012-12-28 10:48:27.493 -rw-r--r--   1 ftp      ftp       2273187 Dec 27 18:50 catalog.xml
. 2012-12-28 10:48:27.493 -rw-r--r--   1 ftp      ftp       2180606 Sep 27 06:42 _catalog.xml
. 2012-12-28 10:48:27.493 -rw-r--r--   1 ftp      ftp             9 May 18  2012 index.html
. 2012-12-28 10:48:27.493 -rw-r--r--   1 ftp      ftp       2252807 Dec 19 07:31 testni.xml
< 2012-12-28 10:48:27.507 226 Transfer complete.
> 2012-12-28 10:48:27.507 TYPE I
< 2012-12-28 10:48:27.521 200 Type set to I
> 2012-12-28 10:48:27.521 PASV
< 2012-12-28 10:48:27.531 227 Entering Passive Mode (51,165,179,39,195,80).
> 2012-12-28 10:48:27.531 STOR transfer.lock
< 2012-12-28 10:48:27.560 150 Opening BINARY mode data connection for transfer.lock
< 2012-12-28 10:48:27.597 226 Transfer complete.
. 2012-12-28 10:48:27.598 Upload successful
> 2012-12-28 10:48:27.598 Script: put catalog.xml ./
. 2012-12-28 10:48:27.598 Copying 1 files/directories to remote directory "./"
. 2012-12-28 10:48:27.598   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 
. 2012-12-28 10:48:27.598   TM: B; ClAr: No; CPS: 0; InclM: |*.ftpquota
. 2012-12-28 10:48:27.598   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2012-12-28 10:48:27.598 File: "catalog.xml"
. 2012-12-28 10:48:27.599 Copying "catalog.xml" to remote directory started.
. 2012-12-28 10:48:27.599 Binary transfer mode selected.
. 2012-12-28 10:48:27.599 Starting upload of catalog.xml
> 2012-12-28 10:48:27.599 TYPE I
< 2012-12-28 10:48:27.609 200 Type set to I
> 2012-12-28 10:48:27.609 PASV
< 2012-12-28 10:48:27.623 227 Entering Passive Mode (51,165,179,39,193,19).
> 2012-12-28 10:48:27.623 STOR catalog.xml
< 2012-12-28 10:48:27.680 150 Opening BINARY mode data connection for catalog.xml
< 2012-12-28 10:49:02.853 226 Transfer complete.
. 2012-12-28 10:49:02.853 Upload successful
> 2012-12-28 10:49:02.854 Script: rm transfer.lock
. 2012-12-28 10:49:02.854 Listing file "transfer.lock".
. 2012-12-28 10:49:02.854 Retrieving directory listing...
> 2012-12-28 10:49:02.854 TYPE A
< 2012-12-28 10:49:02.961 200 Type set to A
> 2012-12-28 10:49:02.962 PASV
< 2012-12-28 10:49:02.993 227 Entering Passive Mode (51,165,179,39,201,61).
> 2012-12-28 10:49:02.993 LIST -a
< 2012-12-28 10:49:03.014 150 Opening ASCII mode data connection for file list
. 2012-12-28 10:49:03.016 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 09:47 .
. 2012-12-28 10:49:03.016 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 09:47 ..
. 2012-12-28 10:49:03.016 -rw-r--r--   1 ftp      ftp       2260223 Dec 19 16:08 catalog - Copy.xml
. 2012-12-28 10:49:03.016 -rw-r--r--   1 ftp      ftp       2273187 Dec 28 09:48 catalog.xml
. 2012-12-28 10:49:03.016 -rw-r--r--   1 ftp      ftp       2180606 Sep 27 06:42 _catalog.xml
. 2012-12-28 10:49:03.016 -rw-r--r--   1 ftp      ftp             9 May 18  2012 index.html
. 2012-12-28 10:49:03.016 -rw-r--r--   1 ftp      ftp       2252807 Dec 19 07:31 testni.xml
. 2012-12-28 10:49:03.016 -rw-r--r--   1 ftp      ftp             0 Dec 28 09:47 transfer.lock
< 2012-12-28 10:49:03.026 226 Transfer complete.
. 2012-12-28 10:49:03.026 Directory listing successful
. 2012-12-28 10:49:03.026 Deleting file "transfer.lock".
> 2012-12-28 10:49:03.026 DELE /transfer.lock
< 2012-12-28 10:49:03.039 250 DELE command successful
< 2012-12-28 10:49:03.039 Script: transfer.lock
> 2012-12-28 10:49:03.039 Script: close
. 2012-12-28 10:49:03.040 Disconnected from server

and WITH -resume:

. 2012-12-28 11:08:30.363 --------------------------------------------------------------------------
. 2012-12-28 11:08:30.363 WinSCP Version 5.1.2 (Build 2816) (OS 6.1.7601 Service Pack 1)
. 2012-12-28 11:08:30.363 Configuration: HKEY_CURRENT_USER\Software\Martin Prikryl\WinSCP 2\
. 2012-12-28 11:08:30.363 Local account: SERVER1\servis
. 2012-12-28 11:08:30.363 Working directory: C:\_upload_Web_
. 2012-12-28 11:08:30.363 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" /console=512 /consoleinstance=_4504_534 "/script=upload.ini" 
. 2012-12-28 11:08:30.363 Time zone: Current: GMT+1, Standard: GMT+1, DST: GMT+2, DST Start: 25.3.2012, DST End: 28.10.2012
. 2012-12-28 11:08:30.363 Login time: 28. december 2012 11:08:30
. 2012-12-28 11:08:30.363 --------------------------------------------------------------------------
. 2012-12-28 11:08:30.363 Session name: vargalant (Stored session)
. 2012-12-28 11:08:30.363 Host name: ftp.company.com (Port: 21)
. 2012-12-28 11:08:30.363 User name: xml@company.com (Password: Yes, Key file: No)
. 2012-12-28 11:08:30.363 Tunnel: No
. 2012-12-28 11:08:30.363 Transfer Protocol: FTP
. 2012-12-28 11:08:30.363 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2012-12-28 11:08:30.363 Proxy: none
. 2012-12-28 11:08:30.363 FTP: FTPS: None; Passive: Yes [Force IP: A]
. 2012-12-28 11:08:30.363 Local directory: C:\_upload_Web_, Remote directory: /, Update: No, Cache: Yes
. 2012-12-28 11:08:30.363 Cache directory changes: Yes, Permanent: Yes
. 2012-12-28 11:08:30.363 DST mode: 1
. 2012-12-28 11:08:30.363 --------------------------------------------------------------------------
. 2012-12-28 11:08:30.363 Connecting to ftp.company.com ...
. 2012-12-28 11:08:30.394 Connected with ftp.company.com. Waiting for welcome message...
< 2012-12-28 11:08:30.410 220 ProFTPD 1.3.0 Server ready.
> 2012-12-28 11:08:30.410 USER xml@company.com
< 2012-12-28 11:08:30.426 331 Password required for xml@company.com.
> 2012-12-28 11:08:30.426 PASS ********
< 2012-12-28 11:08:32.032 230 User xml@company.com logged in.
> 2012-12-28 11:08:32.032 SYST
< 2012-12-28 11:08:32.048 215 UNIX Type: L8
> 2012-12-28 11:08:32.048 FEAT
< 2012-12-28 11:08:32.064 211-Features:
< 2012-12-28 11:08:32.064  MDTM
< 2012-12-28 11:08:32.064  REST STREAM
< 2012-12-28 11:08:32.064  SIZE
< 2012-12-28 11:08:32.064 211 End
. 2012-12-28 11:08:32.064 Connected
. 2012-12-28 11:08:32.064 --------------------------------------------------------------------------
. 2012-12-28 11:08:32.064 Using FTP protocol.
. 2012-12-28 11:08:32.064 Doing startup conversation with host.
> 2012-12-28 11:08:32.064 PWD
< 2012-12-28 11:08:32.064 257 "/" is current directory.
. 2012-12-28 11:08:32.064 Changing directory to "/".
> 2012-12-28 11:08:32.064 CWD /
< 2012-12-28 11:08:32.079 250 CWD command successful
. 2012-12-28 11:08:32.079 Getting current directory name.
> 2012-12-28 11:08:32.079 PWD
< 2012-12-28 11:08:32.095 257 "/" is current directory.
. 2012-12-28 11:08:32.095 Startup conversation with host finished.
< 2012-12-28 11:08:32.095 Script: Active session: [1] vargalant
> 2012-12-28 11:08:32.095 Script: put -resume transfer.lock ./
. 2012-12-28 11:08:32.095 Copying 1 files/directories to remote directory "./"
. 2012-12-28 11:08:32.095   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 
. 2012-12-28 11:08:32.095   TM: B; ClAr: No; CPS: 0; InclM: |*.ftpquota
. 2012-12-28 11:08:32.095   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2012-12-28 11:08:32.095 File: "transfer.lock"
. 2012-12-28 11:08:32.095 Copying "transfer.lock" to remote directory started.
. 2012-12-28 11:08:32.095 Binary transfer mode selected.
. 2012-12-28 11:08:32.095 Starting upload of transfer.lock
> 2012-12-28 11:08:32.095 TYPE A
< 2012-12-28 11:08:32.095 200 Type set to A
> 2012-12-28 11:08:32.095 PASV
< 2012-12-28 11:08:32.110 227 Entering Passive Mode (51,165,179,39,130,68).
> 2012-12-28 11:08:32.110 LIST
< 2012-12-28 11:08:32.126 150 Opening ASCII mode data connection for file list
. 2012-12-28 11:08:32.142 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 09:56 .
. 2012-12-28 11:08:32.142 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 09:56 ..
. 2012-12-28 11:08:32.142 -rw-r--r--   1 ftp      ftp       2260223 Dec 19 16:08 catalog - Copy.xml
. 2012-12-28 11:08:32.142 -rw-r--r--   1 ftp      ftp       2273187 Dec 28 09:56 catalog.xml
. 2012-12-28 11:08:32.142 -rw-r--r--   1 ftp      ftp       2180606 Sep 27 06:42 _catalog.xml
. 2012-12-28 11:08:32.142 -rw-r--r--   1 ftp      ftp             9 May 18  2012 index.html
. 2012-12-28 11:08:32.142 -rw-r--r--   1 ftp      ftp       2252807 Dec 19 07:31 testni.xml
< 2012-12-28 11:08:32.142 226 Transfer complete.
> 2012-12-28 11:08:32.142 TYPE I
< 2012-12-28 11:08:32.157 200 Type set to I
> 2012-12-28 11:08:32.157 PASV
< 2012-12-28 11:08:32.173 227 Entering Passive Mode (51,165,179,39,128,146).
> 2012-12-28 11:08:32.173 STOR transfer.lock
< 2012-12-28 11:08:32.188 150 Opening BINARY mode data connection for transfer.lock
< 2012-12-28 11:08:32.204 226 Transfer complete.
. 2012-12-28 11:08:32.204 Upload successful
> 2012-12-28 11:08:32.204 Script: put -resume catalog.xml ./
. 2012-12-28 11:08:32.204 Copying 1 files/directories to remote directory "./"
. 2012-12-28 11:08:32.204   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 
. 2012-12-28 11:08:32.204   TM: B; ClAr: No; CPS: 0; InclM: |*.ftpquota
. 2012-12-28 11:08:32.204   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2012-12-28 11:08:32.204 File: "catalog.xml"
. 2012-12-28 11:08:32.204 Copying "catalog.xml" to remote directory started.
. 2012-12-28 11:08:32.204 Binary transfer mode selected.
. 2012-12-28 11:08:32.204 Starting upload of catalog.xml
> 2012-12-28 11:08:32.204 TYPE I
< 2012-12-28 11:08:32.204 200 Type set to I
> 2012-12-28 11:08:32.204 PASV
< 2012-12-28 11:08:32.220 227 Entering Passive Mode (51,165,179,39,144,187).
> 2012-12-28 11:08:32.220 STOR catalog.xml
< 2012-12-28 11:08:32.235 150 Opening BINARY mode data connection for catalog.xml
< 2012-12-28 11:09:00.269 226 Transfer complete.
. 2012-12-28 11:09:00.269 Upload successful
> 2012-12-28 11:09:00.269 Script: rm transfer.lock
. 2012-12-28 11:09:00.269 Listing file "transfer.lock".
. 2012-12-28 11:09:00.269 Retrieving directory listing...
> 2012-12-28 11:09:00.269 TYPE A
< 2012-12-28 11:09:00.535 200 Type set to A
> 2012-12-28 11:09:00.535 PASV
< 2012-12-28 11:09:00.815 227 Entering Passive Mode (51,165,179,39,198,146).
> 2012-12-28 11:09:00.815 LIST -a
< 2012-12-28 11:09:01.377 150 Opening ASCII mode data connection for file list
. 2012-12-28 11:09:01.377 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 10:08 .
. 2012-12-28 11:09:01.377 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 10:08 ..
. 2012-12-28 11:09:01.377 -rw-r--r--   1 ftp      ftp       2260223 Dec 19 16:08 catalog - Copy.xml
. 2012-12-28 11:09:01.377 -rw-r--r--   1 ftp      ftp       2273187 Dec 28 10:08 catalog.xml
. 2012-12-28 11:09:01.377 -rw-r--r--   1 ftp      ftp       2180606 Sep 27 06:42 _catalog.xml
. 2012-12-28 11:09:01.377 -rw-r--r--   1 ftp      ftp             9 May 18  2012 index.html
. 2012-12-28 11:09:01.377 -rw-r--r--   1 ftp      ftp       2252807 Dec 19 07:31 testni.xml
. 2012-12-28 11:09:01.377 -rw-r--r--   1 ftp      ftp             0 Dec 28 10:08 transfer.lock
< 2012-12-28 11:09:01.673 226 Transfer complete.
. 2012-12-28 11:09:01.673 Directory listing successful
. 2012-12-28 11:09:01.673 Deleting file "transfer.lock".
> 2012-12-28 11:09:01.673 DELE /transfer.lock
< 2012-12-28 11:09:02.001 250 DELE command successful
< 2012-12-28 11:09:02.001 Script: transfer.lock
> 2012-12-28 11:09:02.001 Script: close
. 2012-12-28 11:09:02.001 Disconnected from server
. 2012-12-28 11:09:40.902 --------------------------------------------------------------------------
. 2012-12-28 11:09:40.902 WinSCP Version 5.1.2 (Build 2816) (OS 6.1.7601 Service Pack 1)
. 2012-12-28 11:09:40.902 Configuration: HKEY_CURRENT_USER\Software\Martin Prikryl\WinSCP 2\
. 2012-12-28 11:09:40.902 Local account: SERVER1\servis
. 2012-12-28 11:09:40.902 Working directory: C:\_upload_Web_
. 2012-12-28 11:09:40.902 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" /console=512 /consoleinstance=_3932_640 "/script=upload.ini" 
. 2012-12-28 11:09:40.902 Time zone: Current: GMT+1, Standard: GMT+1, DST: GMT+2, DST Start: 25.3.2012, DST End: 28.10.2012
. 2012-12-28 11:09:40.902 Login time: 28. december 2012 11:09:40
. 2012-12-28 11:09:40.902 --------------------------------------------------------------------------
. 2012-12-28 11:09:40.902 Session name: vargalant (Stored session)
. 2012-12-28 11:09:40.902 Host name: ftp.company.com (Port: 21)
. 2012-12-28 11:09:40.902 User name: xml@company.com (Password: Yes, Key file: No)
. 2012-12-28 11:09:40.902 Tunnel: No
. 2012-12-28 11:09:40.902 Transfer Protocol: FTP
. 2012-12-28 11:09:40.902 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2012-12-28 11:09:40.902 Proxy: none
. 2012-12-28 11:09:40.902 FTP: FTPS: None; Passive: Yes [Force IP: A]
. 2012-12-28 11:09:40.902 Local directory: C:\_upload_Web_, Remote directory: /, Update: No, Cache: Yes
. 2012-12-28 11:09:40.902 Cache directory changes: Yes, Permanent: Yes
. 2012-12-28 11:09:40.902 DST mode: 1
. 2012-12-28 11:09:40.902 --------------------------------------------------------------------------
. 2012-12-28 11:09:40.902 Connecting to ftp.company.com ...
. 2012-12-28 11:09:41.242 Connected with ftp.company.com. Waiting for welcome message...
< 2012-12-28 11:09:41.582 220 ProFTPD 1.3.0 Server ready.
> 2012-12-28 11:09:41.582 USER xml@company.com
< 2012-12-28 11:09:41.892 331 Password required for xml@company.com.
> 2012-12-28 11:09:41.892 PASS ********
< 2012-12-28 11:09:43.977 230 User xml@company.com logged in.
> 2012-12-28 11:09:43.977 SYST
< 2012-12-28 11:09:44.302 215 UNIX Type: L8
> 2012-12-28 11:09:44.302 FEAT
< 2012-12-28 11:09:44.632 211-Features:
< 2012-12-28 11:09:44.632  MDTM
< 2012-12-28 11:09:44.632  REST STREAM
< 2012-12-28 11:09:44.632  SIZE
< 2012-12-28 11:09:44.632 211 End
. 2012-12-28 11:09:44.632 Connected
. 2012-12-28 11:09:44.632 --------------------------------------------------------------------------
. 2012-12-28 11:09:44.632 Using FTP protocol.
. 2012-12-28 11:09:44.632 Doing startup conversation with host.
> 2012-12-28 11:09:44.632 PWD
< 2012-12-28 11:09:44.982 257 "/" is current directory.
. 2012-12-28 11:09:44.982 Changing directory to "/".
> 2012-12-28 11:09:44.982 CWD /
< 2012-12-28 11:09:45.332 250 CWD command successful
. 2012-12-28 11:09:45.332 Getting current directory name.
> 2012-12-28 11:09:45.332 PWD
< 2012-12-28 11:09:45.697 257 "/" is current directory.
. 2012-12-28 11:09:45.697 Startup conversation with host finished.
< 2012-12-28 11:09:45.697 Script: Active session: [1] vargalant
> 2012-12-28 11:09:45.697 Script: put -resume transfer.lock ./
. 2012-12-28 11:09:45.697 Copying 1 files/directories to remote directory "./"
. 2012-12-28 11:09:45.697   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 
. 2012-12-28 11:09:45.697   TM: B; ClAr: No; CPS: 0; InclM: |*.ftpquota
. 2012-12-28 11:09:45.697   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2012-12-28 11:09:45.697 File: "transfer.lock"
. 2012-12-28 11:09:45.697 Copying "transfer.lock" to remote directory started.
. 2012-12-28 11:09:45.697 Binary transfer mode selected.
. 2012-12-28 11:09:45.697 Starting upload of transfer.lock
> 2012-12-28 11:09:45.697 TYPE A
< 2012-12-28 11:09:46.087 200 Type set to A
> 2012-12-28 11:09:46.087 PASV
< 2012-12-28 11:09:46.487 227 Entering Passive Mode (51,165,179,39,163,136).
> 2012-12-28 11:09:46.487 LIST
< 2012-12-28 11:09:47.412 150 Opening ASCII mode data connection for file list
. 2012-12-28 11:09:47.412 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 10:08 .
. 2012-12-28 11:09:47.412 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 10:08 ..
. 2012-12-28 11:09:47.412 -rw-r--r--   1 ftp      ftp       2260223 Dec 19 16:08 catalog - Copy.xml
. 2012-12-28 11:09:47.412 -rw-r--r--   1 ftp      ftp       2273187 Dec 28 10:08 catalog.xml
. 2012-12-28 11:09:47.412 -rw-r--r--   1 ftp      ftp       2180606 Sep 27 06:42 _catalog.xml
. 2012-12-28 11:09:47.412 -rw-r--r--   1 ftp      ftp             9 May 18  2012 index.html
. 2012-12-28 11:09:47.412 -rw-r--r--   1 ftp      ftp       2252807 Dec 19 07:31 testni.xml
< 2012-12-28 11:09:47.897 226 Transfer complete.
> 2012-12-28 11:09:47.897 TYPE I
< 2012-12-28 11:09:48.332 200 Type set to I
> 2012-12-28 11:09:48.332 PASV
< 2012-12-28 11:09:48.627 227 Entering Passive Mode (51,165,179,39,200,170).
> 2012-12-28 11:09:48.627 STOR transfer.lock
< 2012-12-28 11:09:49.212 150 Opening BINARY mode data connection for transfer.lock
< 2012-12-28 11:09:49.507 226 Transfer complete.
. 2012-12-28 11:09:49.507 Upload successful
> 2012-12-28 11:09:49.507 Script: put -resume catalog.xml ./
. 2012-12-28 11:09:49.512 Copying 1 files/directories to remote directory "./"
. 2012-12-28 11:09:49.512   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 0100; Resume: S (102400); CalcS: No; Mask: 
. 2012-12-28 11:09:49.512   TM: B; ClAr: No; CPS: 0; InclM: |*.ftpquota
. 2012-12-28 11:09:49.512   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; *.js; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2012-12-28 11:09:49.512 File: "catalog.xml"
. 2012-12-28 11:09:49.512 Copying "catalog.xml" to remote directory started.
. 2012-12-28 11:09:49.512 Binary transfer mode selected.
. 2012-12-28 11:09:49.512 Starting upload of catalog.xml
> 2012-12-28 11:09:49.512 TYPE I
< 2012-12-28 11:09:49.882 200 Type set to I
> 2012-12-28 11:09:49.882 PASV
< 2012-12-28 11:09:50.227 227 Entering Passive Mode (51,165,179,39,128,37).
> 2012-12-28 11:09:50.227 STOR catalog.xml
< 2012-12-28 11:09:50.967 150 Opening BINARY mode data connection for catalog.xml
< 2012-12-28 11:10:47.907 226 Transfer complete.
. 2012-12-28 11:10:47.907 Upload successful
> 2012-12-28 11:10:47.907 Script: rm transfer.lock
. 2012-12-28 11:10:47.907 Listing file "transfer.lock".
. 2012-12-28 11:10:47.907 Retrieving directory listing...
> 2012-12-28 11:10:47.907 TYPE A
< 2012-12-28 11:10:48.392 200 Type set to A
> 2012-12-28 11:10:48.392 PASV
< 2012-12-28 11:10:48.802 227 Entering Passive Mode (51,165,179,39,164,206).
> 2012-12-28 11:10:48.802 LIST -a
< 2012-12-28 11:10:49.677 150 Opening ASCII mode data connection for file list
. 2012-12-28 11:10:49.677 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 10:09 .
. 2012-12-28 11:10:49.677 drwxr-xr-x   2 ftp      ftp          4096 Dec 28 10:09 ..
. 2012-12-28 11:10:49.677 -rw-r--r--   1 ftp      ftp       2260223 Dec 19 16:08 catalog - Copy.xml
. 2012-12-28 11:10:49.677 -rw-r--r--   1 ftp      ftp       2273187 Dec 28 10:10 catalog.xml
. 2012-12-28 11:10:49.677 -rw-r--r--   1 ftp      ftp       2180606 Sep 27 06:42 _catalog.xml
. 2012-12-28 11:10:49.677 -rw-r--r--   1 ftp      ftp             9 May 18  2012 index.html
. 2012-12-28 11:10:49.677 -rw-r--r--   1 ftp      ftp       2252807 Dec 19 07:31 testni.xml
. 2012-12-28 11:10:49.677 -rw-r--r--   1 ftp      ftp             0 Dec 28 10:09 transfer.lock
< 2012-12-28 11:10:50.267 226 Transfer complete.
. 2012-12-28 11:10:50.267 Directory listing successful
. 2012-12-28 11:10:50.267 Deleting file "transfer.lock".
> 2012-12-28 11:10:50.267 DELE /transfer.lock
< 2012-12-28 11:10:50.752 250 DELE command successful
< 2012-12-28 11:10:50.752 Script: transfer.lock
> 2012-12-28 11:10:50.752 Script: close
. 2012-12-28 11:10:50.752 Disconnected from server

But unfortunatelly I cannot replicate the issue at this time. Each transfer is now OK, with and without -resume.

Reply with quote

File modified after FTP transfer problem