JDK-8043707 : TEST_BUG java/rmi/activation/Activatable/restartService/RestartService.java failed once in same binary run b12
  • Type: Bug
  • Component: core-libs
  • Sub-Component: java.rmi
  • Affected Version: 9
  • Priority: P4
  • Status: Closed
  • Resolution: Duplicate
  • Submitted: 2014-05-22
  • Updated: 2016-11-21
  • Resolved: 2016-11-21
The Version table provides details related to the release that this issue/RFE will be addressed.

Unresolved : Release in which this issue/RFE will be addressed.
Resolved: Release in which this issue/RFE has been resolved.
Fixed : Release in which this issue/RFE has been fixed. The release containing this fix may be available for download as an Early Access Release or a General Availability Release.

To download the current JDK release, click here.
Other
tbd_minorResolved
Related Reports
Duplicate :  
JDK-8168975 - java/rmi/activation/Activatable tests fail due to "Port already in use" in RMID.restart()
Duplicate :  
JDK-8085192 - java/rmi/activation/Activatable tests fail intermittently due to "Port already in use"
Relates :  
JDK-8161011 - Mark RMI tests DownloadActivationGroup, UseCustomSocketFactory, and RestartService as itnermittent
Relates :  
JDK-8085192 - java/rmi/activation/Activatable tests fail intermittently due to "Port already in use"
Description
TESTFAIL:java/rmi/activation/Activatable/restartService/RestartService.java
The test  java/rmi/activation/Activatable/restartService/RestartService.java failed once in same binary run b12, see logs:

#section:main
----------messages:(3/159)----------
command: main RestartService
reason: User specified action: run main/othervm/policy=security.policy/timeout=240 RestartService 
elapsed time (seconds): 11.489
----------System.out:(3/34)----------

Regression test for bug 4095165

----------System.err:(168/10648)----------
RMID: starting rmid on port #35782...
JAVAVM: command = [/scratch/aurora/sandbox_keepme/jdk/jre/bin/java, -ea, -esa, -Xmx512m, -Djava.security.policy=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService/rmid.security.policy, -Dsun.rmi.server.activation.debugExec=true, -Dtest.src=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService, -Dtest.classes=/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService, -Djava.rmi.server.logLevel=v, -Dsun.rmi.activation.execTimeout=60000, sun.rmi.server.Activation, -log, /scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/scratch/./log, -port, 35782, -C-Dtest.src=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService, -C-Dtest.classes=/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService, -C-ea, -C-esa, -C-Xmx512m, -C-Djava.rmi.server.useCodebaseOnly=false]
RMID: rmid not started, will retry for 9900ms
RMID: finished starting rmid.
Creating descriptors
Registering descriptors
RMID: testlibrary finished shutting down rmid
activation daemon shut down
RMID: RMID's process still not terminated after more than 200 milliseconds
RMID: rmid exited on shutdown request
RMID: starting rmid on port #35782...
JAVAVM: command = [/scratch/aurora/sandbox_keepme/jdk/jre/bin/java, -ea, -esa, -Xmx512m, -Djava.security.policy=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService/rmid.security.policy, -ea, -esa, -Xmx512m, -Djava.security.policy=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService/rmid.security.policy, -Dsun.rmi.server.activation.debugExec=true, -Dtest.src=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService, -Dtest.classes=/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService, -Djava.rmi.server.logLevel=v, -Dsun.rmi.activation.execTimeout=60000, sun.rmi.server.Activation, -log, /scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/scratch/./log, -port, 35782, -C-Dtest.src=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService, -C-Dtest.classes=/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService, -C-ea, -C-esa, -C-Xmx512m, -C-Djava.rmi.server.useCodebaseOnly=false]
RMID: rmid not started, will retry for 9900ms
RMID: rmid not started, will retry for 9800ms
RMID: rmid not started, will retry for 9700ms
Activation.main: an exception occurred: java.rmi.server.ExportException: Port already in use: 35782; nested exception is: 
	java.net.BindException: Address already in use
