Our Products:   CompleteFTP  edtFTPnet/Free  edtFTPnet/PRO  edtFTPj/Free  edtFTPj/PRO
0 votes
477 views
in .NET FTP by (120 points)

I'm running into an issue where using v 9.0, upgraded from 8.5.0.20 where we're getting an error when trying to connect to an FTPES server. I have two logs. The first is from version 8.5.0.20 and the second is from 9.0. 

Version 8.5.0.20

-------------------------------------------------

-- partially removed to save characters

DEBUG [FTPControlSocket] 1 sep 2016 08:13:41.103 : StrictReturnCodes=False
DEBUG [FTPControlSocket] 1 sep 2016 08:13:41.111 : 220 Microsoft FTP Service
DEBUG [FTPControlSocket] 1 sep 2016 08:13:41.118 : ---> AUTH TLS
DEBUG [FTPControlSocket] 1 sep 2016 08:13:41.135 : 234 AUTH command ok. Expecting TLS Negotiation.
DEBUG [SSLFTPControlSocket] 1 sep 2016 08:13:41.136 : Beginning Ssl3, Tls1, Tls11 handshake.
DEBUG [SocketController] 1 sep 2016 08:13:41.550 : FTPConnection.1Processing hello
DEBUG [SecureSocket] 1 sep 2016 08:13:41.647 : FTPConnection.1OnHandshakeComplete(True,null)
DEBUG [SSLFTPControlSocket] 1 sep 2016 08:13:41.647 : Ssl3, Tls1, Tls11 handshake complete.
DEBUG [FTPControlSocket] 1 sep 2016 08:13:41.647 : ---> PBSZ 0
ALL [AsyncResult] 1 sep 2016 08:13:41.650 : FTPConnection.1Notify setting completed: null 0
ALL [AsyncResult] 1 sep 2016 08:13:41.650 : FTPConnection.1Notify setting wait: 0
ALL [SocketController] 1 sep 2016 08:13:41.651 : FTPConnection.1WaitUntilCompleted(0, EndSend)
ALL [TransferBuffer] 1 sep 2016 08:13:41.656 : FTPConnection.1Wait begin: TransferBuffer.Read (timeout=60000)
ALL [TransferBuffer] 1 sep 2016 08:13:41.663 : FTPConnection.1Wait end: TransferBuffer.Read
DEBUG [FTPControlSocket] 1 sep 2016 08:13:41.663 : FTPConnection.1200 PBSZ command successful.
ALL [AsyncResult] 1 sep 2016 08:13:41.664 : FTPConnection.1Notify setting completed: null 1
ALL [AsyncResult] 1 sep 2016 08:13:41.664 : FTPConnection.1Notify setting wait: 1
DEBUG [FTPControlSocket] 1 sep 2016 08:13:41.664 : FTPConnection.1---> PROT P
ALL [SocketController] 1 sep 2016 08:13:41.664 : FTPConnection.1WaitUntilCompleted(2, EndSend)
ALL [AsyncResult] 1 sep 2016 08:13:41.664 : FTPConnection.1Notify setting completed: null 2
ALL [AsyncResult] 1 sep 2016 08:13:41.664 : FTPConnection.1Notify setting wait: 2
ALL [AsyncResult] 1 sep 2016 08:13:41.665 : FTPConnection.1WaitOne completed: 2
ALL [TransferBuffer] 1 sep 2016 08:13:41.665 : FTPConnection.1Wait begin: TransferBuffer.Read (timeout=60000)

... 400 more lines of successful transfer log

-------------------------------------------------

 

Version 9.0

-------------------------------------------------

