[2025-05-28T21:15:05.455Z] Running test renaissance-philosophers_0 ... [2025-05-28T21:15:05.455Z] =============================================== [2025-05-28T21:15:05.455Z] renaissance-philosophers_0 Start Time: Wed May 28 17:15:05 2025 Epoch Time (ms): 1748466905082 [2025-05-28T21:15:05.455Z] variation: NoOptions [2025-05-28T21:15:05.455Z] JVM_OPTIONS: [2025-05-28T21:15:05.455Z] { \ [2025-05-28T21:15:05.455Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T21:15:05.455Z] echo "Nothing to be done for setup."; \ [2025-05-28T21:15:05.455Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17484660466246/renaissance-philosophers_0"; \ [2025-05-28T21:15:05.455Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17484660466246/renaissance-philosophers_0"; \ [2025-05-28T21:15:05.455Z] echo ""; echo "TESTING:"; \ [2025-05-28T21:15:05.456Z] "/Users/admin/workspace/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17484660466246/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-28T21:15:05.456Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17484660466246/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T21:15:05.456Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T21:15:05.456Z] echo "Nothing to be done for teardown."; \ [2025-05-28T21:15:05.456Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17484660466246/TestTargetResult"; [2025-05-28T21:15:05.456Z] [2025-05-28T21:15:05.456Z] TEST SETUP: [2025-05-28T21:15:05.456Z] Nothing to be done for setup. [2025-05-28T21:15:05.456Z] [2025-05-28T21:15:05.456Z] TESTING: [2025-05-28T21:15:05.809Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-28T21:15:05.809Z] GC before operation: completed in 7.047 ms, heap usage 17.254 MB -> 4.843 MB. [2025-05-28T21:15:08.276Z] Camera thread performed 122 scans. [2025-05-28T21:15:08.276Z] ====== philosophers (scala) [default], iteration 0 completed (2191.062 ms) ====== [2025-05-28T21:15:08.276Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-28T21:15:08.276Z] GC before operation: completed in 8.017 ms, heap usage 30.997 MB -> 5.199 MB. [2025-05-28T21:15:08.630Z] Camera thread performed 122 scans. [2025-05-28T21:15:08.630Z] ====== philosophers (scala) [default], iteration 1 completed (832.790 ms) ====== [2025-05-28T21:15:08.630Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-28T21:15:08.630Z] GC before operation: completed in 5.027 ms, heap usage 25.054 MB -> 5.199 MB. [2025-05-28T21:15:09.410Z] Camera thread performed 122 scans. [2025-05-28T21:15:09.410Z] ====== philosophers (scala) [default], iteration 2 completed (791.378 ms) ====== [2025-05-28T21:15:09.410Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-28T21:15:09.410Z] GC before operation: completed in 7.178 ms, heap usage 52.032 MB -> 5.200 MB. [2025-05-28T21:15:10.630Z] Camera thread performed 122 scans. [2025-05-28T21:15:10.630Z] ====== philosophers (scala) [default], iteration 3 completed (783.121 ms) ====== [2025-05-28T21:15:10.630Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-28T21:15:10.630Z] GC before operation: completed in 8.112 ms, heap usage 15.986 MB -> 5.200 MB. [2025-05-28T21:15:10.983Z] Camera thread performed 122 scans. [2025-05-28T21:15:10.983Z] ====== philosophers (scala) [default], iteration 4 completed (738.589 ms) ====== [2025-05-28T21:15:10.983Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-28T21:15:10.983Z] GC before operation: completed in 6.978 ms, heap usage 6.445 MB -> 5.200 MB. [2025-05-28T21:15:11.752Z] Camera thread performed 122 scans. [2025-05-28T21:15:11.752Z] ====== philosophers (scala) [default], iteration 5 completed (776.276 ms) ====== [2025-05-28T21:15:11.752Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-28T21:15:11.752Z] GC before operation: completed in 8.280 ms, heap usage 16.191 MB -> 5.201 MB. [2025-05-28T21:15:12.509Z] Camera thread performed 122 scans. [2025-05-28T21:15:12.509Z] ====== philosophers (scala) [default], iteration 6 completed (709.903 ms) ====== [2025-05-28T21:15:12.509Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-28T21:15:12.509Z] GC before operation: completed in 7.743 ms, heap usage 22.851 MB -> 5.201 MB. [2025-05-28T21:15:13.264Z] Camera thread performed 122 scans. [2025-05-28T21:15:13.264Z] ====== philosophers (scala) [default], iteration 7 completed (756.889 ms) ====== [2025-05-28T21:15:13.264Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-28T21:15:13.264Z] GC before operation: completed in 5.399 ms, heap usage 57.756 MB -> 5.201 MB. [2025-05-28T21:15:14.093Z] Camera thread performed 122 scans. [2025-05-28T21:15:14.093Z] ====== philosophers (scala) [default], iteration 8 completed (755.810 ms) ====== [2025-05-28T21:15:14.093Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-28T21:15:14.093Z] GC before operation: completed in 7.931 ms, heap usage 40.747 MB -> 5.201 MB. [2025-05-28T21:15:14.864Z] Camera thread performed 122 scans. [2025-05-28T21:15:14.864Z] ====== philosophers (scala) [default], iteration 9 completed (801.899 ms) ====== [2025-05-28T21:15:14.864Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-28T21:15:14.864Z] GC before operation: completed in 5.369 ms, heap usage 50.094 MB -> 5.202 MB. [2025-05-28T21:15:15.624Z] Camera thread performed 122 scans. [2025-05-28T21:15:15.624Z] ====== philosophers (scala) [default], iteration 10 completed (680.075 ms) ====== [2025-05-28T21:15:15.624Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-28T21:15:15.624Z] GC before operation: completed in 6.412 ms, heap usage 27.763 MB -> 5.202 MB. [2025-05-28T21:15:16.386Z] Camera thread performed 122 scans. [2025-05-28T21:15:16.386Z] ====== philosophers (scala) [default], iteration 11 completed (748.984 ms) ====== [2025-05-28T21:15:16.386Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-28T21:15:16.386Z] GC before operation: completed in 7.539 ms, heap usage 10.384 MB -> 5.202 MB. [2025-05-28T21:15:17.143Z] Camera thread performed 122 scans. [2025-05-28T21:15:17.143Z] ====== philosophers (scala) [default], iteration 12 completed (733.243 ms) ====== [2025-05-28T21:15:17.143Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-28T21:15:17.143Z] GC before operation: completed in 7.537 ms, heap usage 32.389 MB -> 5.202 MB. [2025-05-28T21:15:19.280Z] Camera thread performed 122 scans. [2025-05-28T21:15:19.280Z] ====== philosophers (scala) [default], iteration 13 completed (735.274 ms) ====== [2025-05-28T21:15:19.280Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-28T21:15:19.280Z] GC before operation: completed in 5.131 ms, heap usage 12.808 MB -> 5.202 MB. [2025-05-28T21:15:19.280Z] Camera thread performed 122 scans. [2025-05-28T21:15:19.280Z] ====== philosophers (scala) [default], iteration 14 completed (740.973 ms) ====== [2025-05-28T21:15:19.280Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-28T21:15:19.280Z] GC before operation: completed in 5.337 ms, heap usage 20.860 MB -> 5.203 MB. [2025-05-28T21:15:19.280Z] Camera thread performed 122 scans. [2025-05-28T21:15:19.280Z] ====== philosophers (scala) [default], iteration 15 completed (710.793 ms) ====== [2025-05-28T21:15:19.280Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-28T21:15:19.280Z] GC before operation: completed in 5.168 ms, heap usage 20.138 MB -> 5.203 MB. [2025-05-28T21:15:20.039Z] Camera thread performed 122 scans. [2025-05-28T21:15:20.039Z] ====== philosophers (scala) [default], iteration 16 completed (796.019 ms) ====== [2025-05-28T21:15:20.039Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-28T21:15:20.039Z] GC before operation: completed in 5.264 ms, heap usage 38.116 MB -> 5.203 MB. [2025-05-28T21:15:20.796Z] Camera thread performed 122 scans. [2025-05-28T21:15:20.796Z] ====== philosophers (scala) [default], iteration 17 completed (716.878 ms) ====== [2025-05-28T21:15:20.796Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-28T21:15:20.796Z] GC before operation: completed in 6.222 ms, heap usage 8.782 MB -> 5.203 MB. [2025-05-28T21:15:21.561Z] Camera thread performed 122 scans. [2025-05-28T21:15:21.561Z] ====== philosophers (scala) [default], iteration 18 completed (718.843 ms) ====== [2025-05-28T21:15:21.561Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-28T21:15:21.561Z] GC before operation: completed in 5.355 ms, heap usage 52.269 MB -> 5.203 MB. [2025-05-28T21:15:22.316Z] Camera thread performed 122 scans. [2025-05-28T21:15:22.316Z] ====== philosophers (scala) [default], iteration 19 completed (720.440 ms) ====== [2025-05-28T21:15:22.316Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-28T21:15:22.316Z] GC before operation: completed in 5.269 ms, heap usage 10.751 MB -> 5.203 MB. [2025-05-28T21:15:23.074Z] Camera thread performed 122 scans. [2025-05-28T21:15:23.074Z] ====== philosophers (scala) [default], iteration 20 completed (750.728 ms) ====== [2025-05-28T21:15:23.074Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-28T21:15:23.074Z] GC before operation: completed in 5.501 ms, heap usage 49.794 MB -> 5.204 MB. [2025-05-28T21:15:23.834Z] Camera thread performed 122 scans. [2025-05-28T21:15:23.834Z] ====== philosophers (scala) [default], iteration 21 completed (737.876 ms) ====== [2025-05-28T21:15:23.834Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-28T21:15:23.834Z] GC before operation: completed in 9.345 ms, heap usage 12.810 MB -> 5.203 MB. [2025-05-28T21:15:24.591Z] Camera thread performed 122 scans. [2025-05-28T21:15:24.591Z] ====== philosophers (scala) [default], iteration 22 completed (649.541 ms) ====== [2025-05-28T21:15:24.591Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-28T21:15:24.591Z] GC before operation: completed in 5.350 ms, heap usage 43.758 MB -> 5.204 MB. [2025-05-28T21:15:25.346Z] Camera thread performed 122 scans. [2025-05-28T21:15:25.346Z] ====== philosophers (scala) [default], iteration 23 completed (727.395 ms) ====== [2025-05-28T21:15:25.347Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-28T21:15:25.347Z] GC before operation: completed in 6.118 ms, heap usage 16.856 MB -> 5.204 MB. [2025-05-28T21:15:26.113Z] Camera thread performed 122 scans. [2025-05-28T21:15:26.113Z] ====== philosophers (scala) [default], iteration 24 completed (761.808 ms) ====== [2025-05-28T21:15:26.113Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-28T21:15:26.113Z] GC before operation: completed in 5.537 ms, heap usage 12.796 MB -> 5.204 MB. [2025-05-28T21:15:26.869Z] Camera thread performed 122 scans. [2025-05-28T21:15:26.869Z] ====== philosophers (scala) [default], iteration 25 completed (750.732 ms) ====== [2025-05-28T21:15:26.869Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-28T21:15:26.869Z] GC before operation: completed in 8.430 ms, heap usage 26.204 MB -> 5.204 MB. [2025-05-28T21:15:27.626Z] Camera thread performed 122 scans. [2025-05-28T21:15:27.626Z] ====== philosophers (scala) [default], iteration 26 completed (763.459 ms) ====== [2025-05-28T21:15:27.626Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-28T21:15:27.627Z] GC before operation: completed in 5.592 ms, heap usage 18.272 MB -> 5.204 MB. [2025-05-28T21:15:28.397Z] Camera thread performed 122 scans. [2025-05-28T21:15:28.397Z] ====== philosophers (scala) [default], iteration 27 completed (768.952 ms) ====== [2025-05-28T21:15:28.397Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-28T21:15:28.397Z] GC before operation: completed in 5.477 ms, heap usage 23.393 MB -> 5.204 MB. [2025-05-28T21:15:29.151Z] Camera thread performed 122 scans. [2025-05-28T21:15:29.151Z] ====== philosophers (scala) [default], iteration 28 completed (712.285 ms) ====== [2025-05-28T21:15:29.151Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-28T21:15:29.151Z] GC before operation: completed in 7.779 ms, heap usage 38.843 MB -> 5.204 MB. [2025-05-28T21:15:29.927Z] Camera thread performed 122 scans. [2025-05-28T21:15:29.927Z] ====== philosophers (scala) [default], iteration 29 completed (730.038 ms) ====== [2025-05-28T21:15:29.927Z] ----------------------------------- [2025-05-28T21:15:29.927Z] renaissance-philosophers_0_PASSED [2025-05-28T21:15:29.927Z] ----------------------------------- [2025-05-28T21:15:29.927Z] [2025-05-28T21:15:29.927Z] TEST TEARDOWN: [2025-05-28T21:15:29.927Z] Nothing to be done for teardown. [2025-05-28T21:15:29.927Z] renaissance-philosophers_0 Finish Time: Wed May 28 17:15:29 2025 Epoch Time (ms): 1748466929616