JDK-8288907 : serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.java fails with -XX:TieredStopAtLevel=2,3
  • Type: Bug
  • Component: hotspot
  • Sub-Component: jvmti
  • Affected Version: 19
  • Priority: P4
  • Status: Resolved
  • Resolution: Fixed
  • OS: linux
  • CPU: aarch64
  • Submitted: 2022-06-21
  • Updated: 2022-12-19
  • Resolved: 2022-10-03
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.
JDK 20
20 b18Fixed
Related Reports
Relates :  
JDK-8299035 - serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.java times out with -XX:TieredStopAtLevel=3
Relates :  
JDK-8294741 - poor C1 performance on linux-aarch64 with VM option -XX:TieredStopAtLevel=2,3
Description
Test 
serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.java fails with  -XX:TieredStopAtLevel=2,3
Comments
Changeset: 07ed68ef Author: Serguei Spitsyn <sspitsyn@openjdk.org> Date: 2022-10-03 23:16:51 +0000 URL: https://git.openjdk.org/jdk/commit/07ed68efb2e9758732787447fb0339d0899be884
03-10-2022
Below is a comment with some time measurements from Chris: ----------- I'm not so sure increasing the timeout is the right thing to do. Here are the results of running 5 times on each of our 5 platforms with `-XX:TieredStopAtLevel=2"` macosx-aarch64-debug 22s windows-x64-debug 23s windows-x64-debug 23s macosx-aarch64-debug 23s macosx-aarch64-debug 24s macosx-aarch64-debug 24s windows-x64-debug 24s macosx-x64-debug 24s macosx-aarch64-debug 25s macosx-x64-debug 26s macosx-x64-debug 26s macosx-x64-debug 26s macosx-x64-debug 26s windows-x64-debug 28s windows-x64-debug 33s linux-x64-debug 43s linux-x64-debug 45s linux-aarch64-debug 2m 17s linux-x64-debug 2m 20s linux-x64-debug 2m 42s linux-x64-debug 2m 59s linux-aarch64-debug 3m 22s linux-aarch64-debug 4m 31s linux-aarch64-debug 6m 30s linux-aarch64-debug 9m 22s The last one was a timeout. So it seems that linux-aarch64 is consistently slow. linux-x64 is also a bit slow in some cases. It seems it would be worth understanding these performance differences. --------
30-09-2022
A pull request was submitted for review. URL: https://git.openjdk.org/jdk/pull/10480 Date: 2022-09-29 07:21:46 +0000
29-09-2022
Setting a proper test timeout will fix the issue.
29-09-2022
Adding timeout=<big-time> showed that the test stopped failing by timeout. It means the test execution time is not enough on linux-aarch64*.
29-09-2022
I'm suspecting that sometimes there is not enough time for this test to go through. I wonder why it is failing a lot on linux-aarch64* but not observable on other platforms. The VM flag -XX:TieredStopAtLevel=2,3 forces the C1 compilation. It can be that the C1 compiled code on linux-aarch64* is much slower. We may want to increase the timeout for this test.
29-09-2022
The log: command: main --enable-preview -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 -agentlib:SuspendResume1 SuspendResume1 reason: User specified action: run main/othervm/native --enable-preview -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 -agentlib:SuspendResume1 SuspendResume1 Mode: othervm [/othervm specified] Timeout information: --- Timeout information end. elapsed time (seconds): 1025.765 ----------configuration:(0/0)---------- ----------System.out:(24/849)---------- Agent init started Agent init finished ## Java: runIt: Starting threads ## Java: started thread: TestedThread0 ## Java: started thread: TestedThread1 ## Java: started thread: TestedThread2 ## Java: started thread: TestedThread3 ## Java: started thread: TestedThread4 ## Java: started thread: TestedThread5 ## Java: started thread: TestedThread6 ## Java: started thread: TestedThread7 ## Java: started thread: TestedThread8 ## Java: started thread: TestedThread9 ## Java: started thread: TestedThread10 ## Java: started thread: TestedThread11 ## Java: started thread: TestedThread12 ## Java: started thread: TestedThread13 ## Java: started thread: TestedThread14 ## Java: started thread: TestedThread15 ## Java: started thread: TestedThread16 ## Java: started thread: TestedThread17 ## Java: started thread: TestedThread18 Timeout refired 960 times ----------System.err:(0/0)---------- ----------rerun:(38/7290)*---------- cd /opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/scratch/3 && \\ HOME=/tmp/sparky-temp-home-253993776036225289/user_home \\ JIB_DATA_DIR=/opt/mach5/mesos/work_dir/jib-master \\ JIB_HOME=/opt/mach5/mesos/work_dir/jib-master/install/com/oracle/java/jib/jib/3.0-SNAPSHOT/jib-3.0-20220603.213301-493-distribution.zip/jib-3.0-SNAPSHOT-distribution \\ JTREG_KEYWORDS='"!jfr & !headful"' \\ JTREG_TIMEOUT_FACTOR=8 \\ JTREG_VERBOSE=fail,error,time \\ LC_ALL=C \\ PATH=/bin:/usr/bin:/usr/sbin \\ TEST_IMAGE_DIR=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.test \\ LD_LIBRARY_PATH=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.test/hotspot/jtreg/native \\ CLASSPATH=/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/3/serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.d:/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/src.full/open/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume1:/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/3/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/src.full/open/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/javatest.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/jtreg.jar \\ /opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.jdk/jdk-19/bin/java \\ -Dtest.vm.opts='-XX:MaxRAMPercentage=6.25 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/tmp' \\ -Dtest.tool.vm.opts='-J-XX:MaxRAMPercentage=6.25 -J-Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/tmp' \\ -Dtest.compiler.opts= \\ -Dtest.java.opts='-XX:+CreateCoredumpOnCrash -XX:TieredStopAtLevel=2' \\ -Dtest.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.jdk/jdk-19 \\ -Dcompile.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.jdk/jdk-19 \\ -Dtest.timeout.factor=8.0 \\ -Dtest.nativepath=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.test/hotspot/jtreg/native \\ -Dtest.root=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/src.full/open/test/hotspot/jtreg \\ -Dtest.name=serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.java \\ -Dtest.file=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/src.full/open/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.java \\ -Dtest.src=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/src.full/open/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume1 \\ -Dtest.src.path=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/src.full/open/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume1:/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/src.full/open/test/lib \\ -Dtest.classes=/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/3/serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.d \\ -Dtest.class.path=/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/3/serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.d:/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/3/test/lib \\ -Dtest.class.path.prefix=/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/3/serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.d:/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/src.full/open/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume1:/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/3/test/lib \\ -XX:MaxRAMPercentage=6.25 \\ -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/tmp \\ -XX:+CreateCoredumpOnCrash \\ -XX:TieredStopAtLevel=2 \\ -Djava.library.path=/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.test/hotspot/jtreg/native \\ --enable-preview \\ -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 \\ -agentlib:SuspendResume1 \\ com.sun.javatest.regtest.agent.MainWrapper /opt/mach5/mesos/work_dir/slaves/779adf21-f3e5-4e6a-a889-8cc0f9bc6fbb-S68327/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/e057b156-e748-41d2-9150-9b17d8a4abe4/runs/64422327-ca4a-4b7f-91d9-659b50826e80/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/serviceability/jvmti/vthread/SuspendResume1/SuspendResume1.d/main.0.jta result: Error. Program `/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.jdk/jdk-19/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 1025745ms). test result: Error. Program `/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+26-2060/linux-aarch64.jdk/jdk-19/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 1025745ms).
21-06-2022