[2025-12-24T23:17:37.208Z] Running test renaissance-philosophers_0 ... [2025-12-24T23:17:37.208Z] =============================================== [2025-12-24T23:17:37.208Z] renaissance-philosophers_0 Start Time: Wed Dec 24 23:17:36 2025 Epoch Time (ms): 1766618256227 [2025-12-24T23:17:37.208Z] variation: NoOptions [2025-12-24T23:17:37.208Z] JVM_OPTIONS: [2025-12-24T23:17:37.208Z] { \ [2025-12-24T23:17:37.208Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T23:17:37.208Z] echo "Nothing to be done for setup."; \ [2025-12-24T23:17:37.208Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666142414256/renaissance-philosophers_0"; \ [2025-12-24T23:17:37.208Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666142414256/renaissance-philosophers_0"; \ [2025-12-24T23:17:37.208Z] echo ""; echo "TESTING:"; \ [2025-12-24T23:17:37.208Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666142414256/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-24T23:17:37.208Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666142414256/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T23:17:37.208Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T23:17:37.208Z] echo "Nothing to be done for teardown."; \ [2025-12-24T23:17:37.208Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666142414256/TestTargetResult"; [2025-12-24T23:17:37.208Z] [2025-12-24T23:17:37.208Z] TEST SETUP: [2025-12-24T23:17:37.208Z] Nothing to be done for setup. [2025-12-24T23:17:37.208Z] [2025-12-24T23:17:37.208Z] TESTING: [2025-12-24T23:17:38.167Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-24T23:17:38.167Z] GC before operation: completed in 34.899 ms, heap usage 14.715 MB -> 4.900 MB. [2025-12-24T23:17:44.916Z] Camera thread performed 122 scans. [2025-12-24T23:17:44.916Z] ====== philosophers (scala) [default], iteration 0 completed (5558.078 ms) ====== [2025-12-24T23:17:44.916Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-24T23:17:44.916Z] GC before operation: completed in 36.375 ms, heap usage 40.417 MB -> 5.274 MB. [2025-12-24T23:17:47.945Z] Camera thread performed 122 scans. [2025-12-24T23:17:47.945Z] ====== philosophers (scala) [default], iteration 1 completed (3287.825 ms) ====== [2025-12-24T23:17:47.945Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-24T23:17:47.945Z] GC before operation: completed in 26.549 ms, heap usage 40.105 MB -> 5.274 MB. [2025-12-24T23:17:52.115Z] Camera thread performed 122 scans. [2025-12-24T23:17:52.115Z] ====== philosophers (scala) [default], iteration 2 completed (4462.265 ms) ====== [2025-12-24T23:17:52.115Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-24T23:17:52.115Z] GC before operation: completed in 27.551 ms, heap usage 35.924 MB -> 5.275 MB. [2025-12-24T23:17:56.288Z] Camera thread performed 122 scans. [2025-12-24T23:17:56.288Z] ====== philosophers (scala) [default], iteration 3 completed (4467.282 ms) ====== [2025-12-24T23:17:56.288Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-24T23:17:56.288Z] GC before operation: completed in 24.089 ms, heap usage 22.164 MB -> 5.275 MB. [2025-12-24T23:18:01.681Z] Camera thread performed 122 scans. [2025-12-24T23:18:01.681Z] ====== philosophers (scala) [default], iteration 4 completed (4902.333 ms) ====== [2025-12-24T23:18:01.681Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-24T23:18:01.681Z] GC before operation: completed in 20.498 ms, heap usage 7.802 MB -> 5.276 MB. [2025-12-24T23:18:05.852Z] Camera thread performed 122 scans. [2025-12-24T23:18:05.852Z] ====== philosophers (scala) [default], iteration 5 completed (4105.750 ms) ====== [2025-12-24T23:18:05.852Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-24T23:18:05.852Z] GC before operation: completed in 28.317 ms, heap usage 37.087 MB -> 5.276 MB. [2025-12-24T23:18:10.030Z] Camera thread performed 122 scans. [2025-12-24T23:18:10.031Z] ====== philosophers (scala) [default], iteration 6 completed (4915.723 ms) ====== [2025-12-24T23:18:10.031Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-24T23:18:10.997Z] GC before operation: completed in 31.831 ms, heap usage 16.348 MB -> 5.277 MB. [2025-12-24T23:18:15.202Z] Camera thread performed 122 scans. [2025-12-24T23:18:15.202Z] ====== philosophers (scala) [default], iteration 7 completed (4164.779 ms) ====== [2025-12-24T23:18:15.202Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-24T23:18:15.202Z] GC before operation: completed in 25.048 ms, heap usage 23.469 MB -> 5.277 MB. [2025-12-24T23:18:19.479Z] Camera thread performed 122 scans. [2025-12-24T23:18:19.479Z] ====== philosophers (scala) [default], iteration 8 completed (4598.814 ms) ====== [2025-12-24T23:18:19.479Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-24T23:18:19.479Z] GC before operation: completed in 23.652 ms, heap usage 52.328 MB -> 5.277 MB. [2025-12-24T23:18:24.400Z] Camera thread performed 122 scans. [2025-12-24T23:18:24.400Z] ====== philosophers (scala) [default], iteration 9 completed (4730.139 ms) ====== [2025-12-24T23:18:24.400Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-24T23:18:24.400Z] GC before operation: completed in 20.583 ms, heap usage 38.499 MB -> 5.277 MB. [2025-12-24T23:18:28.568Z] Camera thread performed 122 scans. [2025-12-24T23:18:28.568Z] ====== philosophers (scala) [default], iteration 10 completed (4143.783 ms) ====== [2025-12-24T23:18:28.568Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-24T23:18:28.568Z] GC before operation: completed in 22.767 ms, heap usage 37.886 MB -> 5.277 MB. [2025-12-24T23:18:33.981Z] Camera thread performed 122 scans. [2025-12-24T23:18:33.981Z] ====== philosophers (scala) [default], iteration 11 completed (4956.850 ms) ====== [2025-12-24T23:18:33.981Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-24T23:18:33.981Z] GC before operation: completed in 22.587 ms, heap usage 25.929 MB -> 5.278 MB. [2025-12-24T23:18:38.163Z] Camera thread performed 122 scans. [2025-12-24T23:18:38.163Z] ====== philosophers (scala) [default], iteration 12 completed (4334.914 ms) ====== [2025-12-24T23:18:38.163Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-24T23:18:38.163Z] GC before operation: completed in 23.986 ms, heap usage 37.782 MB -> 5.278 MB. [2025-12-24T23:18:41.197Z] Camera thread performed 122 scans. [2025-12-24T23:18:41.197Z] ====== philosophers (scala) [default], iteration 13 completed (3846.719 ms) ====== [2025-12-24T23:18:41.197Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-24T23:18:41.197Z] GC before operation: completed in 22.673 ms, heap usage 24.381 MB -> 5.278 MB. [2025-12-24T23:18:45.403Z] Camera thread performed 122 scans. [2025-12-24T23:18:45.403Z] ====== philosophers (scala) [default], iteration 14 completed (4388.694 ms) ====== [2025-12-24T23:18:45.403Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-24T23:18:46.358Z] GC before operation: completed in 22.998 ms, heap usage 6.065 MB -> 5.278 MB. [2025-12-24T23:18:50.531Z] Camera thread performed 122 scans. [2025-12-24T23:18:50.531Z] ====== philosophers (scala) [default], iteration 15 completed (4144.920 ms) ====== [2025-12-24T23:18:50.531Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-24T23:18:50.531Z] GC before operation: completed in 24.540 ms, heap usage 50.249 MB -> 5.278 MB. [2025-12-24T23:18:54.716Z] Camera thread performed 122 scans. [2025-12-24T23:18:54.716Z] ====== philosophers (scala) [default], iteration 16 completed (4806.138 ms) ====== [2025-12-24T23:18:54.716Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-24T23:18:54.716Z] GC before operation: completed in 21.443 ms, heap usage 31.798 MB -> 5.278 MB. [2025-12-24T23:18:58.922Z] Camera thread performed 122 scans. [2025-12-24T23:18:58.922Z] ====== philosophers (scala) [default], iteration 17 completed (4335.428 ms) ====== [2025-12-24T23:18:58.922Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-24T23:18:58.922Z] GC before operation: completed in 20.560 ms, heap usage 30.834 MB -> 5.278 MB. [2025-12-24T23:19:03.123Z] Camera thread performed 122 scans. [2025-12-24T23:19:03.123Z] ====== philosophers (scala) [default], iteration 18 completed (3823.883 ms) ====== [2025-12-24T23:19:03.123Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-24T23:19:03.123Z] GC before operation: completed in 21.589 ms, heap usage 7.379 MB -> 5.279 MB. [2025-12-24T23:19:07.306Z] Camera thread performed 122 scans. [2025-12-24T23:19:07.306Z] ====== philosophers (scala) [default], iteration 19 completed (4409.936 ms) ====== [2025-12-24T23:19:07.306Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-24T23:19:07.306Z] GC before operation: completed in 20.649 ms, heap usage 37.066 MB -> 5.279 MB. [2025-12-24T23:19:11.476Z] Camera thread performed 122 scans. [2025-12-24T23:19:11.476Z] ====== philosophers (scala) [default], iteration 20 completed (3816.047 ms) ====== [2025-12-24T23:19:11.476Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-24T23:19:11.476Z] GC before operation: completed in 24.569 ms, heap usage 36.915 MB -> 5.279 MB. [2025-12-24T23:19:15.655Z] Camera thread performed 122 scans. [2025-12-24T23:19:15.655Z] ====== philosophers (scala) [default], iteration 21 completed (4209.497 ms) ====== [2025-12-24T23:19:15.655Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-24T23:19:15.655Z] GC before operation: completed in 22.372 ms, heap usage 10.378 MB -> 5.279 MB. [2025-12-24T23:19:19.410Z] Camera thread performed 122 scans. [2025-12-24T23:19:19.410Z] ====== philosophers (scala) [default], iteration 22 completed (3842.901 ms) ====== [2025-12-24T23:19:19.410Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-24T23:19:19.410Z] GC before operation: completed in 24.732 ms, heap usage 30.835 MB -> 5.279 MB. [2025-12-24T23:19:23.619Z] Camera thread performed 122 scans. [2025-12-24T23:19:23.619Z] ====== philosophers (scala) [default], iteration 23 completed (4521.498 ms) ====== [2025-12-24T23:19:23.619Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-24T23:19:23.619Z] GC before operation: completed in 24.693 ms, heap usage 12.235 MB -> 5.279 MB. [2025-12-24T23:19:27.858Z] Camera thread performed 122 scans. [2025-12-24T23:19:27.858Z] ====== philosophers (scala) [default], iteration 24 completed (4063.794 ms) ====== [2025-12-24T23:19:27.858Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-24T23:19:27.858Z] GC before operation: completed in 27.625 ms, heap usage 19.855 MB -> 5.279 MB. [2025-12-24T23:19:33.337Z] Camera thread performed 122 scans. [2025-12-24T23:19:33.337Z] ====== philosophers (scala) [default], iteration 25 completed (4666.485 ms) ====== [2025-12-24T23:19:33.337Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-24T23:19:33.337Z] GC before operation: completed in 23.614 ms, heap usage 30.421 MB -> 5.279 MB. [2025-12-24T23:19:37.546Z] Camera thread performed 122 scans. [2025-12-24T23:19:37.546Z] ====== philosophers (scala) [default], iteration 26 completed (4759.724 ms) ====== [2025-12-24T23:19:37.546Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-24T23:19:37.546Z] GC before operation: completed in 24.922 ms, heap usage 38.374 MB -> 5.279 MB. [2025-12-24T23:19:41.758Z] Camera thread performed 122 scans. [2025-12-24T23:19:41.758Z] ====== philosophers (scala) [default], iteration 27 completed (4006.564 ms) ====== [2025-12-24T23:19:41.758Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-24T23:19:41.758Z] GC before operation: completed in 24.722 ms, heap usage 37.226 MB -> 5.279 MB. [2025-12-24T23:19:47.231Z] Camera thread performed 122 scans. [2025-12-24T23:19:47.231Z] ====== philosophers (scala) [default], iteration 28 completed (4946.858 ms) ====== [2025-12-24T23:19:47.231Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-24T23:19:47.231Z] GC before operation: completed in 24.101 ms, heap usage 28.224 MB -> 5.279 MB. [2025-12-24T23:19:51.583Z] Camera thread performed 122 scans. [2025-12-24T23:19:51.583Z] ====== philosophers (scala) [default], iteration 29 completed (4663.662 ms) ====== [2025-12-24T23:19:51.583Z] ----------------------------------- [2025-12-24T23:19:51.583Z] renaissance-philosophers_0_PASSED [2025-12-24T23:19:51.583Z] ----------------------------------- [2025-12-24T23:19:51.583Z] [2025-12-24T23:19:51.583Z] TEST TEARDOWN: [2025-12-24T23:19:51.583Z] Nothing to be done for teardown. [2025-12-24T23:19:51.583Z] renaissance-philosophers_0 Finish Time: Wed Dec 24 23:19:51 2025 Epoch Time (ms): 1766618391147