[2025-05-01T07:10:22.237Z] Running test renaissance-philosophers_0 ... [2025-05-01T07:10:22.237Z] =============================================== [2025-05-01T07:10:22.237Z] renaissance-philosophers_0 Start Time: Thu May 1 07:10:22 2025 Epoch Time (ms): 1746083422142 [2025-05-01T07:10:22.237Z] variation: NoOptions [2025-05-01T07:10:22.237Z] JVM_OPTIONS: [2025-05-01T07:10:22.237Z] { \ [2025-05-01T07:10:22.237Z] echo ""; echo "TEST SETUP:"; \ [2025-05-01T07:10:22.237Z] echo "Nothing to be done for setup."; \ [2025-05-01T07:10:22.237Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17460832302112/renaissance-philosophers_0"; \ [2025-05-01T07:10:22.237Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17460832302112/renaissance-philosophers_0"; \ [2025-05-01T07:10:22.237Z] echo ""; echo "TESTING:"; \ [2025-05-01T07:10:22.237Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17460832302112/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-01T07:10:22.237Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17460832302112/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-01T07:10:22.237Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-01T07:10:22.237Z] echo "Nothing to be done for teardown."; \ [2025-05-01T07:10:22.237Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17460832302112/TestTargetResult"; [2025-05-01T07:10:22.237Z] [2025-05-01T07:10:22.237Z] TEST SETUP: [2025-05-01T07:10:22.237Z] Nothing to be done for setup. [2025-05-01T07:10:22.237Z] [2025-05-01T07:10:22.237Z] TESTING: [2025-05-01T07:10:22.981Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-01T07:10:22.981Z] GC before operation: completed in 14.526 ms, heap usage 14.729 MB -> 4.858 MB. [2025-05-01T07:10:26.320Z] Camera thread performed 122 scans. [2025-05-01T07:10:26.320Z] ====== philosophers (scala) [default], iteration 0 completed (2564.853 ms) ====== [2025-05-01T07:10:26.320Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-01T07:10:26.320Z] GC before operation: completed in 11.646 ms, heap usage 12.010 MB -> 5.234 MB. [2025-05-01T07:10:27.067Z] Camera thread performed 122 scans. [2025-05-01T07:10:27.067Z] ====== philosophers (scala) [default], iteration 1 completed (1491.293 ms) ====== [2025-05-01T07:10:27.067Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-01T07:10:27.067Z] GC before operation: completed in 10.520 ms, heap usage 8.354 MB -> 5.233 MB. [2025-05-01T07:10:28.611Z] Camera thread performed 122 scans. [2025-05-01T07:10:28.612Z] ====== philosophers (scala) [default], iteration 2 completed (1667.012 ms) ====== [2025-05-01T07:10:28.612Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-01T07:10:29.358Z] GC before operation: completed in 10.521 ms, heap usage 25.323 MB -> 5.234 MB. [2025-05-01T07:10:30.898Z] Camera thread performed 122 scans. [2025-05-01T07:10:30.898Z] ====== philosophers (scala) [default], iteration 3 completed (1530.131 ms) ====== [2025-05-01T07:10:30.898Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-01T07:10:30.898Z] GC before operation: completed in 10.177 ms, heap usage 24.113 MB -> 5.235 MB. [2025-05-01T07:10:32.439Z] Camera thread performed 122 scans. [2025-05-01T07:10:32.439Z] ====== philosophers (scala) [default], iteration 4 completed (1773.089 ms) ====== [2025-05-01T07:10:32.439Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-01T07:10:32.439Z] GC before operation: completed in 10.454 ms, heap usage 44.625 MB -> 5.235 MB. [2025-05-01T07:10:33.981Z] Camera thread performed 122 scans. [2025-05-01T07:10:33.981Z] ====== philosophers (scala) [default], iteration 5 completed (1479.365 ms) ====== [2025-05-01T07:10:33.981Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-01T07:10:33.981Z] GC before operation: completed in 11.091 ms, heap usage 76.898 MB -> 5.235 MB. [2025-05-01T07:10:35.528Z] Camera thread performed 122 scans. [2025-05-01T07:10:35.528Z] ====== philosophers (scala) [default], iteration 6 completed (1648.656 ms) ====== [2025-05-01T07:10:35.528Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-01T07:10:35.528Z] GC before operation: completed in 11.707 ms, heap usage 51.445 MB -> 5.236 MB. [2025-05-01T07:10:37.069Z] Camera thread performed 122 scans. [2025-05-01T07:10:37.069Z] ====== philosophers (scala) [default], iteration 7 completed (1522.762 ms) ====== [2025-05-01T07:10:37.069Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-01T07:10:37.069Z] GC before operation: completed in 11.045 ms, heap usage 68.465 MB -> 5.235 MB. [2025-05-01T07:10:49.107Z] Camera thread performed 122 scans. [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 8 completed (1576.875 ms) ====== [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-01T07:10:49.107Z] GC before operation: completed in 10.218 ms, heap usage 35.907 MB -> 5.237 MB. [2025-05-01T07:10:49.107Z] Camera thread performed 122 scans. [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 9 completed (1756.088 ms) ====== [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-01T07:10:49.107Z] GC before operation: completed in 11.480 ms, heap usage 56.463 MB -> 5.236 MB. [2025-05-01T07:10:49.107Z] Camera thread performed 122 scans. [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 10 completed (1736.964 ms) ====== [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-01T07:10:49.107Z] GC before operation: completed in 10.201 ms, heap usage 27.926 MB -> 5.237 MB. [2025-05-01T07:10:49.107Z] Camera thread performed 122 scans. [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 11 completed (1547.912 ms) ====== [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-01T07:10:49.107Z] GC before operation: completed in 10.859 ms, heap usage 59.872 MB -> 5.237 MB. [2025-05-01T07:10:49.107Z] Camera thread performed 122 scans. [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 12 completed (1608.288 ms) ====== [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-01T07:10:49.107Z] GC before operation: completed in 12.982 ms, heap usage 74.874 MB -> 5.238 MB. [2025-05-01T07:10:49.107Z] Camera thread performed 122 scans. [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 13 completed (1564.611 ms) ====== [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-01T07:10:49.107Z] GC before operation: completed in 9.527 ms, heap usage 11.957 MB -> 5.238 MB. [2025-05-01T07:10:49.107Z] Camera thread performed 122 scans. [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 14 completed (1571.709 ms) ====== [2025-05-01T07:10:49.107Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-01T07:10:49.107Z] GC before operation: completed in 11.011 ms, heap usage 80.226 MB -> 5.238 MB. [2025-05-01T07:10:56.761Z] Camera thread performed 122 scans. [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 15 completed (1756.172 ms) ====== [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-01T07:10:56.761Z] GC before operation: completed in 11.047 ms, heap usage 28.452 MB -> 5.238 MB. [2025-05-01T07:10:56.761Z] Camera thread performed 122 scans. [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 16 completed (1589.418 ms) ====== [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-01T07:10:56.761Z] GC before operation: completed in 10.479 ms, heap usage 36.224 MB -> 5.238 MB. [2025-05-01T07:10:56.761Z] Camera thread performed 122 scans. [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 17 completed (1641.013 ms) ====== [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-01T07:10:56.761Z] GC before operation: completed in 10.458 ms, heap usage 68.846 MB -> 5.238 MB. [2025-05-01T07:10:56.761Z] Camera thread performed 122 scans. [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 18 completed (1555.542 ms) ====== [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-01T07:10:56.761Z] GC before operation: completed in 10.065 ms, heap usage 25.454 MB -> 5.238 MB. [2025-05-01T07:10:56.761Z] Camera thread performed 122 scans. [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 19 completed (1681.839 ms) ====== [2025-05-01T07:10:56.761Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-01T07:10:56.761Z] GC before operation: completed in 10.518 ms, heap usage 72.214 MB -> 5.238 MB. [2025-05-01T07:10:58.300Z] Camera thread performed 122 scans. [2025-05-01T07:10:58.300Z] ====== philosophers (scala) [default], iteration 20 completed (1722.417 ms) ====== [2025-05-01T07:10:58.300Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-01T07:10:58.300Z] GC before operation: completed in 10.480 ms, heap usage 20.258 MB -> 5.238 MB. [2025-05-01T07:10:59.837Z] Camera thread performed 122 scans. [2025-05-01T07:10:59.837Z] ====== philosophers (scala) [default], iteration 21 completed (1558.485 ms) ====== [2025-05-01T07:10:59.837Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-01T07:10:59.837Z] GC before operation: completed in 9.792 ms, heap usage 33.460 MB -> 5.238 MB. [2025-05-01T07:11:01.375Z] Camera thread performed 122 scans. [2025-05-01T07:11:01.375Z] ====== philosophers (scala) [default], iteration 22 completed (1499.307 ms) ====== [2025-05-01T07:11:01.375Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-01T07:11:01.375Z] GC before operation: completed in 10.508 ms, heap usage 38.358 MB -> 5.239 MB. [2025-05-01T07:11:02.953Z] Camera thread performed 122 scans. [2025-05-01T07:11:02.953Z] ====== philosophers (scala) [default], iteration 23 completed (1741.623 ms) ====== [2025-05-01T07:11:02.953Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-01T07:11:02.953Z] GC before operation: completed in 11.786 ms, heap usage 63.464 MB -> 5.238 MB. [2025-05-01T07:11:04.492Z] Camera thread performed 122 scans. [2025-05-01T07:11:04.492Z] ====== philosophers (scala) [default], iteration 24 completed (1447.747 ms) ====== [2025-05-01T07:11:04.492Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-01T07:11:04.492Z] GC before operation: completed in 10.138 ms, heap usage 21.391 MB -> 5.239 MB. [2025-05-01T07:11:06.032Z] Camera thread performed 122 scans. [2025-05-01T07:11:06.032Z] ====== philosophers (scala) [default], iteration 25 completed (1643.549 ms) ====== [2025-05-01T07:11:06.032Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-01T07:11:06.778Z] GC before operation: completed in 9.754 ms, heap usage 7.447 MB -> 5.239 MB. [2025-05-01T07:11:08.320Z] Camera thread performed 122 scans. [2025-05-01T07:11:08.320Z] ====== philosophers (scala) [default], iteration 26 completed (1568.693 ms) ====== [2025-05-01T07:11:08.320Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-01T07:11:08.320Z] GC before operation: completed in 12.314 ms, heap usage 77.314 MB -> 5.239 MB. [2025-05-01T07:11:09.856Z] Camera thread performed 122 scans. [2025-05-01T07:11:09.856Z] ====== philosophers (scala) [default], iteration 27 completed (1483.982 ms) ====== [2025-05-01T07:11:09.856Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-01T07:11:09.856Z] GC before operation: completed in 10.760 ms, heap usage 58.764 MB -> 5.239 MB. [2025-05-01T07:11:11.449Z] Camera thread performed 122 scans. [2025-05-01T07:11:11.449Z] ====== philosophers (scala) [default], iteration 28 completed (1599.588 ms) ====== [2025-05-01T07:11:11.449Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-01T07:11:11.449Z] GC before operation: completed in 10.389 ms, heap usage 46.183 MB -> 5.239 MB. [2025-05-01T07:11:12.983Z] Camera thread performed 122 scans. [2025-05-01T07:11:12.983Z] ====== philosophers (scala) [default], iteration 29 completed (1516.457 ms) ====== [2025-05-01T07:11:12.983Z] ----------------------------------- [2025-05-01T07:11:12.983Z] renaissance-philosophers_0_PASSED [2025-05-01T07:11:12.983Z] ----------------------------------- [2025-05-01T07:11:12.983Z] [2025-05-01T07:11:12.983Z] TEST TEARDOWN: [2025-05-01T07:11:12.983Z] Nothing to be done for teardown. [2025-05-01T07:11:12.983Z] renaissance-philosophers_0 Finish Time: Thu May 1 07:11:12 2025 Epoch Time (ms): 1746083472345