Horde login problem

Sun Nov 8 14:25:54 CET 2009

Gunnar Wrobel wrote:

> Quoting Carsten Burghardt <carsten at cburghardt.com>:
> 
>> Hi Johannes,
>>
>> Zitat von Johannes Graumann <johannes_graumann at web.de>:
>>
>>> Hi,
>>>
>>> My setup (2.2.2) is cruising along, but horde suddenly gives me trouble.
>>> I can login fine, but all I will see is the upper Toolbar.
> 
> This sounds like there is a problem with cached data. Do you have any
> errors in  /kolab/var/kolab/www/client/log/php-errors.log?

Here's this morning's try - same error. See the php-errors.log excerpt 
below 
... thanks for your help.

Joh

Prompt:/kolab# date
Fri Nov  6 09:58:08 CET 2009
Prompt:/kolab# find -mmin -2
./var/kolab/www/client/log/horde.log
./var/kolab/www/client/log/php-errors.log
./var/kolab/www/client/storage
./var/kolab/www/client/storage/horde.db
./var/kolab/www/client/storage/johannes.graumann at graumannschaft.org.prefs
./var/kolab/www/client/tmp
./var/kolab/www/client/tmp/sess_3016885fd41653c82f32f19bbcdfac0a
./var/kolab/www/client/tmp/kolab_cache53e4259e4bbec4a006266e3b9a240651
./var/kolab/www/client/tmp/kolab_cache3122828096ac8b51d5a5ebe3714531bb
./var/apache/log/apache-access.log
./var/sasl/log/saslauthd.log
./var/openldap/openldap-data/__db.004
./var/openldap/openldap-data/__db.002
./var/openldap/openldap-data/__db.003
./var/openldap/openldap-data/__db.006
./var/openldap/openldap-data/__db.005
./var/clamav/clamd.log
./var/imapd/proc
./var/imapd/log/imapd.log
./var/imapd/log/cyrmaster.log
./var/imapd/domain/g/graumannschaft.org/user/j/johannes^graumann.seen
./var/imapd/db/__db.004
./var/imapd/db/__db.002
./var/imapd/db/__db.003
./var/imapd/db/__db.001
./var/imapd/db/__db.006
./var/imapd/db/__db.005

Excerpt ./var/kolab/www/client/log/php-errors.log

[06-Nov-2009 09:58:20] PHP Warning:  DOMDocument::loadXML() [<a 
href='domdocument.loadxml'>domdocument.loadxml</a>]: Start tag expected, 
'<' not found in Entity, line: 2 in 
/kolab/var/kolab/www/client/lib/Horde/DOM.php on line 137
[06-Nov-2009 09:58:20] PHP Fatal error:  Call to a member function 
hasChildNodes() on a non-object in 
/kolab/var/kolab/www/client/lib/Horde/DOM.php on line 372
[06-Nov-2009 09:58:21] PHP Warning:  DOMDocument::loadXML() [<a 
href='domdocument.loadxml'>domdocument.loadxml</a>]: Start tag expected, 
'<' not found in Entity, line: 2 in 
/kolab/var/kolab/www/client/lib/Horde/DOM.php on line 137

Excerpt ./var/kolab/www/client/log/horde.log

