Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Build-time detection of Linux POSIX ACL support broken since 1.3.8rc2 #1568

Closed
eomanis opened this issue Dec 16, 2022 · 12 comments
Closed

Build-time detection of Linux POSIX ACL support broken since 1.3.8rc2 #1568

eomanis opened this issue Dec 16, 2022 · 12 comments
Assignees
Milestone

Comments

@eomanis
Copy link

eomanis commented Dec 16, 2022

What I Did

Upgraded from 1.3.7e to 1.3.8.
System is Arch Linux, package is the proftpd AUR package.

What I Expected/Wanted

Basically, version 1.3.8 working comparably well as version 1.3.7e.
Instead the server is unusable because while the users can successfully connect, they cannot view the shared directory's contents.
Downgrading to version 1.3.7e works around the issue.

ProFTPD Version and Configuration

Version is 1.3.8. I built and tested this version twice, both built with yay using the "clean" feature, i.e. without having files of previous builds lying around.

Something of note concerning the configuration, I am using Access Control Lists (ACL) to control which user may access what on the FTP server, and due to this I am making use of the mod_facl.c module.
As far as FTP servers go this may be somewhat unusual, which is why I suspect a regression in mod_facl.c.

Output of proftpd -V

Compile-time Settings:
  Version: 1.3.8 (stable)
  Platform: LINUX [Linux 5.15.83-1-lts x86_64]
  OS/Release:
    NAME="Arch Linux"
    PRETTY_NAME="Arch Linux"
    ID=arch
    BUILD_ID=rolling
  Built: Fri Dec 16 2022 19:29:29 UTC
  Built With:
    configure  '--prefix=/usr' '--sbindir=/usr/bin' '--libexecdir=/usr/lib' '--disable-pam' '--with-modules=mod_digest:mod_sftp:mod_quotatab:mod_quotatab_sql:mod_quotatab_file:mod_tls:mod_tls_shmcache:mod_ldap:mod_sql:mod_sql_mysql:mod_sql_postgres:mod_facl:mod_dynmasq' '--sysconfdir=/etc' '--localstatedir=/run/proftpd' '--enable-ctrls' '--enable-ipv6' '--with-includes=/usr/include/mysql:/usr/include/postgresql' '--with-libraries=/usr/lib/mysql:/usr/lib/postgresql' '--enable-nls' '--enable-redis' '--enable-facl' '--with-systemd' 'CFLAGS=-march=x86-64 -mtune=generic -O2 -pipe -fno-plt -fexceptions         -Wp,-D_FORTIFY_SOURCE=2 -Wformat -Werror=format-security         -fstack-clash-protection -fcf-protection' 'LDFLAGS=-Wl,-O1,--sort-common,--as-needed,-z,relro,-z,now' 'CXXFLAGS=-march=x86-64 -mtune=generic -O2 -pipe -fno-plt -fexceptions         -Wp,-D_FORTIFY_SOURCE=2 -Wformat -Werror=format-security         -fstack-clash-protection -fcf-protection -Wp,-D_GLIBCXX_ASSERTIONS'

  CFLAGS: -g2 -march=x86-64 -mtune=generic -O2 -pipe -fno-plt -fexceptions         -Wp,-D_FORTIFY_SOURCE=2 -Wformat -Werror=format-security         -fstack-clash-protection -fcf-protection -Wall -fno-omit-frame-pointer -fno-strict-aliasing
  LDFLAGS: -Wl,-L$(top_srcdir)/lib,-L$(top_builddir)/lib -Wl,-O1,--sort-common,--as-needed,-z,relro,-z,now -rdynamic -L/usr/lib/postgresql -L/usr/lib/mysql -L/usr/lib/ -L/usr/lib
  LIBS:  -lssl -lcrypto -lcap  -lpq  -lm -lmysqlclient -lz  -lldap -llber  -lssl  -lpam  -lcrypto -lattr -lcrypt -lhiredis  -pthread

  Files:
    Configuration File:
      /etc/proftpd.conf
    Pid File:
      /run/proftpd/proftpd.pid
    Scoreboard File:
      /run/proftpd/proftpd.scoreboard

  Info:
    + Max supported UID: 4294967295
    + Max supported GID: 4294967295

  Features:
    - Autoshadow support
    + Controls support
    + curses support
    - Developer support
    - DSO support
    + IPv6 support
    + Largefile support
    - Lastlog support
    - Memcache support
    + ncursesw support
    + NLS support
    + OpenSSL support (OpenSSL 3.0.7 1 Nov 2022)
    - PCRE support
    - PCRE2 support
    + POSIX ACL support
    + Redis support
    + Sendfile support
    + Shadow file support
    - Sodium support
    + Trace support
    + xattr support

  Tunable Options:
    PR_TUNABLE_BUFFER_SIZE = 1024
    PR_TUNABLE_DEFAULT_RCVBUFSZ = 8192
    PR_TUNABLE_DEFAULT_SNDBUFSZ = 8192
    PR_TUNABLE_ENV_MAX = 2048
    PR_TUNABLE_GLOBBING_MAX_MATCHES = 100000
    PR_TUNABLE_GLOBBING_MAX_RECURSION = 8
    PR_TUNABLE_HASH_TABLE_SIZE = 40
    PR_TUNABLE_LOGIN_MAX = 256
    PR_TUNABLE_NEW_POOL_SIZE = 512
    PR_TUNABLE_PATH_MAX = 4096
    PR_TUNABLE_SCOREBOARD_BUFFER_SIZE = 80
    PR_TUNABLE_SCOREBOARD_SCRUB_TIMER = 30
    PR_TUNABLE_SELECT_TIMEOUT = 30
    PR_TUNABLE_TIMEOUTIDENT = 10
    PR_TUNABLE_TIMEOUTIDLE = 600
    PR_TUNABLE_TIMEOUTLINGER = 10
    PR_TUNABLE_TIMEOUTLOGIN = 300
    PR_TUNABLE_TIMEOUTNOXFER = 300
    PR_TUNABLE_TIMEOUTSTALLED = 3600
    PR_TUNABLE_XFER_SCOREBOARD_UPDATES = 10

Configuration file

# ProFTPD configuration file
# ===============================================================

# Basic stuff
ServerName                  "XXXXXXXXXXXXXXXXXXXXXXXXX"
ServerAdmin                 "XXXXXXXXXXX@XXXXXXXXXXXXX"
ServerType                  standalone
DefaultServer               on

# Host on a custom port
# See also PassivePorts
Port                        XXXXX

# Enable IPv6
UseIPv6                     on

# DoS attack mitigation
MaxInstances                80

# Timing attack mitigation with mod_delay
DelayEngine                 on
DelayTable                  /var/proftpd/proftpd.delay

# Enable ACL support if the respective module is available
<IfModule mod_facl.c>
    FACLEngine                  on
</IfModule>

# Dynamic IP address handling
# Periodically fetch a new external IP address from the DNS
# name specified at MasqueradeAddress
<IfModule mod_dynmasq.c>
    # Fetch a new IP address every 5 minutes
    DynMasqRefresh              300
</IfModule>

# Server-level TLS configuration
# Requires mod_tls_shmcache
TLSSessionCache             shm:/file=/tmp/proftpd/sess_cache
TLSStaplingCache            shm:/file=/tmp/proftpd/ocsp_cache

