[2025-11-12T23:42:14.441Z] Running test renaissance-philosophers_0 ... [2025-11-12T23:42:14.441Z] =============================================== [2025-11-12T23:42:14.441Z] renaissance-philosophers_0 Start Time: Wed Nov 12 23:42:13 2025 Epoch Time (ms): 1762990933746 [2025-11-12T23:42:14.441Z] variation: NoOptions [2025-11-12T23:42:14.441Z] JVM_OPTIONS: [2025-11-12T23:42:14.441Z] { \ [2025-11-12T23:42:14.441Z] echo ""; echo "TEST SETUP:"; \ [2025-11-12T23:42:14.441Z] echo "Nothing to be done for setup."; \ [2025-11-12T23:42:14.441Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629880619860/renaissance-philosophers_0"; \ [2025-11-12T23:42:14.441Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629880619860/renaissance-philosophers_0"; \ [2025-11-12T23:42:14.441Z] echo ""; echo "TESTING:"; \ [2025-11-12T23:42:14.441Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629880619860/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-12T23:42:14.441Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629880619860/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-12T23:42:14.441Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-12T23:42:14.441Z] echo "Nothing to be done for teardown."; \ [2025-11-12T23:42:14.441Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629880619860/TestTargetResult"; [2025-11-12T23:42:14.441Z] [2025-11-12T23:42:14.441Z] TEST SETUP: [2025-11-12T23:42:14.441Z] Nothing to be done for setup. [2025-11-12T23:42:14.441Z] [2025-11-12T23:42:14.441Z] TESTING: [2025-11-12T23:42:14.776Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-12T23:42:14.776Z] GC before operation: completed in 15.789 ms, heap usage 16.174 MB -> 4.659 MB. [2025-11-12T23:42:17.127Z] Camera thread performed 122 scans. [2025-11-12T23:42:17.127Z] ====== philosophers (scala) [default], iteration 0 completed (2235.695 ms) ====== [2025-11-12T23:42:17.127Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-12T23:42:17.127Z] GC before operation: completed in 12.098 ms, heap usage 21.710 MB -> 5.007 MB. [2025-11-12T23:42:18.285Z] Camera thread performed 122 scans. [2025-11-12T23:42:18.285Z] ====== philosophers (scala) [default], iteration 1 completed (1139.973 ms) ====== [2025-11-12T23:42:18.285Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-12T23:42:18.285Z] GC before operation: completed in 10.387 ms, heap usage 17.945 MB -> 5.007 MB. [2025-11-12T23:42:19.070Z] Camera thread performed 122 scans. [2025-11-12T23:42:19.070Z] ====== philosophers (scala) [default], iteration 2 completed (928.495 ms) ====== [2025-11-12T23:42:19.070Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-12T23:42:19.070Z] GC before operation: completed in 11.702 ms, heap usage 45.118 MB -> 5.008 MB. [2025-11-12T23:42:20.233Z] Camera thread performed 122 scans. [2025-11-12T23:42:20.233Z] ====== philosophers (scala) [default], iteration 3 completed (1028.564 ms) ====== [2025-11-12T23:42:20.233Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-12T23:42:20.233Z] GC before operation: completed in 11.302 ms, heap usage 49.293 MB -> 5.008 MB. [2025-11-12T23:42:21.424Z] Camera thread performed 122 scans. [2025-11-12T23:42:21.424Z] ====== philosophers (scala) [default], iteration 4 completed (1073.771 ms) ====== [2025-11-12T23:42:21.424Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-12T23:42:21.424Z] GC before operation: completed in 11.212 ms, heap usage 8.664 MB -> 5.008 MB. [2025-11-12T23:42:22.160Z] Camera thread performed 122 scans. [2025-11-12T23:42:22.160Z] ====== philosophers (scala) [default], iteration 5 completed (1088.028 ms) ====== [2025-11-12T23:42:22.160Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-12T23:42:22.160Z] GC before operation: completed in 12.476 ms, heap usage 24.677 MB -> 5.008 MB. [2025-11-12T23:42:23.337Z] Camera thread performed 122 scans. [2025-11-12T23:42:23.337Z] ====== philosophers (scala) [default], iteration 6 completed (1083.461 ms) ====== [2025-11-12T23:42:23.337Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-12T23:42:23.337Z] GC before operation: completed in 12.205 ms, heap usage 30.657 MB -> 5.008 MB. [2025-11-12T23:42:24.495Z] Camera thread performed 122 scans. [2025-11-12T23:42:24.495Z] ====== philosophers (scala) [default], iteration 7 completed (1070.533 ms) ====== [2025-11-12T23:42:24.495Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-12T23:42:24.495Z] GC before operation: completed in 12.728 ms, heap usage 29.144 MB -> 5.008 MB. [2025-11-12T23:42:25.706Z] Camera thread performed 122 scans. [2025-11-12T23:42:25.706Z] ====== philosophers (scala) [default], iteration 8 completed (1123.659 ms) ====== [2025-11-12T23:42:25.706Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-12T23:42:25.706Z] GC before operation: completed in 12.495 ms, heap usage 23.142 MB -> 5.008 MB. [2025-11-12T23:42:26.868Z] Camera thread performed 122 scans. [2025-11-12T23:42:26.868Z] ====== philosophers (scala) [default], iteration 9 completed (1107.312 ms) ====== [2025-11-12T23:42:26.868Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-12T23:42:26.868Z] GC before operation: completed in 14.159 ms, heap usage 20.639 MB -> 5.009 MB. [2025-11-12T23:42:28.022Z] Camera thread performed 122 scans. [2025-11-12T23:42:28.023Z] ====== philosophers (scala) [default], iteration 10 completed (1069.584 ms) ====== [2025-11-12T23:42:28.023Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-12T23:42:28.023Z] GC before operation: completed in 12.516 ms, heap usage 18.637 MB -> 5.009 MB. [2025-11-12T23:42:28.737Z] Camera thread performed 122 scans. [2025-11-12T23:42:28.737Z] ====== philosophers (scala) [default], iteration 11 completed (1122.594 ms) ====== [2025-11-12T23:42:28.737Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-12T23:42:29.069Z] GC before operation: completed in 12.599 ms, heap usage 52.632 MB -> 5.009 MB. [2025-11-12T23:42:30.223Z] Camera thread performed 122 scans. [2025-11-12T23:42:30.223Z] ====== philosophers (scala) [default], iteration 12 completed (1122.451 ms) ====== [2025-11-12T23:42:30.223Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-12T23:42:30.223Z] GC before operation: completed in 13.204 ms, heap usage 60.636 MB -> 5.009 MB. [2025-11-12T23:42:31.377Z] Camera thread performed 122 scans. [2025-11-12T23:42:31.377Z] ====== philosophers (scala) [default], iteration 13 completed (1127.717 ms) ====== [2025-11-12T23:42:31.377Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-12T23:42:31.377Z] GC before operation: completed in 12.816 ms, heap usage 53.133 MB -> 5.009 MB. [2025-11-12T23:42:32.604Z] Camera thread performed 122 scans. [2025-11-12T23:42:32.604Z] ====== philosophers (scala) [default], iteration 14 completed (1106.271 ms) ====== [2025-11-12T23:42:32.604Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-12T23:42:32.604Z] GC before operation: completed in 12.875 ms, heap usage 6.109 MB -> 5.010 MB. [2025-11-12T23:42:33.315Z] Camera thread performed 122 scans. [2025-11-12T23:42:33.315Z] ====== philosophers (scala) [default], iteration 15 completed (1123.407 ms) ====== [2025-11-12T23:42:33.315Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-12T23:42:33.315Z] GC before operation: completed in 13.124 ms, heap usage 60.637 MB -> 5.010 MB. [2025-11-12T23:42:34.473Z] Camera thread performed 122 scans. [2025-11-12T23:42:34.473Z] ====== philosophers (scala) [default], iteration 16 completed (1057.606 ms) ====== [2025-11-12T23:42:34.473Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-12T23:42:34.473Z] GC before operation: completed in 13.536 ms, heap usage 21.635 MB -> 5.011 MB. [2025-11-12T23:42:35.626Z] Camera thread performed 122 scans. [2025-11-12T23:42:35.626Z] ====== philosophers (scala) [default], iteration 17 completed (1127.195 ms) ====== [2025-11-12T23:42:35.626Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-12T23:42:35.626Z] GC before operation: completed in 12.361 ms, heap usage 59.638 MB -> 5.011 MB. [2025-11-12T23:42:36.792Z] Camera thread performed 122 scans. [2025-11-12T23:42:36.792Z] ====== philosophers (scala) [default], iteration 18 completed (1130.159 ms) ====== [2025-11-12T23:42:36.792Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-12T23:42:36.792Z] GC before operation: completed in 12.326 ms, heap usage 57.636 MB -> 5.011 MB. [2025-11-12T23:42:37.954Z] Camera thread performed 122 scans. [2025-11-12T23:42:37.954Z] ====== philosophers (scala) [default], iteration 19 completed (1116.776 ms) ====== [2025-11-12T23:42:37.954Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-12T23:42:37.954Z] GC before operation: completed in 12.055 ms, heap usage 21.641 MB -> 5.010 MB. [2025-11-12T23:42:38.920Z] Camera thread performed 122 scans. [2025-11-12T23:42:38.920Z] ====== philosophers (scala) [default], iteration 20 completed (1106.116 ms) ====== [2025-11-12T23:42:38.920Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-12T23:42:38.920Z] GC before operation: completed in 13.698 ms, heap usage 37.140 MB -> 5.033 MB. [2025-11-12T23:42:40.081Z] Camera thread performed 122 scans. [2025-11-12T23:42:40.081Z] ====== philosophers (scala) [default], iteration 21 completed (1138.873 ms) ====== [2025-11-12T23:42:40.081Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-12T23:42:40.081Z] GC before operation: completed in 13.049 ms, heap usage 29.161 MB -> 5.011 MB. [2025-11-12T23:42:41.254Z] Camera thread performed 122 scans. [2025-11-12T23:42:41.254Z] ====== philosophers (scala) [default], iteration 22 completed (1129.582 ms) ====== [2025-11-12T23:42:41.254Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-12T23:42:41.254Z] GC before operation: completed in 12.733 ms, heap usage 40.141 MB -> 5.011 MB. [2025-11-12T23:42:42.426Z] Camera thread performed 122 scans. [2025-11-12T23:42:42.426Z] ====== philosophers (scala) [default], iteration 23 completed (1126.330 ms) ====== [2025-11-12T23:42:42.426Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-12T23:42:42.426Z] GC before operation: completed in 12.695 ms, heap usage 35.138 MB -> 5.011 MB. [2025-11-12T23:42:43.649Z] Camera thread performed 122 scans. [2025-11-12T23:42:43.649Z] ====== philosophers (scala) [default], iteration 24 completed (1129.049 ms) ====== [2025-11-12T23:42:43.649Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-12T23:42:43.649Z] GC before operation: completed in 12.838 ms, heap usage 48.143 MB -> 5.011 MB. [2025-11-12T23:42:44.814Z] Camera thread performed 122 scans. [2025-11-12T23:42:44.814Z] ====== philosophers (scala) [default], iteration 25 completed (1117.825 ms) ====== [2025-11-12T23:42:44.814Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-12T23:42:44.814Z] GC before operation: completed in 13.718 ms, heap usage 32.139 MB -> 5.011 MB. [2025-11-12T23:42:45.983Z] Camera thread performed 122 scans. [2025-11-12T23:42:45.983Z] ====== philosophers (scala) [default], iteration 26 completed (1123.064 ms) ====== [2025-11-12T23:42:45.983Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-12T23:42:45.983Z] GC before operation: completed in 13.587 ms, heap usage 10.113 MB -> 5.012 MB. [2025-11-12T23:42:47.146Z] Camera thread performed 122 scans. [2025-11-12T23:42:47.146Z] ====== philosophers (scala) [default], iteration 27 completed (1115.155 ms) ====== [2025-11-12T23:42:47.146Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-12T23:42:47.146Z] GC before operation: completed in 11.936 ms, heap usage 14.140 MB -> 5.012 MB. [2025-11-12T23:42:48.353Z] Camera thread performed 122 scans. [2025-11-12T23:42:48.353Z] ====== philosophers (scala) [default], iteration 28 completed (1112.682 ms) ====== [2025-11-12T23:42:48.353Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-12T23:42:48.353Z] GC before operation: completed in 12.545 ms, heap usage 61.136 MB -> 5.012 MB. [2025-11-12T23:42:49.072Z] Camera thread performed 122 scans. [2025-11-12T23:42:49.072Z] ====== philosophers (scala) [default], iteration 29 completed (1069.321 ms) ====== [2025-11-12T23:42:49.407Z] ----------------------------------- [2025-11-12T23:42:49.407Z] renaissance-philosophers_0_PASSED [2025-11-12T23:42:49.407Z] ----------------------------------- [2025-11-12T23:42:49.407Z] [2025-11-12T23:42:49.407Z] TEST TEARDOWN: [2025-11-12T23:42:49.407Z] Nothing to be done for teardown. [2025-11-12T23:42:49.407Z] renaissance-philosophers_0 Finish Time: Wed Nov 12 23:42:49 2025 Epoch Time (ms): 1762990969261