-->
These old forums are deprecated now and set to read-only. We are waiting for you on our new forums!
More modern, Discourse-based and with GitHub/Google/Twitter authentication built-in.

All times are UTC - 5 hours [ DST ]



Forum locked This topic is locked, you cannot edit posts or make further replies.  [ 3 posts ] 
Author Message
 Post subject: Using fetch=join throws LazyInitializationException
PostPosted: Thu Apr 20, 2006 10:23 pm 
Regular
Regular

Joined: Wed Jul 27, 2005 2:33 am
Posts: 118
Hi,
We are using Hibernate-3.1.2 as MBean in JBoss-3.2.3.
We have a simple parent-child relationship mapped as follows:

--------
Parent:
--------


Code:
<hibernate-mapping default-lazy="false">

<class
     name="com.dbdata.processingengine.hibernateobject.CrProcessingEngine"
     table="CR_PROCESSING_ENGINE"
     discriminator-value="-1"
      lazy="false"
>
<cache usage="transactional" />
     <composite-id name="id" class="com.dbdata.processingengine.hibernateobject.CrProcessingEngineId">
         <key-property
             name="id"
             column="PROCESSING_ENGINE_ID"
             
         >
         </key-property>
         <key-property
             name="versionId"
             column="PROCESSING_ENGINE_VERSION_ID"
             
         >
         </key-property>
         
    </composite-id>   
   
   
    <!-- Child collection -->
    <set
                name="crExecutionLists"
                lazy="false"
                inverse="true"
               cascade="all-delete-orphan"
          optimistic-lock="false"
          
           >
           
           <cache usage="transactional" />
           
           <key
           >
           <column
                name="PROCESSING_ENGINE_ID"
           />
           <column
                name="PROCESSING_ENGINE_VERSION_ID"
           />
           </key>
   
           <one-to-many
              class="com.dbdata.processingengine.hibernateobject.CrExecutionList"
           />
   
       </set>
       
       </class>
</hibernate-mapping>


-------
Child:
-------


Code:
<hibernate-mapping default-lazy="false">

<class
    name="com.dbdata.processingengine.hibernateobject.CrExecutionList"
    table="CR_EXECUTION_LIST"
      lazy="false"
>
<cache usage="transactional" />
    <composite-id name="id" class="com.dbdata.processingengine.hibernateobject.CrExecutionListId">
        <key-property
            name="id"
            column="PROCESSING_ENGINE_ID"
           
        >
        </key-property>
        <key-property
            name="versionId"
            column="PROCESSING_ENGINE_VERSION_ID"

        >
        </key-property>
        <key-property
            name="executionListId"
            column="EXECUTION_LIST_ID"
           
        >
        </key-property>
    </composite-id>   
   
   
       <many-to-one
            name="crProcessingEngine"
            class="com.dbdata.processingengine.hibernateobject.CrProcessingEngine"
            not-null="true"
            update="false"
            insert="false"
          lazy="false"
          fetch="join"
        >
            <column name="PROCESSING_ENGINE_ID" />
            <column name="PROCESSING_ENGINE_VERSION_ID" />
        </many-to-one>
   
    </class>
</hibernate-mapping>
       


Everything works fine. However, during performance testing of our application, we felt that we will
have to specify "fetch=join" to reduce the number of queries. So we added the fetch=join on the child collection
in the parent's one-to-many mapping and also added the fetch=join on the many-to-one mapping from
the child end, as follows:
-----------
Parent hbm:
-----------

Code:
<set
                 name="crExecutionLists"
                 lazy="false"
                 inverse="true"
                cascade="all-delete-orphan"
           optimistic-lock="false"
           fetch=join
            >
           
            <cache usage="transactional" />
           
            <key
            >
            <column
                 name="PROCESSING_ENGINE_ID"
            />
            <column
                 name="PROCESSING_ENGINE_VERSION_ID"
            />
            </key>
     
            <one-to-many
               class="com.dbdata.processingengine.hibernateobject.CrExecutionList"
            />
     
       </set>


----------
Child hbm:
----------

Code:
<many-to-one
             name="crProcessingEngine"
             class="com.dbdata.processingengine.hibernateobject.CrProcessingEngine"
             not-null="true"
             update="false"
             insert="false"
           lazy="false"
           fetch="join"
         >
             <column name="PROCESSING_ENGINE_ID" />
             <column name="PROCESSING_ENGINE_VERSION_ID" />
        </many-to-one>
       


However, when we tested our application, it started throwing the following exception:

