[2026-04-21T22:52:15.832Z] Running test renaissance-philosophers_0 ... [2026-04-21T22:52:15.832Z] =============================================== [2026-04-21T22:52:15.832Z] renaissance-philosophers_0 Start Time: Tue Apr 21 22:52:14 2026 Epoch Time (ms): 1776811934956 [2026-04-21T22:52:15.832Z] variation: NoOptions [2026-04-21T22:52:15.832Z] JVM_OPTIONS: [2026-04-21T22:52:15.832Z] { \ [2026-04-21T22:52:15.832Z] echo ""; echo "TEST SETUP:"; \ [2026-04-21T22:52:15.832Z] echo "Nothing to be done for setup."; \ [2026-04-21T22:52:15.832Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17768106137468/renaissance-philosophers_0"; \ [2026-04-21T22:52:15.832Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17768106137468/renaissance-philosophers_0"; \ [2026-04-21T22:52:15.832Z] echo ""; echo "TESTING:"; \ [2026-04-21T22:52:15.832Z] "/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_17768106137468/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2026-04-21T22:52:15.832Z] 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_17768106137468/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-21T22:52:15.832Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-21T22:52:15.832Z] echo "Nothing to be done for teardown."; \ [2026-04-21T22:52:15.832Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17768106137468/TestTargetResult"; [2026-04-21T22:52:15.832Z] [2026-04-21T22:52:15.832Z] TEST SETUP: [2026-04-21T22:52:15.832Z] Nothing to be done for setup. [2026-04-21T22:52:15.832Z] [2026-04-21T22:52:15.832Z] TESTING: [2026-04-21T22:52:16.803Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-04-21T22:52:16.803Z] GC before operation: completed in 19.948 ms, heap usage 6.614 MB -> 4.317 MB. [2026-04-21T22:52:28.396Z] Camera thread performed 122 scans. [2026-04-21T22:52:28.396Z] ====== philosophers (scala) [default], iteration 0 completed (10565.906 ms) ====== [2026-04-21T22:52:28.396Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-04-21T22:52:28.396Z] GC before operation: completed in 18.843 ms, heap usage 8.200 MB -> 4.756 MB. [2026-04-21T22:52:35.140Z] Camera thread performed 122 scans. [2026-04-21T22:52:35.140Z] ====== philosophers (scala) [default], iteration 1 completed (7536.957 ms) ====== [2026-04-21T22:52:35.140Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-04-21T22:52:35.140Z] GC before operation: completed in 12.997 ms, heap usage 8.200 MB -> 4.759 MB. [2026-04-21T22:52:42.131Z] Camera thread performed 122 scans. [2026-04-21T22:52:42.131Z] ====== philosophers (scala) [default], iteration 2 completed (7559.607 ms) ====== [2026-04-21T22:52:42.131Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-04-21T22:52:42.131Z] GC before operation: completed in 14.345 ms, heap usage 169.766 MB -> 4.760 MB. [2026-04-21T22:52:50.331Z] Camera thread performed 122 scans. [2026-04-21T22:52:50.331Z] ====== philosophers (scala) [default], iteration 3 completed (7672.176 ms) ====== [2026-04-21T22:52:50.331Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-04-21T22:52:50.331Z] GC before operation: completed in 14.504 ms, heap usage 71.313 MB -> 4.760 MB. [2026-04-21T22:52:58.529Z] Camera thread performed 122 scans. [2026-04-21T22:52:58.529Z] ====== philosophers (scala) [default], iteration 4 completed (7568.730 ms) ====== [2026-04-21T22:52:58.529Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-04-21T22:52:58.529Z] GC before operation: completed in 13.862 ms, heap usage 178.679 MB -> 4.760 MB. [2026-04-21T22:53:05.269Z] Camera thread performed 122 scans. [2026-04-21T22:53:05.269Z] ====== philosophers (scala) [default], iteration 5 completed (7720.454 ms) ====== [2026-04-21T22:53:05.269Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-04-21T22:53:05.269Z] GC before operation: completed in 14.055 ms, heap usage 82.406 MB -> 4.760 MB. [2026-04-21T22:53:13.464Z] Camera thread performed 122 scans. [2026-04-21T22:53:13.464Z] ====== philosophers (scala) [default], iteration 6 completed (7640.213 ms) ====== [2026-04-21T22:53:13.464Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-04-21T22:53:13.464Z] GC before operation: completed in 13.215 ms, heap usage 10.757 MB -> 4.760 MB. [2026-04-21T22:53:21.658Z] Camera thread performed 122 scans. [2026-04-21T22:53:21.658Z] ====== philosophers (scala) [default], iteration 7 completed (7735.130 ms) ====== [2026-04-21T22:53:21.658Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-04-21T22:53:21.658Z] GC before operation: completed in 14.924 ms, heap usage 127.582 MB -> 4.760 MB. [2026-04-21T22:53:28.595Z] Camera thread performed 122 scans. [2026-04-21T22:53:28.595Z] ====== philosophers (scala) [default], iteration 8 completed (7523.676 ms) ====== [2026-04-21T22:53:28.595Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-04-21T22:53:28.596Z] GC before operation: completed in 12.702 ms, heap usage 90.592 MB -> 4.760 MB. [2026-04-21T22:53:36.791Z] Camera thread performed 122 scans. [2026-04-21T22:53:36.791Z] ====== philosophers (scala) [default], iteration 9 completed (7753.915 ms) ====== [2026-04-21T22:53:36.791Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-04-21T22:53:36.791Z] GC before operation: completed in 14.437 ms, heap usage 127.187 MB -> 4.760 MB. [2026-04-21T22:53:43.629Z] Camera thread performed 122 scans. [2026-04-21T22:53:43.629Z] ====== philosophers (scala) [default], iteration 10 completed (7614.265 ms) ====== [2026-04-21T22:53:43.629Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-04-21T22:53:43.629Z] GC before operation: completed in 12.831 ms, heap usage 106.317 MB -> 4.760 MB. [2026-04-21T22:53:51.835Z] Camera thread performed 122 scans. [2026-04-21T22:53:51.835Z] ====== philosophers (scala) [default], iteration 11 completed (7762.396 ms) ====== [2026-04-21T22:53:51.835Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-04-21T22:53:51.835Z] GC before operation: completed in 14.700 ms, heap usage 184.832 MB -> 4.760 MB. [2026-04-21T22:54:00.029Z] Camera thread performed 122 scans. [2026-04-21T22:54:00.029Z] ====== philosophers (scala) [default], iteration 12 completed (7620.554 ms) ====== [2026-04-21T22:54:00.029Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-04-21T22:54:00.029Z] GC before operation: completed in 13.365 ms, heap usage 148.427 MB -> 4.760 MB. [2026-04-21T22:54:06.764Z] Camera thread performed 122 scans. [2026-04-21T22:54:06.764Z] ====== philosophers (scala) [default], iteration 13 completed (7715.393 ms) ====== [2026-04-21T22:54:06.764Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-04-21T22:54:06.764Z] GC before operation: completed in 14.274 ms, heap usage 18.034 MB -> 4.760 MB. [2026-04-21T22:54:15.170Z] Camera thread performed 122 scans. [2026-04-21T22:54:15.170Z] ====== philosophers (scala) [default], iteration 14 completed (7644.875 ms) ====== [2026-04-21T22:54:15.170Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-04-21T22:54:15.170Z] GC before operation: completed in 13.489 ms, heap usage 204.579 MB -> 4.761 MB. [2026-04-21T22:54:23.357Z] Camera thread performed 122 scans. [2026-04-21T22:54:23.357Z] ====== philosophers (scala) [default], iteration 15 completed (7831.726 ms) ====== [2026-04-21T22:54:23.357Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-04-21T22:54:23.357Z] GC before operation: completed in 14.257 ms, heap usage 144.592 MB -> 4.760 MB. [2026-04-21T22:54:30.177Z] Camera thread performed 122 scans. [2026-04-21T22:54:30.177Z] ====== philosophers (scala) [default], iteration 16 completed (7674.695 ms) ====== [2026-04-21T22:54:30.177Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-04-21T22:54:30.177Z] GC before operation: completed in 13.298 ms, heap usage 168.689 MB -> 4.761 MB. [2026-04-21T22:54:38.367Z] Camera thread performed 122 scans. [2026-04-21T22:54:38.367Z] ====== philosophers (scala) [default], iteration 17 completed (7491.640 ms) ====== [2026-04-21T22:54:38.367Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-04-21T22:54:38.367Z] GC before operation: completed in 15.004 ms, heap usage 105.564 MB -> 4.761 MB. [2026-04-21T22:54:45.096Z] Camera thread performed 122 scans. [2026-04-21T22:54:45.096Z] ====== philosophers (scala) [default], iteration 18 completed (7575.879 ms) ====== [2026-04-21T22:54:45.096Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-04-21T22:54:45.096Z] GC before operation: completed in 13.204 ms, heap usage 86.009 MB -> 4.761 MB. [2026-04-21T22:54:53.383Z] Camera thread performed 122 scans. [2026-04-21T22:54:53.383Z] ====== philosophers (scala) [default], iteration 19 completed (7546.052 ms) ====== [2026-04-21T22:54:53.383Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-04-21T22:54:53.383Z] GC before operation: completed in 14.137 ms, heap usage 183.776 MB -> 4.761 MB. [2026-04-21T22:55:00.112Z] Camera thread performed 122 scans. [2026-04-21T22:55:00.112Z] ====== philosophers (scala) [default], iteration 20 completed (7591.082 ms) ====== [2026-04-21T22:55:00.112Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-04-21T22:55:00.112Z] GC before operation: completed in 12.898 ms, heap usage 57.454 MB -> 4.761 MB. [2026-04-21T22:55:08.300Z] Camera thread performed 122 scans. [2026-04-21T22:55:08.301Z] ====== philosophers (scala) [default], iteration 21 completed (7622.279 ms) ====== [2026-04-21T22:55:08.301Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-04-21T22:55:08.301Z] GC before operation: completed in 13.131 ms, heap usage 94.120 MB -> 4.761 MB. [2026-04-21T22:55:16.499Z] Camera thread performed 122 scans. [2026-04-21T22:55:16.499Z] ====== philosophers (scala) [default], iteration 22 completed (7580.833 ms) ====== [2026-04-21T22:55:16.499Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-04-21T22:55:16.499Z] GC before operation: completed in 13.194 ms, heap usage 211.773 MB -> 4.761 MB. [2026-04-21T22:55:23.450Z] Camera thread performed 122 scans. [2026-04-21T22:55:23.450Z] ====== philosophers (scala) [default], iteration 23 completed (7742.643 ms) ====== [2026-04-21T22:55:23.450Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-04-21T22:55:23.450Z] GC before operation: completed in 12.742 ms, heap usage 105.767 MB -> 4.761 MB. [2026-04-21T22:55:31.648Z] Camera thread performed 122 scans. [2026-04-21T22:55:31.648Z] ====== philosophers (scala) [default], iteration 24 completed (7591.054 ms) ====== [2026-04-21T22:55:31.648Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-04-21T22:55:31.648Z] GC before operation: completed in 11.658 ms, heap usage 147.395 MB -> 4.761 MB. [2026-04-21T22:55:38.749Z] Camera thread performed 122 scans. [2026-04-21T22:55:38.749Z] ====== philosophers (scala) [default], iteration 25 completed (7643.014 ms) ====== [2026-04-21T22:55:38.749Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-04-21T22:55:38.749Z] GC before operation: completed in 11.725 ms, heap usage 25.459 MB -> 4.761 MB. [2026-04-21T22:55:47.022Z] Camera thread performed 122 scans. [2026-04-21T22:55:47.022Z] ====== philosophers (scala) [default], iteration 26 completed (7637.771 ms) ====== [2026-04-21T22:55:47.022Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-04-21T22:55:47.022Z] GC before operation: completed in 14.743 ms, heap usage 199.100 MB -> 4.761 MB. [2026-04-21T22:55:53.762Z] Camera thread performed 122 scans. [2026-04-21T22:55:53.763Z] ====== philosophers (scala) [default], iteration 27 completed (7643.930 ms) ====== [2026-04-21T22:55:53.763Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-04-21T22:55:53.763Z] GC before operation: completed in 11.919 ms, heap usage 91.015 MB -> 4.762 MB. [2026-04-21T22:56:01.957Z] Camera thread performed 122 scans. [2026-04-21T22:56:01.957Z] ====== philosophers (scala) [default], iteration 28 completed (7734.751 ms) ====== [2026-04-21T22:56:01.957Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-04-21T22:56:01.957Z] GC before operation: completed in 12.690 ms, heap usage 83.352 MB -> 4.762 MB. [2026-04-21T22:56:10.149Z] Camera thread performed 122 scans. [2026-04-21T22:56:10.149Z] ====== philosophers (scala) [default], iteration 29 completed (7667.658 ms) ====== [2026-04-21T22:56:10.149Z] ----------------------------------- [2026-04-21T22:56:10.149Z] renaissance-philosophers_0_PASSED [2026-04-21T22:56:10.149Z] ----------------------------------- [2026-04-21T22:56:10.149Z] [2026-04-21T22:56:10.149Z] TEST TEARDOWN: [2026-04-21T22:56:10.149Z] Nothing to be done for teardown. [2026-04-21T22:56:10.149Z] renaissance-philosophers_0 Finish Time: Tue Apr 21 22:56:09 2026 Epoch Time (ms): 1776812169098