date ok, but time bad syncronyzing with ftp

Hello,
I've 2 ftp servers.
One server acts as a master and the other is slave.
The slave is the mirror of the master.
I'm using WinSCP for syncronyzing both.
I'm using a script and a task that runs as administrator for running in background. That script runs in the slave.
Master is Windows 2008 R2 and using IIS 7.5 for ftp server.
Slave is Windows 2003 Server SP2 and using IIS 6 for ftp server.
Both of them are spanish versions and have Madrid as timezone configured.
Seems that slave is reading that master have utc+1 as it's timezone and adding one hour to new fetched files of slave.
One example of bad time in one file (dates fetched from servers, using Remote Desktop):
slave date and time is (modified date): 26/9/2015 07:28:00
the same file in master is (modified date) : 26/9/2015 06:28:00

The log of that transfered file is (changed ip and users):
. 2015-09-26 14:02:46.101 File: '/backups/citrix2/directorio_backups_citrix/backups_citrix 26_09_2015_05_45_03.7z.001' [2015-09-26T04:28:00.000Z] [250331136]
. 2015-09-26 14:02:46.101 Copying "/backups/citrix2/directorio_backups_citrix/backups_citrix 26_09_2015_05_45_03.7z.001" to local directory started.
. 2015-09-26 14:02:46.101 Binary transfer mode selected.
. 2015-09-26 14:02:46.101 Iniciando descarga de /backups/citrix2/directorio_backups_citrix/backups_citrix 26_09_2015_05_45_03.7z.001
> 2015-09-26 14:02:46.101 TYPE I
< 2015-09-26 14:02:46.116 200 Type set to I.
> 2015-09-26 14:02:46.116 PASV
< 2015-09-26 14:02:46.148 227 Entering Passive Mode (x,y,z,v,195,84).
> 2015-09-26 14:02:46.148 RETR backups_citrix 26_09_2015_05_45_03.7z.001
< 2015-09-26 14:02:46.226 125 Data connection already open; Transfer starting.
. 2015-09-26 14:02:46.241 Session ID reused
. 2015-09-26 14:02:46.241 conexión SSL establecida
< 2015-09-26 14:04:47.101 226 Transfer complete.
. 2015-09-26 14:04:48.132 Descarga Exitosa
. 2015-09-26 14:04:48.132 Transfer done: '/backups/citrix2/directorio_backups_citrix/backups_citrix 26_09_2015_05_45_03.7z.001' [250331136]


When connecting to master ftp, log is:

015-09-26 06:30:02.461 --------------------------------------------------------------------------
. 2015-09-26 06:30:02.461 WinSCP Versión 5.7.5 (Compilación 5665) (OS 5.2.3790 Service Pack 2 - Microsoft Windows Server 2003)
. 2015-09-26 06:30:02.461 Configuration: c:\backups\WinSCP.ini
. 2015-09-26 06:30:02.461 Log level: Normal
. 2015-09-26 06:30:02.461 Local account: FTP\Administrador
. 2015-09-26 06:30:02.477 Working directory: c:\backups
. 2015-09-26 06:30:02.477 Process ID: 3916
. 2015-09-26 06:30:02.477 Command-line: "c:\backups\WinSCP.exe" /console=575 /consoleinstance=_656_393 "/script=c:\backups\sincronizaftp.script" "/log=c:\backups\sincronizaftp.log"
. 2015-09-26 06:30:02.477 Time zone: Current: GMT+2, Standard: GMT+1 (Hora estándar romance), DST: GMT+2 (Hora de verano romance), DST Start: 29/03/2015, DST End: 25/10/2015
. 2015-09-26 06:30:02.477 Login time: sábado, 26 de septiembre de 2015 6:30:02
. 2015-09-26 06:30:02.477 --------------------------------------------------------------------------
. 2015-09-26 06:30:02.477 Script: Retrospectively logging previous script records:
> 2015-09-26 06:30:02.477 Script: open ftps://user:***@ipaddress -timeout=120 -implicit
. 2015-09-26 06:30:02.477 --------------------------------------------------------------------------
. 2015-09-26 06:30:02.477 Session name: adminftp@ipaddress (Ad-Hoc site)
. 2015-09-26 06:30:02.477 Host name: ipaddress (Port: 990)
. 2015-09-26 06:30:02.477 User name: user (Password: Yes, Key file: No)
. 2015-09-26 06:30:02.477 Transfer Protocol: FTP
. 2015-09-26 06:30:02.477 Ping type: C, Ping interval: 30 sec; Timeout: 120 sec
. 2015-09-26 06:30:02.477 Disable Nagle: No
. 2015-09-26 06:30:02.477 Proxy: none
. 2015-09-26 06:30:02.477 Send buffer: 262144
. 2015-09-26 06:30:02.477 UTF: 2
. 2015-09-26 06:30:02.477 FTP: FTPS: Implicit TLS/SSL; Passive: Yes [Force IP: A]; MLSD: A [List all: A]
. 2015-09-26 06:30:02.477 Session reuse: Yes
. 2015-09-26 06:30:02.477 TLS/SSL versions: TLSv1.0-TLSv1.2
. 2015-09-26 06:30:02.477 Local directory: default, Remote directory: home, Update: Yes, Cache: Yes
. 2015-09-26 06:30:02.477 Cache directory changes: Yes, Permanent: Yes
. 2015-09-26 06:30:02.477 Timezone offset: 0h 0m
. 2015-09-26 06:30:02.477 --------------------------------------------------------------------------
. 2015-09-26 06:30:02.602 Conectando con ipaddress:990 ...
. 2015-09-26 06:30:02.617 Conectado con ipaddress:990, negociando conexión SSL...
. 2015-09-26 06:30:02.711 Verifying certificate for "" with fingerprint --- and 20 failures
. 2015-09-26 06:30:02.711 Certificate for "" matches legacy cached fingerprint
. 2015-09-26 06:30:02.711 Using TLSv1, cipher TLSv1/SSLv3: AES128-SHA, 2048 bit RSA
. 2015-09-26 06:30:02.711 conexión SSL establecida, esperando la bienvenida...
< 2015-09-26 06:30:02.711 220 Microsoft FTP Service
> 2015-09-26 06:30:02.711 USER user
< 2015-09-26 06:30:02.742 331 Password required for user.
> 2015-09-26 06:30:02.742 PASS *****
< 2015-09-26 06:30:02.758 230 User logged in.
> 2015-09-26 06:30:02.758 SYST
< 2015-09-26 06:30:02.774 215 Windows_NT
> 2015-09-26 06:30:02.774 FEAT
< 2015-09-26 06:30:02.805 211-Extended features supported:
< 2015-09-26 06:30:02.805 LANG EN*
< 2015-09-26 06:30:02.805 UTF8
< 2015-09-26 06:30:02.805 AUTH TLS;TLS-C;SSL;TLS-P;
< 2015-09-26 06:30:02.805 PBSZ
< 2015-09-26 06:30:02.805 PROT C;P;
< 2015-09-26 06:30:02.805 CCC
< 2015-09-26 06:30:02.805 HOST
< 2015-09-26 06:30:02.805 SIZE
< 2015-09-26 06:30:02.805 MDTM
< 2015-09-26 06:30:02.805 REST STREAM
< 2015-09-26 06:30:02.805 211 END
> 2015-09-26 06:30:02.805 OPTS UTF8 ON
< 2015-09-26 06:30:02.820 200 OPTS UTF8 command successful - UTF8 encoding now ON.
> 2015-09-26 06:30:02.820 PBSZ 0
< 2015-09-26 06:30:02.836 200 PBSZ command successful.
> 2015-09-26 06:30:02.836 PROT P
< 2015-09-26 06:30:02.852 200 PROT command successful.
. 2015-09-26 06:30:02.852 Conectado
. 2015-09-26 06:30:02.852 --------------------------------------------------------------------------
. 2015-09-26 06:30:02.852 Using FTP protocol.
. 2015-09-26 06:30:02.852 Doing startup conversation with host.
> 2015-09-26 06:30:02.867 PWD
< 2015-09-26 06:30:02.883 257 "/" is current directory.
. 2015-09-26 06:30:02.883 Getting current directory name.
. 2015-09-26 06:30:02.883 Startup conversation with host finished.
< 2015-09-26 06:30:02.883 Script: Sesión activa: [1] user@ipaddress
> 2015-09-26 06:30:02.883 Script: option batch continue
< 2015-09-26 06:30:02.883 Script: batch continue
< 2015-09-26 06:30:02.883 Script: reconnecttime 120
> 2015-09-26 06:30:02.883 Script: option confirm off
< 2015-09-26 06:30:02.883 Script: confirm off
> 2015-09-26 06:30:02.883 Script: option reconnecttime 60
< 2015-09-26 06:30:02.883 Script: reconnecttime 60
> 2015-09-26 06:30:02.883 Script: lcd "E:\dir"
< 2015-09-26 06:30:02.883 Script: E:\dir
> 2015-09-26 06:30:02.883 Script: synchronize local "E:\dir" /dir -mirror -delete -speed=2000 -resumesupport=on -preservetime
< 2015-09-26 06:30:02.883 Script: Comparando...

