PLEASE HELP: Info in the Log file is incorrect (mixed-up)

Scenario:

Three bat files (A, B, C) are executed simultaneously. Each Bat file contains one line only.
A: "C:\Program Files\WinSCP3\winscp3" /console /script=F:\BATFILE_A.txt > F:\LOGS\LOGFILE_A.txt
B: "C:\Program Files\WinSCP3\winscp3" /console /script=F:\BATFILE_B.txt > F:\LOGS\LOGFILE_B.txt
C: "C:\Program Files\WinSCP3\winscp3" /console /script=F:\BATFILE_C.txt > F:\LOGS\LOGFILE_C.txt

Each bat file calls a text file (A, B, C) that it will execute.
A: F:\BATFILE_A.txt
option batch on
option confirm off
open sftp://UserA:PasswordA@server
cd /home/test
option transfer binary
Put F:\FILES\A_File.asc
close
exit

B: F:\BATFILE_B.txt
option batch on
option confirm off
open sftp://UserB:PasswordB@server
cd /home/test
option transfer binary
Put F:\FILES\B_File.asc
close
exit

C: F:\BATFILE_C.txt
option batch on
option confirm off
open sftp://UserC:PasswordC@server
cd /home/test
option transfer binary
Put F:\FILES\C_File.asc
close
exit


Each bat file produces one log file. See content of log file below. You'll notice that the information in the log files (A, B) were mixed-up. E.g. In logfile A, there are some info that is supposed to be present in logfile B only.

A: F:\LOGS\LOGFILE_A.txt
batch on
confirm off
Initialisation...
Searching for host...
Connecting to host...
Authenticating...
Using username "UserA".
Using keyboard-interactive authentication.
Starting the session...
Starting the session...
Reading remote directory...
Active session: [1] UserB@server
/home/test
transfer binary
B_File.asc | 0 kB | 0.0 kB/s | binary | 0%Active session: [1] UserC@server
/home/test
transfer binary
A_File.asc | 0 kB | 2.7 kB/s | binary | 100%
Session 'UserB@server' closed.No session.


B: F:\LOGS\LOGFILE_B.txt
Searching for host...
batch on
confirm off
Initialisation...
Connecting to host...
Authenticating...
Using username "UserB".
Using keyboard-interactive authentication.
Authenticated.
Authenticated.
Reading remote directory...
Session started.
Session started.
B_File.asc | 0 kB | 2.2 kB/s | binary | 100%Session 'UserB@server' closed.
C_File.asc | 0 kB | 0.0 kB/s | binary | 0%

C: F:\LOGS\LOGFILE_C.txt
batch on
confirm off
Initialisation...
Searching for host...
Connecting to host...
Authenticating...
Using username "UserC".
Using keyboard-interactive authentication.
Authenticated.
Starting the session...
Reading remote directory...
Session started.
Active session: [1] UserC@server
/home/test
transfer binary
C_File.asc | 0 kB | 2.6 kB/s | binary | 100%
Session 'UserC@server' closed.
No session.

*Note: All bat files connect to the same Target Server. But they use a different account (UserA, UserB, UserC).

How do we resolve this issue?

Winscp version: 3.7.6 Build 306

Are you able to reproduce this with the latest version?

Thanks for the reply. Very much appreciated.

No, we still need approval to upgrade our winscp application since this is in our production server. Do you think this is a bug of version 3.7.6? We noticed that this issue is intermittent -- out of 5 runs, the issue occurred only once.

Hi,
Do you have any idea why this happens? Any input is appreciated.

Thanks!

martin wrote:

Are you able to reproduce this with the latest version?

Please give possible root cause of this issue. Any input is greatly appreciated. Thanks!

Anonymous wrote:

Do you have any idea why this happens? Any input is appreciated.

I'm sorry I cannot provide support for 3.7.6. It is four years old. If you can possibly test this with the latest version (just for testing purposes) to see if you are able to reproduce that, it would help.

martin wrote:

Anonymous wrote:

Do you have any idea why this happens? Any input is appreciated.

I'm sorry I cannot provide support for 3.7.6. It is four years old. If you can possibly test this with the latest version (just for testing purposes) to see if you are able to reproduce that, it would help.

Hello,
Thanks for the response.

Can you give us an overview of how the logging process works? In version 3.7.6, we noticed that the logfile for example: > "C:\Program Files\WinSCP3\winscp3" /console /script=F:\BATFILE_A.txt > F:\LOGS\LOGFILE_A.txt would NOT be created until the transfer completes. Is the transaction details "cached" somewhere? How does Winscp handle this? Also, I was able to download version 4.1.9 and test the bat files, and we noticed that the logfiles are created at the onset and just get updated until the transaction completes. The mixed-up in the logfile in version 3.7.6 maybe due to how this version handles logging of transaction details.

Any input from you is greatly appreciated. Thanks again.

Hello prikryl,

Do you have any documentation regarding my inquiry below? Any input is greatly appreciated.

Thanks.

It is not a log file. You redirect standard output of the application to file. So it is Windows who create/write to the file. WinSCP just write output to its standard output, just as it would be doing if you do not redirect it to the file.

martin wrote:

It is not a log file. You redirect standard output of the application to file. So it is Windows who create/write to the file. WinSCP just write output to its standard output, just as it would be doing if you do not redirect it to the file.

But what could cause the mixed-up? Windows is redirecting the output to a UNIQUE LOG FILENAME. How come there are details of B in C, for example?
.../script=F:\BATFILE_A.txt > F:\LOGS\LOGFILE_A.txt
.../script=F:\BATFILE_B.txt > F:\LOGS\LOGFILE_B.txt
.../script=F:\BATFILE_C.txt > F:\LOGS\LOGFILE_C.txt

I do not know. Once you have the latest version installed, please try to reproduce the problem with it. If you are able to do so, I may be able to try to find out a cause.