[horde] Horde 5.2.22 performance issues

Fri Oct 28 20:21:15 UTC 2022

  Quoting Louis-Philippe Allard <lp.allard.1 at gmail.com>:

> Hello maillist,
>
> I am experiencing severe performance issues on a Horde 5.2.22 setup  
> running on Rocky Linux.  Everything is extremely slow (just opening  
> horde takes 30sec or more, creating tasks takes 10-20sec, etc)...   
> This issue started about 6 months ago and AFAIK its getting worst.
>
> PHP 7.2.34 (cli) (built: Nov 15 2021 08:11:05) ( NTS )
>   Copyright (c) 1997-2018 The PHP Group
>   Zend Engine v3.2.0, Copyright (c) 1998-2018 Zend Technologies
>       with Zend OPcache v7.2.34, Copyright (c) 1999-2018, by Zend  
> Technologies
>
> Looking at /var/log/horde.log I see these:
>
>    2022-10-26T10:03:22-04:00 WARN: HORDE PHP ERROR:  
> mysqli::__construct(): php_network_getaddresses: getaddrinfo failed:  
> Name or service not known [pid 1380 on line 135 of  
> "/usr/share/pear/Horde/Db/Adapter/Mysqli.php"]
>    2022-10-26T10:03:22-04:00 WARN: HORDE PHP ERROR:  
> mysqli::__construct(): (HY000/2002): php_network_getaddresses:  
> getaddrinfo failed: Name or service not known [pid 1380 on line 135  
> of "/usr/share/pear/Horde/Db/Adapter/Mysqli.php"]
>    2022-10-26T10:03:22-04:00 EMERG: HORDE Connect failed: (2002)  
> php_network_getaddresses: getaddrinfo failed: Name or service not  
> known [pid 1380 on line 138 of  
> "/usr/share/pear/Horde/Db/Adapter/Mysqli.php"
>
>
> I am not sure what Horde is trying to connect to, or where is the  
> issue.  The Rocky Linux VM on which Horde runs has pretty much free  
> access to the web.  Is it possible to see what address Horde is  
> trying to reach when it complains that "Name or service not  
> known"???  That would help troubleshooting if this is somehow  
> related to firewalling issues,  or MYSQL server issues.....
>
> Then I see these in the logs when I work with Nag, albeit everything  
> seems fine from my POV:
>  
>   2022-10-26T10:10:30-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Horde_Form_Type_country::init($prompt = NULL) should be  
> compatible with Horde_Form_Type_enum::init($values, $prompt = NULL)  
> [pid 1304 on line 0 of "/usr/share/pear/Horde/Form/Type.php"]
>   2022-10-26T10:10:30-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Nag_Form_Task::renderActive() should be compatible with  
> Horde_Form::renderActive($renderer = NULL, $vars = NULL, $action =  
> '', $method = 'get', $enctype = NULL, $focus = true) [pid 1304 on  
> line 17 of "/var/www/html/horde/nag/lib/Form/Task.php"]
>   2022-10-26T10:10:57-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Horde_Form_Type_country::init($prompt = NULL) should be  
> compatible with Horde_Form_Type_enum::init($values, $prompt = NULL)  
> [pid 1305 on line 0 of "/usr/share/pear/Horde/Form/Type.php"]
>   2022-10-26T10:10:57-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Nag_Form_Task::renderActive() should be compatible with  
> Horde_Form::renderActive($renderer = NULL, $vars = NULL, $action =  
> '', $method = 'get', $enctype = NULL, $focus = true) [pid 1305 on  
> line 17 of "/var/www/html/horde/nag/lib/Form/Task.php"]
>   2022-10-26T10:10:57-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Horde_Form_Type_country::init($prompt = NULL) should be  
> compatible with Horde_Form_Type_enum::init($values, $prompt = NULL)  
> [pid 1380 on line 0 of "/usr/share/pear/Horde/Form/Type.php"]
>   2022-10-26T10:10:57-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Nag_Form_Task::renderActive() should be compatible with  
> Horde_Form::renderActive($renderer = NULL, $vars = NULL, $action =  
> '', $method = 'get', $enctype = NULL, $focus = true) [pid 1380 on  
> line 17 of "/var/www/html/horde/nag/lib/Form/Task.php"]
>   2022-10-26T10:11:16-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Horde_Form_Type_country::init($prompt = NULL) should be  
> compatible with Horde_Form_Type_enum::init($values, $prompt = NULL)  
> [pid 1761 on line 0 of "/usr/share/pear/Horde/Form/Type.php"]
>   2022-10-26T10:11:16-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Nag_Form_Task::renderActive() should be compatible with  
> Horde_Form::renderActive($renderer = NULL, $vars = NULL, $action =  
> '', $method = 'get', $enctype = NULL, $focus = true) [pid 1761 on  
> line 17 of "/var/www/html/horde/nag/lib/Form/Task.php"]
>   2022-10-26T10:11:17-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Horde_Form_Type_country::init($prompt = NULL) should be  
> compatible with Horde_Form_Type_enum::init($values, $prompt = NULL)  
> [pid 1303 on line 0 of "/usr/share/pear/Horde/Form/Type.php"]
>   2022-10-26T10:11:17-04:00 WARN: HORDE [nag] PHP ERROR: Declaration  
> of Nag_Form_Task::renderActive() should be compatible with  
> Horde_Form::renderActive($renderer = NULL, $vars = NULL, $action =  
> '', $method = 'get', $enctype = NULL, $focus = true) [pid 1303 on  
> line 17 of "/var/www/html/horde/nag/lib/Form/Task.php"]
>
> Same type of errors with Whups:
>
>    2022-10-26T10:18:04-04:00 WARN: HORDE [whups] PHP ERROR:  
> Declaration of Horde_Form_Type_country::init($prompt = NULL) should  
> be compatible with Horde_Form_Type_enum::init($values, $prompt =  
> NULL) [pid 1380 on line 0 of "/usr/share/pear/Horde/Form/Type.php"]
>    2022-10-26T10:18:33-04:00 WARN: HORDE [whups] PHP ERROR:  
> Declaration of Horde_Form_Type_country::init($prompt = NULL) should  
> be compatible with Horde_Form_Type_enum::init($values, $prompt =  
> NULL) [pid 1761 on line 0 of "/usr/share/pear/Horde/Form/Type.php"]
>    2022-10-26T10:18:33-04:00 WARN: HORDE [whups] PHP ERROR:  
> Declaration of Whups_Form_Ticket_CreateStepThree::validate(&$vars,  
> $canAutoFill = false) should be compatible with  
> Horde_Form::validate($vars = NULL, $canAutoFill = false) [pid 1761  
> on line 0 of  
> "/var/www/html/horde/whups/lib/Form/Ticket/CreateStepThree.php"]
>    2022-10-26T10:18:36-04:00 WARN: HORDE [whups] PHP ERROR:  
> Declaration of Horde_Form_Type_country::init($prompt = NULL) should  
> be compatible with Horde_Form_Type_enum::init($values, $prompt =  
> NULL) [pid 1307 on line 0 of "/usr/share/pear/Horde/Form/Type.php"]
>    2022-10-26T10:18:36-04:00 WARN: HORDE [whups] PHP ERROR:  
> Declaration of Whups_Form_Ticket_CreateStepThree::validate(&$vars,  
> $canAutoFill = false) should be compatible with  
> Horde_Form::validate($vars = NULL, $canAutoFill = false) [pid 1307  
> on line 0 of  
> "/var/www/html/horde/whups/lib/Form/Ticket/CreateStepThree.php"]
>     
>
>
> Thanks!
>   Louis-Philippe Allard
> lp.allard.1 at gmail.com
> Sent using Horde Groupware on GNU/Linux

Re-hello maillist,

  Following my previous email (see above), I am having a hard time  
with Horde+Gmail.  At this moment, things seem to be fine, but for the  
most part of the day, Horde was extremely slow, often not even logging  
in my Gmail account.

  When this happens I get in my browser (probably due to PHP's max  
execution time):

  Gateway Timeout
  The gateway did not receive a timely response from the upstream  
server or application.

  or if I manage to login to Horde webinterface I see red tags with  
"Error communicating with the server".

  Looking at /var/log/horde.log I see nothing abnormal other than:

  Mail server denied authentication. [pid 1300 on line 730 of  
"/var/www/html/horde/imp/lib/Imap.php"]

  Which could explain the issues I am having. I tried to proceed by  
elimination to pinpoint the root cause, to no avail...

  Firewall:  deactivated and ran in DMZ.  No improvements.
  MariaDB server issues: server is mostly idling...
  I tried connecting to Gmail with Thunderbird: works flawlessly

  IMAP debug log show issues:

  >> Fri, 28 Oct 2022 12:50:22 -0400
  >> Connection to: imap://imap.gmail.com:993/
  >> Server connection took 0.1599 seconds.
  S: * OK Gimap ready for requests from xx.xx.xx.xx XXXXXXXXXXXXXXXXXXXXx
  C: 1 CAPABILITY
  S: * CAPABILITY IMAP4rev1 UNSELECT IDLE NAMESPACE QUOTA ID XLIST  
CHILDREN X-GM-EXT-1 XYZZY SASL-IR AUTH=XOAUTH2 AUTH=PLAIN  
AUTH=PLAIN-CLIENTTOKEN AUTH=OAUTHBEARER AUTH=XOAUTH
  S: 1 OK Thats all she wrote! XXXXXXXXXXXXXXXXXXXXx
  >> Command 1 took 0.0364 seconds.
  C: 2 AUTHENTICATE PLAIN [INITIAL CLIENT RESPONSE (username: xxxxxxxxxxxxxx)]
  >> Slow Command: 30.03 seconds
  >> ERROR: read/timeout error.
  >> Slow Command: 30.047 seconds
  >> ERROR: read/timeout error.
  S: * CAPABILITY IMAP4rev1 UNSELECT IDLE NAMESPACE QUOTA ID XLIST  
CHILDREN X-GM-EXT-1 UIDPLUS COMPRESS=DEFLATE ENABLE MOVE CONDSTORE  
ESEARCH UTF8=ACCEPT LIST-EXTENDED LIST-STATUS LITERAL- SPECIAL-USE  
APPENDLIMIT=XXXXXXXXXXXX
  S: 2 OK lp.allard.1 at gmail.com authenticated (Success)
  >> Command 2 took 63.6592 seconds.

  Notice the two Error: read/timeout errors..... and the 63 sec  
command run time.... something seems to interfere with traffic..

  Is there an issue with the Horde IMAP libraries (perhaps deprecated  
methods or obsolete libs)?  Does it look like a network/firewall issue  
on my side?  I keep digging in my router's logs and misc alerts,  
nothing comes up with a Google e100 domain....  Actually nothing seems  
to be blocked for traffic orifinating or going to the VPS where Horde  
runs...

Then right now, everything is fine.  Definitely looks like fuc***ry on  
google's side with less secure apps, or my pfsense router is the root  
cause?  Not sure how to further investigate.  If someone has a  
brilliant idea, please share.

Thanks
  Louis-Philippe Allard
lp.allard.1 at gmail.com
Sent using Horde Groupware on GNU/Linux