Hi all,

We are currently facing issues with our production website.


We use Facelets, Seam, Spring and Hibernate. Weblogic is the app server and Sun One is the web server. Sometimes suddenly we start seeing resultset is already closed exception in our logs. Once this happens weblogic runs out of connections and we end up seeing stuck threads in the logs.

When we saw this, we thought of reducing the log level to debug for org.hibernate.jdbc. The output from the logs is attached.

Once this exception is seeing in the logs, lot of collection loads cause the same issue and ultimately server has to be recycled.

Any insights into this issue would be highly appreciated.

The configuration details and log snippets are below :

We let Seam manage the hibernate session by using the following entry in components.xml

<persistence:managed-hibernate-session name="hibernateSession"
auto-create="true" session-factory="#{springSessionFactory}" />

The Spring/Hibernate config is as follows :-

<bean id="sessionFactory"
class="org.jboss.seam.ioc.spring.SeamManagedSessionFactoryBean">
<property name="sessionName" value="hibernateSession" />
</bean>

<!-- Set up the WLS Transaction Manager. Note: in order for Seam integration to work,
you must use this ID in the Seam components.xml
-->
<bean id="transactionManager"
class="org.springframework.transaction.jta.WebLogicJtaTransactionManager">
<property name="userTransactionName"
value="java:comp/UserTransaction" />
<property name="transactionManagerName"
value="javax.transaction.TransactionManager" />

</bean>


<bean id="springSessionFactory" class="org.springframework.orm.hibernate3.annotation.AnnotationSessionFactoryBean">
<property name="dataSource" ref="xxxxxx"/>
<property name="hibernateProperties">
<props>
<prop key="hibernate.dialect">org.hibernate.dialect.Oracle10gDialect</prop>
<prop key="hibernate.show_sql">false</prop>
<prop key="hibernate.use_outer_join">true</prop>
<prop key="hibernate.format_sql">true</prop>
<prop key="hibernate.order_updates">true</prop>
<prop key="hibernate.use_sql_comments">true</prop>
<prop key="hibernate.jdbc.fetch_size">50</prop>
<prop key="hibernate.jdbc.batch_size">50</prop>
<prop key="hibernate.default_batch_fetch_size">16</prop>
<prop key="hibernate.cache.use_second_level_cache">false</prop>
<prop key="hibernate.cache.use_query_cache">false</prop>
<prop key="hibernate.generate_statistics">true</prop>
<!-- These properties are required in order to enable JTA
for Hibernate within a Spring context -->
<prop key="hibernate.transaction.factory_class">org.hibernate.transaction.CMTTransactionFactory</prop>
<prop key="hibernate.current_session_context_class">jta</prop>
<prop key="hibernate.transaction.manager_lookup_class">org.hibernate.transaction.WeblogicTransactionManagerLookup</prop>
<prop key="hibernate.connection.release_mode">auto</prop>
<prop key="hibernate.connection.flush_before_completion">true</prop>
<prop key="hibernate.connection.auto_close_session">true</prop>
</props>
</property>

We are not able to reproduce this in sub production environments.

We were wondering if anyone has seeing similar exceptions before and any recommendation you might have to address this.

Hibernate version:
3.2.6.ga


Code between sessionFactory.openSession() and session.close():
No explict openSession and session.close. We let JBOSS Seam manage the hibernate session. The session is referenced using getCurrentSession

