[horde] horde3.2-alpha + memcached + session tracking

Majeed mabuqu at ilstu.edu
Sat Aug 4 01:07:07 UTC 2007


Hey all,

I'm trying to setup horde to use memcached as my session handler. It 
seems to work fine (i can log in successfully) if I set 
$conf['sessionhandler']['params']['track'] = false;. When memcache 
session tracking is set to true, I can't login. I would love to track 
those memcache sessions.

Here is some version info.
apache: 2.2.3
PHP Version: 5.2.3
Memcached  1.2.0 (from coolstack 
http://cooltools.sunsource.net/coolstack/) built for solaris 10 (sparc)
pear: memcache  2.1.0   stable
horde3.2-alpha

Does anyone know of any compatibility issues with the above versions or 
anything I might be doing wrong?

Sorry about the wall of text below. Figured more info is better than less.

Thanks much for any suggestions,
Majeed














Here is a snip from the horde log. The time frame is the second I click 
on the login button to when the page is finished loading (back at the 
login page since I am not able to login). This is what happens when I 
have session tracking enabled.
***********
Aug 03 19:39:16 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:39:16 HORDE [error] [horde] Error retrieving session data (id 
= 316cb9c184c0ceec4cb137359b406f7f) [on line 88 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:16 HORDE [debug] [horde] Deleted session data (id = 
316cb9c184c0ceec4cb137359b406f7f) [on line 165 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:16 HORDE [debug] [horde] SQL Query by 
Auth_sql::_authenticate(): SELECT * FROM horde_users WHERE user_uid = ? 
[on line 134 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Auth/sql.php"]
Aug 03 19:39:16 HORDE [debug] [horde] SQL Query by 
Prefs_sql::retrieve(): SELECT pref_scope, pref_name, pref_value FROM 
horde_prefs WHERE pref_uid = ? AND pref_scope = ?, values: mabuqu, horde 
[on line 148 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Prefs/sql.php"]
Aug 03 19:39:16 HORDE [debug] [horde] Hook _prefs_change_hook_last_login 
in application horde not called. [on line 1484 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde.php"]
Aug 03 19:39:16 HORDE [notice] [horde] Login success for mabuqu 
[138.87.1.97] to Horde [on line 120 of 
"/usr/local/webmail/horde-3.2-alpha/horde/login.php"]
Aug 03 19:39:16 HORDE [debug] [horde] SQL Query by Prefs_sql::store(): 
SELECT 1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND 
pref_scope = ?, values: mabuqu, last_login, horde [on line 210 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Prefs/sql.php"]
Aug 03 19:39:16 HORDE [debug] [horde] SQL Query by Prefs_sql::store(): 
UPDATE horde_prefs SET pref_value = ? WHERE pref_uid = ? AND pref_name = 
? AND pref_scope = ?, values: 
a:2:{s:4:"time";i:1186187956;s:4:"host";s:11:"138.87.1.97";}, mabuqu, 
last_login, horde [on line 244 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Prefs/sql.php"]
Aug 03 19:39:17 HORDE [debug] [horde] Wrote session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:17 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:39:17 HORDE [debug] [horde] Read session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 93 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:17 HORDE [debug] [horde] Wrote session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:17 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:39:17 HORDE [error] [horde] Error retrieving session data (id 
= 1e1b8983b5ee0ef79042ee4b53b42421) [on line 88 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:17 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:39:17 HORDE [error] [horde] Error retrieving session data (id 
= 1e1b8983b5ee0ef79042ee4b53b42421) [on line 88 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:17 HORDE [debug] [] Guest user does not have READ 
permission for horde [on line 777 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Registry.php"]
Aug 03 19:39:17 HORDE [debug] [] Wrote session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:18 HORDE [debug] [horde] Wrote session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:18 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:39:18 HORDE [error] [horde] Error retrieving session data (id 
= 1e1b8983b5ee0ef79042ee4b53b42421) [on line 88 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:18 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:39:18 HORDE [error] [horde] Error retrieving session data (id 
= 1e1b8983b5ee0ef79042ee4b53b42421) [on line 88 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:18 HORDE [debug] [horde] Wrote session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:18 HORDE [debug] [horde] Wrote session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:18 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:39:18 HORDE [error] [horde] Error retrieving session data (id 
= 1e1b8983b5ee0ef79042ee4b53b42421) [on line 88 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:39:18 HORDE [debug] [horde] Wrote session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]

Then when I disable memcache session tracking, here is what a successful 
login looks like.
***********************
Aug 03 19:43:35 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:43:35 HORDE [error] [horde] Error retrieving session data (id 
= 1e1b8983b5ee0ef79042ee4b53b42421) [on line 88 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:35 HORDE [debug] [horde] Deleted session data (id = 
1e1b8983b5ee0ef79042ee4b53b42421) [on line 165 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:35 HORDE [debug] [horde] SQL Query by 
Auth_sql::_authenticate(): SELECT * FROM horde_users WHERE user_uid = ? 
[on line 134 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Auth/sql.php"]
Aug 03 19:43:35 HORDE [debug] [horde] SQL Query by 
Prefs_sql::retrieve(): SELECT pref_scope, pref_name, pref_value FROM 
horde_prefs WHERE pref_uid = ? AND pref_scope = ?, values: mabuqu, horde 
[on line 148 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Prefs/sql.php"]
Aug 03 19:43:35 HORDE [debug] [horde] Hook _prefs_change_hook_last_login 
in application horde not called. [on line 1484 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde.php"]
Aug 03 19:43:35 HORDE [notice] [horde] Login success for mabuqu 
[138.x.x.x] to Horde [on line 120 of 
"/usr/local/webmail/horde-3.2-alpha/horde/login.php"]
Aug 03 19:43:36 HORDE [debug] [horde] SQL Query by Prefs_sql::store(): 
SELECT 1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND 
pref_scope = ?, values: mabuqu, last_login, horde [on line 210 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Prefs/sql.php"]
Aug 03 19:43:36 HORDE [debug] [horde] SQL Query by Prefs_sql::store(): 
UPDATE horde_prefs SET pref_value = ? WHERE pref_uid = ? AND pref_name = 
? AND pref_scope = ?, values: 
a:2:{s:4:"time";i:1186188215;s:4:"host";s:11:"138.x.x.x";}, mabuqu, 
last_login, horde [on line 244 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Prefs/sql.php"]
Aug 03 19:43:36 HORDE [debug] [horde] Wrote session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:36 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:43:36 HORDE [debug] [horde] Read session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 93 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:36 HORDE [debug] [horde] Wrote session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:36 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:43:36 HORDE [debug] [horde] Read session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 93 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:36 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:43:37 HORDE [debug] [horde] SQL Query by 
Prefs_sql::retrieve(): SELECT pref_scope, pref_name, pref_value FROM 
horde_prefs WHERE pref_uid = ? AND pref_scope = ?, values: mabuqu, imp 
[on line 148 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Prefs/sql.php"]
Aug 03 19:43:37 HORDE [debug] [horde] Wrote session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:37 HORDE [debug] [horde] Read session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 93 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:37 HORDE [debug] [horde] Connected to the following 
memcache servers for memcache SessionHandler:127.0.0.1:12000 [on line 
119 of "/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/Memcache.php"]
Aug 03 19:43:37 HORDE [debug] [horde] Wrote session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:37 HORDE [debug] [horde] Read session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 93 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
Aug 03 19:43:37 HORDE [debug] [horde] Wrote session data (id = 
919e3bdc60fc1f419adc3d24b459ae4f) [on line 136 of 
"/usr/local/webmail/horde-3.2-alpha/horde/lib/Horde/SessionHandler/memcache.php"]
***********************


More information about the horde mailing list