Nov 06 09:58:12 HORDE [debug] [imp] Hook _prefs_change_hook_last_login in 
application horde not called. [pid 25859 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:12 HORDE [debug] [imp] Hook 
_prefs_change_hook_expanded_folders 
in application horde not called. [pid 25859 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
... LOTS OF COPIES ...
Nov 06 09:58:13 HORDE [notice] [imp] Login success for 
johannes.graumann at graumannschaft.org [141.61.32.61] to 
{h1616679.stratoserver.net:143 [imap/notls/novalidate-cert]} [pid 25859 on 
line 304 of "/kolab/var/kolab/www/client/imp/lib/Session.php"]
Nov 06 09:58:13 HORDE [debug] [imp] Hook 
_prefs_change_hook_last_maintenance 
in application horde not called. [pid 25859 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:13 HORDE [debug] [imp] Max memory usage: 11272192 bytes [pid 
25859 on line 339 of "/kolab/var/kolab/www/client/lib/Horde/Registry.php"]
Nov 06 09:58:13 HORDE [debug] [imp] IMAP errors: SECURITY PROBLEM: insecure 
server advertised AUTH=PLAIN SECURITY PROBLEM: insecure server advertised 
AUTH=PLAIN [pid 25859 on line 175 of 
"/kolab/var/kolab/www/client/imp/lib/IMAP.php"]
Nov 06 09:58:15 HORDE [debug] [horde] Max memory usage: 5242880 bytes [pid 
25859 on line 339 of "/kolab/var/kolab/www/client/lib/Horde/Registry.php"]
Nov 06 09:58:15 HORDE [debug] [horde] Max memory usage: 3932160 bytes [pid 
25864 on line 339 of "/kolab/var/kolab/www/client/lib/Horde/Registry.php"]
Nov 06 09:58:15 HORDE [debug] [turba] Hook _horde_hook_share_init in 
application horde not called. [pid 30686 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:19 HORDE [debug] [turba] Hook 
_prefs_change_hook_turba_maintenance_tasks in application horde not called. 
[pid 30686 on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [kronolith] Hook _horde_hook_share_init in 
application horde not called. [pid 30686 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [kronolith] Hook 
_prefs_change_hook_display_remote_cals in application horde not called. 
[pid 
30686 on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [nag] Hook _horde_hook_share_init in 
application horde not called. [pid 30686 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [nag] Hook 
_prefs_change_hook_display_tasklists in application horde not called. [pid 
30686 on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [kronolith] Hook 
_prefs_change_hook_display_external_cals in application horde not called. 
[pid 30686 on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [kronolith] Hook 
_prefs_change_hook_display_cals in application horde not called. [pid 30686 
on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [kronolith] Max memory usage: 15990784 bytes 
[pid 30686 on line 339 of 
"/kolab/var/kolab/www/client/lib/Horde/Registry.php"]
Nov 06 09:58:20 HORDE [debug] [kronolith] IMAP errors: SECURITY PROBLEM: 
insecure server advertised AUTH=PLAIN SECURITY PROBLEM: insecure server 
advertised AUTH=PLAIN Mailbox does not exist Mailbox does not exist [pid 
30686 on line 175 of "/kolab/var/kolab/www/client/imp/lib/IMAP.php"]
Nov 06 09:58:20 HORDE [debug] [imp] Max memory usage: 4980736 bytes [pid 
25859 on line 339 of "/kolab/var/kolab/www/client/lib/Horde/Registry.php"]
Nov 06 09:58:20 HORDE [debug] [nag] Hook _horde_hook_share_init in 
application horde not called. [pid 25864 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [nag] Hook 
_prefs_change_hook_display_tasklists in application horde not called. [pid 
25864 on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [mnemo] Hook _horde_hook_share_init in 
application horde not called. [pid 25864 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:20 HORDE [debug] [mnemo] Hook 
_prefs_change_hook_display_notepads in application horde not called. [pid 
25864 on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:21 HORDE [debug] [kronolith] Hook _horde_hook_share_init in 
application horde not called. [pid 25864 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:21 HORDE [debug] [kronolith] Hook 
_prefs_change_hook_display_remote_cals in application horde not called. 
[pid 
25864 on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:21 HORDE [debug] [kronolith] Hook 
_prefs_change_hook_display_external_cals in application horde not called. 
[pid 25864 on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:21 HORDE [debug] [kronolith] Hook 
_prefs_change_hook_display_cals in application horde not called. [pid 25864 
on line 1683 of "/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:21 HORDE [debug] [kronolith] Hook 
_prefs_change_hook_last_kronolith_maintenance in application horde not 
called. [pid 25864 on line 1683 of 
"/kolab/var/kolab/www/client/lib/Horde.php"]
Nov 06 09:58:21 HORDE [debug] [kronolith] Max memory usage: 15204352 bytes 
[pid 25864 on line 339 of 
"/kolab/var/kolab/www/client/lib/Horde/Registry.php"]
Nov 06 09:58:21 HORDE [debug] [kronolith] IMAP errors: SECURITY PROBLEM: 
insecure server advertised AUTH=PLAIN SECURITY PROBLEM: insecure server 
advertised AUTH=PLAIN [pid 25864 on line 175 of 
"/kolab/var/kolab/www/client/imp/lib/IMAP.php"]