[2026-01-31T22:47:48.686Z] Running test renaissance-philosophers_0 ... [2026-01-31T22:47:48.686Z] =============================================== [2026-01-31T22:47:48.686Z] renaissance-philosophers_0 Start Time: Sat Jan 31 22:47:48 2026 Epoch Time (ms): 1769899668434 [2026-01-31T22:47:48.686Z] variation: NoOptions [2026-01-31T22:47:48.686Z] JVM_OPTIONS: [2026-01-31T22:47:48.686Z] { \ [2026-01-31T22:47:48.686Z] echo ""; echo "TEST SETUP:"; \ [2026-01-31T22:47:48.686Z] echo "Nothing to be done for setup."; \ [2026-01-31T22:47:48.686Z] mkdir -p "/home/jenkins/workspace/Test_openjdk27_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17698992797836/renaissance-philosophers_0"; \ [2026-01-31T22:47:48.686Z] cd "/home/jenkins/workspace/Test_openjdk27_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17698992797836/renaissance-philosophers_0"; \ [2026-01-31T22:47:48.686Z] echo ""; echo "TESTING:"; \ [2026-01-31T22:47:48.686Z] "/home/jenkins/workspace/Test_openjdk27_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_openjdk27_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk27_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17698992797836/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2026-01-31T22:47:48.686Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk27_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk27_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17698992797836/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-31T22:47:48.686Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-31T22:47:48.686Z] echo "Nothing to be done for teardown."; \ [2026-01-31T22:47:48.686Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk27_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17698992797836/TestTargetResult"; [2026-01-31T22:47:48.686Z] [2026-01-31T22:47:48.686Z] TEST SETUP: [2026-01-31T22:47:48.686Z] Nothing to be done for setup. [2026-01-31T22:47:48.686Z] [2026-01-31T22:47:48.686Z] TESTING: [2026-01-31T22:47:49.305Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-31T22:47:49.305Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk27_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17698992797836/renaissance-philosophers_0/launcher-224748-689272713740938625/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-31T22:47:49.305Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-31T22:47:49.305Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-31T22:47:49.919Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-01-31T22:47:49.919Z] GC before operation: completed in 18.733 ms, heap usage 17.549 MB -> 5.027 MB. [2026-01-31T22:47:51.235Z] Camera thread performed 122 scans. [2026-01-31T22:47:51.235Z] ====== philosophers (scala) [default], iteration 0 completed (1545.520 ms) ====== [2026-01-31T22:47:51.235Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-01-31T22:47:51.235Z] GC before operation: completed in 18.180 ms, heap usage 45.061 MB -> 5.363 MB. [2026-01-31T22:47:52.500Z] Camera thread performed 122 scans. [2026-01-31T22:47:52.500Z] ====== philosophers (scala) [default], iteration 1 completed (903.438 ms) ====== [2026-01-31T22:47:52.500Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-01-31T22:47:52.500Z] GC before operation: completed in 13.940 ms, heap usage 66.992 MB -> 5.364 MB. [2026-01-31T22:47:53.109Z] Camera thread performed 122 scans. [2026-01-31T22:47:53.109Z] ====== philosophers (scala) [default], iteration 2 completed (579.100 ms) ====== [2026-01-31T22:47:53.109Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-01-31T22:47:53.109Z] GC before operation: completed in 15.092 ms, heap usage 68.947 MB -> 5.365 MB. [2026-01-31T22:47:53.730Z] Camera thread performed 122 scans. [2026-01-31T22:47:53.730Z] ====== philosophers (scala) [default], iteration 3 completed (648.428 ms) ====== [2026-01-31T22:47:53.730Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-01-31T22:47:53.730Z] GC before operation: completed in 13.603 ms, heap usage 110.971 MB -> 5.365 MB. [2026-01-31T22:47:54.365Z] Camera thread performed 122 scans. [2026-01-31T22:47:54.365Z] ====== philosophers (scala) [default], iteration 4 completed (674.867 ms) ====== [2026-01-31T22:47:54.365Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-01-31T22:47:54.365Z] GC before operation: completed in 13.239 ms, heap usage 146.760 MB -> 5.365 MB. [2026-01-31T22:47:54.977Z] Camera thread performed 122 scans. [2026-01-31T22:47:54.977Z] ====== philosophers (scala) [default], iteration 5 completed (739.505 ms) ====== [2026-01-31T22:47:54.977Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-01-31T22:47:54.977Z] GC before operation: completed in 11.664 ms, heap usage 121.591 MB -> 5.365 MB. [2026-01-31T22:47:55.589Z] Camera thread performed 122 scans. [2026-01-31T22:47:55.589Z] ====== philosophers (scala) [default], iteration 6 completed (884.913 ms) ====== [2026-01-31T22:47:55.589Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-01-31T22:47:55.589Z] GC before operation: completed in 11.674 ms, heap usage 137.521 MB -> 5.365 MB. [2026-01-31T22:47:56.283Z] Camera thread performed 122 scans. [2026-01-31T22:47:56.283Z] ====== philosophers (scala) [default], iteration 7 completed (651.250 ms) ====== [2026-01-31T22:47:56.283Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-01-31T22:47:56.283Z] GC before operation: completed in 11.374 ms, heap usage 32.500 MB -> 5.366 MB. [2026-01-31T22:47:56.998Z] Camera thread performed 122 scans. [2026-01-31T22:47:56.998Z] ====== philosophers (scala) [default], iteration 8 completed (804.595 ms) ====== [2026-01-31T22:47:56.998Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-01-31T22:47:57.627Z] GC before operation: completed in 16.219 ms, heap usage 121.488 MB -> 5.366 MB. [2026-01-31T22:47:58.255Z] Camera thread performed 122 scans. [2026-01-31T22:47:58.256Z] ====== philosophers (scala) [default], iteration 9 completed (661.467 ms) ====== [2026-01-31T22:47:58.256Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-01-31T22:47:58.256Z] GC before operation: completed in 12.686 ms, heap usage 35.481 MB -> 5.366 MB. [2026-01-31T22:47:58.909Z] Camera thread performed 122 scans. [2026-01-31T22:47:58.909Z] ====== philosophers (scala) [default], iteration 10 completed (755.780 ms) ====== [2026-01-31T22:47:58.909Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-01-31T22:47:58.909Z] GC before operation: completed in 12.734 ms, heap usage 126.475 MB -> 5.366 MB. [2026-01-31T22:47:59.524Z] Camera thread performed 122 scans. [2026-01-31T22:47:59.524Z] ====== philosophers (scala) [default], iteration 11 completed (653.017 ms) ====== [2026-01-31T22:47:59.524Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-01-31T22:47:59.524Z] GC before operation: completed in 14.028 ms, heap usage 145.479 MB -> 5.366 MB. [2026-01-31T22:48:00.160Z] Camera thread performed 122 scans. [2026-01-31T22:48:00.160Z] ====== philosophers (scala) [default], iteration 12 completed (837.398 ms) ====== [2026-01-31T22:48:00.160Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-01-31T22:48:00.160Z] GC before operation: completed in 27.860 ms, heap usage 99.643 MB -> 5.366 MB. [2026-01-31T22:48:01.459Z] Camera thread performed 122 scans. [2026-01-31T22:48:01.459Z] ====== philosophers (scala) [default], iteration 13 completed (864.494 ms) ====== [2026-01-31T22:48:01.459Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-01-31T22:48:01.459Z] GC before operation: completed in 16.765 ms, heap usage 111.977 MB -> 5.366 MB. [2026-01-31T22:48:02.069Z] Camera thread performed 122 scans. [2026-01-31T22:48:02.069Z] ====== philosophers (scala) [default], iteration 14 completed (701.641 ms) ====== [2026-01-31T22:48:02.069Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-01-31T22:48:02.069Z] GC before operation: completed in 12.981 ms, heap usage 111.477 MB -> 5.367 MB. [2026-01-31T22:48:02.831Z] Camera thread performed 122 scans. [2026-01-31T22:48:02.831Z] ====== philosophers (scala) [default], iteration 15 completed (741.412 ms) ====== [2026-01-31T22:48:02.831Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-01-31T22:48:02.831Z] GC before operation: completed in 11.880 ms, heap usage 114.980 MB -> 5.366 MB. [2026-01-31T22:48:03.447Z] Camera thread performed 122 scans. [2026-01-31T22:48:03.447Z] ====== philosophers (scala) [default], iteration 16 completed (756.934 ms) ====== [2026-01-31T22:48:03.447Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-01-31T22:48:03.447Z] GC before operation: completed in 13.684 ms, heap usage 102.981 MB -> 5.367 MB. [2026-01-31T22:48:04.057Z] Camera thread performed 122 scans. [2026-01-31T22:48:04.057Z] ====== philosophers (scala) [default], iteration 17 completed (758.420 ms) ====== [2026-01-31T22:48:04.057Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-01-31T22:48:04.057Z] GC before operation: completed in 18.749 ms, heap usage 97.480 MB -> 5.367 MB. [2026-01-31T22:48:04.685Z] Camera thread performed 122 scans. [2026-01-31T22:48:04.685Z] ====== philosophers (scala) [default], iteration 18 completed (773.275 ms) ====== [2026-01-31T22:48:04.685Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-01-31T22:48:04.685Z] GC before operation: completed in 14.556 ms, heap usage 83.980 MB -> 5.368 MB. [2026-01-31T22:48:05.959Z] Camera thread performed 122 scans. [2026-01-31T22:48:05.959Z] ====== philosophers (scala) [default], iteration 19 completed (769.365 ms) ====== [2026-01-31T22:48:05.959Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-01-31T22:48:05.959Z] GC before operation: completed in 15.157 ms, heap usage 414.371 MB -> 5.368 MB. [2026-01-31T22:48:06.588Z] Camera thread performed 122 scans. [2026-01-31T22:48:06.588Z] ====== philosophers (scala) [default], iteration 20 completed (763.409 ms) ====== [2026-01-31T22:48:06.588Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-01-31T22:48:06.588Z] GC before operation: completed in 12.523 ms, heap usage 417.871 MB -> 5.368 MB. [2026-01-31T22:48:07.202Z] Camera thread performed 122 scans. [2026-01-31T22:48:07.202Z] ====== philosophers (scala) [default], iteration 21 completed (755.343 ms) ====== [2026-01-31T22:48:07.202Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-01-31T22:48:07.202Z] GC before operation: completed in 15.629 ms, heap usage 402.371 MB -> 5.368 MB. [2026-01-31T22:48:08.482Z] Camera thread performed 122 scans. [2026-01-31T22:48:08.482Z] ====== philosophers (scala) [default], iteration 22 completed (866.298 ms) ====== [2026-01-31T22:48:08.482Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-01-31T22:48:08.482Z] GC before operation: completed in 17.195 ms, heap usage 400.371 MB -> 5.368 MB. [2026-01-31T22:48:08.482Z] Camera thread performed 122 scans. [2026-01-31T22:48:08.482Z] ====== philosophers (scala) [default], iteration 23 completed (537.867 ms) ====== [2026-01-31T22:48:08.482Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-01-31T22:48:08.482Z] GC before operation: completed in 13.954 ms, heap usage 395.871 MB -> 5.368 MB. [2026-01-31T22:48:09.747Z] Camera thread performed 122 scans. [2026-01-31T22:48:09.747Z] ====== philosophers (scala) [default], iteration 24 completed (896.105 ms) ====== [2026-01-31T22:48:09.747Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-01-31T22:48:09.747Z] GC before operation: completed in 17.700 ms, heap usage 408.871 MB -> 5.368 MB. [2026-01-31T22:48:10.356Z] Camera thread performed 122 scans. [2026-01-31T22:48:10.356Z] ====== philosophers (scala) [default], iteration 25 completed (788.793 ms) ====== [2026-01-31T22:48:10.356Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-01-31T22:48:10.356Z] GC before operation: completed in 13.521 ms, heap usage 34.985 MB -> 5.368 MB. [2026-01-31T22:48:10.963Z] Camera thread performed 122 scans. [2026-01-31T22:48:10.963Z] ====== philosophers (scala) [default], iteration 26 completed (611.774 ms) ====== [2026-01-31T22:48:10.963Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-01-31T22:48:10.963Z] GC before operation: completed in 12.904 ms, heap usage 432.372 MB -> 5.369 MB. [2026-01-31T22:48:12.233Z] Camera thread performed 122 scans. [2026-01-31T22:48:12.233Z] ====== philosophers (scala) [default], iteration 27 completed (819.096 ms) ====== [2026-01-31T22:48:12.233Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-01-31T22:48:12.233Z] GC before operation: completed in 13.090 ms, heap usage 42.486 MB -> 5.369 MB. [2026-01-31T22:48:12.847Z] Camera thread performed 122 scans. [2026-01-31T22:48:12.847Z] ====== philosophers (scala) [default], iteration 28 completed (762.070 ms) ====== [2026-01-31T22:48:12.847Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-01-31T22:48:12.847Z] GC before operation: completed in 13.081 ms, heap usage 67.982 MB -> 5.369 MB. [2026-01-31T22:48:13.456Z] Camera thread performed 122 scans. [2026-01-31T22:48:13.456Z] ====== philosophers (scala) [default], iteration 29 completed (599.549 ms) ====== [2026-01-31T22:48:13.456Z] ----------------------------------- [2026-01-31T22:48:13.456Z] renaissance-philosophers_0_PASSED [2026-01-31T22:48:13.456Z] ----------------------------------- [2026-01-31T22:48:13.456Z] [2026-01-31T22:48:13.456Z] TEST TEARDOWN: [2026-01-31T22:48:13.456Z] Nothing to be done for teardown. [2026-01-31T22:48:13.456Z] renaissance-philosophers_0 Finish Time: Sat Jan 31 22:48:13 2026 Epoch Time (ms): 1769899693230