On 9/24/2010 2:01 PM, Patricia Shanahan wrote:
On 9/23/2010 6:15 AM, Tom Hobbs wrote:
Maybe this is a red herring, but it's nothing to do with your use of
OpenJDK, is it? Are we restricted to the Sun JDK?
For the last couple of days, I've been setting up VirtualBox
environments to run this test continuously. I just got a failure on an
OpenSolaris using only the Sun JDK - no OpenJDK installed.
This eliminates the theory that it is related to OpenJDK vs. Sun JDK. I
am more convinced than ever that this test either itself contains a low
frequency concurrency bug or brings out a low frequency concurrency bug
in River.
Unfortunately, the failure rate is inconveniently low. This failure was
preceded by 1613 consecutive passes, taking over 13 hours. I have
tracked down problems with lower failure rates, but it can be a slow
process.
I have also got one failure in 3772 runs under Ubuntu with the Sun
JDK. I think it is averaging about one failure per 2000 runs.
[java] ENVIRONMENT PROPERTIES:
[java]
[java] JVM information:
[java] Java HotSpot(TM) Client VM, 17.0-b16, 32 bit VM mode
[java] Sun Microsystems Inc.
[java] OS information:
[java] Linux, 2.6.32-21-generic, i386
[java]
[java] -----------------------------------------
[java] STARTING TO RUN THE TESTS
[java]
[java]
[java] Running com/sun/jini/test/spec/eventmailbox/EMSRIFT_PULL2.td
[java] Time is Fri Sep 24 12:08:15 PDT 2010
[java] Starting test in separate process with command:
[java] /usr/jdk/jdk1.6.0_21/jre/bin/java
-Djava.security.policy=file:/home/pats/River/qa/harness/policy/defaulttest.policy
-cp
/home/pats/River/qa/lib/jiniharness.jar:/home/pats/River/qa/lib/jinitests.jar:/home/pats/River/lib/jsk-platform.jar:/home/pats/River/lib/jsk-lib.jar
-client
-Djava.ext.dirs=/usr/jdk/jdk1.6.0_21/jre/lib/ext:/usr/java/packages/lib/ext:/home/pats/River/qa/lib-ext:/home/pats/River/lib-ext
-Dcom.sun.jini.jsk.port=8080 -Dcom.sun.jini.qa.port=8081
-Dcom.sun.jini.jsk.home=/home/pats/River
-Dcom.sun.jini.qa.home=/home/pats/River/qa
-Dcom.sun.jini.qa.harness.harnessJar=/home/pats/River/qa/lib/jiniharness.jar
-Dcom.sun.jini.qa.harness.testJar=/home/pats/River/qa/lib/jinitests.jar
-Dcom.sun.jini.qa.harness.runjiniserver=true
-Dcom.sun.jini.qa.harness.runkitserver=true
-Djava.security.properties=file:/home/pats/River/qa/harness/trust/dynamic-policy.properties
-Dcom.sun.jini.qa.harness.testhosts=
-Djava.util.logging.config.file=/home/pats/River/qa/src/com/sun/jini/test/resources/qa1.logging
-Dcom.sun.jini.test.home=/home/pats/River/qa
-Dcom.sun.jini.test.port=8082
-Dcom.sun.jini.qa.harness.policies=file:/home/pats/River/qa/src/com/sun/jini/test/resources/jinitest.policy
-Djava.ext.dirs=/usr/jdk/jdk1.6.0_21/jre/lib/ext:/usr/java/packages/lib/ext:/home/pats/River/qa/lib-ext:/home/pats/River/lib-ext
com.sun.jini.qa.harness.MasterTest
com/sun/jini/test/spec/eventmailbox/EMSRIFT_PULL2.td
[java]
[java] TIME: 12:08:16 PM
[java]
[java] MasterTest.doTest INFO:
[java] ============================== CALLING SETUP()
==============================
[java]
[java] Sep 24, 2010 12:08:16 PM com.sun.jini.tool.ClassServer run
[java] INFO: ClassServer started [[/home/pats/River/qa/lib/],
port 8081]
[java] Sep 24, 2010 12:08:16 PM com.sun.jini.tool.ClassServer run
[java] INFO: ClassServer started [[/home/pats/River/lib-dl/],
port 8080]
[java] MasterTest.doTest INFO:
[java] =============================== CALLING RUN()
===============================
[java]
[java] Sep 24, 2010 12:08:16 PM com.sun.jini.tool.ClassServer run
[java] INFO: ClassServer started [[/home/pats/River/qa/lib/],
port 8082]
[java] NonActGrp-out: Sep 24, 2010 12:08:17 PM
com.sun.jini.reggie.RegistrarImpl init
[java] NonActGrp-out: INFO: started Reggie:
d7a3752e-1a6b-4941-9e74-cb75c71a4430,
[LDSLookupServiceGroup_Verify2_1285355295530],
ConstrainableLookupLocator[[jini://Verify2/], [null]]
[java] NonActGrp-out: Sep 24, 2010 12:08:18 PM
com.sun.jini.mercury.MailboxImpl doInit
[java] NonActGrp-out: INFO: Mercury started:
com.sun.jini.mercury.transientmercuryi...@18f51f
[java] MailboxTestBase.getPullMailbox INFO: Got reference to
PullEventMailbox service:
com.sun.jini.mercury.mailboxproxy$constrainablemailboxpr...@178efc09
[java] MailboxTestBase.getPullRegistration INFO: Got reference to
MailboxPullRegistration object:
com.sun.jini.mercury.registration$constrainableregistrat...@d17f8d9
[java] MailboxTestBase.getPullRegistration INFO: registration
proxy preparer: BasicProxyPreparer[]
[java] MailboxTestBase.getPullRegistration INFO: Prepared
registration proxy:
com.sun.jini.mercury.registration$constrainableregistrat...@d17f8d9
[java] MailboxTestBase.getPullMailboxLease INFO: Calling
getPullMailboxLease()
[java] MailboxTestBase.getPullMailboxLease INFO: mailbox
lease preparer: BasicProxyPreparer[]
[java] MailboxTestBase.getPullMailboxLease INFO: Prepared
mailbox lease proxy:
LandlordLease:6f1578f8-3e9e-4fd4-b948-0610e5d4c9e5
landlord:Proxy[MailboxBackEnd,BasicInvocationHandler[BasicObjectEndpoint[99d45127-9a1e-49d4-a206-4712eed82c29,TcpEndpoint[127.0.1.1:53284]]]]
landlordUuid:acaa7a0f-9fc7-47a4-bd02-ea5699e12bf4
com.sun.jini.landlord.constrainablelandlordle...@d17f8d9
[java] MailboxTestBase.checkLease INFO: Lease request for 180000
granted
[java] EMSRIFT_PULL.run INFO: Mailbox lease good untilFri Sep 24
12:11:18 PDT 2010
[java] MailboxTestBase.getPullMailboxListener INFO: Calling
getPullMailboxListener()
[java] MailboxTestBase.getPullMailboxListener INFO: mailbox
listener preparer: BasicProxyPreparer[]
[java] MailboxTestBase.getPullMailboxListener INFO: Prepared
mailbox listener proxy:
com.sun.jini.mercury.listenerproxy$constrainablelistenerpr...@d17f8d9
[java] MailboxTestBase.checkLease INFO: Lease request for 180000
granted
[java] MailboxTestUtils: createPullListeners: created 1
TestPullListener(s)
[java] EMSRIFT_PULL.run INFO: Generating 5 events
[java] EMSRIFT_PULL.run INFO: Wating for event delivery
[java] java.rmi.ServerException: RemoteException in server
thread; nested exception is:
[java] java.rmi.NoSuchObjectException: Requested registration
objecthas expired
[java] at
net.jini.jeri.BasicInvocationDispatcher.dispatch(Unknown Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target$2.run(Unknown
Source)
[java] at
net.jini.export.ServerContext.doWithServerContext(Unknown Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target.dispatch0(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target.access$700(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target$1.run(Unknown
Source)
[java] at
com.sun.jini.start.AggregatePolicyProvider$AggregateSecurityContext$2.run(Unknown
Source)
[java] at java.security.AccessController.doPrivileged(Native
Method)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target.dispatch(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target.dispatch(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$RD.dispatch(Unknown
Source)
[java] at
net.jini.jeri.connection.ServerConnectionManager$Dispatcher.dispatch(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.mux.MuxServer$1$1.run(Unknown Source)
[java] at
com.sun.jini.start.AggregatePolicyProvider$AggregateSecurityContext$1.run(Unknown
Source)
[java] at java.security.AccessController.doPrivileged(Native
Method)
[java] at
com.sun.jini.jeri.internal.mux.MuxServer$1.run(Unknown Source)
[java] at com.sun.jini.thread.ThreadPool$Worker.run(Unknown
Source)
[java] at java.lang.Thread.run(Thread.java:619)
[java] at
com.sun.jini.jeri.internal.runtime.Util.__________EXCEPTION_RECEIVED_FROM_SERVER__________(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.Util.exceptionReceivedFromServer(Unknown
Source)
[java] at
net.jini.jeri.BasicInvocationHandler.unmarshalThrow(Unknown Source)
[java] at
net.jini.jeri.BasicInvocationHandler.invokeRemoteMethodOnce(Unknown
Source)
[java] at
net.jini.jeri.BasicInvocationHandler.invokeRemoteMethod(Unknown Source)
[java] at net.jini.jeri.BasicInvocationHandler.invoke(Unknown
Source)
[java] at $Proxy3.getRemoteEvents(Unknown Source)
[java] at
com.sun.jini.test.impl.mercury.EMSTestBase.getCollectedRemoteEvents(Unknown
Source)
[java] at
com.sun.jini.test.spec.eventmailbox.EMSRIFT_PULL.run(Unknown Source)
[java] at com.sun.jini.qa.harness.MasterTest.doTest(Unknown
Source)
[java] at com.sun.jini.qa.harness.MasterTest.main(Unknown
Source)
[java] Caused by: java.rmi.NoSuchObjectException: Requested
registration objecthas expired
[java] at
com.sun.jini.mercury.MailboxImpl.getServiceRegistration(Unknown Source)
[java] at
com.sun.jini.mercury.MailboxImpl.validateIterator(Unknown Source)
[java] at
com.sun.jini.mercury.MailboxImpl.getNextBatchDo(Unknown Source)
[java] at
com.sun.jini.mercury.MailboxImpl.getNextBatch(Unknown Source)
[java] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
Method)
[java] at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[java] at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[java] at java.lang.reflect.Method.invoke(Method.java:597)
[java] at
net.jini.jeri.BasicInvocationDispatcher.invoke(Unknown Source)
[java] at
net.jini.jeri.BasicInvocationDispatcher.dispatch(Unknown Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target$2.run(Unknown
Source)
[java] at
net.jini.export.ServerContext.doWithServerContext(Unknown Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target.dispatch0(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target.access$700(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target$1.run(Unknown
Source)
[java] at
com.sun.jini.start.AggregatePolicyProvider$AggregateSecurityContext$2.run(Unknown
Source)
[java] at java.security.AccessController.doPrivileged(Native
Method)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target.dispatch(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$Target.dispatch(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.runtime.ObjectTable$RD.dispatch(Unknown
Source)
[java] at
net.jini.jeri.connection.ServerConnectionManager$Dispatcher.dispatch(Unknown
Source)
[java] at
com.sun.jini.jeri.internal.mux.MuxServer$1$1.run(Unknown Source)
[java] at
com.sun.jini.start.AggregatePolicyProvider$AggregateSecurityContext$1.run(Unknown
Source)
[java] at java.security.AccessController.doPrivileged(Native
Method)
[java] at
com.sun.jini.jeri.internal.mux.MuxServer$1.run(Unknown Source)
[java] at com.sun.jini.thread.ThreadPool$Worker.run(Unknown
Source)
[java] at java.lang.Thread.run(Thread.java:619)
[java]
[java] TIME: 12:29:27 PM
[java]
[java] MasterTest.doTest INFO:
[java] ============================ CALLING TEARDOWN()
=============================
[java]
[java] NonActGrp-out: Sep 24, 2010 12:29:27 PM
com.sun.jini.reggie.RegistrarImpl destroy
[java] NonActGrp-out: INFO: starting Reggie shutdown
[java] NonActGrp-out: Sep 24, 2010 12:29:27 PM
com.sun.jini.reggie.RegistrarImpl$DestroyThread run
[java] NonActGrp-out: INFO: Reggie shutdown completed
[java] Sep 24, 2010 12:29:29 PM com.sun.jini.tool.ClassServer
terminate
[java] INFO: ClassServer terminated [port 8081]
[java] Sep 24, 2010 12:29:29 PM com.sun.jini.tool.ClassServer
terminate
[java] INFO: ClassServer terminated [port 8082]
[java] Sep 24, 2010 12:29:29 PM com.sun.jini.tool.ClassServer
terminate
[java] INFO: ClassServer terminated [port 8081]
[java] Sep 24, 2010 12:29:29 PM com.sun.jini.tool.ClassServer
terminate
[java] INFO: ClassServer terminated [port 8080]
[java] Sep 24, 2010 12:29:29 PM com.sun.jini.tool.ClassServer
terminate
[java] INFO: ClassServer terminated [port 8080]
[java] Sep 24, 2010 12:29:29 PM com.sun.jini.tool.ClassServer
terminate
[java] INFO: ClassServer terminated [port 8082]
[java]
[java] TIME: 12:29:29 PM
[java]
[java] Test process was destroyed and returned code 1
[java] com/sun/jini/test/spec/eventmailbox/EMSRIFT_PULL2.td
[java] Test Failed: Test Failed with an Unexpected Exception:
java.rmi.ServerException: RemoteException in server thread; nested
exception is:
[java] java.rmi.NoSuchObjectException: Requested registration
objecthas expired
[java]
[java]
[java] -----------------------------------------
[java]
[java] SUMMARY =================================
[java]
[java] com/sun/jini/test/spec/eventmailbox/EMSRIFT_PULL2.td
[java] Test Failed: Test Failed with an Unexpected Exception:
java.rmi.ServerException: RemoteException in server thread; nested
exception is:
[java] java.rmi.NoSuchObjectException: Requested registration
objecthas expired
[java]
[java] -----------------------------------------
[java]
[java] # of tests started = 1
[java] # of tests completed = 1
[java] # of tests passed = 0
[java] # of tests failed = 1
[java]
