[2025-05-22T05:49:50.927Z] Running test renaissance-philosophers_0 ... [2025-05-22T05:49:50.927Z] =============================================== [2025-05-22T05:49:50.927Z] renaissance-philosophers_0 Start Time: Thu May 22 05:49:50 2025 Epoch Time (ms): 1747892990590 [2025-05-22T05:49:50.927Z] variation: NoOptions [2025-05-22T05:49:50.927Z] JVM_OPTIONS: [2025-05-22T05:49:50.927Z] { \ [2025-05-22T05:49:50.927Z] echo ""; echo "TEST SETUP:"; \ [2025-05-22T05:49:50.927Z] echo "Nothing to be done for setup."; \ [2025-05-22T05:49:50.927Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17478877433422/renaissance-philosophers_0"; \ [2025-05-22T05:49:50.927Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17478877433422/renaissance-philosophers_0"; \ [2025-05-22T05:49:50.927Z] echo ""; echo "TESTING:"; \ [2025-05-22T05:49:50.927Z] "/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_17478877433422/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-22T05:49:50.927Z] 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_17478877433422/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-22T05:49:50.927Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-22T05:49:50.927Z] echo "Nothing to be done for teardown."; \ [2025-05-22T05:49:50.927Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17478877433422/TestTargetResult"; [2025-05-22T05:49:50.927Z] [2025-05-22T05:49:50.927Z] TEST SETUP: [2025-05-22T05:49:50.927Z] Nothing to be done for setup. [2025-05-22T05:49:50.927Z] [2025-05-22T05:49:50.927Z] TESTING: [2025-05-22T05:49:53.116Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-22T05:49:53.116Z] GC before operation: completed in 25.734 ms, heap usage 5.872 MB -> 4.817 MB. [2025-05-22T05:49:56.141Z] Camera thread performed 122 scans. [2025-05-22T05:49:56.141Z] ====== philosophers (scala) [default], iteration 0 completed (2513.725 ms) ====== [2025-05-22T05:49:56.141Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-22T05:49:56.141Z] GC before operation: completed in 25.728 ms, heap usage 28.879 MB -> 5.186 MB. [2025-05-22T05:49:56.802Z] Camera thread performed 122 scans. [2025-05-22T05:49:56.802Z] ====== philosophers (scala) [default], iteration 1 completed (1319.887 ms) ====== [2025-05-22T05:49:56.802Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-22T05:49:57.554Z] GC before operation: completed in 48.278 ms, heap usage 21.699 MB -> 5.185 MB. [2025-05-22T05:49:58.315Z] Camera thread performed 122 scans. [2025-05-22T05:49:58.315Z] ====== philosophers (scala) [default], iteration 2 completed (839.590 ms) ====== [2025-05-22T05:49:58.315Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-22T05:49:58.315Z] GC before operation: completed in 24.745 ms, heap usage 25.208 MB -> 5.185 MB. [2025-05-22T05:49:59.024Z] Camera thread performed 122 scans. [2025-05-22T05:49:59.024Z] ====== philosophers (scala) [default], iteration 3 completed (645.461 ms) ====== [2025-05-22T05:49:59.024Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-22T05:49:59.024Z] GC before operation: completed in 21.958 ms, heap usage 5.923 MB -> 5.186 MB. [2025-05-22T05:49:59.764Z] Camera thread performed 122 scans. [2025-05-22T05:49:59.764Z] ====== philosophers (scala) [default], iteration 4 completed (887.326 ms) ====== [2025-05-22T05:49:59.764Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-22T05:49:59.764Z] GC before operation: completed in 36.424 ms, heap usage 28.956 MB -> 5.186 MB. [2025-05-22T05:50:00.485Z] Camera thread performed 122 scans. [2025-05-22T05:50:00.485Z] ====== philosophers (scala) [default], iteration 5 completed (965.721 ms) ====== [2025-05-22T05:50:00.485Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-22T05:50:00.485Z] GC before operation: completed in 39.584 ms, heap usage 10.347 MB -> 5.186 MB. [2025-05-22T05:50:01.162Z] Camera thread performed 122 scans. [2025-05-22T05:50:01.162Z] ====== philosophers (scala) [default], iteration 6 completed (736.100 ms) ====== [2025-05-22T05:50:01.162Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-22T05:50:01.162Z] GC before operation: completed in 48.136 ms, heap usage 69.848 MB -> 5.186 MB. [2025-05-22T05:50:04.059Z] Camera thread performed 122 scans. [2025-05-22T05:50:04.059Z] ====== philosophers (scala) [default], iteration 7 completed (2104.405 ms) ====== [2025-05-22T05:50:04.059Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-22T05:50:04.059Z] GC before operation: completed in 36.939 ms, heap usage 59.338 MB -> 5.186 MB. [2025-05-22T05:50:04.776Z] Camera thread performed 122 scans. [2025-05-22T05:50:04.776Z] ====== philosophers (scala) [default], iteration 8 completed (1221.733 ms) ====== [2025-05-22T05:50:04.776Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-22T05:50:04.776Z] GC before operation: completed in 35.613 ms, heap usage 20.220 MB -> 5.187 MB. [2025-05-22T05:50:06.321Z] Camera thread performed 122 scans. [2025-05-22T05:50:06.321Z] ====== philosophers (scala) [default], iteration 9 completed (1221.936 ms) ====== [2025-05-22T05:50:06.321Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-22T05:50:06.321Z] GC before operation: completed in 24.893 ms, heap usage 37.621 MB -> 5.186 MB. [2025-05-22T05:50:07.055Z] Camera thread performed 122 scans. [2025-05-22T05:50:07.055Z] ====== philosophers (scala) [default], iteration 10 completed (934.513 ms) ====== [2025-05-22T05:50:07.055Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-22T05:50:07.055Z] GC before operation: completed in 49.970 ms, heap usage 10.794 MB -> 5.187 MB. [2025-05-22T05:50:08.611Z] Camera thread performed 122 scans. [2025-05-22T05:50:08.611Z] ====== philosophers (scala) [default], iteration 11 completed (1157.948 ms) ====== [2025-05-22T05:50:08.611Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-22T05:50:08.611Z] GC before operation: completed in 76.006 ms, heap usage 56.788 MB -> 5.187 MB. [2025-05-22T05:50:09.283Z] Camera thread performed 122 scans. [2025-05-22T05:50:09.283Z] ====== philosophers (scala) [default], iteration 12 completed (1153.263 ms) ====== [2025-05-22T05:50:09.283Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-22T05:50:09.283Z] GC before operation: completed in 52.983 ms, heap usage 28.776 MB -> 5.187 MB. [2025-05-22T05:50:10.709Z] Camera thread performed 122 scans. [2025-05-22T05:50:10.709Z] ====== philosophers (scala) [default], iteration 13 completed (913.333 ms) ====== [2025-05-22T05:50:10.709Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-22T05:50:10.709Z] GC before operation: completed in 24.377 ms, heap usage 37.317 MB -> 5.187 MB. [2025-05-22T05:50:10.709Z] Camera thread performed 122 scans. [2025-05-22T05:50:10.709Z] ====== philosophers (scala) [default], iteration 14 completed (611.918 ms) ====== [2025-05-22T05:50:10.709Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-22T05:50:11.421Z] GC before operation: completed in 29.120 ms, heap usage 15.279 MB -> 5.187 MB. [2025-05-22T05:50:11.421Z] Camera thread performed 122 scans. [2025-05-22T05:50:11.421Z] ====== philosophers (scala) [default], iteration 15 completed (683.653 ms) ====== [2025-05-22T05:50:11.421Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-22T05:50:12.088Z] GC before operation: completed in 22.526 ms, heap usage 5.222 MB -> 5.188 MB. [2025-05-22T05:50:12.773Z] Camera thread performed 122 scans. [2025-05-22T05:50:12.773Z] ====== philosophers (scala) [default], iteration 16 completed (739.527 ms) ====== [2025-05-22T05:50:12.773Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-22T05:50:12.773Z] GC before operation: completed in 30.700 ms, heap usage 11.796 MB -> 5.188 MB. [2025-05-22T05:50:13.458Z] Camera thread performed 122 scans. [2025-05-22T05:50:13.458Z] ====== philosophers (scala) [default], iteration 17 completed (662.651 ms) ====== [2025-05-22T05:50:13.458Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-22T05:50:13.458Z] GC before operation: completed in 16.799 ms, heap usage 49.826 MB -> 5.188 MB. [2025-05-22T05:50:14.134Z] Camera thread performed 122 scans. [2025-05-22T05:50:14.134Z] ====== philosophers (scala) [default], iteration 18 completed (828.789 ms) ====== [2025-05-22T05:50:14.134Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-22T05:50:14.134Z] GC before operation: completed in 22.826 ms, heap usage 6.293 MB -> 5.188 MB. [2025-05-22T05:50:14.803Z] Camera thread performed 122 scans. [2025-05-22T05:50:14.803Z] ====== philosophers (scala) [default], iteration 19 completed (691.276 ms) ====== [2025-05-22T05:50:14.803Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-22T05:50:14.803Z] GC before operation: completed in 28.980 ms, heap usage 29.316 MB -> 5.188 MB. [2025-05-22T05:50:15.463Z] Camera thread performed 122 scans. [2025-05-22T05:50:15.463Z] ====== philosophers (scala) [default], iteration 20 completed (741.142 ms) ====== [2025-05-22T05:50:15.463Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-22T05:50:15.463Z] GC before operation: completed in 45.601 ms, heap usage 5.683 MB -> 5.188 MB. [2025-05-22T05:50:16.119Z] Camera thread performed 122 scans. [2025-05-22T05:50:16.119Z] ====== philosophers (scala) [default], iteration 21 completed (790.312 ms) ====== [2025-05-22T05:50:16.119Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-22T05:50:16.119Z] GC before operation: completed in 22.263 ms, heap usage 36.806 MB -> 5.188 MB. [2025-05-22T05:50:17.555Z] Camera thread performed 122 scans. [2025-05-22T05:50:17.555Z] ====== philosophers (scala) [default], iteration 22 completed (1012.124 ms) ====== [2025-05-22T05:50:17.555Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-22T05:50:17.555Z] GC before operation: completed in 45.228 ms, heap usage 51.811 MB -> 5.189 MB. [2025-05-22T05:50:19.005Z] Camera thread performed 122 scans. [2025-05-22T05:50:19.005Z] ====== philosophers (scala) [default], iteration 23 completed (1180.467 ms) ====== [2025-05-22T05:50:19.005Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-22T05:50:19.005Z] GC before operation: completed in 69.407 ms, heap usage 46.279 MB -> 5.189 MB. [2025-05-22T05:50:19.712Z] Camera thread performed 122 scans. [2025-05-22T05:50:19.712Z] ====== philosophers (scala) [default], iteration 24 completed (925.477 ms) ====== [2025-05-22T05:50:19.712Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-22T05:50:19.712Z] GC before operation: completed in 31.394 ms, heap usage 7.279 MB -> 5.189 MB. [2025-05-22T05:50:20.392Z] Camera thread performed 122 scans. [2025-05-22T05:50:20.392Z] ====== philosophers (scala) [default], iteration 25 completed (1059.594 ms) ====== [2025-05-22T05:50:20.392Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-22T05:50:21.074Z] GC before operation: completed in 32.087 ms, heap usage 21.296 MB -> 5.189 MB. [2025-05-22T05:50:21.074Z] Camera thread performed 122 scans. [2025-05-22T05:50:21.074Z] ====== philosophers (scala) [default], iteration 26 completed (637.979 ms) ====== [2025-05-22T05:50:21.074Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-22T05:50:21.074Z] GC before operation: completed in 23.677 ms, heap usage 72.827 MB -> 5.189 MB. [2025-05-22T05:50:22.564Z] Camera thread performed 122 scans. [2025-05-22T05:50:22.565Z] ====== philosophers (scala) [default], iteration 27 completed (903.824 ms) ====== [2025-05-22T05:50:22.565Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-22T05:50:22.565Z] GC before operation: completed in 54.109 ms, heap usage 44.308 MB -> 5.189 MB. [2025-05-22T05:50:23.256Z] Camera thread performed 122 scans. [2025-05-22T05:50:23.256Z] ====== philosophers (scala) [default], iteration 28 completed (1109.398 ms) ====== [2025-05-22T05:50:23.256Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-22T05:50:23.256Z] GC before operation: completed in 21.602 ms, heap usage 55.806 MB -> 5.189 MB. [2025-05-22T05:50:23.907Z] Camera thread performed 122 scans. [2025-05-22T05:50:23.907Z] ====== philosophers (scala) [default], iteration 29 completed (634.876 ms) ====== [2025-05-22T05:50:24.554Z] ----------------------------------- [2025-05-22T05:50:24.554Z] renaissance-philosophers_0_PASSED [2025-05-22T05:50:24.554Z] ----------------------------------- [2025-05-22T05:50:24.554Z] [2025-05-22T05:50:24.554Z] TEST TEARDOWN: [2025-05-22T05:50:24.554Z] Nothing to be done for teardown. [2025-05-22T05:50:24.554Z] renaissance-philosophers_0 Finish Time: Thu May 22 05:50:23 2025 Epoch Time (ms): 1747893023987