[2025-10-09T22:04:32.891Z] Running test renaissance-philosophers_0 ... [2025-10-09T22:04:32.891Z] =============================================== [2025-10-09T22:04:32.891Z] renaissance-philosophers_0 Start Time: Thu Oct 9 22:04:32 2025 Epoch Time (ms): 1760047472761 [2025-10-09T22:04:32.891Z] variation: NoOptions [2025-10-09T22:04:32.891Z] JVM_OPTIONS: [2025-10-09T22:04:32.891Z] { \ [2025-10-09T22:04:32.891Z] echo ""; echo "TEST SETUP:"; \ [2025-10-09T22:04:32.891Z] echo "Nothing to be done for setup."; \ [2025-10-09T22:04:32.891Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17600468427491/renaissance-philosophers_0"; \ [2025-10-09T22:04:32.891Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17600468427491/renaissance-philosophers_0"; \ [2025-10-09T22:04:32.891Z] echo ""; echo "TESTING:"; \ [2025-10-09T22:04:32.891Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17600468427491/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-10-09T22:04:32.891Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17600468427491/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-09T22:04:32.891Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-09T22:04:32.891Z] echo "Nothing to be done for teardown."; \ [2025-10-09T22:04:32.891Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17600468427491/TestTargetResult"; [2025-10-09T22:04:32.891Z] [2025-10-09T22:04:32.891Z] TEST SETUP: [2025-10-09T22:04:32.891Z] Nothing to be done for setup. [2025-10-09T22:04:32.891Z] [2025-10-09T22:04:32.891Z] TESTING: [2025-10-09T22:04:33.693Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-09T22:04:33.693Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17600468427491/renaissance-philosophers_0/launcher-220432-12847994385567690403/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-09T22:04:33.693Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-09T22:04:33.693Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-09T22:04:34.070Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-10-09T22:04:34.070Z] GC before operation: completed in 17.263 ms, heap usage 12.321 MB -> 4.835 MB. [2025-10-09T22:04:37.482Z] Camera thread performed 122 scans. [2025-10-09T22:04:37.482Z] ====== philosophers (scala) [default], iteration 0 completed (3305.150 ms) ====== [2025-10-09T22:04:37.482Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-10-09T22:04:37.482Z] GC before operation: completed in 20.346 ms, heap usage 22.927 MB -> 5.175 MB. [2025-10-09T22:04:39.549Z] Camera thread performed 122 scans. [2025-10-09T22:04:39.549Z] ====== philosophers (scala) [default], iteration 1 completed (1763.696 ms) ====== [2025-10-09T22:04:39.549Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-10-09T22:04:39.549Z] GC before operation: completed in 12.659 ms, heap usage 23.895 MB -> 5.176 MB. [2025-10-09T22:04:40.899Z] Camera thread performed 122 scans. [2025-10-09T22:04:40.899Z] ====== philosophers (scala) [default], iteration 2 completed (1673.914 ms) ====== [2025-10-09T22:04:40.899Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-10-09T22:04:40.899Z] GC before operation: completed in 11.689 ms, heap usage 168.355 MB -> 5.177 MB. [2025-10-09T22:04:42.834Z] Camera thread performed 122 scans. [2025-10-09T22:04:42.834Z] ====== philosophers (scala) [default], iteration 3 completed (1646.804 ms) ====== [2025-10-09T22:04:42.834Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-10-09T22:04:42.834Z] GC before operation: completed in 11.892 ms, heap usage 27.270 MB -> 5.177 MB. [2025-10-09T22:04:44.726Z] Camera thread performed 122 scans. [2025-10-09T22:04:44.726Z] ====== philosophers (scala) [default], iteration 4 completed (1703.996 ms) ====== [2025-10-09T22:04:44.726Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-10-09T22:04:44.726Z] GC before operation: completed in 11.578 ms, heap usage 136.674 MB -> 5.177 MB. [2025-10-09T22:04:46.054Z] Camera thread performed 122 scans. [2025-10-09T22:04:46.054Z] ====== philosophers (scala) [default], iteration 5 completed (1692.817 ms) ====== [2025-10-09T22:04:46.054Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-10-09T22:04:46.054Z] GC before operation: completed in 12.388 ms, heap usage 105.968 MB -> 5.177 MB. [2025-10-09T22:04:47.942Z] Camera thread performed 122 scans. [2025-10-09T22:04:47.942Z] ====== philosophers (scala) [default], iteration 6 completed (1727.169 ms) ====== [2025-10-09T22:04:47.942Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-10-09T22:04:47.942Z] GC before operation: completed in 13.608 ms, heap usage 43.793 MB -> 5.178 MB. [2025-10-09T22:04:49.807Z] Camera thread performed 122 scans. [2025-10-09T22:04:49.807Z] ====== philosophers (scala) [default], iteration 7 completed (1681.636 ms) ====== [2025-10-09T22:04:49.807Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-10-09T22:04:49.807Z] GC before operation: completed in 12.133 ms, heap usage 96.865 MB -> 5.179 MB. [2025-10-09T22:04:51.121Z] Camera thread performed 122 scans. [2025-10-09T22:04:51.121Z] ====== philosophers (scala) [default], iteration 8 completed (1692.359 ms) ====== [2025-10-09T22:04:51.121Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-10-09T22:04:51.121Z] GC before operation: completed in 11.783 ms, heap usage 65.885 MB -> 5.179 MB. [2025-10-09T22:04:53.037Z] Camera thread performed 122 scans. [2025-10-09T22:04:53.037Z] ====== philosophers (scala) [default], iteration 9 completed (1683.838 ms) ====== [2025-10-09T22:04:53.037Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-10-09T22:04:53.037Z] GC before operation: completed in 12.065 ms, heap usage 75.273 MB -> 5.179 MB. [2025-10-09T22:04:54.926Z] Camera thread performed 122 scans. [2025-10-09T22:04:54.926Z] ====== philosophers (scala) [default], iteration 10 completed (1651.770 ms) ====== [2025-10-09T22:04:54.926Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-10-09T22:04:54.926Z] GC before operation: completed in 11.340 ms, heap usage 102.345 MB -> 5.179 MB. [2025-10-09T22:04:56.285Z] Camera thread performed 122 scans. [2025-10-09T22:04:56.285Z] ====== philosophers (scala) [default], iteration 11 completed (1671.482 ms) ====== [2025-10-09T22:04:56.285Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-10-09T22:04:56.285Z] GC before operation: completed in 10.978 ms, heap usage 7.890 MB -> 5.180 MB. [2025-10-09T22:04:58.192Z] Camera thread performed 122 scans. [2025-10-09T22:04:58.193Z] ====== philosophers (scala) [default], iteration 12 completed (1711.404 ms) ====== [2025-10-09T22:04:58.193Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-10-09T22:04:58.193Z] GC before operation: completed in 12.433 ms, heap usage 62.346 MB -> 5.180 MB. [2025-10-09T22:04:59.543Z] Camera thread performed 122 scans. [2025-10-09T22:04:59.543Z] ====== philosophers (scala) [default], iteration 13 completed (1675.341 ms) ====== [2025-10-09T22:04:59.543Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-10-09T22:04:59.543Z] GC before operation: completed in 13.513 ms, heap usage 139.873 MB -> 5.180 MB. [2025-10-09T22:05:01.445Z] Camera thread performed 122 scans. [2025-10-09T22:05:01.445Z] ====== philosophers (scala) [default], iteration 14 completed (1687.780 ms) ====== [2025-10-09T22:05:01.445Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-10-09T22:05:01.445Z] GC before operation: completed in 12.461 ms, heap usage 58.225 MB -> 5.180 MB. [2025-10-09T22:05:02.925Z] Camera thread performed 122 scans. [2025-10-09T22:05:02.925Z] ====== philosophers (scala) [default], iteration 15 completed (1696.197 ms) ====== [2025-10-09T22:05:02.925Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-10-09T22:05:02.925Z] GC before operation: completed in 12.263 ms, heap usage 15.841 MB -> 5.180 MB. [2025-10-09T22:05:04.859Z] Camera thread performed 122 scans. [2025-10-09T22:05:04.860Z] ====== philosophers (scala) [default], iteration 16 completed (1670.969 ms) ====== [2025-10-09T22:05:04.860Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-10-09T22:05:04.860Z] GC before operation: completed in 11.716 ms, heap usage 167.343 MB -> 5.180 MB. [2025-10-09T22:05:06.771Z] Camera thread performed 122 scans. [2025-10-09T22:05:06.771Z] ====== philosophers (scala) [default], iteration 17 completed (1686.185 ms) ====== [2025-10-09T22:05:06.771Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-10-09T22:05:06.771Z] GC before operation: completed in 11.556 ms, heap usage 93.381 MB -> 5.180 MB. [2025-10-09T22:05:08.116Z] Camera thread performed 122 scans. [2025-10-09T22:05:08.116Z] ====== philosophers (scala) [default], iteration 18 completed (1680.875 ms) ====== [2025-10-09T22:05:08.116Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-10-09T22:05:08.116Z] GC before operation: completed in 12.057 ms, heap usage 82.834 MB -> 5.180 MB. [2025-10-09T22:05:10.004Z] Camera thread performed 122 scans. [2025-10-09T22:05:10.004Z] ====== philosophers (scala) [default], iteration 19 completed (1696.017 ms) ====== [2025-10-09T22:05:10.004Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-10-09T22:05:10.004Z] GC before operation: completed in 12.203 ms, heap usage 146.374 MB -> 5.180 MB. [2025-10-09T22:05:11.345Z] Camera thread performed 122 scans. [2025-10-09T22:05:11.345Z] ====== philosophers (scala) [default], iteration 20 completed (1660.427 ms) ====== [2025-10-09T22:05:11.345Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-10-09T22:05:11.744Z] GC before operation: completed in 12.308 ms, heap usage 137.853 MB -> 5.181 MB. [2025-10-09T22:05:13.064Z] Camera thread performed 122 scans. [2025-10-09T22:05:13.064Z] ====== philosophers (scala) [default], iteration 21 completed (1660.417 ms) ====== [2025-10-09T22:05:13.064Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-10-09T22:05:13.064Z] GC before operation: completed in 11.937 ms, heap usage 28.786 MB -> 5.180 MB. [2025-10-09T22:05:15.016Z] Camera thread performed 122 scans. [2025-10-09T22:05:15.016Z] ====== philosophers (scala) [default], iteration 22 completed (1664.910 ms) ====== [2025-10-09T22:05:15.016Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-10-09T22:05:15.016Z] GC before operation: completed in 12.521 ms, heap usage 111.374 MB -> 5.180 MB. [2025-10-09T22:05:16.907Z] Camera thread performed 122 scans. [2025-10-09T22:05:16.907Z] ====== philosophers (scala) [default], iteration 23 completed (1671.322 ms) ====== [2025-10-09T22:05:16.907Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-10-09T22:05:16.907Z] GC before operation: completed in 10.823 ms, heap usage 23.354 MB -> 5.180 MB. [2025-10-09T22:05:18.301Z] Camera thread performed 122 scans. [2025-10-09T22:05:18.301Z] ====== philosophers (scala) [default], iteration 24 completed (1672.643 ms) ====== [2025-10-09T22:05:18.301Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-10-09T22:05:18.301Z] GC before operation: completed in 11.168 ms, heap usage 58.850 MB -> 5.181 MB. [2025-10-09T22:05:20.265Z] Camera thread performed 122 scans. [2025-10-09T22:05:20.265Z] ====== philosophers (scala) [default], iteration 25 completed (1669.596 ms) ====== [2025-10-09T22:05:20.265Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-10-09T22:05:20.265Z] GC before operation: completed in 12.304 ms, heap usage 150.337 MB -> 5.181 MB. [2025-10-09T22:05:21.631Z] Camera thread performed 122 scans. [2025-10-09T22:05:21.631Z] ====== philosophers (scala) [default], iteration 26 completed (1663.431 ms) ====== [2025-10-09T22:05:21.631Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-10-09T22:05:21.631Z] GC before operation: completed in 12.227 ms, heap usage 99.884 MB -> 5.181 MB. [2025-10-09T22:05:23.583Z] Camera thread performed 122 scans. [2025-10-09T22:05:23.583Z] ====== philosophers (scala) [default], iteration 27 completed (1709.354 ms) ====== [2025-10-09T22:05:23.583Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-10-09T22:05:23.583Z] GC before operation: completed in 11.642 ms, heap usage 7.796 MB -> 5.181 MB. [2025-10-09T22:05:25.031Z] Camera thread performed 122 scans. [2025-10-09T22:05:25.031Z] ====== philosophers (scala) [default], iteration 28 completed (1635.121 ms) ====== [2025-10-09T22:05:25.031Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-10-09T22:05:25.031Z] GC before operation: completed in 11.860 ms, heap usage 8.389 MB -> 5.181 MB. [2025-10-09T22:05:26.972Z] Camera thread performed 122 scans. [2025-10-09T22:05:26.972Z] ====== philosophers (scala) [default], iteration 29 completed (1691.704 ms) ====== [2025-10-09T22:05:26.972Z] ----------------------------------- [2025-10-09T22:05:26.972Z] renaissance-philosophers_0_PASSED [2025-10-09T22:05:26.972Z] ----------------------------------- [2025-10-09T22:05:26.972Z] [2025-10-09T22:05:26.972Z] TEST TEARDOWN: [2025-10-09T22:05:26.972Z] Nothing to be done for teardown. [2025-10-09T22:05:26.972Z] renaissance-philosophers_0 Finish Time: Thu Oct 9 22:05:26 2025 Epoch Time (ms): 1760047526804