Error: PASV command failed

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
bigbadbear
500 Command not understood
Posts: 5
Joined: 2022-05-14 16:25

Error: PASV command failed

#1 Post by bigbadbear » 2022-05-14 16:35

Hello,

I am attempting to set up a server, and when testing at https://ftptest.net/#result I get the 'Error: PASV command failed' as the last line.

Screenshot: https://i.use_direct_attachments_thankyou/7iUcsRT.png

I have the passive ports defined in my config, and allowed in my firewall (also tested with firewall completely disabled with same result), and forwarded in my router.

Is there something else I could be missing?
Attachments
ftptest.png
ftptest.png (62.88 KiB) Viewed 2516 times

User avatar
oibaf
Contributor
Posts: 398
Joined: 2021-07-16 21:02
First name: Fabio
Last name: Alemagna

Re: Error: PASV command failed

#2 Post by oibaf » 2022-05-14 17:14

Can you please set the server's log to Debug level and attach it here?

bigbadbear
500 Command not understood
Posts: 5
Joined: 2022-05-14 16:25

Re: Error: PASV command failed

#3 Post by bigbadbear » 2022-05-14 18:22

The ports like/near 51912 are the ones I set for passive, and have open on firewall, and forwarded in router.

Code: Select all

2022-05-14T18:17:01.622Z II [FTP Session 14 49.12.121.47] Session 0x25c5175c770 with ID 14 created.
2022-05-14T18:17:01.622Z << [FTP Session 14 49.12.121.47] 220-FileZilla Server 1.4.1
2022-05-14T18:17:01.622Z << [FTP Session 14 49.12.121.47] 220-Please visit https://filezilla-project.org/
2022-05-14T18:17:01.622Z << [FTP Session 14 49.12.121.47] 220 welcome to the thingy
2022-05-14T18:17:01.941Z >> [FTP Session 14 49.12.121.47] CLNT https://ftptest.net on behalf of <<redacted IP>>
2022-05-14T18:17:01.941Z << [FTP Session 14 49.12.121.47] 200 Don't care.
2022-05-14T18:17:02.270Z >> [FTP Session 14 49.12.121.47] AUTH TLS
2022-05-14T18:17:02.270Z DD [FTP Session 14 49.12.121.47] securer(1) ENTERING state = 0
2022-05-14T18:17:02.270Z DD [FTP Session 14 49.12.121.47] calling tls_layer_->set_certificate_file("C:\WINDOWS\system32\config\systemprofile\AppData\Local\filezilla-server\certificates\<<redacted>>\key.pem", "C:\WINDOWS\system32\config\systemprofile\AppData\Local\filezilla-server\certificates\<<redacted>>\cert.pem", "****")
2022-05-14T18:17:02.270Z DD [FTP Session 14 49.12.121.47] securer(1) EXITING state = 1
2022-05-14T18:17:02.270Z << [FTP Session 14 49.12.121.47] 234 Using authentication type TLS.
2022-05-14T18:17:02.270Z DD [FTP Session 14 49.12.121.47] ~securer(1) ENTERING state = 1
2022-05-14T18:17:02.270Z DD [FTP Session 14 49.12.121.47] calling tls_layer_->set_alpn()
2022-05-14T18:17:02.270Z VV [FTP Session 14 49.12.121.47] tls_layer_impl::server_handshake()
2022-05-14T18:17:02.270Z VV [FTP Session 14 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T18:17:02.271Z DD [FTP Session 14 49.12.121.47] ~securer(1) EXITING state = 2
2022-05-14T18:17:02.271Z DD [FTP Session 14 49.12.121.47] tls_layer_impl::on_send()
2022-05-14T18:17:02.271Z VV [FTP Session 14 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T18:17:02.408Z DD [FTP Session 14 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T18:17:02.408Z VV [FTP Session 14 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T18:17:02.408Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Received CLIENT HELLO
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Processed CLIENT HELLO
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: About to send SERVER HELLO
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Sent SERVER HELLO
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: About to send ENCRYPTED EXTENSIONS
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Sent ENCRYPTED EXTENSIONS
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: About to send CERTIFICATE
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Sent CERTIFICATE
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: About to send CERTIFICATE VERIFY
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Sent CERTIFICATE VERIFY
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: About to send FINISHED
2022-05-14T18:17:02.409Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Sent FINISHED
2022-05-14T18:17:02.576Z DD [FTP Session 14 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T18:17:02.576Z VV [FTP Session 14 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T18:17:02.755Z DD [FTP Session 14 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T18:17:02.755Z VV [FTP Session 14 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T18:17:02.755Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Received FINISHED
2022-05-14T18:17:02.755Z DD [FTP Session 14 49.12.121.47] TLS handshakep: Processed FINISHED
2022-05-14T18:17:02.755Z II [FTP Session 14 49.12.121.47] TLS Handshake successful
2022-05-14T18:17:02.755Z II [FTP Session 14 49.12.121.47] Protocol: TLS1.3, Key exchange: ECDHE-SECP384R1-ECDSA-SECP256R1-SHA256, Cipher: AES-256-GCM, MAC: AEAD, ALPN: 
2022-05-14T18:17:02.755Z >> [FTP Session 14 49.12.121.47] USER <<redacted>>
2022-05-14T18:17:02.755Z << [FTP Session 14 49.12.121.47] 331 Please, specify the password.
2022-05-14T18:17:02.892Z DD [FTP Session 14 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T18:17:03.084Z DD [FTP Session 14 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T18:17:03.084Z >> [FTP Session 14 49.12.121.47] PASS ****
2022-05-14T18:17:03.130Z DD [File-based Authenticator] authenticate: is_from_system: 0, impersonation_token: { username: "", home: "" }
2022-05-14T18:17:03.131Z << [FTP Session 14 49.12.121.47 bob] 230 Login successful.
2022-05-14T18:17:03.268Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:03.446Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:03.446Z >> [FTP Session 14 49.12.121.47 bob] SYST
2022-05-14T18:17:03.446Z << [FTP Session 14 49.12.121.47 bob] 215 UNIX emulated by FileZilla.
2022-05-14T18:17:03.612Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:03.790Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:03.790Z >> [FTP Session 14 49.12.121.47 bob] FEAT
2022-05-14T18:17:03.790Z << [FTP Session 14 49.12.121.47 bob] 211-Features:
2022-05-14T18:17:03.790Z << [FTP Session 14 49.12.121.47 bob] 211 End
2022-05-14T18:17:03.928Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:04.118Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:04.118Z >> [FTP Session 14 49.12.121.47 bob] PBSZ 0
2022-05-14T18:17:04.118Z << [FTP Session 14 49.12.121.47 bob] 200 PBSZ=0
2022-05-14T18:17:04.255Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:04.447Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:04.448Z >> [FTP Session 14 49.12.121.47 bob] PROT P
2022-05-14T18:17:04.448Z << [FTP Session 14 49.12.121.47 bob] 200 Protection level set to P
2022-05-14T18:17:04.585Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:04.783Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:04.783Z >> [FTP Session 14 49.12.121.47 bob] PWD
2022-05-14T18:17:04.783Z << [FTP Session 14 49.12.121.47 bob] 257 "/" is current directory.
2022-05-14T18:17:04.920Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:05.102Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:05.102Z >> [FTP Session 14 49.12.121.47 bob] TYPE I
2022-05-14T18:17:05.102Z << [FTP Session 14 49.12.121.47 bob] 200 Type set to I
2022-05-14T18:17:05.240Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:05.419Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:05.419Z >> [FTP Session 14 49.12.121.47 bob] PASV
2022-05-14T18:17:05.419Z DD [FTP Session 14 49.12.121.47 bob] TLS handshakep: About to send NEW SESSION TICKET
2022-05-14T18:17:05.419Z DD [FTP Session 14 49.12.121.47 bob] TLS handshakep: Sent NEW SESSION TICKET
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51912) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51912) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51913) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51913) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51914) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51914) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51915) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51915) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51916) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51916) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51917) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51917) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51918) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51918) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51919) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51919) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51910) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51910) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z VV [FTP Session 14 49.12.121.47 bob] Trying listen(1, 51911) for data connection.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Failed listen(1, 51911) for data connection. Reason: EACCES - Permission denied.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] Leaser's get_port() returned 0.
2022-05-14T18:17:05.419Z !! [FTP Session 14 49.12.121.47 bob] No more tries left, giving up.
2022-05-14T18:17:05.419Z << [FTP Session 14 49.12.121.47 bob] 425 Cannot prepare for data connection.
2022-05-14T18:17:05.557Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T18:17:05.557Z DD [FTP Session 14 49.12.121.47 bob] tls_layer_impl::failure(-110)
2022-05-14T18:17:05.557Z !! [FTP Session 14 49.12.121.47 bob] GnuTLS error -110 in gnutls_record_recv: The TLS connection was non-properly terminated.
2022-05-14T18:17:05.557Z == [FTP Session 14 49.12.121.47 bob] Client did not properly shut down TLS connection
2022-05-14T18:17:05.557Z !! [FTP Session 14 49.12.121.47 bob] Control channel closed with error from source 0. Reason: ECONNABORTED - Connection aborted.
2022-05-14T18:17:05.557Z II [FTP Session 14 49.12.121.47] Session 0x25c5175c770 with ID 14 destroyed.