<Global>
    User                        proftpd
    Group                       proftpd
    DefaultRoot                 /srv/ftp/share

    # Connection-related settings
    PassivePorts                XXXXX XXXXX
    # NAT handling
    MasqueradeAddress           XXXXXXXXXXXXXXXXXXXXXXXXX

    # Rate limiting etc.
    MaxClients                  40

    # Global file access/transmission tweaks
    AllowOverwrite              on
    AllowRetrieveRestart        on
    AllowStoreRestart           on
    DefaultTransferMode         binary

    # Lockdown and obfuscation
    DirFakeGroup                on
    DirFakeUser                 on
    DirFakeMode                 770
    ServerIdent                 off
    ShowSymlinks                off

    # Logging
    # Do not write an xferlog, so that we do not need to use logrotate
    TransferLog                 none
    # Restrict other logging to important stuff
    SyslogLevel                 warn

    # FTPS/TLS configuration
    TLSEngine                   on
    TLSRequired                 on
    TLSOptions                  NoSessionReuseRequired
    TLSRSACertificateFile       /path/to/fullchain.pem
    TLSRSACertificateKeyFile    /path/to/privkey.pem
    TLSDHParamFile              /etc/proftpd/ffdhe2048.txt
    TLSProtocol                 ALL -SSLv3 -TLSv1 -TLSv1.1
    TLSCipherSuite              ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-CHACHA20-POLY1305:DHE-RSA-AES128-GCM-SHA256:DHE-RSA-AES256-GCM-SHA384
    TLSServerCipherPreference   off
    TLSSessionTickets           off

    # User access settings
    # Only allow members of group(s) ftp,othergroup
    <Limit LOGIN>
        AllowGroup              OR ftp
    </Limit>

    # Always deny any kind of file or directory access
    # for "other", i.e. prevent world-readable and world-
    # writable files and directories
    Umask                       007

    # Bar use of SITE CHMOD by default
    <Limit SITE_CHMOD>
        DenyAll
    </Limit>

    # Hide stuff from the users that they cannot access
    # If ACLs are used to control user access, then for
    # HideNoAccess to work properly the module
    # "mod_facl.c" must be available and its
    # server-config-level directive "FACLEngine" must
    # be set to "on"
    <IfModule mod_facl.c>
        <Directory /srv/ftp/share>
            HideNoAccess                on
        </Directory>
        # Do not allow any operations on items that are
        # hidden by the HideNoAccess directive
        <Limit DIRS>
            IgnoreHidden                on
        </Limit>
    </IfModule>
</Global>

FileZilla FTP client message log of version 1.3.8 (bad)

Status: Resolving address of XXXXXXXXXXXXXXXXXXXXXXXXX
Status: Connecting to [XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX]:XXXXX...
Status: Connection established, waiting for welcome message...
Status: Initializing TLS...
Status: TLS connection established.
Status: Logged in
Status: Retrieving directory listing...
Command:    PWD
Response:   550 PWD: Permission denied
Error:  Failed to retrieve directory listing

FileZilla FTP client message log of version 1.3.7e (good)

Status: Resolving address of XXXXXXXXXXXXXXXXXXXXXXXXX
Status: Connecting to [XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX]:XXXXX...
Status: Connection established, waiting for welcome message...
Status: Initializing TLS...
Status: TLS connection established.
Status: Logged in
Status: Retrieving directory listing...
Status: Directory listing of "/" successful
@Castaglia Castaglia self-assigned this Dec 16, 2022
@Castaglia
Copy link
Member

Can you provide the server-side debug logging generated? See e.g. http://www.proftpd.org/docs/howto/Debugging.html That might help provide some more clues about what's happening on the backend.

@eomanis
Copy link
Author

eomanis commented Dec 17, 2022

All right, here goes, I have created debug logs for 1.3.7e and 1.3.8.
Both times these steps have been done:

  1. Start ProFTPD with /usr/bin/proftpd --nodaemon --config /etc/proftpd/proftpd.conf --debug 10
  2. Connect with an FTP client
  3. Disconnect the FTP client

Something that stands out is that 1.3.8 says

PWD command denied by <Limit> configuration

Edit: I have fiddled around with HideNoAccess and IgnoreHidden.

  • If I comment out IgnoreHidden, PWD succeeds, but the client gets an empty directory listing
  • If I comment out HideNoAccess, PWD succeeds, and the client gets a full directory listing

So it smells as if ProFTPD fails to correctly assess the users' access rights to file system objects.

Log of 1.3.7e (good)

