Bug#725456: c3p0: FTBFS: Testsuite hangs
Daniel Schepler
dschepler at gmail.com
Sun Oct 6 01:01:10 UTC 2013
Source: c3p0
Version: 0.9.1.2-8
Severity: serious
>From my pbuilder build log [on amd64]:
...
compile:
jar:
test-init:
[mkdir] Created dir: /tmp/buildd/c3p0-0.9.1.2/build/testclasses
[copy] Copying 47 files to /tmp/buildd/c3p0-0.9.1.2/build/testclasses
[echo] test-classpath: /tmp/buildd/c3p0-0.9.1.2/test-properties:/tmp/buildd/c3p0-0.9.1.2/build/c3p0-0.9.1.2.jar:/tmp/buildd/c3p0-0.9.1.2/build/testclasses:/tmp/buildd/c3p0-0.9.1.2/${j2ee.classpath}:/usr/share/java/ant-1.9.2.jar:/usr/share/java/ant-antlr-1.9.2.jar:/usr/share/java/ant-antlr.jar:/usr/share/java/ant-apache-bcel-1.9.2.jar:/usr/share/java/ant-apache-bcel.jar:/usr/share/java/ant-apache-bsf-1.9.2.jar:/usr/share/java/ant-apache-bsf.jar:/usr/share/java/ant-apache-log4j-1.9.2.jar:/usr/share/java/ant-apache-log4j.jar:/usr/share/java/ant-apache-oro-1.9.2.jar:/usr/share/java/ant-apache-oro.jar:/usr/share/java/ant-apache-regexp-1.9.2.jar:/usr/share/java/ant-apache-regexp.jar:/usr/share/java/ant-apache-resolver-1.9.2.jar:/usr/share/java/ant-apache-resolver.jar:/usr/share/java/ant-apache-xalan2-1.9.2.jar:/usr/share/java/ant-apache-xalan2.jar:/usr/share/java/ant-bootstrap.jar:/usr/share/java/ant-commons-logging-1.9.2.jar:/usr/share/java/ant-commons-logging.jar:/usr/share/java/ant-commons-net-1.9.2.jar:/usr/share/java/ant-commons-net.jar:/usr/share/java/ant-javamail-1.9.2.jar:/usr/share/java/ant-javamail.jar:/usr/share/java/ant-jdepend-1.9.2.jar:/usr/share/java/ant-jdepend.jar:/usr/share/java/ant-jmf-1.9.2.jar:/usr/share/java/ant-jmf.jar:/usr/share/java/ant-jsch-1.9.2.jar:/usr/share/java/ant-jsch.jar:/usr/share/java/ant-junit-1.9.2.jar:/usr/share/java/ant-junit.jar:/usr/share/java/ant-junit4-1.9.2.jar:/usr/share/java/ant-junit4.jar:/usr/share/java/ant-launcher-1.9.2.jar:/usr/share/java/ant-launcher.jar:/usr/share/java/ant-swing-1.9.2.jar:/usr/share/java/ant-swing.jar:/usr/share/java/ant-testutil-1.9.2.jar:/usr/share/java/ant-testutil.jar:/usr/share/java/ant.jar:/usr/share/java/el-api-2.1.jar:/usr/share/java/gettext.jar:/usr/share/java/hsqldb-2.2.9+dfsg.jar:/usr/share/java/hsqldb.jar:/usr/share/java/hsqldbutil-2.2.9+dfsg.jar:/usr/share/java/hsqldbutil.jar:/usr/share/java/java-atk-wrapper.jar:/usr/share/java/jaxp-1.3.jar:/usr/share/java/jaxp-1.4.jar:/usr/share/java/jsp-api-2.1.jar:/usr/share/java/junit-3.8.2.jar:/usr/share/java/junit.jar:/usr/share/java/libintl.jar:/usr/share/java/log4j-1.2-1.2.17.jar:/usr/share/java/log4j-1.2.jar:/usr/share/java/maven-repo-helper.jar:/usr/share/java/servlet-api-2.5.jar:/usr/share/java/stax-1.2.0.jar:/usr/share/java/stax-api-1.0.1.jar:/usr/share/java/stax-api.jar:/usr/share/java/stax.jar:/usr/share/java/xercesImpl-2.11.0.jar:/usr/share/java/xercesImpl.jar:/usr/share/java/xercesSamples.jar:/usr/share/java/xml-apis-1.3.04.jar:/usr/share/java/xml-apis-1.4.01.jar:/usr/share/java/xml-apis-ext-1.4.01.jar:/usr/share/java/xml-apis-ext.jar:/usr/share/java/xml-apis.jar:/usr/share/java/xml-commons-external-1.4.01.jar:/usr/share/java/xml-commons-external.jar:/usr/share/java/xml-resolver-1.2.jar:/usr/share/java/xml-resolver.jar:/usr/share/java/xmlParserAPIs.jar
junit-tests:
[mkdir] Created dir: /tmp/buildd/c3p0-0.9.1.2/build/testresults
[junit] WARNING: multiple versions of ant detected in path for junit
[junit] jar:file:/usr/share/java/ant-1.9.2.jar!/org/apache/tools/ant/Project.class
[junit] and jar:file:/usr/share/java/ant.jar!/org/apache/tools/ant/Project.class
[junit] Running com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase
[junit] INFO - MLog clients using log4j logging.
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb: interrupt ignored!
[junit] WARN - APPARENT DEADLOCK! (com.mchange.v2.async.ThreadPerTaskAsynchronousRunner at d324de2) Deadlocked threads (unresponsive to interrupt()) are being set aside as hopeless and up to 5 may now be spawned for new tasks. If tasks continue to deadlock, you may run out of memory. Deadlocked task list: com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 28246ceb
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471: interrupt ignored!
[junit] WARN - APPARENT DEADLOCK! (com.mchange.v2.async.ThreadPerTaskAsynchronousRunner at 3a34c0bd) Deadlocked threads (unresponsive to interrupt()) are being set aside as hopeless and up to 5 may now be spawned for new tasks. If tasks continue to deadlock, you may run out of memory. Deadlocked task list: com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask at 8487471
[junit] done.
[junit] done.
[junit] done.
[junit] done.
[junit] done.
[junit] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 27.152 sec
[junit] Running com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase
[junit] INFO - MLog clients using log4j logging.
[junit] WARN - A task has exceeded the maximum allowable task time. Will interrupt() thread [Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2,5,main]], with current task: com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117: interrupt ignored!
[junit] WARN - Thread [Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2,5,main]] interrupted.
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117: interrupt ignored!
[junit] WARN - A task has exceeded the maximum allowable task time. Will interrupt() thread [Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0,5,main]], with current task: com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117: interrupt ignored!
[junit] WARN - Thread [Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0,5,main]] interrupted.
[junit] WARN - A task has exceeded the maximum allowable task time. Will interrupt() thread [Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1,5,main]], with current task: com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117
[junit] WARN - Thread [Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1,5,main]] interrupted.
[junit] WARN - com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector at 24e671a1 -- APPARENT DEADLOCK!!! Creating emergency threads for unassigned pending tasks!
[junit] WARN - com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector at 24e671a1 -- APPARENT DEADLOCK!!! Complete Status:
[junit] Managed Threads: 3
[junit] Active Threads: 3
[junit] Active Tasks:
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0)
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1)
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2)
[junit] Pending Tasks:
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117
[junit] Pool thread stack traces:
[junit] Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0,5,main]
[junit] java.lang.Object.wait(Native Method)
[junit] java.lang.Object.wait(Object.java:503)
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask.run(ThreadPoolAsynchronousRunnerJUnitTestCase.java:94)
[junit] com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
[junit] Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1,5,main]
[junit] java.lang.Object.wait(Native Method)
[junit] java.lang.Object.wait(Object.java:503)
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask.run(ThreadPoolAsynchronousRunnerJUnitTestCase.java:94)
[junit] com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
[junit] Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2,5,main]
[junit] java.lang.Object.wait(Native Method)
[junit] java.lang.Object.wait(Object.java:503)
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask.run(ThreadPoolAsynchronousRunnerJUnitTestCase.java:94)
[junit] com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
[junit]
[junit]
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117: interrupt ignored!
[junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117: interrupt ignored!
[junit] WARN - Task com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117 (in deadlocked PoolThread) failed to complete in maximum time 3000ms. Trying interrupt().
[junit] WARN - Task com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117 (in deadlocked PoolThread) failed to complete in maximum time 3000ms. Trying interrupt().
[junit] WARN - Task com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask at 46b98117 (in deadlocked PoolThread) failed to complete in maximum time 3000ms. Trying interrupt().
[junit] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 10.566 sec
[junit] Running com.mchange.v2.c3p0.test.junit.ConnectionPropertiesResetJUnitTestCase
[junit] INFO - MLog clients using log4j logging.
[junit] INFO - Initializing c3p0-0.9.1.2 [built 06-October-2013 00:56:22; debug? false; trace: 5]
[junit] INFO - Initializing c3p0 pool... com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> z8kfsx8x12n43621f2kgv7|45f76fc7, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> org.hsqldb.jdbcDriver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> z8kfsx8x12n43621f2kgv7|45f76fc7, idleConnectionTestPeriod -> 0, initialPoolSize -> 5, jdbcUrl -> jdbc:hsqldb:file:/tmp/testdb, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 5, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 5, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, parentLogger -> null, preferredTestQuery -> null, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]
[junit] INFO - Checkpoint start
[junit] INFO - checkpointClose start
[junit] INFO - checkpointClose end
[junit] INFO - Checkpoint end
[junit] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.78 sec
[junit] Running com.mchange.v2.c3p0.test.junit.MarshallUnmarshallDataSourcesJUnitTestCase
[junit] INFO - MLog clients using log4j logging.
[junit] INFO - Initializing c3p0-0.9.1.2 [built 06-October-2013 00:56:22; debug? false; trace: 5]
[junit] INFO - Initializing c3p0 pool... com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, dataSourceName -> poop, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> org.hsqldb.jdbcDriver, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> poop, idleConnectionTestPeriod -> 0, initialPoolSize -> 3, jdbcUrl -> jdbc:hsqldb:file:/tmp/testdb, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 15, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 3, numHelperThreads -> 3, numThreadsAwaitingCheckoutDefaultUser -> 0, parentLogger -> null, preferredTestQuery -> null, properties -> {user=******, password=******}, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false ]
At this point the build process hangs for at least an hour, and I had to
interrupt it.
--
Daniel Schepler
More information about the pkg-java-maintainers
mailing list