Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

OpenJDK serviceability/jvmti/vthread/SuspendResume2/SuspendResume2 no-vmcontinuations timeout #18675

Closed
pshipton opened this issue Dec 28, 2023 · 14 comments · Fixed by #19564
Closed
Assignees
Labels
comp:jvmti comp:vm project:loom Used to track Project Loom related work test failure

Comments

@pshipton
Copy link
Member

https://hyc-runtimes-jenkins.swg-devops.com/job/Test_openjdk21_j9_extended.openjdk_aarch64_mac/23
serviceability_jvmti_j9_1
serviceability/jvmti/vthread/SuspendResume2/SuspendResume2.java#no-vmcontinuations

00:38:54  ACTION: main -- Error. Program `/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960385ms).
00:38:54  REASON: User specified action: run main/othervm/native -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 -agentlib:SuspendResume2 -XX:+UnlockExperimentalVMOptions -XX:-VMContinuations SuspendResume2 
00:38:54  TIME:   960.39 seconds
00:38:54  messages:
00:38:54  command: main -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 -agentlib:SuspendResume2 -XX:+UnlockExperimentalVMOptions -XX:-VMContinuations SuspendResume2
00:38:54  reason: User specified action: run main/othervm/native -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 -agentlib:SuspendResume2 -XX:+UnlockExperimentalVMOptions -XX:-VMContinuations SuspendResume2 
00:38:54  started: Wed Dec 27 00:22:49 EST 2023
00:38:54  Mode: othervm [/othervm specified]
00:38:54  Timeout information:
00:38:54  Running jstack on process 28750
00:38:54  2023-12-27T00:38:49.465924
00:38:54  Error getting data from 28750: Exception connecting to 28750
00:38:54  --- Timeout information end.
00:38:54  finished: Wed Dec 27 00:38:49 EST 2023
00:38:54  elapsed time (seconds): 960.39
00:38:54  configuration:
00:38:54  STDOUT:
00:38:54  Agent init started
00:38:54  Agent init finished
00:38:54  
00:38:54  ## Java: runIt: Starting threads
00:38:54  ## Java: started thread: TestedThread0
00:38:54  ## Java: started thread: TestedThread1
00:38:54  ## Java: started thread: TestedThread2
00:38:54  ## Java: started thread: TestedThread3
00:38:54  ## Java: started thread: TestedThread4
00:38:54  ## Java: started thread: TestedThread5
00:38:54  ## Java: started thread: TestedThread6
00:38:54  ## Java: started thread: TestedThread7
00:38:54  ## Java: started thread: TestedThread8
00:38:54  ## Java: started thread: TestedThread9
00:38:54  ## Java: started thread: TestedThread10
00:38:54  ## Java: started thread: TestedThread11
00:38:54  ## Java: started thread: TestedThread12
00:38:54  ## Java: started thread: TestedThread13
00:38:54  ## Java: started thread: TestedThread14
00:38:54  ## Java: started thread: TestedThread15
00:38:54  ## Java: started thread: TestedThread16
00:38:54  ## Java: started thread: TestedThread17
00:38:54  ## Java: started thread: TestedThread18
00:38:54  ## Java: started thread: TestedThread19
00:38:54  
00:38:54  ## Java: runIt: testing Suspend/Resume
00:38:54  
00:38:54  ## TestSuspendResume: started
00:38:54  
00:38:54  ## Agent: test_vthread_suspend_all started
00:38:54  ## Agent: thread[0] 0x149865788 TestedThread0: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[1] 0x149865790 TestedThread1: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[2] 0x149865798 TestedThread2: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[3] 0x1498657a0 TestedThread3: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[4] 0x1498657a8 TestedThread4: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[5] 0x1498657b0 TestedThread5: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[6] 0x1498657b8 TestedThread6: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[7] 0x1498657c0 TestedThread7: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[8] 0x1498657c8 TestedThread8: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[9] 0x1498657d0 TestedThread9: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[10] 0x1498657d8 TestedThread10: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[11] 0x1498657e0 TestedThread11: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[12] 0x1498657e8 TestedThread12: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[13] 0x1498657f0 TestedThread13: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[14] 0x1498657f8 TestedThread14: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[15] 0x149865808 TestedThread15: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[16] 0x149865810 TestedThread16: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[17] 0x149865818 TestedThread17: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[18] 0x149865820 TestedThread18: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[19] 0x149865828 TestedThread19: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  
00:38:54  ## Agent: test_vthread_suspend_all finished
00:38:54  
00:38:54  ## Agent: test_vthread_resume_all started
00:38:54  ## Agent: thread[0] 0x149865788 TestedThread0: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[0] 0x149865788 TestedThread0: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[1] 0x149865790 TestedThread1: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[1] 0x149865790 TestedThread1: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[2] 0x149865798 TestedThread2: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[3] 0x1498657a0 TestedThread3: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[4] 0x1498657a8 TestedThread4: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[5] 0x1498657b0 TestedThread5: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[6] 0x1498657b8 TestedThread6: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[7] 0x1498657c0 TestedThread7: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[8] 0x1498657c8 TestedThread8: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[9] 0x1498657d0 TestedThread9: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[10] 0x1498657d8 TestedThread10: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[11] 0x1498657e0 TestedThread11: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[12] 0x1498657e8 TestedThread12: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[13] 0x1498657f0 TestedThread13: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[14] 0x1498657f8 TestedThread14: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[15] 0x149865808 TestedThread15: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[16] 0x149865810 TestedThread16: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[17] 0x149865818 TestedThread17: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[18] 0x149865820 TestedThread18: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[19] 0x149865828 TestedThread19: state after resume:  ALIVE RUNNABLE (5)
00:38:54  
00:38:54  ## Agent: test_vthread_resume_all: finished
00:38:54  
00:38:54  ## Agent: test_vthread_suspend_half started
00:38:54  ## Agent: thread[0] 0x149865788 TestedThread0: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[2] 0x149865798 TestedThread2: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[4] 0x1498657a8 TestedThread4: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[6] 0x1498657b8 TestedThread6: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[8] 0x1498657c8 TestedThread8: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[10] 0x1498657d8 TestedThread10: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[12] 0x1498657e8 TestedThread12: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[14] 0x1498657f8 TestedThread14: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[16] 0x149865810 TestedThread16: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[18] 0x149865820 TestedThread18: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  
00:38:54  ## Agent: test_vthread_suspend_half finished
00:38:54  
00:38:54  ## Agent: test_vthread_resume_all started
00:38:54  ## Agent: thread[0] 0x149865788 TestedThread0: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[0] 0x149865788 TestedThread0: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[1] 0x149865790 TestedThread1: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[2] 0x149865798 TestedThread2: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[3] 0x1498657a0 TestedThread3: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[4] 0x1498657a8 TestedThread4: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[5] 0x1498657b0 TestedThread5: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[6] 0x1498657b8 TestedThread6: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[7] 0x1498657c0 TestedThread7: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[8] 0x1498657c8 TestedThread8: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[9] 0x1498657d0 TestedThread9: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[10] 0x1498657d8 TestedThread10: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[11] 0x1498657e0 TestedThread11: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[12] 0x1498657e8 TestedThread12: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[13] 0x1498657f0 TestedThread13: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[14] 0x1498657f8 TestedThread14: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[15] 0x149865808 TestedThread15: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[16] 0x149865810 TestedThread16: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[17] 0x149865818 TestedThread17: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[18] 0x149865820 TestedThread18: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[19] 0x149865828 TestedThread19: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  
00:38:54  ## Agent: test_vthread_resume_all: finished
00:38:54  
00:38:54  ## Agent: test_vthread_suspend_all started
00:38:54  ## Agent: thread[0] 0x149865788 TestedThread0: state after resume:  ALIVE RUNNABLE (5)
00:38:54  ## Agent: thread[1] 0x149865790 TestedThread1: state after resume:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED (673)
00:38:54  ## Agent: thread[2] 0x149865798 TestedThread2: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[3] 0x1498657a0 TestedThread3: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[4] 0x1498657a8 TestedThread4: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[5] 0x1498657b0 TestedThread5: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[6] 0x1498657b8 TestedThread6: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[7] 0x1498657c0 TestedThread7: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[8] 0x1498657c8 TestedThread8: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[9] 0x1498657d0 TestedThread9: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[10] 0x1498657d8 TestedThread10: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[11] 0x1498657e0 TestedThread11: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[12] 0x1498657e8 TestedThread12: state after suspend:  ALIVE WAITING WAITING_WITH_TIMEOUT PARKED SUSPENDED (1049249)
00:38:54  ## Agent: thread[13] 0x1498657f0 TestedThread13: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[14] 0x1498657f8 TestedThread14: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  ## Agent: thread[15] 0x149865808 TestedThread15: state after suspend:  ALIVE RUNNABLE SUSPENDED (1048581)
00:38:54  Timeout signalled after 960 seconds
00:38:54  STDERR:
00:38:54  rerun:
00:38:54  cd /Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/scratch/4 && \
00:38:54  HOME=/Users/jenkins \
00:38:54  PATH=/bin:/usr/bin:/usr/sbin \
00:38:54  DYLD_LIBRARY_PATH=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
00:38:54  CLASSPATH=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/classes/1/serviceability/jvmti/vthread/SuspendResume2/SuspendResume2_no-vmcontinuations.d:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume2:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/classes/1/test/lib:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/openjdk/openjdk-jdk/test/lib:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jvmtest/openjdk/jtreg/lib/javatest.jar:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jvmtest/openjdk/jtreg/lib/jtreg.jar \
00:38:54      /Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/java \
00:38:54          -Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops -Xverbosegclog' \
00:38:54          -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops -J-Xverbosegclog' \
00:38:54          -Dtest.compiler.opts= \
00:38:54          -Dtest.java.opts= \
00:38:54          -Dtest.jdk=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/.. \
00:38:54          -Dcompile.jdk=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/.. \
00:38:54          -Dtest.timeout.factor=8.0 \
00:38:54          -Dtest.nativepath=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
00:38:54          -Dtest.root=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg \
00:38:54          -Dtest.name=serviceability/jvmti/vthread/SuspendResume2/SuspendResume2.java#no-vmcontinuations \
00:38:54          -Dtest.file=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume2/SuspendResume2.java \
00:38:54          -Dtest.src=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume2 \
00:38:54          -Dtest.src.path=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume2:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/openjdk/openjdk-jdk/test/lib \
00:38:54          -Dtest.classes=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/classes/1/serviceability/jvmti/vthread/SuspendResume2/SuspendResume2_no-vmcontinuations.d \
00:38:54          -Dtest.class.path=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/classes/1/serviceability/jvmti/vthread/SuspendResume2/SuspendResume2_no-vmcontinuations.d:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/classes/1/test/lib \
00:38:54          -Dtest.class.path.prefix=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/classes/1/serviceability/jvmti/vthread/SuspendResume2/SuspendResume2_no-vmcontinuations.d:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/openjdk/openjdk-jdk/test/hotspot/jtreg/serviceability/jvmti/vthread/SuspendResume2:/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/classes/1/test/lib \
00:38:54          -ea \
00:38:54          -esa \
00:38:54          -Xmx512m \
00:38:54          -XX:-UseCompressedOops \
00:38:54          -Xverbosegclog \
00:38:54          -Djava.library.path=/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jdkbinary/openjdk-test-image/hotspot/jtreg/native \
00:38:54          -Djava.util.concurrent.ForkJoinPool.common.parallelism=1 \
00:38:54          -agentlib:SuspendResume2 \
00:38:54          -XX:+UnlockExperimentalVMOptions \
00:38:54          -XX:-VMContinuations \
00:38:54          com.sun.javatest.regtest.agent.MainWrapper /Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/aqa-tests/TKG/output_17036544697711/serviceability_jvmti_j9_1/work/serviceability/jvmti/vthread/SuspendResume2/SuspendResume2_no-vmcontinuations.d/main.0.jta
00:38:54  
00:38:54  TEST RESULT: Error. Program `/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960385ms).
@pshipton pshipton added test failure project:loom Used to track Project Loom related work labels Dec 28, 2023
@pshipton
Copy link
Member Author

