[2026-04-02T04:24:40.161Z] Running test renaissance-philosophers_0 ... [2026-04-02T04:24:40.161Z] =============================================== [2026-04-02T04:24:40.161Z] renaissance-philosophers_0 Start Time: Thu Apr 2 04:24:40 2026 Epoch Time (ms): 1775103880120 [2026-04-02T04:24:40.519Z] variation: NoOptions [2026-04-02T04:24:40.519Z] JVM_OPTIONS: [2026-04-02T04:24:40.519Z] { \ [2026-04-02T04:24:40.519Z] echo ""; echo "TEST SETUP:"; \ [2026-04-02T04:24:40.519Z] echo "Nothing to be done for setup."; \ [2026-04-02T04:24:40.519Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\renaissance-philosophers_0"; \ [2026-04-02T04:24:40.519Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\renaissance-philosophers_0"; \ [2026-04-02T04:24:40.519Z] echo ""; echo "TESTING:"; \ [2026-04-02T04:24:40.519Z] "c:/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2026-04-02T04:24:40.519Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-02T04:24:40.519Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-02T04:24:40.519Z] echo "Nothing to be done for teardown."; \ [2026-04-02T04:24:40.519Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17751015981465\\TestTargetResult"; [2026-04-02T04:24:40.519Z] [2026-04-02T04:24:40.519Z] TEST SETUP: [2026-04-02T04:24:40.519Z] Nothing to be done for setup. [2026-04-02T04:24:40.519Z] [2026-04-02T04:24:40.519Z] TESTING: [2026-04-02T04:24:41.854Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-04-02T04:24:41.854Z] GC before operation: completed in 10.564 ms, heap usage 15.584 MB -> 4.480 MB. [2026-04-02T04:24:43.706Z] Camera thread performed 122 scans. [2026-04-02T04:24:43.706Z] ====== philosophers (scala) [default], iteration 0 completed (2046.026 ms) ====== [2026-04-02T04:24:43.706Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-04-02T04:24:43.706Z] GC before operation: completed in 12.177 ms, heap usage 43.687 MB -> 4.878 MB. [2026-04-02T04:24:44.899Z] Camera thread performed 122 scans. [2026-04-02T04:24:44.899Z] ====== philosophers (scala) [default], iteration 1 completed (1340.723 ms) ====== [2026-04-02T04:24:44.899Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-04-02T04:24:44.899Z] GC before operation: completed in 11.359 ms, heap usage 79.074 MB -> 4.879 MB. [2026-04-02T04:24:46.613Z] Camera thread performed 122 scans. [2026-04-02T04:24:46.613Z] ====== philosophers (scala) [default], iteration 2 completed (1461.230 ms) ====== [2026-04-02T04:24:46.613Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-04-02T04:24:46.613Z] GC before operation: completed in 10.408 ms, heap usage 48.265 MB -> 4.884 MB. [2026-04-02T04:24:47.801Z] Camera thread performed 122 scans. [2026-04-02T04:24:47.801Z] ====== philosophers (scala) [default], iteration 3 completed (1433.317 ms) ====== [2026-04-02T04:24:47.801Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-04-02T04:24:47.801Z] GC before operation: completed in 10.303 ms, heap usage 6.664 MB -> 4.884 MB. [2026-04-02T04:24:49.511Z] Camera thread performed 122 scans. [2026-04-02T04:24:49.511Z] ====== philosophers (scala) [default], iteration 4 completed (1449.618 ms) ====== [2026-04-02T04:24:49.511Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-04-02T04:24:49.511Z] GC before operation: completed in 10.872 ms, heap usage 65.646 MB -> 4.885 MB. [2026-04-02T04:24:51.225Z] Camera thread performed 122 scans. [2026-04-02T04:24:51.225Z] ====== philosophers (scala) [default], iteration 5 completed (1548.314 ms) ====== [2026-04-02T04:24:51.225Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-04-02T04:24:51.225Z] GC before operation: completed in 10.675 ms, heap usage 56.757 MB -> 4.887 MB. [2026-04-02T04:24:52.417Z] Camera thread performed 122 scans. [2026-04-02T04:24:52.417Z] ====== philosophers (scala) [default], iteration 6 completed (1404.891 ms) ====== [2026-04-02T04:24:52.417Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-04-02T04:24:52.417Z] GC before operation: completed in 10.260 ms, heap usage 17.533 MB -> 4.888 MB. [2026-04-02T04:24:54.126Z] Camera thread performed 122 scans. [2026-04-02T04:24:54.126Z] ====== philosophers (scala) [default], iteration 7 completed (1597.008 ms) ====== [2026-04-02T04:24:54.126Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-04-02T04:24:54.126Z] GC before operation: completed in 10.266 ms, heap usage 26.845 MB -> 4.889 MB. [2026-04-02T04:24:55.319Z] Camera thread performed 122 scans. [2026-04-02T04:24:55.319Z] ====== philosophers (scala) [default], iteration 8 completed (1439.666 ms) ====== [2026-04-02T04:24:55.319Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-04-02T04:24:55.319Z] GC before operation: completed in 11.506 ms, heap usage 71.927 MB -> 4.889 MB. [2026-04-02T04:24:57.028Z] Camera thread performed 122 scans. [2026-04-02T04:24:57.028Z] ====== philosophers (scala) [default], iteration 9 completed (1518.673 ms) ====== [2026-04-02T04:24:57.028Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-04-02T04:24:57.028Z] GC before operation: completed in 10.587 ms, heap usage 34.564 MB -> 4.889 MB. [2026-04-02T04:24:58.737Z] Camera thread performed 122 scans. [2026-04-02T04:24:58.737Z] ====== philosophers (scala) [default], iteration 10 completed (1564.013 ms) ====== [2026-04-02T04:24:58.737Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-04-02T04:24:58.737Z] GC before operation: completed in 10.911 ms, heap usage 77.917 MB -> 4.890 MB. [2026-04-02T04:24:59.928Z] Camera thread performed 122 scans. [2026-04-02T04:24:59.928Z] ====== philosophers (scala) [default], iteration 11 completed (1391.905 ms) ====== [2026-04-02T04:24:59.928Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-04-02T04:24:59.928Z] GC before operation: completed in 11.058 ms, heap usage 75.526 MB -> 4.890 MB. [2026-04-02T04:25:01.638Z] Camera thread performed 122 scans. [2026-04-02T04:25:01.638Z] ====== philosophers (scala) [default], iteration 12 completed (1408.836 ms) ====== [2026-04-02T04:25:01.638Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-04-02T04:25:01.638Z] GC before operation: completed in 12.394 ms, heap usage 60.019 MB -> 4.891 MB. [2026-04-02T04:25:02.842Z] Camera thread performed 122 scans. [2026-04-02T04:25:02.842Z] ====== philosophers (scala) [default], iteration 13 completed (1548.598 ms) ====== [2026-04-02T04:25:02.842Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-04-02T04:25:02.842Z] GC before operation: completed in 11.132 ms, heap usage 66.516 MB -> 4.892 MB. [2026-04-02T04:25:04.564Z] Camera thread performed 122 scans. [2026-04-02T04:25:04.564Z] ====== philosophers (scala) [default], iteration 14 completed (1387.038 ms) ====== [2026-04-02T04:25:04.564Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-04-02T04:25:04.564Z] GC before operation: completed in 10.540 ms, heap usage 29.058 MB -> 4.892 MB. [2026-04-02T04:25:05.751Z] Camera thread performed 122 scans. [2026-04-02T04:25:05.751Z] ====== philosophers (scala) [default], iteration 15 completed (1507.511 ms) ====== [2026-04-02T04:25:05.751Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-04-02T04:25:05.751Z] GC before operation: completed in 9.778 ms, heap usage 8.110 MB -> 4.892 MB. [2026-04-02T04:25:06.938Z] Camera thread performed 122 scans. [2026-04-02T04:25:06.938Z] ====== philosophers (scala) [default], iteration 16 completed (1289.690 ms) ====== [2026-04-02T04:25:06.938Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-04-02T04:25:06.938Z] GC before operation: completed in 10.713 ms, heap usage 9.036 MB -> 4.893 MB. [2026-04-02T04:25:08.645Z] Camera thread performed 122 scans. [2026-04-02T04:25:08.645Z] ====== philosophers (scala) [default], iteration 17 completed (1369.130 ms) ====== [2026-04-02T04:25:08.645Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-04-02T04:25:08.645Z] GC before operation: completed in 10.519 ms, heap usage 12.108 MB -> 4.893 MB. [2026-04-02T04:25:10.339Z] Camera thread performed 122 scans. [2026-04-02T04:25:10.339Z] ====== philosophers (scala) [default], iteration 18 completed (1552.081 ms) ====== [2026-04-02T04:25:10.339Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-04-02T04:25:10.339Z] GC before operation: completed in 10.417 ms, heap usage 50.531 MB -> 4.893 MB. [2026-04-02T04:25:11.528Z] Camera thread performed 122 scans. [2026-04-02T04:25:11.528Z] ====== philosophers (scala) [default], iteration 19 completed (1428.198 ms) ====== [2026-04-02T04:25:11.528Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-04-02T04:25:11.528Z] GC before operation: completed in 10.280 ms, heap usage 63.475 MB -> 4.893 MB. [2026-04-02T04:25:13.232Z] Camera thread performed 122 scans. [2026-04-02T04:25:13.232Z] ====== philosophers (scala) [default], iteration 20 completed (1540.400 ms) ====== [2026-04-02T04:25:13.232Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-04-02T04:25:13.232Z] GC before operation: completed in 10.498 ms, heap usage 34.757 MB -> 4.893 MB. [2026-04-02T04:25:14.420Z] Camera thread performed 122 scans. [2026-04-02T04:25:14.420Z] ====== philosophers (scala) [default], iteration 21 completed (1453.137 ms) ====== [2026-04-02T04:25:14.420Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-04-02T04:25:14.420Z] GC before operation: completed in 11.041 ms, heap usage 60.817 MB -> 4.893 MB. [2026-04-02T04:25:16.127Z] Camera thread performed 122 scans. [2026-04-02T04:25:16.127Z] ====== philosophers (scala) [default], iteration 22 completed (1502.397 ms) ====== [2026-04-02T04:25:16.127Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-04-02T04:25:16.127Z] GC before operation: completed in 10.157 ms, heap usage 50.809 MB -> 4.894 MB. [2026-04-02T04:25:17.311Z] Camera thread performed 122 scans. [2026-04-02T04:25:17.311Z] ====== philosophers (scala) [default], iteration 23 completed (1416.816 ms) ====== [2026-04-02T04:25:17.311Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-04-02T04:25:17.311Z] GC before operation: completed in 10.931 ms, heap usage 24.112 MB -> 4.893 MB. [2026-04-02T04:25:19.050Z] Camera thread performed 122 scans. [2026-04-02T04:25:19.050Z] ====== philosophers (scala) [default], iteration 24 completed (1498.090 ms) ====== [2026-04-02T04:25:19.050Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-04-02T04:25:19.050Z] GC before operation: completed in 10.674 ms, heap usage 48.799 MB -> 4.893 MB. [2026-04-02T04:25:20.238Z] Camera thread performed 122 scans. [2026-04-02T04:25:20.238Z] ====== philosophers (scala) [default], iteration 25 completed (1431.493 ms) ====== [2026-04-02T04:25:20.238Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-04-02T04:25:20.238Z] GC before operation: completed in 10.643 ms, heap usage 16.844 MB -> 4.893 MB. [2026-04-02T04:25:21.944Z] Camera thread performed 122 scans. [2026-04-02T04:25:21.944Z] ====== philosophers (scala) [default], iteration 26 completed (1507.682 ms) ====== [2026-04-02T04:25:21.944Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-04-02T04:25:21.944Z] GC before operation: completed in 10.372 ms, heap usage 21.628 MB -> 4.893 MB. [2026-04-02T04:25:23.650Z] Camera thread performed 122 scans. [2026-04-02T04:25:23.650Z] ====== philosophers (scala) [default], iteration 27 completed (1537.408 ms) ====== [2026-04-02T04:25:23.650Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-04-02T04:25:23.650Z] GC before operation: completed in 10.859 ms, heap usage 48.534 MB -> 4.893 MB. [2026-04-02T04:25:25.357Z] Camera thread performed 122 scans. [2026-04-02T04:25:25.357Z] ====== philosophers (scala) [default], iteration 28 completed (1602.470 ms) ====== [2026-04-02T04:25:25.357Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-04-02T04:25:25.357Z] GC before operation: completed in 10.529 ms, heap usage 11.133 MB -> 4.894 MB. [2026-04-02T04:25:26.543Z] Camera thread performed 122 scans. [2026-04-02T04:25:26.543Z] ====== philosophers (scala) [default], iteration 29 completed (1510.116 ms) ====== [2026-04-02T04:25:26.880Z] ----------------------------------- [2026-04-02T04:25:26.880Z] renaissance-philosophers_0_PASSED [2026-04-02T04:25:26.880Z] ----------------------------------- [2026-04-02T04:25:26.880Z] [2026-04-02T04:25:26.880Z] TEST TEARDOWN: [2026-04-02T04:25:26.880Z] Nothing to be done for teardown. [2026-04-02T04:25:26.880Z] renaissance-philosophers_0 Finish Time: Thu Apr 2 04:25:26 2026 Epoch Time (ms): 1775103926636