river-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Peter Firmstone <j...@zeus.net.au>
Subject Help needed with concurrency bug
Date Sat, 24 Dec 2011 11:36:07 GMT
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


Mime
View raw message