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:
Here is the log from my FTP server (FileZilla 0.9.31 beta):
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.
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 acceptedHere 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.
