[2025-06-27T07:27:19.367Z] Running test renaissance-philosophers_0 ... [2025-06-27T07:27:19.367Z] =============================================== [2025-06-27T07:27:19.367Z] renaissance-philosophers_0 Start Time: Fri Jun 27 07:27:18 2025 Epoch Time (ms): 1751009238663 [2025-06-27T07:27:19.367Z] variation: NoOptions [2025-06-27T07:27:19.367Z] JVM_OPTIONS: [2025-06-27T07:27:19.367Z] { \ [2025-06-27T07:27:19.367Z] echo ""; echo "TEST SETUP:"; \ [2025-06-27T07:27:19.367Z] echo "Nothing to be done for setup."; \ [2025-06-27T07:27:19.367Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17509993728803/renaissance-philosophers_0"; \ [2025-06-27T07:27:19.367Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17509993728803/renaissance-philosophers_0"; \ [2025-06-27T07:27:19.367Z] echo ""; echo "TESTING:"; \ [2025-06-27T07:27:19.367Z] "/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_17509993728803/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-27T07:27:19.367Z] 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_17509993728803/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-27T07:27:19.367Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-27T07:27:19.367Z] echo "Nothing to be done for teardown."; \ [2025-06-27T07:27:19.367Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17509993728803/TestTargetResult"; [2025-06-27T07:27:19.367Z] [2025-06-27T07:27:19.367Z] TEST SETUP: [2025-06-27T07:27:19.367Z] Nothing to be done for setup. [2025-06-27T07:27:19.367Z] [2025-06-27T07:27:19.367Z] TESTING: [2025-06-27T07:27:22.724Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-06-27T07:27:22.724Z] 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_17509993728803/renaissance-philosophers_0/launcher-072719-18081776536389938061/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-06-27T07:27:22.724Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-06-27T07:27:22.724Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-06-27T07:27:24.328Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-27T07:27:24.328Z] GC before operation: completed in 156.914 ms, heap usage 27.129 MB -> 4.802 MB. [2025-06-27T07:27:43.644Z] Camera thread performed 122 scans. [2025-06-27T07:27:43.644Z] ====== philosophers (scala) [default], iteration 0 completed (17336.011 ms) ====== [2025-06-27T07:27:43.644Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-27T07:27:43.644Z] GC before operation: completed in 114.423 ms, heap usage 9.870 MB -> 5.134 MB. [2025-06-27T07:27:46.191Z] Camera thread performed 122 scans. [2025-06-27T07:27:46.191Z] ====== philosophers (scala) [default], iteration 1 completed (4176.780 ms) ====== [2025-06-27T07:27:46.191Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-27T07:27:46.191Z] GC before operation: completed in 76.452 ms, heap usage 15.325 MB -> 5.137 MB. [2025-06-27T07:27:50.756Z] Camera thread performed 122 scans. [2025-06-27T07:27:50.756Z] ====== philosophers (scala) [default], iteration 2 completed (4322.051 ms) ====== [2025-06-27T07:27:50.756Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-27T07:27:50.756Z] GC before operation: completed in 69.790 ms, heap usage 19.244 MB -> 5.137 MB. [2025-06-27T07:27:55.197Z] Camera thread performed 122 scans. [2025-06-27T07:27:55.197Z] ====== philosophers (scala) [default], iteration 3 completed (3974.801 ms) ====== [2025-06-27T07:27:55.197Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-27T07:27:55.197Z] GC before operation: completed in 76.088 ms, heap usage 22.482 MB -> 5.137 MB. [2025-06-27T07:27:59.646Z] Camera thread performed 122 scans. [2025-06-27T07:27:59.646Z] ====== philosophers (scala) [default], iteration 4 completed (4318.522 ms) ====== [2025-06-27T07:27:59.646Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-27T07:27:59.646Z] GC before operation: completed in 67.334 ms, heap usage 16.147 MB -> 5.138 MB. [2025-06-27T07:28:04.608Z] Camera thread performed 122 scans. [2025-06-27T07:28:04.608Z] ====== philosophers (scala) [default], iteration 5 completed (5553.816 ms) ====== [2025-06-27T07:28:04.608Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-27T07:28:04.608Z] GC before operation: completed in 81.366 ms, heap usage 23.816 MB -> 5.138 MB. [2025-06-27T07:28:09.017Z] Camera thread performed 122 scans. [2025-06-27T07:28:09.017Z] ====== philosophers (scala) [default], iteration 6 completed (3915.104 ms) ====== [2025-06-27T07:28:09.017Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-27T07:28:09.017Z] GC before operation: completed in 73.299 ms, heap usage 16.379 MB -> 5.138 MB. [2025-06-27T07:28:12.537Z] Camera thread performed 122 scans. [2025-06-27T07:28:12.537Z] ====== philosophers (scala) [default], iteration 7 completed (3713.055 ms) ====== [2025-06-27T07:28:12.537Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-27T07:28:12.537Z] GC before operation: completed in 89.654 ms, heap usage 16.536 MB -> 5.138 MB. [2025-06-27T07:28:18.250Z] Camera thread performed 122 scans. [2025-06-27T07:28:18.250Z] ====== philosophers (scala) [default], iteration 8 completed (4821.254 ms) ====== [2025-06-27T07:28:18.250Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-27T07:28:18.250Z] GC before operation: completed in 66.751 ms, heap usage 25.006 MB -> 5.138 MB. [2025-06-27T07:28:21.754Z] Camera thread performed 122 scans. [2025-06-27T07:28:21.754Z] ====== philosophers (scala) [default], iteration 9 completed (3812.893 ms) ====== [2025-06-27T07:28:21.754Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-27T07:28:21.754Z] GC before operation: completed in 86.963 ms, heap usage 6.269 MB -> 5.138 MB. [2025-06-27T07:28:26.338Z] Camera thread performed 122 scans. [2025-06-27T07:28:26.338Z] ====== philosophers (scala) [default], iteration 10 completed (4560.127 ms) ====== [2025-06-27T07:28:26.338Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-27T07:28:26.338Z] GC before operation: completed in 76.472 ms, heap usage 29.734 MB -> 5.139 MB. [2025-06-27T07:28:30.820Z] Camera thread performed 122 scans. [2025-06-27T07:28:30.820Z] ====== philosophers (scala) [default], iteration 11 completed (4833.800 ms) ====== [2025-06-27T07:28:30.820Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-27T07:28:30.820Z] GC before operation: completed in 72.646 ms, heap usage 44.726 MB -> 5.139 MB. [2025-06-27T07:28:35.360Z] Camera thread performed 122 scans. [2025-06-27T07:28:35.360Z] ====== philosophers (scala) [default], iteration 12 completed (4048.464 ms) ====== [2025-06-27T07:28:35.360Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-27T07:28:35.360Z] GC before operation: completed in 88.971 ms, heap usage 29.811 MB -> 5.140 MB. [2025-06-27T07:28:39.880Z] Camera thread performed 122 scans. [2025-06-27T07:28:39.880Z] ====== philosophers (scala) [default], iteration 13 completed (4293.427 ms) ====== [2025-06-27T07:28:39.880Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-27T07:28:39.880Z] GC before operation: completed in 103.971 ms, heap usage 26.541 MB -> 5.139 MB. [2025-06-27T07:28:44.345Z] Camera thread performed 122 scans. [2025-06-27T07:28:44.345Z] ====== philosophers (scala) [default], iteration 14 completed (4582.625 ms) ====== [2025-06-27T07:28:44.345Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-27T07:28:44.345Z] GC before operation: completed in 104.805 ms, heap usage 10.131 MB -> 5.140 MB. [2025-06-27T07:28:49.311Z] Camera thread performed 122 scans. [2025-06-27T07:28:49.311Z] ====== philosophers (scala) [default], iteration 15 completed (5141.035 ms) ====== [2025-06-27T07:28:49.311Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-27T07:28:49.311Z] GC before operation: completed in 96.590 ms, heap usage 26.544 MB -> 5.140 MB. [2025-06-27T07:28:53.761Z] Camera thread performed 122 scans. [2025-06-27T07:28:53.761Z] ====== philosophers (scala) [default], iteration 16 completed (4377.360 ms) ====== [2025-06-27T07:28:53.761Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-27T07:28:53.761Z] GC before operation: completed in 82.652 ms, heap usage 20.158 MB -> 5.140 MB. [2025-06-27T07:28:58.225Z] Camera thread performed 122 scans. [2025-06-27T07:28:58.225Z] ====== philosophers (scala) [default], iteration 17 completed (4078.067 ms) ====== [2025-06-27T07:28:58.225Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-27T07:28:58.225Z] GC before operation: completed in 72.679 ms, heap usage 34.710 MB -> 5.140 MB. [2025-06-27T07:29:01.673Z] Camera thread performed 122 scans. [2025-06-27T07:29:01.673Z] ====== philosophers (scala) [default], iteration 18 completed (3867.859 ms) ====== [2025-06-27T07:29:01.673Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-27T07:29:02.470Z] GC before operation: completed in 67.292 ms, heap usage 7.930 MB -> 5.140 MB. [2025-06-27T07:29:05.988Z] Camera thread performed 122 scans. [2025-06-27T07:29:05.988Z] ====== philosophers (scala) [default], iteration 19 completed (3704.914 ms) ====== [2025-06-27T07:29:05.988Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-27T07:29:05.988Z] GC before operation: completed in 80.451 ms, heap usage 12.187 MB -> 5.141 MB. [2025-06-27T07:29:09.488Z] Camera thread performed 122 scans. [2025-06-27T07:29:09.488Z] ====== philosophers (scala) [default], iteration 20 completed (3923.199 ms) ====== [2025-06-27T07:29:09.488Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-27T07:29:10.282Z] GC before operation: completed in 84.234 ms, heap usage 45.227 MB -> 5.140 MB. [2025-06-27T07:29:13.796Z] Camera thread performed 122 scans. [2025-06-27T07:29:13.796Z] ====== philosophers (scala) [default], iteration 21 completed (3943.533 ms) ====== [2025-06-27T07:29:13.796Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-27T07:29:13.796Z] GC before operation: completed in 75.639 ms, heap usage 28.998 MB -> 5.141 MB. [2025-06-27T07:29:18.349Z] Camera thread performed 122 scans. [2025-06-27T07:29:18.349Z] ====== philosophers (scala) [default], iteration 22 completed (4288.599 ms) ====== [2025-06-27T07:29:18.349Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-27T07:29:18.349Z] GC before operation: completed in 104.544 ms, heap usage 7.331 MB -> 5.140 MB. [2025-06-27T07:29:21.764Z] Camera thread performed 122 scans. [2025-06-27T07:29:21.764Z] ====== philosophers (scala) [default], iteration 23 completed (3770.877 ms) ====== [2025-06-27T07:29:21.764Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-27T07:29:22.529Z] GC before operation: completed in 66.851 ms, heap usage 43.340 MB -> 5.141 MB. [2025-06-27T07:29:25.917Z] Camera thread performed 122 scans. [2025-06-27T07:29:25.917Z] ====== philosophers (scala) [default], iteration 24 completed (3662.828 ms) ====== [2025-06-27T07:29:25.917Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-27T07:29:25.917Z] GC before operation: completed in 60.490 ms, heap usage 22.329 MB -> 5.141 MB. [2025-06-27T07:29:30.332Z] Camera thread performed 122 scans. [2025-06-27T07:29:30.332Z] ====== philosophers (scala) [default], iteration 25 completed (3970.833 ms) ====== [2025-06-27T07:29:30.332Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-27T07:29:30.332Z] GC before operation: completed in 69.726 ms, heap usage 14.874 MB -> 5.141 MB. [2025-06-27T07:29:33.796Z] Camera thread performed 122 scans. [2025-06-27T07:29:33.796Z] ====== philosophers (scala) [default], iteration 26 completed (3792.227 ms) ====== [2025-06-27T07:29:33.796Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-27T07:29:33.796Z] GC before operation: completed in 79.320 ms, heap usage 56.567 MB -> 5.142 MB. [2025-06-27T07:29:37.809Z] Camera thread performed 122 scans. [2025-06-27T07:29:37.809Z] ====== philosophers (scala) [default], iteration 27 completed (4301.444 ms) ====== [2025-06-27T07:29:37.809Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-27T07:29:37.809Z] GC before operation: completed in 67.044 ms, heap usage 18.794 MB -> 5.142 MB. [2025-06-27T07:29:42.387Z] Camera thread performed 122 scans. [2025-06-27T07:29:42.387Z] ====== philosophers (scala) [default], iteration 28 completed (3594.055 ms) ====== [2025-06-27T07:29:42.387Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-27T07:29:42.387Z] GC before operation: completed in 78.297 ms, heap usage 41.884 MB -> 5.141 MB. [2025-06-27T07:29:45.877Z] Camera thread performed 122 scans. [2025-06-27T07:29:45.877Z] ====== philosophers (scala) [default], iteration 29 completed (3914.910 ms) ====== [2025-06-27T07:29:47.474Z] ----------------------------------- [2025-06-27T07:29:47.474Z] renaissance-philosophers_0_PASSED [2025-06-27T07:29:47.474Z] ----------------------------------- [2025-06-27T07:29:47.474Z] [2025-06-27T07:29:47.474Z] TEST TEARDOWN: [2025-06-27T07:29:47.474Z] Nothing to be done for teardown. [2025-06-27T07:29:47.474Z] renaissance-philosophers_0 Finish Time: Fri Jun 27 07:29:46 2025 Epoch Time (ms): 1751009386990