@babsingh

@JasonFengJ9
Copy link
Member

JasonFengJ9 commented Jan 6, 2024

JDK21 x86-64_mac(mac10x64rt02)

[2024-01-05T07:29:45.736Z] variation: Mode150
[2024-01-05T07:29:45.736Z] JVM_OPTIONS:  -XX:+UseCompressedOops -Xverbosegclog 

[2024-01-05T07:49:38.020Z] TEST: serviceability/jvmti/vthread/SuspendResume2/SuspendResume2.java#default

[2024-01-05T07:49:38.022Z] Timeout signalled after 960 seconds
[2024-01-05T07:49:38.022Z] STDERR:

[2024-01-05T07:49:38.022Z] TEST RESULT: Error. Program `/Users/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_x86-64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/../bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 960609ms).
[2024-01-05T07:49:38.022Z] --------------------------------------------------
[2024-01-05T07:49:38.022Z] Test results: passed: 155; error: 1

[2024-01-05T07:49:45.791Z] serviceability_jvmti_j9_0_FAILED

50x grinder - 7/50 failed

@pshipton
Copy link
Member Author

pshipton commented Jan 7, 2024

@babsingh should this be in the 0.43 milestone plan?

@JasonFengJ9
Copy link
Member

Another timeout at serviceability/jvmti/vthread/VThreadEventTest/VThreadEventTest.java
JDK21 x86-64_windows(win19x86-svl-rt4-1)

13:40:34  ===============================================
13:40:35  Running test serviceability_jvmti_j9_0 ...
13:40:35  ===============================================
13:40:36  serviceability_jvmti_j9_0 Start Time: Sun Jan  7 10:40:35 2024 Epoch Time (ms): 1704652835766
13:40:36  variation: Mode150
13:40:36  JVM_OPTIONS:  -XX:+UseCompressedOops -Xverbosegclog 

14:32:36  TEST: serviceability/jvmti/vthread/VThreadEventTest/VThreadEventTest.java

14:32:36  TEST RESULT: Error. Program `C:\Users\jenkins\workspace\Test_openjdk21_j9_extended.openjdk_x86-64_windows_testList_5\jdkbinary\j2sdk-image\bin\java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 1199454ms).
14:32:36  --------------------------------------------------
14:32:36  Test results: passed: 155; error: 1

