Help needed with concurrency bug

Sat, 24 Dec 2011 03:45:45 -0800 

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. 

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

Reply via email to