[2025-06-19T11:49:55.578Z] Running test renaissance-philosophers_0 ... [2025-06-19T11:49:55.578Z] =============================================== [2025-06-19T11:49:55.902Z] renaissance-philosophers_0 Start Time: Thu Jun 19 11:49:55 2025 Epoch Time (ms): 1750333795585 [2025-06-19T11:49:55.902Z] variation: NoOptions [2025-06-19T11:49:56.230Z] JVM_OPTIONS: [2025-06-19T11:49:56.230Z] { \ [2025-06-19T11:49:56.230Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T11:49:56.230Z] echo "Nothing to be done for setup."; \ [2025-06-19T11:49:56.230Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\renaissance-philosophers_0"; \ [2025-06-19T11:49:56.230Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\renaissance-philosophers_0"; \ [2025-06-19T11:49:56.230Z] echo ""; echo "TESTING:"; \ [2025-06-19T11:49:56.230Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-06-19T11:49:56.230Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T11:49:56.230Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T11:49:56.230Z] echo "Nothing to be done for teardown."; \ [2025-06-19T11:49:56.230Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17503312181534\\TestTargetResult"; [2025-06-19T11:49:56.230Z] [2025-06-19T11:49:56.230Z] TEST SETUP: [2025-06-19T11:49:56.230Z] Nothing to be done for setup. [2025-06-19T11:49:56.230Z] [2025-06-19T11:49:56.230Z] TESTING: [2025-06-19T11:49:57.412Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-19T11:49:57.412Z] GC before operation: completed in 9.571 ms, heap usage 15.293 MB -> 4.480 MB. [2025-06-19T11:49:59.057Z] Camera thread performed 122 scans. [2025-06-19T11:49:59.057Z] ====== philosophers (scala) [default], iteration 0 completed (2050.571 ms) ====== [2025-06-19T11:49:59.057Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-19T11:49:59.057Z] GC before operation: completed in 10.632 ms, heap usage 52.666 MB -> 4.879 MB. [2025-06-19T11:50:00.722Z] Camera thread performed 122 scans. [2025-06-19T11:50:00.722Z] ====== philosophers (scala) [default], iteration 1 completed (1604.798 ms) ====== [2025-06-19T11:50:00.722Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-19T11:50:00.722Z] GC before operation: completed in 9.310 ms, heap usage 7.090 MB -> 4.879 MB. [2025-06-19T11:50:02.323Z] Camera thread performed 122 scans. [2025-06-19T11:50:02.323Z] ====== philosophers (scala) [default], iteration 2 completed (1488.428 ms) ====== [2025-06-19T11:50:02.323Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-19T11:50:02.323Z] GC before operation: completed in 9.841 ms, heap usage 59.341 MB -> 4.885 MB. [2025-06-19T11:50:03.924Z] Camera thread performed 122 scans. [2025-06-19T11:50:03.924Z] ====== philosophers (scala) [default], iteration 3 completed (1381.756 ms) ====== [2025-06-19T11:50:03.924Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-19T11:50:03.924Z] GC before operation: completed in 9.233 ms, heap usage 19.597 MB -> 4.886 MB. [2025-06-19T11:50:05.042Z] Camera thread performed 122 scans. [2025-06-19T11:50:05.042Z] ====== philosophers (scala) [default], iteration 4 completed (1458.570 ms) ====== [2025-06-19T11:50:05.042Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-19T11:50:05.042Z] GC before operation: completed in 9.791 ms, heap usage 41.573 MB -> 4.886 MB. [2025-06-19T11:50:06.641Z] Camera thread performed 122 scans. [2025-06-19T11:50:06.641Z] ====== philosophers (scala) [default], iteration 5 completed (1459.384 ms) ====== [2025-06-19T11:50:06.641Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-19T11:50:06.641Z] GC before operation: completed in 9.848 ms, heap usage 62.076 MB -> 4.886 MB. [2025-06-19T11:50:08.239Z] Camera thread performed 122 scans. [2025-06-19T11:50:08.239Z] ====== philosophers (scala) [default], iteration 6 completed (1435.499 ms) ====== [2025-06-19T11:50:08.239Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-19T11:50:08.239Z] GC before operation: completed in 10.166 ms, heap usage 62.121 MB -> 4.887 MB. [2025-06-19T11:50:09.346Z] Camera thread performed 122 scans. [2025-06-19T11:50:09.346Z] ====== philosophers (scala) [default], iteration 7 completed (1426.645 ms) ====== [2025-06-19T11:50:09.346Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-19T11:50:09.346Z] GC before operation: completed in 9.579 ms, heap usage 26.039 MB -> 4.887 MB. [2025-06-19T11:50:10.949Z] Camera thread performed 122 scans. [2025-06-19T11:50:10.949Z] ====== philosophers (scala) [default], iteration 8 completed (1472.837 ms) ====== [2025-06-19T11:50:10.949Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-19T11:50:10.949Z] GC before operation: completed in 9.925 ms, heap usage 43.833 MB -> 4.888 MB. [2025-06-19T11:50:12.534Z] Camera thread performed 122 scans. [2025-06-19T11:50:12.534Z] ====== philosophers (scala) [default], iteration 9 completed (1426.053 ms) ====== [2025-06-19T11:50:12.534Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-19T11:50:12.534Z] GC before operation: completed in 9.260 ms, heap usage 14.514 MB -> 4.887 MB. [2025-06-19T11:50:14.138Z] Camera thread performed 122 scans. [2025-06-19T11:50:14.138Z] ====== philosophers (scala) [default], iteration 10 completed (1488.763 ms) ====== [2025-06-19T11:50:14.138Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-19T11:50:14.138Z] GC before operation: completed in 9.969 ms, heap usage 63.101 MB -> 4.888 MB. [2025-06-19T11:50:15.245Z] Camera thread performed 122 scans. [2025-06-19T11:50:15.245Z] ====== philosophers (scala) [default], iteration 11 completed (1425.889 ms) ====== [2025-06-19T11:50:15.245Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-19T11:50:15.245Z] GC before operation: completed in 9.913 ms, heap usage 14.102 MB -> 4.888 MB. [2025-06-19T11:50:16.842Z] Camera thread performed 122 scans. [2025-06-19T11:50:16.842Z] ====== philosophers (scala) [default], iteration 12 completed (1483.187 ms) ====== [2025-06-19T11:50:16.842Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-19T11:50:16.842Z] GC before operation: completed in 9.999 ms, heap usage 28.747 MB -> 4.889 MB. [2025-06-19T11:50:18.451Z] Camera thread performed 122 scans. [2025-06-19T11:50:18.451Z] ====== philosophers (scala) [default], iteration 13 completed (1481.423 ms) ====== [2025-06-19T11:50:18.451Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-19T11:50:18.451Z] GC before operation: completed in 10.312 ms, heap usage 68.461 MB -> 4.890 MB. [2025-06-19T11:50:20.051Z] Camera thread performed 122 scans. [2025-06-19T11:50:20.051Z] ====== philosophers (scala) [default], iteration 14 completed (1406.236 ms) ====== [2025-06-19T11:50:20.051Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-19T11:50:20.051Z] GC before operation: completed in 9.931 ms, heap usage 69.434 MB -> 4.890 MB. [2025-06-19T11:50:21.160Z] Camera thread performed 122 scans. [2025-06-19T11:50:21.160Z] ====== philosophers (scala) [default], iteration 15 completed (1468.523 ms) ====== [2025-06-19T11:50:21.160Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-19T11:50:21.160Z] GC before operation: completed in 10.081 ms, heap usage 56.422 MB -> 4.890 MB. [2025-06-19T11:50:22.761Z] Camera thread performed 122 scans. [2025-06-19T11:50:22.761Z] ====== philosophers (scala) [default], iteration 16 completed (1412.406 ms) ====== [2025-06-19T11:50:22.761Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-19T11:50:22.761Z] GC before operation: completed in 10.384 ms, heap usage 41.101 MB -> 4.890 MB. [2025-06-19T11:50:24.361Z] Camera thread performed 122 scans. [2025-06-19T11:50:24.361Z] ====== philosophers (scala) [default], iteration 17 completed (1427.941 ms) ====== [2025-06-19T11:50:24.361Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-19T11:50:24.361Z] GC before operation: completed in 10.379 ms, heap usage 38.893 MB -> 4.890 MB. [2025-06-19T11:50:25.468Z] Camera thread performed 122 scans. [2025-06-19T11:50:25.468Z] ====== philosophers (scala) [default], iteration 18 completed (1425.463 ms) ====== [2025-06-19T11:50:25.468Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-19T11:50:25.468Z] GC before operation: completed in 10.105 ms, heap usage 30.445 MB -> 4.890 MB. [2025-06-19T11:50:27.067Z] Camera thread performed 122 scans. [2025-06-19T11:50:27.067Z] ====== philosophers (scala) [default], iteration 19 completed (1393.421 ms) ====== [2025-06-19T11:50:27.067Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-19T11:50:27.067Z] GC before operation: completed in 9.857 ms, heap usage 19.102 MB -> 4.890 MB. [2025-06-19T11:50:28.665Z] Camera thread performed 122 scans. [2025-06-19T11:50:28.665Z] ====== philosophers (scala) [default], iteration 20 completed (1455.834 ms) ====== [2025-06-19T11:50:28.665Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-19T11:50:28.665Z] GC before operation: completed in 10.591 ms, heap usage 54.870 MB -> 4.891 MB. [2025-06-19T11:50:29.774Z] Camera thread performed 122 scans. [2025-06-19T11:50:29.774Z] ====== philosophers (scala) [default], iteration 21 completed (1491.796 ms) ====== [2025-06-19T11:50:29.774Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-19T11:50:29.774Z] GC before operation: completed in 9.533 ms, heap usage 10.130 MB -> 4.892 MB. [2025-06-19T11:50:31.373Z] Camera thread performed 122 scans. [2025-06-19T11:50:31.373Z] ====== philosophers (scala) [default], iteration 22 completed (1411.460 ms) ====== [2025-06-19T11:50:31.373Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-19T11:50:31.373Z] GC before operation: completed in 10.033 ms, heap usage 58.118 MB -> 4.892 MB. [2025-06-19T11:50:32.973Z] Camera thread performed 122 scans. [2025-06-19T11:50:32.973Z] ====== philosophers (scala) [default], iteration 23 completed (1438.760 ms) ====== [2025-06-19T11:50:32.973Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-19T11:50:32.973Z] GC before operation: completed in 10.121 ms, heap usage 46.124 MB -> 4.892 MB. [2025-06-19T11:50:34.122Z] Camera thread performed 122 scans. [2025-06-19T11:50:34.122Z] ====== philosophers (scala) [default], iteration 24 completed (1362.104 ms) ====== [2025-06-19T11:50:34.122Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-19T11:50:34.122Z] GC before operation: completed in 10.179 ms, heap usage 59.113 MB -> 4.892 MB. [2025-06-19T11:50:35.722Z] Camera thread performed 122 scans. [2025-06-19T11:50:35.722Z] ====== philosophers (scala) [default], iteration 25 completed (1453.252 ms) ====== [2025-06-19T11:50:35.722Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-19T11:50:35.722Z] GC before operation: completed in 9.954 ms, heap usage 66.718 MB -> 4.892 MB. [2025-06-19T11:50:37.320Z] Camera thread performed 122 scans. [2025-06-19T11:50:37.321Z] ====== philosophers (scala) [default], iteration 26 completed (1421.322 ms) ====== [2025-06-19T11:50:37.321Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-19T11:50:37.321Z] GC before operation: completed in 10.873 ms, heap usage 26.437 MB -> 4.892 MB. [2025-06-19T11:50:38.434Z] Camera thread performed 122 scans. [2025-06-19T11:50:38.434Z] ====== philosophers (scala) [default], iteration 27 completed (1391.789 ms) ====== [2025-06-19T11:50:38.434Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-19T11:50:38.434Z] GC before operation: completed in 9.945 ms, heap usage 22.790 MB -> 4.892 MB. [2025-06-19T11:50:40.018Z] Camera thread performed 122 scans. [2025-06-19T11:50:40.018Z] ====== philosophers (scala) [default], iteration 28 completed (1474.582 ms) ====== [2025-06-19T11:50:40.018Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-19T11:50:40.018Z] GC before operation: completed in 11.574 ms, heap usage 30.473 MB -> 4.892 MB. [2025-06-19T11:50:41.603Z] Camera thread performed 122 scans. [2025-06-19T11:50:41.603Z] ====== philosophers (scala) [default], iteration 29 completed (1373.221 ms) ====== [2025-06-19T11:50:41.603Z] ----------------------------------- [2025-06-19T11:50:41.603Z] renaissance-philosophers_0_PASSED [2025-06-19T11:50:41.603Z] ----------------------------------- [2025-06-19T11:50:41.603Z] [2025-06-19T11:50:41.603Z] TEST TEARDOWN: [2025-06-19T11:50:41.603Z] Nothing to be done for teardown. [2025-06-19T11:50:41.603Z] renaissance-philosophers_0 Finish Time: Thu Jun 19 11:50:41 2025 Epoch Time (ms): 1750333841416