[2025-10-22T18:18:30.506Z] Running test renaissance-philosophers_0 ... [2025-10-22T18:18:30.506Z] =============================================== [2025-10-22T18:18:30.506Z] renaissance-philosophers_0 Start Time: Wed Oct 22 18:18:29 2025 Epoch Time (ms): 1761157109763 [2025-10-22T18:18:30.506Z] variation: NoOptions [2025-10-22T18:18:30.506Z] JVM_OPTIONS: [2025-10-22T18:18:30.506Z] { \ [2025-10-22T18:18:30.506Z] echo ""; echo "TEST SETUP:"; \ [2025-10-22T18:18:30.506Z] echo "Nothing to be done for setup."; \ [2025-10-22T18:18:30.506Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/renaissance-philosophers_0"; \ [2025-10-22T18:18:30.506Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/renaissance-philosophers_0"; \ [2025-10-22T18:18:30.506Z] echo ""; echo "TESTING:"; \ [2025-10-22T18:18:30.507Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/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_s390x_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-10-22T18:18:30.507Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-22T18:18:30.507Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-22T18:18:30.507Z] echo "Nothing to be done for teardown."; \ [2025-10-22T18:18:30.507Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/../TKG/output_17611555014445/TestTargetResult"; [2025-10-22T18:18:30.507Z] [2025-10-22T18:18:30.507Z] TEST SETUP: [2025-10-22T18:18:30.507Z] Nothing to be done for setup. [2025-10-22T18:18:30.507Z] [2025-10-22T18:18:30.507Z] TESTING: [2025-10-22T18:18:31.224Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-22T18:18:31.224Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux_testList_1/aqa-tests/TKG/output_17611555014445/renaissance-philosophers_0/launcher-181830-4067745335513992884/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-22T18:18:31.224Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-22T18:18:31.224Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-22T18:18:31.224Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-10-22T18:18:31.224Z] GC before operation: completed in 15.440 ms, heap usage 15.602 MB -> 5.046 MB. [2025-10-22T18:18:32.607Z] Camera thread performed 122 scans. [2025-10-22T18:18:32.607Z] ====== philosophers (scala) [default], iteration 0 completed (1342.668 ms) ====== [2025-10-22T18:18:32.607Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-10-22T18:18:32.607Z] GC before operation: completed in 15.130 ms, heap usage 11.981 MB -> 5.380 MB. [2025-10-22T18:18:33.237Z] Camera thread performed 122 scans. [2025-10-22T18:18:33.237Z] ====== philosophers (scala) [default], iteration 1 completed (530.245 ms) ====== [2025-10-22T18:18:33.237Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-10-22T18:18:33.237Z] GC before operation: completed in 12.500 ms, heap usage 35.228 MB -> 5.381 MB. [2025-10-22T18:18:33.869Z] Camera thread performed 122 scans. [2025-10-22T18:18:33.869Z] ====== philosophers (scala) [default], iteration 2 completed (736.945 ms) ====== [2025-10-22T18:18:33.869Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-10-22T18:18:33.869Z] GC before operation: completed in 11.968 ms, heap usage 36.633 MB -> 5.384 MB. [2025-10-22T18:18:34.489Z] Camera thread performed 122 scans. [2025-10-22T18:18:34.489Z] ====== philosophers (scala) [default], iteration 3 completed (674.603 ms) ====== [2025-10-22T18:18:34.489Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-10-22T18:18:34.489Z] GC before operation: completed in 11.536 ms, heap usage 23.905 MB -> 5.384 MB. [2025-10-22T18:18:35.783Z] Camera thread performed 122 scans. [2025-10-22T18:18:35.783Z] ====== philosophers (scala) [default], iteration 4 completed (983.214 ms) ====== [2025-10-22T18:18:35.783Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-10-22T18:18:35.783Z] GC before operation: completed in 10.282 ms, heap usage 25.532 MB -> 5.384 MB. [2025-10-22T18:18:35.783Z] Camera thread performed 122 scans. [2025-10-22T18:18:35.783Z] ====== philosophers (scala) [default], iteration 5 completed (622.462 ms) ====== [2025-10-22T18:18:35.783Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-10-22T18:18:36.409Z] GC before operation: completed in 10.950 ms, heap usage 26.226 MB -> 5.384 MB. [2025-10-22T18:18:36.409Z] Camera thread performed 122 scans. [2025-10-22T18:18:36.409Z] ====== philosophers (scala) [default], iteration 6 completed (517.575 ms) ====== [2025-10-22T18:18:36.409Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-10-22T18:18:36.409Z] GC before operation: completed in 9.477 ms, heap usage 38.512 MB -> 5.384 MB. [2025-10-22T18:18:37.039Z] Camera thread performed 122 scans. [2025-10-22T18:18:37.039Z] ====== philosophers (scala) [default], iteration 7 completed (546.633 ms) ====== [2025-10-22T18:18:37.039Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-10-22T18:18:37.039Z] GC before operation: completed in 9.982 ms, heap usage 40.173 MB -> 5.384 MB. [2025-10-22T18:18:37.658Z] Camera thread performed 122 scans. [2025-10-22T18:18:37.658Z] ====== philosophers (scala) [default], iteration 8 completed (567.547 ms) ====== [2025-10-22T18:18:37.658Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-10-22T18:18:37.658Z] GC before operation: completed in 10.352 ms, heap usage 35.811 MB -> 5.385 MB. [2025-10-22T18:18:38.276Z] Camera thread performed 122 scans. [2025-10-22T18:18:38.276Z] ====== philosophers (scala) [default], iteration 9 completed (499.739 ms) ====== [2025-10-22T18:18:38.276Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-10-22T18:18:38.276Z] GC before operation: completed in 9.810 ms, heap usage 39.775 MB -> 5.385 MB. [2025-10-22T18:18:38.895Z] Camera thread performed 122 scans. [2025-10-22T18:18:38.895Z] ====== philosophers (scala) [default], iteration 10 completed (557.001 ms) ====== [2025-10-22T18:18:38.895Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-10-22T18:18:38.895Z] GC before operation: completed in 9.857 ms, heap usage 15.835 MB -> 5.385 MB. [2025-10-22T18:18:39.515Z] Camera thread performed 122 scans. [2025-10-22T18:18:39.515Z] ====== philosophers (scala) [default], iteration 11 completed (703.143 ms) ====== [2025-10-22T18:18:39.515Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-10-22T18:18:39.515Z] GC before operation: completed in 11.618 ms, heap usage 24.888 MB -> 5.385 MB. [2025-10-22T18:18:40.133Z] Camera thread performed 122 scans. [2025-10-22T18:18:40.133Z] ====== philosophers (scala) [default], iteration 12 completed (687.058 ms) ====== [2025-10-22T18:18:40.133Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-10-22T18:18:40.133Z] GC before operation: completed in 12.521 ms, heap usage 6.323 MB -> 5.385 MB. [2025-10-22T18:18:40.754Z] Camera thread performed 122 scans. [2025-10-22T18:18:40.754Z] ====== philosophers (scala) [default], iteration 13 completed (584.843 ms) ====== [2025-10-22T18:18:40.754Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-10-22T18:18:40.754Z] GC before operation: completed in 13.981 ms, heap usage 22.966 MB -> 5.386 MB. [2025-10-22T18:18:41.375Z] Camera thread performed 122 scans. [2025-10-22T18:18:41.375Z] ====== philosophers (scala) [default], iteration 14 completed (617.003 ms) ====== [2025-10-22T18:18:41.375Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-10-22T18:18:41.375Z] GC before operation: completed in 13.585 ms, heap usage 30.479 MB -> 5.386 MB. [2025-10-22T18:18:41.992Z] Camera thread performed 122 scans. [2025-10-22T18:18:41.992Z] ====== philosophers (scala) [default], iteration 15 completed (646.966 ms) ====== [2025-10-22T18:18:41.992Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-10-22T18:18:41.992Z] GC before operation: completed in 12.552 ms, heap usage 36.944 MB -> 5.386 MB. [2025-10-22T18:18:43.282Z] Camera thread performed 122 scans. [2025-10-22T18:18:43.282Z] ====== philosophers (scala) [default], iteration 16 completed (1391.437 ms) ====== [2025-10-22T18:18:43.282Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-10-22T18:18:43.282Z] GC before operation: completed in 9.264 ms, heap usage 7.902 MB -> 5.387 MB. [2025-10-22T18:18:44.573Z] Camera thread performed 122 scans. [2025-10-22T18:18:44.573Z] ====== philosophers (scala) [default], iteration 17 completed (684.506 ms) ====== [2025-10-22T18:18:44.573Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-10-22T18:18:44.573Z] GC before operation: completed in 8.901 ms, heap usage 14.959 MB -> 5.387 MB. [2025-10-22T18:18:44.573Z] Camera thread performed 122 scans. [2025-10-22T18:18:44.573Z] ====== philosophers (scala) [default], iteration 18 completed (557.268 ms) ====== [2025-10-22T18:18:44.573Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-10-22T18:18:44.573Z] GC before operation: completed in 10.001 ms, heap usage 14.488 MB -> 5.387 MB. [2025-10-22T18:18:45.864Z] Camera thread performed 122 scans. [2025-10-22T18:18:45.864Z] ====== philosophers (scala) [default], iteration 19 completed (777.960 ms) ====== [2025-10-22T18:18:45.864Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-10-22T18:18:45.864Z] GC before operation: completed in 10.366 ms, heap usage 35.983 MB -> 5.387 MB. [2025-10-22T18:18:45.864Z] Camera thread performed 122 scans. [2025-10-22T18:18:45.864Z] ====== philosophers (scala) [default], iteration 20 completed (466.232 ms) ====== [2025-10-22T18:18:45.864Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-10-22T18:18:45.864Z] GC before operation: completed in 11.444 ms, heap usage 29.979 MB -> 5.387 MB. [2025-10-22T18:18:46.482Z] Camera thread performed 122 scans. [2025-10-22T18:18:46.482Z] ====== philosophers (scala) [default], iteration 21 completed (699.311 ms) ====== [2025-10-22T18:18:46.482Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-10-22T18:18:46.482Z] GC before operation: completed in 10.373 ms, heap usage 21.917 MB -> 5.387 MB. [2025-10-22T18:18:47.101Z] Camera thread performed 122 scans. [2025-10-22T18:18:47.101Z] ====== philosophers (scala) [default], iteration 22 completed (606.925 ms) ====== [2025-10-22T18:18:47.101Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-10-22T18:18:47.101Z] GC before operation: completed in 11.230 ms, heap usage 22.473 MB -> 5.388 MB. [2025-10-22T18:18:47.722Z] Camera thread performed 122 scans. [2025-10-22T18:18:47.722Z] ====== philosophers (scala) [default], iteration 23 completed (580.268 ms) ====== [2025-10-22T18:18:47.722Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-10-22T18:18:47.722Z] GC before operation: completed in 9.644 ms, heap usage 38.858 MB -> 5.388 MB. [2025-10-22T18:18:49.610Z] Camera thread performed 122 scans. [2025-10-22T18:18:49.610Z] ====== philosophers (scala) [default], iteration 24 completed (550.110 ms) ====== [2025-10-22T18:18:49.610Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-10-22T18:18:49.610Z] GC before operation: completed in 10.091 ms, heap usage 31.922 MB -> 5.388 MB. [2025-10-22T18:18:50.333Z] Camera thread performed 122 scans. [2025-10-22T18:18:50.333Z] ====== philosophers (scala) [default], iteration 25 completed (572.516 ms) ====== [2025-10-22T18:18:50.333Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-10-22T18:18:50.333Z] GC before operation: completed in 12.647 ms, heap usage 44.440 MB -> 5.388 MB. [2025-10-22T18:18:50.333Z] Camera thread performed 122 scans. [2025-10-22T18:18:50.333Z] ====== philosophers (scala) [default], iteration 26 completed (698.764 ms) ====== [2025-10-22T18:18:50.333Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-10-22T18:18:50.333Z] GC before operation: completed in 13.878 ms, heap usage 27.405 MB -> 5.388 MB. [2025-10-22T18:18:50.333Z] Camera thread performed 122 scans. [2025-10-22T18:18:50.333Z] ====== philosophers (scala) [default], iteration 27 completed (570.267 ms) ====== [2025-10-22T18:18:50.333Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-10-22T18:18:50.333Z] GC before operation: completed in 12.130 ms, heap usage 20.986 MB -> 5.388 MB. [2025-10-22T18:18:51.639Z] Camera thread performed 122 scans. [2025-10-22T18:18:51.639Z] ====== philosophers (scala) [default], iteration 28 completed (865.200 ms) ====== [2025-10-22T18:18:51.639Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-10-22T18:18:51.639Z] GC before operation: completed in 11.033 ms, heap usage 34.170 MB -> 5.388 MB. [2025-10-22T18:18:51.639Z] Camera thread performed 122 scans. [2025-10-22T18:18:51.639Z] ====== philosophers (scala) [default], iteration 29 completed (575.161 ms) ====== [2025-10-22T18:18:52.258Z] ----------------------------------- [2025-10-22T18:18:52.259Z] renaissance-philosophers_0_PASSED [2025-10-22T18:18:52.259Z] ----------------------------------- [2025-10-22T18:18:52.259Z] [2025-10-22T18:18:52.259Z] TEST TEARDOWN: [2025-10-22T18:18:52.259Z] Nothing to be done for teardown. [2025-10-22T18:18:52.259Z] renaissance-philosophers_0 Finish Time: Wed Oct 22 18:18:51 2025 Epoch Time (ms): 1761157131789