Starting ProFTPD FTP Server, --config /etc/proftpd/proftpd.conf...
Started ProFTPD FTP Server, --config /etc/proftpd/proftpd.conf.
2022-12-17 03:00:08,742 theserver proftpd[234382]: using TCP receive buffer size of 131072 bytes
2022-12-17 03:00:08,742 theserver proftpd[234382]: using TCP send buffer size of 16384 bytes
2022-12-17 03:00:08,742 theserver proftpd[234382]: testing Unix domain socket using S_ISFIFO
2022-12-17 03:00:08,742 theserver proftpd[234382]: testing Unix domain socket using S_ISSOCK
2022-12-17 03:00:08,742 theserver proftpd[234382]: using S_ISSOCK macro for Unix domain socket detection
2022-12-17 03:00:08,743 theserver proftpd[234382]: mod_sftp/1.0.1: using OpenSSL 3.0.7 1 Nov 2022
2022-12-17 03:00:08,743 theserver proftpd[234382]: mod_tls/2.9: using OpenSSL 3.0.7 1 Nov 2022
2022-12-17 03:00:08,743 theserver proftpd[234382]: mod_ldap/2.9.5: compiled using LDAP vendor 'OpenLDAP', LDAP API version 3001
2022-12-17 03:00:08,744 theserver proftpd[234382]: mod_ldap/2.9.5 linked with LDAP vendor 'OpenLDAP' (LDAP API version 3001, vendor version 20603), features: X_OPENLDAP, THREAD_SAFE, SESSION_THREAD_SAFE, OPERATION_THREAD_SAFE, X_OPENLDAP_REENTRANT, X_OPENLDAP_THREAD_SAFE
2022-12-17 03:00:08,744 theserver proftpd[234382]: mod_ldap/2.9.5: LDAP TLS package = OpenSSL
2022-12-17 03:00:08,744 theserver proftpd[234382]: mod_redis/0.2.2: using hiredis-1.1.0
2022-12-17 03:00:08,744 theserver proftpd[234382]: using 'UTF-8' as local charset for UTF-8 conversion
2022-12-17 03:00:08,744 theserver proftpd[234382]: <IfModule>: using 'mod_facl.c' section at line 30
2022-12-17 03:00:08,744 theserver proftpd[234382]: <IfModule>: using 'mod_dynmasq.c' section at line 37
2022-12-17 03:00:08,744 theserver proftpd[234382]: retrieved UID 857 for user 'proftpd'
2022-12-17 03:00:08,745 theserver proftpd[234382]: retrieved GID 857 for group 'proftpd'
2022-12-17 03:00:08,867 theserver proftpd[234382]: ROOT PRIVS at mod_tls.c:14449
2022-12-17 03:00:08,869 theserver proftpd[234382]: RELINQUISH PRIVS at mod_tls.c:14452
2022-12-17 03:00:08,869 theserver proftpd[234382]: ROOT PRIVS at mod_tls.c:14474
2022-12-17 03:00:08,870 theserver proftpd[234382]: RELINQUISH PRIVS at mod_tls.c:14518
2022-12-17 03:00:08,870 theserver proftpd[234382]: ROOT PRIVS at mod_tls.c:13551
2022-12-17 03:00:08,870 theserver proftpd[234382]: RELINQUISH PRIVS at mod_tls.c:13553
2022-12-17 03:00:08,871 theserver proftpd[234382]: <IfModule>: using 'mod_facl.c' section at line 128
2022-12-17 03:00:08,871 theserver proftpd[234382]: <Directory /srv/ftp/share>: adding section for resolved path '/srv/ftp/share'
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: XXX.XXX.XXX.XXX:XXXXX masquerading as XXX.XXX.XXX.XXX
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: Config for The FTP Server:
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: DefaultServer
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: DelayEngine
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: DelayTable
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: TLSSessionCache
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: TLSStaplingCache
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: Limit
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  IgnoreHidden
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver: /srv/ftp/share
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  HideNoAccess
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  MaxClients
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  AllowOverwrite
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  AllowRetrieveRestart
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  AllowStoreRestart
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  DirFakeGroup
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  DirFakeUser
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  DirFakeMode
2022-12-17 03:00:08,871 theserver proftpd[234382] theserver:  ShowSymlinks
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver:  TransferLog
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver:  TLSRequired
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver:  Umask
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: Limit
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver:  DenyAll
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: Limit
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver:  AllowGroup
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: UserID
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: UserName
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: GroupID
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: GroupName
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: DefaultRoot
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: PassivePorts
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: MasqueradeAddress
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: MaxClients
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: AllowOverwrite
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: AllowRetrieveRestart
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: AllowStoreRestart
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: DefaultTransferMode
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: DirFakeGroup
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: DirFakeUser
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: DirFakeMode
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: ServerIdent
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: ShowSymlinks
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TransferLog
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: SyslogLevel
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSEngine
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSRequired
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSOptions
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSRSACertificateFile
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSRSACertificateKeyFile
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSDHParamFile
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSProtocol
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSCipherSuite
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSServerCipherPreference
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: TLSSessionTickets
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: Umask
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: binding to text domain 'proftpd' using locale path '/usr/share/locale'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: using locale files in '/usr/share/locale'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'bg_BG': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'es_ES': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'fr_FR': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'it_IT': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'ja_JP': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'ko_KR': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'ru_RU': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'zh_CN': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: skipping possible language 'zh_TW': not supported by setlocale(3); see `locale -a'
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_lang/1.1: added the following supported languages: en_US.UTF-8, en_US
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: ROOT PRIVS at mod_ctrls.c:1203
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: RELINQUISH PRIVS at mod_ctrls.c:1205
2022-12-17 03:00:08,872 theserver proftpd[234382] theserver: mod_facl/0.6: registered 'facl' FS
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: mod_tls/2.9: generating initial TLS session ticket key
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: ROOT PRIVS at mod_tls.c:6319
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: RELINQUISH PRIVS at mod_tls.c:6322
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: mod_tls/2.9: scheduling new TLS session ticket key every 3600 secs
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: mod_tls/2.9: opening 'shm' TLSSessionCache
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: ROOT PRIVS at mod_tls_shmcache.c:808
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: RELINQUISH PRIVS at mod_tls_shmcache.c:811
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: mod_tls_shmcache/0.2: error: unable to open file '/tmp/proftpd/sess_cache': No such file or directory
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: mod_tls/2.9: error opening 'shm' TLSSessionCache: Invalid argument
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: mod_tls/2.9: opening 'shm' TLSStaplingCache
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: ROOT PRIVS at mod_tls_shmcache.c:1825
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: RELINQUISH PRIVS at mod_tls_shmcache.c:1828
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: mod_tls_shmcache/0.2: error: unable to open file '/tmp/proftpd/ocsp_cache': No such file or directory
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: mod_tls/2.9: error opening 'shm' TLSStaplingCache: Invalid argument
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: ROOT PRIVS at mod_tls.c:3473
2022-12-17 03:00:08,873 theserver proftpd[234382] theserver: RELINQUISH PRIVS at mod_tls.c:3476
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: ROOT PRIVS at mod_delay.c:580
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: RELINQUISH PRIVS at mod_delay.c:583
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: ROOT PRIVS at mod_rlimit.c:555
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: RELINQUISH PRIVS at mod_rlimit.c:558
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: set core resource limits for daemon
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: ignoring supplemental groups for non-root UID 857
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: SETUP PRIVS at main.c:2619
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: ROOT PRIVS at main.c:1897
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: opening scoreboard '/run/proftpd/proftpd.scoreboard'
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: RELINQUISH PRIVS at main.c:1924
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: ProFTPD 1.3.7e (maint) (built Fri Dec 16 2022 17:37:04 UTC) standalone mode STARTUP
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: ROOT PRIVS at pidfile.c:56
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: RELINQUISH PRIVS at pidfile.c:59
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: ROOT PRIVS at pidfile.c:67
2022-12-17 03:00:08,876 theserver proftpd[234382] theserver: RELINQUISH PRIVS at pidfile.c:70
2022-12-17 03:00:18,277 theserver proftpd[234382] theserver: ROOT PRIVS at mod_ctrls.c:727
2022-12-17 03:00:18,277 theserver proftpd[234382] theserver: RELINQUISH PRIVS at mod_ctrls.c:733
2022-12-17 03:00:26,095 theserver proftpd[234384] theserver: ROOT PRIVS at main.c:1291
2022-12-17 03:00:26,095 theserver proftpd[234384] theserver: RELINQUISH PRIVS at main.c:1295
2022-12-17 03:00:26,095 theserver proftpd[234384] theserver: no matching vhost found for XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX#XXXXX, using 'The FTP Server' listening on wildcard address
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at main.c:1100
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): SETUP PRIVS at main.c:1105
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): session requested from client in unknown class
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): performing module session initializations
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): mod_cap/1.1: adding CAP_AUDIT_WRITE capability
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): mod_ldap/2.9.5: no LDAPServer configured, using LDAP library defaults
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): mod_ldap/2.9.5: LDAPUsers not configured, skipping LDAP-based user authentication
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): mod_ldap/2.9.5: LDAPGroups not configured, skipping LDAP-based group memberships
2022-12-17 03:00:26,096 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_tls.c:6509
2022-12-17 03:00:26,097 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_tls.c:6512
2022-12-17 03:00:26,097 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_tls.c:15751
2022-12-17 03:00:26,097 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_tls.c:15754
2022-12-17 03:00:26,097 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_tls.c:3664
2022-12-17 03:00:26,097 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_tls.c:3694
2022-12-17 03:00:26,132 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_tls.c:17381
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_tls.c:17466
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): mod_tls/2.9: supporting TLSv1.2, TLSv1.3 protocols
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_delay.c:2105
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_delay.c:2108
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth.c:215
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): opening scoreboard '/run/proftpd/proftpd.scoreboard'
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:217
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): connected - local  : XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXXX
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): connected - remote : XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:42566
2022-12-17 03:00:26,134 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): FTP session opened.
2022-12-17 03:00:26,135 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'AUTH TLS' to mod_tls
2022-12-17 03:00:26,135 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'AUTH TLS' to mod_core
2022-12-17 03:00:26,135 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'AUTH TLS' to mod_core
2022-12-17 03:00:26,135 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'AUTH TLS' to mod_tls
2022-12-17 03:00:26,140 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'AUTH TLS' to mod_sql
2022-12-17 03:00:26,140 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'AUTH TLS' to mod_tls
2022-12-17 03:00:26,140 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_redis
2022-12-17 03:00:26,140 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_sql
2022-12-17 03:00:26,140 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_digest
2022-12-17 03:00:26,140 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_log
2022-12-17 03:00:26,140 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_tls
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_tls
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_core
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_core
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_delay
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_auth
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'USER theuser' to mod_auth
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'USER theuser' to mod_sql
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'USER theuser' to mod_delay
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_redis
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_sql
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_digest
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_log
2022-12-17 03:00:26,143 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_delay
2022-12-17 03:00:26,144 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_tls
2022-12-17 03:00:26,144 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_core
2022-12-17 03:00:26,144 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_core
2022-12-17 03:00:26,144 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_sql
2022-12-17 03:00:26,144 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_delay
2022-12-17 03:00:26,144 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_auth
2022-12-17 03:00:26,144 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'PASS (hidden)' to mod_auth
2022-12-17 03:00:26,144 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): retrieved UID XXXX for user 'theuser'
2022-12-17 03:00:26,152 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): retrieved group IDs: XXXXXXXXXXXX
2022-12-17 03:00:26,152 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): retrieved group names: XXXXXXXXXXXX
2022-12-17 03:00:26,152 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth_pam.c:348
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth_pam.c:582
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): user 'theuser' authenticated by mod_auth_pam.c
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at auth.c:1815
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at auth.c:1818
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): USER PRIVS XXXX at mod_auth.c:1569
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:1571
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): Config for The FTP Server:
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): DefaultServer
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): DelayEngine
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): DelayTable
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSSessionCache
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSStaplingCache
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): Limit
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  IgnoreHidden
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): /srv/ftp/share
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  HideNoAccess
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  MaxClients
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  AllowOverwrite
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  AllowRetrieveRestart
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  AllowStoreRestart
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  DirFakeGroup
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  DirFakeUser
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  DirFakeMode
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  ShowSymlinks
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  TransferLog
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  TLSRequired
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  Umask
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): Limit
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  DenyAll
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): Limit
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]):  AllowGroup
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): UserID
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): UserName
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): GroupID
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): GroupName
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): DefaultRoot
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): PassivePorts
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): MasqueradeAddress
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): MaxClients
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): AllowOverwrite
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): AllowRetrieveRestart
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): AllowStoreRestart
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): DefaultTransferMode
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): DirFakeGroup
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): DirFakeUser
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): DirFakeMode
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ServerIdent
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ShowSymlinks
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TransferLog
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): SyslogLevel
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSEngine
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSRequired
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSOptions
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSRSACertificateFile
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSRSACertificateKeyFile
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSDHParamFile
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSProtocol
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSCipherSuite
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSServerCipherPreference
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): TLSSessionTickets
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): Umask
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth.c:1659
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): setting group IDs: XXXXXXXXXXXX
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:1701
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): USER PRIVS XXXX at mod_auth.c:940
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:943
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): set TZ environment variable to 'CET'
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): Preparing to chroot to directory '/srv/ftp/share'
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at auth.c:1953
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at auth.c:1956
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): Environment successfully chroot()ed
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth.c:1750
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): SETUP PRIVS at mod_auth.c:1772
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): unable to chdir to /home/theuser (No such file or directory), defaulting to chroot directory /srv/ftp/share
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): in dir_check_full(): path = '/', fullpath = '/srv/ftp/share/'
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_sql
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_lang
2022-12-17 03:00:26,164 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_cap
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): mod_cap/1.1: uid = XXXX, euid = XXXX, gid = XXXX, egid = XXXX
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): mod_cap/1.1: capabilities 'cap_chown,cap_setgid,cap_setuid,cap_net_bind_service,cap_audit_write=ep'
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_redis
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_sql
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_tls
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_quotatab
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_digest
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_delay
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_log
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_ls
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_auth
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:2148
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth.c:725
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS: ID switching disabled
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): REVOKE PRIVS at mod_auth.c:726
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RootRevoke in effect, dropped root privs
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_rlimit
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_xfer
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_core
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_redis
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_sql
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_digest
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_log
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_delay
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_auth
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): USER theuser: Login successful.
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'SYST' to mod_tls
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'SYST' to mod_core
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'SYST' to mod_core
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'SYST' to mod_core
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'SYST' to mod_sql
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'SYST' to mod_redis
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'SYST' to mod_sql
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'SYST' to mod_digest
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'SYST' to mod_log
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'FEAT' to mod_tls
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'FEAT' to mod_core
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'FEAT' to mod_core
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'FEAT' to mod_core
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'FEAT' to mod_sql
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'FEAT' to mod_redis
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'FEAT' to mod_sql
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'FEAT' to mod_digest
2022-12-17 03:00:26,165 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'FEAT' to mod_log
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'CLNT FileZilla' to mod_tls
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'CLNT FileZilla' to mod_core
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'CLNT FileZilla' to mod_core
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'CLNT FileZilla' to mod_core
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'CLNT FileZilla' to mod_sql
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'CLNT FileZilla' to mod_redis
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'CLNT FileZilla' to mod_sql
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'CLNT FileZilla' to mod_digest
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'CLNT FileZilla' to mod_log
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS UTF8 ON' to mod_tls
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS UTF8 ON' to mod_core
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS UTF8 ON' to mod_core
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'OPTS UTF8 ON' to mod_core
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS_UTF8 ON' to mod_tls
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS_UTF8 ON' to mod_core
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS_UTF8 ON' to mod_core
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'OPTS_UTF8 ON' to mod_lang
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): mod_lang/1.1: Handling OPTS UTF8 ON (current encoding is 'UTF-8')
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'OPTS_UTF8 ON' to mod_sql
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS_UTF8 ON' to mod_redis
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS_UTF8 ON' to mod_sql
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS_UTF8 ON' to mod_digest
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS_UTF8 ON' to mod_log
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'OPTS UTF8 ON' to mod_sql
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS UTF8 ON' to mod_redis
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS UTF8 ON' to mod_sql
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS UTF8 ON' to mod_digest
2022-12-17 03:00:26,166 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS UTF8 ON' to mod_log
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PBSZ 0' to mod_tls
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PBSZ 0' to mod_core
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PBSZ 0' to mod_core
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'PBSZ 0' to mod_tls
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PBSZ 0' to mod_sql
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PBSZ 0' to mod_redis
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PBSZ 0' to mod_sql
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PBSZ 0' to mod_digest
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PBSZ 0' to mod_log
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PROT P' to mod_tls
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PROT P' to mod_core
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PROT P' to mod_core
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'PROT P' to mod_tls
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PROT P' to mod_sql
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PROT P' to mod_xfer
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PROT P' to mod_redis
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PROT P' to mod_sql
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PROT P' to mod_digest
2022-12-17 03:00:26,167 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PROT P' to mod_log
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PWD' to mod_tls
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PWD' to mod_core
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PWD' to mod_core
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'PWD' to mod_core
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PWD' to mod_sql
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PWD' to mod_redis
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PWD' to mod_sql
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PWD' to mod_digest
2022-12-17 03:00:26,168 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PWD' to mod_log
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'TYPE I' to mod_tls
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'TYPE I' to mod_core
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'TYPE I' to mod_core
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'TYPE I' to mod_xfer
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'TYPE I' to mod_sql
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'TYPE I' to mod_redis
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'TYPE I' to mod_sql
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'TYPE I' to mod_digest
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'TYPE I' to mod_log
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'EPSV' to mod_tls
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'EPSV' to mod_core
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'EPSV' to mod_core
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'EPSV' to mod_core
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): Entering Extended Passive Mode (|||XXXXX|)
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'EPSV' to mod_sql
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'EPSV' to mod_redis
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'EPSV' to mod_sql
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'EPSV' to mod_digest
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'EPSV' to mod_log
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'MLSD' to mod_tls
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'MLSD' to mod_core
2022-12-17 03:00:26,169 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'MLSD' to mod_core
2022-12-17 03:00:26,170 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'MLSD' to mod_facts
2022-12-17 03:00:26,170 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): passive data connection opened - local  : XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXXX
2022-12-17 03:00:26,170 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): passive data connection opened - remote : XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:44667
2022-12-17 03:00:26,202 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'MLSD' to mod_sql
2022-12-17 03:00:26,202 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'MLSD' to mod_redis
2022-12-17 03:00:26,202 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'MLSD' to mod_sql
2022-12-17 03:00:26,202 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'MLSD' to mod_digest
2022-12-17 03:00:26,202 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'MLSD' to mod_log
2022-12-17 03:00:26,202 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'MLSD' to mod_facts
2022-12-17 03:00:37,671 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth_pam.c:198
2022-12-17 03:00:37,671 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS: unable to seteuid(): Operation not permitted
2022-12-17 03:00:37,671 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS: unable to setegid(): Operation not permitted
2022-12-17 03:00:37,677 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth_pam.c:229
2022-12-17 03:00:37,677 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS: unable to seteuid(PR_ROOT_UID): Operation not permitted
2022-12-17 03:00:37,678 theserver proftpd[234384] theserver (doma.in[IPv6:addr:ess]): FTP session closed.
2022-12-17 03:00:39,080 theserver proftpd[234382] theserver: scrubbing scoreboard
2022-12-17 03:00:39,081 theserver proftpd[234382] theserver: ROOT PRIVS at scoreboard.c:1433
2022-12-17 03:00:39,081 theserver proftpd[234382] theserver: RELINQUISH PRIVS at scoreboard.c:1436
2022-12-17 03:00:39,081 theserver proftpd[234382] theserver: ROOT PRIVS at scoreboard.c:1477
2022-12-17 03:00:39,081 theserver proftpd[234382] theserver: RELINQUISH PRIVS at scoreboard.c:1586
2022-12-17 03:00:39,081 theserver proftpd[234382] theserver: finished scrubbing scoreboard

Log of 1.3.8 (bad)

Starting ProFTPD FTP Server, --config /etc/proftpd/proftpd.conf...
Started ProFTPD FTP Server, --config /etc/proftpd/proftpd.conf.
2022-12-17 02:58:01,467 theserver proftpd[233639]: using TCP receive buffer size of 131072 bytes
2022-12-17 02:58:01,467 theserver proftpd[233639]: using TCP send buffer size of 16384 bytes
2022-12-17 02:58:01,467 theserver proftpd[233639]: using 'LC_CTYPE=en_US.utf8;LC_NUMERIC=en_US.utf8;LC_TIME=de_DE.utf8;LC_COLLATE=en_US.utf8;LC_MONETARY=de_DE.utf8;LC_MESSAGES=en_US.utf8;LC_PAPER=de_DE.utf8;LC_NAME=en_US.utf8;LC_ADDRESS=en_US.utf8;LC_TELEPHONE=en_US.utf8;LC_MEASUREMENT=de_DE.utf8;LC_IDENTIFICATION=en_US.utf8' locale based on LANG=en_US.utf8 environment variable
2022-12-17 02:58:01,467 theserver proftpd[233639]: using 'UTF-8' as local charset for UTF-8 conversion
2022-12-17 02:58:01,467 theserver proftpd[233639]: mod_sftp/1.1.1: using OpenSSL 3.0.7 1 Nov 2022
2022-12-17 02:58:01,468 theserver proftpd[233639]: mod_tls/2.9.2: using OpenSSL 3.0.7 1 Nov 2022
2022-12-17 02:58:01,468 theserver proftpd[233639]: mod_ldap/2.9.5: compiled using LDAP vendor 'OpenLDAP', LDAP API version 3001
2022-12-17 02:58:01,469 theserver proftpd[233639]: mod_ldap/2.9.5 linked with LDAP vendor 'OpenLDAP' (LDAP API version 3001, vendor version 20603), features: X_OPENLDAP, THREAD_SAFE, SESSION_THREAD_SAFE, OPERATION_THREAD_SAFE, X_OPENLDAP_REENTRANT, X_OPENLDAP_THREAD_SAFE
2022-12-17 02:58:01,469 theserver proftpd[233639]: mod_ldap/2.9.5: LDAP TLS package = OpenSSL
2022-12-17 02:58:01,469 theserver proftpd[233639]: mod_redis/0.2.3: using hiredis-1.1.0
2022-12-17 02:58:01,469 theserver proftpd[233639]: <IfModule>: using 'mod_facl.c' section at line 30
2022-12-17 02:58:01,469 theserver proftpd[233639]: <IfModule>: using 'mod_dynmasq.c' section at line 37
2022-12-17 02:58:01,469 theserver proftpd[233639]: retrieved UID 857 for user 'proftpd'
2022-12-17 02:58:01,470 theserver proftpd[233639]: retrieved GID 857 for group 'proftpd'
2022-12-17 02:58:01,594 theserver proftpd[233639]: ROOT PRIVS at mod_tls.c:14879
2022-12-17 02:58:01,597 theserver proftpd[233639]: RELINQUISH PRIVS at mod_tls.c:14882
2022-12-17 02:58:01,597 theserver proftpd[233639]: ROOT PRIVS at mod_tls.c:14904
2022-12-17 02:58:01,598 theserver proftpd[233639]: RELINQUISH PRIVS at mod_tls.c:14948
2022-12-17 02:58:01,598 theserver proftpd[233639]: ROOT PRIVS at mod_tls.c:13979
2022-12-17 02:58:01,598 theserver proftpd[233639]: RELINQUISH PRIVS at mod_tls.c:13981
2022-12-17 02:58:01,598 theserver proftpd[233639]: <IfModule>: using 'mod_facl.c' section at line 128
2022-12-17 02:58:01,598 theserver proftpd[233639]: <Directory /srv/ftp/share>: adding section for resolved path '/srv/ftp/share'
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: XXX.XXX.XXX.XXX:XXXXX masquerading as XXX.XXX.XXX.XXX
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: Config for The FTP Server:
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: DefaultServer
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: Port
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: DelayEngine
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: DelayTable
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSSessionCache
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSStaplingCache
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: Limit
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  IgnoreHidden
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: /srv/ftp/share
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  HideNoAccess
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  MaxClients
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  AllowOverwrite
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  AllowRetrieveRestart
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  AllowStoreRestart
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  DirFakeGroup
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  DirFakeUser
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  DirFakeMode
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  ShowSymlinks
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  TransferLog
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  TLSRequired
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  Umask
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: Limit
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  DenyAll
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: Limit
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver:  AllowGroup
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: UserID
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: UserName
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: GroupID
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: GroupName
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: DefaultRoot
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: PassivePorts
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: MasqueradeAddress
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: MaxClients
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: AllowOverwrite
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: AllowRetrieveRestart
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: AllowStoreRestart
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: DefaultTransferMode
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: DirFakeGroup
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: DirFakeUser
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: DirFakeMode
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: ServerIdent
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: ShowSymlinks
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TransferLog
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: SyslogLevel
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSEngine
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSRequired
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSOptions
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSRSACertificateFile
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSRSACertificateKeyFile
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSDHParamFile
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSProtocol
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSCipherSuite
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSServerCipherPreference
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: TLSSessionTickets
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: Umask
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: mod_lang/1.1: binding to text domain 'proftpd' using locale path '/usr/share/locale'
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: mod_lang/1.1: using locale files in '/usr/share/locale'
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'bg_BG': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'es_ES': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'fr_FR': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'it_IT': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'ja_JP': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,599 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'ko_KR': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'ru_RU': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'zh_CN': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_lang/1.1: skipping possible language 'zh_TW': not supported by setlocale(3); see `locale -a'
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_lang/1.1: added the following supported languages: en_US.UTF-8, en_US
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: ROOT PRIVS at mod_ctrls.c:1233
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: RELINQUISH PRIVS at mod_ctrls.c:1235
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_tls/2.9.2: generating initial TLS session ticket key
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: ROOT PRIVS at mod_tls.c:6548
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: RELINQUISH PRIVS at mod_tls.c:6551
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_tls/2.9.2: scheduling new TLS session ticket key every 3600 secs
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_tls/2.9.2: opening 'shm' TLSSessionCache
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: ROOT PRIVS at mod_tls_shmcache.c:808
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: RELINQUISH PRIVS at mod_tls_shmcache.c:811
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_tls_shmcache/0.2: error: unable to open file '/tmp/proftpd/sess_cache': No such file or directory
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_tls/2.9.2: error opening 'shm' TLSSessionCache: Invalid argument
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_tls/2.9.2: opening 'shm' TLSStaplingCache
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: ROOT PRIVS at mod_tls_shmcache.c:1825
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: RELINQUISH PRIVS at mod_tls_shmcache.c:1828
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_tls_shmcache/0.2: error: unable to open file '/tmp/proftpd/ocsp_cache': No such file or directory
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: mod_tls/2.9.2: error opening 'shm' TLSStaplingCache: Invalid argument
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: ROOT PRIVS at mod_tls.c:3629
2022-12-17 02:58:01,600 theserver proftpd[233639] theserver: RELINQUISH PRIVS at mod_tls.c:3632
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: ROOT PRIVS at mod_delay.c:583
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: RELINQUISH PRIVS at mod_delay.c:586
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: ROOT PRIVS at mod_rlimit.c:554
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: RELINQUISH PRIVS at mod_rlimit.c:557
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: set core resource limits for daemon
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: ignoring supplemental groups for non-root UID 857
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: SETUP PRIVS at main.c:2770
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: ROOT PRIVS at main.c:1926
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: opening scoreboard '/run/proftpd/proftpd.scoreboard'
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: RELINQUISH PRIVS at main.c:1953
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: ROOT PRIVS at pidfile.c:56
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: RELINQUISH PRIVS at pidfile.c:59
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: ROOT PRIVS at pidfile.c:67
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: RELINQUISH PRIVS at pidfile.c:70
2022-12-17 02:58:01,604 theserver proftpd[233639] theserver: ProFTPD 1.3.8 (stable) (built Fri Dec 16 2022 19:29:29 UTC) standalone mode STARTUP
2022-12-17 02:58:12,406 theserver proftpd[233639] theserver: ROOT PRIVS at mod_ctrls.c:747
2022-12-17 02:58:12,406 theserver proftpd[233639] theserver: RELINQUISH PRIVS at mod_ctrls.c:753
2022-12-17 02:58:18,118 theserver proftpd[233641] theserver: ROOT PRIVS at main.c:1301
2022-12-17 02:58:18,118 theserver proftpd[233641] theserver: RELINQUISH PRIVS at main.c:1305
2022-12-17 02:58:18,118 theserver proftpd[233641] theserver: no matching vhost found for XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX#XXXXX, using 'The FTP Server' listening on wildcard address
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at main.c:1110
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): SETUP PRIVS at main.c:1115
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): session requested from client in unknown class
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): performing module session initializations
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): mod_cap/1.1: adding CAP_AUDIT_WRITE capability
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): mod_ldap/2.9.5: no LDAPServer configured, using LDAP library defaults
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): mod_ldap/2.9.5: LDAPUsers not configured, skipping LDAP-based user authentication
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): mod_ldap/2.9.5: LDAPGroups not configured, skipping LDAP-based group memberships
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_tls.c:6738
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_tls.c:6741
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_tls.c:16183
2022-12-17 02:58:18,146 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_tls.c:16186
2022-12-17 02:58:18,147 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_tls.c:3820
2022-12-17 02:58:18,147 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_tls.c:3850
2022-12-17 02:58:18,185 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_tls.c:17856
2022-12-17 02:58:18,188 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_tls.c:17941
2022-12-17 02:58:18,188 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): mod_tls/2.9.2: supporting TLSv1.2, TLSv1.3 protocols
2022-12-17 02:58:18,188 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_delay.c:2111
2022-12-17 02:58:18,188 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_delay.c:2114
2022-12-17 02:58:18,188 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth.c:215
2022-12-17 02:58:18,188 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): opening scoreboard '/run/proftpd/proftpd.scoreboard'
2022-12-17 02:58:18,188 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:217
2022-12-17 02:58:18,189 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): connected - local  : XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXXX
2022-12-17 02:58:18,189 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): connected - remote : XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:XXXX:45670
2022-12-17 02:58:18,189 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): FTP session opened.
2022-12-17 02:58:18,189 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'AUTH TLS' to mod_tls
2022-12-17 02:58:18,189 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'AUTH TLS' to mod_core
2022-12-17 02:58:18,189 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'AUTH TLS' to mod_core
2022-12-17 02:58:18,189 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'AUTH TLS' to mod_tls
2022-12-17 02:58:18,196 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'AUTH TLS' to mod_sql
2022-12-17 02:58:18,196 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'AUTH TLS' to mod_tls
2022-12-17 02:58:18,196 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_redis
2022-12-17 02:58:18,196 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_sql
2022-12-17 02:58:18,196 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_digest
2022-12-17 02:58:18,196 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_log
2022-12-17 02:58:18,196 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'AUTH TLS' to mod_tls
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_tls
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_core
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_core
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_delay
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'USER theuser' to mod_auth
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'USER theuser' to mod_auth
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'USER theuser' to mod_sql
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'USER theuser' to mod_delay
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_redis
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_sql
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_digest
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_log
2022-12-17 02:58:18,201 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'USER theuser' to mod_delay
2022-12-17 02:58:18,202 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_tls
2022-12-17 02:58:18,202 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_core
2022-12-17 02:58:18,202 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_core
2022-12-17 02:58:18,202 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_sql
2022-12-17 02:58:18,202 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_delay
2022-12-17 02:58:18,202 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PASS (hidden)' to mod_auth
2022-12-17 02:58:18,202 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'PASS (hidden)' to mod_auth
2022-12-17 02:58:18,202 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): retrieved UID XXXX for user 'theuser'
2022-12-17 02:58:18,212 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): retrieved group IDs: XXXXXXXXXXXX
2022-12-17 02:58:18,212 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): retrieved group names: XXXXXXXXXXXX
2022-12-17 02:58:18,212 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth_pam.c:348
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth_pam.c:582
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): user 'theuser' authenticated by mod_auth_pam.c
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at auth.c:1774
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at auth.c:1777
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): USER PRIVS XXXX at mod_auth.c:1576
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:1578
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): Config for The FTP Server:
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): DefaultServer
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): Port
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): DelayEngine
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): DelayTable
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSSessionCache
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSStaplingCache
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): Limit
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  IgnoreHidden
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): /srv/ftp/share
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  HideNoAccess
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  MaxClients
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  AllowOverwrite
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  AllowRetrieveRestart
2022-12-17 02:58:18,225 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  AllowStoreRestart
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  DirFakeGroup
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  DirFakeUser
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  DirFakeMode
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  ShowSymlinks
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  TransferLog
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  TLSRequired
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  Umask
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): Limit
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  DenyAll
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): Limit
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]):  AllowGroup
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): UserID
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): UserName
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): GroupID
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): GroupName
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): DefaultRoot
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): PassivePorts
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): MasqueradeAddress
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): MaxClients
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): AllowOverwrite
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): AllowRetrieveRestart
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): AllowStoreRestart
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): DefaultTransferMode
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): DirFakeGroup
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): DirFakeUser
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): DirFakeMode
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ServerIdent
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ShowSymlinks
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TransferLog
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): SyslogLevel
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSEngine
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSRequired
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSOptions
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSRSACertificateFile
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSRSACertificateKeyFile
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSDHParamFile
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSProtocol
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSCipherSuite
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSServerCipherPreference
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): TLSSessionTickets
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): Umask
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth.c:1672
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): setting group IDs: XXXXXXXXXXXX
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:1717
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): USER PRIVS XXXX at mod_auth.c:942
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:945
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): set TZ environment variable to 'CET'
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): Preparing to chroot to directory '/srv/ftp/share'
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at auth.c:1912
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at auth.c:1915
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): Environment successfully chroot()ed
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth.c:1768
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): SETUP PRIVS at mod_auth.c:1790
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): unable to chdir to /home/theuser (No such file or directory), defaulting to chroot directory /srv/ftp/share
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): in dir_check_full(): path = '/', fullpath = '/srv/ftp/share/'
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_sql
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_lang
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_cap
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): mod_cap/1.1: uid = XXXX, euid = XXXX, gid = XXXX, egid = XXXX
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): mod_cap/1.1: capabilities 'cap_chown,cap_setgid,cap_setuid,cap_net_bind_service,cap_audit_write=ep'
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_redis
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_sql
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_tls
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_quotatab
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_sftp
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_digest
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_delay
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_log
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_ls
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_auth
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth.c:2175
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth.c:727
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS: ID switching disabled
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): REVOKE PRIVS at mod_auth.c:728
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RootRevoke in effect, dropped root privs
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_rlimit
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_xfer
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PASS (hidden)' to mod_core
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_redis
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_sql
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_digest
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_log
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_delay
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PASS (hidden)' to mod_auth
2022-12-17 02:58:18,226 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): USER theuser: Login successful.
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'SYST' to mod_tls
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'SYST' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'SYST' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'SYST' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'SYST' to mod_sql
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'SYST' to mod_redis
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'SYST' to mod_sql
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'SYST' to mod_digest
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'SYST' to mod_log
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'FEAT' to mod_tls
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'FEAT' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'FEAT' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'FEAT' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'FEAT' to mod_sql
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'FEAT' to mod_redis
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'FEAT' to mod_sql
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'FEAT' to mod_digest
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'FEAT' to mod_log
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'CLNT FileZilla' to mod_tls
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'CLNT FileZilla' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'CLNT FileZilla' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'CLNT FileZilla' to mod_core
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'CLNT FileZilla' to mod_sql
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'CLNT FileZilla' to mod_redis
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'CLNT FileZilla' to mod_sql
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'CLNT FileZilla' to mod_digest
2022-12-17 02:58:18,227 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'CLNT FileZilla' to mod_log
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS UTF8 ON' to mod_tls
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS UTF8 ON' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS UTF8 ON' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'OPTS UTF8 ON' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS_UTF8 ON' to mod_tls
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS_UTF8 ON' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'OPTS_UTF8 ON' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'OPTS_UTF8 ON' to mod_lang
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): mod_lang/1.1: Handling OPTS UTF8 ON (current encoding is 'UTF-8')
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'OPTS_UTF8 ON' to mod_sql
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS_UTF8 ON' to mod_redis
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS_UTF8 ON' to mod_sql
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS_UTF8 ON' to mod_digest
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS_UTF8 ON' to mod_log
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'OPTS UTF8 ON' to mod_sql
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS UTF8 ON' to mod_redis
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS UTF8 ON' to mod_sql
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS UTF8 ON' to mod_digest
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'OPTS UTF8 ON' to mod_log
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PBSZ 0' to mod_tls
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PBSZ 0' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PBSZ 0' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'PBSZ 0' to mod_tls
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PBSZ 0' to mod_sql
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PBSZ 0' to mod_redis
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PBSZ 0' to mod_sql
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PBSZ 0' to mod_digest
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PBSZ 0' to mod_log
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PROT P' to mod_tls
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PROT P' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PROT P' to mod_core
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'PROT P' to mod_tls
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PROT P' to mod_sql
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD command 'PROT P' to mod_xfer
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PROT P' to mod_redis
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PROT P' to mod_sql
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PROT P' to mod_digest
2022-12-17 02:58:18,228 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD command 'PROT P' to mod_log
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PWD' to mod_tls
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PWD' to mod_core
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching PRE_CMD command 'PWD' to mod_core
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching CMD command 'PWD' to mod_core
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): PWD command denied by <Limit> configuration
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching POST_CMD_ERR command 'PWD' to mod_sql
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD_ERR command 'PWD' to mod_redis
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD_ERR command 'PWD' to mod_sql
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD_ERR command 'PWD' to mod_digest
2022-12-17 02:58:18,229 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): dispatching LOG_CMD_ERR command 'PWD' to mod_log
2022-12-17 02:58:31,329 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS at mod_auth_pam.c:198
2022-12-17 02:58:31,329 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS: unable to seteuid(): Operation not permitted
2022-12-17 02:58:31,329 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): ROOT PRIVS: unable to setegid(): Operation not permitted
2022-12-17 02:58:31,337 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS at mod_auth_pam.c:229
2022-12-17 02:58:31,337 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): RELINQUISH PRIVS: unable to seteuid(PR_ROOT_UID): Operation not permitted
2022-12-17 02:58:31,338 theserver proftpd[233641] theserver (doma.in[IPv6:addr:ess]): FTP session closed.
2022-12-17 02:58:31,609 theserver proftpd[233639] theserver: scrubbing scoreboard
2022-12-17 02:58:31,609 theserver proftpd[233639] theserver: ROOT PRIVS at scoreboard.c:1411
2022-12-17 02:58:31,609 theserver proftpd[233639] theserver: RELINQUISH PRIVS at scoreboard.c:1414
2022-12-17 02:58:31,609 theserver proftpd[233639] theserver: ROOT PRIVS at scoreboard.c:1455
2022-12-17 02:58:31,609 theserver proftpd[233639] theserver: RELINQUISH PRIVS at scoreboard.c:1564
2022-12-17 02:58:31,610 theserver proftpd[233639] theserver: finished scrubbing scoreboard

