[2025-08-28T22:29:18.256Z] Running test renaissance-philosophers_0 ... [2025-08-28T22:29:18.256Z] =============================================== [2025-08-28T22:29:18.256Z] renaissance-philosophers_0 Start Time: Thu Aug 28 22:29:17 2025 Epoch Time (ms): 1756420157651 [2025-08-28T22:29:18.256Z] variation: NoOptions [2025-08-28T22:29:18.256Z] JVM_OPTIONS: [2025-08-28T22:29:18.256Z] { \ [2025-08-28T22:29:18.256Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T22:29:18.256Z] echo "Nothing to be done for setup."; \ [2025-08-28T22:29:18.257Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17564170007691/renaissance-philosophers_0"; \ [2025-08-28T22:29:18.257Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17564170007691/renaissance-philosophers_0"; \ [2025-08-28T22:29:18.257Z] echo ""; echo "TESTING:"; \ [2025-08-28T22:29:18.257Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17564170007691/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-28T22:29:18.257Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17564170007691/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T22:29:18.257Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T22:29:18.257Z] echo "Nothing to be done for teardown."; \ [2025-08-28T22:29:18.257Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17564170007691/TestTargetResult"; [2025-08-28T22:29:18.257Z] [2025-08-28T22:29:18.257Z] TEST SETUP: [2025-08-28T22:29:18.257Z] Nothing to be done for setup. [2025-08-28T22:29:18.257Z] [2025-08-28T22:29:18.257Z] TESTING: [2025-08-28T22:29:19.844Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-28T22:29:19.844Z] GC before operation: completed in 27.720 ms, heap usage 15.069 MB -> 4.206 MB. [2025-08-28T22:29:25.406Z] Camera thread performed 122 scans. [2025-08-28T22:29:25.406Z] ====== philosophers (scala) [default], iteration 0 completed (5066.247 ms) ====== [2025-08-28T22:29:25.406Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-28T22:29:25.406Z] GC before operation: completed in 44.377 ms, heap usage 9.947 MB -> 4.616 MB. [2025-08-28T22:29:26.970Z] Camera thread performed 122 scans. [2025-08-28T22:29:26.970Z] ====== philosophers (scala) [default], iteration 1 completed (1676.062 ms) ====== [2025-08-28T22:29:26.970Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-28T22:29:26.970Z] GC before operation: completed in 42.918 ms, heap usage 63.591 MB -> 4.616 MB. [2025-08-28T22:29:28.540Z] Camera thread performed 122 scans. [2025-08-28T22:29:28.540Z] ====== philosophers (scala) [default], iteration 2 completed (1561.333 ms) ====== [2025-08-28T22:29:28.540Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-28T22:29:28.540Z] GC before operation: completed in 33.593 ms, heap usage 21.398 MB -> 4.621 MB. [2025-08-28T22:29:29.299Z] Camera thread performed 122 scans. [2025-08-28T22:29:29.299Z] ====== philosophers (scala) [default], iteration 3 completed (1292.424 ms) ====== [2025-08-28T22:29:29.299Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-28T22:29:29.299Z] GC before operation: completed in 36.605 ms, heap usage 39.810 MB -> 4.622 MB. [2025-08-28T22:29:31.391Z] Camera thread performed 122 scans. [2025-08-28T22:29:31.391Z] ====== philosophers (scala) [default], iteration 4 completed (1579.999 ms) ====== [2025-08-28T22:29:31.391Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-28T22:29:31.391Z] GC before operation: completed in 46.687 ms, heap usage 86.679 MB -> 4.624 MB. [2025-08-28T22:29:32.964Z] Camera thread performed 122 scans. [2025-08-28T22:29:32.964Z] ====== philosophers (scala) [default], iteration 5 completed (1564.139 ms) ====== [2025-08-28T22:29:32.964Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-28T22:29:32.964Z] GC before operation: completed in 42.955 ms, heap usage 57.616 MB -> 4.624 MB. [2025-08-28T22:29:34.549Z] Camera thread performed 122 scans. [2025-08-28T22:29:34.549Z] ====== philosophers (scala) [default], iteration 6 completed (1526.008 ms) ====== [2025-08-28T22:29:34.549Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-28T22:29:34.549Z] GC before operation: completed in 50.249 ms, heap usage 56.829 MB -> 4.624 MB. [2025-08-28T22:29:36.127Z] Camera thread performed 122 scans. [2025-08-28T22:29:36.127Z] ====== philosophers (scala) [default], iteration 7 completed (1498.724 ms) ====== [2025-08-28T22:29:36.127Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-28T22:29:36.127Z] GC before operation: completed in 43.502 ms, heap usage 13.840 MB -> 4.625 MB. [2025-08-28T22:29:37.709Z] Camera thread performed 122 scans. [2025-08-28T22:29:37.709Z] ====== philosophers (scala) [default], iteration 8 completed (1528.301 ms) ====== [2025-08-28T22:29:37.709Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-28T22:29:37.709Z] GC before operation: completed in 38.484 ms, heap usage 50.624 MB -> 4.626 MB. [2025-08-28T22:29:38.478Z] Camera thread performed 122 scans. [2025-08-28T22:29:38.478Z] ====== philosophers (scala) [default], iteration 9 completed (1195.347 ms) ====== [2025-08-28T22:29:38.478Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-28T22:29:38.478Z] GC before operation: completed in 51.610 ms, heap usage 22.460 MB -> 4.626 MB. [2025-08-28T22:29:40.064Z] Camera thread performed 122 scans. [2025-08-28T22:29:40.064Z] ====== philosophers (scala) [default], iteration 10 completed (1153.267 ms) ====== [2025-08-28T22:29:40.064Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-28T22:29:40.064Z] GC before operation: completed in 53.008 ms, heap usage 20.189 MB -> 4.626 MB. [2025-08-28T22:29:41.644Z] Camera thread performed 122 scans. [2025-08-28T22:29:41.644Z] ====== philosophers (scala) [default], iteration 11 completed (1342.006 ms) ====== [2025-08-28T22:29:41.644Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-28T22:29:41.644Z] GC before operation: completed in 49.006 ms, heap usage 83.203 MB -> 4.626 MB. [2025-08-28T22:29:42.414Z] Camera thread performed 122 scans. [2025-08-28T22:29:42.414Z] ====== philosophers (scala) [default], iteration 12 completed (1360.292 ms) ====== [2025-08-28T22:29:42.414Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-28T22:29:43.245Z] GC before operation: completed in 39.147 ms, heap usage 5.295 MB -> 4.627 MB. [2025-08-28T22:29:44.819Z] Camera thread performed 122 scans. [2025-08-28T22:29:44.819Z] ====== philosophers (scala) [default], iteration 13 completed (1590.739 ms) ====== [2025-08-28T22:29:44.819Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-28T22:29:44.819Z] GC before operation: completed in 51.068 ms, heap usage 58.815 MB -> 4.626 MB. [2025-08-28T22:29:45.584Z] Camera thread performed 122 scans. [2025-08-28T22:29:45.584Z] ====== philosophers (scala) [default], iteration 14 completed (1411.544 ms) ====== [2025-08-28T22:29:45.584Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-28T22:29:45.584Z] GC before operation: completed in 50.414 ms, heap usage 29.336 MB -> 4.627 MB. [2025-08-28T22:29:47.155Z] Camera thread performed 122 scans. [2025-08-28T22:29:47.155Z] ====== philosophers (scala) [default], iteration 15 completed (1448.233 ms) ====== [2025-08-28T22:29:47.155Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-28T22:29:47.155Z] GC before operation: completed in 48.671 ms, heap usage 44.295 MB -> 4.627 MB. [2025-08-28T22:29:49.603Z] Camera thread performed 122 scans. [2025-08-28T22:29:49.603Z] ====== philosophers (scala) [default], iteration 16 completed (1710.870 ms) ====== [2025-08-28T22:29:49.603Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-28T22:29:49.603Z] GC before operation: completed in 53.317 ms, heap usage 62.456 MB -> 4.627 MB. [2025-08-28T22:29:51.181Z] Camera thread performed 122 scans. [2025-08-28T22:29:51.181Z] ====== philosophers (scala) [default], iteration 17 completed (1773.679 ms) ====== [2025-08-28T22:29:51.181Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-28T22:29:51.181Z] GC before operation: completed in 48.968 ms, heap usage 32.184 MB -> 4.627 MB. [2025-08-28T22:29:52.759Z] Camera thread performed 122 scans. [2025-08-28T22:29:52.759Z] ====== philosophers (scala) [default], iteration 18 completed (1533.921 ms) ====== [2025-08-28T22:29:52.759Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-28T22:29:52.759Z] GC before operation: completed in 52.490 ms, heap usage 22.337 MB -> 4.627 MB. [2025-08-28T22:29:54.330Z] Camera thread performed 122 scans. [2025-08-28T22:29:54.330Z] ====== philosophers (scala) [default], iteration 19 completed (1369.259 ms) ====== [2025-08-28T22:29:54.330Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-28T22:29:54.330Z] GC before operation: completed in 50.518 ms, heap usage 27.836 MB -> 4.627 MB. [2025-08-28T22:29:55.097Z] Camera thread performed 122 scans. [2025-08-28T22:29:55.097Z] ====== philosophers (scala) [default], iteration 20 completed (1420.763 ms) ====== [2025-08-28T22:29:55.097Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-28T22:29:55.859Z] GC before operation: completed in 46.364 ms, heap usage 85.830 MB -> 4.628 MB. [2025-08-28T22:29:56.618Z] Camera thread performed 122 scans. [2025-08-28T22:29:56.618Z] ====== philosophers (scala) [default], iteration 21 completed (1175.816 ms) ====== [2025-08-28T22:29:56.618Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-28T22:29:56.618Z] GC before operation: completed in 43.749 ms, heap usage 9.224 MB -> 4.663 MB. [2025-08-28T22:29:58.190Z] Camera thread performed 122 scans. [2025-08-28T22:29:58.190Z] ====== philosophers (scala) [default], iteration 22 completed (1156.944 ms) ====== [2025-08-28T22:29:58.190Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-28T22:29:58.190Z] GC before operation: completed in 44.143 ms, heap usage 65.165 MB -> 4.628 MB. [2025-08-28T22:29:59.766Z] Camera thread performed 122 scans. [2025-08-28T22:29:59.766Z] ====== philosophers (scala) [default], iteration 23 completed (1449.827 ms) ====== [2025-08-28T22:29:59.766Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-28T22:29:59.766Z] GC before operation: completed in 47.349 ms, heap usage 41.759 MB -> 4.663 MB. [2025-08-28T22:30:00.526Z] Camera thread performed 122 scans. [2025-08-28T22:30:00.526Z] ====== philosophers (scala) [default], iteration 24 completed (1503.986 ms) ====== [2025-08-28T22:30:00.526Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-28T22:30:01.293Z] GC before operation: completed in 57.316 ms, heap usage 64.869 MB -> 4.628 MB. [2025-08-28T22:30:03.029Z] Camera thread performed 122 scans. [2025-08-28T22:30:03.029Z] ====== philosophers (scala) [default], iteration 25 completed (1574.444 ms) ====== [2025-08-28T22:30:03.029Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-28T22:30:03.029Z] GC before operation: completed in 48.023 ms, heap usage 50.245 MB -> 4.628 MB. [2025-08-28T22:30:03.807Z] Camera thread performed 122 scans. [2025-08-28T22:30:03.807Z] ====== philosophers (scala) [default], iteration 26 completed (1248.728 ms) ====== [2025-08-28T22:30:03.807Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-28T22:30:03.807Z] GC before operation: completed in 49.539 ms, heap usage 33.446 MB -> 4.628 MB. [2025-08-28T22:30:05.383Z] Camera thread performed 122 scans. [2025-08-28T22:30:05.383Z] ====== philosophers (scala) [default], iteration 27 completed (1329.942 ms) ====== [2025-08-28T22:30:05.383Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-28T22:30:05.383Z] GC before operation: completed in 50.617 ms, heap usage 37.275 MB -> 4.628 MB. [2025-08-28T22:30:06.989Z] Camera thread performed 122 scans. [2025-08-28T22:30:06.989Z] ====== philosophers (scala) [default], iteration 28 completed (1399.212 ms) ====== [2025-08-28T22:30:06.989Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-28T22:30:06.989Z] GC before operation: completed in 41.014 ms, heap usage 25.245 MB -> 4.628 MB. [2025-08-28T22:30:08.567Z] Camera thread performed 122 scans. [2025-08-28T22:30:08.567Z] ====== philosophers (scala) [default], iteration 29 completed (1474.596 ms) ====== [2025-08-28T22:30:08.567Z] ----------------------------------- [2025-08-28T22:30:08.567Z] renaissance-philosophers_0_PASSED [2025-08-28T22:30:08.567Z] ----------------------------------- [2025-08-28T22:30:08.567Z] [2025-08-28T22:30:08.567Z] TEST TEARDOWN: [2025-08-28T22:30:08.567Z] Nothing to be done for teardown. [2025-08-28T22:30:08.567Z] renaissance-philosophers_0 Finish Time: Thu Aug 28 22:30:08 2025 Epoch Time (ms): 1756420208075