Full stack trace of any exception that occurs:
Mar 10 2009 10:47:37] ERROR: org.jboss.seam.web.ExceptionFilter - exception root cause
org.hibernate.exception.GenericJDBCException: could not initialize a collection batch: [XXXXXXX#<1053163973, 1053188627, 1053163960, 1053141797, 1053163961, 1052858410, 1053188637, 1052858413, 1053188602, 1053163962, 1053163963, 1052858449, 1053188603, 1053141796, 1052858412, 1052858411>]
at org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:103)
at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:91)
at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
at org.hibernate.loader.Loader.loadCollectionBatch(Loader.java:2039)
at org.hibernate.loader.collection.BatchingCollectionInitializer.initialize(BatchingCollectionInitializer.java:47)
at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:565)
at org.hibernate.event.def.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:63)
at org.hibernate.impl.SessionImpl.initializeCollection(SessionImpl.java:1716)
at org.hibernate.collection.AbstractPersistentCollection.initialize(AbstractPersistentCollection.java:344)
at org.hibernate.collection.AbstractPersistentCollection.read(AbstractPersistentCollection.java:86)
at org.hibernate.collection.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:109)
at org.hibernate.collection.PersistentBag.size(PersistentBag.java:225)
at org.jboss.seam.el.SeamELResolver.resolveInCollection(SeamELResolver.java:128)
at org.jboss.seam.el.SeamELResolver.getValue(SeamELResolver.java:65)
at javax.el.CompositeELResolver.getValue(CompositeELResolver.java:143)
at com.sun.faces.el.FacesCompositeELResolver.getValue(FacesCompositeELResolver.java:73)
at com.sun.el.parser.AstValue.getValue(Unknown Source)
at com.sun.el.parser.AstGreaterThan.getValue(Unknown Source)
at com.sun.el.parser.AstAnd.getValue(Unknown Source)
at com.sun.el.ValueExpressionImpl.getValue(Unknown Source)
at com.sun.facelets.el.TagValueExpression.getValue(TagValueExpression.java:71)
at javax.faces.component.UIComponentBase.isRendered(UIComponentBase.java:384)
at javax.faces.component.UIComponent.encodeAll(UIComponent.java:930)
at javax.faces.component.UIComponent.encodeAll(UIComponent.java:942)
at com.sun.facelets.FaceletViewHandler.renderView(FaceletViewHandler.java:592)
at org.ajax4jsf.application.ViewHandlerWrapper.renderView(ViewHandlerWrapper.java:108)
at org.ajax4jsf.application.AjaxViewHandler.renderView(AjaxViewHandler.java:189)
at com.sun.faces.lifecycle.RenderResponsePhase.execute(RenderResponsePhase.java:110)
at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:100)
at com.sun.faces.lifecycle.LifecycleImpl.render(LifecycleImpl.java:139)
at javax.faces.webapp.FacesServlet.service(FacesServlet.java:266)
at weblogic.servlet.internal.StubSecurityHelper$ServletServiceAction.run(StubSecurityHelper.java:227)
at weblogic.servlet.internal.StubSecurityHelper.invokeServlet(StubSecurityHelper.java:125)
at weblogic.servlet.internal.ServletStubImpl.execute(ServletStubImpl.java:283)
at weblogic.servlet.internal.TailFilter.doFilter(TailFilter.java:26)
at weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:42)
at org.ajax4jsf.webapp.BaseXMLFilter.doXmlFilter(BaseXMLFilter.java:154)
at org.ajax4jsf.webapp.BaseFilter.handleRequest(BaseFilter.java:260)
at org.ajax4jsf.webapp.BaseFilter.processUploadsAndHandleRequest(BaseFilter.java:366)
at org.ajax4jsf.webapp.BaseFilter.doFilter(BaseFilter.java:493)
at weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:42)
at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:83)
at org.jboss.seam.web.LoggingFilter.doFilter(LoggingFilter.java:58)
at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
at org.jboss.seam.web.MultipartFilter.doFilter(MultipartFilter.java:85)
at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
at org.jboss.seam.web.ExceptionFilter.doFilter(ExceptionFilter.java:64)
at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
at org.jboss.seam.web.RedirectFilter.doFilter(RedirectFilter.java:45)
at org.jboss.seam.servlet.SeamFilter$FilterChainImpl.doFilter(SeamFilter.java:69)
at org.jboss.seam.servlet.SeamFilter.doFilter(SeamFilter.java:158)
at weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:42)
at weblogic.servlet.internal.RequestEventsFilter.doFilter(RequestEventsFilter.java:26)
at weblogic.servlet.internal.FilterChainImpl.doFilter(FilterChainImpl.java:42)
at weblogic.servlet.internal.WebAppServletContext$ServletInvocationAction.run(WebAppServletContext.java:3251)
at weblogic.security.acl.internal.AuthenticatedSubject.doAs(AuthenticatedSubject.java:321)
at weblogic.security.service.SecurityManager.runAs(SecurityManager.java:121)
at weblogic.servlet.internal.WebAppServletContext.securedExecute(WebAppServletContext.java:2010)
at weblogic.servlet.internal.WebAppServletContext.execute(WebAppServletContext.java:1916)
at weblogic.servlet.internal.ServletRequestImpl.run(ServletRequestImpl.java:1366)
at weblogic.work.ExecuteThread.execute(ExecuteThread.java:209)
at weblogic.work.ExecuteThread.run(ExecuteThread.java:181)
Caused by: java.sql.SQLException: Result set already closed
at weblogic.jdbc.wrapper.ResultSet.checkResultSet(ResultSet.java:102)
at weblogic.jdbc.wrapper.ResultSet.preInvocationHandler(ResultSet.java:58)
at weblogic.jdbc.wrapper.ResultSet_oracle_jdbc_driver_OracleResultSetImpl.getTimestamp(Unknown Source)
at org.hibernate.type.TimestampType.get(TimestampType.java:30)
at org.hibernate.type.NullableType.nullSafeGet(NullableType.java:163)
at org.hibernate.type.NullableType.nullSafeGet(NullableType.java:154)
at org.hibernate.type.AbstractType.hydrate(AbstractType.java:81)
at org.hibernate.persister.entity.AbstractEntityPersister.hydrate(AbstractEntityPersister.java:2101)
at org.hibernate.loader.Loader.loadFromResultSet(Loader.java:1380)
at org.hibernate.loader.Loader.instanceNotYetLoaded(Loader.java:1308)
at org.hibernate.loader.Loader.getRow(Loader.java:1206)
at org.hibernate.loader.Loader.getRowFromResultSet(Loader.java:580)
at org.hibernate.loader.Loader.doQuery(Loader.java:701)
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:236)
at org.hibernate.loader.Loader.loadCollectionBatch(Loader.java:2032)
... 58 more
Name and version of the database you are using:
Oracle 10g, JDBC Driver 10.2.0.3