@Castaglia
Copy link
Member

Hmm. There have been almost no changes to mod_facl, so I'm wondering what else might be involved. Could you provide the output of these commands, so that I can attempt to reproduce this locally?

$ ls -ald /srv/ftp/share
$ getfacl /srv/ftp/share

Thanks!

@eomanis
Copy link
Author

eomanis commented Jan 3, 2023

ls -ald /srv/ftp/share

[root@theserver ~]# ls -ald /srv/ftp/share
drwxrwx---+ 41 root root 4096 17. Nov 19:40 /srv/ftp/share

getfacl /srv/ftp/share

[root@theserver ~]# getfacl /srv/ftp/share
getfacl: Removing leading '/' from absolute path names
# file: srv/ftp/share
# owner: root
# group: root
user::rwx
user:root:rwx
group::---
group:ftp:r-x
group:data_rw:rwx
group:data_r:r-x
mask::rwx
other::---
default:user::rwx
default:user:root:rwx
default:group::---
default:group:data_rw:rwx
default:group:data_r:r-x
default:mask::rwx
default:other::---

Parent directories

The idea is that membership of group ftp paves the way to /srv/ftp/share, and whatever happens then depends on what other groups the user is a member of.

/srv/ftp has the same owner, group, permissions, and ACL as /srv/ftp/share, except that instead of group:ftp:r-x it has group:ftp:--x, i. e. members of group ftp may enter/traverse it, but they may not list its contents.

