I'm trying to solve failures on the ARM platform, for now I've changed the Reggie ServerSocket to wait for 4 minutes for the
TCP TIME_WAIT period then retry, unfortunately the port is still in use after waiting, which tends to indicate a stale process.
Reggie works in other tests, at least until the port is no longer available.
Any ideas?
Cheers,
Peter.
Running com/sun/jini/test/spec/lookupservice/test_set00/EvntLeaseExpiration.td
Time is Sat Nov 10 07:53:02 CST 2012
Starting test in separate process with command:
/usr/lib/jvm/jdk1.7.0_06/jre/bin/java -Djava.security.manager=org.apache.river.api.security.CombinerSecurityManager -Djava.security.policy=file:/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/harness/policy/defaulttest.policy -Djava.rmi.server.codebase=http://je-cal-12:9082/qa1-lookupservice-dl.jar -cp /home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/lib/jiniharness.jar:/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/lib/jinitests.jar:/home/jenkins/jenkins-slave/workspace/River-QA-arm/lib/jsk-platform.jar:/home/jenkins/jenkins-slave/workspace/River-QA-arm/lib/jsk-lib.jar:/home/jenkins/jenkins-slave/workspace/River-QA-arm/lib/high-scale-lib.jar:/home/jenkins/jenkins-slave/workspace/River-QA-arm/lib/reference-collections-1.0.1.jar -client -Djava.ext.dirs=/usr/lib/jvm/jdk1.7.0_06/jre/lib/ext:/usr/java/packages/lib/ext:/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/lib-ext:/home/jenkins/jenkins-slave/workspace/River-QA-arm/lib-ext -Dcom.sun.jini.jsk.port=9080 -Dcom.sun.jini.qa.port=9081 -Dcom.sun.jini.jsk.home=/home/jenkins/jenkins-slave/workspace/River-QA-arm -Dcom.sun.jini.qa.home=/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa -Dcom.sun.jini.qa.harness.harnessJar=/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/lib/jiniharness.jar -Dcom.sun.jini.qa.harness.testJar=/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/lib/jinitests.jar -Dcom.sun.jini.qa.harness.runjiniserver=true -Dcom.sun.jini.qa.harness.runkitserver=true -Djava.security.properties=file:/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/harness/trust/dynamic-policy.properties -Dcom.sun.jini.qa.harness.testhosts= -Djava.util.logging.config.file=/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/src/com/sun/jini/test/resources/qa1.logging -Dcom.sun.jini.test.home=/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa -Dcom.sun.jini.test.port=9082 -Dcom.sun.jini.qa.harness.policies=file:/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/src/com/sun/jini/test/resources/jinitest.policy -Djava.ext.dirs=/usr/lib/jvm/jdk1.7.0_06/jre/lib/ext:/usr/java/packages/lib/ext:/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/lib-ext:/home/jenkins/jenkins-slave/workspace/River-QA-arm/lib-ext com.sun.jini.qa.harness.MasterTest com/sun/jini/test/spec/lookupservice/test_set00/EvntLeaseExpiration.td
TIME: 7:53:03 AM
MasterTest.doTest INFO:
============================== CALLING SETUP() ==============================
Nov 10, 2012 7:53:03 AM com.sun.jini.tool.ClassServer run
INFO: ClassServer started
[[/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/lib/], port 9081]
Nov 10, 2012 7:53:03 AM com.sun.jini.tool.ClassServer run
INFO: ClassServer started
[[/home/jenkins/jenkins-slave/workspace/River-QA-arm/lib-dl/], port 9080]
Nov 10, 2012 7:53:03 AM com.sun.jini.tool.ClassServer run
INFO: ClassServer started
[[/home/jenkins/jenkins-slave/workspace/River-QA-arm/qa/lib/], port 9082]
NonActGrp-out: Nov 10, 2012 7:57:08 AM com.sun.jini.reggie.RegistrarImpl <init>
NonActGrp-out: SEVERE: Reggie initialization failed
NonActGrp-out: java.io.IOException: Reggie ServerSocket port already in use:
4160
NonActGrp-out: at
com.sun.jini.reggie.RegistrarImpl$UnicastThread.<init>(RegistrarImpl.java:2514)
NonActGrp-out: at
com.sun.jini.reggie.RegistrarImpl.init(RegistrarImpl.java:4689)
NonActGrp-out: at
com.sun.jini.reggie.RegistrarImpl.access$000(RegistrarImpl.java:146)
NonActGrp-out: at
com.sun.jini.reggie.RegistrarImpl$1.run(RegistrarImpl.java:451)
NonActGrp-out: at
com.sun.jini.reggie.RegistrarImpl.loginAndRun(RegistrarImpl.java:464)
NonActGrp-out: at
com.sun.jini.reggie.RegistrarImpl.<init>(RegistrarImpl.java:399)
NonActGrp-out: at
com.sun.jini.reggie.TransientRegistrarImpl.<init>(TransientRegistrarImpl.java:39)
NonActGrp-out: at
sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
NonActGrp-out: at
sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
NonActGrp-out: at
sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
NonActGrp-out: at
java.lang.reflect.Constructor.newInstance(Constructor.java:525)
NonActGrp-out: at
com.sun.jini.start.NonActivatableServiceDescriptor.create(NonActivatableServiceDescriptor.java:674)
NonActGrp-out: at
com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl.startService(NonActivatableGroupImpl.java:182)
NonActGrp-out: at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
NonActGrp-out: at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
NonActGrp-out: at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
NonActGrp-out: at java.lang.reflect.Method.invoke(Method.java:601)
NonActGrp-out: at
sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:322)
NonActGrp-out: at sun.rmi.transport.Transport$1.run(Transport.java:177)
NonActGrp-out: at sun.rmi.transport.Transport$1.run(Transport.java:174)
NonActGrp-out: at java.security.AccessController.doPrivileged(Native Method)
NonActGrp-out: at sun.rmi.transport.Transport.serviceCall(Transport.java:173)
NonActGrp-out: at
sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:553)
NonActGrp-out: at
sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:808)
NonActGrp-out: at
sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:667)
NonActGrp-out: at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
NonActGrp-out: at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
NonActGrp-out: at java.lang.Thread.run(Thread.java:722)
NonActGrp-out: Caused by: java.net.BindException: Address already in use
NonActGrp-out: at
com.sun.jini.reggie.RegistrarImpl$UnicastThread.<init>(RegistrarImpl.java:2513)
NonActGrp-out: ... 27 more
NonActGrp-out:
com.sun.jini.qa.harness.TestException: Problem creating service for net.jini.core.lookup.ServiceRegistrar; nested exception is:
RemoteException occurred in server thread; nested exception is:
java.rmi.RemoteException: Create failed; nested exception is:
java.lang.reflect.InvocationTargetException
at
com.sun.jini.qa.harness.NonActivatableServiceStarterAdmin.start(NonActivatableServiceStarterAdmin.java:157)
at
com.sun.jini.qa.harness.AdminManager.startService(AdminManager.java:639)
at
com.sun.jini.qa.harness.AdminManager.startService(AdminManager.java:660)
at
com.sun.jini.qa.harness.AdminManager.startLookupService(AdminManager.java:679)
at
com.sun.jini.test.spec.lookupservice.QATestRegistrar.setup(QATestRegistrar.java:470)
at
com.sun.jini.test.spec.lookupservice.test_set00.EvntLeaseExpiration.setup(EvntLeaseExpiration.java:90)
at com.sun.jini.qa.harness.MasterTest.doTest(MasterTest.java:219)
at com.sun.jini.qa.harness.MasterTest.main(MasterTest.java:144)
Caused by: java.rmi.ServerException: RemoteException occurred in server thread; nested exception is:
java.rmi.RemoteException: Create failed; nested exception is:
java.lang.reflect.InvocationTargetException
at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:353)
at sun.rmi.transport.Transport$1.run(Transport.java:177)
at sun.rmi.transport.Transport$1.run(Transport.java:174)
at java.security.AccessController.doPrivileged(Native Method)
at sun.rmi.transport.Transport.serviceCall(Transport.java:173)
at
sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:553)
at
sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:808)
at
sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:667)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:722)
at
sun.rmi.transport.StreamRemoteCall.exceptionReceivedFromServer(StreamRemoteCall.java:273)
at
sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:251)
at sun.rmi.server.UnicastRef.invoke(UnicastRef.java:160)
at
com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl_Stub.startService(Unknown
Source)
at
com.sun.jini.qa.harness.NonActivatableServiceStarterAdmin.start(NonActivatableServiceStarterAdmin.java:148)
... 7 more
Caused by: java.rmi.RemoteException: Create failed; nested exception is:
java.lang.reflect.InvocationTargetException
at
com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl.startService(NonActivatableGroupImpl.java:186)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:322)
at sun.rmi.transport.Transport$1.run(Transport.java:177)
at sun.rmi.transport.Transport$1.run(Transport.java:174)
at java.security.AccessController.doPrivileged(Native Method)
at sun.rmi.transport.Transport.serviceCall(Transport.java:173)
at
sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:553)
at
sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:808)
at
sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:667)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:722)
Caused by: java.lang.reflect.InvocationTargetException
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at
sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
at
sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:525)
at
com.sun.jini.start.NonActivatableServiceDescriptor.create(NonActivatableServiceDescriptor.java:674)
at
com.sun.jini.qa.harness.NonActivatableGroupImpl$GroupImpl.startService(NonActivatableGroupImpl.java:182)
... 15 more
Caused by: java.io.IOException: Reggie ServerSocket port already in use: 4160
at
com.sun.jini.reggie.RegistrarImpl$UnicastThread.<init>(RegistrarImpl.java:2514)
at com.sun.jini.reggie.RegistrarImpl.init(RegistrarImpl.java:4689)
at com.sun.jini.reggie.RegistrarImpl.access$000(RegistrarImpl.java:146)
at com.sun.jini.reggie.RegistrarImpl$1.run(RegistrarImpl.java:451)
at com.sun.jini.reggie.RegistrarImpl.loginAndRun(RegistrarImpl.java:464)
at com.sun.jini.reggie.RegistrarImpl.<init>(RegistrarImpl.java:399)
at
com.sun.jini.reggie.TransientRegistrarImpl.<init>(TransientRegistrarImpl.java:39)
... 21 more
Caused by: java.net.BindException: Address already in use
at
com.sun.jini.reggie.RegistrarImpl$UnicastThread.<init>(RegistrarImpl.java:2513)
... 27 more
TIME: 7:57:08 AM
MasterTest.doTest INFO:
============================ CALLING TEARDOWN() =============================
Nov 10, 2012 7:57:10 AM com.sun.jini.tool.ClassServer terminate
INFO: ClassServer terminated [port 9081]
Nov 10, 2012 7:57:10 AM com.sun.jini.tool.ClassServer terminate
INFO: ClassServer terminated [port 9082]
Nov 10, 2012 7:57:10 AM com.sun.jini.tool.ClassServer terminate
INFO: ClassServer terminated [port 9082]
Nov 10, 2012 7:57:10 AM com.sun.jini.tool.ClassServer terminate
INFO: ClassServer terminated [port 9081]
Nov 10, 2012 7:57:10 AM com.sun.jini.tool.ClassServer terminate
INFO: ClassServer terminated [port 9080]
Nov 10, 2012 7:57:10 AM com.sun.jini.tool.ClassServer terminate
INFO: ClassServer terminated [port 9080]
TIME: 7:57:10 AM
Test process was destroyed and returned code 1
com/sun/jini/test/spec/lookupservice/test_set00/EvntLeaseExpiration.td
Test Failed: Setup Failed: com.sun.jini.qa.harness.TestException: Problem creating service for net.jini.core.lookup.ServiceRegistrar; nested exception is:
RemoteException occurred in server thread; nested exception is:
java.rmi.RemoteException: Create failed; nested exception is:
java.lang.reflect.InvocationTargetException
