[2025-08-13T21:56:54.525Z] Running test renaissance-philosophers_0 ... [2025-08-13T21:56:54.525Z] =============================================== [2025-08-13T21:56:54.525Z] renaissance-philosophers_0 Start Time: Wed Aug 13 14:56:54 2025 Epoch Time (ms): 1755122214086 [2025-08-13T21:56:54.525Z] variation: NoOptions [2025-08-13T21:56:54.525Z] JVM_OPTIONS: [2025-08-13T21:56:54.525Z] { \ [2025-08-13T21:56:54.525Z] echo ""; echo "TEST SETUP:"; \ [2025-08-13T21:56:54.525Z] echo "Nothing to be done for setup."; \ [2025-08-13T21:56:54.525Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/renaissance-philosophers_0"; \ [2025-08-13T21:56:54.525Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/renaissance-philosophers_0"; \ [2025-08-13T21:56:54.525Z] echo ""; echo "TESTING:"; \ [2025-08-13T21:56:54.525Z] "/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_17551174266516/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-13T21:56:54.525Z] 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_17551174266516/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-13T21:56:54.525Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-13T21:56:54.525Z] echo "Nothing to be done for teardown."; \ [2025-08-13T21:56:54.525Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/TestTargetResult"; [2025-08-13T21:56:54.525Z] [2025-08-13T21:56:54.525Z] TEST SETUP: [2025-08-13T21:56:54.525Z] Nothing to be done for setup. [2025-08-13T21:56:54.525Z] [2025-08-13T21:56:54.525Z] TESTING: [2025-08-13T21:56:57.573Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-13T21:56:57.573Z] GC before operation: completed in 81.432 ms, heap usage 10.040 MB -> 4.520 MB. [2025-08-13T21:57:03.371Z] Camera thread performed 122 scans. [2025-08-13T21:57:03.371Z] ====== philosophers (scala) [default], iteration 0 completed (5442.078 ms) ====== [2025-08-13T21:57:03.371Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-13T21:57:03.371Z] GC before operation: completed in 56.318 ms, heap usage 152.520 MB -> 4.892 MB. [2025-08-13T21:57:05.608Z] Camera thread performed 122 scans. [2025-08-13T21:57:05.608Z] ====== philosophers (scala) [default], iteration 1 completed (2851.941 ms) ====== [2025-08-13T21:57:05.608Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-13T21:57:06.049Z] GC before operation: completed in 63.789 ms, heap usage 137.030 MB -> 4.920 MB. [2025-08-13T21:57:08.102Z] Camera thread performed 122 scans. [2025-08-13T21:57:08.102Z] ====== philosophers (scala) [default], iteration 2 completed (1939.538 ms) ====== [2025-08-13T21:57:08.102Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-13T21:57:08.102Z] GC before operation: completed in 37.215 ms, heap usage 14.201 MB -> 4.894 MB. [2025-08-13T21:57:10.870Z] Camera thread performed 122 scans. [2025-08-13T21:57:10.870Z] ====== philosophers (scala) [default], iteration 3 completed (2622.589 ms) ====== [2025-08-13T21:57:10.870Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-13T21:57:10.870Z] GC before operation: completed in 47.502 ms, heap usage 8.271 MB -> 4.894 MB. [2025-08-13T21:57:12.527Z] Camera thread performed 122 scans. [2025-08-13T21:57:12.527Z] ====== philosophers (scala) [default], iteration 4 completed (2123.084 ms) ====== [2025-08-13T21:57:12.527Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-13T21:57:12.527Z] GC before operation: completed in 57.994 ms, heap usage 96.613 MB -> 4.922 MB. [2025-08-13T21:57:15.414Z] Camera thread performed 122 scans. [2025-08-13T21:57:15.414Z] ====== philosophers (scala) [default], iteration 5 completed (2441.145 ms) ====== [2025-08-13T21:57:15.414Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-13T21:57:15.414Z] GC before operation: completed in 124.213 ms, heap usage 136.420 MB -> 4.895 MB. [2025-08-13T21:57:17.526Z] Camera thread performed 122 scans. [2025-08-13T21:57:17.526Z] ====== philosophers (scala) [default], iteration 6 completed (2026.809 ms) ====== [2025-08-13T21:57:17.526Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-13T21:57:17.526Z] GC before operation: completed in 102.444 ms, heap usage 141.445 MB -> 4.895 MB. [2025-08-13T21:57:20.277Z] Camera thread performed 122 scans. [2025-08-13T21:57:20.277Z] ====== philosophers (scala) [default], iteration 7 completed (2709.418 ms) ====== [2025-08-13T21:57:20.277Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-13T21:57:20.277Z] GC before operation: completed in 77.702 ms, heap usage 142.311 MB -> 4.895 MB. [2025-08-13T21:57:22.291Z] Camera thread performed 122 scans. [2025-08-13T21:57:22.291Z] ====== philosophers (scala) [default], iteration 8 completed (1921.173 ms) ====== [2025-08-13T21:57:22.291Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-13T21:57:22.291Z] GC before operation: completed in 45.045 ms, heap usage 145.411 MB -> 4.895 MB. [2025-08-13T21:57:25.130Z] Camera thread performed 122 scans. [2025-08-13T21:57:25.130Z] ====== philosophers (scala) [default], iteration 9 completed (2575.930 ms) ====== [2025-08-13T21:57:25.130Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-13T21:57:25.130Z] GC before operation: completed in 41.988 ms, heap usage 146.140 MB -> 4.927 MB. [2025-08-13T21:57:26.647Z] Camera thread performed 122 scans. [2025-08-13T21:57:26.647Z] ====== philosophers (scala) [default], iteration 10 completed (1616.307 ms) ====== [2025-08-13T21:57:26.647Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-13T21:57:26.647Z] GC before operation: completed in 55.451 ms, heap usage 71.564 MB -> 4.900 MB. [2025-08-13T21:57:28.182Z] Camera thread performed 122 scans. [2025-08-13T21:57:28.182Z] ====== philosophers (scala) [default], iteration 11 completed (1787.669 ms) ====== [2025-08-13T21:57:28.182Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-13T21:57:28.182Z] GC before operation: completed in 33.290 ms, heap usage 140.159 MB -> 4.900 MB. [2025-08-13T21:57:31.000Z] Camera thread performed 122 scans. [2025-08-13T21:57:31.000Z] ====== philosophers (scala) [default], iteration 12 completed (2746.498 ms) ====== [2025-08-13T21:57:31.000Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-13T21:57:31.000Z] GC before operation: completed in 73.645 ms, heap usage 143.438 MB -> 4.900 MB. [2025-08-13T21:57:33.045Z] Camera thread performed 122 scans. [2025-08-13T21:57:33.045Z] ====== philosophers (scala) [default], iteration 13 completed (1930.122 ms) ====== [2025-08-13T21:57:33.045Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-13T21:57:33.045Z] GC before operation: completed in 98.285 ms, heap usage 139.461 MB -> 4.900 MB. [2025-08-13T21:57:35.163Z] Camera thread performed 122 scans. [2025-08-13T21:57:35.163Z] ====== philosophers (scala) [default], iteration 14 completed (1783.438 ms) ====== [2025-08-13T21:57:35.163Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-13T21:57:35.163Z] GC before operation: completed in 344.191 ms, heap usage 58.884 MB -> 4.900 MB. [2025-08-13T21:57:37.234Z] Camera thread performed 122 scans. [2025-08-13T21:57:37.234Z] ====== philosophers (scala) [default], iteration 15 completed (1823.641 ms) ====== [2025-08-13T21:57:37.234Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-13T21:57:37.234Z] GC before operation: completed in 20.493 ms, heap usage 144.895 MB -> 4.928 MB. [2025-08-13T21:57:38.611Z] Camera thread performed 122 scans. [2025-08-13T21:57:38.611Z] ====== philosophers (scala) [default], iteration 16 completed (1613.402 ms) ====== [2025-08-13T21:57:38.611Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-13T21:57:38.611Z] GC before operation: completed in 56.007 ms, heap usage 144.451 MB -> 4.901 MB. [2025-08-13T21:57:41.404Z] Camera thread performed 122 scans. [2025-08-13T21:57:41.404Z] ====== philosophers (scala) [default], iteration 17 completed (2878.373 ms) ====== [2025-08-13T21:57:41.404Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-13T21:57:41.800Z] GC before operation: completed in 30.927 ms, heap usage 69.047 MB -> 4.901 MB. [2025-08-13T21:57:43.754Z] Camera thread performed 122 scans. [2025-08-13T21:57:43.754Z] ====== philosophers (scala) [default], iteration 18 completed (2043.954 ms) ====== [2025-08-13T21:57:43.754Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-13T21:57:43.754Z] GC before operation: completed in 133.453 ms, heap usage 152.352 MB -> 4.903 MB. [2025-08-13T21:57:46.487Z] Camera thread performed 122 scans. [2025-08-13T21:57:46.487Z] ====== philosophers (scala) [default], iteration 19 completed (2195.959 ms) ====== [2025-08-13T21:57:46.487Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-13T21:57:46.487Z] GC before operation: completed in 59.736 ms, heap usage 66.990 MB -> 4.930 MB. [2025-08-13T21:57:47.921Z] Camera thread performed 122 scans. [2025-08-13T21:57:47.921Z] ====== philosophers (scala) [default], iteration 20 completed (1931.778 ms) ====== [2025-08-13T21:57:47.921Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-13T21:57:47.921Z] GC before operation: completed in 36.300 ms, heap usage 140.983 MB -> 4.903 MB. [2025-08-13T21:57:50.184Z] Camera thread performed 122 scans. [2025-08-13T21:57:50.184Z] ====== philosophers (scala) [default], iteration 21 completed (2117.817 ms) ====== [2025-08-13T21:57:50.184Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-13T21:57:50.184Z] GC before operation: completed in 45.908 ms, heap usage 141.401 MB -> 4.903 MB. [2025-08-13T21:57:52.931Z] Camera thread performed 122 scans. [2025-08-13T21:57:52.931Z] ====== philosophers (scala) [default], iteration 22 completed (2683.908 ms) ====== [2025-08-13T21:57:52.931Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-13T21:57:52.931Z] GC before operation: completed in 62.387 ms, heap usage 141.036 MB -> 4.903 MB. [2025-08-13T21:57:54.873Z] Camera thread performed 122 scans. [2025-08-13T21:57:54.873Z] ====== philosophers (scala) [default], iteration 23 completed (1940.026 ms) ====== [2025-08-13T21:57:54.873Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-13T21:57:54.873Z] GC before operation: completed in 66.295 ms, heap usage 144.175 MB -> 4.903 MB. [2025-08-13T21:57:56.968Z] Camera thread performed 122 scans. [2025-08-13T21:57:56.968Z] ====== philosophers (scala) [default], iteration 24 completed (1801.631 ms) ====== [2025-08-13T21:57:56.968Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-13T21:57:56.968Z] GC before operation: completed in 49.589 ms, heap usage 141.942 MB -> 4.903 MB. [2025-08-13T21:57:59.113Z] Camera thread performed 122 scans. [2025-08-13T21:57:59.113Z] ====== philosophers (scala) [default], iteration 25 completed (2493.247 ms) ====== [2025-08-13T21:57:59.113Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-13T21:57:59.578Z] GC before operation: completed in 79.239 ms, heap usage 137.761 MB -> 4.930 MB. [2025-08-13T21:58:02.477Z] Camera thread performed 122 scans. [2025-08-13T21:58:02.477Z] ====== philosophers (scala) [default], iteration 26 completed (1931.567 ms) ====== [2025-08-13T21:58:02.477Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-13T21:58:02.477Z] GC before operation: completed in 25.632 ms, heap usage 141.539 MB -> 4.903 MB. [2025-08-13T21:58:03.958Z] Camera thread performed 122 scans. [2025-08-13T21:58:03.959Z] ====== philosophers (scala) [default], iteration 27 completed (2446.774 ms) ====== [2025-08-13T21:58:03.959Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-13T21:58:03.959Z] GC before operation: completed in 75.361 ms, heap usage 146.235 MB -> 4.903 MB. [2025-08-13T21:58:05.501Z] Camera thread performed 122 scans. [2025-08-13T21:58:05.501Z] ====== philosophers (scala) [default], iteration 28 completed (1627.452 ms) ====== [2025-08-13T21:58:05.501Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-13T21:58:05.501Z] GC before operation: completed in 26.722 ms, heap usage 62.804 MB -> 4.903 MB. [2025-08-13T21:58:06.982Z] Camera thread performed 122 scans. [2025-08-13T21:58:06.982Z] ====== philosophers (scala) [default], iteration 29 completed (1478.013 ms) ====== [2025-08-13T21:58:07.383Z] ----------------------------------- [2025-08-13T21:58:07.383Z] renaissance-philosophers_0_PASSED [2025-08-13T21:58:07.383Z] ----------------------------------- [2025-08-13T21:58:07.383Z] [2025-08-13T21:58:07.383Z] TEST TEARDOWN: [2025-08-13T21:58:07.383Z] Nothing to be done for teardown. [2025-08-13T21:58:07.383Z] renaissance-philosophers_0 Finish Time: Wed Aug 13 14:58:07 2025 Epoch Time (ms): 1755122287084