[2025-05-22T23:07:42.133Z] Running test renaissance-philosophers_0 ... [2025-05-22T23:07:42.133Z] =============================================== [2025-05-22T23:07:42.133Z] renaissance-philosophers_0 Start Time: Thu May 22 23:07:40 2025 Epoch Time (ms): 1747955260973 [2025-05-22T23:07:42.133Z] variation: NoOptions [2025-05-22T23:07:42.134Z] JVM_OPTIONS: [2025-05-22T23:07:42.134Z] { \ [2025-05-22T23:07:42.134Z] echo ""; echo "TEST SETUP:"; \ [2025-05-22T23:07:42.134Z] echo "Nothing to be done for setup."; \ [2025-05-22T23:07:42.134Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/renaissance-philosophers_0"; \ [2025-05-22T23:07:42.134Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/renaissance-philosophers_0"; \ [2025-05-22T23:07:42.134Z] echo ""; echo "TESTING:"; \ [2025-05-22T23:07:42.134Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-22T23:07:42.134Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-22T23:07:42.134Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-22T23:07:42.134Z] echo "Nothing to be done for teardown."; \ [2025-05-22T23:07:42.134Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/TestTargetResult"; [2025-05-22T23:07:42.134Z] [2025-05-22T23:07:42.134Z] TEST SETUP: [2025-05-22T23:07:42.134Z] Nothing to be done for setup. [2025-05-22T23:07:42.134Z] [2025-05-22T23:07:42.134Z] TESTING: [2025-05-22T23:07:43.705Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-05-22T23:07:43.705Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17479500784375/renaissance-philosophers_0/launcher-230741-16667384066397521315/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-05-22T23:07:43.705Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-05-22T23:07:43.705Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-05-22T23:07:44.464Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-22T23:07:45.238Z] GC before operation: completed in 83.359 ms, heap usage 27.115 MB -> 4.817 MB. [2025-05-22T23:07:57.581Z] Camera thread performed 122 scans. [2025-05-22T23:07:57.581Z] ====== philosophers (scala) [default], iteration 0 completed (11324.692 ms) ====== [2025-05-22T23:07:57.581Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-22T23:07:57.581Z] GC before operation: completed in 106.442 ms, heap usage 9.148 MB -> 5.150 MB. [2025-05-22T23:08:00.053Z] Camera thread performed 122 scans. [2025-05-22T23:08:00.053Z] ====== philosophers (scala) [default], iteration 1 completed (3795.454 ms) ====== [2025-05-22T23:08:00.053Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-22T23:08:00.053Z] GC before operation: completed in 60.008 ms, heap usage 37.334 MB -> 5.151 MB. [2025-05-22T23:08:03.531Z] Camera thread performed 122 scans. [2025-05-22T23:08:03.531Z] ====== philosophers (scala) [default], iteration 2 completed (3584.376 ms) ====== [2025-05-22T23:08:03.531Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-22T23:08:03.531Z] GC before operation: completed in 75.292 ms, heap usage 7.931 MB -> 5.151 MB. [2025-05-22T23:08:06.997Z] Camera thread performed 122 scans. [2025-05-22T23:08:06.997Z] ====== philosophers (scala) [default], iteration 3 completed (3115.549 ms) ====== [2025-05-22T23:08:06.997Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-22T23:08:06.997Z] GC before operation: completed in 54.274 ms, heap usage 10.173 MB -> 5.152 MB. [2025-05-22T23:08:10.502Z] Camera thread performed 122 scans. [2025-05-22T23:08:10.502Z] ====== philosophers (scala) [default], iteration 4 completed (3054.951 ms) ====== [2025-05-22T23:08:10.502Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-22T23:08:10.502Z] GC before operation: completed in 56.173 ms, heap usage 9.871 MB -> 5.153 MB. [2025-05-22T23:08:14.014Z] Camera thread performed 122 scans. [2025-05-22T23:08:14.015Z] ====== philosophers (scala) [default], iteration 5 completed (3463.249 ms) ====== [2025-05-22T23:08:14.015Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-22T23:08:14.015Z] GC before operation: completed in 55.002 ms, heap usage 42.062 MB -> 5.152 MB. [2025-05-22T23:08:17.452Z] Camera thread performed 122 scans. [2025-05-22T23:08:17.453Z] ====== philosophers (scala) [default], iteration 6 completed (3370.469 ms) ====== [2025-05-22T23:08:17.453Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-22T23:08:17.453Z] GC before operation: completed in 62.915 ms, heap usage 6.894 MB -> 5.152 MB. [2025-05-22T23:08:20.912Z] Camera thread performed 122 scans. [2025-05-22T23:08:20.912Z] ====== philosophers (scala) [default], iteration 7 completed (3336.421 ms) ====== [2025-05-22T23:08:20.912Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-22T23:08:20.912Z] GC before operation: completed in 66.385 ms, heap usage 15.826 MB -> 5.153 MB. [2025-05-22T23:08:24.340Z] Camera thread performed 122 scans. [2025-05-22T23:08:24.340Z] ====== philosophers (scala) [default], iteration 8 completed (3357.707 ms) ====== [2025-05-22T23:08:24.340Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-22T23:08:24.340Z] GC before operation: completed in 63.196 ms, heap usage 11.815 MB -> 5.153 MB. [2025-05-22T23:08:27.762Z] Camera thread performed 122 scans. [2025-05-22T23:08:27.762Z] ====== philosophers (scala) [default], iteration 9 completed (3268.642 ms) ====== [2025-05-22T23:08:27.762Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-22T23:08:27.762Z] GC before operation: completed in 57.859 ms, heap usage 18.778 MB -> 5.153 MB. [2025-05-22T23:08:30.271Z] Camera thread performed 122 scans. [2025-05-22T23:08:30.271Z] ====== philosophers (scala) [default], iteration 10 completed (3215.761 ms) ====== [2025-05-22T23:08:30.271Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-22T23:08:30.271Z] GC before operation: completed in 65.460 ms, heap usage 10.548 MB -> 5.153 MB. [2025-05-22T23:08:34.833Z] Camera thread performed 122 scans. [2025-05-22T23:08:34.833Z] ====== philosophers (scala) [default], iteration 11 completed (3790.971 ms) ====== [2025-05-22T23:08:34.833Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-22T23:08:34.833Z] GC before operation: completed in 73.054 ms, heap usage 9.877 MB -> 5.154 MB. [2025-05-22T23:08:37.291Z] Camera thread performed 122 scans. [2025-05-22T23:08:37.291Z] ====== philosophers (scala) [default], iteration 12 completed (3209.695 ms) ====== [2025-05-22T23:08:37.291Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-22T23:08:38.057Z] GC before operation: completed in 68.917 ms, heap usage 24.506 MB -> 5.155 MB. [2025-05-22T23:08:41.445Z] Camera thread performed 122 scans. [2025-05-22T23:08:41.445Z] ====== philosophers (scala) [default], iteration 13 completed (3310.474 ms) ====== [2025-05-22T23:08:41.445Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-22T23:08:41.445Z] GC before operation: completed in 73.257 ms, heap usage 43.553 MB -> 5.154 MB. [2025-05-22T23:08:43.921Z] Camera thread performed 122 scans. [2025-05-22T23:08:43.921Z] ====== philosophers (scala) [default], iteration 14 completed (3089.046 ms) ====== [2025-05-22T23:08:43.921Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-22T23:08:43.921Z] GC before operation: completed in 55.517 ms, heap usage 41.313 MB -> 5.155 MB. [2025-05-22T23:08:47.307Z] Camera thread performed 122 scans. [2025-05-22T23:08:47.307Z] ====== philosophers (scala) [default], iteration 15 completed (3441.654 ms) ====== [2025-05-22T23:08:47.307Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-22T23:08:48.735Z] GC before operation: completed in 70.579 ms, heap usage 21.162 MB -> 5.154 MB. [2025-05-22T23:08:51.205Z] Camera thread performed 122 scans. [2025-05-22T23:08:51.205Z] ====== philosophers (scala) [default], iteration 16 completed (3273.273 ms) ====== [2025-05-22T23:08:51.205Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-22T23:08:51.205Z] GC before operation: completed in 60.826 ms, heap usage 7.053 MB -> 5.155 MB. [2025-05-22T23:08:54.597Z] Camera thread performed 122 scans. [2025-05-22T23:08:54.597Z] ====== philosophers (scala) [default], iteration 17 completed (3131.358 ms) ====== [2025-05-22T23:08:54.597Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-22T23:08:54.597Z] GC before operation: completed in 61.652 ms, heap usage 29.709 MB -> 5.155 MB. [2025-05-22T23:08:57.085Z] Camera thread performed 122 scans. [2025-05-22T23:08:57.085Z] ====== philosophers (scala) [default], iteration 18 completed (2675.635 ms) ====== [2025-05-22T23:08:57.085Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-22T23:08:57.085Z] GC before operation: completed in 58.268 ms, heap usage 12.113 MB -> 5.155 MB. [2025-05-22T23:09:00.480Z] Camera thread performed 122 scans. [2025-05-22T23:09:00.480Z] ====== philosophers (scala) [default], iteration 19 completed (2941.620 ms) ====== [2025-05-22T23:09:00.480Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-22T23:09:00.480Z] GC before operation: completed in 68.487 ms, heap usage 31.554 MB -> 5.155 MB. [2025-05-22T23:09:02.922Z] Camera thread performed 122 scans. [2025-05-22T23:09:02.922Z] ====== philosophers (scala) [default], iteration 20 completed (3127.592 ms) ====== [2025-05-22T23:09:02.922Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-22T23:09:02.923Z] GC before operation: completed in 44.996 ms, heap usage 10.725 MB -> 5.156 MB. [2025-05-22T23:09:06.335Z] Camera thread performed 122 scans. [2025-05-22T23:09:06.335Z] ====== philosophers (scala) [default], iteration 21 completed (3002.541 ms) ====== [2025-05-22T23:09:06.335Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-22T23:09:06.335Z] GC before operation: completed in 53.763 ms, heap usage 8.659 MB -> 5.156 MB. [2025-05-22T23:09:09.733Z] Camera thread performed 122 scans. [2025-05-22T23:09:09.733Z] ====== philosophers (scala) [default], iteration 22 completed (3208.124 ms) ====== [2025-05-22T23:09:09.733Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-22T23:09:09.733Z] GC before operation: completed in 88.060 ms, heap usage 35.518 MB -> 5.156 MB. [2025-05-22T23:09:12.179Z] Camera thread performed 122 scans. [2025-05-22T23:09:12.179Z] ====== philosophers (scala) [default], iteration 23 completed (2826.039 ms) ====== [2025-05-22T23:09:12.179Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-22T23:09:12.179Z] GC before operation: completed in 47.082 ms, heap usage 24.545 MB -> 5.156 MB. [2025-05-22T23:09:15.582Z] Camera thread performed 122 scans. [2025-05-22T23:09:15.582Z] ====== philosophers (scala) [default], iteration 24 completed (2888.624 ms) ====== [2025-05-22T23:09:15.582Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-22T23:09:15.582Z] GC before operation: completed in 43.809 ms, heap usage 29.837 MB -> 5.156 MB. [2025-05-22T23:09:19.004Z] Camera thread performed 122 scans. [2025-05-22T23:09:19.004Z] ====== philosophers (scala) [default], iteration 25 completed (3080.345 ms) ====== [2025-05-22T23:09:19.004Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-22T23:09:19.004Z] GC before operation: completed in 76.432 ms, heap usage 16.785 MB -> 5.155 MB. [2025-05-22T23:09:21.463Z] Camera thread performed 122 scans. [2025-05-22T23:09:21.463Z] ====== philosophers (scala) [default], iteration 26 completed (2825.284 ms) ====== [2025-05-22T23:09:21.463Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-22T23:09:21.463Z] GC before operation: completed in 50.910 ms, heap usage 38.571 MB -> 5.156 MB. [2025-05-22T23:09:24.867Z] Camera thread performed 122 scans. [2025-05-22T23:09:24.867Z] ====== philosophers (scala) [default], iteration 27 completed (3054.284 ms) ====== [2025-05-22T23:09:24.867Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-22T23:09:24.867Z] GC before operation: completed in 82.419 ms, heap usage 30.941 MB -> 5.156 MB. [2025-05-22T23:09:27.329Z] Camera thread performed 122 scans. [2025-05-22T23:09:27.329Z] ====== philosophers (scala) [default], iteration 28 completed (3097.536 ms) ====== [2025-05-22T23:09:27.329Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-22T23:09:28.092Z] GC before operation: completed in 61.820 ms, heap usage 38.887 MB -> 5.156 MB. [2025-05-22T23:09:31.474Z] Camera thread performed 122 scans. [2025-05-22T23:09:31.474Z] ====== philosophers (scala) [default], iteration 29 completed (3605.396 ms) ====== [2025-05-22T23:09:32.262Z] ----------------------------------- [2025-05-22T23:09:32.262Z] renaissance-philosophers_0_PASSED [2025-05-22T23:09:32.262Z] ----------------------------------- [2025-05-22T23:09:32.262Z] [2025-05-22T23:09:32.262Z] TEST TEARDOWN: [2025-05-22T23:09:32.262Z] Nothing to be done for teardown. [2025-05-22T23:09:32.262Z] renaissance-philosophers_0 Finish Time: Thu May 22 23:09:31 2025 Epoch Time (ms): 1747955371951