[2025-12-25T00:40:48.578Z] Running test renaissance-philosophers_0 ... [2025-12-25T00:40:48.578Z] =============================================== [2025-12-25T00:40:48.877Z] renaissance-philosophers_0 Start Time: Thu Dec 25 00:40:48 2025 Epoch Time (ms): 1766623248655 [2025-12-25T00:40:48.877Z] variation: NoOptions [2025-12-25T00:40:49.176Z] JVM_OPTIONS: [2025-12-25T00:40:49.176Z] { \ [2025-12-25T00:40:49.176Z] echo ""; echo "TEST SETUP:"; \ [2025-12-25T00:40:49.176Z] echo "Nothing to be done for setup."; \ [2025-12-25T00:40:49.176Z] mkdir -p "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17666223534494\\renaissance-philosophers_0"; \ [2025-12-25T00:40:49.176Z] cd "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17666223534494\\renaissance-philosophers_0"; \ [2025-12-25T00:40:49.176Z] echo ""; echo "TESTING:"; \ [2025-12-25T00:40:49.176Z] "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_17666223534494\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-12-25T00:40:49.176Z] 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_17666223534494\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-25T00:40:49.176Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-25T00:40:49.176Z] echo "Nothing to be done for teardown."; \ [2025-12-25T00:40:49.176Z] } 2>&1 | tee -a "C:/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_windows/aqa-tests/\\TKG\\output_17666223534494\\TestTargetResult"; [2025-12-25T00:40:49.176Z] [2025-12-25T00:40:49.176Z] TEST SETUP: [2025-12-25T00:40:49.176Z] Nothing to be done for setup. [2025-12-25T00:40:49.176Z] [2025-12-25T00:40:49.176Z] TESTING: [2025-12-25T00:40:50.722Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-25T00:40:50.722Z] GC before operation: completed in 10.811 ms, heap usage 15.964 MB -> 4.549 MB. [2025-12-25T00:40:52.900Z] Camera thread performed 122 scans. [2025-12-25T00:40:52.900Z] ====== philosophers (scala) [default], iteration 0 completed (1988.359 ms) ====== [2025-12-25T00:40:52.900Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-25T00:40:52.900Z] GC before operation: completed in 14.243 ms, heap usage 85.207 MB -> 4.922 MB. [2025-12-25T00:40:54.437Z] Camera thread performed 122 scans. [2025-12-25T00:40:54.437Z] ====== philosophers (scala) [default], iteration 1 completed (1603.393 ms) ====== [2025-12-25T00:40:54.437Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-25T00:40:54.437Z] GC before operation: completed in 10.458 ms, heap usage 44.135 MB -> 4.924 MB. [2025-12-25T00:40:55.993Z] Camera thread performed 122 scans. [2025-12-25T00:40:55.993Z] ====== philosophers (scala) [default], iteration 2 completed (1777.574 ms) ====== [2025-12-25T00:40:55.993Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-25T00:40:55.993Z] GC before operation: completed in 9.094 ms, heap usage 52.129 MB -> 4.925 MB. [2025-12-25T00:40:57.531Z] Camera thread performed 122 scans. [2025-12-25T00:40:57.531Z] ====== philosophers (scala) [default], iteration 3 completed (1655.420 ms) ====== [2025-12-25T00:40:57.531Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-25T00:40:57.531Z] GC before operation: completed in 9.019 ms, heap usage 49.133 MB -> 4.925 MB. [2025-12-25T00:40:59.638Z] Camera thread performed 122 scans. [2025-12-25T00:40:59.638Z] ====== philosophers (scala) [default], iteration 4 completed (1745.175 ms) ====== [2025-12-25T00:40:59.638Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-25T00:40:59.638Z] GC before operation: completed in 9.119 ms, heap usage 64.132 MB -> 4.926 MB. [2025-12-25T00:41:01.178Z] Camera thread performed 122 scans. [2025-12-25T00:41:01.178Z] ====== philosophers (scala) [default], iteration 5 completed (1599.414 ms) ====== [2025-12-25T00:41:01.178Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-25T00:41:01.178Z] GC before operation: completed in 9.603 ms, heap usage 60.013 MB -> 4.929 MB. [2025-12-25T00:41:02.714Z] Camera thread performed 122 scans. [2025-12-25T00:41:02.714Z] ====== philosophers (scala) [default], iteration 6 completed (1790.718 ms) ====== [2025-12-25T00:41:02.714Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-25T00:41:02.714Z] GC before operation: completed in 8.881 ms, heap usage 19.589 MB -> 4.931 MB. [2025-12-25T00:41:04.823Z] Camera thread performed 122 scans. [2025-12-25T00:41:04.823Z] ====== philosophers (scala) [default], iteration 7 completed (1693.313 ms) ====== [2025-12-25T00:41:04.823Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-25T00:41:04.823Z] GC before operation: completed in 9.160 ms, heap usage 26.518 MB -> 4.931 MB. [2025-12-25T00:41:06.361Z] Camera thread performed 122 scans. [2025-12-25T00:41:06.361Z] ====== philosophers (scala) [default], iteration 8 completed (1695.468 ms) ====== [2025-12-25T00:41:06.361Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-25T00:41:06.361Z] GC before operation: completed in 9.257 ms, heap usage 67.760 MB -> 4.931 MB. [2025-12-25T00:41:07.897Z] Camera thread performed 122 scans. [2025-12-25T00:41:07.897Z] ====== philosophers (scala) [default], iteration 9 completed (1653.646 ms) ====== [2025-12-25T00:41:07.897Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-25T00:41:07.897Z] GC before operation: completed in 8.648 ms, heap usage 14.074 MB -> 4.931 MB. [2025-12-25T00:41:09.442Z] Camera thread performed 122 scans. [2025-12-25T00:41:09.442Z] ====== philosophers (scala) [default], iteration 10 completed (1647.908 ms) ====== [2025-12-25T00:41:09.442Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-25T00:41:09.442Z] GC before operation: completed in 9.041 ms, heap usage 21.834 MB -> 4.932 MB. [2025-12-25T00:41:11.553Z] Camera thread performed 122 scans. [2025-12-25T00:41:11.553Z] ====== philosophers (scala) [default], iteration 11 completed (1597.643 ms) ====== [2025-12-25T00:41:11.553Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-25T00:41:11.553Z] GC before operation: completed in 9.010 ms, heap usage 18.931 MB -> 4.932 MB. [2025-12-25T00:41:13.091Z] Camera thread performed 122 scans. [2025-12-25T00:41:13.091Z] ====== philosophers (scala) [default], iteration 12 completed (1659.295 ms) ====== [2025-12-25T00:41:13.091Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-25T00:41:13.091Z] GC before operation: completed in 8.880 ms, heap usage 19.927 MB -> 4.932 MB. [2025-12-25T00:41:14.640Z] Camera thread performed 122 scans. [2025-12-25T00:41:14.640Z] ====== philosophers (scala) [default], iteration 13 completed (1637.801 ms) ====== [2025-12-25T00:41:14.640Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-25T00:41:14.640Z] GC before operation: completed in 8.902 ms, heap usage 43.454 MB -> 4.932 MB. [2025-12-25T00:41:16.178Z] Camera thread performed 122 scans. [2025-12-25T00:41:16.178Z] ====== philosophers (scala) [default], iteration 14 completed (1747.289 ms) ====== [2025-12-25T00:41:16.178Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-25T00:41:16.178Z] GC before operation: completed in 9.439 ms, heap usage 19.147 MB -> 4.932 MB. [2025-12-25T00:41:18.292Z] Camera thread performed 122 scans. [2025-12-25T00:41:18.292Z] ====== philosophers (scala) [default], iteration 15 completed (1711.797 ms) ====== [2025-12-25T00:41:18.292Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-25T00:41:18.292Z] GC before operation: completed in 9.334 ms, heap usage 40.545 MB -> 4.932 MB. [2025-12-25T00:41:19.829Z] Camera thread performed 122 scans. [2025-12-25T00:41:19.829Z] ====== philosophers (scala) [default], iteration 16 completed (1627.167 ms) ====== [2025-12-25T00:41:19.829Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-25T00:41:19.829Z] GC before operation: completed in 9.160 ms, heap usage 59.107 MB -> 4.933 MB. [2025-12-25T00:41:21.364Z] Camera thread performed 122 scans. [2025-12-25T00:41:21.364Z] ====== philosophers (scala) [default], iteration 17 completed (1861.499 ms) ====== [2025-12-25T00:41:21.364Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-25T00:41:21.364Z] GC before operation: completed in 8.926 ms, heap usage 36.117 MB -> 4.932 MB. [2025-12-25T00:41:22.419Z] Camera thread performed 122 scans. [2025-12-25T00:41:22.419Z] ====== philosophers (scala) [default], iteration 18 completed (1083.376 ms) ====== [2025-12-25T00:41:22.419Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-25T00:41:22.419Z] GC before operation: completed in 9.407 ms, heap usage 24.075 MB -> 4.933 MB. [2025-12-25T00:41:24.525Z] Camera thread performed 122 scans. [2025-12-25T00:41:24.525Z] ====== philosophers (scala) [default], iteration 19 completed (1590.672 ms) ====== [2025-12-25T00:41:24.525Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-25T00:41:24.525Z] GC before operation: completed in 8.848 ms, heap usage 25.545 MB -> 4.932 MB. [2025-12-25T00:41:26.062Z] Camera thread performed 122 scans. [2025-12-25T00:41:26.062Z] ====== philosophers (scala) [default], iteration 20 completed (1691.534 ms) ====== [2025-12-25T00:41:26.062Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-25T00:41:26.062Z] GC before operation: completed in 9.442 ms, heap usage 44.735 MB -> 4.933 MB. [2025-12-25T00:41:27.599Z] Camera thread performed 122 scans. [2025-12-25T00:41:27.599Z] ====== philosophers (scala) [default], iteration 21 completed (1791.740 ms) ====== [2025-12-25T00:41:27.599Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-25T00:41:27.599Z] GC before operation: completed in 9.352 ms, heap usage 60.979 MB -> 4.933 MB. [2025-12-25T00:41:29.139Z] Camera thread performed 122 scans. [2025-12-25T00:41:29.139Z] ====== philosophers (scala) [default], iteration 22 completed (1601.765 ms) ====== [2025-12-25T00:41:29.139Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-25T00:41:29.139Z] GC before operation: completed in 9.295 ms, heap usage 19.514 MB -> 4.933 MB. [2025-12-25T00:41:31.247Z] Camera thread performed 122 scans. [2025-12-25T00:41:31.247Z] ====== philosophers (scala) [default], iteration 23 completed (1719.775 ms) ====== [2025-12-25T00:41:31.247Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-25T00:41:31.247Z] GC before operation: completed in 9.157 ms, heap usage 23.156 MB -> 4.933 MB. [2025-12-25T00:41:32.783Z] Camera thread performed 122 scans. [2025-12-25T00:41:32.783Z] ====== philosophers (scala) [default], iteration 24 completed (1499.140 ms) ====== [2025-12-25T00:41:32.783Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-25T00:41:32.783Z] GC before operation: completed in 8.749 ms, heap usage 6.927 MB -> 4.933 MB. [2025-12-25T00:41:34.326Z] Camera thread performed 122 scans. [2025-12-25T00:41:34.326Z] ====== philosophers (scala) [default], iteration 25 completed (1641.671 ms) ====== [2025-12-25T00:41:34.326Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-25T00:41:34.326Z] GC before operation: completed in 8.993 ms, heap usage 42.746 MB -> 4.933 MB. [2025-12-25T00:41:35.868Z] Camera thread performed 122 scans. [2025-12-25T00:41:35.868Z] ====== philosophers (scala) [default], iteration 26 completed (1673.716 ms) ====== [2025-12-25T00:41:35.868Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-25T00:41:35.868Z] GC before operation: completed in 9.071 ms, heap usage 55.105 MB -> 4.933 MB. [2025-12-25T00:41:37.973Z] Camera thread performed 122 scans. [2025-12-25T00:41:37.973Z] ====== philosophers (scala) [default], iteration 27 completed (1975.622 ms) ====== [2025-12-25T00:41:37.973Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-25T00:41:37.973Z] GC before operation: completed in 8.818 ms, heap usage 21.589 MB -> 4.933 MB. [2025-12-25T00:41:39.512Z] Camera thread performed 122 scans. [2025-12-25T00:41:39.512Z] ====== philosophers (scala) [default], iteration 28 completed (1814.404 ms) ====== [2025-12-25T00:41:39.512Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-25T00:41:39.811Z] GC before operation: completed in 9.547 ms, heap usage 55.148 MB -> 4.934 MB. [2025-12-25T00:41:41.348Z] Camera thread performed 122 scans. [2025-12-25T00:41:41.348Z] ====== philosophers (scala) [default], iteration 29 completed (1683.287 ms) ====== [2025-12-25T00:41:41.647Z] ----------------------------------- [2025-12-25T00:41:41.647Z] renaissance-philosophers_0_PASSED [2025-12-25T00:41:41.647Z] ----------------------------------- [2025-12-25T00:41:41.647Z] [2025-12-25T00:41:41.647Z] TEST TEARDOWN: [2025-12-25T00:41:41.647Z] Nothing to be done for teardown. [2025-12-25T00:41:41.647Z] renaissance-philosophers_0 Finish Time: Thu Dec 25 00:41:41 2025 Epoch Time (ms): 1766623301550