[2025-09-26T14:07:16.367Z] Running test renaissance-philosophers_0 ... [2025-09-26T14:07:16.367Z] =============================================== [2025-09-26T14:07:16.367Z] renaissance-philosophers_0 Start Time: Fri Sep 26 14:07:15 2025 Epoch Time (ms): 1758895635867 [2025-09-26T14:07:16.367Z] variation: NoOptions [2025-09-26T14:07:16.367Z] JVM_OPTIONS: [2025-09-26T14:07:16.367Z] { \ [2025-09-26T14:07:16.367Z] echo ""; echo "TEST SETUP:"; \ [2025-09-26T14:07:16.367Z] echo "Nothing to be done for setup."; \ [2025-09-26T14:07:16.367Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1758893310241/renaissance-philosophers_0"; \ [2025-09-26T14:07:16.367Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1758893310241/renaissance-philosophers_0"; \ [2025-09-26T14:07:16.367Z] echo ""; echo "TESTING:"; \ [2025-09-26T14:07:16.367Z] "/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_1758893310241/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-09-26T14:07:16.367Z] 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_1758893310241/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-26T14:07:16.367Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-26T14:07:16.367Z] echo "Nothing to be done for teardown."; \ [2025-09-26T14:07:16.367Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1758893310241/TestTargetResult"; [2025-09-26T14:07:16.367Z] [2025-09-26T14:07:16.367Z] TEST SETUP: [2025-09-26T14:07:16.367Z] Nothing to be done for setup. [2025-09-26T14:07:16.367Z] [2025-09-26T14:07:16.367Z] TESTING: [2025-09-26T14:07:16.669Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-26T14:07:16.669Z] GC before operation: completed in 13.406 ms, heap usage 15.698 MB -> 4.651 MB. [2025-09-26T14:07:21.202Z] Camera thread performed 122 scans. [2025-09-26T14:07:21.202Z] ====== philosophers (scala) [default], iteration 0 completed (4173.904 ms) ====== [2025-09-26T14:07:21.202Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-26T14:07:21.202Z] GC before operation: completed in 9.780 ms, heap usage 35.692 MB -> 5.000 MB. [2025-09-26T14:07:22.752Z] Camera thread performed 122 scans. [2025-09-26T14:07:22.752Z] ====== philosophers (scala) [default], iteration 1 completed (1682.851 ms) ====== [2025-09-26T14:07:22.752Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-26T14:07:22.752Z] GC before operation: completed in 10.128 ms, heap usage 20.109 MB -> 5.000 MB. [2025-09-26T14:07:23.405Z] Camera thread performed 122 scans. [2025-09-26T14:07:23.405Z] ====== philosophers (scala) [default], iteration 2 completed (821.985 ms) ====== [2025-09-26T14:07:23.405Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-26T14:07:23.405Z] GC before operation: completed in 13.396 ms, heap usage 42.831 MB -> 5.001 MB. [2025-09-26T14:07:24.468Z] Camera thread performed 122 scans. [2025-09-26T14:07:24.468Z] ====== philosophers (scala) [default], iteration 3 completed (781.133 ms) ====== [2025-09-26T14:07:24.468Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-26T14:07:24.468Z] GC before operation: completed in 8.788 ms, heap usage 35.226 MB -> 5.001 MB. [2025-09-26T14:07:25.120Z] Camera thread performed 122 scans. [2025-09-26T14:07:25.120Z] ====== philosophers (scala) [default], iteration 4 completed (781.324 ms) ====== [2025-09-26T14:07:25.120Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-26T14:07:25.120Z] GC before operation: completed in 10.746 ms, heap usage 74.179 MB -> 5.001 MB. [2025-09-26T14:07:25.772Z] Camera thread performed 122 scans. [2025-09-26T14:07:25.772Z] ====== philosophers (scala) [default], iteration 5 completed (772.901 ms) ====== [2025-09-26T14:07:25.772Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-26T14:07:25.772Z] GC before operation: completed in 9.821 ms, heap usage 19.146 MB -> 5.000 MB. [2025-09-26T14:07:26.834Z] Camera thread performed 122 scans. [2025-09-26T14:07:26.834Z] ====== philosophers (scala) [default], iteration 6 completed (753.238 ms) ====== [2025-09-26T14:07:26.834Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-26T14:07:26.834Z] GC before operation: completed in 10.896 ms, heap usage 57.634 MB -> 5.001 MB. [2025-09-26T14:07:27.487Z] Camera thread performed 122 scans. [2025-09-26T14:07:27.487Z] ====== philosophers (scala) [default], iteration 7 completed (732.698 ms) ====== [2025-09-26T14:07:27.487Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-26T14:07:27.487Z] GC before operation: completed in 10.684 ms, heap usage 29.128 MB -> 5.001 MB. [2025-09-26T14:07:28.137Z] Camera thread performed 122 scans. [2025-09-26T14:07:28.137Z] ====== philosophers (scala) [default], iteration 8 completed (714.938 ms) ====== [2025-09-26T14:07:28.137Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-26T14:07:28.137Z] GC before operation: completed in 10.729 ms, heap usage 54.635 MB -> 5.002 MB. [2025-09-26T14:07:28.789Z] Camera thread performed 122 scans. [2025-09-26T14:07:28.789Z] ====== philosophers (scala) [default], iteration 9 completed (723.717 ms) ====== [2025-09-26T14:07:28.789Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-26T14:07:28.789Z] GC before operation: completed in 10.308 ms, heap usage 29.128 MB -> 5.002 MB. [2025-09-26T14:07:29.444Z] Camera thread performed 122 scans. [2025-09-26T14:07:29.444Z] ====== philosophers (scala) [default], iteration 10 completed (738.644 ms) ====== [2025-09-26T14:07:29.444Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-26T14:07:29.444Z] GC before operation: completed in 11.208 ms, heap usage 18.635 MB -> 5.002 MB. [2025-09-26T14:07:30.514Z] Camera thread performed 122 scans. [2025-09-26T14:07:30.514Z] ====== philosophers (scala) [default], iteration 11 completed (720.080 ms) ====== [2025-09-26T14:07:30.514Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-26T14:07:30.514Z] GC before operation: completed in 10.310 ms, heap usage 41.118 MB -> 5.002 MB. [2025-09-26T14:07:31.162Z] Camera thread performed 122 scans. [2025-09-26T14:07:31.162Z] ====== philosophers (scala) [default], iteration 12 completed (697.374 ms) ====== [2025-09-26T14:07:31.162Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-26T14:07:31.162Z] GC before operation: completed in 9.737 ms, heap usage 61.630 MB -> 5.002 MB. [2025-09-26T14:07:31.850Z] Camera thread performed 122 scans. [2025-09-26T14:07:31.850Z] ====== philosophers (scala) [default], iteration 13 completed (701.395 ms) ====== [2025-09-26T14:07:31.850Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-26T14:07:31.850Z] GC before operation: completed in 9.741 ms, heap usage 56.629 MB -> 5.002 MB. [2025-09-26T14:07:32.504Z] Camera thread performed 122 scans. [2025-09-26T14:07:32.505Z] ====== philosophers (scala) [default], iteration 14 completed (731.589 ms) ====== [2025-09-26T14:07:32.505Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-26T14:07:32.505Z] GC before operation: completed in 10.579 ms, heap usage 38.130 MB -> 5.002 MB. [2025-09-26T14:07:33.154Z] Camera thread performed 122 scans. [2025-09-26T14:07:33.155Z] ====== philosophers (scala) [default], iteration 15 completed (706.528 ms) ====== [2025-09-26T14:07:33.155Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-26T14:07:33.155Z] GC before operation: completed in 10.528 ms, heap usage 39.102 MB -> 5.003 MB. [2025-09-26T14:07:33.805Z] Camera thread performed 122 scans. [2025-09-26T14:07:33.805Z] ====== philosophers (scala) [default], iteration 16 completed (722.991 ms) ====== [2025-09-26T14:07:33.805Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-26T14:07:33.805Z] GC before operation: completed in 9.259 ms, heap usage 40.132 MB -> 5.003 MB. [2025-09-26T14:07:34.863Z] Camera thread performed 122 scans. [2025-09-26T14:07:34.863Z] ====== philosophers (scala) [default], iteration 17 completed (712.660 ms) ====== [2025-09-26T14:07:34.863Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-26T14:07:34.863Z] GC before operation: completed in 10.655 ms, heap usage 18.604 MB -> 5.003 MB. [2025-09-26T14:07:35.513Z] Camera thread performed 122 scans. [2025-09-26T14:07:35.514Z] ====== philosophers (scala) [default], iteration 18 completed (706.940 ms) ====== [2025-09-26T14:07:35.514Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-26T14:07:35.514Z] GC before operation: completed in 11.095 ms, heap usage 41.122 MB -> 5.003 MB. [2025-09-26T14:07:36.167Z] Camera thread performed 122 scans. [2025-09-26T14:07:36.167Z] ====== philosophers (scala) [default], iteration 19 completed (705.406 ms) ====== [2025-09-26T14:07:36.167Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-26T14:07:36.167Z] GC before operation: completed in 10.154 ms, heap usage 62.133 MB -> 5.004 MB. [2025-09-26T14:07:36.824Z] Camera thread performed 122 scans. [2025-09-26T14:07:36.824Z] ====== philosophers (scala) [default], iteration 20 completed (703.010 ms) ====== [2025-09-26T14:07:36.824Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-26T14:07:36.824Z] GC before operation: completed in 9.974 ms, heap usage 55.630 MB -> 5.004 MB. [2025-09-26T14:07:37.476Z] Camera thread performed 122 scans. [2025-09-26T14:07:37.476Z] ====== philosophers (scala) [default], iteration 21 completed (705.179 ms) ====== [2025-09-26T14:07:37.476Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-26T14:07:37.476Z] GC before operation: completed in 9.607 ms, heap usage 65.630 MB -> 5.004 MB. [2025-09-26T14:07:38.125Z] Camera thread performed 122 scans. [2025-09-26T14:07:38.125Z] ====== philosophers (scala) [default], iteration 22 completed (714.744 ms) ====== [2025-09-26T14:07:38.125Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-26T14:07:38.125Z] GC before operation: completed in 8.883 ms, heap usage 7.105 MB -> 5.005 MB. [2025-09-26T14:07:38.779Z] Camera thread performed 122 scans. [2025-09-26T14:07:38.779Z] ====== philosophers (scala) [default], iteration 23 completed (711.324 ms) ====== [2025-09-26T14:07:38.779Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-26T14:07:38.779Z] GC before operation: completed in 10.657 ms, heap usage 51.637 MB -> 5.004 MB. [2025-09-26T14:07:39.842Z] Camera thread performed 122 scans. [2025-09-26T14:07:39.842Z] ====== philosophers (scala) [default], iteration 24 completed (722.406 ms) ====== [2025-09-26T14:07:39.842Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-26T14:07:39.842Z] GC before operation: completed in 10.725 ms, heap usage 31.136 MB -> 5.004 MB. [2025-09-26T14:07:40.498Z] Camera thread performed 122 scans. [2025-09-26T14:07:40.498Z] ====== philosophers (scala) [default], iteration 25 completed (744.399 ms) ====== [2025-09-26T14:07:40.498Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-26T14:07:40.498Z] GC before operation: completed in 10.392 ms, heap usage 24.136 MB -> 5.004 MB. [2025-09-26T14:07:41.149Z] Camera thread performed 122 scans. [2025-09-26T14:07:41.149Z] ====== philosophers (scala) [default], iteration 26 completed (739.090 ms) ====== [2025-09-26T14:07:41.149Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-26T14:07:41.149Z] GC before operation: completed in 10.665 ms, heap usage 18.637 MB -> 5.005 MB. [2025-09-26T14:07:41.799Z] Camera thread performed 122 scans. [2025-09-26T14:07:41.799Z] ====== philosophers (scala) [default], iteration 27 completed (706.700 ms) ====== [2025-09-26T14:07:41.799Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-26T14:07:41.799Z] GC before operation: completed in 9.644 ms, heap usage 13.138 MB -> 5.005 MB. [2025-09-26T14:07:42.448Z] Camera thread performed 122 scans. [2025-09-26T14:07:42.448Z] ====== philosophers (scala) [default], iteration 28 completed (700.533 ms) ====== [2025-09-26T14:07:42.448Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-26T14:07:42.448Z] GC before operation: completed in 9.979 ms, heap usage 14.125 MB -> 5.005 MB. [2025-09-26T14:07:43.507Z] Camera thread performed 122 scans. [2025-09-26T14:07:43.507Z] ====== philosophers (scala) [default], iteration 29 completed (703.231 ms) ====== [2025-09-26T14:07:43.507Z] ----------------------------------- [2025-09-26T14:07:43.507Z] renaissance-philosophers_0_PASSED [2025-09-26T14:07:43.507Z] ----------------------------------- [2025-09-26T14:07:43.507Z] [2025-09-26T14:07:43.507Z] TEST TEARDOWN: [2025-09-26T14:07:43.507Z] Nothing to be done for teardown. [2025-09-26T14:07:43.507Z] renaissance-philosophers_0 Finish Time: Fri Sep 26 14:07:43 2025 Epoch Time (ms): 1758895663353