14:33:05  -----------------------------------
14:33:05  serviceability_jvmti_j9_0_FAILED

50x grinder

@babsingh
Copy link
Contributor

babsingh commented Jan 8, 2024

should this be in the 0.43 milestone plan?

Yes since the failure happens quite often on OSX. Taking a look at the failure.

Another timeout at serviceability/jvmti/vthread/VThreadEventTest/VThreadEventTest.java

The timeout in SuspendResume2 and VThreadEventTest might not be related.

@JasonFengJ9
Copy link
Member

Another timeout at serviceability/jvmti/vthread/VThreadEventTest/VThreadEventTest.java

The timeout in SuspendResume2 and VThreadEventTest might not be related.

Created

@babsingh
Copy link
Contributor

babsingh commented Jan 15, 2024

  • The virtual thread is in a unsteady state (inspectorCount = -1) after being suspended via JVMTI. This causes the next JVMTI operations on the virtual thread to deadlock in acquireVThreadInspector, which expect (inspectorCount != -1).
  • This is a duplicate of https://bugs.openjdk.org/browse/JDK-8311218.
  • The solution is related to [Loom] Implement JVM_VirtualThreadDisableSuspend #18671. The synchronized (interruptLock) blocks also exist in JDK21's VirtualThread. They will need to be protected with JVM_VirtualThreadDisableSuspend. So, we will need to backport this function to JDK21.
  • The RI has pushed the solution only till JDK22. The solution doesn't exist in the RI's JDK21. I won't be able to provide a reliable fix for the 0.43 release by the deadline. @pshipton Can we push this issue to the 0.44 release?

