[2025-09-03T23:01:10.596Z] Running test renaissance-philosophers_0 ... [2025-09-03T23:01:10.596Z] =============================================== [2025-09-03T23:01:10.596Z] renaissance-philosophers_0 Start Time: Wed Sep 3 23:01:10 2025 Epoch Time (ms): 1756940470121 [2025-09-03T23:01:10.596Z] variation: NoOptions [2025-09-03T23:01:10.596Z] JVM_OPTIONS: [2025-09-03T23:01:10.596Z] { \ [2025-09-03T23:01:10.596Z] echo ""; echo "TEST SETUP:"; \ [2025-09-03T23:01:10.596Z] echo "Nothing to be done for setup."; \ [2025-09-03T23:01:10.596Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17569356957324/renaissance-philosophers_0"; \ [2025-09-03T23:01:10.596Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17569356957324/renaissance-philosophers_0"; \ [2025-09-03T23:01:10.596Z] echo ""; echo "TESTING:"; \ [2025-09-03T23:01:10.596Z] "/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_17569356957324/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-09-03T23:01:10.596Z] 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_17569356957324/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-03T23:01:10.597Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-03T23:01:10.597Z] echo "Nothing to be done for teardown."; \ [2025-09-03T23:01:10.597Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17569356957324/TestTargetResult"; [2025-09-03T23:01:10.597Z] [2025-09-03T23:01:10.597Z] TEST SETUP: [2025-09-03T23:01:10.597Z] Nothing to be done for setup. [2025-09-03T23:01:10.597Z] [2025-09-03T23:01:10.597Z] TESTING: [2025-09-03T23:01:11.983Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-03T23:01:11.983Z] GC before operation: completed in 49.025 ms, heap usage 18.367 MB -> 4.853 MB. [2025-09-03T23:01:15.052Z] Camera thread performed 122 scans. [2025-09-03T23:01:15.052Z] ====== philosophers (scala) [default], iteration 0 completed (2334.778 ms) ====== [2025-09-03T23:01:15.052Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-03T23:01:15.052Z] GC before operation: completed in 38.570 ms, heap usage 42.464 MB -> 5.223 MB. [2025-09-03T23:01:15.702Z] Camera thread performed 122 scans. [2025-09-03T23:01:15.702Z] ====== philosophers (scala) [default], iteration 1 completed (928.017 ms) ====== [2025-09-03T23:01:15.702Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-03T23:01:15.702Z] GC before operation: completed in 28.726 ms, heap usage 44.127 MB -> 5.224 MB. [2025-09-03T23:01:16.360Z] Camera thread performed 122 scans. [2025-09-03T23:01:16.360Z] ====== philosophers (scala) [default], iteration 2 completed (909.063 ms) ====== [2025-09-03T23:01:16.360Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-03T23:01:16.360Z] GC before operation: completed in 26.268 ms, heap usage 21.954 MB -> 5.225 MB. [2025-09-03T23:01:17.835Z] Camera thread performed 122 scans. [2025-09-03T23:01:17.835Z] ====== philosophers (scala) [default], iteration 3 completed (1194.339 ms) ====== [2025-09-03T23:01:17.835Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-03T23:01:17.835Z] GC before operation: completed in 103.668 ms, heap usage 39.935 MB -> 5.225 MB. [2025-09-03T23:01:18.509Z] Camera thread performed 122 scans. [2025-09-03T23:01:18.509Z] ====== philosophers (scala) [default], iteration 4 completed (977.200 ms) ====== [2025-09-03T23:01:18.509Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-03T23:01:18.509Z] GC before operation: completed in 16.428 ms, heap usage 51.177 MB -> 5.246 MB. [2025-09-03T23:01:19.910Z] Camera thread performed 122 scans. [2025-09-03T23:01:19.910Z] ====== philosophers (scala) [default], iteration 5 completed (904.244 ms) ====== [2025-09-03T23:01:19.910Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-03T23:01:19.910Z] GC before operation: completed in 36.031 ms, heap usage 25.351 MB -> 5.247 MB. [2025-09-03T23:01:20.572Z] Camera thread performed 122 scans. [2025-09-03T23:01:20.572Z] ====== philosophers (scala) [default], iteration 6 completed (876.073 ms) ====== [2025-09-03T23:01:20.572Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-03T23:01:20.572Z] GC before operation: completed in 23.089 ms, heap usage 30.371 MB -> 5.226 MB. [2025-09-03T23:01:22.059Z] Camera thread performed 122 scans. [2025-09-03T23:01:22.059Z] ====== philosophers (scala) [default], iteration 7 completed (1024.256 ms) ====== [2025-09-03T23:01:22.059Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-03T23:01:22.059Z] GC before operation: completed in 33.893 ms, heap usage 64.356 MB -> 5.247 MB. [2025-09-03T23:01:23.238Z] Camera thread performed 122 scans. [2025-09-03T23:01:23.238Z] ====== philosophers (scala) [default], iteration 8 completed (995.262 ms) ====== [2025-09-03T23:01:23.238Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-03T23:01:23.238Z] GC before operation: completed in 44.715 ms, heap usage 17.304 MB -> 5.227 MB. [2025-09-03T23:01:23.982Z] Camera thread performed 122 scans. [2025-09-03T23:01:23.982Z] ====== philosophers (scala) [default], iteration 9 completed (996.195 ms) ====== [2025-09-03T23:01:23.982Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-03T23:01:23.982Z] GC before operation: completed in 21.087 ms, heap usage 56.829 MB -> 5.226 MB. [2025-09-03T23:01:24.647Z] Camera thread performed 122 scans. [2025-09-03T23:01:24.647Z] ====== philosophers (scala) [default], iteration 10 completed (865.061 ms) ====== [2025-09-03T23:01:24.647Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-03T23:01:24.647Z] GC before operation: completed in 47.687 ms, heap usage 14.320 MB -> 5.227 MB. [2025-09-03T23:01:25.307Z] Camera thread performed 122 scans. [2025-09-03T23:01:25.307Z] ====== philosophers (scala) [default], iteration 11 completed (899.803 ms) ====== [2025-09-03T23:01:25.307Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-03T23:01:25.999Z] GC before operation: completed in 33.548 ms, heap usage 19.820 MB -> 5.247 MB. [2025-09-03T23:01:26.686Z] Camera thread performed 122 scans. [2025-09-03T23:01:26.686Z] ====== philosophers (scala) [default], iteration 12 completed (867.737 ms) ====== [2025-09-03T23:01:26.686Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-03T23:01:26.686Z] GC before operation: completed in 34.714 ms, heap usage 69.861 MB -> 5.227 MB. [2025-09-03T23:01:27.403Z] Camera thread performed 122 scans. [2025-09-03T23:01:27.403Z] ====== philosophers (scala) [default], iteration 13 completed (789.964 ms) ====== [2025-09-03T23:01:27.403Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-03T23:01:27.403Z] GC before operation: completed in 30.217 ms, heap usage 30.823 MB -> 5.227 MB. [2025-09-03T23:01:28.060Z] Camera thread performed 122 scans. [2025-09-03T23:01:28.060Z] ====== philosophers (scala) [default], iteration 14 completed (808.674 ms) ====== [2025-09-03T23:01:28.060Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-03T23:01:28.060Z] GC before operation: completed in 35.672 ms, heap usage 56.346 MB -> 5.228 MB. [2025-09-03T23:01:28.705Z] Camera thread performed 122 scans. [2025-09-03T23:01:28.705Z] ====== philosophers (scala) [default], iteration 15 completed (799.974 ms) ====== [2025-09-03T23:01:28.705Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-03T23:01:29.402Z] GC before operation: completed in 31.192 ms, heap usage 33.282 MB -> 5.248 MB. [2025-09-03T23:01:30.127Z] Camera thread performed 122 scans. [2025-09-03T23:01:30.127Z] ====== philosophers (scala) [default], iteration 16 completed (809.918 ms) ====== [2025-09-03T23:01:30.127Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-03T23:01:30.127Z] GC before operation: completed in 52.354 ms, heap usage 20.225 MB -> 5.248 MB. [2025-09-03T23:01:30.818Z] Camera thread performed 122 scans. [2025-09-03T23:01:30.818Z] ====== philosophers (scala) [default], iteration 17 completed (721.664 ms) ====== [2025-09-03T23:01:30.818Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-03T23:01:30.818Z] GC before operation: completed in 15.604 ms, heap usage 9.340 MB -> 5.228 MB. [2025-09-03T23:01:31.457Z] Camera thread performed 122 scans. [2025-09-03T23:01:31.458Z] ====== philosophers (scala) [default], iteration 18 completed (781.457 ms) ====== [2025-09-03T23:01:31.458Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-03T23:01:31.458Z] GC before operation: completed in 41.654 ms, heap usage 49.312 MB -> 5.249 MB. [2025-09-03T23:01:32.143Z] Camera thread performed 122 scans. [2025-09-03T23:01:32.143Z] ====== philosophers (scala) [default], iteration 19 completed (928.066 ms) ====== [2025-09-03T23:01:32.143Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-03T23:01:32.143Z] GC before operation: completed in 39.598 ms, heap usage 16.804 MB -> 5.229 MB. [2025-09-03T23:01:33.640Z] Camera thread performed 122 scans. [2025-09-03T23:01:33.640Z] ====== philosophers (scala) [default], iteration 20 completed (906.070 ms) ====== [2025-09-03T23:01:33.640Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-03T23:01:33.640Z] GC before operation: completed in 31.971 ms, heap usage 13.854 MB -> 5.228 MB. [2025-09-03T23:01:34.283Z] Camera thread performed 122 scans. [2025-09-03T23:01:34.283Z] ====== philosophers (scala) [default], iteration 21 completed (861.813 ms) ====== [2025-09-03T23:01:34.283Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-03T23:01:34.283Z] GC before operation: completed in 19.503 ms, heap usage 40.222 MB -> 5.228 MB. [2025-09-03T23:01:34.936Z] Camera thread performed 122 scans. [2025-09-03T23:01:34.936Z] ====== philosophers (scala) [default], iteration 22 completed (850.641 ms) ====== [2025-09-03T23:01:34.936Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-03T23:01:34.936Z] GC before operation: completed in 13.556 ms, heap usage 33.355 MB -> 5.229 MB. [2025-09-03T23:01:36.321Z] Camera thread performed 122 scans. [2025-09-03T23:01:36.321Z] ====== philosophers (scala) [default], iteration 23 completed (923.679 ms) ====== [2025-09-03T23:01:36.321Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-03T23:01:36.321Z] GC before operation: completed in 28.035 ms, heap usage 19.853 MB -> 5.229 MB. [2025-09-03T23:01:37.007Z] Camera thread performed 122 scans. [2025-09-03T23:01:37.007Z] ====== philosophers (scala) [default], iteration 24 completed (867.854 ms) ====== [2025-09-03T23:01:37.007Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-03T23:01:37.007Z] GC before operation: completed in 14.023 ms, heap usage 15.704 MB -> 5.229 MB. [2025-09-03T23:01:37.710Z] Camera thread performed 122 scans. [2025-09-03T23:01:37.710Z] ====== philosophers (scala) [default], iteration 25 completed (785.026 ms) ====== [2025-09-03T23:01:37.710Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-03T23:01:37.710Z] GC before operation: completed in 27.203 ms, heap usage 29.202 MB -> 5.229 MB. [2025-09-03T23:01:38.385Z] Camera thread performed 122 scans. [2025-09-03T23:01:38.385Z] ====== philosophers (scala) [default], iteration 26 completed (829.764 ms) ====== [2025-09-03T23:01:38.385Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-03T23:01:38.385Z] GC before operation: completed in 34.374 ms, heap usage 24.828 MB -> 5.250 MB. [2025-09-03T23:01:39.845Z] Camera thread performed 122 scans. [2025-09-03T23:01:39.845Z] ====== philosophers (scala) [default], iteration 27 completed (796.654 ms) ====== [2025-09-03T23:01:39.845Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-03T23:01:39.845Z] GC before operation: completed in 23.748 ms, heap usage 44.235 MB -> 5.250 MB. [2025-09-03T23:01:40.508Z] Camera thread performed 122 scans. [2025-09-03T23:01:40.508Z] ====== philosophers (scala) [default], iteration 28 completed (860.888 ms) ====== [2025-09-03T23:01:40.508Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-03T23:01:40.508Z] GC before operation: completed in 22.328 ms, heap usage 55.334 MB -> 5.230 MB. [2025-09-03T23:01:41.144Z] Camera thread performed 122 scans. [2025-09-03T23:01:41.144Z] ====== philosophers (scala) [default], iteration 29 completed (816.622 ms) ====== [2025-09-03T23:01:41.144Z] ----------------------------------- [2025-09-03T23:01:41.144Z] renaissance-philosophers_0_PASSED [2025-09-03T23:01:41.144Z] ----------------------------------- [2025-09-03T23:01:41.144Z] [2025-09-03T23:01:41.144Z] TEST TEARDOWN: [2025-09-03T23:01:41.144Z] Nothing to be done for teardown. [2025-09-03T23:01:41.144Z] renaissance-philosophers_0 Finish Time: Wed Sep 3 23:01:41 2025 Epoch Time (ms): 1756940501045