On 07/02/12 11:12, Patrick Ben Koetter wrote:
* Patrick Ben Koetter<users@sogo.nu>:
We're running SOGo for a customer with 25k accounts. Occasionally sogod locks
up and users cannot be served.
In such cases, the log below is from December, 15th 2011, sogod reports it has
been hanging for a while:
Happened again today and people can't login on the Webinterface:
Feb 07 11:47:48 sogod [14423]: [WARN]<0x0x128e6848[WOWatchDogChild]> pid 24369
has been hanging in the same request for 6 minutes
Feb 07 11:48:30 sogod [14423]: [WARN]<0x0x128e6608[WOWatchDogChild]> pid 16354
has been hanging in the same request for 2 minutes
Feb 07 11:48:31 sogod [14423]: [WARN]<0x0x128e75a8[WOWatchDogChild]> pid 17308
has been hanging in the same request for 2 minutes ...
Feb 07 11:51:53 sogod [14423]: [WARN]<0x0x128e6848[WOWatchDogChild]> pid 24369
has been hanging in the same request for 2 minutes
Feb 07 11:52:35 sogod [14423]: [WARN]<0x0x128e6608[WOWatchDogChild]> pid 16354
has been hanging in the same request for 2 minutes
Dec 15 10:27:06 sogod [953]: [WARN]<0x0x1757e948[WOWatchDogChild]> pid 18602
has been hanging in the same request for 7 minutes
Looking for the PID I get log like this:
Dec 15 09:33:35 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:35 sogod [18602]: SOGoRootPage successful login for user
'012345...@example.com ' - expire = -1 grace = -1
Dec 15 09:33:35 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:36 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:36 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:36 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:36 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:37 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:37 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:37 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:37 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:38 sogod [18602]: [WARN]<0x0x1a25f338[UIxEnvelopeAddressFormatter]>
unexpected envelope address:<0x0x1a6b37b8[NGImap4EnvelopeAddress]: mailbox=''
host='MISSING_DOMAIN'>
Dec 15 09:33:39 sogod [18602]: [WARN]<0x0x1a25f338[UIxEnvelopeAddressFormatter]>
unexpected envelope address:<0x0x1a434df8[NGImap4EnvelopeAddress]:>
Dec 15 09:33:43 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:43 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:43 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:43 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:44 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:44 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:44 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:44 sogod [18602]:<0x0x177f6518[SOGoCache]> an error occurred when caching
value for key '012345...@example.com +attributes': "CLIENT ERROR"
Dec 15 09:33:48 sogod [18602]: [ERROR]<0x0x19254068[UIxMailRenderingContext]>
found no viewer for MIME type: application/msword
Dec 15 09:33:50 sogod [18602]: [ERROR]<0x0x186fd538[UIxMailRenderingContext]>
found no viewer for MIME type: application/msword
Dec 15 09:33:51 sogod [18602]: [WARN]<0x0x1a6a5618[UIxEnvelopeAddressFormatter]>
unexpected envelope address:<0x0x1a6c4718[NGImap4EnvelopeAddress]: mailbox=''
host='MISSING_DOMAIN'>
Dec 15 09:33:51 sogod [18602]: [WARN]<0x0x1a6a5618[UIxEnvelopeAddressFormatter]>
unexpected envelope address:<0x0x19cb7598[NGImap4EnvelopeAddress]:>
Dec 15 09:33:51 sogod [18602]: [WARN]<0x0x1a6a5618[UIxEnvelopeAddressFormatter]>
unexpected envelope address:<0x0x1a6a1158[NGImap4EnvelopeAddress]: mailbox=''
host='MISSING_DOMAIN'>
The system is:
- RHEL 5.7
- sogo-1.3.10-1-el5
- memcached-1.4.5-1-el5.rf
- libmemcached-0.34-1
My first idea and blind guess was that memory size for memcache was too small.
Increasing memcache memory seems to have helped a bit, but still sogod locks
up.
What can I do to find out what causes the error?
I don't think my thoughts will help, but you never know.
Firstly have you got memcached set to 127.0.0.1 not localhost? I found
and others too, that you get problems with setting to localhost (why i
dont know)
Secondly, have you all debug logs enabled in config?
Apart from that I've no further suggestions, good luck with it though
Julian
--
users@sogo.nu
https://inverse.ca/sogo/lists
