6:15 a.m.
[ http://jira.jboss.com/jira/browse/JBAS-4160?page=all ]
Dimitris Andreadis closed JBAS-4160.
------------------------------------
Resolution: Done
After a very painful investigation I discovered the failing test is caused by the
replacement of
<componentref name="oswego-concurrent" version="1.3.4"/>
with
<componentref name="oswego-concurrent"
version="1.3.4-brew"/>
The 2 are supposed to only differ in that the 2nd is compiled with debug=on.
JBoss uses a thread pool, that lives in jboss common and is backed by a
EDU.oswego.cs.dl.util.concurrent.PooledExecutor.
The test that fails involves a queued task and a thread pool of size 1, executing a
spinning task. The spining tasks times out, is initially interrupted and then
Thread.stopped().
The original oswego jar makes the stopped pooled thread available to the queued task. In
the debug version the pool thread seems to hung and so the remaining queued task is not
executed, and the test fails.
I would recommend that any brew replacements must - at a mininum - make sure they
don't alter the testsuite status.
failing org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase
---------------------------------------------------------------
Key: JBAS-4160
URL: http://jira.jboss.com/jira/browse/JBAS-4160
Project: JBoss Application Server
Issue Type: Sub-task
Security Level: Public(Everyone can see)
Components: Other
Affects Versions: JBossAS-4.2.0.CR1
Reporter: Dimitris Andreadis
Assigned To: Dimitris Andreadis
Priority: Critical
Fix For: JBossAS-4.2.0.GA
The test timeouts.
Testsuite: org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase
Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
Testcase: unknown took 0 sec
Caused an ERROR
Timeout occurred
junit.framework.AssertionFailedError: Timeout occurred
test.log:
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=1
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:33,832 DEBUG [org.jboss.util.threadpool.BasicThreadPool] stop,
immediate=true
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=3
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test1
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test1
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test2
2007-02-28 01:59:33,832 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test3
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test2
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test3
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:33,842 DEBUG [org.jboss.util.threadpool.BasicThreadPool] stop,
immediate=true
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=1
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test1
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test1
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test2
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test2
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:33,842 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=2
2007-02-28 01:59:33,842 DEBUG [org.jboss.util.threadpool.BasicThreadPool] stop,
immediate=true
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitStarted, target=1
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test1
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitForReleaseStarted, data=test1
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=1
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test2
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test2
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
releaseStarted, data=test1
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=2
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test1
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:33,852 DEBUG [org.jboss.util.threadpool.BasicThreadPool] stop,
immediate=true
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitStarted, target=1
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test1
2007-02-28 01:59:33,852 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitForReleaseStarted, data=test1
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
releaseStarted, data=test1
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=2
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test1
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test2
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test2
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:34,853 DEBUG [org.jboss.util.threadpool.BasicThreadPool] stop,
immediate=true
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitStarted, target=1
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test1
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
releaseStarted, data=test1
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=2
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitForReleaseStarted, data=test1
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test1
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test2
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test2
2007-02-28 01:59:34,853 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:34,853 DEBUG [org.jboss.util.threadpool.BasicThreadPool] stop,
immediate=true
2007-02-28 01:59:34,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitStarted, target=1
2007-02-28 01:59:34,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:34,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test1
2007-02-28 01:59:34,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
releaseStarted, data=test1
2007-02-28 01:59:34,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=1
2007-02-28 01:59:34,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitForReleaseStarted, data=test1
2007-02-28 01:59:34,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin spin loop
2007-02-28 01:59:34,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin sleep
2007-02-28 01:59:44,867 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyFinished, data=test1
2007-02-28 01:59:44,867 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
End run
2007-02-28 01:59:44,867 DEBUG [org.jboss.util.threadpool.BasicThreadPool] stop,
immediate=true
2007-02-28 01:59:44,867 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitStarted, target=1
2007-02-28 01:59:44,867 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin run
2007-02-28 01:59:44,867 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
notifyStarted, data=test1
2007-02-28 01:59:44,867 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
releaseStarted, data=test1
2007-02-28 01:59:44,867 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitForReleaseStarted, data=test1
2007-02-28 01:59:44,867 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Begin spin loop
2007-02-28 01:59:55,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitStarted, target=1
2007-02-28 01:59:55,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
releaseStarted, data=test2
2007-02-28 01:59:55,863 INFO [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
waitFinished, target=1
2007-02-28 01:59:55,883 WARN [org.jboss.test.util.test.ThreadPoolRunnableUnitTestCase]
Caught throwable
java.lang.ThreadDeath
at java.lang.Thread.stop(Thread.java:698)
at org.jboss.util.threadpool.RunnableTaskWrapper.stopTask(RunnableTaskWrapper.java:122)
at
org.jboss.util.threadpool.BasicThreadPool$TimeoutInfo.stopTask(BasicThreadPool.java:517)
at
org.jboss.util.threadpool.BasicThreadPool$TimeoutMonitor.run(BasicThreadPool.java:579)
at java.lang.Thread.run(Thread.java:595)
--
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
http://jira.jboss.com/jira/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira