[2025-09-24T22:53:26.574Z] Running test renaissance-philosophers_0 ... [2025-09-24T22:53:26.574Z] =============================================== [2025-09-24T22:53:26.574Z] renaissance-philosophers_0 Start Time: Wed Sep 24 22:53:26 2025 Epoch Time (ms): 1758754406133 [2025-09-24T22:53:26.574Z] variation: NoOptions [2025-09-24T22:53:26.574Z] JVM_OPTIONS: [2025-09-24T22:53:26.574Z] { \ [2025-09-24T22:53:26.574Z] echo ""; echo "TEST SETUP:"; \ [2025-09-24T22:53:26.574Z] echo "Nothing to be done for setup."; \ [2025-09-24T22:53:26.574Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17587522621380/renaissance-philosophers_0"; \ [2025-09-24T22:53:26.574Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17587522621380/renaissance-philosophers_0"; \ [2025-09-24T22:53:26.574Z] echo ""; echo "TESTING:"; \ [2025-09-24T22:53:26.574Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17587522621380/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-09-24T22:53:26.574Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17587522621380/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-24T22:53:26.574Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-24T22:53:26.574Z] echo "Nothing to be done for teardown."; \ [2025-09-24T22:53:26.574Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17587522621380/TestTargetResult"; [2025-09-24T22:53:26.574Z] [2025-09-24T22:53:26.574Z] TEST SETUP: [2025-09-24T22:53:26.574Z] Nothing to be done for setup. [2025-09-24T22:53:26.574Z] [2025-09-24T22:53:26.574Z] TESTING: [2025-09-24T22:53:27.164Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-24T22:53:27.164Z] GC before operation: completed in 28.145 ms, heap usage 18.545 MB -> 4.838 MB. [2025-09-24T22:53:29.098Z] Camera thread performed 122 scans. [2025-09-24T22:53:29.098Z] ====== philosophers (scala) [default], iteration 0 completed (1301.623 ms) ====== [2025-09-24T22:53:29.098Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-24T22:53:29.098Z] GC before operation: completed in 14.821 ms, heap usage 33.112 MB -> 5.202 MB. [2025-09-24T22:53:29.727Z] Camera thread performed 122 scans. [2025-09-24T22:53:29.727Z] ====== philosophers (scala) [default], iteration 1 completed (1014.937 ms) ====== [2025-09-24T22:53:29.727Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-24T22:53:29.727Z] GC before operation: completed in 10.136 ms, heap usage 30.835 MB -> 5.204 MB. [2025-09-24T22:53:30.320Z] Camera thread performed 122 scans. [2025-09-24T22:53:30.320Z] ====== philosophers (scala) [default], iteration 2 completed (609.946 ms) ====== [2025-09-24T22:53:30.320Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-24T22:53:30.320Z] GC before operation: completed in 16.514 ms, heap usage 36.271 MB -> 5.204 MB. [2025-09-24T22:53:31.552Z] Camera thread performed 122 scans. [2025-09-24T22:53:31.552Z] ====== philosophers (scala) [default], iteration 3 completed (1097.802 ms) ====== [2025-09-24T22:53:31.552Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-24T22:53:31.552Z] GC before operation: completed in 11.686 ms, heap usage 27.296 MB -> 5.204 MB. [2025-09-24T22:53:32.145Z] Camera thread performed 122 scans. [2025-09-24T22:53:32.146Z] ====== philosophers (scala) [default], iteration 4 completed (779.796 ms) ====== [2025-09-24T22:53:32.146Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-24T22:53:32.146Z] GC before operation: completed in 13.343 ms, heap usage 35.939 MB -> 5.204 MB. [2025-09-24T22:53:33.386Z] Camera thread performed 122 scans. [2025-09-24T22:53:33.386Z] ====== philosophers (scala) [default], iteration 5 completed (830.173 ms) ====== [2025-09-24T22:53:33.386Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-24T22:53:33.386Z] GC before operation: completed in 8.546 ms, heap usage 37.705 MB -> 5.204 MB. [2025-09-24T22:53:33.981Z] Camera thread performed 122 scans. [2025-09-24T22:53:33.981Z] ====== philosophers (scala) [default], iteration 6 completed (769.048 ms) ====== [2025-09-24T22:53:33.981Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-24T22:53:33.981Z] GC before operation: completed in 10.921 ms, heap usage 34.337 MB -> 5.205 MB. [2025-09-24T22:53:34.572Z] Camera thread performed 122 scans. [2025-09-24T22:53:34.572Z] ====== philosophers (scala) [default], iteration 7 completed (840.387 ms) ====== [2025-09-24T22:53:34.572Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-24T22:53:34.572Z] GC before operation: completed in 18.187 ms, heap usage 41.338 MB -> 5.205 MB. [2025-09-24T22:53:35.803Z] Camera thread performed 122 scans. [2025-09-24T22:53:35.803Z] ====== philosophers (scala) [default], iteration 8 completed (1196.119 ms) ====== [2025-09-24T22:53:35.803Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-24T22:53:35.803Z] GC before operation: completed in 10.719 ms, heap usage 24.113 MB -> 5.205 MB. [2025-09-24T22:53:37.736Z] Camera thread performed 122 scans. [2025-09-24T22:53:37.736Z] ====== philosophers (scala) [default], iteration 9 completed (1603.037 ms) ====== [2025-09-24T22:53:37.736Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-24T22:53:37.736Z] GC before operation: completed in 12.553 ms, heap usage 22.644 MB -> 5.205 MB. [2025-09-24T22:53:38.967Z] Camera thread performed 122 scans. [2025-09-24T22:53:38.967Z] ====== philosophers (scala) [default], iteration 10 completed (1067.839 ms) ====== [2025-09-24T22:53:38.967Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-24T22:53:38.967Z] GC before operation: completed in 12.357 ms, heap usage 37.330 MB -> 5.206 MB. [2025-09-24T22:53:39.556Z] Camera thread performed 122 scans. [2025-09-24T22:53:39.556Z] ====== philosophers (scala) [default], iteration 11 completed (1096.854 ms) ====== [2025-09-24T22:53:39.556Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-24T22:53:39.556Z] GC before operation: completed in 10.215 ms, heap usage 8.945 MB -> 5.206 MB. [2025-09-24T22:53:40.803Z] Camera thread performed 122 scans. [2025-09-24T22:53:40.803Z] ====== philosophers (scala) [default], iteration 12 completed (873.072 ms) ====== [2025-09-24T22:53:40.803Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-24T22:53:40.803Z] GC before operation: completed in 11.849 ms, heap usage 30.792 MB -> 5.206 MB. [2025-09-24T22:53:41.394Z] Camera thread performed 122 scans. [2025-09-24T22:53:41.394Z] ====== philosophers (scala) [default], iteration 13 completed (968.252 ms) ====== [2025-09-24T22:53:41.394Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-24T22:53:41.394Z] GC before operation: completed in 11.176 ms, heap usage 27.744 MB -> 5.206 MB. [2025-09-24T22:53:42.627Z] Camera thread performed 122 scans. [2025-09-24T22:53:42.627Z] ====== philosophers (scala) [default], iteration 14 completed (855.649 ms) ====== [2025-09-24T22:53:42.627Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-24T22:53:42.627Z] GC before operation: completed in 13.745 ms, heap usage 29.749 MB -> 5.206 MB. [2025-09-24T22:53:43.875Z] Camera thread performed 122 scans. [2025-09-24T22:53:43.875Z] ====== philosophers (scala) [default], iteration 15 completed (1007.582 ms) ====== [2025-09-24T22:53:43.875Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-24T22:53:43.875Z] GC before operation: completed in 21.133 ms, heap usage 23.337 MB -> 5.206 MB. [2025-09-24T22:53:44.467Z] Camera thread performed 122 scans. [2025-09-24T22:53:44.467Z] ====== philosophers (scala) [default], iteration 16 completed (885.929 ms) ====== [2025-09-24T22:53:44.467Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-24T22:53:44.467Z] GC before operation: completed in 8.529 ms, heap usage 28.813 MB -> 5.207 MB. [2025-09-24T22:53:45.696Z] Camera thread performed 122 scans. [2025-09-24T22:53:45.696Z] ====== philosophers (scala) [default], iteration 17 completed (1031.705 ms) ====== [2025-09-24T22:53:45.696Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-24T22:53:45.696Z] GC before operation: completed in 8.889 ms, heap usage 24.702 MB -> 5.207 MB. [2025-09-24T22:53:46.287Z] Camera thread performed 122 scans. [2025-09-24T22:53:46.288Z] ====== philosophers (scala) [default], iteration 18 completed (825.958 ms) ====== [2025-09-24T22:53:46.288Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-24T22:53:46.288Z] GC before operation: completed in 12.271 ms, heap usage 48.819 MB -> 5.207 MB. [2025-09-24T22:53:47.519Z] Camera thread performed 122 scans. [2025-09-24T22:53:47.519Z] ====== philosophers (scala) [default], iteration 19 completed (1168.799 ms) ====== [2025-09-24T22:53:47.519Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-24T22:53:47.519Z] GC before operation: completed in 9.862 ms, heap usage 23.066 MB -> 5.207 MB. [2025-09-24T22:53:48.749Z] Camera thread performed 122 scans. [2025-09-24T22:53:48.749Z] ====== philosophers (scala) [default], iteration 20 completed (1131.053 ms) ====== [2025-09-24T22:53:48.749Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-24T22:53:48.749Z] GC before operation: completed in 12.013 ms, heap usage 21.125 MB -> 5.207 MB. [2025-09-24T22:53:49.979Z] Camera thread performed 122 scans. [2025-09-24T22:53:49.979Z] ====== philosophers (scala) [default], iteration 21 completed (1113.160 ms) ====== [2025-09-24T22:53:49.979Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-24T22:53:49.979Z] GC before operation: completed in 11.337 ms, heap usage 37.063 MB -> 5.207 MB. [2025-09-24T22:53:51.219Z] Camera thread performed 122 scans. [2025-09-24T22:53:51.219Z] ====== philosophers (scala) [default], iteration 22 completed (1086.372 ms) ====== [2025-09-24T22:53:51.219Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-24T22:53:51.219Z] GC before operation: completed in 12.381 ms, heap usage 27.707 MB -> 5.208 MB. [2025-09-24T22:53:51.823Z] Camera thread performed 122 scans. [2025-09-24T22:53:51.823Z] ====== philosophers (scala) [default], iteration 23 completed (965.328 ms) ====== [2025-09-24T22:53:51.823Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-24T22:53:51.823Z] GC before operation: completed in 11.842 ms, heap usage 15.253 MB -> 5.208 MB. [2025-09-24T22:53:53.066Z] Camera thread performed 122 scans. [2025-09-24T22:53:53.066Z] ====== philosophers (scala) [default], iteration 24 completed (852.467 ms) ====== [2025-09-24T22:53:53.066Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-24T22:53:53.066Z] GC before operation: completed in 12.019 ms, heap usage 43.792 MB -> 5.208 MB. [2025-09-24T22:53:53.657Z] Camera thread performed 122 scans. [2025-09-24T22:53:53.657Z] ====== philosophers (scala) [default], iteration 25 completed (996.974 ms) ====== [2025-09-24T22:53:53.657Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-24T22:53:53.657Z] GC before operation: completed in 12.772 ms, heap usage 16.795 MB -> 5.208 MB. [2025-09-24T22:53:55.316Z] Camera thread performed 122 scans. [2025-09-24T22:53:55.316Z] ====== philosophers (scala) [default], iteration 26 completed (948.995 ms) ====== [2025-09-24T22:53:55.316Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-24T22:53:55.316Z] GC before operation: completed in 13.845 ms, heap usage 16.760 MB -> 5.208 MB. [2025-09-24T22:53:55.316Z] Camera thread performed 122 scans. [2025-09-24T22:53:55.316Z] ====== philosophers (scala) [default], iteration 27 completed (798.690 ms) ====== [2025-09-24T22:53:55.316Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-24T22:53:55.316Z] GC before operation: completed in 14.512 ms, heap usage 39.157 MB -> 5.208 MB. [2025-09-24T22:53:56.634Z] Camera thread performed 122 scans. [2025-09-24T22:53:56.634Z] ====== philosophers (scala) [default], iteration 28 completed (1257.041 ms) ====== [2025-09-24T22:53:56.634Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-24T22:53:56.634Z] GC before operation: completed in 15.540 ms, heap usage 25.083 MB -> 5.209 MB. [2025-09-24T22:53:57.964Z] Camera thread performed 122 scans. [2025-09-24T22:53:57.964Z] ====== philosophers (scala) [default], iteration 29 completed (857.217 ms) ====== [2025-09-24T22:53:57.964Z] ----------------------------------- [2025-09-24T22:53:57.964Z] renaissance-philosophers_0_PASSED [2025-09-24T22:53:57.964Z] ----------------------------------- [2025-09-24T22:53:57.964Z] [2025-09-24T22:53:57.964Z] TEST TEARDOWN: [2025-09-24T22:53:57.964Z] Nothing to be done for teardown. [2025-09-24T22:53:57.964Z] renaissance-philosophers_0 Finish Time: Wed Sep 24 22:53:57 2025 Epoch Time (ms): 1758754437510