[2025-12-11T23:17:34.484Z] Running test renaissance-philosophers_0 ... [2025-12-11T23:17:34.484Z] =============================================== [2025-12-11T23:17:34.484Z] renaissance-philosophers_0 Start Time: Thu Dec 11 23:17:33 2025 Epoch Time (ms): 1765495053950 [2025-12-11T23:17:34.484Z] variation: NoOptions [2025-12-11T23:17:34.484Z] JVM_OPTIONS: [2025-12-11T23:17:34.484Z] { \ [2025-12-11T23:17:34.484Z] echo ""; echo "TEST SETUP:"; \ [2025-12-11T23:17:34.484Z] echo "Nothing to be done for setup."; \ [2025-12-11T23:17:34.484Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17654926795646/renaissance-philosophers_0"; \ [2025-12-11T23:17:34.484Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17654926795646/renaissance-philosophers_0"; \ [2025-12-11T23:17:34.484Z] echo ""; echo "TESTING:"; \ [2025-12-11T23:17:34.484Z] "/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_17654926795646/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-11T23:17:34.484Z] 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_17654926795646/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-11T23:17:34.484Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-11T23:17:34.484Z] echo "Nothing to be done for teardown."; \ [2025-12-11T23:17:34.484Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17654926795646/TestTargetResult"; [2025-12-11T23:17:34.484Z] [2025-12-11T23:17:34.484Z] TEST SETUP: [2025-12-11T23:17:34.484Z] Nothing to be done for setup. [2025-12-11T23:17:34.484Z] [2025-12-11T23:17:34.484Z] TESTING: [2025-12-11T23:17:34.781Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-11T23:17:34.781Z] GC before operation: completed in 13.416 ms, heap usage 15.575 MB -> 4.643 MB. [2025-12-11T23:17:36.929Z] Camera thread performed 122 scans. [2025-12-11T23:17:36.929Z] ====== philosophers (scala) [default], iteration 0 completed (2020.386 ms) ====== [2025-12-11T23:17:36.929Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-11T23:17:36.929Z] GC before operation: completed in 12.385 ms, heap usage 17.130 MB -> 5.001 MB. [2025-12-11T23:17:37.988Z] Camera thread performed 122 scans. [2025-12-11T23:17:37.988Z] ====== philosophers (scala) [default], iteration 1 completed (1259.560 ms) ====== [2025-12-11T23:17:37.988Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-11T23:17:37.988Z] GC before operation: completed in 11.313 ms, heap usage 36.615 MB -> 5.001 MB. [2025-12-11T23:17:39.042Z] Camera thread performed 122 scans. [2025-12-11T23:17:39.042Z] ====== philosophers (scala) [default], iteration 2 completed (812.765 ms) ====== [2025-12-11T23:17:39.042Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-11T23:17:39.042Z] GC before operation: completed in 8.688 ms, heap usage 19.729 MB -> 5.001 MB. [2025-12-11T23:17:39.695Z] Camera thread performed 122 scans. [2025-12-11T23:17:39.695Z] ====== philosophers (scala) [default], iteration 3 completed (803.033 ms) ====== [2025-12-11T23:17:39.695Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-11T23:17:39.695Z] GC before operation: completed in 9.601 ms, heap usage 44.784 MB -> 5.002 MB. [2025-12-11T23:17:40.748Z] Camera thread performed 122 scans. [2025-12-11T23:17:40.748Z] ====== philosophers (scala) [default], iteration 4 completed (816.165 ms) ====== [2025-12-11T23:17:40.748Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-11T23:17:40.748Z] GC before operation: completed in 9.293 ms, heap usage 43.682 MB -> 5.001 MB. [2025-12-11T23:17:41.395Z] Camera thread performed 122 scans. [2025-12-11T23:17:41.395Z] ====== philosophers (scala) [default], iteration 5 completed (839.929 ms) ====== [2025-12-11T23:17:41.395Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-11T23:17:41.395Z] GC before operation: completed in 10.114 ms, heap usage 6.134 MB -> 5.002 MB. [2025-12-11T23:17:42.449Z] Camera thread performed 122 scans. [2025-12-11T23:17:42.449Z] ====== philosophers (scala) [default], iteration 6 completed (780.792 ms) ====== [2025-12-11T23:17:42.449Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-11T23:17:42.449Z] GC before operation: completed in 10.378 ms, heap usage 23.612 MB -> 5.002 MB. [2025-12-11T23:17:43.094Z] Camera thread performed 122 scans. [2025-12-11T23:17:43.094Z] ====== philosophers (scala) [default], iteration 7 completed (883.472 ms) ====== [2025-12-11T23:17:43.094Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-11T23:17:43.094Z] GC before operation: completed in 12.458 ms, heap usage 41.141 MB -> 5.002 MB. [2025-12-11T23:17:44.149Z] Camera thread performed 122 scans. [2025-12-11T23:17:44.149Z] ====== philosophers (scala) [default], iteration 8 completed (856.809 ms) ====== [2025-12-11T23:17:44.149Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-11T23:17:44.149Z] GC before operation: completed in 9.655 ms, heap usage 10.603 MB -> 5.002 MB. [2025-12-11T23:17:44.794Z] Camera thread performed 122 scans. [2025-12-11T23:17:44.794Z] ====== philosophers (scala) [default], iteration 9 completed (765.089 ms) ====== [2025-12-11T23:17:44.794Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-11T23:17:44.794Z] GC before operation: completed in 11.262 ms, heap usage 59.587 MB -> 5.002 MB. [2025-12-11T23:17:45.443Z] Camera thread performed 122 scans. [2025-12-11T23:17:45.443Z] ====== philosophers (scala) [default], iteration 10 completed (813.286 ms) ====== [2025-12-11T23:17:45.443Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-11T23:17:45.443Z] GC before operation: completed in 9.984 ms, heap usage 7.102 MB -> 5.003 MB. [2025-12-11T23:17:46.502Z] Camera thread performed 122 scans. [2025-12-11T23:17:46.502Z] ====== philosophers (scala) [default], iteration 11 completed (887.055 ms) ====== [2025-12-11T23:17:46.502Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-11T23:17:46.502Z] GC before operation: completed in 10.098 ms, heap usage 50.130 MB -> 5.003 MB. [2025-12-11T23:17:47.556Z] Camera thread performed 122 scans. [2025-12-11T23:17:47.556Z] ====== philosophers (scala) [default], iteration 12 completed (879.264 ms) ====== [2025-12-11T23:17:47.556Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-11T23:17:47.556Z] GC before operation: completed in 12.214 ms, heap usage 40.131 MB -> 5.003 MB. [2025-12-11T23:17:48.202Z] Camera thread performed 122 scans. [2025-12-11T23:17:48.202Z] ====== philosophers (scala) [default], iteration 13 completed (880.177 ms) ====== [2025-12-11T23:17:48.202Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-11T23:17:48.202Z] GC before operation: completed in 10.531 ms, heap usage 39.128 MB -> 5.003 MB. [2025-12-11T23:17:49.259Z] Camera thread performed 122 scans. [2025-12-11T23:17:49.259Z] ====== philosophers (scala) [default], iteration 14 completed (863.178 ms) ====== [2025-12-11T23:17:49.259Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-11T23:17:49.259Z] GC before operation: completed in 10.550 ms, heap usage 48.625 MB -> 5.004 MB. [2025-12-11T23:17:49.904Z] Camera thread performed 122 scans. [2025-12-11T23:17:49.904Z] ====== philosophers (scala) [default], iteration 15 completed (879.673 ms) ====== [2025-12-11T23:17:49.904Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-11T23:17:49.904Z] GC before operation: completed in 11.249 ms, heap usage 29.120 MB -> 5.004 MB. [2025-12-11T23:17:50.958Z] Camera thread performed 122 scans. [2025-12-11T23:17:50.958Z] ====== philosophers (scala) [default], iteration 16 completed (802.021 ms) ====== [2025-12-11T23:17:50.958Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-11T23:17:50.958Z] GC before operation: completed in 10.755 ms, heap usage 37.629 MB -> 5.004 MB. [2025-12-11T23:17:51.603Z] Camera thread performed 122 scans. [2025-12-11T23:17:51.603Z] ====== philosophers (scala) [default], iteration 17 completed (881.750 ms) ====== [2025-12-11T23:17:51.603Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-11T23:17:51.603Z] GC before operation: completed in 12.852 ms, heap usage 32.131 MB -> 5.004 MB. [2025-12-11T23:17:52.718Z] Camera thread performed 122 scans. [2025-12-11T23:17:52.719Z] ====== philosophers (scala) [default], iteration 18 completed (890.434 ms) ====== [2025-12-11T23:17:52.719Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-11T23:17:52.719Z] GC before operation: completed in 10.718 ms, heap usage 43.130 MB -> 5.005 MB. [2025-12-11T23:17:53.365Z] Camera thread performed 122 scans. [2025-12-11T23:17:53.365Z] ====== philosophers (scala) [default], iteration 19 completed (860.765 ms) ====== [2025-12-11T23:17:53.365Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-11T23:17:53.662Z] GC before operation: completed in 10.159 ms, heap usage 21.104 MB -> 5.005 MB. [2025-12-11T23:17:54.309Z] Camera thread performed 122 scans. [2025-12-11T23:17:54.309Z] ====== philosophers (scala) [default], iteration 20 completed (868.305 ms) ====== [2025-12-11T23:17:54.309Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-11T23:17:54.309Z] GC before operation: completed in 10.197 ms, heap usage 55.630 MB -> 5.005 MB. [2025-12-11T23:17:55.359Z] Camera thread performed 122 scans. [2025-12-11T23:17:55.359Z] ====== philosophers (scala) [default], iteration 21 completed (894.224 ms) ====== [2025-12-11T23:17:55.359Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-11T23:17:55.359Z] GC before operation: completed in 10.751 ms, heap usage 41.134 MB -> 5.005 MB. [2025-12-11T23:17:56.416Z] Camera thread performed 122 scans. [2025-12-11T23:17:56.416Z] ====== philosophers (scala) [default], iteration 22 completed (893.713 ms) ====== [2025-12-11T23:17:56.416Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-11T23:17:56.416Z] GC before operation: completed in 11.401 ms, heap usage 44.630 MB -> 5.005 MB. [2025-12-11T23:17:57.059Z] Camera thread performed 122 scans. [2025-12-11T23:17:57.059Z] ====== philosophers (scala) [default], iteration 23 completed (885.147 ms) ====== [2025-12-11T23:17:57.059Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-11T23:17:57.059Z] GC before operation: completed in 11.349 ms, heap usage 65.632 MB -> 5.005 MB. [2025-12-11T23:17:58.111Z] Camera thread performed 122 scans. [2025-12-11T23:17:58.111Z] ====== philosophers (scala) [default], iteration 24 completed (895.589 ms) ====== [2025-12-11T23:17:58.111Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-11T23:17:58.111Z] GC before operation: completed in 11.529 ms, heap usage 65.134 MB -> 5.005 MB. [2025-12-11T23:17:59.164Z] Camera thread performed 122 scans. [2025-12-11T23:17:59.164Z] ====== philosophers (scala) [default], iteration 25 completed (894.410 ms) ====== [2025-12-11T23:17:59.164Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-11T23:17:59.164Z] GC before operation: completed in 12.719 ms, heap usage 6.103 MB -> 5.005 MB. [2025-12-11T23:17:59.808Z] Camera thread performed 122 scans. [2025-12-11T23:17:59.808Z] ====== philosophers (scala) [default], iteration 26 completed (873.020 ms) ====== [2025-12-11T23:17:59.808Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-11T23:17:59.808Z] GC before operation: completed in 11.382 ms, heap usage 22.130 MB -> 5.005 MB. [2025-12-11T23:18:00.861Z] Camera thread performed 122 scans. [2025-12-11T23:18:00.861Z] ====== philosophers (scala) [default], iteration 27 completed (885.204 ms) ====== [2025-12-11T23:18:00.861Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-11T23:18:00.861Z] GC before operation: completed in 12.627 ms, heap usage 31.133 MB -> 5.005 MB. [2025-12-11T23:18:01.515Z] Camera thread performed 122 scans. [2025-12-11T23:18:01.515Z] ====== philosophers (scala) [default], iteration 28 completed (875.906 ms) ====== [2025-12-11T23:18:01.515Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-11T23:18:01.515Z] GC before operation: completed in 12.406 ms, heap usage 22.632 MB -> 5.006 MB. [2025-12-11T23:18:02.567Z] Camera thread performed 122 scans. [2025-12-11T23:18:02.567Z] ====== philosophers (scala) [default], iteration 29 completed (820.828 ms) ====== [2025-12-11T23:18:02.567Z] ----------------------------------- [2025-12-11T23:18:02.567Z] renaissance-philosophers_0_PASSED [2025-12-11T23:18:02.567Z] ----------------------------------- [2025-12-11T23:18:02.567Z] [2025-12-11T23:18:02.567Z] TEST TEARDOWN: [2025-12-11T23:18:02.567Z] Nothing to be done for teardown. [2025-12-11T23:18:02.567Z] renaissance-philosophers_0 Finish Time: Thu Dec 11 23:18:02 2025 Epoch Time (ms): 1765495082478