[2025-11-20T03:12:43.657Z] Running test renaissance-philosophers_0 ... [2025-11-20T03:12:43.657Z] =============================================== [2025-11-20T03:12:43.657Z] renaissance-philosophers_0 Start Time: Thu Nov 20 03:12:43 2025 Epoch Time (ms): 1763608363232 [2025-11-20T03:12:43.657Z] variation: NoOptions [2025-11-20T03:12:43.657Z] JVM_OPTIONS: [2025-11-20T03:12:43.657Z] { \ [2025-11-20T03:12:43.657Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T03:12:43.657Z] echo "Nothing to be done for setup."; \ [2025-11-20T03:12:43.657Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1763605648115/renaissance-philosophers_0"; \ [2025-11-20T03:12:43.657Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1763605648115/renaissance-philosophers_0"; \ [2025-11-20T03:12:43.657Z] echo ""; echo "TESTING:"; \ [2025-11-20T03:12:43.657Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1763605648115/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-20T03:12:43.657Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1763605648115/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T03:12:43.657Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T03:12:43.657Z] echo "Nothing to be done for teardown."; \ [2025-11-20T03:12:43.657Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1763605648115/TestTargetResult"; [2025-11-20T03:12:43.657Z] [2025-11-20T03:12:43.657Z] TEST SETUP: [2025-11-20T03:12:43.657Z] Nothing to be done for setup. [2025-11-20T03:12:43.657Z] [2025-11-20T03:12:43.657Z] TESTING: [2025-11-20T03:12:44.296Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-20T03:12:44.296Z] GC before operation: completed in 37.606 ms, heap usage 5.949 MB -> 4.826 MB. [2025-11-20T03:12:47.148Z] Camera thread performed 122 scans. [2025-11-20T03:12:47.148Z] ====== philosophers (scala) [default], iteration 0 completed (2653.132 ms) ====== [2025-11-20T03:12:47.148Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-20T03:12:47.148Z] GC before operation: completed in 12.646 ms, heap usage 22.388 MB -> 5.195 MB. [2025-11-20T03:12:47.781Z] Camera thread performed 122 scans. [2025-11-20T03:12:47.781Z] ====== philosophers (scala) [default], iteration 1 completed (641.248 ms) ====== [2025-11-20T03:12:47.781Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-20T03:12:47.781Z] GC before operation: completed in 9.363 ms, heap usage 28.262 MB -> 5.193 MB. [2025-11-20T03:12:47.781Z] Camera thread performed 122 scans. [2025-11-20T03:12:47.781Z] ====== philosophers (scala) [default], iteration 2 completed (521.127 ms) ====== [2025-11-20T03:12:47.781Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-20T03:12:48.397Z] GC before operation: completed in 10.532 ms, heap usage 15.242 MB -> 5.194 MB. [2025-11-20T03:12:48.397Z] Camera thread performed 122 scans. [2025-11-20T03:12:48.397Z] ====== philosophers (scala) [default], iteration 3 completed (609.082 ms) ====== [2025-11-20T03:12:48.397Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-20T03:12:49.019Z] GC before operation: completed in 8.570 ms, heap usage 12.100 MB -> 5.194 MB. [2025-11-20T03:12:49.635Z] Camera thread performed 122 scans. [2025-11-20T03:12:49.635Z] ====== philosophers (scala) [default], iteration 4 completed (846.025 ms) ====== [2025-11-20T03:12:49.635Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-20T03:12:49.635Z] GC before operation: completed in 14.722 ms, heap usage 48.804 MB -> 5.195 MB. [2025-11-20T03:12:50.261Z] Camera thread performed 122 scans. [2025-11-20T03:12:50.261Z] ====== philosophers (scala) [default], iteration 5 completed (893.383 ms) ====== [2025-11-20T03:12:50.261Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-20T03:12:50.261Z] GC before operation: completed in 9.168 ms, heap usage 13.764 MB -> 5.194 MB. [2025-11-20T03:12:51.568Z] Camera thread performed 122 scans. [2025-11-20T03:12:51.568Z] ====== philosophers (scala) [default], iteration 6 completed (840.588 ms) ====== [2025-11-20T03:12:51.568Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-20T03:12:51.568Z] GC before operation: completed in 11.039 ms, heap usage 31.332 MB -> 5.195 MB. [2025-11-20T03:12:52.198Z] Camera thread performed 122 scans. [2025-11-20T03:12:52.198Z] ====== philosophers (scala) [default], iteration 7 completed (847.892 ms) ====== [2025-11-20T03:12:52.198Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-20T03:12:52.198Z] GC before operation: completed in 9.582 ms, heap usage 14.798 MB -> 5.196 MB. [2025-11-20T03:12:53.488Z] Camera thread performed 122 scans. [2025-11-20T03:12:53.488Z] ====== philosophers (scala) [default], iteration 8 completed (953.012 ms) ====== [2025-11-20T03:12:53.488Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-20T03:12:53.488Z] GC before operation: completed in 11.224 ms, heap usage 27.777 MB -> 5.195 MB. [2025-11-20T03:12:54.118Z] Camera thread performed 122 scans. [2025-11-20T03:12:54.118Z] ====== philosophers (scala) [default], iteration 9 completed (871.662 ms) ====== [2025-11-20T03:12:54.118Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-20T03:12:54.118Z] GC before operation: completed in 10.106 ms, heap usage 17.230 MB -> 5.195 MB. [2025-11-20T03:12:54.741Z] Camera thread performed 122 scans. [2025-11-20T03:12:54.741Z] ====== philosophers (scala) [default], iteration 10 completed (746.650 ms) ====== [2025-11-20T03:12:54.741Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-20T03:12:54.741Z] GC before operation: completed in 13.922 ms, heap usage 26.301 MB -> 5.218 MB. [2025-11-20T03:12:55.361Z] Camera thread performed 122 scans. [2025-11-20T03:12:55.362Z] ====== philosophers (scala) [default], iteration 11 completed (744.384 ms) ====== [2025-11-20T03:12:55.362Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-20T03:12:55.362Z] GC before operation: completed in 9.952 ms, heap usage 23.279 MB -> 5.196 MB. [2025-11-20T03:12:56.665Z] Camera thread performed 122 scans. [2025-11-20T03:12:56.665Z] ====== philosophers (scala) [default], iteration 12 completed (764.638 ms) ====== [2025-11-20T03:12:56.665Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-20T03:12:56.665Z] GC before operation: completed in 13.464 ms, heap usage 28.292 MB -> 5.196 MB. [2025-11-20T03:12:57.303Z] Camera thread performed 122 scans. [2025-11-20T03:12:57.303Z] ====== philosophers (scala) [default], iteration 13 completed (795.327 ms) ====== [2025-11-20T03:12:57.303Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-20T03:12:57.303Z] GC before operation: completed in 10.457 ms, heap usage 27.793 MB -> 5.196 MB. [2025-11-20T03:12:57.945Z] Camera thread performed 122 scans. [2025-11-20T03:12:57.945Z] ====== philosophers (scala) [default], iteration 14 completed (743.510 ms) ====== [2025-11-20T03:12:57.945Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-20T03:12:57.945Z] GC before operation: completed in 12.845 ms, heap usage 39.792 MB -> 5.196 MB. [2025-11-20T03:12:58.586Z] Camera thread performed 122 scans. [2025-11-20T03:12:58.586Z] ====== philosophers (scala) [default], iteration 15 completed (842.765 ms) ====== [2025-11-20T03:12:58.586Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-20T03:12:58.586Z] GC before operation: completed in 10.165 ms, heap usage 32.329 MB -> 5.197 MB. [2025-11-20T03:12:59.213Z] Camera thread performed 122 scans. [2025-11-20T03:12:59.213Z] ====== philosophers (scala) [default], iteration 16 completed (600.780 ms) ====== [2025-11-20T03:12:59.213Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-20T03:12:59.213Z] GC before operation: completed in 11.435 ms, heap usage 20.300 MB -> 5.197 MB. [2025-11-20T03:12:59.833Z] Camera thread performed 122 scans. [2025-11-20T03:12:59.833Z] ====== philosophers (scala) [default], iteration 17 completed (606.402 ms) ====== [2025-11-20T03:12:59.833Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-20T03:12:59.833Z] GC before operation: completed in 9.585 ms, heap usage 25.811 MB -> 5.197 MB. [2025-11-20T03:13:00.474Z] Camera thread performed 122 scans. [2025-11-20T03:13:00.474Z] ====== philosophers (scala) [default], iteration 18 completed (668.639 ms) ====== [2025-11-20T03:13:00.474Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-20T03:13:00.474Z] GC before operation: completed in 11.475 ms, heap usage 27.729 MB -> 5.197 MB. [2025-11-20T03:13:01.788Z] Camera thread performed 122 scans. [2025-11-20T03:13:01.788Z] ====== philosophers (scala) [default], iteration 19 completed (802.554 ms) ====== [2025-11-20T03:13:01.788Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-20T03:13:01.788Z] GC before operation: completed in 10.966 ms, heap usage 19.727 MB -> 5.197 MB. [2025-11-20T03:13:02.421Z] Camera thread performed 122 scans. [2025-11-20T03:13:02.421Z] ====== philosophers (scala) [default], iteration 20 completed (794.696 ms) ====== [2025-11-20T03:13:02.421Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-20T03:13:02.421Z] GC before operation: completed in 11.085 ms, heap usage 40.316 MB -> 5.197 MB. [2025-11-20T03:13:03.129Z] Camera thread performed 122 scans. [2025-11-20T03:13:03.129Z] ====== philosophers (scala) [default], iteration 21 completed (798.363 ms) ====== [2025-11-20T03:13:03.129Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-20T03:13:03.129Z] GC before operation: completed in 18.018 ms, heap usage 41.825 MB -> 5.197 MB. [2025-11-20T03:13:04.164Z] Camera thread performed 122 scans. [2025-11-20T03:13:04.164Z] ====== philosophers (scala) [default], iteration 22 completed (632.674 ms) ====== [2025-11-20T03:13:04.164Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-20T03:13:04.164Z] GC before operation: completed in 10.583 ms, heap usage 47.180 MB -> 5.198 MB. [2025-11-20T03:13:04.797Z] Camera thread performed 122 scans. [2025-11-20T03:13:04.797Z] ====== philosophers (scala) [default], iteration 23 completed (855.554 ms) ====== [2025-11-20T03:13:04.797Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-20T03:13:04.797Z] GC before operation: completed in 17.516 ms, heap usage 39.288 MB -> 5.198 MB. [2025-11-20T03:13:05.413Z] Camera thread performed 122 scans. [2025-11-20T03:13:05.413Z] ====== philosophers (scala) [default], iteration 24 completed (1031.896 ms) ====== [2025-11-20T03:13:05.413Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-20T03:13:05.413Z] GC before operation: completed in 10.409 ms, heap usage 43.799 MB -> 5.199 MB. [2025-11-20T03:13:06.743Z] Camera thread performed 122 scans. [2025-11-20T03:13:06.743Z] ====== philosophers (scala) [default], iteration 25 completed (840.069 ms) ====== [2025-11-20T03:13:06.743Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-20T03:13:06.743Z] GC before operation: completed in 10.052 ms, heap usage 37.722 MB -> 5.198 MB. [2025-11-20T03:13:07.363Z] Camera thread performed 122 scans. [2025-11-20T03:13:07.363Z] ====== philosophers (scala) [default], iteration 26 completed (839.132 ms) ====== [2025-11-20T03:13:07.363Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-20T03:13:07.363Z] GC before operation: completed in 10.410 ms, heap usage 37.678 MB -> 5.198 MB. [2025-11-20T03:13:07.998Z] Camera thread performed 122 scans. [2025-11-20T03:13:07.998Z] ====== philosophers (scala) [default], iteration 27 completed (772.507 ms) ====== [2025-11-20T03:13:07.998Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-20T03:13:07.998Z] GC before operation: completed in 9.456 ms, heap usage 16.305 MB -> 5.199 MB. [2025-11-20T03:13:09.309Z] Camera thread performed 122 scans. [2025-11-20T03:13:09.309Z] ====== philosophers (scala) [default], iteration 28 completed (839.594 ms) ====== [2025-11-20T03:13:09.309Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-20T03:13:09.309Z] GC before operation: completed in 11.371 ms, heap usage 37.831 MB -> 5.199 MB. [2025-11-20T03:13:09.931Z] Camera thread performed 122 scans. [2025-11-20T03:13:09.931Z] ====== philosophers (scala) [default], iteration 29 completed (923.886 ms) ====== [2025-11-20T03:13:09.931Z] ----------------------------------- [2025-11-20T03:13:09.931Z] renaissance-philosophers_0_PASSED [2025-11-20T03:13:09.931Z] ----------------------------------- [2025-11-20T03:13:09.931Z] [2025-11-20T03:13:09.931Z] TEST TEARDOWN: [2025-11-20T03:13:09.931Z] Nothing to be done for teardown. [2025-11-20T03:13:09.931Z] renaissance-philosophers_0 Finish Time: Thu Nov 20 03:13:09 2025 Epoch Time (ms): 1763608389757