[2025-10-17T18:42:00.074Z] Running test renaissance-philosophers_0 ... [2025-10-17T18:42:00.074Z] =============================================== [2025-10-17T18:42:00.074Z] renaissance-philosophers_0 Start Time: Fri Oct 17 18:41:59 2025 Epoch Time (ms): 1760726519898 [2025-10-17T18:42:00.074Z] variation: NoOptions [2025-10-17T18:42:00.074Z] JVM_OPTIONS: [2025-10-17T18:42:00.074Z] { \ [2025-10-17T18:42:00.074Z] echo ""; echo "TEST SETUP:"; \ [2025-10-17T18:42:00.074Z] echo "Nothing to be done for setup."; \ [2025-10-17T18:42:00.074Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17607249122968/renaissance-philosophers_0"; \ [2025-10-17T18:42:00.074Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17607249122968/renaissance-philosophers_0"; \ [2025-10-17T18:42:00.074Z] echo ""; echo "TESTING:"; \ [2025-10-17T18:42:00.074Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-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_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17607249122968/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-10-17T18:42:00.074Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17607249122968/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-17T18:42:00.074Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-17T18:42:00.074Z] echo "Nothing to be done for teardown."; \ [2025-10-17T18:42:00.074Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17607249122968/TestTargetResult"; [2025-10-17T18:42:00.074Z] [2025-10-17T18:42:00.074Z] TEST SETUP: [2025-10-17T18:42:00.074Z] Nothing to be done for setup. [2025-10-17T18:42:00.074Z] [2025-10-17T18:42:00.074Z] TESTING: [2025-10-17T18:42:00.825Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-17T18:42:00.825Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17607249122968/renaissance-philosophers_0/launcher-184200-15829928364339212031/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-17T18:42:00.825Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-17T18:42:00.825Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-17T18:42:00.825Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-10-17T18:42:01.584Z] GC before operation: completed in 29.888 ms, heap usage 27.129 MB -> 4.803 MB. [2025-10-17T18:42:04.005Z] Camera thread performed 122 scans. [2025-10-17T18:42:04.005Z] ====== philosophers (scala) [default], iteration 0 completed (3086.545 ms) ====== [2025-10-17T18:42:04.005Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-10-17T18:42:04.005Z] GC before operation: completed in 18.601 ms, heap usage 6.184 MB -> 5.137 MB. [2025-10-17T18:42:06.424Z] Camera thread performed 122 scans. [2025-10-17T18:42:06.424Z] ====== philosophers (scala) [default], iteration 1 completed (1987.103 ms) ====== [2025-10-17T18:42:06.424Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-10-17T18:42:06.424Z] GC before operation: completed in 15.699 ms, heap usage 71.849 MB -> 5.137 MB. [2025-10-17T18:42:07.978Z] Camera thread performed 122 scans. [2025-10-17T18:42:07.978Z] ====== philosophers (scala) [default], iteration 2 completed (1629.865 ms) ====== [2025-10-17T18:42:07.978Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-10-17T18:42:07.978Z] GC before operation: completed in 18.043 ms, heap usage 30.612 MB -> 5.138 MB. [2025-10-17T18:42:09.533Z] Camera thread performed 122 scans. [2025-10-17T18:42:09.533Z] ====== philosophers (scala) [default], iteration 3 completed (1548.087 ms) ====== [2025-10-17T18:42:09.533Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-10-17T18:42:09.533Z] GC before operation: completed in 13.553 ms, heap usage 65.332 MB -> 5.139 MB. [2025-10-17T18:42:11.088Z] Camera thread performed 122 scans. [2025-10-17T18:42:11.088Z] ====== philosophers (scala) [default], iteration 4 completed (1520.782 ms) ====== [2025-10-17T18:42:11.088Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-10-17T18:42:11.088Z] GC before operation: completed in 14.212 ms, heap usage 9.260 MB -> 5.139 MB. [2025-10-17T18:42:12.651Z] Camera thread performed 122 scans. [2025-10-17T18:42:12.651Z] ====== philosophers (scala) [default], iteration 5 completed (1478.118 ms) ====== [2025-10-17T18:42:12.651Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-10-17T18:42:12.651Z] GC before operation: completed in 15.360 ms, heap usage 49.046 MB -> 5.139 MB. [2025-10-17T18:42:14.207Z] Camera thread performed 122 scans. [2025-10-17T18:42:14.207Z] ====== philosophers (scala) [default], iteration 6 completed (1406.151 ms) ====== [2025-10-17T18:42:14.207Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-10-17T18:42:14.207Z] GC before operation: completed in 14.610 ms, heap usage 33.346 MB -> 5.139 MB. [2025-10-17T18:42:15.760Z] Camera thread performed 122 scans. [2025-10-17T18:42:15.760Z] ====== philosophers (scala) [default], iteration 7 completed (1526.151 ms) ====== [2025-10-17T18:42:15.760Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-10-17T18:42:15.760Z] GC before operation: completed in 15.818 ms, heap usage 24.856 MB -> 5.139 MB. [2025-10-17T18:42:17.314Z] Camera thread performed 122 scans. [2025-10-17T18:42:17.314Z] ====== philosophers (scala) [default], iteration 8 completed (1465.523 ms) ====== [2025-10-17T18:42:17.314Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-10-17T18:42:17.314Z] GC before operation: completed in 13.746 ms, heap usage 32.354 MB -> 5.140 MB. [2025-10-17T18:42:18.065Z] Camera thread performed 122 scans. [2025-10-17T18:42:18.065Z] ====== philosophers (scala) [default], iteration 9 completed (1410.708 ms) ====== [2025-10-17T18:42:18.065Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-10-17T18:42:18.818Z] GC before operation: completed in 16.263 ms, heap usage 67.365 MB -> 5.140 MB. [2025-10-17T18:42:19.571Z] Camera thread performed 122 scans. [2025-10-17T18:42:19.571Z] ====== philosophers (scala) [default], iteration 10 completed (1410.967 ms) ====== [2025-10-17T18:42:19.571Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-10-17T18:42:19.571Z] GC before operation: completed in 13.768 ms, heap usage 40.014 MB -> 5.140 MB. [2025-10-17T18:42:21.129Z] Camera thread performed 122 scans. [2025-10-17T18:42:21.129Z] ====== philosophers (scala) [default], iteration 11 completed (1564.311 ms) ====== [2025-10-17T18:42:21.129Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-10-17T18:42:21.129Z] GC before operation: completed in 13.364 ms, heap usage 56.006 MB -> 5.141 MB. [2025-10-17T18:42:22.692Z] Camera thread performed 122 scans. [2025-10-17T18:42:22.692Z] ====== philosophers (scala) [default], iteration 12 completed (1402.015 ms) ====== [2025-10-17T18:42:22.692Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-10-17T18:42:22.692Z] GC before operation: completed in 13.028 ms, heap usage 7.103 MB -> 5.141 MB. [2025-10-17T18:42:24.247Z] Camera thread performed 122 scans. [2025-10-17T18:42:24.247Z] ====== philosophers (scala) [default], iteration 13 completed (1569.057 ms) ====== [2025-10-17T18:42:24.247Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-10-17T18:42:24.247Z] GC before operation: completed in 15.693 ms, heap usage 82.707 MB -> 5.141 MB. [2025-10-17T18:42:25.805Z] Camera thread performed 122 scans. [2025-10-17T18:42:25.805Z] ====== philosophers (scala) [default], iteration 14 completed (1432.651 ms) ====== [2025-10-17T18:42:25.805Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-10-17T18:42:25.805Z] GC before operation: completed in 13.028 ms, heap usage 15.924 MB -> 5.141 MB. [2025-10-17T18:42:27.358Z] Camera thread performed 122 scans. [2025-10-17T18:42:27.358Z] ====== philosophers (scala) [default], iteration 15 completed (1425.691 ms) ====== [2025-10-17T18:42:27.358Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-10-17T18:42:27.358Z] GC before operation: completed in 15.299 ms, heap usage 28.837 MB -> 5.141 MB. [2025-10-17T18:42:28.914Z] Camera thread performed 122 scans. [2025-10-17T18:42:28.914Z] ====== philosophers (scala) [default], iteration 16 completed (1609.133 ms) ====== [2025-10-17T18:42:28.914Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-10-17T18:42:28.914Z] GC before operation: completed in 12.441 ms, heap usage 11.339 MB -> 5.141 MB. [2025-10-17T18:42:30.470Z] Camera thread performed 122 scans. [2025-10-17T18:42:30.470Z] ====== philosophers (scala) [default], iteration 17 completed (1491.397 ms) ====== [2025-10-17T18:42:30.470Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-10-17T18:42:30.470Z] GC before operation: completed in 13.222 ms, heap usage 23.796 MB -> 5.141 MB. [2025-10-17T18:42:32.031Z] Camera thread performed 122 scans. [2025-10-17T18:42:32.031Z] ====== philosophers (scala) [default], iteration 18 completed (1334.474 ms) ====== [2025-10-17T18:42:32.031Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-10-17T18:42:32.031Z] GC before operation: completed in 12.866 ms, heap usage 17.717 MB -> 5.142 MB. [2025-10-17T18:42:33.182Z] Camera thread performed 122 scans. [2025-10-17T18:42:33.182Z] ====== philosophers (scala) [default], iteration 19 completed (1532.976 ms) ====== [2025-10-17T18:42:33.183Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-10-17T18:42:33.183Z] GC before operation: completed in 14.389 ms, heap usage 45.777 MB -> 5.142 MB. [2025-10-17T18:42:34.737Z] Camera thread performed 122 scans. [2025-10-17T18:42:34.737Z] ====== philosophers (scala) [default], iteration 20 completed (1638.358 ms) ====== [2025-10-17T18:42:34.737Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-10-17T18:42:34.737Z] GC before operation: completed in 14.543 ms, heap usage 32.773 MB -> 5.142 MB. [2025-10-17T18:42:36.296Z] Camera thread performed 122 scans. [2025-10-17T18:42:36.296Z] ====== philosophers (scala) [default], iteration 21 completed (1594.459 ms) ====== [2025-10-17T18:42:36.296Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-10-17T18:42:36.296Z] GC before operation: completed in 16.385 ms, heap usage 41.722 MB -> 5.142 MB. [2025-10-17T18:42:37.850Z] Camera thread performed 122 scans. [2025-10-17T18:42:37.850Z] ====== philosophers (scala) [default], iteration 22 completed (1509.151 ms) ====== [2025-10-17T18:42:37.850Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-10-17T18:42:37.850Z] GC before operation: completed in 14.872 ms, heap usage 24.327 MB -> 5.142 MB. [2025-10-17T18:42:39.406Z] Camera thread performed 122 scans. [2025-10-17T18:42:39.406Z] ====== philosophers (scala) [default], iteration 23 completed (1410.292 ms) ====== [2025-10-17T18:42:39.406Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-10-17T18:42:39.406Z] GC before operation: completed in 15.916 ms, heap usage 71.721 MB -> 5.142 MB. [2025-10-17T18:42:40.961Z] Camera thread performed 122 scans. [2025-10-17T18:42:40.961Z] ====== philosophers (scala) [default], iteration 24 completed (1464.775 ms) ====== [2025-10-17T18:42:40.961Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-10-17T18:42:40.961Z] GC before operation: completed in 16.499 ms, heap usage 19.363 MB -> 5.142 MB. [2025-10-17T18:42:42.520Z] Camera thread performed 122 scans. [2025-10-17T18:42:42.520Z] ====== philosophers (scala) [default], iteration 25 completed (1445.183 ms) ====== [2025-10-17T18:42:42.520Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-10-17T18:42:42.520Z] GC before operation: completed in 15.177 ms, heap usage 17.099 MB -> 5.142 MB. [2025-10-17T18:42:44.076Z] Camera thread performed 122 scans. [2025-10-17T18:42:44.076Z] ====== philosophers (scala) [default], iteration 26 completed (1554.543 ms) ====== [2025-10-17T18:42:44.076Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-10-17T18:42:44.076Z] GC before operation: completed in 17.327 ms, heap usage 24.703 MB -> 5.142 MB. [2025-10-17T18:42:45.643Z] Camera thread performed 122 scans. [2025-10-17T18:42:45.643Z] ====== philosophers (scala) [default], iteration 27 completed (1480.414 ms) ====== [2025-10-17T18:42:45.643Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-10-17T18:42:45.643Z] GC before operation: completed in 16.660 ms, heap usage 65.665 MB -> 5.142 MB. [2025-10-17T18:42:47.208Z] Camera thread performed 122 scans. [2025-10-17T18:42:47.208Z] ====== philosophers (scala) [default], iteration 28 completed (1534.913 ms) ====== [2025-10-17T18:42:47.208Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-10-17T18:42:47.208Z] GC before operation: completed in 14.097 ms, heap usage 8.728 MB -> 5.142 MB. [2025-10-17T18:42:48.764Z] Camera thread performed 122 scans. [2025-10-17T18:42:48.764Z] ====== philosophers (scala) [default], iteration 29 completed (1431.526 ms) ====== [2025-10-17T18:42:48.765Z] ----------------------------------- [2025-10-17T18:42:48.765Z] renaissance-philosophers_0_PASSED [2025-10-17T18:42:48.765Z] ----------------------------------- [2025-10-17T18:42:48.765Z] [2025-10-17T18:42:48.765Z] TEST TEARDOWN: [2025-10-17T18:42:48.765Z] Nothing to be done for teardown. [2025-10-17T18:42:48.765Z] renaissance-philosophers_0 Finish Time: Fri Oct 17 18:42:48 2025 Epoch Time (ms): 1760726568383