Data connection closed :: Support Forum

gbartley
Joined:: 2024-06-21
Posts:: 2
Location:: Canada

Data connection closed

2024-06-21 00:28

Hello,

I know that this error has been see before but I do recall seeing a fix for it.
When I connect to the site that and it tries to list the directory, the directory is empty. I turned on logging and below is the part of the log that is stating that the TLS layer state changed from open to closed. I have checked the firewall and all the settings are set correctly. I have tested with FileZilla and that works. I have uninstalled and reinstalled, I have updated version to the latest but no joy. I have contacted the host of the FTP site and validated that I have all the correct settings. Please let me know if you have seen this error and are able to help solve this mystery.

Gerald

 Data connection opened
. 2024-06-20 16:07:35.777 TLS layer changed state from none to connected
. 2024-06-20 16:07:35.839 Read 28 bytes
< 2024-06-20 16:07:35.839 150 Transferring directory
. 2024-06-20 16:07:35.933 TLS connect: SSLv3/TLS write client hello
. 2024-06-20 16:07:35.933 TLS connect: SSLv3/TLS read server hello
. 2024-06-20 16:07:35.933 TLS connect: SSLv3/TLS read server certificate
. 2024-06-20 16:07:35.933 TLS connect: SSLv3/TLS read server key exchange
. 2024-06-20 16:07:35.933 TLS connect: SSLv3/TLS read server done
. 2024-06-20 16:07:35.949 TLS connect: SSLv3/TLS write client key exchange
. 2024-06-20 16:07:35.949 TLS connect: SSLv3/TLS write change cipher spec
. 2024-06-20 16:07:35.949 TLS connect: SSLv3/TLS write finished
. 2024-06-20 16:07:36.011 TLS connect: SSLv3/TLS write finished
. 2024-06-20 16:07:36.011 TLS connect: SSLv3/TLS read server session ticket
. 2024-06-20 16:07:36.011 TLS connect: SSLv3/TLS read change cipher spec
. 2024-06-20 16:07:36.011 TLS connect: SSLv3/TLS read finished
. 2024-06-20 16:07:36.011 Using TLSv1.2, cipher TLSv1.2: ECDHE-RSA-AES256-GCM-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-GCM-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AESGCM(256) Mac=AEAD
. 2024-06-20 16:07:36.011 TLS connection established
. 2024-06-20 16:07:36.011 TLS layer changed state from connected to aborted
. 2024-06-20 16:07:36.011 Data connection closed
. 2024-06-20 16:07:36.011 Data connection closed
. 2024-06-20 16:07:36.011 <Empty directory listing>
. 2024-06-20 16:07:36.027 Read 24 bytes
< 2024-06-20 16:07:36.027 226 Transfer completed
. 2024-06-20 16:07:36.027 Directory listing successful
. 2024-06-20 16:07:36.027 Got reply 1 to the command 2
. 2024-06-20 16:07:36.027 ..;D;0;1899-12-30T08:00:00.000Z;0;"" [0];"" [0];---------;0
. 2024-06-20 16:07:36.027 Session upkeep
. 2024-06-20 16:07:36.074 Startup conversation with host finished.
. 2024-06-20 16:07:36.121 Not refreshing directory, caching is off.
. 2024-06-20 16:07:36.339 Session upkeep
. 2024-06-20 16:07:36.855 Session upkeep

Reply with quote

martin◆ Site Admin
Joined:: 2002-12-10
Posts:: 41,441
Location:: Prague, Czechia

Re: Data connection closed

2024-06-24

Please post complete WinSCP and FileZilla logs.

Reply with quote

gbartley
Joined:: 2024-06-21
Posts:: 2
Location:: Canada

Re: Data connection closed

2024-07-15 23:53

Hi Martin,

I thought that I had it fixed but it has appeared again, as you requested here is the whole log. I have tested this time and FileZilla does not work. I have tested with telnet to the port and that works. It appears that other connections that just use port 21 work without issue, but this script uses port 2010.

Thanks for any assistance.

