[2025-06-29T20:58:33.392Z] Running test renaissance-philosophers_0 ... [2025-06-29T20:58:33.392Z] =============================================== [2025-06-29T20:58:33.392Z] renaissance-philosophers_0 Start Time: Sun Jun 29 20:58:33 2025 Epoch Time (ms): 1751230713262 [2025-06-29T20:58:33.392Z] variation: NoOptions [2025-06-29T20:58:33.392Z] JVM_OPTIONS: [2025-06-29T20:58:33.392Z] { \ [2025-06-29T20:58:33.392Z] echo ""; echo "TEST SETUP:"; \ [2025-06-29T20:58:33.392Z] echo "Nothing to be done for setup."; \ [2025-06-29T20:58:33.392Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17512288609013/renaissance-philosophers_0"; \ [2025-06-29T20:58:33.392Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17512288609013/renaissance-philosophers_0"; \ [2025-06-29T20:58:33.392Z] echo ""; echo "TESTING:"; \ [2025-06-29T20:58:33.392Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_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_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17512288609013/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-29T20:58:33.392Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17512288609013/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-29T20:58:33.392Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-29T20:58:33.392Z] echo "Nothing to be done for teardown."; \ [2025-06-29T20:58:33.392Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17512288609013/TestTargetResult"; [2025-06-29T20:58:33.392Z] [2025-06-29T20:58:33.392Z] TEST SETUP: [2025-06-29T20:58:33.392Z] Nothing to be done for setup. [2025-06-29T20:58:33.392Z] [2025-06-29T20:58:33.392Z] TESTING: [2025-06-29T20:58:34.332Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-29T20:58:34.332Z] GC before operation: completed in 12.284 ms, heap usage 14.316 MB -> 4.709 MB. [2025-06-29T20:58:40.364Z] Camera thread performed 122 scans. [2025-06-29T20:58:40.365Z] ====== philosophers (scala) [default], iteration 0 completed (5436.727 ms) ====== [2025-06-29T20:58:40.365Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-29T20:58:40.365Z] GC before operation: completed in 14.739 ms, heap usage 11.401 MB -> 5.019 MB. [2025-06-29T20:58:45.641Z] Camera thread performed 122 scans. [2025-06-29T20:58:45.641Z] ====== philosophers (scala) [default], iteration 1 completed (4994.042 ms) ====== [2025-06-29T20:58:45.641Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-29T20:58:45.641Z] GC before operation: completed in 12.801 ms, heap usage 44.040 MB -> 5.020 MB. [2025-06-29T20:58:50.987Z] Camera thread performed 122 scans. [2025-06-29T20:58:50.987Z] ====== philosophers (scala) [default], iteration 2 completed (5222.529 ms) ====== [2025-06-29T20:58:50.987Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-29T20:58:50.987Z] GC before operation: completed in 15.035 ms, heap usage 7.002 MB -> 5.022 MB. [2025-06-29T20:58:56.302Z] Camera thread performed 122 scans. [2025-06-29T20:58:56.302Z] ====== philosophers (scala) [default], iteration 3 completed (5249.694 ms) ====== [2025-06-29T20:58:56.302Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-29T20:58:56.302Z] GC before operation: completed in 13.843 ms, heap usage 27.040 MB -> 5.022 MB. [2025-06-29T20:59:01.685Z] Camera thread performed 122 scans. [2025-06-29T20:59:01.685Z] ====== philosophers (scala) [default], iteration 4 completed (5442.917 ms) ====== [2025-06-29T20:59:01.685Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-29T20:59:01.685Z] GC before operation: completed in 13.112 ms, heap usage 33.624 MB -> 5.022 MB. [2025-06-29T20:59:05.794Z] Camera thread performed 122 scans. [2025-06-29T20:59:05.794Z] ====== philosophers (scala) [default], iteration 5 completed (4372.261 ms) ====== [2025-06-29T20:59:05.794Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-29T20:59:05.794Z] GC before operation: completed in 13.754 ms, heap usage 24.793 MB -> 5.022 MB. [2025-06-29T20:59:11.123Z] Camera thread performed 122 scans. [2025-06-29T20:59:11.123Z] ====== philosophers (scala) [default], iteration 6 completed (5655.534 ms) ====== [2025-06-29T20:59:11.123Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-29T20:59:11.123Z] GC before operation: completed in 14.100 ms, heap usage 32.274 MB -> 5.023 MB. [2025-06-29T20:59:16.438Z] Camera thread performed 122 scans. [2025-06-29T20:59:16.438Z] ====== philosophers (scala) [default], iteration 7 completed (4743.611 ms) ====== [2025-06-29T20:59:16.438Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-29T20:59:16.438Z] GC before operation: completed in 12.207 ms, heap usage 21.259 MB -> 5.023 MB. [2025-06-29T20:59:20.555Z] Camera thread performed 122 scans. [2025-06-29T20:59:20.555Z] ====== philosophers (scala) [default], iteration 8 completed (4482.700 ms) ====== [2025-06-29T20:59:20.555Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-29T20:59:20.555Z] GC before operation: completed in 15.607 ms, heap usage 11.979 MB -> 5.023 MB. [2025-06-29T20:59:24.669Z] Camera thread performed 122 scans. [2025-06-29T20:59:24.669Z] ====== philosophers (scala) [default], iteration 9 completed (4719.496 ms) ====== [2025-06-29T20:59:24.669Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-29T20:59:25.604Z] GC before operation: completed in 15.068 ms, heap usage 10.687 MB -> 5.023 MB. [2025-06-29T20:59:29.688Z] Camera thread performed 122 scans. [2025-06-29T20:59:29.688Z] ====== philosophers (scala) [default], iteration 10 completed (4464.943 ms) ====== [2025-06-29T20:59:29.688Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-29T20:59:29.688Z] GC before operation: completed in 10.328 ms, heap usage 35.941 MB -> 5.023 MB. [2025-06-29T20:59:33.846Z] Camera thread performed 122 scans. [2025-06-29T20:59:33.847Z] ====== philosophers (scala) [default], iteration 11 completed (4588.720 ms) ====== [2025-06-29T20:59:33.847Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-29T20:59:33.847Z] GC before operation: completed in 13.143 ms, heap usage 37.809 MB -> 5.024 MB. [2025-06-29T20:59:39.462Z] Camera thread performed 122 scans. [2025-06-29T20:59:39.462Z] ====== philosophers (scala) [default], iteration 12 completed (4665.480 ms) ====== [2025-06-29T20:59:39.462Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-29T20:59:39.462Z] GC before operation: completed in 12.502 ms, heap usage 21.256 MB -> 5.024 MB. [2025-06-29T20:59:43.576Z] Camera thread performed 122 scans. [2025-06-29T20:59:43.576Z] ====== philosophers (scala) [default], iteration 13 completed (4790.204 ms) ====== [2025-06-29T20:59:43.576Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-29T20:59:43.576Z] GC before operation: completed in 11.748 ms, heap usage 42.487 MB -> 5.024 MB. [2025-06-29T20:59:50.187Z] Camera thread performed 122 scans. [2025-06-29T20:59:50.187Z] ====== philosophers (scala) [default], iteration 14 completed (6089.877 ms) ====== [2025-06-29T20:59:50.187Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-29T20:59:50.187Z] GC before operation: completed in 15.999 ms, heap usage 45.129 MB -> 5.024 MB. [2025-06-29T20:59:55.496Z] Camera thread performed 122 scans. [2025-06-29T20:59:55.496Z] ====== philosophers (scala) [default], iteration 15 completed (5611.430 ms) ====== [2025-06-29T20:59:55.496Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-29T20:59:55.496Z] GC before operation: completed in 12.082 ms, heap usage 36.988 MB -> 5.024 MB. [2025-06-29T21:00:02.142Z] Camera thread performed 122 scans. [2025-06-29T21:00:02.142Z] ====== philosophers (scala) [default], iteration 16 completed (5836.517 ms) ====== [2025-06-29T21:00:02.142Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-29T21:00:02.142Z] GC before operation: completed in 15.235 ms, heap usage 20.973 MB -> 5.025 MB. [2025-06-29T21:00:07.462Z] Camera thread performed 122 scans. [2025-06-29T21:00:07.462Z] ====== philosophers (scala) [default], iteration 17 completed (5676.308 ms) ====== [2025-06-29T21:00:07.462Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-29T21:00:07.462Z] GC before operation: completed in 12.521 ms, heap usage 18.159 MB -> 5.025 MB. [2025-06-29T21:00:12.772Z] Camera thread performed 122 scans. [2025-06-29T21:00:12.772Z] ====== philosophers (scala) [default], iteration 18 completed (5455.631 ms) ====== [2025-06-29T21:00:12.772Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-29T21:00:12.772Z] GC before operation: completed in 15.675 ms, heap usage 36.262 MB -> 5.025 MB. [2025-06-29T21:00:16.862Z] Camera thread performed 122 scans. [2025-06-29T21:00:16.862Z] ====== philosophers (scala) [default], iteration 19 completed (4779.349 ms) ====== [2025-06-29T21:00:16.862Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-29T21:00:16.862Z] GC before operation: completed in 10.447 ms, heap usage 34.095 MB -> 5.025 MB. [2025-06-29T21:00:22.238Z] Camera thread performed 122 scans. [2025-06-29T21:00:22.238Z] ====== philosophers (scala) [default], iteration 20 completed (5361.524 ms) ====== [2025-06-29T21:00:22.238Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-29T21:00:22.238Z] GC before operation: completed in 12.464 ms, heap usage 7.841 MB -> 5.025 MB. [2025-06-29T21:00:27.530Z] Camera thread performed 122 scans. [2025-06-29T21:00:27.530Z] ====== philosophers (scala) [default], iteration 21 completed (5004.827 ms) ====== [2025-06-29T21:00:27.530Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-29T21:00:27.530Z] GC before operation: completed in 10.600 ms, heap usage 17.204 MB -> 5.025 MB. [2025-06-29T21:00:32.821Z] Camera thread performed 122 scans. [2025-06-29T21:00:32.822Z] ====== philosophers (scala) [default], iteration 22 completed (5121.379 ms) ====== [2025-06-29T21:00:32.822Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-29T21:00:32.822Z] GC before operation: completed in 12.339 ms, heap usage 41.238 MB -> 5.025 MB. [2025-06-29T21:00:38.278Z] Camera thread performed 122 scans. [2025-06-29T21:00:38.278Z] ====== philosophers (scala) [default], iteration 23 completed (5320.005 ms) ====== [2025-06-29T21:00:38.278Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-29T21:00:38.278Z] GC before operation: completed in 13.371 ms, heap usage 23.967 MB -> 5.025 MB. [2025-06-29T21:00:42.365Z] Camera thread performed 122 scans. [2025-06-29T21:00:42.366Z] ====== philosophers (scala) [default], iteration 24 completed (4296.093 ms) ====== [2025-06-29T21:00:42.366Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-29T21:00:42.366Z] GC before operation: completed in 9.893 ms, heap usage 16.241 MB -> 5.025 MB. [2025-06-29T21:00:46.453Z] Camera thread performed 122 scans. [2025-06-29T21:00:46.453Z] ====== philosophers (scala) [default], iteration 25 completed (3612.436 ms) ====== [2025-06-29T21:00:46.453Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-29T21:00:46.453Z] GC before operation: completed in 9.486 ms, heap usage 6.906 MB -> 5.025 MB. [2025-06-29T21:00:49.419Z] Camera thread performed 122 scans. [2025-06-29T21:00:49.419Z] ====== philosophers (scala) [default], iteration 26 completed (3707.256 ms) ====== [2025-06-29T21:00:49.419Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-29T21:00:49.419Z] GC before operation: completed in 11.214 ms, heap usage 17.625 MB -> 5.025 MB. [2025-06-29T21:00:53.499Z] Camera thread performed 122 scans. [2025-06-29T21:00:53.499Z] ====== philosophers (scala) [default], iteration 27 completed (3598.532 ms) ====== [2025-06-29T21:00:53.499Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-29T21:00:53.499Z] GC before operation: completed in 10.252 ms, heap usage 60.908 MB -> 5.025 MB. [2025-06-29T21:00:56.476Z] Camera thread performed 122 scans. [2025-06-29T21:00:56.476Z] ====== philosophers (scala) [default], iteration 28 completed (3546.984 ms) ====== [2025-06-29T21:00:56.476Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-29T21:00:56.476Z] GC before operation: completed in 13.434 ms, heap usage 12.175 MB -> 5.025 MB. [2025-06-29T21:01:01.762Z] Camera thread performed 122 scans. [2025-06-29T21:01:01.762Z] ====== philosophers (scala) [default], iteration 29 completed (4274.280 ms) ====== [2025-06-29T21:01:01.762Z] ----------------------------------- [2025-06-29T21:01:01.762Z] renaissance-philosophers_0_PASSED [2025-06-29T21:01:01.762Z] ----------------------------------- [2025-06-29T21:01:01.762Z] [2025-06-29T21:01:01.762Z] TEST TEARDOWN: [2025-06-29T21:01:01.762Z] Nothing to be done for teardown. [2025-06-29T21:01:01.762Z] renaissance-philosophers_0 Finish Time: Sun Jun 29 21:01:00 2025 Epoch Time (ms): 1751230860845