[2025-08-01T22:41:11.983Z] Running test renaissance-philosophers_0 ... [2025-08-01T22:41:11.983Z] =============================================== [2025-08-01T22:41:11.983Z] renaissance-philosophers_0 Start Time: Fri Aug 1 22:41:10 2025 Epoch Time (ms): 1754088070700 [2025-08-01T22:41:11.983Z] variation: NoOptions [2025-08-01T22:41:11.983Z] JVM_OPTIONS: [2025-08-01T22:41:11.983Z] { \ [2025-08-01T22:41:11.984Z] echo ""; echo "TEST SETUP:"; \ [2025-08-01T22:41:11.984Z] echo "Nothing to be done for setup."; \ [2025-08-01T22:41:11.984Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1754084504458/renaissance-philosophers_0"; \ [2025-08-01T22:41:11.984Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1754084504458/renaissance-philosophers_0"; \ [2025-08-01T22:41:11.984Z] echo ""; echo "TESTING:"; \ [2025-08-01T22:41:11.984Z] "/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_1754084504458/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-01T22:41:11.984Z] 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_1754084504458/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-01T22:41:11.984Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-01T22:41:11.984Z] echo "Nothing to be done for teardown."; \ [2025-08-01T22:41:11.984Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_1754084504458/TestTargetResult"; [2025-08-01T22:41:11.984Z] [2025-08-01T22:41:11.984Z] TEST SETUP: [2025-08-01T22:41:11.984Z] Nothing to be done for setup. [2025-08-01T22:41:11.984Z] [2025-08-01T22:41:11.984Z] TESTING: [2025-08-01T22:41:12.347Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-01T22:41:12.347Z] GC before operation: completed in 13.775 ms, heap usage 14.631 MB -> 4.202 MB. [2025-08-01T22:41:16.479Z] Camera thread performed 122 scans. [2025-08-01T22:41:16.479Z] ====== philosophers (scala) [default], iteration 0 completed (4213.837 ms) ====== [2025-08-01T22:41:16.479Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-01T22:41:16.479Z] GC before operation: completed in 17.783 ms, heap usage 39.655 MB -> 4.613 MB. [2025-08-01T22:41:18.436Z] Camera thread performed 122 scans. [2025-08-01T22:41:18.436Z] ====== philosophers (scala) [default], iteration 1 completed (1962.964 ms) ====== [2025-08-01T22:41:18.436Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-01T22:41:18.436Z] GC before operation: completed in 15.164 ms, heap usage 10.855 MB -> 4.616 MB. [2025-08-01T22:41:20.290Z] Camera thread performed 122 scans. [2025-08-01T22:41:20.290Z] ====== philosophers (scala) [default], iteration 2 completed (1904.785 ms) ====== [2025-08-01T22:41:20.290Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-01T22:41:20.290Z] GC before operation: completed in 14.248 ms, heap usage 28.872 MB -> 4.617 MB. [2025-08-01T22:41:22.773Z] Camera thread performed 122 scans. [2025-08-01T22:41:22.773Z] ====== philosophers (scala) [default], iteration 3 completed (1933.061 ms) ====== [2025-08-01T22:41:22.773Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-01T22:41:22.773Z] GC before operation: completed in 14.574 ms, heap usage 57.115 MB -> 4.618 MB. [2025-08-01T22:41:24.597Z] Camera thread performed 122 scans. [2025-08-01T22:41:24.597Z] ====== philosophers (scala) [default], iteration 4 completed (1902.314 ms) ====== [2025-08-01T22:41:24.597Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-01T22:41:24.597Z] GC before operation: completed in 19.553 ms, heap usage 9.641 MB -> 4.620 MB. [2025-08-01T22:41:26.462Z] Camera thread performed 122 scans. [2025-08-01T22:41:26.462Z] ====== philosophers (scala) [default], iteration 5 completed (1925.357 ms) ====== [2025-08-01T22:41:26.462Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-01T22:41:26.462Z] GC before operation: completed in 14.554 ms, heap usage 30.653 MB -> 4.620 MB. [2025-08-01T22:41:28.324Z] Camera thread performed 122 scans. [2025-08-01T22:41:28.324Z] ====== philosophers (scala) [default], iteration 6 completed (1982.877 ms) ====== [2025-08-01T22:41:28.324Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-01T22:41:28.324Z] GC before operation: completed in 14.671 ms, heap usage 14.736 MB -> 4.621 MB. [2025-08-01T22:41:30.140Z] Camera thread performed 122 scans. [2025-08-01T22:41:30.140Z] ====== philosophers (scala) [default], iteration 7 completed (2010.842 ms) ====== [2025-08-01T22:41:30.140Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-01T22:41:30.140Z] GC before operation: completed in 15.040 ms, heap usage 58.888 MB -> 4.621 MB. [2025-08-01T22:41:32.601Z] Camera thread performed 122 scans. [2025-08-01T22:41:32.601Z] ====== philosophers (scala) [default], iteration 8 completed (2228.885 ms) ====== [2025-08-01T22:41:32.601Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-01T22:41:32.601Z] GC before operation: completed in 14.689 ms, heap usage 15.823 MB -> 4.622 MB. [2025-08-01T22:41:34.458Z] Camera thread performed 122 scans. [2025-08-01T22:41:34.458Z] ====== philosophers (scala) [default], iteration 9 completed (1921.434 ms) ====== [2025-08-01T22:41:34.458Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-01T22:41:34.458Z] GC before operation: completed in 15.281 ms, heap usage 50.882 MB -> 4.622 MB. [2025-08-01T22:41:36.303Z] Camera thread performed 122 scans. [2025-08-01T22:41:36.303Z] ====== philosophers (scala) [default], iteration 10 completed (1930.422 ms) ====== [2025-08-01T22:41:36.303Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-01T22:41:36.303Z] GC before operation: completed in 14.774 ms, heap usage 42.523 MB -> 4.622 MB. [2025-08-01T22:41:38.143Z] Camera thread performed 122 scans. [2025-08-01T22:41:38.143Z] ====== philosophers (scala) [default], iteration 11 completed (1926.353 ms) ====== [2025-08-01T22:41:38.143Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-01T22:41:38.143Z] GC before operation: completed in 14.990 ms, heap usage 59.270 MB -> 4.622 MB. [2025-08-01T22:41:40.614Z] Camera thread performed 122 scans. [2025-08-01T22:41:40.614Z] ====== philosophers (scala) [default], iteration 12 completed (1898.158 ms) ====== [2025-08-01T22:41:40.614Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-01T22:41:40.614Z] GC before operation: completed in 14.592 ms, heap usage 65.784 MB -> 4.623 MB. [2025-08-01T22:41:42.038Z] Camera thread performed 122 scans. [2025-08-01T22:41:42.038Z] ====== philosophers (scala) [default], iteration 13 completed (1877.638 ms) ====== [2025-08-01T22:41:42.038Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-01T22:41:42.038Z] GC before operation: completed in 15.017 ms, heap usage 75.273 MB -> 4.623 MB. [2025-08-01T22:41:44.500Z] Camera thread performed 122 scans. [2025-08-01T22:41:44.500Z] ====== philosophers (scala) [default], iteration 14 completed (1949.848 ms) ====== [2025-08-01T22:41:44.500Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-01T22:41:44.500Z] GC before operation: completed in 14.909 ms, heap usage 46.685 MB -> 4.623 MB. [2025-08-01T22:41:46.332Z] Camera thread performed 122 scans. [2025-08-01T22:41:46.332Z] ====== philosophers (scala) [default], iteration 15 completed (2041.810 ms) ====== [2025-08-01T22:41:46.332Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-01T22:41:46.332Z] GC before operation: completed in 14.779 ms, heap usage 16.874 MB -> 4.623 MB. [2025-08-01T22:41:48.234Z] Camera thread performed 122 scans. [2025-08-01T22:41:48.234Z] ====== philosophers (scala) [default], iteration 16 completed (1913.862 ms) ====== [2025-08-01T22:41:48.234Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-01T22:41:48.234Z] GC before operation: completed in 15.058 ms, heap usage 59.814 MB -> 4.623 MB. [2025-08-01T22:41:50.104Z] Camera thread performed 122 scans. [2025-08-01T22:41:50.104Z] ====== philosophers (scala) [default], iteration 17 completed (1972.561 ms) ====== [2025-08-01T22:41:50.104Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-01T22:41:50.104Z] GC before operation: completed in 14.564 ms, heap usage 30.820 MB -> 4.623 MB. [2025-08-01T22:41:51.965Z] Camera thread performed 122 scans. [2025-08-01T22:41:51.965Z] ====== philosophers (scala) [default], iteration 18 completed (1931.605 ms) ====== [2025-08-01T22:41:51.965Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-01T22:41:51.965Z] GC before operation: completed in 14.823 ms, heap usage 48.297 MB -> 4.623 MB. [2025-08-01T22:41:53.824Z] Camera thread performed 122 scans. [2025-08-01T22:41:53.824Z] ====== philosophers (scala) [default], iteration 19 completed (2001.143 ms) ====== [2025-08-01T22:41:53.824Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-01T22:41:54.207Z] GC before operation: completed in 14.945 ms, heap usage 50.883 MB -> 4.623 MB. [2025-08-01T22:41:56.023Z] Camera thread performed 122 scans. [2025-08-01T22:41:56.023Z] ====== philosophers (scala) [default], iteration 20 completed (1885.462 ms) ====== [2025-08-01T22:41:56.023Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-01T22:41:56.023Z] GC before operation: completed in 14.941 ms, heap usage 30.872 MB -> 4.623 MB. [2025-08-01T22:41:57.865Z] Camera thread performed 122 scans. [2025-08-01T22:41:57.865Z] ====== philosophers (scala) [default], iteration 21 completed (1930.010 ms) ====== [2025-08-01T22:41:57.865Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-01T22:41:57.865Z] GC before operation: completed in 14.586 ms, heap usage 49.204 MB -> 4.623 MB. [2025-08-01T22:41:59.701Z] Camera thread performed 122 scans. [2025-08-01T22:41:59.701Z] ====== philosophers (scala) [default], iteration 22 completed (1953.691 ms) ====== [2025-08-01T22:41:59.701Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-01T22:41:59.701Z] GC before operation: completed in 15.535 ms, heap usage 53.376 MB -> 4.624 MB. [2025-08-01T22:42:02.167Z] Camera thread performed 122 scans. [2025-08-01T22:42:02.168Z] ====== philosophers (scala) [default], iteration 23 completed (1962.653 ms) ====== [2025-08-01T22:42:02.168Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-01T22:42:02.168Z] GC before operation: completed in 15.168 ms, heap usage 26.864 MB -> 4.623 MB. [2025-08-01T22:42:04.024Z] Camera thread performed 122 scans. [2025-08-01T22:42:04.024Z] ====== philosophers (scala) [default], iteration 24 completed (2006.820 ms) ====== [2025-08-01T22:42:04.024Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-01T22:42:04.024Z] GC before operation: completed in 14.708 ms, heap usage 61.887 MB -> 4.624 MB. [2025-08-01T22:42:05.852Z] Camera thread performed 122 scans. [2025-08-01T22:42:05.852Z] ====== philosophers (scala) [default], iteration 25 completed (2090.221 ms) ====== [2025-08-01T22:42:05.852Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-01T22:42:05.852Z] GC before operation: completed in 15.201 ms, heap usage 11.397 MB -> 4.624 MB. [2025-08-01T22:42:07.849Z] Camera thread performed 122 scans. [2025-08-01T22:42:07.849Z] ====== philosophers (scala) [default], iteration 26 completed (1916.006 ms) ====== [2025-08-01T22:42:07.849Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-01T22:42:07.849Z] GC before operation: completed in 16.542 ms, heap usage 9.865 MB -> 4.624 MB. [2025-08-01T22:42:09.707Z] Camera thread performed 122 scans. [2025-08-01T22:42:09.707Z] ====== philosophers (scala) [default], iteration 27 completed (1966.257 ms) ====== [2025-08-01T22:42:09.707Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-01T22:42:09.707Z] GC before operation: completed in 14.934 ms, heap usage 34.602 MB -> 4.624 MB. [2025-08-01T22:42:12.179Z] Camera thread performed 122 scans. [2025-08-01T22:42:12.179Z] ====== philosophers (scala) [default], iteration 28 completed (2212.305 ms) ====== [2025-08-01T22:42:12.179Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-01T22:42:12.180Z] GC before operation: completed in 14.967 ms, heap usage 61.509 MB -> 4.624 MB. [2025-08-01T22:42:14.784Z] Camera thread performed 122 scans. [2025-08-01T22:42:14.784Z] ====== philosophers (scala) [default], iteration 29 completed (2326.754 ms) ====== [2025-08-01T22:42:14.784Z] ----------------------------------- [2025-08-01T22:42:14.784Z] renaissance-philosophers_0_PASSED [2025-08-01T22:42:14.784Z] ----------------------------------- [2025-08-01T22:42:14.784Z] [2025-08-01T22:42:14.784Z] TEST TEARDOWN: [2025-08-01T22:42:14.784Z] Nothing to be done for teardown. [2025-08-01T22:42:14.784Z] renaissance-philosophers_0 Finish Time: Fri Aug 1 22:42:14 2025 Epoch Time (ms): 1754088134518