[2025-07-01T13:26:49.024Z] Running test renaissance-philosophers_0 ... [2025-07-01T13:26:49.024Z] =============================================== [2025-07-01T13:26:49.024Z] renaissance-philosophers_0 Start Time: Tue Jul 1 13:26:48 2025 Epoch Time (ms): 1751376408358 [2025-07-01T13:26:49.024Z] variation: NoOptions [2025-07-01T13:26:49.024Z] JVM_OPTIONS: [2025-07-01T13:26:49.024Z] { \ [2025-07-01T13:26:49.024Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T13:26:49.024Z] echo "Nothing to be done for setup."; \ [2025-07-01T13:26:49.024Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-philosophers_0"; \ [2025-07-01T13:26:49.024Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-philosophers_0"; \ [2025-07-01T13:26:49.024Z] echo ""; echo "TESTING:"; \ [2025-07-01T13:26:49.024Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-07-01T13:26:49.024Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T13:26:49.024Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T13:26:49.024Z] echo "Nothing to be done for teardown."; \ [2025-07-01T13:26:49.024Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/TestTargetResult"; [2025-07-01T13:26:49.024Z] [2025-07-01T13:26:49.024Z] TEST SETUP: [2025-07-01T13:26:49.024Z] Nothing to be done for setup. [2025-07-01T13:26:49.024Z] [2025-07-01T13:26:49.024Z] TESTING: [2025-07-01T13:26:50.664Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-07-01T13:26:50.664Z] GC before operation: completed in 49.270 ms, heap usage 14.614 MB -> 4.695 MB. [2025-07-01T13:26:56.523Z] Camera thread performed 122 scans. [2025-07-01T13:26:56.523Z] ====== philosophers (scala) [default], iteration 0 completed (5683.684 ms) ====== [2025-07-01T13:26:56.523Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-07-01T13:26:56.523Z] GC before operation: completed in 48.098 ms, heap usage 19.855 MB -> 5.140 MB. [2025-07-01T13:26:59.091Z] Camera thread performed 122 scans. [2025-07-01T13:26:59.091Z] ====== philosophers (scala) [default], iteration 1 completed (2429.808 ms) ====== [2025-07-01T13:26:59.091Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-07-01T13:26:59.091Z] GC before operation: completed in 38.619 ms, heap usage 31.951 MB -> 5.070 MB. [2025-07-01T13:27:01.654Z] Camera thread performed 122 scans. [2025-07-01T13:27:01.654Z] ====== philosophers (scala) [default], iteration 2 completed (2194.237 ms) ====== [2025-07-01T13:27:01.654Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-07-01T13:27:01.654Z] GC before operation: completed in 33.271 ms, heap usage 25.761 MB -> 5.069 MB. [2025-07-01T13:27:03.314Z] Camera thread performed 122 scans. [2025-07-01T13:27:03.314Z] ====== philosophers (scala) [default], iteration 3 completed (1939.208 ms) ====== [2025-07-01T13:27:03.314Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-07-01T13:27:03.314Z] GC before operation: completed in 34.772 ms, heap usage 57.680 MB -> 5.070 MB. [2025-07-01T13:27:05.872Z] Camera thread performed 122 scans. [2025-07-01T13:27:05.872Z] ====== philosophers (scala) [default], iteration 4 completed (2330.163 ms) ====== [2025-07-01T13:27:05.872Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-07-01T13:27:05.872Z] GC before operation: completed in 42.843 ms, heap usage 15.635 MB -> 5.071 MB. [2025-07-01T13:27:08.431Z] Camera thread performed 122 scans. [2025-07-01T13:27:08.431Z] ====== philosophers (scala) [default], iteration 5 completed (2088.475 ms) ====== [2025-07-01T13:27:08.431Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-07-01T13:27:08.431Z] GC before operation: completed in 43.396 ms, heap usage 47.015 MB -> 5.071 MB. [2025-07-01T13:27:10.092Z] Camera thread performed 122 scans. [2025-07-01T13:27:10.092Z] ====== philosophers (scala) [default], iteration 6 completed (1847.774 ms) ====== [2025-07-01T13:27:10.092Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-07-01T13:27:10.092Z] GC before operation: completed in 35.276 ms, heap usage 22.956 MB -> 5.072 MB. [2025-07-01T13:27:11.746Z] Camera thread performed 122 scans. [2025-07-01T13:27:11.746Z] ====== philosophers (scala) [default], iteration 7 completed (1988.388 ms) ====== [2025-07-01T13:27:11.746Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-07-01T13:27:11.746Z] GC before operation: completed in 27.058 ms, heap usage 34.273 MB -> 5.072 MB. [2025-07-01T13:27:14.353Z] Camera thread performed 122 scans. [2025-07-01T13:27:14.353Z] ====== philosophers (scala) [default], iteration 8 completed (2149.437 ms) ====== [2025-07-01T13:27:14.353Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-07-01T13:27:14.353Z] GC before operation: completed in 35.142 ms, heap usage 38.262 MB -> 5.072 MB. [2025-07-01T13:27:15.997Z] Camera thread performed 122 scans. [2025-07-01T13:27:15.997Z] ====== philosophers (scala) [default], iteration 9 completed (2108.146 ms) ====== [2025-07-01T13:27:15.997Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-07-01T13:27:15.997Z] GC before operation: completed in 43.198 ms, heap usage 24.275 MB -> 5.072 MB. [2025-07-01T13:27:17.641Z] Camera thread performed 122 scans. [2025-07-01T13:27:17.641Z] ====== philosophers (scala) [default], iteration 10 completed (1743.645 ms) ====== [2025-07-01T13:27:17.641Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-07-01T13:27:17.641Z] GC before operation: completed in 34.023 ms, heap usage 7.259 MB -> 5.073 MB. [2025-07-01T13:27:20.214Z] Camera thread performed 122 scans. [2025-07-01T13:27:20.214Z] ====== philosophers (scala) [default], iteration 11 completed (1998.495 ms) ====== [2025-07-01T13:27:20.214Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-07-01T13:27:20.214Z] GC before operation: completed in 38.012 ms, heap usage 14.598 MB -> 5.073 MB. [2025-07-01T13:27:21.856Z] Camera thread performed 122 scans. [2025-07-01T13:27:21.856Z] ====== philosophers (scala) [default], iteration 12 completed (1966.918 ms) ====== [2025-07-01T13:27:21.856Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-07-01T13:27:21.856Z] GC before operation: completed in 44.543 ms, heap usage 36.195 MB -> 5.108 MB. [2025-07-01T13:27:24.405Z] Camera thread performed 122 scans. [2025-07-01T13:27:24.405Z] ====== philosophers (scala) [default], iteration 13 completed (1874.646 ms) ====== [2025-07-01T13:27:24.405Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-07-01T13:27:24.405Z] GC before operation: completed in 31.686 ms, heap usage 19.240 MB -> 5.073 MB. [2025-07-01T13:27:26.047Z] Camera thread performed 122 scans. [2025-07-01T13:27:26.047Z] ====== philosophers (scala) [default], iteration 14 completed (1981.731 ms) ====== [2025-07-01T13:27:26.047Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-07-01T13:27:26.047Z] GC before operation: completed in 25.100 ms, heap usage 63.553 MB -> 5.074 MB. [2025-07-01T13:27:27.696Z] Camera thread performed 122 scans. [2025-07-01T13:27:27.696Z] ====== philosophers (scala) [default], iteration 15 completed (1921.086 ms) ====== [2025-07-01T13:27:27.696Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-07-01T13:27:27.696Z] GC before operation: completed in 29.355 ms, heap usage 31.118 MB -> 5.108 MB. [2025-07-01T13:27:29.328Z] Camera thread performed 122 scans. [2025-07-01T13:27:29.328Z] ====== philosophers (scala) [default], iteration 16 completed (1795.328 ms) ====== [2025-07-01T13:27:29.328Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-07-01T13:27:30.134Z] GC before operation: completed in 34.984 ms, heap usage 10.303 MB -> 5.074 MB. [2025-07-01T13:27:31.772Z] Camera thread performed 122 scans. [2025-07-01T13:27:31.772Z] ====== philosophers (scala) [default], iteration 17 completed (2013.433 ms) ====== [2025-07-01T13:27:31.772Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-07-01T13:27:31.772Z] GC before operation: completed in 33.794 ms, heap usage 23.554 MB -> 5.073 MB. [2025-07-01T13:27:33.408Z] Camera thread performed 122 scans. [2025-07-01T13:27:33.408Z] ====== philosophers (scala) [default], iteration 18 completed (1731.372 ms) ====== [2025-07-01T13:27:33.408Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-07-01T13:27:33.408Z] GC before operation: completed in 35.426 ms, heap usage 39.268 MB -> 5.074 MB. [2025-07-01T13:27:35.041Z] Camera thread performed 122 scans. [2025-07-01T13:27:35.041Z] ====== philosophers (scala) [default], iteration 19 completed (1736.862 ms) ====== [2025-07-01T13:27:35.041Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-07-01T13:27:35.041Z] GC before operation: completed in 36.496 ms, heap usage 25.254 MB -> 5.074 MB. [2025-07-01T13:27:37.230Z] Camera thread performed 122 scans. [2025-07-01T13:27:37.230Z] ====== philosophers (scala) [default], iteration 20 completed (1756.886 ms) ====== [2025-07-01T13:27:37.230Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-07-01T13:27:37.230Z] GC before operation: completed in 39.152 ms, heap usage 39.209 MB -> 5.074 MB. [2025-07-01T13:27:38.877Z] Camera thread performed 122 scans. [2025-07-01T13:27:38.877Z] ====== philosophers (scala) [default], iteration 21 completed (1887.152 ms) ====== [2025-07-01T13:27:38.877Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-07-01T13:27:38.877Z] GC before operation: completed in 50.511 ms, heap usage 25.087 MB -> 5.074 MB. [2025-07-01T13:27:41.431Z] Camera thread performed 122 scans. [2025-07-01T13:27:41.431Z] ====== philosophers (scala) [default], iteration 22 completed (2199.349 ms) ====== [2025-07-01T13:27:41.431Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-07-01T13:27:41.431Z] GC before operation: completed in 47.033 ms, heap usage 43.160 MB -> 5.074 MB. [2025-07-01T13:27:43.070Z] Camera thread performed 122 scans. [2025-07-01T13:27:43.070Z] ====== philosophers (scala) [default], iteration 23 completed (2053.443 ms) ====== [2025-07-01T13:27:43.070Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-07-01T13:27:43.070Z] GC before operation: completed in 34.845 ms, heap usage 36.836 MB -> 5.074 MB. [2025-07-01T13:27:45.636Z] Camera thread performed 122 scans. [2025-07-01T13:27:45.636Z] ====== philosophers (scala) [default], iteration 24 completed (2075.524 ms) ====== [2025-07-01T13:27:45.636Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-07-01T13:27:45.636Z] GC before operation: completed in 39.599 ms, heap usage 24.625 MB -> 5.075 MB. [2025-07-01T13:27:48.210Z] Camera thread performed 122 scans. [2025-07-01T13:27:48.211Z] ====== philosophers (scala) [default], iteration 25 completed (2320.917 ms) ====== [2025-07-01T13:27:48.211Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-07-01T13:27:48.211Z] GC before operation: completed in 46.558 ms, heap usage 21.978 MB -> 5.075 MB. [2025-07-01T13:27:49.854Z] Camera thread performed 122 scans. [2025-07-01T13:27:49.854Z] ====== philosophers (scala) [default], iteration 26 completed (2274.369 ms) ====== [2025-07-01T13:27:49.854Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-07-01T13:27:49.854Z] GC before operation: completed in 43.733 ms, heap usage 40.789 MB -> 5.075 MB. [2025-07-01T13:27:52.405Z] Camera thread performed 122 scans. [2025-07-01T13:27:52.405Z] ====== philosophers (scala) [default], iteration 27 completed (1861.954 ms) ====== [2025-07-01T13:27:52.405Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-07-01T13:27:52.405Z] GC before operation: completed in 42.036 ms, heap usage 30.268 MB -> 5.074 MB. [2025-07-01T13:27:54.059Z] Camera thread performed 122 scans. [2025-07-01T13:27:54.059Z] ====== philosophers (scala) [default], iteration 28 completed (1972.146 ms) ====== [2025-07-01T13:27:54.059Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-07-01T13:27:54.059Z] GC before operation: completed in 30.103 ms, heap usage 51.264 MB -> 5.075 MB. [2025-07-01T13:27:55.699Z] Camera thread performed 122 scans. [2025-07-01T13:27:55.699Z] ====== philosophers (scala) [default], iteration 29 completed (1905.382 ms) ====== [2025-07-01T13:27:56.500Z] ----------------------------------- [2025-07-01T13:27:56.500Z] renaissance-philosophers_0_PASSED [2025-07-01T13:27:56.500Z] ----------------------------------- [2025-07-01T13:27:56.500Z] [2025-07-01T13:27:56.500Z] TEST TEARDOWN: [2025-07-01T13:27:56.500Z] Nothing to be done for teardown. [2025-07-01T13:27:56.500Z] renaissance-philosophers_0 Finish Time: Tue Jul 1 13:27:56 2025 Epoch Time (ms): 1751376476157