[2025-12-03T22:40:04.362Z] Running test renaissance-philosophers_0 ... [2025-12-03T22:40:04.362Z] =============================================== [2025-12-03T22:40:04.362Z] renaissance-philosophers_0 Start Time: Wed Dec 3 17:40:03 2025 Epoch Time (ms): 1764801603952 [2025-12-03T22:40:04.362Z] variation: NoOptions [2025-12-03T22:40:04.362Z] JVM_OPTIONS: [2025-12-03T22:40:04.362Z] { \ [2025-12-03T22:40:04.362Z] echo ""; echo "TEST SETUP:"; \ [2025-12-03T22:40:04.362Z] echo "Nothing to be done for setup."; \ [2025-12-03T22:40:04.362Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17648007584522/renaissance-philosophers_0"; \ [2025-12-03T22:40:04.362Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17648007584522/renaissance-philosophers_0"; \ [2025-12-03T22:40:04.362Z] echo ""; echo "TESTING:"; \ [2025-12-03T22:40:04.362Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_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/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17648007584522/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-03T22:40:04.362Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17648007584522/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-03T22:40:04.362Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-03T22:40:04.362Z] echo "Nothing to be done for teardown."; \ [2025-12-03T22:40:04.362Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17648007584522/TestTargetResult"; [2025-12-03T22:40:04.362Z] [2025-12-03T22:40:04.362Z] TEST SETUP: [2025-12-03T22:40:04.362Z] Nothing to be done for setup. [2025-12-03T22:40:04.362Z] [2025-12-03T22:40:04.362Z] TESTING: [2025-12-03T22:40:04.362Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-03T22:40:04.362Z] GC before operation: completed in 5.955 ms, heap usage 14.953 MB -> 4.898 MB. [2025-12-03T22:40:05.120Z] Camera thread performed 122 scans. [2025-12-03T22:40:05.120Z] ====== philosophers (scala) [default], iteration 0 completed (625.241 ms) ====== [2025-12-03T22:40:05.120Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-03T22:40:05.120Z] GC before operation: completed in 6.044 ms, heap usage 46.065 MB -> 5.274 MB. [2025-12-03T22:40:05.471Z] Camera thread performed 122 scans. [2025-12-03T22:40:05.471Z] ====== philosophers (scala) [default], iteration 1 completed (434.806 ms) ====== [2025-12-03T22:40:05.471Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-03T22:40:05.471Z] GC before operation: completed in 4.841 ms, heap usage 65.471 MB -> 5.274 MB. [2025-12-03T22:40:06.225Z] Camera thread performed 122 scans. [2025-12-03T22:40:06.225Z] ====== philosophers (scala) [default], iteration 2 completed (426.090 ms) ====== [2025-12-03T22:40:06.225Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-03T22:40:06.225Z] GC before operation: completed in 4.698 ms, heap usage 60.083 MB -> 5.275 MB. [2025-12-03T22:40:06.573Z] Camera thread performed 122 scans. [2025-12-03T22:40:06.573Z] ====== philosophers (scala) [default], iteration 3 completed (430.283 ms) ====== [2025-12-03T22:40:06.573Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-03T22:40:06.573Z] GC before operation: completed in 4.209 ms, heap usage 20.037 MB -> 5.275 MB. [2025-12-03T22:40:06.921Z] Camera thread performed 122 scans. [2025-12-03T22:40:06.921Z] ====== philosophers (scala) [default], iteration 4 completed (410.260 ms) ====== [2025-12-03T22:40:06.921Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-03T22:40:06.921Z] GC before operation: completed in 4.326 ms, heap usage 21.988 MB -> 5.276 MB. [2025-12-03T22:40:07.273Z] Camera thread performed 122 scans. [2025-12-03T22:40:07.273Z] ====== philosophers (scala) [default], iteration 5 completed (393.639 ms) ====== [2025-12-03T22:40:07.273Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-03T22:40:07.273Z] GC before operation: completed in 4.143 ms, heap usage 44.973 MB -> 5.276 MB. [2025-12-03T22:40:07.627Z] Camera thread performed 122 scans. [2025-12-03T22:40:07.627Z] ====== philosophers (scala) [default], iteration 6 completed (448.798 ms) ====== [2025-12-03T22:40:07.627Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-03T22:40:07.627Z] GC before operation: completed in 4.730 ms, heap usage 51.975 MB -> 5.277 MB. [2025-12-03T22:40:07.978Z] Camera thread performed 122 scans. [2025-12-03T22:40:07.978Z] ====== philosophers (scala) [default], iteration 7 completed (416.612 ms) ====== [2025-12-03T22:40:07.978Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-03T22:40:07.978Z] GC before operation: completed in 4.539 ms, heap usage 27.493 MB -> 5.277 MB. [2025-12-03T22:40:08.736Z] Camera thread performed 122 scans. [2025-12-03T22:40:08.736Z] ====== philosophers (scala) [default], iteration 8 completed (453.080 ms) ====== [2025-12-03T22:40:08.736Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-03T22:40:08.736Z] GC before operation: completed in 4.193 ms, heap usage 30.922 MB -> 5.278 MB. [2025-12-03T22:40:09.092Z] Camera thread performed 122 scans. [2025-12-03T22:40:09.092Z] ====== philosophers (scala) [default], iteration 9 completed (414.232 ms) ====== [2025-12-03T22:40:09.092Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-03T22:40:09.092Z] GC before operation: completed in 4.560 ms, heap usage 88.903 MB -> 5.277 MB. [2025-12-03T22:40:09.449Z] Camera thread performed 122 scans. [2025-12-03T22:40:09.449Z] ====== philosophers (scala) [default], iteration 10 completed (404.402 ms) ====== [2025-12-03T22:40:09.449Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-03T22:40:09.449Z] GC before operation: completed in 4.158 ms, heap usage 46.488 MB -> 5.278 MB. [2025-12-03T22:40:09.802Z] Camera thread performed 122 scans. [2025-12-03T22:40:09.802Z] ====== philosophers (scala) [default], iteration 11 completed (367.046 ms) ====== [2025-12-03T22:40:09.802Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-03T22:40:09.802Z] GC before operation: completed in 4.275 ms, heap usage 48.474 MB -> 5.278 MB. [2025-12-03T22:40:10.163Z] Camera thread performed 122 scans. [2025-12-03T22:40:10.163Z] ====== philosophers (scala) [default], iteration 12 completed (420.138 ms) ====== [2025-12-03T22:40:10.163Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-03T22:40:10.163Z] GC before operation: completed in 4.331 ms, heap usage 40.931 MB -> 5.278 MB. [2025-12-03T22:40:10.512Z] Camera thread performed 122 scans. [2025-12-03T22:40:10.512Z] ====== philosophers (scala) [default], iteration 13 completed (410.843 ms) ====== [2025-12-03T22:40:10.512Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-03T22:40:10.512Z] GC before operation: completed in 4.521 ms, heap usage 29.959 MB -> 5.278 MB. [2025-12-03T22:40:11.412Z] Camera thread performed 122 scans. [2025-12-03T22:40:11.412Z] ====== philosophers (scala) [default], iteration 14 completed (438.918 ms) ====== [2025-12-03T22:40:11.412Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-03T22:40:11.412Z] GC before operation: completed in 4.304 ms, heap usage 6.992 MB -> 5.279 MB. [2025-12-03T22:40:11.412Z] Camera thread performed 122 scans. [2025-12-03T22:40:11.412Z] ====== philosophers (scala) [default], iteration 15 completed (411.700 ms) ====== [2025-12-03T22:40:11.412Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-03T22:40:11.412Z] GC before operation: completed in 4.008 ms, heap usage 42.199 MB -> 5.278 MB. [2025-12-03T22:40:11.762Z] Camera thread performed 122 scans. [2025-12-03T22:40:11.762Z] ====== philosophers (scala) [default], iteration 16 completed (438.353 ms) ====== [2025-12-03T22:40:11.762Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-03T22:40:11.762Z] GC before operation: completed in 3.980 ms, heap usage 52.492 MB -> 5.279 MB. [2025-12-03T22:40:12.631Z] Camera thread performed 122 scans. [2025-12-03T22:40:12.632Z] ====== philosophers (scala) [default], iteration 17 completed (381.754 ms) ====== [2025-12-03T22:40:12.632Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-03T22:40:12.632Z] GC before operation: completed in 4.656 ms, heap usage 65.889 MB -> 5.279 MB. [2025-12-03T22:40:12.632Z] Camera thread performed 122 scans. [2025-12-03T22:40:12.632Z] ====== philosophers (scala) [default], iteration 18 completed (447.397 ms) ====== [2025-12-03T22:40:12.632Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-03T22:40:12.632Z] GC before operation: completed in 4.453 ms, heap usage 53.238 MB -> 5.279 MB. [2025-12-03T22:40:13.396Z] Camera thread performed 122 scans. [2025-12-03T22:40:13.396Z] ====== philosophers (scala) [default], iteration 19 completed (433.566 ms) ====== [2025-12-03T22:40:13.396Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-03T22:40:13.396Z] GC before operation: completed in 4.118 ms, heap usage 36.867 MB -> 5.279 MB. [2025-12-03T22:40:13.765Z] Camera thread performed 122 scans. [2025-12-03T22:40:13.765Z] ====== philosophers (scala) [default], iteration 20 completed (431.711 ms) ====== [2025-12-03T22:40:13.765Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-03T22:40:13.765Z] GC before operation: completed in 4.372 ms, heap usage 68.895 MB -> 5.279 MB. [2025-12-03T22:40:14.226Z] Camera thread performed 122 scans. [2025-12-03T22:40:14.226Z] ====== philosophers (scala) [default], iteration 21 completed (429.793 ms) ====== [2025-12-03T22:40:14.226Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-03T22:40:14.226Z] GC before operation: completed in 4.149 ms, heap usage 36.468 MB -> 5.279 MB. [2025-12-03T22:40:14.580Z] Camera thread performed 122 scans. [2025-12-03T22:40:14.580Z] ====== philosophers (scala) [default], iteration 22 completed (448.913 ms) ====== [2025-12-03T22:40:14.580Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-03T22:40:14.580Z] GC before operation: completed in 4.394 ms, heap usage 49.340 MB -> 5.280 MB. [2025-12-03T22:40:14.934Z] Camera thread performed 122 scans. [2025-12-03T22:40:14.934Z] ====== philosophers (scala) [default], iteration 23 completed (420.488 ms) ====== [2025-12-03T22:40:14.934Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-03T22:40:14.934Z] GC before operation: completed in 4.102 ms, heap usage 16.494 MB -> 5.280 MB. [2025-12-03T22:40:15.285Z] Camera thread performed 122 scans. [2025-12-03T22:40:15.285Z] ====== philosophers (scala) [default], iteration 24 completed (395.679 ms) ====== [2025-12-03T22:40:15.285Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-03T22:40:15.285Z] GC before operation: completed in 4.497 ms, heap usage 22.390 MB -> 5.280 MB. [2025-12-03T22:40:15.657Z] Camera thread performed 122 scans. [2025-12-03T22:40:15.657Z] ====== philosophers (scala) [default], iteration 25 completed (414.662 ms) ====== [2025-12-03T22:40:15.657Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-03T22:40:15.657Z] GC before operation: completed in 4.238 ms, heap usage 6.412 MB -> 5.280 MB. [2025-12-03T22:40:16.012Z] Camera thread performed 122 scans. [2025-12-03T22:40:16.012Z] ====== philosophers (scala) [default], iteration 26 completed (408.773 ms) ====== [2025-12-03T22:40:16.012Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-03T22:40:16.012Z] GC before operation: completed in 4.290 ms, heap usage 54.366 MB -> 5.280 MB. [2025-12-03T22:40:16.775Z] Camera thread performed 122 scans. [2025-12-03T22:40:16.775Z] ====== philosophers (scala) [default], iteration 27 completed (407.873 ms) ====== [2025-12-03T22:40:16.775Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-03T22:40:16.775Z] GC before operation: completed in 4.208 ms, heap usage 23.494 MB -> 5.280 MB. [2025-12-03T22:40:17.128Z] Camera thread performed 122 scans. [2025-12-03T22:40:17.128Z] ====== philosophers (scala) [default], iteration 28 completed (421.088 ms) ====== [2025-12-03T22:40:17.128Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-03T22:40:17.128Z] GC before operation: completed in 4.259 ms, heap usage 27.444 MB -> 5.280 MB. [2025-12-03T22:40:17.481Z] Camera thread performed 122 scans. [2025-12-03T22:40:17.481Z] ====== philosophers (scala) [default], iteration 29 completed (427.364 ms) ====== [2025-12-03T22:40:17.481Z] ----------------------------------- [2025-12-03T22:40:17.481Z] renaissance-philosophers_0_PASSED [2025-12-03T22:40:17.481Z] ----------------------------------- [2025-12-03T22:40:17.481Z] [2025-12-03T22:40:17.481Z] TEST TEARDOWN: [2025-12-03T22:40:17.481Z] Nothing to be done for teardown. [2025-12-03T22:40:17.481Z] renaissance-philosophers_0 Finish Time: Wed Dec 3 17:40:17 2025 Epoch Time (ms): 1764801617317