[2025-05-28T22:32:41.932Z] Running test renaissance-philosophers_0 ... [2025-05-28T22:32:41.932Z] =============================================== [2025-05-28T22:32:41.932Z] renaissance-philosophers_0 Start Time: Wed May 28 15:32:41 2025 Epoch Time (ms): 1748471561606 [2025-05-28T22:32:42.341Z] variation: NoOptions [2025-05-28T22:32:42.341Z] JVM_OPTIONS: [2025-05-28T22:32:42.341Z] { \ [2025-05-28T22:32:42.341Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T22:32:42.341Z] echo "Nothing to be done for setup."; \ [2025-05-28T22:32:42.341Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17484668972134/renaissance-philosophers_0"; \ [2025-05-28T22:32:42.341Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17484668972134/renaissance-philosophers_0"; \ [2025-05-28T22:32:42.341Z] echo ""; echo "TESTING:"; \ [2025-05-28T22:32:42.341Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/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_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17484668972134/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-28T22:32:42.341Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17484668972134/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T22:32:42.341Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T22:32:42.341Z] echo "Nothing to be done for teardown."; \ [2025-05-28T22:32:42.341Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17484668972134/TestTargetResult"; [2025-05-28T22:32:42.341Z] [2025-05-28T22:32:42.341Z] TEST SETUP: [2025-05-28T22:32:42.341Z] Nothing to be done for setup. [2025-05-28T22:32:42.341Z] [2025-05-28T22:32:42.341Z] TESTING: [2025-05-28T22:32:43.924Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-28T22:32:43.924Z] GC before operation: completed in 83.764 ms, heap usage 9.988 MB -> 4.463 MB. [2025-05-28T22:32:49.698Z] Camera thread performed 122 scans. [2025-05-28T22:32:49.698Z] ====== philosophers (scala) [default], iteration 0 completed (4849.720 ms) ====== [2025-05-28T22:32:49.698Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-28T22:32:49.698Z] GC before operation: completed in 55.688 ms, heap usage 108.699 MB -> 4.867 MB. [2025-05-28T22:32:52.642Z] Camera thread performed 122 scans. [2025-05-28T22:32:52.642Z] ====== philosophers (scala) [default], iteration 1 completed (3747.835 ms) ====== [2025-05-28T22:32:52.642Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-28T22:32:52.642Z] GC before operation: completed in 45.197 ms, heap usage 51.010 MB -> 4.867 MB. [2025-05-28T22:32:55.592Z] Camera thread performed 122 scans. [2025-05-28T22:32:55.592Z] ====== philosophers (scala) [default], iteration 2 completed (2816.736 ms) ====== [2025-05-28T22:32:55.592Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-28T22:32:55.592Z] GC before operation: completed in 40.288 ms, heap usage 92.945 MB -> 4.868 MB. [2025-05-28T22:32:57.114Z] Camera thread performed 122 scans. [2025-05-28T22:32:57.114Z] ====== philosophers (scala) [default], iteration 3 completed (1583.836 ms) ====== [2025-05-28T22:32:57.114Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-28T22:32:57.533Z] GC before operation: completed in 49.361 ms, heap usage 69.012 MB -> 4.870 MB. [2025-05-28T22:32:59.535Z] Camera thread performed 122 scans. [2025-05-28T22:32:59.535Z] ====== philosophers (scala) [default], iteration 4 completed (2061.873 ms) ====== [2025-05-28T22:32:59.535Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-28T22:32:59.535Z] GC before operation: completed in 50.635 ms, heap usage 187.279 MB -> 4.874 MB. [2025-05-28T22:33:00.946Z] Camera thread performed 122 scans. [2025-05-28T22:33:00.946Z] ====== philosophers (scala) [default], iteration 5 completed (1307.187 ms) ====== [2025-05-28T22:33:00.946Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-28T22:33:00.946Z] GC before operation: completed in 39.426 ms, heap usage 144.024 MB -> 4.874 MB. [2025-05-28T22:33:03.034Z] Camera thread performed 122 scans. [2025-05-28T22:33:03.034Z] ====== philosophers (scala) [default], iteration 6 completed (1826.683 ms) ====== [2025-05-28T22:33:03.034Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-28T22:33:03.034Z] GC before operation: completed in 69.674 ms, heap usage 24.048 MB -> 4.875 MB. [2025-05-28T22:33:04.464Z] Camera thread performed 122 scans. [2025-05-28T22:33:04.464Z] ====== philosophers (scala) [default], iteration 7 completed (1577.344 ms) ====== [2025-05-28T22:33:04.464Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-28T22:33:04.464Z] GC before operation: completed in 66.266 ms, heap usage 80.064 MB -> 4.875 MB. [2025-05-28T22:33:06.560Z] Camera thread performed 122 scans. [2025-05-28T22:33:06.560Z] ====== philosophers (scala) [default], iteration 8 completed (1877.111 ms) ====== [2025-05-28T22:33:06.560Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-28T22:33:06.560Z] GC before operation: completed in 66.532 ms, heap usage 151.997 MB -> 4.875 MB. [2025-05-28T22:33:08.668Z] Camera thread performed 122 scans. [2025-05-28T22:33:08.668Z] ====== philosophers (scala) [default], iteration 9 completed (2209.499 ms) ====== [2025-05-28T22:33:08.668Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-28T22:33:08.668Z] GC before operation: completed in 60.630 ms, heap usage 123.808 MB -> 4.875 MB. [2025-05-28T22:33:10.068Z] Camera thread performed 122 scans. [2025-05-28T22:33:10.068Z] ====== philosophers (scala) [default], iteration 10 completed (1596.073 ms) ====== [2025-05-28T22:33:10.068Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-28T22:33:10.068Z] GC before operation: completed in 36.231 ms, heap usage 73.023 MB -> 4.876 MB. [2025-05-28T22:33:11.573Z] Camera thread performed 122 scans. [2025-05-28T22:33:11.573Z] ====== philosophers (scala) [default], iteration 11 completed (1271.362 ms) ====== [2025-05-28T22:33:11.573Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-28T22:33:11.573Z] GC before operation: completed in 20.515 ms, heap usage 21.511 MB -> 4.876 MB. [2025-05-28T22:33:13.042Z] Camera thread performed 122 scans. [2025-05-28T22:33:13.042Z] ====== philosophers (scala) [default], iteration 12 completed (1527.850 ms) ====== [2025-05-28T22:33:13.042Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-28T22:33:13.042Z] GC before operation: completed in 53.962 ms, heap usage 188.023 MB -> 4.877 MB. [2025-05-28T22:33:15.011Z] Camera thread performed 122 scans. [2025-05-28T22:33:15.011Z] ====== philosophers (scala) [default], iteration 13 completed (1639.200 ms) ====== [2025-05-28T22:33:15.011Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-28T22:33:15.011Z] GC before operation: completed in 33.990 ms, heap usage 69.963 MB -> 4.876 MB. [2025-05-28T22:33:16.402Z] Camera thread performed 122 scans. [2025-05-28T22:33:16.402Z] ====== philosophers (scala) [default], iteration 14 completed (1655.166 ms) ====== [2025-05-28T22:33:16.402Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-28T22:33:16.402Z] GC before operation: completed in 132.535 ms, heap usage 99.026 MB -> 4.877 MB. [2025-05-28T22:33:18.395Z] Camera thread performed 122 scans. [2025-05-28T22:33:18.395Z] ====== philosophers (scala) [default], iteration 15 completed (1792.901 ms) ====== [2025-05-28T22:33:18.395Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-28T22:33:18.395Z] GC before operation: completed in 56.610 ms, heap usage 147.890 MB -> 4.906 MB. [2025-05-28T22:33:20.490Z] Camera thread performed 122 scans. [2025-05-28T22:33:20.490Z] ====== philosophers (scala) [default], iteration 16 completed (1825.432 ms) ====== [2025-05-28T22:33:20.490Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-28T22:33:20.490Z] GC before operation: completed in 27.287 ms, heap usage 126.750 MB -> 4.877 MB. [2025-05-28T22:33:21.833Z] Camera thread performed 122 scans. [2025-05-28T22:33:21.833Z] ====== philosophers (scala) [default], iteration 17 completed (1324.057 ms) ====== [2025-05-28T22:33:21.833Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-28T22:33:21.833Z] GC before operation: completed in 34.409 ms, heap usage 174.023 MB -> 4.877 MB. [2025-05-28T22:33:22.719Z] Camera thread performed 122 scans. [2025-05-28T22:33:22.719Z] ====== philosophers (scala) [default], iteration 18 completed (1237.478 ms) ====== [2025-05-28T22:33:22.719Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-28T22:33:22.719Z] GC before operation: completed in 49.178 ms, heap usage 141.023 MB -> 4.877 MB. [2025-05-28T22:33:24.893Z] Camera thread performed 122 scans. [2025-05-28T22:33:24.893Z] ====== philosophers (scala) [default], iteration 19 completed (1822.312 ms) ====== [2025-05-28T22:33:24.893Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-28T22:33:24.893Z] GC before operation: completed in 72.598 ms, heap usage 168.063 MB -> 4.906 MB. [2025-05-28T22:33:26.302Z] Camera thread performed 122 scans. [2025-05-28T22:33:26.302Z] ====== philosophers (scala) [default], iteration 20 completed (1435.109 ms) ====== [2025-05-28T22:33:26.302Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-28T22:33:26.302Z] GC before operation: completed in 25.430 ms, heap usage 21.532 MB -> 4.878 MB. [2025-05-28T22:33:27.752Z] Camera thread performed 122 scans. [2025-05-28T22:33:27.752Z] ====== philosophers (scala) [default], iteration 21 completed (1684.324 ms) ====== [2025-05-28T22:33:27.752Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-28T22:33:27.752Z] GC before operation: completed in 30.203 ms, heap usage 193.023 MB -> 4.877 MB. [2025-05-28T22:33:29.756Z] Camera thread performed 122 scans. [2025-05-28T22:33:29.756Z] ====== philosophers (scala) [default], iteration 22 completed (1619.709 ms) ====== [2025-05-28T22:33:29.756Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-28T22:33:29.756Z] GC before operation: completed in 43.347 ms, heap usage 166.711 MB -> 4.906 MB. [2025-05-28T22:33:31.746Z] Camera thread performed 122 scans. [2025-05-28T22:33:31.746Z] ====== philosophers (scala) [default], iteration 23 completed (1948.241 ms) ====== [2025-05-28T22:33:31.746Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-28T22:33:31.746Z] GC before operation: completed in 43.248 ms, heap usage 98.011 MB -> 4.878 MB. [2025-05-28T22:33:32.617Z] Camera thread performed 122 scans. [2025-05-28T22:33:32.617Z] ====== philosophers (scala) [default], iteration 24 completed (1186.576 ms) ====== [2025-05-28T22:33:32.617Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-28T22:33:33.000Z] GC before operation: completed in 44.856 ms, heap usage 20.991 MB -> 4.907 MB. [2025-05-28T22:33:34.354Z] Camera thread performed 122 scans. [2025-05-28T22:33:34.354Z] ====== philosophers (scala) [default], iteration 25 completed (1431.194 ms) ====== [2025-05-28T22:33:34.354Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-28T22:33:34.354Z] GC before operation: completed in 41.035 ms, heap usage 26.872 MB -> 4.879 MB. [2025-05-28T22:33:35.903Z] Camera thread performed 122 scans. [2025-05-28T22:33:35.903Z] ====== philosophers (scala) [default], iteration 26 completed (1230.254 ms) ====== [2025-05-28T22:33:35.903Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-28T22:33:35.903Z] GC before operation: completed in 50.194 ms, heap usage 8.976 MB -> 4.879 MB. [2025-05-28T22:33:37.366Z] Camera thread performed 122 scans. [2025-05-28T22:33:37.367Z] ====== philosophers (scala) [default], iteration 27 completed (1489.431 ms) ====== [2025-05-28T22:33:37.367Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-28T22:33:37.367Z] GC before operation: completed in 50.393 ms, heap usage 173.104 MB -> 4.879 MB. [2025-05-28T22:33:38.752Z] Camera thread performed 122 scans. [2025-05-28T22:33:38.752Z] ====== philosophers (scala) [default], iteration 28 completed (1805.402 ms) ====== [2025-05-28T22:33:38.752Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-28T22:33:39.179Z] GC before operation: completed in 82.808 ms, heap usage 147.934 MB -> 4.880 MB. [2025-05-28T22:33:41.375Z] Camera thread performed 122 scans. [2025-05-28T22:33:41.375Z] ====== philosophers (scala) [default], iteration 29 completed (2340.552 ms) ====== [2025-05-28T22:33:41.812Z] ----------------------------------- [2025-05-28T22:33:41.812Z] renaissance-philosophers_0_PASSED [2025-05-28T22:33:41.812Z] ----------------------------------- [2025-05-28T22:33:41.812Z] [2025-05-28T22:33:41.812Z] TEST TEARDOWN: [2025-05-28T22:33:41.812Z] Nothing to be done for teardown. [2025-05-28T22:33:41.812Z] renaissance-philosophers_0 Finish Time: Wed May 28 15:33:41 2025 Epoch Time (ms): 1748471621291