search

eclipse-openj9 / openj9

Eclipse OpenJ9: A Java Virtual Machine for OpenJDK that's optimized for small footprint, fast start-up, and high throughput. Builds on Eclipse OMR (https://github.com/eclipse/omr) and combines with the Extensions for OpenJDK for OpenJ9 repo.
Other
3.27k stars 720 forks source link

javax/naming/InitialContext/NoApplet.java: openj9 jdk8 x86-64_mac timeout #12638

Open lumpfish opened 3 years ago

lumpfish commented 3 years ago

extended.openjdk jdk_other test javax/naming/InitialContext/NoApplet.java fails on openj9 jdk8 x86-64_mac.

The test hits the 960 sec timeout limit. The last message output by the test is

_RegisterApplication(), FAILED TO establish the default connection to the WindowServer, _CGSDefaultConnection() is NULL.

Maybe the jdk hangs after receiving that error?

The test passes on hotspot jdk8 and jdk11, and passes on openj9 jdk11 - only fails on openj9 jdk8.

Failing job link: https://ci.adoptopenjdk.net/job/Grinder/545/console

To reproduce: https://ci.adoptopenjdk.net/job/Grinder/parambuild/?JDK_VERSION=8&JDK_IMPL=openj9&JDK_VENDOR=eclipse&BUILD_LIST=openjdk&PLATFORM=x86-64_mac&CUSTOM_TARGET=jdk/test/javax/naming/InitialContext/NoApplet.java&TARGET=jdk_custom

The failure does not seem to be a regression - the test also fails on this old build:

openjdk version "1.8.0_232" OpenJDK Runtime Environment (build 1.8.0_232-b09) Eclipse OpenJ9 VM (build openj9-0.17.0, JRE 1.8.0 Mac OS X amd64-64-Bit Compressed References 20191017_369 (JIT enabled, AOT enabled) OpenJ9 - 77c1cf708 OMR - 20db4fbc JCL - 97b5ec8f383 based on jdk8u232-b09)

Full output of failing test:

