[2025-03-30T23:41:33.944Z] Running test renaissance-philosophers_0 ... [2025-03-30T23:41:33.944Z] =============================================== [2025-03-30T23:41:33.944Z] renaissance-philosophers_0 Start Time: Sun Mar 30 23:41:33 2025 Epoch Time (ms): 1743378093181 [2025-03-30T23:41:33.944Z] variation: NoOptions [2025-03-30T23:41:33.944Z] JVM_OPTIONS: [2025-03-30T23:41:33.944Z] { \ [2025-03-30T23:41:33.944Z] echo ""; echo "TEST SETUP:"; \ [2025-03-30T23:41:33.944Z] echo "Nothing to be done for setup."; \ [2025-03-30T23:41:33.944Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17433701275835/renaissance-philosophers_0"; \ [2025-03-30T23:41:33.944Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17433701275835/renaissance-philosophers_0"; \ [2025-03-30T23:41:33.944Z] echo ""; echo "TESTING:"; \ [2025-03-30T23:41:33.944Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17433701275835/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-03-30T23:41:33.944Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17433701275835/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-30T23:41:33.944Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-30T23:41:33.944Z] echo "Nothing to be done for teardown."; \ [2025-03-30T23:41:33.944Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17433701275835/TestTargetResult"; [2025-03-30T23:41:33.944Z] [2025-03-30T23:41:33.944Z] TEST SETUP: [2025-03-30T23:41:33.944Z] Nothing to be done for setup. [2025-03-30T23:41:33.944Z] [2025-03-30T23:41:33.944Z] TESTING: [2025-03-30T23:41:37.483Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-03-30T23:41:37.483Z] GC before operation: completed in 82.395 ms, heap usage 15.073 MB -> 4.203 MB. [2025-03-30T23:41:49.651Z] Camera thread performed 122 scans. [2025-03-30T23:41:49.651Z] ====== philosophers (scala) [default], iteration 0 completed (11093.731 ms) ====== [2025-03-30T23:41:49.651Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-03-30T23:41:49.651Z] GC before operation: completed in 206.920 ms, heap usage 29.590 MB -> 4.612 MB. [2025-03-30T23:41:52.250Z] Camera thread performed 122 scans. [2025-03-30T23:41:52.250Z] ====== philosophers (scala) [default], iteration 1 completed (3361.127 ms) ====== [2025-03-30T23:41:52.250Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-03-30T23:41:52.250Z] GC before operation: completed in 61.000 ms, heap usage 10.801 MB -> 4.612 MB. [2025-03-30T23:41:54.857Z] Camera thread performed 122 scans. [2025-03-30T23:41:54.857Z] ====== philosophers (scala) [default], iteration 2 completed (3094.465 ms) ====== [2025-03-30T23:41:54.857Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-03-30T23:41:54.857Z] GC before operation: completed in 83.709 ms, heap usage 45.110 MB -> 4.617 MB. [2025-03-30T23:41:58.461Z] Camera thread performed 122 scans. [2025-03-30T23:41:58.461Z] ====== philosophers (scala) [default], iteration 3 completed (2985.691 ms) ====== [2025-03-30T23:41:58.461Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-03-30T23:41:58.461Z] GC before operation: completed in 62.730 ms, heap usage 7.179 MB -> 4.617 MB. [2025-03-30T23:42:01.048Z] Camera thread performed 122 scans. [2025-03-30T23:42:01.048Z] ====== philosophers (scala) [default], iteration 4 completed (3002.170 ms) ====== [2025-03-30T23:42:01.048Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-03-30T23:42:01.048Z] GC before operation: completed in 118.276 ms, heap usage 47.376 MB -> 4.619 MB. [2025-03-30T23:42:04.716Z] Camera thread performed 122 scans. [2025-03-30T23:42:04.716Z] ====== philosophers (scala) [default], iteration 5 completed (3298.774 ms) ====== [2025-03-30T23:42:04.716Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-03-30T23:42:04.716Z] GC before operation: completed in 116.341 ms, heap usage 53.091 MB -> 4.619 MB. [2025-03-30T23:42:08.286Z] Camera thread performed 122 scans. [2025-03-30T23:42:08.286Z] ====== philosophers (scala) [default], iteration 6 completed (3143.234 ms) ====== [2025-03-30T23:42:08.286Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-03-30T23:42:08.286Z] GC before operation: completed in 72.438 ms, heap usage 27.029 MB -> 4.619 MB. [2025-03-30T23:42:11.832Z] Camera thread performed 122 scans. [2025-03-30T23:42:11.832Z] ====== philosophers (scala) [default], iteration 7 completed (3432.498 ms) ====== [2025-03-30T23:42:11.832Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-03-30T23:42:11.832Z] GC before operation: completed in 59.992 ms, heap usage 54.787 MB -> 4.619 MB. [2025-03-30T23:42:14.422Z] Camera thread performed 122 scans. [2025-03-30T23:42:14.422Z] ====== philosophers (scala) [default], iteration 8 completed (2953.457 ms) ====== [2025-03-30T23:42:14.422Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-03-30T23:42:14.422Z] GC before operation: completed in 50.284 ms, heap usage 25.090 MB -> 4.620 MB. [2025-03-30T23:42:17.980Z] Camera thread performed 122 scans. [2025-03-30T23:42:17.980Z] ====== philosophers (scala) [default], iteration 9 completed (3212.820 ms) ====== [2025-03-30T23:42:17.980Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-03-30T23:42:17.980Z] GC before operation: completed in 66.408 ms, heap usage 25.641 MB -> 4.621 MB. [2025-03-30T23:42:21.576Z] Camera thread performed 122 scans. [2025-03-30T23:42:21.577Z] ====== philosophers (scala) [default], iteration 10 completed (3285.155 ms) ====== [2025-03-30T23:42:21.577Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-03-30T23:42:21.577Z] GC before operation: completed in 135.705 ms, heap usage 18.578 MB -> 4.620 MB. [2025-03-30T23:42:24.173Z] Camera thread performed 122 scans. [2025-03-30T23:42:24.173Z] ====== philosophers (scala) [default], iteration 11 completed (3286.452 ms) ====== [2025-03-30T23:42:24.173Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-03-30T23:42:24.173Z] GC before operation: completed in 62.988 ms, heap usage 60.824 MB -> 4.621 MB. [2025-03-30T23:42:27.746Z] Camera thread performed 122 scans. [2025-03-30T23:42:27.746Z] ====== philosophers (scala) [default], iteration 12 completed (3268.718 ms) ====== [2025-03-30T23:42:27.746Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-03-30T23:42:27.746Z] GC before operation: completed in 53.615 ms, heap usage 42.223 MB -> 4.622 MB. [2025-03-30T23:42:31.353Z] Camera thread performed 122 scans. [2025-03-30T23:42:31.353Z] ====== philosophers (scala) [default], iteration 13 completed (3221.323 ms) ====== [2025-03-30T23:42:31.353Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-03-30T23:42:31.353Z] GC before operation: completed in 82.619 ms, heap usage 20.649 MB -> 4.622 MB. [2025-03-30T23:42:34.950Z] Camera thread performed 122 scans. [2025-03-30T23:42:34.950Z] ====== philosophers (scala) [default], iteration 14 completed (3519.125 ms) ====== [2025-03-30T23:42:34.950Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-03-30T23:42:34.950Z] GC before operation: completed in 78.739 ms, heap usage 42.381 MB -> 4.622 MB. [2025-03-30T23:42:38.103Z] Camera thread performed 122 scans. [2025-03-30T23:42:38.103Z] ====== philosophers (scala) [default], iteration 15 completed (3056.263 ms) ====== [2025-03-30T23:42:38.103Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-03-30T23:42:38.103Z] GC before operation: completed in 66.904 ms, heap usage 34.390 MB -> 4.622 MB. [2025-03-30T23:42:40.695Z] Camera thread performed 122 scans. [2025-03-30T23:42:40.695Z] ====== philosophers (scala) [default], iteration 16 completed (3156.165 ms) ====== [2025-03-30T23:42:40.695Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-03-30T23:42:40.695Z] GC before operation: completed in 63.076 ms, heap usage 30.099 MB -> 4.622 MB. [2025-03-30T23:42:44.310Z] Camera thread performed 122 scans. [2025-03-30T23:42:44.310Z] ====== philosophers (scala) [default], iteration 17 completed (2638.150 ms) ====== [2025-03-30T23:42:44.310Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-03-30T23:42:44.310Z] GC before operation: completed in 86.294 ms, heap usage 16.094 MB -> 4.623 MB. [2025-03-30T23:42:46.910Z] Camera thread performed 122 scans. [2025-03-30T23:42:46.910Z] ====== philosophers (scala) [default], iteration 18 completed (2798.552 ms) ====== [2025-03-30T23:42:46.911Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-03-30T23:42:46.911Z] GC before operation: completed in 67.691 ms, heap usage 38.783 MB -> 4.624 MB. [2025-03-30T23:42:49.501Z] Camera thread performed 122 scans. [2025-03-30T23:42:49.501Z] ====== philosophers (scala) [default], iteration 19 completed (2859.728 ms) ====== [2025-03-30T23:42:49.501Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-03-30T23:42:49.501Z] GC before operation: completed in 172.204 ms, heap usage 97.796 MB -> 4.623 MB. [2025-03-30T23:42:54.153Z] Camera thread performed 122 scans. [2025-03-30T23:42:54.153Z] ====== philosophers (scala) [default], iteration 20 completed (3829.459 ms) ====== [2025-03-30T23:42:54.153Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-03-30T23:42:54.153Z] GC before operation: completed in 72.781 ms, heap usage 8.106 MB -> 4.624 MB. [2025-03-30T23:42:56.761Z] Camera thread performed 122 scans. [2025-03-30T23:42:56.761Z] ====== philosophers (scala) [default], iteration 21 completed (3460.386 ms) ====== [2025-03-30T23:42:56.761Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-03-30T23:42:57.593Z] GC before operation: completed in 107.821 ms, heap usage 50.706 MB -> 4.623 MB. [2025-03-30T23:43:01.196Z] Camera thread performed 122 scans. [2025-03-30T23:43:01.196Z] ====== philosophers (scala) [default], iteration 22 completed (3643.617 ms) ====== [2025-03-30T23:43:01.196Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-03-30T23:43:01.196Z] GC before operation: completed in 64.227 ms, heap usage 32.087 MB -> 4.624 MB. [2025-03-30T23:43:03.792Z] Camera thread performed 122 scans. [2025-03-30T23:43:03.792Z] ====== philosophers (scala) [default], iteration 23 completed (2835.680 ms) ====== [2025-03-30T23:43:03.792Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-03-30T23:43:03.792Z] GC before operation: completed in 70.837 ms, heap usage 15.220 MB -> 4.624 MB. [2025-03-30T23:43:06.493Z] Camera thread performed 122 scans. [2025-03-30T23:43:06.493Z] ====== philosophers (scala) [default], iteration 24 completed (2922.189 ms) ====== [2025-03-30T23:43:06.493Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-03-30T23:43:06.493Z] GC before operation: completed in 68.093 ms, heap usage 25.658 MB -> 4.623 MB. [2025-03-30T23:43:09.059Z] Camera thread performed 122 scans. [2025-03-30T23:43:09.059Z] ====== philosophers (scala) [default], iteration 25 completed (2596.552 ms) ====== [2025-03-30T23:43:09.059Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-03-30T23:43:09.869Z] GC before operation: completed in 93.899 ms, heap usage 39.302 MB -> 4.624 MB. [2025-03-30T23:43:12.449Z] Camera thread performed 122 scans. [2025-03-30T23:43:12.449Z] ====== philosophers (scala) [default], iteration 26 completed (2940.318 ms) ====== [2025-03-30T23:43:12.449Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-03-30T23:43:12.449Z] GC before operation: completed in 93.539 ms, heap usage 21.100 MB -> 4.624 MB. [2025-03-30T23:43:15.992Z] Camera thread performed 122 scans. [2025-03-30T23:43:15.992Z] ====== philosophers (scala) [default], iteration 27 completed (3040.110 ms) ====== [2025-03-30T23:43:15.992Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-03-30T23:43:15.992Z] GC before operation: completed in 59.534 ms, heap usage 7.350 MB -> 4.624 MB. [2025-03-30T23:43:18.578Z] Camera thread performed 122 scans. [2025-03-30T23:43:18.578Z] ====== philosophers (scala) [default], iteration 28 completed (2786.807 ms) ====== [2025-03-30T23:43:18.578Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-03-30T23:43:18.578Z] GC before operation: completed in 79.057 ms, heap usage 29.716 MB -> 4.625 MB. [2025-03-30T23:43:22.145Z] Camera thread performed 122 scans. [2025-03-30T23:43:22.145Z] ====== philosophers (scala) [default], iteration 29 completed (3280.077 ms) ====== [2025-03-30T23:43:23.461Z] ----------------------------------- [2025-03-30T23:43:23.461Z] renaissance-philosophers_0_PASSED [2025-03-30T23:43:23.461Z] ----------------------------------- [2025-03-30T23:43:23.461Z] [2025-03-30T23:43:23.461Z] TEST TEARDOWN: [2025-03-30T23:43:23.461Z] Nothing to be done for teardown. [2025-03-30T23:43:23.461Z] renaissance-philosophers_0 Finish Time: Sun Mar 30 23:43:22 2025 Epoch Time (ms): 1743378202259