db-derby-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Mike Matrigali (JIRA)" <derby-...@db.apache.org>
Subject [jira] Updated: (DERBY-989) unit/daemonService.unit fails intermittently: 'ran out of time'
Date Thu, 15 Jun 2006 23:29:31 GMT
     [ http://issues.apache.org/jira/browse/DERBY-989?page=all ]

Mike Matrigali updated DERBY-989:
---------------------------------


Test run on ibm1.4.2 jvm, last night, 6/14 - build (

diff:
********* Diff file derbyall/unit/daemonService.diff
*** Start: daemonService jdk1.4.2 derbyall:unit 2006-06-15 08:58:59 ***
2 del
< -- Unit Test T_DaemonService finished
2 add
> ran out of time
> Shutting down due to unit test failure.
> Exit due to time bomb
Test Failed.
*** End:   daemonService jdk1.4.2 derbyall:unit 2006-06-15 09:59:44 ***

probably the underlying error from derby.log:
[Thread_8] Pass - null T02
org.apache.derbyTesting.unitTests.harness.T_Fail: Test failed - unsubscribed continue to get
serviced
	at org.apache.derbyTesting.unitTests.harness.T_Fail.testFailMsg(T_Fail.java:95)
	at org.apache.derbyTesting.unitTests.services.T_DaemonService.T03(T_DaemonService.java:247)
	at org.apache.derbyTesting.unitTests.services.T_DaemonService.runTestSet(T_DaemonService.java:118)
	at org.apache.derbyTesting.unitTests.harness.T_MultiThreadedIterations.run(T_MultiThreadedIterations.java:180)
	at java.lang.Thread.run(Thread.java:570)


portion of derby.log for the daemonService test:
2006-06-15 15:59:00.192 GMT Thread[main,5,main] Starting test  'org.apache.derbyTesting.unitTests.services.T_DaemonService'.
[Thread_1] started
[Thread_0] started
[Thread_4] started
[Thread_2] started
[Thread_8] started
[Thread_9] started
[Thread_7] started
[Thread_6] started
[Thread_5] started
[Thread_3] started
[Thread_1] Pass - null T01
[Thread_9] Pass - null T01
[Thread_5] Pass - null T01
[Thread_0] Pass - null T01
[Thread_8] Pass - null T01
[Thread_6] Pass - null T01
[Thread_3] Pass - null T01
[Thread_2] Pass - null T01
[Thread_7] Pass - null T01
[Thread_4] Pass - null T01
[Thread_2] Pass - null T02
[Thread_6] Pass - null T02
[Thread_4] Pass - null T02
[Thread_9] Pass - null T02
[Thread_5] Pass - null T02
[Thread_3] Pass - null T02
[Thread_8] Pass - null T02
[Thread_0] Pass - null T02
[Thread_7] Pass - null T02
[Thread_1] Pass - null T02
[Thread_2] Pass - null T03
[Thread_9] Pass - null T03
[Thread_4] Pass - null T03
[Thread_0] Pass - null T03
[Thread_3] Pass - null T03
[Thread_6] Pass - null T03
[Thread_7] Pass - null T03
[Thread_8] Pass - null T03
[Thread_1] Pass - null T03
[Thread_5] Pass - null T03
[Thread_2] Pass - null T_CheckStatus
[Thread_2] Iteration 0 took 99876ms
[Thread_2] Total memory increased by 0 is 4389376
[Thread_2] Used  memory increased by 24240 is 1557112
[Thread_2] Pass - null T01
[Thread_9] Pass - null T_CheckStatus
[Thread_4] Pass - null T_CheckStatus
[Thread_9] Iteration 0 took 119856ms
[Thread_0] Pass - null T_CheckStatus
[Thread_4] Iteration 0 took 119872ms
[Thread_3] Pass - null T_CheckStatus
[Thread_0] Iteration 0 took 119872ms
[Thread_3] Iteration 0 took 119856ms
[Thread_0] Total memory increased by 0 is 4389376
[Thread_8] Pass - null T_CheckStatus
[Thread_6] Pass - null T_CheckStatus
[Thread_8] Iteration 0 took 119856ms
[Thread_9] Total memory increased by 0 is 4389376
[Thread_3] Total memory increased by 0 is 4389376
[Thread_6] Iteration 0 took 119872ms
[Thread_3] Used  memory increased by 23272 is 1559776
[Thread_8] Total memory increased by 0 is 4389376
[Thread_0] Used  memory increased by 26944 is 1559600
[Thread_9] Used  memory increased by 26072 is 1560080
[Thread_4] Total memory increased by 0 is 4389376
[Thread_8] Used  memory increased by 28016 is 1561688
[Thread_7] Pass - null T_CheckStatus
[Thread_6] Total memory increased by 0 is 4389376
[Thread_5] Pass - null T_CheckStatus
[Thread_6] Used  memory increased by 26984 is 1562000
[Thread_4] Used  memory increased by 27496 is 1560152
[Thread_5] Iteration 0 took 119888ms
[Thread_7] Iteration 0 took 119888ms
[Thread_7] Total memory increased by 0 is 4389376
[Thread_7] Used  memory increased by 27360 is 1563768
[Thread_5] Total memory increased by 0 is 4389376
[Thread_1] Pass - null T_CheckStatus
[Thread_5] Used  memory increased by 26264 is 1563816
[Thread_1] Iteration 0 took 119919ms
[Thread_1] Total memory increased by 0 is 4389376
[Thread_1] Used  memory increased by 29640 is 1565848
[Thread_9] Pass - null T01
[Thread_2] Pass - null T02
[Thread_1] Pass - null T01
[Thread_5] Pass - null T01
[Thread_3] Pass - null T01
[Thread_6] Pass - null T01
[Thread_0] Pass - null T01
[Thread_4] Pass - null T01
[Thread_7] Pass - null T01
[Thread_8] Pass - null T01
[Thread_6] Pass - null T02
[Thread_9] Pass - null T02
[Thread_1] Pass - null T02
[Thread_7] Pass - null T02
[Thread_3] Pass - null T02
[Thread_2] Pass - null T03
[Thread_5] Pass - null T02
[Thread_4] Pass - null T02
[Thread_0] Pass - null T02
[Thread_3] Pass - null T03
[Thread_8] Pass - null T02
org.apache.derbyTesting.unitTests.harness.T_Fail: Test failed - unsubscribed continue to get
serviced
	at org.apache.derbyTesting.unitTests.harness.T_Fail.testFailMsg(T_Fail.java:95)
	at org.apache.derbyTesting.unitTests.services.T_DaemonService.T03(T_DaemonService.java:247)
	at org.apache.derbyTesting.unitTests.services.T_DaemonService.runTestSet(T_DaemonService.java:118)
	at org.apache.derbyTesting.unitTests.harness.T_MultiThreadedIterations.run(T_MultiThreadedIterations.java:180)
	at java.lang.Thread.run(Thread.java:570)
[Thread_6] Pass - null T03
[Thread_9] Pass - null T03
[Thread_4] Pass - null T03
[Thread_7] Pass - null T03
[Thread_0] Pass - null T03
[Thread_8] Pass - null T03
[Thread_1] Pass - null T03
[Thread_2] Pass - null T_CheckStatus
[Thread_2] Iteration 1 took 90118ms
[Thread_2] Total memory increased by 0 is 4389376
[Thread_2] Used  memory increased by 6736 is 1563840
[Thread_2] Pass - null T01
[Thread_3] Pass - null T_CheckStatus
[Thread_3] Iteration 1 took 80206ms
[Thread_3] Total memory increased by 0 is 4389376
[Thread_3] Used  memory increased by -98224 is 1464696
[Thread_2] Pass - null T02
[Thread_3] Pass - null T01
[Thread_6] Pass - null T_CheckStatus
[Thread_9] Pass - null T_CheckStatus
[Thread_6] Iteration 1 took 90227ms
[Thread_9] Iteration 1 took 90259ms
[Thread_6] Total memory increased by 0 is 4389376
[Thread_6] Used  memory increased by -96200 is 1468224
[Thread_9] Total memory increased by 0 is 4389376
[Thread_9] Used  memory increased by -96488 is 1467784
[Thread_3] Pass - null T02
[Thread_9] Pass - null T01
[Thread_6] Pass - null T01
[Thread_4] Pass - null T_CheckStatus
[Thread_4] Iteration 1 took 100311ms
[Thread_4] Total memory increased by 0 is 4389376
[Thread_4] Used  memory increased by -94160 is 1469608
[Thread_9] Pass - null T02
[Thread_6] Pass - null T02
[Thread_4] Pass - null T01
[Thread_7] Pass - null T_CheckStatus
[Thread_0] Pass - null T_CheckStatus
[Thread_7] Iteration 1 took 110380ms
[Thread_1] Pass - null T_CheckStatus
[Thread_8] Pass - null T_CheckStatus
[Thread_7] Total memory increased by 0 is 4389376
[Thread_1] Iteration 1 took 110380ms
[Thread_7] Used  memory increased by -95256 is 1471880
[Thread_1] Total memory increased by 0 is 4389376
[Thread_0] Iteration 1 took 110411ms
[Thread_8] Iteration 1 took 110395ms
[Thread_0] Total memory increased by 0 is 4389376
[Thread_8] Total memory increased by 0 is 4389376
[Thread_8] Used  memory increased by -93688 is 1469664
[Thread_0] Used  memory increased by -92864 is 1470136
[Thread_1] Used  memory increased by -79968 is 1470920
[Thread_2] Pass - null T03
[Thread_4] Pass - null T02
[Thread_0] Pass - null T01
[Thread_1] Pass - null T01
[Thread_8] Pass - null T01
[Thread_7] Pass - null T01
[Thread_3] Pass - null T03
[Thread_1] Pass - null T02
[Thread_0] Pass - null T02
[Thread_9] Pass - null T03
[Thread_6] Pass - null T03
[Thread_8] Pass - null T02
[Thread_7] Pass - null T02
[Thread_4] Pass - null T03
[Thread_0] Pass - null T03
[Thread_1] Pass - null T03
[Thread_7] Pass - null T03
[Thread_8] Pass - null T03
[Thread_9] Pass - null T_CheckStatus
[Thread_6] Pass - null T_CheckStatus
[Thread_6] Iteration 2 took 110099ms
[Thread_9] Iteration 2 took 110099ms
[Thread_9] Total memory increased by 0 is 4389376
[Thread_6] Total memory increased by 0 is 4389376
[Thread_9] Used  memory increased by 12880 is 1479200
[Thread_6] Used  memory increased by 12264 is 1479976
[Thread_4] Pass - null T_CheckStatus
[Thread_4] Iteration 2 took 110022ms
[Thread_6] Pass - null T01
[Thread_9] Pass - null T01
[Thread_4] Total memory increased by 0 is 4389376
[Thread_4] Used  memory increased by 11112 is 1480296
[Thread_0] Pass - null T_CheckStatus
[Thread_1] Pass - null T_CheckStatus
[Thread_0] Iteration 2 took 109928ms
[Thread_4] Pass - null T01
[Thread_1] Iteration 2 took 109928ms
[Thread_0] Total memory increased by 0 is 4389376
[Thread_1] Total memory increased by 0 is 4389376
[Thread_0] Used  memory increased by 10832 is 1482880
[Thread_1] Used  memory increased by 12696 is 1483856
[Thread_6] Pass - null T02
[Thread_9] Pass - null T02
[Thread_1] Pass - null T01
[Thread_0] Pass - null T01
[Thread_7] Pass - null T_CheckStatus
[Thread_8] Pass - null T_CheckStatus
[Thread_7] Iteration 2 took 110022ms
[Thread_8] Iteration 2 took 110006ms
[Thread_8] Total memory increased by 0 is 4389376
[Thread_8] Used  memory increased by 17008 is 1488232
[Thread_7] Total memory increased by 0 is 4389376
[Thread_7] Used  memory increased by 16168 is 1487896
[Thread_4] Pass - null T02
[Thread_7] Pass - null T01
[Thread_0] Pass - null T02
[Thread_1] Pass - null T02
[Thread_8] Pass - null T01
[Thread_9] Pass - null T03
[Thread_6] Pass - null T03
[Thread_7] Pass - null T02
[Thread_8] Pass - null T02
[Thread_4] Pass - null T03
[Thread_1] Pass - null T03
[Thread_0] Pass - null T03
[Thread_8] Pass - null T03
[Thread_7] Pass - null T03
[Thread_9] Pass - null T_CheckStatus
[Thread_6] Pass - null T_CheckStatus
[Thread_9] Iteration 3 took 113674ms
[Thread_6] Iteration 3 took 113674ms
[Thread_9] Total memory increased by 0 is 4389376
[Thread_9] Used  memory increased by 15632 is 1495208
[Thread_6] Total memory increased by 0 is 4389376
[Thread_6] Used  memory increased by 14984 is 1494408
[Thread_6] Pass - null T01
[Thread_9] Pass - null T01
[Thread_6] Pass - null T02
[Thread_9] Pass - null T02
[Thread_4] Pass - null T_CheckStatus
[Thread_0] Pass - null T_CheckStatus
[Thread_4] Iteration 3 took 124708ms
[Thread_1] Pass - null T_CheckStatus
[Thread_4] Total memory increased by 0 is 4389376
[Thread_4] Used  memory increased by 21256 is 1500992
[Thread_0] Iteration 3 took 114702ms
[Thread_0] Total memory increased by 0 is 4389376
[Thread_0] Used  memory increased by 17128 is 1499688
[Thread_1] Iteration 3 took 114687ms
[Thread_1] Total memory increased by 0 is 4389376
[Thread_1] Used  memory increased by 17296 is 1499688
[Thread_4] Pass - null T01
[Thread_0] Pass - null T01
[Thread_1] Pass - null T01
[Thread_4] Pass - null T02
[Thread_0] Pass - null T02
[Thread_8] Pass - null T_CheckStatus
[Thread_8] Iteration 3 took 135667ms
[Thread_8] Total memory increased by 0 is 4389376
[Thread_8] Used  memory increased by 20400 is 1509016
[Thread_7] Pass - null T_CheckStatus
[Thread_7] Iteration 3 took 135667ms
[Thread_7] Total memory increased by 0 is 4389376
[Thread_7] Used  memory increased by 22912 is 1509752
[Thread_9] Pass - null T03
[Thread_6] Pass - null T03
[Thread_1] Pass - null T02
[Thread_7] Pass - null T01
[Thread_8] Pass - null T01
[Thread_4] Pass - null T03
[Thread_0] Pass - null T03
[Thread_7] Pass - null T02
[Thread_8] Pass - null T02
[Thread_1] Pass - null T03
[Thread_7] Pass - null T03
[Thread_8] Pass - null T03
[Thread_9] Pass - null T_CheckStatus
[Thread_6] Pass - null T_CheckStatus
[Thread_9] Iteration 4 took 115216ms
[Thread_6] Iteration 4 took 115216ms
[Thread_9] Total memory increased by 0 is 4389376
[Thread_6] Total memory increased by 0 is 4389376
[Thread_6] Used  memory increased by 19616 is 1514848
[Thread_6] finished with no error
[Thread_9] Used  memory increased by 19264 is 1514088
[Thread_9] finished with no error
[Thread_4] Pass - null T_CheckStatus
[Thread_4] Iteration 4 took 115085ms
[Thread_0] Pass - null T_CheckStatus
[Thread_4] Total memory increased by 0 is 4389376
[Thread_0] Iteration 4 took 115069ms
[Thread_4] Used  memory increased by 12480 is 1512168
[Thread_0] Total memory increased by 0 is 4389376
[Thread_4] finished with no error
[Thread_0] Used  memory increased by 10560 is 1512368
[Thread_0] finished with no error
[Thread_1] Pass - null T_CheckStatus
[Thread_1] Iteration 4 took 135931ms
[Thread_1] Total memory increased by 0 is 4389376
[Thread_1] Used  memory increased by 10800 is 1511608
[Thread_1] finished with no error
[Thread_7] Pass - null T_CheckStatus
[Thread_8] Pass - null T_CheckStatus
[Thread_7] Iteration 4 took 135736ms
[Thread_8] Iteration 4 took 135752ms
[Thread_7] Total memory increased by 0 is 4389376
[Thread_8] Total memory increased by 0 is 4389376
[Thread_7] Used  memory increased by 2896 is 1511848
[Thread_8] Used  memory increased by 3720 is 1512792
[Thread_7] finished with no error
[Thread_8] finished with no error
[main] FAIL - org.apache.derbyTesting.unitTests.harness.T_Fail: The test failed with an exception:
java.lang.InterruptedException: JVMLK007: operation interrupted
org.apache.derbyTesting.unitTests.harness.T_Fail: The test failed with an exception: java.lang.InterruptedException:
JVMLK007: operation interrupted
	at org.apache.derbyTesting.unitTests.harness.T_Fail.exceptionFail(T_Fail.java:72)
	at org.apache.derbyTesting.unitTests.harness.T_MultiThreadedIterations.runTests(T_MultiThreadedIterations.java:147)
	at org.apache.derbyTesting.unitTests.harness.T_Generic.Execute(T_Generic.java:117)
	at org.apache.derbyTesting.unitTests.harness.BasicUnitTestManager.runATest(BasicUnitTestManager.java:183)
	at org.apache.derbyTesting.unitTests.harness.BasicUnitTestManager.runTests(BasicUnitTestManager.java:245)
	at org.apache.derbyTesting.unitTests.harness.BasicUnitTestManager.boot(BasicUnitTestManager.java:92)
	at org.apache.derby.impl.services.monitor.BaseMonitor.boot(BaseMonitor.java:1996)
	at org.apache.derby.impl.services.monitor.TopService.bootModule(TopService.java:290)
	at org.apache.derby.impl.services.monitor.BaseMonitor.bootService(BaseMonitor.java:1831)
	at org.apache.derby.impl.services.monitor.BaseMonitor.startServices(BaseMonitor.java:966)
	at org.apache.derby.impl.services.monitor.BaseMonitor.runWithState(BaseMonitor.java:398)
	at org.apache.derby.impl.services.monitor.FileMonitor.<init>(FileMonitor.java:59)
	at org.apache.derby.iapi.services.monitor.Monitor.startMonitor(Monitor.java:288)
	at org.apache.derbyTesting.unitTests.harness.UnitTestMain.main(UnitTestMain.java:50)
2006-06-15 16:59:14.953 GMT Thread[main,5,main] Test 'org.apache.derbyTesting.unitTests.services.T_DaemonService'
failed
2006-06-15 16:59:14.953 GMT Thread[main,5,main] Test Summary - Run 1, Passed 0, Failed 1,
Skipped 0.
2006-06-15 16:59:14.953 GMT Thread[main,5,main] Shutting down due to unit test failure, see
log for more information.
2006-06-15 16:59:14.969 GMT Thread[main,5,main] 2006-06-15 16:59:14.969 GMT Thread[main,5,main]
Startup failed due to an exception. See next exception for details. 
ERROR XBM01: Startup failed due to an exception. See next exception for details. 
	at org.apache.derby.iapi.error.StandardException.newException(StandardException.java:297)
	at org.apache.derby.iapi.services.monitor.Monitor.exceptionStartingModule(Monitor.java:668)
	at org.apache.derby.impl.services.monitor.BaseMonitor.bootService(BaseMonitor.java:1849)
	at org.apache.derby.impl.services.monitor.BaseMonitor.startServices(BaseMonitor.java:966)
	at org.apache.derby.impl.services.monitor.BaseMonitor.runWithState(BaseMonitor.java:398)
	at org.apache.derby.impl.services.monitor.FileMonitor.<init>(FileMonitor.java:59)
	at org.apache.derby.iapi.services.monitor.Monitor.startMonitor(Monitor.java:288)
	at org.apache.derbyTesting.unitTests.harness.UnitTestMain.main(UnitTestMain.java:50)
============= begin nested exception, level (1) ===========
java.lang.NullPointerException
	at org.apache.derby.impl.services.monitor.TopService.getService(TopService.java:129)
	at org.apache.derby.impl.services.monitor.BaseMonitor.shutdown(BaseMonitor.java:197)
	at org.apache.derbyTesting.unitTests.harness.BasicUnitTestManager.boot(BasicUnitTestManager.java:102)
	at org.apache.derby.impl.services.monitor.BaseMonitor.boot(BaseMonitor.java:1996)
	at org.apache.derby.impl.services.monitor.TopService.bootModule(TopService.java:290)
	at org.apache.derby.impl.services.monitor.BaseMonitor.bootService(BaseMonitor.java:1831)
	at org.apache.derby.impl.services.monitor.BaseMonitor.startServices(BaseMonitor.java:966)
	at org.apache.derby.impl.services.monitor.BaseMonitor.runWithState(BaseMonitor.java:398)
	at org.apache.derby.impl.services.monitor.FileMonitor.<init>(FileMonitor.java:59)
	at org.apache.derby.iapi.services.monitor.Monitor.startMonitor(Monitor.java:288)
	at org.apache.derbyTesting.unitTests.harness.UnitTestMain.main(UnitTestMain.java:50)
============= end nested exception, level (1) ===========

> unit/daemonService.unit fails intermittently: 'ran out of time'
> ---------------------------------------------------------------
>
>          Key: DERBY-989
>          URL: http://issues.apache.org/jira/browse/DERBY-989
>      Project: Derby
>         Type: Test

>   Components: Regression Test Failure
>     Versions: 10.2.0.0
>  Environment: OS: Solaris 10 3/05 s10_74L2a X86 - SunOS 5.10 Generic, JVM: Sun Microsystems
Inc. 1.5.0_04
> OS: Solaris 9 9/04 s9s_u7wos_09 SPARC - SunOS 5.9 Generic_118558-11, JVM: Sun Microsystems
Inc. 1.5.0_03
> OS: Red Hat Enterprise Linux AS release 3 (Taroon Update 4) - Linux 2.4.21-27.ELsmp #1
SMP Wed Dec 1 21:50:31 EST 2004 GNU/Linux, JVM: Sun Microsystems Inc. 1.5.0_03
>     Reporter: Ole Solberg
>     Priority: Minor

>
> "Signature":
> ********* Diff file unit/unit/daemonService.diff
> *** Start: daemonService jdk1.5.0_04 unit:unit 2006-02-14 20:46:42 ***
> 2 del
> < -- Unit Test T_DaemonService finished
> 2 add
> > ran out of time
> > Shutting down due to unit test failure.
> > Exit due to time bomb
> Test Failed.
> *** End:   daemonService jdk1.5.0_04 unit:unit 2006-02-14 21:47:13 ***
> http://www.multinet.no/~solberg/public/Apache/Derby/Limited/testSummary-377800.html [SunOS-5.10
i86pc-i386]

-- 
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
   http://issues.apache.org/jira/secure/Administrators.jspa
-
For more information on JIRA, see:
   http://www.atlassian.com/software/jira


Mime
View raw message