Forums / Developer / eZP hangs on loopNodeAssignment method call

eZP hangs on loopNodeAssignment method call

Author Message

Marko Žmak

Friday 23 November 2007 2:24:33 am

I have installed eZP 3.10.0 on one of my sites, set it up, and everything was working OK until few days ago. The site was fully functional and users were using it without problems.

But few days ago a serious problem started to occur. Users couldn't publish the objects, eZ just hangs up with a blank page when user clicks the publish button. So I tried to publish some object from the admin interface and I found out that I cannot publish any object (of any class), and also cannot add new locations to existing objects.

Then I did some debugging in eZP source code and found out that eZP hangs on calling this line:

 return call_user_func_array( array( $classObject, $methodName ), $parameterArray );

in "lib/ezutils/classes/ezmoduleoperationinfo.php" file in function:

function callClassMethod( $methodName, &$classObject, $parameterArray )

and it blocks when it calls the "loopNodeAssignment" method of "ezcontentoperationcollection" object.

This is how I did the debug... I added some debugging messages in "callClassMethod":

    function callClassMethod( $methodName, &$classObject, $parameterArray )
    {

eZDebug::writeError("callClassMethod: " . $methodName, "DEBUGGING");


        if ( $this->UseOldCall )
        {
            return call_user_method_array( $methodName, $classObject, $parameterArray );
        }
        else
        {

eZDebug::writeError("callClassMethod (NOT OLD): " . $methodName . "\n" .
print_r($classObject, true) . "\n" .
print_r($parameterArray, true),
"DEBUGGING");

            return call_user_func_array( array( $classObject, $methodName ), $parameterArray );
        }

    }

and I have also inserted:

eZDebug::Error("loopNodeAssignment: Obj: $objectID - Ver: $versionNum", "DEBUGGING");

on the first line of function:

function loopNodeAssignment( $objectID, $versionNum )

in "kernel/content/ezcontentoperationcollection.php" file. Then I tried publishing an object and looked at the "error.log". I found this at the end of "error.log":

[ Nov 23 2007 10:07:40 ] [195.29.90.53] DEBUGGING:
callClassMethod: loopNodeAssignment
[ Nov 23 2007 10:07:40 ] [195.29.90.53] DEBUGGING:
callClassMethod (NOT OLD): loopNodeAssignment
ezcontentoperationcollection Object
(
)

Array
(
    [0] => 539
    [1] => 1
)

It is strange, because it looks like it didn't even enter the "loopNodeAssignment" method, since there's no entry in error.log that should have be written by the debug call I inserted at the first line of "loopNodeAssignment" function.

Could someone help me with this one? What could be the problem, and how could I do some more debugging in order to find where's the problem?

--
Nothing is impossible. Not if you can imagine it!

Hubert Farnsworth

Marko Žmak

Wednesday 28 November 2007 7:20:39 am

Could anyone help me with this one?

I tried everything that I could think of and no results...

--
Nothing is impossible. Not if you can imagine it!

Hubert Farnsworth

eZ debug

Timing: Jan 20 2025 23:36:05
Script start
Timing: Jan 20 2025 23:36:05
Module start 'content'
Timing: Jan 20 2025 23:36:05
Module end 'content'
Timing: Jan 20 2025 23:36:05
Script end

Main resources:

Total runtime0.2296 sec
Peak memory usage2,048.0000 KB
Database Queries141

Timing points:

CheckpointStart (sec)Duration (sec)Memory at start (KB)Memory used (KB)
Script start 0.00000.0078 587.8438180.8125
Module start 'content' 0.00780.0098 768.656393.9609
Module end 'content' 0.01760.2119 862.6172525.3750
Script end 0.2295  1,387.9922 

Time accumulators:

 Accumulator Duration (sec) Duration (%) Count Average (sec)
Ini load
Load cache0.00401.7442200.0002
Check MTime0.00160.6882200.0001
Mysql Total
Database connection0.00160.709510.0016
Mysqli_queries0.160469.83781410.0011
Looping result0.00150.65311390.0000
Template Total0.211492.110.2114
Template load0.00080.355010.0008
Template processing0.210591.695010.2105
Override
Cache load0.00060.255110.0006
Sytem overhead
Fetch class attribute can translate value0.00100.419010.0010
XML
Image XML parsing0.00030.116810.0003
General
dbfile0.01566.8121200.0008
String conversion0.00000.003430.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
1pagelayout.tpl<No override>extension/sevenx/design/simple/templates/pagelayout.tplEdit templateOverride template
 Number of times templates used: 1
 Number of unique templates used: 1

Time used to render debug report: 0.0001 secs