c3p0: FTBFS: Testsuite hangs

Bug #1245868 reported by Andreas Moog
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
c3p0 (Debian)
Fix Released
Unknown
c3p0 (Ubuntu)
Fix Released
High
Unassigned

Bug Description

Imported from Debian bug http://bugs.debian.org/725456:

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@28246ceb: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@28246ceb: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@28246ceb: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@28246ceb: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@28246ceb: interrupt ignored!
    [junit] WARN - APPARENT DEADLOCK! (com.mchange.v2.async.ThreadPerTaskAsynchronousRunner@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@28246ceb, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@28246ceb, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@28246ceb, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@28246ceb, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@28246ceb
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@8487471: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@8487471: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@8487471: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@8487471: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@8487471: interrupt ignored!
    [junit] WARN - APPARENT DEADLOCK! (com.mchange.v2.async.ThreadPerTaskAsynchronousRunner@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@8487471, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@8487471, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@8487471, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@8487471, com.mchange.v2.async.junit.ThreadPerTaskAsynchronousRunnerJUnitTestCase$DumbTask@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@46b98117
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@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@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@46b98117
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@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@46b98117
    [junit] WARN - Thread [Thread[com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1,5,main]] interrupted.
    [junit] WARN - com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector@24e671a1 -- APPARENT DEADLOCK!!! Creating emergency threads for unassigned pending tasks!
    [junit] WARN - com.mchange.v2.async.ThreadPoolAsynchronousRunner$DeadlockDetector@24e671a1 -- APPARENT DEADLOCK!!! Complete Status:
    [junit] Managed Threads: 3
    [junit] Active Threads: 3
    [junit] Active Tasks:
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#0)
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#1)
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117 (com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2)
    [junit] Pending Tasks:
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@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@46b98117: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117: interrupt ignored!
    [junit] com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117: interrupt ignored!
    [junit] WARN - Task com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117 (in deadlocked PoolThread) failed to complete in maximum time 3000ms. Trying interrupt().
    [junit] WARN - Task com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@46b98117 (in deadlocked PoolThread) failed to complete in maximum time 3000ms. Trying interrupt().
    [junit] WARN - Task com.mchange.v2.async.junit.ThreadPoolAsynchronousRunnerJUnitTestCase$DumbTask@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

Tags: ftbfs trusty
Andreas Moog (ampelbein)
Changed in c3p0 (Ubuntu):
importance: Undecided → High
tags: added: ftbfs trusty
Changed in c3p0 (Debian):
importance: Undecided → Unknown
status: New → Confirmed
Changed in c3p0 (Debian):
status: Confirmed → Fix Released
Changed in c3p0 (Debian):
status: Fix Released → Confirmed
Changed in c3p0 (Debian):
status: Confirmed → Fix Released
Andreas Moog (ampelbein)
Changed in c3p0 (Ubuntu):
status: New → Fix Released
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.