JDK-8051694 : TEST_BUG: java/rmi/activation/Activatable/checkAnnotations/CheckAnnotations.java fails with "timeout creating child process"
  • Type: Bug
  • Component: core-libs
  • Sub-Component: java.rmi
  • Affected Version: 9
  • Priority: P4
  • Status: Closed
  • Resolution: Won't Fix
  • Submitted: 2014-07-23
  • Updated: 2018-03-27
  • Resolved: 2018-03-27
Related Reports
Relates :  
JDK-8169569 - TEST_BUG: tests under java/rmi/activation/** failed due to "timeout creating child process"
Description
TESTFAIL:java/rmi/activation/Activatable/checkAnnotations/CheckAnnotations.java

The test java/rmi/activation/Activatable/checkAnnotations/CheckAnnotations.jtr fails in nightly intermittently. see log:
#section:main
----------messages:(3/166)----------
command: main CheckAnnotations
reason: User specified action: run main/othervm/policy=security.policy/timeout=480 CheckAnnotations 
elapsed time (seconds): 37.488
----------System.out:(2/14)----------
out-1
out-1
----------System.err:(117/8569)*----------

Regression test for bug/rfe 4109103

RMID: starting rmid on port #60936...
JAVAVM: command = [C:\\users\\aurora\\CommonData\\jdk\\jre\\bin\\java, -ea, -esa, -Xmx512m, -Djava.security.policy=C:\\Users\\aurora\\sandbox\\testbase\\test\\java\\rmi\\activation\\Activatable\\checkAnnotations\\rmid.security.policy, -Dsun.rmi.server.activation.debugExec=false, -Dtest.src=C:\\Users\\aurora\\sandbox\\testbase\\test\\java\\rmi\\activation\\Activatable\\checkAnnotations, -Dtest.classes=C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\activation\\Activatable\\checkAnnotations, -Djava.rmi.server.logLevel=v, -Dsun.rmi.activation.execTimeout=60000, sun.rmi.server.Activation, -log, C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\scratch\\.\\log, -port, 60936, -C-Dtest.src=C:\\Users\\aurora\\sandbox\\testbase\\test\\java\\rmi\\activation\\Activatable\\checkAnnotations, -C-Dtest.classes=C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\activation\\Activatable\\checkAnnotations, -C-ea, -C-esa, -C-Xmx512m, -C-Djava.rmi.server.useCodebaseOnly=false]
RMID: finished starting rmid.
Create activation group in this VM
err-1
err-1
rmid output not yet received, retrying...
rmid output not yet received, retrying...
rmid output not yet received, retrying...
rmid output not yet received, retrying...
rmid output not yet received, retrying...
Deactivate object via method call
ACTIVATION_LIBRARY: inactive successful
TEST FAILED: 
Test failed with: activation failed; nested exception is: 
	java.rmi.activation.ActivationException: timeout creating child process
java.rmi.activation.ActivateFailedException: activation failed; nested exception is: 
	java.rmi.activation.ActivationException: timeout creating child process
	at sun.rmi.server.ActivatableRef.activate(ActivatableRef.java:304)
	at sun.rmi.server.ActivatableRef.invoke(ActivatableRef.java:210)
	at CheckAnnotations_Stub.printOut(CheckAnnotations_Stub.java:107)
	at CheckAnnotations.checkAnnotations(CheckAnnotations.java:155)
	at CheckAnnotations.main(CheckAnnotations.java:109)
	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)
Caused by: java.rmi.activation.ActivationException: timeout creating child process
	at sun.rmi.server.Activation$GroupEntry.getInstantiator(Activation.java:1298)
	at sun.rmi.server.Activation$GroupEntry.activate(Activation.java:1163)
	at sun.rmi.server.Activation$ActivatorImpl.activate(Activation.java:436)
	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 sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:321)
	at sun.rmi.transport.Transport$1.run(Transport.java:179)
	at sun.rmi.transport.Transport$1.run(Transport.java:176)
	at java.security.AccessController.doPrivileged(Native Method)
	at sun.rmi.transport.Transport.serviceCall(Transport.java:175)
	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:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
	at sun.rmi.transport.StreamRemoteCall.exceptionReceivedFromServer(StreamRemoteCall.java:276)
	at sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:253)
	at sun.rmi.server.UnicastRef.invoke(UnicastRef.java:162)
	at java.rmi.server.RemoteObjectInvocationHandler.invokeRemoteMethod(RemoteObjectInvocationHandler.java:194)
	at java.rmi.server.RemoteObjectInvocationHandler.invoke(RemoteObjectInvocationHandler.java:148)
	at com.sun.proxy.$Proxy1.activate(Unknown Source)
	at java.rmi.activation.ActivationID.activate(ActivationID.java:118)
	at sun.rmi.server.ActivatableRef.activate(ActivatableRef.java:277)
	... 10 more
rmid shut down
RMID: testlibrary finished shutting down rmid
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...
TestFailedException: TEST FAILED: ; nested exception is: 
	java.rmi.activation.ActivateFailedException: activation failed; nested exception is: 
	java.rmi.activation.ActivationException: timeout creating child process
java.rmi.activation.ActivateFailedException: activation failed; nested exception is: 
	java.rmi.activation.ActivationException: timeout creating child process
	at sun.rmi.server.ActivatableRef.activate(ActivatableRef.java:304)
	at sun.rmi.server.ActivatableRef.invoke(ActivatableRef.java:210)
	at CheckAnnotations_Stub.printOut(CheckAnnotations_Stub.java:107)
	at CheckAnnotations.checkAnnotations(CheckAnnotations.java:155)
	at CheckAnnotations.main(CheckAnnotations.java:109)
	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)
Caused by: java.rmi.activation.ActivationException: timeout creating child process
	at sun.rmi.server.Activation$GroupEntry.getInstantiator(Activation.java:1298)
	at sun.rmi.server.Activation$GroupEntry.activate(Activation.java:1163)
	at sun.rmi.server.Activation$ActivatorImpl.activate(Activation.java:436)
	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 sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:321)
	at sun.rmi.transport.Transport$1.run(Transport.java:179)
	at sun.rmi.transport.Transport$1.run(Transport.java:176)
	at java.security.AccessController.doPrivileged(Native Method)
	at sun.rmi.transport.Transport.serviceCall(Transport.java:175)
	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:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
	at sun.rmi.transport.StreamRemoteCall.exceptionReceivedFromServer(StreamRemoteCall.java:276)
	at sun.rmi.transport.StreamRemoteCall.executeCall(StreamRemoteCall.java:253)
	at sun.rmi.server.UnicastRef.invoke(UnicastRef.java:162)
	at java.rmi.server.RemoteObjectInvocationHandler.invokeRemoteMethod(RemoteObjectInvocationHandler.java:194)
	at java.rmi.server.RemoteObjectInvocationHandler.invoke(RemoteObjectInvocationHandler.java:148)
	at com.sun.proxy.$Proxy1.activate(Unknown Source)
	at java.rmi.activation.ActivationID.activate(ActivationID.java:118)
	at sun.rmi.server.ActivatableRef.activate(ActivatableRef.java:277)
	... 10 more

JavaTest Message: Test threw exception: TestFailedException: TEST FAILED: ; nested exception is: 
	java.rmi.activation.ActivateFailedException: activation failed; nested exception is: 
	java.rmi.activation.ActivationException: timeout creating child process
JavaTest Message: shutting down test

STATUS:Failed.`main' threw exception: TestFailedException: TEST FAILED: ; nested exception is: java.rmi.activation.ActivateFailedException: activation failed; nested exception is: java.rmi.activation.ActivationException: timeout creating child process
----------rerun:(24/2802)*----------
'PATH=C:\\cygwin64\\usr\\local\\bin;C:\\cygwin64\\bin;C:\\Program Files\\Oracle\\VirtualBox;C:\\Windows\\system32;C:\\Windows;C:\\Windows\\System32\\Wbem;C:\\Windows\\System32\\WindowsPowerShell\\v1.0;C:\\Program Files\\TortoiseHg' \\
SystemDrive=C: \\
'SystemRoot=C:\\Windows' \\
'TEMP=C:\\Users\\aurora\\AppData\\Local\\Temp' \\
'TMP=C:\\Users\\aurora\\AppData\\Local\\Temp' \\
'windir=C:\\Windows' \\
'CLASSPATH=C:\\Users\\aurora\\CommonData\\jtreg\\lib\\javatest.jar;C:\\Users\\aurora\\CommonData\\jtreg\\lib\\jtreg.jar;C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\activation\\Activatable\\checkAnnotations;C:\\Users\\aurora\\sandbox\\testbase\\test\\java\\rmi\\activation\\Activatable\\checkAnnotations;C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\testlibrary;C:\\users\\aurora\\CommonData\\jdk\\lib\\tools.jar' \\
    'C:\\users\\aurora\\CommonData\\jdk\\bin\\java' \\
        -Dtest.vm.opts='-ea -esa -Xmx512m' \\
        -Dcompile.jdk='C:\\users\\aurora\\CommonData\\jdk' \\
        -Dtest.src.path='C:\\Users\\aurora\\sandbox\\testbase\\test\\java\\rmi\\activation\\Activatable\\checkAnnotations;C:\\Users\\aurora\\sandbox\\testbase\\test\\java\\rmi\\testlibrary' \\
        -Dtest.src='C:\\Users\\aurora\\sandbox\\testbase\\test\\java\\rmi\\activation\\Activatable\\checkAnnotations' \\
        -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \\
        -Dtest.class.path='C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\activation\\Activatable\\checkAnnotations;C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\testlibrary' \\
        -Dtest.timeout.factor=4.0 \\
        -Dtest.classes='C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\activation\\Activatable\\checkAnnotations' \\
        -Dtest.class.path.prefix='C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\activation\\Activatable\\checkAnnotations;C:\\Users\\aurora\\sandbox\\testbase\\test\\java\\rmi\\activation\\Activatable\\checkAnnotations;C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\testlibrary' \\
        -Dtest.jdk='C:\\users\\aurora\\CommonData\\jdk' \\
        -Dtest.java.opts= \\
        -Dtest.compiler.opts= \\
        -Djava.security.policy='=C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\scratch\\security.policy_new' \\
        -Djava.security.manager=default \\
        -ea -esa -Xmx512m \\
        com.sun.javatest.regtest.MainWrapper 'C:\\Users\\aurora\\sandbox\\gresults\\testoutput\\jdk_rmi\\JTwork\\classes\\java\\rmi\\activation\\Activatable\\checkAnnotations\\CheckAnnotations.jta'
result: Failed. Execution failed: `main' threw exception: TestFailedException: TEST FAILED: ; nested exception is: java.rmi.activation.ActivateFailedException: activation failed; nested exception is: java.rmi.activation.ActivationException: timeout creating child process


test result: Failed. Execution failed: `main' threw exception: TestFailedException: TEST FAILED: ; nested exception is: java.rmi.activation.ActivateFailedException: activation failed; nested exception is: java.rmi.activation.ActivationException: timeout creating child process
Comments
Not seen in a long time
27-03-2018