[horde] phptype errors - Login No Longer Works

Fri Oct 31 15:28:41 UTC 2008

On Thu, Oct 30, 2008 at 02:29:27PM -0400, Michael C. Ibarra wrote:
> On Thu, Oct 30, 2008 at 06:46:32PM +0100, Jan Schneider wrote:
> > Zitat von "Michael C. Ibarra" <ibarra at hawk.com>:
> > 
> > >All;
> > >
> > >I am stil baffled to what is happening, and my log does not appear  
> > >to have had any data injected into it since 4 hours ago, even though  
> > >I've been trying to logon.
> > >
> > >My conf.php has
> > >
> > >$conf['debug_level'] = E_NOTICE;
> > >$conf['log']['name'] = '/tmp/horde.log';
> > >
> > >and before had
> > >
> > >$conf['debug_level'] = E_ALL;
> > >
> > >Does anyone have any ideas as to what may have gone wrong?
> > 
> > E_NOTICE does log events of that error level *only*.
> > 
> 
> Whats strange is that I am not even seeing anyting at all in my horde.log.
> 
> In fact, the only error I am seeing anywhere is in my /tmp/php_error.log 
> It looks like this:
> 
> [30-Oct-2008 14:25:01] PHP Warning:  Module 'readline' already loaded in Unknown on line 0
> 
> I've just confirmed that I do not have SELinux enabled.

Still stuck, I've modified debug_level to E_ALL & ~E_NOTICE & E_STRICT
I've modified PEAR log priority to PEAR_LOG_DEBUG and am getting this in my horde.log:

When I attempt to login, I am only seeing the top portion of the login, "Welcome to Horde" and it contains *no* login box.

my ssl_access_log shows only this:

192.168.1.1 - - [31/Oct/2008:10:33:28 -0400] "GET /horde/login.php HTTP/1.1" 200 764
192.168.1.1 - - [31/Oct/2008:10:33:29 -0400] "GET /horde/js/horde.js HTTP/1.1" 200 4090
192.168.1.1 - - [31/Oct/2008:10:33:29 -0400] "GET /horde/js/prototype.js HTTP/1.1" 200 74946
192.168.1.1 - - [31/Oct/2008:10:33:31 -0400] "GET /horde/js/accesskeys.js HTTP/1.1" 200 1053
192.168.1.1 - - [31/Oct/2008:10:33:31 -0400] "GET /horde/themes/graphics/favicon.ico HTTP/1.1" 200 1150
192.168.1.1 - - [31/Oct/2008:10:33:31 -0400] "GET /horde/themes/bluewhite/screen.css HTTP/1.1" 200 3205
192.168.1.1 - - [31/Oct/2008:10:33:31 -0400] "GET /horde/themes/screen.css HTTP/1.1" 200 1

Here is my /tmp/horde.log

