Continuous loop of uploading

Advertisement

thedrewster77
Joined:
Posts:
1
Location:
WA

Continuous loop of uploading

Hi,

I'm trying to automate some backups and have it mostly nailed down. On small-ish transfers there is no problem and things go as planned. However, on last night's backup file the system is choking. The zip it's trying to put (via FTP) is 130,744KB big.

Here's the log from WinSCP:

. 2009-03-04 09:15:59.593 --------------------------------------------------------------------------
. 2009-03-04 09:15:59.593 WinSCP Version 4.2.1 (Build 428) (OS 5.2.3790 Service Pack 2)
. 2009-03-04 09:15:59.593 Login time: Wednesday, March 04, 2009 9:15:59 AM
. 2009-03-04 09:15:59.593 --------------------------------------------------------------------------
. 2009-03-04 09:15:59.593 Session name: tmbackup
. 2009-03-04 09:15:59.593 Host name: server.domain.com (Port: 21)
. 2009-03-04 09:15:59.593 User name: tmupload (Password: Yes, Key file: No)
. 2009-03-04 09:15:59.593 Tunnel: No
. 2009-03-04 09:15:59.593 Transfer Protocol: FTP
. 2009-03-04 09:15:59.593 Ping type: C, Ping interval: 30 sec; Timeout: 15 sec
. 2009-03-04 09:15:59.593 Proxy: none
. 2009-03-04 09:15:59.593 FTP: FTPS: None; Passive: Yes
. 2009-03-04 09:15:59.593 Local directory: default, Remote directory: home, Update: No, Cache: Yes
. 2009-03-04 09:15:59.593 Cache directory changes: Yes, Permanent: Yes
. 2009-03-04 09:15:59.593 DST mode: 1
. 2009-03-04 09:15:59.593 --------------------------------------------------------------------------
. 2009-03-04 09:15:59.593 Connecting to server.domain.com ...
. 2009-03-04 09:15:59.625 Connected with server.domain.com. Waiting for welcome message...
< 2009-03-04 09:15:59.640 220-FileZilla Server version 0.9.31 beta
< 2009-03-04 09:15:59.640 220-written by Tim Kosse (Tim.Kosse@gmx.de)
< 2009-03-04 09:15:59.640 220 Please visit http://sourceforge.net/projects/filezilla/
> 2009-03-04 09:15:59.640 USER tmupload
< 2009-03-04 09:15:59.656 331 Password required for tmupload
> 2009-03-04 09:15:59.656 PASS *******
< 2009-03-04 09:15:59.671 230 Logged on
> 2009-03-04 09:15:59.671 SYST
< 2009-03-04 09:15:59.687 215 UNIX emulated by FileZilla
> 2009-03-04 09:15:59.687 FEAT
< 2009-03-04 09:15:59.703 211-Features:
< 2009-03-04 09:15:59.703  MDTM
< 2009-03-04 09:15:59.703  REST STREAM
< 2009-03-04 09:15:59.703  SIZE
< 2009-03-04 09:15:59.718  MLST type*;size*;modify*;
< 2009-03-04 09:15:59.718  MLSD
< 2009-03-04 09:15:59.718  UTF8
< 2009-03-04 09:15:59.718  CLNT
< 2009-03-04 09:15:59.718  MFMT
< 2009-03-04 09:15:59.718 211 End
. 2009-03-04 09:15:59.718 Connected
. 2009-03-04 09:15:59.718 --------------------------------------------------------------------------
. 2009-03-04 09:15:59.718 Using FTP protocol.
. 2009-03-04 09:15:59.718 Doing startup conversation with host.
> 2009-03-04 09:15:59.718 PWD
< 2009-03-04 09:15:59.734 257 "/" is current directory.
. 2009-03-04 09:15:59.734 Getting current directory name.
. 2009-03-04 09:15:59.734 Startup conversation with host finished.
. 2009-03-04 09:15:59.734 Copying 1 files/directories to remote directory "/"
. 2009-03-04 09:15:59.734   PrTime: Yes; PrRO: No; Rght: rw-r--r--; PrR: No (No); FnCs: N; RIC: 01; Resume: S (102400); CalcS: No; Mask: 
. 2009-03-04 09:15:59.734   TM: M; ClAr: No; CPS: 0; ExclM(No): 
. 2009-03-04 09:15:59.734   AscM: *.*html; *.htm; *.txt; *.php; *.php3; *.cgi; *.c; *.cpp; *.h; *.pas; *.bas; *.tex; *.pl; .htaccess; *.xtml; *.css; *.cfg; *.ini; *.sh; *.xml
. 2009-03-04 09:15:59.734 File: "200904_tm.zip"
. 2009-03-04 09:15:59.734 Copying "200904_tm.zip" to remote directory started.
. 2009-03-04 09:15:59.734 Binary transfer mode selected.
. 2009-03-04 09:15:59.734 Starting upload of 200904_tm.zip
> 2009-03-04 09:15:59.734 TYPE A
< 2009-03-04 09:15:59.750 200 Type set to A
> 2009-03-04 09:15:59.750 PASV
< 2009-03-04 09:15:59.765 227 Entering Passive Mode (xxx,xxx,xxx,xxx,171,5)
> 2009-03-04 09:15:59.765 LIST
< 2009-03-04 09:15:59.812 150 Connection accepted
< 2009-03-04 09:15:59.812 226 Transfer OK
> 2009-03-04 09:15:59.812 TYPE I
< 2009-03-04 09:15:59.828 200 Type set to I
> 2009-03-04 09:15:59.828 PASV
< 2009-03-04 09:15:59.843 227 Entering Passive Mode (xxx,xxx,xxx,xxx,171,6)
> 2009-03-04 09:15:59.843 STOR 200904_tm.zip
< 2009-03-04 09:15:59.875 150 Connection accepted
. 2009-03-04 09:22:19.593 Timeout detected.
. 2009-03-04 09:22:19.593 Copying files to remote side failed.
. 2009-03-04 09:22:19.593 Connection was lost, asking what to do.
. 2009-03-04 09:22:19.593 Asking user:
. 2009-03-04 09:22:19.593 Lost connection. ("Timeout detected.","Copying files to remote side failed.","Connection accepted")
. 2009-03-04 09:22:24.671 Connecting to server.domain.com ...
. 2009-03-04 09:22:24.750 Connected with server.domain.com. Waiting for welcome message...
< 2009-03-04 09:22:24.765 220-FileZilla Server version 0.9.31 beta
< 2009-03-04 09:22:24.765 220-written by Tim Kosse (Tim.Kosse@gmx.de)
< 2009-03-04 09:22:24.765 220 Please visit http://sourceforge.net/projects/filezilla/
> 2009-03-04 09:22:24.765 USER tmupload
< 2009-03-04 09:22:24.781 331 Password required for tmupload
> 2009-03-04 09:22:24.781 PASS *******
< 2009-03-04 09:22:24.796 230 Logged on
> 2009-03-04 09:22:24.796 SYST
< 2009-03-04 09:22:24.812 215 UNIX emulated by FileZilla
> 2009-03-04 09:22:24.812 FEAT
< 2009-03-04 09:22:24.843 211-Features:
< 2009-03-04 09:22:24.843  MDTM
< 2009-03-04 09:22:24.843  REST STREAM
< 2009-03-04 09:22:24.843  SIZE
< 2009-03-04 09:22:24.843  MLST type*;size*;modify*;
< 2009-03-04 09:22:24.843  MLSD
< 2009-03-04 09:22:24.843  UTF8
< 2009-03-04 09:22:24.843  CLNT
< 2009-03-04 09:22:24.843  MFMT
< 2009-03-04 09:22:24.843 211 End
. 2009-03-04 09:22:24.843 Connected
. 2009-03-04 09:22:24.859 Doing startup conversation with host.
. 2009-03-04 09:22:24.859 Changing directory to "/".
. 2009-03-04 09:22:24.859 Synchronizing current directory "/".
> 2009-03-04 09:22:24.859 CWD /
< 2009-03-04 09:22:24.875 250 CWD successful. "/" is current directory.
> 2009-03-04 09:22:24.875 CWD /
< 2009-03-04 09:22:24.890 250 CWD successful. "/" is current directory.
. 2009-03-04 09:22:24.890 Getting current directory name.
> 2009-03-04 09:22:24.890 PWD
< 2009-03-04 09:22:24.906 257 "/" is current directory.
. 2009-03-04 09:22:24.906 Startup conversation with host finished.
. 2009-03-04 09:22:24.906 File: "200904_tm.zip"
. 2009-03-04 09:22:24.906 Copying "200904_tm.zip" to remote directory started.
. 2009-03-04 09:22:24.906 Binary transfer mode selected.
. 2009-03-04 09:22:24.906 Starting upload of 200904_tm.zip
> 2009-03-04 09:22:24.906 TYPE A
< 2009-03-04 09:22:24.937 200 Type set to A
> 2009-03-04 09:22:24.937 PASV
< 2009-03-04 09:22:24.953 227 Entering Passive Mode (xxx,xxx,xxx,xxx,171,195)
> 2009-03-04 09:22:24.953 LIST
< 2009-03-04 09:22:24.984 150 Connection accepted
< 2009-03-04 09:22:25.000 226 Transfer OK
> 2009-03-04 09:22:25.000 TYPE I
< 2009-03-04 09:22:25.015 200 Type set to I
> 2009-03-04 09:22:25.015 PASV
< 2009-03-04 09:22:25.031 227 Entering Passive Mode (xxx,xxx,xxx,xxx,171,196)
> 2009-03-04 09:22:25.031 STOR 200904_tm.zip
< 2009-03-04 09:22:25.062 150 Connection accepted

Here is the log from my FTP server (FileZilla 0.9.31 beta):
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 230 Logged on
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> SYST
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 215 UNIX emulated by FileZilla
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> FEAT
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 211-Features:
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)>  MDTM
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)>  REST STREAM
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)>  SIZE
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)>  MLST type*;size*;modify*;
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)>  MLSD
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)>  UTF8
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)>  CLNT
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)>  MFMT
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 211 End
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> PWD
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 257 "/" is current directory.
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> TYPE A
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 200 Type set to A
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> PASV
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 227 Entering Passive Mode (192,168,102,20,171,5)
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> LIST
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 150 Connection accepted
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 226 Transfer OK
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> TYPE I
(000091) 3/4/2009 9:16:13 AM - tmupload (client.ip.is.here)> 200 Type set to I
(000091) 3/4/2009 9:16:14 AM - tmupload (client.ip.is.here)> PASV
(000091) 3/4/2009 9:16:14 AM - tmupload (client.ip.is.here)> 227 Entering Passive Mode (192,168,102,20,171,6)
(000091) 3/4/2009 9:16:14 AM - tmupload (client.ip.is.here)> STOR 200904_tm.zip
(000091) 3/4/2009 9:16:14 AM - tmupload (client.ip.is.here)> 150 Connection accepted
(000091) 3/4/2009 9:22:19 AM - tmupload (client.ip.is.here)> 226 Transfer OK
(000091) 3/4/2009 9:22:33 AM - tmupload (client.ip.is.here)> disconnected.

I didn't grab the log from the FTP server that has the reconnect, but it's the same as the first connection. The FTP server returns a 226 Transfer OK, however WinSCP doesn't seem to get that message.

I am calling WinSCP from the command line by the following in a larger windows' batch file. %backupfile% is a variable that looks something like 200904_tm.zip for example.:
winscp /console /command "option batch on" "open tmbackup" "put %backupfile%" "exit" /log=backuplog.txt

Am I doing something wrong? Please help.

Reply with quote

Advertisement

Advertisement

You can post new topics in this forum