[2025-08-14T02:56:29.130Z] Running test renaissance-philosophers_0 ... [2025-08-14T02:56:29.130Z] =============================================== [2025-08-14T02:56:29.130Z] renaissance-philosophers_0 Start Time: Thu Aug 14 02:56:28 2025 Epoch Time (ms): 1755140188000 [2025-08-14T02:56:29.130Z] variation: NoOptions [2025-08-14T02:56:29.130Z] JVM_OPTIONS: [2025-08-14T02:56:29.130Z] { \ [2025-08-14T02:56:29.130Z] echo ""; echo "TEST SETUP:"; \ [2025-08-14T02:56:29.130Z] echo "Nothing to be done for setup."; \ [2025-08-14T02:56:29.130Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17551344801251/renaissance-philosophers_0"; \ [2025-08-14T02:56:29.130Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17551344801251/renaissance-philosophers_0"; \ [2025-08-14T02:56:29.130Z] echo ""; echo "TESTING:"; \ [2025-08-14T02:56:29.130Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17551344801251/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-14T02:56:29.130Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17551344801251/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-14T02:56:29.130Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-14T02:56:29.130Z] echo "Nothing to be done for teardown."; \ [2025-08-14T02:56:29.130Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17551344801251/TestTargetResult"; [2025-08-14T02:56:29.130Z] [2025-08-14T02:56:29.130Z] TEST SETUP: [2025-08-14T02:56:29.130Z] Nothing to be done for setup. [2025-08-14T02:56:29.130Z] [2025-08-14T02:56:29.130Z] TESTING: [2025-08-14T02:56:30.483Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-14T02:56:30.483Z] GC before operation: completed in 38.219 ms, heap usage 10.661 MB -> 4.392 MB. [2025-08-14T02:56:32.717Z] Camera thread performed 122 scans. [2025-08-14T02:56:32.717Z] ====== philosophers (scala) [default], iteration 0 completed (2285.784 ms) ====== [2025-08-14T02:56:32.717Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-14T02:56:32.717Z] GC before operation: completed in 29.555 ms, heap usage 20.797 MB -> 4.805 MB. [2025-08-14T02:56:34.154Z] Camera thread performed 122 scans. [2025-08-14T02:56:34.154Z] ====== philosophers (scala) [default], iteration 1 completed (1456.504 ms) ====== [2025-08-14T02:56:34.154Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-14T02:56:34.154Z] GC before operation: completed in 66.549 ms, heap usage 8.828 MB -> 4.804 MB. [2025-08-14T02:56:35.556Z] Camera thread performed 122 scans. [2025-08-14T02:56:35.556Z] ====== philosophers (scala) [default], iteration 2 completed (993.246 ms) ====== [2025-08-14T02:56:35.556Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-14T02:56:35.556Z] GC before operation: completed in 35.118 ms, heap usage 32.884 MB -> 4.805 MB. [2025-08-14T02:56:36.236Z] Camera thread performed 122 scans. [2025-08-14T02:56:36.236Z] ====== philosophers (scala) [default], iteration 3 completed (866.804 ms) ====== [2025-08-14T02:56:36.236Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-14T02:56:36.236Z] GC before operation: completed in 17.184 ms, heap usage 8.883 MB -> 4.809 MB. [2025-08-14T02:56:36.888Z] Camera thread performed 122 scans. [2025-08-14T02:56:36.888Z] ====== philosophers (scala) [default], iteration 4 completed (830.557 ms) ====== [2025-08-14T02:56:36.888Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-14T02:56:36.889Z] GC before operation: completed in 35.575 ms, heap usage 66.596 MB -> 4.809 MB. [2025-08-14T02:56:38.292Z] Camera thread performed 122 scans. [2025-08-14T02:56:38.292Z] ====== philosophers (scala) [default], iteration 5 completed (957.924 ms) ====== [2025-08-14T02:56:38.292Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-14T02:56:38.292Z] GC before operation: completed in 107.762 ms, heap usage 48.504 MB -> 4.809 MB. [2025-08-14T02:56:38.982Z] Camera thread performed 122 scans. [2025-08-14T02:56:38.982Z] ====== philosophers (scala) [default], iteration 6 completed (986.110 ms) ====== [2025-08-14T02:56:38.982Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-14T02:56:38.982Z] GC before operation: completed in 26.220 ms, heap usage 16.223 MB -> 4.810 MB. [2025-08-14T02:56:40.562Z] Camera thread performed 122 scans. [2025-08-14T02:56:40.562Z] ====== philosophers (scala) [default], iteration 7 completed (922.532 ms) ====== [2025-08-14T02:56:40.562Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-14T02:56:40.562Z] GC before operation: completed in 43.051 ms, heap usage 36.904 MB -> 4.810 MB. [2025-08-14T02:56:41.208Z] Camera thread performed 122 scans. [2025-08-14T02:56:41.208Z] ====== philosophers (scala) [default], iteration 8 completed (1312.067 ms) ====== [2025-08-14T02:56:41.208Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-14T02:56:41.208Z] GC before operation: completed in 103.122 ms, heap usage 6.406 MB -> 4.813 MB. [2025-08-14T02:56:42.843Z] Camera thread performed 122 scans. [2025-08-14T02:56:42.843Z] ====== philosophers (scala) [default], iteration 9 completed (938.705 ms) ====== [2025-08-14T02:56:42.843Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-14T02:56:42.843Z] GC before operation: completed in 46.040 ms, heap usage 21.915 MB -> 4.813 MB. [2025-08-14T02:56:43.921Z] Camera thread performed 122 scans. [2025-08-14T02:56:43.921Z] ====== philosophers (scala) [default], iteration 10 completed (1453.338 ms) ====== [2025-08-14T02:56:43.921Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-14T02:56:43.921Z] GC before operation: completed in 33.810 ms, heap usage 44.447 MB -> 4.814 MB. [2025-08-14T02:56:44.636Z] Camera thread performed 122 scans. [2025-08-14T02:56:44.636Z] ====== philosophers (scala) [default], iteration 11 completed (697.876 ms) ====== [2025-08-14T02:56:44.636Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-14T02:56:44.636Z] GC before operation: completed in 27.483 ms, heap usage 10.397 MB -> 4.813 MB. [2025-08-14T02:56:45.372Z] Camera thread performed 122 scans. [2025-08-14T02:56:45.372Z] ====== philosophers (scala) [default], iteration 12 completed (925.473 ms) ====== [2025-08-14T02:56:45.372Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-14T02:56:46.148Z] GC before operation: completed in 47.288 ms, heap usage 30.449 MB -> 4.815 MB. [2025-08-14T02:56:46.836Z] Camera thread performed 122 scans. [2025-08-14T02:56:46.836Z] ====== philosophers (scala) [default], iteration 13 completed (950.269 ms) ====== [2025-08-14T02:56:46.836Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-14T02:56:46.836Z] GC before operation: completed in 40.141 ms, heap usage 25.432 MB -> 4.814 MB. [2025-08-14T02:56:47.518Z] Camera thread performed 122 scans. [2025-08-14T02:56:47.519Z] ====== philosophers (scala) [default], iteration 14 completed (1004.120 ms) ====== [2025-08-14T02:56:47.519Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-14T02:56:47.519Z] GC before operation: completed in 62.660 ms, heap usage 5.792 MB -> 4.814 MB. [2025-08-14T02:56:48.882Z] Camera thread performed 122 scans. [2025-08-14T02:56:48.882Z] ====== philosophers (scala) [default], iteration 15 completed (986.084 ms) ====== [2025-08-14T02:56:48.882Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-14T02:56:48.882Z] GC before operation: completed in 43.663 ms, heap usage 18.939 MB -> 4.814 MB. [2025-08-14T02:56:49.583Z] Camera thread performed 122 scans. [2025-08-14T02:56:49.583Z] ====== philosophers (scala) [default], iteration 16 completed (1034.861 ms) ====== [2025-08-14T02:56:49.583Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-14T02:56:49.583Z] GC before operation: completed in 23.300 ms, heap usage 29.512 MB -> 4.815 MB. [2025-08-14T02:56:51.037Z] Camera thread performed 122 scans. [2025-08-14T02:56:51.037Z] ====== philosophers (scala) [default], iteration 17 completed (943.981 ms) ====== [2025-08-14T02:56:51.037Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-14T02:56:51.037Z] GC before operation: completed in 32.935 ms, heap usage 15.412 MB -> 4.815 MB. [2025-08-14T02:56:51.732Z] Camera thread performed 122 scans. [2025-08-14T02:56:51.732Z] ====== philosophers (scala) [default], iteration 18 completed (834.966 ms) ====== [2025-08-14T02:56:51.732Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-14T02:56:51.732Z] GC before operation: completed in 83.140 ms, heap usage 20.905 MB -> 4.816 MB. [2025-08-14T02:56:52.371Z] Camera thread performed 122 scans. [2025-08-14T02:56:52.371Z] ====== philosophers (scala) [default], iteration 19 completed (801.393 ms) ====== [2025-08-14T02:56:52.371Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-14T02:56:52.371Z] GC before operation: completed in 19.916 ms, heap usage 18.442 MB -> 4.816 MB. [2025-08-14T02:56:53.059Z] Camera thread performed 122 scans. [2025-08-14T02:56:53.059Z] ====== philosophers (scala) [default], iteration 20 completed (742.348 ms) ====== [2025-08-14T02:56:53.059Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-14T02:56:53.059Z] GC before operation: completed in 38.823 ms, heap usage 63.931 MB -> 4.817 MB. [2025-08-14T02:56:54.486Z] Camera thread performed 122 scans. [2025-08-14T02:56:54.486Z] ====== philosophers (scala) [default], iteration 21 completed (1017.463 ms) ====== [2025-08-14T02:56:54.486Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-14T02:56:54.486Z] GC before operation: completed in 40.068 ms, heap usage 37.445 MB -> 4.816 MB. [2025-08-14T02:56:55.112Z] Camera thread performed 122 scans. [2025-08-14T02:56:55.112Z] ====== philosophers (scala) [default], iteration 22 completed (870.549 ms) ====== [2025-08-14T02:56:55.112Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-14T02:56:55.112Z] GC before operation: completed in 15.082 ms, heap usage 52.431 MB -> 4.816 MB. [2025-08-14T02:56:56.441Z] Camera thread performed 122 scans. [2025-08-14T02:56:56.441Z] ====== philosophers (scala) [default], iteration 23 completed (843.424 ms) ====== [2025-08-14T02:56:56.441Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-14T02:56:56.441Z] GC before operation: completed in 55.390 ms, heap usage 61.861 MB -> 4.816 MB. [2025-08-14T02:56:57.166Z] Camera thread performed 122 scans. [2025-08-14T02:56:57.166Z] ====== philosophers (scala) [default], iteration 24 completed (670.435 ms) ====== [2025-08-14T02:56:57.166Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-14T02:56:57.166Z] GC before operation: completed in 25.635 ms, heap usage 28.582 MB -> 4.816 MB. [2025-08-14T02:56:57.826Z] Camera thread performed 122 scans. [2025-08-14T02:56:57.826Z] ====== philosophers (scala) [default], iteration 25 completed (700.597 ms) ====== [2025-08-14T02:56:57.826Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-14T02:56:57.826Z] GC before operation: completed in 52.677 ms, heap usage 55.921 MB -> 4.816 MB. [2025-08-14T02:56:58.526Z] Camera thread performed 122 scans. [2025-08-14T02:56:58.526Z] ====== philosophers (scala) [default], iteration 26 completed (856.858 ms) ====== [2025-08-14T02:56:58.526Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-14T02:56:58.526Z] GC before operation: completed in 41.123 ms, heap usage 47.884 MB -> 4.817 MB. [2025-08-14T02:57:00.021Z] Camera thread performed 122 scans. [2025-08-14T02:57:00.021Z] ====== philosophers (scala) [default], iteration 27 completed (1180.600 ms) ====== [2025-08-14T02:57:00.021Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-14T02:57:00.021Z] GC before operation: completed in 82.312 ms, heap usage 55.452 MB -> 4.816 MB. [2025-08-14T02:57:00.682Z] Camera thread performed 122 scans. [2025-08-14T02:57:00.682Z] ====== philosophers (scala) [default], iteration 28 completed (855.694 ms) ====== [2025-08-14T02:57:00.682Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-14T02:57:00.682Z] GC before operation: completed in 43.767 ms, heap usage 34.698 MB -> 4.818 MB. [2025-08-14T02:57:01.323Z] Camera thread performed 122 scans. [2025-08-14T02:57:01.323Z] ====== philosophers (scala) [default], iteration 29 completed (761.710 ms) ====== [2025-08-14T02:57:01.956Z] ----------------------------------- [2025-08-14T02:57:01.956Z] renaissance-philosophers_0_PASSED [2025-08-14T02:57:01.956Z] ----------------------------------- [2025-08-14T02:57:01.956Z] [2025-08-14T02:57:01.956Z] TEST TEARDOWN: [2025-08-14T02:57:01.956Z] Nothing to be done for teardown. [2025-08-14T02:57:01.956Z] renaissance-philosophers_0 Finish Time: Thu Aug 14 02:57:01 2025 Epoch Time (ms): 1755140221332