Добрый день, форумчане По религиозным причинам пришлось уйти с любимого pureFTPd на proFTPd. Переезд прошел в принципе безболезненно, но есть одна проблема: аутентификация пользователя происходит примерно за 3 секунды, если сравнивать с pureFTPD, то там все происходило мгновенно. Такое время весьма критично, т.к. используется старый софт, который для каждого действия заново ломится на сервер, т.е. открытой сессиию не держит.
Сервер СУБД находит на отдельной тачке, запросы отрабатывает мгновенно, та и таблицы там килобайтные, с сетью проблем нет.
В кусочке лога proftpd_sql.log я приложил лог нажатия одной кнопки, даты и время оставил, что бы можно оценить, как долго происходит сие действо.
Вопрос, может кто-то помочь затюнить mod_sql таким образом, что бы работало быстро, как у pureFTPd?
ЗЫ доки все перечитал,
http://www.proftpd.org/docs/howto/SQL.html#SQLFaster - эта штука не помогла.
Есть еще идеи?
Linux ftp 3.13.0-24-generic #47-Ubuntu SMP Fri May 2 23:30:00 UTC 2014 x86_64 x86_64 x86_64 GNU/Linux
ProFTPD Version: 1.3.5rc3 (devel)
Scoreboard Version: 01040003
Built: Fri Dec 20 2013 18:05:41 UTC
Loaded modules:
mod_ifsession/1.3
mod_tls_memcache/0.1
mod_ifversion/0.1
mod_deflate/0.5.6
mod_copy/0.4
mod_unique_id/0.2
mod_facl/0.4
mod_sftp_pam/0.3
mod_sftp/0.9.9
mod_site_misc/1.5
mod_ratio/3.3
mod_shaper/0.6.6
mod_exec/0.9.11
mod_dynmasq/0.4
mod_wrap2_file/1.2
mod_wrap2/2.0.6
mod_ban/0.6.1
mod_rewrite/0.9
mod_wrap/1.2.4
mod_quotatab_radius.c
mod_quotatab_file.c
mod_quotatab/1.3.1
mod_radius/0.9.2
mod_sql_mysql/4.0.8
mod_sql/4.3
mod_tls/2.4.5
mod_ctrls_admin/0.9.7
mod_lang/1.0
mod_ctrls/0.9.5
mod_cap/1.1
mod_memcache/0.1
mod_readme/1.0
mod_auth_pam/1.2
mod_ident/1.0
mod_dso/0.5
mod_facts/0.3
mod_delay/0.7
mod_site.c
mod_log.c
mod_ls.c
mod_auth.c
mod_auth_file/1.0
mod_auth_unix.c
mod_rlimit.c
mod_xfer.c
mod_core.c
UseIPv6 off
IdentLookups off
UseReverseDNS off
ServerName "My FTP server"
ServerType standalone
DeferWelcome off
MultilineRFC2228 on
DefaultServer on
ShowSymlinks on
TimeoutNoTransfer 600
TimeoutStalled 600
TimeoutIdle 1200
DisplayLogin welcome.msg
DisplayChdir .message true
ListOptions "-l"
DebugLevel 10
<IfModule mod_lang.c>
LangDefault en_US
LangEngine on
LangPath /usr/share/locale
</IfModule>
DenyFilter \*.*/
DefaultRoot ~
RequireValidShell off
Port 21
PassivePorts 49152 65534
<IfModule mod_dynmasq.c>
</IfModule>
User ftpuser
Group ftpgroup
Umask 022 022
AllowOverwrite on
TransferLog /var/log/proftpd/xferlog
SystemLog /var/log/proftpd/proftpd.log
SQLLogFile /var/log/proftpd/proftpd_sql.log
<IfModule mod_quotatab.c>
QuotaEngine off
</IfModule>
<IfModule mod_ratio.c>
Ratios off
</IfModule>
<IfModule mod_delay.c>
DelayEngine on
</IfModule>
<IfModule mod_ctrls.c>
ControlsEngine off
ControlsMaxClients 2
ControlsLog /var/log/proftpd/controls.log
ControlsSocket /var/run/proftpd/proftpd.sock
</IfModule>
<IfModule mod_ctrls_admin.c>
AdminControlsEngine off
</IfModule>
Include /etc/proftpd/sql.conf
<Anonymous /ftp/public>
SQLEngine off
User ftpuser
Group ftpgroup
UserAlias anonymous ftpuser
DirFakeUser on ftpuser
DirFakeGroup on ftpuser
MaxClients 100
<Directory *>
<Limit WRITE>
DenyAll
</Limit>
</Directory>
</Anonymous>
Include /etc/proftpd/conf.d/
<IfModule mod_sql.c>
SQLBackend mysql
SQLAuthenticate users
SQLAuthTypes Plaintext Empty
SQLConnectInfo db@mysqlhost user pass
SQLNegativeCache off
SQLMinUserGID 2
SQLMinUserUID 1
SQLDefaultGID 2001
SQLDefaultUID 2001
SQLDefaultHomedir /ftp/public
SQLNamedQuery auth SELECT "p.`User`, p.`pwd_crypt`, p.`Uid`, p.`Gid`, p.`Dir`, p.`shell` FROM pftp as p LEFT JOIN pftp_ipranges as ipr ON ipr.user = p.User WHERE p.User='%U' AND p.`status`='1' AND (p.ipaccess='*' OR ( INET_ATON('%a') >= INET_ATON(ipr.ip_start) AND INET_ATON('%a') <= INET_ATON(ipr.ip_end) )) LIMIT 1"
SQLNamedQuery all-names SELECT "p.`User` FROM pftp as p LEFT JOIN pftp_ipranges as ipr ON ipr.user = p.User WHERE p.`status`='1' AND (p.ipaccess='*' OR ( INET_ATON('%a') >= INET_ATON(ipr.ip_start) AND INET_ATON('%a') <= INET_ATON(ipr.ip_end)))"
SQLNamedQuery all-users SELECT "p.`User`, p.`pwd_crypt`, p.`Uid`, p.`Gid`, p.`Dir`, p.`shell` FROM pftp as p LEFT JOIN pftp_ipranges as ipr ON ipr.user = p.User WHERE p.`status`='1' AND (p.ipaccess='*' OR ( INET_ATON('%a') >= INET_ATON(ipr.ip_start) AND INET_ATON('%a') <= INET_ATON(ipr.ip_end)))"
SQLNamedQuery by-id SELECT "p.`User`, p.`pwd_crypt`, p.`Uid`, p.`Gid`, p.`Dir`, p.`shell` FROM pftp as p LEFT JOIN pftp_ipranges as ipr ON ipr.user = p.User WHERE p.Uid=%{0} AND p.`status`='1' AND (p.ipaccess='*' OR ( INET_ATON('%a') >= INET_ATON(ipr.ip_start) AND INET_ATON('%a') <= INET_ATON(ipr.ip_end))) LIMIT 1"
SQLUserInfo custom:/auth/by-id/all-names/all-users
</IfModule>
2016-04-21 15:37:04,146 mod_sql/4.3[3337]: defaulting to 'mysql' backend
2016-04-21 15:37:04,146 mod_sql/4.3[3337]: backend module 'mod_sql_mysql/4.0.8'
2016-04-21 15:37:04,146 mod_sql/4.3[3337]: backend api 'mod_sql_api_v1'
2016-04-21 15:37:04,146 mod_sql/4.3[3337]: >>> sql_sess_init
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: entering mysql cmd_defineconnection
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: name: 'default'
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: user: 'pftp'
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: host: 'host'
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: db: 'dbname'
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: port: '3306'
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: ttl: '0'
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: exiting mysql cmd_defineconnection
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: connection 'default' successfully established
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: mod_sql engine : on
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: negative_cache : off
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: authenticate : users
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: usertable : users
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: userid field : userid
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: password field : passwd
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: UID field : uid
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: GID field : gid
2016-04-21 15:37:04,147 mod_sql/4.3[3337]: homedir field : homedir
2016-04-21 15:37:04,148 mod_sql/4.3[3337]: homedir(default) : '/ftp/public'
2016-04-21 15:37:04,148 mod_sql/4.3[3337]: shell field : shell
2016-04-21 15:37:04,148 mod_sql/4.3[3337]: SQLMinUserUID : 1
2016-04-21 15:37:04,148 mod_sql/4.3[3337]: SQLMinUserGID : 2
2016-04-21 15:37:04,148 mod_sql/4.3[3337]: <<< sql_sess_init
2016-04-21 15:37:04,175 mod_sql/4.3[3337]: >>> cmd_getpwnam
2016-04-21 15:37:04,175 mod_sql/4.3[3337]: entering mysql cmd_escapestring
2016-04-21 15:37:04,175 mod_sql/4.3[3337]: entering mysql cmd_open
2016-04-21 15:37:04,229 mod_sql/4.3[3337]: MySQL client version: 5.5.47
2016-04-21 15:37:04,229 mod_sql/4.3[3337]: MySQL server version: 5.1.51-community-log
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: MySQL connection character set now 'utf8' (from 'UTF-8')
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: connection 'default' opened
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: connection 'default' count is now 2
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: exiting mysql cmd_open
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: entering mysql cmd_close
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: connection 'default' count is now 1
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: exiting mysql cmd_close
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: exiting mysql cmd_escapestring
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: cache miss for user 'ftp_user'
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: >>> sql_lookup
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: >>> process_named_query 'auth'
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: entering mysql cmd_escapestring
2016-04-21 15:37:04,230 mod_sql/4.3[3337]: entering mysql cmd_open
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: connection 'default' count is now 2
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: exiting mysql cmd_open
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: entering mysql cmd_close
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: connection 'default' count is now 1
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: exiting mysql cmd_close
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: exiting mysql cmd_escapestring
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: entering mysql cmd_escapestring
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: entering mysql cmd_open
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: connection 'default' count is now 2
2016-04-21 15:37:04,231 mod_sql/4.3[3337]: exiting mysql cmd_open
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: entering mysql cmd_close
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: connection 'default' count is now 1
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: exiting mysql cmd_close
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: exiting mysql cmd_escapestring
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: entering mysql cmd_escapestring
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: entering mysql cmd_open
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: connection 'default' count is now 2
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: exiting mysql cmd_open
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: entering mysql cmd_close
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: connection 'default' count is now 1
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: exiting mysql cmd_close
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: exiting mysql cmd_escapestring
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: entering mysql cmd_select
2016-04-21 15:37:04,232 mod_sql/4.3[3337]: entering mysql cmd_open
2016-04-21 15:37:04,233 mod_sql/4.3[3337]: connection 'default' count is now 2
2016-04-21 15:37:04,233 mod_sql/4.3[3337]: exiting mysql cmd_open
2016-04-21 15:37:04,233 mod_sql/4.3[3337]: query "SELECT p.`User`, p.`pwd_crypt`, p.`Uid`, p.`Gid`, p.`Dir`, p.`shell` FROM pftp as p LEFT JOIN pftp_ipranges as ipr ON ipr.user = p.User WHERE p.User='ftp_user' AND p.`status`='1' AND (p.ipaccess='*' OR ( INET_ATON('192.168.0.2') >= INET_ATON(ipr.ip_start) AND INET_ATON('192.168.0.2') <= INET_ATON(ipr.ip_end) )) LIMIT 1"
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: entering mysql cmd_close
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: connection 'default' count is now 1
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: exiting mysql cmd_close
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: exiting mysql cmd_select
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: <<< process_named_query 'auth'
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: <<< sql_lookup
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: custom SQLUserInfo query 'auth' returned 6 columns for user 'ftp_user'
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: cache miss for user 'ftp_user'
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: user 'ftp_user' cached
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: + pwd.pw_name : ftp_user
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: + pwd.pw_uid : 2001
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: + pwd.pw_gid : 2001
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: + pwd.pw_dir : /ftp/users/ftp_user
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: + pwd.pw_shell : /bin/false
2016-04-21 15:37:04,234 mod_sql/4.3[3337]: <<< cmd_getpwnam
2016-04-21 15:37:04,235 mod_sql/4.3[3337]: >>> sql_pre_pass
2016-04-21 15:37:04,235 mod_sql/4.3[3337]: <<< sql_pre_pass
2016-04-21 15:37:04,236 mod_sql/4.3[3337]: >>> cmd_getpwnam
2016-04-21 15:37:04,236 mod_sql/4.3[3337]: cache hit for user 'ftp_user'
2016-04-21 15:37:04,236 mod_sql/4.3[3337]: <<< cmd_getpwnam
2016-04-21 15:37:05,961 mod_sql/4.3[3337]: >>> cmd_auth
2016-04-21 15:37:05,961 mod_sql/4.3[3337]: entering mysql cmd_escapestring
2016-04-21 15:37:05,961 mod_sql/4.3[3337]: entering mysql cmd_open
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: connection 'default' count is now 2
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: exiting mysql cmd_open
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: entering mysql cmd_close
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: connection 'default' count is now 1
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: exiting mysql cmd_close
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: exiting mysql cmd_escapestring
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: cache hit for user 'ftp_user'
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: >>> cmd_check
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: checking password using SQLAuthType 'Plaintext'
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: 'Plaintext' SQLAuthType handler reports success
2016-04-21 15:37:05,962 mod_sql/4.3[3337]: cache hit for user 'ftp_user'
2016-04-21 15:37:05,963 mod_sql/4.3[3337]: <<< cmd_check
2016-04-21 15:37:05,963 mod_sql/4.3[3337]: <<< cmd_auth
2016-04-21 15:37:05,968 mod_sql/4.3[3337]: >>> cmd_getpwnam
2016-04-21 15:37:05,968 mod_sql/4.3[3337]: cache hit for user 'ftp_user'
2016-04-21 15:37:05,969 mod_sql/4.3[3337]: <<< cmd_getpwnam
2016-04-21 12:37:05,991 mod_sql/4.3[3337]: >>> sql_post_retr
2016-04-21 12:37:05,991 mod_sql/4.3[3337]: <<< sql_post_retr
2016-04-21 12:37:05,992 mod_sql/4.3[3337]: entering mysql cmd_exit
2016-04-21 12:37:05,992 mod_sql/4.3[3337]: entering mysql cmd_close
2016-04-21 12:37:05,993 mod_sql/4.3[3337]: connection 'default' closed
2016-04-21 12:37:05,993 mod_sql/4.3[3337]: connection 'default' count is now 0
2016-04-21 12:37:05,993 mod_sql/4.3[3337]: exiting mysql cmd_close
2016-04-21 12:37:05,993 mod_sql/4.3[3337]: exiting mysql cmd_exit
2016-04-21 15:37:06,199 mod_sql/4.3[3339]: defaulting to 'mysql' backend
2016-04-21 15:37:06,199 mod_sql/4.3[3339]: backend module 'mod_sql_mysql/4.0.8'
2016-04-21 15:37:06,200 mod_sql/4.3[3339]: backend api 'mod_sql_api_v1'
2016-04-21 15:37:06,200 mod_sql/4.3[3339]: >>> sql_sess_init
2016-04-21 15:37:06,200 mod_sql/4.3[3339]: entering mysql cmd_defineconnection
2016-04-21 15:37:06,200 mod_sql/4.3[3339]: name: 'default'
2016-04-21 15:37:06,201 mod_sql/4.3[3339]: user: 'pftp'
2016-04-21 15:37:06,201 mod_sql/4.3[3339]: host: 'host'
2016-04-21 15:37:06,201 mod_sql/4.3[3339]: db: 'dbname'
2016-04-21 15:37:06,201 mod_sql/4.3[3339]: port: '3306'
2016-04-21 15:37:06,201 mod_sql/4.3[3339]: ttl: '0'
2016-04-21 15:37:06,201 mod_sql/4.3[3339]: exiting mysql cmd_defineconnection
2016-04-21 15:37:06,201 mod_sql/4.3[3339]: connection 'default' successfully established
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: mod_sql engine : on
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: negative_cache : off
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: authenticate : users
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: usertable : users
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: userid field : userid
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: password field : passwd
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: UID field : uid
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: GID field : gid
2016-04-21 15:37:06,202 mod_sql/4.3[3339]: homedir field : homedir
2016-04-21 15:37:06,203 mod_sql/4.3[3339]: homedir(default) : '/ftp/public'
2016-04-21 15:37:06,203 mod_sql/4.3[3339]: shell field : shell
2016-04-21 15:37:06,203 mod_sql/4.3[3339]: SQLMinUserUID : 1
2016-04-21 15:37:06,203 mod_sql/4.3[3339]: SQLMinUserGID : 2
2016-04-21 15:37:06,203 mod_sql/4.3[3339]: <<< sql_sess_init
2016-04-21 15:37:06,225 mod_sql/4.3[3339]: >>> cmd_getpwnam
2016-04-21 15:37:06,225 mod_sql/4.3[3339]: entering mysql cmd_escapestring
2016-04-21 15:37:06,225 mod_sql/4.3[3339]: entering mysql cmd_open
2016-04-21 15:37:06,278 mod_sql/4.3[3339]: MySQL client version: 5.5.47
2016-04-21 15:37:06,278 mod_sql/4.3[3339]: MySQL server version: 5.1.51-community-log
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: MySQL connection character set now 'utf8' (from 'UTF-8')
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: connection 'default' opened
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: connection 'default' count is now 2
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: exiting mysql cmd_open
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: entering mysql cmd_close
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: connection 'default' count is now 1
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: exiting mysql cmd_close
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: exiting mysql cmd_escapestring
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: cache miss for user 'ftp_user'
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: >>> sql_lookup
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: >>> process_named_query 'auth'
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: entering mysql cmd_escapestring
2016-04-21 15:37:06,279 mod_sql/4.3[3339]: entering mysql cmd_open
2016-04-21 15:37:06,280 mod_sql/4.3[3339]: connection 'default' count is now 2
2016-04-21 15:37:06,280 mod_sql/4.3[3339]: exiting mysql cmd_open
2016-04-21 15:37:06,280 mod_sql/4.3[3339]: entering mysql cmd_close
2016-04-21 15:37:06,280 mod_sql/4.3[3339]: connection 'default' count is now 1
2016-04-21 15:37:06,280 mod_sql/4.3[3339]: exiting mysql cmd_close
2016-04-21 15:37:06,280 mod_sql/4.3[3339]: exiting mysql cmd_escapestring
2016-04-21 15:37:06,280 mod_sql/4.3[3339]: entering mysql cmd_escapestring
2016-04-21 15:37:06,280 mod_sql/4.3[3339]: entering mysql cmd_open
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: connection 'default' count is now 2
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: exiting mysql cmd_open
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: entering mysql cmd_close
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: connection 'default' count is now 1
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: exiting mysql cmd_close
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: exiting mysql cmd_escapestring
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: entering mysql cmd_escapestring
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: entering mysql cmd_open
2016-04-21 15:37:06,281 mod_sql/4.3[3339]: connection 'default' count is now 2
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: exiting mysql cmd_open
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: entering mysql cmd_close
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: connection 'default' count is now 1
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: exiting mysql cmd_close
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: exiting mysql cmd_escapestring
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: entering mysql cmd_select
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: entering mysql cmd_open
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: connection 'default' count is now 2
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: exiting mysql cmd_open
2016-04-21 15:37:06,282 mod_sql/4.3[3339]: query "SELECT p.`User`, p.`pwd_crypt`, p.`Uid`, p.`Gid`, p.`Dir`, p.`shell` FROM pftp as p LEFT JOIN pftp_ipranges as ipr ON ipr.user = p.User WHERE p.User='ftp_user' AND p.`status`='1' AND (p.ipaccess='*' OR ( INET_ATON('192.168.0.2') >= INET_ATON(ipr.ip_start) AND INET_ATON('192.168.0.2') <= INET_ATON(ipr.ip_end) )) LIMIT 1"
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: entering mysql cmd_close
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: connection 'default' count is now 1
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: exiting mysql cmd_close
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: exiting mysql cmd_select
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: <<< process_named_query 'auth'
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: <<< sql_lookup
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: custom SQLUserInfo query 'auth' returned 6 columns for user 'ftp_user'
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: cache miss for user 'ftp_user'
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: user 'ftp_user' cached
2016-04-21 15:37:06,283 mod_sql/4.3[3339]: + pwd.pw_name : ftp_user
2016-04-21 15:37:06,284 mod_sql/4.3[3339]: + pwd.pw_uid : 2001
2016-04-21 15:37:06,284 mod_sql/4.3[3339]: + pwd.pw_gid : 2001
2016-04-21 15:37:06,284 mod_sql/4.3[3339]: + pwd.pw_dir : /ftp/users/ftp_user
2016-04-21 15:37:06,284 mod_sql/4.3[3339]: + pwd.pw_shell : /bin/false
2016-04-21 15:37:06,284 mod_sql/4.3[3339]: <<< cmd_getpwnam
2016-04-21 15:37:06,285 mod_sql/4.3[3339]: >>> sql_pre_pass
2016-04-21 15:37:06,285 mod_sql/4.3[3339]: <<< sql_pre_pass
2016-04-21 15:37:06,286 mod_sql/4.3[3339]: >>> cmd_getpwnam
2016-04-21 15:37:06,286 mod_sql/4.3[3339]: cache hit for user 'ftp_user'
2016-04-21 15:37:06,286 mod_sql/4.3[3339]: <<< cmd_getpwnam
2016-04-21 15:37:07,951 mod_sql/4.3[3339]: >>> cmd_auth
2016-04-21 15:37:07,951 mod_sql/4.3[3339]: entering mysql cmd_escapestring
2016-04-21 15:37:07,952 mod_sql/4.3[3339]: connection 'default' count is now 2
2016-04-21 15:37:07,952 mod_sql/4.3[3339]: exiting mysql cmd_open
2016-04-21 15:37:07,952 mod_sql/4.3[3339]: entering mysql cmd_close
2016-04-21 15:37:07,952 mod_sql/4.3[3339]: connection 'default' count is now 1
2016-04-21 15:37:07,952 mod_sql/4.3[3339]: exiting mysql cmd_close
2016-04-21 15:37:07,952 mod_sql/4.3[3339]: exiting mysql cmd_escapestring
2016-04-21 15:37:07,952 mod_sql/4.3[3339]: cache hit for user 'ftp_user'
2016-04-21 15:37:07,953 mod_sql/4.3[3339]: >>> cmd_check
2016-04-21 15:37:07,953 mod_sql/4.3[3339]: checking password using SQLAuthType 'Plaintext'
2016-04-21 15:37:07,953 mod_sql/4.3[3339]: 'Plaintext' SQLAuthType handler reports success
2016-04-21 15:37:07,953 mod_sql/4.3[3339]: cache hit for user 'ftp_user'
2016-04-21 15:37:07,953 mod_sql/4.3[3339]: <<< cmd_check
2016-04-21 15:37:07,953 mod_sql/4.3[3339]: <<< cmd_auth
2016-04-21 15:37:07,959 mod_sql/4.3[3339]: >>> cmd_getpwnam
2016-04-21 15:37:07,959 mod_sql/4.3[3339]: cache hit for user 'ftp_user'
2016-04-21 15:37:07,959 mod_sql/4.3[3339]: <<< cmd_getpwnam
2016-04-21 12:37:07,984 mod_sql/4.3[3339]: >>> sql_post_retr
2016-04-21 12:37:07,984 mod_sql/4.3[3339]: <<< sql_post_retr
2016-04-21 12:37:07,996 mod_sql/4.3[3339]: >>> sql_post_stor
2016-04-21 12:37:07,996 mod_sql/4.3[3339]: <<< sql_post_stor
2016-04-21 12:37:10,182 mod_sql/4.3[3339]: >>> sql_post_stor
2016-04-21 12:37:10,182 mod_sql/4.3[3339]: <<< sql_post_stor
2016-04-21 12:37:10,183 mod_sql/4.3[3339]: entering mysql cmd_exit
2016-04-21 12:37:10,183 mod_sql/4.3[3339]: entering mysql cmd_close
2016-04-21 12:37:10,183 mod_sql/4.3[3339]: connection 'default' closed
2016-04-21 12:37:10,183 mod_sql/4.3[3339]: connection 'default' count is now 0
2016-04-21 12:37:10,183 mod_sql/4.3[3339]: exiting mysql cmd_close
2016-04-21 12:37:10,183 mod_sql/4.3[3339]: exiting mysql cmd_exit