[2025-11-19T23:29:32.891Z] Running test renaissance-philosophers_0 ... [2025-11-19T23:29:32.891Z] =============================================== [2025-11-19T23:29:32.891Z] renaissance-philosophers_0 Start Time: Wed Nov 19 23:29:32 2025 Epoch Time (ms): 1763594972700 [2025-11-19T23:29:32.891Z] variation: NoOptions [2025-11-19T23:29:32.891Z] JVM_OPTIONS: [2025-11-19T23:29:32.891Z] { \ [2025-11-19T23:29:32.891Z] echo ""; echo "TEST SETUP:"; \ [2025-11-19T23:29:32.891Z] echo "Nothing to be done for setup."; \ [2025-11-19T23:29:32.891Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17635928547027/renaissance-philosophers_0"; \ [2025-11-19T23:29:32.891Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17635928547027/renaissance-philosophers_0"; \ [2025-11-19T23:29:32.891Z] echo ""; echo "TESTING:"; \ [2025-11-19T23:29:32.891Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17635928547027/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-19T23:29:32.891Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17635928547027/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-19T23:29:32.891Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-19T23:29:32.891Z] echo "Nothing to be done for teardown."; \ [2025-11-19T23:29:32.891Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17635928547027/TestTargetResult"; [2025-11-19T23:29:32.891Z] [2025-11-19T23:29:32.891Z] TEST SETUP: [2025-11-19T23:29:32.891Z] Nothing to be done for setup. [2025-11-19T23:29:32.891Z] [2025-11-19T23:29:32.891Z] TESTING: [2025-11-19T23:29:33.599Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-19T23:29:33.599Z] GC before operation: completed in 21.546 ms, heap usage 15.761 MB -> 4.664 MB. [2025-11-19T23:29:35.849Z] Camera thread performed 122 scans. [2025-11-19T23:29:35.849Z] ====== philosophers (scala) [default], iteration 0 completed (1945.013 ms) ====== [2025-11-19T23:29:35.849Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-19T23:29:35.849Z] GC before operation: completed in 12.389 ms, heap usage 32.154 MB -> 5.030 MB. [2025-11-19T23:29:36.557Z] Camera thread performed 122 scans. [2025-11-19T23:29:36.557Z] ====== philosophers (scala) [default], iteration 1 completed (930.203 ms) ====== [2025-11-19T23:29:36.557Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-19T23:29:36.557Z] GC before operation: completed in 24.401 ms, heap usage 11.814 MB -> 5.031 MB. [2025-11-19T23:29:37.263Z] Camera thread performed 122 scans. [2025-11-19T23:29:37.263Z] ====== philosophers (scala) [default], iteration 2 completed (716.683 ms) ====== [2025-11-19T23:29:37.263Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-19T23:29:37.263Z] GC before operation: completed in 11.121 ms, heap usage 42.112 MB -> 5.032 MB. [2025-11-19T23:29:37.969Z] Camera thread performed 122 scans. [2025-11-19T23:29:37.969Z] ====== philosophers (scala) [default], iteration 3 completed (771.627 ms) ====== [2025-11-19T23:29:37.969Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-19T23:29:37.969Z] GC before operation: completed in 11.350 ms, heap usage 38.139 MB -> 5.032 MB. [2025-11-19T23:29:39.116Z] Camera thread performed 122 scans. [2025-11-19T23:29:39.116Z] ====== philosophers (scala) [default], iteration 4 completed (784.187 ms) ====== [2025-11-19T23:29:39.116Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-19T23:29:39.116Z] GC before operation: completed in 11.242 ms, heap usage 52.699 MB -> 5.032 MB. [2025-11-19T23:29:39.822Z] Camera thread performed 122 scans. [2025-11-19T23:29:39.822Z] ====== philosophers (scala) [default], iteration 5 completed (783.463 ms) ====== [2025-11-19T23:29:39.822Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-19T23:29:39.822Z] GC before operation: completed in 10.717 ms, heap usage 35.129 MB -> 5.032 MB. [2025-11-19T23:29:40.528Z] Camera thread performed 122 scans. [2025-11-19T23:29:40.528Z] ====== philosophers (scala) [default], iteration 6 completed (782.200 ms) ====== [2025-11-19T23:29:40.528Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-19T23:29:40.528Z] GC before operation: completed in 10.127 ms, heap usage 10.632 MB -> 5.033 MB. [2025-11-19T23:29:41.236Z] Camera thread performed 122 scans. [2025-11-19T23:29:41.236Z] ====== philosophers (scala) [default], iteration 7 completed (777.800 ms) ====== [2025-11-19T23:29:41.236Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-19T23:29:41.236Z] GC before operation: completed in 11.300 ms, heap usage 20.161 MB -> 5.032 MB. [2025-11-19T23:29:41.941Z] Camera thread performed 122 scans. [2025-11-19T23:29:41.941Z] ====== philosophers (scala) [default], iteration 8 completed (767.411 ms) ====== [2025-11-19T23:29:41.941Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-19T23:29:41.941Z] GC before operation: completed in 12.195 ms, heap usage 35.156 MB -> 5.033 MB. [2025-11-19T23:29:42.648Z] Camera thread performed 122 scans. [2025-11-19T23:29:42.978Z] ====== philosophers (scala) [default], iteration 9 completed (792.083 ms) ====== [2025-11-19T23:29:42.978Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-19T23:29:42.978Z] GC before operation: completed in 11.364 ms, heap usage 15.647 MB -> 5.033 MB. [2025-11-19T23:29:43.683Z] Camera thread performed 122 scans. [2025-11-19T23:29:43.683Z] ====== philosophers (scala) [default], iteration 10 completed (774.216 ms) ====== [2025-11-19T23:29:43.683Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-19T23:29:43.683Z] GC before operation: completed in 10.543 ms, heap usage 27.656 MB -> 5.033 MB. [2025-11-19T23:29:44.387Z] Camera thread performed 122 scans. [2025-11-19T23:29:44.387Z] ====== philosophers (scala) [default], iteration 11 completed (780.611 ms) ====== [2025-11-19T23:29:44.387Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-19T23:29:44.387Z] GC before operation: completed in 10.249 ms, heap usage 25.654 MB -> 5.033 MB. [2025-11-19T23:29:45.095Z] Camera thread performed 122 scans. [2025-11-19T23:29:45.095Z] ====== philosophers (scala) [default], iteration 12 completed (770.520 ms) ====== [2025-11-19T23:29:45.095Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-19T23:29:45.095Z] GC before operation: completed in 11.423 ms, heap usage 44.654 MB -> 5.034 MB. [2025-11-19T23:29:45.897Z] Camera thread performed 122 scans. [2025-11-19T23:29:45.897Z] ====== philosophers (scala) [default], iteration 13 completed (753.532 ms) ====== [2025-11-19T23:29:45.897Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-19T23:29:45.897Z] GC before operation: completed in 12.498 ms, heap usage 48.149 MB -> 5.033 MB. [2025-11-19T23:29:46.601Z] Camera thread performed 122 scans. [2025-11-19T23:29:46.601Z] ====== philosophers (scala) [default], iteration 14 completed (752.637 ms) ====== [2025-11-19T23:29:46.601Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-19T23:29:46.601Z] GC before operation: completed in 11.261 ms, heap usage 11.127 MB -> 5.034 MB. [2025-11-19T23:29:47.743Z] Camera thread performed 122 scans. [2025-11-19T23:29:47.743Z] ====== philosophers (scala) [default], iteration 15 completed (755.828 ms) ====== [2025-11-19T23:29:47.743Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-19T23:29:47.743Z] GC before operation: completed in 13.738 ms, heap usage 50.154 MB -> 5.034 MB. [2025-11-19T23:29:48.461Z] Camera thread performed 122 scans. [2025-11-19T23:29:48.461Z] ====== philosophers (scala) [default], iteration 16 completed (754.206 ms) ====== [2025-11-19T23:29:48.461Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-19T23:29:48.461Z] GC before operation: completed in 11.703 ms, heap usage 43.155 MB -> 5.035 MB. [2025-11-19T23:29:49.165Z] Camera thread performed 122 scans. [2025-11-19T23:29:49.165Z] ====== philosophers (scala) [default], iteration 17 completed (762.211 ms) ====== [2025-11-19T23:29:49.165Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-19T23:29:49.165Z] GC before operation: completed in 11.959 ms, heap usage 13.162 MB -> 5.035 MB. [2025-11-19T23:29:49.870Z] Camera thread performed 122 scans. [2025-11-19T23:29:49.870Z] ====== philosophers (scala) [default], iteration 18 completed (749.142 ms) ====== [2025-11-19T23:29:49.870Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-19T23:29:49.870Z] GC before operation: completed in 13.478 ms, heap usage 30.147 MB -> 5.035 MB. [2025-11-19T23:29:50.575Z] Camera thread performed 122 scans. [2025-11-19T23:29:50.575Z] ====== philosophers (scala) [default], iteration 19 completed (771.247 ms) ====== [2025-11-19T23:29:50.575Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-19T23:29:50.575Z] GC before operation: completed in 11.444 ms, heap usage 46.655 MB -> 5.035 MB. [2025-11-19T23:29:51.283Z] Camera thread performed 122 scans. [2025-11-19T23:29:51.283Z] ====== philosophers (scala) [default], iteration 20 completed (769.777 ms) ====== [2025-11-19T23:29:51.283Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-19T23:29:51.283Z] GC before operation: completed in 24.921 ms, heap usage 12.661 MB -> 5.036 MB. [2025-11-19T23:29:51.988Z] Camera thread performed 122 scans. [2025-11-19T23:29:51.988Z] ====== philosophers (scala) [default], iteration 21 completed (759.105 ms) ====== [2025-11-19T23:29:51.988Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-19T23:29:51.988Z] GC before operation: completed in 11.882 ms, heap usage 28.654 MB -> 5.036 MB. [2025-11-19T23:29:53.128Z] Camera thread performed 122 scans. [2025-11-19T23:29:53.128Z] ====== philosophers (scala) [default], iteration 22 completed (762.581 ms) ====== [2025-11-19T23:29:53.128Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-19T23:29:53.128Z] GC before operation: completed in 11.157 ms, heap usage 24.653 MB -> 5.036 MB. [2025-11-19T23:29:53.831Z] Camera thread performed 122 scans. [2025-11-19T23:29:53.831Z] ====== philosophers (scala) [default], iteration 23 completed (758.339 ms) ====== [2025-11-19T23:29:53.831Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-19T23:29:53.831Z] GC before operation: completed in 11.259 ms, heap usage 11.656 MB -> 5.036 MB. [2025-11-19T23:29:54.534Z] Camera thread performed 122 scans. [2025-11-19T23:29:54.534Z] ====== philosophers (scala) [default], iteration 24 completed (765.039 ms) ====== [2025-11-19T23:29:54.534Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-19T23:29:54.534Z] GC before operation: completed in 11.085 ms, heap usage 16.646 MB -> 5.036 MB. [2025-11-19T23:29:55.237Z] Camera thread performed 122 scans. [2025-11-19T23:29:55.237Z] ====== philosophers (scala) [default], iteration 25 completed (759.354 ms) ====== [2025-11-19T23:29:55.237Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-19T23:29:55.237Z] GC before operation: completed in 12.317 ms, heap usage 51.157 MB -> 5.036 MB. [2025-11-19T23:29:55.940Z] Camera thread performed 122 scans. [2025-11-19T23:29:55.940Z] ====== philosophers (scala) [default], iteration 26 completed (755.971 ms) ====== [2025-11-19T23:29:55.940Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-19T23:29:55.940Z] GC before operation: completed in 13.238 ms, heap usage 64.662 MB -> 5.036 MB. [2025-11-19T23:29:56.643Z] Camera thread performed 122 scans. [2025-11-19T23:29:56.643Z] ====== philosophers (scala) [default], iteration 27 completed (767.074 ms) ====== [2025-11-19T23:29:56.643Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-19T23:29:56.643Z] GC before operation: completed in 11.218 ms, heap usage 59.657 MB -> 5.036 MB. [2025-11-19T23:29:57.784Z] Camera thread performed 122 scans. [2025-11-19T23:29:57.784Z] ====== philosophers (scala) [default], iteration 28 completed (763.586 ms) ====== [2025-11-19T23:29:57.784Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-19T23:29:57.784Z] GC before operation: completed in 21.990 ms, heap usage 27.151 MB -> 5.036 MB. [2025-11-19T23:29:58.487Z] Camera thread performed 122 scans. [2025-11-19T23:29:58.487Z] ====== philosophers (scala) [default], iteration 29 completed (734.441 ms) ====== [2025-11-19T23:29:58.487Z] ----------------------------------- [2025-11-19T23:29:58.487Z] renaissance-philosophers_0_PASSED [2025-11-19T23:29:58.487Z] ----------------------------------- [2025-11-19T23:29:58.487Z] [2025-11-19T23:29:58.487Z] TEST TEARDOWN: [2025-11-19T23:29:58.487Z] Nothing to be done for teardown. [2025-11-19T23:29:58.487Z] renaissance-philosophers_0 Finish Time: Wed Nov 19 23:29:58 2025 Epoch Time (ms): 1763594998339