Oct 31 11:20:01 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: smith, horde [pid 2152 on line 148 of "/usr/share/pear/Horde/Prefs/sql.php"]
Oct 31 11:20:01 HORDE [debug] [horde] Hook _prefs_change_hook_last_login in application horde not called. [pid 2152 on line 1687 of "/usr/share/pear/Horde.php"]
Oct 31 11:20:02 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: smith, kronolith [pid 2152 on line 148 of "/usr/share/pear/Horde/Prefs/sql.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] Hook _horde_hook_share_init in application horde not called. [pid 2152 on line 1687 of "/usr/share/pear/Horde.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] SQL Query by DataTree_sql::getByAttributes(): SELECT c.datatree_id, c.datatree_name FROM horde_datatree c LEFT JOIN horde_datatree_attributes a1 ON a1.datatree_id = c.datatree_id WHERE c.group_uid = ? AND (a1.attribute_name = ? AND a1.attribute_key = ?)   GROUP BY c.datatree_id, c.datatree_name, c.datatree_order ORDER BY c.datatree_order, c.datatree_name, c.datatree_id, array (
  0 => 'horde.groups',
  1 => 'user',
  2 => 'smith',
) [pid 2152 on line 1249 of "/usr/share/pear/Horde/DataTree/sql.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] SQL Query by DataTree_sql::getByAttributes(): SELECT c.datatree_id, c.datatree_name FROM horde_datatree c LEFT JOIN horde_datatree_attributes a1 ON a1.datatree_id = c.datatree_id LEFT JOIN horde_datatree_attributes a2 ON a2.datatree_id = c.datatree_id WHERE c.group_uid = ? AND (a1.attribute_name = ? AND a1.attribute_value = ?)  AND a2.attribute_name = ?  GROUP BY c.datatree_id, c.datatree_name, c.datatree_order, a2.attribute_value ORDER BY a2.attribute_value ASC, array (
  0 => 'horde.shares.kronolith',
  1 => 'owner',
  2 => 'smith',
  3 => 'name',
) [pid 2152 on line 1214 of "/usr/share/pear/Horde/DataTree/sql.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] SQL Query by DataTree_sql::getByAttributes(): SELECT c.datatree_id, c.datatree_name FROM horde_datatree c LEFT JOIN horde_datatree_attributes a1 ON a1.datatree_id = c.datatree_id LEFT JOIN horde_datatree_attributes a2 ON a2.datatree_id = c.datatree_id WHERE c.group_uid = ? AND (a1.attribute_name = ? AND a1.attribute_key = ? AND a1.attribute_value \& ?)  AND a2.attribute_name = ?  GROUP BY c.datatree_id, c.datatree_name, c.datatree_order, a2.attribute_value ORDER BY a2.attribute_value ASC, array (
  0 => 'horde.shares.kronolith',
  1 => 'perm_users',
  2 => 'smith',
  3 => 2,
  4 => 'name',
) [pid 2152 on line 1214 of "/usr/share/pear/Horde/DataTree/sql.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] SQL Query by DataTree_sql::getByAttributes(): SELECT c.datatree_id, c.datatree_name FROM horde_datatree c LEFT JOIN horde_datatree_attributes a1 ON a1.datatree_id = c.datatree_id LEFT JOIN horde_datatree_attributes a2 ON a2.datatree_id = c.datatree_id WHERE c.group_uid = ? AND (a1.attribute_name = ? AND a1.attribute_value \& ?)  AND a2.attribute_name = ?  GROUP BY c.datatree_id, c.datatree_name, c.datatree_order, a2.attribute_value ORDER BY a2.attribute_value ASC, array (
  0 => 'horde.shares.kronolith',
  1 => 'perm_creator',
  2 => 2,
  3 => 'name',
) [pid 2152 on line 1214 of "/usr/share/pear/Horde/DataTree/sql.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] SQL Query by DataTree_sql::getByAttributes(): SELECT c.datatree_id, c.datatree_name FROM horde_datatree c LEFT JOIN horde_datatree_attributes a1 ON a1.datatree_id = c.datatree_id LEFT JOIN horde_datatree_attributes a2 ON a2.datatree_id = c.datatree_id WHERE c.group_uid = ? AND (a1.attribute_name = ? AND a1.attribute_value \& ?)  AND a2.attribute_name = ?  GROUP BY c.datatree_id, c.datatree_name, c.datatree_order, a2.attribute_value ORDER BY a2.attribute_value ASC, array (
  0 => 'horde.shares.kronolith',
  1 => 'perm_default',
  2 => 2,
  3 => 'name',
) [pid 2152 on line 1214 of "/usr/share/pear/Horde/DataTree/sql.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] SQL Query by DataTree_sql::_loadById(): SELECT datatree_id, datatree_parents FROM horde_datatree WHERE datatree_id IN (2352, 9, 2351, 234) AND group_uid = 'horde.shares.kronolith' ORDER BY datatree_id [pid 2152 on line 340 of "/usr/share/pear/Horde/DataTree/sql.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] SQL Query by DataTree_sql::_loadById(): SELECT datatree_id, datatree_name, datatree_parents, datatree_order FROM horde_datatree WHERE datatree_id IN (?, ?, ?, ?, ?) AND group_uid = ? ORDER BY datatree_id, array (
  0 => 9,
  1 => 0,
  2 => 234,
  3 => 2351,
  4 => 2352,
  5 => 'horde.shares.kronolith',
) [pid 2152 on line 373 of "/usr/share/pear/Horde/DataTree/sql.php"]
Oct 31 11:20:02 HORDE [debug] [kronolith] SQL Query by DataTree_sql::getAttributes(): SELECT datatree_id, attribute_name AS name, attribute_key AS "key", attribute_value AS value FROM horde_datatree_attributes WHERE datatree_id IN (2352, 9, 2351, 234) [pid 2152 on line 1085 of "/usr/share/pear/Horde/DataTree/sql.php"]
Oct 31 11:20:02 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: smith, turba [pid 2152 on line 148 of "/usr/share/pear/Horde/Prefs/sql.php"]
Oct 31 11:20:02 HORDE [debug] [turba] SQL Query by Prefs_sql::store(): SELECT 1 FROM horde_prefs WHERE pref_uid = ? AND pref_name = ? AND pref_scope = ?, values: smith, last_login, horde [pid 2152 on line 210 of "/usr/share/pear/Horde/Prefs/sql.php"]
Oct 31 11:20:02 HORDE [debug] [turba] 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:1225466401;s:4:"host";b:0;}, smith, last_login, horde [pid 2152 on line 244 of "/usr/share/pear/Horde/Prefs/sql.php"]
[