[2025-11-20T00:20:48.159Z] Running test renaissance-philosophers_0 ... [2025-11-20T00:20:48.159Z] =============================================== [2025-11-20T00:20:48.493Z] renaissance-philosophers_0 Start Time: Thu Nov 20 00:20:48 2025 Epoch Time (ms): 1763598048106 [2025-11-20T00:20:48.493Z] variation: NoOptions [2025-11-20T00:20:48.493Z] JVM_OPTIONS: [2025-11-20T00:20:48.493Z] { \ [2025-11-20T00:20:48.493Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T00:20:48.493Z] echo "Nothing to be done for setup."; \ [2025-11-20T00:20:48.493Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17635935998036\\renaissance-philosophers_0"; \ [2025-11-20T00:20:48.493Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17635935998036\\renaissance-philosophers_0"; \ [2025-11-20T00:20:48.493Z] echo ""; echo "TESTING:"; \ [2025-11-20T00:20:48.493Z] "c:/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17635935998036\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-11-20T00:20:48.493Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17635935998036\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T00:20:48.493Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T00:20:48.493Z] echo "Nothing to be done for teardown."; \ [2025-11-20T00:20:48.493Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17635935998036\\TestTargetResult"; [2025-11-20T00:20:48.493Z] [2025-11-20T00:20:48.493Z] TEST SETUP: [2025-11-20T00:20:48.493Z] Nothing to be done for setup. [2025-11-20T00:20:48.493Z] [2025-11-20T00:20:48.493Z] TESTING: [2025-11-20T00:20:50.137Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-20T00:20:50.137Z] GC before operation: completed in 18.955 ms, heap usage 7.794 MB -> 4.568 MB. [2025-11-20T00:20:53.051Z] Camera thread performed 122 scans. [2025-11-20T00:20:53.051Z] ====== philosophers (scala) [default], iteration 0 completed (2887.416 ms) ====== [2025-11-20T00:20:53.051Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-20T00:20:53.051Z] GC before operation: completed in 14.111 ms, heap usage 40.626 MB -> 4.947 MB. [2025-11-20T00:20:54.259Z] Camera thread performed 122 scans. [2025-11-20T00:20:54.259Z] ====== philosophers (scala) [default], iteration 1 completed (1136.196 ms) ====== [2025-11-20T00:20:54.259Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-20T00:20:54.259Z] GC before operation: completed in 13.874 ms, heap usage 45.743 MB -> 4.948 MB. [2025-11-20T00:20:54.944Z] Camera thread performed 122 scans. [2025-11-20T00:20:54.944Z] ====== philosophers (scala) [default], iteration 2 completed (944.214 ms) ====== [2025-11-20T00:20:54.944Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-20T00:20:54.944Z] GC before operation: completed in 13.218 ms, heap usage 29.182 MB -> 4.949 MB. [2025-11-20T00:20:56.046Z] Camera thread performed 122 scans. [2025-11-20T00:20:56.046Z] ====== philosophers (scala) [default], iteration 3 completed (920.935 ms) ====== [2025-11-20T00:20:56.046Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-20T00:20:56.046Z] GC before operation: completed in 12.867 ms, heap usage 8.083 MB -> 4.949 MB. [2025-11-20T00:20:57.199Z] Camera thread performed 122 scans. [2025-11-20T00:20:57.199Z] ====== philosophers (scala) [default], iteration 4 completed (1013.575 ms) ====== [2025-11-20T00:20:57.199Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-20T00:20:57.199Z] GC before operation: completed in 20.299 ms, heap usage 46.521 MB -> 4.950 MB. [2025-11-20T00:20:57.889Z] Camera thread performed 122 scans. [2025-11-20T00:20:57.889Z] ====== philosophers (scala) [default], iteration 5 completed (1034.063 ms) ====== [2025-11-20T00:20:57.889Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-20T00:20:58.334Z] GC before operation: completed in 14.871 ms, heap usage 37.955 MB -> 4.951 MB. [2025-11-20T00:20:59.016Z] Camera thread performed 122 scans. [2025-11-20T00:20:59.016Z] ====== philosophers (scala) [default], iteration 6 completed (1063.012 ms) ====== [2025-11-20T00:20:59.016Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-20T00:20:59.016Z] GC before operation: completed in 13.094 ms, heap usage 26.992 MB -> 4.951 MB. [2025-11-20T00:21:00.118Z] Camera thread performed 122 scans. [2025-11-20T00:21:00.118Z] ====== philosophers (scala) [default], iteration 7 completed (906.841 ms) ====== [2025-11-20T00:21:00.118Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-20T00:21:00.118Z] GC before operation: completed in 12.599 ms, heap usage 15.855 MB -> 4.951 MB. [2025-11-20T00:21:01.218Z] Camera thread performed 122 scans. [2025-11-20T00:21:01.218Z] ====== philosophers (scala) [default], iteration 8 completed (910.517 ms) ====== [2025-11-20T00:21:01.218Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-20T00:21:01.218Z] GC before operation: completed in 13.080 ms, heap usage 25.901 MB -> 4.951 MB. [2025-11-20T00:21:01.900Z] Camera thread performed 122 scans. [2025-11-20T00:21:01.900Z] ====== philosophers (scala) [default], iteration 9 completed (949.014 ms) ====== [2025-11-20T00:21:01.900Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-20T00:21:01.900Z] GC before operation: completed in 13.466 ms, heap usage 47.931 MB -> 4.951 MB. [2025-11-20T00:21:03.007Z] Camera thread performed 122 scans. [2025-11-20T00:21:03.007Z] ====== philosophers (scala) [default], iteration 10 completed (928.533 ms) ====== [2025-11-20T00:21:03.007Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-20T00:21:03.007Z] GC before operation: completed in 13.285 ms, heap usage 29.474 MB -> 4.955 MB. [2025-11-20T00:21:03.731Z] Camera thread performed 122 scans. [2025-11-20T00:21:03.731Z] ====== philosophers (scala) [default], iteration 11 completed (928.309 ms) ====== [2025-11-20T00:21:03.731Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-20T00:21:03.731Z] GC before operation: completed in 13.568 ms, heap usage 34.483 MB -> 4.957 MB. [2025-11-20T00:21:04.837Z] Camera thread performed 122 scans. [2025-11-20T00:21:04.837Z] ====== philosophers (scala) [default], iteration 12 completed (986.170 ms) ====== [2025-11-20T00:21:04.837Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-20T00:21:04.837Z] GC before operation: completed in 12.610 ms, heap usage 32.924 MB -> 4.957 MB. [2025-11-20T00:21:05.945Z] Camera thread performed 122 scans. [2025-11-20T00:21:05.945Z] ====== philosophers (scala) [default], iteration 13 completed (952.994 ms) ====== [2025-11-20T00:21:05.945Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-20T00:21:05.945Z] GC before operation: completed in 12.691 ms, heap usage 47.917 MB -> 4.957 MB. [2025-11-20T00:21:06.633Z] Camera thread performed 122 scans. [2025-11-20T00:21:06.633Z] ====== philosophers (scala) [default], iteration 14 completed (993.292 ms) ====== [2025-11-20T00:21:06.633Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-20T00:21:06.957Z] GC before operation: completed in 14.770 ms, heap usage 31.501 MB -> 4.957 MB. [2025-11-20T00:21:07.642Z] Camera thread performed 122 scans. [2025-11-20T00:21:07.642Z] ====== philosophers (scala) [default], iteration 15 completed (991.540 ms) ====== [2025-11-20T00:21:07.642Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-20T00:21:07.968Z] GC before operation: completed in 13.417 ms, heap usage 45.509 MB -> 4.957 MB. [2025-11-20T00:21:08.650Z] Camera thread performed 122 scans. [2025-11-20T00:21:08.650Z] ====== philosophers (scala) [default], iteration 16 completed (954.384 ms) ====== [2025-11-20T00:21:08.650Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-20T00:21:08.650Z] GC before operation: completed in 12.583 ms, heap usage 13.925 MB -> 4.957 MB. [2025-11-20T00:21:09.862Z] Camera thread performed 122 scans. [2025-11-20T00:21:09.862Z] ====== philosophers (scala) [default], iteration 17 completed (890.553 ms) ====== [2025-11-20T00:21:09.862Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-20T00:21:09.862Z] GC before operation: completed in 13.291 ms, heap usage 26.966 MB -> 4.957 MB. [2025-11-20T00:21:10.961Z] Camera thread performed 122 scans. [2025-11-20T00:21:10.961Z] ====== philosophers (scala) [default], iteration 18 completed (1094.990 ms) ====== [2025-11-20T00:21:10.961Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-20T00:21:10.961Z] GC before operation: completed in 13.071 ms, heap usage 39.890 MB -> 4.958 MB. [2025-11-20T00:21:11.643Z] Camera thread performed 122 scans. [2025-11-20T00:21:11.643Z] ====== philosophers (scala) [default], iteration 19 completed (935.955 ms) ====== [2025-11-20T00:21:11.643Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-20T00:21:11.643Z] GC before operation: completed in 12.723 ms, heap usage 15.048 MB -> 4.958 MB. [2025-11-20T00:21:12.747Z] Camera thread performed 122 scans. [2025-11-20T00:21:12.747Z] ====== philosophers (scala) [default], iteration 20 completed (965.672 ms) ====== [2025-11-20T00:21:12.747Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-20T00:21:12.747Z] GC before operation: completed in 12.280 ms, heap usage 14.482 MB -> 4.958 MB. [2025-11-20T00:21:13.845Z] Camera thread performed 122 scans. [2025-11-20T00:21:13.845Z] ====== philosophers (scala) [default], iteration 21 completed (942.461 ms) ====== [2025-11-20T00:21:13.845Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-20T00:21:13.845Z] GC before operation: completed in 12.864 ms, heap usage 9.986 MB -> 4.958 MB. [2025-11-20T00:21:14.531Z] Camera thread performed 122 scans. [2025-11-20T00:21:14.531Z] ====== philosophers (scala) [default], iteration 22 completed (1010.717 ms) ====== [2025-11-20T00:21:14.531Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-20T00:21:14.901Z] GC before operation: completed in 12.990 ms, heap usage 18.546 MB -> 4.958 MB. [2025-11-20T00:21:15.585Z] Camera thread performed 122 scans. [2025-11-20T00:21:15.585Z] ====== philosophers (scala) [default], iteration 23 completed (969.502 ms) ====== [2025-11-20T00:21:15.585Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-20T00:21:15.585Z] GC before operation: completed in 12.966 ms, heap usage 44.902 MB -> 4.958 MB. [2025-11-20T00:21:16.686Z] Camera thread performed 122 scans. [2025-11-20T00:21:16.686Z] ====== philosophers (scala) [default], iteration 24 completed (1002.026 ms) ====== [2025-11-20T00:21:16.686Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-20T00:21:16.686Z] GC before operation: completed in 12.879 ms, heap usage 31.582 MB -> 4.958 MB. [2025-11-20T00:21:17.789Z] Camera thread performed 122 scans. [2025-11-20T00:21:17.789Z] ====== philosophers (scala) [default], iteration 25 completed (990.689 ms) ====== [2025-11-20T00:21:17.789Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-20T00:21:17.789Z] GC before operation: completed in 16.912 ms, heap usage 12.074 MB -> 4.958 MB. [2025-11-20T00:21:18.889Z] Camera thread performed 122 scans. [2025-11-20T00:21:18.889Z] ====== philosophers (scala) [default], iteration 26 completed (978.044 ms) ====== [2025-11-20T00:21:18.890Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-20T00:21:18.890Z] GC before operation: completed in 13.197 ms, heap usage 44.577 MB -> 4.959 MB. [2025-11-20T00:21:19.569Z] Camera thread performed 122 scans. [2025-11-20T00:21:19.569Z] ====== philosophers (scala) [default], iteration 27 completed (967.214 ms) ====== [2025-11-20T00:21:19.569Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-20T00:21:19.569Z] GC before operation: completed in 12.583 ms, heap usage 8.551 MB -> 4.958 MB. [2025-11-20T00:21:20.654Z] Camera thread performed 122 scans. [2025-11-20T00:21:20.654Z] ====== philosophers (scala) [default], iteration 28 completed (1001.153 ms) ====== [2025-11-20T00:21:20.654Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-20T00:21:20.654Z] GC before operation: completed in 13.728 ms, heap usage 28.500 MB -> 4.959 MB. [2025-11-20T00:21:21.755Z] Camera thread performed 122 scans. [2025-11-20T00:21:21.755Z] ====== philosophers (scala) [default], iteration 29 completed (954.993 ms) ====== [2025-11-20T00:21:21.755Z] ----------------------------------- [2025-11-20T00:21:21.755Z] renaissance-philosophers_0_PASSED [2025-11-20T00:21:21.755Z] ----------------------------------- [2025-11-20T00:21:21.755Z] [2025-11-20T00:21:21.755Z] TEST TEARDOWN: [2025-11-20T00:21:21.755Z] Nothing to be done for teardown. [2025-11-20T00:21:22.062Z] renaissance-philosophers_0 Finish Time: Thu Nov 20 00:21:21 2025 Epoch Time (ms): 1763598081832