psa-proftpd art hangs on uploads at 100%

[premierhosting]

Hello,

I've been struggling with an issue with psa-proftpd art version where uploads, small and large will upload to 100% and then "fail". This has been seen with FileZilla clients on Windows, with some Mac FTP, and most recently with RapidWeaver. I've even just used unix FTP from another client to see the same behavior.

I found the same behavior online on a Solaris specific version. No idea how to apply the lessons to this, as the "solution" was to nuke the OS specific version and compile source. I'm thinking that would mean nuking the psa-proftpd(art) version in my case, but first I'd like to see if there are any fixes known here!
https://forums.proftpd.org/smf/index.php?topic=11070.0

Restarting xinetd doesn't seem to help, however rebooting the server entirely can get things working again for a while, but eventually it happens again.

Plenty of disk space, here's my art kernel version too. Reason for failure is "Timeout"

Any ideas?

Code: Select all

[root@ssl1 log]# df -h
Filesystem            Size  Used Avail Use% Mounted on
/dev/sda1             4.0G  1.2G  2.9G  29% /
/dev/mapper/vg00-usr  4.0G  1.8G  2.1G  46% /usr
/dev/mapper/vg00-var  542G   65G  450G  13% /var
/dev/mapper/vg00-home
                      4.0G  137M  3.7G   4% /home
none                  1.9G   62M  1.9G   4% /tmp
[root@ssl1 log]# uname -a
Linux ssl1.safessl.net 2.6.32.60-36.art.x86_64 #1 SMP Thu Dec 6 13:27:27 EST 2012 x86_64 x86_64 x86_64 GNU/Linux

Code: Select all

[root@ssl1 log]# proftpd -V
Compile-time Settings:
  Version: 1.3.4c (maint)
  Platform: LINUX [Linux 2.6.32.60-36.art.x86_64 x86_64]
  Built: Thu Mar 28 2013 11:10:20 EDT
  Built With:
    configure  '--build=x86_64-redhat-linux-gnu' '--host=x86_64-redhat-linux-gnu' '--target=x86_64-redhat-linux-gnu' '--program-prefix=' '--prefix=/usr' '--exec-prefix=/usr' '--bindir=/usr/bin' '--sbindir=/usr/sbin' '--sysconfdir=/etc' '--datadir=/usr/share' '--includedir=/usr/include' '--libdir=/usr/lib64' '--libexecdir=/usr/libexec' '--localstatedir=/var' '--sharedstatedir=/var/lib' '--mandir=/usr/share/man' '--infodir=/usr/share/info' '--libexecdir=/usr/libexec/proftpd' '--localstatedir=/var/run/proftpd' '--enable-ctrls' '--enable-dso' '--enable-facl' '--enable-ipv6' '--enable-openssl' '--with-includes=/usr/include/mysql' '--with-libraries=/usr/lib64/mysql' '--with-modules=mod_clamav:mod_quotatab:mod_quotatab_file:mod_tls:mod_readme:mod_auth_pam:mod_dnsbl:mod_ifsession' '--with-shared=mod_ldap:mod_sql:mod_sql_mysql:mod_sql_postgres:mod_quotatab_ldap:mod_quotatab_sql:mod_sftp' 'build_alias=x86_64-redhat-linux-gnu' 'host_alias=x86_64-redhat-linux-gnu' 'target_alias=x86_64-redhat-linux-gnu' 'CFLAGS=-O2 -g -pipe -Wall -Wp,-D_FORTIFY_SOURCE=2 -fexceptions -fstack-protector --param=ssp-buffer-size=4 -m64 -mtune=generic  ' 'LDFLAGS=  '

  CFLAGS: -O2 -g -pipe -Wall -Wp,-D_FORTIFY_SOURCE=2 -fexceptions -fstack-protector --param=ssp-buffer-size=4 -m64 -mtune=generic   -Wall
  LDFLAGS: -L$(top_srcdir)/lib    -L/usr/lib64/mysql -L/usr/lib64/mysql -L/usr/lib64
  LIBS: -lacl  -lssl -lcrypto -lssl -lcrypto -L$(top_srcdir)/lib/libcap -lcap  /usr/lib64/libresolv.a  -lpam  -lssl -lcrypto -lsupp -lcrypt -ldl  -ldl -lz

  Files:
    Configuration File:
      /etc/proftpd.conf
    Pid File:
      /var/run/proftpd/proftpd.pid
    Scoreboard File:
      /var/run/proftpd/proftpd.scoreboard
    Header Directory:
      /usr/include/proftpd
    Shared Module Directory:
      /usr/libexec/proftpd

  Features:
    - Autoshadow support
    + Controls support
    + curses support
    - Developer support
    + DSO support
    + IPv6 support
    + Largefile support
    - Lastlog support
    - Memcache support
    + ncurses support
    - NLS support
    + OpenSSL support (FIPS enabled)
    - PCRE support
    + POSIX ACL support
    + Shadow file support
    + Sendfile support
    + Trace support

  Tunable Options:
    PR_TUNABLE_BUFFER_SIZE = 1024
    PR_TUNABLE_DEFAULT_RCVBUFSZ = 8192
    PR_TUNABLE_DEFAULT_SNDBUFSZ = 8192
    PR_TUNABLE_GLOBBING_MAX_MATCHES = 100000
    PR_TUNABLE_GLOBBING_MAX_RECURSION = 8
    PR_TUNABLE_HASH_TABLE_SIZE = 40
    PR_TUNABLE_NEW_POOL_SIZE = 512
    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 = 30
    PR_TUNABLE_TIMEOUTLOGIN = 300
    PR_TUNABLE_TIMEOUTNOXFER = 300
    PR_TUNABLE_TIMEOUTSTALLED = 3600
    PR_TUNABLE_XFER_SCOREBOARD_UPDATES = 10