. 2024-07-15 15:35:08.867 --------------------------------------------------------------------------
. 2024-07-15 15:35:08.867 WinSCP Version 6.3.4 (Build 14955 2024-06-17) (OS 10.0.14393 – Windows Server 2016 Standard)
. 2024-07-15 15:35:08.867 Configuration: HKCU\Software\Martin Prikryl\WinSCP 2\
. 2024-07-15 15:35:08.867 Log level: Debug 2
. 2024-07-15 15:35:08.867 Local account: username
. 2024-07-15 15:35:08.867 Working directory: C:\Program Files (x86)\WinSCP
. 2024-07-15 15:35:08.867 Process ID: 5664
. 2024-07-15 15:35:08.867 Ancestor processes: explorer, ...
. 2024-07-15 15:35:08.867 Command-line: "C:\Program Files (x86)\WinSCP\WinSCP.exe" 
. 2024-07-15 15:35:08.867 Time zone: Current: GMT-7, Standard: GMT-8 (Pacific Standard Time), DST: GMT-7 (Pacific Daylight Time), DST Start: 3/10/2024, DST End: 11/3/2024
. 2024-07-15 15:35:08.867 Login time: Monday, July 15, 2024 3:35:08 PM
. 2024-07-15 15:35:08.867 --------------------------------------------------------------------------
. 2024-07-15 15:35:08.867 Session name: my site (Site)
. 2024-07-15 15:35:08.867 Host name: ftp.securesite.com (Port: 2010)
. 2024-07-15 15:35:08.867 User name: username (Password: No, Key file: No, Passphrase: No)
. 2024-07-15 15:35:08.867 Transfer Protocol: FTP
. 2024-07-15 15:35:08.867 Ping type: Dummy, Ping interval: 30 sec; Timeout: 15 sec
. 2024-07-15 15:35:08.867 Disable Nagle: No
. 2024-07-15 15:35:08.867 Proxy: None
. 2024-07-15 15:35:08.867 Send buffer: 262144
. 2024-07-15 15:35:08.867 UTF: Auto
. 2024-07-15 15:35:08.867 FTPS: Implicit TLS/SSL [Client certificate: No]
. 2024-07-15 15:35:08.867 FTP: Passive: Yes [Force IP: Auto]; MLSD: Auto [List all: Auto]; HOST: Auto
. 2024-07-15 15:35:08.867 Session reuse: No
. 2024-07-15 15:35:08.867 TLS/SSL versions: TLSv1.2-TLSv1.3
. 2024-07-15 15:35:08.867 Local directory: C:\Users\username\Documents, Remote directory: /A539P/OutTrans, Update: Yes, Cache: Yes
. 2024-07-15 15:35:08.867 Cache directory changes: Yes, Permanent: Yes
. 2024-07-15 15:35:08.867 Recycle bin: Delete to: No, Overwritten to: No, Bin path: 
. 2024-07-15 15:35:08.867 Timezone offset: 0h 0m
. 2024-07-15 15:35:08.867 --------------------------------------------------------------------------
. 2024-07-15 15:35:08.898 Session upkeep
. 2024-07-15 15:35:08.914 Connecting to ftp.securesite.com:2010 ...
. 2024-07-15 15:35:08.945 Connected
. 2024-07-15 15:35:08.945 TLS layer changed state from unconnected to connecting
. 2024-07-15 15:35:08.992 TLS layer changed state from connecting to connected
. 2024-07-15 15:35:08.992 Connected with ftp.securesite.com:2010, negotiating TLS connection...
. 2024-07-15 15:35:09.086 TLS connect: SSLv3/TLS write client hello
. 2024-07-15 15:35:09.086 TLS connect: SSLv3/TLS read server hello
. 2024-07-15 15:35:09.086 TLS connect: SSLv3/TLS read server certificate
. 2024-07-15 15:35:09.086 TLS connect: SSLv3/TLS read server key exchange
. 2024-07-15 15:35:09.086 TLS connect: SSLv3/TLS read server done
. 2024-07-15 15:35:09.101 TLS connect: SSLv3/TLS write client key exchange
. 2024-07-15 15:35:09.101 TLS connect: SSLv3/TLS write change cipher spec
. 2024-07-15 15:35:09.101 TLS connect: SSLv3/TLS write finished
. 2024-07-15 15:35:09.164 TLS connect: SSLv3/TLS write finished
. 2024-07-15 15:35:09.164 TLS connect: SSLv3/TLS read server session ticket
. 2024-07-15 15:35:09.164 TLS connect: SSLv3/TLS read change cipher spec
. 2024-07-15 15:35:09.164 TLS connect: SSLv3/TLS read finished
. 2024-07-15 15:35:09.164 Verifying certificate for "Secure site." with fingerprint 39:bc:43:8a:50:11:24:c9:c1:c3:79:ed:6b:b4:60:44:f4:4d:e8:c4:9e:03:56:7d:18:53:95:d5:7a:68:db:3c and 19 failures
. 2024-07-15 15:35:09.164 Certificate common name "ftp.securesite.com" matches hostname
. 2024-07-15 15:35:09.211 Certificate verified against Windows certificate store
. 2024-07-15 15:35:09.211 Using TLSv1.2, cipher TLSv1.2: ECDHE-RSA-AES256-GCM-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-GCM-SHA384    TLSv1.2 Kx=ECDH     Au=RSA   Enc=AESGCM(256)            Mac=AEAD
. 2024-07-15 15:35:09.211 Session upkeep
. 2024-07-15 15:35:09.242 TLS connection established. Waiting for welcome message...
. 2024-07-15 15:35:09.242 Read 65 bytes
< 2024-07-15 15:35:09.242 220-ftp.securesite.com X2 WS_FTP Server 8.6.1(88230079)
. 2024-07-15 15:35:09.242 Read 111 bytes
< 2024-07-15 15:35:09.242    This ftp site and all data accessed from this site are considered proprietary and are solely intended for 
. 2024-07-15 15:35:09.242 Read 123 bytes
< 2024-07-15 15:35:09.242    authorized users only. Use of this site and all applications accessed from this site implies you have received proper 
. 2024-07-15 15:35:09.242 Read 120 bytes
< 2024-07-15 15:35:09.242    authorization, have agreed to comply with all security policies, and are consenting to have all activity monitored.
. 2024-07-15 15:35:09.242 Read 65 bytes
< 2024-07-15 15:35:09.242 220 ftp.Securesite.com X2 WS_FTP Server 8.6.1(88230079)
> 2024-07-15 15:35:09.242 USER username
. 2024-07-15 15:35:09.257 Read 20 bytes
< 2024-07-15 15:35:09.257 331 Enter password
. 2024-07-15 15:35:10.258 Session upkeep
. 2024-07-15 15:35:11.273 Session upkeep
. 2024-07-15 15:35:12.289 Session upkeep
. 2024-07-15 15:35:13.305 Session upkeep
. 2024-07-15 15:35:14.320 Session upkeep
. 2024-07-15 15:35:15.320 Session upkeep
. 2024-07-15 15:35:16.320 Session upkeep
. 2024-07-15 15:35:17.336 Session upkeep
. 2024-07-15 15:35:18.339 Session upkeep
. 2024-07-15 15:35:19.339 Session upkeep
. 2024-07-15 15:35:20.340 Session upkeep
. 2024-07-15 15:35:21.340 Session upkeep
. 2024-07-15 15:35:22.340 Session upkeep
. 2024-07-15 15:35:23.356 Session upkeep
. 2024-07-15 15:35:24.356 Session upkeep
. 2024-07-15 15:35:25.360 Session upkeep
. 2024-07-15 15:35:26.361 Session upkeep
. 2024-07-15 15:35:27.361 Session upkeep
. 2024-07-15 15:35:28.376 Session upkeep
. 2024-07-15 15:35:29.376 Session upkeep
. 2024-07-15 15:35:30.392 Session upkeep
> 2024-07-15 15:35:31.314 PASS ********
. 2024-07-15 15:35:31.392 Read 20 bytes
< 2024-07-15 15:35:31.392 230 User logged in
> 2024-07-15 15:35:31.392 SYST
. 2024-07-15 15:35:31.455 Read 10 bytes
< 2024-07-15 15:35:31.455 215 UNIX
> 2024-07-15 15:35:31.455 FEAT
. 2024-07-15 15:35:31.502 Read 267 bytes
< 2024-07-15 15:35:31.502 211-Extensions supported
< 2024-07-15 15:35:31.502     SIZE
< 2024-07-15 15:35:31.502     XMD5
< 2024-07-15 15:35:31.533     XSHA1
< 2024-07-15 15:35:31.533     XSHA256
< 2024-07-15 15:35:31.533     XSHA512
< 2024-07-15 15:35:31.533     XQUOTA
< 2024-07-15 15:35:31.533     LANG EN, ES, FR, GE
< 2024-07-15 15:35:31.533     MDTM
< 2024-07-15 15:35:31.533     MLST size*;type*;perm*;create*;modify*;
< 2024-07-15 15:35:31.533     REST STREAM
< 2024-07-15 15:35:31.533     TVFS
< 2024-07-15 15:35:31.533     UTF8
< 2024-07-15 15:35:31.533     AUTH SSL;TLS-P;
< 2024-07-15 15:35:31.533     PBSZ
< 2024-07-15 15:35:31.533     PROT C;P;
< 2024-07-15 15:35:31.533 211 end
> 2024-07-15 15:35:31.533 OPTS UTF8 ON
. 2024-07-15 15:35:31.580 Read 26 bytes
< 2024-07-15 15:35:31.580 200 Command OPTS succeed
> 2024-07-15 15:35:31.580 PBSZ 0
. 2024-07-15 15:35:31.627 Read 12 bytes
< 2024-07-15 15:35:31.627 200 PBSZ=0
> 2024-07-15 15:35:31.627 PROT P
. 2024-07-15 15:35:31.689 Read 47 bytes
< 2024-07-15 15:35:31.689 200 PRIVATE data channel protection level set
. 2024-07-15 15:35:31.689 Session upkeep
. 2024-07-15 15:35:31.752 Connected
. 2024-07-15 15:35:31.752 Got reply 1 to the command 1
. 2024-07-15 15:35:31.752 --------------------------------------------------------------------------
. 2024-07-15 15:35:31.752 Using FTP protocol.
. 2024-07-15 15:35:31.752 Doing startup conversation with host.
> 2024-07-15 15:35:31.767 PWD
. 2024-07-15 15:35:31.814 Read 30 bytes
< 2024-07-15 15:35:31.814 257 "/" is current directory
. 2024-07-15 15:35:31.814 Got reply 1 to the command 16
. 2024-07-15 15:35:31.814 Changing directory to "/A539P/OutTrans".
> 2024-07-15 15:35:31.814 CWD /A539P/OutTrans
. 2024-07-15 15:35:31.877 Read 25 bytes
< 2024-07-15 15:35:31.877 250 Command CWD succeed
. 2024-07-15 15:35:31.877 Got reply 1 to the command 16
. 2024-07-15 15:35:31.877 Getting current directory name.
> 2024-07-15 15:35:31.877 PWD
. 2024-07-15 15:35:31.939 Read 44 bytes
< 2024-07-15 15:35:31.939 257 "/A539P/OutTrans" is current directory
. 2024-07-15 15:35:31.939 Got reply 1 to the command 16
. 2024-07-15 15:35:32.002 Retrieving directory listing...
> 2024-07-15 15:35:32.002 TYPE A
. 2024-07-15 15:35:32.049 Read 32 bytes
< 2024-07-15 15:35:32.049 200 Transfer mode set to ASCII
> 2024-07-15 15:35:32.049 PASV
. 2024-07-15 15:35:32.111 Read 51 bytes
< 2024-07-15 15:35:32.111 227 Entering Passive Mode (25,175,149,248,7,219).
> 2024-07-15 15:35:32.111 MLSD
. 2024-07-15 15:35:32.111 Connecting to 25.175.149.248:2011 ...
. 2024-07-15 15:35:32.111 Connection pending
. 2024-07-15 15:35:32.174 Data connection opened
. 2024-07-15 15:35:32.174 TLS layer changed state from none to connected
. 2024-07-15 15:35:32.220 Read 28 bytes
< 2024-07-15 15:35:32.220 150 Transferring directory
. 2024-07-15 15:35:32.314 TLS connect: SSLv3/TLS write client hello
. 2024-07-15 15:35:32.314 TLS connect: SSLv3/TLS read server hello
. 2024-07-15 15:35:32.314 TLS connect: SSLv3/TLS read server certificate
. 2024-07-15 15:35:32.314 TLS connect: SSLv3/TLS read server key exchange
. 2024-07-15 15:35:32.314 TLS connect: SSLv3/TLS read server done
. 2024-07-15 15:35:32.330 TLS connect: SSLv3/TLS write client key exchange
. 2024-07-15 15:35:32.330 TLS connect: SSLv3/TLS write change cipher spec
. 2024-07-15 15:35:32.330 TLS connect: SSLv3/TLS write finished
. 2024-07-15 15:35:32.392 TLS connect: SSLv3/TLS write finished
. 2024-07-15 15:35:32.392 TLS connect: SSLv3/TLS read server session ticket
. 2024-07-15 15:35:32.392 TLS connect: SSLv3/TLS read change cipher spec
. 2024-07-15 15:35:32.392 TLS connect: SSLv3/TLS read finished
. 2024-07-15 15:35:32.392 Using TLSv1.2, cipher TLSv1.2: ECDHE-RSA-AES256-GCM-SHA384, 2048 bit RSA, ECDHE-RSA-AES256-GCM-SHA384    TLSv1.2 Kx=ECDH     Au=RSA   Enc=AESGCM(256)            Mac=AEAD
. 2024-07-15 15:35:32.392 TLS connection established
. 2024-07-15 15:35:32.392 TLS layer changed state from connected to aborted
. 2024-07-15 15:35:32.392 Data connection closed
. 2024-07-15 15:35:32.392 Data connection closed
. 2024-07-15 15:35:32.392 <Empty directory listing>
. 2024-07-15 15:35:32.392 Read 24 bytes
< 2024-07-15 15:35:32.392 226 Transfer completed
. 2024-07-15 15:35:32.392 Directory listing successful
. 2024-07-15 15:35:32.392 Got reply 1 to the command 2
. 2024-07-15 15:35:32.392 ..;D;0;1899-12-30T08:00:00.000Z;0;"" [0];"" [0];---------;0
. 2024-07-15 15:35:32.392 Session upkeep
. 2024-07-15 15:35:32.455 Startup conversation with host finished.
. 2024-07-15 15:35:32.642 Session upkeep
. 2024-07-15 15:35:33.142 Session upkeep
. 2024-07-15 15:35:33.645 Session upkeep
. 2024-07-15 15:35:34.148 Session upkeep
. 2024-07-15 15:35:34.651 Session upkeep
. 2024-07-15 15:35:35.151 Session upkeep
. 2024-07-15 15:35:35.639 Session upkeep
. 2024-07-15 15:35:36.139 Session upkeep
. 2024-07-15 15:35:36.644 Session upkeep
. 2024-07-15 15:35:37.144 Session upkeep
. 2024-07-15 15:35:37.649 Session upkeep
. 2024-07-15 15:35:38.149 Session upkeep
. 2024-07-15 15:35:38.654 Session upkeep
. 2024-07-15 15:35:39.170 Session upkeep
. 2024-07-15 15:35:39.674 Session upkeep
. 2024-07-15 15:35:40.096 Connection closed
. 2024-07-15 15:35:40.096 Got reply 1004 to the command 2
. 2024-07-15 15:35:40.096 Not waiting for complete TLS shutdown
. 2024-07-15 15:35:42.229 Disconnected from server
. 2024-07-15 15:35:42.229 Connection closed