Debug level Hibernate log excerpt:

[Mar 10 2009 10:46:44] INFO : org.hibernate.jdbc.ConnectionManager - forcing batcher resource cleanup on transaction completion; forgot to close ScrollableResults/Iterator?
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 18)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 34)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 17)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 18)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 35)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 17)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 34)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 33)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 34) (open ResultSets: 0, globally: 16)]
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 34)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 33) (open ResultSets: 0, globally: 16)]
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 16)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 35)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 34) (open ResultSets: 0, globally: 15)]
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 35) (open ResultSets: 0, globally: 15)]
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 15)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.JDBCContext - successfully registered Synchronization
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 34)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 33) (open ResultSets: 0, globally: 16)]
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 33) (open ResultSets: 0, globally: 16)]
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 33)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 34)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 17)
[Mar 10 2009 10:46:44] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 17)
[Mar 10 2009 10:46:32] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 17)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 35)
[Mar 10 2009 10:46:32] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 36)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 35) (open ResultSets: 0, globally: 15)]
[Mar 10 2009 10:46:20] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 36)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 15)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 16)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 16)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 18)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 19)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 20)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 21)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 22)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 36)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 35) (open ResultSets: 0, globally: 21)]
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 35)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 21)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 36)
[Mar 10 2009 10:46:32] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 36)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 20)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 36) (open ResultSets: 0, globally: 20)]
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 36)
[Mar 10 2009 10:47:00] INFO : org.hibernate.jdbc.ConnectionManager - forcing batcher resource cleanup on transaction completion; forgot to close ScrollableResults/Iterator?
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 19)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 35)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 34) (open ResultSets: 0, globally: 18)]
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 18)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 36)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:46:32] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 17)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 35) (open ResultSets: 0, globally: 19)]
[Mar 10 2009 10:47:00] INFO : org.hibernate.jdbc.ConnectionManager - forcing batcher resource cleanup on transaction completion; forgot to close ScrollableResults/Iterator?
[Mar 10 2009 10:46:32] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 17)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 35)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 19)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 20)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 34)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 33) (open ResultSets: 0, globally: 19)]
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 19)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 20)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 33)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 33)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 31) (open ResultSets: 0, globally: 17)]
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open ResultSet (open ResultSets: 0, globally: 17)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 18)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 31)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 32)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - releasing JDBC connection [ (open PreparedStatements: 0, globally: 31) (open ResultSets: 0, globally: 17)]
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.JDBCContext - successfully registered Synchronization
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.JDBCContext - successfully registered Synchronization
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 31)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 32)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close ResultSet (open ResultSets: 1, globally: 17)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 33)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to open PreparedStatement (open PreparedStatements: 0, globally: 34)
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - opening JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.ConnectionManager - aggressively releasing JDBC connection
[Mar 10 2009 10:47:00] DEBUG: org.hibernate.jdbc.AbstractBatcher - about to close PreparedStatement (open PreparedStatements: 1, globally: 35)
[Mar 10 2009 10:47:00] ERROR: org.hibernate.util.JDBCExceptionReporter - Result set already closed