@pshipton
Copy link
Member Author

Moved it.

@babsingh
Copy link
Contributor

Further investigation showed that the bug associated with JVM_VirtualThreadDisableSuspend doesn't exist in OpenJ9; see #18671 for more details. Guess: a fix for #18504 might help with this failure.

@JasonFengJ9
Copy link
Member

JDK21 x86-64_linux_fips140_2(rhel8x86-svl-rtfips5-1)

openjdk version "21.0.3" 2024-04-16 LTS
IBM Semeru Runtime Open Edition 21.0.3.0-m3 (build 21.0.3+7-LTS)
Eclipse OpenJ9 VM 21.0.3.0-m3 (build v0.44.0-release-e14f0f01c, JRE 21 Linux amd64-64-Bit Compressed References 20240416_133 (JIT enabled, AOT enabled)
OpenJ9   - e14f0f01c
OMR      - 254af5a04
JCL      - d85f56ae366 based on jdk-21.0.3+7)

[2024-03-25T21:43:51.386Z] variation: Mode650
[2024-03-25T21:43:51.386Z] JVM_OPTIONS:  -XX:-UseCompressedOops -Xverbosegclog  -Dsemeru.fips=true

[2024-03-25T22:04:12.922Z] TEST: serviceability/jvmti/vthread/SuspendResume2/SuspendResume2.java#no-vmcontinuations

