[imp] remote mail server sieve unable to authentificate

soeren at mindorf-it.de soeren at mindorf-it.de
Tue Oct 20 12:22:01 UTC 2015 

Zitat von Michael J Rubinsky <mrubinsk at horde.org>:

[...]

> Either the hook isn't being run, the webserver can't write to /tmp  
> folder, or it's actually writing elsewhere. Try using  
> Horde::log($GLOBALS['registry']->getAuth(), 'ERR') which will log to  
> your normal Horde log as an ERR.

Thanks, here is the log from ingo:

Oct 20 14:16:40 liskov HORDE: [ingo] Load config file (conf.php; app:  
ingo) [pid 14267 on line 109 of  
"/usr/share/php/Horde/Registry/Loadconfig.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] Load config file (hooks.php; app:  
ingo) [pid 14267 on line 109 of  
"/usr/share/php/Horde/Registry/Loadconfig.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0000s)  #012#011SELECT *  
FROM ingo_vacations WHERE vacation_owner =#012#011   
'soeren at domain.tld' [pid 14267 on line 321 of  
"/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0002s)  #012#011SELECT *  
FROM ingo_rules WHERE rule_owner =#012#011  'soeren at domain.tld' ORDER  
BY rule_order [pid 14267 on line 321 of  
"/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0001s)  #012#011DELETE  
FROM horde_tokens WHERE token_timestamp < 1445257000 [pid 14267 on  
line 321 of "/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0001s)  #012#011SELECT  
token_id FROM horde_tokens WHERE token_address =#012#011   
'OTEuMTA2LjEzMS42OA==' AND token_id = 'arqdyklPhAd2VcWBu3adX_Fd0vE'  
[pid 14267 on line 321 of "/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0282s)  #012#011INSERT  
INTO horde_tokens (token_address, token_id, token_timestamp)#012#011   
VALUES ('OTEuMTA2LjEzMS42OA==', 'arqdyklPhAd2VcWBu3adX_Fd0vE',#012#011  
  1445343400) [pid 14267 on line 321 of  
"/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0234s)  #012#011UPDATE  
ingo_vacations SET vacation_addresses =#012#011  'soeren at domain.tld',  
vacation_subject = 'test',#012#011  vacation_reason = 'test',  
vacation_days = 7, vacation_start =#012#011  1445904000, vacation_end  
= 1445990400, vacation_excludes = '',#012#011  vacation_ignorelists =  
1 WHERE vacation_owner =#012#011  'soeren at domain.tld' [pid 14267 on  
line 321 of "/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0243s)  #012#011UPDATE  
ingo_rules SET rule_name = 'Vacation', rule_action = 8,#012#011   
rule_value = NULL, rule_flags = 0, rule_conditions = NULL,#012#011   
rule_combine = 0, rule_stop = 0, rule_active = 1, rule_order = 2  
WHERE#012#011  rule_id = 2 AND rule_owner = 'soeren at domain.tld' [pid  
14267 on line 321 of "/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0000s)  #012#011SELECT  
list_address FROM ingo_lists WHERE list_owner =#012#011   
'soeren at domain.tld' AND list_blacklist = 0 [pid 14267 on line 321 of  
"/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] SQL  (0,0000s)  #012#011SELECT  
list_address FROM ingo_lists WHERE list_owner =#012#011   
'soeren at domain.tld' AND list_blacklist = 1 [pid 14267 on line 321 of  
"/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:40 liskov HORDE: [ingo] Hook transport_auth in  
application ingo called. [pid 14267 on line 58 of  
"/usr/share/php/Horde/Core/Hooks.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "IMPLEMENTATION" "Dovecot  
Pigeonhole" [pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "SIEVE" "fileinto reject  
envelope encoded-character vacation subaddress  
comparator-i;ascii-numeric relational regex imap4flags copy include  
variables body enotify environment mailbox date ihave" [pid 14267 on  
line 105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "NOTIFY" "mailto" [pid 14267  
on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "SASL" "PLAIN LOGIN" [pid  
14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "STARTTLS" [pid 14267 on line  
105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "VERSION" "1.0" [pid 14267 on  
line 105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: OK "Dovecot ready." [pid 14267  
on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] C: CAPABILITY [pid 14267 on line  
105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "IMPLEMENTATION" "Dovecot  
Pigeonhole" [pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "SIEVE" "fileinto reject  
envelope encoded-character vacation subaddress  
comparator-i;ascii-numeric relational regex imap4flags copy include  
variables body enotify environment mailbox date ihave" [pid 14267 on  
line 105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "NOTIFY" "mailto" [pid 14267  
on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "SASL" "PLAIN LOGIN" [pid  
14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "STARTTLS" [pid 14267 on line  
105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "VERSION" "1.0" [pid 14267 on  
line 105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: OK "Capability completed."  
[pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] C: STARTTLS [pid 14267 on line  
105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: OK "Begin TLS negotiation  
now." [pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] STARTTLS negotiation successful  
[pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "IMPLEMENTATION" "Dovecot  
Pigeonhole" [pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "SIEVE" "fileinto reject  
envelope encoded-character vacation subaddress  
comparator-i;ascii-numeric relational regex imap4flags copy include  
variables body enotify environment mailbox date ihave" [pid 14267 on  
line 105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "NOTIFY" "mailto" [pid 14267  
on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "NOTIFY" "mailto" [pid 14267  
on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "SASL" "PLAIN LOGIN" [pid  
14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: "VERSION" "1.0" [pid 14267 on  
line 105 of "/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] S: OK "Capability completed."  
[pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:50 liskov HORDE: [ingo] C: AUTHENTICATE "PLAIN"  
"c29lcmVuAHNvZXJlbgA4d3pCYWVxYVVD" [pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:52 liskov HORDE: [ingo] S: NO "Authentication failed."  
[pid 14267 on line 105 of  
"/usr/share/php/www/horde/ingo/lib/Transport/Timsieved.php"]
Oct 20 14:16:52 liskov HORDE: [ingo] SQL  (0,0003s)  #012#011SELECT  
alarm_id, alarm_uid, alarm_start, alarm_end, alarm_methods,#012#011   
alarm_params, alarm_title, alarm_text, alarm_snooze,  
alarm_internal#012#011  FROM horde_alarms WHERE alarm_dismissed = 0  
AND ((alarm_snooze IS NULL#012#011  AND alarm_start <= '2015-10-20  
12:16:52') OR alarm_snooze <=#012#011  '2015-10-20 12:16:52') AND  
(alarm_end IS NULL OR alarm_end >=#012#011  '2015-10-20 12:16:52') AND  
(alarm_uid IS NULL OR alarm_uid = '' OR#012#011  alarm_uid =  
'soeren at domain.tld') ORDER BY alarm_start,#012#011  alarm_end [pid  
14267 on line 321 of "/usr/share/php/Horde/Db/Adapter/Mysqli.php"]
Oct 20 14:16:52 liskov HORDE: [ingo] Max memory usage: 36962304 bytes  
[pid 14267 on line 609 of "/usr/share/php/Horde/Registry.php"]

I hope that helps.

Kind regards,
Soeren

More information about the imp mailing list