Ubuntu
proftpd package

proftpd-mysql-1.2.10-27ubuntu3 has stopped authenticating with mysql

Bug #59359 reported by yacek87
4
Affects Status Importance Assigned to Milestone
Dapper Backports
Fix Released
Undecided
Unassigned
proftpd (Ubuntu)
Fix Released
Undecided
Unassigned

Bug Description

Binary package hint: proftpd-mysql

Hi!
I have configured mysql and proftpd-mysql-1.2.10-22 to work with the mysql. Everything was working like a charm. Problems appeared after upgrading to proftpd-mysql-1.2.10.27, ftp server has stopped authenticating. It starts properly and allows me to connect to it, but it always says "503 Login incorrect".

MySql version: >=4.1 (including 5 and upper).

Log from sql-mod (invalid session):
**********************************
Sep 07 16:58:03 mod_sql/4.11[5779]: backend module 'mod_sql_mysql/4.04'
Sep 07 16:58:03 mod_sql/4.11[5779]: backend api 'mod_sql_api_v1'
Sep 07 16:58:03 mod_sql/4.11[5779]: >>> sql_getconf
Sep 07 16:58:03 mod_sql/4.11[5779]: entering mysql cmd_defineconnection
Sep 07 16:58:03 mod_sql/4.11[5779]: name: 'default'
Sep 07 16:58:03 mod_sql/4.11[5779]: user: 'proftpd'
Sep 07 16:58:03 mod_sql/4.11[5779]: host: 'localhost'
Sep 07 16:58:03 mod_sql/4.11[5779]: db: 'proftpd'
Sep 07 16:58:03 mod_sql/4.11[5779]: port: '3306'
Sep 07 16:58:03 mod_sql/4.11[5779]: ttl: '0'
Sep 07 16:58:03 mod_sql/4.11[5779]: exiting mysql cmd_defineconnection
Sep 07 16:58:03 mod_sql/4.11[5779]: entering mysql cmd_open
Sep 07 16:58:03 mod_sql/4.11[5779]: connection 'default' opened
Sep 07 16:58:03 mod_sql/4.11[5779]: connection 'default' count is now 1
Sep 07 16:58:03 mod_sql/4.11[5779]: exiting mysql cmd_open
Sep 07 16:58:03 mod_sql/4.11[5779]: backend successfully connected.
Sep 07 16:58:03 mod_sql/4.11[5779]: mod_sql status : on
Sep 07 16:58:03 mod_sql/4.11[5779]: negative_cache : off
Sep 07 16:58:03 mod_sql/4.11[5779]: authenticate : users groups
Sep 07 16:58:03 mod_sql/4.11[5779]: usertable : users
Sep 07 16:58:03 mod_sql/4.11[5779]: userid field : userid
Sep 07 16:58:03 mod_sql/4.11[5779]: password field : passwd
Sep 07 16:58:03 mod_sql/4.11[5779]: uid field : uid
Sep 07 16:58:03 mod_sql/4.11[5779]: gid field : gid
Sep 07 16:58:03 mod_sql/4.11[5779]: homedir field : homedir
Sep 07 16:58:03 mod_sql/4.11[5779]: shell field : shell
Sep 07 16:58:03 mod_sql/4.11[5779]: homedirondemand : false
Sep 07 16:58:03 mod_sql/4.11[5779]: group table : groups
Sep 07 16:58:03 mod_sql/4.11[5779]: groupname field : groupid
Sep 07 16:58:03 mod_sql/4.11[5779]: grp gid field : gid
Sep 07 16:58:03 mod_sql/4.11[5779]: grp members field : members
Sep 07 16:58:03 mod_sql/4.11[5779]: SQLMinUserUID : 999
Sep 07 16:58:03 mod_sql/4.11[5779]: SQLMinUserGID : 999
Sep 07 16:58:03 mod_sql/4.11[5779]: SQLDefaultUID : 65533
Sep 07 16:58:03 mod_sql/4.11[5779]: SQLDefaultGID : 65533
Sep 07 16:58:03 mod_sql/4.11[5779]: <<< sql_getconf
Sep 07 16:58:05 mod_sql/4.11[5779]: >>> cmd_getgroups
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_escapestring
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_escapestring
Sep 07 16:58:05 mod_sql/4.11[5779]: cache miss for user 'yacek'
Sep 07 16:58:05 mod_sql/4.11[5779]: : entering mysql cmd_select
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_open
Sep 07 16:58:05 mod_sql/4.11[5779]: connection 'default' count is now 2
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_open
Sep 07 16:58:05 mod_sql/4.11[5779]: query "SELECT userid, passwd, uid, gid, homedir, shell FROM users WHERE (userid='yacek') LIMIT 1"
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_close
Sep 07 16:58:05 mod_sql/4.11[5779]: connection 'default' count is now 1
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_close
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_select
Sep 07 16:58:05 mod_sql/4.11[5779]: cache miss for user 'yacek'
Sep 07 16:58:05 mod_sql/4.11[5779]: user 'yacek' cached
Sep 07 16:58:05 mod_sql/4.11[5779]: + pwd.pw_name : yacek
Sep 07 16:58:05 mod_sql/4.11[5779]: + pwd.pw_uid : 1006
Sep 07 16:58:05 mod_sql/4.11[5779]: + pwd.pw_gid : 10000
Sep 07 16:58:05 mod_sql/4.11[5779]: + pwd.pw_dir : /home/ftp/yacek
Sep 07 16:58:05 mod_sql/4.11[5779]: + pwd.pw_shell : /bin/bash
Sep 07 16:58:05 mod_sql/4.11[5779]: cache miss for gid '10000'
Sep 07 16:58:05 mod_sql/4.11[5779]: : entering mysql cmd_select
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_open
Sep 07 16:58:05 mod_sql/4.11[5779]: connection 'default' count is now 2
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_open
Sep 07 16:58:05 mod_sql/4.11[5779]: query "SELECT groupid FROM groups WHERE (gid = 10000) LIMIT 1"
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_close
Sep 07 16:58:05 mod_sql/4.11[5779]: connection 'default' count is now 1
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_close
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_select
Sep 07 16:58:05 mod_sql/4.11[5779]: : entering mysql cmd_select
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_open
Sep 07 16:58:05 mod_sql/4.11[5779]: connection 'default' count is now 2
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_open
Sep 07 16:58:05 mod_sql/4.11[5779]: query "SELECT groupid, gid, members FROM groups WHERE (groupid = 'vgroup')"
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_close
Sep 07 16:58:05 mod_sql/4.11[5779]: connection 'default' count is now 1
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_close
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_select
Sep 07 16:58:05 mod_sql/4.11[5779]: cache miss for group 'vgroup'
Sep 07 16:58:05 mod_sql/4.11[5779]: group 'vgroup' cached
Sep 07 16:58:05 mod_sql/4.11[5779]: + grp.gr_name : vgroup
Sep 07 16:58:05 mod_sql/4.11[5779]: + grp.gr_gid : 10000
Sep 07 16:58:05 mod_sql/4.11[5779]: + grp.gr_mem :
Sep 07 16:58:05 mod_sql/4.11[5779]: : entering mysql cmd_select
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_open
Sep 07 16:58:05 mod_sql/4.11[5779]: connection 'default' count is now 2
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_open
Sep 07 16:58:05 mod_sql/4.11[5779]: query "SELECT groupid, gid, members FROM groups WHERE (members = 'yacek' OR members LIKE 'yacek,%' OR members LIKE '%,yacek' OR members LIKE '%,yacek,%')"
Sep 07 16:58:05 mod_sql/4.11[5779]: entering mysql cmd_close
Sep 07 16:58:05 mod_sql/4.11[5779]: connection 'default' count is now 1
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_close
Sep 07 16:58:05 mod_sql/4.11[5779]: exiting mysql cmd_select
Sep 07 16:58:05 mod_sql/4.11[5779]: <<< cmd_getgroups
Sep 07 16:58:07 mod_sql/4.11[5779]: >>> cmd_getgroups
Sep 07 16:58:07 mod_sql/4.11[5779]: cache hit for user 'yacek'
Sep 07 16:58:07 mod_sql/4.11[5779]: cache hit for group vgroup
Sep 07 16:58:07 mod_sql/4.11[5779]: : entering mysql cmd_select
Sep 07 16:58:07 mod_sql/4.11[5779]: entering mysql cmd_open
Sep 07 16:58:07 mod_sql/4.11[5779]: connection 'default' count is now 2
Sep 07 16:58:07 mod_sql/4.11[5779]: exiting mysql cmd_open
Sep 07 16:58:07 mod_sql/4.11[5779]: query "SELECT groupid, gid, members FROM groups WHERE (members = 'yacek' OR members LIKE 'yacek,%' OR members LIKE '%,yacek' OR members LIKE '%,yacek,%')"
Sep 07 16:58:07 mod_sql/4.11[5779]: entering mysql cmd_close
Sep 07 16:58:07 mod_sql/4.11[5779]: connection 'default' count is now 1
Sep 07 16:58:07 mod_sql/4.11[5779]: exiting mysql cmd_close
Sep 07 16:58:07 mod_sql/4.11[5779]: exiting mysql cmd_select
Sep 07 16:58:07 mod_sql/4.11[5779]: <<< cmd_getgroups
Sep 07 16:58:07 mod_sql/4.11[5779]: >>> cmd_getpwnam
Sep 07 16:58:07 mod_sql/4.11[5779]: cache hit for user 'yacek'
Sep 07 16:58:07 mod_sql/4.11[5779]: <<< cmd_getpwnam
Sep 07 16:58:07 mod_sql/4.11[5779]: >>> cmd_gid2name
Sep 07 16:58:07 mod_sql/4.11[5779]: cache hit for group vgroup
Sep 07 16:58:07 mod_sql/4.11[5779]: <<< cmd_gid2name
Sep 07 16:58:07 mod_sql/4.11[5779]: >>> cmd_auth
Sep 07 16:58:07 mod_sql/4.11[5779]: entering mysql cmd_escapestring
Sep 07 16:58:07 mod_sql/4.11[5779]: exiting mysql cmd_escapestring
Sep 07 16:58:07 mod_sql/4.11[5779]: cache hit for user 'yacek'
Sep 07 16:58:07 mod_sql/4.11[5779]: >>> cmd_check
Sep 07 16:58:07 mod_sql/4.11[5779]: checking auth_type Crypt
Sep 07 16:58:07 mod_sql/4.11[5779]: checking auth_type Backend
Sep 07 16:58:07 mod_sql/4.11[5779]: entering mysql cmd_checkauth
Sep 07 16:58:07 mod_sql/4.11[5779]: exiting mysql cmd_checkauth
Sep 07 16:58:07 mod_sql/4.11[5779]: <<< cmd_check
Sep 07 16:58:07 mod_sql/4.11[5779]: <<< cmd_auth
Sep 07 16:58:12 mod_sql/4.11[5779]: entering mysql cmd_exit
Sep 07 16:58:12 mod_sql/4.11[5779]: entering mysql cmd_close
Sep 07 16:58:12 mod_sql/4.11[5779]: connection 'default' closed
Sep 07 16:58:12 mod_sql/4.11[5779]: connection 'default' count is now 0
Sep 07 16:58:12 mod_sql/4.11[5779]: exiting mysql cmd_close
Sep 07 16:58:12 mod_sql/4.11[5779]: exiting mysql cmd_exit
***************************

