[2025-08-20T22:26:11.752Z] Running test renaissance-philosophers_0 ... [2025-08-20T22:26:12.045Z] =============================================== [2025-08-20T22:26:12.045Z] renaissance-philosophers_0 Start Time: Wed Aug 20 22:26:11 2025 Epoch Time (ms): 1755728771974 [2025-08-20T22:26:12.338Z] variation: NoOptions [2025-08-20T22:26:12.339Z] JVM_OPTIONS: [2025-08-20T22:26:12.633Z] { \ [2025-08-20T22:26:12.633Z] echo ""; echo "TEST SETUP:"; \ [2025-08-20T22:26:12.633Z] echo "Nothing to be done for setup."; \ [2025-08-20T22:26:12.633Z] mkdir -p "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17557278094539\\renaissance-philosophers_0"; \ [2025-08-20T22:26:12.633Z] cd "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17557278094539\\renaissance-philosophers_0"; \ [2025-08-20T22:26:12.633Z] echo ""; echo "TESTING:"; \ [2025-08-20T22:26:12.633Z] "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/jdkbinary/j2sdk-image\\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 "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17557278094539\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-08-20T22:26:12.633Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/; rm -f -r "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17557278094539\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-20T22:26:12.633Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-20T22:26:12.633Z] echo "Nothing to be done for teardown."; \ [2025-08-20T22:26:12.633Z] } 2>&1 | tee -a "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17557278094539\\TestTargetResult"; [2025-08-20T22:26:12.633Z] [2025-08-20T22:26:12.633Z] TEST SETUP: [2025-08-20T22:26:12.633Z] Nothing to be done for setup. [2025-08-20T22:26:12.633Z] [2025-08-20T22:26:12.633Z] TESTING: [2025-08-20T22:26:14.150Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-20T22:26:14.150Z] GC before operation: completed in 11.379 ms, heap usage 6.027 MB -> 4.533 MB. [2025-08-20T22:26:16.886Z] Camera thread performed 122 scans. [2025-08-20T22:26:16.886Z] ====== philosophers (scala) [default], iteration 0 completed (2246.267 ms) ====== [2025-08-20T22:26:16.886Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-20T22:26:16.886Z] GC before operation: completed in 14.891 ms, heap usage 31.168 MB -> 4.912 MB. [2025-08-20T22:26:17.925Z] Camera thread performed 122 scans. [2025-08-20T22:26:17.925Z] ====== philosophers (scala) [default], iteration 1 completed (1473.025 ms) ====== [2025-08-20T22:26:17.925Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-20T22:26:17.925Z] GC before operation: completed in 13.393 ms, heap usage 21.043 MB -> 4.912 MB. [2025-08-20T22:26:19.441Z] Camera thread performed 122 scans. [2025-08-20T22:26:19.441Z] ====== philosophers (scala) [default], iteration 2 completed (1636.291 ms) ====== [2025-08-20T22:26:19.441Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-20T22:26:19.441Z] GC before operation: completed in 10.874 ms, heap usage 22.720 MB -> 4.913 MB. [2025-08-20T22:26:20.954Z] Camera thread performed 122 scans. [2025-08-20T22:26:20.954Z] ====== philosophers (scala) [default], iteration 3 completed (1537.137 ms) ====== [2025-08-20T22:26:20.954Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-20T22:26:21.246Z] GC before operation: completed in 11.101 ms, heap usage 75.019 MB -> 4.914 MB. [2025-08-20T22:26:22.758Z] Camera thread performed 122 scans. [2025-08-20T22:26:22.758Z] ====== philosophers (scala) [default], iteration 4 completed (1634.458 ms) ====== [2025-08-20T22:26:22.758Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-20T22:26:22.758Z] GC before operation: completed in 10.740 ms, heap usage 6.122 MB -> 4.914 MB. [2025-08-20T22:26:24.285Z] Camera thread performed 122 scans. [2025-08-20T22:26:24.285Z] ====== philosophers (scala) [default], iteration 5 completed (1596.401 ms) ====== [2025-08-20T22:26:24.285Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-20T22:26:24.285Z] GC before operation: completed in 11.233 ms, heap usage 39.600 MB -> 4.918 MB. [2025-08-20T22:26:25.805Z] Camera thread performed 122 scans. [2025-08-20T22:26:25.805Z] ====== philosophers (scala) [default], iteration 6 completed (1439.587 ms) ====== [2025-08-20T22:26:25.805Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-20T22:26:25.805Z] GC before operation: completed in 10.437 ms, heap usage 6.497 MB -> 4.918 MB. [2025-08-20T22:26:26.846Z] Camera thread performed 122 scans. [2025-08-20T22:26:26.846Z] ====== philosophers (scala) [default], iteration 7 completed (1151.057 ms) ====== [2025-08-20T22:26:26.846Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-20T22:26:26.846Z] GC before operation: completed in 11.498 ms, heap usage 20.323 MB -> 4.919 MB. [2025-08-20T22:26:28.359Z] Camera thread performed 122 scans. [2025-08-20T22:26:28.359Z] ====== philosophers (scala) [default], iteration 8 completed (1442.024 ms) ====== [2025-08-20T22:26:28.359Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-20T22:26:28.359Z] GC before operation: completed in 10.722 ms, heap usage 85.937 MB -> 4.919 MB. [2025-08-20T22:26:29.872Z] Camera thread performed 122 scans. [2025-08-20T22:26:29.872Z] ====== philosophers (scala) [default], iteration 9 completed (1574.510 ms) ====== [2025-08-20T22:26:29.872Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-20T22:26:29.872Z] GC before operation: completed in 11.544 ms, heap usage 57.516 MB -> 4.919 MB. [2025-08-20T22:26:31.387Z] Camera thread performed 122 scans. [2025-08-20T22:26:31.387Z] ====== philosophers (scala) [default], iteration 10 completed (1472.366 ms) ====== [2025-08-20T22:26:31.387Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-20T22:26:31.387Z] GC before operation: completed in 10.999 ms, heap usage 60.353 MB -> 4.919 MB. [2025-08-20T22:26:32.903Z] Camera thread performed 122 scans. [2025-08-20T22:26:32.903Z] ====== philosophers (scala) [default], iteration 11 completed (1461.746 ms) ====== [2025-08-20T22:26:32.903Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-20T22:26:32.903Z] GC before operation: completed in 11.067 ms, heap usage 30.905 MB -> 4.920 MB. [2025-08-20T22:26:34.421Z] Camera thread performed 122 scans. [2025-08-20T22:26:34.421Z] ====== philosophers (scala) [default], iteration 12 completed (1440.664 ms) ====== [2025-08-20T22:26:34.421Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-20T22:26:34.421Z] GC before operation: completed in 10.724 ms, heap usage 73.935 MB -> 4.920 MB. [2025-08-20T22:26:35.938Z] Camera thread performed 122 scans. [2025-08-20T22:26:35.938Z] ====== philosophers (scala) [default], iteration 13 completed (1505.966 ms) ====== [2025-08-20T22:26:35.938Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-20T22:26:35.938Z] GC before operation: completed in 10.810 ms, heap usage 10.027 MB -> 4.920 MB. [2025-08-20T22:26:37.453Z] Camera thread performed 122 scans. [2025-08-20T22:26:37.453Z] ====== philosophers (scala) [default], iteration 14 completed (1410.876 ms) ====== [2025-08-20T22:26:37.453Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-20T22:26:37.453Z] GC before operation: completed in 10.806 ms, heap usage 83.927 MB -> 4.920 MB. [2025-08-20T22:26:38.966Z] Camera thread performed 122 scans. [2025-08-20T22:26:38.966Z] ====== philosophers (scala) [default], iteration 15 completed (1491.165 ms) ====== [2025-08-20T22:26:38.966Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-20T22:26:38.966Z] GC before operation: completed in 10.801 ms, heap usage 39.530 MB -> 4.920 MB. [2025-08-20T22:26:40.478Z] Camera thread performed 122 scans. [2025-08-20T22:26:40.478Z] ====== philosophers (scala) [default], iteration 16 completed (1527.272 ms) ====== [2025-08-20T22:26:40.478Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-20T22:26:40.478Z] GC before operation: completed in 10.880 ms, heap usage 38.771 MB -> 4.920 MB. [2025-08-20T22:26:41.991Z] Camera thread performed 122 scans. [2025-08-20T22:26:41.991Z] ====== philosophers (scala) [default], iteration 17 completed (1520.843 ms) ====== [2025-08-20T22:26:41.991Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-20T22:26:41.991Z] GC before operation: completed in 10.904 ms, heap usage 52.389 MB -> 4.920 MB. [2025-08-20T22:26:43.503Z] Camera thread performed 122 scans. [2025-08-20T22:26:43.503Z] ====== philosophers (scala) [default], iteration 18 completed (1424.136 ms) ====== [2025-08-20T22:26:43.503Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-20T22:26:43.503Z] GC before operation: completed in 10.138 ms, heap usage 23.125 MB -> 4.920 MB. [2025-08-20T22:26:45.017Z] Camera thread performed 122 scans. [2025-08-20T22:26:45.017Z] ====== philosophers (scala) [default], iteration 19 completed (1501.332 ms) ====== [2025-08-20T22:26:45.017Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-20T22:26:45.017Z] GC before operation: completed in 10.600 ms, heap usage 10.997 MB -> 4.920 MB. [2025-08-20T22:26:46.532Z] Camera thread performed 122 scans. [2025-08-20T22:26:46.532Z] ====== philosophers (scala) [default], iteration 20 completed (1422.930 ms) ====== [2025-08-20T22:26:46.532Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-20T22:26:46.532Z] GC before operation: completed in 13.777 ms, heap usage 46.766 MB -> 4.921 MB. [2025-08-20T22:26:48.044Z] Camera thread performed 122 scans. [2025-08-20T22:26:48.044Z] ====== philosophers (scala) [default], iteration 21 completed (1487.268 ms) ====== [2025-08-20T22:26:48.044Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-20T22:26:48.044Z] GC before operation: completed in 11.110 ms, heap usage 50.089 MB -> 4.920 MB. [2025-08-20T22:26:49.560Z] Camera thread performed 122 scans. [2025-08-20T22:26:49.560Z] ====== philosophers (scala) [default], iteration 22 completed (1469.359 ms) ====== [2025-08-20T22:26:49.560Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-20T22:26:49.561Z] GC before operation: completed in 10.954 ms, heap usage 78.320 MB -> 4.921 MB. [2025-08-20T22:26:50.599Z] Camera thread performed 122 scans. [2025-08-20T22:26:50.599Z] ====== philosophers (scala) [default], iteration 23 completed (1136.428 ms) ====== [2025-08-20T22:26:50.599Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-20T22:26:50.599Z] GC before operation: completed in 10.731 ms, heap usage 17.517 MB -> 4.921 MB. [2025-08-20T22:26:52.111Z] Camera thread performed 122 scans. [2025-08-20T22:26:52.111Z] ====== philosophers (scala) [default], iteration 24 completed (1548.351 ms) ====== [2025-08-20T22:26:52.111Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-20T22:26:52.111Z] GC before operation: completed in 10.553 ms, heap usage 41.102 MB -> 4.921 MB. [2025-08-20T22:26:53.626Z] Camera thread performed 122 scans. [2025-08-20T22:26:53.626Z] ====== philosophers (scala) [default], iteration 25 completed (1503.498 ms) ====== [2025-08-20T22:26:53.626Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-20T22:26:53.626Z] GC before operation: completed in 10.654 ms, heap usage 43.109 MB -> 4.921 MB. [2025-08-20T22:26:55.139Z] Camera thread performed 122 scans. [2025-08-20T22:26:55.139Z] ====== philosophers (scala) [default], iteration 26 completed (1536.075 ms) ====== [2025-08-20T22:26:55.139Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-20T22:26:55.139Z] GC before operation: completed in 10.562 ms, heap usage 18.469 MB -> 4.921 MB. [2025-08-20T22:26:56.650Z] Camera thread performed 122 scans. [2025-08-20T22:26:56.650Z] ====== philosophers (scala) [default], iteration 27 completed (1499.540 ms) ====== [2025-08-20T22:26:56.650Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-20T22:26:56.650Z] GC before operation: completed in 10.597 ms, heap usage 69.896 MB -> 4.921 MB. [2025-08-20T22:26:58.162Z] Camera thread performed 122 scans. [2025-08-20T22:26:58.162Z] ====== philosophers (scala) [default], iteration 28 completed (1485.750 ms) ====== [2025-08-20T22:26:58.162Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-20T22:26:58.162Z] GC before operation: completed in 11.056 ms, heap usage 19.532 MB -> 4.921 MB. [2025-08-20T22:26:59.711Z] Camera thread performed 122 scans. [2025-08-20T22:26:59.711Z] ====== philosophers (scala) [default], iteration 29 completed (1439.270 ms) ====== [2025-08-20T22:26:59.711Z] ----------------------------------- [2025-08-20T22:26:59.711Z] renaissance-philosophers_0_PASSED [2025-08-20T22:26:59.711Z] ----------------------------------- [2025-08-20T22:26:59.711Z] [2025-08-20T22:26:59.711Z] TEST TEARDOWN: [2025-08-20T22:26:59.711Z] Nothing to be done for teardown. [2025-08-20T22:27:00.004Z] renaissance-philosophers_0 Finish Time: Wed Aug 20 22:26:59 2025 Epoch Time (ms): 1755728819773