FTP-Upload only successfull on second try | Ticket#5955787

Question

Hello,

I have the following problem on uploading files to a customers FTP. Every upload seems successfull but the uploaded file is only shown on the server if i do a second run. If I do the same with FileZilla the first upload shows the file on the server.

Any guess what could be wrong? In the Logs I see no difference between this two runs, so i have no glue.

Thanks,

Kirsten

Answer 1

not successfull:

2024-03-26 09:44:03.763 Opening log file.
2024-03-26 09:44:03.766 INFO FileLogWriter(1)[1] Info: Assembly: Rebex.Common 7.0.8816 for .NET Framework 4.6-4.8
2024-03-26 09:44:03.767 INFO FileLogWriter(1)[1] Info: Platform: Windows 6.2.9200 64-bit; CLR: 4.0.30319.42000
2024-03-26 09:44:03.768 DEBUG FileLogWriter(1)[1] Info: Culture: de; Windows-1252
2024-03-26 09:44:03.838 INFO Ftp(1)[1] Info: Connecting to ftp2.xyz.com:21 using Ftp.
2024-03-26 09:44:03.839 INFO Ftp(1)[1] Info: Assembly: Rebex.Ftp 7.0.8816 for .NET Framework 4.6-4.8
2024-03-26 09:44:03.839 INFO Ftp(1)[1] Info: Platform: Windows 6.2.9200 64-bit; CLR: 4.0.30319.42000
2024-03-26 09:44:03.839 DEBUG Ftp(1)[1] Info: Culture: de; Windows-1252
2024-03-26 09:44:03.839 INFO Ftp(1)[1] Info: Using proxy none.
2024-03-26 09:44:03.850 DEBUG Ftp(1)[1] Proxy: Resolving 'ftp2.xyz.com'.
2024-03-26 09:44:03.891 DEBUG Ftp(1)[1] Proxy: Connecting to 91.198.25.106:21 (no proxy).
2024-03-26 09:44:03.919 DEBUG Ftp(1)[1] Proxy: Connection established.
2024-03-26 09:44:03.920 DEBUG Ftp(1)[1] Info: Connection succeeded.
2024-03-26 09:44:04.057 INFO Ftp(1)[1] Response: 220 FTP Server ready.
2024-03-26 09:44:04.068 INFO Ftp(1)[1] Command: AUTH TLS
2024-03-26 09:44:04.093 INFO Ftp(1)[1] Response: 234 AUTH TLS successful
2024-03-26 09:44:04.096 DEBUG Ftp(1)[1] Info: Upgrading control connection to TLS.
2024-03-26 09:44:04.153 DEBUG Ftp(1)[1] TLS: Using classic TLS core.
2024-03-26 09:44:04.171 DEBUG Ftp(1)[1] TLS: Enabled cipher suites: 0x000F3CF3EBC00600.
2024-03-26 09:44:04.171 DEBUG Ftp(1)[1] TLS: Applicable cipher suites: 0x000F3CF3EBC00600.
2024-03-26 09:44:04.233 DEBUG Ftp(1)[1] TLS: HandshakeMessage:ClientHello was sent.
2024-03-26 09:44:04.277 DEBUG Ftp(1)[1] TLS: HandshakeMessage:ServerHello was received.
2024-03-26 09:44:04.283 INFO Ftp(1)[1] TLS: Negotiating TLS 1.2, RSA with ephemeral ECDH, AES with 128-bit key in GCM mode, AEAD.
2024-03-26 09:44:04.285 DEBUG Ftp(1)[1] TLS: The server supports secure renegotiation.
2024-03-26 09:44:04.286 DEBUG Ftp(1)[1] TLS: The server does not support extended master secret.
2024-03-26 09:44:04.287 DEBUG Ftp(1)[1] TLS: HandshakeMessage:Certificate was received.
2024-03-26 09:44:04.297 DEBUG Ftp(1)[1] TLS: HandshakeMessage:ServerKeyExchange was received.
2024-03-26 09:44:04.299 DEBUG Ftp(1)[1] TLS: HandshakeMessage:CertificateRequest was received.
2024-03-26 09:44:04.299 DEBUG Ftp(1)[1] TLS: HandshakeMessage:ServerHelloDone was received.
2024-03-26 09:44:04.301 DEBUG Ftp(1)[1] TLS: Verifying server certificate ('CN=*.xyz.com, O=PBS Logitek Dienstleistungen GmbH, L=Wels, S=Oberösterreich, C=AT').
2024-03-26 09:44:04.340 DEBUG Ftp(1)[1] TLS: Certificate verification result: Accept
2024-03-26 09:44:04.341 DEBUG Ftp(1)[1] TLS: Verifying server key exchange signature.
2024-03-26 09:44:04.359 DEBUG Ftp(1)[1] TLS: Using ephemeral ECDH public key exchange with NIST P-256 curve.
2024-03-26 09:44:04.364 DEBUG Ftp(1)[1] TLS: Client certificate authentication was requested.
2024-03-26 09:44:04.364 DEBUG Ftp(1)[1] TLS: No suitable client certificate is available.
2024-03-26 09:44:04.365 DEBUG Ftp(1)[1] TLS: HandshakeMessage:Certificate was sent.
2024-03-26 09:44:04.366 DEBUG Ftp(1)[1] TLS: HandshakeMessage:ClientKeyExchange was sent.
2024-03-26 09:44:04.376 DEBUG Ftp(1)[1] TLS: CipherSpec:ChangeCipherSpec was sent.
2024-03-26 09:44:04.377 DEBUG Ftp(1)[1] TLS: HandshakeMessage:Finished was sent.
2024-03-26 09:44:04.407 DEBUG Ftp(1)[1] TLS: CipherSpec:ChangeCipherSpec was received.
2024-03-26 09:44:04.409 DEBUG Ftp(1)[1] TLS: HandshakeMessage:Finished was received.
2024-03-26 09:44:04.410 INFO Ftp(1)[1] TLS: Connection secured using cipher: TLS 1.2, RSA with ephemeral ECDH, AES with 128-bit key in GCM mode, AEAD.
2024-03-26 09:44:04.410 DEBUG Ftp(1)[1] Info: Control connection upgraded to TLS 1.2.
2024-03-26 09:44:04.414 INFO Ftp(1)[1] Command: USER user
2024-03-26 09:44:04.444 INFO Ftp(1)[1] Response: 331 Password required for user
2024-03-26 09:44:04.446 INFO Ftp(1)[1] Command: PASS **********
2024-03-26 09:44:04.506 INFO Ftp(1)[1] Response: 230 User user logged in
2024-03-26 09:44:04.509 INFO Ftp(1)[1] Command: FEAT
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response: 211-Features:
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  MFMT
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  LANG en-US.UTF-8*;en-US
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  SIZE
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  PROT
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  CCC
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  SITE MKDIR
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  PBSZ
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  AUTH TLS
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  MFF modify;UNIX.group;UNIX.mode;
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  REST STREAM
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.mode*;UNIX.owner*;
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  UTF8
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  EPRT
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  SITE SYMLINK
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  EPSV
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  SITE UTIME
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  MDTM
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  SITE RMDIR
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  SSCN
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  MODE Z
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  TVFS
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response:  SITE COPY
2024-03-26 09:44:04.535 INFO Ftp(1)[1] Response: 211 End
2024-03-26 09:44:04.542 INFO Ftp(1)[1] Command: OPTS UTF8 ON
2024-03-26 09:44:04.567 INFO Ftp(1)[1] Response: 200 UTF8 set to on
2024-03-26 09:44:04.571 INFO Ftp(1)[1] Command: OPTS MLST modify;perm;size;type;UNIX.group;UNIX.mode;UNIX.owner;
2024-03-26 09:44:04.597 INFO Ftp(1)[1] Response: 200 OPTS MLST modify;perm;size;type;UNIX.group;UNIX.mode;UNIX.owner;
2024-03-26 09:44:04.599 INFO Ftp(1)[1] Batch: Calling Upload(string = 'C:\tmp\Seidler\seidler-test2.xml', string = '.\', TraversalMode = 'Recursive', TransferMethod = 'Copy', ActionOnExistingFiles = 'OverwriteOlder').
2024-03-26 09:44:04.619 DEBUG Ftp(1)[1] Batch: Executing multi-file operation: Uploading, source = 'C:\tmp\Seidler\seidler-test2.xml', target = '.\', TransferMethod.Copy, MoveMode.All, LinkProcessingMode.FollowLinks, ActionOnExistingFiles.OverwriteOlder.
2024-03-26 09:44:04.620 DEBUG Ftp(1)[1] Batch: Normalizing source path ('C:\tmp\Seidler\seidler-test2.xml').
2024-03-26 09:44:04.621 DEBUG Ftp(1)[1] Batch: Checking source path ('C:\tmp\Seidler\seidler-test2.xml').
2024-03-26 09:44:04.624 DEBUG Ftp(1)[1] Batch: Normalizing target path ('.\').
2024-03-26 09:44:04.625 INFO Ftp(1)[1] Command: PWD
2024-03-26 09:44:04.651 INFO Ftp(1)[1] Response: 257 "/" is the current directory
2024-03-26 09:44:04.658 DEBUG Ftp(1)[1] Batch: Checking target path ('/').
2024-03-26 09:44:04.659 DEBUG Ftp(1)[1] Batch: Multi-file operation started.
2024-03-26 09:44:04.668 DEBUG Ftp(1)[1] Batch: Transferring hierarchy started.
2024-03-26 09:44:04.671 DEBUG Ftp(1)[1] Batch: Processing file to transfer ('C:\tmp\Seidler\seidler-test2.xml').
2024-03-26 09:44:04.672 DEBUG Ftp(1)[1] Batch: Checking existence of target item ('/seidler-test2.xml').
2024-03-26 09:44:04.676 INFO Ftp(1)[1] Command: MLST seidler-test2.xml
2024-03-26 09:44:04.702 INFO Ftp(1)[1] Response: 550 'seidler-test2.xml' cannot be listed
2024-03-26 09:44:04.703 DEBUG Ftp(1)[1] Batch: Transferring file ('C:\tmp\Seidler\seidler-test2.xml' ---> '/seidler-test2.xml').
2024-03-26 09:44:04.715 DEBUG Ftp(1)[1] Info: Starting data transfer.
2024-03-26 09:44:04.716 INFO Ftp(1)[1] Command: PBSZ 0
2024-03-26 09:44:04.742 INFO Ftp(1)[1] Response: 200 PBSZ 0 successful
2024-03-26 09:44:04.742 INFO Ftp(1)[1] Command: PROT P
2024-03-26 09:44:04.767 INFO Ftp(1)[1] Response: 200 Protection set to Private
2024-03-26 09:44:04.768 INFO Ftp(1)[1] Command: TYPE I
2024-03-26 09:44:04.793 INFO Ftp(1)[1] Response: 200 Type set to I
2024-03-26 09:44:04.798 INFO Ftp(1)[1] Command: PASV
2024-03-26 09:44:04.825 INFO Ftp(1)[1] Response: 227 Entering Passive Mode (192,168,1,7,194,54).
2024-03-26 09:44:04.829 INFO Ftp(1)[1] Info: Using server address 91.198.25.106 instead of received address 192.168.1.7.
2024-03-26 09:44:04.831 DEBUG Ftp(1)[1] Info: Establishing data connection to 91.198.25.106:49718.
2024-03-26 09:44:04.831 DEBUG Ftp(1)[1] Proxy: Connecting to 91.198.25.106:49718 (no proxy).
2024-03-26 09:44:04.858 DEBUG Ftp(1)[1] Proxy: Connection established.
2024-03-26 09:44:04.858 DEBUG Ftp(1)[1] Info: Established data connection from 192.168.42.109:53695.
2024-03-26 09:44:04.860 INFO Ftp(1)[1] Command: STOR seidler-test2.xml
2024-03-26 09:44:04.886 INFO Ftp(1)[1] Response: 150 Opening BINARY mode data connection for seidler-test2.xml
2024-03-26 09:44:04.892 DEBUG Ftp(1)[1] Info: Upgrading data connection to TLS.
2024-03-26 09:44:04.893 DEBUG Ftp(1)[1] TLS: Data connection: Using classic TLS core.
2024-03-26 09:44:04.893 DEBUG Ftp(1)[1] TLS: Data connection: Enabled cipher suites: 0x000F3CF3EBC00600.
2024-03-26 09:44:04.893 DEBUG Ftp(1)[1] TLS: Data connection: Applicable cipher suites: 0x000F3CF3EBC00600.
2024-03-26 09:44:04.893 INFO Ftp(1)[1] TLS: Data connection: Trying to resume session.
2024-03-26 09:44:04.894 DEBUG Ftp(1)[1] TLS: Data connection: HandshakeMessage:ClientHello was sent.
2024-03-26 09:44:05.159 DEBUG Ftp(1)[1] TLS: Data connection: HandshakeMessage:ServerHello was received.
2024-03-26 09:44:05.159 INFO Ftp(1)[1] TLS: Data connection: Negotiating TLS 1.2, RSA with ephemeral ECDH, AES with 128-bit key in GCM mode, AEAD.
2024-03-26 09:44:05.159 DEBUG Ftp(1)[1] TLS: Data connection: The server supports secure renegotiation.
2024-03-26 09:44:05.159 INFO Ftp(1)[1] TLS: Data connection: Resuming session.
2024-03-26 09:44:05.159 DEBUG Ftp(1)[1] TLS: Data connection: CipherSpec:ChangeCipherSpec was received.
2024-03-26 09:44:05.159 DEBUG Ftp(1)[1] TLS: Data connection: HandshakeMessage:Finished was received.
2024-03-26 09:44:05.160 DEBUG Ftp(1)[1] TLS: Data connection: CipherSpec:ChangeCipherSpec was sent.
2024-03-26 09:44:05.160 DEBUG Ftp(1)[1] TLS: Data connection: HandshakeMessage:Finished was sent.
2024-03-26 09:44:05.160 INFO Ftp(1)[1] TLS: Data connection: Connection secured using cipher: TLS 1.2, RSA with ephemeral ECDH, AES with 128-bit key in GCM mode, AEAD.
2024-03-26 09:44:05.160 DEBUG Ftp(1)[1] Info: Data connection upgraded to TLS 1.2.
2024-03-26 09:44:05.160 DEBUG Ftp(1)[1] Info: Data transfer started.
2024-03-26 09:44:05.165 DEBUG Ftp(1)[1] Info: Closing uploading data connection.
2024-03-26 09:44:05.166 DEBUG Ftp(1)[1] TLS: Data connection: Closing TLS socket.
2024-03-26 09:44:05.168 DEBUG Ftp(1)[1] TLS: Data connection: Alert:CloseNotify was sent.
2024-03-26 09:44:05.172 DEBUG Ftp(1)[1] Info: Waiting for data transfer ending message.
2024-03-26 09:44:05.195 INFO Ftp(1)[1] Response: 226 Transfer complete
2024-03-26 09:44:05.196 DEBUG Ftp(1)[1] Info: Data transfer ended.
2024-03-26 09:44:05.196 DEBUG Ftp(1)[1] Info: Transfer ended successfully.
2024-03-26 09:44:05.196 DEBUG Ftp(1)[1] Info: Data connection closed.
2024-03-26 09:44:05.198 DEBUG Ftp(1)[1] Batch: Multi-file operation done.
2024-03-26 09:44:05.199 INFO Ftp(1)[1] Command: QUIT
2024-03-26 09:44:05.225 INFO Ftp(1)[1] Response: 221 Goodbye.
2024-03-26 09:44:05.226 DEBUG Ftp(1)[1] TLS: Closing TLS socket.
2024-03-26 09:44:05.226 DEBUG Ftp(1)[1] TLS: Alert:CloseNotify was sent.

Comments

successfull:

2024-03-26 09:44:52.742 Opening log file.
2024-03-26 09:44:52.742 INFO FileLogWriter(2)[1] Info: Assembly: Rebex.Common 7.0.8816 for .NET Framework 4.6-4.8
2024-03-26 09:44:52.742 INFO FileLogWriter(2)[1] Info: Platform: Windows 6.2.9200 64-bit; CLR: 4.0.30319.42000
2024-03-26 09:44:52.742 DEBUG FileLogWriter(2)[1] Info: Culture: de; Windows-1252
2024-03-26 09:44:52.743 INFO Ftp(2)[1] Info: Connecting to ftp2.xyz.com:21 using Ftp.
2024-03-26 09:44:52.743 INFO Ftp(2)[1] Info: Assembly: Rebex.Ftp 7.0.8816 for .NET Framework 4.6-4.8
2024-03-26 09:44:52.743 INFO Ftp(2)[1] Info: Platform: Windows 6.2.9200 64-bit; CLR: 4.0.30319.42000
2024-03-26 09:44:52.743 DEBUG Ftp(2)[1] Info: Culture: de; Windows-1252
2024-03-26 09:44:52.743 INFO Ftp(2)[1] Info: Using proxy none.
2024-03-26 09:44:52.743 DEBUG Ftp(2)[1] Proxy: Resolving 'ftp2.xyz.com'.
2024-03-26 09:44:52.758 DEBUG Ftp(2)[1] Proxy: Connecting to 91.198.25.106:21 (no proxy).
2024-03-26 09:44:52.786 DEBUG Ftp(2)[1] Proxy: Connection established.
2024-03-26 09:44:52.786 DEBUG Ftp(2)[1] Info: Connection succeeded.
2024-03-26 09:44:52.939 INFO Ftp(2)[1] Response: 220 FTP Server ready.
2024-03-26 09:44:52.940 INFO Ftp(2)[1] Command: AUTH TLS
2024-03-26 09:44:52.965 INFO Ftp(2)[1] Response: 234 AUTH TLS successful
2024-03-26 09:44:52.965 DEBUG Ftp(2)[1] Info: Upgrading control connection to TLS.
2024-03-26 09:44:52.966 DEBUG Ftp(2)[1] TLS: Using classic TLS core.
2024-03-26 09:44:52.966 DEBUG Ftp(2)[1] TLS: Enabled cipher suites: 0x000F3CF3EBC00600.
2024-03-26 09:44:52.966 DEBUG Ftp(2)[1] TLS: Applicable cipher suites: 0x000F3CF3EBC00600.
2024-03-26 09:44:52.966 DEBUG Ftp(2)[1] TLS: HandshakeMessage:ClientHello was sent.
2024-03-26 09:44:52.996 DEBUG Ftp(2)[1] TLS: HandshakeMessage:ServerHello was received.
2024-03-26 09:44:52.996 INFO Ftp(2)[1] TLS: Negotiating TLS 1.2, RSA with ephemeral ECDH, AES with 128-bit key in GCM mode, AEAD.
2024-03-26 09:44:52.996 DEBUG Ftp(2)[1] TLS: The server supports secure renegotiation.
2024-03-26 09:44:52.996 DEBUG Ftp(2)[1] TLS: The server does not support extended master secret.
2024-03-26 09:44:52.996 DEBUG Ftp(2)[1] TLS: HandshakeMessage:Certificate was received.
2024-03-26 09:44:52.997 DEBUG Ftp(2)[1] TLS: HandshakeMessage:ServerKeyExchange was received.
2024-03-26 09:44:52.997 DEBUG Ftp(2)[1] TLS: HandshakeMessage:CertificateRequest was received.
2024-03-26 09:44:52.997 DEBUG Ftp(2)[1] TLS: HandshakeMessage:ServerHelloDone was received.
2024-03-26 09:44:52.997 DEBUG Ftp(2)[1] TLS: Verifying server certificate ('CN=*.xyz.com, O=PBS Logitek Dienstleistungen GmbH, L=Wels, S=Oberösterreich, C=AT').
2024-03-26 09:44:52.998 DEBUG Ftp(2)[1] TLS: Certificate verification result: Accept
2024-03-26 09:44:53.000 DEBUG Ftp(2)[1] TLS: Verifying server key exchange signature.
2024-03-26 09:44:53.000 DEBUG Ftp(2)[1] TLS: Using ephemeral ECDH public key exchange with NIST P-256 curve.
2024-03-26 09:44:53.001 DEBUG Ftp(2)[1] TLS: Client certificate authentication was requested.
2024-03-26 09:44:53.001 DEBUG Ftp(2)[1] TLS: No suitable client certificate is available.
2024-03-26 09:44:53.001 DEBUG Ftp(2)[1] TLS: HandshakeMessage:Certificate was sent.
2024-03-26 09:44:53.001 DEBUG Ftp(2)[1] TLS: HandshakeMessage:ClientKeyExchange was sent.
2024-03-26 09:44:53.002 DEBUG Ftp(2)[1] TLS: CipherSpec:ChangeCipherSpec was sent.
2024-03-26 09:44:53.002 DEBUG Ftp(2)[1] TLS: HandshakeMessage:Finished was sent.
2024-03-26 09:44:53.027 DEBUG Ftp(2)[1] TLS: CipherSpec:ChangeCipherSpec was received.
2024-03-26 09:44:53.027 DEBUG Ftp(2)[1] TLS: HandshakeMessage:Finished was received.
2024-03-26 09:44:53.028 INFO Ftp(2)[1] TLS: Connection secured using cipher: TLS 1.2, RSA with ephemeral ECDH, AES with 128-bit key in GCM mode, AEAD.
2024-03-26 09:44:53.028 DEBUG Ftp(2)[1] Info: Control connection upgraded to TLS 1.2.
2024-03-26 09:44:53.028 INFO Ftp(2)[1] Command: USER user
2024-03-26 09:44:53.053 INFO Ftp(2)[1] Response: 331 Password required for user
2024-03-26 09:44:53.054 INFO Ftp(2)[1] Command: PASS **********
2024-03-26 09:44:53.119 INFO Ftp(2)[1] Response: 230 User user logged in
2024-03-26 09:44:53.120 INFO Ftp(2)[1] Command: FEAT
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response: 211-Features:
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  MFMT
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  LANG en-US.UTF-8*;en-US
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  SIZE
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  PROT
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  CCC
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  SITE MKDIR
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  PBSZ
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  AUTH TLS
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  MFF modify;UNIX.group;UNIX.mode;
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  REST STREAM
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.mode*;UNIX.owner*;
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  UTF8
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  EPRT
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  SITE SYMLINK
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  EPSV
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  SITE UTIME
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  MDTM
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  SITE RMDIR
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  SSCN
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  MODE Z
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  TVFS
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response:  SITE COPY
2024-03-26 09:44:53.145 INFO Ftp(2)[1] Response: 211 End
2024-03-26 09:44:53.146 INFO Ftp(2)[1] Command: OPTS UTF8 ON
2024-03-26 09:44:53.172 INFO Ftp(2)[1] Response: 200 UTF8 set to on
2024-03-26 09:44:53.172 INFO Ftp(2)[1] Command: OPTS MLST modify;perm;size;type;UNIX.group;UNIX.mode;UNIX.owner;
2024-03-26 09:44:53.197 INFO Ftp(2)[1] Response: 200 OPTS MLST modify;perm;size;type;UNIX.group;UNIX.mode;UNIX.owner;
2024-03-26 09:44:53.197 INFO Ftp(2)[1] Batch: Calling Upload(string = 'C:\tmp\Seidler\seidler-test2.xml', string = '.\', TraversalMode = 'Recursive', TransferMethod = 'Copy', ActionOnExistingFiles = 'OverwriteOlder').
2024-03-26 09:44:53.198 DEBUG Ftp(2)[1] Batch: Executing multi-file operation: Uploading, source = 'C:\tmp\Seidler\seidler-test2.xml', target = '.\', TransferMethod.Copy, MoveMode.All, LinkProcessingMode.FollowLinks, ActionOnExistingFiles.OverwriteOlder.
2024-03-26 09:44:53.198 DEBUG Ftp(2)[1] Batch: Normalizing source path ('C:\tmp\Seidler\seidler-test2.xml').
2024-03-26 09:44:53.198 DEBUG Ftp(2)[1] Batch: Checking source path ('C:\tmp\Seidler\seidler-test2.xml').
2024-03-26 09:44:53.198 DEBUG Ftp(2)[1] Batch: Normalizing target path ('.\').
2024-03-26 09:44:53.198 INFO Ftp(2)[1] Command: PWD
2024-03-26 09:44:53.223 INFO Ftp(2)[1] Response: 257 "/" is the current directory
2024-03-26 09:44:53.223 DEBUG Ftp(2)[1] Batch: Checking target path ('/').
2024-03-26 09:44:53.223 DEBUG Ftp(2)[1] Batch: Multi-file operation started.
2024-03-26 09:44:53.223 DEBUG Ftp(2)[1] Batch: Transferring hierarchy started.
2024-03-26 09:44:53.223 DEBUG Ftp(2)[1] Batch: Processing file to transfer ('C:\tmp\Seidler\seidler-test2.xml').
2024-03-26 09:44:53.223 DEBUG Ftp(2)[1] Batch: Checking existence of target item ('/seidler-test2.xml').
2024-03-26 09:44:53.223 INFO Ftp(2)[1] Command: MLST seidler-test2.xml
2024-03-26 09:44:53.249 INFO Ftp(2)[1] Response: 550 'seidler-test2.xml' cannot be listed
2024-03-26 09:44:53.249 DEBUG Ftp(2)[1] Batch: Transferring file ('C:\tmp\Seidler\seidler-test2.xml' ---> '/seidler-test2.xml').
2024-03-26 09:44:53.249 DEBUG Ftp(2)[1] Info: Starting data transfer.
2024-03-26 09:44:53.249 INFO Ftp(2)[1] Command: PBSZ 0
2024-03-26 09:44:53.276 INFO Ftp(2)[1] Response: 200 PBSZ 0 successful
2024-03-26 09:44:53.276 INFO Ftp(2)[1] Command: PROT P
2024-03-26 09:44:53.302 INFO Ftp(2)[1] Response: 200 Protection set to Private
2024-03-26 09:44:53.302 INFO Ftp(2)[1] Command: TYPE I
2024-03-26 09:44:53.327 INFO Ftp(2)[1] Response: 200 Type set to I
2024-03-26 09:44:53.327 INFO Ftp(2)[1] Command: PASV
2024-03-26 09:44:53.353 INFO Ftp(2)[1] Response: 227 Entering Passive Mode (192,168,1,7,218,165).
2024-03-26 09:44:53.354 INFO Ftp(2)[1] Info: Using server address 91.198.25.106 instead of received address 192.168.1.7.
2024-03-26 09:44:53.354 DEBUG Ftp(2)[1] Info: Establishing data connection to 91.198.25.106:55973.
2024-03-26 09:44:53.354 DEBUG Ftp(2)[1] Proxy: Connecting to 91.198.25.106:55973 (no proxy).
2024-03-26 09:44:53.380 DEBUG Ftp(2)[1] Proxy: Connection established.
2024-03-26 09:44:53.380 DEBUG Ftp(2)[1] Info: Established data connection from 192.168.42.109:53702.
2024-03-26 09:44:53.380 INFO Ftp(2)[1] Command: STOR seidler-test2.xml
2024-03-26 09:44:53.407 INFO Ftp(2)[1] Response: 150 Opening BINARY mode data connection for seidler-test2.xml
2024-03-26 09:44:53.407 DEBUG Ftp(2)[1] Info: Upgrading data connection to TLS.
2024-03-26 09:44:53.407 DEBUG Ftp(2)[1] TLS: Data connection: Using classic TLS core.
2024-03-26 09:44:53.407 DEBUG Ftp(2)[1] TLS: Data connection: Enabled cipher suites: 0x000F3CF3EBC00600.
2024-03-26 09:44:53.407 DEBUG Ftp(2)[1] TLS: Data connection: Applicable cipher suites: 0x000F3CF3EBC00600.
2024-03-26 09:44:53.407 INFO Ftp(2)[1] TLS: Data connection: Trying to resume session.
2024-03-26 09:44:53.407 DEBUG Ftp(2)[1] TLS: Data connection: HandshakeMessage:ClientHello was sent.
2024-03-26 09:44:53.671 DEBUG Ftp(2)[1] TLS: Data connection: HandshakeMessage:ServerHello was received.
2024-03-26 09:44:53.672 INFO Ftp(2)[1] TLS: Data connection: Negotiating TLS 1.2, RSA with ephemeral ECDH, AES with 128-bit key in GCM mode, AEAD.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] TLS: Data connection: The server supports secure renegotiation.
2024-03-26 09:44:53.672 INFO Ftp(2)[1] TLS: Data connection: Resuming session.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] TLS: Data connection: CipherSpec:ChangeCipherSpec was received.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] TLS: Data connection: HandshakeMessage:Finished was received.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] TLS: Data connection: CipherSpec:ChangeCipherSpec was sent.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] TLS: Data connection: HandshakeMessage:Finished was sent.
2024-03-26 09:44:53.672 INFO Ftp(2)[1] TLS: Data connection: Connection secured using cipher: TLS 1.2, RSA with ephemeral ECDH, AES with 128-bit key in GCM mode, AEAD.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] Info: Data connection upgraded to TLS 1.2.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] Info: Data transfer started.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] Info: Closing uploading data connection.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] TLS: Data connection: Closing TLS socket.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] TLS: Data connection: Alert:CloseNotify was sent.
2024-03-26 09:44:53.672 DEBUG Ftp(2)[1] Info: Waiting for data transfer ending message.
2024-03-26 09:44:53.698 INFO Ftp(2)[1] Response: 226 Transfer complete
2024-03-26 09:44:53.698 DEBUG Ftp(2)[1] Info: Data transfer ended.
2024-03-26 09:44:53.698 DEBUG Ftp(2)[1] Info: Transfer ended successfully.
2024-03-26 09:44:53.698 DEBUG Ftp(2)[1] Info: Data connection closed.
2024-03-26 09:44:53.698 DEBUG Ftp(2)[1] Batch: Multi-file operation done.
2024-03-26 09:44:53.698 INFO Ftp(2)[1] Command: QUIT
2024-03-26 09:44:53.723 INFO Ftp(2)[1] Response: 221 Goodbye.
2024-03-26 09:44:53.723 DEBUG Ftp(2)[1] TLS: Closing TLS socket.
2024-03-26 09:44:53.723 DEBUG Ftp(2)[1] TLS: Alert:CloseNotify was sent.

