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.

View unanswered posts | View active topics


Board index » Hibernate & Java Persistence » Hibernate Users

All times are UTC - 5 hours [ DST ]


Forum locked This topic is locked, you cannot edit posts or make further replies.  Page 1 of 1
  [ 5 posts ] 
  Previous topic | Next topic 
Author Message
vasu_suku@yahoo.com
 Post subject: org.hibernate.AssertionFailure] an assertion failure occured
PostPosted: Tue May 10, 2005 1:16 am 
Newbie

Joined: Wed Apr 20, 2005 10:57 pm
Posts: 6
Hi,

I have a Position Object. In my UI I choose a Position from a list of Positions retrieved from a previous request and try to save it after modifying some values. The Position has a Parent/Child relationship (Position has childPositions) and a few other relationships with other objects.

I use the spring framework.

As per the message what is a safe usage of a session. Can anyone explain.

I have a the stack trace and the debug output below


Hibernate version: 3.0.1
Name and version of the database you are using: Oracle 9i


Full stack trace of any exception that occurs:

2005-05-09 21:49:21,549 ERROR [org.hibernate.AssertionFailure] an assertion failure occured (this may indicate a bug in Hibernate, but is more likely due to unsafe use of the session)org.hibernate.AssertionFailure: collection was not processed by flush()
at org.hibernate.engine.CollectionEntry.postFlush(CollectionEntry.java:144)
at org.hibernate.event.def.AbstractFlushingEventListener.postFlush(AbstractFlushingEventListener.java:305)
at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:28)
at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:678)
at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:309)
at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:86)
at org.springframework.orm.hibernate3.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:470)
at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:401)
at org.springframework.transaction.interceptor.TransactionAspectSupport.doCommitTransactionAfterReturning(TransactionAspectSupport.java:256)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:67)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:144)
at org.springframework.aop.framework.Cglib2AopProxy$DynamicAdvisedInterceptor.intercept(Cglib2AopProxy.java:606)
at com.xactly.icm.org.dao.OrgDAOImplEx$$EnhancerByCGLIB$$8dac1f63_5.savePosition(<generated>)
at com.xactly.icm.webapp.actions.design.ReferenceDataSave.execute(ReferenceDataSave.java:68)
at com.xactly.icm.webapp.actions.BasicAction.execute(BasicAction.java:35)
at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:421)
at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:226)
at com.xactly.icm.webapp.servlets.IcmRequestProcessor.process(IcmRequestProcessor.java:75)
at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1164)
at org.apache.struts.action.ActionServlet.doGet(ActionServlet.java:397)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:697)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:810)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:237)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
at org.springframework.orm.hibernate3.support.OpenSessionInViewFilter.doFilterInternal(OpenSessionInViewFilter.java:172)
at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:186)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:75)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:186)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:214)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:198)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:152)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.jboss.web.tomcat.security.CustomPrincipalValve.invoke(CustomPrincipalValve.java:66)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:150)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:54)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:137)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:118)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:929)
at org.apache.coyote.tomcat5.CoyoteAdapter.service(CoyoteAdapter.java:160)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:799)
at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:705)
at org.apache.tomcat.util.net.TcpWorkerThread.runIt(PoolTcpEndpoint.java:577)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:683)
at java.lang.Thread.run(Thread.java:595)



The generated SQL (show_sql=true):

Debug level Hibernate log excerpt:

2005-05-09 21:49:21,259 DEBUG [org.hibernate.event.def.DefaultInitializeCollectionEventListener] checking second-level cache
2005-05-09 21:49:21,259 DEBUG [org.hibernate.event.def.DefaultInitializeCollectionEventListener] collection not cached
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] batch loading collection: [com.xactly.icm.org.entity.Position.fromPositions#<810, 812, 811, 814, 813, 816, 818, 817, 815>]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2005-05-09 21:49:21,259 DEBUG [org.hibernate.SQL] select frompositi0_.FROM_POS_ID as FROM10___, frompositi0_.ID as ID__, frompositi0_.ID as ID0_, frompositi0_.VERSION as VERSION225_0_, frompositi0_.CREATED_DATE as CREATED3_225_0_, frompositi0_.CREATED_BY_ID as CREATED4_225_0_, frompositi0_.CREATED_BY_NAME as CREATED5_225_0_, frompositi0_.MODIFIED_DATE as MODIFIED6_225_0_, frompositi0_.MODIFIED_BY_ID as MODIFIED7_225_0_, frompositi0_.MODIFIED_BY_NAME as MODIFIED8_225_0_, frompositi0_.BUSINESS_ID as BUSINESS9_225_0_, frompositi0_.FROM_POS_ID as FROM10_225_0_, frompositi0_.TO_POS_ID as TO11_225_0_, frompositi0_.POS_REL_TYPE_ID as POS12_225_0_ from XC_POS_RELATIONS frompositi0_ where frompositi0_.FROM_POS_ID in (?, ?, ?, ?, ?, ?, ?, ?, ?)
2005-05-09 21:49:21,259 INFO [STDOUT] Hibernate: select frompositi0_.FROM_POS_ID as FROM10___, frompositi0_.ID as ID__, frompositi0_.ID as ID0_, frompositi0_.VERSION as VERSION225_0_, frompositi0_.CREATED_DATE as CREATED3_225_0_, frompositi0_.CREATED_BY_ID as CREATED4_225_0_, frompositi0_.CREATED_BY_NAME as CREATED5_225_0_, frompositi0_.MODIFIED_DATE as MODIFIED6_225_0_, frompositi0_.MODIFIED_BY_ID as MODIFIED7_225_0_, frompositi0_.MODIFIED_BY_NAME as MODIFIED8_225_0_, frompositi0_.BUSINESS_ID as BUSINESS9_225_0_, frompositi0_.FROM_POS_ID as FROM10_225_0_, frompositi0_.TO_POS_ID as TO11_225_0_, frompositi0_.POS_REL_TYPE_ID as POS12_225_0_ from XC_POS_RELATIONS frompositi0_ where frompositi0_.FROM_POS_ID in (?, ?, ?, ?, ?, ?, ?, ?, ?)
2005-05-09 21:49:21,259 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '810' to parameter: 1
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '812' to parameter: 2
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '811' to parameter: 3
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '814' to parameter: 4
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '813' to parameter: 5
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '816' to parameter: 6
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '818' to parameter: 7
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '817' to parameter: 8
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] binding '815' to parameter: 9
2005-05-09 21:49:21,259 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#810]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#812]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#811]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#814]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#813]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#816]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#818]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#817]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [com.xactly.icm.org.entity.Position.fromPositions#815]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.engine.CollectionLoadContext] uninitialized collection: initializing
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] processing result set
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result set row: 0
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] returning '876' as column: ID0_
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#876]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#876]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#876]
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,259 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '818' as column: FROM10_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '816' as column: TO11_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '860' as column: POS12_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '818' as column: FROM10___
2005-05-09 21:49:21,269 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#818]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '876' as column: ID__
2005-05-09 21:49:21,269 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#876]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,269 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#876]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#876]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.loader.Loader] result set row: 1
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '886' as column: ID0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#886]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#886]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#886]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '818' as column: FROM10_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '816' as column: TO11_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '861' as column: POS12_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '818' as column: FROM10___
2005-05-09 21:49:21,269 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#818]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '886' as column: ID__
2005-05-09 21:49:21,269 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#886]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,269 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#886]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#886]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.loader.Loader] result set row: 2
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '875' as column: ID0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#875]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#875]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#875]
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,269 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,279 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,279 DEBUG [org.hibernate.type.LongType] returning '816' as column: FROM10_225_0_
2005-05-09 21:49:21,279 DEBUG [org.hibernate.type.LongType] returning '814' as column: TO11_225_0_
2005-05-09 21:49:21,279 DEBUG [org.hibernate.type.LongType] returning '860' as column: POS12_225_0_
2005-05-09 21:49:21,279 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,279 DEBUG [org.hibernate.type.LongType] returning '816' as column: FROM10___
2005-05-09 21:49:21,279 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#816]
2005-05-09 21:49:21,279 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,279 DEBUG [org.hibernate.type.LongType] returning '875' as column: ID__
2005-05-09 21:49:21,279 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#875]
2005-05-09 21:49:21,279 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,279 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#875]
2005-05-09 21:49:21,279 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#875]
2005-05-09 21:49:21,279 DEBUG [org.hibernate.loader.Loader] result set row: 3
2005-05-09 21:49:21,279 DEBUG [org.hibernate.type.LongType] returning '885' as column: ID0_
2005-05-09 21:49:21,279 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#885]
2005-05-09 21:49:21,279 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#885]
2005-05-09 21:49:21,279 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#885]
2005-05-09 21:49:21,279 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '816' as column: FROM10_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '814' as column: TO11_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '861' as column: POS12_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '816' as column: FROM10___
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#816]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '885' as column: ID__
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#885]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#885]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#885]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result set row: 4
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '874' as column: ID0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#874]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#874]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#874]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '814' as column: FROM10_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: TO11_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '860' as column: POS12_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '814' as column: FROM10___
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#814]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '874' as column: ID__
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#874]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#874]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#874]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result set row: 5
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '884' as column: ID0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#884]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#884]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#884]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '814' as column: FROM10_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: TO11_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '861' as column: POS12_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '814' as column: FROM10___
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#814]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '884' as column: ID__
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#884]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#884]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#884]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result set row: 6
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '872' as column: ID0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#872]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#872]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#872]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: FROM10_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '810' as column: TO11_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '860' as column: POS12_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: FROM10___
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#811]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '872' as column: ID__
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#872]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#872]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#872]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result set row: 7
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '882' as column: ID0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#882]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#882]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#882]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: FROM10_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '810' as column: TO11_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '861' as column: POS12_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: FROM10___
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#811]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '882' as column: ID__
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#882]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#882]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#882]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result set row: 8
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '873' as column: ID0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#873]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#873]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#873]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: FROM10_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '812' as column: TO11_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '860' as column: POS12_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: FROM10___
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#811]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '873' as column: ID__
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#873]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#873]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#873]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result set row: 9
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '883' as column: ID0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelations#883]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelations#883]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelations#883]
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: CREATED3_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: CREATED4_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: CREATED5_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.TimestampType] returning '2005-05-05 23:39:20' as column: MODIFIED6_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '200' as column: MODIFIED7_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.StringType] returning 'Administrator' as column: MODIFIED8_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS9_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '811' as column: FROM10_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '812' as column: TO11_225_0_
2005-05-09 21:49:21,289 DEBUG [org.hibernate.type.LongType] returning '861' as column: POS12_225_0_
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] Version: 0
2005-05-09 21:49:21,299 DEBUG [org.hibernate.type.LongType] returning '811' as column: FROM10___
2005-05-09 21:49:21,299 DEBUG [org.hibernate.loader.Loader] found row of collection: [com.xactly.icm.org.entity.Position.fromPositions#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.CollectionLoadContext] reading row
2005-05-09 21:49:21,299 DEBUG [org.hibernate.type.LongType] returning '883' as column: ID__
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelations#883]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelations#883]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.PositionRelations#883]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.loader.Loader] done processing result set (10 rows)
2005-05-09 21:49:21,299 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2005-05-09 21:49:21,299 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2005-05-09 21:49:21,299 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2005-05-09 21:49:21,299 DEBUG [org.hibernate.loader.Loader] total objects hydrated: 10
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#876]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#818]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#818]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#818]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#876]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#886]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#818]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#818]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#818]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#861]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#886]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#875]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#875]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#885]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#816]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#861]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#885]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#874]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#874]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#884]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#814]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#861]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#884]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#872]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#810]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#810]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#810]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#872]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#882]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#810]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#810]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#810]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#861]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#882]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#873]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#812]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#812]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#812]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#873]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.xactly.icm.org.entity.PositionRelations#883]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Business#100]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#811]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.Position#812]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.Position#812]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] resolved object in session cache: [com.xactly.icm.org.entity.Position#812]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.xactly.icm.org.entity.PositionRelationType#861]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.xactly.icm.org.entity.PositionRelations#883]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.CollectionLoadContext] 9 collections were found in result set
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [com.xactly.icm.org.entity.Position.fromPositions#812]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [com.xactly.icm.org.entity.Position.fromPositions#810]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [com.xactly.icm.org.entity.Position.fromPositions#813]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [com.xactly.icm.org.entity.Position.fromPositions#815]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] object not resolved in any cache: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Materializing entity: [com.xactly.icm.org.entity.PositionRelationType#860]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.loader.Loader] batch loading entity: [com.xactly.icm.org.entity.PositionRelationType#<860, 861>]
2005-05-09 21:49:21,299 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2005-05-09 21:49:21,299 DEBUG [org.hibernate.SQL] select positionre0_.POS_REL_TYPE_ID as POS1_0_, positionre0_.VERSION as VERSION226_0_, positionre0_.NAME as NAME226_0_, positionre0_.DESCR as DESCR226_0_, positionre0_.BUSINESS_ID as BUSINESS5_226_0_, positionre0_.IS_ACTIVE as IS6_226_0_, positionre0_.CREATED_DATE as CREATED7_226_0_, positionre0_.CREATED_BY_ID as CREATED8_226_0_, positionre0_.CREATED_BY_NAME as CREATED9_226_0_, positionre0_.MODIFIED_DATE as MODIFIED10_226_0_, positionre0_.MODIFIED_BY_ID as MODIFIED11_226_0_, positionre0_.MODIFIED_BY_NAME as MODIFIED12_226_0_ from XC_POS_REL_TYPE positionre0_ where positionre0_.POS_REL_TYPE_ID in (?, ?)
2005-05-09 21:49:21,299 INFO [STDOUT] Hibernate: select positionre0_.POS_REL_TYPE_ID as POS1_0_, positionre0_.VERSION as VERSION226_0_, positionre0_.NAME as NAME226_0_, positionre0_.DESCR as DESCR226_0_, positionre0_.BUSINESS_ID as BUSINESS5_226_0_, positionre0_.IS_ACTIVE as IS6_226_0_, positionre0_.CREATED_DATE as CREATED7_226_0_, positionre0_.CREATED_BY_ID as CREATED8_226_0_, positionre0_.CREATED_BY_NAME as CREATED9_226_0_, positionre0_.MODIFIED_DATE as MODIFIED10_226_0_, positionre0_.MODIFIED_BY_ID as MODIFIED11_226_0_, positionre0_.MODIFIED_BY_NAME as MODIFIED12_226_0_ from XC_POS_REL_TYPE positionre0_ where positionre0_.POS_REL_TYPE_ID in (?, ?)
2005-05-09 21:49:21,299 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2005-05-09 21:49:21,299 DEBUG [org.hibernate.type.LongType] binding '860' to parameter: 1
2005-05-09 21:49:21,299 DEBUG [org.hibernate.type.LongType] binding '861' to parameter: 2
2005-05-09 21:49:21,309 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2005-05-09 21:49:21,309 DEBUG [org.hibernate.loader.Loader] processing result set
2005-05-09 21:49:21,309 DEBUG [org.hibernate.loader.Loader] result set row: 0
2005-05-09 21:49:21,309 DEBUG [org.hibernate.type.LongType] returning '861' as column: POS1_0_
2005-05-09 21:49:21,309 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.xactly.icm.org.entity.PositionRelationType#861]
2005-05-09 21:49:21,309 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.xactly.icm.org.entity.PositionRelationType#861]
2005-05-09 21:49:21,309 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [com.xactly.icm.org.entity.PositionRelationType#861]
2005-05-09 21:49:21,309 DEBUG [org.hibernate.type.IntegerType] returning '0' as column: VERSION226_0_
2005-05-09 21:49:21,309 DEBUG [org.hibernate.type.StringType] returning 'RollDown' as column: NAME226_0_
2005-05-09 21:49:21,309 DEBUG [org.hibernate.type.StringType] returning null as column: DESCR226_0_
2005-05-09 21:49:21,309 DEBUG [org.hibernate.type.LongType] returning '100' as column: BUSINESS5_226_0_
2005-05-09 21:49:21,309 DEBUG [org.hibernate.type.BooleanType] returning 'true' as column: IS6_226_0_
2005-05-09 21:49:21,309 DEBUG [org.hibernate.type.TimestampType] returning '
Top
 Profile  
 
vasu_suku@yahoo.com
 Post subject: Hibernate Bug: Registering update listener causes this issue
PostPosted: Tue May 10, 2005 12:28 pm 
Newbie

Joined: Wed Apr 20, 2005 10:57 pm
Posts: 6
Hi,

This problem is caused when you register a save-update or update listener. When I unregistered my listener the problem went away. Looks like a bug in hibernate. I am using v3.0.1. I am also using SpringFramework.
Top
 Profile  
 
maulinshah
 Post subject:
PostPosted: Mon Oct 24, 2005 3:14 pm 
Newbie

Joined: Thu Sep 09, 2004 6:50 am
Posts: 15
just fyi: I get the exact same behavior.
Top
 Profile  
 
maulinshah
 Post subject: my problem is solved
PostPosted: Mon Oct 24, 2005 9:32 pm 
Newbie

Joined: Thu Sep 09, 2004 6:50 am
Posts: 15
I solved my problem by:

1. upgrading to 3.1rc2
2. i t turned out that my listener to the postUpdate event simply rebroadcast the event via the spring application context event broadcast mechanism. one of the listeners is used in both web and on-web context, and so it was wrapped in a hibernateInterceptor, so i was opening a new session... and that must have been confuisng hibernate. anyway, i unwrapped it (and create a seperate bean for the non-web use that could be wrapped) and it fixed the problem.

my $0.02
Top
 Profile  
 
maulinshah
 Post subject:
PostPosted: Mon Oct 24, 2005 10:56 pm 
Newbie

Joined: Thu Sep 09, 2004 6:50 am
Posts: 15
woops. and...

3) changed the event listener registration post-commit-* instead of the plain post-*

hope this helps.

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

Board index » Hibernate & Java Persistence » Hibernate Users

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.