Log from proftpd (invalid session):
***************************
Sep 07 16:58:03 ubuntu proftpd[5779] localhost (localhost[127.0.0.1]): FTP session opened.
Sep 07 16:58:10 ubuntu proftpd[5779] localhost (localhost[127.0.0.1]): PAM(yacek): User not known to the underlying authentication module.
Sep 07 16:58:10 ubuntu proftpd[5779] localhost (localhost[127.0.0.1]): USER yacek (Login failed): No such user found.
Sep 07 16:58:12 ubuntu proftpd[5779] localhost (localhost[127.0.0.1]): FTP session closed.
****************************

Log from proftpd output (proftpd -n -d10):
****************************
 - dispatching event 'core.module-load' to mod_sql_mysql
 - dispatching event 'core.module-load' to mod_sql_mysql
 - dispatching event 'core.preparse' to mod_sql
 - parsing '/usr/local/etc/proftpd.conf' configuration
 - FS: using system open()
 - FS: using system read()
 - dispatching directive 'ServerName' to module mod_core
 - dispatching directive 'ServerType' to module mod_core
 - dispatching directive 'DefaultServer' to module mod_core
 - dispatching directive 'Port' to module mod_core
 - dispatching directive 'Umask' to module mod_core
 - dispatching directive 'MaxInstances' to module mod_core
 - dispatching directive 'User' to module mod_core
 - dispatching auth request "getpwnam" to module mod_sql
 - dispatching auth request "getpwnam" to module mod_auth_file
 - dispatching auth request "getpwnam" to module mod_auth_unix
 - retrieved UID 65534 for user 'nobody'
 - dispatching directive 'Group' to module mod_core
 - dispatching auth request "getgrnam" to module mod_sql
 - dispatching auth request "getgrnam" to module mod_auth_file
 - dispatching auth request "getgrnam" to module mod_auth_unix
 - retrieved GID 65534 for group 'nogroup'
 - FS: using system read()
 - dispatching directive 'AllowOverwrite' to module mod_xfer
 - dispatching directive '<Limit>' to module mod_core
 - dispatching directive 'DenyAll' to module mod_core
 - dispatching directive '</Limit>' to module mod_core
 - dispatching directive '<Anonymous>' to module mod_core
 - dispatching directive 'User' to module mod_core
 - dispatching directive 'Group' to module mod_core
 - dispatching directive 'UserAlias' to module mod_auth
 - dispatching directive 'MaxClients' to module mod_auth
 - dispatching directive 'DisplayLogin' to module mod_core
 - dispatching directive 'DisplayFirstChdir' to module mod_core
 - dispatching directive '<Limit>' to module mod_core
 - dispatching directive 'DenyAll' to module mod_core
 - dispatching directive '</Limit>' to module mod_core
 - dispatching directive '</Anonymous>' to module mod_core
 - FS: using system read()
 - FS: using system close()
 - attempting to resolve 'localhost' to IPv4 address via DNS
 - resolved 'localhost' to IPv4 address 127.0.0.1
