The problem I am currently having is this:

I load up a collection via an EJB session facade call to hibernate code, so its all in a CMT. This is called from a web front end.

I am manually opening the sessions, letting hibernate automatically close them due to the aforementioned property setting.

My collection is cached via ehcache. When I access it a second time, it comes out of the cache rather than the DB. However what seems to be happening is that the session still gets opened, but never automatically closed. This means that later on, when I try to save an object loaded out of that collection I get org.hibernate.HibernateException: illegally attempted to associate a proxy with two open Sessions

If I turn off caching for my class , I don't get the error, but it doesn't update the database, I get just this in the log:

5-02-07 12:16:38,480 [Servlet.Engine.Transports : 1] DEBUG org.hibernate.impl.SessionImpl - opened session
2005-02-07 12:16:38,480 [Servlet.Engine.Transports : 1] DEBUG org.hibernate.event.AbstractSaveEventListener - detached instance of: net.targetgroup.standingdata.StandingDataRow
2005-02-07 12:16:38,480 [Servlet.Engine.Transports : 1] DEBUG org.hibernate.event.DefaultSaveOrUpdateEventListener - updating detached instance
2005-02-07 12:16:38,480 [Servlet.Engine.Transports : 1] DEBUG org.hibernate.event.DefaultSaveOrUpdateEventListener - updating [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@2ce968c1[tabkey=QUEUENAME,genid=45]]
2005-02-07 12:16:38,480 [Servlet.Engine.Transports : 1] DEBUG org.hibernate.event.DefaultSaveOrUpdateEventListener - updating [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@2ce968c1[tabkey=QUEUENAME,genid=45]]
2005-02-07 12:16:38,480 [Servlet.Engine.Transports : 1] DEBUG org.hibernate.impl.SessionImpl - closing session
2005-02-

Here's the error if caching is turned on


[07/02/05 11:54:24:652 GMT] 5c0eea0 SystemErr R org.hibernate.HibernateException: illegally attempted to associate a proxy with two open Sessions
at org.hibernate.proxy.AbstractLazyInitializer.setSession(AbstractLazyInitializer.java:68)
at org.hibernate.impl.PersistenceContext.reassociateProxy(PersistenceContext.java:406)
at org.hibernate.impl.PersistenceContext.unproxyAndReassociate(PersistenceContext.java:431)
at org.hibernate.impl.SessionImpl.unproxyAndReassociate(SessionImpl.java:874)
at org.hibernate.event.DefaultSaveOrUpdateEventListener.onSaveOrUpdate(DefaultSaveOrUpdateEventListener.java:60)
at org.hibernate.impl.SessionImpl.saveOrUpdate(SessionImpl.java:582)
at org.hibernate.impl.SessionImpl.saveOrUpdate(SessionImpl.java:577)
at net.targetgroup.standingdata.StandingDataDAOHibernate.saveStandingDataRow(StandingDataDAOHibernate.java:104)

Here is the log for the unclosed session:

2005-02-07 11:37:21,683 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.impl.SessionFactoryObjectFactory - JNDI lookup: hibernate/HibernateFactory
2005-02-07 11:37:21,683 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.impl.SessionFactoryObjectFactory - lookup: uid=bcd0a3b401ec98620101ec986b340000
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.impl.SessionImpl - opened session
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.cache.StandardQueryCache - checking cached query results in region: org.hibernate.cache.StandardQueryCache
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.cache.EhCache - key: sql: select this_.TABKEY as TABKEY0_, this_.GENID as GENID0_, this_.datStamp as datStamp0_0_, this_.KEY1 as KEY10_0_, this_.KEY2 as KEY20_0_, this_.CODE as CODE0_0_, this_.DESC1 as DESC10_0_, this_.NUMERIC_FIELD as NUMERIC_8_0_0_, this_.DESC2 as DESC20_0_, this_.DESC3 as DESC30_0_, this_.DFTFLG as DFTFLG0_0_, this_.RSTFLG as RSTFLG0_0_, this_.SYSFLG as SYSFLG0_0_, this_.NONACT as NONACT0_0_, this_.USER as USER0_0_, this_.NOTES as NOTES0_0_ from SDDATA this_ where this_.TABKEY=? and this_.GENID>? and this_.NONACT=? and this_.SYSFLG=?; parameters: QUEUENAME, 0, 0, 0,
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.cache.StandardQueryCache - Checking query spaces for up-to-dateness [[SDDATA]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.cache.EhCache - key: SDDATA
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.cache.EhCache - Element for SDDATA is null
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.cache.StandardQueryCache - returning cached query results
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5bc1e31d[tabkey=QUEUENAME ,genid=5]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@50d2e31d[tabkey=QUEUENAME ,genid=22]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5184231d[tabkey=QUEUENAME ,genid=24]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5219e31d[tabkey=QUEUENAME ,genid=25]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@548ce31d[tabkey=QUEUENAME ,genid=26]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5563e31d[tabkey=QUEUENAME ,genid=33]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@563c231d[tabkey=QUEUENAME ,genid=39]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@6892a31d[tabkey=QUEUENAME ,genid=40]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@6907631d[tabkey=QUEUENAME ,genid=44]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - loading entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@6b53e31d[tabkey=QUEUENAME ,genid=45]]
2005-02-07 11:37:23,168 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - creating new proxy for entity
2005-02-07 11:37:23,339 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - attempting to resolve: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5bc1e31d[tabkey=QUEUENAME ,genid=5]]
2005-02-07 11:37:23,339 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - object not resolved in any cache: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5bc1e31d[tabkey=QUEUENAME ,genid=5]]
2005-02-07 11:37:23,339 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.persister.BasicEntityPersister - Materializing entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5bc1e31d[tabkey=QUEUENAME ,genid=5]]
2005-02-07 11:37:23,355 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2005-02-07 11:37:23,355 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - opening JDBC connection
2005-02-07 11:37:23,355 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.SQL - select standingda0_.TABKEY as TABKEY0_, standingda0_.GENID as GENID0_, standingda0_.datStamp as datStamp0_0_, standingda0_.KEY1 as KEY10_0_, standingda0_.KEY2 as KEY20_0_, standingda0_.CODE as CODE0_0_, standingda0_.DESC1 as DESC10_0_, standingda0_.NUMERIC_FIELD as NUMERIC_8_0_0_, standingda0_.DESC2 as DESC20_0_, standingda0_.DESC3 as DESC30_0_, standingda0_.DFTFLG as DFTFLG0_0_, standingda0_.RSTFLG as RSTFLG0_0_, standingda0_.SYSFLG as SYSFLG0_0_, standingda0_.NONACT as NONACT0_0_, standingda0_.USER as USER0_0_, standingda0_.NOTES as NOTES0_0_ from SDDATA standingda0_ where standingda0_.TABKEY=? and standingda0_.GENID=?
Hibernate: select standingda0_.TABKEY as TABKEY0_, standingda0_.GENID as GENID0_, standingda0_.datStamp as datStamp0_0_, standingda0_.KEY1 as KEY10_0_, standingda0_.KEY2 as KEY20_0_, standingda0_.CODE as CODE0_0_, standingda0_.DESC1 as DESC10_0_, standingda0_.NUMERIC_FIELD as NUMERIC_8_0_0_, standingda0_.DESC2 as DESC20_0_, standingda0_.DESC3 as DESC30_0_, standingda0_.DFTFLG as DFTFLG0_0_, standingda0_.RSTFLG as RSTFLG0_0_, standingda0_.SYSFLG as SYSFLG0_0_, standingda0_.NONACT as NONACT0_0_, standingda0_.USER as USER0_0_, standingda0_.NOTES as NOTES0_0_ from SDDATA standingda0_ where standingda0_.TABKEY=? and standingda0_.GENID=?
[07/02/05 11:37:23:355 GMT] 49a4a336 SystemOut O 2005-02-07 11:37:23,355 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - preparing statement
2005-02-07 11:37:23,355 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - binding 'QUEUENAME ' to parameter: 1
2005-02-07 11:37:23,355 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - binding '5' to parameter: 2
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 0)
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - Using naked result set
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - processing result set
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - result row: EntityKey[net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5bc1e31d[tabkey=QUEUENAME ,genid=5]]
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - Initializing object from ResultSet: EntityKey[net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5bc1e31d[tabkey=QUEUENAME ,genid=5]]
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.persister.BasicEntityPersister - Hydrating entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5bc1e31d[tabkey=QUEUENAME ,genid=5]]
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.TimestampType - returning '2004-11-03 16:09:00' as column: datStamp0_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning ' ' as column: KEY10_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning ' ' as column: KEY20_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning 'CANCELLED ' as column: CODE0_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning 'Cancelled ' as column: DESC10_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning null as column: NUMERIC_8_0_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning ' ' as column: DESC20_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning ' ' as column: DESC30_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning '0' as column: DFTFLG0_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning '0' as column: RSTFLG0_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning '0' as column: SYSFLG0_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning '0' as column: NONACT0_0_
2005-02-07 11:37:23,371 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning 'TGTBROKER ' as column: USER0_0_
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning '' as column: NOTES0_0_
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - done processing result set (1 rows)
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 1)
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - closing statement
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - total objects hydrated: 1
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.impl.PersistenceContext - initializing non-lazy collections
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - attempting to resolve: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@50d2e31d[tabkey=QUEUENAME ,genid=22]]
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - object not resolved in any cache: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@50d2e31d[tabkey=QUEUENAME ,genid=22]]
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.persister.BasicEntityPersister - Materializing entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@50d2e31d[tabkey=QUEUENAME ,genid=22]]
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.SQL - select standingda0_.TABKEY as TABKEY0_, standingda0_.GENID as GENID0_, standingda0_.datStamp as datStamp0_0_, standingda0_.KEY1 as KEY10_0_, standingda0_.KEY2 as KEY20_0_, standingda0_.CODE as CODE0_0_, standingda0_.DESC1 as DESC10_0_, standingda0_.NUMERIC_FIELD as NUMERIC_8_0_0_, standingda0_.DESC2 as DESC20_0_, standingda0_.DESC3 as DESC30_0_, standingda0_.DFTFLG as DFTFLG0_0_, standingda0_.RSTFLG as RSTFLG0_0_, standingda0_.SYSFLG as SYSFLG0_0_, standingda0_.NONACT as NONACT0_0_, standingda0_.USER as USER0_0_, standingda0_.NOTES as NOTES0_0_ from SDDATA standingda0_ where standingda0_.TABKEY=? and standingda0_.GENID=?
Hibernate: select standingda0_.TABKEY as TABKEY0_, standingda0_.GENID as GENID0_, standingda0_.datStamp as datStamp0_0_, standingda0_.KEY1 as KEY10_0_, standingda0_.KEY2 as KEY20_0_, standingda0_.CODE as CODE0_0_, standingda0_.DESC1 as DESC10_0_, standingda0_.NUMERIC_FIELD as NUMERIC_8_0_0_, standingda0_.DESC2 as DESC20_0_, standingda0_.DESC3 as DESC30_0_, standingda0_.DFTFLG as DFTFLG0_0_, standingda0_.RSTFLG as RSTFLG0_0_, standingda0_.SYSFLG as SYSFLG0_0_, standingda0_.NONACT as NONACT0_0_, standingda0_.USER as USER0_0_, standingda0_.NOTES as NOTES0_0_ from SDDATA standingda0_ where standingda0_.TABKEY=? and standingda0_.GENID=?
[07/02/05 11:37:23:386 GMT] 49a4a336 SystemOut O 2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - preparing statement
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - binding 'QUEUENAME ' to parameter: 1
2005-02-07 11:37:23,386 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - binding '22' to parameter: 2
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 0)
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - Using naked result set
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - processing result set
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - result row: EntityKey[net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@50d2e31d[tabkey=QUEUENAME ,genid=22]]
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - Initializing object from ResultSet: EntityKey[net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@50d2e31d[tabkey=QUEUENAME ,genid=22]]
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.persister.BasicEntityPersister - Hydrating entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@50d2e31d[tabkey=QUEUENAME ,genid=22]]
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.TimestampType - returning '2004-11-03 16:09:00' as column: datStamp0_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning ' ' as column: KEY10_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning ' ' as column: KEY20_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning 'APPLICAT ' as column: CODE0_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning 'Application ' as column: DESC10_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning null as column: NUMERIC_8_0_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning ' ' as column: DESC20_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning ' ' as column: DESC30_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning '0' as column: DFTFLG0_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning '0' as column: RSTFLG0_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning '0' as column: SYSFLG0_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.BigDecimalType - returning '0' as column: NONACT0_0_
2005-02-07 11:37:23,402 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning 'TGTBROKER ' as column: USER0_0_
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.type.StringType - returning '' as column: NOTES0_0_
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - done processing result set (1 rows)
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 1)
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.jdbc.AbstractBatcher - closing statement
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.loader.Loader - total objects hydrated: 1
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.impl.PersistenceContext - initializing non-lazy collections
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - attempting to resolve: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5184231d[tabkey=QUEUENAME ,genid=24]]
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.event.DefaultLoadEventListener - object not resolved in any cache: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5184231d[tabkey=QUEUENAME ,genid=24]]
2005-02-07 11:37:23,418 [Servlet.Engine.Transports : 0] DEBUG org.hibernate.persister.BasicEntityPersister - Materializing entity: [net.targetgroup.standingdata.StandingDataRow#net.targetgroup.standingdata.StandingDataPK@5184231d[tabkey=QUEUENAME ,genid=24]]
2005-02-07 11:37:23,574 [Finalizer] DEBUG org.hibernate.impl.SessionImpl - running Session.finalize()
2005-02-07 11:37:23,574 [Finalizer] WARN org.hibernate.impl.SessionImpl - unclosed connection, forgot to call close() on your session?
2005-02-07 11:37:23,574 [Finalizer] DEBUG org.hibernate.impl.SessionImpl - running Session.finalize()
2005-02-07 11:37:23,574 [Finalizer] WARN org.hibernate.impl.SessionImpl - unclosed connection, forgot to call close() on your session?

I don't see any evidence that the CacheSynchronization has been registered.

Step into SessionImpl.connect(), and find out why.


Are you really, really sure your hibernate.transaction.manager_lookup_class is correctly enabled?

Nope, but I will find out tomorrow, and look very carefully. Sorry about all this, trying to migrate from h2/spring to h3, whilst doing real work at the same time ! Hopefully I'll be able to learn something and give it back to the Hibernate community.

How does this affect lazy initialization of an object which first is accessed in the presentation layer? Based on the responses to (1) & (2) above, it looks like this wouldn't work. I hope I'm wrong, but I don't see a solution here.

BTW, HiA has been a big help. Is there an update for H3 in the works?

Thanks,

Justin