-
Bug
-
Resolution: Duplicate
-
P4
-
9
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
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
- duplicates
-
JDK-8085192 java/rmi/activation/Activatable tests fail intermittently due to "Port already in use"
- Resolved
-
JDK-8168975 java/rmi/activation/Activatable tests fail due to "Port already in use" in RMID.restart()
- Closed
- relates to
-
JDK-8085192 java/rmi/activation/Activatable tests fail intermittently due to "Port already in use"
- Resolved
-
JDK-8161011 Mark RMI tests DownloadActivationGroup, UseCustomSocketFactory, and RestartService as itnermittent
- Resolved