To make sure I understand the problem: You connect from the "slave" server using FTP to the "master" server (IIS). So you actually do not use the "slave" IIS server (for purposes of this task), right?

Can you attach a complete script log? Ideally showing a synchronization of a single file only (or a small set of files).

Yes, I connect to the master server using FTP from the slave.

martin wrote:

To make sure I understand the problem: You connect from the "slave" server using FTP to the "master" server (IIS). So you actually do not use the "slave" IIS server (for purposes of this task), right?



Can you attach a complete script log? Ideally showing a synchronization of a single file only (or a small set of files).

I've attached log of some files syncronized.

Regards,

A "partiallog" is not a "complete log".

Also please pick one file and specify, what timestamp does the local file have after the transfer; and what timestamp you expected instead.

martin wrote:

A "partiallog" is not a "complete log".

Also please pick one file and specify, what timestamp does the local file have after the transfer; and what timestamp you expected instead.

Hello,

transfer of one file:
. 2015-10-03 18:00:31.042 File: '/backups/citrix2/directorio_backups_citrix/backups_citrix 30_09_2015_05_45_03.7z.040' [2015-09-30T04:50:00.000Z] [250331136]
. 2015-10-03 18:00:31.042 Copying "/backups/citrix2/directorio_backups_citrix/backups_citrix 30_09_2015_05_45_03.7z.040" to local directory started.
. 2015-10-03 18:00:31.042 Binary transfer mode selected.
. 2015-10-03 18:00:31.042 Iniciando descarga de /backups/citrix2/directorio_backups_citrix/backups_citrix 30_09_2015_05_45_03.7z.040
> 2015-10-03 18:00:31.042 TYPE I
< 2015-10-03 18:00:31.073 200 Type set to I.
> 2015-10-03 18:00:31.073 PASV
< 2015-10-03 18:00:31.089 227 Entering Passive Mode (x,y,z,v,195,151).
> 2015-10-03 18:00:31.105 RETR backups_citrix 30_09_2015_05_45_03.7z.040
< 2015-10-03 18:00:31.136 150 Opening BINARY mode data connection.
. 2015-10-03 18:00:31.152 Session ID reused
. 2015-10-03 18:00:31.152 conexión SSL establecida
< 2015-10-03 18:02:32.058 226 Transfer complete.
. 2015-10-03 18:02:33.058 Descarga Exitosa
. 2015-10-03 18:02:33.058 Transfer done: '/backups/citrix2/directorio_backups_citrix/backups_citrix 30_09_2015_05_45_03.7z.040' [250331136]

that file has timestamp("fecha de modificación" in spanish version) in slave ftp of: 30/9/2015 07:50

expected timestamp is (timestamp in master ftp) : 30/9/2015 06:50

time offset between the two is 1 hour.

Regards,