#Test Results (version 2) #Thu Nov 07 06:51:07 UTC 2024 #-----testdescription----- $file=/home/amit/jdk/test/jdk/java/lang/Thread/virtual/CancelTimerWithContention.java $root=/home/amit/jdk/test/jdk keywords=randomness othervm run=USER_SPECIFIED main/othervm -Djdk.virtualThreadScheduler.parallelism\=2 -Djdk.virtualThreadScheduler.timerQueues\=1 CancelTimerWithContention\n source=CancelTimerWithContention.java title=Test cancelling a timeout task for Object.wait(millis) when there is contention on the timer queue #-----environment----- #-----testresult----- description=file\:/home/amit/jdk/test/jdk/java/lang/Thread/virtual/CancelTimerWithContention.java elapsed=480966 0\:08\:00.966 end=Thu Nov 07 06\:51\:07 UTC 2024 environment=regtest execStatus=Error. Program `/home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk/bin/java' timed out (timeout set to 480000ms, elapsed time including timeout handling was 480874ms). harnessLoaderMode=Classpath Loader harnessVariety=Full Bundle hostname=t8360021.lnxne.boe javatestOS=Linux 5.15.0-124-generic (s390x) javatestVersion=6.0-ea+b24-2024-06-23-${BUILT_FROM_COMMIT} jtregVersion=jtreg 7.5 dev 0 script=com.sun.javatest.regtest.exec.RegressionScript sections=script_messages build compile main start=Thu Nov 07 06\:43\:06 UTC 2024 test=java/lang/Thread/virtual/CancelTimerWithContention.java testJDK=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk testJDK_OS=[name\:Linux,arch\:s390x,version\:5.15.0-124-generic,family\:linux,simple_arch\:s390x,simple_version\:5.15,processors\:64,maxMemory\:65361444864,maxSwap\:21472735232] testJDK_os.arch=s390x testJDK_os.name=Linux testJDK_os.version=5.15.0-124-generic totalTime=481155 user.name=amit work=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/java/lang/Thread/virtual #section:script_messages ----------messages:(4/282)---------- JDK under test: /home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk openjdk version "24-internal" 2025-03-18 OpenJDK Runtime Environment (fastdebug build 24-internal-adhoc.amit.jdk) OpenJDK 64-Bit Server VM (fastdebug build 24-internal-adhoc.amit.jdk, mixed mode, sharing) #section:build ----------messages:(7/240)---------- command: build CancelTimerWithContention reason: Named class compiled on demand started: Thu Nov 07 06:43:06 UTC 2024 Test directory: compile: CancelTimerWithContention finished: Thu Nov 07 06:43:06 UTC 2024 elapsed time (seconds): 0.036 result: Passed. Build successful #section:compile ----------messages:(7/281)---------- command: compile /home/amit/jdk/test/jdk/java/lang/Thread/virtual/CancelTimerWithContention.java reason: .class file out of date or does not exist started: Thu Nov 07 06:43:06 UTC 2024 Mode: agentvm Agent id: 23 finished: Thu Nov 07 06:43:06 UTC 2024 elapsed time (seconds): 0.036 ----------configuration:(10/583)---------- Boot Layer (javac runtime environment) class path: /home/amit/jtreg/lib/javatest.jar /home/amit/jtreg/lib/jtreg.jar patch: java.base /home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/patches/java.base javac compilation environment source path: /home/amit/jdk/test/jdk/java/lang/Thread/virtual class path: /home/amit/jdk/test/jdk/java/lang/Thread/virtual /home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d ----------rerun:(42/3466)*---------- cd /home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/scratch/5 && \\ DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus \\ DOCS_JDK_IMAGE_DIR=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/docs \\ HOME=/home/amit \\ LANG=C.UTF-8 \\ LC_ALL=C.UTF-8 \\ LC_CTYPE=C.UTF-8 \\ PATH=/bin:/usr/bin:/usr/sbin \\ TEST_IMAGE_DIR=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/test \\ XDG_DATA_DIRS=/usr/local/share:/usr/share:/var/lib/snapd/desktop \\ XDG_RUNTIME_DIR=/run/user/1000 \\ XDG_SESSION_CLASS=user \\ XDG_SESSION_ID=25 \\ XDG_SESSION_TYPE=tty \\ _JVM_DWARF_PATH=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/symbols \\ /home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk/bin/javac \\ -J-Xmx768m \\ -J-XX:MaxRAMPercentage=0.833333 \\ -J-Dtest.boot.jdk=/home/amit/boot_jdk_23 \\ -J-Djava.io.tmpdir=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/tmp \\ -J-ea \\ -J-esa \\ -J-Dtest.vm.opts='-Xmx768m -XX:MaxRAMPercentage=0.833333 -Dtest.boot.jdk=/home/amit/boot_jdk_23 -Djava.io.tmpdir=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/tmp -ea -esa' \\ -J-Dtest.tool.vm.opts='-J-Xmx768m -J-XX:MaxRAMPercentage=0.833333 -J-Dtest.boot.jdk=/home/amit/boot_jdk_23 -J-Djava.io.tmpdir=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/tmp -J-ea -J-esa' \\ -J-Dtest.compiler.opts= \\ -J-Dtest.java.opts= \\ -J-Dtest.jdk=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk \\ -J-Dcompile.jdk=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk \\ -J-Dtest.timeout.factor=4.0 \\ -J-Dtest.nativepath=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/test/jdk/jtreg/native \\ -J-Dtest.root=/home/amit/jdk/test/jdk \\ -J-Dtest.name=java/lang/Thread/virtual/CancelTimerWithContention.java \\ -J-Dtest.verbose=Verbose[p=SUMMARY,f=FULL,e=FULL,t=false,m=false] \\ -J-Dtest.file=/home/amit/jdk/test/jdk/java/lang/Thread/virtual/CancelTimerWithContention.java \\ -J-Dtest.src=/home/amit/jdk/test/jdk/java/lang/Thread/virtual \\ -J-Dtest.src.path=/home/amit/jdk/test/jdk/java/lang/Thread/virtual \\ -J-Dtest.classes=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d \\ -J-Dtest.class.path=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d \\ -J-Dtest.class.path.prefix=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d:/home/amit/jdk/test/jdk/java/lang/Thread/virtual \\ -d /home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d \\ -sourcepath /home/amit/jdk/test/jdk/java/lang/Thread/virtual \\ -classpath /home/amit/jdk/test/jdk/java/lang/Thread/virtual:/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d /home/amit/jdk/test/jdk/java/lang/Thread/virtual/CancelTimerWithContention.java result: Passed. Compilation successful #section:main ----------messages:(288/18235)---------- command: main -Djdk.virtualThreadScheduler.parallelism=2 -Djdk.virtualThreadScheduler.timerQueues=1 CancelTimerWithContention reason: User specified action: run main/othervm -Djdk.virtualThreadScheduler.parallelism=2 -Djdk.virtualThreadScheduler.timerQueues=1 CancelTimerWithContention started: Thu Nov 07 06:43:06 UTC 2024 Mode: othervm [/othervm specified] Timeout information: Running jstack on process 3199496 2024-11-07 06:51:06 Full thread dump OpenJDK 64-Bit Server VM (24-internal-adhoc.amit.jdk mixed mode, sharing): Threads class SMR info: _java_thread_list=0x000003fe00001170, length=22, elements={ 0x000003ff9802e070, 0x000003ff98a3b130, 0x000003ff98a3ca10, 0x000003ff98a3e730, 0x000003ff98a40100, 0x000003ff98a41990, 0x000003ff98a53090, 0x000003ff98a54b10, 0x000003ff98a96410, 0x000003ff98a9b570, 0x000003ff98ace320, 0x000003ff4c000fe0, 0x000003ff1c048b90, 0x000003ff1c0cd0f0, 0x000003ff1c1e7e60, 0x000003ff1c1a8590, 0x000003ff1c06d7b0, 0x000003ff1c1c2ca0, 0x000003ff1c0c6da0, 0x000003ff1c1fe8a0, 0x000003ff1c14c230, 0x000003ff1c1cc130 } _java_thread_list_alloc_cnt=867949, _java_thread_list_free_cnt=867947, _java_thread_list_max=301, _nested_thread_list_max=0 _tlh_cnt=82, _tlh_times=92, avg_tlh_time=1.12, _tlh_time_max=35 _deleted_thread_cnt=433963, _deleted_thread_times=99814, avg_deleted_thread_time=0.23, _deleted_thread_time_max=261 _delete_lock_wait_cnt=0, _delete_lock_wait_max=9 _to_delete_list_cnt=0, _to_delete_list_max=2 "main" #3 [3199520] prio=5 os_prio=0 cpu=288.95ms elapsed=480.74s tid=0x000003ff9802e070 nid=3199520 in Object.wait() [0x000003ff9c57e000] java.lang.Thread.State: WAITING (on object monitor) Thread: 0x000003ff9802e070 [0x30d220] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on <0x00000000fb900000> (a java.lang.Thread) at java.lang.Object.wait(java.base@24-internal/Object.java:389) at java.lang.Thread.join(java.base@24-internal/Thread.java:2024) - locked <0x00000000fb900000> (a java.lang.Thread) at java.lang.Thread.join(java.base@24-internal/Thread.java:2100) at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:85) "Reference Handler" #11 [3199592] daemon prio=10 os_prio=0 cpu=0.69ms elapsed=480.59s tid=0x000003ff98a3b130 nid=3199592 waiting on condition [0x000003ff6b1fe000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff98a3b130 [0x30d268] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.ref.Reference.waitForReferencePendingList(java.base@24-internal/Native Method) at java.lang.ref.Reference.processPendingReferences(java.base@24-internal/Reference.java:246) at java.lang.ref.Reference$ReferenceHandler.run(java.base@24-internal/Reference.java:208) "Finalizer" #12 [3199593] daemon prio=8 os_prio=0 cpu=0.13ms elapsed=480.59s tid=0x000003ff98a3ca10 nid=3199593 in Object.wait() [0x000003ff6b0fe000] java.lang.Thread.State: WAITING (on object monitor) Thread: 0x000003ff98a3ca10 [0x30d269] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on <0x00000000fb902378> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.Object.wait(java.base@24-internal/Object.java:389) at java.lang.Object.wait(java.base@24-internal/Object.java:351) at java.lang.ref.ReferenceQueue.remove0(java.base@24-internal/ReferenceQueue.java:138) at java.lang.ref.ReferenceQueue.remove(java.base@24-internal/ReferenceQueue.java:229) - locked <0x00000000fb902378> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.Finalizer$FinalizerThread.run(java.base@24-internal/Finalizer.java:173) "Signal Dispatcher" #13 [3199598] daemon prio=9 os_prio=0 cpu=0.54ms elapsed=480.58s tid=0x000003ff98a3e730 nid=3199598 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff98a3e730 [0x30d26e] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "Service Thread" #14 [3199602] daemon prio=9 os_prio=0 cpu=1757.11ms elapsed=480.58s tid=0x000003ff98a40100 nid=3199602 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff98a40100 [0x30d272] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "Monitor Deflation Thread" #15 [3199603] daemon prio=9 os_prio=0 cpu=22.07ms elapsed=480.58s tid=0x000003ff98a41990 nid=3199603 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff98a41990 [0x30d273] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "C2 CompilerThread0" #16 [3199604] daemon prio=9 os_prio=0 cpu=462.34ms elapsed=480.57s tid=0x000003ff98a53090 nid=3199604 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff98a53090 [0x30d274] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked No compile task "C1 CompilerThread0" #28 [3199614] daemon prio=9 os_prio=0 cpu=99.87ms elapsed=480.55s tid=0x000003ff98a54b10 nid=3199614 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff98a54b10 [0x30d27e] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked No compile task "Notification Thread" #34 [3199668] daemon prio=9 os_prio=0 cpu=0.08ms elapsed=480.43s tid=0x000003ff98a96410 nid=3199668 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff98a96410 [0x30d2b4] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "Common-Cleaner" #35 [3199677] daemon prio=8 os_prio=0 cpu=1.13ms elapsed=480.42s tid=0x000003ff98a9b570 nid=3199677 in Object.wait() [0x000003ff6a3fe000] java.lang.Thread.State: TIMED_WAITING (on object monitor) Thread: 0x000003ff98a9b570 [0x30d2bd] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on at java.lang.Object.wait(java.base@24-internal/Object.java:389) at java.lang.ref.ReferenceQueue.remove0(java.base@24-internal/ReferenceQueue.java:124) at java.lang.ref.ReferenceQueue.remove(java.base@24-internal/ReferenceQueue.java:215) - locked <0x00000000fb9034f8> (a java.lang.ref.ReferenceQueue$Lock) at jdk.internal.ref.CleanerImpl.run(java.base@24-internal/CleanerImpl.java:140) at java.lang.Thread.runWith(java.base@24-internal/Thread.java:1595) at java.lang.Thread.run(java.base@24-internal/Thread.java:1582) at jdk.internal.misc.InnocuousThread.run(java.base@24-internal/InnocuousThread.java:186) "MainThread" #36 [3199725] prio=5 os_prio=0 cpu=30520.29ms elapsed=480.28s tid=0x000003ff98ace320 nid=3199725 runnable [0x000003ff68d7d000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff98ace320 [0x30d2ed] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Thread.start0(java.base@24-internal/Native Method) at java.lang.Thread.start(java.base@24-internal/Thread.java:1552) - locked <0x00000000ff6f1e70> (a java.lang.ThreadBuilders$BoundVirtualThread) at java.lang.System$2.start(java.base@24-internal/System.java:2690) at java.util.concurrent.ThreadPerTaskExecutor.start(java.base@24-internal/ThreadPerTaskExecutor.java:243) at java.util.concurrent.ThreadPerTaskExecutor.submit(java.base@24-internal/ThreadPerTaskExecutor.java:281) at CancelTimerWithContention.test(CancelTimerWithContention.java:79) at CancelTimerWithContention.main(CancelTimerWithContention.java:56) at java.lang.invoke.LambdaForm$DMH/0x000003ff584063e0.invokeStatic(java.base@24-internal/LambdaForm$DMH) at java.lang.invoke.LambdaForm$MH/0x000003ff58408c10.invoke(java.base@24-internal/LambdaForm$MH) at java.lang.invoke.Invokers$Holder.invokeExact_MT(java.base@24-internal/Invokers$Holder) at jdk.internal.reflect.DirectMethodHandleAccessor.invokeImpl(java.base@24-internal/DirectMethodHandleAccessor.java:155) at jdk.internal.reflect.DirectMethodHandleAccessor.invoke(java.base@24-internal/DirectMethodHandleAccessor.java:104) at java.lang.reflect.Method.invoke(java.base@24-internal/Method.java:573) at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) at java.lang.Thread.runWith(java.base@24-internal/Thread.java:1595) at java.lang.Thread.run(java.base@24-internal/Thread.java:1582) "Attach Listener" #432862 [4087224] daemon prio=9 os_prio=0 cpu=0.69ms elapsed=0.12s tid=0x000003ff4c000fe0 nid=4087224 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff4c000fe0 [0x3e5db8] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "" #433879 [4088246] daemon prio=5 os_prio=0 cpu=0.10ms elapsed=0.02s tid=0x000003ff1c048b90 nid=4088246 runnable [0x0000000000000000] java.lang.Thread.State: TERMINATED Thread: 0x000003ff1c048b90 [0x3e61b6] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "" #433880 [4088247] daemon prio=5 os_prio=0 cpu=0.09ms elapsed=0.02s tid=0x000003ff1c0cd0f0 nid=4088247 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff1c0cd0f0 [0x3e61b7] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "" #434003 [4088373] daemon prio=5 os_prio=0 cpu=0.07ms elapsed=0.01s tid=0x000003ff1c1e7e60 nid=4088373 runnable [0x0000000000000000] java.lang.Thread.State: TERMINATED Thread: 0x000003ff1c1e7e60 [0x3e6235] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "" #434005 [4088375] daemon prio=5 os_prio=0 cpu=0.03ms elapsed=0.01s tid=0x000003ff1c1a8590 nid=4088375 in Object.wait() [0x000003ff69cfe000] java.lang.Thread.State: TIMED_WAITING (on object monitor) Thread: 0x000003ff1c1a8590 [0x3e6237] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on at java.lang.Object.wait(java.base@24-internal/Object.java:389) at CancelTimerWithContention.lambda$test$0(CancelTimerWithContention.java:81) - locked <0x00000000ff686b60> (a java.lang.Object) at CancelTimerWithContention$$Lambda/0x000000001b001218.call(Unknown Source) at java.util.concurrent.FutureTask.run(java.base@24-internal/FutureTask.java:328) at java.lang.Thread.runWith(java.base@24-internal/Thread.java:1595) at java.lang.ThreadBuilders$BoundVirtualThread.run(java.base@24-internal/ThreadBuilders.java:432) "" #434010 [4088380] daemon prio=5 os_prio=0 cpu=0.09ms elapsed=0.01s tid=0x000003ff1c06d7b0 nid=4088380 in Object.wait() [0x000003fdb57fd000] java.lang.Thread.State: TIMED_WAITING (on object monitor) Thread: 0x000003ff1c06d7b0 [0x3e623c] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on at java.lang.Object.wait(java.base@24-internal/Object.java:389) at CancelTimerWithContention.lambda$test$0(CancelTimerWithContention.java:81) - locked <0x00000000ff686b60> (a java.lang.Object) at CancelTimerWithContention$$Lambda/0x000000001b001218.call(Unknown Source) at java.util.concurrent.FutureTask.run(java.base@24-internal/FutureTask.java:328) at java.lang.Thread.runWith(java.base@24-internal/Thread.java:1595) at java.lang.ThreadBuilders$BoundVirtualThread.run(java.base@24-internal/ThreadBuilders.java:432) "" #434013 [4088383] daemon prio=5 os_prio=0 cpu=0.05ms elapsed=0.00s tid=0x000003ff1c1c2ca0 nid=4088383 in Object.wait() [0x000003ff695fe000] java.lang.Thread.State: TIMED_WAITING (on object monitor) Thread: 0x000003ff1c1c2ca0 [0x3e623f] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on at java.lang.Object.wait(java.base@24-internal/Object.java:389) at CancelTimerWithContention.lambda$test$0(CancelTimerWithContention.java:81) - locked <0x00000000ff686b60> (a java.lang.Object) at CancelTimerWithContention$$Lambda/0x000000001b001218.call(Unknown Source) at java.util.concurrent.FutureTask.run(java.base@24-internal/FutureTask.java:328) at java.lang.Thread.runWith(java.base@24-internal/Thread.java:1595) at java.lang.ThreadBuilders$BoundVirtualThread.run(java.base@24-internal/ThreadBuilders.java:432) "" #434016 [4088386] daemon prio=5 os_prio=0 cpu=0.04ms elapsed=0.00s tid=0x000003ff1c0c6da0 nid=4088386 in Object.wait() [0x000003fee76fd000] java.lang.Thread.State: TIMED_WAITING (on object monitor) Thread: 0x000003ff1c0c6da0 [0x3e6242] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on at java.lang.Object.wait(java.base@24-internal/Object.java:389) at CancelTimerWithContention.lambda$test$0(CancelTimerWithContention.java:81) - locked <0x00000000ff686b50> (a java.lang.Object) at CancelTimerWithContention$$Lambda/0x000000001b001218.call(Unknown Source) at java.util.concurrent.FutureTask.run(java.base@24-internal/FutureTask.java:328) at java.lang.Thread.runWith(java.base@24-internal/Thread.java:1595) at java.lang.ThreadBuilders$BoundVirtualThread.run(java.base@24-internal/ThreadBuilders.java:432) "" #434017 [4088387] daemon prio=5 os_prio=0 cpu=0.04ms elapsed=0.00s tid=0x000003ff1c1fe8a0 nid=4088387 in Object.wait() [0x000003fee5afd000] java.lang.Thread.State: TIMED_WAITING (on object monitor) Thread: 0x000003ff1c1fe8a0 [0x3e6243] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on at java.lang.Object.wait(java.base@24-internal/Object.java:389) at CancelTimerWithContention.lambda$test$0(CancelTimerWithContention.java:81) - locked <0x00000000ff686b50> (a java.lang.Object) at CancelTimerWithContention$$Lambda/0x000000001b001218.call(Unknown Source) at java.util.concurrent.FutureTask.run(java.base@24-internal/FutureTask.java:328) at java.lang.Thread.runWith(java.base@24-internal/Thread.java:1595) at java.lang.ThreadBuilders$BoundVirtualThread.run(java.base@24-internal/ThreadBuilders.java:432) "" #434018 [4088388] daemon prio=5 os_prio=0 cpu=0.05ms elapsed=0.00s tid=0x000003ff1c14c230 nid=4088388 in Object.wait() [0x000003ff6a1fe000] java.lang.Thread.State: TIMED_WAITING (on object monitor) Thread: 0x000003ff1c14c230 [0x3e6244] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked at java.lang.Object.wait0(java.base@24-internal/Native Method) - waiting on at java.lang.Object.wait(java.base@24-internal/Object.java:389) at CancelTimerWithContention.lambda$test$0(CancelTimerWithContention.java:81) - locked <0x00000000ff686b60> (a java.lang.Object) at CancelTimerWithContention$$Lambda/0x000000001b001218.call(Unknown Source) at java.util.concurrent.FutureTask.run(java.base@24-internal/FutureTask.java:328) at java.lang.Thread.runWith(java.base@24-internal/Thread.java:1595) at java.lang.ThreadBuilders$BoundVirtualThread.run(java.base@24-internal/ThreadBuilders.java:432) "" #434019 [4088389] daemon prio=5 os_prio=0 cpu=0.05ms elapsed=0.00s tid=0x000003ff1c1cc130 nid=4088389 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Thread: 0x000003ff1c1cc130 [0x3e6245] State: _at_safepoint _at_poll_safepoint 0 JavaThread state: _thread_blocked "GC Thread#4" os_prio=0 cpu=7.84ms elapsed=307.77s tid=0x000003ff50013e00 nid=3493975 runnable "GC Thread#17" os_prio=0 cpu=9.16ms elapsed=307.77s tid=0x000003ff5001c2f0 nid=3493995 runnable "GC Thread#16" os_prio=0 cpu=8.25ms elapsed=307.77s tid=0x000003ff5001b8a0 nid=3493993 runnable "GC Thread#15" os_prio=0 cpu=4.90ms elapsed=307.77s tid=0x000003ff5001ae50 nid=3493992 runnable "GC Thread#14" os_prio=0 cpu=4.36ms elapsed=307.77s tid=0x000003ff5001a400 nid=3493991 runnable "GC Thread#13" os_prio=0 cpu=8.08ms elapsed=307.77s tid=0x000003ff500199b0 nid=3493990 runnable "GC Thread#12" os_prio=0 cpu=3.79ms elapsed=307.77s tid=0x000003ff50018f60 nid=3493989 runnable "GC Thread#11" os_prio=0 cpu=7.02ms elapsed=307.77s tid=0x000003ff50018510 nid=3493988 runnable "GC Thread#10" os_prio=0 cpu=4.52ms elapsed=307.77s tid=0x000003ff50017ac0 nid=3493987 runnable "GC Thread#9" os_prio=0 cpu=8.74ms elapsed=307.77s tid=0x000003ff500170a0 nid=3493986 runnable "GC Thread#8" os_prio=0 cpu=5.01ms elapsed=307.77s tid=0x000003ff50016680 nid=3493985 runnable "GC Thread#7" os_prio=0 cpu=5.07ms elapsed=307.77s tid=0x000003ff50015c60 nid=3493984 runnable "GC Thread#6" os_prio=0 cpu=9.30ms elapsed=307.77s tid=0x000003ff50015240 nid=3493983 runnable "GC Thread#5" os_prio=0 cpu=8.34ms elapsed=307.77s tid=0x000003ff50014820 nid=3493982 runnable "GC Thread#2" os_prio=0 cpu=6.48ms elapsed=307.78s tid=0x000003ff50012a80 nid=3493971 runnable "GC Thread#3" os_prio=0 cpu=6.61ms elapsed=307.77s tid=0x000003ff500133e0 nid=3493974 runnable "GC Thread#1" os_prio=0 cpu=2.72ms elapsed=307.79s tid=0x000003ff50012540 nid=3493953 runnable "VM Thread" os_prio=0 cpu=10.25ms elapsed=480.60s tid=0x000003ff98a124f0 nid=3199589 runnable "G1 Service" os_prio=0 cpu=10.11ms elapsed=480.66s tid=0x000003ff985c9bd0 nid=3199573 runnable "VM Periodic Task Thread" os_prio=0 cpu=96.08ms elapsed=480.63s tid=0x000003ff985f6fd0 nid=3199584 waiting on condition "G1 Refine#0" os_prio=0 cpu=0.05ms elapsed=480.66s tid=0x000003ff985c8990 nid=3199572 runnable "G1 Conc#0" os_prio=0 cpu=0.04ms elapsed=480.70s tid=0x000003ff98083540 nid=3199554 runnable "GC Thread#0" os_prio=0 cpu=10.00ms elapsed=480.71s tid=0x000003ff98078890 nid=3199546 runnable "G1 Main Marker" os_prio=0 cpu=0.06ms elapsed=480.70s tid=0x000003ff980822c0 nid=3199553 runnable JNI global refs: 23, weak refs: 0 --- Timeout information end. finished: Thu Nov 07 06:51:06 UTC 2024 elapsed time (seconds): 480.929 ----------configuration:(0/0)---------- ----------System.out:(48/2233)---------- 2024-11-07T06:43:06.693071620Z #threads = 100 2024-11-07T06:43:07.776669024Z #threads = 200 2024-11-07T06:43:08.960181995Z #threads = 300 2024-11-07T06:43:10.321313076Z #threads = 400 2024-11-07T06:43:12.652506747Z #threads = 500 2024-11-07T06:43:14.693826510Z #threads = 600 2024-11-07T06:43:16.120757356Z #threads = 700 2024-11-07T06:43:17.727076689Z #threads = 800 2024-11-07T06:43:19.937058740Z #threads = 900 2024-11-07T06:43:24.100326698Z #threads = 1000 2024-11-07T06:43:28.361217808Z #threads = 1100 2024-11-07T06:43:31.888112615Z #threads = 1200 2024-11-07T06:43:35.698119286Z #threads = 1300 2024-11-07T06:43:40.402141068Z #threads = 1400 2024-11-07T06:43:47.131802719Z #threads = 1500 2024-11-07T06:43:57.719616808Z #threads = 1600 2024-11-07T06:44:06.255336631Z #threads = 1700 2024-11-07T06:44:21.513453488Z #threads = 1800 2024-11-07T06:44:36.074108182Z #threads = 1900 2024-11-07T06:44:52.412936855Z #threads = 2000 2024-11-07T06:45:15.109802699Z #threads = 2100 2024-11-07T06:45:33.368590831Z #threads = 2200 2024-11-07T06:45:56.160194912Z #threads = 2300 2024-11-07T06:46:17.295625448Z #threads = 2400 2024-11-07T06:46:32.348082212Z #threads = 2500 2024-11-07T06:46:44.588613039Z #threads = 2600 2024-11-07T06:46:53.096456858Z #threads = 2700 2024-11-07T06:47:20.478212717Z #threads = 2800 2024-11-07T06:47:55.366989152Z #threads = 2900 2024-11-07T06:48:14.717936761Z #threads = 3000 2024-11-07T06:48:30.875477Z #threads = 3100 2024-11-07T06:48:48.754566567Z #threads = 3200 2024-11-07T06:48:55.805512751Z #threads = 3300 2024-11-07T06:49:01.568384516Z #threads = 3400 2024-11-07T06:49:10.572692911Z #threads = 3500 2024-11-07T06:49:14.798039346Z #threads = 3600 2024-11-07T06:49:18.335928462Z #threads = 3700 2024-11-07T06:49:22.988337237Z #threads = 3800 2024-11-07T06:49:26.632504462Z #threads = 3900 2024-11-07T06:49:33.404822415Z #threads = 4000 2024-11-07T06:49:50.779616483Z #threads = 4100 2024-11-07T06:50:34.956320202Z #threads = 4200 2024-11-07T06:50:53.395224405Z #threads = 4300 2024-11-07T06:50:56.206063605Z #threads = 4400 2024-11-07T06:50:58.858650415Z #threads = 4500 2024-11-07T06:51:04.066037837Z #threads = 4600 Timeout signalled after 480 seconds 2024-11-07T06:51:06.705190059Z #threads = 4700 ----------System.err:(0/0)---------- ----------rerun:(44/3581)*---------- cd /home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/scratch/5 && \\ DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus \\ DOCS_JDK_IMAGE_DIR=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/docs \\ HOME=/home/amit \\ LANG=C.UTF-8 \\ LC_ALL=C.UTF-8 \\ LC_CTYPE=C.UTF-8 \\ PATH=/bin:/usr/bin:/usr/sbin \\ TEST_IMAGE_DIR=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/test \\ XDG_DATA_DIRS=/usr/local/share:/usr/share:/var/lib/snapd/desktop \\ XDG_RUNTIME_DIR=/run/user/1000 \\ XDG_SESSION_CLASS=user \\ XDG_SESSION_ID=25 \\ XDG_SESSION_TYPE=tty \\ _JVM_DWARF_PATH=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/symbols \\ CLASSPATH=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d:/home/amit/jdk/test/jdk/java/lang/Thread/virtual:/home/amit/jtreg/lib/javatest.jar:/home/amit/jtreg/lib/jtreg.jar \\ /home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk/bin/java \\ -Dtest.vm.opts='-Xmx768m -XX:MaxRAMPercentage=0.833333 -Dtest.boot.jdk=/home/amit/boot_jdk_23 -Djava.io.tmpdir=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/tmp -ea -esa' \\ -Dtest.tool.vm.opts='-J-Xmx768m -J-XX:MaxRAMPercentage=0.833333 -J-Dtest.boot.jdk=/home/amit/boot_jdk_23 -J-Djava.io.tmpdir=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/tmp -J-ea -J-esa' \\ -Dtest.compiler.opts= \\ -Dtest.java.opts= \\ -Dtest.jdk=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk \\ -Dcompile.jdk=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk \\ -Dtest.timeout.factor=4.0 \\ -Dtest.nativepath=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/test/jdk/jtreg/native \\ -Dtest.root=/home/amit/jdk/test/jdk \\ -Dtest.name=java/lang/Thread/virtual/CancelTimerWithContention.java \\ -Dtest.verbose=Verbose[p=SUMMARY,f=FULL,e=FULL,t=false,m=false] \\ -Dtest.file=/home/amit/jdk/test/jdk/java/lang/Thread/virtual/CancelTimerWithContention.java \\ -Dtest.src=/home/amit/jdk/test/jdk/java/lang/Thread/virtual \\ -Dtest.src.path=/home/amit/jdk/test/jdk/java/lang/Thread/virtual \\ -Dtest.classes=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d \\ -Dtest.class.path=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d \\ -Dtest.class.path.prefix=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/classes/17/java/lang/Thread/virtual/CancelTimerWithContention.d:/home/amit/jdk/test/jdk/java/lang/Thread/virtual \\ -Xmx768m \\ -XX:MaxRAMPercentage=0.833333 \\ -Dtest.boot.jdk=/home/amit/boot_jdk_23 \\ -Djava.io.tmpdir=/home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/tmp \\ -ea \\ -esa \\ -Djava.library.path=/home/amit/jdk/build/linux-s390x-server-fastdebug/images/test/jdk/jtreg/native \\ -Djdk.virtualThreadScheduler.parallelism=2 \\ -Djdk.virtualThreadScheduler.timerQueues=1 \\ com.sun.javatest.regtest.agent.MainWrapper /home/amit/jdk/build/linux-s390x-server-fastdebug/test-support/jtreg_test_jdk_tier1/java/lang/Thread/virtual/CancelTimerWithContention.d/main.0.jta result: Error. Program `/home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk/bin/java' timed out (timeout set to 480000ms, elapsed time including timeout handling was 480874ms). test result: Error. Program `/home/amit/jdk/build/linux-s390x-server-fastdebug/images/jdk/bin/java' timed out (timeout set to 480000ms, elapsed time including timeout handling was 480874ms).