11:08:52 TEST: javax/naming/InitialContext/NoApplet.java 11:08:52 TEST JDK: /Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/.. 11:08:52
11:08:52 ACTION: build -- Passed. Build successful 11:08:52 REASON: Named class compiled on demand 11:08:52 TIME: 1.068 seconds 11:08:52 messages: 11:08:52 command: build NoApplet 11:08:52 reason: Named class compiled on demand 11:08:52 Test directory: 11:08:52 compile: NoApplet 11:08:52 elapsed time (seconds): 1.068 11:08:52
11:08:52 ACTION: compile -- Passed. Compilation successful 11:08:52 REASON: .class file out of date or does not exist 11:08:52 TIME: 1.054 seconds 11:08:52 messages: 11:08:52 command: compile -XDignore.symbol.file=true /Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext/NoApplet.java 11:08:52 reason: .class file out of date or does not exist 11:08:52 Mode: agentvm 11:08:52 Agent id: 1 11:08:52 elapsed time (seconds): 1.054 11:08:52 configuration: 11:08:52 Boot Layer (javac runtime environment) 11:08:52 class path: /Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/../lib/tools.jar 11:08:52 /Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/javatest.jar 11:08:52 /Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/jtreg.jar 11:08:52
11:08:52 javac compilation environment 11:08:52 source path: /Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext 11:08:52 class path: /Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext 11:08:52 /Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext 11:08:52 /Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/../lib/tools.jar 11:08:52
11:08:52 rerun: 11:08:52 cd /Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/scratch/0 && \ 11:08:52 HOME=/Users/jenkins \ 11:08:52 PATH=/bin:/usr/bin:/usr/sbin \ 11:08:52 /Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/../bin/javac \ 11:08:52 -J-ea \ 11:08:52 -J-esa \ 11:08:52 -J-Xmx512m \ 11:08:52 -J-XX:+UseCompressedOops \ 11:08:52 -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:+UseCompressedOops' \ 11:08:52 -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:+UseCompressedOops' \ 11:08:52 -J-Dtest.compiler.opts= \ 11:08:52 -J-Dtest.java.opts= \ 11:08:52 -J-Dtest.jdk=/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/.. \ 11:08:52 -J-Dcompile.jdk=/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/.. \ 11:08:52 -J-Dtest.timeout.factor=8.0 \ 11:08:52 -J-Dtest.root=/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test \ 11:08:52 -J-Dtest.name=javax/naming/InitialContext/NoApplet.java \ 11:08:52 -J-Dtest.file=/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext/NoApplet.java \ 11:08:52 -J-Dtest.src=/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext \ 11:08:52 -J-Dtest.src.path=/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext \ 11:08:52 -J-Dtest.classes=/Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext \ 11:08:52 -J-Dtest.class.path=/Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext \ 11:08:52 -J-Dtest.class.path.prefix=/Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext:/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext \ 11:08:52 -d /Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext \ 11:08:52 -sourcepath /Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext \ 11:08:52 -classpath /Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext:/Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext:/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/../lib/tools.jar \ 11:08:52 -XDignore.symbol.file=true /Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext/NoApplet.java 11:08:52
11:08:52 ACTION: main -- Error. Agent error: java.lang.Exception: Agent 2 timed out with a timeout of 960 seconds; check console log for any additional details 11:08:52 REASON: Assumed action based on file name: run main NoApplet 11:08:52 TIME: 960.622 seconds 11:08:52 messages: 11:08:52 command: main NoApplet 11:08:52 reason: Assumed action based on file name: run main NoApplet 11:08:52 Mode: agentvm 11:08:52 Agent id: 2 11:08:52 Timeout refired 960 times 11:08:52 Timeout information: 11:08:52 Running jcmd on process 65337 11:08:52 Error getting data from 65337: Exception connecting to 65337 11:08:52 Usage : jcmd 11:08:52
11:08:52 -J : supply arguments to the Java VM running jcmd 11:08:52 -l : list JVM processes on the local machine 11:08:52 -h : print this help message 11:08:52
11:08:52 : Attach API VM ID as shown in jps or other Attach API-based tools 11:08:52
11:08:52 arguments: 11:08:52 help : print the list of diagnostic commands 11:08:52 help : print help for the specific command 11:08:52 [command arguments] : command from the list returned by "help" 11:08:52
11:08:52 list JVM processes on the local machine. Default behavior when no options are specified. 11:08:52
11:08:52 NOTE: this utility may significantly affect the performance of the target JVM. 11:08:52 The available diagnostic commands are determined by 11:08:52 the target VM and may vary between VMs. 11:08:52 Running jstack on process 65337 11:08:52 2021-05-06T11:08:38.684 11:08:52 Error getting data from 65337: Exception connecting to 65337 11:08:52 --- Timeout information end. 11:08:52 elapsed time (seconds): 960.622 11:08:52 configuration: 11:08:52 Boot Layer 11:08:52 class path: /Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/javatest.jar 11:08:52 /Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/jtreg.jar 11:08:52 /Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/../lib/tools.jar 11:08:52 /Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/junit.jar 11:08:52 /Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/testng.jar 11:08:52 /Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/jcommander.jar 11:08:52
11:08:52 Test Layer 11:08:52 class path: /Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext 11:08:52 /Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext 11:08:52
11:08:52 rerun: 11:08:52 cd /Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/scratch/0 && \ 11:08:52 HOME=/Users/jenkins \ 11:08:52 PATH=/bin:/usr/bin:/usr/sbin \ 11:08:52 /Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/../bin/java \ 11:08:52 -Dtest.vm.opts='-ea -esa -Xmx512m -XX:+UseCompressedOops' \ 11:08:52 -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:+UseCompressedOops' \ 11:08:52 -Dtest.compiler.opts= \ 11:08:52 -Dtest.java.opts= \ 11:08:52 -Dtest.jdk=/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/.. \ 11:08:52 -Dcompile.jdk=/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/.. \ 11:08:52 -Dtest.timeout.factor=8.0 \ 11:08:52 -Dtest.root=/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test \ 11:08:52 -Dtest.name=javax/naming/InitialContext/NoApplet.java \ 11:08:52 -Dtest.file=/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext/NoApplet.java \ 11:08:52 -Dtest.src=/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext \ 11:08:52 -Dtest.src.path=/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext \ 11:08:52 -Dtest.classes=/Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext \ 11:08:52 -Dtest.class.path=/Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext \ 11:08:52 -Dtest.class.path.prefix=/Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext:/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext \ 11:08:52 -classpath /Users/jenkins/workspace/Grinder/openjdk-tests/TKG/output_16202947535076/jdk_custom_0/work/classes/0/javax/naming/InitialContext:/Users/jenkins/workspace/Grinder/openjdk-tests/openjdk/openjdk-jdk/jdk/test/javax/naming/InitialContext:/Users/jenkins/workspace/Grinder/openjdkbinary/j2sdk-image/Contents/Home/bin/../lib/tools.jar:/Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/javatest.jar:/Users/jenkins/workspace/Grinder/jvmtest/openjdk/jtreg/lib/jtreg.jar \ 11:08:52 NoApplet 11:08:52 stderr: 11:08:52 _RegisterApplication(), FAILED TO establish the default connection to the WindowServer, _CGSDefaultConnection() is NULL. 11:08:52
11:08:52 TEST RESULT: Error. Agent error: java.lang.Exception: Agent 2 timed out with a timeout of 960 seconds; check

pshipton commented 3 years ago

_RegisterApplication(), FAILED TO establish the default connection to the WindowServer, _CGSDefaultConnection() is NULL. and then hang is an old jdk8 problem we haven't yet been able to resolve. There are older issues to be found about this.

pshipton commented 3 years ago

@tajila fyi

lumpfish commented 3 years ago

Unless there's an objection I'll exclude the test on jdk8 osx to stop keep seeing the failure,

pshipton commented 3 years ago

No objection.