[2025-05-15T10:20:44.932Z] Running test renaissance-philosophers_0 ... [2025-05-15T10:20:44.932Z] =============================================== [2025-05-15T10:20:44.932Z] renaissance-philosophers_0 Start Time: Thu May 15 10:20:44 2025 Epoch Time (ms): 1747304444100 [2025-05-15T10:20:44.932Z] variation: NoOptions [2025-05-15T10:20:44.932Z] JVM_OPTIONS: [2025-05-15T10:20:44.932Z] { \ [2025-05-15T10:20:44.932Z] echo ""; echo "TEST SETUP:"; \ [2025-05-15T10:20:44.932Z] echo "Nothing to be done for setup."; \ [2025-05-15T10:20:44.932Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17473039987188/renaissance-philosophers_0"; \ [2025-05-15T10:20:44.932Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17473039987188/renaissance-philosophers_0"; \ [2025-05-15T10:20:44.932Z] echo ""; echo "TESTING:"; \ [2025-05-15T10:20:44.933Z] "/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_17473039987188/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-15T10:20:44.933Z] 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_17473039987188/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-15T10:20:44.933Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-15T10:20:44.933Z] echo "Nothing to be done for teardown."; \ [2025-05-15T10:20:44.933Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17473039987188/TestTargetResult"; [2025-05-15T10:20:44.933Z] [2025-05-15T10:20:44.933Z] TEST SETUP: [2025-05-15T10:20:44.933Z] Nothing to be done for setup. [2025-05-15T10:20:44.933Z] [2025-05-15T10:20:44.933Z] TESTING: [2025-05-15T10:20:45.298Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-15T10:20:45.298Z] GC before operation: completed in 13.320 ms, heap usage 14.508 MB -> 4.690 MB. [2025-05-15T10:20:49.438Z] Camera thread performed 122 scans. [2025-05-15T10:20:49.438Z] ====== philosophers (scala) [default], iteration 0 completed (3741.438 ms) ====== [2025-05-15T10:20:49.438Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-15T10:20:49.438Z] GC before operation: completed in 15.732 ms, heap usage 14.852 MB -> 5.056 MB. [2025-05-15T10:20:51.251Z] Camera thread performed 122 scans. [2025-05-15T10:20:51.251Z] ====== philosophers (scala) [default], iteration 1 completed (2173.972 ms) ====== [2025-05-15T10:20:51.251Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-15T10:20:51.251Z] GC before operation: completed in 12.673 ms, heap usage 72.201 MB -> 5.055 MB. [2025-05-15T10:20:53.725Z] Camera thread performed 122 scans. [2025-05-15T10:20:53.726Z] ====== philosophers (scala) [default], iteration 2 completed (1995.772 ms) ====== [2025-05-15T10:20:53.726Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-15T10:20:53.726Z] GC before operation: completed in 11.613 ms, heap usage 18.135 MB -> 5.056 MB. [2025-05-15T10:20:55.580Z] Camera thread performed 122 scans. [2025-05-15T10:20:55.580Z] ====== philosophers (scala) [default], iteration 3 completed (2030.124 ms) ====== [2025-05-15T10:20:55.580Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-15T10:20:55.580Z] GC before operation: completed in 11.487 ms, heap usage 48.258 MB -> 5.057 MB. [2025-05-15T10:20:57.416Z] Camera thread performed 122 scans. [2025-05-15T10:20:57.416Z] ====== philosophers (scala) [default], iteration 4 completed (2055.701 ms) ====== [2025-05-15T10:20:57.416Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-15T10:20:57.416Z] GC before operation: completed in 11.460 ms, heap usage 31.796 MB -> 5.057 MB. [2025-05-15T10:20:59.905Z] Camera thread performed 122 scans. [2025-05-15T10:20:59.905Z] ====== philosophers (scala) [default], iteration 5 completed (2003.975 ms) ====== [2025-05-15T10:20:59.905Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-15T10:20:59.905Z] GC before operation: completed in 11.464 ms, heap usage 45.095 MB -> 5.058 MB. [2025-05-15T10:21:01.784Z] Camera thread performed 122 scans. [2025-05-15T10:21:01.784Z] ====== philosophers (scala) [default], iteration 6 completed (1963.750 ms) ====== [2025-05-15T10:21:01.784Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-15T10:21:01.784Z] GC before operation: completed in 11.471 ms, heap usage 33.672 MB -> 5.058 MB. [2025-05-15T10:21:03.608Z] Camera thread performed 122 scans. [2025-05-15T10:21:03.608Z] ====== philosophers (scala) [default], iteration 7 completed (2025.558 ms) ====== [2025-05-15T10:21:03.608Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-15T10:21:03.608Z] GC before operation: completed in 11.544 ms, heap usage 30.171 MB -> 5.058 MB. [2025-05-15T10:21:05.422Z] Camera thread performed 122 scans. [2025-05-15T10:21:05.422Z] ====== philosophers (scala) [default], iteration 8 completed (2030.771 ms) ====== [2025-05-15T10:21:05.422Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-15T10:21:05.422Z] GC before operation: completed in 11.535 ms, heap usage 22.750 MB -> 5.059 MB. [2025-05-15T10:21:07.886Z] Camera thread performed 122 scans. [2025-05-15T10:21:07.886Z] ====== philosophers (scala) [default], iteration 9 completed (2010.848 ms) ====== [2025-05-15T10:21:07.886Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-15T10:21:07.886Z] GC before operation: completed in 12.345 ms, heap usage 59.271 MB -> 5.058 MB. [2025-05-15T10:21:09.696Z] Camera thread performed 122 scans. [2025-05-15T10:21:09.696Z] ====== philosophers (scala) [default], iteration 10 completed (1986.848 ms) ====== [2025-05-15T10:21:09.696Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-15T10:21:09.696Z] GC before operation: completed in 11.444 ms, heap usage 28.288 MB -> 5.059 MB. [2025-05-15T10:21:11.510Z] Camera thread performed 122 scans. [2025-05-15T10:21:11.510Z] ====== philosophers (scala) [default], iteration 11 completed (2015.825 ms) ====== [2025-05-15T10:21:11.510Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-15T10:21:11.510Z] GC before operation: completed in 11.563 ms, heap usage 15.232 MB -> 5.059 MB. [2025-05-15T10:21:13.955Z] Camera thread performed 122 scans. [2025-05-15T10:21:13.955Z] ====== philosophers (scala) [default], iteration 12 completed (2004.882 ms) ====== [2025-05-15T10:21:13.955Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-15T10:21:13.955Z] GC before operation: completed in 11.417 ms, heap usage 36.669 MB -> 5.059 MB. [2025-05-15T10:21:15.756Z] Camera thread performed 122 scans. [2025-05-15T10:21:15.756Z] ====== philosophers (scala) [default], iteration 13 completed (2078.064 ms) ====== [2025-05-15T10:21:15.756Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-15T10:21:15.756Z] GC before operation: completed in 11.607 ms, heap usage 69.673 MB -> 5.059 MB. [2025-05-15T10:21:18.203Z] Camera thread performed 122 scans. [2025-05-15T10:21:18.203Z] ====== philosophers (scala) [default], iteration 14 completed (2021.715 ms) ====== [2025-05-15T10:21:18.203Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-15T10:21:18.203Z] GC before operation: completed in 11.670 ms, heap usage 62.719 MB -> 5.059 MB. [2025-05-15T10:21:20.019Z] Camera thread performed 122 scans. [2025-05-15T10:21:20.019Z] ====== philosophers (scala) [default], iteration 15 completed (2016.058 ms) ====== [2025-05-15T10:21:20.019Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-15T10:21:20.019Z] GC before operation: completed in 11.883 ms, heap usage 82.737 MB -> 5.060 MB. [2025-05-15T10:21:21.851Z] Camera thread performed 122 scans. [2025-05-15T10:21:21.851Z] ====== philosophers (scala) [default], iteration 16 completed (2103.082 ms) ====== [2025-05-15T10:21:21.851Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-15T10:21:21.851Z] GC before operation: completed in 11.547 ms, heap usage 61.688 MB -> 5.060 MB. [2025-05-15T10:21:24.300Z] Camera thread performed 122 scans. [2025-05-15T10:21:24.300Z] ====== philosophers (scala) [default], iteration 17 completed (2031.549 ms) ====== [2025-05-15T10:21:24.300Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-15T10:21:24.301Z] GC before operation: completed in 11.466 ms, heap usage 56.273 MB -> 5.060 MB. [2025-05-15T10:21:26.115Z] Camera thread performed 122 scans. [2025-05-15T10:21:26.115Z] ====== philosophers (scala) [default], iteration 18 completed (2150.239 ms) ====== [2025-05-15T10:21:26.115Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-15T10:21:26.115Z] GC before operation: completed in 11.485 ms, heap usage 38.787 MB -> 5.060 MB. [2025-05-15T10:21:28.556Z] Camera thread performed 122 scans. [2025-05-15T10:21:28.556Z] ====== philosophers (scala) [default], iteration 19 completed (2018.349 ms) ====== [2025-05-15T10:21:28.556Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-15T10:21:28.556Z] GC before operation: completed in 13.766 ms, heap usage 61.272 MB -> 5.060 MB. [2025-05-15T10:21:30.376Z] Camera thread performed 122 scans. [2025-05-15T10:21:30.376Z] ====== philosophers (scala) [default], iteration 20 completed (2012.942 ms) ====== [2025-05-15T10:21:30.376Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-15T10:21:30.376Z] GC before operation: completed in 11.812 ms, heap usage 87.096 MB -> 5.060 MB. [2025-05-15T10:21:32.206Z] Camera thread performed 122 scans. [2025-05-15T10:21:32.206Z] ====== philosophers (scala) [default], iteration 21 completed (1983.012 ms) ====== [2025-05-15T10:21:32.206Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-15T10:21:32.206Z] GC before operation: completed in 11.736 ms, heap usage 71.682 MB -> 5.060 MB. [2025-05-15T10:21:34.657Z] Camera thread performed 122 scans. [2025-05-15T10:21:34.657Z] ====== philosophers (scala) [default], iteration 22 completed (2094.775 ms) ====== [2025-05-15T10:21:34.657Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-15T10:21:34.657Z] GC before operation: completed in 11.401 ms, heap usage 6.135 MB -> 5.060 MB. [2025-05-15T10:21:36.586Z] Camera thread performed 122 scans. [2025-05-15T10:21:36.586Z] ====== philosophers (scala) [default], iteration 23 completed (2073.987 ms) ====== [2025-05-15T10:21:36.586Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-15T10:21:36.586Z] GC before operation: completed in 13.658 ms, heap usage 19.224 MB -> 5.060 MB. [2025-05-15T10:21:38.406Z] Camera thread performed 122 scans. [2025-05-15T10:21:38.406Z] ====== philosophers (scala) [default], iteration 24 completed (2100.623 ms) ====== [2025-05-15T10:21:38.406Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-15T10:21:38.406Z] GC before operation: completed in 11.312 ms, heap usage 12.148 MB -> 5.060 MB. [2025-05-15T10:21:40.528Z] Camera thread performed 122 scans. [2025-05-15T10:21:40.528Z] ====== philosophers (scala) [default], iteration 25 completed (2004.389 ms) ====== [2025-05-15T10:21:40.528Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-15T10:21:40.528Z] GC before operation: completed in 11.389 ms, heap usage 63.149 MB -> 5.060 MB. [2025-05-15T10:21:42.963Z] Camera thread performed 122 scans. [2025-05-15T10:21:42.963Z] ====== philosophers (scala) [default], iteration 26 completed (2068.323 ms) ====== [2025-05-15T10:21:42.963Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-15T10:21:42.963Z] GC before operation: completed in 11.658 ms, heap usage 10.141 MB -> 5.060 MB. [2025-05-15T10:21:44.776Z] Camera thread performed 122 scans. [2025-05-15T10:21:44.776Z] ====== philosophers (scala) [default], iteration 27 completed (2195.633 ms) ====== [2025-05-15T10:21:44.776Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-15T10:21:44.776Z] GC before operation: completed in 11.521 ms, heap usage 37.221 MB -> 5.060 MB. [2025-05-15T10:21:47.229Z] Camera thread performed 122 scans. [2025-05-15T10:21:47.229Z] ====== philosophers (scala) [default], iteration 28 completed (2042.241 ms) ====== [2025-05-15T10:21:47.229Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-15T10:21:47.229Z] GC before operation: completed in 11.437 ms, heap usage 11.094 MB -> 5.061 MB. [2025-05-15T10:21:49.049Z] Camera thread performed 122 scans. [2025-05-15T10:21:49.049Z] ====== philosophers (scala) [default], iteration 29 completed (2096.200 ms) ====== [2025-05-15T10:21:49.049Z] ----------------------------------- [2025-05-15T10:21:49.049Z] renaissance-philosophers_0_PASSED [2025-05-15T10:21:49.049Z] ----------------------------------- [2025-05-15T10:21:49.049Z] [2025-05-15T10:21:49.049Z] TEST TEARDOWN: [2025-05-15T10:21:49.049Z] Nothing to be done for teardown. [2025-05-15T10:21:49.049Z] renaissance-philosophers_0 Finish Time: Thu May 15 10:21:49 2025 Epoch Time (ms): 1747304509031