[2025-08-21T01:25:33.856Z] Running test renaissance-philosophers_0 ... [2025-08-21T01:25:33.856Z] =============================================== [2025-08-21T01:25:33.856Z] renaissance-philosophers_0 Start Time: Thu Aug 21 01:25:33 2025 Epoch Time (ms): 1755739533320 [2025-08-21T01:25:33.856Z] variation: NoOptions [2025-08-21T01:25:33.856Z] JVM_OPTIONS: [2025-08-21T01:25:33.856Z] { \ [2025-08-21T01:25:33.856Z] echo ""; echo "TEST SETUP:"; \ [2025-08-21T01:25:33.856Z] echo "Nothing to be done for setup."; \ [2025-08-21T01:25:33.856Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/renaissance-philosophers_0"; \ [2025-08-21T01:25:33.856Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/renaissance-philosophers_0"; \ [2025-08-21T01:25:33.856Z] echo ""; echo "TESTING:"; \ [2025-08-21T01:25:33.856Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_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_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-21T01:25:33.856Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-21T01:25:33.856Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-21T01:25:33.856Z] echo "Nothing to be done for teardown."; \ [2025-08-21T01:25:33.856Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17557377207327/TestTargetResult"; [2025-08-21T01:25:33.856Z] [2025-08-21T01:25:33.856Z] TEST SETUP: [2025-08-21T01:25:33.856Z] Nothing to be done for setup. [2025-08-21T01:25:33.856Z] [2025-08-21T01:25:33.856Z] TESTING: [2025-08-21T01:25:34.682Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-21T01:25:34.682Z] GC before operation: completed in 14.038 ms, heap usage 14.268 MB -> 4.671 MB. [2025-08-21T01:25:38.088Z] Camera thread performed 122 scans. [2025-08-21T01:25:38.088Z] ====== philosophers (scala) [default], iteration 0 completed (3264.622 ms) ====== [2025-08-21T01:25:38.088Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-21T01:25:38.088Z] GC before operation: completed in 15.688 ms, heap usage 20.848 MB -> 5.039 MB. [2025-08-21T01:25:40.628Z] Camera thread performed 122 scans. [2025-08-21T01:25:40.628Z] ====== philosophers (scala) [default], iteration 1 completed (2230.893 ms) ====== [2025-08-21T01:25:40.628Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-21T01:25:40.628Z] GC before operation: completed in 12.140 ms, heap usage 59.089 MB -> 5.040 MB. [2025-08-21T01:25:42.513Z] Camera thread performed 122 scans. [2025-08-21T01:25:42.513Z] ====== philosophers (scala) [default], iteration 2 completed (2008.332 ms) ====== [2025-08-21T01:25:42.513Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-21T01:25:42.513Z] GC before operation: completed in 11.358 ms, heap usage 65.263 MB -> 5.040 MB. [2025-08-21T01:25:44.409Z] Camera thread performed 122 scans. [2025-08-21T01:25:44.409Z] ====== philosophers (scala) [default], iteration 3 completed (2031.136 ms) ====== [2025-08-21T01:25:44.409Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-21T01:25:44.409Z] GC before operation: completed in 11.350 ms, heap usage 37.710 MB -> 5.041 MB. [2025-08-21T01:25:46.304Z] Camera thread performed 122 scans. [2025-08-21T01:25:46.304Z] ====== philosophers (scala) [default], iteration 4 completed (1997.075 ms) ====== [2025-08-21T01:25:46.304Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-21T01:25:46.304Z] GC before operation: completed in 11.488 ms, heap usage 72.330 MB -> 5.042 MB. [2025-08-21T01:25:48.898Z] Camera thread performed 122 scans. [2025-08-21T01:25:48.898Z] ====== philosophers (scala) [default], iteration 5 completed (2156.479 ms) ====== [2025-08-21T01:25:48.898Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-21T01:25:48.898Z] GC before operation: completed in 11.302 ms, heap usage 56.274 MB -> 5.042 MB. [2025-08-21T01:25:50.354Z] Camera thread performed 122 scans. [2025-08-21T01:25:50.354Z] ====== philosophers (scala) [default], iteration 6 completed (2004.610 ms) ====== [2025-08-21T01:25:50.354Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-21T01:25:50.354Z] GC before operation: completed in 11.685 ms, heap usage 38.025 MB -> 5.043 MB. [2025-08-21T01:25:52.917Z] Camera thread performed 122 scans. [2025-08-21T01:25:52.917Z] ====== philosophers (scala) [default], iteration 7 completed (1993.962 ms) ====== [2025-08-21T01:25:52.917Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-21T01:25:52.917Z] GC before operation: completed in 11.348 ms, heap usage 21.275 MB -> 5.043 MB. [2025-08-21T01:25:54.244Z] Camera thread performed 122 scans. [2025-08-21T01:25:54.244Z] ====== philosophers (scala) [default], iteration 8 completed (1944.248 ms) ====== [2025-08-21T01:25:54.244Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-21T01:25:54.634Z] GC before operation: completed in 11.384 ms, heap usage 23.006 MB -> 5.043 MB. [2025-08-21T01:25:56.536Z] Camera thread performed 122 scans. [2025-08-21T01:25:56.536Z] ====== philosophers (scala) [default], iteration 9 completed (1959.098 ms) ====== [2025-08-21T01:25:56.536Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-21T01:25:56.536Z] GC before operation: completed in 12.157 ms, heap usage 58.263 MB -> 5.043 MB. [2025-08-21T01:25:58.434Z] Camera thread performed 122 scans. [2025-08-21T01:25:58.434Z] ====== philosophers (scala) [default], iteration 10 completed (1988.320 ms) ====== [2025-08-21T01:25:58.434Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-21T01:25:58.434Z] GC before operation: completed in 11.379 ms, heap usage 53.977 MB -> 5.043 MB. [2025-08-21T01:26:00.332Z] Camera thread performed 122 scans. [2025-08-21T01:26:00.332Z] ====== philosophers (scala) [default], iteration 11 completed (1971.260 ms) ====== [2025-08-21T01:26:00.332Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-21T01:26:00.332Z] GC before operation: completed in 11.685 ms, heap usage 62.994 MB -> 5.044 MB. [2025-08-21T01:26:02.250Z] Camera thread performed 122 scans. [2025-08-21T01:26:02.250Z] ====== philosophers (scala) [default], iteration 12 completed (1977.203 ms) ====== [2025-08-21T01:26:02.250Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-21T01:26:02.250Z] GC before operation: completed in 11.546 ms, heap usage 23.945 MB -> 5.044 MB. [2025-08-21T01:26:04.814Z] Camera thread performed 122 scans. [2025-08-21T01:26:04.814Z] ====== philosophers (scala) [default], iteration 13 completed (2013.444 ms) ====== [2025-08-21T01:26:04.814Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-21T01:26:04.814Z] GC before operation: completed in 11.388 ms, heap usage 9.581 MB -> 5.044 MB. [2025-08-21T01:26:06.717Z] Camera thread performed 122 scans. [2025-08-21T01:26:06.717Z] ====== philosophers (scala) [default], iteration 14 completed (1987.840 ms) ====== [2025-08-21T01:26:06.717Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-21T01:26:06.717Z] GC before operation: completed in 11.532 ms, heap usage 75.665 MB -> 5.044 MB. [2025-08-21T01:26:08.612Z] Camera thread performed 122 scans. [2025-08-21T01:26:08.612Z] ====== philosophers (scala) [default], iteration 15 completed (2000.597 ms) ====== [2025-08-21T01:26:08.612Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-21T01:26:08.612Z] GC before operation: completed in 11.412 ms, heap usage 69.266 MB -> 5.044 MB. [2025-08-21T01:26:10.516Z] Camera thread performed 122 scans. [2025-08-21T01:26:10.516Z] ====== philosophers (scala) [default], iteration 16 completed (1974.521 ms) ====== [2025-08-21T01:26:10.516Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-21T01:26:10.516Z] GC before operation: completed in 11.392 ms, heap usage 71.638 MB -> 5.044 MB. [2025-08-21T01:26:12.417Z] Camera thread performed 122 scans. [2025-08-21T01:26:12.417Z] ====== philosophers (scala) [default], iteration 17 completed (1966.781 ms) ====== [2025-08-21T01:26:12.417Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-21T01:26:12.417Z] GC before operation: completed in 11.338 ms, heap usage 40.932 MB -> 5.044 MB. [2025-08-21T01:26:14.318Z] Camera thread performed 122 scans. [2025-08-21T01:26:14.318Z] ====== philosophers (scala) [default], iteration 18 completed (1987.636 ms) ====== [2025-08-21T01:26:14.318Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-21T01:26:14.318Z] GC before operation: completed in 11.173 ms, heap usage 38.941 MB -> 5.044 MB. [2025-08-21T01:26:16.873Z] Camera thread performed 122 scans. [2025-08-21T01:26:16.873Z] ====== philosophers (scala) [default], iteration 19 completed (2050.772 ms) ====== [2025-08-21T01:26:16.873Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-21T01:26:16.873Z] GC before operation: completed in 13.329 ms, heap usage 33.038 MB -> 5.044 MB. [2025-08-21T01:26:18.783Z] Camera thread performed 122 scans. [2025-08-21T01:26:18.783Z] ====== philosophers (scala) [default], iteration 20 completed (2041.846 ms) ====== [2025-08-21T01:26:18.783Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-21T01:26:18.783Z] GC before operation: completed in 11.282 ms, heap usage 51.655 MB -> 5.045 MB. [2025-08-21T01:26:20.682Z] Camera thread performed 122 scans. [2025-08-21T01:26:20.682Z] ====== philosophers (scala) [default], iteration 21 completed (1958.341 ms) ====== [2025-08-21T01:26:20.682Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-21T01:26:20.682Z] GC before operation: completed in 13.732 ms, heap usage 63.939 MB -> 5.044 MB. [2025-08-21T01:26:22.577Z] Camera thread performed 122 scans. [2025-08-21T01:26:22.578Z] ====== philosophers (scala) [default], iteration 22 completed (2019.327 ms) ====== [2025-08-21T01:26:22.579Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-21T01:26:22.579Z] GC before operation: completed in 11.194 ms, heap usage 6.984 MB -> 5.045 MB. [2025-08-21T01:26:24.486Z] Camera thread performed 122 scans. [2025-08-21T01:26:24.486Z] ====== philosophers (scala) [default], iteration 23 completed (2028.557 ms) ====== [2025-08-21T01:26:24.486Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-21T01:26:24.486Z] GC before operation: completed in 11.262 ms, heap usage 17.270 MB -> 5.045 MB. [2025-08-21T01:26:27.044Z] Camera thread performed 122 scans. [2025-08-21T01:26:27.044Z] ====== philosophers (scala) [default], iteration 24 completed (2041.544 ms) ====== [2025-08-21T01:26:27.044Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-21T01:26:27.044Z] GC before operation: completed in 11.430 ms, heap usage 44.263 MB -> 5.045 MB. [2025-08-21T01:26:28.934Z] Camera thread performed 122 scans. [2025-08-21T01:26:28.935Z] ====== philosophers (scala) [default], iteration 25 completed (1989.232 ms) ====== [2025-08-21T01:26:28.935Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-21T01:26:28.935Z] GC before operation: completed in 11.264 ms, heap usage 33.263 MB -> 5.045 MB. [2025-08-21T01:26:30.827Z] Camera thread performed 122 scans. [2025-08-21T01:26:30.827Z] ====== philosophers (scala) [default], iteration 26 completed (1996.155 ms) ====== [2025-08-21T01:26:30.827Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-21T01:26:30.827Z] GC before operation: completed in 11.443 ms, heap usage 66.995 MB -> 5.045 MB. [2025-08-21T01:26:32.720Z] Camera thread performed 122 scans. [2025-08-21T01:26:32.720Z] ====== philosophers (scala) [default], iteration 27 completed (1970.145 ms) ====== [2025-08-21T01:26:32.720Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-21T01:26:32.720Z] GC before operation: completed in 11.376 ms, heap usage 67.258 MB -> 5.045 MB. [2025-08-21T01:26:34.627Z] Camera thread performed 122 scans. [2025-08-21T01:26:34.627Z] ====== philosophers (scala) [default], iteration 28 completed (1996.292 ms) ====== [2025-08-21T01:26:34.627Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-21T01:26:34.627Z] GC before operation: completed in 11.490 ms, heap usage 30.950 MB -> 5.045 MB. [2025-08-21T01:26:36.532Z] Camera thread performed 122 scans. [2025-08-21T01:26:36.532Z] ====== philosophers (scala) [default], iteration 29 completed (1975.627 ms) ====== [2025-08-21T01:26:36.915Z] ----------------------------------- [2025-08-21T01:26:36.915Z] renaissance-philosophers_0_PASSED [2025-08-21T01:26:36.915Z] ----------------------------------- [2025-08-21T01:26:36.915Z] [2025-08-21T01:26:36.915Z] TEST TEARDOWN: [2025-08-21T01:26:36.915Z] Nothing to be done for teardown. [2025-08-21T01:26:36.915Z] renaissance-philosophers_0 Finish Time: Thu Aug 21 01:26:36 2025 Epoch Time (ms): 1755739596658