[2025-07-30T20:39:15.909Z] Running test renaissance-philosophers_0 ... [2025-07-30T20:39:15.909Z] =============================================== [2025-07-30T20:39:15.909Z] renaissance-philosophers_0 Start Time: Wed Jul 30 15:39:15 2025 Epoch Time (ms): 1753907955071 [2025-07-30T20:39:15.909Z] variation: NoOptions [2025-07-30T20:39:15.909Z] JVM_OPTIONS: [2025-07-30T20:39:15.909Z] { \ [2025-07-30T20:39:15.909Z] echo ""; echo "TEST SETUP:"; \ [2025-07-30T20:39:15.909Z] echo "Nothing to be done for setup."; \ [2025-07-30T20:39:15.909Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17539053688225/renaissance-philosophers_0"; \ [2025-07-30T20:39:15.909Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17539053688225/renaissance-philosophers_0"; \ [2025-07-30T20:39:15.909Z] echo ""; echo "TESTING:"; \ [2025-07-30T20:39:15.909Z] "/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_17539053688225/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-07-30T20:39:15.910Z] 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_17539053688225/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-30T20:39:15.910Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-30T20:39:15.910Z] echo "Nothing to be done for teardown."; \ [2025-07-30T20:39:15.910Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17539053688225/TestTargetResult"; [2025-07-30T20:39:15.910Z] [2025-07-30T20:39:15.910Z] TEST SETUP: [2025-07-30T20:39:15.910Z] Nothing to be done for setup. [2025-07-30T20:39:15.910Z] [2025-07-30T20:39:15.910Z] TESTING: [2025-07-30T20:39:15.910Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-07-30T20:39:15.910Z] GC before operation: completed in 35.536 ms, heap usage 18.333 MB -> 4.864 MB. [2025-07-30T20:39:17.867Z] Camera thread performed 122 scans. [2025-07-30T20:39:17.867Z] ====== philosophers (scala) [default], iteration 0 completed (1761.051 ms) ====== [2025-07-30T20:39:17.867Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-07-30T20:39:17.867Z] GC before operation: completed in 10.964 ms, heap usage 8.441 MB -> 5.235 MB. [2025-07-30T20:39:18.465Z] Camera thread performed 122 scans. [2025-07-30T20:39:18.465Z] ====== philosophers (scala) [default], iteration 1 completed (710.257 ms) ====== [2025-07-30T20:39:18.465Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-07-30T20:39:18.465Z] GC before operation: completed in 10.541 ms, heap usage 25.007 MB -> 5.236 MB. [2025-07-30T20:39:19.707Z] Camera thread performed 122 scans. [2025-07-30T20:39:19.707Z] ====== philosophers (scala) [default], iteration 2 completed (838.703 ms) ====== [2025-07-30T20:39:19.707Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-07-30T20:39:19.707Z] GC before operation: completed in 10.063 ms, heap usage 15.136 MB -> 5.236 MB. [2025-07-30T20:39:20.329Z] Camera thread performed 122 scans. [2025-07-30T20:39:20.329Z] ====== philosophers (scala) [default], iteration 3 completed (879.038 ms) ====== [2025-07-30T20:39:20.329Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-07-30T20:39:20.329Z] GC before operation: completed in 15.482 ms, heap usage 8.833 MB -> 5.236 MB. [2025-07-30T20:39:20.939Z] Camera thread performed 122 scans. [2025-07-30T20:39:20.939Z] ====== philosophers (scala) [default], iteration 4 completed (797.869 ms) ====== [2025-07-30T20:39:20.939Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-07-30T20:39:20.939Z] GC before operation: completed in 11.078 ms, heap usage 43.443 MB -> 5.237 MB. [2025-07-30T20:39:22.234Z] Camera thread performed 122 scans. [2025-07-30T20:39:22.234Z] ====== philosophers (scala) [default], iteration 5 completed (888.268 ms) ====== [2025-07-30T20:39:22.234Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-07-30T20:39:22.234Z] GC before operation: completed in 12.505 ms, heap usage 14.274 MB -> 5.237 MB. [2025-07-30T20:39:22.832Z] Camera thread performed 122 scans. [2025-07-30T20:39:22.832Z] ====== philosophers (scala) [default], iteration 6 completed (835.251 ms) ====== [2025-07-30T20:39:22.832Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-07-30T20:39:22.832Z] GC before operation: completed in 14.566 ms, heap usage 30.335 MB -> 5.237 MB. [2025-07-30T20:39:24.099Z] Camera thread performed 122 scans. [2025-07-30T20:39:24.099Z] ====== philosophers (scala) [default], iteration 7 completed (857.377 ms) ====== [2025-07-30T20:39:24.099Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-07-30T20:39:24.099Z] GC before operation: completed in 13.562 ms, heap usage 21.707 MB -> 5.238 MB. [2025-07-30T20:39:24.727Z] Camera thread performed 122 scans. [2025-07-30T20:39:24.727Z] ====== philosophers (scala) [default], iteration 8 completed (785.616 ms) ====== [2025-07-30T20:39:24.727Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-07-30T20:39:24.727Z] GC before operation: completed in 13.592 ms, heap usage 30.368 MB -> 5.238 MB. [2025-07-30T20:39:25.344Z] Camera thread performed 122 scans. [2025-07-30T20:39:25.344Z] ====== philosophers (scala) [default], iteration 9 completed (742.416 ms) ====== [2025-07-30T20:39:25.344Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-07-30T20:39:25.344Z] GC before operation: completed in 9.528 ms, heap usage 5.684 MB -> 5.238 MB. [2025-07-30T20:39:26.600Z] Camera thread performed 122 scans. [2025-07-30T20:39:26.600Z] ====== philosophers (scala) [default], iteration 10 completed (958.736 ms) ====== [2025-07-30T20:39:26.600Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-07-30T20:39:26.600Z] GC before operation: completed in 15.253 ms, heap usage 7.357 MB -> 5.238 MB. [2025-07-30T20:39:27.198Z] Camera thread performed 122 scans. [2025-07-30T20:39:27.199Z] ====== philosophers (scala) [default], iteration 11 completed (835.161 ms) ====== [2025-07-30T20:39:27.199Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-07-30T20:39:27.199Z] GC before operation: completed in 13.122 ms, heap usage 15.317 MB -> 5.238 MB. [2025-07-30T20:39:27.795Z] Camera thread performed 122 scans. [2025-07-30T20:39:27.795Z] ====== philosophers (scala) [default], iteration 12 completed (844.733 ms) ====== [2025-07-30T20:39:27.795Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-07-30T20:39:27.795Z] GC before operation: completed in 11.799 ms, heap usage 11.841 MB -> 5.238 MB. [2025-07-30T20:39:29.033Z] Camera thread performed 122 scans. [2025-07-30T20:39:29.033Z] ====== philosophers (scala) [default], iteration 13 completed (661.022 ms) ====== [2025-07-30T20:39:29.033Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-07-30T20:39:29.033Z] GC before operation: completed in 13.826 ms, heap usage 10.201 MB -> 5.238 MB. [2025-07-30T20:39:29.648Z] Camera thread performed 122 scans. [2025-07-30T20:39:29.648Z] ====== philosophers (scala) [default], iteration 14 completed (763.661 ms) ====== [2025-07-30T20:39:29.648Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-07-30T20:39:29.648Z] GC before operation: completed in 13.396 ms, heap usage 7.269 MB -> 5.238 MB. [2025-07-30T20:39:30.251Z] Camera thread performed 122 scans. [2025-07-30T20:39:30.251Z] ====== philosophers (scala) [default], iteration 15 completed (789.686 ms) ====== [2025-07-30T20:39:30.251Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-07-30T20:39:30.251Z] GC before operation: completed in 29.847 ms, heap usage 36.363 MB -> 5.238 MB. [2025-07-30T20:39:31.562Z] Camera thread performed 122 scans. [2025-07-30T20:39:31.562Z] ====== philosophers (scala) [default], iteration 16 completed (913.876 ms) ====== [2025-07-30T20:39:31.562Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-07-30T20:39:31.562Z] GC before operation: completed in 12.216 ms, heap usage 5.631 MB -> 5.238 MB. [2025-07-30T20:39:32.170Z] Camera thread performed 122 scans. [2025-07-30T20:39:32.170Z] ====== philosophers (scala) [default], iteration 17 completed (925.107 ms) ====== [2025-07-30T20:39:32.170Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-07-30T20:39:32.170Z] GC before operation: completed in 20.268 ms, heap usage 30.329 MB -> 5.239 MB. [2025-07-30T20:39:33.433Z] Camera thread performed 122 scans. [2025-07-30T20:39:33.433Z] ====== philosophers (scala) [default], iteration 18 completed (1065.830 ms) ====== [2025-07-30T20:39:33.433Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-07-30T20:39:33.433Z] GC before operation: completed in 36.006 ms, heap usage 27.702 MB -> 5.239 MB. [2025-07-30T20:39:34.046Z] Camera thread performed 122 scans. [2025-07-30T20:39:34.046Z] ====== philosophers (scala) [default], iteration 19 completed (811.413 ms) ====== [2025-07-30T20:39:34.046Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-07-30T20:39:34.046Z] GC before operation: completed in 33.227 ms, heap usage 45.281 MB -> 5.239 MB. [2025-07-30T20:39:35.320Z] Camera thread performed 122 scans. [2025-07-30T20:39:35.320Z] ====== philosophers (scala) [default], iteration 20 completed (876.662 ms) ====== [2025-07-30T20:39:35.320Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-07-30T20:39:35.320Z] GC before operation: completed in 12.925 ms, heap usage 31.281 MB -> 5.239 MB. [2025-07-30T20:39:35.929Z] Camera thread performed 122 scans. [2025-07-30T20:39:35.929Z] ====== philosophers (scala) [default], iteration 21 completed (824.811 ms) ====== [2025-07-30T20:39:35.929Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-07-30T20:39:35.929Z] GC before operation: completed in 10.781 ms, heap usage 32.835 MB -> 5.239 MB. [2025-07-30T20:39:36.544Z] Camera thread performed 122 scans. [2025-07-30T20:39:36.544Z] ====== philosophers (scala) [default], iteration 22 completed (973.636 ms) ====== [2025-07-30T20:39:36.544Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-07-30T20:39:37.144Z] GC before operation: completed in 12.274 ms, heap usage 26.296 MB -> 5.240 MB. [2025-07-30T20:39:37.742Z] Camera thread performed 122 scans. [2025-07-30T20:39:37.742Z] ====== philosophers (scala) [default], iteration 23 completed (724.848 ms) ====== [2025-07-30T20:39:37.742Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-07-30T20:39:37.742Z] GC before operation: completed in 17.712 ms, heap usage 42.359 MB -> 5.260 MB. [2025-07-30T20:39:38.985Z] Camera thread performed 122 scans. [2025-07-30T20:39:38.985Z] ====== philosophers (scala) [default], iteration 24 completed (1088.861 ms) ====== [2025-07-30T20:39:38.985Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-07-30T20:39:38.985Z] GC before operation: completed in 10.799 ms, heap usage 16.391 MB -> 5.240 MB. [2025-07-30T20:39:39.584Z] Camera thread performed 122 scans. [2025-07-30T20:39:39.584Z] ====== philosophers (scala) [default], iteration 25 completed (907.627 ms) ====== [2025-07-30T20:39:39.584Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-07-30T20:39:39.584Z] GC before operation: completed in 25.697 ms, heap usage 33.009 MB -> 5.240 MB. [2025-07-30T20:39:40.187Z] Camera thread performed 122 scans. [2025-07-30T20:39:40.187Z] ====== philosophers (scala) [default], iteration 26 completed (822.163 ms) ====== [2025-07-30T20:39:40.187Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-07-30T20:39:40.187Z] GC before operation: completed in 10.821 ms, heap usage 11.351 MB -> 5.240 MB. [2025-07-30T20:39:41.419Z] Camera thread performed 122 scans. [2025-07-30T20:39:41.419Z] ====== philosophers (scala) [default], iteration 27 completed (868.196 ms) ====== [2025-07-30T20:39:41.419Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-07-30T20:39:41.419Z] GC before operation: completed in 15.293 ms, heap usage 18.288 MB -> 5.240 MB. [2025-07-30T20:39:42.013Z] Camera thread performed 122 scans. [2025-07-30T20:39:42.013Z] ====== philosophers (scala) [default], iteration 28 completed (746.952 ms) ====== [2025-07-30T20:39:42.013Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-07-30T20:39:42.013Z] GC before operation: completed in 12.320 ms, heap usage 26.058 MB -> 5.241 MB. [2025-07-30T20:39:43.306Z] Camera thread performed 122 scans. [2025-07-30T20:39:43.306Z] ====== philosophers (scala) [default], iteration 29 completed (1078.923 ms) ====== [2025-07-30T20:39:43.306Z] ----------------------------------- [2025-07-30T20:39:43.306Z] renaissance-philosophers_0_PASSED [2025-07-30T20:39:43.306Z] ----------------------------------- [2025-07-30T20:39:43.306Z] [2025-07-30T20:39:43.306Z] TEST TEARDOWN: [2025-07-30T20:39:43.306Z] Nothing to be done for teardown. [2025-07-30T20:39:43.306Z] renaissance-philosophers_0 Finish Time: Wed Jul 30 15:39:43 2025 Epoch Time (ms): 1753907983064