[2026-01-21T12:06:53.151Z] Running test renaissance-philosophers_0 ... [2026-01-21T12:06:53.151Z] =============================================== [2026-01-21T12:06:53.464Z] renaissance-philosophers_0 Start Time: Wed Jan 21 12:06:53 2026 Epoch Time (ms): 1768997213226 [2026-01-21T12:06:53.464Z] variation: NoOptions [2026-01-21T12:06:53.828Z] JVM_OPTIONS: [2026-01-21T12:06:53.828Z] { \ [2026-01-21T12:06:53.828Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T12:06:53.828Z] echo "Nothing to be done for setup."; \ [2026-01-21T12:06:53.828Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689933831533\\renaissance-philosophers_0"; \ [2026-01-21T12:06:53.828Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689933831533\\renaissance-philosophers_0"; \ [2026-01-21T12:06:53.828Z] echo ""; echo "TESTING:"; \ [2026-01-21T12:06:53.828Z] "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_17689933831533\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2026-01-21T12:06:53.828Z] 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_17689933831533\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T12:06:53.828Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T12:06:53.828Z] echo "Nothing to be done for teardown."; \ [2026-01-21T12:06:53.828Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17689933831533\\TestTargetResult"; [2026-01-21T12:06:53.828Z] [2026-01-21T12:06:53.828Z] TEST SETUP: [2026-01-21T12:06:53.828Z] Nothing to be done for setup. [2026-01-21T12:06:53.828Z] [2026-01-21T12:06:53.828Z] TESTING: [2026-01-21T12:06:55.511Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-01-21T12:06:55.511Z] GC before operation: completed in 30.636 ms, heap usage 7.217 MB -> 4.444 MB. [2026-01-21T12:06:57.509Z] Camera thread performed 122 scans. [2026-01-21T12:06:57.509Z] ====== philosophers (scala) [default], iteration 0 completed (1663.218 ms) ====== [2026-01-21T12:06:57.509Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-01-21T12:06:57.509Z] GC before operation: completed in 26.035 ms, heap usage 16.727 MB -> 4.864 MB. [2026-01-21T12:06:58.456Z] Camera thread performed 122 scans. [2026-01-21T12:06:58.456Z] ====== philosophers (scala) [default], iteration 1 completed (1010.829 ms) ====== [2026-01-21T12:06:58.456Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-01-21T12:06:58.456Z] GC before operation: completed in 26.242 ms, heap usage 34.387 MB -> 4.864 MB. [2026-01-21T12:06:59.429Z] Camera thread performed 122 scans. [2026-01-21T12:06:59.429Z] ====== philosophers (scala) [default], iteration 2 completed (907.986 ms) ====== [2026-01-21T12:06:59.429Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-01-21T12:06:59.429Z] GC before operation: completed in 22.580 ms, heap usage 30.605 MB -> 4.865 MB. [2026-01-21T12:07:00.259Z] Camera thread performed 122 scans. [2026-01-21T12:07:00.259Z] ====== philosophers (scala) [default], iteration 3 completed (859.569 ms) ====== [2026-01-21T12:07:00.259Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-01-21T12:07:00.259Z] GC before operation: completed in 23.016 ms, heap usage 41.411 MB -> 4.869 MB. [2026-01-21T12:07:00.985Z] Camera thread performed 122 scans. [2026-01-21T12:07:00.985Z] ====== philosophers (scala) [default], iteration 4 completed (767.541 ms) ====== [2026-01-21T12:07:00.985Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-01-21T12:07:00.985Z] GC before operation: completed in 19.746 ms, heap usage 14.992 MB -> 4.869 MB. [2026-01-21T12:07:01.792Z] Camera thread performed 122 scans. [2026-01-21T12:07:01.792Z] ====== philosophers (scala) [default], iteration 5 completed (809.891 ms) ====== [2026-01-21T12:07:01.792Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-01-21T12:07:01.792Z] GC before operation: completed in 24.650 ms, heap usage 30.708 MB -> 4.869 MB. [2026-01-21T12:07:02.496Z] Camera thread performed 122 scans. [2026-01-21T12:07:02.496Z] ====== philosophers (scala) [default], iteration 6 completed (777.941 ms) ====== [2026-01-21T12:07:02.496Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-01-21T12:07:02.496Z] GC before operation: completed in 21.192 ms, heap usage 43.849 MB -> 4.871 MB. [2026-01-21T12:07:03.625Z] Camera thread performed 122 scans. [2026-01-21T12:07:03.625Z] ====== philosophers (scala) [default], iteration 7 completed (828.846 ms) ====== [2026-01-21T12:07:03.625Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-01-21T12:07:03.625Z] GC before operation: completed in 26.400 ms, heap usage 47.846 MB -> 4.871 MB. [2026-01-21T12:07:04.300Z] Camera thread performed 122 scans. [2026-01-21T12:07:04.300Z] ====== philosophers (scala) [default], iteration 8 completed (820.211 ms) ====== [2026-01-21T12:07:04.300Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-01-21T12:07:04.300Z] GC before operation: completed in 21.680 ms, heap usage 38.968 MB -> 4.871 MB. [2026-01-21T12:07:05.146Z] Camera thread performed 122 scans. [2026-01-21T12:07:05.146Z] ====== philosophers (scala) [default], iteration 9 completed (796.844 ms) ====== [2026-01-21T12:07:05.146Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-01-21T12:07:05.146Z] GC before operation: completed in 28.742 ms, heap usage 34.536 MB -> 4.872 MB. [2026-01-21T12:07:05.900Z] Camera thread performed 122 scans. [2026-01-21T12:07:05.900Z] ====== philosophers (scala) [default], iteration 10 completed (718.388 ms) ====== [2026-01-21T12:07:05.900Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-01-21T12:07:05.900Z] GC before operation: completed in 21.017 ms, heap usage 40.461 MB -> 4.872 MB. [2026-01-21T12:07:06.603Z] Camera thread performed 122 scans. [2026-01-21T12:07:06.603Z] ====== philosophers (scala) [default], iteration 11 completed (860.171 ms) ====== [2026-01-21T12:07:06.603Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-01-21T12:07:06.945Z] GC before operation: completed in 25.754 ms, heap usage 36.417 MB -> 4.873 MB. [2026-01-21T12:07:07.651Z] Camera thread performed 122 scans. [2026-01-21T12:07:07.651Z] ====== philosophers (scala) [default], iteration 12 completed (842.725 ms) ====== [2026-01-21T12:07:07.651Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-01-21T12:07:07.651Z] GC before operation: completed in 23.384 ms, heap usage 20.960 MB -> 4.873 MB. [2026-01-21T12:07:08.363Z] Camera thread performed 122 scans. [2026-01-21T12:07:08.363Z] ====== philosophers (scala) [default], iteration 13 completed (823.579 ms) ====== [2026-01-21T12:07:08.363Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-01-21T12:07:08.363Z] GC before operation: completed in 25.398 ms, heap usage 24.852 MB -> 4.874 MB. [2026-01-21T12:07:09.494Z] Camera thread performed 122 scans. [2026-01-21T12:07:09.494Z] ====== philosophers (scala) [default], iteration 14 completed (846.853 ms) ====== [2026-01-21T12:07:09.494Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-01-21T12:07:09.494Z] GC before operation: completed in 22.812 ms, heap usage 9.958 MB -> 4.874 MB. [2026-01-21T12:07:10.201Z] Camera thread performed 122 scans. [2026-01-21T12:07:10.201Z] ====== philosophers (scala) [default], iteration 15 completed (873.786 ms) ====== [2026-01-21T12:07:10.201Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-01-21T12:07:10.201Z] GC before operation: completed in 28.496 ms, heap usage 13.506 MB -> 4.874 MB. [2026-01-21T12:07:11.330Z] Camera thread performed 122 scans. [2026-01-21T12:07:11.330Z] ====== philosophers (scala) [default], iteration 16 completed (849.604 ms) ====== [2026-01-21T12:07:11.330Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-01-21T12:07:11.330Z] GC before operation: completed in 27.575 ms, heap usage 38.996 MB -> 4.875 MB. [2026-01-21T12:07:12.037Z] Camera thread performed 122 scans. [2026-01-21T12:07:12.037Z] ====== philosophers (scala) [default], iteration 17 completed (849.511 ms) ====== [2026-01-21T12:07:12.037Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-01-21T12:07:12.037Z] GC before operation: completed in 24.080 ms, heap usage 55.500 MB -> 4.874 MB. [2026-01-21T12:07:12.760Z] Camera thread performed 122 scans. [2026-01-21T12:07:12.760Z] ====== philosophers (scala) [default], iteration 18 completed (863.703 ms) ====== [2026-01-21T12:07:12.760Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-01-21T12:07:12.760Z] GC before operation: completed in 26.494 ms, heap usage 21.001 MB -> 4.875 MB. [2026-01-21T12:07:13.904Z] Camera thread performed 122 scans. [2026-01-21T12:07:13.904Z] ====== philosophers (scala) [default], iteration 19 completed (858.712 ms) ====== [2026-01-21T12:07:13.904Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-01-21T12:07:13.904Z] GC before operation: completed in 28.967 ms, heap usage 16.996 MB -> 4.874 MB. [2026-01-21T12:07:14.608Z] Camera thread performed 122 scans. [2026-01-21T12:07:14.608Z] ====== philosophers (scala) [default], iteration 20 completed (830.017 ms) ====== [2026-01-21T12:07:14.608Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-01-21T12:07:14.608Z] GC before operation: completed in 26.144 ms, heap usage 40.490 MB -> 4.875 MB. [2026-01-21T12:07:15.338Z] Camera thread performed 122 scans. [2026-01-21T12:07:15.338Z] ====== philosophers (scala) [default], iteration 21 completed (824.709 ms) ====== [2026-01-21T12:07:15.338Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-01-21T12:07:17.301Z] GC before operation: completed in 29.054 ms, heap usage 5.475 MB -> 4.876 MB. [2026-01-21T12:07:17.301Z] Camera thread performed 122 scans. [2026-01-21T12:07:17.301Z] ====== philosophers (scala) [default], iteration 22 completed (844.270 ms) ====== [2026-01-21T12:07:17.301Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-01-21T12:07:17.301Z] GC before operation: completed in 24.107 ms, heap usage 30.971 MB -> 4.875 MB. [2026-01-21T12:07:17.301Z] Camera thread performed 122 scans. [2026-01-21T12:07:17.301Z] ====== philosophers (scala) [default], iteration 23 completed (904.149 ms) ====== [2026-01-21T12:07:17.301Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-01-21T12:07:17.301Z] GC before operation: completed in 39.470 ms, heap usage 39.396 MB -> 4.876 MB. [2026-01-21T12:07:18.011Z] Camera thread performed 122 scans. [2026-01-21T12:07:18.011Z] ====== philosophers (scala) [default], iteration 24 completed (834.094 ms) ====== [2026-01-21T12:07:18.011Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-01-21T12:07:18.351Z] GC before operation: completed in 24.389 ms, heap usage 33.962 MB -> 4.876 MB. [2026-01-21T12:07:19.055Z] Camera thread performed 122 scans. [2026-01-21T12:07:19.055Z] ====== philosophers (scala) [default], iteration 25 completed (857.183 ms) ====== [2026-01-21T12:07:19.055Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-01-21T12:07:19.055Z] GC before operation: completed in 27.685 ms, heap usage 15.439 MB -> 4.876 MB. [2026-01-21T12:07:20.189Z] Camera thread performed 122 scans. [2026-01-21T12:07:20.189Z] ====== philosophers (scala) [default], iteration 26 completed (881.976 ms) ====== [2026-01-21T12:07:20.189Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-01-21T12:07:20.189Z] GC before operation: completed in 28.363 ms, heap usage 17.010 MB -> 4.877 MB. [2026-01-21T12:07:20.899Z] Camera thread performed 122 scans. [2026-01-21T12:07:20.899Z] ====== philosophers (scala) [default], iteration 27 completed (842.440 ms) ====== [2026-01-21T12:07:20.899Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-01-21T12:07:20.899Z] GC before operation: completed in 24.543 ms, heap usage 38.502 MB -> 4.876 MB. [2026-01-21T12:07:21.612Z] Camera thread performed 122 scans. [2026-01-21T12:07:21.612Z] ====== philosophers (scala) [default], iteration 28 completed (846.629 ms) ====== [2026-01-21T12:07:21.612Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-01-21T12:07:21.612Z] GC before operation: completed in 27.390 ms, heap usage 42.982 MB -> 4.877 MB. [2026-01-21T12:07:22.743Z] Camera thread performed 122 scans. [2026-01-21T12:07:22.743Z] ====== philosophers (scala) [default], iteration 29 completed (865.298 ms) ====== [2026-01-21T12:07:22.743Z] ----------------------------------- [2026-01-21T12:07:22.743Z] renaissance-philosophers_0_PASSED [2026-01-21T12:07:22.743Z] ----------------------------------- [2026-01-21T12:07:23.066Z] [2026-01-21T12:07:23.066Z] TEST TEARDOWN: [2026-01-21T12:07:23.066Z] Nothing to be done for teardown. [2026-01-21T12:07:23.066Z] renaissance-philosophers_0 Finish Time: Wed Jan 21 12:07:22 2026 Epoch Time (ms): 1768997242899