FTPS fails to transfer file, but plain nossl FTP does work #1389

JonBendtsen · 2023-08-10T14:47:48Z

JonBendtsen
Aug 10, 2023

Hi

For both active and passive file transfers I have problems with FTPS, where as the plain nossl FTP always works.

I get a connection just file, and it does transfer a file with FTPS, but the size of the file ends up being 0.

SFTPgo version: 2.5.3.1
Linux Ubuntu Jammy 22.04
My SFTPgo.json file:

  "ftpd": {
    "bindings": [
      {
        "port": 21,
        "address": "{{ SFTPgo_address }}",
        "apply_proxy_config": true,
        "tls_mode": 0,
        "certificate_file": "/etc/ssl/certs/{{ hostname }}.{{ domain }}.crt",
        "certificate_key_file": "/etc/ssl/private/{{ hostname }}.{{ domain }}.key",
        "min_tls_version": 12,
        "force_passive_ip": "",
        "passive_ip_overrides": [],
        "passive_host": "",
        "client_auth_type": 0,
        "tls_cipher_suites": [],
        "passive_connections_security": 0,
        "active_connections_security": 0,
        "debug": true
      }
    ],
    "banner": "DMI-FTP_2.0.23",
    "banner_file": "",
    "active_transfers_port_non_20": true,
    "passive_port_range": {
      "start": 50000,
      "end": 50100
    },
    "disable_active_mode": false,
    "enable_site": false,
    "hash_support": 0,
    "combine_support": 0,
    "certificate_file": "/etc/ssl/certs/{{ hostname }}.{{ domain }}.crt",
    "certificate_key_file": "/etc/ssl/private/{{ hostname }}.{{ domain }}.key",
    "ca_certificates": [],
    "ca_revocation_lists": []
  },

Output from journalctl -u sftpgo --since="now":

For active:

  "level": "debug",
  "time": "2023-08-10T14:28:59.897",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "TYPE I",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.897",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "200 Type set to binary",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.898",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "PORT 192,168,134,69,198,25",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.899",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "200 PORT command successful",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.900",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "STOR date.txt",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.900",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "transfer added, id: 1, active transfers: 1"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.901",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "150 Using transfer connection",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.902",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "remoteAddr": "192.168.134.69:50713",
  "localAddr": "192.168.134.69:48562",
  "message": "Transfer connection opened"
}
{
  "level": "error",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "Unexpected error for transfer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\", error: \"read tcp 192.168.134.69:48562->192.168.134.69:50713: read: connection reset by peer\" bytes sent: 0, bytes received: 0 transfer running since 4 ms"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "upload file size 0, num files 0, deleted files 0, fs path \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "info",
  "time": "2023-08-10T14:28:59.905",
  "sender": "Upload",
  "local_addr": "192.168.134.69:21",
  "remote_addr": "192.168.134.69:37830",
  "elapsed_ms": 4,
  "size_bytes": 0,
  "username": "jonbendtsen",
  "file_path": "/srv/sftpgo/data/jonbendtsen/date.txt",
  "connection_id": "FTP_0_3",
  "protocol": "FTP",
  "ftp_mode": "active"
}
{
  "level": "error",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "transfer error: read tcp 192.168.134.69:48562->192.168.134.69:50713: read: connection reset by peer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "transfer removed, id: 1 active transfers: 0"
}
{
  "level": "error",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "generic error: read tcp 192.168.134.69:48562->192.168.134.69:50713: read: connection reset by peer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.905",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "message": "Transfer connection closed"
}
{
  "level": "warn",
  "time": "2023-08-10T14:28:59.905",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "err": "read tcp 192.168.134.69:48562->192.168.134.69:50713: read: connection reset by peer",
  "message": "Problem closing transfer connection"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.905",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "550 Issue during transfer close: tls: failed to send closeNotify alert (but connection was closed anyway): write tcp 192.168.134.69:48562->192.168.134.69:50713: write: broken pipe",
  "message": "Sending answer"
}

Active Client side

