[2025-06-30T17:53:16.907Z] Running test renaissance-philosophers_0 ... [2025-06-30T17:53:16.907Z] =============================================== [2025-06-30T17:53:16.907Z] renaissance-philosophers_0 Start Time: Mon Jun 30 17:53:16 2025 Epoch Time (ms): 1751305996050 [2025-06-30T17:53:16.907Z] variation: NoOptions [2025-06-30T17:53:16.907Z] JVM_OPTIONS: [2025-06-30T17:53:16.907Z] { \ [2025-06-30T17:53:16.907Z] echo ""; echo "TEST SETUP:"; \ [2025-06-30T17:53:16.907Z] echo "Nothing to be done for setup."; \ [2025-06-30T17:53:16.907Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513047269342/renaissance-philosophers_0"; \ [2025-06-30T17:53:16.907Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513047269342/renaissance-philosophers_0"; \ [2025-06-30T17:53:16.907Z] echo ""; echo "TESTING:"; \ [2025-06-30T17:53:16.907Z] "/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_17513047269342/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-30T17:53:16.908Z] 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_17513047269342/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-30T17:53:16.908Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-30T17:53:16.908Z] echo "Nothing to be done for teardown."; \ [2025-06-30T17:53:16.908Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513047269342/TestTargetResult"; [2025-06-30T17:53:16.908Z] [2025-06-30T17:53:16.908Z] TEST SETUP: [2025-06-30T17:53:16.908Z] Nothing to be done for setup. [2025-06-30T17:53:16.908Z] [2025-06-30T17:53:16.908Z] TESTING: [2025-06-30T17:53:17.833Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-30T17:53:17.833Z] GC before operation: completed in 12.479 ms, heap usage 7.223 MB -> 4.872 MB. [2025-06-30T17:53:21.900Z] Camera thread performed 122 scans. [2025-06-30T17:53:21.900Z] ====== philosophers (scala) [default], iteration 0 completed (4681.691 ms) ====== [2025-06-30T17:53:21.900Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-30T17:53:21.900Z] GC before operation: completed in 20.212 ms, heap usage 52.704 MB -> 5.248 MB. [2025-06-30T17:53:24.837Z] Camera thread performed 122 scans. [2025-06-30T17:53:24.837Z] ====== philosophers (scala) [default], iteration 1 completed (2973.169 ms) ====== [2025-06-30T17:53:24.837Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-30T17:53:24.837Z] GC before operation: completed in 14.808 ms, heap usage 20.906 MB -> 5.249 MB. [2025-06-30T17:53:28.883Z] Camera thread performed 122 scans. [2025-06-30T17:53:28.883Z] ====== philosophers (scala) [default], iteration 2 completed (4223.617 ms) ====== [2025-06-30T17:53:28.883Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-30T17:53:29.809Z] GC before operation: completed in 13.710 ms, heap usage 65.455 MB -> 5.250 MB. [2025-06-30T17:53:33.987Z] Camera thread performed 122 scans. [2025-06-30T17:53:33.987Z] ====== philosophers (scala) [default], iteration 3 completed (4640.788 ms) ====== [2025-06-30T17:53:33.987Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-30T17:53:33.987Z] GC before operation: completed in 13.821 ms, heap usage 55.396 MB -> 5.251 MB. [2025-06-30T17:53:38.032Z] Camera thread performed 122 scans. [2025-06-30T17:53:38.032Z] ====== philosophers (scala) [default], iteration 4 completed (3934.805 ms) ====== [2025-06-30T17:53:38.032Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-30T17:53:38.032Z] GC before operation: completed in 15.822 ms, heap usage 102.526 MB -> 5.251 MB. [2025-06-30T17:53:42.076Z] Camera thread performed 122 scans. [2025-06-30T17:53:42.076Z] ====== philosophers (scala) [default], iteration 5 completed (3949.276 ms) ====== [2025-06-30T17:53:42.076Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-30T17:53:42.076Z] GC before operation: completed in 16.065 ms, heap usage 71.708 MB -> 5.251 MB. [2025-06-30T17:53:46.136Z] Camera thread performed 122 scans. [2025-06-30T17:53:46.136Z] ====== philosophers (scala) [default], iteration 6 completed (4474.544 ms) ====== [2025-06-30T17:53:46.136Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-30T17:53:46.136Z] GC before operation: completed in 16.479 ms, heap usage 14.415 MB -> 5.252 MB. [2025-06-30T17:53:52.691Z] Camera thread performed 122 scans. [2025-06-30T17:53:52.691Z] ====== philosophers (scala) [default], iteration 7 completed (5571.365 ms) ====== [2025-06-30T17:53:52.691Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-30T17:53:52.691Z] GC before operation: completed in 14.352 ms, heap usage 87.104 MB -> 5.252 MB. [2025-06-30T17:53:55.628Z] Camera thread performed 122 scans. [2025-06-30T17:53:55.628Z] ====== philosophers (scala) [default], iteration 8 completed (3883.417 ms) ====== [2025-06-30T17:53:55.628Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-30T17:53:55.628Z] GC before operation: completed in 14.914 ms, heap usage 39.371 MB -> 5.252 MB. [2025-06-30T17:53:59.682Z] Camera thread performed 122 scans. [2025-06-30T17:53:59.682Z] ====== philosophers (scala) [default], iteration 9 completed (3844.248 ms) ====== [2025-06-30T17:53:59.683Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-30T17:53:59.683Z] GC before operation: completed in 17.459 ms, heap usage 94.975 MB -> 5.252 MB. [2025-06-30T17:54:03.725Z] Camera thread performed 122 scans. [2025-06-30T17:54:03.725Z] ====== philosophers (scala) [default], iteration 10 completed (3922.238 ms) ====== [2025-06-30T17:54:03.725Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-30T17:54:03.725Z] GC before operation: completed in 14.620 ms, heap usage 85.061 MB -> 5.252 MB. [2025-06-30T17:54:07.773Z] Camera thread performed 122 scans. [2025-06-30T17:54:07.773Z] ====== philosophers (scala) [default], iteration 11 completed (4008.991 ms) ====== [2025-06-30T17:54:07.773Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-30T17:54:07.773Z] GC before operation: completed in 14.124 ms, heap usage 94.769 MB -> 5.252 MB. [2025-06-30T17:54:11.818Z] Camera thread performed 122 scans. [2025-06-30T17:54:11.818Z] ====== philosophers (scala) [default], iteration 12 completed (4057.132 ms) ====== [2025-06-30T17:54:11.818Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-30T17:54:11.818Z] GC before operation: completed in 14.512 ms, heap usage 73.625 MB -> 5.252 MB. [2025-06-30T17:54:15.865Z] Camera thread performed 122 scans. [2025-06-30T17:54:15.865Z] ====== philosophers (scala) [default], iteration 13 completed (4255.519 ms) ====== [2025-06-30T17:54:15.865Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-30T17:54:15.865Z] GC before operation: completed in 16.864 ms, heap usage 96.813 MB -> 5.252 MB. [2025-06-30T17:54:22.411Z] Camera thread performed 122 scans. [2025-06-30T17:54:22.411Z] ====== philosophers (scala) [default], iteration 14 completed (5925.255 ms) ====== [2025-06-30T17:54:22.411Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-30T17:54:22.411Z] GC before operation: completed in 14.313 ms, heap usage 59.042 MB -> 5.253 MB. [2025-06-30T17:54:27.309Z] Camera thread performed 122 scans. [2025-06-30T17:54:27.309Z] ====== philosophers (scala) [default], iteration 15 completed (5221.080 ms) ====== [2025-06-30T17:54:27.309Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-30T17:54:27.309Z] GC before operation: completed in 18.369 ms, heap usage 80.587 MB -> 5.252 MB. [2025-06-30T17:54:32.574Z] Camera thread performed 122 scans. [2025-06-30T17:54:32.574Z] ====== philosophers (scala) [default], iteration 16 completed (5421.851 ms) ====== [2025-06-30T17:54:32.574Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-30T17:54:32.574Z] GC before operation: completed in 15.992 ms, heap usage 69.328 MB -> 5.253 MB. [2025-06-30T17:54:37.840Z] Camera thread performed 122 scans. [2025-06-30T17:54:37.841Z] ====== philosophers (scala) [default], iteration 17 completed (4924.701 ms) ====== [2025-06-30T17:54:37.841Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-30T17:54:37.841Z] GC before operation: completed in 12.939 ms, heap usage 84.692 MB -> 5.253 MB. [2025-06-30T17:54:44.405Z] Camera thread performed 122 scans. [2025-06-30T17:54:44.405Z] ====== philosophers (scala) [default], iteration 18 completed (5952.235 ms) ====== [2025-06-30T17:54:44.405Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-30T17:54:44.405Z] GC before operation: completed in 12.905 ms, heap usage 86.154 MB -> 5.253 MB. [2025-06-30T17:54:49.668Z] Camera thread performed 122 scans. [2025-06-30T17:54:49.669Z] ====== philosophers (scala) [default], iteration 19 completed (5456.548 ms) ====== [2025-06-30T17:54:49.669Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-30T17:54:49.669Z] GC before operation: completed in 13.991 ms, heap usage 15.487 MB -> 5.253 MB. [2025-06-30T17:54:53.715Z] Camera thread performed 122 scans. [2025-06-30T17:54:53.715Z] ====== philosophers (scala) [default], iteration 20 completed (4266.807 ms) ====== [2025-06-30T17:54:53.715Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-30T17:54:53.715Z] GC before operation: completed in 14.538 ms, heap usage 52.150 MB -> 5.253 MB. [2025-06-30T17:54:57.762Z] Camera thread performed 122 scans. [2025-06-30T17:54:57.762Z] ====== philosophers (scala) [default], iteration 21 completed (3814.686 ms) ====== [2025-06-30T17:54:57.762Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-30T17:54:57.762Z] GC before operation: completed in 14.834 ms, heap usage 33.595 MB -> 5.253 MB. [2025-06-30T17:55:00.701Z] Camera thread performed 122 scans. [2025-06-30T17:55:00.701Z] ====== philosophers (scala) [default], iteration 22 completed (3875.984 ms) ====== [2025-06-30T17:55:00.701Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-30T17:55:00.701Z] GC before operation: completed in 14.551 ms, heap usage 84.493 MB -> 5.253 MB. [2025-06-30T17:55:04.750Z] Camera thread performed 122 scans. [2025-06-30T17:55:04.750Z] ====== philosophers (scala) [default], iteration 23 completed (3960.556 ms) ====== [2025-06-30T17:55:04.750Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-30T17:55:04.750Z] GC before operation: completed in 13.342 ms, heap usage 32.295 MB -> 5.253 MB. [2025-06-30T17:55:08.799Z] Camera thread performed 122 scans. [2025-06-30T17:55:08.799Z] ====== philosophers (scala) [default], iteration 24 completed (3896.451 ms) ====== [2025-06-30T17:55:08.799Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-30T17:55:08.799Z] GC before operation: completed in 14.012 ms, heap usage 25.169 MB -> 5.253 MB. [2025-06-30T17:55:15.336Z] Camera thread performed 122 scans. [2025-06-30T17:55:15.336Z] ====== philosophers (scala) [default], iteration 25 completed (5590.199 ms) ====== [2025-06-30T17:55:15.336Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-30T17:55:15.336Z] GC before operation: completed in 17.619 ms, heap usage 51.170 MB -> 5.253 MB. [2025-06-30T17:55:19.380Z] Camera thread performed 122 scans. [2025-06-30T17:55:19.380Z] ====== philosophers (scala) [default], iteration 26 completed (4727.899 ms) ====== [2025-06-30T17:55:19.380Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-30T17:55:19.380Z] GC before operation: completed in 13.740 ms, heap usage 17.078 MB -> 5.254 MB. [2025-06-30T17:55:24.629Z] Camera thread performed 122 scans. [2025-06-30T17:55:24.629Z] ====== philosophers (scala) [default], iteration 27 completed (4803.728 ms) ====== [2025-06-30T17:55:24.629Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-30T17:55:24.629Z] GC before operation: completed in 14.479 ms, heap usage 9.627 MB -> 5.253 MB. [2025-06-30T17:55:28.684Z] Camera thread performed 122 scans. [2025-06-30T17:55:28.684Z] ====== philosophers (scala) [default], iteration 28 completed (4381.496 ms) ====== [2025-06-30T17:55:28.684Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-30T17:55:28.684Z] GC before operation: completed in 13.147 ms, heap usage 17.608 MB -> 5.254 MB. [2025-06-30T17:55:35.226Z] Camera thread performed 122 scans. [2025-06-30T17:55:35.226Z] ====== philosophers (scala) [default], iteration 29 completed (6386.527 ms) ====== [2025-06-30T17:55:35.226Z] ----------------------------------- [2025-06-30T17:55:35.226Z] renaissance-philosophers_0_PASSED [2025-06-30T17:55:35.226Z] ----------------------------------- [2025-06-30T17:55:35.226Z] [2025-06-30T17:55:35.226Z] TEST TEARDOWN: [2025-06-30T17:55:35.226Z] Nothing to be done for teardown. [2025-06-30T17:55:35.226Z] renaissance-philosophers_0 Finish Time: Mon Jun 30 17:55:34 2025 Epoch Time (ms): 1751306134904