[2025-08-28T06:32:12.563Z] Running test renaissance-philosophers_0 ... [2025-08-28T06:32:12.563Z] =============================================== [2025-08-28T06:32:12.563Z] renaissance-philosophers_0 Start Time: Thu Aug 28 06:32:12 2025 Epoch Time (ms): 1756362732121 [2025-08-28T06:32:12.563Z] variation: NoOptions [2025-08-28T06:32:12.563Z] JVM_OPTIONS: [2025-08-28T06:32:12.563Z] { \ [2025-08-28T06:32:12.563Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T06:32:12.563Z] echo "Nothing to be done for setup."; \ [2025-08-28T06:32:12.563Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563604383126/renaissance-philosophers_0"; \ [2025-08-28T06:32:12.563Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563604383126/renaissance-philosophers_0"; \ [2025-08-28T06:32:12.563Z] echo ""; echo "TESTING:"; \ [2025-08-28T06:32:12.563Z] "/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_17563604383126/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-28T06:32:12.563Z] 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_17563604383126/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T06:32:12.563Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T06:32:12.563Z] echo "Nothing to be done for teardown."; \ [2025-08-28T06:32:12.563Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563604383126/TestTargetResult"; [2025-08-28T06:32:12.563Z] [2025-08-28T06:32:12.563Z] TEST SETUP: [2025-08-28T06:32:12.563Z] Nothing to be done for setup. [2025-08-28T06:32:12.563Z] [2025-08-28T06:32:12.563Z] TESTING: [2025-08-28T06:32:13.834Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-28T06:32:13.834Z] GC before operation: completed in 20.390 ms, heap usage 18.422 MB -> 4.855 MB. [2025-08-28T06:32:15.983Z] Camera thread performed 122 scans. [2025-08-28T06:32:15.983Z] ====== philosophers (scala) [default], iteration 0 completed (1873.119 ms) ====== [2025-08-28T06:32:15.983Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-28T06:32:15.983Z] GC before operation: completed in 28.722 ms, heap usage 18.898 MB -> 5.223 MB. [2025-08-28T06:32:16.638Z] Camera thread performed 122 scans. [2025-08-28T06:32:16.638Z] ====== philosophers (scala) [default], iteration 1 completed (998.866 ms) ====== [2025-08-28T06:32:16.638Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-28T06:32:16.638Z] GC before operation: completed in 21.788 ms, heap usage 65.328 MB -> 5.224 MB. [2025-08-28T06:32:17.975Z] Camera thread performed 122 scans. [2025-08-28T06:32:17.975Z] ====== philosophers (scala) [default], iteration 2 completed (880.516 ms) ====== [2025-08-28T06:32:17.975Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-28T06:32:17.975Z] GC before operation: completed in 20.708 ms, heap usage 32.334 MB -> 5.225 MB. [2025-08-28T06:32:18.606Z] Camera thread performed 122 scans. [2025-08-28T06:32:18.606Z] ====== philosophers (scala) [default], iteration 3 completed (646.579 ms) ====== [2025-08-28T06:32:18.606Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-28T06:32:18.606Z] GC before operation: completed in 22.927 ms, heap usage 24.303 MB -> 5.225 MB. [2025-08-28T06:32:19.238Z] Camera thread performed 122 scans. [2025-08-28T06:32:19.238Z] ====== philosophers (scala) [default], iteration 4 completed (799.231 ms) ====== [2025-08-28T06:32:19.238Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-28T06:32:19.238Z] GC before operation: completed in 26.648 ms, heap usage 20.200 MB -> 5.246 MB. [2025-08-28T06:32:19.850Z] Camera thread performed 122 scans. [2025-08-28T06:32:19.850Z] ====== philosophers (scala) [default], iteration 5 completed (736.448 ms) ====== [2025-08-28T06:32:19.850Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-28T06:32:19.850Z] GC before operation: completed in 27.180 ms, heap usage 55.899 MB -> 5.246 MB. [2025-08-28T06:32:20.509Z] Camera thread performed 122 scans. [2025-08-28T06:32:20.510Z] ====== philosophers (scala) [default], iteration 6 completed (728.470 ms) ====== [2025-08-28T06:32:20.510Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-28T06:32:20.510Z] GC before operation: completed in 19.941 ms, heap usage 61.312 MB -> 5.226 MB. [2025-08-28T06:32:21.820Z] Camera thread performed 122 scans. [2025-08-28T06:32:21.820Z] ====== philosophers (scala) [default], iteration 7 completed (881.384 ms) ====== [2025-08-28T06:32:21.820Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-28T06:32:21.820Z] GC before operation: completed in 18.766 ms, heap usage 12.982 MB -> 5.226 MB. [2025-08-28T06:32:22.427Z] Camera thread performed 122 scans. [2025-08-28T06:32:22.427Z] ====== philosophers (scala) [default], iteration 8 completed (759.075 ms) ====== [2025-08-28T06:32:22.427Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-28T06:32:22.427Z] GC before operation: completed in 15.378 ms, heap usage 46.066 MB -> 5.226 MB. [2025-08-28T06:32:23.682Z] Camera thread performed 122 scans. [2025-08-28T06:32:23.682Z] ====== philosophers (scala) [default], iteration 9 completed (879.597 ms) ====== [2025-08-28T06:32:23.682Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-28T06:32:23.682Z] GC before operation: completed in 31.593 ms, heap usage 33.764 MB -> 5.247 MB. [2025-08-28T06:32:24.298Z] Camera thread performed 122 scans. [2025-08-28T06:32:24.299Z] ====== philosophers (scala) [default], iteration 10 completed (773.927 ms) ====== [2025-08-28T06:32:24.299Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-28T06:32:24.299Z] GC before operation: completed in 16.004 ms, heap usage 50.319 MB -> 5.247 MB. [2025-08-28T06:32:24.915Z] Camera thread performed 122 scans. [2025-08-28T06:32:24.915Z] ====== philosophers (scala) [default], iteration 11 completed (842.670 ms) ====== [2025-08-28T06:32:24.915Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-28T06:32:24.915Z] GC before operation: completed in 25.023 ms, heap usage 58.371 MB -> 5.226 MB. [2025-08-28T06:32:25.544Z] Camera thread performed 122 scans. [2025-08-28T06:32:25.544Z] ====== philosophers (scala) [default], iteration 12 completed (783.730 ms) ====== [2025-08-28T06:32:25.544Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-28T06:32:25.544Z] GC before operation: completed in 15.282 ms, heap usage 27.122 MB -> 5.227 MB. [2025-08-28T06:32:26.834Z] Camera thread performed 122 scans. [2025-08-28T06:32:26.834Z] ====== philosophers (scala) [default], iteration 13 completed (839.316 ms) ====== [2025-08-28T06:32:26.834Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-28T06:32:26.834Z] GC before operation: completed in 10.441 ms, heap usage 30.034 MB -> 5.227 MB. [2025-08-28T06:32:27.458Z] Camera thread performed 122 scans. [2025-08-28T06:32:27.458Z] ====== philosophers (scala) [default], iteration 14 completed (956.993 ms) ====== [2025-08-28T06:32:27.458Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-28T06:32:27.458Z] GC before operation: completed in 25.151 ms, heap usage 37.673 MB -> 5.227 MB. [2025-08-28T06:32:28.782Z] Camera thread performed 122 scans. [2025-08-28T06:32:28.782Z] ====== philosophers (scala) [default], iteration 15 completed (741.145 ms) ====== [2025-08-28T06:32:28.782Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-28T06:32:28.782Z] GC before operation: completed in 16.431 ms, heap usage 21.815 MB -> 5.228 MB. [2025-08-28T06:32:29.420Z] Camera thread performed 122 scans. [2025-08-28T06:32:29.420Z] ====== philosophers (scala) [default], iteration 16 completed (714.737 ms) ====== [2025-08-28T06:32:29.420Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-28T06:32:29.420Z] GC before operation: completed in 24.653 ms, heap usage 55.758 MB -> 5.228 MB. [2025-08-28T06:32:30.051Z] Camera thread performed 122 scans. [2025-08-28T06:32:30.051Z] ====== philosophers (scala) [default], iteration 17 completed (706.689 ms) ====== [2025-08-28T06:32:30.051Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-28T06:32:30.051Z] GC before operation: completed in 60.681 ms, heap usage 9.814 MB -> 5.228 MB. [2025-08-28T06:32:30.672Z] Camera thread performed 122 scans. [2025-08-28T06:32:30.672Z] ====== philosophers (scala) [default], iteration 18 completed (751.277 ms) ====== [2025-08-28T06:32:30.672Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-28T06:32:30.672Z] GC before operation: completed in 19.159 ms, heap usage 49.339 MB -> 5.228 MB. [2025-08-28T06:32:31.326Z] Camera thread performed 122 scans. [2025-08-28T06:32:31.326Z] ====== philosophers (scala) [default], iteration 19 completed (791.162 ms) ====== [2025-08-28T06:32:31.326Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-28T06:32:31.326Z] GC before operation: completed in 14.177 ms, heap usage 11.711 MB -> 5.228 MB. [2025-08-28T06:32:31.956Z] Camera thread performed 122 scans. [2025-08-28T06:32:31.956Z] ====== philosophers (scala) [default], iteration 20 completed (697.375 ms) ====== [2025-08-28T06:32:31.956Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-28T06:32:31.956Z] GC before operation: completed in 32.030 ms, heap usage 61.339 MB -> 5.228 MB. [2025-08-28T06:32:33.256Z] Camera thread performed 122 scans. [2025-08-28T06:32:33.256Z] ====== philosophers (scala) [default], iteration 21 completed (940.458 ms) ====== [2025-08-28T06:32:33.256Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-28T06:32:33.256Z] GC before operation: completed in 48.629 ms, heap usage 16.817 MB -> 5.248 MB. [2025-08-28T06:32:33.937Z] Camera thread performed 122 scans. [2025-08-28T06:32:33.937Z] ====== philosophers (scala) [default], iteration 22 completed (787.722 ms) ====== [2025-08-28T06:32:33.937Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-28T06:32:33.937Z] GC before operation: completed in 34.496 ms, heap usage 24.334 MB -> 5.228 MB. [2025-08-28T06:32:35.212Z] Camera thread performed 122 scans. [2025-08-28T06:32:35.212Z] ====== philosophers (scala) [default], iteration 23 completed (809.202 ms) ====== [2025-08-28T06:32:35.212Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-28T06:32:35.212Z] GC before operation: completed in 25.247 ms, heap usage 10.328 MB -> 5.229 MB. [2025-08-28T06:32:35.836Z] Camera thread performed 122 scans. [2025-08-28T06:32:35.837Z] ====== philosophers (scala) [default], iteration 24 completed (792.872 ms) ====== [2025-08-28T06:32:35.837Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-28T06:32:35.837Z] GC before operation: completed in 30.045 ms, heap usage 13.827 MB -> 5.229 MB. [2025-08-28T06:32:36.456Z] Camera thread performed 122 scans. [2025-08-28T06:32:36.456Z] ====== philosophers (scala) [default], iteration 25 completed (725.644 ms) ====== [2025-08-28T06:32:36.456Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-28T06:32:36.456Z] GC before operation: completed in 21.121 ms, heap usage 29.751 MB -> 5.229 MB. [2025-08-28T06:32:37.152Z] Camera thread performed 122 scans. [2025-08-28T06:32:37.152Z] ====== philosophers (scala) [default], iteration 26 completed (881.169 ms) ====== [2025-08-28T06:32:37.152Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-28T06:32:37.152Z] GC before operation: completed in 22.787 ms, heap usage 13.870 MB -> 5.229 MB. [2025-08-28T06:32:38.499Z] Camera thread performed 122 scans. [2025-08-28T06:32:38.499Z] ====== philosophers (scala) [default], iteration 27 completed (936.679 ms) ====== [2025-08-28T06:32:38.499Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-28T06:32:38.499Z] GC before operation: completed in 38.933 ms, heap usage 24.315 MB -> 5.229 MB. [2025-08-28T06:32:39.204Z] Camera thread performed 122 scans. [2025-08-28T06:32:39.204Z] ====== philosophers (scala) [default], iteration 28 completed (795.400 ms) ====== [2025-08-28T06:32:39.204Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-28T06:32:39.204Z] GC before operation: completed in 25.979 ms, heap usage 30.293 MB -> 5.230 MB. [2025-08-28T06:32:39.822Z] Camera thread performed 122 scans. [2025-08-28T06:32:39.822Z] ====== philosophers (scala) [default], iteration 29 completed (645.336 ms) ====== [2025-08-28T06:32:39.822Z] ----------------------------------- [2025-08-28T06:32:39.822Z] renaissance-philosophers_0_PASSED [2025-08-28T06:32:39.822Z] ----------------------------------- [2025-08-28T06:32:39.822Z] [2025-08-28T06:32:39.822Z] TEST TEARDOWN: [2025-08-28T06:32:39.822Z] Nothing to be done for teardown. [2025-08-28T06:32:39.822Z] renaissance-philosophers_0 Finish Time: Thu Aug 28 06:32:39 2025 Epoch Time (ms): 1756362759646