Reply with quote

Guest

2024-07-16 14:24

Looks to me that the TLS handshake appears to complete successfully, but the listing send on the data connection appears to fail.

Could be a networking hardware or connection stability issue, or server side issue. I don't expect it to be the 150 location here as that should timeout. Could in theory be other things.

When the handshake happens, the socket will have been created and accepted on the port. So its not a data connection port issue. The handshake happens after the socket and port are accepted (opened for use) but before the listing sends its bytes.

Stuff like the "Directory listing successful" appears to be red herrings.

This one can be tricky. A server side log has a higher chance of helping you but you might not have access to one here.

Reply with quote

martin◆ Site Admin

Re: Data connection closed

2024-07-17

@gbartley: So now you are not able to list contents of any directory with WinSCP nor with FileZilla?

Reply with quote

Guest

Re: Data connection closed

2024-07-17 17:35

Hi Martin,

Yes, you are correct I was not able to connect with either WinSCP or FileZilla. I was able to connect with another server that is on the same subnet with WinSCP. I uninstalled the application yesterday afternoon on the server that is not working. I then removed all the folders and registry settings associated with WinSCP that I could find. (A list of what should be removed for a full clean would be great.) I rebooted the server and reinstalled WinSCP and tested but still did not work. Now since then overnight something changed, and I am not sure what, but it is working. By chance do you have a scrub tool that allows us to fully uninstall so that we can do a complete clean install?

Gerald

Reply with quote