[imp] user stuck at login.php

Geoff Sweet geoffrsweet at gmail.com
Tue Jan 12 03:24:45 UTC 2010


Greetings all,
I have read a little bit about users getting stuck at login.php and the
system perpetually just redirecting back to login.php.  I seem to have that
issue but thus far nothing I can do seems to resolve the issue.  I have
validated that my cookie path matches where my Horde install is.  That seems
to be the most common case.  My Horde install lives at /webmail.

I authenticate without issue and I can see the successful authentication on
both my IMAP and in my Horde logs.  Can anyone see anything here that I'm
missing?

Horde config.php:


Horde logs in debug:
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::retrieve():
SELECT pref_scope, pref_name, pref_value FROM horde_prefs WHERE pref_uid = ?
AND pref_scope = ?, values: hihi at testtesttest.com, horde [pid 375 on line
148 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::retrieve():
SELECT pref_scope, pref_name, pref_value FROM horde_prefs WHERE pref_uid = ?
AND pref_scope = ?, values: hihi at testtesttest.com, imp [pid 375 on line 148
of "/home/gorf/public_html/whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] Hook _prefs_change_hook_last_login in
application horde not called. [pid 375 on line 1713 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde.php"]
Jan 11 18:50:21 HORDE [debug] [imp] Hook _prefs_change_hook_expanded_folders
in application horde not called. [pid 375 on line 1713 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde.php"]
Jan 11 18:50:21 HORDE [debug] [imp] Hook _prefs_change_hook_expanded_folders
in application horde not called. [pid 375 on line 1713 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde.php"]
Jan 11 18:50:21 HORDE [debug] [imp] Hook _prefs_change_hook_expanded_folders
in application horde not called. [pid 375 on line 1713 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde.php"]
Jan 11 18:50:21 HORDE [debug] [imp] Hook _prefs_change_hook_vinbox_id in
application horde not called. [pid 375 on line 1713 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde.php"]
Jan 11 18:50:21 HORDE [notice] [imp] Login success for
hihi at testtesttest.com[173.160.151.225] to {
mail1.prod.whootis.net:143 [imap/notls]} [pid 375 on line 307 of
"/home/gorf/public_html/whootis.net/www/webmail/imp/lib/Session.php"]
Jan 11 18:50:21 HORDE [debug] [imp] Hook _prefs_change_hook_last_maintenance
in application horde not called. [pid 375 on line 1713 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde.php"]
Jan 11 18:50:21 HORDE [debug] [imp] Max memory usage: 6553600 bytes [pid 375
on line 339 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Registry.php"]
Jan 11 18:50:21 HORDE [debug] [imp] IMAP errors: SECURITY PROBLEM: insecure
server advertised AUTH=PLAIN [pid 375 on line 175 of
"/home/gorf/public_html/whootis.net/www/webmail/imp/lib/IMAP.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::store(): SELECT
1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND pref_scope = ?,
values: hihi at testtesttest.com, last_maintenance, horde [pid 375 on line 210
of "/home/gorf/public_html/whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::store(): INSERT
INTO horde_prefs (pref_uid, pref_scope, pref_name, pref_value) VALUES(?, ?,
?, ?), values: hihi at testtesttest.com, horde, last_maintenance, 1263264621
[pid 375 on line 244 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::store(): SELECT
1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND pref_scope = ?,
values: hihi at testtesttest.com, last_login, horde [pid 375 on line 210 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::store(): INSERT
INTO horde_prefs (pref_uid, pref_scope, pref_name, pref_value) VALUES(?, ?,
?, ?), values: hihi at testtesttest.com, horde, last_login,
a:2:{s:4:"time";i:1263264621;s:4:"host";s:50:"
173-160-151-225-Washington.hfc.comcastbusiness.net";} [pid 375 on line 244
of "/home/gorf/public_html/whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::store(): SELECT
1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND pref_scope = ?,
values: hihi at testtesttest.com, expanded_folders, imp [pid 375 on line 210 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::store(): INSERT
INTO horde_prefs (pref_uid, pref_scope, pref_name, pref_value) VALUES(?, ?,
?, ?), values: hihi at testtesttest.com, imp, expanded_folders, a:0:{} [pid 375
on line 244 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::store(): SELECT
1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND pref_scope = ?,
values: hihi at testtesttest.com, vinbox_id, imp [pid 375 on line 210 of
"/home/gorf/public_html/whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL Query by Prefs_sql::store(): INSERT
INTO horde_prefs (pref_uid, pref_scope, pref_name, pref_value) VALUES(?, ?,
?, ?), values: hihi at testtesttest.com, imp, vinbox_id, vuydm2n6qggsksswc0og
[pid 375 on line 244 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Prefs/sql.php"]
Jan 11 18:50:21 HORDE [debug] [horde] Max memory usage: 4718592 bytes [pid
375 on line 339 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Registry.php"]
Jan 11 18:50:21 HORDE [debug] [] Guest user does not have READ permission
for horde [pid 375 on line 814 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Registry.php"]
Jan 11 18:50:21 HORDE [debug] [] Max memory usage: 4194304 bytes [pid 375 on
line 339 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Registry.php"]
Jan 11 18:50:21 HORDE [debug] [horde] Max memory usage: 4718592 bytes [pid
375 on line 339 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Registry.php"]
Jan 11 18:50:21 HORDE [debug] [imp] SQL query by Horde_Alarm_sql::_list():
SELECT alarm_id, alarm_uid, alarm_start, alarm_end, alarm_methods,
alarm_params, alarm_title, alarm_text, alarm_snooze, alarm_internal FROM
horde_alarms WHERE alarm_dismissed = 0 AND ((alarm_snooze IS NULL AND
alarm_start <= ?) OR alarm_snooze <= ?) AND (alarm_end IS NULL OR alarm_end
>= ?) AND (alarm_uid IS NULL OR alarm_uid = ? OR alarm_uid = ?) ORDER BY
alarm_start, alarm_end [pid 375 on line 151 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Alarm/sql.php"]
Jan 11 18:50:21 HORDE [debug] [imp] Max memory usage: 5767168 bytes [pid 375
on line 339 of "/home/gorf/public_html/
whootis.net/www/webmail/lib/Horde/Registry.php"]




Dovecot IMAP logs:
Jan 11 18:48:56 mail1 dovecot: auth(default): client in: AUTH    1
PLAIN    service=imap    lip=192.168.20.12    rip=192.168.20.11
lport=143    rport=58309
Jan 11 18:48:56 mail1 dovecot: auth(default): client out: CONT    1
Jan 11 18:48:56 mail1 dovecot: auth(default): client in: CONT    1
AGhpaGlAdGVzdHRlc3R0ZXN0LmNvbQBib2IxMjM0
Jan 11 18:48:56 mail1 dovecot: auth-worker(default): sql(
hihi at testtesttest.com,192.168.20.11): query: SELECT email as user, password
FROM view_mailboxes WHERE email='hihi at testtesttest.com';
Jan 11 18:48:56 mail1 dovecot: auth(default): client out: OK    1    user=
hihi at testtesttest.com
Jan 11 18:48:56 mail1 dovecot: auth(default): master in: REQUEST    234
8348    1
Jan 11 18:48:56 mail1 dovecot: auth(default): master out: USER    234
hihi at testtesttest.com    uid=5000    gid=5000    home=/home/vmail/
testtesttest.com/hihi
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): Effective
uid=5000, gid=5000, home=/home/vmail/testtesttest.com/hihi
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): maildir:
data=/home/vmail/testtesttest.com/hihi/Maildir
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): maildir++:
root=/home/vmail/testtesttest.com/hihi/Maildir, index=, control=,
inbox=/home/vmail/testtesttest.com/hihi/Maildir
Jan 11 18:48:56 mail1 dovecot: imap-login: Login: user=<
hihi at testtesttest.com>, method=PLAIN, rip=192.168.20.11, lip=192.168.20.12
Jan 11 18:48:56 mail1 dovecot: auth(default): new auth connection: pid=8370
Jan 11 18:48:56 mail1 dovecot: auth(default): client in: AUTH    1
PLAIN    service=imap    lip=192.168.20.12    rip=192.168.20.11
lport=143    rport=58311
resp=aGloaUB0ZXN0dGVzdHRlc3QuY29tAGhpaGlAdGVzdHRlc3R0ZXN0LmNvbQBib2IxMjM0
Jan 11 18:48:56 mail1 dovecot: auth-worker(default): sql(
hihi at testtesttest.com,192.168.20.11): query: SELECT email as user, password
FROM view_mailboxes WHERE email='hihi at testtesttest.com';
Jan 11 18:48:56 mail1 dovecot: auth(default): client out: OK    1    user=
hihi at testtesttest.com
Jan 11 18:48:56 mail1 dovecot: auth(default): master in: REQUEST    235
8347    1
Jan 11 18:48:56 mail1 dovecot: auth(default): master out: USER    235
hihi at testtesttest.com    uid=5000    gid=5000    home=/home/vmail/
testtesttest.com/hihi
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): Effective
uid=5000, gid=5000, home=/home/vmail/testtesttest.com/hihi
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): maildir:
data=/home/vmail/testtesttest.com/hihi/Maildir
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): maildir++:
root=/home/vmail/testtesttest.com/hihi/Maildir, index=, control=,
inbox=/home/vmail/testtesttest.com/hihi/Maildir
Jan 11 18:48:56 mail1 dovecot: imap-login: Login: user=<
hihi at testtesttest.com>, method=PLAIN, rip=192.168.20.11, lip=192.168.20.12
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): Namespace :
Permission lookup failed from /home/vmail/testtesttest.com/hihi/Maildir
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): Namespace :
Using permissions from /home/vmail/testtesttest.com/hihi/Maildir: mode=0700
gid=-1
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): Disconnected:
Logged out bytes=94/747
Jan 11 18:48:56 mail1 dovecot: IMAP(hihi at testtesttest.com): Disconnected:
Logged out bytes=35/396
Jan 11 18:48:57 mail1 dovecot: auth(default): new auth connection: pid=8372


Horde config.php:
/* CONFIG START. DO NOT CHANGE ANYTHING IN OR AFTER THIS LINE. */
// $Horde: horde/config/conf.xml,v 1.74.2.88 2009-09-02 08:21:46 jan Exp $
$conf['vhosts'] = false;
$conf['debug_level'] = E_ALL & ~E_NOTICE & ~8192;
$conf['max_exec_time'] = 0;
$conf['compress_pages'] = true;
$conf['secret_key'] = 'snip';
$conf['umask'] = 077;
$conf['use_ssl'] = 2;
$conf['server']['name'] = $_SERVER['SERVER_NAME'];
$conf['server']['port'] = $_SERVER['SERVER_PORT'];
$conf['urls']['token_lifetime'] = 30;
$conf['urls']['hmac_lifetime'] = 30;
$conf['urls']['pretty'] = 'rewrite';
$conf['safe_ips'] = array();
$conf['session']['name'] = 'Whootisnet';
$conf['session']['use_only_cookies'] = true;
$conf['session']['cache_limiter'] = 'nocache';
$conf['session']['timeout'] = 0;
$conf['cookie']['domain'] = $_SERVER['SERVER_NAME'];
$conf['cookie']['path'] = '/webmail';
$conf['sql']['username'] = 'horde';
$conf['sql']['password'] = 'snip';
$conf['sql']['hostspec'] = 'sql1.prod.whootis.net';
$conf['sql']['port'] = 3306;
$conf['sql']['protocol'] = 'tcp';
$conf['sql']['database'] = 'whootisnet_horde';
$conf['sql']['charset'] = 'utf-8';
$conf['sql']['splitread'] = false;
$conf['sql']['ssl'] = false;
$conf['sql']['phptype'] = 'mysqli';
$conf['auth']['admins'] = array('Administrator');
$conf['auth']['checkip'] = true;
$conf['auth']['checkbrowser'] = true;
$conf['auth']['alternate_login'] = false;
$conf['auth']['redirect_on_logout'] = false;
$conf['auth']['list_users'] = 'list';
$conf['auth']['params']['app'] = 'imp';
$conf['auth']['driver'] = 'application';
$conf['signup']['allow'] = false;
$conf['log']['priority'] = PEAR_LOG_DEBUG;
$conf['log']['ident'] = 'HORDE';
$conf['log']['params'] = array();
$conf['log']['name'] = '/home/gorf/public_html/logs/hordelog_log';
$conf['log']['params']['append'] = true;
$conf['log']['type'] = 'file';
$conf['log']['enabled'] = true;
$conf['log_accesskeys'] = false;
$conf['prefs']['params']['driverconfig'] = 'horde';
$conf['prefs']['driver'] = 'sql';
$conf['alarms']['params']['table'] = 'horde_alarms';
$conf['alarms']['params']['driverconfig'] = 'horde';
$conf['alarms']['params']['ttl'] = 300;
$conf['alarms']['driver'] = 'sql';
$conf['datatree']['params']['table'] = 'horde_datatree';
$conf['datatree']['params']['table_attributes'] =
'horde_datatree_attributes';
$conf['datatree']['params']['driverconfig'] = 'horde';
$conf['datatree']['driver'] = 'sql';
$conf['group']['driver'] = 'datatree';
$conf['group']['cache'] = false;
$conf['perms']['driver'] = 'datatree';
$conf['share']['no_sharing'] = true;
$conf['share']['any_group'] = false;
$conf['share']['cache'] = false;
$conf['share']['driver'] = 'datatree';
$conf['cache']['default_lifetime'] = 86400;
$conf['cache']['params']['dir'] = '/tmp/horde';
$conf['cache']['params']['sub'] = 0;
$conf['cache']['driver'] = 'file';
$conf['lock']['params']['driverconfig'] = 'horde';
$conf['lock']['driver'] = 'sql';
$conf['token']['params']['driverconfig'] = 'horde';
$conf['token']['driver'] = 'sql';
$conf['mailer']['params']['host'] = 'mail1.prod.whootis.net';
$conf['mailer']['params']['port'] = 25;
$conf['mailer']['params']['localhost'] = 'web1.prod.whootis.net';
$conf['mailer']['params']['auth'] = false;
$conf['mailer']['type'] = 'smtp';
$conf['mailformat']['brokenrfc2231'] = false;
$conf['vfs']['params']['driverconfig'] = 'horde';
$conf['vfs']['type'] = 'sql';
$conf['sessionhandler']['type'] = 'none';
$conf['sessionhandler']['memcache'] = false;
$conf['mime']['magic_db'] = '/usr/share/magic';
$conf['problems']['email'] = 'hostmaster at whootis.net';
$conf['problems']['maildomain'] = 'whootis.net';
$conf['problems']['tickets'] = false;
$conf['problems']['attachments'] = true;
$conf['menu']['apps'] = array();
$conf['menu']['always'] = false;
$conf['menu']['links']['help'] = 'authenticated';
$conf['menu']['links']['options'] = 'authenticated';
$conf['menu']['links']['problem'] = 'authenticated';
$conf['menu']['links']['login'] = 'all';
$conf['menu']['links']['logout'] = 'authenticated';
$conf['hooks']['permsdenied'] = false;
$conf['hooks']['username'] = false;
$conf['hooks']['preauthenticate'] = false;
$conf['hooks']['postauthenticate'] = false;
$conf['hooks']['authldap'] = false;
$conf['hooks']['groupldap'] = false;
$conf['portal']['fixed_blocks'] = array();
$conf['accounts']['driver'] = 'null';
$conf['user']['verify_from_addr'] = false;
$conf['imsp']['enabled'] = false;
$conf['kolab']['enabled'] = false;
$conf['memcache']['enabled'] = false;
/* CONFIG END. DO NOT CHANGE ANYTHING IN OR BEFORE THIS LINE. */

IMP config.php:
/* CONFIG START. DO NOT CHANGE ANYTHING IN OR AFTER THIS LINE. */
// $Horde: imp/config/conf.xml,v 1.53.2.43 2009-07-02 06:18:15 slusarz Exp $
$conf['spell']['params']['path'] = '/usr/bin/aspell';
$conf['spell']['driver'] = 'aspell';
$conf['utils']['gnupg'] = '/usr/bin/gpg';
$conf['utils']['gnupg_keyserver'] = array('pgp.mit.edu', '
keyserver.noreply.org');
$conf['utils']['gnupg_timeout'] = 10;
$conf['utils']['openssl_cafile'] = '/etc/pki/tls/certs/ca-bundle.crt';
$conf['utils']['openssl_binary'] = '/usr/bin/openssl';
$conf['menu']['apps'] = array('imp');
$conf['user']['select_sentmail_folder'] = true;
$conf['user']['allow_resume_all_in_drafts'] = true;
$conf['user']['allow_folders'] = true;
$conf['user']['allow_resume_all'] = false;
$conf['user']['allow_view_source'] = true;
$conf['user']['alternate_login'] = false;
$conf['user']['redirect_on_logout'] = false;
$conf['user']['select_view'] = true;
$conf['server']['change_server'] = false;
$conf['server']['change_port'] = false;
$conf['server']['change_protocol'] = false;
$conf['server']['change_smtphost'] = false;
$conf['server']['change_smtpport'] = false;
$conf['server']['server_list'] = 'none';
$conf['server']['fixed_folders'] = array();
$conf['server']['sort_limit'] = 0;
$conf['server']['cache_folders'] = true;
$conf['server']['token_lifetime'] = 1800;
$conf['server']['cachejs'] = 'none';
$conf['server']['cachecss'] = 'none';
$conf['mailbox']['show_preview'] = false;
$conf['fetchmail']['show_account_colors'] = false;
$conf['fetchmail']['size_limit'] = 4000000;
$conf['msgcache']['use_msgcache'] = false;
$conf['mlistcache']['use_mlistcache'] = false;
$conf['msgsettings']['filtering']['words'] = './config/filter.txt';
$conf['msgsettings']['filtering']['replacement'] = '****';
$conf['spam']['reporting'] = false;
$conf['notspam']['reporting'] = false;
$conf['print']['add_printedby'] = false;
$conf['msg']['prepend_header'] = true;
$conf['msg']['append_trailer'] = true;
$conf['compose']['allow_receipts'] = true;
$conf['compose']['special_characters'] = true;
$conf['compose']['use_vfs'] = false;
$conf['compose']['link_attachments'] = false;
$conf['compose']['attach_size_limit'] = 10485760;
$conf['compose']['attach_count_limit'] = 5;
$conf['compose']['reply_limit'] = 200000;
$conf['hooks']['vinfo'] = false;
$conf['hooks']['postlogin'] = false;
$conf['hooks']['postsent'] = false;
$conf['hooks']['signature'] = false;
$conf['hooks']['trailer'] = false;
$conf['hooks']['fetchmail_filter'] = false;
$conf['hooks']['mbox_redirect'] = false;
$conf['hooks']['mbox_icon'] = false;
$conf['hooks']['spam_bounce'] = false;
$conf['hooks']['msglist_format'] = false;
$conf['hooks']['display_folder'] = false;
$conf['maillog']['use_maillog'] = true;
$conf['sentmail']['params']['threshold'] = 60;
$conf['sentmail']['params']['limit_period'] = 24;
$conf['sentmail']['params']['table'] = 'imp_sentmail';
$conf['sentmail']['params']['driverconfig'] = 'horde';
$conf['sentmail']['driver'] = 'sql';
$conf['tasklist']['use_tasklist'] = true;
$conf['notepad']['use_notepad'] = true;
/* CONFIG END. DO NOT CHANGE ANYTHING IN OR BEFORE THIS LINE. */


More information about the imp mailing list