DEBUG [SFTPMessageStore] 1 sep 2016 08:01:34.206 :  Set timeout=60
DEBUG [SFTPMessageStore] 1 sep 2016 08:01:34.209 :  Set timeout=60000
INFO [LicenseProperties] 1 sep 2016 08:01:34.216 :  Licence expiry date: 31-12-9999
INFO [LicenseProperties] 1 sep 2016 08:01:34.217 :  Production license
DEBUG [FTPConnection] 1 sep 2016 08:01:34.217 :  Set LocalDirectory='C:\WINDOWS\system32'
DEBUG [SecureFTPConnection] 1 sep 2016 08:01:34.218 :  Set LocalDirectory to C:\WINDOWS\system32
INFO [LicenseProperties] 1 sep 2016 08:01:34.219 :  Licence expiry date: 31-12-9999
INFO [LicenseProperties] 1 sep 2016 08:01:34.219 :  Production license
DEBUG [SSLFTPClient] 1 sep 2016 08:01:34.219 :  Connecting to ftpvoogd.pricewise.com:21
DEBUG [SSLFTPControlSocket] 1 sep 2016 08:01:34.219 :  waitOnShutdownSSL=True
DEBUG [SecureSocket] 1 sep 2016 08:01:34.223 :  ChangeSecurityProtocol: None
DEBUG [ExFTPControlSocket] 1 sep 2016 08:01:34.224 :  Created control-socket: SocksContext=, ProxySettings=NoProxy, RemoteHost=ftpvoogd.pricewise.com, controlPort=21, timeout=60000
DEBUG [FTPControlSocket] 1 sep 2016 08:01:34.225 :  SetActivePortRange(10000,11000). NextPort=10723
DEBUG [FTPControlSocket] 1 sep 2016 08:01:34.225 :  StrictReturnCodes=False
DEBUG [HostNameResolver] 1 sep 2016 08:01:34.226 :  Resolving ftpvoogd.pricewise.com
DEBUG [HostNameResolver] 1 sep 2016 08:01:34.231 :  Obtained 1 addresses
DEBUG [HostNameResolver] 1 sep 2016 08:01:34.231 :  IP address: 92.111.253.50
DEBUG [HostNameResolver] 1 sep 2016 08:01:34.231 :  ftpvoogd.pricewise.com resolved to 92.111.253.50
DEBUG [ExFTPControlSocket] 1 sep 2016 08:01:34.231 :  Connecting directly to ftp-server ftpvoogd.pricewise.com:21
INFO [SSLFTPSocket] 1 sep 2016 08:01:34.232 :  Connecting to 92.111.253.50:21 with timeout 60000 ms
DEBUG [SSLFTPSocket] 1 sep 2016 08:01:34.247 :  Successfully connected to 92.111.253.50:21
DEBUG [FTPControlSocket] 1 sep 2016 08:01:34.247 :  Setting socket timeout=60000
DEBUG [FTPControlSocket] 1 sep 2016 08:01:34.247 :  SetSocketTimeout: 60000
ALL [SecureSocket] 1 sep 2016 08:01:34.247 :  SecureSocket timeout=60000
ALL [SecureSocket] 1 sep 2016 08:01:34.248 :  SecureSocket timeout=60000
INFO [FTPControlSocket] 1 sep 2016 08:01:34.248 :  Command encoding=System.Text.UTF8Encoding
DEBUG [FTPControlSocket] 1 sep 2016 08:01:34.260 :  220 Microsoft FTP Service
DEBUG [FTPControlSocket] 1 sep 2016 08:01:34.262 :  ---> AUTH TLS
DEBUG [FTPControlSocket] 1 sep 2016 08:01:34.276 :  234 AUTH command ok. Expecting TLS Negotiation.
DEBUG [SSLFTPControlSocket] 1 sep 2016 08:01:34.277 :  Beginning Tls1, Tls11, Tls12 handshake.
DEBUG [SecureSocket] 1 sep 2016 08:01:34.277 :  ChangeSecurityProtocol: Tls1, Tls11, Tls12
DEBUG [SecureSocket] 1 sep 2016 08:01:34.277 :  Initiating handshake
DEBUG [SecureSocket] 1 sep 2016 08:01:34.277 :  Doing synchronous handshake
DEBUG [SecureSocket] 1 sep 2016 08:01:34.277 :  Timeout used for monitor waiting for handshake completion: 60000
DEBUG [CompatibilityLayer] 1 sep 2016 08:01:34.279 : FTPConnection.1 Local min SSL version: 3.1, max SSL version: 3.3
DEBUG [SocketController] 1 sep 2016 08:01:34.599 : FTPConnection.1 OnReceive closing (size == 0)
DEBUG [SocketController] 1 sep 2016 08:01:34.600 : FTPConnection.1 CloseConnection(e=null)
DEBUG [SocketController] 1 sep 2016 08:01:34.600 : FTPConnection.1 Shut down socket
DEBUG [SocketController] 1 sep 2016 08:01:34.600 : FTPConnection.1 Closed socket
DEBUG [TransferBuffer] 1 sep 2016 08:01:34.600 : FTPConnection.1 Close() called when open
ERROR [SocketController] 1 sep 2016 08:01:34.601 : FTPConnection.1 OnReceive - caught exception - closing
ERROR [SocketController] 1 sep 2016 08:01:34.601 : FTPConnection.1 System.IO.IOException: Socket closed before handshake is complete (2)
ERROR [SocketController] 1 sep 2016 08:01:34.601 : FTPConnection.1    at OLfCAvw8k5jUtKbRQIi.JnXKa2wBB0CFSVax5Jm.SnsFNxckQE(IAsyncResult )
DEBUG [SocketController] 1 sep 2016 08:01:34.602 : FTPConnection.1 CloseConnection(e=Socket closed before handshake is complete (2))
DEBUG [SecureSocket] 1 sep 2016 08:01:34.602 : FTPConnection.1 OnHandshakeComplete(False,Socket closed before handshake is complete (2))
DEBUG [SecureSocket] 1 sep 2016 08:01:34.602 : FTPConnection.1 OnHandshakeComplete - in lock
DEBUG [SecureSocket] 1 sep 2016 08:01:34.602 : FTPConnection.1 OnHandshakeComplete - exiting lock
DEBUG [SecureSocket] 1 sep 2016 08:01:34.602 : FTPConnection.1 OnHandshakeComplete - exit
ERROR [SecureSocket] 1 sep 2016 08:01:34.602 :  Exception during handshake: Socket closed before handshake is complete (2)

--------------------------------------------------------------

We thought the problem might be related to SSL3 not being part of the default security protocol anymore but we explicitly set it to SSL3 and received the same error. 

Is there anything else I can provide to help with resolving the issue?

by (161k points)
Please open a support ticket here:

http://enterprisedt.com/support/open.php

Please log in or register to answer this question.

Categories

...