[2025-06-30T20:33:36.927Z] Running test renaissance-philosophers_0 ... [2025-06-30T20:33:36.927Z] =============================================== [2025-06-30T20:33:36.927Z] renaissance-philosophers_0 Start Time: Mon Jun 30 20:33:36 2025 Epoch Time (ms): 1751315616694 [2025-06-30T20:33:36.927Z] variation: NoOptions [2025-06-30T20:33:36.927Z] JVM_OPTIONS: [2025-06-30T20:33:36.927Z] { \ [2025-06-30T20:33:36.927Z] echo ""; echo "TEST SETUP:"; \ [2025-06-30T20:33:36.927Z] echo "Nothing to be done for setup."; \ [2025-06-30T20:33:36.927Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_1751314424950/renaissance-philosophers_0"; \ [2025-06-30T20:33:36.927Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_1751314424950/renaissance-philosophers_0"; \ [2025-06-30T20:33:36.927Z] echo ""; echo "TESTING:"; \ [2025-06-30T20:33:36.927Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/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_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_1751314424950/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-30T20:33:36.927Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_1751314424950/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-30T20:33:36.927Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-30T20:33:36.927Z] echo "Nothing to be done for teardown."; \ [2025-06-30T20:33:36.927Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_1751314424950/TestTargetResult"; [2025-06-30T20:33:36.927Z] [2025-06-30T20:33:36.927Z] TEST SETUP: [2025-06-30T20:33:36.927Z] Nothing to be done for setup. [2025-06-30T20:33:36.927Z] [2025-06-30T20:33:36.927Z] TESTING: [2025-06-30T20:33:37.853Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-30T20:33:37.853Z] GC before operation: completed in 11.457 ms, heap usage 7.291 MB -> 4.876 MB. [2025-06-30T20:33:43.108Z] Camera thread performed 122 scans. [2025-06-30T20:33:43.108Z] ====== philosophers (scala) [default], iteration 0 completed (4559.934 ms) ====== [2025-06-30T20:33:43.108Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-30T20:33:43.108Z] GC before operation: completed in 17.099 ms, heap usage 109.246 MB -> 5.255 MB. [2025-06-30T20:33:46.040Z] Camera thread performed 122 scans. [2025-06-30T20:33:46.040Z] ====== philosophers (scala) [default], iteration 1 completed (3003.066 ms) ====== [2025-06-30T20:33:46.040Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-30T20:33:46.040Z] GC before operation: completed in 14.288 ms, heap usage 103.552 MB -> 5.255 MB. [2025-06-30T20:33:47.940Z] Camera thread performed 122 scans. [2025-06-30T20:33:47.940Z] ====== philosophers (scala) [default], iteration 2 completed (2160.446 ms) ====== [2025-06-30T20:33:47.940Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-30T20:33:47.940Z] GC before operation: completed in 13.736 ms, heap usage 145.089 MB -> 5.256 MB. [2025-06-30T20:33:51.044Z] Camera thread performed 122 scans. [2025-06-30T20:33:51.044Z] ====== philosophers (scala) [default], iteration 3 completed (3370.240 ms) ====== [2025-06-30T20:33:51.044Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-30T20:33:51.044Z] GC before operation: completed in 12.035 ms, heap usage 18.253 MB -> 5.257 MB. [2025-06-30T20:33:55.090Z] Camera thread performed 122 scans. [2025-06-30T20:33:55.090Z] ====== philosophers (scala) [default], iteration 4 completed (3548.867 ms) ====== [2025-06-30T20:33:55.090Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-30T20:33:55.090Z] GC before operation: completed in 14.428 ms, heap usage 85.556 MB -> 5.257 MB. [2025-06-30T20:33:58.023Z] Camera thread performed 122 scans. [2025-06-30T20:33:58.023Z] ====== philosophers (scala) [default], iteration 5 completed (3361.923 ms) ====== [2025-06-30T20:33:58.023Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-30T20:33:58.023Z] GC before operation: completed in 11.806 ms, heap usage 74.635 MB -> 5.257 MB. [2025-06-30T20:34:02.064Z] Camera thread performed 122 scans. [2025-06-30T20:34:02.064Z] ====== philosophers (scala) [default], iteration 6 completed (3651.921 ms) ====== [2025-06-30T20:34:02.064Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-30T20:34:02.064Z] GC before operation: completed in 12.385 ms, heap usage 57.113 MB -> 5.258 MB. [2025-06-30T20:34:06.107Z] Camera thread performed 122 scans. [2025-06-30T20:34:06.107Z] ====== philosophers (scala) [default], iteration 7 completed (3803.000 ms) ====== [2025-06-30T20:34:06.107Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-30T20:34:06.107Z] GC before operation: completed in 10.892 ms, heap usage 34.998 MB -> 5.258 MB. [2025-06-30T20:34:08.009Z] Camera thread performed 122 scans. [2025-06-30T20:34:08.009Z] ====== philosophers (scala) [default], iteration 8 completed (2266.548 ms) ====== [2025-06-30T20:34:08.009Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-30T20:34:08.009Z] GC before operation: completed in 11.268 ms, heap usage 76.521 MB -> 5.258 MB. [2025-06-30T20:34:10.946Z] Camera thread performed 122 scans. [2025-06-30T20:34:10.946Z] ====== philosophers (scala) [default], iteration 9 completed (3222.105 ms) ====== [2025-06-30T20:34:10.946Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-30T20:34:10.946Z] GC before operation: completed in 10.924 ms, heap usage 17.371 MB -> 5.258 MB. [2025-06-30T20:34:13.881Z] Camera thread performed 122 scans. [2025-06-30T20:34:13.881Z] ====== philosophers (scala) [default], iteration 10 completed (2917.178 ms) ====== [2025-06-30T20:34:13.881Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-30T20:34:13.881Z] GC before operation: completed in 12.440 ms, heap usage 51.148 MB -> 5.258 MB. [2025-06-30T20:34:17.938Z] Camera thread performed 122 scans. [2025-06-30T20:34:17.938Z] ====== philosophers (scala) [default], iteration 11 completed (3731.842 ms) ====== [2025-06-30T20:34:17.938Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-30T20:34:17.938Z] GC before operation: completed in 15.805 ms, heap usage 93.623 MB -> 5.258 MB. [2025-06-30T20:34:21.993Z] Camera thread performed 122 scans. [2025-06-30T20:34:21.994Z] ====== philosophers (scala) [default], iteration 12 completed (4132.720 ms) ====== [2025-06-30T20:34:21.994Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-30T20:34:21.994Z] GC before operation: completed in 13.418 ms, heap usage 12.610 MB -> 5.258 MB. [2025-06-30T20:34:26.710Z] Camera thread performed 122 scans. [2025-06-30T20:34:26.710Z] ====== philosophers (scala) [default], iteration 13 completed (4499.085 ms) ====== [2025-06-30T20:34:26.710Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-30T20:34:26.710Z] GC before operation: completed in 20.085 ms, heap usage 34.400 MB -> 5.258 MB. [2025-06-30T20:34:31.946Z] Camera thread performed 122 scans. [2025-06-30T20:34:31.946Z] ====== philosophers (scala) [default], iteration 14 completed (4876.631 ms) ====== [2025-06-30T20:34:31.946Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-30T20:34:31.946Z] GC before operation: completed in 12.254 ms, heap usage 26.659 MB -> 5.258 MB. [2025-06-30T20:34:34.896Z] Camera thread performed 122 scans. [2025-06-30T20:34:34.896Z] ====== philosophers (scala) [default], iteration 15 completed (3363.476 ms) ====== [2025-06-30T20:34:34.896Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-30T20:34:34.896Z] GC before operation: completed in 15.407 ms, heap usage 93.930 MB -> 5.258 MB. [2025-06-30T20:34:38.937Z] Camera thread performed 122 scans. [2025-06-30T20:34:38.937Z] ====== philosophers (scala) [default], iteration 16 completed (4590.856 ms) ====== [2025-06-30T20:34:38.937Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-30T20:34:39.863Z] GC before operation: completed in 14.685 ms, heap usage 22.648 MB -> 5.259 MB. [2025-06-30T20:34:43.902Z] Camera thread performed 122 scans. [2025-06-30T20:34:43.902Z] ====== philosophers (scala) [default], iteration 17 completed (4702.523 ms) ====== [2025-06-30T20:34:43.902Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-30T20:34:43.902Z] GC before operation: completed in 12.912 ms, heap usage 13.232 MB -> 5.259 MB. [2025-06-30T20:34:47.942Z] Camera thread performed 122 scans. [2025-06-30T20:34:47.942Z] ====== philosophers (scala) [default], iteration 18 completed (4174.789 ms) ====== [2025-06-30T20:34:47.942Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-30T20:34:47.942Z] GC before operation: completed in 16.106 ms, heap usage 24.997 MB -> 5.259 MB. [2025-06-30T20:34:53.175Z] Camera thread performed 122 scans. [2025-06-30T20:34:53.175Z] ====== philosophers (scala) [default], iteration 19 completed (4506.709 ms) ====== [2025-06-30T20:34:53.175Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-30T20:34:53.175Z] GC before operation: completed in 15.162 ms, heap usage 51.443 MB -> 5.259 MB. [2025-06-30T20:34:57.220Z] Camera thread performed 122 scans. [2025-06-30T20:34:57.220Z] ====== philosophers (scala) [default], iteration 20 completed (4734.778 ms) ====== [2025-06-30T20:34:57.220Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-30T20:34:57.220Z] GC before operation: completed in 14.437 ms, heap usage 18.624 MB -> 5.259 MB. [2025-06-30T20:35:02.456Z] Camera thread performed 122 scans. [2025-06-30T20:35:02.456Z] ====== philosophers (scala) [default], iteration 21 completed (4470.525 ms) ====== [2025-06-30T20:35:02.456Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-30T20:35:02.456Z] GC before operation: completed in 13.281 ms, heap usage 20.290 MB -> 5.259 MB. [2025-06-30T20:35:06.509Z] Camera thread performed 122 scans. [2025-06-30T20:35:06.509Z] ====== philosophers (scala) [default], iteration 22 completed (4843.466 ms) ====== [2025-06-30T20:35:06.509Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-30T20:35:06.509Z] GC before operation: completed in 11.350 ms, heap usage 39.627 MB -> 5.259 MB. [2025-06-30T20:35:10.558Z] Camera thread performed 122 scans. [2025-06-30T20:35:10.558Z] ====== philosophers (scala) [default], iteration 23 completed (3616.873 ms) ====== [2025-06-30T20:35:10.558Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-30T20:35:10.558Z] GC before operation: completed in 12.018 ms, heap usage 44.289 MB -> 5.259 MB. [2025-06-30T20:35:13.499Z] Camera thread performed 122 scans. [2025-06-30T20:35:13.499Z] ====== philosophers (scala) [default], iteration 24 completed (3333.467 ms) ====== [2025-06-30T20:35:13.499Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-30T20:35:13.499Z] GC before operation: completed in 11.407 ms, heap usage 32.323 MB -> 5.259 MB. [2025-06-30T20:35:17.550Z] Camera thread performed 122 scans. [2025-06-30T20:35:17.550Z] ====== philosophers (scala) [default], iteration 25 completed (3176.215 ms) ====== [2025-06-30T20:35:17.550Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-30T20:35:17.550Z] GC before operation: completed in 12.010 ms, heap usage 63.405 MB -> 5.259 MB. [2025-06-30T20:35:21.163Z] Camera thread performed 122 scans. [2025-06-30T20:35:21.163Z] ====== philosophers (scala) [default], iteration 26 completed (3089.123 ms) ====== [2025-06-30T20:35:21.163Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-30T20:35:21.163Z] GC before operation: completed in 11.232 ms, heap usage 43.624 MB -> 5.259 MB. [2025-06-30T20:35:24.103Z] Camera thread performed 122 scans. [2025-06-30T20:35:24.103Z] ====== philosophers (scala) [default], iteration 27 completed (3720.249 ms) ====== [2025-06-30T20:35:24.103Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-30T20:35:24.103Z] GC before operation: completed in 11.248 ms, heap usage 89.101 MB -> 5.259 MB. [2025-06-30T20:35:27.038Z] Camera thread performed 122 scans. [2025-06-30T20:35:27.038Z] ====== philosophers (scala) [default], iteration 28 completed (3466.172 ms) ====== [2025-06-30T20:35:27.038Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-30T20:35:27.038Z] GC before operation: completed in 11.902 ms, heap usage 13.076 MB -> 5.259 MB. [2025-06-30T20:35:31.080Z] Camera thread performed 122 scans. [2025-06-30T20:35:31.081Z] ====== philosophers (scala) [default], iteration 29 completed (3433.184 ms) ====== [2025-06-30T20:35:31.081Z] ----------------------------------- [2025-06-30T20:35:31.081Z] renaissance-philosophers_0_PASSED [2025-06-30T20:35:31.081Z] ----------------------------------- [2025-06-30T20:35:31.081Z] [2025-06-30T20:35:31.081Z] TEST TEARDOWN: [2025-06-30T20:35:31.081Z] Nothing to be done for teardown. [2025-06-30T20:35:31.081Z] renaissance-philosophers_0 Finish Time: Mon Jun 30 20:35:30 2025 Epoch Time (ms): 1751315730542