---

Could you please specify what you actually mean by "the uploaded file is only shown on the server"? Or, in other words, how exactly (and using what apps or tools) are you determining whether a file is shown on the server?
Also, if you call Ftp.GetList() after uploading the file, does the file appear in the retrieved listing?

---

What I mean is, that after the first upload, the file is NOT shown on the server, if I look with another FTP-Tool like FileZilla.
After the second upload of the same file, the file IS shown on the server.
The logs of both uploads seem for me identically, so i have no glue, what could be wrong.

The FTP.GetFileList() I did'nt use, but I will make a Test now.

[€] Tried it now, and everything worked. Maybe the customers-IT did some changes without notifing us.

Answer 2

From the FTP client's point-of-view, both the transfers were performed the same way and both were successful and acknowledged by the server with "226 Transfer complete" response. If they are not appearing in a list retrieved by Filezilla client, then either Filezilla has not retrieved an up-to-date listing, or the server is withholding the newly-appeared items from the client. For example, this could happen if the server needs to perform some action with the upload files (such as scanning them for viruses) before adding the to the list. Perhaps this is what was going on?

Comments

Yes, this could be, but they did not answer my question, if this was the case. So don't know what was going on, but now it is working. Tnaks You.

---

If it happens again, try calling Rebex FTP's Ftp.GetList after uploading the file to make sure it actually appears in the listing. If it's not there, it would suggest that there is indeed some kind of processing of the uploaded file at the server.