[2025-07-01T01:46:09.300Z] Running test renaissance-philosophers_0 ... [2025-07-01T01:46:09.300Z] =============================================== [2025-07-01T01:46:09.300Z] renaissance-philosophers_0 Start Time: Mon Jun 30 21:46:09 2025 Epoch Time (ms): 1751334369024 [2025-07-01T01:46:09.300Z] variation: NoOptions [2025-07-01T01:46:09.300Z] JVM_OPTIONS: [2025-07-01T01:46:09.300Z] { \ [2025-07-01T01:46:09.300Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T01:46:09.300Z] echo "Nothing to be done for setup."; \ [2025-07-01T01:46:09.300Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17513336089673/renaissance-philosophers_0"; \ [2025-07-01T01:46:09.300Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17513336089673/renaissance-philosophers_0"; \ [2025-07-01T01:46:09.300Z] echo ""; echo "TESTING:"; \ [2025-07-01T01:46:09.300Z] "/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_17513336089673/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-07-01T01:46:09.301Z] 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_17513336089673/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T01:46:09.301Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T01:46:09.301Z] echo "Nothing to be done for teardown."; \ [2025-07-01T01:46:09.301Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17513336089673/TestTargetResult"; [2025-07-01T01:46:09.301Z] [2025-07-01T01:46:09.301Z] TEST SETUP: [2025-07-01T01:46:09.301Z] Nothing to be done for setup. [2025-07-01T01:46:09.301Z] [2025-07-01T01:46:09.301Z] TESTING: [2025-07-01T01:46:09.653Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-07-01T01:46:09.653Z] GC before operation: completed in 6.250 ms, heap usage 18.452 MB -> 4.865 MB. [2025-07-01T01:46:11.450Z] Camera thread performed 122 scans. [2025-07-01T01:46:11.450Z] ====== philosophers (scala) [default], iteration 0 completed (1777.070 ms) ====== [2025-07-01T01:46:11.450Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-07-01T01:46:11.450Z] GC before operation: completed in 7.159 ms, heap usage 46.542 MB -> 5.230 MB. [2025-07-01T01:46:12.208Z] Camera thread performed 122 scans. [2025-07-01T01:46:12.208Z] ====== philosophers (scala) [default], iteration 1 completed (543.483 ms) ====== [2025-07-01T01:46:12.208Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-07-01T01:46:12.208Z] GC before operation: completed in 5.858 ms, heap usage 11.274 MB -> 5.230 MB. [2025-07-01T01:46:12.560Z] Camera thread performed 122 scans. [2025-07-01T01:46:12.560Z] ====== philosophers (scala) [default], iteration 2 completed (626.698 ms) ====== [2025-07-01T01:46:12.560Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-07-01T01:46:12.560Z] GC before operation: completed in 5.631 ms, heap usage 38.965 MB -> 5.230 MB. [2025-07-01T01:46:13.317Z] Camera thread performed 122 scans. [2025-07-01T01:46:13.317Z] ====== philosophers (scala) [default], iteration 3 completed (591.220 ms) ====== [2025-07-01T01:46:13.317Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-07-01T01:46:13.317Z] GC before operation: completed in 5.188 ms, heap usage 37.881 MB -> 5.230 MB. [2025-07-01T01:46:14.069Z] Camera thread performed 122 scans. [2025-07-01T01:46:14.069Z] ====== philosophers (scala) [default], iteration 4 completed (642.400 ms) ====== [2025-07-01T01:46:14.069Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-07-01T01:46:14.069Z] GC before operation: completed in 6.472 ms, heap usage 12.462 MB -> 5.231 MB. [2025-07-01T01:46:14.420Z] Camera thread performed 122 scans. [2025-07-01T01:46:14.420Z] ====== philosophers (scala) [default], iteration 5 completed (625.219 ms) ====== [2025-07-01T01:46:14.420Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-07-01T01:46:14.420Z] GC before operation: completed in 5.315 ms, heap usage 63.231 MB -> 5.231 MB. [2025-07-01T01:46:15.173Z] Camera thread performed 122 scans. [2025-07-01T01:46:15.173Z] ====== philosophers (scala) [default], iteration 6 completed (658.839 ms) ====== [2025-07-01T01:46:15.173Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-07-01T01:46:15.173Z] GC before operation: completed in 8.368 ms, heap usage 10.786 MB -> 5.231 MB. [2025-07-01T01:46:15.524Z] Camera thread performed 122 scans. [2025-07-01T01:46:15.524Z] ====== philosophers (scala) [default], iteration 7 completed (483.576 ms) ====== [2025-07-01T01:46:15.524Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-07-01T01:46:15.524Z] GC before operation: completed in 4.770 ms, heap usage 33.377 MB -> 5.232 MB. [2025-07-01T01:46:16.289Z] Camera thread performed 122 scans. [2025-07-01T01:46:16.289Z] ====== philosophers (scala) [default], iteration 8 completed (622.254 ms) ====== [2025-07-01T01:46:16.289Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-07-01T01:46:16.289Z] GC before operation: completed in 7.462 ms, heap usage 28.190 MB -> 5.232 MB. [2025-07-01T01:46:17.043Z] Camera thread performed 122 scans. [2025-07-01T01:46:17.043Z] ====== philosophers (scala) [default], iteration 9 completed (546.850 ms) ====== [2025-07-01T01:46:17.043Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-07-01T01:46:17.043Z] GC before operation: completed in 5.233 ms, heap usage 11.390 MB -> 5.232 MB. [2025-07-01T01:46:17.395Z] Camera thread performed 122 scans. [2025-07-01T01:46:17.395Z] ====== philosophers (scala) [default], iteration 10 completed (571.166 ms) ====== [2025-07-01T01:46:17.395Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-07-01T01:46:17.395Z] GC before operation: completed in 9.641 ms, heap usage 65.816 MB -> 5.233 MB. [2025-07-01T01:46:18.149Z] Camera thread performed 122 scans. [2025-07-01T01:46:18.149Z] ====== philosophers (scala) [default], iteration 11 completed (600.170 ms) ====== [2025-07-01T01:46:18.149Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-07-01T01:46:18.149Z] GC before operation: completed in 5.825 ms, heap usage 49.781 MB -> 5.232 MB. [2025-07-01T01:46:18.501Z] Camera thread performed 122 scans. [2025-07-01T01:46:18.501Z] ====== philosophers (scala) [default], iteration 12 completed (617.601 ms) ====== [2025-07-01T01:46:18.501Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-07-01T01:46:18.855Z] GC before operation: completed in 8.492 ms, heap usage 11.790 MB -> 5.233 MB. [2025-07-01T01:46:19.205Z] Camera thread performed 122 scans. [2025-07-01T01:46:19.205Z] ====== philosophers (scala) [default], iteration 13 completed (594.451 ms) ====== [2025-07-01T01:46:19.205Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-07-01T01:46:19.205Z] GC before operation: completed in 6.394 ms, heap usage 17.412 MB -> 5.233 MB. [2025-07-01T01:46:19.959Z] Camera thread performed 122 scans. [2025-07-01T01:46:19.959Z] ====== philosophers (scala) [default], iteration 14 completed (584.522 ms) ====== [2025-07-01T01:46:19.959Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-07-01T01:46:19.959Z] GC before operation: completed in 5.729 ms, heap usage 47.779 MB -> 5.233 MB. [2025-07-01T01:46:20.313Z] Camera thread performed 122 scans. [2025-07-01T01:46:20.313Z] ====== philosophers (scala) [default], iteration 15 completed (605.711 ms) ====== [2025-07-01T01:46:20.313Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-07-01T01:46:20.664Z] GC before operation: completed in 10.512 ms, heap usage 44.412 MB -> 5.233 MB. [2025-07-01T01:46:21.017Z] Camera thread performed 122 scans. [2025-07-01T01:46:21.017Z] ====== philosophers (scala) [default], iteration 16 completed (564.734 ms) ====== [2025-07-01T01:46:21.017Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-07-01T01:46:21.017Z] GC before operation: completed in 6.777 ms, heap usage 57.108 MB -> 5.234 MB. [2025-07-01T01:46:21.782Z] Camera thread performed 122 scans. [2025-07-01T01:46:21.782Z] ====== philosophers (scala) [default], iteration 17 completed (593.167 ms) ====== [2025-07-01T01:46:21.782Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-07-01T01:46:21.782Z] GC before operation: completed in 5.959 ms, heap usage 21.841 MB -> 5.233 MB. [2025-07-01T01:46:22.134Z] Camera thread performed 122 scans. [2025-07-01T01:46:22.134Z] ====== philosophers (scala) [default], iteration 18 completed (591.875 ms) ====== [2025-07-01T01:46:22.134Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-07-01T01:46:22.134Z] GC before operation: completed in 7.063 ms, heap usage 59.253 MB -> 5.234 MB. [2025-07-01T01:46:22.888Z] Camera thread performed 122 scans. [2025-07-01T01:46:22.888Z] ====== philosophers (scala) [default], iteration 19 completed (594.280 ms) ====== [2025-07-01T01:46:22.888Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-07-01T01:46:22.888Z] GC before operation: completed in 5.796 ms, heap usage 61.146 MB -> 5.234 MB. [2025-07-01T01:46:23.243Z] Camera thread performed 122 scans. [2025-07-01T01:46:23.243Z] ====== philosophers (scala) [default], iteration 20 completed (424.844 ms) ====== [2025-07-01T01:46:23.243Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-07-01T01:46:23.243Z] GC before operation: completed in 6.105 ms, heap usage 44.409 MB -> 5.234 MB. [2025-07-01T01:46:24.005Z] Camera thread performed 122 scans. [2025-07-01T01:46:24.005Z] ====== philosophers (scala) [default], iteration 21 completed (603.429 ms) ====== [2025-07-01T01:46:24.005Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-07-01T01:46:24.005Z] GC before operation: completed in 7.800 ms, heap usage 33.790 MB -> 5.234 MB. [2025-07-01T01:46:24.357Z] Camera thread performed 122 scans. [2025-07-01T01:46:24.357Z] ====== philosophers (scala) [default], iteration 22 completed (462.281 ms) ====== [2025-07-01T01:46:24.357Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-07-01T01:46:24.357Z] GC before operation: completed in 5.251 ms, heap usage 50.401 MB -> 5.234 MB. [2025-07-01T01:46:25.109Z] Camera thread performed 122 scans. [2025-07-01T01:46:25.109Z] ====== philosophers (scala) [default], iteration 23 completed (613.224 ms) ====== [2025-07-01T01:46:25.109Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-07-01T01:46:25.109Z] GC before operation: completed in 10.915 ms, heap usage 48.811 MB -> 5.234 MB. [2025-07-01T01:46:25.461Z] Camera thread performed 122 scans. [2025-07-01T01:46:25.461Z] ====== philosophers (scala) [default], iteration 24 completed (586.104 ms) ====== [2025-07-01T01:46:25.461Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-07-01T01:46:25.461Z] GC before operation: completed in 9.727 ms, heap usage 5.416 MB -> 5.234 MB. [2025-07-01T01:46:26.231Z] Camera thread performed 122 scans. [2025-07-01T01:46:26.231Z] ====== philosophers (scala) [default], iteration 25 completed (642.373 ms) ====== [2025-07-01T01:46:26.231Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-07-01T01:46:26.231Z] GC before operation: completed in 5.574 ms, heap usage 7.335 MB -> 5.234 MB. [2025-07-01T01:46:26.983Z] Camera thread performed 122 scans. [2025-07-01T01:46:26.983Z] ====== philosophers (scala) [default], iteration 26 completed (671.993 ms) ====== [2025-07-01T01:46:26.983Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-07-01T01:46:26.983Z] GC before operation: completed in 5.728 ms, heap usage 44.407 MB -> 5.235 MB. [2025-07-01T01:46:27.735Z] Camera thread performed 122 scans. [2025-07-01T01:46:27.735Z] ====== philosophers (scala) [default], iteration 27 completed (633.813 ms) ====== [2025-07-01T01:46:27.735Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-07-01T01:46:27.735Z] GC before operation: completed in 6.083 ms, heap usage 8.915 MB -> 5.236 MB. [2025-07-01T01:46:28.087Z] Camera thread performed 122 scans. [2025-07-01T01:46:28.087Z] ====== philosophers (scala) [default], iteration 28 completed (627.391 ms) ====== [2025-07-01T01:46:28.087Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-07-01T01:46:28.087Z] GC before operation: completed in 6.590 ms, heap usage 7.861 MB -> 5.236 MB. [2025-07-01T01:46:28.837Z] Camera thread performed 122 scans. [2025-07-01T01:46:28.837Z] ====== philosophers (scala) [default], iteration 29 completed (692.514 ms) ====== [2025-07-01T01:46:28.837Z] ----------------------------------- [2025-07-01T01:46:28.837Z] renaissance-philosophers_0_PASSED [2025-07-01T01:46:28.837Z] ----------------------------------- [2025-07-01T01:46:28.837Z] [2025-07-01T01:46:28.837Z] TEST TEARDOWN: [2025-07-01T01:46:28.837Z] Nothing to be done for teardown. [2025-07-01T01:46:28.837Z] renaissance-philosophers_0 Finish Time: Mon Jun 30 21:46:28 2025 Epoch Time (ms): 1751334388738