/srv has o=rx with traditional permissions, i. e. without ACL.

I guess I can test what happens if I give /srv/ftp group:ftp:r-x, there are no other files/dirs inside it besides share.

@eomanis
Copy link
Author

eomanis commented Jan 10, 2023

I guess I can test what happens if I give /srv/ftp group:ftp:r-x

For what it's worth, this also results in the "Permission denied" error for the client.

@Castaglia
Copy link
Member

Just confirm (for my local setup) -- do the textual user, group names retrieved align with the user/group names displayed by getfacl /srv/ftp/share? That is, the debug logging provided, we see:

retrieved UID XXXX for user 'theuser'
retrieved group IDs: XXXXXXXXXXXX
retrieved group names: XXXXXXXXXXXX

I understand wanting to keep the actual IDs, names obscured. I just need to know whether the "retrieved group names", logged by ProFTPD, include any of e.g.:

group:ftp:r-x
group:data_rw:rwx
group:data_r:r-x

If not, that might explain one possible cause of the behavior you see. I don't think that will be the case, but I need to double-check.

@Castaglia Castaglia added this to the 1.3.9 milestone Jan 29, 2023
@Castaglia
Copy link
Member

I'm able to reproduce this locally now; hopefully it won't be long before I have a fix.

@Castaglia
Copy link
Member

