[2025-06-18T22:26:40.062Z] Running test renaissance-philosophers_0 ... [2025-06-18T22:26:40.062Z] =============================================== [2025-06-18T22:26:40.062Z] renaissance-philosophers_0 Start Time: Wed Jun 18 22:26:39 2025 Epoch Time (ms): 1750285599672 [2025-06-18T22:26:40.062Z] variation: NoOptions [2025-06-18T22:26:40.062Z] JVM_OPTIONS: [2025-06-18T22:26:40.062Z] { \ [2025-06-18T22:26:40.062Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T22:26:40.062Z] echo "Nothing to be done for setup."; \ [2025-06-18T22:26:40.062Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17502812906484/renaissance-philosophers_0"; \ [2025-06-18T22:26:40.062Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17502812906484/renaissance-philosophers_0"; \ [2025-06-18T22:26:40.062Z] echo ""; echo "TESTING:"; \ [2025-06-18T22:26:40.062Z] "/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_17502812906484/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-18T22:26:40.062Z] 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_17502812906484/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T22:26:40.062Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T22:26:40.062Z] echo "Nothing to be done for teardown."; \ [2025-06-18T22:26:40.062Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17502812906484/TestTargetResult"; [2025-06-18T22:26:40.062Z] [2025-06-18T22:26:40.062Z] TEST SETUP: [2025-06-18T22:26:40.062Z] Nothing to be done for setup. [2025-06-18T22:26:40.062Z] [2025-06-18T22:26:40.062Z] TESTING: [2025-06-18T22:26:43.154Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-18T22:26:43.154Z] GC before operation: completed in 45.535 ms, heap usage 18.345 MB -> 4.838 MB. [2025-06-18T22:26:47.318Z] Camera thread performed 122 scans. [2025-06-18T22:26:47.318Z] ====== philosophers (scala) [default], iteration 0 completed (4329.074 ms) ====== [2025-06-18T22:26:47.318Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-18T22:26:47.318Z] GC before operation: completed in 51.748 ms, heap usage 24.079 MB -> 5.204 MB. [2025-06-18T22:26:49.728Z] Camera thread performed 122 scans. [2025-06-18T22:26:49.728Z] ====== philosophers (scala) [default], iteration 1 completed (2256.174 ms) ====== [2025-06-18T22:26:49.728Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-18T22:26:49.728Z] GC before operation: completed in 28.416 ms, heap usage 7.614 MB -> 5.203 MB. [2025-06-18T22:26:51.113Z] Camera thread performed 122 scans. [2025-06-18T22:26:51.113Z] ====== philosophers (scala) [default], iteration 2 completed (1523.305 ms) ====== [2025-06-18T22:26:51.113Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-18T22:26:51.113Z] GC before operation: completed in 25.022 ms, heap usage 9.245 MB -> 5.204 MB. [2025-06-18T22:26:52.583Z] Camera thread performed 122 scans. [2025-06-18T22:26:52.583Z] ====== philosophers (scala) [default], iteration 3 completed (1442.724 ms) ====== [2025-06-18T22:26:52.583Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-18T22:26:52.583Z] GC before operation: completed in 36.437 ms, heap usage 61.984 MB -> 5.204 MB. [2025-06-18T22:26:54.079Z] Camera thread performed 122 scans. [2025-06-18T22:26:54.079Z] ====== philosophers (scala) [default], iteration 4 completed (1088.776 ms) ====== [2025-06-18T22:26:54.079Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-18T22:26:54.079Z] GC before operation: completed in 30.625 ms, heap usage 25.374 MB -> 5.204 MB. [2025-06-18T22:26:54.739Z] Camera thread performed 122 scans. [2025-06-18T22:26:54.739Z] ====== philosophers (scala) [default], iteration 5 completed (1072.975 ms) ====== [2025-06-18T22:26:54.739Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-18T22:26:54.739Z] GC before operation: completed in 27.478 ms, heap usage 19.683 MB -> 5.205 MB. [2025-06-18T22:26:55.439Z] Camera thread performed 122 scans. [2025-06-18T22:26:55.439Z] ====== philosophers (scala) [default], iteration 6 completed (926.941 ms) ====== [2025-06-18T22:26:55.439Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-18T22:26:56.124Z] GC before operation: completed in 40.206 ms, heap usage 6.284 MB -> 5.205 MB. [2025-06-18T22:26:56.816Z] Camera thread performed 122 scans. [2025-06-18T22:26:56.816Z] ====== philosophers (scala) [default], iteration 7 completed (1137.782 ms) ====== [2025-06-18T22:26:56.816Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-18T22:26:56.816Z] GC before operation: completed in 56.433 ms, heap usage 33.721 MB -> 5.205 MB. [2025-06-18T22:26:58.273Z] Camera thread performed 122 scans. [2025-06-18T22:26:58.273Z] ====== philosophers (scala) [default], iteration 8 completed (1071.760 ms) ====== [2025-06-18T22:26:58.273Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-18T22:26:58.273Z] GC before operation: completed in 33.773 ms, heap usage 45.767 MB -> 5.205 MB. [2025-06-18T22:26:58.972Z] Camera thread performed 122 scans. [2025-06-18T22:26:58.972Z] ====== philosophers (scala) [default], iteration 9 completed (964.283 ms) ====== [2025-06-18T22:26:58.972Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-18T22:26:58.972Z] GC before operation: completed in 28.002 ms, heap usage 37.267 MB -> 5.205 MB. [2025-06-18T22:26:59.724Z] Camera thread performed 122 scans. [2025-06-18T22:26:59.724Z] ====== philosophers (scala) [default], iteration 10 completed (969.204 ms) ====== [2025-06-18T22:26:59.724Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-18T22:27:00.380Z] GC before operation: completed in 20.758 ms, heap usage 9.714 MB -> 5.205 MB. [2025-06-18T22:27:02.379Z] Camera thread performed 122 scans. [2025-06-18T22:27:02.379Z] ====== philosophers (scala) [default], iteration 11 completed (1442.550 ms) ====== [2025-06-18T22:27:02.379Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-18T22:27:02.379Z] GC before operation: completed in 40.966 ms, heap usage 15.779 MB -> 5.205 MB. [2025-06-18T22:27:03.034Z] Camera thread performed 122 scans. [2025-06-18T22:27:03.034Z] ====== philosophers (scala) [default], iteration 12 completed (868.565 ms) ====== [2025-06-18T22:27:03.034Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-18T22:27:03.034Z] GC before operation: completed in 21.099 ms, heap usage 18.290 MB -> 5.206 MB. [2025-06-18T22:27:03.034Z] Camera thread performed 122 scans. [2025-06-18T22:27:03.034Z] ====== philosophers (scala) [default], iteration 13 completed (788.315 ms) ====== [2025-06-18T22:27:03.034Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-18T22:27:03.034Z] GC before operation: completed in 22.968 ms, heap usage 19.804 MB -> 5.207 MB. [2025-06-18T22:27:04.427Z] Camera thread performed 122 scans. [2025-06-18T22:27:04.427Z] ====== philosophers (scala) [default], iteration 14 completed (819.279 ms) ====== [2025-06-18T22:27:04.427Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-18T22:27:04.427Z] GC before operation: completed in 15.717 ms, heap usage 22.661 MB -> 5.208 MB. [2025-06-18T22:27:05.172Z] Camera thread performed 122 scans. [2025-06-18T22:27:05.172Z] ====== philosophers (scala) [default], iteration 15 completed (1066.104 ms) ====== [2025-06-18T22:27:05.172Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-18T22:27:05.172Z] GC before operation: completed in 63.982 ms, heap usage 16.295 MB -> 5.207 MB. [2025-06-18T22:27:06.767Z] Camera thread performed 122 scans. [2025-06-18T22:27:06.767Z] ====== philosophers (scala) [default], iteration 16 completed (1133.537 ms) ====== [2025-06-18T22:27:06.767Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-18T22:27:06.767Z] GC before operation: completed in 20.153 ms, heap usage 21.037 MB -> 5.208 MB. [2025-06-18T22:27:07.493Z] Camera thread performed 122 scans. [2025-06-18T22:27:07.493Z] ====== philosophers (scala) [default], iteration 17 completed (862.804 ms) ====== [2025-06-18T22:27:07.493Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-18T22:27:07.493Z] GC before operation: completed in 41.060 ms, heap usage 19.301 MB -> 5.208 MB. [2025-06-18T22:27:09.208Z] Camera thread performed 122 scans. [2025-06-18T22:27:09.208Z] ====== philosophers (scala) [default], iteration 18 completed (1560.778 ms) ====== [2025-06-18T22:27:09.208Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-18T22:27:09.208Z] GC before operation: completed in 78.734 ms, heap usage 36.824 MB -> 5.208 MB. [2025-06-18T22:27:09.905Z] Camera thread performed 122 scans. [2025-06-18T22:27:09.905Z] ====== philosophers (scala) [default], iteration 19 completed (860.087 ms) ====== [2025-06-18T22:27:09.905Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-18T22:27:09.905Z] GC before operation: completed in 25.929 ms, heap usage 59.055 MB -> 5.208 MB. [2025-06-18T22:27:10.583Z] Camera thread performed 122 scans. [2025-06-18T22:27:10.583Z] ====== philosophers (scala) [default], iteration 20 completed (879.122 ms) ====== [2025-06-18T22:27:10.583Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-18T22:27:10.583Z] GC before operation: completed in 24.413 ms, heap usage 12.298 MB -> 5.208 MB. [2025-06-18T22:27:11.228Z] Camera thread performed 122 scans. [2025-06-18T22:27:11.228Z] ====== philosophers (scala) [default], iteration 21 completed (861.640 ms) ====== [2025-06-18T22:27:11.228Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-18T22:27:11.865Z] GC before operation: completed in 93.352 ms, heap usage 32.794 MB -> 5.208 MB. [2025-06-18T22:27:12.575Z] Camera thread performed 122 scans. [2025-06-18T22:27:12.575Z] ====== philosophers (scala) [default], iteration 22 completed (764.555 ms) ====== [2025-06-18T22:27:12.575Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-18T22:27:12.575Z] GC before operation: completed in 36.671 ms, heap usage 72.352 MB -> 5.209 MB. [2025-06-18T22:27:13.235Z] Camera thread performed 122 scans. [2025-06-18T22:27:13.235Z] ====== philosophers (scala) [default], iteration 23 completed (824.221 ms) ====== [2025-06-18T22:27:13.235Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-18T22:27:13.235Z] GC before operation: completed in 19.399 ms, heap usage 16.028 MB -> 5.209 MB. [2025-06-18T22:27:13.974Z] Camera thread performed 122 scans. [2025-06-18T22:27:13.974Z] ====== philosophers (scala) [default], iteration 24 completed (771.051 ms) ====== [2025-06-18T22:27:13.974Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-18T22:27:13.974Z] GC before operation: completed in 20.717 ms, heap usage 12.203 MB -> 5.209 MB. [2025-06-18T22:27:14.697Z] Camera thread performed 122 scans. [2025-06-18T22:27:14.697Z] ====== philosophers (scala) [default], iteration 25 completed (812.834 ms) ====== [2025-06-18T22:27:14.697Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-18T22:27:14.697Z] GC before operation: completed in 36.055 ms, heap usage 22.342 MB -> 5.209 MB. [2025-06-18T22:27:16.174Z] Camera thread performed 122 scans. [2025-06-18T22:27:16.174Z] ====== philosophers (scala) [default], iteration 26 completed (1099.612 ms) ====== [2025-06-18T22:27:16.174Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-18T22:27:16.175Z] GC before operation: completed in 19.965 ms, heap usage 9.754 MB -> 5.209 MB. [2025-06-18T22:27:16.819Z] Camera thread performed 122 scans. [2025-06-18T22:27:16.819Z] ====== philosophers (scala) [default], iteration 27 completed (924.791 ms) ====== [2025-06-18T22:27:16.819Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-18T22:27:16.819Z] GC before operation: completed in 23.617 ms, heap usage 9.024 MB -> 5.209 MB. [2025-06-18T22:27:18.218Z] Camera thread performed 122 scans. [2025-06-18T22:27:18.218Z] ====== philosophers (scala) [default], iteration 28 completed (847.729 ms) ====== [2025-06-18T22:27:18.218Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-18T22:27:18.218Z] GC before operation: completed in 20.767 ms, heap usage 14.803 MB -> 5.209 MB. [2025-06-18T22:27:18.872Z] Camera thread performed 122 scans. [2025-06-18T22:27:18.872Z] ====== philosophers (scala) [default], iteration 29 completed (855.576 ms) ====== [2025-06-18T22:27:18.872Z] ----------------------------------- [2025-06-18T22:27:18.872Z] renaissance-philosophers_0_PASSED [2025-06-18T22:27:18.872Z] ----------------------------------- [2025-06-18T22:27:18.872Z] [2025-06-18T22:27:18.872Z] TEST TEARDOWN: [2025-06-18T22:27:18.872Z] Nothing to be done for teardown. [2025-06-18T22:27:18.872Z] renaissance-philosophers_0 Finish Time: Wed Jun 18 22:27:18 2025 Epoch Time (ms): 1750285638619