[2025-12-30T15:59:43.762Z] Running test renaissance-philosophers_0 ... [2025-12-30T15:59:43.762Z] =============================================== [2025-12-30T15:59:43.762Z] renaissance-philosophers_0 Start Time: Tue Dec 30 15:59:43 2025 Epoch Time (ms): 1767110383454 [2025-12-30T15:59:43.762Z] variation: NoOptions [2025-12-30T15:59:43.762Z] JVM_OPTIONS: [2025-12-30T15:59:43.762Z] { \ [2025-12-30T15:59:43.762Z] echo ""; echo "TEST SETUP:"; \ [2025-12-30T15:59:43.762Z] echo "Nothing to be done for setup."; \ [2025-12-30T15:59:43.762Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17671101826428/renaissance-philosophers_0"; \ [2025-12-30T15:59:43.762Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17671101826428/renaissance-philosophers_0"; \ [2025-12-30T15:59:43.762Z] echo ""; echo "TESTING:"; \ [2025-12-30T15:59:43.762Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_s390x_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_openjdk26_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17671101826428/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-30T15:59:43.762Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17671101826428/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-30T15:59:43.762Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-30T15:59:43.762Z] echo "Nothing to be done for teardown."; \ [2025-12-30T15:59:43.762Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17671101826428/TestTargetResult"; [2025-12-30T15:59:43.762Z] [2025-12-30T15:59:43.762Z] TEST SETUP: [2025-12-30T15:59:43.762Z] Nothing to be done for setup. [2025-12-30T15:59:43.762Z] [2025-12-30T15:59:43.762Z] TESTING: [2025-12-30T15:59:44.380Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-30T15:59:44.380Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17671101826428/renaissance-philosophers_0/launcher-155943-348373437892730084/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-30T15:59:44.380Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-30T15:59:44.380Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-30T15:59:44.380Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-30T15:59:44.380Z] GC before operation: completed in 33.338 ms, heap usage 17.691 MB -> 5.052 MB. [2025-12-30T15:59:45.664Z] Camera thread performed 122 scans. [2025-12-30T15:59:45.664Z] ====== philosophers (scala) [default], iteration 0 completed (1091.430 ms) ====== [2025-12-30T15:59:45.664Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-30T15:59:45.664Z] GC before operation: completed in 13.967 ms, heap usage 48.546 MB -> 5.387 MB. [2025-12-30T15:59:46.281Z] Camera thread performed 122 scans. [2025-12-30T15:59:46.281Z] ====== philosophers (scala) [default], iteration 1 completed (473.694 ms) ====== [2025-12-30T15:59:46.282Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-30T15:59:46.282Z] GC before operation: completed in 13.505 ms, heap usage 38.698 MB -> 5.388 MB. [2025-12-30T15:59:47.565Z] Camera thread performed 122 scans. [2025-12-30T15:59:47.565Z] ====== philosophers (scala) [default], iteration 2 completed (954.559 ms) ====== [2025-12-30T15:59:47.565Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-30T15:59:47.565Z] GC before operation: completed in 10.964 ms, heap usage 41.018 MB -> 5.389 MB. [2025-12-30T15:59:48.182Z] Camera thread performed 122 scans. [2025-12-30T15:59:48.182Z] ====== philosophers (scala) [default], iteration 3 completed (926.358 ms) ====== [2025-12-30T15:59:48.182Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-30T15:59:48.182Z] GC before operation: completed in 8.391 ms, heap usage 40.352 MB -> 5.389 MB. [2025-12-30T15:59:48.798Z] Camera thread performed 122 scans. [2025-12-30T15:59:48.798Z] ====== philosophers (scala) [default], iteration 4 completed (460.544 ms) ====== [2025-12-30T15:59:48.798Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-30T15:59:48.798Z] GC before operation: completed in 8.224 ms, heap usage 16.521 MB -> 5.389 MB. [2025-12-30T15:59:49.413Z] Camera thread performed 122 scans. [2025-12-30T15:59:49.413Z] ====== philosophers (scala) [default], iteration 5 completed (469.019 ms) ====== [2025-12-30T15:59:49.413Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-30T15:59:49.413Z] GC before operation: completed in 8.821 ms, heap usage 18.492 MB -> 5.389 MB. [2025-12-30T15:59:49.413Z] Camera thread performed 122 scans. [2025-12-30T15:59:49.413Z] ====== philosophers (scala) [default], iteration 6 completed (449.645 ms) ====== [2025-12-30T15:59:49.413Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-30T15:59:49.413Z] GC before operation: completed in 8.009 ms, heap usage 7.960 MB -> 5.390 MB. [2025-12-30T15:59:50.029Z] Camera thread performed 122 scans. [2025-12-30T15:59:50.029Z] ====== philosophers (scala) [default], iteration 7 completed (490.304 ms) ====== [2025-12-30T15:59:50.029Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-30T15:59:50.029Z] GC before operation: completed in 10.943 ms, heap usage 41.313 MB -> 5.390 MB. [2025-12-30T15:59:51.312Z] Camera thread performed 122 scans. [2025-12-30T15:59:51.312Z] ====== philosophers (scala) [default], iteration 8 completed (1292.493 ms) ====== [2025-12-30T15:59:51.312Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-30T15:59:51.312Z] GC before operation: completed in 11.453 ms, heap usage 41.921 MB -> 5.391 MB. [2025-12-30T15:59:51.930Z] Camera thread performed 122 scans. [2025-12-30T15:59:51.930Z] ====== philosophers (scala) [default], iteration 9 completed (831.069 ms) ====== [2025-12-30T15:59:51.930Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-30T15:59:51.930Z] GC before operation: completed in 13.061 ms, heap usage 28.481 MB -> 5.391 MB. [2025-12-30T15:59:53.214Z] Camera thread performed 122 scans. [2025-12-30T15:59:53.214Z] ====== philosophers (scala) [default], iteration 10 completed (937.238 ms) ====== [2025-12-30T15:59:53.214Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-30T15:59:53.214Z] GC before operation: completed in 10.210 ms, heap usage 14.892 MB -> 5.391 MB. [2025-12-30T15:59:53.830Z] Camera thread performed 122 scans. [2025-12-30T15:59:53.830Z] ====== philosophers (scala) [default], iteration 11 completed (708.539 ms) ====== [2025-12-30T15:59:53.830Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-30T15:59:53.830Z] GC before operation: completed in 14.373 ms, heap usage 19.412 MB -> 5.391 MB. [2025-12-30T15:59:55.113Z] Camera thread performed 122 scans. [2025-12-30T15:59:55.113Z] ====== philosophers (scala) [default], iteration 12 completed (1010.455 ms) ====== [2025-12-30T15:59:55.113Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-30T15:59:55.113Z] GC before operation: completed in 12.031 ms, heap usage 23.335 MB -> 5.391 MB. [2025-12-30T15:59:55.729Z] Camera thread performed 122 scans. [2025-12-30T15:59:55.729Z] ====== philosophers (scala) [default], iteration 13 completed (536.084 ms) ====== [2025-12-30T15:59:55.729Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-30T15:59:55.729Z] GC before operation: completed in 15.424 ms, heap usage 16.992 MB -> 5.392 MB. [2025-12-30T15:59:55.729Z] Camera thread performed 122 scans. [2025-12-30T15:59:55.729Z] ====== philosophers (scala) [default], iteration 14 completed (530.503 ms) ====== [2025-12-30T15:59:55.729Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-30T15:59:55.729Z] GC before operation: completed in 11.486 ms, heap usage 18.853 MB -> 5.392 MB. [2025-12-30T15:59:56.345Z] Camera thread performed 122 scans. [2025-12-30T15:59:56.345Z] ====== philosophers (scala) [default], iteration 15 completed (557.417 ms) ====== [2025-12-30T15:59:56.345Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-30T15:59:56.345Z] GC before operation: completed in 10.862 ms, heap usage 44.519 MB -> 5.392 MB. [2025-12-30T15:59:56.962Z] Camera thread performed 122 scans. [2025-12-30T15:59:56.962Z] ====== philosophers (scala) [default], iteration 16 completed (518.452 ms) ====== [2025-12-30T15:59:56.962Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-30T15:59:56.962Z] GC before operation: completed in 12.457 ms, heap usage 16.876 MB -> 5.393 MB. [2025-12-30T15:59:57.580Z] Camera thread performed 122 scans. [2025-12-30T15:59:57.580Z] ====== philosophers (scala) [default], iteration 17 completed (518.768 ms) ====== [2025-12-30T15:59:57.580Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-30T15:59:57.580Z] GC before operation: completed in 12.183 ms, heap usage 100.281 MB -> 5.393 MB. [2025-12-30T15:59:58.559Z] Camera thread performed 122 scans. [2025-12-30T15:59:58.559Z] ====== philosophers (scala) [default], iteration 18 completed (549.642 ms) ====== [2025-12-30T15:59:58.559Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-30T15:59:58.559Z] GC before operation: completed in 13.369 ms, heap usage 124.004 MB -> 5.393 MB. [2025-12-30T15:59:59.181Z] Camera thread performed 122 scans. [2025-12-30T15:59:59.181Z] ====== philosophers (scala) [default], iteration 19 completed (933.532 ms) ====== [2025-12-30T15:59:59.181Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-30T15:59:59.181Z] GC before operation: completed in 12.052 ms, heap usage 33.315 MB -> 5.393 MB. [2025-12-30T15:59:59.796Z] Camera thread performed 122 scans. [2025-12-30T15:59:59.796Z] ====== philosophers (scala) [default], iteration 20 completed (514.742 ms) ====== [2025-12-30T15:59:59.796Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-30T15:59:59.796Z] GC before operation: completed in 11.743 ms, heap usage 96.897 MB -> 5.393 MB. [2025-12-30T16:00:01.080Z] Camera thread performed 122 scans. [2025-12-30T16:00:01.080Z] ====== philosophers (scala) [default], iteration 21 completed (1260.136 ms) ====== [2025-12-30T16:00:01.080Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-30T16:00:01.080Z] GC before operation: completed in 15.995 ms, heap usage 8.489 MB -> 5.393 MB. [2025-12-30T16:00:01.697Z] Camera thread performed 122 scans. [2025-12-30T16:00:01.697Z] ====== philosophers (scala) [default], iteration 22 completed (567.033 ms) ====== [2025-12-30T16:00:01.697Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-30T16:00:01.697Z] GC before operation: completed in 15.611 ms, heap usage 23.987 MB -> 5.394 MB. [2025-12-30T16:00:02.352Z] Camera thread performed 122 scans. [2025-12-30T16:00:02.352Z] ====== philosophers (scala) [default], iteration 23 completed (527.398 ms) ====== [2025-12-30T16:00:02.352Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-30T16:00:02.352Z] GC before operation: completed in 11.638 ms, heap usage 23.308 MB -> 5.394 MB. [2025-12-30T16:00:02.352Z] Camera thread performed 122 scans. [2025-12-30T16:00:02.352Z] ====== philosophers (scala) [default], iteration 24 completed (529.695 ms) ====== [2025-12-30T16:00:02.352Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-30T16:00:02.352Z] GC before operation: completed in 13.915 ms, heap usage 40.988 MB -> 5.394 MB. [2025-12-30T16:00:03.021Z] Camera thread performed 122 scans. [2025-12-30T16:00:03.021Z] ====== philosophers (scala) [default], iteration 25 completed (613.744 ms) ====== [2025-12-30T16:00:03.021Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-30T16:00:03.021Z] GC before operation: completed in 11.650 ms, heap usage 27.467 MB -> 5.394 MB. [2025-12-30T16:00:03.640Z] Camera thread performed 122 scans. [2025-12-30T16:00:03.640Z] ====== philosophers (scala) [default], iteration 26 completed (486.987 ms) ====== [2025-12-30T16:00:03.640Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-30T16:00:03.640Z] GC before operation: completed in 10.242 ms, heap usage 40.481 MB -> 5.394 MB. [2025-12-30T16:00:04.256Z] Camera thread performed 122 scans. [2025-12-30T16:00:04.256Z] ====== philosophers (scala) [default], iteration 27 completed (504.128 ms) ====== [2025-12-30T16:00:04.256Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-30T16:00:04.256Z] GC before operation: completed in 12.404 ms, heap usage 18.511 MB -> 5.394 MB. [2025-12-30T16:00:04.872Z] Camera thread performed 122 scans. [2025-12-30T16:00:04.872Z] ====== philosophers (scala) [default], iteration 28 completed (535.638 ms) ====== [2025-12-30T16:00:04.872Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-30T16:00:04.872Z] GC before operation: completed in 10.982 ms, heap usage 28.491 MB -> 5.394 MB. [2025-12-30T16:00:05.494Z] Camera thread performed 122 scans. [2025-12-30T16:00:05.494Z] ====== philosophers (scala) [default], iteration 29 completed (465.917 ms) ====== [2025-12-30T16:00:05.494Z] ----------------------------------- [2025-12-30T16:00:05.494Z] renaissance-philosophers_0_PASSED [2025-12-30T16:00:05.494Z] ----------------------------------- [2025-12-30T16:00:05.494Z] [2025-12-30T16:00:05.494Z] TEST TEARDOWN: [2025-12-30T16:00:05.494Z] Nothing to be done for teardown. [2025-12-30T16:00:05.494Z] renaissance-philosophers_0 Finish Time: Tue Dec 30 16:00:05 2025 Epoch Time (ms): 1767110405079