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
426 Connection closed; transfer aborted.
Moderator: Project members
Re: 426 Connection closed; transfer aborted.
Please update to the most recent version of FileZilla Server, old versions are not supported.
Re: 426 Connection closed; transfer aborted.
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.
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.
Re: 426 Connection closed; transfer aborted.
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.(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"
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.
Re: 426 Connection closed; transfer aborted.
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.
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.
Re: 426 Connection closed; transfer aborted.
Try disabling MODE Z for a test.
Re: 426 Connection closed; transfer aborted.
MODE Z disabled. Same outcome.
Re: 426 Connection closed; transfer aborted.
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?
If you use a network sniffer such as Wireshark, can you check which side of the connection sends the first RST packet?
Irrelevant but funny000002)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