localhost -
localhost - Config for ProFTPD Default Installation:
localhost - ~ftp/
localhost - Limit
localhost - DenyAll
localhost - UserName
localhost - GroupName
localhost - UserAlias
localhost - MaxClients
localhost - DisplayLogin
localhost - DisplayFirstChdir
localhost - Umask
localhost - AllowOverwrite
localhost - Limit
localhost - DenyAll
localhost - DefaultServer
localhost - Umask
localhost - UserID
localhost - UserName
localhost - GroupID
localhost - GroupName
localhost - AllowOverwrite
localhost - dispatching event 'core.postparse' to mod_delay
localhost - ROOT PRIVS at mod_delay.c:292
localhost - FS: using system open()
localhost - RELINQUISH PRIVS at mod_delay.c:294
localhost - FS: using system fstat()
localhost - mod_delay/0.5: mapping DelayTable '/usr/local/var/proftpd/proftpd.delay' into memory
localhost - mod_delay/0.5: unmapping DelayTable '/usr/local/var/proftpd/proftpd.delay' from memory
localhost - FS: using system close()
localhost - dispatching auth request "getgroups" to module mod_sql
localhost - dispatching auth request "getgroups" to module mod_auth_file
localhost - dispatching auth request "getgroups" to module mod_auth_unix
localhost - retrieved group ID: 65534
localhost - setting group ID: 65534
localhost - SETUP PRIVS at main.c:2897
localhost - ROOT PRIVS at main.c:1991
localhost - RELINQUISH PRIVS at main.c:1998
localhost - ROOT PRIVS at main.c:2346
localhost - opening scoreboard '/usr/local/var/proftpd/proftpd.scoreboard'
localhost - RELINQUISH PRIVS at main.c:2372
localhost - dispatching event 'core.startup' to mod_core
localhost - ROOT PRIVS at inet.c:323
localhost - RELINQUISH PRIVS at inet.c:381
localhost - ProFTPD 1.3.0 (stable) (built śro wrz 6 21:14:09 CEST 2006) standalone mode STARTUP
localhost - ROOT PRIVS at main.c:2209
localhost - RELINQUISH PRIVS at main.c:2211
localhost - FS: using system lstat()
localhost - ROOT PRIVS at main.c:1176
localhost - RELINQUISH PRIVS at main.c:1180
localhost - no matching vhost found for 83.16.177.26#21, using DefaultServer 'ProFTPD Default Installation'
localhost - FS: using system lstat()
localhost - FS: using system lstat()
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - ROOT PRIVS at main.c:1025
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - SETUP PRIVS at main.c:1030
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - FTP session requested from unknown class
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - performing module session initializations
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: opening DelayTable '/usr/local/var/proftpd/proftpd.delay'
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - ROOT PRIVS at mod_delay.c:948
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - FS: using system open()
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - RELINQUISH PRIVS at mod_delay.c:950
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - ROOT PRIVS at mod_auth.c:145
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - opening scoreboard '/usr/local/var/proftpd/proftpd.scoreboard'
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - RELINQUISH PRIVS at mod_auth.c:147
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - performing ident lookup
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - ident connection failed: Interrupted system call
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - ident lookup returned 'UNKNOWN'
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - connected - local : 83.16.177.26:21
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - connected - remote : xxx.xx.xx.xx:57347
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - FTP session opened.
localhost - scrubbing scoreboard
localhost - ROOT PRIVS at mod_core.c:131
localhost - RELINQUISH PRIVS at mod_core.c:133
localhost - ROOT PRIVS at mod_core.c:161
localhost - RELINQUISH PRIVS at mod_core.c:192
localhost - FS: using system lstat()
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'USER yacek' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'USER yacek' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'USER yacek' to mod_delay
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'USER yacek' to mod_auth
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching CMD command 'USER yacek' to mod_auth
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getgroups" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getgroups" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getgroups" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - no supplemental groups found for user 'yacek'
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching POST_CMD command 'USER yacek' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching POST_CMD command 'USER yacek' to mod_delay
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: mapping DelayTable '/usr/local/var/proftpd/proftpd.delay' into memory
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: write-locking DelayTable '/usr/local/var/proftpd/proftpd.delay', row 1
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: selecting median interval from 1 value
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: adding 676 usecs to USER row
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: unlocking DelayTable '/usr/local/var/proftpd/proftpd.delay', row 1
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: unmapping DelayTable '/usr/local/var/proftpd/proftpd.delay' from memory
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD command 'USER yacek' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD command 'USER yacek' to mod_log
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'PASS (hidden)' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'PASS (hidden)' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'PASS (hidden)' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'PASS (hidden)' to mod_delay
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'PASS (hidden)' to mod_auth
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching CMD command 'PASS (hidden)' to mod_auth
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getgroups" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getgroups" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getgroups" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - no supplemental groups found for user 'yacek'
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getpwnam" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getpwnam" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "getpwnam" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - no such user 'yacek'
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - USER yacek: no such user found from xxx.xx.xx.xx [xxx.xx.xx.xx] to 83.16.177.26:21
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching POST_CMD_ERR command 'PASS (hidden)' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching POST_CMD_ERR command 'PASS (hidden)' to mod_delay
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: mapping DelayTable '/usr/local/var/proftpd/proftpd.delay' into memory
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: write-locking DelayTable '/usr/local/var/proftpd/proftpd.delay', row 2
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: selecting median interval from 1 value
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: adding 785 usecs to PASS row
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: unlocking DelayTable '/usr/local/var/proftpd/proftpd.delay', row 2
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - mod_delay/0.5: unmapping DelayTable '/usr/local/var/proftpd/proftpd.delay' from memory
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD_ERR command 'PASS (hidden)' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD_ERR command 'PASS (hidden)' to mod_log
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD_ERR command 'PASS (hidden)' to mod_auth
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'SYST' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'SYST' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching CMD command 'SYST' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching POST_CMD command 'SYST' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD command 'SYST' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD command 'SYST' to mod_log
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'QUIT' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'QUIT' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching PRE_CMD command 'QUIT' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching CMD command 'QUIT' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching POST_CMD command 'QUIT' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD command 'QUIT' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD command 'QUIT' to mod_log
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching LOG_CMD command 'QUIT' to mod_core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching event 'core.exit' to core
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching event 'core.exit' to mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching event 'core.exit' to mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endpwent" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_sql
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_auth_file
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching auth request "endgrent" to module mod_auth_unix
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - dispatching event 'core.exit' to mod_xfer
localhost (xxx.xx.xx.xx[xxx.xx.xx.xx]) - FTP session closed.
localhost - FS: using system lstat()
localhost - ProFTPD terminating (signal 2)
localhost - ROOT PRIVS at main.c:1872
localhost - dispatching event 'core.exit' to core
localhost - dispatching event 'core.exit' to mod_delay
localhost - ROOT PRIVS at mod_delay.c:828
localhost - FS: using system open()
localhost - RELINQUISH PRIVS at mod_delay.c:830
localhost - mod_delay/0.5: write-locking DelayTable '/usr/local/var/proftpd/proftpd.delay'
localhost - mod_delay/0.5: mapping DelayTable '/usr/local/var/proftpd/proftpd.delay' into memory
localhost - mod_delay/0.5: unmapping DelayTable '/usr/local/var/proftpd/proftpd.delay' from memory
localhost - mod_delay/0.5: unlocking DelayTable '/usr/local/var/proftpd/proftpd.delay'
localhost - FS: using system write()
localhost - FS: using system close()
localhost - RELINQUISH PRIVS at main.c:1893
localhost - ProFTPD 1.3.0 standalone mode SHUTDOWN
localhost - ROOT PRIVS at main.c:1900
localhost - deleting existing scoreboard '/usr/local/var/proftpd/proftpd.scoreboard'
localhost - RELINQUISH PRIVS at main.c:1902
localhost - no event handlers registered for 'core.exit'
***************************