Quote:
05:09:58,531 ERROR [LazyInitializationException] failed to lazily initialize a collection, no session or session was closed
org.hibernate.LazyInitializationException: failed to lazily initialize a collection, no session or session was closed
at org.hibernate.collection.AbstractPersistentCollection.throwLazyInitializationException(AbstractPersistentCollection.java:358)
at org.hibernate.collection.AbstractPersistentCollection.throwLazyInitializationExceptionIfNotConnected(AbstractPersistentCollection.java:350)
at org.hibernate.collection.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:97)
at org.hibernate.collection.PersistentSet.size(PersistentSet.java:114)
at java.util.ArrayList.<init>(ArrayList.java:132)
at com.dbdata.util.SetWrapper.<init>(SetWrapper.java:38)
at com.dbdata.processingengine.util.OurSetWrapper.<init>(ProcessingEngineSetWrapper.java:60)
at com.dbdata.processingengine.hibernateobject.CrProcessingEngine.setCrExecutionLists(CrProcessingEngine.java:422)
at com.dbdata.processingengine.hibernateobject.GroupProcessingEngine$$BulkBeanByCGLIB$$6d595814.setPropertyValues(<generated>)
at org.hibernate.tuple.PojoEntityTuplizer.setPropertyValuesWithOptimizer(PojoEntityTuplizer.java:212)
at org.hibernate.tuple.PojoEntityTuplizer.setPropertyValues(PojoEntityTuplizer.java:185)
at org.hibernate.persister.entity.AbstractEntityPersister.setPropertyValues(AbstractEntityPersister.java:3232)
at org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:126)
at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:842)
at org.hibernate.loader.Loader.doQuery(Loader.java:717)
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:224)
at org.hibernate.loader.Loader.loadEntity(Loader.java:1785)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:93)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:81)
at org.hibernate.persister.entity.AbstractEntityPersister.load(AbstractEntityPersister.java:2730)
at org.hibernate.event.def.DefaultLoadEventListener.loadFromDatasource(DefaultLoadEventListener.java:365)
at org.hibernate.event.def.DefaultLoadEventListener.doLoad(DefaultLoadEventListener.java:346)
at org.hibernate.event.def.DefaultLoadEventListener.load(DefaultLoadEventListener.java:123)
at org.hibernate.event.def.DefaultLoadEventListener.proxyOrLoad(DefaultLoadEventListener.java:161)
at org.hibernate.event.def.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:87)
at org.hibernate.impl.SessionImpl.fireLoad(SessionImpl.java:891)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:828)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:821)
at com.dbdata.system.persistence.ObjectHandler.getObjectById(ObjectHandler.java:218)
at com.dbdata.system.persistence.ObjectHandler.getObjectById(ObjectHandler.java:192)
at com.dbdata.system.persistence.PersistenceService.getObjectById(PersistenceService.java:91)
at com.dbdata.system.persistence.ejb.PersistenceManagerBean.getObjectById(PersistenceManagerBean.java:203)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:324)
at org.jboss.ejb.StatelessSessionContainer$ContainerInterceptor.invoke(StatelessSessionContainer.java:683)
at org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(CachedConnectionInterceptor.java:185)
at org.jboss.ejb.plugins.StatelessSessionInstanceInterceptor.invoke(StatelessSessionInstanceInterceptor.java:72)
at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(AbstractTxInterceptor.java:84)
at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(TxInterceptorCMT.java:267)
at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(TxInterceptorCMT.java:128)
at org.jboss.ejb.plugins.SecurityInterceptor.invoke(SecurityInterceptor.java:118)
at org.jboss.ejb.plugins.LogInterceptor.invoke(LogInterceptor.java:191)
at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(ProxyFactoryFinderInterceptor.java:122)
at org.jboss.ejb.StatelessSessionContainer.internalInvoke(StatelessSessionContainer.java:331)
at org.jboss.ejb.Container.invoke(Container.java:700)
at org.jboss.ejb.plugins.local.BaseLocalProxyFactory.invoke(BaseLocalProxyFactory.java:375)
at org.jboss.ejb.plugins.local.StatelessSessionProxy.invoke(StatelessSessionProxy.java:83)
at $Proxy234.getObjectById(Unknown Source)
at com.dbdata.ejb.CRMProviderBean.getByObjectId(CRMProviderBean.java:266)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:324)
at org.jboss.ejb.StatelessSessionContainer$ContainerInterceptor.invoke(StatelessSessionContainer.java:683)
at org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(CachedConnectionInterceptor.java:185)
at org.jboss.ejb.plugins.StatelessSessionInstanceInterceptor.invoke(StatelessSessionInstanceInterceptor.java:72)
at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(AbstractTxInterceptor.java:84)
at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(TxInterceptorCMT.java:267)
at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(TxInterceptorCMT.java:128)
at org.jboss.ejb.plugins.SecurityInterceptor.invoke(SecurityInterceptor.java:118)
at org.jboss.ejb.plugins.LogInterceptor.invoke(LogInterceptor.java:191)
at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(ProxyFactoryFinderInterceptor.java:122)
at org.jboss.ejb.StatelessSessionContainer.internalInvoke(StatelessSessionContainer.java:331)
at org.jboss.ejb.Container.invoke(Container.java:700)
at org.jboss.ejb.plugins.local.BaseLocalProxyFactory.invoke(BaseLocalProxyFactory.java:375)
at org.jboss.ejb.plugins.local.StatelessSessionProxy.invoke(StatelessSessionProxy.java:83)
at $Proxy163.getByObjectId(Unknown Source)
at com.dbdata.processingengine.hibernateobject.ProcessingEngineConnection.onLoad(ProcessingEngineConnection.java:557)
at org.hibernate.event.def.DefaultPostLoadEventListener.onPostLoad(DefaultPostLoadEventListener.java:18)
at org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:198)
at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:842)
at org.hibernate.loader.Loader.doQuery(Loader.java:717)
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:224)
at org.hibernate.loader.Loader.loadCollection(Loader.java:1919)
at org.hibernate.loader.collection.CollectionLoader.initialize(CollectionLoader.java:71)
at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:520)
at org.hibernate.event.def.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:60)
at org.hibernate.impl.SessionImpl.initializeCollection(SessionImpl.java:1695)
at org.hibernate.collection.AbstractPersistentCollection.forceInitialization(AbstractPersistentCollection.java:454)
at org.hibernate.engine.StatefulPersistenceContext.initializeNonLazyCollections(StatefulPersistenceContext.java:828)
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:229)
at org.hibernate.loader.Loader.loadEntity(Loader.java:1785)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:93)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:81)
at org.hibernate.persister.entity.AbstractEntityPersister.load(AbstractEntityPersister.java:2730)
at org.hibernate.event.def.DefaultLoadEventListener.loadFromDatasource(DefaultLoadEventListener.java:365)
at org.hibernate.event.def.DefaultLoadEventListener.doLoad(DefaultLoadEventListener.java:346)
at org.hibernate.event.def.DefaultLoadEventListener.load(DefaultLoadEventListener.java:123)
at org.hibernate.event.def.DefaultLoadEventListener.proxyOrLoad(DefaultLoadEventListener.java:161)
at org.hibernate.event.def.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:87)
at org.hibernate.impl.SessionImpl.fireLoad(SessionImpl.java:891)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:828)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:821)


The code in the setCrExecutionLists() method is doing something like the following:

Code:
  void setCrExecutionLists(Set children) {
      this.crExecutionLists = new OurSetWrapper(crExecutionLists);

  }


We have our own wrapper over the Set interface, into which we are wrapping the Set passed onto the
setCrExecutionLists method.

The code works when fetch=join is NOT set. The reason for setting fetch=join was to reduce queries and
improve performance.

Anyone has any ideas for the cause of this exception and any solutions?

Thank you.


Top
 Profile  
 
 Post subject:
PostPosted: Fri Apr 21, 2006 11:09 am 
Regular
Regular

Joined: Wed Jul 27, 2005 2:33 am
Posts: 118
Here's the hibernate logs:

Quote:
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.persistence.ObjectHandler] ObjectHandler.getObjectById(klass, id)
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.persistence.ObjectHandler] ObjectHandler.getObjectById(klass, id, loadLobs): (com.dbdata.processingengine.hibernateobject.CrProcessingEngine,com.dbdata.processingengine.hibernateobject.CrProcessingEngineId[id=103, versionId=1])
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.getHibernateSession()
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.util.ServiceLocator] getHibernateSessionFactory(): Entering...
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.util.ServiceLocator] getHibernateSessionFactory(): seeking cache in cache
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.util.ServiceLocator] getHibernateSessionFactory(): Entering...
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.util.ServiceLocator] getHibernateSessionFactory(): seeking cache in cache
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.getConnFromDataSource() for dataSource:java:/CrmDS
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.getConnFromDataSource returning connection:org.jboss.resource.adapter.jdbc.WrappedConnection@1349d88
2006-04-21 19:42:24,062 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: -92233720368547758082006-04-21 19:42:24,062 DEBUG [org.hibernate.jdbc.JDBCContext] successfully registered Synchronization
2006-04-21 19:42:24,062 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.getHibernateSession returning session:SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2006-04-21 19:42:24,062 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:24,062 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:24,062 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] object not resolved in any cache: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:24,062 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Fetching entity: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:24,062 DEBUG [org.hibernate.loader.Loader] loading entity: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:24,062 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-04-21 19:42:24,078 DEBUG [org.hibernate.SQL] select crprocessi0_.PROCESSING_ENGINE_ID as PROCESSING1_241_1_, crprocessi0_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_241_1_, crprocessi0_.UPDATE_DTTM as UPDATE4_241_1_, crprocessi0_.Name as Name241_1_, crprocessi0_.Description as Descript6_241_1_, crprocessi0_.Status_Cd as Status7_241_1_, crprocessi0_.OWNER_DIVISION_ID as OWNER8_241_1_, crprocessi0_.REFERENCE_TXT as REFERENCE9_241_1_, crprocessi0_.FOLDER_ID as FOLDER10_241_1_, crprocessi0_.SHARED_IND as SHARED11_241_1_, crprocessi0_.SOURCE_ENTITY_ID as SOURCE12_241_1_, crprocessi0_.SOURCE_VERSION_ID as SOURCE13_241_1_, crprocessi0_.Application_Name_Txt as Applica14_241_1_, crprocessi0_.Component_Type_Cd as Component15_241_1_, crprocessi0_.Processing_Type_Cd as Processing3_241_1_, crprocessi0_.Max_Parallel_Streams_Num as Max16_241_1_, crprocessi0_.Allow_One_To_One_Logon_Ind as Allow17_241_1_, crprocessi0_.Tap_Job_Id as Tap18_241_1_, crprocessi0_.Execution_Parameters_Txt as Execution19_241_1_, crprocessi0_.Lock_Cd as Lock20_241_1_, crprocessi0_.Clone_Ind as Clone21_241_1_, crprocessi0_.Externally_Scheduled_Ind as Externally22_241_1_, crprocessi0_.Best_Lead_Order_Cd as Best23_241_1_, crprocessi0_.Channel_Lead_Order_Cd as Channel24_241_1_, crprocessi0_.Default_User as Default25_241_1_, crprocessi0_.Default_Password as Default26_241_1_, crprocessi0_.UPDATE_USER as UPDATE27_241_1_, crprocessi0_.CREATE_USER as CREATE28_241_1_, crprocessi0_.CREATE_DTTM as CREATE29_241_1_, crprocessi0_.ACTIVATE_USER as ACTIVATE30_241_1_, crprocessi0_.ACTIVATE_DTTM as ACTIVATE31_241_1_, crexecutio1_.PROCESSING_ENGINE_ID as PROCESSING1_3_, crexecutio1_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_3_, crexecutio1_.EXECUTION_LIST_ID as EXECUTION3_3_, crexecutio1_.PROCESSING_ENGINE_ID as PROCESSING1_242_0_, crexecutio1_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_242_0_, crexecutio1_.EXECUTION_LIST_ID as EXECUTION3_242_0_, crexecutio1_.UPDATE_DTTM as UPDATE4_242_0_, crexecutio1_.Component_Id as Component5_242_0_, crexecutio1_.Component_Version_Id as Component6_242_0_, crexecutio1_.Component_Type_Cd as Component7_242_0_, crexecutio1_.Application_Sub_Type_Cd as Applicat8_242_0_, crexecutio1_.Priority_Ord as Priority9_242_0_, crexecutio1_.Logon_User as Logon10_242_0_, crexecutio1_.Logon_Password as Logon11_242_0_, crexecutio1_.Tap_Job_Id as Tap12_242_0_, crexecutio1_.Execution_Parameters_Txt as Execution13_242_0_, crexecutio1_.Lock_Cd as Lock14_242_0_, crexecutio1_.Submitted_Ind as Submitted15_242_0_, crexecutio1_.UPDATE_USER as UPDATE16_242_0_ from CR_PROCESSING_ENGINE crprocessi0_ left outer join CR_EXECUTION_LIST crexecutio1_ on crprocessi0_.PROCESSING_ENGINE_ID=crexecutio1_.PROCESSING_ENGINE_ID and crprocessi0_.PROCESSING_ENGINE_VERSION_ID=crexecutio1_.PROCESSING_ENGINE_VERSION_ID where crprocessi0_.PROCESSING_ENGINE_ID=? and crprocessi0_.PROCESSING_ENGINE_VERSION_ID=?
2006-04-21 19:42:24,078 INFO [STDOUT] Hibernate: select crprocessi0_.PROCESSING_ENGINE_ID as PROCESSING1_241_1_, crprocessi0_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_241_1_, crprocessi0_.UPDATE_DTTM as UPDATE4_241_1_, crprocessi0_.Name as Name241_1_, crprocessi0_.Description as Descript6_241_1_, crprocessi0_.Status_Cd as Status7_241_1_, crprocessi0_.OWNER_DIVISION_ID as OWNER8_241_1_, crprocessi0_.REFERENCE_TXT as REFERENCE9_241_1_, crprocessi0_.FOLDER_ID as FOLDER10_241_1_, crprocessi0_.SHARED_IND as SHARED11_241_1_, crprocessi0_.SOURCE_ENTITY_ID as SOURCE12_241_1_, crprocessi0_.SOURCE_VERSION_ID as SOURCE13_241_1_, crprocessi0_.Application_Name_Txt as Applica14_241_1_, crprocessi0_.Component_Type_Cd as Component15_241_1_, crprocessi0_.Processing_Type_Cd as Processing3_241_1_, crprocessi0_.Max_Parallel_Streams_Num as Max16_241_1_, crprocessi0_.Allow_One_To_One_Logon_Ind as Allow17_241_1_, crprocessi0_.Tap_Job_Id as Tap18_241_1_, crprocessi0_.Execution_Parameters_Txt as Execution19_241_1_, crprocessi0_.Lock_Cd as Lock20_241_1_, crprocessi0_.Clone_Ind as Clone21_241_1_, crprocessi0_.Externally_Scheduled_Ind as Externally22_241_1_, crprocessi0_.Best_Lead_Order_Cd as Best23_241_1_, crprocessi0_.Channel_Lead_Order_Cd as Channel24_241_1_, crprocessi0_.Default_User as Default25_241_1_, crprocessi0_.Default_Password as Default26_241_1_, crprocessi0_.UPDATE_USER as UPDATE27_241_1_, crprocessi0_.CREATE_USER as CREATE28_241_1_, crprocessi0_.CREATE_DTTM as CREATE29_241_1_, crprocessi0_.ACTIVATE_USER as ACTIVATE30_241_1_, crprocessi0_.ACTIVATE_DTTM as ACTIVATE31_241_1_, crexecutio1_.PROCESSING_ENGINE_ID as PROCESSING1_3_, crexecutio1_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_3_, crexecutio1_.EXECUTION_LIST_ID as EXECUTION3_3_, crexecutio1_.PROCESSING_ENGINE_ID as PROCESSING1_242_0_, crexecutio1_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_242_0_, crexecutio1_.EXECUTION_LIST_ID as EXECUTION3_242_0_, crexecutio1_.UPDATE_DTTM as UPDATE4_242_0_, crexecutio1_.Component_Id as Component5_242_0_, crexecutio1_.Component_Version_Id as Component6_242_0_, crexecutio1_.Component_Type_Cd as Component7_242_0_, crexecutio1_.Application_Sub_Type_Cd as Applicat8_242_0_, crexecutio1_.Priority_Ord as Priority9_242_0_, crexecutio1_.Logon_User as Logon10_242_0_, crexecutio1_.Logon_Password as Logon11_242_0_, crexecutio1_.Tap_Job_Id as Tap12_242_0_, crexecutio1_.Execution_Parameters_Txt as Execution13_242_0_, crexecutio1_.Lock_Cd as Lock14_242_0_, crexecutio1_.Submitted_Ind as Submitted15_242_0_, crexecutio1_.UPDATE_USER as UPDATE16_242_0_ from CR_PROCESSING_ENGINE crprocessi0_ left outer join CR_EXECUTION_LIST crexecutio1_ on crprocessi0_.PROCESSING_ENGINE_ID=crexecutio1_.PROCESSING_ENGINE_ID and crprocessi0_.PROCESSING_ENGINE_VERSION_ID=crexecutio1_.PROCESSING_ENGINE_VERSION_ID where crprocessi0_.PROCESSING_ENGINE_ID=? and crprocessi0_.PROCESSING_ENGINE_VERSION_ID=?
2006-04-21 19:42:27,203 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2006-04-21 19:42:27,203 DEBUG [org.hibernate.type.IntegerType] binding '103' to parameter: 1
2006-04-21 19:42:27,203 DEBUG [org.hibernate.type.IntegerType] binding '1' to parameter: 2
2006-04-21 19:42:27,328 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2006-04-21 19:42:27,328 DEBUG [org.hibernate.loader.Loader] processing result set
2006-04-21 19:42:27,328 DEBUG [org.hibernate.loader.Loader] result set row: 0
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '103' as column: PROCESSING1_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: PROCESSING2_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '14' as column: EXECUTION3_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Hydrating entity: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.TimestampType] returning '2006-03-22 15:20:15' as column: UPDATE4_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '506' as column: Component5_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: Component6_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ShortType] returning '32' as column: Component7_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ShortType] returning '2' as column: Applicat8_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning null as column: Priority9_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'tapdev' as column: Logon10_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'tapdev' as column: Logon11_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning null as column: Tap12_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning '<map/>' as column: Execution13_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Lock14_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Submitted15_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'tapdev' as column: UPDATE16_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '103' as column: PROCESSING1_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: PROCESSING2_242_0_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 2006-03-22 15:20:15.355
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ShortType] returning '3' as column: Processing3_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.dbdata.processingengine.hibernateobject.CustomProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Hydrating entity: [com.dbdata.processingengine.hibernateobject.CustomProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.TimestampType] returning '2006-03-17 09:40:03' as column: UPDATE4_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'Custom Seg Plan PE' as column: Name241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning '' as column: Descript6_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ShortType] returning '2' as column: Status7_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: OWNER8_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning '' as column: REFERENCE9_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning null as column: FOLDER10_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ByteType] returning null as column: SHARED11_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning null as column: SOURCE12_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning null as column: SOURCE13_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'Segment Plan Processing' as column: Applica14_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ShortType] returning '32' as column: Component15_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ShortType] returning '3' as column: Processing3_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '5' as column: Max16_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Allow17_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning null as column: Tap18_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning null as column: Execution19_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Lock20_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Clone21_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Externally22_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ShortType] returning '0' as column: Best23_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.ShortType] returning '0' as column: Channel24_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'mstrm600bu' as column: Default25_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'baril3' as column: Default26_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'tapadmin' as column: UPDATE27_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning 'tapadmin' as column: CREATE28_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.TimestampType] returning '2006-03-17 09:40:02' as column: CREATE29_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.StringType] returning null as column: ACTIVATE30_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.TimestampType] returning null as column: ACTIVATE31_241_1_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 2006-03-17 09:40:03.123
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '103' as column: PROCESSING1_3_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: PROCESSING2_3_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.engine.CollectionLoadContext] new collection: instantiating
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '103' as column: PROCESSING1_3_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: PROCESSING2_3_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.type.IntegerType] returning '14' as column: EXECUTION3_3_
2006-04-21 19:42:27,328 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 19:42:27,406 DEBUG [org.hibernate.loader.Loader] done processing result set (1 rows)
2006-04-21 19:42:27,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2006-04-21 19:42:27,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-04-21 19:42:27,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2006-04-21 19:42:27,406 DEBUG [org.hibernate.loader.Loader] total objects hydrated: 2

