426 Connection closed; transfer aborted.

Need help with FileZilla Server? Something does not work as expected? In this forum you may find an answer.

Moderator: Project members

Post Reply
Message
Author
redhonker
500 Command not understood
Posts: 4
Joined: 2014-09-16 04:22

426 Connection closed; transfer aborted.

#1 Post by redhonker » 2014-09-16 04:30

Hi,

I am using FileZilla Server. It was working fine about a month ago. Now I am seeing this error frequently (once every a few seconds to once every few minutes).

- The server is behind a firewall and uses passive mode.
- These are large data files.
- The connection is 100 Mbps and the transfer rate ranges from 100KB to 5 MB. Higher speed appears to suffer from more frequent disconnect.

Below is the log. Any hint on how to troubleshoot?

Thanks

> 227 Entering Passive Mode (64,119,##,##,10,148)
> REST 11159189
> 350 Rest supported. Restarting at 11159189
> RETR 130801_182708.mts
> 150 Connection accepted, restarting at offset 11159189
> SSL connection for data connection established
> 226 Transfer OK, compression saved 378938 of 38705515 bytes (0.98%)
> PASV
> 227 Entering Passive Mode (64,119,##,##,10,147)
> RETR 130801_183135.mts
> 150 Connection accepted
> SSL connection for data connection established
> 426 Connection closed; transfer aborted.
> QUIT
> 221 Goodbye
> disconnected

User avatar
botg
Site Admin
Posts: 31605
Joined: 2004-02-23 20:49
First name: Tim
Last name: Kosse
Contact:

Re: 426 Connection closed; transfer aborted.

#2 Post by botg » 2014-09-16 08:16

Please update to the most recent version of FileZilla Server, old versions are not supported.

redhonker
500 Command not understood
Posts: 4
Joined: 2014-09-16 04:22

Re: 426 Connection closed; transfer aborted.

#3 Post by redhonker » 2014-09-17 03:17

Just upgraded to 0.9.46 and tested again. Same problem. Any way to troubleshoot?

Thanks

(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> Connected on port 2709, sending welcome message...
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> 220 Hello
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> AUTH TLS
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> 234 Using authentication type TLS
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> SSL connection established
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> PBSZ 0
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> 200 PBSZ=0
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> USER hh
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> 331 Password required for hh
(000003)9/16/2014 23:10:31 - (not logged in) (156.111.*.*)> PASS ********
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 230 Logged on
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> SYST
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 215 UNIX emulated by FileZilla
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> FEAT
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 211-Features:
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> MDTM
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> REST STREAM
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> SIZE
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> MODE Z
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> MLST type*;size*;modify*;
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> MLSD
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> AUTH SSL
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> AUTH TLS
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> PROT
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> PBSZ
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> UTF8
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> CLNT
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> MFMT
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 211 End
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> OPTS UTF8 ON
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 202 UTF8 mode is always enabled. No need to send this command.
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> PWD
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 257 "/" is current directory.
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> TYPE I
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 200 Type set to I
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> CWD /2014/
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 250 CWD successful. "2014" is current directory.
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> PWD
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 257 "2014" is current directory.
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> TYPE I
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 200 Type set to I
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> REST 1
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 350 Rest supported. Restarting at 1
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> REST 0
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 350 Rest supported. Restarting at 0
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> MDTM 130806_085614.file
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 213 20130806125614
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> TYPE I
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 200 Type set to I
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> TYPE I
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 200 Type set to I
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> SIZE 130806_085614.file
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 213 30400512
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> MODE Z
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 200 MODE set to Z.
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> PROT P
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 200 Protection level set to P
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> PASV
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 227 Entering Passive Mode (64,119,83,121,10,148)
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> REST 14120770
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 350 Rest supported. Restarting at 14120770
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> RETR 130806_085614.file
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> 150 Opening data channel for file download from server of "/2014/130806_085614.file", restarting at offset 14120770
(000003)9/16/2014 23:10:31 - hh (156.111.*.*)> SSL connection for data connection established
(000003)9/16/2014 23:10:35 - hh (156.111.*.*)> 226 Successfully transferred "/2014/130806_085614.file", unfortunately compression did increase the transfer size by 2491 bytes to 16282233 bytes (0.01%)
(000003)9/16/2014 23:10:35 - hh (156.111.*.*)> TYPE I
(000003)9/16/2014 23:10:35 - hh (156.111.*.*)> 200 Type set to I
(000003)9/16/2014 23:10:35 - hh (156.111.*.*)> PASV
(000003)9/16/2014 23:10:35 - hh (156.111.*.*)> 227 Entering Passive Mode (64,119,83,121,10,147)
(000003)9/16/2014 23:10:35 - hh (156.111.*.*)> RETR 130818_113431.file
(000003)9/16/2014 23:10:35 - hh (156.111.*.*)> 150 Opening data channel for file download from server of "/2014/130818_113431.file"
(000003)9/16/2014 23:10:35 - hh (156.111.*.*)> SSL connection for data connection established
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> 226 Successfully transferred "/2014/130818_113431.file", unfortunately compression did increase the transfer size by 1316 bytes to 8553764 bytes (0.01%)
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> TYPE I
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> 200 Type set to I
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> PASV
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> 227 Entering Passive Mode (64,119,83,121,10,148)
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> RETR 130822_182111.file
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> 150 Opening data channel for file download from server of "/2014/130822_182111.file"
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> SSL connection for data connection established
(000003)9/16/2014 23:11:07 - hh (156.111.*.*)> NOOP
(000003)9/16/2014 23:11:07 - hh (156.111.*.*)> 200 OK
(000003)9/16/2014 23:11:09 - hh (156.111.*.*)> 426 Connection closed; aborted transfer of "/2014/130822_182111.file"
(000003)9/16/2014 23:11:09 - hh (156.111.*.*)> QUIT
(000003)9/16/2014 23:11:09 - hh (156.111.*.*)> 221 Goodbye
(000003)9/16/2014 23:11:09 - hh (156.111.*.*)> disconnected.

User avatar
botg
Site Admin
Posts: 31605
Joined: 2004-02-23 20:49
First name: Tim
Last name: Kosse
Contact:

Re: 426 Connection closed; transfer aborted.

#4 Post by botg » 2014-09-17 08:10

(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> RETR 130822_182111.file
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> 150 Opening data channel for file download from server of "/2014/130822_182111.file"
(000003)9/16/2014 23:10:37 - hh (156.111.*.*)> SSL connection for data connection established
(000003)9/16/2014 23:11:07 - hh (156.111.*.*)> NOOP
(000003)9/16/2014 23:11:07 - hh (156.111.*.*)> 200 OK
(000003)9/16/2014 23:11:09 - hh (156.111.*.*)> 426 Connection closed; aborted transfer of "/2014/130822_182111.file"
Your client is in violation to the FTP specifications. In FTP, at most one command may be outstanding. A client MUST NOT send a command unless it has received a non-preliminary reply to the previous command.

Preliminary replies start with 1, non-preliminary replies start with 2 through 5.

In this case there has only been a preliminary reply to the RETR command, but no non-preliminary reply. Yet your client already sends the next command (NOOP) a few seconds later.

Your client needs to be fixed to adhere to one of the most fundamental aspects of the FTP specifications. Contact your client vendor for assistance.

redhonker
500 Command not understood
Posts: 4
Joined: 2014-09-16 04:22

Re: 426 Connection closed; transfer aborted.

#5 Post by redhonker » 2014-09-18 02:12

That was the keep-alive option from the client. I've turned that off. Still get the abort. Any other hint?

Thanks

(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> Connected on port 2709, sending welcome message...
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> 220 Hello
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> AUTH TLS
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> 234 Using authentication type TLS
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> SSL connection established
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> PBSZ 0
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> 200 PBSZ=0
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> USER hh
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> 331 Password required for hh
(000002)9/17/2014 22:04:56 - (not logged in) (156.111.*.*)> PASS ********
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 230 Logged on
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> SYST
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 215 UNIX emulated by FileZilla
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> FEAT
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 211-Features:
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> MDTM
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> REST STREAM
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> SIZE
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> MODE Z
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> MLST type*;size*;modify*;
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> MLSD
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> AUTH SSL
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> AUTH TLS
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> PROT
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> PBSZ
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> UTF8
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> CLNT
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> MFMT
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 211 End
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> CLNT Sync
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 200 Don't care
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> OPTS UTF8 ON
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 202 UTF8 mode is always enabled. No need to send this command.
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> PWD
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 257 "/" is current directory.
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> TYPE I
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 200 Type set to I
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> CWD /dv
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 250 CWD successful. "/dv" is current directory.
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> PWD
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 257 "/dv" is current directory.
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> TYPE A
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 200 Type set to A
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> TYPE A
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 200 Type set to A
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> MODE Z
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 200 MODE set to Z.
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> PROT P
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 200 Protection level set to P
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> PASV
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 227 Entering Passive Mode (64,119,83,121,10,148)
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> MLSD
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 150 Opening data channel for directory listing of "/dv"
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> SSL connection for data connection established
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 226 Successfully transferred "/dv", unfortunately compression did increase the transfer size by 11 bytes to 3569 bytes (0.30%)
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> CWD /dv/K08/
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 250 CWD successful. "/dv/K08" is current directory.
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> PWD
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 257 "/dv/K08" is current directory.
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> PASV
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 227 Entering Passive Mode (64,119,83,121,10,147)
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> MLSD
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 150 Opening data channel for directory listing of "/dv/K08"
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> SSL connection for data connection established
(000002)9/17/2014 22:04:56 - hh (156.111.*.*)> 226 Successfully transferred "/dv/K08", unfortunately compression did increase the transfer size by 11 bytes to 317 bytes (3.59%)
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> CWD 2014/
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> 250 CWD successful. "2014" is current directory.
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> PWD
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> 257 "2014" is current directory.
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> PASV
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> 227 Entering Passive Mode (64,119,83,121,10,148)
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> MLSD
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> 150 Opening data channel for directory listing of "2014"
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> SSL connection for data connection established
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> 226 Successfully transferred "2014", unfortunately compression did increase the transfer size by 11 bytes to 30726 bytes (0.03%)
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> TYPE I
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> 200 Type set to I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> TYPE I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> 200 Type set to I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> TYPE I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> 200 Type set to I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> PASV
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> 227 Entering Passive Mode (64,119,83,121,10,147)
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> RETR 130801_182708.file
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> 150 Opening data channel for file download from server of "2014/130801_182708.file"
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> SSL connection for data connection established
(000002)9/17/2014 22:05:36 - hh (156.111.*.*)> 426 Connection closed; aborted transfer of "2014/130801_182708.file"
(000002)9/17/2014 22:05:36 - hh (156.111.*.*)> QUIT
(000002)9/17/2014 22:05:36 - hh (156.111.*.*)> 221 Goodbye
(000002)9/17/2014 22:05:36 - hh (156.111.*.*)> disconnected.

User avatar
botg
Site Admin
Posts: 31605
Joined: 2004-02-23 20:49
First name: Tim
Last name: Kosse
Contact:

Re: 426 Connection closed; transfer aborted.

#6 Post by botg » 2014-09-18 07:36

Try disabling MODE Z for a test.

redhonker
500 Command not understood
Posts: 4
Joined: 2014-09-16 04:22

Re: 426 Connection closed; transfer aborted.

#7 Post by redhonker » 2014-09-18 11:03

MODE Z disabled. Same outcome.

User avatar
botg
Site Admin
Posts: 31605
Joined: 2004-02-23 20:49
First name: Tim
Last name: Kosse
Contact:

Re: 426 Connection closed; transfer aborted.

#8 Post by botg » 2014-09-18 13:47

Unfortunately it doesn't say in the log why the connection got closed or in which state it got closed.

If you use a network sniffer such as Wireshark, can you check which side of the connection sends the first RST packet?
000002)9/17/2014 22:04:57 - hh (156.111.*.*)> TYPE I
(000002)9/17/2014 22:04:57 - hh (156.111.*.*)> 200 Type set to I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> TYPE I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> 200 Type set to I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> TYPE I
(000002)9/17/2014 22:04:59 - hh (156.111.*.*)> 200 Type set to I
Irrelevant but funny :)

Post Reply