[horde] Upgrade to PHP7 finished - mail notification issue in Wicked

Fri Feb 10 17:24:05 UTC 2017

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

> Quoting Torben Dannhauer <torben at dannhauer.info>:
>
>> Hi,
>>
>>
>>
>> I nearly finished my migration of my horde setup to a PHP7 system.
>>
>>
>>
>> However, I'm fighting against a strange error:
>>
>>
>>
>> When editing Wiki pages in Wicked, it saves the changes as expected, but it
>> does not send the notification email. Instead, it throws a red notification
>> "Error connecting to SMTP server."
>>
>> In contrast, composing an sending an email in Imp works as expected.
>>
>> Does Wicked use different mailer settings than the global ones defined in
>> the horde configuration page?
>
> IIRC, ultimately they should both send mail through the mailer  
> returned from the injector, which pulls from the global config  
> (unless overriding it in IMP's config). However, the actual email  
> body may be built using different objects. Any information in the  
> logs? If not, it might help to do a Horde::log($e) in the catch  
> block in Wicked::mail().

The logs horde gave me at DEBUG level were:

tail -f -n0 /tmp-horde/horde.log | grep wicked
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] Load config file  
(conf.php; app: wicked) [pid 817 on line 108 of  
"/usr/share/php/Horde/Registry/Loadconfig.php"]
2017-02-10T17:47:24+01:00 WARN: HORDE [wicked] PHP ERROR: Declaration  
of Wicked_Page_EditPage::getPermissions() should be compatible with  
Wicked_Page::getPermissions($pageName = NULL) [pid 817 on line 0 of  
"/var/www/torben/web/ssl-dannhauer.de/horde/wicked/lib/Page/EditPage.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE 1.  
Wicked_Page::getCurrentPage()  
/var/www/torben/web/ssl-dannhauer.de/horde/wicked/display.php:16
2. Wicked_Page::getPage()  
/var/www/torben/web/ssl-dannhauer.de/horde/wicked/lib/Page.php:206
3. class_exists()  
/var/www/torben/web/ssl-dannhauer.de/horde/wicked/lib/Page.php:224
2017-02-10T17:47:24+01:00 WARN: HORDE [wicked] PHP ERROR: Declaration  
of Wicked_Page_EditPage::preDisplay() should be compatible with  
Wicked_Page::preDisplay($mode, $params) [pid 817 on line 0 of  
"/var/www/torben/web/ssl-dannhauer.de/horde/wicked/lib/Page/EditPage.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE 1.  
Wicked_Page::getCurrentPage()  
/var/www/torben/web/ssl-dannhauer.de/horde/wicked/display.php:16
2. Wicked_Page::getPage()  
/var/www/torben/web/ssl-dannhauer.de/horde/wicked/lib/Page.php:206
3. class_exists()  
/var/www/torben/web/ssl-dannhauer.de/horde/wicked/lib/Page.php:224
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0014s)
         SELECT * FROM wicked_pages WHERE page_name = 'Wiki/Home' [pid  
817 on line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] Connected to the  
following memcache servers:localhost:11211 [pid 817 on line 205 of  
"/usr/share/php/Horde/Memcache.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked,hordeperm_sql_exists_2wicked_t) [pid 817  
on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
817 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0004s)
           lock_scope = 'wicked' AND lock_type = 1 [pid 817 on line  
238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0001s)
         SELECT * FROM wicked_pages WHERE page_name = 'Wiki/Home' [pid  
817 on line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked,hordeperm_sql_exists_2wicked_t) [pid 817  
on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
817 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0001s)
           lock_scope = 'wicked' AND lock_type = 1 [pid 817 on line  
238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0001s)
         SELECT page_id, page_name FROM wicked_pages [pid 817 on line  
238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages:1,hordeperm_sql_exists_2wicked:pages:1_t)  
[pid 817 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
817 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_2wicked:pages,hordeperm_sql_2wicked:pages_t) [pid 817  
on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0006s)
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] Page Wiki/Home saved  
with user agent Mozilla/5.0 (Windows NT 10.0; WOW64; rv:51.0)  
Gecko/20100101 Firefox/51.0 [pid 817 on line 683 of  
"/var/www/torben/web/ssl-dannhauer.de/horde/wicked/lib/Driver/Sql.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL PK and serial  
sequence (0,0017s)
           AND dep.refobjid = 'wicked_history'::regclass [pid 817 on  
line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL PK and custom  
sequence (0,0009s)
           'wicked_history'::regclass AND cons.contype = 'p' AND def.adsrc ~*
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL PK and serial  
sequence (0,0007s)
           AND dep.refobjid = 'wicked_history'::regclass [pid 817 on  
line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL PK and custom  
sequence (0,0006s)
           'wicked_history'::regclass AND cons.contype = 'p' AND def.adsrc ~*
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0227s)
         INSERT INTO wicked_history (page_id, page_name, page_text,
           change_author, change_log FROM wicked_pages WHERE page_name =
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0003s)
         UPDATE wicked_pages SET change_author = 'torben at dannhauer.info',
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
         SELECT * FROM wicked_pages WHERE page_name = 'Wiki/Home' [pid  
817 on line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked,hordeperm_sql_exists_2wicked_t) [pid 817  
on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
817 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
           lock_scope = 'wicked' AND lock_type = 1 [pid 817 on line  
238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0001s)
         SELECT * FROM wicked_pages WHERE page_name = 'Wiki/Home' [pid  
817 on line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked,hordeperm_sql_exists_2wicked_t) [pid 817  
on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
817 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0001s)
           lock_scope = 'wicked' AND lock_type = 1 [pid 817 on line  
238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0055s)
         SELECT * FROM wicked_history WHERE page_name = 'Wiki/Home' AND
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0109s)
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] Lock  
589dee8b-d2cc-441e-9285-7df300000000 cleared successfully. [pid 817 on  
line 449 of  
"/var/www/torben/web/ssl-dannhauer.de/horde/wicked/lib/Page/StandardPage.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] Max memory usage:  
2097152 bytes [pid 817 on line 613 of  
"/usr/share/php/Horde/Registry.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] Load config file  
(conf.php; app: wicked) [pid 32243 on line 108 of  
"/usr/share/php/Horde/Registry/Loadconfig.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0014s)
         SELECT * FROM wicked_pages WHERE page_name = 'Wiki/Home' [pid  
32243 on line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] Connected to the  
following memcache servers:localhost:11211 [pid 32243 on line 205 of  
"/usr/share/php/Horde/Memcache.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked,hordeperm_sql_exists_2wicked_t) [pid  
32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0004s)
           lock_scope = 'wicked' AND lock_type = 1 [pid 32243 on line  
238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0293s)
         UPDATE wicked_pages SET page_hits = page_hits + 1 WHERE page_name =
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
         SELECT page_id, page_name FROM wicked_pages [pid 32243 on  
line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages:1,hordeperm_sql_exists_2wicked:pages:1_t)  
[pid 32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_2wicked:pages,hordeperm_sql_2wicked:pages_t) [pid 32243  
on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0006s)
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages:1,hordeperm_sql_exists_2wicked:pages:1_t)  
[pid 32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages:1,hordeperm_sql_exists_2wicked:pages:1_t)  
[pid 32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages:1,hordeperm_sql_exists_2wicked:pages:1_t)  
[pid 32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages:1,hordeperm_sql_exists_2wicked:p    
       ages:1_t) [pid 32243 on line 232 of  
"/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages:1,hordeperm_sql_exists_2wicked:pages:1_t)  
[pid 32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked]  
Horde_HashTable_Memcache: Retrieved keys  
(hordeperm_sql_exists_2wicked:pages,hordeperm_sql_exists_2wicked:pages_t) [pid  
32243 on line 232 of "/usr/share/php/Horde/HashTable/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0002s)
         SELECT * FROM wicked_attachments WHERE page_id = 1 [pid 32243  
on line 238 of "/usr/share/php/Horde/Db/Adapter/Pdo/Base.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] SQL  (0,0009s)
2017-02-10T17:47:24+01:00 WARN: HORDE [wicked] PHP ERROR: Illegal  
string offset 'url' [pid 32243 on line 74 of  
"/usr/share/php/Horde/Menu.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE 1. Horde_PageOutput->footer()  
/var/www/torben/web/ssl-dannhauer.de/horde/wicked/display.php:153
2017-02-10T17:47:24+01:00 WARN: HORDE [wicked] PHP ERROR:  
array_merge(): Argument #2 is not an array [pid 32243 on line 80 of  
"/usr/share/php/Horde/Menu.php"]
2017-02-10T17:47:24+01:00 DEBUG: HORDE 1. Horde_PageOutput->footer()  
/var/www/torben/web/ssl-dannhauer.de/horde/wicked/display.php:153
2017-02-10T17:47:24+01:00 DEBUG: HORDE [wicked] Max memory usage:  
2097152 bytes [pid 32243 on line 613 of  
"/usr/share/php/Horde/Registry.php"]
^C

nothing really helpful i think..
I'll try that exception handler..

Thanks,
Torben

>
>
>> Thanks for your ideas,
>>
>> Torben
>>
>>
>>
>> --
>> Horde mailing list
>> Frequently Asked Questions: http://horde.org/faq/
>> To unsubscribe, mail: horde-unsubscribe at lists.horde.org
>
>
>
> -- 
> mike
> The Horde Project
> http://www.horde.org
> https://www.facebook.com/hordeproject
> https://www.twitter.com/hordeproject