On 24 December 2011 11:36, Peter Firmstone <j...@zeus.net.au> wrote: > I'm experiencing some failing tests: > > run.tests=com/sun/jini/test/spec/lookupservice/test_set00/MultipleEvntLeaseRenewals.td,\ > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrAdd.td,\ > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrDel.td,\ > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrMod.td,\ > com/sun/jini/test/spec/lookupservice/test_set02/NotifyOnAttrSet.td,\ > com/sun/jini/test/impl/start/aggregatepolicyprovider/GetContextTest.td > > Not sure about the last test which is failing a permission check, but the > first 5 are dropping events, without there being any security exceptions, so > I'm guessing the policy's concurrency has exposed another bug.
Where "bug" is potentially a swallowed exception. Reggie for the most part holds a writeLock for any significant invocation from the client and events are dispatched via a TaskManager. Seems like an unlikely source for problems. Am I right in assuming the test itself is single-threaded? Sure looks like it is? That would suggest to me a problem in the remote comms layer if anywhere. My biggest worry would be that your permissions work is leading to a security exception or similar and it's just being swallowed. Take that worry and combine it with the fact that the last failing test does throw an exception and doesn't touch remote services or indeed the JERI layer, I'd say this is the test to look at first and perhaps we're not looking at an additional bug. > > http://svn.apache.org/viewvc/river/jtsk/skunk/peterConcurrentPolicy/ > > Any help would be much appreciated ;) > > bash-3.00$ ant run-tests > Buildfile: build.xml > > james-brown: > [delete] Deleting directory > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/soul > [mkdir] Created dir: > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/soul > [touch] Creating > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/soul/soul.201112241952044566 > > run-tests: > [java] > [java] ----------------------------------------- > [java] CONFIGURATION FILE: > [java] > [java] > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/src/com/sun/jini/test/resources/qaHarness.prop > [java] > [java] ----------------------------------------- > [java] SETTING UP THE TEST LIST: > [java] > [java] Adding test: > com/sun/jini/test/spec/lookupservice/test_set00/MultipleEvntLeaseRenewals.td > [java] Adding test: > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrAdd.td > [java] Adding test: > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrDel.td > [java] Adding test: > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrMod.td > [java] Adding test: > com/sun/jini/test/spec/lookupservice/test_set02/NotifyOnAttrSet.td > [java] Adding test: > com/sun/jini/test/impl/start/aggregatepolicyprovider/GetContextTest.td > [java] > [java] ----------------------------------------- > [java] GENERAL HARNESS CONFIGURATION INFORMATION: > [java] > [java] Date started: > [java] Sat Dec 24 19:52:47 EST 2011 > [java] Installation directory of the JSK: > [java] > com.sun.jini.jsk.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy > [java] Installation directory of the harness: > [java] > com.sun.jini.qa.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > [java] Categories being tested: > [java] categories=No Categories > [java] ----------------------------------------- > [java] ENVIRONMENT PROPERTIES: > [java] > [java] JVM information: > [java] Java HotSpot(TM) Server VM, 20.0-b11, 32 bit VM mode > [java] Sun Microsystems Inc. > [java] OS information: > [java] SunOS, 5.10, sparc > [java] > [java] ----------------------------------------- > [java] STARTING TO RUN THE TESTS > [java] > [java] > [java] Running > com/sun/jini/test/spec/lookupservice/test_set00/MultipleEvntLeaseRenewals.td > [java] Time is Sat Dec 24 19:52:47 EST 2011 > [java] Starting test in separate process with command: > [java] /usr/jdk/instances/jdk1.6.0/jre/bin/java > -Djava.security.policy=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/policy/defaulttest.policy > -Djava.rmi.server.codebase=http://bluto:9082/qa1-lookupservice-dl.jar -cp > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-platform.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-lib.jar > -client > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > -Dcom.sun.jini.jsk.port=9080 -Dcom.sun.jini.qa.port=9081 > -Dcom.sun.jini.jsk.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy > -Dcom.sun.jini.qa.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.qa.harness.harnessJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar > -Dcom.sun.jini.qa.harness.testJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar > -Dcom.sun.jini.qa.harness.runjiniserver=true > -Dcom.sun.jini.qa.harness.runkitserver=true > -Djava.security.properties=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/trust/dynamic-policy.properties > -Dcom.sun.jini.qa.harness.testhosts= > -Djava.util.logging.config.file=/home/peter/logging.properties > -Dcom.sun.jini.test.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.test.port=9082 > -Dcom.sun.jini.qa.harness.policies=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/src/com/sun/jini/test/resources/jinitest.policy > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > com.sun.jini.qa.harness.MasterTest > com/sun/jini/test/spec/lookupservice/test_set00/MultipleEvntLeaseRenewals.td > [java] > [java] TIME: 7:52:51 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================== CALLING SETUP() > ============================== > [java] > [java] 24/12/2011 7:52:52 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9081] > [java] 24/12/2011 7:52:52 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-dl/], port 9080] > [java] 24/12/2011 7:52:53 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9082] > [java] NonActGrp-out: 24/12/2011 7:53:06 PM > com.sun.jini.reggie.RegistrarImpl init > [java] NonActGrp-out: INFO: started Reggie: > c14d2980-a0fa-49e5-b2fc-c9cf521828a4, [], > ConstrainableLookupLocator[[jini://bluto/], [null]] > [java] > [java] TIME: 7:53:34 PM > [java] > [java] MasterTest.doTest INFO: > [java] =============================== CALLING RUN() > =============================== > [java] > [java] com.sun.jini.qa.harness.TestException: # of Events Received (35) > != # of Events Expected (50) > [java] at > com.sun.jini.test.spec.lookupservice.test_set00.MultipleEvntLeaseRenewals.verifyNotification(MultipleEvntLeaseRenewals.java:285) > [java] at > com.sun.jini.test.spec.lookupservice.test_set00.MultipleEvntLeaseRenewals.run(MultipleEvntLeaseRenewals.java:218) > [java] at > com.sun.jini.qa.harness.MasterTest.doTest(MasterTest.java:252) > [java] at > com.sun.jini.qa.harness.MasterTest.main(MasterTest.java:142) > [java] > [java] TIME: 7:58:29 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================ CALLING TEARDOWN() > ============================= > [java] > [java] NonActGrp-out: 24/12/2011 7:58:30 PM > com.sun.jini.reggie.RegistrarImpl destroy > [java] NonActGrp-out: INFO: starting Reggie shutdown > [java] NonActGrp-out: 24/12/2011 7:58:30 PM > com.sun.jini.reggie.RegistrarImpl$DestroyThread run > [java] NonActGrp-out: INFO: Reggie shutdown completed > [java] 24/12/2011 7:58:32 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 7:58:32 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 7:58:32 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 7:58:32 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 7:58:32 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] 24/12/2011 7:58:32 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] > [java] TIME: 7:58:33 PM > [java] > [java] Test process was destroyed and returned code 1 > [java] > com/sun/jini/test/spec/lookupservice/test_set00/MultipleEvntLeaseRenewals.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (35) != # of Events Expected (50) > [java] > [java] > [java] ----------------------------------------- > [java] > [java] Running > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrAdd.td > [java] Time is Sat Dec 24 19:58:38 EST 2011 > [java] Starting test in separate process with command: > [java] /usr/jdk/instances/jdk1.6.0/jre/bin/java > -Djava.security.policy=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/policy/defaulttest.policy > -Djava.rmi.server.codebase=http://bluto:9082/qa1-lookupservice-dl.jar -cp > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-platform.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-lib.jar > -client > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > -Dcom.sun.jini.jsk.port=9080 -Dcom.sun.jini.qa.port=9081 > -Dcom.sun.jini.jsk.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy > -Dcom.sun.jini.qa.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.qa.harness.harnessJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar > -Dcom.sun.jini.qa.harness.testJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar > -Dcom.sun.jini.qa.harness.runjiniserver=true > -Dcom.sun.jini.qa.harness.runkitserver=true > -Djava.security.properties=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/trust/dynamic-policy.properties > -Dcom.sun.jini.qa.harness.testhosts= > -Djava.util.logging.config.file=/home/peter/logging.properties > -Dcom.sun.jini.test.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.test.port=9082 > -Dcom.sun.jini.qa.harness.policies=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/src/com/sun/jini/test/resources/jinitest.policy > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > com.sun.jini.qa.harness.MasterTest > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrAdd.td > [java] > [java] TIME: 7:58:41 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================== CALLING SETUP() > ============================== > [java] > [java] 24/12/2011 7:58:42 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9081] > [java] 24/12/2011 7:58:42 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-dl/], port 9080] > [java] 24/12/2011 7:58:43 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9082] > [java] NonActGrp-out: 24/12/2011 7:58:58 PM > com.sun.jini.reggie.RegistrarImpl init > [java] NonActGrp-out: INFO: started Reggie: > db79cb8e-d6b2-47c4-8c98-7519cf555cfe, [], > ConstrainableLookupLocator[[jini://bluto/], [null]] > [java] > [java] TIME: 7:59:27 PM > [java] > [java] MasterTest.doTest INFO: > [java] =============================== CALLING RUN() > =============================== > [java] > [java] com.sun.jini.qa.harness.TestException: # of Events Received (23) > != # of Events Expected (50) > [java] at > com.sun.jini.test.spec.lookupservice.QATestUtils.verifyEventVector(QATestUtils.java:473) > [java] at > com.sun.jini.test.spec.lookupservice.test_set00.NotifyOnAttrAdd.run(NotifyOnAttrAdd.java:183) > [java] at > com.sun.jini.qa.harness.MasterTest.doTest(MasterTest.java:252) > [java] at > com.sun.jini.qa.harness.MasterTest.main(MasterTest.java:142) > [java] > [java] TIME: 7:59:37 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================ CALLING TEARDOWN() > ============================= > [java] > [java] NonActGrp-out: 24/12/2011 7:59:38 PM > com.sun.jini.reggie.RegistrarImpl destroy > [java] NonActGrp-out: INFO: starting Reggie shutdown > [java] NonActGrp-out: 24/12/2011 7:59:38 PM > com.sun.jini.reggie.RegistrarImpl$DestroyThread run > [java] NonActGrp-out: INFO: Reggie shutdown completed > [java] 24/12/2011 7:59:40 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 7:59:40 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] 24/12/2011 7:59:40 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 7:59:40 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 7:59:40 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 7:59:40 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] > [java] TIME: 7:59:41 PM > [java] > [java] Test process was destroyed and returned code 1 > [java] com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrAdd.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (23) != # of Events Expected (50) > [java] > [java] > [java] ----------------------------------------- > [java] > [java] Running > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrDel.td > [java] Time is Sat Dec 24 19:59:46 EST 2011 > [java] Starting test in separate process with command: > [java] /usr/jdk/instances/jdk1.6.0/jre/bin/java > -Djava.security.policy=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/policy/defaulttest.policy > -Djava.rmi.server.codebase=http://bluto:9082/qa1-lookupservice-dl.jar -cp > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-platform.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-lib.jar > -client > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > -Dcom.sun.jini.jsk.port=9080 -Dcom.sun.jini.qa.port=9081 > -Dcom.sun.jini.jsk.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy > -Dcom.sun.jini.qa.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.qa.harness.harnessJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar > -Dcom.sun.jini.qa.harness.testJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar > -Dcom.sun.jini.qa.harness.runjiniserver=true > -Dcom.sun.jini.qa.harness.runkitserver=true > -Djava.security.properties=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/trust/dynamic-policy.properties > -Dcom.sun.jini.qa.harness.testhosts= > -Djava.util.logging.config.file=/home/peter/logging.properties > -Dcom.sun.jini.test.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.test.port=9082 > -Dcom.sun.jini.qa.harness.policies=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/src/com/sun/jini/test/resources/jinitest.policy > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > com.sun.jini.qa.harness.MasterTest > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrDel.td > [java] > [java] TIME: 7:59:49 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================== CALLING SETUP() > ============================== > [java] > [java] 24/12/2011 7:59:50 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9081] > [java] 24/12/2011 7:59:51 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-dl/], port 9080] > [java] 24/12/2011 7:59:52 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9082] > [java] NonActGrp-out: 24/12/2011 8:00:08 PM > com.sun.jini.reggie.RegistrarImpl init > [java] NonActGrp-out: INFO: started Reggie: > e0627d4b-f59f-4a98-88f7-27fdfd250a6e, [], > ConstrainableLookupLocator[[jini://bluto/], [null]] > [java] > [java] TIME: 8:00:40 PM > [java] > [java] MasterTest.doTest INFO: > [java] =============================== CALLING RUN() > =============================== > [java] > [java] com.sun.jini.qa.harness.TestException: # of Events Received (24) > != # of Events Expected (50) > [java] at > com.sun.jini.test.spec.lookupservice.QATestUtils.verifyEventVector(QATestUtils.java:473) > [java] at > com.sun.jini.test.spec.lookupservice.test_set00.NotifyOnAttrDel.run(NotifyOnAttrDel.java:203) > [java] at > com.sun.jini.qa.harness.MasterTest.doTest(MasterTest.java:252) > [java] at > com.sun.jini.qa.harness.MasterTest.main(MasterTest.java:142) > [java] MasterTest.doTest INFO: > [java] ============================ CALLING TEARDOWN() > ============================= > [java] > [java] NonActGrp-out: 24/12/2011 8:00:50 PM > com.sun.jini.reggie.RegistrarImpl destroy > [java] NonActGrp-out: INFO: starting Reggie shutdown > [java] NonActGrp-out: 24/12/2011 8:00:50 PM > com.sun.jini.reggie.RegistrarImpl$DestroyThread run > [java] NonActGrp-out: INFO: Reggie shutdown completed > [java] 24/12/2011 8:00:52 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 8:00:52 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] 24/12/2011 8:00:52 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 8:00:52 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 8:00:52 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] 24/12/2011 8:00:52 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] > [java] TIME: 8:00:53 PM > [java] > [java] Test process was destroyed and returned code 1 > [java] com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrDel.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (24) != # of Events Expected (50) > [java] > [java] > [java] ----------------------------------------- > [java] > [java] Running > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrMod.td > [java] Time is Sat Dec 24 20:00:58 EST 2011 > [java] Starting test in separate process with command: > [java] /usr/jdk/instances/jdk1.6.0/jre/bin/java > -Djava.security.policy=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/policy/defaulttest.policy > -Djava.rmi.server.codebase=http://bluto:9082/qa1-lookupservice-dl.jar -cp > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-platform.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-lib.jar > -client > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > -Dcom.sun.jini.jsk.port=9080 -Dcom.sun.jini.qa.port=9081 > -Dcom.sun.jini.jsk.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy > -Dcom.sun.jini.qa.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.qa.harness.harnessJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar > -Dcom.sun.jini.qa.harness.testJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar > -Dcom.sun.jini.qa.harness.runjiniserver=true > -Dcom.sun.jini.qa.harness.runkitserver=true > -Djava.security.properties=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/trust/dynamic-policy.properties > -Dcom.sun.jini.qa.harness.testhosts= > -Djava.util.logging.config.file=/home/peter/logging.properties > -Dcom.sun.jini.test.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.test.port=9082 > -Dcom.sun.jini.qa.harness.policies=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/src/com/sun/jini/test/resources/jinitest.policy > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > com.sun.jini.qa.harness.MasterTest > com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrMod.td > [java] > [java] TIME: 8:01:01 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================== CALLING SETUP() > ============================== > [java] > [java] 24/12/2011 8:01:02 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9081] > [java] 24/12/2011 8:01:03 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-dl/], port 9080] > [java] 24/12/2011 8:01:04 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9082] > [java] NonActGrp-out: 24/12/2011 8:01:21 PM > com.sun.jini.reggie.RegistrarImpl init > [java] NonActGrp-out: INFO: started Reggie: > 4c1e8624-a70c-4cf6-a3fa-95b8bde54936, [], > ConstrainableLookupLocator[[jini://bluto/], [null]] > [java] > [java] TIME: 8:01:55 PM > [java] > [java] MasterTest.doTest INFO: > [java] =============================== CALLING RUN() > =============================== > [java] > [java] com.sun.jini.qa.harness.TestException: # of Events Received (20) > != # of Events Expected (50) > [java] at > com.sun.jini.test.spec.lookupservice.QATestUtils.verifyEventVector(QATestUtils.java:473) > [java] at > com.sun.jini.test.spec.lookupservice.test_set00.NotifyOnAttrMod.run(NotifyOnAttrMod.java:204) > [java] at > com.sun.jini.qa.harness.MasterTest.doTest(MasterTest.java:252) > [java] at > com.sun.jini.qa.harness.MasterTest.main(MasterTest.java:142) > [java] > [java] TIME: 8:02:06 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================ CALLING TEARDOWN() > ============================= > [java] > [java] NonActGrp-out: 24/12/2011 8:02:06 PM > com.sun.jini.reggie.RegistrarImpl destroy > [java] NonActGrp-out: INFO: starting Reggie shutdown > [java] NonActGrp-out: 24/12/2011 8:02:06 PM > com.sun.jini.reggie.RegistrarImpl$DestroyThread run > [java] NonActGrp-out: INFO: Reggie shutdown completed > [java] 24/12/2011 8:02:09 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 8:02:09 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 8:02:09 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 8:02:09 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 8:02:09 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] 24/12/2011 8:02:09 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] > [java] TIME: 8:02:10 PM > [java] > [java] Test process was destroyed and returned code 1 > [java] com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrMod.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (20) != # of Events Expected (50) > [java] > [java] > [java] ----------------------------------------- > [java] > [java] Running > com/sun/jini/test/spec/lookupservice/test_set02/NotifyOnAttrSet.td > [java] Time is Sat Dec 24 20:02:15 EST 2011 > [java] Starting test in separate process with command: > [java] /usr/jdk/instances/jdk1.6.0/jre/bin/java > -Djava.security.policy=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/policy/defaulttest.policy > -Djava.rmi.server.codebase=http://bluto:9082/qa1-lookupservice-dl.jar -cp > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-platform.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-lib.jar > -client > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > -Dcom.sun.jini.jsk.port=9080 -Dcom.sun.jini.qa.port=9081 > -Dcom.sun.jini.jsk.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy > -Dcom.sun.jini.qa.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.qa.harness.harnessJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar > -Dcom.sun.jini.qa.harness.testJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar > -Dcom.sun.jini.qa.harness.runjiniserver=true > -Dcom.sun.jini.qa.harness.runkitserver=true > -Djava.security.properties=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/trust/dynamic-policy.properties > -Dcom.sun.jini.qa.harness.testhosts= > -Djava.util.logging.config.file=/home/peter/logging.properties > -Dcom.sun.jini.test.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.test.port=9082 > -Dcom.sun.jini.qa.harness.policies=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/src/com/sun/jini/test/resources/jinitest.policy > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > com.sun.jini.qa.harness.MasterTest > com/sun/jini/test/spec/lookupservice/test_set02/NotifyOnAttrSet.td > [java] > [java] TIME: 8:02:17 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================== CALLING SETUP() > ============================== > [java] > [java] 24/12/2011 8:02:18 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9081] > [java] 24/12/2011 8:02:19 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-dl/], port 9080] > [java] 24/12/2011 8:02:19 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9082] > [java] NonActGrp-out: 24/12/2011 8:02:33 PM > com.sun.jini.reggie.RegistrarImpl init > [java] NonActGrp-out: INFO: started Reggie: > 26bb9126-c21f-4220-b2fd-9619dbb40538, [], > ConstrainableLookupLocator[[jini://bluto/], [null]] > [java] > [java] TIME: 8:03:04 PM > [java] > [java] MasterTest.doTest INFO: > [java] =============================== CALLING RUN() > =============================== > [java] > [java] com.sun.jini.qa.harness.TestException: # of Events Received (19) > != # of Events Expected (50) > [java] at > com.sun.jini.test.spec.lookupservice.QATestUtils.verifyEventVector(QATestUtils.java:473) > [java] at > com.sun.jini.test.spec.lookupservice.test_set02.NotifyOnAttrSet.run(NotifyOnAttrSet.java:220) > [java] at > com.sun.jini.qa.harness.MasterTest.doTest(MasterTest.java:252) > [java] at > com.sun.jini.qa.harness.MasterTest.main(MasterTest.java:142) > [java] MasterTest.doTest INFO: > [java] ============================ CALLING TEARDOWN() > ============================= > [java] > [java] NonActGrp-out: 24/12/2011 8:03:14 PM > com.sun.jini.reggie.RegistrarImpl destroy > [java] NonActGrp-out: INFO: starting Reggie shutdown > [java] NonActGrp-out: 24/12/2011 8:03:15 PM > com.sun.jini.reggie.RegistrarImpl$DestroyThread run > [java] NonActGrp-out: INFO: Reggie shutdown completed > [java] 24/12/2011 8:03:16 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 8:03:16 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 8:03:16 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 8:03:16 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 8:03:16 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] 24/12/2011 8:03:16 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] > [java] TIME: 8:03:17 PM > [java] > [java] Test process was destroyed and returned code 1 > [java] com/sun/jini/test/spec/lookupservice/test_set02/NotifyOnAttrSet.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (19) != # of Events Expected (50) > [java] > [java] > [java] ----------------------------------------- > [java] > [java] Running > com/sun/jini/test/impl/start/aggregatepolicyprovider/GetContextTest.td > [java] Time is Sat Dec 24 20:03:22 EST 2011 > [java] Starting test in separate process with command: > [java] /usr/jdk/instances/jdk1.6.0/jre/bin/java > -Djava.security.policy=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/src/com/sun/jini/test/impl/start/aggregatepolicyprovider/GetContextTest.policy > -Djava.rmi.server.codebase=http://bluto:9082/qa1-start-probe-dl.jar -cp > /opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar:lib/qa1-start-tests.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-platform.jar:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib/jsk-lib.jar > -client > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > -Dcom.sun.jini.jsk.port=9080 -Dcom.sun.jini.qa.port=9081 > -Dcom.sun.jini.jsk.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy > -Dcom.sun.jini.qa.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.qa.harness.harnessJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jiniharness.jar > -Dcom.sun.jini.qa.harness.testJar=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/jinitests.jar > -Dcom.sun.jini.qa.harness.runjiniserver=true > -Dcom.sun.jini.qa.harness.runkitserver=true > -Djava.security.properties=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/harness/trust/dynamic-policy.properties > -Dcom.sun.jini.qa.harness.testhosts= > -Djava.util.logging.config.file=/home/peter/logging.properties > -Dcom.sun.jini.test.home=/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa > -Dcom.sun.jini.test.port=9082 > -Dcom.sun.jini.qa.harness.policies=file:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/src/com/sun/jini/test/resources/jinitest.policy > -Djava.ext.dirs=/usr/jdk/instances/jdk1.6.0/jre/lib/ext:/usr/jdk/packages/lib/ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib-ext:/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-ext > com.sun.jini.qa.harness.MasterTest > com/sun/jini/test/impl/start/aggregatepolicyprovider/GetContextTest.td > [java] > [java] TIME: 8:03:25 PM > [java] > [java] MasterTest.doTest INFO: > [java] ============================== CALLING SETUP() > ============================== > [java] > [java] 24/12/2011 8:03:26 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9081] > [java] 24/12/2011 8:03:26 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/lib-dl/], port 9080] > [java] 24/12/2011 8:03:27 PM com.sun.jini.tool.ClassServer run > [java] INFO: ClassServer started > [[/opt/src/River_Fixed_2nd_Try/peterConcurrentPolicy/qa/lib/], port 9082] > [java] MasterTest.doTest INFO: > [java] =============================== CALLING RUN() > =============================== > [java] > [java] java.security.AccessControlException: access denied > (java.lang.RuntimePermission A) > [java] at > java.security.AccessControlContext.checkPermission(AccessControlContext.java:374) > [java] at > java.security.AccessController.checkPermission(AccessController.java:546) > [java] at > java.lang.SecurityManager.checkPermission(SecurityManager.java:532) > [java] at > com.sun.jini.test.impl.start.aggregatepolicyprovider.CheckContextAction.run(CheckContextAction.java:35) > [java] at > com.sun.jini.start.AggregatePolicyProvider$AggregateSecurityContext$1.run(AggregatePolicyProvider.java:513) > [java] at java.security.AccessController.doPrivileged(Native Method) > [java] at > com.sun.jini.test.impl.start.aggregatepolicyprovider.RestoreContext$Action.run(RestoreContext.java:30) > [java] at java.security.AccessController.doPrivileged(Native Method) > [java] at > com.sun.jini.test.impl.start.aggregatepolicyprovider.RestoreContext.run(RestoreContext.java:38) > [java] at > com.sun.jini.test.impl.start.aggregatepolicyprovider.GetContextTest.run(GetContextTest.java:107) > [java] at > com.sun.jini.qa.harness.MasterTest.doTest(MasterTest.java:252) > [java] at > com.sun.jini.qa.harness.MasterTest.main(MasterTest.java:142) > [java] MasterTest.doTest INFO: > [java] ============================ CALLING TEARDOWN() > ============================= > [java] > [java] 24/12/2011 8:03:27 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] 24/12/2011 8:03:27 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9082] > [java] 24/12/2011 8:03:27 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 8:03:27 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9081] > [java] 24/12/2011 8:03:27 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] 24/12/2011 8:03:27 PM com.sun.jini.tool.ClassServer terminate > [java] INFO: ClassServer terminated [port 9080] > [java] > [java] TIME: 8:03:28 PM > [java] > [java] Test process was destroyed and returned code 1 > [java] > com/sun/jini/test/impl/start/aggregatepolicyprovider/GetContextTest.td > [java] Test Failed: Test Failed with an Unexpected Exception: > java.security.AccessControlException: access denied > (java.lang.RuntimePermission A) > [java] > [java] > [java] ----------------------------------------- > [java] > [java] SUMMARY ================================= > [java] > [java] > com/sun/jini/test/spec/lookupservice/test_set00/MultipleEvntLeaseRenewals.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (35) != # of Events Expected (50) > [java] > [java] ----------------------------------------- > [java] com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrAdd.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (23) != # of Events Expected (50) > [java] > [java] ----------------------------------------- > [java] com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrDel.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (24) != # of Events Expected (50) > [java] > [java] ----------------------------------------- > [java] com/sun/jini/test/spec/lookupservice/test_set00/NotifyOnAttrMod.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (20) != # of Events Expected (50) > [java] > [java] ----------------------------------------- > [java] com/sun/jini/test/spec/lookupservice/test_set02/NotifyOnAttrSet.td > [java] Test Failed: Test Failed: com.sun.jini.qa.harness.TestException: # > of Events Received (19) != # of Events Expected (50) > [java] > [java] ----------------------------------------- > [java] > com/sun/jini/test/impl/start/aggregatepolicyprovider/GetContextTest.td > [java] Test Failed: Test Failed with an Unexpected Exception: > java.security.AccessControlException: access denied > (java.lang.RuntimePermission A) > [java] > [java] ----------------------------------------- > [java] > [java] # of tests started = 6 > [java] # of tests completed = 6 > [java] # of tests passed = 0 > [java] # of tests failed = 6 > [java] > [java] ----------------------------------------- > [java] > [java] Date finished: > [java] Sat Dec 24 20:03:33 EST 2011 > [java] Time elapsed: > [java] 646 seconds > [java] > [java] Java Result: 1 >