java.rmi.server.ExportException: Port already in use: 35782; nested exception is: 
	java.net.BindException: Address already in use
	at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:326)
	at sun.rmi.transport.tcp.TCPTransport.exportObject(TCPTransport.java:234)
	at sun.rmi.transport.tcp.TCPEndpoint.exportObject(TCPEndpoint.java:411)
	at sun.rmi.transport.LiveRef.exportObject(LiveRef.java:147)
	at sun.rmi.server.UnicastServerRef.exportObject(UnicastServerRef.java:206)
	at sun.rmi.server.Activation$ActivatorImpl.<init>(Activation.java:428)
	at sun.rmi.server.Activation.init(Activation.java:250)
	at sun.rmi.server.Activation.startActivation(Activation.java:219)
	at sun.rmi.server.Activation.main(Activation.java:2111)
Caused by: java.net.BindException: Address already in use
	at java.net.PlainSocketImpl.socketBind(Native Method)
	at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:382)
	at java.net.ServerSocket.bind(ServerSocket.java:376)
	at java.net.ServerSocket.<init>(ServerSocket.java:238)
	at java.net.ServerSocket.<init>(ServerSocket.java:130)
	at sun.rmi.transport.proxy.RMIDirectSocketFactory.createServerSocket(RMIDirectSocketFactory.java:45)
	at sun.rmi.transport.proxy.RMIMasterSocketFactory.createServerSocket(RMIMasterSocketFactory.java:341)
	at sun.rmi.transport.tcp.TCPEndpoint.newServerSocket(TCPEndpoint.java:666)
	at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:315)
	... 8 more
RMID: rmid not started, will retry for 9600ms
......
RMID: rmid not started, will retry for 100ms
TEST FAILED: start rmid failed... giving up
TEST FAILED: 
Test failed with: TEST FAILED: start rmid failed... giving up
TestFailedException: TEST FAILED: start rmid failed... giving up
	at TestLibrary.bomb(TestLibrary.java:121)
	at RMID.start(RMID.java:290)
	at RMID.start(RMID.java:219)
	at RMID.restart(RMID.java:295)
	at RestartService.main(RestartService.java:178)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:484)
	at com.sun.javatest.regtest.MainWrapper$MainThread.run(MainWrapper.java:94)
	at java.lang.Thread.run(Thread.java:745)
ACTIVATION_LIBRARY: tried to shutdown when rmid was not running
TEST FAILED: rmid not destroyed in: 60000 milliseconds
TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds
	at TestLibrary.bomb(TestLibrary.java:121)
	at TestLibrary.bomb(TestLibrary.java:124)
	at ActivationLibrary.rmidCleanup(ActivationLibrary.java:111)
	at RestartService.main(RestartService.java:224)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:484)
	at com.sun.javatest.regtest.MainWrapper$MainThread.run(MainWrapper.java:94)
	at java.lang.Thread.run(Thread.java:745)

JavaTest Message: Test threw exception: TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds
JavaTest Message: shutting down test

STATUS:Failed.`main' threw exception: TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds
----------rerun:(23/2447)*----------
DISPLAY=sc11152338.us.oracle.com:1 \\
HOME=/scratch/aurora \\
LANG=en_US.UTF-8 \\
PATH=/bin:/usr/bin \\
TZ=PST8PDT \\
CLASSPATH=/scratch/aurora/sandbox_keepme/jtreg/lib/javatest.jar:/scratch/aurora/sandbox_keepme/jtreg/lib/jtreg.jar:/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService:/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService:/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary:/scratch/aurora/sandbox_keepme/jdk/lib/tools.jar \\
    /scratch/aurora/sandbox_keepme/jdk/bin/java \\
        -Dtest.vm.opts='-ea -esa -Xmx512m' \\
        -Dcompile.jdk=/scratch/aurora/sandbox_keepme/jdk \\
        -Dtest.src.path=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService:/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/testlibrary \\
        -Dtest.src=/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService \\
        -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \\
        -Dtest.class.path=/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService:/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary \\
        -Dtest.timeout.factor=4.0 \\
        -Dtest.classes=/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService \\
        -Dtest.class.path.prefix=/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService:/scratch/aurora/sandbox_keepme/testbase/test/java/rmi/activation/Activatable/restartService:/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary \\
        -Dtest.jdk=/scratch/aurora/sandbox_keepme/jdk \\
        -Dtest.java.opts= \\
        -Dtest.compiler.opts= \\
        -Djava.security.policy==/scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/scratch/security.policy_new \\
        -Djava.security.manager=default \\
        -ea -esa -Xmx512m \\
        com.sun.javatest.regtest.MainWrapper /scratch/aurora/sandbox_keepme/results/run_118/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/restartService/RestartService.jta
result: Failed. Execution failed: `main' threw exception: TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds


test result: Failed. Execution failed: `main' threw exception: TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds
Comments
Here's a log from the failure of java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable.java which is a different test, but it seems like it's the same failure pathology. TEST: java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable.java JDK under test: (/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product) java version "1.8.0_60-internal" Java(TM) SE Runtime Environment (build 1.8.0_60-internal-20150319221526.ssteuck.jdk8u-dev-pit-b00) Java HotSpot(TM) 64-Bit Server VM (build 25.60-b07, mixed mode) ACTION: build -- Passed. Build successful REASON: User specified action: run build TestLibrary RMID ActivationLibrary ActivateMe NonExistentActivatable_Stub TIME: 0.775 seconds messages: command: build TestLibrary RMID ActivationLibrary ActivateMe NonExistentActivatable_Stub reason: User specified action: run build TestLibrary RMID ActivationLibrary ActivateMe NonExistentActivatable_Stub elapsed time (seconds): 0.775 ACTION: compile -- Passed. Compilation successful REASON: .class file out of date or does not exist TIME: 0.775 seconds messages: command: compile -XDignore.symbol.file=true /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable/ActivateMe.java /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable_Stub.java reason: .class file out of date or does not exist elapsed time (seconds): 0.775 rerun: DISPLAY=scaaa563.us.oracle.com:520 \ HOME=/opt/jprt/jprtadm \ LANG=C \ LC_ALL=C \ LC_CTYPE= \ PATH=/bin:/usr/bin \ /opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product/bin/javac \ -J-ea \ -J-esa \ -J-server \ -J-Xmx512m \ -J-Dtest.src=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable \ -J-Dtest.src.path=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/testlibrary \ -J-Dtest.classes=/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable \ -J-Dtest.class.path=/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary \ -J-Dtest.vm.opts='-ea -esa -server -Xmx512m' \ -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-server -J-Xmx512m' \ -J-Dtest.compiler.opts= \ -J-Dtest.java.opts='-Duser.home=/opt/jprt/T/P1/221526.ssteuck -Djava.io.tmpdir=/opt/jprt/T/P1/221526.ssteuck/io/linux_x64_2.6-product-c2-jdk_rmi -server' \ -J-Dtest.jdk=/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product \ -J-Dcompile.jdk=/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product \ -J-Dtest.timeout.factor=4.0 \ -d /opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable -classpath /opt/jprt/products/P1/jtreg4.1-latest/jtreg/lib/javatest.jar:/opt/jprt/products/P1/jtreg4.1-latest/jtreg/lib/jtreg.jar:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary:/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product/lib/tools.jar -sourcepath /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/testlibrary -XDignore.symbol.file=true /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable/ActivateMe.java /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable_Stub.java STDOUT: STDERR: Note: /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable_Stub.java uses or overrides a deprecated API. Note: Recompile with -Xlint:deprecation for details. ACTION: build -- Passed. Build successful REASON: Named class compiled on demand TIME: 0.861 seconds messages: command: build NonExistentActivatable reason: Named class compiled on demand elapsed time (seconds): 0.861 ACTION: compile -- Passed. Compilation successful REASON: .class file out of date or does not exist TIME: 0.86 seconds messages: command: compile -XDignore.symbol.file=true /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable.java reason: .class file out of date or does not exist elapsed time (seconds): 0.86 rerun: DISPLAY=scaaa563.us.oracle.com:520 \ HOME=/opt/jprt/jprtadm \ LANG=C \ LC_ALL=C \ LC_CTYPE= \ PATH=/bin:/usr/bin \ /opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product/bin/javac \ -J-ea \ -J-esa \ -J-server \ -J-Xmx512m \ -J-Dtest.src=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable \ -J-Dtest.src.path=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/testlibrary \ -J-Dtest.classes=/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable \ -J-Dtest.class.path=/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary \ -J-Dtest.vm.opts='-ea -esa -server -Xmx512m' \ -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-server -J-Xmx512m' \ -J-Dtest.compiler.opts= \ -J-Dtest.java.opts='-Duser.home=/opt/jprt/T/P1/221526.ssteuck -Djava.io.tmpdir=/opt/jprt/T/P1/221526.ssteuck/io/linux_x64_2.6-product-c2-jdk_rmi -server' \ -J-Dtest.jdk=/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product \ -J-Dcompile.jdk=/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product \ -J-Dtest.timeout.factor=4.0 \ -d /opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable -classpath /opt/jprt/products/P1/jtreg4.1-latest/jtreg/lib/javatest.jar:/opt/jprt/products/P1/jtreg4.1-latest/jtreg/lib/jtreg.jar:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary:/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product/lib/tools.jar -sourcepath /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/testlibrary -XDignore.symbol.file=true /opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable.java STDOUT: STDERR: ACTION: main -- Failed. Execution failed: `main' threw exception: TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds REASON: User specified action: run main/othervm/policy=security.policy/timeout=240 NonExistentActivatable TIME: 518.301 seconds messages: command: main NonExistentActivatable reason: User specified action: run main/othervm/policy=security.policy/timeout=240 NonExistentActivatable elapsed time (seconds): 518.301 STDOUT: Regression test for bug 4115331 STDERR: RMID: starting rmid on port #45480... JAVAVM: command = [/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product/jre/bin/java, -Djava.security.policy=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable/rmid.security.policy, -Dsun.rmi.server.activation.debugExec=true, -Dtest.src=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable, -Dtest.classes=/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable, -Djava.rmi.server.logLevel=v, -Dsun.rmi.activation.execTimeout=60000, sun.rmi.server.Activation, -log, /opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable/./log, -port, 45480, -C-Dtest.src=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable, -C-Dtest.classes=/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable, -C-Djava.rmi.server.useCodebaseOnly=false] ACTIVATION_LIBRARY: caught an exception trying to start rmid, last exception was: non-JRMP server at remote endpoint java.rmi.ConnectIOException: non-JRMP server at remote endpoint at sun.rmi.transport.tcp.TCPChannel.createConnection(TCPChannel.java:248) at sun.rmi.transport.tcp.TCPChannel.newConnection(TCPChannel.java:202) at sun.rmi.server.UnicastRef.newCall(UnicastRef.java:342) at sun.rmi.registry.RegistryImpl_Stub.lookup(Unknown Source) at ActivationLibrary.rmidRunning(ActivationLibrary.java:124) at RMID.start(RMID.java:241) at RMID.start(RMID.java:203) at NonExistentActivatable.main(NonExistentActivatable.java:91) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:92) at java.lang.Thread.run(Thread.java:745) Activation.main: an exception occurred: java.rmi.server.ExportException: Port already in use: 45480; nested exception is: java.net.BindException: Address already in use java.rmi.server.ExportException: Port already in use: 45480; nested exception is: java.net.BindException: Address already in use at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:341) at sun.rmi.transport.tcp.TCPTransport.exportObject(TCPTransport.java:249) at sun.rmi.transport.tcp.TCPEndpoint.exportObject(TCPEndpoint.java:411) at sun.rmi.transport.LiveRef.exportObject(LiveRef.java:147) at sun.rmi.server.UnicastServerRef.exportObject(UnicastServerRef.java:208) at sun.rmi.server.Activation$ActivatorImpl.<init>(Activation.java:398) at sun.rmi.server.Activation.init(Activation.java:252) at sun.rmi.server.Activation.startActivation(Activation.java:221) at sun.rmi.server.Activation.main(Activation.java:2081) Caused by: java.net.BindException: Address already in use at java.net.PlainSocketImpl.socketBind(Native Method) at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:382) at java.net.ServerSocket.bind(ServerSocket.java:375) at java.net.ServerSocket.<init>(ServerSocket.java:237) at java.net.ServerSocket.<init>(ServerSocket.java:128) at sun.rmi.transport.proxy.RMIDirectSocketFactory.createServerSocket(RMIDirectSocketFactory.java:45) at sun.rmi.transport.proxy.RMIMasterSocketFactory.createServerSocket(RMIMasterSocketFactory.java:345) at sun.rmi.transport.tcp.TCPEndpoint.newServerSocket(TCPEndpoint.java:666) at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:330) ... 8 more ACTIVATION_LIBRARY: Remote connection refused after 100 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 200 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 300 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 400 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 500 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 600 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 700 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 800 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 900 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1000 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1100 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1200 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1300 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1400 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1500 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1600 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1700 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1800 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 1900 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2000 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2100 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2200 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2300 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2400 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2500 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2600 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2700 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2800 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 2900 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3000 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3100 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3200 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3300 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3400 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3500 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3600 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3700 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3800 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 3900 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4000 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4100 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4200 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4300 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4400 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4500 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4600 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4700 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4800 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 4900 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 5000 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 5100 milliseconds RMID: rmid still not started ACTIVATION_LIBRARY: Remote connection refused after 0 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 100 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 200 milliseconds [...snip...] ACTIVATION_LIBRARY: Remote connection refused after 4900 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 5000 milliseconds RMID: rmid still not started TEST FAILED: start rmid failed... giving up TEST FAILED: Test failed with: TEST FAILED: start rmid failed... giving up TestFailedException: TEST FAILED: start rmid failed... giving up at TestLibrary.bomb(TestLibrary.java:121) at RMID.start(RMID.java:258) at RMID.start(RMID.java:203) at NonExistentActivatable.main(NonExistentActivatable.java:91) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:92) at java.lang.Thread.run(Thread.java:745) ACTIVATION_LIBRARY: Remote connection refused after 0 milliseconds ACTIVATION_LIBRARY: Remote connection refused after 100 milliseconds [...snip...] ACTIVATION_LIBRARY: Remote connection refused after 5000 milliseconds ACTIVATION_LIBRARY: tried to shutdown when rmid was not running TEST FAILED: rmid not destroyed in: 60000 milliseconds TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds at TestLibrary.bomb(TestLibrary.java:121) at TestLibrary.bomb(TestLibrary.java:124) at ActivationLibrary.rmidCleanup(ActivationLibrary.java:176) at NonExistentActivatable.main(NonExistentActivatable.java:142) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:92) at java.lang.Thread.run(Thread.java:745) JavaTest Message: Test threw exception: TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds JavaTest Message: shutting down test STATUS:Failed.`main' threw exception: TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds rerun: DISPLAY=scaaa563.us.oracle.com:520 \ HOME=/opt/jprt/jprtadm \ LANG=C \ LC_ALL=C \ LC_CTYPE= \ PATH=/bin:/usr/bin \ CLASSPATH=/opt/jprt/products/P1/jtreg4.1-latest/jtreg/lib/javatest.jar:/opt/jprt/products/P1/jtreg4.1-latest/jtreg/lib/jtreg.jar:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary:/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product/lib/tools.jar \ /opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product/bin/java \ -Dtest.src=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable \ -Dtest.src.path=/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/test/java/rmi/testlibrary \ -Dtest.classes=/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable \ -Dtest.class.path=/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable:/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/testlibrary \ -Dtest.vm.opts='-ea -esa -server -Xmx512m' \ -Dtest.tool.vm.opts='-J-ea -J-esa -J-server -J-Xmx512m' \ -Dtest.compiler.opts= \ -Dtest.java.opts='-Duser.home=/opt/jprt/T/P1/221526.ssteuck -Djava.io.tmpdir=/opt/jprt/T/P1/221526.ssteuck/io/linux_x64_2.6-product-c2-jdk_rmi -server' \ -Dtest.jdk=/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product \ -Dcompile.jdk=/opt/jprt/T/P1/221526.ssteuck/testproduct/linux_x64_2.6-product \ -Dtest.timeout.factor=4.0 \ -Djava.security.policy==/opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable/security.policy_new \ -Djava.security.manager=default \ -ea -esa -server -Xmx512m -Duser.home=/opt/jprt/T/P1/221526.ssteuck -Djava.io.tmpdir=/opt/jprt/T/P1/221526.ssteuck/io/linux_x64_2.6-product-c2-jdk_rmi -server \ com.sun.javatest.regtest.agent.MainWrapper /opt/jprt/T/P1/221526.ssteuck/s/jdk/testoutput/jdk_rmi/JTwork/classes/java/rmi/activation/Activatable/nonExistentActivatable/NonExistentActivatable.jta TEST RESULT: Failed. Execution failed: `main' threw exception: TestFailedException: TEST FAILED: rmid not destroyed in: 60000 milliseconds
21-03-2015