Return-Path: Delivered-To: apmail-db-derby-dev-archive@www.apache.org Received: (qmail 71444 invoked from network); 15 Jun 2006 23:30:51 -0000 Received: from hermes.apache.org (HELO mail.apache.org) (209.237.227.199) by minotaur.apache.org with SMTP; 15 Jun 2006 23:30:51 -0000 Received: (qmail 57292 invoked by uid 500); 15 Jun 2006 23:30:50 -0000 Delivered-To: apmail-db-derby-dev-archive@db.apache.org Received: (qmail 57263 invoked by uid 500); 15 Jun 2006 23:30:50 -0000 Mailing-List: contact derby-dev-help@db.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: Delivered-To: mailing list derby-dev@db.apache.org Received: (qmail 57254 invoked by uid 99); 15 Jun 2006 23:30:50 -0000 Received: from asf.osuosl.org (HELO asf.osuosl.org) (140.211.166.49) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 15 Jun 2006 16:30:50 -0700 X-ASF-Spam-Status: No, hits=0.0 required=10.0 tests= X-Spam-Check-By: apache.org Received: from [209.237.227.198] (HELO brutus.apache.org) (209.237.227.198) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 15 Jun 2006 16:30:48 -0700 Received: from brutus (localhost [127.0.0.1]) by brutus.apache.org (Postfix) with ESMTP id B785641000F for ; Thu, 15 Jun 2006 23:29:31 +0000 (GMT) Message-ID: <29255949.1150414171749.JavaMail.jira@brutus> Date: Thu, 15 Jun 2006 23:29:31 +0000 (GMT+00:00) From: "Mike Matrigali (JIRA)" To: derby-dev@db.apache.org Subject: [jira] Updated: (DERBY-989) unit/daemonService.unit fails intermittently: 'ran out of time' In-Reply-To: <1871496922.1140001869422.JavaMail.jira@ajax.apache.org> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-Virus-Checked: Checked by ClamAV on apache.org X-Spam-Rating: minotaur.apache.org 1.6.2 0/1000/N [ 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.(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.(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.(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