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

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 21 2025 02:56:21
Script start
Timing: Jan 21 2025 02:56:21
Module start 'layout'
Timing: Jan 21 2025 02:56:21
Module start 'content'
Timing: Jan 21 2025 02:56:22
Module end 'content'
Timing: Jan 21 2025 02:56:22
Script end

Main resources:

Total runtime0.9503 sec
Peak memory usage4,096.0000 KB
Database Queries52

Timing points:

CheckpointStart (sec)Duration (sec)Memory at start (KB)Memory used (KB)
Script start 0.00000.0054 588.0469151.2266
Module start 'layout' 0.00540.0026 739.273436.6797
Module start 'content' 0.00800.9415 775.9531526.8906
Module end 'content' 0.94950.0008 1,302.843811.8125
Script end 0.9503  1,314.6563 

Time accumulators:

 Accumulator Duration (sec) Duration (%) Count Average (sec)
Ini load
Load cache0.00320.3375160.0002
Check MTime0.00150.1555160.0001
Mysql Total
Database connection0.00100.100510.0010
Mysqli_queries0.904795.2022520.0174
Looping result0.00040.0439500.0000
Template Total0.923397.220.4616
Template load0.00190.194920.0009
Template processing0.921496.958820.4607
Template load and register function0.00010.014210.0001
states
state_id_array0.00080.088910.0008
state_identifier_array0.00120.126420.0006
Override
Cache load0.00150.1570250.0001
Sytem overhead
Fetch class attribute can translate value0.00070.074810.0007
Fetch class attribute name0.00120.130130.0004
XML
Image XML parsing0.00400.419510.0040
class_abstraction
Instantiating content class attribute0.00000.001040.0000
General
dbfile0.00510.5413180.0003
String conversion0.00000.000840.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
2content/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
8content/datatype/view/ezxmltags/paragraph.tpl<No override>extension/ezwebin/design/ezwebin/templates/content/datatype/view/ezxmltags/paragraph.tplEdit templateOverride template
6content/datatype/view/ezxmltags/literal.tpl<No override>extension/community/design/standard/templates/content/datatype/view/ezxmltags/literal.tplEdit templateOverride template
1print_pagelayout.tpl<No override>extension/community/design/community/templates/print_pagelayout.tplEdit templateOverride template
 Number of times templates used: 20
 Number of unique templates used: 6

Time used to render debug report: 0.0001 secs