Cannot get Hibernate 3.1 to work with c3p0

[Chris Johnston]

This is driving me crazy. Especially since I cannot seem to find any info
about the problem on Google.

Here is the deal, I have set up Hibernate and as long as I use its built in
JDBC connection pooling, everything works fine. However, as soon as I try
and use c3p0, nothing works. I have a JUnit test class that tests my
PersistenceDAO against a live MySQL 4.x database. Basically, it tries to
insert, update, delete a bunch of objects to make sure that Hibernate is
working correctly. This works fine, all tests pass, if I don't use c3p0. As
soon as I turn it on, not a single test passes. I have no idea what is going
on here. In an attempt to answer as many questions as possible, please see
below:

IDE: NetBeans 5.0

Hibernate: version 3.1 using the library version that come bundled with it.
c3p0: version c3p0-0.9.0.jar (I have tried the latest version as well,didn't work)
MySQL: version 4.1.20 running on CentOS 4.3
JDBC: mysql-connector-java-3.1.12-bin.jar

Below are my hibernate config file and, as far as I can tell, the important
parts from the stack trace. As you can see from the config file, I am using
what is more or less a default use of c3p0, but nothing is working. Below
everything, I have also included the source code from one of the methods
from my PersistenceDao class in case the problem is in the code.

Thanks for the help with this,
Chris


hibernate.cfg.xml

<hibernate-configuration>
 
    <session-factory>
 
        <!-- Database connection settings -->
        <property name="connection.driver_class">com.mysql.jdbc.Driver</property>
        <property name="connection.url">jdbc:mysql://rivendell/teamdocs</property>
        <property name="connection.username">****</property>
        <property name="connection.password">****</property>
        <property name="dialect">org.hibernate.dialect.MySQLInnoDBDialect</property>
 
        <!-- JDBC connection pool (use the built-in)
        <property name="connection.pool_size">1</property>
        -->
        
        <property name="hibernate.c3p0.max_size">10</property>
        <property name="hibernate.c3p0.min_size">2</property>
        <property name="hibernate.c3p0.timeout">100</property>
        <property name="hibernate.c3p0.max_statements">10</property>
        <property name="hibernate.c3p0.idle_test_period">10</property>
        <property name="hibernate.c3p0.acquire_increment">2</property>
        
 
        <!-- Enable Hibernate's automatic session context management -->
        <property name="current_session_context_class">thread</property>
 
        <!-- Disable the second-level cache  -->
        <property name="cache.provider_class">org.hibernate.cache.NoCacheProvider</property>
 
        <!-- Echo all executed SQL to stdout -->
        <property name="show_sql">true</property>
        <property name="format_sql">true</property>
 
        <!-- Drop and re-create the database schema on startup
        <property name="hbm2ddl.auto">create</property>
        -->
        
        <!-- Just update the database schema on startup -->
        <property name="hbm2ddl.auto">update</property>
 
 
        <!-- Mapping files for each model class -->
        <mapping resource="com/fuzzylizard/teamdocs/model/Role.hbm.xml"/>
        <mapping resource="com/fuzzylizard/teamdocs/model/User.hbm.xml"/>
        <mapping resource="com/fuzzylizard/teamdocs/model/Document.hbm.xml"/>
        <mapping resource="com/fuzzylizard/teamdocs/model/Directory.hbm.xml"/>
        <mapping resource="com/fuzzylizard/teamdocs/model/Repository.hbm.xml"/>
 
    </session-factory>
 
</hibernate-configuration>

The stacktrace

18:39:50,288  INFO [SchemaUpdate] schema update complete
18:39:50,303 DEBUG [GooGooStatementCache] checkinAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 0; checked out: 0; num connections: 0; num keys: 0
18:39:50,303 DEBUG [BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1a918d5 [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1b273cc)
18:39:50,303 DEBUG [GooGooStatementCache] ENTER METHOD: closeAll( com.mysql.jdbc.Connection@1ed1b0b )! -- num_connections: 0
18:39:50,303 DEBUG [GooGooStatementCache] Set of statements for connection: null
18:39:50,303 DEBUG [GooGooStatementCache] closeAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 0; checked out: 0; num connections: 0; num keys: 0
18:39:50,303 DEBUG [BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1a918d5 [managed: 1, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1b273cc)
18:39:50,303 DEBUG [GooGooStatementCache] ENTER METHOD: closeAll( com.mysql.jdbc.Connection@1980630 )! -- num_connections: 0
18:39:50,303 DEBUG [GooGooStatementCache] Set of statements for connection: null
18:39:50,303 DEBUG [GooGooStatementCache] closeAll(): com.mchange.v2.c3p0.stmt.GlobalMaxOnlyStatementCache stats -- total size: 0; checked out: 0; num connections: 0; num keys: 0
18:39:50,303 DEBUG [BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1a918d5 [managed: 0, unused: 0, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@1b273cc)
18:39:50,303 DEBUG [ThreadPoolAsynchronousRunner] Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2,5,main] interrupted. Shutting down.
18:39:50,303 DEBUG [ThreadPoolAsynchronousRunner] Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1,5,main] interrupted. Shutting down.
18:39:50,303 DEBUG [ThreadPoolAsynchronousRunner] Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0,5,main] interrupted. Shutting down.
18:39:50,303 DEBUG [PoolBackedDataSource] com.mchange.v2.c3p0.PoolBackedDataSource@1e4853f has been closed. force_destroy == false
java.lang.Exception: Debug -- PoolBackedDataSource.close() stack trace.
        at com.mchange.v2.c3p0.PoolBackedDataSource.close(PoolBackedDataSource.java:214)
        at com.mchange.v2.c3p0.DataSources.destroy(DataSources.java:259)
        at com.mchange.v2.c3p0.DataSources.destroy(DataSources.java:226)
        at org.hibernate.connection.C3P0ConnectionProvider.close(C3P0ConnectionProvider.java:118)
        at org.hibernate.tool.hbm2ddl.SchemaUpdate.execute(SchemaUpdate.java:175)
        at org.hibernate.impl.SessionFactoryImpl.<init>(SessionFactoryImpl.java:295)
        at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1154)
        at com.fuzzylizard.teamdocs.persistence.HibernateUtil.<clinit>(HibernateUtil.java:31)
        at com.fuzzylizard.teamdocs.persistence.PersistenceDao.loadObject(PersistenceDao.java:114)
        at com.fuzzylizard.teamdocs.persistence.PersistenceDaoTest.testSaveUserRoleObjects(PersistenceDaoTest.java:40)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at junit.framework.TestCase.runTest(TestCase.java:154)
        at junit.framework.TestCase.runBare(TestCase.java:127)
        at junit.framework.TestResult$1.protect(TestResult.java:106)
        at junit.framework.TestResult.runProtected(TestResult.java:124)
        at junit.framework.TestResult.run(TestResult.java:109)
        at junit.framework.TestCase.run(TestCase.java:118)
        at junit.framework.TestSuite.runTest(TestSuite.java:208)
        at junit.framework.TestSuite.run(TestSuite.java:203)
        at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:297)
        at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:672)
        at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:567)
