This issue has been added to the tracker:
https://winscp.net/tracker/1090

Sent.

Martin Can you send me the debug version again.
I deleted it when I thought we had this working correctly

Gene

Thanks for the log. Can you use the debug version you have and send me a trace file?

Martin,

I used the ftp site you gave me in the past to send entire log. The log I sent is a good representation as to what happens almost every time I run this job.
I think it might possibly have something to do with the number of files being transferred or a file size constraint.

Please let me know if you have any questions.

Gene

martin wrote:

Can you send me a sample log? Or at least its end, if it's too big.

Here is the end of a recent log file.

. 2013-11-01 12:27:39.953 SCP remote side confirmation (0)
. 2013-11-01 12:27:39.953 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Links\291_onramp_workingVigWHT.tif" to remote directory finished.
. 2013-11-01 12:27:39.953 File: "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Links\Volvo_CMYK_L.eps"
. 2013-11-01 12:27:39.968 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Links\Volvo_CMYK_L.eps" to remote directory started.
. 2013-11-01 12:27:39.968 Binary transfer mode selected.
> 2013-11-01 12:27:39.968 T1382442557 0 1382442557 0
. 2013-11-01 12:27:40.093 SCP remote side confirmation (0)
> 2013-11-01 12:27:40.093 C0644 1666577 Volvo_CMYK_L.eps
. 2013-11-01 12:27:40.234 SCP remote side confirmation (0)
. 2013-11-01 12:27:40.234 Sending BINARY data (first block, 4096 bytes)
. 2013-11-01 12:27:52.453 Sending NULL.
. 2013-11-01 12:27:52.984 SCP remote side confirmation (0)
. 2013-11-01 12:27:52.984 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Links\Volvo_CMYK_L.eps" to remote directory finished.
. 2013-11-01 12:27:52.984 Leaving directory "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Links".
> 2013-11-01 12:27:52.984 E
. 2013-11-01 12:27:53.093 SCP remote side confirmation (0)
. 2013-11-01 12:27:53.093 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Links" to remote directory finished.
. 2013-11-01 12:27:53.093 File: "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Template1.xdt"
. 2013-11-01 12:27:53.093 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Template1.xdt" to remote directory started.
. 2013-11-01 12:27:53.093 Binary transfer mode selected.
> 2013-11-01 12:27:53.125 T1383313704 0 1383305885 0
. 2013-11-01 12:27:53.234 SCP remote side confirmation (0)
> 2013-11-01 12:27:53.234 C0644 5038 Template1.xdt
. 2013-11-01 12:27:53.343 SCP remote side confirmation (0)
. 2013-11-01 12:27:53.343 Sending BINARY data (first block, 4096 bytes)
. 2013-11-01 12:27:53.343 Sending NULL.
. 2013-11-01 12:27:53.421 SCP remote side confirmation (0)
. 2013-11-01 12:27:53.421 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Template1.xdt" to remote directory finished.
. 2013-11-01 12:27:53.421 File: "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Text"
. 2013-11-01 12:27:53.437 Entering directory "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Text".
> 2013-11-01 12:27:53.437 D0755 0 Text
. 2013-11-01 12:27:53.578 SCP remote side confirmation (0)
. 2013-11-01 12:27:53.578 Leaving directory "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Text".
> 2013-11-01 12:27:53.578 E
. 2013-11-01 12:27:53.687 SCP remote side confirmation (0)
. 2013-11-01 12:27:53.687 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Text" to remote directory finished.
. 2013-11-01 12:27:53.687 File: "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Images"
. 2013-11-01 12:27:53.687 Entering directory "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Images".
> 2013-11-01 12:27:53.687 D0755 0 Images
. 2013-11-01 12:27:53.828 SCP remote side confirmation (0)
. 2013-11-01 12:27:53.828 File: "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Images\Autogen"
. 2013-11-01 12:27:53.828 Entering directory "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Images\Autogen".
> 2013-11-01 12:27:53.828 D0755 0 Autogen
. 2013-11-01 12:27:53.968 SCP remote side confirmation (0)
. 2013-11-01 12:27:53.968 Leaving directory "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Images\Autogen".
> 2013-11-01 12:27:53.968 E
. 2013-11-01 12:27:54.093 SCP remote side confirmation (0)
. 2013-11-01 12:27:54.093 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Images\Autogen" to remote directory finished.
. 2013-11-01 12:27:54.093 Leaving directory "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Images".
> 2013-11-01 12:27:54.093 E
. 2013-11-01 12:27:54.218 SCP remote side confirmation (0)
. 2013-11-01 12:27:54.218 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder\Images" to remote directory finished.
. 2013-11-01 12:27:54.218 Leaving directory "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder".
> 2013-11-01 12:27:54.218 E
. 2013-11-01 12:27:54.359 SCP remote side confirmation (0)
. 2013-11-01 12:27:54.359 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder" to remote directory finished.
. 2013-11-01 12:27:54.359 File: "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder.zip"
. 2013-11-01 12:27:54.375 Copying "Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder.zip" to remote directory started.
. 2013-11-01 12:27:54.375 Binary transfer mode selected.
> 2013-11-01 12:27:54.390 T1382442334 0 1382442675 0
. 2013-11-01 12:27:54.531 SCP remote side confirmation (0)
> 2013-11-01 12:27:54.531 C0644 312519536 VFSMailer_102113 Folder.zip
. 2013-11-01 12:27:54.656 SCP remote side confirmation (0)
. 2013-11-01 12:27:54.656 Sending BINARY data (first block, 4096 bytes)
. 2013-11-01 13:03:38.046 Network error: Software caused connection abort
< 2013-11-01 13:03:38.046 Script: Network error: Software caused connection abort
< 2013-11-01 13:03:38.046 Script: Copying file 'Z:\050080 VFS Fasttrack Fall 2013\VFSMailer_102113 Folder.zip' fatally failed.

< 2013-11-01 13:03:38.046 Copying files to remote side failed.

Can you send me a sample log? Or at least its end, if it's too big.

Martin,

I am calling Winscp from a Windows batch file. I am using 5.2.5 BETA
The batch file is run as a scheduled task every 24 hours.
The WinSCP command file synchronizes the proper directories but disconnects before completing the last directory sync.

If I look in taskmgr after a few days there are N number of WinSCP.com processes running where N is the number of days since I last checked it.

I believe this is happening because I am losing connection during the backup and the process just keeps retrying.
For each run there are MASSIVE log files of 33GB +/- and they are not easily debugged because of the sheer size.
The disconnect seems to happen only when running the largest sync in the batch job.

How can I get the process to stop or restart.

Below are the Simple Batch file and WinSCP Script I am using

Thanks
Gene


***** Batch File *****
CLS
ECHO OFF

REM **********************************************
REM ******* Automated Backup Batch File ********
REM **********************************************
REM **********************************************
REM **********************************************

"C:\Program Files\DEBUG_WINSCP\WinSCP.com" /script="C:\Program Files\Pace-Bkup\pace-bkup.txt"



***** WinSCP Script *****

#**********************************************
#******* Automated WinSCP Backup ********
#**********************************************
#**********************************************

open pace-bkup
option confirm off
option reconnecttime 10
option batch continue
option echo off

synchronize remote Y:\ /pace-bkup/$(Backup_Executive) -criteria=either -transfer=automatic
synchronize remote Q:\ /pace-bkup/$(Backup_Quickbooks) -criteria=either -transfer=automatic
synchronize remote Z:\ /pace-bkup/$(Backup_WIP)/ -criteria=either -transfer=automatic

close
exit