[2025-11-20T01:01:19.943Z] Running test renaissance-philosophers_0 ... [2025-11-20T01:01:19.943Z] =============================================== [2025-11-20T01:01:19.943Z] renaissance-philosophers_0 Start Time: Thu Nov 20 01:01:19 2025 Epoch Time (ms): 1763600479172 [2025-11-20T01:01:19.943Z] variation: NoOptions [2025-11-20T01:01:19.943Z] JVM_OPTIONS: [2025-11-20T01:01:19.943Z] { \ [2025-11-20T01:01:19.943Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T01:01:19.943Z] echo "Nothing to be done for setup."; \ [2025-11-20T01:01:19.943Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/renaissance-philosophers_0"; \ [2025-11-20T01:01:19.943Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/renaissance-philosophers_0"; \ [2025-11-20T01:01:19.943Z] echo ""; echo "TESTING:"; \ [2025-11-20T01:01:19.943Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-20T01:01:19.943Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T01:01:19.943Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T01:01:19.943Z] echo "Nothing to be done for teardown."; \ [2025-11-20T01:01:19.943Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1763598609916/TestTargetResult"; [2025-11-20T01:01:19.943Z] [2025-11-20T01:01:19.943Z] TEST SETUP: [2025-11-20T01:01:19.943Z] Nothing to be done for setup. [2025-11-20T01:01:19.943Z] [2025-11-20T01:01:19.943Z] TESTING: [2025-11-20T01:01:20.715Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-20T01:01:20.715Z] GC before operation: completed in 19.062 ms, heap usage 7.242 MB -> 4.317 MB. [2025-11-20T01:01:30.654Z] Camera thread performed 122 scans. [2025-11-20T01:01:30.654Z] ====== philosophers (scala) [default], iteration 0 completed (9635.281 ms) ====== [2025-11-20T01:01:30.654Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-20T01:01:30.654Z] GC before operation: completed in 16.647 ms, heap usage 202.515 MB -> 4.759 MB. [2025-11-20T01:01:38.971Z] Camera thread performed 122 scans. [2025-11-20T01:01:38.972Z] ====== philosophers (scala) [default], iteration 1 completed (7405.466 ms) ====== [2025-11-20T01:01:38.972Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-20T01:01:38.972Z] GC before operation: completed in 12.343 ms, heap usage 61.765 MB -> 4.762 MB. [2025-11-20T01:01:45.958Z] Camera thread performed 122 scans. [2025-11-20T01:01:45.958Z] ====== philosophers (scala) [default], iteration 2 completed (7201.441 ms) ====== [2025-11-20T01:01:45.958Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-20T01:01:45.958Z] GC before operation: completed in 13.559 ms, heap usage 109.043 MB -> 4.763 MB. [2025-11-20T01:01:52.858Z] Camera thread performed 122 scans. [2025-11-20T01:01:52.858Z] ====== philosophers (scala) [default], iteration 3 completed (7119.114 ms) ====== [2025-11-20T01:01:52.858Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-20T01:01:52.858Z] GC before operation: completed in 13.854 ms, heap usage 139.705 MB -> 4.763 MB. [2025-11-20T01:01:59.753Z] Camera thread performed 122 scans. [2025-11-20T01:01:59.753Z] ====== philosophers (scala) [default], iteration 4 completed (7285.701 ms) ====== [2025-11-20T01:01:59.753Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-20T01:01:59.753Z] GC before operation: completed in 14.493 ms, heap usage 172.070 MB -> 4.764 MB. [2025-11-20T01:02:06.661Z] Camera thread performed 122 scans. [2025-11-20T01:02:06.661Z] ====== philosophers (scala) [default], iteration 5 completed (7115.991 ms) ====== [2025-11-20T01:02:06.661Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-20T01:02:06.661Z] GC before operation: completed in 14.505 ms, heap usage 105.442 MB -> 4.764 MB. [2025-11-20T01:02:13.560Z] Camera thread performed 122 scans. [2025-11-20T01:02:13.561Z] ====== philosophers (scala) [default], iteration 6 completed (7095.950 ms) ====== [2025-11-20T01:02:13.561Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-20T01:02:13.561Z] GC before operation: completed in 14.202 ms, heap usage 160.100 MB -> 4.764 MB. [2025-11-20T01:02:20.631Z] Camera thread performed 122 scans. [2025-11-20T01:02:20.631Z] ====== philosophers (scala) [default], iteration 7 completed (7077.752 ms) ====== [2025-11-20T01:02:20.631Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-20T01:02:20.631Z] GC before operation: completed in 14.625 ms, heap usage 90.070 MB -> 4.764 MB. [2025-11-20T01:02:28.960Z] Camera thread performed 122 scans. [2025-11-20T01:02:28.960Z] ====== philosophers (scala) [default], iteration 8 completed (7309.447 ms) ====== [2025-11-20T01:02:28.960Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-20T01:02:28.960Z] GC before operation: completed in 13.990 ms, heap usage 159.242 MB -> 4.764 MB. [2025-11-20T01:02:35.865Z] Camera thread performed 122 scans. [2025-11-20T01:02:35.865Z] ====== philosophers (scala) [default], iteration 9 completed (7087.601 ms) ====== [2025-11-20T01:02:35.865Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-20T01:02:35.865Z] GC before operation: completed in 14.401 ms, heap usage 117.020 MB -> 4.764 MB. [2025-11-20T01:02:42.769Z] Camera thread performed 122 scans. [2025-11-20T01:02:42.769Z] ====== philosophers (scala) [default], iteration 10 completed (7258.658 ms) ====== [2025-11-20T01:02:42.769Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-20T01:02:42.769Z] GC before operation: completed in 13.920 ms, heap usage 154.624 MB -> 4.764 MB. [2025-11-20T01:02:49.666Z] Camera thread performed 122 scans. [2025-11-20T01:02:49.666Z] ====== philosophers (scala) [default], iteration 11 completed (7264.318 ms) ====== [2025-11-20T01:02:49.666Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-20T01:02:49.666Z] GC before operation: completed in 13.997 ms, heap usage 37.755 MB -> 4.764 MB. [2025-11-20T01:02:57.984Z] Camera thread performed 122 scans. [2025-11-20T01:02:57.984Z] ====== philosophers (scala) [default], iteration 12 completed (7218.820 ms) ====== [2025-11-20T01:02:57.984Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-20T01:02:57.984Z] GC before operation: completed in 14.154 ms, heap usage 66.629 MB -> 4.764 MB. [2025-11-20T01:03:04.928Z] Camera thread performed 122 scans. [2025-11-20T01:03:04.928Z] ====== philosophers (scala) [default], iteration 13 completed (7272.079 ms) ====== [2025-11-20T01:03:04.928Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-20T01:03:04.928Z] GC before operation: completed in 13.260 ms, heap usage 131.809 MB -> 4.764 MB. [2025-11-20T01:03:11.832Z] Camera thread performed 122 scans. [2025-11-20T01:03:11.832Z] ====== philosophers (scala) [default], iteration 14 completed (7332.523 ms) ====== [2025-11-20T01:03:11.832Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-20T01:03:11.832Z] GC before operation: completed in 15.229 ms, heap usage 37.113 MB -> 4.765 MB. [2025-11-20T01:03:18.733Z] Camera thread performed 122 scans. [2025-11-20T01:03:18.733Z] ====== philosophers (scala) [default], iteration 15 completed (7293.715 ms) ====== [2025-11-20T01:03:18.733Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-20T01:03:19.508Z] GC before operation: completed in 14.750 ms, heap usage 222.201 MB -> 4.764 MB. [2025-11-20T01:03:26.412Z] Camera thread performed 122 scans. [2025-11-20T01:03:26.412Z] ====== philosophers (scala) [default], iteration 16 completed (7291.279 ms) ====== [2025-11-20T01:03:26.412Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-20T01:03:26.412Z] GC before operation: completed in 15.530 ms, heap usage 217.843 MB -> 4.764 MB. [2025-11-20T01:03:33.678Z] Camera thread performed 122 scans. [2025-11-20T01:03:33.678Z] ====== philosophers (scala) [default], iteration 17 completed (7096.581 ms) ====== [2025-11-20T01:03:33.678Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-20T01:03:33.678Z] GC before operation: completed in 14.967 ms, heap usage 90.429 MB -> 4.764 MB. [2025-11-20T01:03:40.602Z] Camera thread performed 122 scans. [2025-11-20T01:03:40.602Z] ====== philosophers (scala) [default], iteration 18 completed (7298.962 ms) ====== [2025-11-20T01:03:40.602Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-20T01:03:40.602Z] GC before operation: completed in 16.078 ms, heap usage 136.755 MB -> 4.765 MB. [2025-11-20T01:03:48.980Z] Camera thread performed 122 scans. [2025-11-20T01:03:48.980Z] ====== philosophers (scala) [default], iteration 19 completed (7116.835 ms) ====== [2025-11-20T01:03:48.980Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-20T01:03:48.980Z] GC before operation: completed in 13.923 ms, heap usage 111.937 MB -> 4.764 MB. [2025-11-20T01:03:55.873Z] Camera thread performed 122 scans. [2025-11-20T01:03:55.873Z] ====== philosophers (scala) [default], iteration 20 completed (7286.320 ms) ====== [2025-11-20T01:03:55.873Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-20T01:03:55.873Z] GC before operation: completed in 16.122 ms, heap usage 126.572 MB -> 4.765 MB. [2025-11-20T01:04:02.783Z] Camera thread performed 122 scans. [2025-11-20T01:04:02.783Z] ====== philosophers (scala) [default], iteration 21 completed (7387.071 ms) ====== [2025-11-20T01:04:02.783Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-20T01:04:02.783Z] GC before operation: completed in 13.792 ms, heap usage 79.485 MB -> 4.765 MB. [2025-11-20T01:04:09.715Z] Camera thread performed 122 scans. [2025-11-20T01:04:09.715Z] ====== philosophers (scala) [default], iteration 22 completed (7305.267 ms) ====== [2025-11-20T01:04:09.715Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-20T01:04:09.715Z] GC before operation: completed in 15.129 ms, heap usage 140.042 MB -> 4.765 MB. [2025-11-20T01:04:18.064Z] Camera thread performed 122 scans. [2025-11-20T01:04:18.064Z] ====== philosophers (scala) [default], iteration 23 completed (7178.964 ms) ====== [2025-11-20T01:04:18.065Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-20T01:04:18.065Z] GC before operation: completed in 13.840 ms, heap usage 5.755 MB -> 4.765 MB. [2025-11-20T01:04:24.959Z] Camera thread performed 122 scans. [2025-11-20T01:04:24.959Z] ====== philosophers (scala) [default], iteration 24 completed (7125.927 ms) ====== [2025-11-20T01:04:24.959Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-20T01:04:24.959Z] GC before operation: completed in 14.893 ms, heap usage 143.970 MB -> 4.765 MB. [2025-11-20T01:04:31.849Z] Camera thread performed 122 scans. [2025-11-20T01:04:31.849Z] ====== philosophers (scala) [default], iteration 25 completed (7200.597 ms) ====== [2025-11-20T01:04:31.849Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-20T01:04:31.849Z] GC before operation: completed in 15.901 ms, heap usage 127.106 MB -> 4.765 MB. [2025-11-20T01:04:38.742Z] Camera thread performed 122 scans. [2025-11-20T01:04:38.742Z] ====== philosophers (scala) [default], iteration 26 completed (7088.832 ms) ====== [2025-11-20T01:04:38.742Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-20T01:04:38.742Z] GC before operation: completed in 13.618 ms, heap usage 66.326 MB -> 4.765 MB. [2025-11-20T01:04:45.815Z] Camera thread performed 122 scans. [2025-11-20T01:04:45.815Z] ====== philosophers (scala) [default], iteration 27 completed (7048.896 ms) ====== [2025-11-20T01:04:45.815Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-20T01:04:45.815Z] GC before operation: completed in 13.930 ms, heap usage 91.531 MB -> 4.765 MB. [2025-11-20T01:04:52.715Z] Camera thread performed 122 scans. [2025-11-20T01:04:52.715Z] ====== philosophers (scala) [default], iteration 28 completed (7060.355 ms) ====== [2025-11-20T01:04:52.715Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-20T01:04:52.715Z] GC before operation: completed in 13.106 ms, heap usage 23.163 MB -> 4.765 MB. [2025-11-20T01:05:01.030Z] Camera thread performed 122 scans. [2025-11-20T01:05:01.030Z] ====== philosophers (scala) [default], iteration 29 completed (7268.116 ms) ====== [2025-11-20T01:05:01.030Z] ----------------------------------- [2025-11-20T01:05:01.030Z] renaissance-philosophers_0_PASSED [2025-11-20T01:05:01.030Z] ----------------------------------- [2025-11-20T01:05:01.030Z] [2025-11-20T01:05:01.030Z] TEST TEARDOWN: [2025-11-20T01:05:01.030Z] Nothing to be done for teardown. [2025-11-20T01:05:01.030Z] renaissance-philosophers_0 Finish Time: Thu Nov 20 01:04:59 2025 Epoch Time (ms): 1763600699873