[2026-01-10T11:11:44.521Z] Running test renaissance-philosophers_0 ... [2026-01-10T11:11:44.521Z] =============================================== [2026-01-10T11:11:44.521Z] renaissance-philosophers_0 Start Time: Sat Jan 10 11:11:44 2026 Epoch Time (ms): 1768043504461 [2026-01-10T11:11:44.862Z] variation: NoOptions [2026-01-10T11:11:44.862Z] JVM_OPTIONS: [2026-01-10T11:11:44.862Z] { \ [2026-01-10T11:11:44.862Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T11:11:44.862Z] echo "Nothing to be done for setup."; \ [2026-01-10T11:11:44.862Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\renaissance-philosophers_0"; \ [2026-01-10T11:11:44.862Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\renaissance-philosophers_0"; \ [2026-01-10T11:11:44.862Z] echo ""; echo "TESTING:"; \ [2026-01-10T11:11:44.862Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_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:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2026-01-10T11:11:44.862Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T11:11:44.862Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T11:11:44.862Z] echo "Nothing to be done for teardown."; \ [2026-01-10T11:11:44.862Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17680397902078\\TestTargetResult"; [2026-01-10T11:11:45.229Z] [2026-01-10T11:11:45.229Z] TEST SETUP: [2026-01-10T11:11:45.229Z] Nothing to be done for setup. [2026-01-10T11:11:45.229Z] [2026-01-10T11:11:45.229Z] TESTING: [2026-01-10T11:11:46.498Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-01-10T11:11:46.498Z] GC before operation: completed in 19.202 ms, heap usage 16.322 MB -> 4.459 MB. [2026-01-10T11:11:48.514Z] Camera thread performed 122 scans. [2026-01-10T11:11:48.514Z] ====== philosophers (scala) [default], iteration 0 completed (2003.705 ms) ====== [2026-01-10T11:11:48.514Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-01-10T11:11:48.514Z] GC before operation: completed in 19.833 ms, heap usage 48.238 MB -> 4.869 MB. [2026-01-10T11:11:49.721Z] Camera thread performed 122 scans. [2026-01-10T11:11:49.721Z] ====== philosophers (scala) [default], iteration 1 completed (1289.914 ms) ====== [2026-01-10T11:11:49.721Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-01-10T11:11:49.721Z] GC before operation: completed in 19.689 ms, heap usage 28.397 MB -> 4.869 MB. [2026-01-10T11:11:51.449Z] Camera thread performed 122 scans. [2026-01-10T11:11:51.449Z] ====== philosophers (scala) [default], iteration 2 completed (1570.980 ms) ====== [2026-01-10T11:11:51.449Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-01-10T11:11:51.449Z] GC before operation: completed in 22.410 ms, heap usage 74.771 MB -> 4.874 MB. [2026-01-10T11:11:52.658Z] Camera thread performed 122 scans. [2026-01-10T11:11:52.658Z] ====== philosophers (scala) [default], iteration 3 completed (1443.034 ms) ====== [2026-01-10T11:11:52.658Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-01-10T11:11:52.658Z] GC before operation: completed in 18.521 ms, heap usage 7.197 MB -> 4.874 MB. [2026-01-10T11:11:53.866Z] Camera thread performed 122 scans. [2026-01-10T11:11:53.866Z] ====== philosophers (scala) [default], iteration 4 completed (1325.898 ms) ====== [2026-01-10T11:11:53.866Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-01-10T11:11:53.866Z] GC before operation: completed in 29.325 ms, heap usage 33.639 MB -> 4.876 MB. [2026-01-10T11:11:55.596Z] Camera thread performed 122 scans. [2026-01-10T11:11:55.596Z] ====== philosophers (scala) [default], iteration 5 completed (1363.831 ms) ====== [2026-01-10T11:11:55.596Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-01-10T11:11:55.596Z] GC before operation: completed in 21.291 ms, heap usage 64.928 MB -> 4.878 MB. [2026-01-10T11:11:56.803Z] Camera thread performed 122 scans. [2026-01-10T11:11:56.803Z] ====== philosophers (scala) [default], iteration 6 completed (1341.715 ms) ====== [2026-01-10T11:11:56.803Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-01-10T11:11:56.803Z] GC before operation: completed in 26.027 ms, heap usage 85.124 MB -> 4.878 MB. [2026-01-10T11:11:58.010Z] Camera thread performed 122 scans. [2026-01-10T11:11:58.010Z] ====== philosophers (scala) [default], iteration 7 completed (1340.184 ms) ====== [2026-01-10T11:11:58.010Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-01-10T11:11:58.010Z] GC before operation: completed in 19.852 ms, heap usage 17.431 MB -> 4.879 MB. [2026-01-10T11:11:59.738Z] Camera thread performed 122 scans. [2026-01-10T11:11:59.738Z] ====== philosophers (scala) [default], iteration 8 completed (1404.502 ms) ====== [2026-01-10T11:11:59.738Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-01-10T11:11:59.738Z] GC before operation: completed in 23.628 ms, heap usage 58.991 MB -> 4.879 MB. [2026-01-10T11:12:00.953Z] Camera thread performed 122 scans. [2026-01-10T11:12:00.953Z] ====== philosophers (scala) [default], iteration 9 completed (1316.814 ms) ====== [2026-01-10T11:12:00.953Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-01-10T11:12:00.953Z] GC before operation: completed in 16.613 ms, heap usage 23.427 MB -> 4.879 MB. [2026-01-10T11:12:02.150Z] Camera thread performed 122 scans. [2026-01-10T11:12:02.150Z] ====== philosophers (scala) [default], iteration 10 completed (1439.272 ms) ====== [2026-01-10T11:12:02.150Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-01-10T11:12:02.517Z] GC before operation: completed in 22.069 ms, heap usage 19.534 MB -> 4.879 MB. [2026-01-10T11:12:03.749Z] Camera thread performed 122 scans. [2026-01-10T11:12:03.749Z] ====== philosophers (scala) [default], iteration 11 completed (1385.091 ms) ====== [2026-01-10T11:12:03.749Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-01-10T11:12:03.749Z] GC before operation: completed in 20.778 ms, heap usage 17.118 MB -> 4.879 MB. [2026-01-10T11:12:05.479Z] Camera thread performed 122 scans. [2026-01-10T11:12:05.479Z] ====== philosophers (scala) [default], iteration 12 completed (1391.006 ms) ====== [2026-01-10T11:12:05.479Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-01-10T11:12:05.479Z] GC before operation: completed in 21.159 ms, heap usage 52.110 MB -> 4.879 MB. [2026-01-10T11:12:06.686Z] Camera thread performed 122 scans. [2026-01-10T11:12:06.686Z] ====== philosophers (scala) [default], iteration 13 completed (1370.770 ms) ====== [2026-01-10T11:12:06.686Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-01-10T11:12:06.686Z] GC before operation: completed in 21.564 ms, heap usage 50.001 MB -> 4.880 MB. [2026-01-10T11:12:07.929Z] Camera thread performed 122 scans. [2026-01-10T11:12:07.929Z] ====== philosophers (scala) [default], iteration 14 completed (1360.762 ms) ====== [2026-01-10T11:12:07.929Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-01-10T11:12:07.929Z] GC before operation: completed in 21.601 ms, heap usage 42.609 MB -> 4.880 MB. [2026-01-10T11:12:09.130Z] Camera thread performed 122 scans. [2026-01-10T11:12:09.131Z] ====== philosophers (scala) [default], iteration 15 completed (1344.800 ms) ====== [2026-01-10T11:12:09.131Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-01-10T11:12:09.469Z] GC before operation: completed in 22.132 ms, heap usage 62.584 MB -> 4.880 MB. [2026-01-10T11:12:10.687Z] Camera thread performed 122 scans. [2026-01-10T11:12:10.687Z] ====== philosophers (scala) [default], iteration 16 completed (1260.485 ms) ====== [2026-01-10T11:12:10.687Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-01-10T11:12:10.687Z] GC before operation: completed in 20.554 ms, heap usage 34.507 MB -> 4.880 MB. [2026-01-10T11:12:11.916Z] Camera thread performed 122 scans. [2026-01-10T11:12:11.916Z] ====== philosophers (scala) [default], iteration 17 completed (1357.449 ms) ====== [2026-01-10T11:12:11.916Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-01-10T11:12:11.916Z] GC before operation: completed in 22.057 ms, heap usage 14.957 MB -> 4.880 MB. [2026-01-10T11:12:13.649Z] Camera thread performed 122 scans. [2026-01-10T11:12:13.649Z] ====== philosophers (scala) [default], iteration 18 completed (1351.440 ms) ====== [2026-01-10T11:12:13.649Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-01-10T11:12:13.649Z] GC before operation: completed in 21.929 ms, heap usage 30.019 MB -> 4.881 MB. [2026-01-10T11:12:14.855Z] Camera thread performed 122 scans. [2026-01-10T11:12:14.855Z] ====== philosophers (scala) [default], iteration 19 completed (1352.238 ms) ====== [2026-01-10T11:12:14.855Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-01-10T11:12:14.855Z] GC before operation: completed in 22.721 ms, heap usage 19.117 MB -> 4.880 MB. [2026-01-10T11:12:16.059Z] Camera thread performed 122 scans. [2026-01-10T11:12:16.059Z] ====== philosophers (scala) [default], iteration 20 completed (1366.312 ms) ====== [2026-01-10T11:12:16.059Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-01-10T11:12:16.059Z] GC before operation: completed in 22.528 ms, heap usage 45.949 MB -> 4.881 MB. [2026-01-10T11:12:17.787Z] Camera thread performed 122 scans. [2026-01-10T11:12:17.787Z] ====== philosophers (scala) [default], iteration 21 completed (1445.031 ms) ====== [2026-01-10T11:12:17.787Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-01-10T11:12:17.787Z] GC before operation: completed in 21.779 ms, heap usage 9.494 MB -> 4.881 MB. [2026-01-10T11:12:19.040Z] Camera thread performed 122 scans. [2026-01-10T11:12:19.040Z] ====== philosophers (scala) [default], iteration 22 completed (1541.113 ms) ====== [2026-01-10T11:12:19.040Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-01-10T11:12:19.040Z] GC before operation: completed in 21.618 ms, heap usage 73.099 MB -> 4.881 MB. [2026-01-10T11:12:20.768Z] Camera thread performed 122 scans. [2026-01-10T11:12:20.768Z] ====== philosophers (scala) [default], iteration 23 completed (1484.811 ms) ====== [2026-01-10T11:12:20.768Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-01-10T11:12:20.768Z] GC before operation: completed in 20.527 ms, heap usage 43.493 MB -> 4.881 MB. [2026-01-10T11:12:21.972Z] Camera thread performed 122 scans. [2026-01-10T11:12:21.972Z] ====== philosophers (scala) [default], iteration 24 completed (1378.691 ms) ====== [2026-01-10T11:12:21.972Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-01-10T11:12:21.972Z] GC before operation: completed in 20.835 ms, heap usage 20.613 MB -> 4.881 MB. [2026-01-10T11:12:23.705Z] Camera thread performed 122 scans. [2026-01-10T11:12:23.705Z] ====== philosophers (scala) [default], iteration 25 completed (1488.427 ms) ====== [2026-01-10T11:12:23.705Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-01-10T11:12:23.705Z] GC before operation: completed in 21.961 ms, heap usage 58.502 MB -> 4.881 MB. [2026-01-10T11:12:24.907Z] Camera thread performed 122 scans. [2026-01-10T11:12:24.907Z] ====== philosophers (scala) [default], iteration 26 completed (1375.131 ms) ====== [2026-01-10T11:12:24.907Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-01-10T11:12:24.907Z] GC before operation: completed in 22.152 ms, heap usage 51.575 MB -> 4.881 MB. [2026-01-10T11:12:26.645Z] Camera thread performed 122 scans. [2026-01-10T11:12:26.645Z] ====== philosophers (scala) [default], iteration 27 completed (1415.220 ms) ====== [2026-01-10T11:12:26.645Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-01-10T11:12:26.645Z] GC before operation: completed in 21.464 ms, heap usage 16.117 MB -> 4.881 MB. [2026-01-10T11:12:27.825Z] Camera thread performed 122 scans. [2026-01-10T11:12:27.825Z] ====== philosophers (scala) [default], iteration 28 completed (1374.306 ms) ====== [2026-01-10T11:12:27.825Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-01-10T11:12:27.825Z] GC before operation: completed in 24.283 ms, heap usage 63.540 MB -> 4.882 MB. [2026-01-10T11:12:29.526Z] Camera thread performed 122 scans. [2026-01-10T11:12:29.527Z] ====== philosophers (scala) [default], iteration 29 completed (1463.517 ms) ====== [2026-01-10T11:12:29.527Z] ----------------------------------- [2026-01-10T11:12:29.527Z] renaissance-philosophers_0_PASSED [2026-01-10T11:12:29.527Z] ----------------------------------- [2026-01-10T11:12:29.527Z] [2026-01-10T11:12:29.527Z] TEST TEARDOWN: [2026-01-10T11:12:29.527Z] Nothing to be done for teardown. [2026-01-10T11:12:29.527Z] renaissance-philosophers_0 Finish Time: Sat Jan 10 11:12:29 2026 Epoch Time (ms): 1768043549361