bigbadbear
500 Command not understood
Posts: 5
Joined: 2022-05-14 16:25

Re: Error: PASV command failed

#4 Post by bigbadbear » 2022-05-14 19:40

Update: I fiddled with the range of ports that I have forwarded, and seemed to get past the last issue/error.

However, now I am getting a new error:
Reply: 150 About to start data transfer.
Error: Connection timed out
This log is from when I had the firewall completely disabled. Is this a file system or directory issue?
I can't seem to find any good docs on how to set up directories for users or groups. I defined some, but not sure that it is working.

Code: Select all

2022-05-14T19:34:35.565Z II [FTP Session 42 49.12.121.47] Session 0x25c51833930 with ID 42 created.
2022-05-14T19:34:35.566Z << [FTP Session 42 49.12.121.47] 220-FileZilla Server 1.4.1
2022-05-14T19:34:35.566Z << [FTP Session 42 49.12.121.47] 220-Please visit https://filezilla-project.org/
2022-05-14T19:34:35.566Z << [FTP Session 42 49.12.121.47] 220 welcome to the thingy
2022-05-14T19:34:35.897Z >> [FTP Session 42 49.12.121.47] CLNT https://ftptest.net on behalf of <<redacted>>
2022-05-14T19:34:35.897Z << [FTP Session 42 49.12.121.47] 200 Don't care.
2022-05-14T19:34:36.220Z >> [FTP Session 42 49.12.121.47] AUTH TLS
2022-05-14T19:34:36.220Z DD [FTP Session 42 49.12.121.47] securer(1) ENTERING state = 0
2022-05-14T19:34:36.220Z DD [FTP Session 42 49.12.121.47] calling tls_layer_->set_certificate_file("C:\WINDOWS\system32\config\systemprofile\AppData\Local\filezilla-server\certificates\<<redacted>>\key.pem", "C:\WINDOWS\system32\config\systemprofile\AppData\Local\filezilla-server\certificates\<<redacted>>\cert.pem", "****")
2022-05-14T19:34:36.221Z DD [FTP Session 42 49.12.121.47] securer(1) EXITING state = 1
2022-05-14T19:34:36.221Z << [FTP Session 42 49.12.121.47] 234 Using authentication type TLS.
2022-05-14T19:34:36.221Z DD [FTP Session 42 49.12.121.47] ~securer(1) ENTERING state = 1
2022-05-14T19:34:36.221Z DD [FTP Session 42 49.12.121.47] calling tls_layer_->set_alpn()
2022-05-14T19:34:36.221Z VV [FTP Session 42 49.12.121.47] tls_layer_impl::server_handshake()
2022-05-14T19:34:36.221Z VV [FTP Session 42 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T19:34:36.221Z DD [FTP Session 42 49.12.121.47] ~securer(1) EXITING state = 2
2022-05-14T19:34:36.221Z DD [FTP Session 42 49.12.121.47] tls_layer_impl::on_send()
2022-05-14T19:34:36.221Z VV [FTP Session 42 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T19:34:36.361Z DD [FTP Session 42 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T19:34:36.361Z VV [FTP Session 42 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T19:34:36.361Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Received CLIENT HELLO
2022-05-14T19:34:36.361Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Processed CLIENT HELLO
2022-05-14T19:34:36.361Z DD [FTP Session 42 49.12.121.47] TLS handshakep: About to send SERVER HELLO
2022-05-14T19:34:36.361Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Sent SERVER HELLO
2022-05-14T19:34:36.362Z DD [FTP Session 42 49.12.121.47] TLS handshakep: About to send ENCRYPTED EXTENSIONS
2022-05-14T19:34:36.362Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Sent ENCRYPTED EXTENSIONS
2022-05-14T19:34:36.362Z DD [FTP Session 42 49.12.121.47] TLS handshakep: About to send CERTIFICATE
2022-05-14T19:34:36.362Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Sent CERTIFICATE
2022-05-14T19:34:36.362Z DD [FTP Session 42 49.12.121.47] TLS handshakep: About to send CERTIFICATE VERIFY
2022-05-14T19:34:36.362Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Sent CERTIFICATE VERIFY
2022-05-14T19:34:36.362Z DD [FTP Session 42 49.12.121.47] TLS handshakep: About to send FINISHED
2022-05-14T19:34:36.362Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Sent FINISHED
2022-05-14T19:34:36.502Z DD [FTP Session 42 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T19:34:36.502Z VV [FTP Session 42 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T19:34:36.688Z DD [FTP Session 42 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T19:34:36.688Z VV [FTP Session 42 49.12.121.47] tls_layer_impl::continue_handshake()
2022-05-14T19:34:36.688Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Received FINISHED
2022-05-14T19:34:36.688Z DD [FTP Session 42 49.12.121.47] TLS handshakep: Processed FINISHED
2022-05-14T19:34:36.688Z II [FTP Session 42 49.12.121.47] TLS Handshake successful
2022-05-14T19:34:36.688Z II [FTP Session 42 49.12.121.47] Protocol: TLS1.3, Key exchange: ECDHE-SECP384R1-ECDSA-SECP256R1-SHA256, Cipher: AES-256-GCM, MAC: AEAD, ALPN: 
2022-05-14T19:34:36.688Z >> [FTP Session 42 49.12.121.47] USER bob
2022-05-14T19:34:36.688Z << [FTP Session 42 49.12.121.47] 331 Please, specify the password.
2022-05-14T19:34:36.829Z DD [FTP Session 42 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T19:34:37.014Z DD [FTP Session 42 49.12.121.47] tls_layer_impl::on_read()
2022-05-14T19:34:37.014Z >> [FTP Session 42 49.12.121.47] PASS ****
2022-05-14T19:34:37.061Z DD [File-based Authenticator] authenticate: is_from_system: 0, impersonation_token: { username: "", home: "" }
2022-05-14T19:34:37.061Z << [FTP Session 42 49.12.121.47 bob] 230 Login successful.
2022-05-14T19:34:37.200Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:37.389Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:37.389Z >> [FTP Session 42 49.12.121.47 bob] SYST
2022-05-14T19:34:37.389Z << [FTP Session 42 49.12.121.47 bob] 215 UNIX emulated by FileZilla.
2022-05-14T19:34:37.529Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:37.717Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:37.717Z >> [FTP Session 42 49.12.121.47 bob] FEAT
2022-05-14T19:34:37.717Z << [FTP Session 42 49.12.121.47 bob] 211-Features:
2022-05-14T19:34:37.717Z << [FTP Session 42 49.12.121.47 bob] 211 End
2022-05-14T19:34:37.857Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:38.050Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:38.050Z >> [FTP Session 42 49.12.121.47 bob] PBSZ 0
2022-05-14T19:34:38.050Z << [FTP Session 42 49.12.121.47 bob] 200 PBSZ=0
2022-05-14T19:34:38.189Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:38.371Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:38.371Z >> [FTP Session 42 49.12.121.47 bob] PROT P
2022-05-14T19:34:38.371Z << [FTP Session 42 49.12.121.47 bob] 200 Protection level set to P
2022-05-14T19:34:38.510Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:38.699Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:38.699Z >> [FTP Session 42 49.12.121.47 bob] PWD
2022-05-14T19:34:38.699Z << [FTP Session 42 49.12.121.47 bob] 257 "/" is current directory.
2022-05-14T19:34:38.839Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:39.029Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:39.029Z >> [FTP Session 42 49.12.121.47 bob] TYPE I
2022-05-14T19:34:39.029Z << [FTP Session 42 49.12.121.47 bob] 200 Type set to I
2022-05-14T19:34:39.168Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:39.355Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:39.356Z >> [FTP Session 42 49.12.121.47 bob] PASV
2022-05-14T19:34:39.356Z DD [FTP Session 42 49.12.121.47 bob] TLS handshakep: About to send NEW SESSION TICKET
2022-05-14T19:34:39.356Z DD [FTP Session 42 49.12.121.47 bob] TLS handshakep: Sent NEW SESSION TICKET
2022-05-14T19:34:39.356Z VV [FTP Session 42 49.12.121.47 bob] Trying listen(1, 49517) for data connection.
2022-05-14T19:34:39.447Z << [FTP Session 42 49.12.121.47 bob] 227 Entering Passive Mode (<<redacted>>)
2022-05-14T19:34:39.587Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:39.781Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:39.781Z >> [FTP Session 42 49.12.121.47 bob] MLSD
2022-05-14T19:34:39.781Z << [FTP Session 42 49.12.121.47 bob] 150 About to start data transfer.
2022-05-14T19:34:50.933Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::on_read()
2022-05-14T19:34:50.933Z DD [FTP Session 42 49.12.121.47 bob] tls_layer_impl::failure(-110)
2022-05-14T19:34:50.933Z !! [FTP Session 42 49.12.121.47 bob] GnuTLS error -110 in gnutls_record_recv: The TLS connection was non-properly terminated.
2022-05-14T19:34:50.933Z == [FTP Session 42 49.12.121.47 bob] Client did not properly shut down TLS connection
2022-05-14T19:34:50.933Z !! [FTP Session 42 49.12.121.47 bob] Control channel closed with error from source 0. Reason: ECONNABORTED - Connection aborted.
2022-05-14T19:34:50.933Z II [FTP Session 42 49.12.121.47] Session 0x25c51833930 with ID 42 destroyed.

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

Re: Error: PASV command failed

#5 Post by botg » 2022-05-16 09:26

Either the data connection port still isn't being forwarded, or there's a firewall blocking it.

bigbadbear
500 Command not understood
Posts: 5
Joined: 2022-05-14 16:25

Re: Error: PASV command failed

#6 Post by bigbadbear » 2022-05-16 14:02

Thanks for the note. Would the data port be something outside of the range of passive ports that I defined?

This error happens when I have windows firewall completely disabled, and my router is forwarding port 20-21 and the passive range of ports (I did 300 ports).

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

Re: Error: PASV command failed

#7 Post by botg » 2022-05-16 14:46

The data connection port is the passive mode port.

There is no need to forward port 20.

Check that you actually forwarded the 300 port range in a 1:1 mapping, ie. port X is forwarded to port X, port X+1 forwarded to X+1 and port X+299 is forwarded to port X+299

bigbadbear
500 Command not understood
Posts: 5
Joined: 2022-05-14 16:25

Re: Error: PASV command failed

#8 Post by bigbadbear » 2022-05-16 15:08

Thanks so much for looking at this with me.
On a whim, I decided to reboot my router, without making any changes, and now it works. (Passes all the way through ftptest.net) So right this moment I am both pleased and super annoyed at my router.

Normally if I have success, I post the info in the forum. In this case, everything was set correctly, but my router needed a reboot. So that would maybe be worth a try for someone else.

Thanks!
:beer:

Post Reply