tapestry-hibernate tests work slow, generate large amounts of
warnings
----------------------------------------------------------------------
Key: TAPESTRY-1993
URL: https://issues.apache.org/jira/browse/TAPESTRY-1993
Project: Tapestry
Issue Type: Bug
Components: tapestry-hibernate
Affects Versions: 5.0.7
Reporter: Howard M. Lewis Ship
Assignee: Howard M. Lewis Ship
Very concerned that the warnings indicate failures and the tests
are passing incorrectly.
[WARN] ThreadPoolAsynchronousRunner
com.mchange.v2.async.ThreadPoolAsynchronousRunner
[EMAIL PROTECTED] -- APPARENT DEADLOCK!!! Creating emergency
threads for unassigned pending tasks!
[WARN] ThreadPoolAsynchronousRunner
com.mchange.v2.async.ThreadPoolAsynchronousRunner
[EMAIL PROTECTED] -- APPARENT DEADLOCK!!! Complete Status:
Managed Threads: 3
Active Threads: 3
Active Tasks:
[EMAIL PROTECTED]
(com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0)
[EMAIL PROTECTED]
(com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1)
[EMAIL PROTECTED]
(com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2)
Pending Tasks:
[EMAIL PROTECTED]
[EMAIL PROTECTED]
Pool thread stack traces:
Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread-#0,5,main]
java.lang.Thread.sleep(Native Method)
com.mchange.v2.resourcepool.BasicResourcePool
$AcquireTask.run(BasicResourcePool.java:1805)
com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread-#1,5,main]
java.lang.Thread.sleep(Native Method)
com.mchange.v2.resourcepool.BasicResourcePool
$AcquireTask.run(BasicResourcePool.java:1805)
com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread-#2,5,main]
java.lang.Thread.sleep(Native Method)
com.mchange.v2.resourcepool.BasicResourcePool
$AcquireTask.run(BasicResourcePool.java:1805)
com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
[WARN] BasicResourcePool
[EMAIL PROTECTED] --
Acquisition Attempt Failed!!! Clearing pending acquires. While
trying to acquire a needed new resource, we failed to succeed more
than the maximum number of allowed acquisition attempts (30). Last
acquisition attempt exception:
java.sql.SQLException: The database is already in use by another
process: [EMAIL PROTECTED] =/Users/
Howard/work/tapestry5/target/unit-testdb.lck, exists=false,
locked=false, valid=false, fl =null]:
java.io.FileNotFoundException: /Users/Howard/work/tapestry5/target/
unit-testdb.lck (No such file or directory)
at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
at org.hsqldb.jdbc.jdbcConnection.<init>(Unknown Source)
at org.hsqldb.jdbcDriver.getConnection(Unknown Source)
at org.hsqldb.jdbcDriver.connect(Unknown Source)
at
com
.mchange
.v2
.c3p0
.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:
135)
at
com
.mchange
.v2
.c3p0
.WrapperConnectionPoolDataSource
.getPooledConnection(WrapperConnectionPoolDataSource.java:182)
at
com
.mchange
.v2
.c3p0
.WrapperConnectionPoolDataSource
.getPooledConnection(WrapperConnectionPoolDataSource.java:171)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool
$
1PooledConnectionResourcePoolManager
.acquireResource(C3P0PooledConnectionPool.java:137)
at
com
.mchange
.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:
1014)
at com.mchange.v2.resourcepool.BasicResourcePool.access
$800(BasicResourcePool.java:32)
at com.mchange.v2.resourcepool.BasicResourcePool
$AcquireTask.run(BasicResourcePool.java:1810)
at com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
[WARN] BasicResourcePool
[EMAIL PROTECTED] --
Acquisition Attempt Failed!!! Clearing pending acquires. While
trying to acquire a needed new resource, we failed to succeed more
than the maximum number of allowed acquisition attempts (30). Last
acquisition attempt exception:
java.sql.SQLException: The database is already in use by another
process: [EMAIL PROTECTED] =/Users/
Howard/work/tapestry5/target/unit-testdb.lck, exists=false,
locked=false, valid=false, fl =null]:
java.io.FileNotFoundException: /Users/Howard/work/tapestry5/target/
unit-testdb.lck (No such file or directory)
at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
at org.hsqldb.jdbc.jdbcConnection.<init>(Unknown Source)
at org.hsqldb.jdbcDriver.getConnection(Unknown Source)
at org.hsqldb.jdbcDriver.connect(Unknown Source)
at
com
.mchange
.v2
.c3p0
.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:
135)
at
com
.mchange
.v2
.c3p0
.WrapperConnectionPoolDataSource
.getPooledConnection(WrapperConnectionPoolDataSource.java:182)
at
com
.mchange
.v2
.c3p0
.WrapperConnectionPoolDataSource
.getPooledConnection(WrapperConnectionPoolDataSource.java:171)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool
$
1PooledConnectionResourcePoolManager
.acquireResource(C3P0PooledConnectionPool.java:137)
at
com
.mchange
.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:
1014)
at com.mchange.v2.resourcepool.BasicResourcePool.access
$800(BasicResourcePool.java:32)
at com.mchange.v2.resourcepool.BasicResourcePool
$AcquireTask.run(BasicResourcePool.java:1810)
at com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
[WARN] BasicResourcePool
[EMAIL PROTECTED] --
Acquisition Attempt Failed!!! Clearing pending acquires. While
trying to acquire a needed new resource, we failed to succeed more
than the maximum number of allowed acquisition attempts (30). Last
acquisition attempt exception:
java.sql.SQLException: The database is already in use by another
process: [EMAIL PROTECTED] =/Users/
Howard/work/tapestry5/target/unit-testdb.lck, exists=false,
locked=false, valid=false, fl =null]:
java.io.FileNotFoundException: /Users/Howard/work/tapestry5/target/
unit-testdb.lck (No such file or directory)
at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
at org.hsqldb.jdbc.jdbcConnection.<init>(Unknown Source)
at org.hsqldb.jdbcDriver.getConnection(Unknown Source)
at org.hsqldb.jdbcDriver.connect(Unknown Source)
at
com
.mchange
.v2
.c3p0
.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:
135)
at
com
.mchange
.v2
.c3p0
.WrapperConnectionPoolDataSource
.getPooledConnection(WrapperConnectionPoolDataSource.java:182)
at
com
.mchange
.v2
.c3p0
.WrapperConnectionPoolDataSource
.getPooledConnection(WrapperConnectionPoolDataSource.java:171)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool
$
1PooledConnectionResourcePoolManager
.acquireResource(C3P0PooledConnectionPool.java:137)
at
com
.mchange
.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:
1014)
at com.mchange.v2.resourcepool.BasicResourcePool.access
$800(BasicResourcePool.java:32)
at com.mchange.v2.resourcepool.BasicResourcePool
$AcquireTask.run(BasicResourcePool.java:1810)
at com.mchange.v2.async.ThreadPoolAsynchronousRunner
$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
[WARN] SettingsFactory Could not obtain connection metadata
java.sql.SQLException: Connections could not be acquired from the
underlying database!
at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:106)
at
com
.mchange
.v2
.c3p0
.impl
.C3P0PooledConnectionPool
.checkoutPooledConnection(C3P0PooledConnectionPool.java:529)
at
com
.mchange
.v2
.c3p0
.impl
.AbstractPoolBackedDataSource
.getConnection(AbstractPoolBackedDataSource.java:128)
at
org
.hibernate
.connection
.C3P0ConnectionProvider.getConnection(C3P0ConnectionProvider.java:56)
at
org
.hibernate.cfg.SettingsFactory.buildSettings(SettingsFactory.java:84)
at
org.hibernate.cfg.Configuration.buildSettings(Configuration.java:
2009)
at
org
.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:
1292)
at
org
.apache
.tapestry
.internal
.hibernate
.HibernateSessionSourceImpl.<init>(HibernateSessionSourceImpl.java:
43)
at
org
.apache
.tapestry
.internal
.hibernate
.HibernateSessionSourceImplTest
.get_configuration(HibernateSessionSourceImplTest.java:77)
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 org.testng.internal.MethodHelper.invokeMethod(MethodHelper.java:
580)
at org.testng.internal.Invoker.invokeMethod(Invoker.java:478)
at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:607)
at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:874)
at
org
.testng
.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:
125)
at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:
109)
at org.testng.TestRunner.runWorkers(TestRunner.java:689)
at org.testng.TestRunner.privateRun(TestRunner.java:566)
at org.testng.TestRunner.run(TestRunner.java:466)
at org.testng.SuiteRunner.runTest(SuiteRunner.java:301)
at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:296)
at org.testng.SuiteRunner.privateRun(SuiteRunner.java:276)
at org.testng.SuiteRunner.run(SuiteRunner.java:191)
at org.testng.TestNG.createAndRunSuiteRunners(TestNG.java:808)
at org.testng.TestNG.runSuitesLocally(TestNG.java:776)
at org.testng.TestNG.run(TestNG.java:701)
at
org
.apache
.maven.surefire.testng.TestNGExecutor.run(TestNGExecutor.java:78)
at
org
.apache
.maven
.surefire.testng.TestNGXmlTestSuite.execute(TestNGXmlTestSuite.java:
92)
at org.apache.maven.surefire.Surefire.run(Surefire.java:177)
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
org
.apache
.maven
.surefire
.booter.SurefireBooter.runSuitesInProcess(SurefireBooter.java:334)
at
org
.apache
.maven.surefire.booter.SurefireBooter.main(SurefireBooter.java:977)
Caused by:
com.mchange.v2.resourcepool.CannotAcquireResourceException: A
ResourcePool could not acquire a resource from its primary factory
or source.
at
com
.mchange
.v2
.resourcepool
.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1319)
at
com
.mchange
.v2
.resourcepool
.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:557)
at
com
.mchange
.v2
.resourcepool
.BasicResourcePool.checkoutResource(BasicResourcePool.java:477)
at
com
.mchange
.v2
.c3p0
.impl
.C3P0PooledConnectionPool
.checkoutPooledConnection(C3P0PooledConnectionPool.java:525)
... 36 more
[INFO] HibernateSessionSourceTest Hibernate startup: 138 ms to
configure, 30,035 ms overall.
[INFO] HibernateSessionSourceTest Configured Hibernate entities:
[WARN] AnnotationBinder Package not found or wo package-info.java:
org.example.myapp.entities
[WARN] AnnotationBinder Package not found or wo package-info.java:
org.example.app0.entities