Horde verweigert periodisch Login


#1

Hallo,
… der Titel sagt alles:
Horde verweigert regelmäßig das Login.
Ich habe des Errorlog auf “DEBUG” gesetzt und finde fogende Fehlermeldungen:

2017-12-29T10:35:28+00:00 INFO: HORDE [imp] FAILED LOGIN for paul@pridt.at (172.17.42.1) (forwarded for [10.0.0.153]) to {imap://mail.pridt.at/} [pid 1754 on line 157 of “/usr/share/horde/imp/lib/Auth.php”]
2017-12-29T10:35:28+00:00 ERR: HORDE [horde] FAILED LOGIN for paul to horde (172.17.42.1) [pid 1754 on line 199 of “/usr/share/horde/login.php”]

Active Sync und CalDav funktionieren ebenfalls nicht.
Imap login über Thunderbird funktioniert jedoch.
Wenn ich Horde über “service docker-app-horde restart” neu start, ist alles wieder ok - bis zum nächsten Mal.

Auffällig sind möglicherweise Sprünge in der Uhrzeit. Etwas vor den obigen Fehlermeldungen fand ich:

2017-12-29T11:35:11+01:00 DEBUG: HORDE [horde] SQL (0,0014s)
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 <= ‘2017-12-29 10:35:11’) OR alarm_snooze <=
‘2017-12-29 10:35:11’) AND (alarm_end IS NULL OR alarm_end >=
‘2017-12-29 10:35:11’) AND (alarm_uid IS NULL OR alarm_uid = ‘’ OR
alarm_uid = ‘paul@pridt.at’) ORDER BY alarm_start, alarm_end [pid 1686 on line 238 of “/usr/share/php/Horde/Db/Adapter/Pdo/Base.php”]
2017-12-29T11:35:11+01:00 DEBUG: HORDE [imp] Load config file (prefs.php; app: imp) [pid 1686 on line 109 of “/usr/share/php/Horde/Registry/Loadconfig.php”]
2017-12-29T11:35:11+01:00 DEBUG: HORDE [imp] Hook prefs_init in application imp called. [pid 1686 on line 58 of “/usr/share/php/Horde/Core/Hooks.php”]
2017-12-29T11:35:11+01:00 DEBUG: HORDE [imp] Hook prefs_init in application imp called. [pid 1686 on line 58 of “/usr/share/php/Horde/Core/Hooks.php”]
2017-12-29T11:35:11+01:00 DEBUG: HORDE [imp] Hook prefs_init in application imp called. [pid 1686 on line 58 of “/usr/share/php/Horde/Core/Hooks.php”]
2017-12-29T11:35:11+01:00 DEBUG: HORDE [horde] Load config file (motd.php; app: horde) [pid 1686 on line 109 of “/usr/share/php/Horde/Registry/Loadconfig.php”]
2017-12-29T11:35:11+01:00 DEBUG: HORDE [horde] Max memory usage: 15466496 bytes [pid 1686 on line 609 of “/usr/share/php/Horde/Registry.php”]
2017-12-29T10:35:17+00:00 DEBUG: HORDE Load config file (conf.php; app: horde) [pid 1751 on line 109 of “/usr/share/php/Horde/Registry/Loadconfig.php”]

Man beachte den Sprung von 11:35 auf 10:35

Bin dankbar für jeden Hinweis!
Paul


#2

Hi,

is this still up-to-date or did you solve your issue?

I would agree the time lapse seems to be somehow strange. As a first step I would try to verify the time/ ntp settings in your domain…

/KNEBB


#3

This is not a real jump in time, but seems like a switch of the timezone …

  • 11:35:11+01:00 means: it’s 11:35 and we are +1 hour ahead of UTC (so UTC is 10:35 right now)
  • 10:35:17+00:00 means it’s 10:35 and we are +0 hours ahead of UTC (so UTC is 10:35 right now, too)

I’m note sure if this is actually causing the problem - might be. It’s just that time/clock sync is critical for e.g. Kerberos authentication, but afaik not for SASL-based authentication against the IMAP server.

Anyway, I would check the auth.log on the server wich is running the IMAP service:

 (forwarded for [10.0.0.153]) to {imap://mail.pridt.at/}

It’s quite likely that there is a corresponding error message which might be more revealing.

Best regards,
Michael Grandjean