TESTFAIL:java/rmi/activation/CommandEnvironment/SetChildEnv.java

The test java/rmi/activation/CommandEnvironment/SetChildEnv.java can fail. This test chooses a random port and then starts up and shuts down rmid several times, using the same port. There appears to be a race condition between the shutdown of a previous instance and startup of the next instance, such that there can be a port conflict. Log file below.

ACTION: main -- Error. Program `C:\jprt\T\P1\223927~1.SWM\TESTPR~1\WINDOW~1.2-P\bin\java' interrupted! (timed out?)
REASON: User specified action: run main/othervm/timeout=240/policy=security.policy -Djava.compiler=NONE SetChildEnv
TIME: 960.006 seconds
messages:
command: main -Djava.compiler=NONE SetChildEnv
reason: User specified action: run main/othervm/timeout=240/policy=security.policy -Djava.compiler=NONE SetChildEnv
elapsed time (seconds): 960.006
STDOUT:
java.compiler=null
Invoking complain()...
HELP ME, DOCTOR. I FEEL VIOLENT TOWARDS PEOPLE WHO INQUIRE ABOUT MY PARENTS.
 => TELL ME ABOUT YOUR MOTHER
STDERR:
RMID: starting rmid on port #2206...
JAVAVM: command = [C:\jprt\T\P1\223927~1.SWM\TESTPR~1\WINDOW~1.2-P\jre\bin\java, -showversion, -Djava.security.policy=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment\rmid.security.policy, -Dsun.rmi.server.activation.debugExec=true, -Dtest.src=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment, -Dtest.classes=C:\jprt\T\P1\223927.swmarks\s\build\windows-amd64\testoutput\jdk_rmi\JTwork\classes\java\rmi\activation\CommandEnvironment, -Djava.rmi.server.logLevel=v, sun.rmi.server.Activation, -log, C:\jprt\T\P1\223927.swmarks\s\build\windows-amd64\testoutput\jdk_rmi\JTwork\java\rmi\activation\CommandEnvironment\SetChildEnv\.\log, -port, 2206, -C-Dtest.src=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment, -C-Dtest.classes=C:\jprt\T\P1\223927.swmarks\s\build\windows-amd64\testoutput\jdk_rmi\JTwork\classes\java\rmi\activation\CommandEnvironment]

# openjdk version "1.8.0-internal"
# OpenJDK Runtime Environment (build 1.8.0-internal-201208302239.swmarks.jdk8-tl-jdk-b00)
# Java HotSpot(TM) 64-Bit Server VM (build 24.0-b20, mixed mode)
#
JAVAVM: distant vm process running, after 100 milliseconds
ACTIVATION_LIBRARY: Activation System available after 0 milliseconds
RMID: finished starting rmid.
# rmid: debugExec: running "C:\jprt\T\P1\223927~1.SWM\TESTPR~1\WINDOW~1.2-P\jre\bin\java -Djava.security.policy=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment\group.security.policy -Djava.security.manager=default -Dtest.src=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment -Dtest.classes=C:\jprt\T\P1\223927.swmarks\s\build\windows-amd64\testoutput\jdk_rmi\JTwork\classes\java\rmi\activation\CommandEnvironment sun.rmi.server.ActivationGroupInit"
# Thu Aug 30 17:22:46 PDT 2012:ExecGroup-0:out:Doctor constructed and exported
debugExec found: <<# rmid: debugExec: running "C:\jprt\T\P1\223927~1.SWM\TESTPR~1\WINDOW~1.2-P\jre\bin\java -Djava.security.policy=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment\group.security.policy -Djava.security.manager=default -Dtest.src=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment -Dtest.classes=C:\jprt\T\P1\223927.swmarks\s\build\windows-amd64\testoutput\jdk_rmi\JTwork\classes\java\rmi\activation\CommandEnvironment sun.rmi.server.ActivationGroupInit">>
# Thu Aug 30 17:22:46 PDT 2012:ExecGroup-0:out:Doctor will see you now
# Thu Aug 30 17:22:46 PDT 2012:ExecGroup-0:out:Doctor retiring
ACTIVATION_LIBRARY: Activation System available after 0 milliseconds
RMID: getting a reference to the activation system
RMID: obtained a reference to the activation system
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
ACTIVATION_LIBRARY: finished destroying rmid
RMID: removing rmid's old log file...
RMID: starting rmid on port #2206...
JAVAVM: command = [C:\jprt\T\P1\223927~1.SWM\TESTPR~1\WINDOW~1.2-P\jre\bin\java, -showversion, -Djava.security.policy=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment\rmid.security.policy, -Dsun.rmi.server.activation.debugExec=true, -Dtest.src=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment, -Dtest.classes=C:\jprt\T\P1\223927.swmarks\s\build\windows-amd64\testoutput\jdk_rmi\JTwork\classes\java\rmi\activation\CommandEnvironment, -Djava.rmi.server.logLevel=v, sun.rmi.server.Activation, -log, C:\jprt\T\P1\223927.swmarks\s\build\windows-amd64\testoutput\jdk_rmi\JTwork\java\rmi\activation\CommandEnvironment\SetChildEnv\.\log, -port, 2206, -C-Dtest.src=C:\jprt\T\P1\223927.swmarks\s\test\java\rmi\activation\CommandEnvironment, -C-Dtest.classes=C:\jprt\T\P1\223927.swmarks\s\build\windows-amd64\testoutput\jdk_rmi\JTwork\classes\java\rmi\activation\CommandEnvironment]

# openjdk version "1.8.0-internal"
# OpenJDK Runtime Environment (build 1.8.0-internal-201208302239.swmarks.jdk8-tl-jdk-b00)
# Java HotSpot(TM) 64-Bit Server VM (build 24.0-b20, mixed mode)
#
JAVAVM: distant vm process running, after 100 milliseconds
# Activation.main: an exception occurred: java.rmi.server.ExportException: Port already in use: 2206; nested exception is:
# java.net.BindException: Address already in use: JVM_Bind
# java.rmi.server.ExportException: Port already in use: 2206; nested exception is:
# java.net.BindException: Address already in use: JVM_Bind
# at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:329)
# at sun.rmi.transport.tcp.TCPTransport.exportObject(TCPTransport.java:237)
# 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:207)
# 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: JVM_Bind
# at java.net.TwoStacksPlainSocketImpl.socketBind(Native Method)
# at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:381)
# at java.net.TwoStacksPlainSocketImpl.bind(TwoStacksPlainSocketImpl.java:101)
# at java.net.PlainSocketImpl.bind(PlainSocketImpl.java:175)
# 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:344)
# at sun.rmi.transport.tcp.TCPEndpoint.newServerSocket(TCPEndpoint.java:666)
# at sun.rmi.transport.tcp.TCPTransport.listen(TCPTransport.java:318)
# ... 8 more
ACTIVATION_LIBRARY: Remote connection refused after 0 milliseconds
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
RMID: rmid still not started
ACTIVATION_LIBRARY: Remote connection refused after 0 milliseconds
ACTIVATION_LIBRARY: Remote connection refused after 100 milliseconds