2006-04-21 19:42:27,406 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 19:42:27,406 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,406 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,406 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,406 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 19:42:27,406 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.dbdata.processingengine.hibernateobject.CustomProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,406 DEBUG [org.hibernate.engine.CollectionLoadContext] returning loading collection:[com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,406 ERROR [org.hibernate.LazyInitializationException] failed to lazily initialize a collection, no session or session was closed
org.hibernate.LazyInitializationException: failed to lazily initialize a collection, no session or session was closed
at org.hibernate.collection.AbstractPersistentCollection.throwLazyInitializationException(AbstractPersistentCollection.java:358)
at org.hibernate.collection.AbstractPersistentCollection.throwLazyInitializationExceptionIfNotConnected(AbstractPersistentCollection.java:350)
at org.hibernate.collection.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:97)
at org.hibernate.collection.PersistentSet.size(PersistentSet.java:114)
at java.util.ArrayList.<init>(ArrayList.java:132)
at com.dbdata.util.SetWrapper.<init>(SetWrapper.java:38)
at com.dbdata.processingengine.util.OurSetWrapper.<init>(OurSetWrapper.java:60)
at com.dbdata.processingengine.hibernateobject.CrProcessingEngine.setCrExecutionLists(CrProcessingEngine.java:422)
at com.dbdata.processingengine.hibernateobject.CustomProcessingEngine$$BulkBeanByCGLIB$$3a54ef44.setPropertyValues(<generated>)
at org.hibernate.tuple.PojoEntityTuplizer.setPropertyValuesWithOptimizer(PojoEntityTuplizer.java:212)
at org.hibernate.tuple.PojoEntityTuplizer.setPropertyValues(PojoEntityTuplizer.java:185)
at org.hibernate.persister.entity.AbstractEntityPersister.setPropertyValues(AbstractEntityPersister.java:3232)
at org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:126)
at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:842)
at org.hibernate.loader.Loader.doQuery(Loader.java:717)
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:224)
at org.hibernate.loader.Loader.loadEntity(Loader.java:1785)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:93)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:81)
at org.hibernate.persister.entity.AbstractEntityPersister.load(AbstractEntityPersister.java:2730)
at org.hibernate.event.def.DefaultLoadEventListener.loadFromDatasource(DefaultLoadEventListener.java:365)
at org.hibernate.event.def.DefaultLoadEventListener.doLoad(DefaultLoadEventListener.java:346)
at org.hibernate.event.def.DefaultLoadEventListener.load(DefaultLoadEventListener.java:123)
at org.hibernate.event.def.DefaultLoadEventListener.proxyOrLoad(DefaultLoadEventListener.java:161)
at org.hibernate.event.def.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:87)
at org.hibernate.impl.SessionImpl.fireLoad(SessionImpl.java:891)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:828)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:821)
at com.dbdata.system.persistence.ObjectHandler.getObjectById(ObjectHandler.java:218)
at com.dbdata.system.persistence.ObjectHandler.getObjectById(ObjectHandler.java:192)
at com.dbdata.system.persistence.PersistenceService.getObjectById(PersistenceService.java:91)
at com.dbdata.system.persistence.ejb.PersistenceManagerBean.getObjectById(PersistenceManagerBean.java:203)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:324)
at org.jboss.ejb.StatelessSessionContainer$ContainerInterceptor.invoke(StatelessSessionContainer.java:683)
at org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(CachedConnectionInterceptor.java:185)
at org.jboss.ejb.plugins.StatelessSessionInstanceInterceptor.invoke(StatelessSessionInstanceInterceptor.java:72)
at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(AbstractTxInterceptor.java:84)
at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(TxInterceptorCMT.java:267)
at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(TxInterceptorCMT.java:128)
at org.jboss.ejb.plugins.SecurityInterceptor.invoke(SecurityInterceptor.java:118)
at org.jboss.ejb.plugins.LogInterceptor.invoke(LogInterceptor.java:191)
at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(ProxyFactoryFinderInterceptor.java:122)
at org.jboss.ejb.StatelessSessionContainer.internalInvoke(StatelessSessionContainer.java:331)
at org.jboss.ejb.Container.invoke(Container.java:700)
at org.jboss.ejb.plugins.local.BaseLocalProxyFactory.invoke(BaseLocalProxyFactory.java:375)
at org.jboss.ejb.plugins.local.StatelessSessionProxy.invoke(StatelessSessionProxy.java:83)
at $Proxy234.getObjectById(Unknown Source)
at com.dbdata.ejb.CRMProviderBean.getByObjectId(CRMProviderBean.java:266)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:324)
at org.jboss.ejb.StatelessSessionContainer$ContainerInterceptor.invoke(StatelessSessionContainer.java:683)
at org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(CachedConnectionInterceptor.java:185)
at org.jboss.ejb.plugins.StatelessSessionInstanceInterceptor.invoke(StatelessSessionInstanceInterceptor.java:72)
at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(AbstractTxInterceptor.java:84)
at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(TxInterceptorCMT.java:267)
at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(TxInterceptorCMT.java:128)
at org.jboss.ejb.plugins.SecurityInterceptor.invoke(SecurityInterceptor.java:118)
at org.jboss.ejb.plugins.LogInterceptor.invoke(LogInterceptor.java:191)
at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(ProxyFactoryFinderInterceptor.java:122)
at org.jboss.ejb.StatelessSessionContainer.internalInvoke(StatelessSessionContainer.java:331)
at org.jboss.ejb.Container.invoke(Container.java:700)
at org.jboss.ejb.plugins.local.BaseLocalProxyFactory.invoke(BaseLocalProxyFactory.java:375)
at org.jboss.ejb.plugins.local.StatelessSessionProxy.invoke(StatelessSessionProxy.java:83)
at $Proxy163.getByObjectId(Unknown Source)
2006-04-21 19:42:27,593 INFO [org.hibernate.event.def.DefaultLoadEventListener] Error performing load command
org.hibernate.PropertyAccessException: exception setting property value with CGLIB (set hibernate.cglib.use_reflection_optimizer=false for more info) setter of com.dbdata.processingengine.hibernateobject.CustomProcessingEngine.setCrExecutionLists
at org.hibernate.tuple.PojoEntityTuplizer.setPropertyValuesWithOptimizer(PojoEntityTuplizer.java:215)
at org.hibernate.tuple.PojoEntityTuplizer.setPropertyValues(PojoEntityTuplizer.java:185)
at org.hibernate.persister.entity.AbstractEntityPersister.setPropertyValues(AbstractEntityPersister.java:3232)
at org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:126)
at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:842)
at org.hibernate.loader.Loader.doQuery(Loader.java:717)
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:224)
at org.hibernate.loader.Loader.loadEntity(Loader.java:1785)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:93)
at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:81)
at org.hibernate.persister.entity.AbstractEntityPersister.load(AbstractEntityPersister.java:2730)
at org.hibernate.event.def.DefaultLoadEventListener.loadFromDatasource(DefaultLoadEventListener.java:365)
at org.hibernate.event.def.DefaultLoadEventListener.doLoad(DefaultLoadEventListener.java:346)
at org.hibernate.event.def.DefaultLoadEventListener.load(DefaultLoadEventListener.java:123)
at org.hibernate.event.def.DefaultLoadEventListener.proxyOrLoad(DefaultLoadEventListener.java:161)
at org.hibernate.event.def.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:87)
at org.hibernate.impl.SessionImpl.fireLoad(SessionImpl.java:891)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:828)
at org.hibernate.impl.SessionImpl.get(SessionImpl.java:821)
at com.dbdata.system.persistence.ObjectHandler.getObjectById(ObjectHandler.java:218)
at com.dbdata.system.persistence.ObjectHandler.getObjectById(ObjectHandler.java:192)
at com.dbdata.system.persistence.PersistenceService.getObjectById(PersistenceService.java:91)
at com.dbdata.system.persistence.ejb.PersistenceManagerBean.getObjectById(PersistenceManagerBean.java:203)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:324)
at org.jboss.ejb.StatelessSessionContainer$ContainerInterceptor.invoke(StatelessSessionContainer.java:683)
at org.jboss.resource.connectionmanager.CachedConnectionInterceptor.invoke(CachedConnectionInterceptor.java:185)
at org.jboss.ejb.plugins.StatelessSessionInstanceInterceptor.invoke(StatelessSessionInstanceInterceptor.java:72)
at org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(AbstractTxInterceptor.java:84)
at org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(TxInterceptorCMT.java:267)
at org.jboss.ejb.plugins.TxInterceptorCMT.invoke(TxInterceptorCMT.java:128)
at org.jboss.ejb.plugins.SecurityInterceptor.invoke(SecurityInterceptor.java:118)
at org.jboss.ejb.plugins.LogInterceptor.invoke(LogInterceptor.java:191)
at org.jboss.ejb.plugins.ProxyFactoryFinderInterceptor.invoke(ProxyFactoryFinderInterceptor.java:122)
at org.jboss.ejb.StatelessSessionContainer.internalInvoke(StatelessSessionContainer.java:331)
at org.jboss.ejb.Container.invoke(Container.java:700)
at org.jboss.ejb.plugins.local.BaseLocalProxyFactory.invoke(BaseLocalProxyFactory.java:375)
at org.jboss.ejb.plugins.local.StatelessSessionProxy.invoke(StatelessSessionProxy.java:83)
at $Proxy234.getObjectById(Unknown Source)
Caused by: net.sf.cglib.beans.BulkBeanException: failed to lazily initialize a collection, no session or session was closed
at com.dbdata.processingengine.hibernateobject.CustomProcessingEngine$$BulkBeanByCGLIB$$3a54ef44.setPropertyValues(<generated>)
at org.hibernate.tuple.PojoEntityTuplizer.setPropertyValuesWithOptimizer(PojoEntityTuplizer.java:212)
... 146 more
Caused by: org.hibernate.LazyInitializationException: failed to lazily initialize a collection, no session or session was closed
at org.hibernate.collection.AbstractPersistentCollection.throwLazyInitializationException(AbstractPersistentCollection.java:358)
at org.hibernate.collection.AbstractPersistentCollection.throwLazyInitializationExceptionIfNotConnected(AbstractPersistentCollection.java:350)
at org.hibernate.collection.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:97)
at org.hibernate.collection.PersistentSet.size(PersistentSet.java:114)
at java.util.ArrayList.<init>(ArrayList.java:132)
at com.dbdata.util.SetWrapper.<init>(SetWrapper.java:38)
at com.dbdata.processingengine.util.OurSetWrapper.<init>(OurSetWrapper.java:60)
at com.dbdata.processingengine.hibernateobject.CrProcessingEngine.setCrExecutionLists(CrProcessingEngine.java:422)
... 148 more