[2024-03-25T22:04:12.923Z] TEST RESULT: Error. Program `/home/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_x86-64_linux_fips140_2/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 965447ms).
[2024-03-25T22:04:12.923Z] --------------------------------------------------
[2024-03-25T22:04:12.923Z] Test results: passed: 155; error: 1
[2024-03-25T22:04:25.172Z] Report written to /home/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_x86-64_linux_fips140_2/jvmtest/openjdk/report/html/report.html
[2024-03-25T22:04:25.172Z] Results written to /home/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_x86-64_linux_fips140_2/aqa-tests/TKG/output_17114027483065/serviceability_jvmti_j9_1/work
[2024-03-25T22:04:25.172Z] Error: Some tests failed or other problems occurred.
[2024-03-25T22:04:25.172Z] -----------------------------------
[2024-03-25T22:04:25.172Z] serviceability_jvmti_j9_1_FAILED

@tajila
Copy link
Contributor

tajila commented May 14, 2024

@JasonFengJ9 @babsingh does this still fail

@JasonFengJ9
Copy link
Member

@tajila yeah, the timeout failures are still seen in JDK 21/22.

JDK21 ppc64le_linux(prhel248)

[2024-05-04T21:12:45.818Z] TEST: serviceability/jvmti/vthread/SuspendResume2/SuspendResume2.java#no-vmcontinuations

[2024-05-04T21:12:45.821Z] TEST RESULT: Error. Program `/home/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_ppc64le_linux_rerun/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 970722ms).
[2024-05-04T21:12:45.821Z] --------------------------------------------------
[2024-05-04T21:12:45.821Z] Test results: passed: 155; error: 1
[2024-05-04T21:13:06.583Z] Report written to /home/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_ppc64le_linux_rerun/jvmtest/openjdk/report/html/report.html
[2024-05-04T21:13:06.583Z] Results written to /home/jenkins/workspace/Test_openjdk21_j9_extended.openjdk_ppc64le_linux_rerun/aqa-tests/TKG/output_17148553778720/serviceability_jvmti_j9_0/work
[2024-05-04T21:13:06.583Z] Error: Some tests failed or other problems occurred.
[2024-05-04T21:13:06.583Z] -----------------------------------
[2024-05-04T21:13:06.583Z] serviceability_jvmti_j9_0_FAILED

JDK22 ppc64le_linux(rtj-rhel8le-rtp-test-vbk34-1)

[2024-05-11T17:20:16.595Z] TEST: serviceability/jvmti/vthread/SuspendResume2/SuspendResume2.java#default

[2024-05-11T17:20:16.599Z] TEST RESULT: Error. Program `/home/jenkins/workspace/Test_openjdk22_j9_extended.openjdk_ppc64le_linux_testList_1/jdkbinary/j2sdk-image/bin/java' timed out (timeout set to 960000ms, elapsed time including timeout handling was 964995ms).
[2024-05-11T17:20:16.599Z] --------------------------------------------------
[2024-05-11T17:20:16.599Z] Test results: passed: 158; error: 1
[2024-05-11T17:20:52.196Z] Report written to /home/jenkins/workspace/Test_openjdk22_j9_extended.openjdk_ppc64le_linux_testList_1/jvmtest/openjdk/report/html/report.html
[2024-05-11T17:20:52.196Z] Results written to /home/jenkins/workspace/Test_openjdk22_j9_extended.openjdk_ppc64le_linux_testList_1/aqa-tests/TKG/output_17154466986793/serviceability_jvmti_j9_1/work
[2024-05-11T17:20:52.196Z] Error: Some tests failed or other problems occurred.
[2024-05-11T17:20:52.196Z] -----------------------------------
[2024-05-11T17:20:52.196Z] serviceability_jvmti_j9_1_FAILED

@babsingh
Copy link
Contributor

babsingh commented Jun 7, 2024

@fengxue-IS This issue targeted for 0.46. Can you open a PR to backport the fix (#19564) to the 0.46 release branch?

@babsingh babsingh reopened this Jun 7, 2024
@babsingh
Copy link
Contributor

babsingh commented Jun 7, 2024

Closing, the 0.46 backport (#19659) has been merged.

@babsingh babsingh closed this as completed Jun 7, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
comp:jvmti comp:vm project:loom Used to track Project Loom related work test failure
Projects
None yet
Development

Successfully merging a pull request may close this issue.

5 participants