root@sftpgo-deic-prod-admin-01:/tmp# date > date.txt ; cat date.txt ; ftp-ssl -d -z secure sftpgo-sftpgo ; cat /srv/sftpgo/data/jonbendtsen/date.txt
Thu Aug 10 14:28:59 UTC 2023
Connected to sftpgo-sftpgo.
220 SFTPGo 2.5.3 ready
ftp: setsockopt: Bad file descriptor
Name (sftpgo-sftpgo:dmz_jobe): jonbendtsen
---> AUTH TLS
234 AUTH command ok. Expecting TLS Negotiation.
[SSL Cipher TLS_AES_128_GCM_SHA256]
---> PBSZ 0
200 Whatever
---> PROT P
200 OK
[Encrypted data transfer.]
---> USER jonbendtsen
331 OK
Password:
---> PASS XXXX
230 Password ok, continue
---> SYST
215 UNIX Type: L8
Remote system type is UNIX.
Using binary mode to transfer files.
ftp> put date.txt
local: date.txt remote: date.txt
---> TYPE I
200 Type set to binary
---> PORT 192,168,134,69,198,25
200 PORT command successful
---> STOR date.txt
150 Using transfer connection
===>START SSL connect on DATA
===>DONE SSL connect on DATA 9
550 Issue during transfer close: tls: failed to send closeNotify alert (but connection was closed anyway): write tcp 192.168.134.69:48562->192.168.134.69:50713: write: broken pipe
29 bytes sent in 0.00 secs (690.7393 kB/s)

