[2025-08-30T01:41:03.383Z] Running test renaissance-philosophers_0 ... [2025-08-30T01:41:03.383Z] =============================================== [2025-08-30T01:41:03.383Z] renaissance-philosophers_0 Start Time: Fri Aug 29 18:41:02 2025 Epoch Time (ms): 1756518062852 [2025-08-30T01:41:03.383Z] variation: NoOptions [2025-08-30T01:41:03.383Z] JVM_OPTIONS: [2025-08-30T01:41:03.383Z] { \ [2025-08-30T01:41:03.383Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T01:41:03.383Z] echo "Nothing to be done for setup."; \ [2025-08-30T01:41:03.383Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/renaissance-philosophers_0"; \ [2025-08-30T01:41:03.383Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/renaissance-philosophers_0"; \ [2025-08-30T01:41:03.383Z] echo ""; echo "TESTING:"; \ [2025-08-30T01:41:03.383Z] "/Users/admin/workspace/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/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-30T01:41:03.383Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T01:41:03.383Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T01:41:03.383Z] echo "Nothing to be done for teardown."; \ [2025-08-30T01:41:03.383Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/TestTargetResult"; [2025-08-30T01:41:03.383Z] [2025-08-30T01:41:03.383Z] TEST SETUP: [2025-08-30T01:41:03.383Z] Nothing to be done for setup. [2025-08-30T01:41:03.383Z] [2025-08-30T01:41:03.383Z] TESTING: [2025-08-30T01:41:06.112Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-30T01:41:06.112Z] GC before operation: completed in 75.915 ms, heap usage 10.447 MB -> 4.563 MB. [2025-08-30T01:41:10.020Z] Camera thread performed 122 scans. [2025-08-30T01:41:10.020Z] ====== philosophers (scala) [default], iteration 0 completed (4226.403 ms) ====== [2025-08-30T01:41:10.020Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-30T01:41:10.020Z] GC before operation: completed in 29.488 ms, heap usage 42.800 MB -> 4.933 MB. [2025-08-30T01:41:12.231Z] Camera thread performed 122 scans. [2025-08-30T01:41:12.231Z] ====== philosophers (scala) [default], iteration 1 completed (1764.443 ms) ====== [2025-08-30T01:41:12.231Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-30T01:41:12.231Z] GC before operation: completed in 24.447 ms, heap usage 24.839 MB -> 4.935 MB. [2025-08-30T01:41:13.653Z] Camera thread performed 122 scans. [2025-08-30T01:41:13.653Z] ====== philosophers (scala) [default], iteration 2 completed (1455.905 ms) ====== [2025-08-30T01:41:13.653Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-30T01:41:13.653Z] GC before operation: completed in 122.435 ms, heap usage 23.445 MB -> 4.937 MB. [2025-08-30T01:41:15.735Z] Camera thread performed 122 scans. [2025-08-30T01:41:15.735Z] ====== philosophers (scala) [default], iteration 3 completed (1958.721 ms) ====== [2025-08-30T01:41:15.735Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-30T01:41:15.735Z] GC before operation: completed in 50.775 ms, heap usage 181.071 MB -> 4.937 MB. [2025-08-30T01:41:17.877Z] Camera thread performed 122 scans. [2025-08-30T01:41:17.877Z] ====== philosophers (scala) [default], iteration 4 completed (2112.925 ms) ====== [2025-08-30T01:41:17.877Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-30T01:41:17.877Z] GC before operation: completed in 22.775 ms, heap usage 77.031 MB -> 4.937 MB. [2025-08-30T01:41:19.326Z] Camera thread performed 122 scans. [2025-08-30T01:41:19.326Z] ====== philosophers (scala) [default], iteration 5 completed (1843.552 ms) ====== [2025-08-30T01:41:19.326Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-30T01:41:19.806Z] GC before operation: completed in 30.619 ms, heap usage 166.618 MB -> 4.937 MB. [2025-08-30T01:41:20.680Z] Camera thread performed 122 scans. [2025-08-30T01:41:20.680Z] ====== philosophers (scala) [default], iteration 6 completed (1155.474 ms) ====== [2025-08-30T01:41:20.680Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-30T01:41:20.680Z] GC before operation: completed in 55.591 ms, heap usage 91.389 MB -> 4.938 MB. [2025-08-30T01:41:22.898Z] Camera thread performed 122 scans. [2025-08-30T01:41:22.898Z] ====== philosophers (scala) [default], iteration 7 completed (2284.357 ms) ====== [2025-08-30T01:41:22.898Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-30T01:41:22.898Z] GC before operation: completed in 40.222 ms, heap usage 63.444 MB -> 4.942 MB. [2025-08-30T01:41:24.901Z] Camera thread performed 122 scans. [2025-08-30T01:41:24.901Z] ====== philosophers (scala) [default], iteration 8 completed (1944.213 ms) ====== [2025-08-30T01:41:24.901Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-30T01:41:24.901Z] GC before operation: completed in 52.150 ms, heap usage 157.572 MB -> 4.942 MB. [2025-08-30T01:41:26.974Z] Camera thread performed 122 scans. [2025-08-30T01:41:26.974Z] ====== philosophers (scala) [default], iteration 9 completed (1625.093 ms) ====== [2025-08-30T01:41:26.974Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-30T01:41:26.974Z] GC before operation: completed in 32.959 ms, heap usage 68.280 MB -> 4.942 MB. [2025-08-30T01:41:27.816Z] Camera thread performed 122 scans. [2025-08-30T01:41:27.816Z] ====== philosophers (scala) [default], iteration 10 completed (1089.770 ms) ====== [2025-08-30T01:41:27.817Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-30T01:41:27.817Z] GC before operation: completed in 48.115 ms, heap usage 79.897 MB -> 4.942 MB. [2025-08-30T01:41:29.285Z] Camera thread performed 122 scans. [2025-08-30T01:41:29.285Z] ====== philosophers (scala) [default], iteration 11 completed (1487.608 ms) ====== [2025-08-30T01:41:29.285Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-30T01:41:29.285Z] GC before operation: completed in 67.401 ms, heap usage 153.072 MB -> 4.969 MB. [2025-08-30T01:41:31.279Z] Camera thread performed 122 scans. [2025-08-30T01:41:31.279Z] ====== philosophers (scala) [default], iteration 12 completed (1581.197 ms) ====== [2025-08-30T01:41:31.279Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-30T01:41:31.279Z] GC before operation: completed in 42.775 ms, heap usage 161.563 MB -> 4.970 MB. [2025-08-30T01:41:33.357Z] Camera thread performed 122 scans. [2025-08-30T01:41:33.357Z] ====== philosophers (scala) [default], iteration 13 completed (1992.929 ms) ====== [2025-08-30T01:41:33.357Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-30T01:41:33.357Z] GC before operation: completed in 48.067 ms, heap usage 9.961 MB -> 4.943 MB. [2025-08-30T01:41:34.895Z] Camera thread performed 122 scans. [2025-08-30T01:41:34.895Z] ====== philosophers (scala) [default], iteration 14 completed (1539.252 ms) ====== [2025-08-30T01:41:34.895Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-30T01:41:34.895Z] GC before operation: completed in 307.651 ms, heap usage 176.079 MB -> 4.943 MB. [2025-08-30T01:41:36.989Z] Camera thread performed 122 scans. [2025-08-30T01:41:36.989Z] ====== philosophers (scala) [default], iteration 15 completed (2023.227 ms) ====== [2025-08-30T01:41:36.989Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-30T01:41:36.989Z] GC before operation: completed in 46.136 ms, heap usage 88.681 MB -> 4.943 MB. [2025-08-30T01:41:39.627Z] Camera thread performed 122 scans. [2025-08-30T01:41:39.627Z] ====== philosophers (scala) [default], iteration 16 completed (2210.102 ms) ====== [2025-08-30T01:41:39.627Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-30T01:41:39.627Z] GC before operation: completed in 38.072 ms, heap usage 170.847 MB -> 4.971 MB. [2025-08-30T01:41:41.041Z] Camera thread performed 122 scans. [2025-08-30T01:41:41.041Z] ====== philosophers (scala) [default], iteration 17 completed (1632.422 ms) ====== [2025-08-30T01:41:41.041Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-30T01:41:41.041Z] GC before operation: completed in 31.062 ms, heap usage 146.285 MB -> 4.944 MB. [2025-08-30T01:41:42.483Z] Camera thread performed 122 scans. [2025-08-30T01:41:42.484Z] ====== philosophers (scala) [default], iteration 18 completed (1546.983 ms) ====== [2025-08-30T01:41:42.484Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-30T01:41:42.484Z] GC before operation: completed in 67.426 ms, heap usage 141.937 MB -> 4.945 MB. [2025-08-30T01:41:44.457Z] Camera thread performed 122 scans. [2025-08-30T01:41:44.457Z] ====== philosophers (scala) [default], iteration 19 completed (1642.942 ms) ====== [2025-08-30T01:41:44.457Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-30T01:41:44.457Z] GC before operation: completed in 55.989 ms, heap usage 153.104 MB -> 4.945 MB. [2025-08-30T01:41:45.875Z] Camera thread performed 122 scans. [2025-08-30T01:41:45.875Z] ====== philosophers (scala) [default], iteration 20 completed (1437.589 ms) ====== [2025-08-30T01:41:45.875Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-30T01:41:45.875Z] GC before operation: completed in 29.553 ms, heap usage 82.676 MB -> 4.945 MB. [2025-08-30T01:41:47.405Z] Camera thread performed 122 scans. [2025-08-30T01:41:47.405Z] ====== philosophers (scala) [default], iteration 21 completed (1688.905 ms) ====== [2025-08-30T01:41:47.405Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-30T01:41:47.405Z] GC before operation: completed in 25.477 ms, heap usage 78.050 MB -> 4.945 MB. [2025-08-30T01:41:49.402Z] Camera thread performed 122 scans. [2025-08-30T01:41:49.402Z] ====== philosophers (scala) [default], iteration 22 completed (1594.624 ms) ====== [2025-08-30T01:41:49.402Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-30T01:41:49.402Z] GC before operation: completed in 75.441 ms, heap usage 206.435 MB -> 4.945 MB. [2025-08-30T01:41:50.998Z] Camera thread performed 122 scans. [2025-08-30T01:41:50.998Z] ====== philosophers (scala) [default], iteration 23 completed (1734.306 ms) ====== [2025-08-30T01:41:50.998Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-30T01:41:50.998Z] GC before operation: completed in 33.152 ms, heap usage 10.912 MB -> 4.945 MB. [2025-08-30T01:41:52.441Z] Camera thread performed 122 scans. [2025-08-30T01:41:52.441Z] ====== philosophers (scala) [default], iteration 24 completed (1465.730 ms) ====== [2025-08-30T01:41:52.441Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-30T01:41:52.441Z] GC before operation: completed in 70.173 ms, heap usage 155.855 MB -> 4.945 MB. [2025-08-30T01:41:54.589Z] Camera thread performed 122 scans. [2025-08-30T01:41:54.589Z] ====== philosophers (scala) [default], iteration 25 completed (1732.658 ms) ====== [2025-08-30T01:41:54.589Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-30T01:41:54.589Z] GC before operation: completed in 73.124 ms, heap usage 141.060 MB -> 4.945 MB. [2025-08-30T01:41:56.819Z] Camera thread performed 122 scans. [2025-08-30T01:41:56.820Z] ====== philosophers (scala) [default], iteration 26 completed (2592.932 ms) ====== [2025-08-30T01:41:56.820Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-30T01:41:56.820Z] GC before operation: completed in 19.229 ms, heap usage 141.027 MB -> 4.946 MB. [2025-08-30T01:41:58.967Z] Camera thread performed 122 scans. [2025-08-30T01:41:58.967Z] ====== philosophers (scala) [default], iteration 27 completed (1827.683 ms) ====== [2025-08-30T01:41:58.967Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-30T01:41:58.967Z] GC before operation: completed in 40.757 ms, heap usage 154.666 MB -> 4.974 MB. [2025-08-30T01:42:01.137Z] Camera thread performed 122 scans. [2025-08-30T01:42:01.137Z] ====== philosophers (scala) [default], iteration 28 completed (2076.600 ms) ====== [2025-08-30T01:42:01.137Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-30T01:42:01.137Z] GC before operation: completed in 37.551 ms, heap usage 152.257 MB -> 4.946 MB. [2025-08-30T01:42:02.634Z] Camera thread performed 122 scans. [2025-08-30T01:42:02.634Z] ====== philosophers (scala) [default], iteration 29 completed (1576.774 ms) ====== [2025-08-30T01:42:03.071Z] ----------------------------------- [2025-08-30T01:42:03.071Z] renaissance-philosophers_0_PASSED [2025-08-30T01:42:03.071Z] ----------------------------------- [2025-08-30T01:42:03.071Z] [2025-08-30T01:42:03.071Z] TEST TEARDOWN: [2025-08-30T01:42:03.071Z] Nothing to be done for teardown. [2025-08-30T01:42:03.071Z] renaissance-philosophers_0 Finish Time: Fri Aug 29 18:42:02 2025 Epoch Time (ms): 1756518122517