[2025-05-08T03:48:36.851Z] Running test renaissance-philosophers_0 ... [2025-05-08T03:48:36.851Z] =============================================== [2025-05-08T03:48:36.851Z] renaissance-philosophers_0 Start Time: Thu May 8 03:48:36 2025 Epoch Time (ms): 1746676116235 [2025-05-08T03:48:36.851Z] variation: NoOptions [2025-05-08T03:48:36.851Z] JVM_OPTIONS: [2025-05-08T03:48:36.851Z] { \ [2025-05-08T03:48:36.851Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T03:48:36.851Z] echo "Nothing to be done for setup."; \ [2025-05-08T03:48:36.851Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466756938592/renaissance-philosophers_0"; \ [2025-05-08T03:48:36.851Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466756938592/renaissance-philosophers_0"; \ [2025-05-08T03:48:36.851Z] echo ""; echo "TESTING:"; \ [2025-05-08T03:48:36.851Z] "/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_17466756938592/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-08T03:48:36.851Z] 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_17466756938592/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T03:48:36.851Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T03:48:36.851Z] echo "Nothing to be done for teardown."; \ [2025-05-08T03:48:36.851Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466756938592/TestTargetResult"; [2025-05-08T03:48:36.851Z] [2025-05-08T03:48:36.851Z] TEST SETUP: [2025-05-08T03:48:36.851Z] Nothing to be done for setup. [2025-05-08T03:48:36.851Z] [2025-05-08T03:48:36.851Z] TESTING: [2025-05-08T03:48:37.543Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-08T03:48:37.543Z] GC before operation: completed in 50.678 ms, heap usage 18.444 MB -> 4.863 MB. [2025-05-08T03:48:40.571Z] Camera thread performed 122 scans. [2025-05-08T03:48:40.571Z] ====== philosophers (scala) [default], iteration 0 completed (2746.742 ms) ====== [2025-05-08T03:48:40.571Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-08T03:48:40.571Z] GC before operation: completed in 18.654 ms, heap usage 7.255 MB -> 5.227 MB. [2025-05-08T03:48:41.980Z] Camera thread performed 122 scans. [2025-05-08T03:48:41.980Z] ====== philosophers (scala) [default], iteration 1 completed (1108.618 ms) ====== [2025-05-08T03:48:41.980Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-08T03:48:41.980Z] GC before operation: completed in 60.154 ms, heap usage 23.830 MB -> 5.227 MB. [2025-05-08T03:48:42.664Z] Camera thread performed 122 scans. [2025-05-08T03:48:42.664Z] ====== philosophers (scala) [default], iteration 2 completed (981.636 ms) ====== [2025-05-08T03:48:42.664Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-08T03:48:42.664Z] GC before operation: completed in 24.356 ms, heap usage 35.334 MB -> 5.228 MB. [2025-05-08T03:48:43.301Z] Camera thread performed 122 scans. [2025-05-08T03:48:43.301Z] ====== philosophers (scala) [default], iteration 3 completed (718.815 ms) ====== [2025-05-08T03:48:43.301Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-08T03:48:43.301Z] GC before operation: completed in 14.684 ms, heap usage 30.022 MB -> 5.248 MB. [2025-05-08T03:48:44.717Z] Camera thread performed 122 scans. [2025-05-08T03:48:44.717Z] ====== philosophers (scala) [default], iteration 4 completed (827.121 ms) ====== [2025-05-08T03:48:44.717Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-08T03:48:44.717Z] GC before operation: completed in 25.544 ms, heap usage 13.861 MB -> 5.229 MB. [2025-05-08T03:48:45.367Z] Camera thread performed 122 scans. [2025-05-08T03:48:45.367Z] ====== philosophers (scala) [default], iteration 5 completed (858.853 ms) ====== [2025-05-08T03:48:45.367Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-08T03:48:45.367Z] GC before operation: completed in 24.760 ms, heap usage 45.334 MB -> 5.229 MB. [2025-05-08T03:48:46.000Z] Camera thread performed 122 scans. [2025-05-08T03:48:46.000Z] ====== philosophers (scala) [default], iteration 6 completed (837.353 ms) ====== [2025-05-08T03:48:46.000Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-08T03:48:46.000Z] GC before operation: completed in 25.702 ms, heap usage 15.267 MB -> 5.229 MB. [2025-05-08T03:48:46.647Z] Camera thread performed 122 scans. [2025-05-08T03:48:46.647Z] ====== philosophers (scala) [default], iteration 7 completed (789.467 ms) ====== [2025-05-08T03:48:46.647Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-08T03:48:46.647Z] GC before operation: completed in 20.038 ms, heap usage 42.356 MB -> 5.229 MB. [2025-05-08T03:48:48.016Z] Camera thread performed 122 scans. [2025-05-08T03:48:48.016Z] ====== philosophers (scala) [default], iteration 8 completed (955.474 ms) ====== [2025-05-08T03:48:48.016Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-08T03:48:48.016Z] GC before operation: completed in 30.651 ms, heap usage 41.833 MB -> 5.229 MB. [2025-05-08T03:48:48.672Z] Camera thread performed 122 scans. [2025-05-08T03:48:48.672Z] ====== philosophers (scala) [default], iteration 9 completed (944.935 ms) ====== [2025-05-08T03:48:48.672Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-08T03:48:48.672Z] GC before operation: completed in 26.418 ms, heap usage 33.095 MB -> 5.229 MB. [2025-05-08T03:48:50.095Z] Camera thread performed 122 scans. [2025-05-08T03:48:50.095Z] ====== philosophers (scala) [default], iteration 10 completed (938.003 ms) ====== [2025-05-08T03:48:50.095Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-08T03:48:50.095Z] GC before operation: completed in 24.064 ms, heap usage 19.318 MB -> 5.230 MB. [2025-05-08T03:48:50.751Z] Camera thread performed 122 scans. [2025-05-08T03:48:50.751Z] ====== philosophers (scala) [default], iteration 11 completed (862.074 ms) ====== [2025-05-08T03:48:50.751Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-08T03:48:50.751Z] GC before operation: completed in 19.878 ms, heap usage 19.320 MB -> 5.250 MB. [2025-05-08T03:48:51.424Z] Camera thread performed 122 scans. [2025-05-08T03:48:51.424Z] ====== philosophers (scala) [default], iteration 12 completed (794.461 ms) ====== [2025-05-08T03:48:51.424Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-08T03:48:51.424Z] GC before operation: completed in 24.841 ms, heap usage 37.785 MB -> 5.230 MB. [2025-05-08T03:48:52.801Z] Camera thread performed 122 scans. [2025-05-08T03:48:52.801Z] ====== philosophers (scala) [default], iteration 13 completed (911.919 ms) ====== [2025-05-08T03:48:52.801Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-08T03:48:52.801Z] GC before operation: completed in 21.189 ms, heap usage 31.285 MB -> 5.230 MB. [2025-05-08T03:48:53.542Z] Camera thread performed 122 scans. [2025-05-08T03:48:53.543Z] ====== philosophers (scala) [default], iteration 14 completed (1041.096 ms) ====== [2025-05-08T03:48:53.543Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-08T03:48:53.543Z] GC before operation: completed in 23.880 ms, heap usage 15.621 MB -> 5.230 MB. [2025-05-08T03:48:54.735Z] Camera thread performed 122 scans. [2025-05-08T03:48:54.735Z] ====== philosophers (scala) [default], iteration 15 completed (972.870 ms) ====== [2025-05-08T03:48:54.736Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-08T03:48:54.736Z] GC before operation: completed in 61.152 ms, heap usage 20.844 MB -> 5.230 MB. [2025-05-08T03:48:55.418Z] Camera thread performed 122 scans. [2025-05-08T03:48:55.418Z] ====== philosophers (scala) [default], iteration 16 completed (933.157 ms) ====== [2025-05-08T03:48:55.418Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-08T03:48:55.419Z] GC before operation: completed in 29.458 ms, heap usage 54.173 MB -> 5.230 MB. [2025-05-08T03:48:56.814Z] Camera thread performed 122 scans. [2025-05-08T03:48:56.814Z] ====== philosophers (scala) [default], iteration 17 completed (918.379 ms) ====== [2025-05-08T03:48:56.814Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-08T03:48:56.814Z] GC before operation: completed in 21.078 ms, heap usage 6.227 MB -> 5.231 MB. [2025-05-08T03:48:57.519Z] Camera thread performed 122 scans. [2025-05-08T03:48:57.519Z] ====== philosophers (scala) [default], iteration 18 completed (789.384 ms) ====== [2025-05-08T03:48:57.519Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-08T03:48:57.519Z] GC before operation: completed in 27.237 ms, heap usage 10.830 MB -> 5.231 MB. [2025-05-08T03:48:58.182Z] Camera thread performed 122 scans. [2025-05-08T03:48:58.182Z] ====== philosophers (scala) [default], iteration 19 completed (872.199 ms) ====== [2025-05-08T03:48:58.182Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-08T03:48:58.182Z] GC before operation: completed in 24.368 ms, heap usage 22.791 MB -> 5.231 MB. [2025-05-08T03:48:58.912Z] Camera thread performed 122 scans. [2025-05-08T03:48:58.912Z] ====== philosophers (scala) [default], iteration 20 completed (761.018 ms) ====== [2025-05-08T03:48:58.913Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-08T03:48:58.913Z] GC before operation: completed in 28.435 ms, heap usage 23.827 MB -> 5.231 MB. [2025-05-08T03:48:59.600Z] Camera thread performed 122 scans. [2025-05-08T03:48:59.600Z] ====== philosophers (scala) [default], iteration 21 completed (776.866 ms) ====== [2025-05-08T03:48:59.600Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-08T03:48:59.600Z] GC before operation: completed in 14.147 ms, heap usage 24.352 MB -> 5.231 MB. [2025-05-08T03:49:00.987Z] Camera thread performed 122 scans. [2025-05-08T03:49:00.987Z] ====== philosophers (scala) [default], iteration 22 completed (871.226 ms) ====== [2025-05-08T03:49:00.987Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-08T03:49:00.987Z] GC before operation: completed in 17.078 ms, heap usage 29.355 MB -> 5.231 MB. [2025-05-08T03:49:01.652Z] Camera thread performed 122 scans. [2025-05-08T03:49:01.652Z] ====== philosophers (scala) [default], iteration 23 completed (920.045 ms) ====== [2025-05-08T03:49:01.652Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-08T03:49:01.652Z] GC before operation: completed in 25.283 ms, heap usage 16.323 MB -> 5.231 MB. [2025-05-08T03:49:02.366Z] Camera thread performed 122 scans. [2025-05-08T03:49:02.366Z] ====== philosophers (scala) [default], iteration 24 completed (757.067 ms) ====== [2025-05-08T03:49:02.366Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-08T03:49:02.366Z] GC before operation: completed in 24.316 ms, heap usage 45.348 MB -> 5.233 MB. [2025-05-08T03:49:03.055Z] Camera thread performed 122 scans. [2025-05-08T03:49:03.055Z] ====== philosophers (scala) [default], iteration 25 completed (820.318 ms) ====== [2025-05-08T03:49:03.055Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-08T03:49:03.055Z] GC before operation: completed in 26.070 ms, heap usage 20.132 MB -> 5.233 MB. [2025-05-08T03:49:04.485Z] Camera thread performed 122 scans. [2025-05-08T03:49:04.485Z] ====== philosophers (scala) [default], iteration 26 completed (846.988 ms) ====== [2025-05-08T03:49:04.485Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-08T03:49:04.485Z] GC before operation: completed in 23.489 ms, heap usage 47.318 MB -> 5.233 MB. [2025-05-08T03:49:05.143Z] Camera thread performed 122 scans. [2025-05-08T03:49:05.143Z] ====== philosophers (scala) [default], iteration 27 completed (790.850 ms) ====== [2025-05-08T03:49:05.143Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-08T03:49:05.143Z] GC before operation: completed in 49.766 ms, heap usage 56.274 MB -> 5.233 MB. [2025-05-08T03:49:05.842Z] Camera thread performed 122 scans. [2025-05-08T03:49:05.842Z] ====== philosophers (scala) [default], iteration 28 completed (892.895 ms) ====== [2025-05-08T03:49:05.842Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-08T03:49:05.842Z] GC before operation: completed in 13.845 ms, heap usage 56.670 MB -> 5.233 MB. [2025-05-08T03:49:07.278Z] Camera thread performed 122 scans. [2025-05-08T03:49:07.278Z] ====== philosophers (scala) [default], iteration 29 completed (1438.779 ms) ====== [2025-05-08T03:49:07.920Z] ----------------------------------- [2025-05-08T03:49:07.920Z] renaissance-philosophers_0_PASSED [2025-05-08T03:49:07.920Z] ----------------------------------- [2025-05-08T03:49:07.920Z] [2025-05-08T03:49:07.920Z] TEST TEARDOWN: [2025-05-08T03:49:07.920Z] Nothing to be done for teardown. [2025-05-08T03:49:07.920Z] renaissance-philosophers_0 Finish Time: Thu May 8 03:49:07 2025 Epoch Time (ms): 1746676147299