Castaglia commented Jan 29, 2023

In my local setup, I added some additional trace logging using:

TraceLog /path/to/proftpd/trace.log
Trace fsio:20 hiding:20

Using ProFTPD 1.3.7e, where the PWD command succeeds, the above logging shows:

2023-01-29 17:08:36,794 [7870] <fsio:8>: using facl lstat() for path '/home/docker/test.d'
2023-01-29 17:08:36,794 [7870] <fsio:8>: using system chroot() for path '/home/docker/test.d'
...
2023-01-29 17:08:37,509 [7870] <fsio:8>: using facl access() for path '/'
2023-01-29 17:08:37,509 [7870] <fsio:8>: using system stat() for path '/'
2023-01-29 17:08:37,509 [7870] <fsio:8>: using system stat() for path '/'
2023-01-29 17:08:37,509 [7870] <fsio:18>: added cached stat for path '/' (retval 0, errno Success)

(I am using /home/docker/test.d as my local equivalent of /srv/ftp/share, but the configuration is the same.) The key thing to notice about the above is this line:

2023-01-29 17:08:37,509 [7870] <fsio:8>: using facl access() for path '/'

That says that ProFTPD is using the "facl" access() FSIO API/function from the mod_facl.c module; this is what we expect.

When using ProFTPD 1.3.8, I see something different:

