[2025-08-27T22:00:46.068Z] Running test renaissance-philosophers_0 ... [2025-08-27T22:00:46.068Z] =============================================== [2025-08-27T22:00:46.068Z] renaissance-philosophers_0 Start Time: Wed Aug 27 23:00:45 2025 Epoch Time (ms): 1756332045670 [2025-08-27T22:00:46.068Z] variation: NoOptions [2025-08-27T22:00:46.068Z] JVM_OPTIONS: [2025-08-27T22:00:46.068Z] { \ [2025-08-27T22:00:46.068Z] echo ""; echo "TEST SETUP:"; \ [2025-08-27T22:00:46.068Z] echo "Nothing to be done for setup."; \ [2025-08-27T22:00:46.068Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-philosophers_0"; \ [2025-08-27T22:00:46.068Z] cd "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-philosophers_0"; \ [2025-08-27T22:00:46.068Z] echo ""; echo "TESTING:"; \ [2025-08-27T22:00:46.068Z] "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-27T22:00:46.068Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-27T22:00:46.068Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-27T22:00:46.068Z] echo "Nothing to be done for teardown."; \ [2025-08-27T22:00:46.068Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/TestTargetResult"; [2025-08-27T22:00:46.068Z] [2025-08-27T22:00:46.068Z] TEST SETUP: [2025-08-27T22:00:46.068Z] Nothing to be done for setup. [2025-08-27T22:00:46.068Z] [2025-08-27T22:00:46.068Z] TESTING: [2025-08-27T22:00:46.821Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-27T22:00:46.821Z] GC before operation: completed in 11.200 ms, heap usage 7.692 MB -> 4.580 MB. [2025-08-27T22:00:50.763Z] Camera thread performed 122 scans. [2025-08-27T22:00:50.763Z] ====== philosophers (scala) [default], iteration 0 completed (3302.755 ms) ====== [2025-08-27T22:00:50.763Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-27T22:00:50.763Z] GC before operation: completed in 11.681 ms, heap usage 9.438 MB -> 4.966 MB. [2025-08-27T22:00:51.980Z] Camera thread performed 122 scans. [2025-08-27T22:00:51.980Z] ====== philosophers (scala) [default], iteration 1 completed (1608.670 ms) ====== [2025-08-27T22:00:51.980Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-27T22:00:51.980Z] GC before operation: completed in 10.577 ms, heap usage 74.676 MB -> 4.967 MB. [2025-08-27T22:00:53.200Z] Camera thread performed 122 scans. [2025-08-27T22:00:53.200Z] ====== philosophers (scala) [default], iteration 2 completed (1553.578 ms) ====== [2025-08-27T22:00:53.200Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-27T22:00:53.554Z] GC before operation: completed in 9.263 ms, heap usage 35.319 MB -> 4.967 MB. [2025-08-27T22:00:54.770Z] Camera thread performed 122 scans. [2025-08-27T22:00:54.771Z] ====== philosophers (scala) [default], iteration 3 completed (1549.846 ms) ====== [2025-08-27T22:00:54.771Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-27T22:00:54.771Z] GC before operation: completed in 9.104 ms, heap usage 88.411 MB -> 4.972 MB. [2025-08-27T22:00:56.519Z] Camera thread performed 122 scans. [2025-08-27T22:00:56.519Z] ====== philosophers (scala) [default], iteration 4 completed (1592.036 ms) ====== [2025-08-27T22:00:56.519Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-27T22:00:56.519Z] GC before operation: completed in 9.113 ms, heap usage 46.205 MB -> 4.972 MB. [2025-08-27T22:00:58.272Z] Camera thread performed 122 scans. [2025-08-27T22:00:58.272Z] ====== philosophers (scala) [default], iteration 5 completed (1581.205 ms) ====== [2025-08-27T22:00:58.272Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-27T22:00:58.272Z] GC before operation: completed in 8.785 ms, heap usage 68.846 MB -> 4.973 MB. [2025-08-27T22:01:00.036Z] Camera thread performed 122 scans. [2025-08-27T22:01:00.036Z] ====== philosophers (scala) [default], iteration 6 completed (1604.283 ms) ====== [2025-08-27T22:01:00.036Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-27T22:01:00.036Z] GC before operation: completed in 9.390 ms, heap usage 102.225 MB -> 4.973 MB. [2025-08-27T22:01:01.447Z] Camera thread performed 122 scans. [2025-08-27T22:01:01.447Z] ====== philosophers (scala) [default], iteration 7 completed (1549.973 ms) ====== [2025-08-27T22:01:01.447Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-27T22:01:01.447Z] GC before operation: completed in 10.377 ms, heap usage 65.374 MB -> 4.973 MB. [2025-08-27T22:01:03.208Z] Camera thread performed 122 scans. [2025-08-27T22:01:03.208Z] ====== philosophers (scala) [default], iteration 8 completed (1572.019 ms) ====== [2025-08-27T22:01:03.208Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-27T22:01:03.208Z] GC before operation: completed in 8.607 ms, heap usage 8.231 MB -> 4.974 MB. [2025-08-27T22:01:04.425Z] Camera thread performed 122 scans. [2025-08-27T22:01:04.425Z] ====== philosophers (scala) [default], iteration 9 completed (1618.335 ms) ====== [2025-08-27T22:01:04.425Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-27T22:01:04.425Z] GC before operation: completed in 9.013 ms, heap usage 34.104 MB -> 4.974 MB. [2025-08-27T22:01:06.173Z] Camera thread performed 122 scans. [2025-08-27T22:01:06.173Z] ====== philosophers (scala) [default], iteration 10 completed (1564.800 ms) ====== [2025-08-27T22:01:06.173Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-27T22:01:06.173Z] GC before operation: completed in 9.322 ms, heap usage 22.850 MB -> 4.974 MB. [2025-08-27T22:01:07.922Z] Camera thread performed 122 scans. [2025-08-27T22:01:07.922Z] ====== philosophers (scala) [default], iteration 11 completed (1517.911 ms) ====== [2025-08-27T22:01:07.922Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-27T22:01:07.922Z] GC before operation: completed in 8.764 ms, heap usage 17.242 MB -> 4.974 MB. [2025-08-27T22:01:09.146Z] Camera thread performed 122 scans. [2025-08-27T22:01:09.146Z] ====== philosophers (scala) [default], iteration 12 completed (1615.018 ms) ====== [2025-08-27T22:01:09.146Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-27T22:01:09.146Z] GC before operation: completed in 8.996 ms, heap usage 71.700 MB -> 4.974 MB. [2025-08-27T22:01:10.901Z] Camera thread performed 122 scans. [2025-08-27T22:01:10.902Z] ====== philosophers (scala) [default], iteration 13 completed (1605.475 ms) ====== [2025-08-27T22:01:10.902Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-27T22:01:10.902Z] GC before operation: completed in 9.877 ms, heap usage 109.747 MB -> 4.974 MB. [2025-08-27T22:01:12.651Z] Camera thread performed 122 scans. [2025-08-27T22:01:12.652Z] ====== philosophers (scala) [default], iteration 14 completed (1552.449 ms) ====== [2025-08-27T22:01:12.652Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-27T22:01:12.652Z] GC before operation: completed in 9.558 ms, heap usage 76.747 MB -> 4.974 MB. [2025-08-27T22:01:13.873Z] Camera thread performed 122 scans. [2025-08-27T22:01:13.873Z] ====== philosophers (scala) [default], iteration 15 completed (1615.582 ms) ====== [2025-08-27T22:01:13.873Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-27T22:01:14.226Z] GC before operation: completed in 9.161 ms, heap usage 40.144 MB -> 4.976 MB. [2025-08-27T22:01:15.454Z] Camera thread performed 122 scans. [2025-08-27T22:01:15.454Z] ====== philosophers (scala) [default], iteration 16 completed (1573.146 ms) ====== [2025-08-27T22:01:15.454Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-27T22:01:15.806Z] GC before operation: completed in 9.012 ms, heap usage 44.071 MB -> 4.976 MB. [2025-08-27T22:01:17.575Z] Camera thread performed 122 scans. [2025-08-27T22:01:17.575Z] ====== philosophers (scala) [default], iteration 17 completed (1612.794 ms) ====== [2025-08-27T22:01:17.575Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-27T22:01:17.575Z] GC before operation: completed in 9.324 ms, heap usage 36.460 MB -> 4.976 MB. [2025-08-27T22:01:18.800Z] Camera thread performed 122 scans. [2025-08-27T22:01:18.800Z] ====== philosophers (scala) [default], iteration 18 completed (1527.422 ms) ====== [2025-08-27T22:01:18.800Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-27T22:01:18.800Z] GC before operation: completed in 9.343 ms, heap usage 11.287 MB -> 4.976 MB. [2025-08-27T22:01:20.561Z] Camera thread performed 122 scans. [2025-08-27T22:01:20.561Z] ====== philosophers (scala) [default], iteration 19 completed (1520.932 ms) ====== [2025-08-27T22:01:20.561Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-27T22:01:20.561Z] GC before operation: completed in 8.791 ms, heap usage 72.975 MB -> 4.976 MB. [2025-08-27T22:01:22.356Z] Camera thread performed 122 scans. [2025-08-27T22:01:22.356Z] ====== philosophers (scala) [default], iteration 20 completed (1666.221 ms) ====== [2025-08-27T22:01:22.356Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-27T22:01:22.356Z] GC before operation: completed in 8.839 ms, heap usage 52.034 MB -> 4.976 MB. [2025-08-27T22:01:23.597Z] Camera thread performed 122 scans. [2025-08-27T22:01:23.597Z] ====== philosophers (scala) [default], iteration 21 completed (1615.092 ms) ====== [2025-08-27T22:01:23.597Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-27T22:01:23.597Z] GC before operation: completed in 9.582 ms, heap usage 51.780 MB -> 4.976 MB. [2025-08-27T22:01:25.354Z] Camera thread performed 122 scans. [2025-08-27T22:01:25.354Z] ====== philosophers (scala) [default], iteration 22 completed (1546.120 ms) ====== [2025-08-27T22:01:25.354Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-27T22:01:25.354Z] GC before operation: completed in 9.649 ms, heap usage 89.353 MB -> 4.976 MB. [2025-08-27T22:01:26.573Z] Camera thread performed 122 scans. [2025-08-27T22:01:26.573Z] ====== philosophers (scala) [default], iteration 23 completed (1547.624 ms) ====== [2025-08-27T22:01:26.573Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-27T22:01:26.573Z] GC before operation: completed in 9.017 ms, heap usage 119.039 MB -> 4.976 MB. [2025-08-27T22:01:28.328Z] Camera thread performed 122 scans. [2025-08-27T22:01:28.328Z] ====== philosophers (scala) [default], iteration 24 completed (1606.740 ms) ====== [2025-08-27T22:01:28.328Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-27T22:01:28.328Z] GC before operation: completed in 8.720 ms, heap usage 21.199 MB -> 4.976 MB. [2025-08-27T22:01:30.078Z] Camera thread performed 122 scans. [2025-08-27T22:01:30.078Z] ====== philosophers (scala) [default], iteration 25 completed (1506.056 ms) ====== [2025-08-27T22:01:30.078Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-27T22:01:30.078Z] GC before operation: completed in 8.963 ms, heap usage 108.748 MB -> 4.976 MB. [2025-08-27T22:01:31.296Z] Camera thread performed 122 scans. [2025-08-27T22:01:31.296Z] ====== philosophers (scala) [default], iteration 26 completed (1567.326 ms) ====== [2025-08-27T22:01:31.296Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-27T22:01:31.296Z] GC before operation: completed in 8.880 ms, heap usage 132.609 MB -> 4.976 MB. [2025-08-27T22:01:33.072Z] Camera thread performed 122 scans. [2025-08-27T22:01:33.072Z] ====== philosophers (scala) [default], iteration 27 completed (1524.749 ms) ====== [2025-08-27T22:01:33.072Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-27T22:01:33.072Z] GC before operation: completed in 9.132 ms, heap usage 124.675 MB -> 4.976 MB. [2025-08-27T22:01:34.832Z] Camera thread performed 122 scans. [2025-08-27T22:01:34.832Z] ====== philosophers (scala) [default], iteration 28 completed (1551.170 ms) ====== [2025-08-27T22:01:34.832Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-27T22:01:34.832Z] GC before operation: completed in 8.884 ms, heap usage 34.069 MB -> 4.977 MB. [2025-08-27T22:01:36.071Z] Camera thread performed 122 scans. [2025-08-27T22:01:36.071Z] ====== philosophers (scala) [default], iteration 29 completed (1520.316 ms) ====== [2025-08-27T22:01:36.429Z] ----------------------------------- [2025-08-27T22:01:36.429Z] renaissance-philosophers_0_PASSED [2025-08-27T22:01:36.429Z] ----------------------------------- [2025-08-27T22:01:36.429Z] [2025-08-27T22:01:36.429Z] TEST TEARDOWN: [2025-08-27T22:01:36.429Z] Nothing to be done for teardown. [2025-08-27T22:01:36.429Z] renaissance-philosophers_0 Finish Time: Wed Aug 27 23:01:36 2025 Epoch Time (ms): 1756332096123