Weird hanging on admin login due to ezfind load

« 
Previous topic
|
General
|
Next topic
 »
Author Message

Olivier Clavel

Tuesday 22 June 2010 12:45:57 am

Hello,

I'm facing a rather weird problem that I've been trying to debug for several days now. The problem only appears on the production server which makes my hunt rather difficult.

If I login to the backoffice with the builtin administrator account (did not test with an other full admin) after some inactivity time (something like a few days), I'm stuck on the login page. Login with an other account is still possible and the front site still answers requests. The server loads rises and java is taking arround 99% of the processing time.

The solr log is filled with this kind of requests:

127.0.0.1 - - [22/06/2010:07:35:01 +0000] "POST /solr/update HTTP/1.1" 200 147
127.0.0.1 - - [22/06/2010:07:35:01 +0000] "POST /solr/update HTTP/1.1" 200 151
127.0.0.1 - - [22/06/2010:07:35:13 +0000] "GET /solr/select?qt=ezfind&elevate-configuration=%3C%3Fxml+version%3D%221.0%22+encoding%3D%22UTF-8%22%3F%3E%0A%3Celevate%2F%3E%0A&wt=php HTTP/1.1" 200 54
127.0.0.1 - - [22/06/2010:07:35:13 +0000] "POST /solr/update HTTP/1.1" 200 147
127.0.0.1 - - [22/06/2010:07:35:13 +0000] "POST /solr/update HTTP/1.1" 200 151
127.0.0.1 - - [22/06/2010:07:35:23 +0000] "GET /solr/select?qt=ezfind&elevate-configuration=%3C%3Fxml+version%3D%221.0%22+encoding%3D%22UTF-8%22%3F%3E%0A%3Celevate%2F%3E%0A&wt=php HTTP/1.1" 200 54
127.0.0.1 - - [22/06/2010:07:35:23 +0000] "POST /solr/update HTTP/1.1" 200 147
127.0.0.1 - - [22/06/2010:07:35:23 +0000] "POST /solr/update HTTP/1.1" 200 151
127.0.0.1 - - [22/06/2010:07:35:36 +0000] "GET /solr/select?qt=ezfind&elevate-configuration=%3C%3Fxml+version%3D%221.0%22+encoding%3D%22UTF-8%22%3F%3E%0A%3Celevate%2F%3E%0A&wt=php HTTP/1.1" 200 54

After diging arround, I could figure out that those request are delete request in solr. The only thing I could get is the objectID. But since the object does not exist anymore, I can't really figure out what was deleted.

Does any body out there has an idea of what could cause such a massive delete on and only on an admin login ?

Important details: We have no custom login handler on this site.

Any help appreciated.

Olivier Clavel - Kaliop Interactive Media
Silver Partner - http://www.kaliop.com

Marco Rogers

Tuesday 04 January 2011 12:09:25 pm

I have this same issue. Running ez 4.2 on redhat 5.4. Solr is on a separate host if that makes a difference. Any ideas?
Powered by eZ Publish™ CMS Open Source Web Content Management. Copyright © 1999-2014 eZ Systems AS (except where otherwise noted). All rights reserved.

eZ debug

Timing: Jan 18 2025 02:05:33
Script start
Timing: Jan 18 2025 02:05:33
Module start 'layout'
Timing: Jan 18 2025 02:05:33
Module start 'content'
Timing: Jan 18 2025 02:05:35
Module end 'content'
Timing: Jan 18 2025 02:05:35
Script end

Main resources:

Total runtime1.0603 sec
Peak memory usage4,096.0000 KB
Database Queries54

Timing points:

CheckpointStart (sec)Duration (sec)Memory at start (KB)Memory used (KB)
Script start 0.00000.0087 588.0469152.6406
Module start 'layout' 0.00870.0037 740.687539.4766
Module start 'content' 0.01241.0465 780.1641525.2813
Module end 'content' 1.05890.0014 1,305.445312.2813
Script end 1.0603  1,317.7266 

Time accumulators:

 Accumulator Duration (sec) Duration (%) Count Average (sec)
Ini load
Load cache0.00350.3276160.0002
Check MTime0.00130.1213160.0001
Mysql Total
Database connection0.00220.205910.0022
Mysqli_queries1.003494.6303540.0186
Looping result0.00060.0606520.0000
Template Total1.024696.620.5123
Template load0.00230.218520.0012
Template processing1.022296.407820.5111
Template load and register function0.00020.022910.0002
states
state_id_array0.00090.082010.0009
state_identifier_array0.00080.075420.0004
Override
Cache load0.00180.1660230.0001
Sytem overhead
Fetch class attribute can translate value0.00070.061820.0003
Fetch class attribute name0.00160.146430.0005
XML
Image XML parsing0.00250.231620.0012
class_abstraction
Instantiating content class attribute0.00000.000730.0000
General
dbfile0.00250.2340160.0002
String conversion0.00000.000940.0000
Note: percentages do not add up to 100% because some accumulators overlap

Templates used to render the page:

UsageRequested templateTemplateTemplate loadedEditOverride
1node/view/full.tplfull/forum_topic.tplextension/sevenx/design/simple/override/templates/full/forum_topic.tplEdit templateOverride template
1content/datatype/view/ezimage.tpl<No override>extension/sevenx/design/simple/templates/content/datatype/view/ezimage.tplEdit templateOverride template
2content/datatype/view/ezxmltext.tpl<No override>extension/community_design/design/suncana/templates/content/datatype/view/ezxmltext.tplEdit templateOverride template
3content/datatype/view/ezxmltags/paragraph.tpl<No override>extension/ezwebin/design/ezwebin/templates/content/datatype/view/ezxmltags/paragraph.tplEdit templateOverride template
1content/datatype/view/ezxmltags/line.tpl<No override>design/standard/templates/content/datatype/view/ezxmltags/line.tplEdit templateOverride template
1print_pagelayout.tpl<No override>extension/community/design/community/templates/print_pagelayout.tplEdit templateOverride template
 Number of times templates used: 9
 Number of unique templates used: 6

Time used to render debug report: 0.0002 secs