[2025-08-30T05:57:24.191Z] Running test renaissance-philosophers_0 ... [2025-08-30T05:57:24.191Z] =============================================== [2025-08-30T05:57:24.191Z] renaissance-philosophers_0 Start Time: Sat Aug 30 05:57:24 2025 Epoch Time (ms): 1756533444056 [2025-08-30T05:57:24.191Z] variation: NoOptions [2025-08-30T05:57:24.514Z] JVM_OPTIONS: [2025-08-30T05:57:24.514Z] { \ [2025-08-30T05:57:24.514Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T05:57:24.514Z] echo "Nothing to be done for setup."; \ [2025-08-30T05:57:24.514Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17565308225683\\renaissance-philosophers_0"; \ [2025-08-30T05:57:24.514Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17565308225683\\renaissance-philosophers_0"; \ [2025-08-30T05:57:24.514Z] echo ""; echo "TESTING:"; \ [2025-08-30T05:57:24.514Z] "c:/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17565308225683\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-08-30T05:57:24.514Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17565308225683\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T05:57:24.514Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T05:57:24.514Z] echo "Nothing to be done for teardown."; \ [2025-08-30T05:57:24.514Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17565308225683\\TestTargetResult"; [2025-08-30T05:57:24.514Z] [2025-08-30T05:57:24.514Z] TEST SETUP: [2025-08-30T05:57:24.514Z] Nothing to be done for setup. [2025-08-30T05:57:24.514Z] [2025-08-30T05:57:24.514Z] TESTING: [2025-08-30T05:57:25.630Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-30T05:57:25.630Z] GC before operation: completed in 11.406 ms, heap usage 16.041 MB -> 4.554 MB. [2025-08-30T05:57:27.818Z] Camera thread performed 122 scans. [2025-08-30T05:57:27.818Z] ====== philosophers (scala) [default], iteration 0 completed (1854.788 ms) ====== [2025-08-30T05:57:27.818Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-30T05:57:27.818Z] GC before operation: completed in 12.202 ms, heap usage 24.034 MB -> 4.930 MB. [2025-08-30T05:57:28.923Z] Camera thread performed 122 scans. [2025-08-30T05:57:28.923Z] ====== philosophers (scala) [default], iteration 1 completed (1417.194 ms) ====== [2025-08-30T05:57:28.923Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-30T05:57:28.923Z] GC before operation: completed in 10.838 ms, heap usage 26.123 MB -> 4.930 MB. [2025-08-30T05:57:30.515Z] Camera thread performed 122 scans. [2025-08-30T05:57:30.515Z] ====== philosophers (scala) [default], iteration 2 completed (1425.272 ms) ====== [2025-08-30T05:57:30.515Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-30T05:57:30.515Z] GC before operation: completed in 10.315 ms, heap usage 51.125 MB -> 4.931 MB. [2025-08-30T05:57:31.618Z] Camera thread performed 122 scans. [2025-08-30T05:57:31.618Z] ====== philosophers (scala) [default], iteration 3 completed (1391.189 ms) ====== [2025-08-30T05:57:31.618Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-30T05:57:31.618Z] GC before operation: completed in 9.834 ms, heap usage 62.651 MB -> 4.932 MB. [2025-08-30T05:57:33.231Z] Camera thread performed 122 scans. [2025-08-30T05:57:33.231Z] ====== philosophers (scala) [default], iteration 4 completed (1473.423 ms) ====== [2025-08-30T05:57:33.231Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-30T05:57:33.231Z] GC before operation: completed in 9.850 ms, heap usage 28.525 MB -> 4.932 MB. [2025-08-30T05:57:34.824Z] Camera thread performed 122 scans. [2025-08-30T05:57:34.824Z] ====== philosophers (scala) [default], iteration 5 completed (1484.607 ms) ====== [2025-08-30T05:57:34.824Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-30T05:57:34.824Z] GC before operation: completed in 10.110 ms, heap usage 69.195 MB -> 4.936 MB. [2025-08-30T05:57:36.416Z] Camera thread performed 122 scans. [2025-08-30T05:57:36.416Z] ====== philosophers (scala) [default], iteration 6 completed (1485.574 ms) ====== [2025-08-30T05:57:36.416Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-30T05:57:36.416Z] GC before operation: completed in 10.248 ms, heap usage 74.156 MB -> 4.937 MB. [2025-08-30T05:57:37.518Z] Camera thread performed 122 scans. [2025-08-30T05:57:37.518Z] ====== philosophers (scala) [default], iteration 7 completed (1442.480 ms) ====== [2025-08-30T05:57:37.518Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-30T05:57:37.518Z] GC before operation: completed in 10.045 ms, heap usage 23.959 MB -> 4.937 MB. [2025-08-30T05:57:39.106Z] Camera thread performed 122 scans. [2025-08-30T05:57:39.106Z] ====== philosophers (scala) [default], iteration 8 completed (1467.709 ms) ====== [2025-08-30T05:57:39.106Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-30T05:57:39.106Z] GC before operation: completed in 9.558 ms, heap usage 33.127 MB -> 4.937 MB. [2025-08-30T05:57:40.696Z] Camera thread performed 122 scans. [2025-08-30T05:57:40.696Z] ====== philosophers (scala) [default], iteration 9 completed (1535.173 ms) ====== [2025-08-30T05:57:40.696Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-30T05:57:40.696Z] GC before operation: completed in 9.838 ms, heap usage 30.622 MB -> 4.937 MB. [2025-08-30T05:57:42.288Z] Camera thread performed 122 scans. [2025-08-30T05:57:42.288Z] ====== philosophers (scala) [default], iteration 10 completed (1475.323 ms) ====== [2025-08-30T05:57:42.288Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-30T05:57:42.288Z] GC before operation: completed in 9.966 ms, heap usage 8.136 MB -> 4.938 MB. [2025-08-30T05:57:43.880Z] Camera thread performed 122 scans. [2025-08-30T05:57:43.880Z] ====== philosophers (scala) [default], iteration 11 completed (1464.037 ms) ====== [2025-08-30T05:57:43.880Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-30T05:57:43.880Z] GC before operation: completed in 10.039 ms, heap usage 70.138 MB -> 4.938 MB. [2025-08-30T05:57:44.982Z] Camera thread performed 122 scans. [2025-08-30T05:57:44.983Z] ====== philosophers (scala) [default], iteration 12 completed (1478.792 ms) ====== [2025-08-30T05:57:44.983Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-30T05:57:44.983Z] GC before operation: completed in 9.833 ms, heap usage 73.936 MB -> 4.938 MB. [2025-08-30T05:57:46.572Z] Camera thread performed 122 scans. [2025-08-30T05:57:46.572Z] ====== philosophers (scala) [default], iteration 13 completed (1475.346 ms) ====== [2025-08-30T05:57:46.572Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-30T05:57:46.572Z] GC before operation: completed in 10.063 ms, heap usage 66.908 MB -> 4.938 MB. [2025-08-30T05:57:48.163Z] Camera thread performed 122 scans. [2025-08-30T05:57:48.163Z] ====== philosophers (scala) [default], iteration 14 completed (1473.951 ms) ====== [2025-08-30T05:57:48.163Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-30T05:57:48.163Z] GC before operation: completed in 9.660 ms, heap usage 9.595 MB -> 4.939 MB. [2025-08-30T05:57:49.755Z] Camera thread performed 122 scans. [2025-08-30T05:57:49.755Z] ====== philosophers (scala) [default], iteration 15 completed (1523.420 ms) ====== [2025-08-30T05:57:49.755Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-30T05:57:49.755Z] GC before operation: completed in 9.990 ms, heap usage 49.150 MB -> 4.939 MB. [2025-08-30T05:57:51.341Z] Camera thread performed 122 scans. [2025-08-30T05:57:51.341Z] ====== philosophers (scala) [default], iteration 16 completed (1498.339 ms) ====== [2025-08-30T05:57:51.341Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-30T05:57:51.341Z] GC before operation: completed in 10.017 ms, heap usage 69.935 MB -> 4.939 MB. [2025-08-30T05:57:52.440Z] Camera thread performed 122 scans. [2025-08-30T05:57:52.440Z] ====== philosophers (scala) [default], iteration 17 completed (1457.148 ms) ====== [2025-08-30T05:57:52.440Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-30T05:57:52.440Z] GC before operation: completed in 9.828 ms, heap usage 29.546 MB -> 4.939 MB. [2025-08-30T05:57:54.014Z] Camera thread performed 122 scans. [2025-08-30T05:57:54.014Z] ====== philosophers (scala) [default], iteration 18 completed (1376.188 ms) ====== [2025-08-30T05:57:54.014Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-30T05:57:54.014Z] GC before operation: completed in 10.128 ms, heap usage 23.847 MB -> 4.939 MB. [2025-08-30T05:57:55.602Z] Camera thread performed 122 scans. [2025-08-30T05:57:55.602Z] ====== philosophers (scala) [default], iteration 19 completed (1438.532 ms) ====== [2025-08-30T05:57:55.602Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-30T05:57:55.602Z] GC before operation: completed in 10.363 ms, heap usage 41.559 MB -> 4.939 MB. [2025-08-30T05:57:57.178Z] Camera thread performed 122 scans. [2025-08-30T05:57:57.178Z] ====== philosophers (scala) [default], iteration 20 completed (1521.599 ms) ====== [2025-08-30T05:57:57.178Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-30T05:57:57.178Z] GC before operation: completed in 9.763 ms, heap usage 41.944 MB -> 4.939 MB. [2025-08-30T05:57:58.278Z] Camera thread performed 122 scans. [2025-08-30T05:57:58.278Z] ====== philosophers (scala) [default], iteration 21 completed (1306.743 ms) ====== [2025-08-30T05:57:58.278Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-30T05:57:58.278Z] GC before operation: completed in 9.857 ms, heap usage 38.766 MB -> 4.939 MB. [2025-08-30T05:57:59.870Z] Camera thread performed 122 scans. [2025-08-30T05:57:59.870Z] ====== philosophers (scala) [default], iteration 22 completed (1477.255 ms) ====== [2025-08-30T05:57:59.870Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-30T05:57:59.870Z] GC before operation: completed in 10.173 ms, heap usage 59.940 MB -> 4.940 MB. [2025-08-30T05:58:01.459Z] Camera thread performed 122 scans. [2025-08-30T05:58:01.459Z] ====== philosophers (scala) [default], iteration 23 completed (1454.217 ms) ====== [2025-08-30T05:58:01.459Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-30T05:58:01.459Z] GC before operation: completed in 9.757 ms, heap usage 44.104 MB -> 4.940 MB. [2025-08-30T05:58:03.047Z] Camera thread performed 122 scans. [2025-08-30T05:58:03.047Z] ====== philosophers (scala) [default], iteration 24 completed (1519.777 ms) ====== [2025-08-30T05:58:03.047Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-30T05:58:03.047Z] GC before operation: completed in 9.971 ms, heap usage 25.992 MB -> 4.940 MB. [2025-08-30T05:58:04.144Z] Camera thread performed 122 scans. [2025-08-30T05:58:04.144Z] ====== philosophers (scala) [default], iteration 25 completed (1494.928 ms) ====== [2025-08-30T05:58:04.144Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-30T05:58:04.144Z] GC before operation: completed in 9.456 ms, heap usage 19.558 MB -> 4.940 MB. [2025-08-30T05:58:05.731Z] Camera thread performed 122 scans. [2025-08-30T05:58:05.731Z] ====== philosophers (scala) [default], iteration 26 completed (1528.286 ms) ====== [2025-08-30T05:58:05.731Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-30T05:58:05.731Z] GC before operation: completed in 10.052 ms, heap usage 63.527 MB -> 4.940 MB. [2025-08-30T05:58:07.321Z] Camera thread performed 122 scans. [2025-08-30T05:58:07.321Z] ====== philosophers (scala) [default], iteration 27 completed (1355.766 ms) ====== [2025-08-30T05:58:07.321Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-30T05:58:07.321Z] GC before operation: completed in 9.927 ms, heap usage 27.480 MB -> 4.940 MB. [2025-08-30T05:58:08.910Z] Camera thread performed 122 scans. [2025-08-30T05:58:08.910Z] ====== philosophers (scala) [default], iteration 28 completed (1479.235 ms) ====== [2025-08-30T05:58:08.910Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-30T05:58:08.910Z] GC before operation: completed in 9.993 ms, heap usage 55.927 MB -> 4.940 MB. [2025-08-30T05:58:10.008Z] Camera thread performed 122 scans. [2025-08-30T05:58:10.008Z] ====== philosophers (scala) [default], iteration 29 completed (1488.299 ms) ====== [2025-08-30T05:58:10.328Z] ----------------------------------- [2025-08-30T05:58:10.328Z] renaissance-philosophers_0_PASSED [2025-08-30T05:58:10.328Z] ----------------------------------- [2025-08-30T05:58:10.328Z] [2025-08-30T05:58:10.328Z] TEST TEARDOWN: [2025-08-30T05:58:10.328Z] Nothing to be done for teardown. [2025-08-30T05:58:10.328Z] renaissance-philosophers_0 Finish Time: Sat Aug 30 05:58:10 2025 Epoch Time (ms): 1756533490272