[2025-10-02T22:56:48.585Z] Running test renaissance-philosophers_0 ... [2025-10-02T22:56:48.585Z] =============================================== [2025-10-02T22:56:48.585Z] renaissance-philosophers_0 Start Time: Thu Oct 2 22:56:47 2025 Epoch Time (ms): 1759445807570 [2025-10-02T22:56:48.585Z] variation: NoOptions [2025-10-02T22:56:48.585Z] JVM_OPTIONS: [2025-10-02T22:56:48.585Z] { \ [2025-10-02T22:56:48.585Z] echo ""; echo "TEST SETUP:"; \ [2025-10-02T22:56:48.585Z] echo "Nothing to be done for setup."; \ [2025-10-02T22:56:48.585Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17594453722508/renaissance-philosophers_0"; \ [2025-10-02T22:56:48.585Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17594453722508/renaissance-philosophers_0"; \ [2025-10-02T22:56:48.585Z] echo ""; echo "TESTING:"; \ [2025-10-02T22:56:48.585Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/jdkbinary/j2sdk-image/bin/java" --add-opens java.base/java.lang=ALL-UNNAMED --add-opens java.base/java.util=ALL-UNNAMED --add-opens java.base/java.util.concurrent=ALL-UNNAMED --add-opens java.base/java.nio=ALL-UNNAMED --add-opens java.base/sun.nio.ch=ALL-UNNAMED --add-opens java.base/java.lang.invoke=ALL-UNNAMED -jar "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17594453722508/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-10-02T22:56:48.585Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17594453722508/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-02T22:56:48.585Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-02T22:56:48.585Z] echo "Nothing to be done for teardown."; \ [2025-10-02T22:56:48.585Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17594453722508/TestTargetResult"; [2025-10-02T22:56:48.585Z] [2025-10-02T22:56:48.585Z] TEST SETUP: [2025-10-02T22:56:48.585Z] Nothing to be done for setup. [2025-10-02T22:56:48.585Z] [2025-10-02T22:56:48.585Z] TESTING: [2025-10-02T22:56:48.585Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-02T22:56:48.585Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17594453722508/renaissance-philosophers_0/launcher-225647-2521916408718427253/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-02T22:56:48.585Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-02T22:56:48.585Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-02T22:56:48.585Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-10-02T22:56:48.585Z] GC before operation: completed in 18.407 ms, heap usage 12.309 MB -> 4.850 MB. [2025-10-02T22:56:53.941Z] Camera thread performed 122 scans. [2025-10-02T22:56:53.941Z] ====== philosophers (scala) [default], iteration 0 completed (4576.734 ms) ====== [2025-10-02T22:56:53.941Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-10-02T22:56:53.941Z] GC before operation: completed in 18.741 ms, heap usage 18.957 MB -> 5.186 MB. [2025-10-02T22:56:58.163Z] Camera thread performed 122 scans. [2025-10-02T22:56:58.163Z] ====== philosophers (scala) [default], iteration 1 completed (4555.870 ms) ====== [2025-10-02T22:56:58.163Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-10-02T22:56:58.163Z] GC before operation: completed in 11.781 ms, heap usage 53.901 MB -> 5.186 MB. [2025-10-02T22:57:02.299Z] Camera thread performed 122 scans. [2025-10-02T22:57:02.299Z] ====== philosophers (scala) [default], iteration 2 completed (4339.295 ms) ====== [2025-10-02T22:57:02.299Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-10-02T22:57:02.299Z] GC before operation: completed in 12.850 ms, heap usage 18.226 MB -> 5.188 MB. [2025-10-02T22:57:07.644Z] Camera thread performed 122 scans. [2025-10-02T22:57:07.644Z] ====== philosophers (scala) [default], iteration 3 completed (4651.006 ms) ====== [2025-10-02T22:57:07.644Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-10-02T22:57:07.644Z] GC before operation: completed in 11.629 ms, heap usage 56.389 MB -> 5.188 MB. [2025-10-02T22:57:11.782Z] Camera thread performed 122 scans. [2025-10-02T22:57:11.782Z] ====== philosophers (scala) [default], iteration 4 completed (4849.821 ms) ====== [2025-10-02T22:57:11.782Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-10-02T22:57:11.782Z] GC before operation: completed in 10.701 ms, heap usage 8.918 MB -> 5.188 MB. [2025-10-02T22:57:17.127Z] Camera thread performed 122 scans. [2025-10-02T22:57:17.127Z] ====== philosophers (scala) [default], iteration 5 completed (5160.528 ms) ====== [2025-10-02T22:57:17.127Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-10-02T22:57:17.127Z] GC before operation: completed in 12.506 ms, heap usage 41.263 MB -> 5.188 MB. [2025-10-02T22:57:21.302Z] Camera thread performed 122 scans. [2025-10-02T22:57:21.302Z] ====== philosophers (scala) [default], iteration 6 completed (4425.962 ms) ====== [2025-10-02T22:57:21.302Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-10-02T22:57:21.302Z] GC before operation: completed in 11.551 ms, heap usage 35.253 MB -> 5.189 MB. [2025-10-02T22:57:25.436Z] Camera thread performed 122 scans. [2025-10-02T22:57:25.436Z] ====== philosophers (scala) [default], iteration 7 completed (4124.971 ms) ====== [2025-10-02T22:57:25.436Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-10-02T22:57:25.436Z] GC before operation: completed in 14.248 ms, heap usage 35.749 MB -> 5.189 MB. [2025-10-02T22:57:30.779Z] Camera thread performed 122 scans. [2025-10-02T22:57:30.779Z] ====== philosophers (scala) [default], iteration 8 completed (4909.220 ms) ====== [2025-10-02T22:57:30.779Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-10-02T22:57:30.779Z] GC before operation: completed in 13.082 ms, heap usage 27.407 MB -> 5.189 MB. [2025-10-02T22:57:36.125Z] Camera thread performed 122 scans. [2025-10-02T22:57:36.125Z] ====== philosophers (scala) [default], iteration 9 completed (5611.517 ms) ====== [2025-10-02T22:57:36.125Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-10-02T22:57:36.125Z] GC before operation: completed in 10.714 ms, heap usage 14.687 MB -> 5.189 MB. [2025-10-02T22:57:41.122Z] Camera thread performed 122 scans. [2025-10-02T22:57:41.122Z] ====== philosophers (scala) [default], iteration 10 completed (4863.647 ms) ====== [2025-10-02T22:57:41.122Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-10-02T22:57:41.122Z] GC before operation: completed in 10.185 ms, heap usage 7.723 MB -> 5.189 MB. [2025-10-02T22:57:46.467Z] Camera thread performed 122 scans. [2025-10-02T22:57:46.467Z] ====== philosophers (scala) [default], iteration 11 completed (4744.215 ms) ====== [2025-10-02T22:57:46.467Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-10-02T22:57:46.467Z] GC before operation: completed in 14.687 ms, heap usage 54.670 MB -> 5.190 MB. [2025-10-02T22:57:50.603Z] Camera thread performed 122 scans. [2025-10-02T22:57:50.603Z] ====== philosophers (scala) [default], iteration 12 completed (5104.687 ms) ====== [2025-10-02T22:57:50.603Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-10-02T22:57:51.552Z] GC before operation: completed in 11.604 ms, heap usage 32.437 MB -> 5.190 MB. [2025-10-02T22:57:55.802Z] Camera thread performed 122 scans. [2025-10-02T22:57:55.802Z] ====== philosophers (scala) [default], iteration 13 completed (4760.232 ms) ====== [2025-10-02T22:57:55.802Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-10-02T22:57:55.802Z] GC before operation: completed in 13.496 ms, heap usage 15.678 MB -> 5.190 MB. [2025-10-02T22:58:01.201Z] Camera thread performed 122 scans. [2025-10-02T22:58:01.201Z] ====== philosophers (scala) [default], iteration 14 completed (5059.190 ms) ====== [2025-10-02T22:58:01.201Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-10-02T22:58:01.201Z] GC before operation: completed in 14.598 ms, heap usage 60.955 MB -> 5.190 MB. [2025-10-02T22:58:06.682Z] Camera thread performed 122 scans. [2025-10-02T22:58:06.682Z] ====== philosophers (scala) [default], iteration 15 completed (5148.034 ms) ====== [2025-10-02T22:58:06.682Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-10-02T22:58:06.682Z] GC before operation: completed in 10.954 ms, heap usage 10.415 MB -> 5.190 MB. [2025-10-02T22:58:10.824Z] Camera thread performed 122 scans. [2025-10-02T22:58:10.825Z] ====== philosophers (scala) [default], iteration 16 completed (4952.887 ms) ====== [2025-10-02T22:58:10.825Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-10-02T22:58:10.825Z] GC before operation: completed in 13.428 ms, heap usage 14.211 MB -> 5.190 MB. [2025-10-02T22:58:16.172Z] Camera thread performed 122 scans. [2025-10-02T22:58:16.172Z] ====== philosophers (scala) [default], iteration 17 completed (4795.944 ms) ====== [2025-10-02T22:58:16.172Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-10-02T22:58:16.172Z] GC before operation: completed in 15.802 ms, heap usage 24.406 MB -> 5.190 MB. [2025-10-02T22:58:20.919Z] Camera thread performed 122 scans. [2025-10-02T22:58:20.919Z] ====== philosophers (scala) [default], iteration 18 completed (4779.842 ms) ====== [2025-10-02T22:58:20.919Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-10-02T22:58:20.919Z] GC before operation: completed in 13.590 ms, heap usage 13.117 MB -> 5.190 MB. [2025-10-02T22:58:25.063Z] Camera thread performed 122 scans. [2025-10-02T22:58:25.063Z] ====== philosophers (scala) [default], iteration 19 completed (4288.454 ms) ====== [2025-10-02T22:58:25.063Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-10-02T22:58:25.063Z] GC before operation: completed in 15.037 ms, heap usage 40.290 MB -> 5.190 MB. [2025-10-02T22:58:30.430Z] Camera thread performed 122 scans. [2025-10-02T22:58:30.431Z] ====== philosophers (scala) [default], iteration 20 completed (4747.708 ms) ====== [2025-10-02T22:58:30.431Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-10-02T22:58:30.431Z] GC before operation: completed in 12.128 ms, heap usage 28.715 MB -> 5.191 MB. [2025-10-02T22:58:33.432Z] Camera thread performed 122 scans. [2025-10-02T22:58:33.432Z] ====== philosophers (scala) [default], iteration 21 completed (3866.959 ms) ====== [2025-10-02T22:58:33.432Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-10-02T22:58:33.432Z] GC before operation: completed in 12.131 ms, heap usage 6.393 MB -> 5.191 MB. [2025-10-02T22:58:37.562Z] Camera thread performed 122 scans. [2025-10-02T22:58:37.562Z] ====== philosophers (scala) [default], iteration 22 completed (4417.682 ms) ====== [2025-10-02T22:58:37.562Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-10-02T22:58:38.602Z] GC before operation: completed in 16.526 ms, heap usage 49.777 MB -> 5.191 MB. [2025-10-02T22:58:42.884Z] Camera thread performed 122 scans. [2025-10-02T22:58:42.884Z] ====== philosophers (scala) [default], iteration 23 completed (4429.407 ms) ====== [2025-10-02T22:58:42.884Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-10-02T22:58:42.884Z] GC before operation: completed in 13.163 ms, heap usage 29.414 MB -> 5.191 MB. [2025-10-02T22:58:47.016Z] Camera thread performed 122 scans. [2025-10-02T22:58:47.016Z] ====== philosophers (scala) [default], iteration 24 completed (4230.481 ms) ====== [2025-10-02T22:58:47.016Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-10-02T22:58:47.016Z] GC before operation: completed in 12.552 ms, heap usage 16.402 MB -> 5.226 MB. [2025-10-02T22:58:51.151Z] Camera thread performed 122 scans. [2025-10-02T22:58:51.151Z] ====== philosophers (scala) [default], iteration 25 completed (4398.080 ms) ====== [2025-10-02T22:58:51.151Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-10-02T22:58:51.151Z] GC before operation: completed in 11.643 ms, heap usage 58.979 MB -> 5.191 MB. [2025-10-02T22:58:55.454Z] Camera thread performed 122 scans. [2025-10-02T22:58:55.454Z] ====== philosophers (scala) [default], iteration 26 completed (4495.817 ms) ====== [2025-10-02T22:58:55.454Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-10-02T22:58:55.454Z] GC before operation: completed in 11.020 ms, heap usage 18.070 MB -> 5.191 MB. [2025-10-02T22:58:59.733Z] Camera thread performed 122 scans. [2025-10-02T22:58:59.733Z] ====== philosophers (scala) [default], iteration 27 completed (4093.589 ms) ====== [2025-10-02T22:58:59.733Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-10-02T22:58:59.733Z] GC before operation: completed in 10.777 ms, heap usage 41.765 MB -> 5.191 MB. [2025-10-02T22:59:03.863Z] Camera thread performed 122 scans. [2025-10-02T22:59:03.863Z] ====== philosophers (scala) [default], iteration 28 completed (4317.825 ms) ====== [2025-10-02T22:59:03.863Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-10-02T22:59:03.863Z] GC before operation: completed in 11.253 ms, heap usage 82.178 MB -> 5.191 MB. [2025-10-02T22:59:09.558Z] Camera thread performed 122 scans. [2025-10-02T22:59:09.558Z] ====== philosophers (scala) [default], iteration 29 completed (4526.596 ms) ====== [2025-10-02T22:59:09.558Z] ----------------------------------- [2025-10-02T22:59:09.558Z] renaissance-philosophers_0_PASSED [2025-10-02T22:59:09.558Z] ----------------------------------- [2025-10-02T22:59:09.558Z] [2025-10-02T22:59:09.558Z] TEST TEARDOWN: [2025-10-02T22:59:09.558Z] Nothing to be done for teardown. [2025-10-02T22:59:09.558Z] renaissance-philosophers_0 Finish Time: Thu Oct 2 22:59:08 2025 Epoch Time (ms): 1759445948301