[2026-01-14T02:48:28.782Z] Running test renaissance-philosophers_0 ... [2026-01-14T02:48:28.782Z] =============================================== [2026-01-14T02:48:28.782Z] renaissance-philosophers_0 Start Time: Wed Jan 14 02:48:27 2026 Epoch Time (ms): 1768358907918 [2026-01-14T02:48:28.782Z] variation: NoOptions [2026-01-14T02:48:28.782Z] JVM_OPTIONS: [2026-01-14T02:48:28.782Z] { \ [2026-01-14T02:48:28.782Z] echo ""; echo "TEST SETUP:"; \ [2026-01-14T02:48:28.782Z] echo "Nothing to be done for setup."; \ [2026-01-14T02:48:28.782Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17683556038882/renaissance-philosophers_0"; \ [2026-01-14T02:48:28.782Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17683556038882/renaissance-philosophers_0"; \ [2026-01-14T02:48:28.782Z] echo ""; echo "TESTING:"; \ [2026-01-14T02:48:28.782Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17683556038882/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2026-01-14T02:48:28.782Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17683556038882/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-14T02:48:28.782Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-14T02:48:28.782Z] echo "Nothing to be done for teardown."; \ [2026-01-14T02:48:28.782Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17683556038882/TestTargetResult"; [2026-01-14T02:48:28.782Z] [2026-01-14T02:48:28.782Z] TEST SETUP: [2026-01-14T02:48:28.782Z] Nothing to be done for setup. [2026-01-14T02:48:28.782Z] [2026-01-14T02:48:28.782Z] TESTING: [2026-01-14T02:48:32.339Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-01-14T02:48:32.339Z] GC before operation: completed in 46.434 ms, heap usage 6.999 MB -> 4.281 MB. [2026-01-14T02:48:48.781Z] Camera thread performed 122 scans. [2026-01-14T02:48:48.781Z] ====== philosophers (scala) [default], iteration 0 completed (15543.631 ms) ====== [2026-01-14T02:48:48.781Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-01-14T02:48:48.781Z] GC before operation: completed in 21.977 ms, heap usage 132.064 MB -> 4.715 MB. [2026-01-14T02:49:00.587Z] Camera thread performed 122 scans. [2026-01-14T02:49:00.587Z] ====== philosophers (scala) [default], iteration 1 completed (11968.749 ms) ====== [2026-01-14T02:49:00.587Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-01-14T02:49:00.587Z] GC before operation: completed in 18.525 ms, heap usage 89.427 MB -> 4.716 MB. [2026-01-14T02:49:12.393Z] Camera thread performed 122 scans. [2026-01-14T02:49:12.393Z] ====== philosophers (scala) [default], iteration 2 completed (11841.325 ms) ====== [2026-01-14T02:49:12.393Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-01-14T02:49:12.393Z] GC before operation: completed in 18.577 ms, heap usage 103.729 MB -> 4.718 MB. [2026-01-14T02:49:22.392Z] Camera thread performed 122 scans. [2026-01-14T02:49:22.392Z] ====== philosophers (scala) [default], iteration 3 completed (10996.849 ms) ====== [2026-01-14T02:49:22.392Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-01-14T02:49:22.392Z] GC before operation: completed in 16.750 ms, heap usage 15.509 MB -> 4.719 MB. [2026-01-14T02:49:30.771Z] Camera thread performed 122 scans. [2026-01-14T02:49:30.771Z] ====== philosophers (scala) [default], iteration 4 completed (7496.350 ms) ====== [2026-01-14T02:49:30.771Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-01-14T02:49:30.771Z] GC before operation: completed in 13.584 ms, heap usage 70.062 MB -> 4.720 MB. [2026-01-14T02:49:37.719Z] Camera thread performed 122 scans. [2026-01-14T02:49:37.719Z] ====== philosophers (scala) [default], iteration 5 completed (7266.702 ms) ====== [2026-01-14T02:49:37.719Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-01-14T02:49:37.719Z] GC before operation: completed in 12.389 ms, heap usage 132.731 MB -> 4.720 MB. [2026-01-14T02:49:46.099Z] Camera thread performed 122 scans. [2026-01-14T02:49:46.099Z] ====== philosophers (scala) [default], iteration 6 completed (8584.246 ms) ====== [2026-01-14T02:49:46.099Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-01-14T02:49:46.099Z] GC before operation: completed in 16.214 ms, heap usage 26.296 MB -> 4.720 MB. [2026-01-14T02:49:57.912Z] Camera thread performed 122 scans. [2026-01-14T02:49:57.912Z] ====== philosophers (scala) [default], iteration 7 completed (11336.655 ms) ====== [2026-01-14T02:49:57.912Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-01-14T02:49:57.912Z] GC before operation: completed in 18.994 ms, heap usage 128.532 MB -> 4.720 MB. [2026-01-14T02:50:07.780Z] Camera thread performed 122 scans. [2026-01-14T02:50:07.780Z] ====== philosophers (scala) [default], iteration 8 completed (10356.408 ms) ====== [2026-01-14T02:50:07.780Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-01-14T02:50:07.780Z] GC before operation: completed in 15.108 ms, heap usage 26.467 MB -> 4.720 MB. [2026-01-14T02:50:17.774Z] Camera thread performed 122 scans. [2026-01-14T02:50:17.774Z] ====== philosophers (scala) [default], iteration 9 completed (10226.084 ms) ====== [2026-01-14T02:50:17.775Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-01-14T02:50:17.775Z] GC before operation: completed in 15.663 ms, heap usage 148.019 MB -> 4.720 MB. [2026-01-14T02:50:26.156Z] Camera thread performed 122 scans. [2026-01-14T02:50:26.156Z] ====== philosophers (scala) [default], iteration 10 completed (8231.400 ms) ====== [2026-01-14T02:50:26.156Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-01-14T02:50:26.156Z] GC before operation: completed in 14.798 ms, heap usage 84.776 MB -> 4.720 MB. [2026-01-14T02:50:33.108Z] Camera thread performed 122 scans. [2026-01-14T02:50:33.108Z] ====== philosophers (scala) [default], iteration 11 completed (7583.705 ms) ====== [2026-01-14T02:50:33.108Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-01-14T02:50:33.890Z] GC before operation: completed in 15.382 ms, heap usage 161.330 MB -> 4.720 MB. [2026-01-14T02:50:42.261Z] Camera thread performed 122 scans. [2026-01-14T02:50:42.261Z] ====== philosophers (scala) [default], iteration 12 completed (7867.825 ms) ====== [2026-01-14T02:50:42.261Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-01-14T02:50:42.261Z] GC before operation: completed in 15.276 ms, heap usage 37.198 MB -> 4.720 MB. [2026-01-14T02:50:49.237Z] Camera thread performed 122 scans. [2026-01-14T02:50:49.237Z] ====== philosophers (scala) [default], iteration 13 completed (7011.667 ms) ====== [2026-01-14T02:50:49.237Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-01-14T02:50:49.237Z] GC before operation: completed in 13.232 ms, heap usage 138.373 MB -> 4.720 MB. [2026-01-14T02:50:56.199Z] Camera thread performed 122 scans. [2026-01-14T02:50:56.199Z] ====== philosophers (scala) [default], iteration 14 completed (7430.240 ms) ====== [2026-01-14T02:50:56.199Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-01-14T02:50:56.199Z] GC before operation: completed in 12.428 ms, heap usage 156.922 MB -> 4.720 MB. [2026-01-14T02:51:03.144Z] Camera thread performed 122 scans. [2026-01-14T02:51:03.144Z] ====== philosophers (scala) [default], iteration 15 completed (7432.510 ms) ====== [2026-01-14T02:51:03.144Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-01-14T02:51:03.144Z] GC before operation: completed in 13.002 ms, heap usage 110.917 MB -> 4.721 MB. [2026-01-14T02:51:11.514Z] Camera thread performed 122 scans. [2026-01-14T02:51:11.514Z] ====== philosophers (scala) [default], iteration 16 completed (7830.093 ms) ====== [2026-01-14T02:51:11.514Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-01-14T02:51:11.514Z] GC before operation: completed in 13.730 ms, heap usage 47.235 MB -> 4.721 MB. [2026-01-14T02:51:18.471Z] Camera thread performed 122 scans. [2026-01-14T02:51:18.471Z] ====== philosophers (scala) [default], iteration 17 completed (7386.241 ms) ====== [2026-01-14T02:51:18.471Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-01-14T02:51:18.471Z] GC before operation: completed in 14.724 ms, heap usage 112.513 MB -> 4.721 MB. [2026-01-14T02:51:26.846Z] Camera thread performed 122 scans. [2026-01-14T02:51:26.846Z] ====== philosophers (scala) [default], iteration 18 completed (8037.572 ms) ====== [2026-01-14T02:51:26.846Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-01-14T02:51:26.846Z] GC before operation: completed in 14.598 ms, heap usage 16.060 MB -> 4.721 MB. [2026-01-14T02:51:35.226Z] Camera thread performed 122 scans. [2026-01-14T02:51:35.226Z] ====== philosophers (scala) [default], iteration 19 completed (8372.288 ms) ====== [2026-01-14T02:51:35.226Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-01-14T02:51:35.226Z] GC before operation: completed in 15.155 ms, heap usage 106.115 MB -> 4.721 MB. [2026-01-14T02:51:43.608Z] Camera thread performed 122 scans. [2026-01-14T02:51:43.608Z] ====== philosophers (scala) [default], iteration 20 completed (8520.081 ms) ====== [2026-01-14T02:51:43.608Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-01-14T02:51:43.608Z] GC before operation: completed in 14.695 ms, heap usage 93.506 MB -> 4.721 MB. [2026-01-14T02:51:52.170Z] Camera thread performed 122 scans. [2026-01-14T02:51:52.170Z] ====== philosophers (scala) [default], iteration 21 completed (7617.204 ms) ====== [2026-01-14T02:51:52.170Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-01-14T02:51:52.170Z] GC before operation: completed in 14.028 ms, heap usage 83.474 MB -> 4.721 MB. [2026-01-14T02:51:59.120Z] Camera thread performed 122 scans. [2026-01-14T02:51:59.120Z] ====== philosophers (scala) [default], iteration 22 completed (7751.570 ms) ====== [2026-01-14T02:51:59.120Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-01-14T02:51:59.120Z] GC before operation: completed in 13.683 ms, heap usage 58.615 MB -> 4.721 MB. [2026-01-14T02:52:06.069Z] Camera thread performed 122 scans. [2026-01-14T02:52:06.069Z] ====== philosophers (scala) [default], iteration 23 completed (7120.146 ms) ====== [2026-01-14T02:52:06.069Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-01-14T02:52:06.069Z] GC before operation: completed in 13.073 ms, heap usage 77.950 MB -> 4.721 MB. [2026-01-14T02:52:14.445Z] Camera thread performed 122 scans. [2026-01-14T02:52:14.445Z] ====== philosophers (scala) [default], iteration 24 completed (7785.381 ms) ====== [2026-01-14T02:52:14.445Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-01-14T02:52:14.445Z] GC before operation: completed in 12.964 ms, heap usage 117.249 MB -> 4.722 MB. [2026-01-14T02:52:21.395Z] Camera thread performed 122 scans. [2026-01-14T02:52:21.395Z] ====== philosophers (scala) [default], iteration 25 completed (7205.901 ms) ====== [2026-01-14T02:52:21.395Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-01-14T02:52:21.395Z] GC before operation: completed in 17.661 ms, heap usage 94.293 MB -> 4.722 MB. [2026-01-14T02:52:29.778Z] Camera thread performed 122 scans. [2026-01-14T02:52:29.778Z] ====== philosophers (scala) [default], iteration 26 completed (8388.332 ms) ====== [2026-01-14T02:52:29.778Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-01-14T02:52:29.778Z] GC before operation: completed in 14.467 ms, heap usage 49.757 MB -> 4.722 MB. [2026-01-14T02:52:39.770Z] Camera thread performed 122 scans. [2026-01-14T02:52:39.770Z] ====== philosophers (scala) [default], iteration 27 completed (9100.160 ms) ====== [2026-01-14T02:52:39.770Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-01-14T02:52:39.770Z] GC before operation: completed in 16.519 ms, heap usage 30.933 MB -> 4.722 MB. [2026-01-14T02:52:49.902Z] Camera thread performed 122 scans. [2026-01-14T02:52:49.902Z] ====== philosophers (scala) [default], iteration 28 completed (11320.458 ms) ====== [2026-01-14T02:52:49.902Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-01-14T02:52:49.902Z] GC before operation: completed in 17.569 ms, heap usage 33.401 MB -> 4.722 MB. [2026-01-14T02:53:01.714Z] Camera thread performed 122 scans. [2026-01-14T02:53:01.714Z] ====== philosophers (scala) [default], iteration 29 completed (12129.164 ms) ====== [2026-01-14T02:53:02.494Z] ----------------------------------- [2026-01-14T02:53:02.494Z] renaissance-philosophers_0_PASSED [2026-01-14T02:53:02.494Z] ----------------------------------- [2026-01-14T02:53:02.494Z] [2026-01-14T02:53:02.494Z] TEST TEARDOWN: [2026-01-14T02:53:02.494Z] Nothing to be done for teardown. [2026-01-14T02:53:02.494Z] renaissance-philosophers_0 Finish Time: Wed Jan 14 02:53:02 2026 Epoch Time (ms): 1768359182299