Forums / General / Weird hanging on admin login due to ezfind load

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?

eZ debug

Timing: Jan 17 2025 23:59:53
Script start
Timing: Jan 17 2025 23:59:53
Module start 'content'
Timing: Jan 17 2025 23:59:54
Module end 'content'
Timing: Jan 17 2025 23:59:54
Script end

Main resources:

Total runtime1.0746 sec
Peak memory usage4,096.0000 KB
Database Queries191

Timing points:

CheckpointStart (sec)Duration (sec)Memory at start (KB)Memory used (KB)
Script start 0.00000.0057 587.8359180.8203
Module start 'content' 0.00570.8891 768.6563530.3750
Module end 'content' 0.89480.1797 1,299.0313332.9063
Script end 1.0746  1,631.9375 

Time accumulators:

 Accumulator Duration (sec) Duration (%) Count Average (sec)
Ini load
Load cache0.00400.3680210.0002
Check MTime0.00150.1368210.0001
Mysql Total
Database connection0.00080.070310.0008
Mysqli_queries1.003093.33541910.0053
Looping result0.00200.18591890.0000
Template Total1.050397.720.5251
Template load0.00210.194020.0010
Template processing1.048297.539520.5241
Template load and register function0.00020.018010.0002
states
state_id_array0.00050.044610.0005
state_identifier_array0.00060.060120.0003
Override
Cache load0.00170.1602230.0001
Sytem overhead
Fetch class attribute can translate value0.00140.132630.0005
Fetch class attribute name0.00100.093730.0003
XML
Image XML parsing0.00280.261730.0009
class_abstraction
Instantiating content class attribute0.00000.000430.0000
General
dbfile0.00720.6701270.0003
String conversion0.00000.000430.0000
Note: percentages do not add up to 100% because some accumulators overlap

CSS/JS files loaded with "ezjscPacker" during request:

CacheTypePacklevelSourceFiles
CSS0extension/community/design/community/stylesheets/ext/jquery.autocomplete.css
extension/community_design/design/suncana/stylesheets/scrollbars.css
extension/community_design/design/suncana/stylesheets/tabs.css
extension/community_design/design/suncana/stylesheets/roadmap.css
extension/community_design/design/suncana/stylesheets/content.css
extension/community_design/design/suncana/stylesheets/star-rating.css
extension/community_design/design/suncana/stylesheets/syntax_and_custom_tags.css
extension/community_design/design/suncana/stylesheets/buttons.css
extension/community_design/design/suncana/stylesheets/tweetbox.css
extension/community_design/design/suncana/stylesheets/jquery.fancybox-1.3.4.css
extension/bcsmoothgallery/design/standard/stylesheets/magnific-popup.css
extension/sevenx/design/simple/stylesheets/star_rating.css
extension/sevenx/design/simple/stylesheets/libs/fontawesome/css/all.min.css
extension/sevenx/design/simple/stylesheets/main.v02.css
extension/sevenx/design/simple/stylesheets/main.v02.res.css
JS0extension/ezjscore/design/standard/lib/yui/3.17.2/build/yui/yui-min.js
extension/ezjscore/design/standard/javascript/jquery-3.7.0.min.js
extension/community_design/design/suncana/javascript/jquery.ui.core.min.js
extension/community_design/design/suncana/javascript/jquery.ui.widget.min.js
extension/community_design/design/suncana/javascript/jquery.easing.1.3.js
extension/community_design/design/suncana/javascript/jquery.ui.tabs.js
extension/community_design/design/suncana/javascript/jquery.hoverIntent.min.js
extension/community_design/design/suncana/javascript/jquery.popmenu.js
extension/community_design/design/suncana/javascript/jScrollPane.js
extension/community_design/design/suncana/javascript/jquery.mousewheel.js
extension/community_design/design/suncana/javascript/jquery.cycle.all.js
extension/sevenx/design/simple/javascript/jquery.scrollTo.js
extension/community_design/design/suncana/javascript/jquery.cookie.js
extension/community_design/design/suncana/javascript/ezstarrating_jquery.js
extension/community_design/design/suncana/javascript/jquery.initboxes.js
extension/community_design/design/suncana/javascript/app.js
extension/community_design/design/suncana/javascript/twitterwidget.js
extension/community_design/design/suncana/javascript/community.js
extension/community_design/design/suncana/javascript/roadmap.js
extension/community_design/design/suncana/javascript/ez.js
extension/community_design/design/suncana/javascript/ezshareevents.js
extension/sevenx/design/simple/javascript/main.js

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
1pagelayout.tpl<No override>extension/sevenx/design/simple/templates/pagelayout.tplEdit templateOverride template
 Number of times templates used: 9
 Number of unique templates used: 6

Time used to render debug report: 0.0001 secs