[2025-08-30T10:39:01.034Z] Running test renaissance-philosophers_0 ... [2025-08-30T10:39:01.034Z] =============================================== [2025-08-30T10:39:01.034Z] renaissance-philosophers_0 Start Time: Sat Aug 30 10:39:00 2025 Epoch Time (ms): 1756550340906 [2025-08-30T10:39:01.034Z] variation: NoOptions [2025-08-30T10:39:01.034Z] JVM_OPTIONS: [2025-08-30T10:39:01.034Z] { \ [2025-08-30T10:39:01.034Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T10:39:01.034Z] echo "Nothing to be done for setup."; \ [2025-08-30T10:39:01.034Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/renaissance-philosophers_0"; \ [2025-08-30T10:39:01.034Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/renaissance-philosophers_0"; \ [2025-08-30T10:39:01.034Z] echo ""; echo "TESTING:"; \ [2025-08-30T10:39:01.034Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-30T10:39:01.034Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T10:39:01.034Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T10:39:01.034Z] echo "Nothing to be done for teardown."; \ [2025-08-30T10:39:01.034Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/TestTargetResult"; [2025-08-30T10:39:01.034Z] [2025-08-30T10:39:01.034Z] TEST SETUP: [2025-08-30T10:39:01.034Z] Nothing to be done for setup. [2025-08-30T10:39:01.034Z] [2025-08-30T10:39:01.034Z] TESTING: [2025-08-30T10:39:02.614Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-30T10:39:02.614Z] GC before operation: completed in 20.359 ms, heap usage 14.697 MB -> 4.872 MB. [2025-08-30T10:39:05.082Z] Camera thread performed 122 scans. [2025-08-30T10:39:05.082Z] ====== philosophers (scala) [default], iteration 0 completed (2451.999 ms) ====== [2025-08-30T10:39:05.082Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-30T10:39:05.082Z] GC before operation: completed in 32.535 ms, heap usage 41.008 MB -> 5.247 MB. [2025-08-30T10:39:05.847Z] Camera thread performed 122 scans. [2025-08-30T10:39:05.847Z] ====== philosophers (scala) [default], iteration 1 completed (1237.009 ms) ====== [2025-08-30T10:39:05.847Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-30T10:39:05.847Z] GC before operation: completed in 19.382 ms, heap usage 57.436 MB -> 5.248 MB. [2025-08-30T10:39:07.448Z] Camera thread performed 122 scans. [2025-08-30T10:39:07.448Z] ====== philosophers (scala) [default], iteration 2 completed (1267.847 ms) ====== [2025-08-30T10:39:07.448Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-30T10:39:07.448Z] GC before operation: completed in 24.985 ms, heap usage 53.652 MB -> 5.283 MB. [2025-08-30T10:39:09.086Z] Camera thread performed 122 scans. [2025-08-30T10:39:09.086Z] ====== philosophers (scala) [default], iteration 3 completed (1352.598 ms) ====== [2025-08-30T10:39:09.086Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-30T10:39:09.086Z] GC before operation: completed in 29.661 ms, heap usage 41.189 MB -> 5.249 MB. [2025-08-30T10:39:09.852Z] Camera thread performed 122 scans. [2025-08-30T10:39:09.852Z] ====== philosophers (scala) [default], iteration 4 completed (1381.811 ms) ====== [2025-08-30T10:39:09.852Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-30T10:39:10.621Z] GC before operation: completed in 30.160 ms, heap usage 64.498 MB -> 5.250 MB. [2025-08-30T10:39:11.390Z] Camera thread performed 122 scans. [2025-08-30T10:39:11.390Z] ====== philosophers (scala) [default], iteration 5 completed (1260.482 ms) ====== [2025-08-30T10:39:11.390Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-30T10:39:11.390Z] GC before operation: completed in 13.628 ms, heap usage 53.255 MB -> 5.251 MB. [2025-08-30T10:39:12.967Z] Camera thread performed 122 scans. [2025-08-30T10:39:12.967Z] ====== philosophers (scala) [default], iteration 6 completed (1253.155 ms) ====== [2025-08-30T10:39:12.967Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-30T10:39:12.967Z] GC before operation: completed in 14.445 ms, heap usage 11.465 MB -> 5.251 MB. [2025-08-30T10:39:14.550Z] Camera thread performed 122 scans. [2025-08-30T10:39:14.550Z] ====== philosophers (scala) [default], iteration 7 completed (1363.963 ms) ====== [2025-08-30T10:39:14.550Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-30T10:39:14.550Z] GC before operation: completed in 25.252 ms, heap usage 11.880 MB -> 5.251 MB. [2025-08-30T10:39:15.317Z] Camera thread performed 122 scans. [2025-08-30T10:39:15.317Z] ====== philosophers (scala) [default], iteration 8 completed (1357.189 ms) ====== [2025-08-30T10:39:15.317Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-30T10:39:15.317Z] GC before operation: completed in 12.731 ms, heap usage 56.774 MB -> 5.251 MB. [2025-08-30T10:39:16.893Z] Camera thread performed 122 scans. [2025-08-30T10:39:16.893Z] ====== philosophers (scala) [default], iteration 9 completed (1366.622 ms) ====== [2025-08-30T10:39:16.893Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-30T10:39:16.893Z] GC before operation: completed in 13.311 ms, heap usage 51.999 MB -> 5.251 MB. [2025-08-30T10:39:18.477Z] Camera thread performed 122 scans. [2025-08-30T10:39:18.477Z] ====== philosophers (scala) [default], iteration 10 completed (1317.569 ms) ====== [2025-08-30T10:39:18.477Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-30T10:39:18.477Z] GC before operation: completed in 14.669 ms, heap usage 15.355 MB -> 5.251 MB. [2025-08-30T10:39:20.059Z] Camera thread performed 122 scans. [2025-08-30T10:39:20.059Z] ====== philosophers (scala) [default], iteration 11 completed (1355.088 ms) ====== [2025-08-30T10:39:20.059Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-30T10:39:20.059Z] GC before operation: completed in 12.110 ms, heap usage 47.764 MB -> 5.252 MB. [2025-08-30T10:39:20.823Z] Camera thread performed 122 scans. [2025-08-30T10:39:20.823Z] ====== philosophers (scala) [default], iteration 12 completed (1346.453 ms) ====== [2025-08-30T10:39:20.823Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-30T10:39:20.823Z] GC before operation: completed in 11.761 ms, heap usage 49.818 MB -> 5.252 MB. [2025-08-30T10:39:22.910Z] Camera thread performed 122 scans. [2025-08-30T10:39:22.910Z] ====== philosophers (scala) [default], iteration 13 completed (1329.208 ms) ====== [2025-08-30T10:39:22.910Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-30T10:39:22.910Z] GC before operation: completed in 11.037 ms, heap usage 63.960 MB -> 5.252 MB. [2025-08-30T10:39:23.678Z] Camera thread performed 122 scans. [2025-08-30T10:39:23.678Z] ====== philosophers (scala) [default], iteration 14 completed (1362.357 ms) ====== [2025-08-30T10:39:23.678Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-30T10:39:23.678Z] GC before operation: completed in 12.722 ms, heap usage 58.918 MB -> 5.252 MB. [2025-08-30T10:39:25.256Z] Camera thread performed 122 scans. [2025-08-30T10:39:25.256Z] ====== philosophers (scala) [default], iteration 15 completed (1340.586 ms) ====== [2025-08-30T10:39:25.256Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-30T10:39:25.256Z] GC before operation: completed in 10.116 ms, heap usage 7.924 MB -> 5.252 MB. [2025-08-30T10:39:26.836Z] Camera thread performed 122 scans. [2025-08-30T10:39:26.836Z] ====== philosophers (scala) [default], iteration 16 completed (1424.200 ms) ====== [2025-08-30T10:39:26.836Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-30T10:39:26.836Z] GC before operation: completed in 14.056 ms, heap usage 13.253 MB -> 5.252 MB. [2025-08-30T10:39:27.599Z] Camera thread performed 122 scans. [2025-08-30T10:39:27.599Z] ====== philosophers (scala) [default], iteration 17 completed (1364.392 ms) ====== [2025-08-30T10:39:27.599Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-30T10:39:27.599Z] GC before operation: completed in 12.307 ms, heap usage 27.269 MB -> 5.252 MB. [2025-08-30T10:39:29.175Z] Camera thread performed 122 scans. [2025-08-30T10:39:29.175Z] ====== philosophers (scala) [default], iteration 18 completed (1209.581 ms) ====== [2025-08-30T10:39:29.175Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-30T10:39:29.175Z] GC before operation: completed in 14.857 ms, heap usage 47.385 MB -> 5.253 MB. [2025-08-30T10:39:30.806Z] Camera thread performed 122 scans. [2025-08-30T10:39:30.806Z] ====== philosophers (scala) [default], iteration 19 completed (1242.552 ms) ====== [2025-08-30T10:39:30.806Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-30T10:39:30.806Z] GC before operation: completed in 14.819 ms, heap usage 6.317 MB -> 5.252 MB. [2025-08-30T10:39:31.578Z] Camera thread performed 122 scans. [2025-08-30T10:39:31.578Z] ====== philosophers (scala) [default], iteration 20 completed (1270.751 ms) ====== [2025-08-30T10:39:31.578Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-30T10:39:31.578Z] GC before operation: completed in 13.374 ms, heap usage 22.166 MB -> 5.253 MB. [2025-08-30T10:39:33.156Z] Camera thread performed 122 scans. [2025-08-30T10:39:33.156Z] ====== philosophers (scala) [default], iteration 21 completed (1297.391 ms) ====== [2025-08-30T10:39:33.156Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-30T10:39:33.156Z] GC before operation: completed in 15.690 ms, heap usage 36.250 MB -> 5.253 MB. [2025-08-30T10:39:33.924Z] Camera thread performed 122 scans. [2025-08-30T10:39:33.924Z] ====== philosophers (scala) [default], iteration 22 completed (1301.945 ms) ====== [2025-08-30T10:39:33.924Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-30T10:39:33.924Z] GC before operation: completed in 12.315 ms, heap usage 18.328 MB -> 5.253 MB. [2025-08-30T10:39:35.508Z] Camera thread performed 122 scans. [2025-08-30T10:39:35.508Z] ====== philosophers (scala) [default], iteration 23 completed (1375.025 ms) ====== [2025-08-30T10:39:35.508Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-30T10:39:35.508Z] GC before operation: completed in 13.989 ms, heap usage 82.084 MB -> 5.253 MB. [2025-08-30T10:39:37.082Z] Camera thread performed 122 scans. [2025-08-30T10:39:37.082Z] ====== philosophers (scala) [default], iteration 24 completed (1429.405 ms) ====== [2025-08-30T10:39:37.082Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-30T10:39:37.082Z] GC before operation: completed in 13.082 ms, heap usage 53.146 MB -> 5.253 MB. [2025-08-30T10:39:38.676Z] Camera thread performed 122 scans. [2025-08-30T10:39:38.676Z] ====== philosophers (scala) [default], iteration 25 completed (1316.831 ms) ====== [2025-08-30T10:39:38.676Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-30T10:39:38.676Z] GC before operation: completed in 15.229 ms, heap usage 69.756 MB -> 5.253 MB. [2025-08-30T10:39:39.439Z] Camera thread performed 122 scans. [2025-08-30T10:39:39.439Z] ====== philosophers (scala) [default], iteration 26 completed (1188.527 ms) ====== [2025-08-30T10:39:39.439Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-30T10:39:39.439Z] GC before operation: completed in 15.031 ms, heap usage 42.086 MB -> 5.253 MB. [2025-08-30T10:39:41.018Z] Camera thread performed 122 scans. [2025-08-30T10:39:41.018Z] ====== philosophers (scala) [default], iteration 27 completed (1377.136 ms) ====== [2025-08-30T10:39:41.018Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-30T10:39:41.018Z] GC before operation: completed in 26.178 ms, heap usage 35.168 MB -> 5.253 MB. [2025-08-30T10:39:42.605Z] Camera thread performed 122 scans. [2025-08-30T10:39:42.605Z] ====== philosophers (scala) [default], iteration 28 completed (1138.455 ms) ====== [2025-08-30T10:39:42.605Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-30T10:39:42.605Z] GC before operation: completed in 15.671 ms, heap usage 61.696 MB -> 5.253 MB. [2025-08-30T10:39:43.371Z] Camera thread performed 122 scans. [2025-08-30T10:39:43.371Z] ====== philosophers (scala) [default], iteration 29 completed (1201.386 ms) ====== [2025-08-30T10:39:43.371Z] ----------------------------------- [2025-08-30T10:39:43.371Z] renaissance-philosophers_0_PASSED [2025-08-30T10:39:43.371Z] ----------------------------------- [2025-08-30T10:39:43.371Z] [2025-08-30T10:39:43.371Z] TEST TEARDOWN: [2025-08-30T10:39:43.371Z] Nothing to be done for teardown. [2025-08-30T10:39:43.371Z] renaissance-philosophers_0 Finish Time: Sat Aug 30 10:39:43 2025 Epoch Time (ms): 1756550383278