[2025-09-04T04:09:14.450Z] Running test renaissance-philosophers_0 ... [2025-09-04T04:09:14.450Z] =============================================== [2025-09-04T04:09:14.450Z] renaissance-philosophers_0 Start Time: Thu Sep 4 04:09:13 2025 Epoch Time (ms): 1756958953925 [2025-09-04T04:09:14.450Z] variation: NoOptions [2025-09-04T04:09:14.450Z] JVM_OPTIONS: [2025-09-04T04:09:14.450Z] { \ [2025-09-04T04:09:14.450Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T04:09:14.450Z] echo "Nothing to be done for setup."; \ [2025-09-04T04:09:14.450Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17569548563617/renaissance-philosophers_0"; \ [2025-09-04T04:09:14.450Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17569548563617/renaissance-philosophers_0"; \ [2025-09-04T04:09:14.450Z] echo ""; echo "TESTING:"; \ [2025-09-04T04:09:14.450Z] "/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_17569548563617/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-09-04T04:09:14.450Z] 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_17569548563617/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T04:09:14.450Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T04:09:14.450Z] echo "Nothing to be done for teardown."; \ [2025-09-04T04:09:14.450Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17569548563617/TestTargetResult"; [2025-09-04T04:09:14.450Z] [2025-09-04T04:09:14.450Z] TEST SETUP: [2025-09-04T04:09:14.450Z] Nothing to be done for setup. [2025-09-04T04:09:14.450Z] [2025-09-04T04:09:14.450Z] TESTING: [2025-09-04T04:09:15.068Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-04T04:09:15.068Z] GC before operation: completed in 16.358 ms, heap usage 5.847 MB -> 4.822 MB. [2025-09-04T04:09:17.123Z] Camera thread performed 122 scans. [2025-09-04T04:09:17.124Z] ====== philosophers (scala) [default], iteration 0 completed (2132.960 ms) ====== [2025-09-04T04:09:17.124Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-04T04:09:17.124Z] GC before operation: completed in 16.146 ms, heap usage 27.921 MB -> 5.212 MB. [2025-09-04T04:09:18.425Z] Camera thread performed 122 scans. [2025-09-04T04:09:18.425Z] ====== philosophers (scala) [default], iteration 1 completed (853.482 ms) ====== [2025-09-04T04:09:18.425Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-04T04:09:18.425Z] GC before operation: completed in 25.036 ms, heap usage 11.287 MB -> 5.192 MB. [2025-09-04T04:09:19.062Z] Camera thread performed 122 scans. [2025-09-04T04:09:19.062Z] ====== philosophers (scala) [default], iteration 2 completed (835.552 ms) ====== [2025-09-04T04:09:19.062Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-04T04:09:19.062Z] GC before operation: completed in 13.263 ms, heap usage 28.271 MB -> 5.193 MB. [2025-09-04T04:09:19.667Z] Camera thread performed 122 scans. [2025-09-04T04:09:19.667Z] ====== philosophers (scala) [default], iteration 3 completed (755.556 ms) ====== [2025-09-04T04:09:19.667Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-04T04:09:19.667Z] GC before operation: completed in 28.576 ms, heap usage 56.140 MB -> 5.193 MB. [2025-09-04T04:09:21.019Z] Camera thread performed 122 scans. [2025-09-04T04:09:21.019Z] ====== philosophers (scala) [default], iteration 4 completed (818.000 ms) ====== [2025-09-04T04:09:21.019Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-04T04:09:21.019Z] GC before operation: completed in 11.618 ms, heap usage 8.412 MB -> 5.194 MB. [2025-09-04T04:09:21.651Z] Camera thread performed 122 scans. [2025-09-04T04:09:21.651Z] ====== philosophers (scala) [default], iteration 5 completed (794.276 ms) ====== [2025-09-04T04:09:21.651Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-04T04:09:21.651Z] GC before operation: completed in 26.350 ms, heap usage 22.285 MB -> 5.194 MB. [2025-09-04T04:09:22.261Z] Camera thread performed 122 scans. [2025-09-04T04:09:22.261Z] ====== philosophers (scala) [default], iteration 6 completed (723.153 ms) ====== [2025-09-04T04:09:22.261Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-04T04:09:22.261Z] GC before operation: completed in 12.899 ms, heap usage 31.823 MB -> 5.194 MB. [2025-09-04T04:09:22.871Z] Camera thread performed 122 scans. [2025-09-04T04:09:22.871Z] ====== philosophers (scala) [default], iteration 7 completed (720.456 ms) ====== [2025-09-04T04:09:22.871Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-04T04:09:22.871Z] GC before operation: completed in 23.262 ms, heap usage 49.803 MB -> 5.194 MB. [2025-09-04T04:09:23.562Z] Camera thread performed 122 scans. [2025-09-04T04:09:23.562Z] ====== philosophers (scala) [default], iteration 8 completed (683.956 ms) ====== [2025-09-04T04:09:23.562Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-04T04:09:23.562Z] GC before operation: completed in 18.340 ms, heap usage 48.317 MB -> 5.216 MB. [2025-09-04T04:09:24.190Z] Camera thread performed 122 scans. [2025-09-04T04:09:24.190Z] ====== philosophers (scala) [default], iteration 9 completed (699.672 ms) ====== [2025-09-04T04:09:24.190Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-04T04:09:24.839Z] GC before operation: completed in 37.060 ms, heap usage 40.797 MB -> 5.216 MB. [2025-09-04T04:09:25.445Z] Camera thread performed 122 scans. [2025-09-04T04:09:25.445Z] ====== philosophers (scala) [default], iteration 10 completed (651.901 ms) ====== [2025-09-04T04:09:25.445Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-04T04:09:25.445Z] GC before operation: completed in 30.882 ms, heap usage 10.848 MB -> 5.194 MB. [2025-09-04T04:09:26.063Z] Camera thread performed 122 scans. [2025-09-04T04:09:26.063Z] ====== philosophers (scala) [default], iteration 11 completed (660.993 ms) ====== [2025-09-04T04:09:26.063Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-04T04:09:26.063Z] GC before operation: completed in 18.048 ms, heap usage 39.101 MB -> 5.194 MB. [2025-09-04T04:09:26.679Z] Camera thread performed 122 scans. [2025-09-04T04:09:26.679Z] ====== philosophers (scala) [default], iteration 12 completed (987.152 ms) ====== [2025-09-04T04:09:26.679Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-04T04:09:26.679Z] GC before operation: completed in 14.826 ms, heap usage 20.314 MB -> 5.195 MB. [2025-09-04T04:09:27.314Z] Camera thread performed 122 scans. [2025-09-04T04:09:27.314Z] ====== philosophers (scala) [default], iteration 13 completed (711.024 ms) ====== [2025-09-04T04:09:27.314Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-04T04:09:27.314Z] GC before operation: completed in 29.925 ms, heap usage 42.295 MB -> 5.195 MB. [2025-09-04T04:09:28.677Z] Camera thread performed 122 scans. [2025-09-04T04:09:28.677Z] ====== philosophers (scala) [default], iteration 14 completed (722.456 ms) ====== [2025-09-04T04:09:28.678Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-04T04:09:28.678Z] GC before operation: completed in 26.814 ms, heap usage 55.833 MB -> 5.217 MB. [2025-09-04T04:09:29.346Z] Camera thread performed 122 scans. [2025-09-04T04:09:29.346Z] ====== philosophers (scala) [default], iteration 15 completed (782.880 ms) ====== [2025-09-04T04:09:29.346Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-04T04:09:29.346Z] GC before operation: completed in 29.213 ms, heap usage 52.836 MB -> 5.217 MB. [2025-09-04T04:09:29.981Z] Camera thread performed 122 scans. [2025-09-04T04:09:29.981Z] ====== philosophers (scala) [default], iteration 16 completed (684.098 ms) ====== [2025-09-04T04:09:29.981Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-04T04:09:29.981Z] GC before operation: completed in 29.748 ms, heap usage 44.316 MB -> 5.196 MB. [2025-09-04T04:09:30.621Z] Camera thread performed 122 scans. [2025-09-04T04:09:30.621Z] ====== philosophers (scala) [default], iteration 17 completed (804.278 ms) ====== [2025-09-04T04:09:30.621Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-04T04:09:30.621Z] GC before operation: completed in 13.311 ms, heap usage 20.286 MB -> 5.196 MB. [2025-09-04T04:09:31.259Z] Camera thread performed 122 scans. [2025-09-04T04:09:31.259Z] ====== philosophers (scala) [default], iteration 18 completed (747.311 ms) ====== [2025-09-04T04:09:31.259Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-04T04:09:31.259Z] GC before operation: completed in 38.143 ms, heap usage 47.313 MB -> 5.197 MB. [2025-09-04T04:09:32.611Z] Camera thread performed 122 scans. [2025-09-04T04:09:32.611Z] ====== philosophers (scala) [default], iteration 19 completed (826.484 ms) ====== [2025-09-04T04:09:32.611Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-04T04:09:32.611Z] GC before operation: completed in 44.885 ms, heap usage 10.286 MB -> 5.196 MB. [2025-09-04T04:09:33.236Z] Camera thread performed 122 scans. [2025-09-04T04:09:33.236Z] ====== philosophers (scala) [default], iteration 20 completed (673.168 ms) ====== [2025-09-04T04:09:33.236Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-04T04:09:33.236Z] GC before operation: completed in 22.241 ms, heap usage 14.799 MB -> 5.196 MB. [2025-09-04T04:09:33.876Z] Camera thread performed 122 scans. [2025-09-04T04:09:33.876Z] ====== philosophers (scala) [default], iteration 21 completed (630.361 ms) ====== [2025-09-04T04:09:33.876Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-04T04:09:33.876Z] GC before operation: completed in 10.703 ms, heap usage 47.314 MB -> 5.197 MB. [2025-09-04T04:09:34.599Z] Camera thread performed 122 scans. [2025-09-04T04:09:34.599Z] ====== philosophers (scala) [default], iteration 22 completed (895.541 ms) ====== [2025-09-04T04:09:34.599Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-04T04:09:34.599Z] GC before operation: completed in 71.771 ms, heap usage 60.815 MB -> 5.219 MB. [2025-09-04T04:09:35.227Z] Camera thread performed 122 scans. [2025-09-04T04:09:35.227Z] ====== philosophers (scala) [default], iteration 23 completed (798.032 ms) ====== [2025-09-04T04:09:35.227Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-04T04:09:35.842Z] GC before operation: completed in 77.220 ms, heap usage 21.339 MB -> 5.197 MB. [2025-09-04T04:09:36.464Z] Camera thread performed 122 scans. [2025-09-04T04:09:36.464Z] ====== philosophers (scala) [default], iteration 24 completed (659.776 ms) ====== [2025-09-04T04:09:36.464Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-04T04:09:36.464Z] GC before operation: completed in 25.381 ms, heap usage 68.814 MB -> 5.198 MB. [2025-09-04T04:09:37.082Z] Camera thread performed 122 scans. [2025-09-04T04:09:37.082Z] ====== philosophers (scala) [default], iteration 25 completed (790.299 ms) ====== [2025-09-04T04:09:37.082Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-04T04:09:37.082Z] GC before operation: completed in 19.899 ms, heap usage 44.324 MB -> 5.197 MB. [2025-09-04T04:09:37.695Z] Camera thread performed 122 scans. [2025-09-04T04:09:37.695Z] ====== philosophers (scala) [default], iteration 26 completed (673.410 ms) ====== [2025-09-04T04:09:37.695Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-04T04:09:37.695Z] GC before operation: completed in 15.004 ms, heap usage 15.831 MB -> 5.198 MB. [2025-09-04T04:09:38.313Z] Camera thread performed 122 scans. [2025-09-04T04:09:38.313Z] ====== philosophers (scala) [default], iteration 27 completed (680.517 ms) ====== [2025-09-04T04:09:38.313Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-04T04:09:38.313Z] GC before operation: completed in 27.199 ms, heap usage 67.816 MB -> 5.198 MB. [2025-09-04T04:09:38.929Z] Camera thread performed 122 scans. [2025-09-04T04:09:38.929Z] ====== philosophers (scala) [default], iteration 28 completed (706.088 ms) ====== [2025-09-04T04:09:38.929Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-04T04:09:38.929Z] GC before operation: completed in 39.253 ms, heap usage 26.299 MB -> 5.197 MB. [2025-09-04T04:09:40.269Z] Camera thread performed 122 scans. [2025-09-04T04:09:40.269Z] ====== philosophers (scala) [default], iteration 29 completed (800.770 ms) ====== [2025-09-04T04:09:40.269Z] ----------------------------------- [2025-09-04T04:09:40.269Z] renaissance-philosophers_0_PASSED [2025-09-04T04:09:40.269Z] ----------------------------------- [2025-09-04T04:09:40.269Z] [2025-09-04T04:09:40.269Z] TEST TEARDOWN: [2025-09-04T04:09:40.269Z] Nothing to be done for teardown. [2025-09-04T04:09:40.269Z] renaissance-philosophers_0 Finish Time: Thu Sep 4 04:09:39 2025 Epoch Time (ms): 1756958979851