[2026-02-05T00:45:21.254Z] Running test renaissance-philosophers_0 ... [2026-02-05T00:45:21.254Z] =============================================== [2026-02-05T00:45:21.254Z] renaissance-philosophers_0 Start Time: Thu Feb 5 00:45:21 2026 Epoch Time (ms): 1770252321126 [2026-02-05T00:45:21.254Z] variation: NoOptions [2026-02-05T00:45:21.254Z] JVM_OPTIONS: [2026-02-05T00:45:21.254Z] { \ [2026-02-05T00:45:21.254Z] echo ""; echo "TEST SETUP:"; \ [2026-02-05T00:45:21.254Z] echo "Nothing to be done for setup."; \ [2026-02-05T00:45:21.254Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1770249833319/renaissance-philosophers_0"; \ [2026-02-05T00:45:21.254Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1770249833319/renaissance-philosophers_0"; \ [2026-02-05T00:45:21.254Z] echo ""; echo "TESTING:"; \ [2026-02-05T00:45:21.254Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1770249833319/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2026-02-05T00:45:21.254Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1770249833319/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-05T00:45:21.254Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-05T00:45:21.254Z] echo "Nothing to be done for teardown."; \ [2026-02-05T00:45:21.254Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_1770249833319/TestTargetResult"; [2026-02-05T00:45:21.254Z] [2026-02-05T00:45:21.254Z] TEST SETUP: [2026-02-05T00:45:21.254Z] Nothing to be done for setup. [2026-02-05T00:45:21.254Z] [2026-02-05T00:45:21.254Z] TESTING: [2026-02-05T00:45:22.850Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-02-05T00:45:22.850Z] GC before operation: completed in 28.407 ms, heap usage 14.051 MB -> 4.639 MB. [2026-02-05T00:45:31.224Z] Camera thread performed 122 scans. [2026-02-05T00:45:31.224Z] ====== philosophers (scala) [default], iteration 0 completed (7607.133 ms) ====== [2026-02-05T00:45:31.224Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-02-05T00:45:31.224Z] GC before operation: completed in 39.847 ms, heap usage 23.229 MB -> 4.990 MB. [2026-02-05T00:45:35.737Z] Camera thread performed 122 scans. [2026-02-05T00:45:35.737Z] ====== philosophers (scala) [default], iteration 1 completed (4495.412 ms) ====== [2026-02-05T00:45:35.737Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-02-05T00:45:35.737Z] GC before operation: completed in 25.775 ms, heap usage 39.178 MB -> 4.991 MB. [2026-02-05T00:45:37.356Z] Camera thread performed 122 scans. [2026-02-05T00:45:37.356Z] ====== philosophers (scala) [default], iteration 2 completed (2313.378 ms) ====== [2026-02-05T00:45:37.356Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-02-05T00:45:37.356Z] GC before operation: completed in 59.480 ms, heap usage 17.180 MB -> 4.990 MB. [2026-02-05T00:45:39.864Z] Camera thread performed 122 scans. [2026-02-05T00:45:39.864Z] ====== philosophers (scala) [default], iteration 3 completed (2411.018 ms) ====== [2026-02-05T00:45:39.864Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-02-05T00:45:39.864Z] GC before operation: completed in 36.221 ms, heap usage 12.319 MB -> 4.990 MB. [2026-02-05T00:45:42.357Z] Camera thread performed 122 scans. [2026-02-05T00:45:42.357Z] ====== philosophers (scala) [default], iteration 4 completed (1811.442 ms) ====== [2026-02-05T00:45:42.357Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-02-05T00:45:42.357Z] GC before operation: completed in 32.184 ms, heap usage 62.297 MB -> 4.991 MB. [2026-02-05T00:45:43.973Z] Camera thread performed 122 scans. [2026-02-05T00:45:43.973Z] ====== philosophers (scala) [default], iteration 5 completed (2016.783 ms) ====== [2026-02-05T00:45:43.973Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-02-05T00:45:43.973Z] GC before operation: completed in 31.078 ms, heap usage 15.237 MB -> 5.027 MB. [2026-02-05T00:45:46.468Z] Camera thread performed 122 scans. [2026-02-05T00:45:46.468Z] ====== philosophers (scala) [default], iteration 6 completed (2093.394 ms) ====== [2026-02-05T00:45:46.468Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-02-05T00:45:46.468Z] GC before operation: completed in 36.494 ms, heap usage 52.817 MB -> 4.991 MB. [2026-02-05T00:45:48.113Z] Camera thread performed 122 scans. [2026-02-05T00:45:48.113Z] ====== philosophers (scala) [default], iteration 7 completed (1986.249 ms) ====== [2026-02-05T00:45:48.113Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-02-05T00:45:48.113Z] GC before operation: completed in 37.909 ms, heap usage 25.090 MB -> 4.991 MB. [2026-02-05T00:45:49.726Z] Camera thread performed 122 scans. [2026-02-05T00:45:49.726Z] ====== philosophers (scala) [default], iteration 8 completed (1859.839 ms) ====== [2026-02-05T00:45:49.726Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-02-05T00:45:49.726Z] GC before operation: completed in 45.932 ms, heap usage 82.706 MB -> 5.028 MB. [2026-02-05T00:45:51.860Z] Camera thread performed 122 scans. [2026-02-05T00:45:51.860Z] ====== philosophers (scala) [default], iteration 9 completed (1840.621 ms) ====== [2026-02-05T00:45:51.860Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-02-05T00:45:51.860Z] GC before operation: completed in 33.303 ms, heap usage 8.998 MB -> 4.992 MB. [2026-02-05T00:45:53.480Z] Camera thread performed 122 scans. [2026-02-05T00:45:53.480Z] ====== philosophers (scala) [default], iteration 10 completed (1907.020 ms) ====== [2026-02-05T00:45:53.480Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-02-05T00:45:54.266Z] GC before operation: completed in 47.452 ms, heap usage 36.583 MB -> 4.993 MB. [2026-02-05T00:45:55.864Z] Camera thread performed 122 scans. [2026-02-05T00:45:55.864Z] ====== philosophers (scala) [default], iteration 11 completed (1781.506 ms) ====== [2026-02-05T00:45:55.864Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-02-05T00:45:55.864Z] GC before operation: completed in 24.407 ms, heap usage 65.543 MB -> 4.993 MB. [2026-02-05T00:45:57.509Z] Camera thread performed 122 scans. [2026-02-05T00:45:57.509Z] ====== philosophers (scala) [default], iteration 12 completed (1544.688 ms) ====== [2026-02-05T00:45:57.509Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-02-05T00:45:57.509Z] GC before operation: completed in 25.911 ms, heap usage 14.186 MB -> 4.993 MB. [2026-02-05T00:45:59.099Z] Camera thread performed 122 scans. [2026-02-05T00:45:59.099Z] ====== philosophers (scala) [default], iteration 13 completed (1430.564 ms) ====== [2026-02-05T00:45:59.099Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-02-05T00:45:59.099Z] GC before operation: completed in 26.197 ms, heap usage 6.206 MB -> 4.993 MB. [2026-02-05T00:46:00.715Z] Camera thread performed 122 scans. [2026-02-05T00:46:00.715Z] ====== philosophers (scala) [default], iteration 14 completed (1743.604 ms) ====== [2026-02-05T00:46:00.715Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-02-05T00:46:00.715Z] GC before operation: completed in 31.922 ms, heap usage 54.169 MB -> 4.993 MB. [2026-02-05T00:46:02.544Z] Camera thread performed 122 scans. [2026-02-05T00:46:02.544Z] ====== philosophers (scala) [default], iteration 15 completed (1765.419 ms) ====== [2026-02-05T00:46:02.544Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-02-05T00:46:02.544Z] GC before operation: completed in 32.657 ms, heap usage 50.202 MB -> 4.993 MB. [2026-02-05T00:46:04.155Z] Camera thread performed 122 scans. [2026-02-05T00:46:04.155Z] ====== philosophers (scala) [default], iteration 16 completed (1680.195 ms) ====== [2026-02-05T00:46:04.155Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-02-05T00:46:04.155Z] GC before operation: completed in 39.420 ms, heap usage 28.153 MB -> 4.995 MB. [2026-02-05T00:46:05.760Z] Camera thread performed 122 scans. [2026-02-05T00:46:05.760Z] ====== philosophers (scala) [default], iteration 17 completed (1653.738 ms) ====== [2026-02-05T00:46:05.760Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-02-05T00:46:05.760Z] GC before operation: completed in 34.938 ms, heap usage 48.825 MB -> 4.994 MB. [2026-02-05T00:46:07.390Z] Camera thread performed 122 scans. [2026-02-05T00:46:07.390Z] ====== philosophers (scala) [default], iteration 18 completed (1655.364 ms) ====== [2026-02-05T00:46:07.390Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-02-05T00:46:07.390Z] GC before operation: completed in 33.108 ms, heap usage 15.205 MB -> 4.994 MB. [2026-02-05T00:46:09.001Z] Camera thread performed 122 scans. [2026-02-05T00:46:09.001Z] ====== philosophers (scala) [default], iteration 19 completed (1525.658 ms) ====== [2026-02-05T00:46:09.001Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-02-05T00:46:09.001Z] GC before operation: completed in 24.269 ms, heap usage 78.905 MB -> 5.030 MB. [2026-02-05T00:46:10.615Z] Camera thread performed 122 scans. [2026-02-05T00:46:10.615Z] ====== philosophers (scala) [default], iteration 20 completed (1933.568 ms) ====== [2026-02-05T00:46:10.615Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-02-05T00:46:10.615Z] GC before operation: completed in 39.832 ms, heap usage 27.585 MB -> 4.994 MB. [2026-02-05T00:46:13.111Z] Camera thread performed 122 scans. [2026-02-05T00:46:13.111Z] ====== philosophers (scala) [default], iteration 21 completed (1863.164 ms) ====== [2026-02-05T00:46:13.111Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-02-05T00:46:13.111Z] GC before operation: completed in 31.934 ms, heap usage 51.188 MB -> 4.994 MB. [2026-02-05T00:46:14.721Z] Camera thread performed 122 scans. [2026-02-05T00:46:14.721Z] ====== philosophers (scala) [default], iteration 22 completed (1596.624 ms) ====== [2026-02-05T00:46:14.721Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-02-05T00:46:14.721Z] GC before operation: completed in 36.936 ms, heap usage 76.543 MB -> 4.994 MB. [2026-02-05T00:46:16.318Z] Camera thread performed 122 scans. [2026-02-05T00:46:16.318Z] ====== philosophers (scala) [default], iteration 23 completed (1854.782 ms) ====== [2026-02-05T00:46:16.318Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-02-05T00:46:16.318Z] GC before operation: completed in 35.276 ms, heap usage 48.876 MB -> 4.994 MB. [2026-02-05T00:46:17.934Z] Camera thread performed 122 scans. [2026-02-05T00:46:17.934Z] ====== philosophers (scala) [default], iteration 24 completed (1655.275 ms) ====== [2026-02-05T00:46:17.934Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-02-05T00:46:17.934Z] GC before operation: completed in 45.104 ms, heap usage 25.010 MB -> 4.995 MB. [2026-02-05T00:46:20.418Z] Camera thread performed 122 scans. [2026-02-05T00:46:20.418Z] ====== philosophers (scala) [default], iteration 25 completed (1924.553 ms) ====== [2026-02-05T00:46:20.418Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-02-05T00:46:20.418Z] GC before operation: completed in 41.204 ms, heap usage 80.600 MB -> 4.994 MB. [2026-02-05T00:46:22.019Z] Camera thread performed 122 scans. [2026-02-05T00:46:22.019Z] ====== philosophers (scala) [default], iteration 26 completed (1648.149 ms) ====== [2026-02-05T00:46:22.019Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-02-05T00:46:22.019Z] GC before operation: completed in 25.104 ms, heap usage 50.528 MB -> 4.994 MB. [2026-02-05T00:46:23.628Z] Camera thread performed 122 scans. [2026-02-05T00:46:23.628Z] ====== philosophers (scala) [default], iteration 27 completed (1580.703 ms) ====== [2026-02-05T00:46:23.628Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-02-05T00:46:23.628Z] GC before operation: completed in 32.686 ms, heap usage 30.795 MB -> 4.995 MB. [2026-02-05T00:46:25.235Z] Camera thread performed 122 scans. [2026-02-05T00:46:25.235Z] ====== philosophers (scala) [default], iteration 28 completed (1602.594 ms) ====== [2026-02-05T00:46:25.235Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-02-05T00:46:25.235Z] GC before operation: completed in 32.354 ms, heap usage 44.818 MB -> 4.995 MB. [2026-02-05T00:46:26.837Z] Camera thread performed 122 scans. [2026-02-05T00:46:26.837Z] ====== philosophers (scala) [default], iteration 29 completed (1614.641 ms) ====== [2026-02-05T00:46:26.837Z] ----------------------------------- [2026-02-05T00:46:26.837Z] renaissance-philosophers_0_PASSED [2026-02-05T00:46:26.837Z] ----------------------------------- [2026-02-05T00:46:26.837Z] [2026-02-05T00:46:26.837Z] TEST TEARDOWN: [2026-02-05T00:46:26.837Z] Nothing to be done for teardown. [2026-02-05T00:46:26.837Z] renaissance-philosophers_0 Finish Time: Thu Feb 5 00:46:26 2026 Epoch Time (ms): 1770252386649