[premierhosting]

I may have fixed it by entirely removing then reinstalling psa-proftpd from ART. yum reinstall did not fix, but removal and reinstall *so far* has been good.

[premierhosting]

Nope, no dice, still breaks. Here's the only way I have found to make it work when it goes out.

Code: Select all

rpm -e --nodeps psa-proftpd; rpm -e --nodeps psa-proftpd-xinetd;yum -y install psa-proftpd

Kicks this error on install:

Code: Select all

** Found 1 pre-existing rpmdb problem(s), 'yum check' output follows:
plesk-core-11.0.9-cos6.build110120608.16.x86_64 has missing requires of psa-proftpd >= ('0', '1.3.3', None)

[premierhosting]

Seems to especially be a problem on large file transfers.

[prupert]

On FTP failures:
- What does the FTP client console log tell you?
- What does the xferlog tell you?
- What does /var/log/messages tell you? Any firewall events?
- What does the ASL event list tell you?

[premierhosting]

Soon as it happens again I'll post the answers. Thanks for helping.

[premierhosting]

FTP Client console says Reason: Timeout

Today both FileZilla on a Windoze PC and a website authoring software on a Mac had the hang up. Hence server side.

xferlog

These were hung (identifying data removed):

Code: Select all

Mon Jun 17 11:03:52 2013 600 000.000.000.000 47287 /var/www/vhosts/domainname.org/httpdocs/page1/file.html b _ i r domainname ftp 0 * c
Mon Jun 17 12:47:46 2013 600 000.000.000.000 47287 /var/www/vhosts/domainname.org/httpdocs/page1/file.html b _ i r domainname ftp 0 * c
Mon Jun 17 17:05:54 2013 0 000.000.000.000 14370 /var/www/vhosts/domainname.com/httpdocs/file.php b _ d r domainname ftp 0 * c
Mon Jun 17 17:16:55 2013 0 000.000.000.000 14372 /var/www/vhosts/domainname.com/httpdocs/file.php b _ i r domainname ftp 0 * c

I reinstalled and this was not hung:

Code: Select all

Mon Jun 17 17:16:59 2013 0 000.000.000.000 14372 /var/www/vhosts/domainname.com/httpdocs/file.php b _ d r domainname ftp 0 * c

From /var/log/messages here are the first logins:

Code: Select all

Jun 17 17:02:58 ssl1 xinetd[1523]: START: ftp pid=24337 from=::ffff:client.ip.address
Jun 17 17:02:58 ssl1 proftpd[24337]: server.ip.address (client.ip.address[client.ip.address]) - FTP session opened.
Jun 17 17:02:58 ssl1 proftpd[24337]: server.ip.address (client.ip.address[client.ip.address]) - Preparing to chroot to directory '/var/www/vhosts/domainname.com'
Jun 17 17:03:20 ssl1 xinetd[1523]: START: ftp pid=24346 from=::ffff:client.ip.address
Jun 17 17:03:20 ssl1 proftpd[24346]: server.ip.address (client.ip.address[client.ip.address]) - FTP session opened.
Jun 17 17:03:20 ssl1 proftpd[24346]: server.ip.address (client.ip.address[client.ip.address]) - Preparing to chroot to directory '/var/www/vhosts/domainname.com'
Jun 17 17:03:42 ssl1 xinetd[1523]: START: ftp pid=24362 from=::ffff:client.ip.address
Jun 17 17:03:42 ssl1 proftpd[24362]: server.ip.address (client.ip.address[client.ip.address]) - FTP session opened.
Jun 17 17:03:42 ssl1 proftpd[24362]: server.ip.address (client.ip.address[client.ip.address]) - Preparing to chroot to directory '/var/www/vhosts/domainname.com'

Reinstalled psa-proftpd and these are the logs after:

Code: Select all

Jun 17 17:16:36 ssl1 yum[25257]: Installed: psa-proftpd-1.3.4c-4.el6.art.x86_64
Jun 17 17:16:36 ssl1 yum[25257]: Installed: psa-proftpd-xinetd-1.3.4c-4.el6.art.x86_64
Jun 17 17:16:46 ssl1 proftpd[24780]: server.ip.address (client.ip.address[client.ip.address]) - FTP session closed.
Jun 17 17:16:46 ssl1 xinetd[24762]: EXIT: ftp status=0 pid=24780 duration=389(sec)
Jun 17 17:16:51 ssl1 xinetd[24762]: START: ftp pid=25290 from=::ffff:client.ip.address
Jun 17 17:16:51 ssl1 proftpd[25290]: server.ip.address (client.ip.address[client.ip.address]) - FTP session opened.
Jun 17 17:16:51 ssl1 proftpd[25290]: server.ip.address (client.ip.address[client.ip.address]) - Preparing to chroot to directory '/var/www/vhosts/domainname.com'
Jun 17 17:16:54 ssl1 xinetd[24762]: START: ftp pid=25291 from=::ffff:client.ip.address
Jun 17 17:16:54 ssl1 proftpd[25291]: server.ip.address (client.ip.address[client.ip.address]) - FTP session opened.
Jun 17 17:16:54 ssl1 proftpd[25291]: server.ip.address (client.ip.address[client.ip.address]) - Preparing to chroot to directory '/var/www/vhosts/domainname.com'
Jun 17 17:17:01 ssl1 proftpd[25290]: server.ip.address (client.ip.address[client.ip.address]) - FTP session closed.
Jun 17 17:17:01 ssl1 xinetd[24762]: EXIT: ftp status=0 pid=25290 duration=9(sec)

ASL event list has nothing regarding this.