2023-01-29 17:08:36,794 [7870] <fsio:8>: using facl lstat() for path '/home/docker/test.d'
2023-01-29 17:08:36,794 [7870] <fsio:8>: using system chroot() for path '/home/docker/test.d'
...
2023-01-29 17:12:23,952 [7928] <fsio:8>: using system access() for path '/'
2023-01-29 17:12:23,952 [7928] <fsio:8>: using system stat() for path '/'
2023-01-29 17:12:23,952 [7928] <fsio:8>: using system stat() for path '/'
2023-01-29 17:12:23,952 [7928] <hiding:8>: hiding directory '/' because of HideNoAccess (errno = Permission denied)

Why is ProFTPD using the default "system" access() FSIO API for that directory, rather than the "facl" API? That, I think, is the heart of the issue. Now to see why this might be happening.

@Castaglia
Copy link
Member

I have narrowed this down to changes between ProFTPD 1.3.8rc1 (which does not exhibit this behavior), and 1.3.8rc2. Now to narrow things down further.

@Castaglia
Copy link
Member

Castaglia commented Jan 29, 2023

Ah-hah! I tracked down the offending commit: 7a79e76

And the PR that that commit references: https://github.com/proftpd/proftpd/pull/1203/files

At first glance, these commits do not seem like they're related to POSIX ACL capabilities, filesystem APIs, or the mod_facl module. So how do these changes lead to the buggy behavior here?

