openj9 icon indicating copy to clipboard operation
openj9 copied to clipboard

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

Open pshipton opened this issue 1 year ago • 12 comments

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 avatar Dec 28 '23 16:12 pshipton

@babsingh

pshipton avatar Dec 28 '23 16:12 pshipton

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

JasonFengJ9 avatar Jan 06 '24 13:01 JasonFengJ9

@babsingh should this be in the 0.43 milestone plan?

pshipton avatar Jan 07 '24 19:01 pshipton

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

JasonFengJ9 avatar Jan 08 '24 13:01 JasonFengJ9

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.

babsingh avatar Jan 08 '24 19:01 babsingh

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

The timeout in SuspendResume2 and VThreadEventTest might not be related.

Created

  • https://github.com/eclipse-openj9/openj9/issues/18712

JasonFengJ9 avatar Jan 08 '24 19:01 JasonFengJ9

  • 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 https://github.com/eclipse-openj9/openj9/issues/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?

babsingh avatar Jan 15 '24 18:01 babsingh

Moved it.

pshipton avatar Jan 15 '24 18:01 pshipton

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

babsingh avatar Jan 29 '24 23:01 babsingh

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

JasonFengJ9 avatar Mar 26 '24 21:03 JasonFengJ9

@JasonFengJ9 @babsingh does this still fail

tajila avatar May 14 '24 13:05 tajila

@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

JasonFengJ9 avatar May 14 '24 13:05 JasonFengJ9

@fengxue-IS This issue targeted for 0.46. Can you open a PR to backport the fix (https://github.com/eclipse-openj9/openj9/pull/19564) to the 0.46 release branch?

babsingh avatar Jun 07 '24 18:06 babsingh

Closing, the 0.46 backport (https://github.com/eclipse-openj9/openj9/pull/19659) has been merged.

babsingh avatar Jun 07 '24 22:06 babsingh