[2025-08-20T20:19:04.202Z] Running test renaissance-philosophers_0 ... [2025-08-20T20:19:04.202Z] =============================================== [2025-08-20T20:19:04.202Z] renaissance-philosophers_0 Start Time: Wed Aug 20 16:19:03 2025 Epoch Time (ms): 1755721143837 [2025-08-20T20:19:04.202Z] variation: NoOptions [2025-08-20T20:19:04.202Z] JVM_OPTIONS: [2025-08-20T20:19:04.202Z] { \ [2025-08-20T20:19:04.202Z] echo ""; echo "TEST SETUP:"; \ [2025-08-20T20:19:04.202Z] echo "Nothing to be done for setup."; \ [2025-08-20T20:19:04.202Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17557202437073/renaissance-philosophers_0"; \ [2025-08-20T20:19:04.202Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17557202437073/renaissance-philosophers_0"; \ [2025-08-20T20:19:04.202Z] echo ""; echo "TESTING:"; \ [2025-08-20T20:19:04.202Z] "/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_17557202437073/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-20T20:19:04.202Z] 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_17557202437073/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-20T20:19:04.202Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-20T20:19:04.202Z] echo "Nothing to be done for teardown."; \ [2025-08-20T20:19:04.202Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17557202437073/TestTargetResult"; [2025-08-20T20:19:04.202Z] [2025-08-20T20:19:04.202Z] TEST SETUP: [2025-08-20T20:19:04.202Z] Nothing to be done for setup. [2025-08-20T20:19:04.202Z] [2025-08-20T20:19:04.202Z] TESTING: [2025-08-20T20:19:04.584Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-20T20:19:04.584Z] GC before operation: completed in 7.672 ms, heap usage 18.480 MB -> 4.860 MB. [2025-08-20T20:19:05.866Z] Camera thread performed 122 scans. [2025-08-20T20:19:05.866Z] ====== philosophers (scala) [default], iteration 0 completed (1058.672 ms) ====== [2025-08-20T20:19:05.866Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-20T20:19:05.866Z] GC before operation: completed in 11.191 ms, heap usage 16.473 MB -> 5.232 MB. [2025-08-20T20:19:06.222Z] Camera thread performed 122 scans. [2025-08-20T20:19:06.222Z] ====== philosophers (scala) [default], iteration 1 completed (770.669 ms) ====== [2025-08-20T20:19:06.222Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-20T20:19:06.222Z] GC before operation: completed in 8.081 ms, heap usage 44.387 MB -> 5.232 MB. [2025-08-20T20:19:07.000Z] Camera thread performed 122 scans. [2025-08-20T20:19:07.000Z] ====== philosophers (scala) [default], iteration 2 completed (787.957 ms) ====== [2025-08-20T20:19:07.000Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-20T20:19:07.000Z] GC before operation: completed in 7.849 ms, heap usage 28.630 MB -> 5.233 MB. [2025-08-20T20:19:08.228Z] Camera thread performed 122 scans. [2025-08-20T20:19:08.228Z] ====== philosophers (scala) [default], iteration 3 completed (810.739 ms) ====== [2025-08-20T20:19:08.228Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-20T20:19:08.228Z] GC before operation: completed in 7.181 ms, heap usage 8.785 MB -> 5.233 MB. [2025-08-20T20:19:08.587Z] Camera thread performed 122 scans. [2025-08-20T20:19:08.587Z] ====== philosophers (scala) [default], iteration 4 completed (660.449 ms) ====== [2025-08-20T20:19:08.587Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-20T20:19:08.588Z] GC before operation: completed in 6.921 ms, heap usage 13.904 MB -> 5.234 MB. [2025-08-20T20:19:09.356Z] Camera thread performed 122 scans. [2025-08-20T20:19:09.356Z] ====== philosophers (scala) [default], iteration 5 completed (709.334 ms) ====== [2025-08-20T20:19:09.356Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-20T20:19:09.356Z] GC before operation: completed in 7.774 ms, heap usage 52.457 MB -> 5.234 MB. [2025-08-20T20:19:10.130Z] Camera thread performed 122 scans. [2025-08-20T20:19:10.130Z] ====== philosophers (scala) [default], iteration 6 completed (793.206 ms) ====== [2025-08-20T20:19:10.130Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-20T20:19:10.130Z] GC before operation: completed in 11.254 ms, heap usage 18.423 MB -> 5.235 MB. [2025-08-20T20:19:10.897Z] Camera thread performed 122 scans. [2025-08-20T20:19:10.898Z] ====== philosophers (scala) [default], iteration 7 completed (792.793 ms) ====== [2025-08-20T20:19:10.898Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-20T20:19:10.898Z] GC before operation: completed in 11.338 ms, heap usage 6.415 MB -> 5.235 MB. [2025-08-20T20:19:11.694Z] Camera thread performed 122 scans. [2025-08-20T20:19:11.694Z] ====== philosophers (scala) [default], iteration 8 completed (810.368 ms) ====== [2025-08-20T20:19:11.694Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-20T20:19:11.694Z] GC before operation: completed in 8.935 ms, heap usage 20.742 MB -> 5.235 MB. [2025-08-20T20:19:12.453Z] Camera thread performed 122 scans. [2025-08-20T20:19:12.453Z] ====== philosophers (scala) [default], iteration 9 completed (754.773 ms) ====== [2025-08-20T20:19:12.453Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-20T20:19:12.453Z] GC before operation: completed in 7.290 ms, heap usage 6.176 MB -> 5.235 MB. [2025-08-20T20:19:13.680Z] Camera thread performed 122 scans. [2025-08-20T20:19:13.680Z] ====== philosophers (scala) [default], iteration 10 completed (834.155 ms) ====== [2025-08-20T20:19:13.680Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-20T20:19:13.680Z] GC before operation: completed in 11.385 ms, heap usage 18.395 MB -> 5.235 MB. [2025-08-20T20:19:14.035Z] Camera thread performed 122 scans. [2025-08-20T20:19:14.035Z] ====== philosophers (scala) [default], iteration 11 completed (773.834 ms) ====== [2025-08-20T20:19:14.035Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-20T20:19:14.035Z] GC before operation: completed in 7.961 ms, heap usage 19.051 MB -> 5.236 MB. [2025-08-20T20:19:15.261Z] Camera thread performed 122 scans. [2025-08-20T20:19:15.261Z] ====== philosophers (scala) [default], iteration 12 completed (785.671 ms) ====== [2025-08-20T20:19:15.261Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-20T20:19:15.261Z] GC before operation: completed in 7.358 ms, heap usage 17.405 MB -> 5.237 MB. [2025-08-20T20:19:15.616Z] Camera thread performed 122 scans. [2025-08-20T20:19:15.616Z] ====== philosophers (scala) [default], iteration 13 completed (719.826 ms) ====== [2025-08-20T20:19:15.616Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-20T20:19:15.616Z] GC before operation: completed in 12.410 ms, heap usage 19.837 MB -> 5.236 MB. [2025-08-20T20:19:16.376Z] Camera thread performed 122 scans. [2025-08-20T20:19:16.376Z] ====== philosophers (scala) [default], iteration 14 completed (747.653 ms) ====== [2025-08-20T20:19:16.376Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-20T20:19:16.376Z] GC before operation: completed in 9.391 ms, heap usage 27.071 MB -> 5.237 MB. [2025-08-20T20:19:17.140Z] Camera thread performed 122 scans. [2025-08-20T20:19:17.140Z] ====== philosophers (scala) [default], iteration 15 completed (739.022 ms) ====== [2025-08-20T20:19:17.140Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-20T20:19:17.140Z] GC before operation: completed in 7.724 ms, heap usage 38.674 MB -> 5.237 MB. [2025-08-20T20:19:17.898Z] Camera thread performed 122 scans. [2025-08-20T20:19:17.898Z] ====== philosophers (scala) [default], iteration 16 completed (767.145 ms) ====== [2025-08-20T20:19:17.898Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-20T20:19:17.898Z] GC before operation: completed in 7.607 ms, heap usage 37.699 MB -> 5.237 MB. [2025-08-20T20:19:18.661Z] Camera thread performed 122 scans. [2025-08-20T20:19:18.661Z] ====== philosophers (scala) [default], iteration 17 completed (796.780 ms) ====== [2025-08-20T20:19:18.661Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-20T20:19:18.661Z] GC before operation: completed in 9.542 ms, heap usage 10.684 MB -> 5.237 MB. [2025-08-20T20:19:19.426Z] Camera thread performed 122 scans. [2025-08-20T20:19:19.426Z] ====== philosophers (scala) [default], iteration 18 completed (730.840 ms) ====== [2025-08-20T20:19:19.426Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-20T20:19:19.426Z] GC before operation: completed in 6.997 ms, heap usage 44.385 MB -> 5.237 MB. [2025-08-20T20:19:20.193Z] Camera thread performed 122 scans. [2025-08-20T20:19:20.193Z] ====== philosophers (scala) [default], iteration 19 completed (748.014 ms) ====== [2025-08-20T20:19:20.193Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-20T20:19:20.193Z] GC before operation: completed in 7.257 ms, heap usage 21.723 MB -> 5.237 MB. [2025-08-20T20:19:20.995Z] Camera thread performed 122 scans. [2025-08-20T20:19:20.995Z] ====== philosophers (scala) [default], iteration 20 completed (721.521 ms) ====== [2025-08-20T20:19:20.995Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-20T20:19:20.995Z] GC before operation: completed in 7.482 ms, heap usage 51.115 MB -> 5.238 MB. [2025-08-20T20:19:21.755Z] Camera thread performed 122 scans. [2025-08-20T20:19:21.755Z] ====== philosophers (scala) [default], iteration 21 completed (711.611 ms) ====== [2025-08-20T20:19:21.755Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-20T20:19:21.755Z] GC before operation: completed in 10.919 ms, heap usage 17.413 MB -> 5.237 MB. [2025-08-20T20:19:22.527Z] Camera thread performed 122 scans. [2025-08-20T20:19:22.527Z] ====== philosophers (scala) [default], iteration 22 completed (684.912 ms) ====== [2025-08-20T20:19:22.527Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-20T20:19:22.527Z] GC before operation: completed in 7.454 ms, heap usage 18.186 MB -> 5.238 MB. [2025-08-20T20:19:23.293Z] Camera thread performed 122 scans. [2025-08-20T20:19:23.293Z] ====== philosophers (scala) [default], iteration 23 completed (729.718 ms) ====== [2025-08-20T20:19:23.293Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-20T20:19:23.293Z] GC before operation: completed in 9.054 ms, heap usage 22.344 MB -> 5.238 MB. [2025-08-20T20:19:24.060Z] Camera thread performed 122 scans. [2025-08-20T20:19:24.060Z] ====== philosophers (scala) [default], iteration 24 completed (789.247 ms) ====== [2025-08-20T20:19:24.060Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-20T20:19:24.060Z] GC before operation: completed in 9.577 ms, heap usage 26.700 MB -> 5.238 MB. [2025-08-20T20:19:24.911Z] Camera thread performed 122 scans. [2025-08-20T20:19:24.911Z] ====== philosophers (scala) [default], iteration 25 completed (699.278 ms) ====== [2025-08-20T20:19:24.911Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-20T20:19:24.911Z] GC before operation: completed in 7.302 ms, heap usage 45.404 MB -> 5.238 MB. [2025-08-20T20:19:25.683Z] Camera thread performed 122 scans. [2025-08-20T20:19:25.683Z] ====== philosophers (scala) [default], iteration 26 completed (773.114 ms) ====== [2025-08-20T20:19:25.683Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-20T20:19:25.683Z] GC before operation: completed in 7.508 ms, heap usage 38.410 MB -> 5.238 MB. [2025-08-20T20:19:26.039Z] Camera thread performed 122 scans. [2025-08-20T20:19:26.039Z] ====== philosophers (scala) [default], iteration 27 completed (720.883 ms) ====== [2025-08-20T20:19:26.039Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-20T20:19:26.039Z] GC before operation: completed in 9.641 ms, heap usage 10.172 MB -> 5.238 MB. [2025-08-20T20:19:26.807Z] Camera thread performed 122 scans. [2025-08-20T20:19:26.807Z] ====== philosophers (scala) [default], iteration 28 completed (724.373 ms) ====== [2025-08-20T20:19:26.807Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-20T20:19:26.807Z] GC before operation: completed in 9.168 ms, heap usage 12.024 MB -> 5.238 MB. [2025-08-20T20:19:27.576Z] Camera thread performed 122 scans. [2025-08-20T20:19:27.577Z] ====== philosophers (scala) [default], iteration 29 completed (697.843 ms) ====== [2025-08-20T20:19:27.929Z] ----------------------------------- [2025-08-20T20:19:27.929Z] renaissance-philosophers_0_PASSED [2025-08-20T20:19:27.929Z] ----------------------------------- [2025-08-20T20:19:27.929Z] [2025-08-20T20:19:27.929Z] TEST TEARDOWN: [2025-08-20T20:19:27.929Z] Nothing to be done for teardown. [2025-08-20T20:19:27.929Z] renaissance-philosophers_0 Finish Time: Wed Aug 20 16:19:27 2025 Epoch Time (ms): 1755721167562