The commits in question are related to the flags used when linking the ProFTPD executable. They are also used by the Autoconf-generated configure script, which is used to probe/detect all kinds of things (such as platform-dependent header files, libraries, etc). The results of these configure probes/tests are recorded in the generated config.h file, which the ProFTPD build system then uses during its compilation and linking processes.

For example, for the working build, here's what we see in the generated config.h for ACL-related macros:

# ./configure --enable-facl --with-modules=mod_facl
...
checking sys/acl.h usability... yes
checking sys/acl.h presence... yes
checking for sys/acl.h... yes
checking acl/libacl.h usability... yes
checking acl/libacl.h presence... yes
checking for acl/libacl.h... yes
checking for perm_copy_fd in -lacl... yes
checking which POSIX ACL implementation to use... Linux
...

# grep ACL config.h 
/* Define if you have BSD POSIX ACLs. */
/* #undef HAVE_BSD_POSIX_ACL */
/* Define if you have Linux POSIX ACLs. */
#define HAVE_LINUX_POSIX_ACL 1
/* Define if you have MacOSX POSIX ACLs. */
/* #undef HAVE_MACOSX_POSIX_ACL */
/* Define if you have Solaris POSIX ACLs. */
/* #undef HAVE_SOLARIS_POSIX_ACL */
/* Define if your system has POSIX ACL support */
#define HAVE_POSIX_ACL 1
#define HAVE_ACL_LIBACL_H 1
#define HAVE_SYS_ACL_H 1
#define HAVE_LIBACL 1
/* Define if use of POSIX ACL support is desired.  */
#define PR_USE_FACL 1

We see that HAVE_LINUX_POSIX_ACL and HAVE_POSIX_ACL are detected, enabled (by setting their value to 1).

And for the buggy, broken build?

# ./configure --enable-facl --with-modules=mod_facl
...
checking sys/acl.h usability... yes
checking sys/acl.h presence... yes
checking for sys/acl.h... yes
checking acl/libacl.h usability... yes
checking acl/libacl.h presence... yes
checking for acl/libacl.h... yes
checking for perm_copy_fd in -lacl... yes
checking which POSIX ACL implementation to use... none
...

# grep ACL config.h
/* Define if you have BSD POSIX ACLs. */
/* #undef HAVE_BSD_POSIX_ACL */
/* Define if you have Linux POSIX ACLs. */
/* #undef HAVE_LINUX_POSIX_ACL */
/* Define if you have MacOSX POSIX ACLs. */
/* #undef HAVE_MACOSX_POSIX_ACL */
/* Define if you have Solaris POSIX ACLs. */
/* #undef HAVE_SOLARIS_POSIX_ACL */
/* Define if your system has POSIX ACL support */
/* #undef HAVE_POSIX_ACL */
#define HAVE_ACL_LIBACL_H 1
#define HAVE_SYS_ACL_H 1
#define HAVE_LIBACL 1
/* Define if use of POSIX ACL support is desired.  */
#define PR_USE_FACL 1

Here, on the generated config.log, we see the compiler/linker error which is at the heart of the issue:

configure:22110: gcc -o conftest -g2 -O2 -Wall -fno-omit-frame-pointer -fno-strict-aliasing  -Wl,,-L$(top_builddir)/lib  -rdynamic conftest.c -lacl -lcrypt -ldl  >&5
conftest.c: In function 'main':
conftest.c:231:9: warning: 'permset' is used uninitialized in this function [-Wuninitialized]
         (void)acl_get_perm(permset, perm);
         ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
conftest.c:231:9: warning: 'perm' is used uninitialized in this function [-Wuninitialized]
/usr/bin/ld: cannot find : No such file or directory
collect2: error: ld returned 1 exit status

This /usr/bin/ld: cannot find : No such file or directory error is a linker error; it doen't say what it cannot find, but we can deduce that it is referring to the "double comma" issue in the -Wl,,-L$(top_builddir)/lib flag.

In addition, we can see the change in the proftpd -V output as well. Here's the working build output:

# ./proftpd -V
Compile-time Settings:
  Version: 1.3.8rc2 (git)
  Platform: LINUX [Linux 4.9.49-moby x86_64]
  Built: Sun Jan 29 2023 19:46:21 UTC
  Built With:
    configure  '--enable-facl' '--with-modules=mod_facl' '--enable-devel=stacktrace'

  CFLAGS: ...
  LDFLAGS: -L$(top_srcdir)/lib -L$(top_builddir)/lib  -rdynamic
  LIBS: -lacl  -lsupp -lattr -lnsl -lresolv -lresolv -lcrypt -ldl

and for the buggy build:

# ./proftpd -V
Compile-time Settings:
  Version: 1.3.8rc2 (git)
  Platform: LINUX [Linux 4.9.49-moby x86_64]
  Built: Sun Jan 29 2023 19:49:33 UTC
  Built With:
    configure  '--enable-facl' '--with-modules=mod_facl' '--enable-devel=stacktrace'

  CFLAGS: ...
  LDFLAGS: -Wl,-L$(top_srcdir)/lib,-L$(top_builddir)/lib  -rdynamic
  LIBS:  -lsupp -lattr -lnsl -lresolv -lresolv -lcrypt -ldl

Notice that the ACL library linker flag (-lacl) is missing for the broken build.

And because these configure tests are now incorrect, the mod_facl module functionality is not properly enabled, which means that the POSIX ACL permissions, which do allow access, are not being properly detected.

Given the nature of the cause, it's possible that more than just the POSIX ACL tests, in the configure script, are now broken; that's what I'm checking right now.

@Castaglia Castaglia changed the title 550 PWD: Permission denied for initial directory listing since 1.3.8 550 PWD: Permission denied for initial directory listing since 1.3.8rc2 Jan 29, 2023
@eomanis
Copy link
Author

eomanis commented Jan 29, 2023

Now that was a wild ride. Linker problems, who would have thought.
You spot a bug and when you go dig it out you end up on the other side of the world and find a whole graveyard.

I assume your question about the user/group names in the log are now moot, anyhow I kept the originals and it was the correct user logins / group names without the getfacl syntax.

At any rate thank you for all the amazing work you put into this application.

Castaglia added a commit that referenced this issue Jan 31, 2023
…g the manipulation of the `LDFLAGS` environment variable for use in these tests.
@Castaglia Castaglia changed the title 550 PWD: Permission denied for initial directory listing since 1.3.8rc2 Build-time detection of Linux POSIX ACL support broken since 1.3.8rc2 Jan 31, 2023
Castaglia added a commit that referenced this issue Jan 31, 2023
…g the manipulation of the `LDFLAGS` environment variable for use in these tests.
Castaglia added a commit that referenced this issue Jan 31, 2023
…g the manipulation of the `LDFLAGS` environment variable for use in these tests.
Castaglia added a commit that referenced this issue Jan 31, 2023
Issue #1568: Fix the Autoconf tests for Linux POSIX ACLs by correctin…
@Castaglia
Copy link
Member

This has been fixed in the master branch, and backported to the 1.3.8 branch now. Thanks!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

2 participants