[2025-06-18T23:46:46.479Z] Running test renaissance-philosophers_0 ... [2025-06-18T23:46:46.480Z] =============================================== [2025-06-18T23:46:46.480Z] renaissance-philosophers_0 Start Time: Wed Jun 18 23:46:45 2025 Epoch Time (ms): 1750290405995 [2025-06-18T23:46:46.480Z] variation: NoOptions [2025-06-18T23:46:46.480Z] JVM_OPTIONS: [2025-06-18T23:46:46.480Z] { \ [2025-06-18T23:46:46.480Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T23:46:46.480Z] echo "Nothing to be done for setup."; \ [2025-06-18T23:46:46.480Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17502884342492/renaissance-philosophers_0"; \ [2025-06-18T23:46:46.480Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17502884342492/renaissance-philosophers_0"; \ [2025-06-18T23:46:46.480Z] echo ""; echo "TESTING:"; \ [2025-06-18T23:46:46.480Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17502884342492/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-18T23:46:46.480Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17502884342492/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T23:46:46.480Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T23:46:46.480Z] echo "Nothing to be done for teardown."; \ [2025-06-18T23:46:46.480Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17502884342492/TestTargetResult"; [2025-06-18T23:46:46.480Z] [2025-06-18T23:46:46.480Z] TEST SETUP: [2025-06-18T23:46:46.480Z] Nothing to be done for setup. [2025-06-18T23:46:46.480Z] [2025-06-18T23:46:46.480Z] TESTING: [2025-06-18T23:46:48.408Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-18T23:46:48.408Z] GC before operation: completed in 23.949 ms, heap usage 6.483 MB -> 4.392 MB. [2025-06-18T23:47:00.018Z] Camera thread performed 122 scans. [2025-06-18T23:47:00.018Z] ====== philosophers (scala) [default], iteration 0 completed (11355.656 ms) ====== [2025-06-18T23:47:00.018Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-18T23:47:00.018Z] GC before operation: completed in 39.539 ms, heap usage 80.293 MB -> 4.823 MB. [2025-06-18T23:47:05.325Z] Camera thread performed 122 scans. [2025-06-18T23:47:05.325Z] ====== philosophers (scala) [default], iteration 1 completed (6037.111 ms) ====== [2025-06-18T23:47:05.325Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-18T23:47:05.325Z] GC before operation: completed in 35.678 ms, heap usage 43.073 MB -> 4.824 MB. [2025-06-18T23:47:14.964Z] Camera thread performed 122 scans. [2025-06-18T23:47:14.964Z] ====== philosophers (scala) [default], iteration 2 completed (8772.653 ms) ====== [2025-06-18T23:47:14.964Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-18T23:47:14.964Z] GC before operation: completed in 31.983 ms, heap usage 67.953 MB -> 4.825 MB. [2025-06-18T23:47:23.015Z] Camera thread performed 122 scans. [2025-06-18T23:47:23.015Z] ====== philosophers (scala) [default], iteration 3 completed (8605.425 ms) ====== [2025-06-18T23:47:23.015Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-18T23:47:23.015Z] GC before operation: completed in 32.910 ms, heap usage 96.200 MB -> 4.826 MB. [2025-06-18T23:47:32.692Z] Camera thread performed 122 scans. [2025-06-18T23:47:32.692Z] ====== philosophers (scala) [default], iteration 4 completed (9019.122 ms) ====== [2025-06-18T23:47:32.692Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-18T23:47:32.692Z] GC before operation: completed in 34.729 ms, heap usage 95.573 MB -> 4.827 MB. [2025-06-18T23:47:39.307Z] Camera thread performed 122 scans. [2025-06-18T23:47:39.307Z] ====== philosophers (scala) [default], iteration 5 completed (7391.981 ms) ====== [2025-06-18T23:47:39.307Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-18T23:47:39.307Z] GC before operation: completed in 38.829 ms, heap usage 21.077 MB -> 4.827 MB. [2025-06-18T23:47:47.615Z] Camera thread performed 122 scans. [2025-06-18T23:47:47.615Z] ====== philosophers (scala) [default], iteration 6 completed (8551.169 ms) ====== [2025-06-18T23:47:47.615Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-18T23:47:47.615Z] GC before operation: completed in 36.798 ms, heap usage 15.757 MB -> 4.828 MB. [2025-06-18T23:47:57.323Z] Camera thread performed 122 scans. [2025-06-18T23:47:57.323Z] ====== philosophers (scala) [default], iteration 7 completed (9652.487 ms) ====== [2025-06-18T23:47:57.323Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-18T23:47:57.323Z] GC before operation: completed in 35.877 ms, heap usage 87.906 MB -> 4.828 MB. [2025-06-18T23:48:07.066Z] Camera thread performed 122 scans. [2025-06-18T23:48:07.066Z] ====== philosophers (scala) [default], iteration 8 completed (9082.188 ms) ====== [2025-06-18T23:48:07.066Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-18T23:48:07.066Z] GC before operation: completed in 32.423 ms, heap usage 61.842 MB -> 4.828 MB. [2025-06-18T23:48:16.702Z] Camera thread performed 122 scans. [2025-06-18T23:48:16.702Z] ====== philosophers (scala) [default], iteration 9 completed (8936.896 ms) ====== [2025-06-18T23:48:16.702Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-18T23:48:16.702Z] GC before operation: completed in 33.460 ms, heap usage 11.221 MB -> 4.828 MB. [2025-06-18T23:48:24.770Z] Camera thread performed 122 scans. [2025-06-18T23:48:24.770Z] ====== philosophers (scala) [default], iteration 10 completed (8740.554 ms) ====== [2025-06-18T23:48:24.770Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-18T23:48:24.770Z] GC before operation: completed in 35.804 ms, heap usage 89.832 MB -> 4.828 MB. [2025-06-18T23:48:32.825Z] Camera thread performed 122 scans. [2025-06-18T23:48:32.825Z] ====== philosophers (scala) [default], iteration 11 completed (7751.000 ms) ====== [2025-06-18T23:48:32.825Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-18T23:48:32.825Z] GC before operation: completed in 33.987 ms, heap usage 49.699 MB -> 4.828 MB. [2025-06-18T23:48:42.456Z] Camera thread performed 122 scans. [2025-06-18T23:48:42.456Z] ====== philosophers (scala) [default], iteration 12 completed (9076.053 ms) ====== [2025-06-18T23:48:42.456Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-18T23:48:42.456Z] GC before operation: completed in 32.589 ms, heap usage 16.629 MB -> 4.828 MB. [2025-06-18T23:48:50.541Z] Camera thread performed 122 scans. [2025-06-18T23:48:50.541Z] ====== philosophers (scala) [default], iteration 13 completed (8804.219 ms) ====== [2025-06-18T23:48:50.541Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-18T23:48:50.541Z] GC before operation: completed in 35.670 ms, heap usage 27.971 MB -> 4.828 MB. [2025-06-18T23:48:59.355Z] Camera thread performed 122 scans. [2025-06-18T23:48:59.355Z] ====== philosophers (scala) [default], iteration 14 completed (8629.212 ms) ====== [2025-06-18T23:48:59.355Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-18T23:48:59.355Z] GC before operation: completed in 31.416 ms, heap usage 59.256 MB -> 4.828 MB. [2025-06-18T23:49:08.998Z] Camera thread performed 122 scans. [2025-06-18T23:49:08.998Z] ====== philosophers (scala) [default], iteration 15 completed (10272.452 ms) ====== [2025-06-18T23:49:08.998Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-18T23:49:08.998Z] GC before operation: completed in 36.275 ms, heap usage 56.747 MB -> 4.828 MB. [2025-06-18T23:49:20.408Z] Camera thread performed 122 scans. [2025-06-18T23:49:20.408Z] ====== philosophers (scala) [default], iteration 16 completed (10235.496 ms) ====== [2025-06-18T23:49:20.408Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-18T23:49:20.408Z] GC before operation: completed in 34.038 ms, heap usage 61.604 MB -> 4.829 MB. [2025-06-18T23:49:30.066Z] Camera thread performed 122 scans. [2025-06-18T23:49:30.066Z] ====== philosophers (scala) [default], iteration 17 completed (9506.440 ms) ====== [2025-06-18T23:49:30.066Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-18T23:49:30.066Z] GC before operation: completed in 38.604 ms, heap usage 41.465 MB -> 4.829 MB. [2025-06-18T23:49:36.694Z] Camera thread performed 122 scans. [2025-06-18T23:49:36.694Z] ====== philosophers (scala) [default], iteration 18 completed (7561.605 ms) ====== [2025-06-18T23:49:36.694Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-18T23:49:36.694Z] GC before operation: completed in 36.836 ms, heap usage 93.025 MB -> 4.829 MB. [2025-06-18T23:49:48.099Z] Camera thread performed 122 scans. [2025-06-18T23:49:48.099Z] ====== philosophers (scala) [default], iteration 19 completed (10281.489 ms) ====== [2025-06-18T23:49:48.099Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-18T23:49:48.099Z] GC before operation: completed in 34.183 ms, heap usage 84.231 MB -> 4.829 MB. [2025-06-18T23:49:57.744Z] Camera thread performed 122 scans. [2025-06-18T23:49:57.744Z] ====== philosophers (scala) [default], iteration 20 completed (9778.607 ms) ====== [2025-06-18T23:49:57.744Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-18T23:49:57.744Z] GC before operation: completed in 34.971 ms, heap usage 82.127 MB -> 4.829 MB. [2025-06-18T23:50:07.391Z] Camera thread performed 122 scans. [2025-06-18T23:50:07.391Z] ====== philosophers (scala) [default], iteration 21 completed (10351.382 ms) ====== [2025-06-18T23:50:07.391Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-18T23:50:07.391Z] GC before operation: completed in 34.606 ms, heap usage 49.661 MB -> 4.829 MB. [2025-06-18T23:50:15.461Z] Camera thread performed 122 scans. [2025-06-18T23:50:15.461Z] ====== philosophers (scala) [default], iteration 22 completed (8084.569 ms) ====== [2025-06-18T23:50:15.461Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-18T23:50:15.461Z] GC before operation: completed in 34.040 ms, heap usage 32.522 MB -> 4.829 MB. [2025-06-18T23:50:25.130Z] Camera thread performed 122 scans. [2025-06-18T23:50:25.130Z] ====== philosophers (scala) [default], iteration 23 completed (8992.837 ms) ====== [2025-06-18T23:50:25.130Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-18T23:50:25.130Z] GC before operation: completed in 32.896 ms, heap usage 44.713 MB -> 4.829 MB. [2025-06-18T23:50:31.775Z] Camera thread performed 122 scans. [2025-06-18T23:50:31.775Z] ====== philosophers (scala) [default], iteration 24 completed (7840.726 ms) ====== [2025-06-18T23:50:31.775Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-18T23:50:32.715Z] GC before operation: completed in 32.245 ms, heap usage 25.220 MB -> 4.830 MB. [2025-06-18T23:50:39.398Z] Camera thread performed 122 scans. [2025-06-18T23:50:39.398Z] ====== philosophers (scala) [default], iteration 25 completed (7335.989 ms) ====== [2025-06-18T23:50:39.398Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-18T23:50:39.398Z] GC before operation: completed in 37.228 ms, heap usage 47.732 MB -> 4.830 MB. [2025-06-18T23:50:47.532Z] Camera thread performed 122 scans. [2025-06-18T23:50:47.532Z] ====== philosophers (scala) [default], iteration 26 completed (7458.453 ms) ====== [2025-06-18T23:50:47.532Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-18T23:50:47.532Z] GC before operation: completed in 32.153 ms, heap usage 53.963 MB -> 4.830 MB. [2025-06-18T23:50:55.666Z] Camera thread performed 122 scans. [2025-06-18T23:50:55.666Z] ====== philosophers (scala) [default], iteration 27 completed (8056.097 ms) ====== [2025-06-18T23:50:55.666Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-18T23:50:55.666Z] GC before operation: completed in 31.756 ms, heap usage 49.232 MB -> 4.830 MB. [2025-06-18T23:51:00.981Z] Camera thread performed 122 scans. [2025-06-18T23:51:00.981Z] ====== philosophers (scala) [default], iteration 28 completed (5110.188 ms) ====== [2025-06-18T23:51:00.981Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-18T23:51:00.981Z] GC before operation: completed in 37.783 ms, heap usage 110.549 MB -> 4.830 MB. [2025-06-18T23:51:06.299Z] Camera thread performed 122 scans. [2025-06-18T23:51:06.299Z] ====== philosophers (scala) [default], iteration 29 completed (6208.716 ms) ====== [2025-06-18T23:51:07.238Z] ----------------------------------- [2025-06-18T23:51:07.238Z] renaissance-philosophers_0_PASSED [2025-06-18T23:51:07.238Z] ----------------------------------- [2025-06-18T23:51:07.238Z] [2025-06-18T23:51:07.238Z] TEST TEARDOWN: [2025-06-18T23:51:07.238Z] Nothing to be done for teardown. [2025-06-18T23:51:07.238Z] renaissance-philosophers_0 Finish Time: Wed Jun 18 23:51:06 2025 Epoch Time (ms): 1750290666331