[2025-09-04T02:30:48.909Z] Running test renaissance-philosophers_0 ... [2025-09-04T02:30:48.909Z] =============================================== [2025-09-04T02:30:48.909Z] renaissance-philosophers_0 Start Time: Thu Sep 4 02:30:48 2025 Epoch Time (ms): 1756953048801 [2025-09-04T02:30:48.909Z] variation: NoOptions [2025-09-04T02:30:48.909Z] JVM_OPTIONS: [2025-09-04T02:30:48.909Z] { \ [2025-09-04T02:30:48.909Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T02:30:48.909Z] echo "Nothing to be done for setup."; \ [2025-09-04T02:30:48.909Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-philosophers_0"; \ [2025-09-04T02:30:48.909Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-philosophers_0"; \ [2025-09-04T02:30:48.909Z] echo ""; echo "TESTING:"; \ [2025-09-04T02:30:48.910Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-09-04T02:30:48.910Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T02:30:48.910Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T02:30:48.910Z] echo "Nothing to be done for teardown."; \ [2025-09-04T02:30:48.910Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569508575764/TestTargetResult"; [2025-09-04T02:30:48.910Z] [2025-09-04T02:30:48.910Z] TEST SETUP: [2025-09-04T02:30:48.910Z] Nothing to be done for setup. [2025-09-04T02:30:48.910Z] [2025-09-04T02:30:48.910Z] TESTING: [2025-09-04T02:30:50.855Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-04T02:30:50.855Z] GC before operation: completed in 15.957 ms, heap usage 14.783 MB -> 4.845 MB. [2025-09-04T02:30:57.576Z] Camera thread performed 122 scans. [2025-09-04T02:30:57.576Z] ====== philosophers (scala) [default], iteration 0 completed (5984.056 ms) ====== [2025-09-04T02:30:57.576Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-04T02:30:57.576Z] GC before operation: completed in 24.786 ms, heap usage 20.995 MB -> 5.198 MB. [2025-09-04T02:31:00.593Z] Camera thread performed 122 scans. [2025-09-04T02:31:00.593Z] ====== philosophers (scala) [default], iteration 1 completed (4177.478 ms) ====== [2025-09-04T02:31:00.593Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-04T02:31:01.545Z] GC before operation: completed in 17.843 ms, heap usage 69.901 MB -> 5.197 MB. [2025-09-04T02:31:03.492Z] Camera thread performed 122 scans. [2025-09-04T02:31:03.492Z] ====== philosophers (scala) [default], iteration 2 completed (2767.651 ms) ====== [2025-09-04T02:31:03.492Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-04T02:31:03.492Z] GC before operation: completed in 17.646 ms, heap usage 57.178 MB -> 5.198 MB. [2025-09-04T02:31:06.505Z] Camera thread performed 122 scans. [2025-09-04T02:31:06.505Z] ====== philosophers (scala) [default], iteration 3 completed (2637.600 ms) ====== [2025-09-04T02:31:06.505Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-04T02:31:06.505Z] GC before operation: completed in 16.201 ms, heap usage 64.300 MB -> 5.199 MB. [2025-09-04T02:31:09.508Z] Camera thread performed 122 scans. [2025-09-04T02:31:09.508Z] ====== philosophers (scala) [default], iteration 4 completed (2734.279 ms) ====== [2025-09-04T02:31:09.508Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-04T02:31:09.508Z] GC before operation: completed in 16.983 ms, heap usage 28.361 MB -> 5.200 MB. [2025-09-04T02:31:11.456Z] Camera thread performed 122 scans. [2025-09-04T02:31:11.457Z] ====== philosophers (scala) [default], iteration 5 completed (2593.359 ms) ====== [2025-09-04T02:31:11.457Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-04T02:31:11.457Z] GC before operation: completed in 26.934 ms, heap usage 95.893 MB -> 5.199 MB. [2025-09-04T02:31:14.465Z] Camera thread performed 122 scans. [2025-09-04T02:31:14.465Z] ====== philosophers (scala) [default], iteration 6 completed (2627.560 ms) ====== [2025-09-04T02:31:14.465Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-04T02:31:14.465Z] GC before operation: completed in 15.627 ms, heap usage 32.952 MB -> 5.200 MB. [2025-09-04T02:31:17.492Z] Camera thread performed 122 scans. [2025-09-04T02:31:17.492Z] ====== philosophers (scala) [default], iteration 7 completed (2628.348 ms) ====== [2025-09-04T02:31:17.492Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-04T02:31:17.492Z] GC before operation: completed in 15.824 ms, heap usage 14.376 MB -> 5.201 MB. [2025-09-04T02:31:19.444Z] Camera thread performed 122 scans. [2025-09-04T02:31:19.444Z] ====== philosophers (scala) [default], iteration 8 completed (2635.901 ms) ====== [2025-09-04T02:31:19.444Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-04T02:31:19.444Z] GC before operation: completed in 20.182 ms, heap usage 82.431 MB -> 5.201 MB. [2025-09-04T02:31:22.470Z] Camera thread performed 122 scans. [2025-09-04T02:31:22.470Z] ====== philosophers (scala) [default], iteration 9 completed (2741.814 ms) ====== [2025-09-04T02:31:22.470Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-04T02:31:22.470Z] GC before operation: completed in 26.238 ms, heap usage 48.429 MB -> 5.201 MB. [2025-09-04T02:31:25.506Z] Camera thread performed 122 scans. [2025-09-04T02:31:25.506Z] ====== philosophers (scala) [default], iteration 10 completed (2699.219 ms) ====== [2025-09-04T02:31:25.506Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-04T02:31:25.506Z] GC before operation: completed in 20.269 ms, heap usage 32.909 MB -> 5.201 MB. [2025-09-04T02:31:28.513Z] Camera thread performed 122 scans. [2025-09-04T02:31:28.513Z] ====== philosophers (scala) [default], iteration 11 completed (2710.747 ms) ====== [2025-09-04T02:31:28.513Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-04T02:31:28.513Z] GC before operation: completed in 17.304 ms, heap usage 11.943 MB -> 5.202 MB. [2025-09-04T02:31:30.461Z] Camera thread performed 122 scans. [2025-09-04T02:31:30.461Z] ====== philosophers (scala) [default], iteration 12 completed (2602.280 ms) ====== [2025-09-04T02:31:30.461Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-04T02:31:30.461Z] GC before operation: completed in 14.648 ms, heap usage 25.842 MB -> 5.202 MB. [2025-09-04T02:31:33.101Z] Camera thread performed 122 scans. [2025-09-04T02:31:33.101Z] ====== philosophers (scala) [default], iteration 13 completed (2546.867 ms) ====== [2025-09-04T02:31:33.101Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-04T02:31:33.101Z] GC before operation: completed in 15.139 ms, heap usage 35.882 MB -> 5.202 MB. [2025-09-04T02:31:36.104Z] Camera thread performed 122 scans. [2025-09-04T02:31:36.104Z] ====== philosophers (scala) [default], iteration 14 completed (2548.016 ms) ====== [2025-09-04T02:31:36.104Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-04T02:31:36.104Z] GC before operation: completed in 15.404 ms, heap usage 102.348 MB -> 5.203 MB. [2025-09-04T02:31:38.049Z] Camera thread performed 122 scans. [2025-09-04T02:31:38.049Z] ====== philosophers (scala) [default], iteration 15 completed (2614.520 ms) ====== [2025-09-04T02:31:38.049Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-04T02:31:38.049Z] GC before operation: completed in 14.556 ms, heap usage 27.456 MB -> 5.202 MB. [2025-09-04T02:31:41.051Z] Camera thread performed 122 scans. [2025-09-04T02:31:41.051Z] ====== philosophers (scala) [default], iteration 16 completed (2595.599 ms) ====== [2025-09-04T02:31:41.051Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-04T02:31:41.051Z] GC before operation: completed in 14.683 ms, heap usage 75.438 MB -> 5.203 MB. [2025-09-04T02:31:44.062Z] Camera thread performed 122 scans. [2025-09-04T02:31:44.062Z] ====== philosophers (scala) [default], iteration 17 completed (2698.464 ms) ====== [2025-09-04T02:31:44.062Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-04T02:31:44.062Z] GC before operation: completed in 18.987 ms, heap usage 27.832 MB -> 5.203 MB. [2025-09-04T02:31:46.013Z] Camera thread performed 122 scans. [2025-09-04T02:31:46.013Z] ====== philosophers (scala) [default], iteration 18 completed (2649.487 ms) ====== [2025-09-04T02:31:46.013Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-04T02:31:46.013Z] GC before operation: completed in 15.772 ms, heap usage 28.257 MB -> 5.203 MB. [2025-09-04T02:31:49.019Z] Camera thread performed 122 scans. [2025-09-04T02:31:49.019Z] ====== philosophers (scala) [default], iteration 19 completed (2645.350 ms) ====== [2025-09-04T02:31:49.019Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-04T02:31:49.019Z] GC before operation: completed in 14.178 ms, heap usage 39.358 MB -> 5.203 MB. [2025-09-04T02:31:52.022Z] Camera thread performed 122 scans. [2025-09-04T02:31:52.022Z] ====== philosophers (scala) [default], iteration 20 completed (2584.767 ms) ====== [2025-09-04T02:31:52.022Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-04T02:31:52.022Z] GC before operation: completed in 16.381 ms, heap usage 53.246 MB -> 5.203 MB. [2025-09-04T02:31:53.967Z] Camera thread performed 122 scans. [2025-09-04T02:31:53.967Z] ====== philosophers (scala) [default], iteration 21 completed (2604.632 ms) ====== [2025-09-04T02:31:53.967Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-04T02:31:53.967Z] GC before operation: completed in 17.351 ms, heap usage 5.826 MB -> 5.203 MB. [2025-09-04T02:31:56.976Z] Camera thread performed 122 scans. [2025-09-04T02:31:56.976Z] ====== philosophers (scala) [default], iteration 22 completed (2649.513 ms) ====== [2025-09-04T02:31:56.976Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-04T02:31:56.976Z] GC before operation: completed in 17.931 ms, heap usage 35.356 MB -> 5.203 MB. [2025-09-04T02:32:00.006Z] Camera thread performed 122 scans. [2025-09-04T02:32:00.006Z] ====== philosophers (scala) [default], iteration 23 completed (2702.270 ms) ====== [2025-09-04T02:32:00.006Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-04T02:32:00.006Z] GC before operation: completed in 15.669 ms, heap usage 76.929 MB -> 5.203 MB. [2025-09-04T02:32:02.007Z] Camera thread performed 122 scans. [2025-09-04T02:32:02.007Z] ====== philosophers (scala) [default], iteration 24 completed (2769.932 ms) ====== [2025-09-04T02:32:02.007Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-04T02:32:02.957Z] GC before operation: completed in 14.784 ms, heap usage 22.931 MB -> 5.203 MB. [2025-09-04T02:32:04.912Z] Camera thread performed 122 scans. [2025-09-04T02:32:04.912Z] ====== philosophers (scala) [default], iteration 25 completed (2801.940 ms) ====== [2025-09-04T02:32:04.912Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-04T02:32:04.912Z] GC before operation: completed in 24.893 ms, heap usage 15.280 MB -> 5.203 MB. [2025-09-04T02:32:07.925Z] Camera thread performed 122 scans. [2025-09-04T02:32:07.925Z] ====== philosophers (scala) [default], iteration 26 completed (2672.159 ms) ====== [2025-09-04T02:32:07.925Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-04T02:32:07.925Z] GC before operation: completed in 16.659 ms, heap usage 40.392 MB -> 5.203 MB. [2025-09-04T02:32:10.937Z] Camera thread performed 122 scans. [2025-09-04T02:32:10.937Z] ====== philosophers (scala) [default], iteration 27 completed (2748.665 ms) ====== [2025-09-04T02:32:10.937Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-04T02:32:10.937Z] GC before operation: completed in 15.362 ms, heap usage 7.390 MB -> 5.203 MB. [2025-09-04T02:32:13.949Z] Camera thread performed 122 scans. [2025-09-04T02:32:13.949Z] ====== philosophers (scala) [default], iteration 28 completed (2704.544 ms) ====== [2025-09-04T02:32:13.949Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-04T02:32:13.949Z] GC before operation: completed in 17.111 ms, heap usage 54.861 MB -> 5.203 MB. [2025-09-04T02:32:15.896Z] Camera thread performed 122 scans. [2025-09-04T02:32:15.896Z] ====== philosophers (scala) [default], iteration 29 completed (2718.862 ms) ====== [2025-09-04T02:32:16.851Z] ----------------------------------- [2025-09-04T02:32:16.851Z] renaissance-philosophers_0_PASSED [2025-09-04T02:32:16.851Z] ----------------------------------- [2025-09-04T02:32:16.851Z] [2025-09-04T02:32:16.851Z] TEST TEARDOWN: [2025-09-04T02:32:16.851Z] Nothing to be done for teardown. [2025-09-04T02:32:16.851Z] renaissance-philosophers_0 Finish Time: Thu Sep 4 02:32:16 2025 Epoch Time (ms): 1756953136058