[Tickets #8385] webdav and _username_hook_[from|to]backend

bugs at horde.org bugs at horde.org
Sat Jun 27 16:26:45 UTC 2009


DO NOT REPLY TO THIS MESSAGE. THIS EMAIL ADDRESS IS NOT MONITORED.

Ticket URL: http://bugs.horde.org/ticket/8385
------------------------------------------------------------------------------
  Ticket             | 8385
  Created By         | adrieder at sbox.tugraz.at
  Summary            | webdav and _username_hook_[from|to]backend
  Queue              | Kronolith
  Version            | FRAMEWORK_3
  Type               | Bug
  State              | Unconfirmed
  Priority           | 2. Medium
  Milestone          |
  Patch              |
  Owners             |
------------------------------------------------------------------------------


adrieder at sbox.tugraz.at (2009-06-27 12:26) wrote:

When using the _username_hook_[from|to]backend hooks for adding and  
removing a realm it seems that the _username_hook_tobackend() hook  
gets not called in all situations when it should. This ends up in  
getting added the realm twice.

This is when trying to access the calender via webdav:
https://myserver/horde/rpc.php/testuser@vrealm/testuser@vrealm.ics?server=vrealm

(As work around I check in the _username_hook_frombackend() hook if  
the realm is already attached, but I think that's not the correct fix  
for this issue)

Here are the debug logs:
Jun 27 16:23:18 HORDE [debug] [horde] Connected to the following  
memcache servers:xxx.xxx.xxx.xxx:11211, xxx.xxx.xxx.xxx:11211,  
xxx.xxx.xxx.xxx:11211, xxx.xxx.xxx.xxx:11211 [pid 18456 on line 127 of  
"/usr/local/lib/php_horde3/Horde/Memcache.php"]
Jun 27 16:23:18 HORDE [debug] [horde] Read session data (id =  
b0nh4kmoe5nccain26inra5c81) [pid 18456 on line 184 of  
"/usr/local/lib/php_horde3/Horde/SessionHandler/memcache.php"]
Jun 27 16:23:18 HORDE [debug] [] fullname hook called [pid 18456 on  
line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:23:18 HORDE [debug] [] from_addr hook called [pid 18456 on  
line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:23:18 HORDE [debug] [horde] fullname hook called [pid 18456  
on line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:23:18 HORDE [debug] [horde] from_addr hook called [pid 18456  
on line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:23:18 HORDE [debug] [horde] fullname hook called [pid 18456  
on line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:23:18 HORDE [debug] [horde] from_addr hook called [pid 18456  
on line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:23:18 HORDE [debug] [horde] Max memory usage: 1048576 bytes  
[pid 18456 on line 339 of  
"/usr/local/lib/php_horde3/Horde/Registry.php"]
Jun 27 16:23:18 HORDE [debug] [horde] Session data unchanged (id =  
b0nh4kmoe5nccain26inra5c81) [pid 18456 on line 253 of  
"/usr/local/lib/php_horde3/Horde/SessionHandler.php"]
Jun 27 16:26:20 HORDE [debug] [horde] Connected to the following  
memcache servers:xxx.xxx.xxx.xxx:11211, xxx.xxx.xxx.xxx:11211,  
xxx.xxx.xxx.xxx:11211, xxx.xxx.xxx.xxx:11211 [pid 18456 on line 127 of  
"/usr/local/lib/php_horde3/Horde/Memcache.php"]
Jun 27 16:26:20 HORDE [debug] [horde] Error retrieving session data  
(id = m83e4hanv7mj7e04afq7jg3ol3) [pid 18456 on line 174 of  
"/usr/local/lib/php_horde3/Horde/SessionHandler/memcache.php"]
Jun 27 16:26:20 HORDE [debug] [] fullname hook called [pid 18456 on  
line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:20 HORDE [debug] [] from_addr hook called [pid 18456 on  
line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:20 HORDE [debug] [horde] fullname hook called [pid 18456  
on line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:20 HORDE [debug] [horde] from_addr hook called [pid 18456  
on line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:20 HORDE [debug] [horde] fullname hook called [pid 18456  
on line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:20 HORDE [debug] [horde] from_addr hook called [pid 18456  
on line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:20 HORDE [debug] [horde] Max memory usage: 1048576 bytes  
[pid 18456 on line 339 of  
"/usr/local/lib/php_horde3/Horde/Registry.php"]
Jun 27 16:26:21 HORDE [debug] [horde] Wrote session data (id =  
m83e4hanv7mj7e04afq7jg3ol3) [pid 18456 on line 232 of  
"/usr/local/lib/php_horde3/Horde/SessionHandler/memcache.php"]
Jun 27 16:26:28 HORDE [debug] [horde] Connected to the following  
memcache servers:xxx.xxx.xxx.xxx:11211, xxx.xxx.xxx.xxx:11211,  
xxx.xxx.xxx.xxx:11211, xxx.xxx.xxx.xxx:11211 [pid 18644 on line 127 of  
"/usr/local/lib/php_horde3/Horde/Memcache.php"]
Jun 27 16:26:28 HORDE [debug] [horde] Error retrieving session data  
(id = 9mpglfvb8e0v984qt923aqkga5) [pid 18644 on line 174 of  
"/usr/local/lib/php_horde3/Horde/SessionHandler/memcache.php"]
Jun 27 16:26:28 HORDE [debug] [] fullname hook called [pid 18644 on  
line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:28 HORDE [debug] [] from_addr hook called [pid 18644 on  
line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:28 HORDE [debug] [horde] fullname hook called [pid 18644  
on line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:28 HORDE [debug] [horde] from_addr hook called [pid 18644  
on line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:28 HORDE [debug] [imp] _username_hook_frombackend called  
for user "testuser - adding "mbox" [pid 18644 on line 278 of  
"/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:28 HORDE [debug] [imp] fullname hook called [pid 18644 on  
line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:28 HORDE [debug] [imp] from_addr hook called [pid 18644  
on line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:28 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:  
testuser at vrealm, horde [pid 18644 on line 148 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:28 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:  
testuser at vrealm, imp [pid 18644 on line 148 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:29 HORDE [debug] [imp] Hook _prefs_change_hook_last_login  
in application horde not called. [pid 18644 on line 1688 of  
"/usr/local/lib/php_horde3/Horde.php"]
Jun 27 16:26:29 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:  
testuser at vrealm, ingo [pid 18644 on line 148 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:30 HORDE [notice] [imp] Login success for testuser  
[84.115.176.156] to {xxx.xxx.xxx.xxx:1430 [imap/notls]} [pid 18644 on  
line 307 of "/usr/local/apache/htdocs/horde3/horde/imp/lib/Session.php"]
Jun 27 16:26:30 HORDE [debug] [horde] _username_hook_frombackend  
called for user "testuser at vrealm - adding "mbox" [pid 18644 on line  
278 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:30 HORDE [debug] [horde] fullname hook called [pid 18644  
on line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:30 HORDE [debug] [horde] from_addr hook called [pid 18644  
on line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:30 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:  
testuser at vrealm@vrealm, horde [pid 18644 on line 148 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:30 HORDE [debug] [horde] fullname hook called [pid 18644  
on line 130 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:30 HORDE [debug] [horde] from_addr hook called [pid 18644  
on line 54 of "/usr/local/apache/htdocs/horde3/horde/config/hooks.php"]
Jun 27 16:26:30 HORDE [debug] [horde] Hook  
_prefs_change_hook_last_login in application horde not called. [pid  
18644 on line 1688 of "/usr/local/lib/php_horde3/Horde.php"]
Jun 27 16:26:30 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:  
testuser at vrealm@vrealm, kronolith [pid 18644 on line 148 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:30 HORDE [debug] [kronolith] Hook _horde_hook_share_init  
in application horde not called. [pid 18644 on line 1688 of  
"/usr/local/lib/php_horde3/Horde.php"]
Jun 27 16:26:31 HORDE [debug] [kronolith] SQL Query by  
Prefs_sql::retrieve(): SELECT pref_scope, pref_name, pref_value FROM  
horde_prefs WHERE pref_uid = ? AND pref_scope = ?, values:  
testuser at vrealm@vrealm, turba [pid 18644 on line 148 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 HORDE [debug] [kronolith] SQL Query by  
Prefs_sql::retrieve(): SELECT pref_scope, pref_name, pref_value FROM  
horde_prefs WHERE pref_uid = ? AND pref_scope = ?, values:  
testuser at vrealm@vrealm, nag [pid 18644 on line 148 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 HORDE [debug] [nag] Hook _horde_hook_share_init in  
application horde not called. [pid 18644 on line 1688 of  
"/usr/local/lib/php_horde3/Horde.php"]
Jun 27 16:26:31 HORDE [debug] [horde] Max memory usage: 11272192 bytes  
[pid 18644 on line 339 of  
"/usr/local/lib/php_horde3/Horde/Registry.php"]
Jun 27 16:26:31 HORDE [debug] [horde] SQL Query by Prefs_sql::store():  
SELECT 1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND  
pref_scope = ?, values: testuser at vrealm, last_login, imp [pid 18644 on  
line 210 of "/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 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:1246112789;s:4:"host";s:33:"chello084115176156.graz.surfer.at";}, testuser at vrealm, last_login, imp [pid 18644 on line 244 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 HORDE [debug] [horde] SQL Query by Prefs_sql::store():  
SELECT 1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND  
pref_scope = ?, values: testuser at vrealm@vrealm, fullname, horde [pid  
18644 on line 210 of "/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 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: , testuser at vrealm@vrealm, fullname,  
horde [pid 18644 on line 244 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 HORDE [debug] [horde] SQL Query by Prefs_sql::store():  
SELECT 1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND  
pref_scope = ?, values: testuser at vrealm@vrealm, from_addr, horde [pid  
18644 on line 210 of "/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 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: , testuser at vrealm@vrealm, from_addr,  
horde [pid 18644 on line 244 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 HORDE [debug] [horde] SQL Query by Prefs_sql::store():  
SELECT 1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND  
pref_scope = ?, values: testuser at vrealm@vrealm, last_login, horde [pid  
18644 on line 210 of "/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:31 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:1246112790;s:4:"host";s:33:"chello084115176156.graz.surfer.at";}, testuser at vrealm@vrealm, last_login, horde [pid 18644 on line 244 of  
"/usr/local/lib/php_horde3/Horde/Prefs/sql.php"]
Jun 27 16:26:32 HORDE [debug] [horde] Wrote session data (id =  
9mpglfvb8e0v984qt923aqkga5) [pid 18644 on line 232 of  
"/usr/local/lib/php_horde3/Horde/SessionHandler/memcache.php"]







More information about the bugs mailing list