[2025-12-11T01:31:42.439Z] Running test renaissance-philosophers_0 ... [2025-12-11T01:31:42.439Z] =============================================== [2025-12-11T01:31:42.439Z] renaissance-philosophers_0 Start Time: Thu Dec 11 01:31:42 2025 Epoch Time (ms): 1765416702352 [2025-12-11T01:31:42.777Z] variation: NoOptions [2025-12-11T01:31:42.777Z] JVM_OPTIONS: [2025-12-11T01:31:42.777Z] { \ [2025-12-11T01:31:42.777Z] echo ""; echo "TEST SETUP:"; \ [2025-12-11T01:31:42.777Z] echo "Nothing to be done for setup."; \ [2025-12-11T01:31:42.777Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\renaissance-philosophers_0"; \ [2025-12-11T01:31:42.777Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\renaissance-philosophers_0"; \ [2025-12-11T01:31:42.777Z] echo ""; echo "TESTING:"; \ [2025-12-11T01:31:42.777Z] "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_17654126173741\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-12-11T01:31:42.777Z] 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_17654126173741\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-11T01:31:42.777Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-11T01:31:42.777Z] echo "Nothing to be done for teardown."; \ [2025-12-11T01:31:42.777Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\TestTargetResult"; [2025-12-11T01:31:42.777Z] [2025-12-11T01:31:42.777Z] TEST SETUP: [2025-12-11T01:31:42.777Z] Nothing to be done for setup. [2025-12-11T01:31:42.777Z] [2025-12-11T01:31:42.777Z] TESTING: [2025-12-11T01:31:44.436Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-11T01:31:44.436Z] GC before operation: completed in 16.098 ms, heap usage 7.344 MB -> 4.444 MB. [2025-12-11T01:31:46.503Z] Camera thread performed 122 scans. [2025-12-11T01:31:46.503Z] ====== philosophers (scala) [default], iteration 0 completed (1694.429 ms) ====== [2025-12-11T01:31:46.503Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-11T01:31:46.503Z] GC before operation: completed in 22.326 ms, heap usage 33.679 MB -> 4.864 MB. [2025-12-11T01:31:47.357Z] Camera thread performed 122 scans. [2025-12-11T01:31:47.357Z] ====== philosophers (scala) [default], iteration 1 completed (919.129 ms) ====== [2025-12-11T01:31:47.357Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-11T01:31:47.357Z] GC before operation: completed in 34.963 ms, heap usage 20.476 MB -> 4.864 MB. [2025-12-11T01:31:48.117Z] Camera thread performed 122 scans. [2025-12-11T01:31:48.117Z] ====== philosophers (scala) [default], iteration 2 completed (873.230 ms) ====== [2025-12-11T01:31:48.117Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-11T01:31:48.117Z] GC before operation: completed in 21.364 ms, heap usage 30.401 MB -> 4.864 MB. [2025-12-11T01:31:48.912Z] Camera thread performed 122 scans. [2025-12-11T01:31:48.912Z] ====== philosophers (scala) [default], iteration 3 completed (840.185 ms) ====== [2025-12-11T01:31:48.912Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-11T01:31:48.912Z] GC before operation: completed in 22.654 ms, heap usage 6.551 MB -> 4.868 MB. [2025-12-11T01:31:50.094Z] Camera thread performed 122 scans. [2025-12-11T01:31:50.094Z] ====== philosophers (scala) [default], iteration 4 completed (901.389 ms) ====== [2025-12-11T01:31:50.094Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-11T01:31:50.094Z] GC before operation: completed in 19.874 ms, heap usage 40.453 MB -> 4.868 MB. [2025-12-11T01:31:50.799Z] Camera thread performed 122 scans. [2025-12-11T01:31:50.799Z] ====== philosophers (scala) [default], iteration 5 completed (874.745 ms) ====== [2025-12-11T01:31:50.799Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-11T01:31:50.799Z] GC before operation: completed in 21.437 ms, heap usage 5.760 MB -> 4.868 MB. [2025-12-11T01:31:51.517Z] Camera thread performed 122 scans. [2025-12-11T01:31:51.517Z] ====== philosophers (scala) [default], iteration 6 completed (852.218 ms) ====== [2025-12-11T01:31:51.517Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-11T01:31:51.518Z] GC before operation: completed in 47.047 ms, heap usage 45.162 MB -> 4.869 MB. [2025-12-11T01:31:52.664Z] Camera thread performed 122 scans. [2025-12-11T01:31:52.664Z] ====== philosophers (scala) [default], iteration 7 completed (918.509 ms) ====== [2025-12-11T01:31:52.664Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-11T01:31:52.664Z] GC before operation: completed in 22.474 ms, heap usage 39.992 MB -> 4.870 MB. [2025-12-11T01:31:53.801Z] Camera thread performed 122 scans. [2025-12-11T01:31:53.801Z] ====== philosophers (scala) [default], iteration 8 completed (947.666 ms) ====== [2025-12-11T01:31:53.801Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-11T01:31:53.801Z] GC before operation: completed in 22.272 ms, heap usage 5.506 MB -> 4.870 MB. [2025-12-11T01:31:54.545Z] Camera thread performed 122 scans. [2025-12-11T01:31:54.545Z] ====== philosophers (scala) [default], iteration 9 completed (831.342 ms) ====== [2025-12-11T01:31:54.545Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-11T01:31:54.545Z] GC before operation: completed in 23.145 ms, heap usage 27.960 MB -> 4.871 MB. [2025-12-11T01:31:55.329Z] Camera thread performed 122 scans. [2025-12-11T01:31:55.329Z] ====== philosophers (scala) [default], iteration 10 completed (870.517 ms) ====== [2025-12-11T01:31:55.329Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-11T01:31:55.329Z] GC before operation: completed in 22.056 ms, heap usage 19.495 MB -> 4.872 MB. [2025-12-11T01:31:56.550Z] Camera thread performed 122 scans. [2025-12-11T01:31:56.550Z] ====== philosophers (scala) [default], iteration 11 completed (907.562 ms) ====== [2025-12-11T01:31:56.550Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-11T01:31:56.550Z] GC before operation: completed in 22.113 ms, heap usage 31.839 MB -> 4.872 MB. [2025-12-11T01:31:57.308Z] Camera thread performed 122 scans. [2025-12-11T01:31:57.308Z] ====== philosophers (scala) [default], iteration 12 completed (947.372 ms) ====== [2025-12-11T01:31:57.308Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-11T01:31:57.308Z] GC before operation: completed in 20.932 ms, heap usage 12.508 MB -> 4.873 MB. [2025-12-11T01:31:58.461Z] Camera thread performed 122 scans. [2025-12-11T01:31:58.461Z] ====== philosophers (scala) [default], iteration 13 completed (1017.737 ms) ====== [2025-12-11T01:31:58.461Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-11T01:31:58.461Z] GC before operation: completed in 19.664 ms, heap usage 47.874 MB -> 4.873 MB. [2025-12-11T01:31:59.158Z] Camera thread performed 122 scans. [2025-12-11T01:31:59.158Z] ====== philosophers (scala) [default], iteration 14 completed (920.773 ms) ====== [2025-12-11T01:31:59.158Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-11T01:31:59.158Z] GC before operation: completed in 22.316 ms, heap usage 24.994 MB -> 4.874 MB. [2025-12-11T01:32:00.282Z] Camera thread performed 122 scans. [2025-12-11T01:32:00.282Z] ====== philosophers (scala) [default], iteration 15 completed (940.034 ms) ====== [2025-12-11T01:32:00.282Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-11T01:32:00.282Z] GC before operation: completed in 21.682 ms, heap usage 48.507 MB -> 4.874 MB. [2025-12-11T01:32:00.981Z] Camera thread performed 122 scans. [2025-12-11T01:32:00.981Z] ====== philosophers (scala) [default], iteration 16 completed (909.052 ms) ====== [2025-12-11T01:32:00.981Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-11T01:32:00.981Z] GC before operation: completed in 20.974 ms, heap usage 26.001 MB -> 4.874 MB. [2025-12-11T01:32:02.108Z] Camera thread performed 122 scans. [2025-12-11T01:32:02.108Z] ====== philosophers (scala) [default], iteration 17 completed (937.276 ms) ====== [2025-12-11T01:32:02.108Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-11T01:32:02.108Z] GC before operation: completed in 24.902 ms, heap usage 36.002 MB -> 4.874 MB. [2025-12-11T01:32:03.251Z] Camera thread performed 122 scans. [2025-12-11T01:32:03.251Z] ====== philosophers (scala) [default], iteration 18 completed (941.401 ms) ====== [2025-12-11T01:32:03.251Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-11T01:32:03.251Z] GC before operation: completed in 20.716 ms, heap usage 32.993 MB -> 4.874 MB. [2025-12-11T01:32:03.952Z] Camera thread performed 122 scans. [2025-12-11T01:32:03.952Z] ====== philosophers (scala) [default], iteration 19 completed (918.729 ms) ====== [2025-12-11T01:32:03.952Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-11T01:32:03.952Z] GC before operation: completed in 21.890 ms, heap usage 49.505 MB -> 4.874 MB. [2025-12-11T01:32:05.075Z] Camera thread performed 122 scans. [2025-12-11T01:32:05.075Z] ====== philosophers (scala) [default], iteration 20 completed (953.930 ms) ====== [2025-12-11T01:32:05.075Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-11T01:32:05.075Z] GC before operation: completed in 24.161 ms, heap usage 17.503 MB -> 4.874 MB. [2025-12-11T01:32:05.837Z] Camera thread performed 122 scans. [2025-12-11T01:32:05.838Z] ====== philosophers (scala) [default], iteration 21 completed (904.220 ms) ====== [2025-12-11T01:32:05.838Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-11T01:32:05.838Z] GC before operation: completed in 21.231 ms, heap usage 52.996 MB -> 4.875 MB. [2025-12-11T01:32:06.945Z] Camera thread performed 122 scans. [2025-12-11T01:32:06.946Z] ====== philosophers (scala) [default], iteration 22 completed (887.825 ms) ====== [2025-12-11T01:32:06.946Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-11T01:32:06.946Z] GC before operation: completed in 19.461 ms, heap usage 15.973 MB -> 4.875 MB. [2025-12-11T01:32:07.645Z] Camera thread performed 122 scans. [2025-12-11T01:32:07.645Z] ====== philosophers (scala) [default], iteration 23 completed (933.464 ms) ====== [2025-12-11T01:32:07.645Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-11T01:32:07.645Z] GC before operation: completed in 21.854 ms, heap usage 5.397 MB -> 4.875 MB. [2025-12-11T01:32:08.771Z] Camera thread performed 122 scans. [2025-12-11T01:32:08.771Z] ====== philosophers (scala) [default], iteration 24 completed (858.091 ms) ====== [2025-12-11T01:32:08.771Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-11T01:32:08.771Z] GC before operation: completed in 22.448 ms, heap usage 15.506 MB -> 4.875 MB. [2025-12-11T01:32:09.473Z] Camera thread performed 122 scans. [2025-12-11T01:32:09.473Z] ====== philosophers (scala) [default], iteration 25 completed (911.107 ms) ====== [2025-12-11T01:32:09.473Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-11T01:32:09.473Z] GC before operation: completed in 21.954 ms, heap usage 29.791 MB -> 4.876 MB. [2025-12-11T01:32:10.598Z] Camera thread performed 122 scans. [2025-12-11T01:32:10.598Z] ====== philosophers (scala) [default], iteration 26 completed (857.767 ms) ====== [2025-12-11T01:32:10.598Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-11T01:32:10.598Z] GC before operation: completed in 21.926 ms, heap usage 49.499 MB -> 4.876 MB. [2025-12-11T01:32:11.297Z] Camera thread performed 122 scans. [2025-12-11T01:32:11.297Z] ====== philosophers (scala) [default], iteration 27 completed (946.195 ms) ====== [2025-12-11T01:32:11.297Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-11T01:32:11.297Z] GC before operation: completed in 24.965 ms, heap usage 54.999 MB -> 4.876 MB. [2025-12-11T01:32:12.522Z] Camera thread performed 122 scans. [2025-12-11T01:32:12.522Z] ====== philosophers (scala) [default], iteration 28 completed (863.225 ms) ====== [2025-12-11T01:32:12.522Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-11T01:32:12.522Z] GC before operation: completed in 23.573 ms, heap usage 39.829 MB -> 4.876 MB. [2025-12-11T01:32:13.221Z] Camera thread performed 122 scans. [2025-12-11T01:32:13.221Z] ====== philosophers (scala) [default], iteration 29 completed (914.178 ms) ====== [2025-12-11T01:32:13.630Z] ----------------------------------- [2025-12-11T01:32:13.630Z] renaissance-philosophers_0_PASSED [2025-12-11T01:32:13.630Z] ----------------------------------- [2025-12-11T01:32:13.630Z] [2025-12-11T01:32:13.630Z] TEST TEARDOWN: [2025-12-11T01:32:13.630Z] Nothing to be done for teardown. [2025-12-11T01:32:13.630Z] renaissance-philosophers_0 Finish Time: Thu Dec 11 01:32:13 2025 Epoch Time (ms): 1765416733408