I do see a session opened statement, but there is NO statement like session closed. So, i am not sure why this exception occurs when i enable fetch=join

Also, this exception does not occur if i do not do any operations on the collection that is passed to the setCrExecutionLists method.

Any help is appreciated.


Top
 Profile  
 
 Post subject:
PostPosted: Fri Apr 21, 2006 2:06 pm 
Regular
Regular

Joined: Wed Jul 27, 2005 2:33 am
Posts: 118
Here are the logs when the fetch=join is NOT specified. Please note the statements marked in bold in the logs:

Quote:
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.persistence.ObjectHandler] ObjectHandler.getObjectById(klass, id)
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.persistence.ObjectHandler] ObjectHandler.getObjectById(klass, id, loadLobs): (com.dbdata.processingengine.hibernateobject.CrProcessingEngine,com.dbdata.processingengine.hibernateobject.CrProcessingEngineId[id=103, versionId=1])
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.getHibernateSession()
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.util.ServiceLocator] getHibernateSessionFactory(): Entering...
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.util.ServiceLocator] getHibernateSessionFactory(): seeking cache in cache
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.util.ServiceLocator] getHibernateSessionFactory(): Entering...
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.util.ServiceLocator] getHibernateSessionFactory(): seeking cache in cache
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.getConnFromDataSource() for dataSource:java:/CrmDS
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.getConnFromDataSource returning connection:org.jboss.resource.adapter.jdbc.WrappedConnection@7216df
2006-04-21 23:13:41,937 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: -92233720368547758082006-04-21 23:13:41,937 DEBUG [org.hibernate.jdbc.JDBCContext] successfully registered Synchronization
2006-04-21 23:13:41,937 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.getHibernateSession returning session:SessionImpl(PersistenceContext[entityKeys=[],collectionKeys=[]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2006-04-21 23:13:41,937 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:41,937 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:41,937 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] object not resolved in any cache: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:41,937 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Fetching entity: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:41,937 DEBUG [org.hibernate.loader.Loader] loading entity: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:41,937 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-04-21 23:13:41,937 DEBUG [org.hibernate.SQL] select crprocessi0_.PROCESSING_ENGINE_ID as PROCESSING1_241_0_, crprocessi0_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_241_0_, crprocessi0_.UPDATE_DTTM as UPDATE4_241_0_, crprocessi0_.Name as Name241_0_, crprocessi0_.Description as Descript6_241_0_, crprocessi0_.Status_Cd as Status7_241_0_, crprocessi0_.OWNER_DIVISION_ID as OWNER8_241_0_, crprocessi0_.REFERENCE_TXT as REFERENCE9_241_0_, crprocessi0_.FOLDER_ID as FOLDER10_241_0_, crprocessi0_.SHARED_IND as SHARED11_241_0_, crprocessi0_.SOURCE_ENTITY_ID as SOURCE12_241_0_, crprocessi0_.SOURCE_VERSION_ID as SOURCE13_241_0_, crprocessi0_.Application_Name_Txt as Applica14_241_0_, crprocessi0_.Component_Type_Cd as Component15_241_0_, crprocessi0_.Processing_Type_Cd as Processing3_241_0_, crprocessi0_.Max_Parallel_Streams_Num as Max16_241_0_, crprocessi0_.Allow_One_To_One_Logon_Ind as Allow17_241_0_, crprocessi0_.Tap_Job_Id as Tap18_241_0_, crprocessi0_.Execution_Parameters_Txt as Execution19_241_0_, crprocessi0_.Lock_Cd as Lock20_241_0_, crprocessi0_.Clone_Ind as Clone21_241_0_, crprocessi0_.Externally_Scheduled_Ind as Externally22_241_0_, crprocessi0_.Best_Lead_Order_Cd as Best23_241_0_, crprocessi0_.Channel_Lead_Order_Cd as Channel24_241_0_, crprocessi0_.Default_User as Default25_241_0_, crprocessi0_.Default_Password as Default26_241_0_, crprocessi0_.UPDATE_USER as UPDATE27_241_0_, crprocessi0_.CREATE_USER as CREATE28_241_0_, crprocessi0_.CREATE_DTTM as CREATE29_241_0_, crprocessi0_.ACTIVATE_USER as ACTIVATE30_241_0_, crprocessi0_.ACTIVATE_DTTM as ACTIVATE31_241_0_ from CR_PROCESSING_ENGINE crprocessi0_ where crprocessi0_.PROCESSING_ENGINE_ID=? and crprocessi0_.PROCESSING_ENGINE_VERSION_ID=?
2006-04-21 23:13:41,937 INFO [STDOUT] Hibernate: select crprocessi0_.PROCESSING_ENGINE_ID as PROCESSING1_241_0_, crprocessi0_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_241_0_, crprocessi0_.UPDATE_DTTM as UPDATE4_241_0_, crprocessi0_.Name as Name241_0_, crprocessi0_.Description as Descript6_241_0_, crprocessi0_.Status_Cd as Status7_241_0_, crprocessi0_.OWNER_DIVISION_ID as OWNER8_241_0_, crprocessi0_.REFERENCE_TXT as REFERENCE9_241_0_, crprocessi0_.FOLDER_ID as FOLDER10_241_0_, crprocessi0_.SHARED_IND as SHARED11_241_0_, crprocessi0_.SOURCE_ENTITY_ID as SOURCE12_241_0_, crprocessi0_.SOURCE_VERSION_ID as SOURCE13_241_0_, crprocessi0_.Application_Name_Txt as Applica14_241_0_, crprocessi0_.Component_Type_Cd as Component15_241_0_, crprocessi0_.Processing_Type_Cd as Processing3_241_0_, crprocessi0_.Max_Parallel_Streams_Num as Max16_241_0_, crprocessi0_.Allow_One_To_One_Logon_Ind as Allow17_241_0_, crprocessi0_.Tap_Job_Id as Tap18_241_0_, crprocessi0_.Execution_Parameters_Txt as Execution19_241_0_, crprocessi0_.Lock_Cd as Lock20_241_0_, crprocessi0_.Clone_Ind as Clone21_241_0_, crprocessi0_.Externally_Scheduled_Ind as Externally22_241_0_, crprocessi0_.Best_Lead_Order_Cd as Best23_241_0_, crprocessi0_.Channel_Lead_Order_Cd as Channel24_241_0_, crprocessi0_.Default_User as Default25_241_0_, crprocessi0_.Default_Password as Default26_241_0_, crprocessi0_.UPDATE_USER as UPDATE27_241_0_, crprocessi0_.CREATE_USER as CREATE28_241_0_, crprocessi0_.CREATE_DTTM as CREATE29_241_0_, crprocessi0_.ACTIVATE_USER as ACTIVATE30_241_0_, crprocessi0_.ACTIVATE_DTTM as ACTIVATE31_241_0_ from CR_PROCESSING_ENGINE crprocessi0_ where crprocessi0_.PROCESSING_ENGINE_ID=? and crprocessi0_.PROCESSING_ENGINE_VERSION_ID=?
2006-04-21 23:13:41,937 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2006-04-21 23:13:41,937 DEBUG [org.hibernate.type.IntegerType] binding '103' to parameter: 1
2006-04-21 23:13:41,937 DEBUG [org.hibernate.type.IntegerType] binding '1' to parameter: 2
2006-04-21 23:13:42,671 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2006-04-21 23:13:42,671 DEBUG [org.hibernate.loader.Loader] processing result set
2006-04-21 23:13:42,671 DEBUG [org.hibernate.loader.Loader] result set row: 0
2006-04-21 23:13:42,671 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.ShortType] returning '3' as column: Processing3_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.dbdata.processingengine.hibernateobject.CustomProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,671 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Hydrating entity: [com.dbdata.processingengine.hibernateobject.CustomProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.TimestampType] returning '2006-03-17 09:40:03' as column: UPDATE4_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning 'Custom Seg Plan PE' as column: Name241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning '' as column: Descript6_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.ShortType] returning '2' as column: Status7_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: OWNER8_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning '' as column: REFERENCE9_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.IntegerType] returning null as column: FOLDER10_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.ByteType] returning null as column: SHARED11_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.IntegerType] returning null as column: SOURCE12_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.IntegerType] returning null as column: SOURCE13_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning 'Segment Plan Processing' as column: Applica14_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.ShortType] returning '32' as column: Component15_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.ShortType] returning '3' as column: Processing3_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.IntegerType] returning '5' as column: Max16_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Allow17_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.IntegerType] returning null as column: Tap18_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning null as column: Execution19_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Lock20_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Clone21_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Externally22_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.ShortType] returning '0' as column: Best23_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.ShortType] returning '0' as column: Channel24_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning 'mstrm600bu' as column: Default25_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning 'baril3' as column: Default26_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning 'tapadmin' as column: UPDATE27_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning 'tapadmin' as column: CREATE28_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.TimestampType] returning '2006-03-17 09:40:02' as column: CREATE29_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.StringType] returning null as column: ACTIVATE30_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.type.TimestampType] returning null as column: ACTIVATE31_241_0_
2006-04-21 23:13:42,671 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 2006-03-17 09:40:03.123
2006-04-21 23:13:42,671 DEBUG [org.hibernate.loader.Loader] done processing result set (1 rows)
2006-04-21 23:13:42,671 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2006-04-21 23:13:42,671 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-04-21 23:13:42,671 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2006-04-21 23:13:42,671 DEBUG [org.hibernate.loader.Loader] total objects hydrated: 1
2006-04-21 23:13:42,671 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.dbdata.processingengine.hibernateobject.CustomProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,671 DEBUG [org.hibernate.engine.CollectionLoadContext] creating collection wrapper:[com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,671 DEBUG [org.hibernate.event.def.DefaultInitializeCollectionEventListener] initializing collection [com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,671 DEBUG [org.hibernate.event.def.DefaultInitializeCollectionEventListener] checking second-level cache
2006-04-21 23:13:42,671 DEBUG [org.hibernate.event.def.DefaultInitializeCollectionEventListener] collection not cached
2006-04-21 23:13:42,671 DEBUG [org.hibernate.loader.Loader] loading collection: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,671 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-04-21 23:13:42,671 DEBUG [org.hibernate.SQL] select crexecutio0_.PROCESSING_ENGINE_ID as PROCESSING1_1_, crexecutio0_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_1_, crexecutio0_.EXECUTION_LIST_ID as EXECUTION3_1_, crexecutio0_.PROCESSING_ENGINE_ID as PROCESSING1_242_0_, crexecutio0_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_242_0_, crexecutio0_.EXECUTION_LIST_ID as EXECUTION3_242_0_, crexecutio0_.UPDATE_DTTM as UPDATE4_242_0_, crexecutio0_.Component_Id as Component5_242_0_, crexecutio0_.Component_Version_Id as Component6_242_0_, crexecutio0_.Component_Type_Cd as Component7_242_0_, crexecutio0_.Application_Sub_Type_Cd as Applicat8_242_0_, crexecutio0_.Priority_Ord as Priority9_242_0_, crexecutio0_.Logon_User as Logon10_242_0_, crexecutio0_.Logon_Password as Logon11_242_0_, crexecutio0_.Tap_Job_Id as Tap12_242_0_, crexecutio0_.Execution_Parameters_Txt as Execution13_242_0_, crexecutio0_.Lock_Cd as Lock14_242_0_, crexecutio0_.Submitted_Ind as Submitted15_242_0_, crexecutio0_.UPDATE_USER as UPDATE16_242_0_ from CR_EXECUTION_LIST crexecutio0_ where crexecutio0_.PROCESSING_ENGINE_ID=? and crexecutio0_.PROCESSING_ENGINE_VERSION_ID=?
2006-04-21 23:13:42,671 INFO [STDOUT] Hibernate: select crexecutio0_.PROCESSING_ENGINE_ID as PROCESSING1_1_, crexecutio0_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_1_, crexecutio0_.EXECUTION_LIST_ID as EXECUTION3_1_, crexecutio0_.PROCESSING_ENGINE_ID as PROCESSING1_242_0_, crexecutio0_.PROCESSING_ENGINE_VERSION_ID as PROCESSING2_242_0_, crexecutio0_.EXECUTION_LIST_ID as EXECUTION3_242_0_, crexecutio0_.UPDATE_DTTM as UPDATE4_242_0_, crexecutio0_.Component_Id as Component5_242_0_, crexecutio0_.Component_Version_Id as Component6_242_0_, crexecutio0_.Component_Type_Cd as Component7_242_0_, crexecutio0_.Application_Sub_Type_Cd as Applicat8_242_0_, crexecutio0_.Priority_Ord as Priority9_242_0_, crexecutio0_.Logon_User as Logon10_242_0_, crexecutio0_.Logon_Password as Logon11_242_0_, crexecutio0_.Tap_Job_Id as Tap12_242_0_, crexecutio0_.Execution_Parameters_Txt as Execution13_242_0_, crexecutio0_.Lock_Cd as Lock14_242_0_, crexecutio0_.Submitted_Ind as Submitted15_242_0_, crexecutio0_.UPDATE_USER as UPDATE16_242_0_ from CR_EXECUTION_LIST crexecutio0_ where crexecutio0_.PROCESSING_ENGINE_ID=? and crexecutio0_.PROCESSING_ENGINE_VERSION_ID=?
2006-04-21 23:13:42,671 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2006-04-21 23:13:42,687 DEBUG [org.hibernate.type.IntegerType] binding '103' to parameter: 1
2006-04-21 23:13:42,687 DEBUG [org.hibernate.type.IntegerType] binding '1' to parameter: 2
2006-04-21 23:13:42,734 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2006-04-21 23:13:42,734 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,734 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2006-04-21 23:13:42,734 DEBUG [org.hibernate.loader.Loader] processing result set
2006-04-21 23:13:42,734 DEBUG [org.hibernate.loader.Loader] result set row: 0
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '103' as column: PROCESSING1_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: PROCESSING2_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '14' as column: EXECUTION3_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 23:13:42,734 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 23:13:42,734 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Hydrating entity: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.TimestampType] returning '2006-03-22 15:20:15' as column: UPDATE4_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '506' as column: Component5_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: Component6_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.ShortType] returning '32' as column: Component7_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.ShortType] returning '2' as column: Applicat8_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning null as column: Priority9_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.StringType] returning 'tapdev' as column: Logon10_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.StringType] returning 'tapdev' as column: Logon11_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning null as column: Tap12_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.StringType] returning '<map/>' as column: Execution13_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Lock14_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.BooleanType] returning 'false' as column: Submitted15_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.StringType] returning 'tapdev' as column: UPDATE16_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '103' as column: PROCESSING1_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: PROCESSING2_242_0_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 2006-03-22 15:20:15.355
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '103' as column: PROCESSING1_1_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: PROCESSING2_1_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,734 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '103' as column: PROCESSING1_1_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '1' as column: PROCESSING2_1_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.type.IntegerType] returning '14' as column: EXECUTION3_1_
2006-04-21 23:13:42,734 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 23:13:42,734 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 23:13:42,734 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 23:13:42,765 DEBUG [org.hibernate.loader.Loader] done processing result set (1 rows)
2006-04-21 23:13:42,765 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2006-04-21 23:13:42,765 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-04-21 23:13:42,765 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2006-04-21 23:13:42,765 DEBUG [org.hibernate.loader.Loader] total objects hydrated: 1
2006-04-21 23:13:42,765 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 23:13:42,765 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,765 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,765 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,765 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}]
2006-04-21 23:13:42,781 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists
2006-04-21 23:13:42,781 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,781 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists
2006-04-21 23:13:42,781 DEBUG [org.hibernate.loader.Loader] done loading collection
2006-04-21 23:13:42,781 DEBUG [org.hibernate.event.def.DefaultInitializeCollectionEventListener] collection initialized
2006-04-21 23:13:42,781 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.dbdata.processingengine.hibernateobject.CustomProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,781 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2006-04-21 23:13:42,781 DEBUG [org.hibernate.loader.Loader] done entity load
2006-04-21 23:13:42,781 DEBUG [com.dbdata.system.persistence.ObjectHandler] ObjectHandler.processTapInterfaces(obj,action),action is:1
2006-04-21 23:13:42,781 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.closeAll()
2006-04-21 23:13:42,781 DEBUG [com.dbdata.system.persistence.SessionHandler] SessionHandler.closeAll - closing session:SessionImpl(PersistenceContext[entityKeys=[EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=7, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=5, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=16, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=11, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=1, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=6, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=10, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=14, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrProcessingEngine#component[id,versionId]{versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=2, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=17, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=3, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=9, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=18, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=19, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=13, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=8, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=4, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=15, versionId=1, id=103}], EntityKey[com.dbdata.processingengine.hibernateobject.CrExecutionList#component[id,versionId,executionListId]{executionListId=12, versionId=1, id=103}]],collectionKeys=[CollectionKey[com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]]];ActionQueue[insertions=[] updates=[] deletions=[] collectionCreations=[] collectionRemovals=[] collectionUpdates=[]])
2006-04-21 23:13:42,781 DEBUG [org.hibernate.impl.SessionImpl] closing session


When fetch=join is specified, we see the following log:

Quote:
2006-04-21 19:42:27,328 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]
2006-04-21 19:42:27,328 DEBUG [org.hibernate.engine.CollectionLoadContext] new collection: instantiating


And when fetch=join is removed, this is what we see:

Quote:
2006-04-21 23:13:42,671 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.dbdata.processingengine.hibernateobject.CustomProcessingEngine#component[id,versionId]{versionId=1, id=103}]
2006-04-21 23:13:42,671 DEBUG [org.hibernate.engine.CollectionLoadContext] creating collection wrapper:[com.dbdata.processingengine.hibernateobject.CrProcessingEngine.crExecutionLists#component[id,versionId]{versionId=1, id=103}]


Top
 Profile  
 
Display posts from previous:  Sort by  
Forum locked This topic is locked, you cannot edit posts or make further replies.  [ 3 posts ] 

All times are UTC - 5 hours [ DST ]


You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum

Search for:
© Copyright 2014, Red Hat Inc. All rights reserved. JBoss and Hibernate are registered trademarks and servicemarks of Red Hat, Inc.