/etc/proftpd.conf:
***************************

ServerName "Debian"
ServerType standalone
DeferWelcome off

MultilineRFC2228 on
DefaultServer on
ShowSymlinks on

TimeoutNoTransfer 600
TimeoutStalled 600
TimeoutIdle 1200

DisplayLogin welcome.msg
DisplayFirstChdir .message
ListOptions "-l"

DenyFilter \*.*/

Port 21
MaxInstances 30

User nobody
Group nogroup
Umask 022 022
AllowOverwrite on

SQLConnectInfo proftpd@localhost proftpd password
SQLAuthenticate users groups
SQLAuthTypes Crypt Backend
SQLUserInfo users userid passwd uid gid homedir shell
SQLGroupInfo groups groupname gid members
*****************

Revision history for this message
yacek87 (conexion2000) wrote :

Please provide 1.3.0 version of proftpd-mysql. The problem seems to be solved in 1.3.0 version.

Revision history for this message
yacek87 (conexion2000) wrote :

Version proftpd-mysql-1.2.10-27 doesn't just support password() function in mysql. Using encrypt() function solves the problem. Versions 1.2.10-22 and 1.3.0 (from source) support both encrypt() and password() functions. Please close this bug.

Revision history for this message
Anthony Mercatante (tonio) wrote :

I can confirm this.
I have asked for edgy version to be backported since that's very annoying issue...

Changed in proftpd:
status: Unconfirmed → Confirmed
Revision history for this message
John Dong (jdong) wrote :

considering for backporting...

Changed in dapper-backports:
status: Unconfirmed → Confirmed
Revision history for this message
John Dong (jdong) wrote :

Tested and Approved for backporting

Changed in dapper-backports:
status: Confirmed → In Progress
Revision history for this message
Colin Watson (cjwatson) wrote :

 * Trying to backport proftpd...
  - <proftpd_1.3.0.orig.tar.gz: downloading from librarian>
  - <proftpd_1.3.0-9.diff.gz: downloading from librarian>
  - <proftpd_1.3.0-9.dsc: downloading from librarian>
I: Extracting proftpd_1.3.0-9.dsc ... done.
I: Building backport of proftpd-1.3.0 ... done.

Changed in proftpd:
status: Confirmed → Fix Released
Changed in dapper-backports:
status: In Progress → Fix Released
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.
Subscribing...

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.