[2025-08-13T20:11:16.477Z] Running test renaissance-philosophers_0 ... [2025-08-13T20:11:16.477Z] =============================================== [2025-08-13T20:11:16.477Z] renaissance-philosophers_0 Start Time: Wed Aug 13 16:11:16 2025 Epoch Time (ms): 1755115876044 [2025-08-13T20:11:16.477Z] variation: NoOptions [2025-08-13T20:11:16.477Z] JVM_OPTIONS: [2025-08-13T20:11:16.477Z] { \ [2025-08-13T20:11:16.477Z] echo ""; echo "TEST SETUP:"; \ [2025-08-13T20:11:16.477Z] echo "Nothing to be done for setup."; \ [2025-08-13T20:11:16.477Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/renaissance-philosophers_0"; \ [2025-08-13T20:11:16.477Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/renaissance-philosophers_0"; \ [2025-08-13T20:11:16.477Z] echo ""; echo "TESTING:"; \ [2025-08-13T20:11:16.477Z] "/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_17551149705909/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-13T20:11:16.477Z] 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_17551149705909/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-13T20:11:16.477Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-13T20:11:16.477Z] echo "Nothing to be done for teardown."; \ [2025-08-13T20:11:16.477Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/TestTargetResult"; [2025-08-13T20:11:16.477Z] [2025-08-13T20:11:16.477Z] TEST SETUP: [2025-08-13T20:11:16.478Z] Nothing to be done for setup. [2025-08-13T20:11:16.478Z] [2025-08-13T20:11:16.478Z] TESTING: [2025-08-13T20:11:16.826Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-13T20:11:16.826Z] GC before operation: completed in 11.950 ms, heap usage 18.654 MB -> 4.860 MB. [2025-08-13T20:11:18.570Z] Camera thread performed 122 scans. [2025-08-13T20:11:18.571Z] ====== philosophers (scala) [default], iteration 0 completed (1822.382 ms) ====== [2025-08-13T20:11:18.571Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-13T20:11:18.571Z] GC before operation: completed in 11.031 ms, heap usage 12.257 MB -> 5.233 MB. [2025-08-13T20:11:19.783Z] Camera thread performed 122 scans. [2025-08-13T20:11:19.783Z] ====== philosophers (scala) [default], iteration 1 completed (1052.247 ms) ====== [2025-08-13T20:11:19.783Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-13T20:11:19.783Z] GC before operation: completed in 10.645 ms, heap usage 14.865 MB -> 5.233 MB. [2025-08-13T20:11:20.541Z] Camera thread performed 122 scans. [2025-08-13T20:11:20.542Z] ====== philosophers (scala) [default], iteration 2 completed (870.753 ms) ====== [2025-08-13T20:11:20.542Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-13T20:11:20.542Z] GC before operation: completed in 6.214 ms, heap usage 6.057 MB -> 5.234 MB. [2025-08-13T20:11:21.299Z] Camera thread performed 122 scans. [2025-08-13T20:11:21.299Z] ====== philosophers (scala) [default], iteration 3 completed (812.061 ms) ====== [2025-08-13T20:11:21.299Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-13T20:11:21.299Z] GC before operation: completed in 7.888 ms, heap usage 26.593 MB -> 5.233 MB. [2025-08-13T20:11:22.053Z] Camera thread performed 122 scans. [2025-08-13T20:11:22.053Z] ====== philosophers (scala) [default], iteration 4 completed (851.952 ms) ====== [2025-08-13T20:11:22.053Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-13T20:11:22.053Z] GC before operation: completed in 8.359 ms, heap usage 24.221 MB -> 5.234 MB. [2025-08-13T20:11:23.270Z] Camera thread performed 122 scans. [2025-08-13T20:11:23.270Z] ====== philosophers (scala) [default], iteration 5 completed (818.947 ms) ====== [2025-08-13T20:11:23.270Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-13T20:11:23.270Z] GC before operation: completed in 7.369 ms, heap usage 13.238 MB -> 5.234 MB. [2025-08-13T20:11:23.619Z] Camera thread performed 122 scans. [2025-08-13T20:11:23.619Z] ====== philosophers (scala) [default], iteration 6 completed (673.768 ms) ====== [2025-08-13T20:11:23.619Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-13T20:11:23.619Z] GC before operation: completed in 8.168 ms, heap usage 12.468 MB -> 5.235 MB. [2025-08-13T20:11:24.374Z] Camera thread performed 122 scans. [2025-08-13T20:11:24.374Z] ====== philosophers (scala) [default], iteration 7 completed (630.446 ms) ====== [2025-08-13T20:11:24.374Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-13T20:11:24.374Z] GC before operation: completed in 10.440 ms, heap usage 36.126 MB -> 5.235 MB. [2025-08-13T20:11:25.135Z] Camera thread performed 122 scans. [2025-08-13T20:11:25.135Z] ====== philosophers (scala) [default], iteration 8 completed (637.190 ms) ====== [2025-08-13T20:11:25.135Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-13T20:11:25.135Z] GC before operation: completed in 7.118 ms, heap usage 31.257 MB -> 5.235 MB. [2025-08-13T20:11:25.887Z] Camera thread performed 122 scans. [2025-08-13T20:11:25.887Z] ====== philosophers (scala) [default], iteration 9 completed (694.520 ms) ====== [2025-08-13T20:11:25.887Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-13T20:11:25.887Z] GC before operation: completed in 6.137 ms, heap usage 41.757 MB -> 5.235 MB. [2025-08-13T20:11:26.239Z] Camera thread performed 122 scans. [2025-08-13T20:11:26.239Z] ====== philosophers (scala) [default], iteration 10 completed (675.249 ms) ====== [2025-08-13T20:11:26.239Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-13T20:11:26.239Z] GC before operation: completed in 7.967 ms, heap usage 21.765 MB -> 5.236 MB. [2025-08-13T20:11:26.990Z] Camera thread performed 122 scans. [2025-08-13T20:11:26.990Z] ====== philosophers (scala) [default], iteration 11 completed (639.012 ms) ====== [2025-08-13T20:11:26.990Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-13T20:11:26.990Z] GC before operation: completed in 7.586 ms, heap usage 47.191 MB -> 5.236 MB. [2025-08-13T20:11:27.742Z] Camera thread performed 122 scans. [2025-08-13T20:11:27.742Z] ====== philosophers (scala) [default], iteration 12 completed (678.329 ms) ====== [2025-08-13T20:11:27.742Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-13T20:11:27.742Z] GC before operation: completed in 7.523 ms, heap usage 10.304 MB -> 5.236 MB. [2025-08-13T20:11:28.494Z] Camera thread performed 122 scans. [2025-08-13T20:11:28.494Z] ====== philosophers (scala) [default], iteration 13 completed (655.995 ms) ====== [2025-08-13T20:11:28.494Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-13T20:11:28.494Z] GC before operation: completed in 6.887 ms, heap usage 54.094 MB -> 5.236 MB. [2025-08-13T20:11:29.249Z] Camera thread performed 122 scans. [2025-08-13T20:11:29.249Z] ====== philosophers (scala) [default], iteration 14 completed (713.057 ms) ====== [2025-08-13T20:11:29.249Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-13T20:11:29.249Z] GC before operation: completed in 7.711 ms, heap usage 57.395 MB -> 5.236 MB. [2025-08-13T20:11:29.598Z] Camera thread performed 122 scans. [2025-08-13T20:11:29.598Z] ====== philosophers (scala) [default], iteration 15 completed (675.633 ms) ====== [2025-08-13T20:11:29.598Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-13T20:11:29.598Z] GC before operation: completed in 5.938 ms, heap usage 14.411 MB -> 5.236 MB. [2025-08-13T20:11:30.349Z] Camera thread performed 122 scans. [2025-08-13T20:11:30.349Z] ====== philosophers (scala) [default], iteration 16 completed (676.076 ms) ====== [2025-08-13T20:11:30.349Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-13T20:11:30.349Z] GC before operation: completed in 7.752 ms, heap usage 37.406 MB -> 5.237 MB. [2025-08-13T20:11:31.101Z] Camera thread performed 122 scans. [2025-08-13T20:11:31.101Z] ====== philosophers (scala) [default], iteration 17 completed (703.480 ms) ====== [2025-08-13T20:11:31.101Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-13T20:11:31.101Z] GC before operation: completed in 9.339 ms, heap usage 30.131 MB -> 5.237 MB. [2025-08-13T20:11:31.854Z] Camera thread performed 122 scans. [2025-08-13T20:11:31.854Z] ====== philosophers (scala) [default], iteration 18 completed (743.130 ms) ====== [2025-08-13T20:11:31.854Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-13T20:11:31.854Z] GC before operation: completed in 9.603 ms, heap usage 45.403 MB -> 5.237 MB. [2025-08-13T20:11:32.601Z] Camera thread performed 122 scans. [2025-08-13T20:11:32.601Z] ====== philosophers (scala) [default], iteration 19 completed (646.044 ms) ====== [2025-08-13T20:11:32.601Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-13T20:11:32.601Z] GC before operation: completed in 7.542 ms, heap usage 33.717 MB -> 5.237 MB. [2025-08-13T20:11:33.355Z] Camera thread performed 122 scans. [2025-08-13T20:11:33.355Z] ====== philosophers (scala) [default], iteration 20 completed (633.391 ms) ====== [2025-08-13T20:11:33.355Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-13T20:11:33.355Z] GC before operation: completed in 8.914 ms, heap usage 45.052 MB -> 5.237 MB. [2025-08-13T20:11:34.109Z] Camera thread performed 122 scans. [2025-08-13T20:11:34.110Z] ====== philosophers (scala) [default], iteration 21 completed (688.783 ms) ====== [2025-08-13T20:11:34.110Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-13T20:11:34.110Z] GC before operation: completed in 7.063 ms, heap usage 49.261 MB -> 5.237 MB. [2025-08-13T20:11:34.462Z] Camera thread performed 122 scans. [2025-08-13T20:11:34.463Z] ====== philosophers (scala) [default], iteration 22 completed (708.410 ms) ====== [2025-08-13T20:11:34.463Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-13T20:11:34.463Z] GC before operation: completed in 8.215 ms, heap usage 29.091 MB -> 5.238 MB. [2025-08-13T20:11:35.226Z] Camera thread performed 122 scans. [2025-08-13T20:11:35.226Z] ====== philosophers (scala) [default], iteration 23 completed (675.968 ms) ====== [2025-08-13T20:11:35.226Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-13T20:11:35.226Z] GC before operation: completed in 7.163 ms, heap usage 21.000 MB -> 5.238 MB. [2025-08-13T20:11:35.975Z] Camera thread performed 122 scans. [2025-08-13T20:11:35.975Z] ====== philosophers (scala) [default], iteration 24 completed (798.818 ms) ====== [2025-08-13T20:11:35.975Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-13T20:11:35.975Z] GC before operation: completed in 9.122 ms, heap usage 19.363 MB -> 5.238 MB. [2025-08-13T20:11:36.738Z] Camera thread performed 122 scans. [2025-08-13T20:11:36.738Z] ====== philosophers (scala) [default], iteration 25 completed (763.085 ms) ====== [2025-08-13T20:11:36.738Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-13T20:11:36.738Z] GC before operation: completed in 7.657 ms, heap usage 35.111 MB -> 5.238 MB. [2025-08-13T20:11:37.503Z] Camera thread performed 122 scans. [2025-08-13T20:11:37.503Z] ====== philosophers (scala) [default], iteration 26 completed (652.524 ms) ====== [2025-08-13T20:11:37.503Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-13T20:11:37.503Z] GC before operation: completed in 9.070 ms, heap usage 11.772 MB -> 5.238 MB. [2025-08-13T20:11:38.261Z] Camera thread performed 122 scans. [2025-08-13T20:11:38.261Z] ====== philosophers (scala) [default], iteration 27 completed (659.981 ms) ====== [2025-08-13T20:11:38.261Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-13T20:11:38.261Z] GC before operation: completed in 7.331 ms, heap usage 34.769 MB -> 5.238 MB. [2025-08-13T20:11:39.013Z] Camera thread performed 122 scans. [2025-08-13T20:11:39.013Z] ====== philosophers (scala) [default], iteration 28 completed (704.588 ms) ====== [2025-08-13T20:11:39.013Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-13T20:11:39.013Z] GC before operation: completed in 9.251 ms, heap usage 39.409 MB -> 5.238 MB. [2025-08-13T20:11:39.774Z] Camera thread performed 122 scans. [2025-08-13T20:11:39.774Z] ====== philosophers (scala) [default], iteration 29 completed (715.516 ms) ====== [2025-08-13T20:11:39.774Z] ----------------------------------- [2025-08-13T20:11:39.774Z] renaissance-philosophers_0_PASSED [2025-08-13T20:11:39.774Z] ----------------------------------- [2025-08-13T20:11:39.774Z] [2025-08-13T20:11:39.774Z] TEST TEARDOWN: [2025-08-13T20:11:39.774Z] Nothing to be done for teardown. [2025-08-13T20:11:39.774Z] renaissance-philosophers_0 Finish Time: Wed Aug 13 16:11:39 2025 Epoch Time (ms): 1755115899544