[2026-04-06T23:02:56.921Z] Running test renaissance-philosophers_0 ... [2026-04-06T23:02:56.921Z] =============================================== [2026-04-06T23:02:56.921Z] renaissance-philosophers_0 Start Time: Mon Apr 6 23:02:56 2026 Epoch Time (ms): 1775516576327 [2026-04-06T23:02:56.921Z] variation: NoOptions [2026-04-06T23:02:56.921Z] JVM_OPTIONS: [2026-04-06T23:02:56.921Z] { \ [2026-04-06T23:02:56.921Z] echo ""; echo "TEST SETUP:"; \ [2026-04-06T23:02:56.921Z] echo "Nothing to be done for setup."; \ [2026-04-06T23:02:56.922Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17755137843114/renaissance-philosophers_0"; \ [2026-04-06T23:02:56.922Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17755137843114/renaissance-philosophers_0"; \ [2026-04-06T23:02:56.922Z] echo ""; echo "TESTING:"; \ [2026-04-06T23:02:56.922Z] "/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_17755137843114/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2026-04-06T23:02:56.922Z] 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_17755137843114/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-06T23:02:56.922Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-06T23:02:56.922Z] echo "Nothing to be done for teardown."; \ [2026-04-06T23:02:56.922Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17755137843114/TestTargetResult"; [2026-04-06T23:02:56.922Z] [2026-04-06T23:02:56.922Z] TEST SETUP: [2026-04-06T23:02:56.922Z] Nothing to be done for setup. [2026-04-06T23:02:56.922Z] [2026-04-06T23:02:56.922Z] TESTING: [2026-04-06T23:02:59.252Z] ====== philosophers (scala) [default], iteration 0 started ====== [2026-04-06T23:02:59.252Z] GC before operation: completed in 27.515 ms, heap usage 7.553 MB -> 4.397 MB. [2026-04-06T23:03:03.849Z] Camera thread performed 122 scans. [2026-04-06T23:03:03.849Z] ====== philosophers (scala) [default], iteration 0 completed (4091.025 ms) ====== [2026-04-06T23:03:03.849Z] ====== philosophers (scala) [default], iteration 1 started ====== [2026-04-06T23:03:03.849Z] GC before operation: completed in 100.581 ms, heap usage 11.221 MB -> 4.816 MB. [2026-04-06T23:03:04.899Z] Camera thread performed 122 scans. [2026-04-06T23:03:04.899Z] ====== philosophers (scala) [default], iteration 1 completed (1580.147 ms) ====== [2026-04-06T23:03:04.899Z] ====== philosophers (scala) [default], iteration 2 started ====== [2026-04-06T23:03:05.747Z] GC before operation: completed in 268.712 ms, heap usage 23.400 MB -> 4.816 MB. [2026-04-06T23:03:06.523Z] Camera thread performed 122 scans. [2026-04-06T23:03:06.523Z] ====== philosophers (scala) [default], iteration 2 completed (1278.117 ms) ====== [2026-04-06T23:03:06.523Z] ====== philosophers (scala) [default], iteration 3 started ====== [2026-04-06T23:03:06.523Z] GC before operation: completed in 123.985 ms, heap usage 12.845 MB -> 4.816 MB. [2026-04-06T23:03:08.108Z] Camera thread performed 122 scans. [2026-04-06T23:03:08.108Z] ====== philosophers (scala) [default], iteration 3 completed (1235.904 ms) ====== [2026-04-06T23:03:08.108Z] ====== philosophers (scala) [default], iteration 4 started ====== [2026-04-06T23:03:08.108Z] GC before operation: completed in 151.733 ms, heap usage 10.215 MB -> 4.820 MB. [2026-04-06T23:03:08.825Z] Camera thread performed 122 scans. [2026-04-06T23:03:08.825Z] ====== philosophers (scala) [default], iteration 4 completed (1033.177 ms) ====== [2026-04-06T23:03:08.825Z] ====== philosophers (scala) [default], iteration 5 started ====== [2026-04-06T23:03:08.825Z] GC before operation: completed in 80.836 ms, heap usage 23.634 MB -> 4.820 MB. [2026-04-06T23:03:10.397Z] Camera thread performed 122 scans. [2026-04-06T23:03:10.397Z] ====== philosophers (scala) [default], iteration 5 completed (1064.559 ms) ====== [2026-04-06T23:03:10.397Z] ====== philosophers (scala) [default], iteration 6 started ====== [2026-04-06T23:03:10.397Z] GC before operation: completed in 78.293 ms, heap usage 66.074 MB -> 4.820 MB. [2026-04-06T23:03:11.097Z] Camera thread performed 122 scans. [2026-04-06T23:03:11.097Z] ====== philosophers (scala) [default], iteration 6 completed (761.800 ms) ====== [2026-04-06T23:03:11.097Z] ====== philosophers (scala) [default], iteration 7 started ====== [2026-04-06T23:03:11.097Z] GC before operation: completed in 48.363 ms, heap usage 46.997 MB -> 4.820 MB. [2026-04-06T23:03:11.818Z] Camera thread performed 122 scans. [2026-04-06T23:03:11.819Z] ====== philosophers (scala) [default], iteration 7 completed (868.004 ms) ====== [2026-04-06T23:03:11.819Z] ====== philosophers (scala) [default], iteration 8 started ====== [2026-04-06T23:03:11.819Z] GC before operation: completed in 74.677 ms, heap usage 49.975 MB -> 4.821 MB. [2026-04-06T23:03:12.543Z] Camera thread performed 122 scans. [2026-04-06T23:03:12.543Z] ====== philosophers (scala) [default], iteration 8 completed (921.621 ms) ====== [2026-04-06T23:03:12.543Z] ====== philosophers (scala) [default], iteration 9 started ====== [2026-04-06T23:03:13.409Z] GC before operation: completed in 77.091 ms, heap usage 12.955 MB -> 4.822 MB. [2026-04-06T23:03:14.160Z] Camera thread performed 122 scans. [2026-04-06T23:03:14.160Z] ====== philosophers (scala) [default], iteration 9 completed (964.860 ms) ====== [2026-04-06T23:03:14.160Z] ====== philosophers (scala) [default], iteration 10 started ====== [2026-04-06T23:03:14.160Z] GC before operation: completed in 79.747 ms, heap usage 6.930 MB -> 4.822 MB. [2026-04-06T23:03:17.212Z] Camera thread performed 122 scans. [2026-04-06T23:03:17.212Z] ====== philosophers (scala) [default], iteration 10 completed (865.687 ms) ====== [2026-04-06T23:03:17.212Z] ====== philosophers (scala) [default], iteration 11 started ====== [2026-04-06T23:03:17.212Z] GC before operation: completed in 45.904 ms, heap usage 32.947 MB -> 4.822 MB. [2026-04-06T23:03:17.212Z] Camera thread performed 122 scans. [2026-04-06T23:03:17.212Z] ====== philosophers (scala) [default], iteration 11 completed (916.165 ms) ====== [2026-04-06T23:03:17.212Z] ====== philosophers (scala) [default], iteration 12 started ====== [2026-04-06T23:03:17.212Z] GC before operation: completed in 58.032 ms, heap usage 22.918 MB -> 4.823 MB. [2026-04-06T23:03:17.212Z] Camera thread performed 122 scans. [2026-04-06T23:03:17.212Z] ====== philosophers (scala) [default], iteration 12 completed (1015.209 ms) ====== [2026-04-06T23:03:17.212Z] ====== philosophers (scala) [default], iteration 13 started ====== [2026-04-06T23:03:17.212Z] GC before operation: completed in 109.845 ms, heap usage 51.940 MB -> 4.845 MB. [2026-04-06T23:03:17.977Z] Camera thread performed 122 scans. [2026-04-06T23:03:17.977Z] ====== philosophers (scala) [default], iteration 13 completed (867.315 ms) ====== [2026-04-06T23:03:17.977Z] ====== philosophers (scala) [default], iteration 14 started ====== [2026-04-06T23:03:17.977Z] GC before operation: completed in 87.524 ms, heap usage 39.945 MB -> 4.825 MB. [2026-04-06T23:03:18.843Z] Camera thread performed 122 scans. [2026-04-06T23:03:18.843Z] ====== philosophers (scala) [default], iteration 14 completed (858.998 ms) ====== [2026-04-06T23:03:18.843Z] ====== philosophers (scala) [default], iteration 15 started ====== [2026-04-06T23:03:18.843Z] GC before operation: completed in 81.426 ms, heap usage 30.921 MB -> 4.825 MB. [2026-04-06T23:03:19.625Z] Camera thread performed 122 scans. [2026-04-06T23:03:19.625Z] ====== philosophers (scala) [default], iteration 15 completed (846.831 ms) ====== [2026-04-06T23:03:19.625Z] ====== philosophers (scala) [default], iteration 16 started ====== [2026-04-06T23:03:19.625Z] GC before operation: completed in 82.233 ms, heap usage 9.925 MB -> 4.826 MB. [2026-04-06T23:03:20.326Z] Camera thread performed 122 scans. [2026-04-06T23:03:20.326Z] ====== philosophers (scala) [default], iteration 16 completed (619.205 ms) ====== [2026-04-06T23:03:20.326Z] ====== philosophers (scala) [default], iteration 17 started ====== [2026-04-06T23:03:20.326Z] GC before operation: completed in 49.573 ms, heap usage 48.915 MB -> 4.827 MB. [2026-04-06T23:03:21.012Z] Camera thread performed 122 scans. [2026-04-06T23:03:21.012Z] ====== philosophers (scala) [default], iteration 17 completed (747.966 ms) ====== [2026-04-06T23:03:21.012Z] ====== philosophers (scala) [default], iteration 18 started ====== [2026-04-06T23:03:21.012Z] GC before operation: completed in 66.760 ms, heap usage 35.440 MB -> 4.847 MB. [2026-04-06T23:03:22.495Z] Camera thread performed 122 scans. [2026-04-06T23:03:22.495Z] ====== philosophers (scala) [default], iteration 18 completed (935.424 ms) ====== [2026-04-06T23:03:22.495Z] ====== philosophers (scala) [default], iteration 19 started ====== [2026-04-06T23:03:22.495Z] GC before operation: completed in 103.072 ms, heap usage 5.949 MB -> 4.826 MB. [2026-04-06T23:03:23.183Z] Camera thread performed 122 scans. [2026-04-06T23:03:23.183Z] ====== philosophers (scala) [default], iteration 19 completed (868.007 ms) ====== [2026-04-06T23:03:23.183Z] ====== philosophers (scala) [default], iteration 20 started ====== [2026-04-06T23:03:23.183Z] GC before operation: completed in 48.336 ms, heap usage 24.923 MB -> 4.826 MB. [2026-04-06T23:03:24.677Z] Camera thread performed 122 scans. [2026-04-06T23:03:24.677Z] ====== philosophers (scala) [default], iteration 20 completed (1103.490 ms) ====== [2026-04-06T23:03:24.677Z] ====== philosophers (scala) [default], iteration 21 started ====== [2026-04-06T23:03:24.677Z] GC before operation: completed in 90.635 ms, heap usage 59.963 MB -> 4.827 MB. [2026-04-06T23:03:25.411Z] Camera thread performed 122 scans. [2026-04-06T23:03:25.411Z] ====== philosophers (scala) [default], iteration 21 completed (794.569 ms) ====== [2026-04-06T23:03:25.411Z] ====== philosophers (scala) [default], iteration 22 started ====== [2026-04-06T23:03:25.411Z] GC before operation: completed in 50.875 ms, heap usage 22.456 MB -> 4.826 MB. [2026-04-06T23:03:26.157Z] Camera thread performed 122 scans. [2026-04-06T23:03:26.157Z] ====== philosophers (scala) [default], iteration 22 completed (790.588 ms) ====== [2026-04-06T23:03:26.157Z] ====== philosophers (scala) [default], iteration 23 started ====== [2026-04-06T23:03:26.157Z] GC before operation: completed in 85.556 ms, heap usage 16.454 MB -> 4.827 MB. [2026-04-06T23:03:26.906Z] Camera thread performed 122 scans. [2026-04-06T23:03:26.906Z] ====== philosophers (scala) [default], iteration 23 completed (943.031 ms) ====== [2026-04-06T23:03:26.906Z] ====== philosophers (scala) [default], iteration 24 started ====== [2026-04-06T23:03:26.906Z] GC before operation: completed in 100.067 ms, heap usage 24.899 MB -> 4.827 MB. [2026-04-06T23:03:28.446Z] Camera thread performed 122 scans. [2026-04-06T23:03:28.446Z] ====== philosophers (scala) [default], iteration 24 completed (905.672 ms) ====== [2026-04-06T23:03:28.446Z] ====== philosophers (scala) [default], iteration 25 started ====== [2026-04-06T23:03:28.446Z] GC before operation: completed in 68.570 ms, heap usage 50.968 MB -> 4.828 MB. [2026-04-06T23:03:29.187Z] Camera thread performed 122 scans. [2026-04-06T23:03:29.187Z] ====== philosophers (scala) [default], iteration 25 completed (933.089 ms) ====== [2026-04-06T23:03:29.187Z] ====== philosophers (scala) [default], iteration 26 started ====== [2026-04-06T23:03:29.187Z] GC before operation: completed in 36.100 ms, heap usage 35.865 MB -> 4.828 MB. [2026-04-06T23:03:30.870Z] Camera thread performed 122 scans. [2026-04-06T23:03:30.870Z] ====== philosophers (scala) [default], iteration 26 completed (942.993 ms) ====== [2026-04-06T23:03:30.870Z] ====== philosophers (scala) [default], iteration 27 started ====== [2026-04-06T23:03:30.870Z] GC before operation: completed in 105.235 ms, heap usage 47.458 MB -> 4.828 MB. [2026-04-06T23:03:31.666Z] Camera thread performed 122 scans. [2026-04-06T23:03:31.666Z] ====== philosophers (scala) [default], iteration 27 completed (1148.351 ms) ====== [2026-04-06T23:03:31.666Z] ====== philosophers (scala) [default], iteration 28 started ====== [2026-04-06T23:03:31.666Z] GC before operation: completed in 89.423 ms, heap usage 55.347 MB -> 4.828 MB. [2026-04-06T23:03:32.389Z] Camera thread performed 122 scans. [2026-04-06T23:03:32.389Z] ====== philosophers (scala) [default], iteration 28 completed (1121.913 ms) ====== [2026-04-06T23:03:32.389Z] ====== philosophers (scala) [default], iteration 29 started ====== [2026-04-06T23:03:32.389Z] GC before operation: completed in 50.975 ms, heap usage 27.921 MB -> 4.828 MB. [2026-04-06T23:03:33.127Z] Camera thread performed 122 scans. [2026-04-06T23:03:33.127Z] ====== philosophers (scala) [default], iteration 29 completed (684.723 ms) ====== [2026-04-06T23:03:33.810Z] ----------------------------------- [2026-04-06T23:03:33.810Z] renaissance-philosophers_0_PASSED [2026-04-06T23:03:33.810Z] ----------------------------------- [2026-04-06T23:03:33.810Z] [2026-04-06T23:03:33.810Z] TEST TEARDOWN: [2026-04-06T23:03:33.810Z] Nothing to be done for teardown. [2026-04-06T23:03:33.810Z] renaissance-philosophers_0 Finish Time: Mon Apr 6 23:03:33 2026 Epoch Time (ms): 1775516613214