[2025-04-03T22:29:39.723Z] Running test renaissance-philosophers_0 ... [2025-04-03T22:29:39.723Z] =============================================== [2025-04-03T22:29:39.723Z] renaissance-philosophers_0 Start Time: Thu Apr 3 22:29:39 2025 Epoch Time (ms): 1743719379182 [2025-04-03T22:29:39.723Z] variation: NoOptions [2025-04-03T22:29:39.723Z] JVM_OPTIONS: [2025-04-03T22:29:39.723Z] { \ [2025-04-03T22:29:39.723Z] echo ""; echo "TEST SETUP:"; \ [2025-04-03T22:29:39.723Z] echo "Nothing to be done for setup."; \ [2025-04-03T22:29:39.723Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17437186465962/renaissance-philosophers_0"; \ [2025-04-03T22:29:39.723Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17437186465962/renaissance-philosophers_0"; \ [2025-04-03T22:29:39.723Z] echo ""; echo "TESTING:"; \ [2025-04-03T22:29:39.723Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17437186465962/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-04-03T22:29:39.723Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17437186465962/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-03T22:29:39.723Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-03T22:29:39.723Z] echo "Nothing to be done for teardown."; \ [2025-04-03T22:29:39.723Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17437186465962/TestTargetResult"; [2025-04-03T22:29:39.723Z] [2025-04-03T22:29:39.723Z] TEST SETUP: [2025-04-03T22:29:39.723Z] Nothing to be done for setup. [2025-04-03T22:29:39.723Z] [2025-04-03T22:29:39.723Z] TESTING: [2025-04-03T22:29:41.508Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-04-03T22:29:41.508Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17437186465962/renaissance-philosophers_0/launcher-222939-14317768119106036221/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-04-03T22:29:41.508Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-04-03T22:29:41.508Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-04-03T22:29:42.386Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-04-03T22:29:42.386Z] GC before operation: completed in 71.830 ms, heap usage 27.125 MB -> 4.787 MB. [2025-04-03T22:29:55.445Z] Camera thread performed 122 scans. [2025-04-03T22:29:55.445Z] ====== philosophers (scala) [default], iteration 0 completed (12255.263 ms) ====== [2025-04-03T22:29:55.445Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-04-03T22:29:55.445Z] GC before operation: completed in 169.465 ms, heap usage 29.873 MB -> 5.128 MB. [2025-04-03T22:30:01.999Z] Camera thread performed 122 scans. [2025-04-03T22:30:01.999Z] ====== philosophers (scala) [default], iteration 1 completed (6989.434 ms) ====== [2025-04-03T22:30:01.999Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-04-03T22:30:01.999Z] GC before operation: completed in 100.365 ms, heap usage 18.852 MB -> 5.128 MB. [2025-04-03T22:30:08.265Z] Camera thread performed 122 scans. [2025-04-03T22:30:08.265Z] ====== philosophers (scala) [default], iteration 2 completed (4864.372 ms) ====== [2025-04-03T22:30:08.265Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-04-03T22:30:08.265Z] GC before operation: completed in 69.708 ms, heap usage 49.493 MB -> 5.129 MB. [2025-04-03T22:30:11.136Z] Camera thread performed 122 scans. [2025-04-03T22:30:11.136Z] ====== philosophers (scala) [default], iteration 3 completed (4292.386 ms) ====== [2025-04-03T22:30:11.136Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-04-03T22:30:11.136Z] GC before operation: completed in 70.739 ms, heap usage 39.656 MB -> 5.130 MB. [2025-04-03T22:30:16.097Z] Camera thread performed 122 scans. [2025-04-03T22:30:16.097Z] ====== philosophers (scala) [default], iteration 4 completed (4390.912 ms) ====== [2025-04-03T22:30:16.097Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-04-03T22:30:16.097Z] GC before operation: completed in 71.916 ms, heap usage 6.586 MB -> 5.130 MB. [2025-04-03T22:30:20.034Z] Camera thread performed 122 scans. [2025-04-03T22:30:20.034Z] ====== philosophers (scala) [default], iteration 5 completed (3975.339 ms) ====== [2025-04-03T22:30:20.034Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-04-03T22:30:20.034Z] GC before operation: completed in 106.519 ms, heap usage 36.634 MB -> 5.130 MB. [2025-04-03T22:30:25.112Z] Camera thread performed 122 scans. [2025-04-03T22:30:25.112Z] ====== philosophers (scala) [default], iteration 6 completed (4313.445 ms) ====== [2025-04-03T22:30:25.112Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-04-03T22:30:25.112Z] GC before operation: completed in 83.924 ms, heap usage 16.720 MB -> 5.131 MB. [2025-04-03T22:30:29.008Z] Camera thread performed 122 scans. [2025-04-03T22:30:29.008Z] ====== philosophers (scala) [default], iteration 7 completed (4067.695 ms) ====== [2025-04-03T22:30:29.008Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-04-03T22:30:29.009Z] GC before operation: completed in 90.306 ms, heap usage 8.863 MB -> 5.131 MB. [2025-04-03T22:30:37.622Z] Camera thread performed 122 scans. [2025-04-03T22:30:37.622Z] ====== philosophers (scala) [default], iteration 8 completed (4700.998 ms) ====== [2025-04-03T22:30:37.622Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-04-03T22:30:37.622Z] GC before operation: completed in 79.254 ms, heap usage 15.988 MB -> 5.131 MB. [2025-04-03T22:30:37.622Z] Camera thread performed 122 scans. [2025-04-03T22:30:37.622Z] ====== philosophers (scala) [default], iteration 9 completed (3342.167 ms) ====== [2025-04-03T22:30:37.622Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-04-03T22:30:37.622Z] GC before operation: completed in 74.656 ms, heap usage 66.965 MB -> 5.131 MB. [2025-04-03T22:30:40.376Z] Camera thread performed 122 scans. [2025-04-03T22:30:40.376Z] ====== philosophers (scala) [default], iteration 10 completed (2514.385 ms) ====== [2025-04-03T22:30:40.376Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-04-03T22:30:40.376Z] GC before operation: completed in 73.649 ms, heap usage 25.214 MB -> 5.132 MB. [2025-04-03T22:30:42.216Z] Camera thread performed 122 scans. [2025-04-03T22:30:42.216Z] ====== philosophers (scala) [default], iteration 11 completed (2995.755 ms) ====== [2025-04-03T22:30:42.216Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-04-03T22:30:42.216Z] GC before operation: completed in 62.821 ms, heap usage 34.634 MB -> 5.132 MB. [2025-04-03T22:30:46.364Z] Camera thread performed 122 scans. [2025-04-03T22:30:47.247Z] ====== philosophers (scala) [default], iteration 12 completed (4280.340 ms) ====== [2025-04-03T22:30:47.247Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-04-03T22:30:47.247Z] GC before operation: completed in 78.388 ms, heap usage 19.049 MB -> 5.132 MB. [2025-04-03T22:30:52.233Z] Camera thread performed 122 scans. [2025-04-03T22:30:52.233Z] ====== philosophers (scala) [default], iteration 13 completed (3399.755 ms) ====== [2025-04-03T22:30:52.233Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-04-03T22:30:52.233Z] GC before operation: completed in 33.262 ms, heap usage 6.323 MB -> 5.133 MB. [2025-04-03T22:30:52.233Z] Camera thread performed 122 scans. [2025-04-03T22:30:52.233Z] ====== philosophers (scala) [default], iteration 14 completed (1793.548 ms) ====== [2025-04-03T22:30:52.233Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-04-03T22:30:52.233Z] GC before operation: completed in 12.302 ms, heap usage 42.883 MB -> 5.133 MB. [2025-04-03T22:30:56.400Z] Camera thread performed 122 scans. [2025-04-03T22:30:56.400Z] ====== philosophers (scala) [default], iteration 15 completed (3843.416 ms) ====== [2025-04-03T22:30:56.400Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-04-03T22:30:56.400Z] GC before operation: completed in 99.926 ms, heap usage 21.964 MB -> 5.133 MB. [2025-04-03T22:31:00.319Z] Camera thread performed 122 scans. [2025-04-03T22:31:00.319Z] ====== philosophers (scala) [default], iteration 16 completed (4061.414 ms) ====== [2025-04-03T22:31:00.319Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-04-03T22:31:00.319Z] GC before operation: completed in 75.649 ms, heap usage 21.931 MB -> 5.134 MB. [2025-04-03T22:31:04.174Z] Camera thread performed 122 scans. [2025-04-03T22:31:04.174Z] ====== philosophers (scala) [default], iteration 17 completed (3595.298 ms) ====== [2025-04-03T22:31:04.174Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-04-03T22:31:04.174Z] GC before operation: completed in 91.124 ms, heap usage 36.703 MB -> 5.167 MB. [2025-04-03T22:31:08.043Z] Camera thread performed 122 scans. [2025-04-03T22:31:08.043Z] ====== philosophers (scala) [default], iteration 18 completed (3784.731 ms) ====== [2025-04-03T22:31:08.043Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-04-03T22:31:08.043Z] GC before operation: completed in 86.487 ms, heap usage 39.842 MB -> 5.134 MB. [2025-04-03T22:31:11.924Z] Camera thread performed 122 scans. [2025-04-03T22:31:11.924Z] ====== philosophers (scala) [default], iteration 19 completed (3870.703 ms) ====== [2025-04-03T22:31:11.924Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-04-03T22:31:11.924Z] GC before operation: completed in 65.225 ms, heap usage 11.557 MB -> 5.133 MB. [2025-04-03T22:31:15.799Z] Camera thread performed 122 scans. [2025-04-03T22:31:15.799Z] ====== philosophers (scala) [default], iteration 20 completed (4253.931 ms) ====== [2025-04-03T22:31:15.799Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-04-03T22:31:15.799Z] GC before operation: completed in 88.085 ms, heap usage 48.305 MB -> 5.134 MB. [2025-04-03T22:31:20.844Z] Camera thread performed 122 scans. [2025-04-03T22:31:20.844Z] ====== philosophers (scala) [default], iteration 21 completed (4329.118 ms) ====== [2025-04-03T22:31:20.844Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-04-03T22:31:20.844Z] GC before operation: completed in 79.661 ms, heap usage 6.304 MB -> 5.133 MB. [2025-04-03T22:31:24.691Z] Camera thread performed 122 scans. [2025-04-03T22:31:24.691Z] ====== philosophers (scala) [default], iteration 22 completed (3833.170 ms) ====== [2025-04-03T22:31:24.691Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-04-03T22:31:24.691Z] GC before operation: completed in 105.804 ms, heap usage 27.291 MB -> 5.134 MB. [2025-04-03T22:31:28.532Z] Camera thread performed 122 scans. [2025-04-03T22:31:28.532Z] ====== philosophers (scala) [default], iteration 23 completed (3994.841 ms) ====== [2025-04-03T22:31:28.532Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-04-03T22:31:28.532Z] GC before operation: completed in 68.408 ms, heap usage 10.567 MB -> 5.134 MB. [2025-04-03T22:31:32.363Z] Camera thread performed 122 scans. [2025-04-03T22:31:32.363Z] ====== philosophers (scala) [default], iteration 24 completed (3976.788 ms) ====== [2025-04-03T22:31:32.363Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-04-03T22:31:32.363Z] GC before operation: completed in 90.137 ms, heap usage 45.014 MB -> 5.134 MB. [2025-04-03T22:31:37.393Z] Camera thread performed 122 scans. [2025-04-03T22:31:37.393Z] ====== philosophers (scala) [default], iteration 25 completed (4143.716 ms) ====== [2025-04-03T22:31:37.393Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-04-03T22:31:37.393Z] GC before operation: completed in 93.084 ms, heap usage 7.570 MB -> 5.133 MB. [2025-04-03T22:31:41.245Z] Camera thread performed 122 scans. [2025-04-03T22:31:41.245Z] ====== philosophers (scala) [default], iteration 26 completed (3867.460 ms) ====== [2025-04-03T22:31:41.245Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-04-03T22:31:41.245Z] GC before operation: completed in 62.343 ms, heap usage 12.734 MB -> 5.134 MB. [2025-04-03T22:31:44.061Z] Camera thread performed 122 scans. [2025-04-03T22:31:44.061Z] ====== philosophers (scala) [default], iteration 27 completed (3696.344 ms) ====== [2025-04-03T22:31:44.061Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-04-03T22:31:44.927Z] GC before operation: completed in 75.254 ms, heap usage 28.190 MB -> 5.134 MB. [2025-04-03T22:31:47.687Z] Camera thread performed 122 scans. [2025-04-03T22:31:47.687Z] ====== philosophers (scala) [default], iteration 28 completed (3160.525 ms) ====== [2025-04-03T22:31:47.687Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-04-03T22:31:47.687Z] GC before operation: completed in 43.238 ms, heap usage 43.126 MB -> 5.134 MB. [2025-04-03T22:31:50.461Z] Camera thread performed 122 scans. [2025-04-03T22:31:50.461Z] ====== philosophers (scala) [default], iteration 29 completed (2982.578 ms) ====== [2025-04-03T22:31:52.271Z] ----------------------------------- [2025-04-03T22:31:52.271Z] renaissance-philosophers_0_PASSED [2025-04-03T22:31:52.271Z] ----------------------------------- [2025-04-03T22:31:52.271Z] [2025-04-03T22:31:52.271Z] TEST TEARDOWN: [2025-04-03T22:31:52.271Z] Nothing to be done for teardown. [2025-04-03T22:31:52.271Z] renaissance-philosophers_0 Finish Time: Thu Apr 3 22:31:51 2025 Epoch Time (ms): 1743719511421