[2026-01-31T05:12:05.227Z] Running test renaissance-philosophers_0 ... [2026-01-31T05:12:05.551Z] =============================================== [2026-01-31T05:12:05.551Z] renaissance-philosophers_0 Start Time: Sat Jan 31 05:12:05 2026 Epoch Time (ms): 1769836325334 [2026-01-31T05:12:05.551Z] variation: NoOptions [2026-01-31T05:12:05.896Z] JVM_OPTIONS: [2026-01-31T05:12:05.896Z] { \ [2026-01-31T05:12:05.896Z] echo ""; echo "TEST SETUP:"; \ [2026-01-31T05:12:05.896Z] echo "Nothing to be done for setup."; \ [2026-01-31T05:12:05.896Z] mkdir -p "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698341717210\\renaissance-philosophers_0"; \ [2026-01-31T05:12:05.896Z] cd "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698341717210\\renaissance-philosophers_0"; \ [2026-01-31T05:12:05.896Z] echo ""; echo "TESTING:"; \ [2026-01-31T05:12:05.896Z] "c:/workspace/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:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698341717210\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2026-01-31T05:12:05.896Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698341717210\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-31T05:12:05.896Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-31T05:12:05.896Z] echo "Nothing to be done for teardown."; \ [2026-01-31T05:12:05.896Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17698341717210\\TestTargetResult"; [2026-01-31T05:12:05.896Z] [2026-01-31T05:12:05.896Z] TEST SETUP: [2026-01-31T05:12:05.896Z] Nothing to be done for setup. [2026-01-31T05:12:05.896Z] [2026-01-31T05:12:05.896Z] TESTING: [2026-01-31T05:12:07.105Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-01-31T05:12:07.105Z] GC before operation: completed in 14.695 ms, heap usage 16.407 MB -> 4.459 MB. [2026-01-31T05:12:08.328Z] Camera thread performed 122 scans. [2026-01-31T05:12:08.328Z] ====== philosophers (scala) [default], iteration 0 completed (1465.648 ms) ====== [2026-01-31T05:12:08.328Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-01-31T05:12:08.328Z] GC before operation: completed in 19.517 ms, heap usage 34.334 MB -> 4.871 MB. [2026-01-31T05:12:09.483Z] Camera thread performed 122 scans. [2026-01-31T05:12:09.483Z] ====== philosophers (scala) [default], iteration 1 completed (951.503 ms) ====== [2026-01-31T05:12:09.483Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-01-31T05:12:09.483Z] GC before operation: completed in 22.107 ms, heap usage 43.066 MB -> 4.874 MB. [2026-01-31T05:12:10.202Z] Camera thread performed 122 scans. [2026-01-31T05:12:10.202Z] ====== philosophers (scala) [default], iteration 2 completed (939.137 ms) ====== [2026-01-31T05:12:10.202Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-01-31T05:12:10.202Z] GC before operation: completed in 17.485 ms, heap usage 64.065 MB -> 4.875 MB. [2026-01-31T05:12:11.362Z] Camera thread performed 122 scans. [2026-01-31T05:12:11.362Z] ====== philosophers (scala) [default], iteration 3 completed (952.189 ms) ====== [2026-01-31T05:12:11.362Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-01-31T05:12:11.362Z] GC before operation: completed in 33.546 ms, heap usage 151.533 MB -> 4.877 MB. [2026-01-31T05:12:12.523Z] Camera thread performed 122 scans. [2026-01-31T05:12:12.523Z] ====== philosophers (scala) [default], iteration 4 completed (1005.559 ms) ====== [2026-01-31T05:12:12.523Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-01-31T05:12:12.523Z] GC before operation: completed in 24.973 ms, heap usage 102.717 MB -> 4.877 MB. [2026-01-31T05:12:13.245Z] Camera thread performed 122 scans. [2026-01-31T05:12:13.245Z] ====== philosophers (scala) [default], iteration 5 completed (966.515 ms) ====== [2026-01-31T05:12:13.245Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-01-31T05:12:13.245Z] GC before operation: completed in 19.114 ms, heap usage 65.118 MB -> 4.878 MB. [2026-01-31T05:12:14.402Z] Camera thread performed 122 scans. [2026-01-31T05:12:14.402Z] ====== philosophers (scala) [default], iteration 6 completed (1012.773 ms) ====== [2026-01-31T05:12:14.402Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-01-31T05:12:14.402Z] GC before operation: completed in 30.209 ms, heap usage 122.116 MB -> 4.878 MB. [2026-01-31T05:12:15.559Z] Camera thread performed 122 scans. [2026-01-31T05:12:15.559Z] ====== philosophers (scala) [default], iteration 7 completed (988.715 ms) ====== [2026-01-31T05:12:15.559Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-01-31T05:12:15.559Z] GC before operation: completed in 19.027 ms, heap usage 57.596 MB -> 4.878 MB. [2026-01-31T05:12:16.280Z] Camera thread performed 122 scans. [2026-01-31T05:12:16.280Z] ====== philosophers (scala) [default], iteration 8 completed (954.233 ms) ====== [2026-01-31T05:12:16.280Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-01-31T05:12:16.280Z] GC before operation: completed in 17.963 ms, heap usage 52.498 MB -> 4.878 MB. [2026-01-31T05:12:17.437Z] Camera thread performed 122 scans. [2026-01-31T05:12:17.437Z] ====== philosophers (scala) [default], iteration 9 completed (902.954 ms) ====== [2026-01-31T05:12:17.437Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-01-31T05:12:17.437Z] GC before operation: completed in 25.287 ms, heap usage 66.081 MB -> 4.878 MB. [2026-01-31T05:12:18.160Z] Camera thread performed 122 scans. [2026-01-31T05:12:18.160Z] ====== philosophers (scala) [default], iteration 10 completed (926.339 ms) ====== [2026-01-31T05:12:18.160Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-01-31T05:12:18.160Z] GC before operation: completed in 19.212 ms, heap usage 76.031 MB -> 4.879 MB. [2026-01-31T05:12:19.315Z] Camera thread performed 122 scans. [2026-01-31T05:12:19.315Z] ====== philosophers (scala) [default], iteration 11 completed (923.584 ms) ====== [2026-01-31T05:12:19.315Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-01-31T05:12:19.315Z] GC before operation: completed in 16.223 ms, heap usage 58.474 MB -> 4.879 MB. [2026-01-31T05:12:20.035Z] Camera thread performed 122 scans. [2026-01-31T05:12:20.035Z] ====== philosophers (scala) [default], iteration 12 completed (978.582 ms) ====== [2026-01-31T05:12:20.035Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-01-31T05:12:20.035Z] GC before operation: completed in 22.440 ms, heap usage 139.091 MB -> 4.879 MB. [2026-01-31T05:12:21.191Z] Camera thread performed 122 scans. [2026-01-31T05:12:21.191Z] ====== philosophers (scala) [default], iteration 13 completed (962.149 ms) ====== [2026-01-31T05:12:21.191Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-01-31T05:12:21.191Z] GC before operation: completed in 17.285 ms, heap usage 60.520 MB -> 4.879 MB. [2026-01-31T05:12:22.349Z] Camera thread performed 122 scans. [2026-01-31T05:12:22.349Z] ====== philosophers (scala) [default], iteration 14 completed (1019.504 ms) ====== [2026-01-31T05:12:22.349Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-01-31T05:12:22.349Z] GC before operation: completed in 24.633 ms, heap usage 143.589 MB -> 4.879 MB. [2026-01-31T05:12:23.073Z] Camera thread performed 122 scans. [2026-01-31T05:12:23.073Z] ====== philosophers (scala) [default], iteration 15 completed (1009.051 ms) ====== [2026-01-31T05:12:23.073Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-01-31T05:12:23.073Z] GC before operation: completed in 19.404 ms, heap usage 14.084 MB -> 4.879 MB. [2026-01-31T05:12:24.237Z] Camera thread performed 122 scans. [2026-01-31T05:12:24.237Z] ====== philosophers (scala) [default], iteration 16 completed (902.628 ms) ====== [2026-01-31T05:12:24.237Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-01-31T05:12:24.237Z] GC before operation: completed in 19.754 ms, heap usage 74.589 MB -> 4.880 MB. [2026-01-31T05:12:25.390Z] Camera thread performed 122 scans. [2026-01-31T05:12:25.390Z] ====== philosophers (scala) [default], iteration 17 completed (1024.391 ms) ====== [2026-01-31T05:12:25.390Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-01-31T05:12:25.390Z] GC before operation: completed in 27.098 ms, heap usage 34.593 MB -> 4.880 MB. [2026-01-31T05:12:26.113Z] Camera thread performed 122 scans. [2026-01-31T05:12:26.113Z] ====== philosophers (scala) [default], iteration 18 completed (934.585 ms) ====== [2026-01-31T05:12:26.113Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-01-31T05:12:26.113Z] GC before operation: completed in 15.945 ms, heap usage 82.070 MB -> 4.880 MB. [2026-01-31T05:12:27.272Z] Camera thread performed 122 scans. [2026-01-31T05:12:27.272Z] ====== philosophers (scala) [default], iteration 19 completed (947.020 ms) ====== [2026-01-31T05:12:27.272Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-01-31T05:12:27.272Z] GC before operation: completed in 29.098 ms, heap usage 122.593 MB -> 4.880 MB. [2026-01-31T05:12:27.994Z] Camera thread performed 122 scans. [2026-01-31T05:12:27.994Z] ====== philosophers (scala) [default], iteration 20 completed (1010.776 ms) ====== [2026-01-31T05:12:27.994Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-01-31T05:12:27.994Z] GC before operation: completed in 20.068 ms, heap usage 50.590 MB -> 4.880 MB. [2026-01-31T05:12:29.148Z] Camera thread performed 122 scans. [2026-01-31T05:12:29.148Z] ====== philosophers (scala) [default], iteration 21 completed (964.859 ms) ====== [2026-01-31T05:12:29.148Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-01-31T05:12:29.148Z] GC before operation: completed in 18.219 ms, heap usage 20.098 MB -> 4.880 MB. [2026-01-31T05:12:30.326Z] Camera thread performed 122 scans. [2026-01-31T05:12:30.327Z] ====== philosophers (scala) [default], iteration 22 completed (968.165 ms) ====== [2026-01-31T05:12:30.327Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-01-31T05:12:30.327Z] GC before operation: completed in 21.184 ms, heap usage 112.088 MB -> 4.880 MB. [2026-01-31T05:12:31.056Z] Camera thread performed 122 scans. [2026-01-31T05:12:31.056Z] ====== philosophers (scala) [default], iteration 23 completed (1020.781 ms) ====== [2026-01-31T05:12:31.056Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-01-31T05:12:31.056Z] GC before operation: completed in 17.656 ms, heap usage 44.469 MB -> 4.880 MB. [2026-01-31T05:12:32.212Z] Camera thread performed 122 scans. [2026-01-31T05:12:32.212Z] ====== philosophers (scala) [default], iteration 24 completed (981.341 ms) ====== [2026-01-31T05:12:32.212Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-01-31T05:12:32.212Z] GC before operation: completed in 25.406 ms, heap usage 46.593 MB -> 4.880 MB. [2026-01-31T05:12:33.374Z] Camera thread performed 122 scans. [2026-01-31T05:12:33.374Z] ====== philosophers (scala) [default], iteration 25 completed (1002.363 ms) ====== [2026-01-31T05:12:33.374Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-01-31T05:12:33.374Z] GC before operation: completed in 22.746 ms, heap usage 55.592 MB -> 4.880 MB. [2026-01-31T05:12:34.094Z] Camera thread performed 122 scans. [2026-01-31T05:12:34.094Z] ====== philosophers (scala) [default], iteration 26 completed (981.884 ms) ====== [2026-01-31T05:12:34.094Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-01-31T05:12:34.094Z] GC before operation: completed in 16.753 ms, heap usage 74.515 MB -> 4.880 MB. [2026-01-31T05:12:35.257Z] Camera thread performed 122 scans. [2026-01-31T05:12:35.257Z] ====== philosophers (scala) [default], iteration 27 completed (961.355 ms) ====== [2026-01-31T05:12:35.257Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-01-31T05:12:35.257Z] GC before operation: completed in 32.689 ms, heap usage 100.595 MB -> 4.880 MB. [2026-01-31T05:12:36.010Z] Camera thread performed 122 scans. [2026-01-31T05:12:36.010Z] ====== philosophers (scala) [default], iteration 28 completed (976.035 ms) ====== [2026-01-31T05:12:36.010Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-01-31T05:12:36.010Z] GC before operation: completed in 22.304 ms, heap usage 88.060 MB -> 4.880 MB. [2026-01-31T05:12:37.146Z] Camera thread performed 122 scans. [2026-01-31T05:12:37.146Z] ====== philosophers (scala) [default], iteration 29 completed (954.929 ms) ====== [2026-01-31T05:12:37.146Z] ----------------------------------- [2026-01-31T05:12:37.146Z] renaissance-philosophers_0_PASSED [2026-01-31T05:12:37.146Z] ----------------------------------- [2026-01-31T05:12:37.146Z] [2026-01-31T05:12:37.146Z] TEST TEARDOWN: [2026-01-31T05:12:37.146Z] Nothing to be done for teardown. [2026-01-31T05:12:37.493Z] renaissance-philosophers_0 Finish Time: Sat Jan 31 05:12:37 2026 Epoch Time (ms): 1769836357184