18:39:50,319  INFO [SessionFactoryImpl] Checking 0 named HQL queries
18:39:50,319  INFO [SessionFactoryImpl] Checking 0 named SQL queries
18:39:50,491  WARN [JDBCExceptionReporter] SQL Error: 0, SQLState: null
18:39:50,491 ERROR [JDBCExceptionReporter] com.mchange.v2.c3p0.PoolBackedDataSource@1e4853f [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@dcb03b [ acquireIncrement -> 2, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> dcb03b, idleConnectionTestPeriod -> 10, initialPoolSize -> 2, maxIdleTime -> 100, maxPoolSize -> 10, maxStatements -> 10, maxStatementsPerConnection -> 0, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@9934d4 [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 9934d4, jdbcUrl -> jdbc:mysql://rivendell/teamdocs, properties -> {user=******, password=******} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 1e4853f, numHelperThreads -> 3 ] has been closed() -- you can no longer use it.
save Directory object
18:39:50,694  WARN [JDBCExceptionReporter] SQL Error: 0, SQLState: null
18:39:50,694 ERROR [JDBCExceptionReporter] com.mchange.v2.c3p0.PoolBackedDataSource@1e4853f [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@dcb03b [ acquireIncrement -> 2, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> dcb03b, idleConnectionTestPeriod -> 10, initialPoolSize -> 2, maxIdleTime -> 100, maxPoolSize -> 10, maxStatements -> 10, maxStatementsPerConnection -> 0, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@9934d4 [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 9934d4, jdbcUrl -> jdbc:mysql://rivendell/teamdocs, properties -> {user=******, password=******} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 1e4853f, numHelperThreads -> 3 ] has been closed() -- you can no longer use it.
save Document object
18:39:50,694  WARN [JDBCExceptionReporter] SQL Error: 0, SQLState: null
18:39:50,694 ERROR [JDBCExceptionReporter] com.mchange.v2.c3p0.PoolBackedDataSource@1e4853f [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@dcb03b [ acquireIncrement -> 2, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> dcb03b, idleConnectionTestPeriod -> 10, initialPoolSize -> 2, maxIdleTime -> 100, maxPoolSize -> 10, maxStatements -> 10, maxStatementsPerConnection -> 0, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@9934d4 [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 9934d4, jdbcUrl -> jdbc:mysql://rivendell/teamdocs, properties -> {user=******, password=******} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 1e4853f, numHelperThreads -> 3 ] has been closed() -- you can no longer use it.
delete
18:39:50,709  WARN [JDBCExceptionReporter] SQL Error: 0, SQLState: null
18:39:50,709 ERROR [JDBCExceptionReporter] com.mchange.v2.c3p0.PoolBackedDataSource@1e4853f [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@dcb03b [ acquireIncrement -> 2, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> dcb03b, idleConnectionTestPeriod -> 10, initialPoolSize -> 2, maxIdleTime -> 100, maxPoolSize -> 10, maxStatements -> 10, maxStatementsPerConnection -> 0, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@9934d4 [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 9934d4, jdbcUrl -> jdbc:mysql://rivendell/teamdocs, properties -> {user=******, password=******} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 1e4853f, numHelperThreads -> 3 ] has been closed() -- you can no longer use it.
find user, should succeed
18:39:50,709 DEBUG [PersistenceDao] findUser() Looking for username: admin password: admin
18:39:50,709  WARN [JDBCExceptionReporter] SQL Error: 0, SQLState: null
18:39:50,709 ERROR [JDBCExceptionReporter] com.mchange.v2.c3p0.PoolBackedDataSource@1e4853f [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@dcb03b [ acquireIncrement -> 2, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> dcb03b, idleConnectionTestPeriod -> 10, initialPoolSize -> 2, maxIdleTime -> 100, maxPoolSize -> 10, maxStatements -> 10, maxStatementsPerConnection -> 0, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@9934d4 [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 9934d4, jdbcUrl -> jdbc:mysql://rivendell/teamdocs, properties -> {user=******, password=******} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 1e4853f, numHelperThreads -> 3 ] has been closed() -- you can no longer use it.
find user, should fail
18:39:50,709 DEBUG [PersistenceDao] findUser() Looking for username: blahblahblah password: blahblah
18:39:50,709  WARN [JDBCExceptionReporter] SQL Error: 0, SQLState: null
18:39:50,709 ERROR [JDBCExceptionReporter] com.mchange.v2.c3p0.PoolBackedDataSource@1e4853f [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@dcb03b [ acquireIncrement -> 2, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> dcb03b, idleConnectionTestPeriod -> 10, initialPoolSize -> 2, maxIdleTime -> 100, maxPoolSize -> 10, maxStatements -> 10, maxStatementsPerConnection -> 0, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@9934d4 [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 9934d4, jdbcUrl -> jdbc:mysql://rivendell/teamdocs, properties -> {user=******, password=******} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 1e4853f, numHelperThreads -> 3 ] has been closed() -- you can no longer use it.
------------- ---------------- ---------------
Testcase: testSaveUserRoleObjects(com.fuzzylizard.teamdocs.persistence.PersistenceDaoTest):Caused an ERROR
Cannot open connection
org.hibernate.exception.GenericJDBCException: Cannot open connection
        at org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:91)
        at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:79)
        at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
        at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:29)
        at org.hibernate.jdbc.ConnectionManager.openConnection(ConnectionManager.java:318)
        at org.hibernate.jdbc.ConnectionManager.getConnection(ConnectionManager.java:109)
        at org.hibernate.jdbc.JDBCContext.connection(JDBCContext.java:137)
        at org.hibernate.transaction.JDBCTransaction.begin(JDBCTransaction.java:57)
        at org.hibernate.impl.SessionImpl.beginTransaction(SessionImpl.java:1262)
        at org.hibernate.context.ThreadLocalSessionContext$TransactionProtectionWrapper.invoke(ThreadLocalSessionContext.java:292)
        at $Proxy0.beginTransaction(Unknown Source)
        at com.fuzzylizard.teamdocs.persistence.PersistenceDao.loadObject(PersistenceDao.java:116)
        at com.fuzzylizard.teamdocs.persistence.PersistenceDaoTest.testSaveUserRoleObjects(PersistenceDaoTest.java:40)
Caused by: java.sql.SQLException: com.mchange.v2.c3p0.PoolBackedDataSource@1e4853f [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@dcb03b [ acquireIncrement -> 2, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> dcb03b, idleConnectionTestPeriod -> 10, initialPoolSize -> 2, maxIdleTime -> 100, maxPoolSize -> 10, maxStatements -> 10, maxStatementsPerConnection -> 0, minPoolSize -> 2, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@9934d4 [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 9934d4, jdbcUrl -> jdbc:mysql://rivendell/teamdocs, properties -> {user=******, password=******} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 1e4853f, numHelperThreads -> 3 ] has been closed() -- you can no longer use it.
        at com.mchange.v2.c3p0.PoolBackedDataSource.assertCpds(PoolBackedDataSource.java:234)
        at com.mchange.v2.c3p0.PoolBackedDataSource.getPoolManager(PoolBackedDataSource.java:246)
        at com.mchange.v2.c3p0.PoolBackedDataSource.getConnection(PoolBackedDataSource.java:94)
        at org.hibernate.connection.C3P0ConnectionProvider.getConnection(C3P0ConnectionProvider.java:35)
        at org.hibernate.jdbc.ConnectionManager.openConnection(ConnectionManager.java:315)
        ... 27 more
 

PersistenceDao method

public Object saveOrUpdate(final Object object) {
    SessionFactory sessionFactory = HibernateUtil.getSessionFactory();
    Session session = sessionFactory.getCurrentSession();
 
    session.beginTransaction();
 
    session.saveOrUpdate(object);
 
    session.getTransaction().commit();
    //session.close();
    return object;
}

[ July 03, 2006: Message edited by: Chris Johnston ]
[ July 03, 2006: Message edited by: Chris Johnston ]

[Chris Johnston]

I seemed to have fixed the problem, but I don't really understand how. I commented out the following statement in my hibernate.cfg file and the tests passed

<!-- Just update the database schema on startup
<property name="hbm2ddl.auto">update</property>
-->

I don't understand why that would make such a big difference. Can someone explain this please?

[ravi bhosale]

Hi,

Me to also faced problem with Hibernate 3.1.2 and c3po. Finally its solved by building source of c3p0-0.9.0.2.

For me problem is because of different log4j version used by Hibernate and C3po. But now its working.

[Nobody Cares]

I am experiencing exactly the same issue...has anyone resolved this issue since the initial post?

[Bear Bibeault]

"Nobody Cares",

You are quite inccorect. We do care! Please take a look at the JavaRanch Naming Policy and adjust your display name to match it.

In particular, your display name must be a first and a last name separated by a space character, and must not be obviously fictitious.

Thanks!
bear
JavaRanch Sheriff

[nirumagic tniranjan]

hi, try as in below.

session.saveOrUpdate(object);
session.commit();


If you want use connection pooling better use jndi lookup. It is the best practise and nice performence.