Passive transfers

  "level": "debug",
  "time": "2023-08-10T14:35:45.776",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "line": "TYPE I",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.777",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "line": "200 Type set to binary",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.778",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "line": "PASV",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.778",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "line": "227 Entering Passive Mode (192,168,134,69,195,133)",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.779",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "line": "STOR date.txt",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.780",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "transfer added, id: 1, active transfers: 1"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.780",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "line": "150 Using transfer connection",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.782",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "remoteAddr": "192.168.134.69:36816",
  "localAddr": "192.168.134.69:50053",
  "message": "Transfer connection opened"
}
{
  "level": "error",
  "time": "2023-08-10T14:35:45.784",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "Unexpected error for transfer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\", error: \"read tcp 192.168.134.69:50053->192.168.134.69:36816: read: connection reset by peer\" bytes sent: 0, bytes received: 0 transfer running since 4 ms"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.785",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "upload file size 0, num files 0, deleted files 0, fs path \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "info",
  "time": "2023-08-10T14:35:45.785",
  "sender": "Upload",
  "local_addr": "192.168.134.69:21",
  "remote_addr": "192.168.134.69:57734",
  "elapsed_ms": 4,
  "size_bytes": 0,
  "username": "jonbendtsen",
  "file_path": "/srv/sftpgo/data/jonbendtsen/date.txt",
  "connection_id": "FTP_0_4",
  "protocol": "FTP",
  "ftp_mode": "passive"
}
{
  "level": "error",
  "time": "2023-08-10T14:35:45.785",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "transfer error: read tcp 192.168.134.69:50053->192.168.134.69:36816: read: connection reset by peer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.785",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "transfer removed, id: 1 active transfers: 0"
}
{
  "level": "error",
  "time": "2023-08-10T14:35:45.786",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "generic error: read tcp 192.168.134.69:50053->192.168.134.69:36816: read: connection reset by peer"
}
{
  "level": "warn",
  "time": "2023-08-10T14:35:45.786",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "err": "tls: failed to send closeNotify alert (but connection was closed anyway): write tcp 192.168.134.69:50053->192.168.134.69:36816: write: broken pipe",
  "message": "Problem closing passive connection"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.786",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "message": "Transfer connection closed"
}
{
  "level": "debug",
  "time": "2023-08-10T14:35:45.787",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "line": "550 Issue during transfer: read tcp 192.168.134.69:50053->192.168.134.69:36816: read: connection reset by peer",
  "message": "Sending answer"
}```

Passive client side
```root@sftpgo-deic-prod-admin-01:/tmp# date > date.txt ; cat date.txt ; ftp-ssl -pd -z secure sftpgo-sftpgo ; cat /srv/sftpgo/data/jonbendtsen/date.txt
Thu Aug 10 14:35:45 UTC 2023
Connected to sftpgo-sftpgo.
220 SFTPGo 2.5.3 ready
ftp: setsockopt: Bad file descriptor
Name (sftpgo-sftpgo:dmz_jobe): jonbendtsen
---> AUTH TLS
234 AUTH command ok. Expecting TLS Negotiation.
[SSL Cipher TLS_AES_128_GCM_SHA256]
---> PBSZ 0
200 Whatever
---> PROT P
200 OK
[Encrypted data transfer.]
---> USER jonbendtsen
331 OK
Password:
---> PASS XXXX
230 Password ok, continue
---> SYST
215 UNIX Type: L8
Remote system type is UNIX.
Using binary mode to transfer files.
ftp> put date.txt
local: date.txt remote: date.txt
---> TYPE I
200 Type set to binary
---> PASV
227 Entering Passive Mode (192,168,134,69,195,147)
---> STOR date.txt
150 Using transfer connection
===>START SSL connect on DATA
===>DONE SSL connect on DATA 9
550 Issue during transfer: read tcp 192.168.134.69:50067->192.168.134.69:44100: read: connection reset by peer
29 bytes sent in 0.00 secs (1.6269 MB/s)```


My ftp client manpage is https://manpages.debian.org/testing/ftp-ssl/ftp-ssl.1.en.html

If I run ftp-ssl with -z nossl then I do not see any errors, neither in active, nor in passive.

JonBendtsen · 2023-08-10T15:02:39Z

JonBendtsen
Aug 10, 2023
Author

My client side (and server side at it is the same machine) has this libssl installed
ii libssl3:amd64 3.0.2-0ubuntu1.10 amd64 Secure Sockets Layer toolkit - shared libraries

0 replies

JonBendtsen · 2023-08-11T10:40:32Z

JonBendtsen
Aug 11, 2023
Author

Trying with SFTPgo version: 2.5.4

2 replies

JonBendtsen Aug 11, 2023
Author

Same issue

{
  "level": "debug",
  "time": "2023-08-11T11:01:10.709",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "clientIp": "192.168.134.69:39618",
  "message": "Client connected"
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:10.709",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "connection added, local address \"192.168.134.69:21\", remote address \"192.168.134.69:39618\", num open connections: 1"
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:19.844",
  "sender": "common",
  "connection_id": "FTP_0_4",
  "message": "connection swapped, close fs error: <nil>"
}
{
  "level": "info",
  "time": "2023-08-11T11:01:19.844",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "User \"jonbendtsen\" logged in with \"password\" from ip \"192.168.134.69\""
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:19.873",
  "sender": "dataprovider_postgresql",
  "message": "post login hook executed for user \"jonbendtsen\", ip 192.168.134.69, protocol FTP, elapsed 28.782735ms err: <nil>"
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:23.338",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "transfer added, id: 1, active transfers: 1"
}
{
  "level": "error",
  "time": "2023-08-11T11:01:23.340",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "Unexpected error for transfer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\", error: \"read tcp 192.168.134.69:50079->192.168.134.69:41024: read: connection reset by peer\" bytes sent: 0, bytes received: 0 transfer running since 1 ms"
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:23.340",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "upload file size 0, num files 0, deleted files 0, fs path \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "info",
  "time": "2023-08-11T11:01:23.340",
  "sender": "Upload",
  "local_addr": "192.168.134.69:21",
  "remote_addr": "192.168.134.69:39618",
  "elapsed_ms": 2,
  "size_bytes": 0,
  "username": "jonbendtsen",
  "file_path": "/srv/sftpgo/data/jonbendtsen/date.txt",
  "connection_id": "FTP_0_4",
  "protocol": "FTP",
  "ftp_mode": "passive"
}
{
  "level": "error",
  "time": "2023-08-11T11:01:23.340",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "transfer error: read tcp 192.168.134.69:50079->192.168.134.69:41024: read: connection reset by peer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:23.340",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "transfer removed, id: 1 active transfers: 0"
}
{
  "level": "error",
  "time": "2023-08-11T11:01:23.340",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "generic error: read tcp 192.168.134.69:50079->192.168.134.69:41024: read: connection reset by peer"
}
{
  "level": "warn",
  "time": "2023-08-11T11:01:23.341",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "err": "tls: failed to send closeNotify alert (but connection was closed anyway): write tcp 192.168.134.69:50079->192.168.134.69:41024: write: broken pipe",
  "message": "Problem closing passive connection"
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:25.192",
  "sender": "FTP",
  "connection_id": "FTP_0_4",
  "message": "connection removed, local address \"192.168.134.69:21\", remote address \"192.168.134.69:39618\" close fs error: <nil>, num open connections: 0"
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:25.192",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "clientIp": "192.168.134.69:39618",
  "message": "Client disconnected"
}
{
  "level": "debug",
  "time": "2023-08-11T11:01:25.192",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "4",
  "err": "use of closed network connection",
  "message": "Problem closing control connection"
}

JonBendtsen Aug 11, 2023
Author

Active

{
  "level": "debug",
  "time": "2023-08-10T14:28:59.897",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "TYPE I",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.897",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "200 Type set to binary",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.898",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "PORT 192,168,134,69,198,25",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.899",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "200 PORT command successful",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.900",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "STOR date.txt",
  "message": "Received line"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.900",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "transfer added, id: 1, active transfers: 1"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.901",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "150 Using transfer connection",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.902",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "remoteAddr": "192.168.134.69:50713",
  "localAddr": "192.168.134.69:48562",
  "message": "Transfer connection opened"
}
{
  "level": "error",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "Unexpected error for transfer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\", error: \"read tcp 192.168.134.69:48562->192.168.134.69:50713: read: connection reset by peer\" bytes sent: 0, bytes received: 0 transfer running since 4 ms"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "upload file size 0, num files 0, deleted files 0, fs path \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "info",
  "time": "2023-08-10T14:28:59.905",
  "sender": "Upload",
  "local_addr": "192.168.134.69:21",
  "remote_addr": "192.168.134.69:37830",
  "elapsed_ms": 4,
  "size_bytes": 0,
  "username": "jonbendtsen",
  "file_path": "/srv/sftpgo/data/jonbendtsen/date.txt",
  "connection_id": "FTP_0_3",
  "protocol": "FTP",
  "ftp_mode": "active"
}
{
  "level": "error",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "transfer error: read tcp 192.168.134.69:48562->192.168.134.69:50713: read: connection reset by peer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "transfer removed, id: 1 active transfers: 0"
}
{
  "level": "error",
  "time": "2023-08-10T14:28:59.905",
  "sender": "FTP",
  "connection_id": "FTP_0_3",
  "message": "generic error: read tcp 192.168.134.69:48562->192.168.134.69:50713: read: connection reset by peer"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.905",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "message": "Transfer connection closed"
}
{
  "level": "warn",
  "time": "2023-08-10T14:28:59.905",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "err": "read tcp 192.168.134.69:48562->192.168.134.69:50713: read: connection reset by peer",
  "message": "Problem closing transfer connection"
}
{
  "level": "debug",
  "time": "2023-08-10T14:28:59.905",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "3",
  "line": "550 Issue during transfer close: tls: failed to send closeNotify alert (but connection was closed anyway): write tcp 192.168.134.69:48562->192.168.134.69:50713: write: broken pipe",
  "message": "Sending answer"
}
{
  "level": "debug",
  "time": "2023-08-11T11:05:47.795",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "5",
  "clientIp": "192.168.134.69:47326",
  "message": "Client connected"
}
{
  "level": "debug",
  "time": "2023-08-11T11:05:47.795",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "connection added, local address \"192.168.134.69:21\", remote address \"192.168.134.69:47326\", num open connections: 1"
}
{
  "level": "debug",
  "time": "2023-08-11T11:05:56.243",
  "sender": "common",
  "connection_id": "FTP_0_5",
  "message": "connection swapped, close fs error: <nil>"
}
{
  "level": "info",
  "time": "2023-08-11T11:05:56.243",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "User \"jonbendtsen\" logged in with \"password\" from ip \"192.168.134.69\""
}
{
  "level": "debug",
  "time": "2023-08-11T11:05:56.272",
  "sender": "dataprovider_postgresql",
  "message": "post login hook executed for user \"jonbendtsen\", ip 192.168.134.69, protocol FTP, elapsed 28.732912ms err: <nil>"
}
{
  "level": "debug",
  "time": "2023-08-11T11:06:02.607",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "transfer added, id: 1, active transfers: 1"
}
{
  "level": "error",
  "time": "2023-08-11T11:06:02.609",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "Unexpected error for transfer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\", error: \"read tcp 192.168.134.69:55142->192.168.134.69:50967: read: connection reset by peer\" bytes sent: 0, bytes received: 0 transfer running since 2 ms"
}
{
  "level": "debug",
  "time": "2023-08-11T11:06:02.609",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "upload file size 0, num files 0, deleted files 0, fs path \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "info",
  "time": "2023-08-11T11:06:02.610",
  "sender": "Upload",
  "local_addr": "192.168.134.69:21",
  "remote_addr": "192.168.134.69:47326",
  "elapsed_ms": 2,
  "size_bytes": 0,
  "username": "jonbendtsen",
  "file_path": "/srv/sftpgo/data/jonbendtsen/date.txt",
  "connection_id": "FTP_0_5",
  "protocol": "FTP",
  "ftp_mode": "active"
}
{
  "level": "error",
  "time": "2023-08-11T11:06:02.610",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "transfer error: read tcp 192.168.134.69:55142->192.168.134.69:50967: read: connection reset by peer, path: \"/srv/sftpgo/data/jonbendtsen/date.txt\""
}
{
  "level": "debug",
  "time": "2023-08-11T11:06:02.610",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "transfer removed, id: 1 active transfers: 0"
}
{
  "level": "error",
  "time": "2023-08-11T11:06:02.610",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "generic error: read tcp 192.168.134.69:55142->192.168.134.69:50967: read: connection reset by peer"
}
{
  "level": "warn",
  "time": "2023-08-11T11:06:02.610",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "5",
  "err": "read tcp 192.168.134.69:55142->192.168.134.69:50967: read: connection reset by peer",
  "message": "Problem closing transfer connection"
}
{
  "level": "debug",
  "time": "2023-08-11T11:06:05.705",
  "sender": "FTP",
  "connection_id": "FTP_0_5",
  "message": "connection removed, local address \"192.168.134.69:21\", remote address \"192.168.134.69:47326\" close fs error: <nil>, num open connections: 0"
}
{
  "level": "debug",
  "time": "2023-08-11T11:06:05.706",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "5",
  "clientIp": "192.168.134.69:47326",
  "message": "Client disconnected"
}
{
  "level": "debug",
  "time": "2023-08-11T11:06:05.706",
  "sender": "ftpserverlib",
  "server_id": "FTP_0",
  "clientId": "5",
  "err": "use of closed network connection",
  "message": "Problem closing control connection"
}

JonBendtsen · 2023-08-14T14:31:05Z

JonBendtsen
Aug 14, 2023
Author

I've now tested with Filezilla 3.46.3, and that works fine, it can transfer files just fine.

I've also tried with ftp-ssl locally on my own laptop, and that also fails.

ii  ftp-ssl        0.17.34+0.2-4.1 amd64        FTP client with SSL or TLS encryption support

ii  ftp-ssl        0.17.34+really0.17-1 amd64        FTP client with SSL or TLS encryption support

I'm starting to think that there is a bug in ftp-ssl and/or SFTPgo since it doesn't work.

0 replies

JonBendtsen · 2023-08-14T14:35:15Z

JonBendtsen
Aug 14, 2023
Author

I now assume that the issue is in ftp-ssl because of this bug report

https://bugs.launchpad.net/ubuntu/+source/netkit-ftp-ssl/+bug/1903918

0 replies

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

FTPS fails to transfer file, but plain nossl FTP does work #1389

{{title}}

Replies: 4 comments 2 replies

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

Select a reply

FTPS fails to transfer file, but plain nossl FTP does work #1389

JonBendtsen Aug 10, 2023

Replies: 4 comments · 2 replies

JonBendtsen Aug 10, 2023 Author

JonBendtsen Aug 11, 2023 Author

JonBendtsen Aug 11, 2023 Author

JonBendtsen Aug 11, 2023 Author

JonBendtsen Aug 14, 2023 Author

JonBendtsen Aug 14, 2023 Author

JonBendtsen
Aug 10, 2023

Replies: 4 comments 2 replies

JonBendtsen
Aug 10, 2023
Author

JonBendtsen
Aug 11, 2023
Author

JonBendtsen Aug 11, 2023
Author

JonBendtsen Aug 11, 2023
Author

JonBendtsen
Aug 14, 2023
Author

JonBendtsen
Aug 14, 2023
Author