[2025-05-22T05:08:04.414Z] Running test renaissance-philosophers_0 ... [2025-05-22T05:08:04.414Z] =============================================== [2025-05-22T05:08:04.414Z] renaissance-philosophers_0 Start Time: Thu May 22 05:08:04 2025 Epoch Time (ms): 1747890484362 [2025-05-22T05:08:04.718Z] variation: NoOptions [2025-05-22T05:08:04.718Z] JVM_OPTIONS: [2025-05-22T05:08:04.718Z] { \ [2025-05-22T05:08:04.718Z] echo ""; echo "TEST SETUP:"; \ [2025-05-22T05:08:04.718Z] echo "Nothing to be done for setup."; \ [2025-05-22T05:08:04.718Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17478898575204\\renaissance-philosophers_0"; \ [2025-05-22T05:08:04.718Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17478898575204\\renaissance-philosophers_0"; \ [2025-05-22T05:08:04.718Z] echo ""; echo "TESTING:"; \ [2025-05-22T05:08:04.718Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_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-32_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17478898575204\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-05-22T05:08:04.718Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17478898575204\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-22T05:08:04.718Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-22T05:08:04.718Z] echo "Nothing to be done for teardown."; \ [2025-05-22T05:08:04.718Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17478898575204\\TestTargetResult"; [2025-05-22T05:08:05.040Z] [2025-05-22T05:08:05.040Z] TEST SETUP: [2025-05-22T05:08:05.040Z] Nothing to be done for setup. [2025-05-22T05:08:05.040Z] [2025-05-22T05:08:05.040Z] TESTING: [2025-05-22T05:08:06.130Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-22T05:08:06.130Z] GC before operation: completed in 10.290 ms, heap usage 5.277 MB -> 4.140 MB. [2025-05-22T05:08:08.982Z] Camera thread performed 122 scans. [2025-05-22T05:08:08.982Z] ====== philosophers (scala) [default], iteration 0 completed (2732.394 ms) ====== [2025-05-22T05:08:08.982Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-22T05:08:08.982Z] GC before operation: completed in 12.172 ms, heap usage 5.164 MB -> 4.608 MB. [2025-05-22T05:08:11.134Z] Camera thread performed 122 scans. [2025-05-22T05:08:11.134Z] ====== philosophers (scala) [default], iteration 1 completed (2530.242 ms) ====== [2025-05-22T05:08:11.134Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-22T05:08:11.134Z] GC before operation: completed in 16.189 ms, heap usage 4.894 MB -> 4.713 MB. [2025-05-22T05:08:14.028Z] Camera thread performed 122 scans. [2025-05-22T05:08:14.028Z] ====== philosophers (scala) [default], iteration 2 completed (2533.993 ms) ====== [2025-05-22T05:08:14.028Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-22T05:08:14.028Z] GC before operation: completed in 12.129 ms, heap usage 5.962 MB -> 4.457 MB. [2025-05-22T05:08:16.207Z] Camera thread performed 122 scans. [2025-05-22T05:08:16.207Z] ====== philosophers (scala) [default], iteration 3 completed (2500.277 ms) ====== [2025-05-22T05:08:16.207Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-22T05:08:16.207Z] GC before operation: completed in 13.560 ms, heap usage 8.293 MB -> 4.562 MB. [2025-05-22T05:08:19.042Z] Camera thread performed 122 scans. [2025-05-22T05:08:19.042Z] ====== philosophers (scala) [default], iteration 4 completed (2556.415 ms) ====== [2025-05-22T05:08:19.042Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-22T05:08:19.042Z] GC before operation: completed in 11.188 ms, heap usage 8.481 MB -> 4.421 MB. [2025-05-22T05:08:21.275Z] Camera thread performed 122 scans. [2025-05-22T05:08:21.275Z] ====== philosophers (scala) [default], iteration 5 completed (2490.670 ms) ====== [2025-05-22T05:08:21.275Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-22T05:08:21.275Z] GC before operation: completed in 11.215 ms, heap usage 5.096 MB -> 3.898 MB. [2025-05-22T05:08:24.128Z] Camera thread performed 122 scans. [2025-05-22T05:08:24.128Z] ====== philosophers (scala) [default], iteration 6 completed (2530.209 ms) ====== [2025-05-22T05:08:24.128Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-22T05:08:24.128Z] GC before operation: completed in 9.983 ms, heap usage 5.927 MB -> 3.365 MB. [2025-05-22T05:08:26.961Z] Camera thread performed 122 scans. [2025-05-22T05:08:26.961Z] ====== philosophers (scala) [default], iteration 7 completed (2530.559 ms) ====== [2025-05-22T05:08:26.961Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-22T05:08:26.961Z] GC before operation: completed in 9.059 ms, heap usage 6.459 MB -> 3.470 MB. [2025-05-22T05:08:29.133Z] Camera thread performed 122 scans. [2025-05-22T05:08:29.133Z] ====== philosophers (scala) [default], iteration 8 completed (2529.745 ms) ====== [2025-05-22T05:08:29.133Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-22T05:08:29.133Z] GC before operation: completed in 9.104 ms, heap usage 7.703 MB -> 3.576 MB. [2025-05-22T05:08:31.994Z] Camera thread performed 122 scans. [2025-05-22T05:08:31.994Z] ====== philosophers (scala) [default], iteration 9 completed (2515.259 ms) ====== [2025-05-22T05:08:31.994Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-22T05:08:31.994Z] GC before operation: completed in 9.317 ms, heap usage 7.673 MB -> 3.681 MB. [2025-05-22T05:08:34.171Z] Camera thread performed 122 scans. [2025-05-22T05:08:34.171Z] ====== philosophers (scala) [default], iteration 10 completed (2546.932 ms) ====== [2025-05-22T05:08:34.171Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-22T05:08:34.171Z] GC before operation: completed in 9.047 ms, heap usage 7.152 MB -> 3.365 MB. [2025-05-22T05:08:37.012Z] Camera thread performed 122 scans. [2025-05-22T05:08:37.012Z] ====== philosophers (scala) [default], iteration 11 completed (2521.777 ms) ====== [2025-05-22T05:08:37.012Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-22T05:08:37.012Z] GC before operation: completed in 8.796 ms, heap usage 4.236 MB -> 3.470 MB. [2025-05-22T05:08:39.170Z] Camera thread performed 122 scans. [2025-05-22T05:08:39.170Z] ====== philosophers (scala) [default], iteration 12 completed (2519.548 ms) ====== [2025-05-22T05:08:39.170Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-22T05:08:39.170Z] GC before operation: completed in 8.679 ms, heap usage 4.699 MB -> 3.575 MB. [2025-05-22T05:08:42.004Z] Camera thread performed 122 scans. [2025-05-22T05:08:42.004Z] ====== philosophers (scala) [default], iteration 13 completed (2537.130 ms) ====== [2025-05-22T05:08:42.004Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-22T05:08:42.004Z] GC before operation: completed in 8.820 ms, heap usage 6.831 MB -> 3.680 MB. [2025-05-22T05:08:44.197Z] Camera thread performed 122 scans. [2025-05-22T05:08:44.197Z] ====== philosophers (scala) [default], iteration 14 completed (2515.443 ms) ====== [2025-05-22T05:08:44.197Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-22T05:08:44.197Z] GC before operation: completed in 8.669 ms, heap usage 4.996 MB -> 3.366 MB. [2025-05-22T05:08:47.076Z] Camera thread performed 122 scans. [2025-05-22T05:08:47.076Z] ====== philosophers (scala) [default], iteration 15 completed (2519.934 ms) ====== [2025-05-22T05:08:47.076Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-22T05:08:47.076Z] GC before operation: completed in 8.633 ms, heap usage 6.366 MB -> 3.471 MB. [2025-05-22T05:08:49.237Z] Camera thread performed 122 scans. [2025-05-22T05:08:49.237Z] ====== philosophers (scala) [default], iteration 16 completed (2541.619 ms) ====== [2025-05-22T05:08:49.237Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-22T05:08:49.237Z] GC before operation: completed in 9.646 ms, heap usage 4.676 MB -> 3.577 MB. [2025-05-22T05:08:52.085Z] Camera thread performed 122 scans. [2025-05-22T05:08:52.085Z] ====== philosophers (scala) [default], iteration 17 completed (2534.752 ms) ====== [2025-05-22T05:08:52.085Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-22T05:08:52.085Z] GC before operation: completed in 8.635 ms, heap usage 7.124 MB -> 3.682 MB. [2025-05-22T05:08:54.245Z] Camera thread performed 122 scans. [2025-05-22T05:08:54.246Z] ====== philosophers (scala) [default], iteration 18 completed (2538.243 ms) ====== [2025-05-22T05:08:54.246Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-22T05:08:54.246Z] GC before operation: completed in 8.871 ms, heap usage 7.161 MB -> 3.366 MB. [2025-05-22T05:08:57.078Z] Camera thread performed 122 scans. [2025-05-22T05:08:57.078Z] ====== philosophers (scala) [default], iteration 19 completed (2532.936 ms) ====== [2025-05-22T05:08:57.078Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-22T05:08:57.078Z] GC before operation: completed in 10.140 ms, heap usage 7.233 MB -> 3.471 MB. [2025-05-22T05:08:59.912Z] Camera thread performed 122 scans. [2025-05-22T05:08:59.912Z] ====== philosophers (scala) [default], iteration 20 completed (2552.025 ms) ====== [2025-05-22T05:08:59.912Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-22T05:08:59.912Z] GC before operation: completed in 8.478 ms, heap usage 4.642 MB -> 3.577 MB. [2025-05-22T05:09:02.084Z] Camera thread performed 122 scans. [2025-05-22T05:09:02.085Z] ====== philosophers (scala) [default], iteration 21 completed (2507.291 ms) ====== [2025-05-22T05:09:02.085Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-22T05:09:02.085Z] GC before operation: completed in 9.345 ms, heap usage 5.393 MB -> 3.682 MB. [2025-05-22T05:09:04.921Z] Camera thread performed 122 scans. [2025-05-22T05:09:04.921Z] ====== philosophers (scala) [default], iteration 22 completed (2538.751 ms) ====== [2025-05-22T05:09:04.921Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-22T05:09:04.921Z] GC before operation: completed in 9.720 ms, heap usage 7.106 MB -> 3.366 MB. [2025-05-22T05:09:07.116Z] Camera thread performed 122 scans. [2025-05-22T05:09:07.116Z] ====== philosophers (scala) [default], iteration 23 completed (2526.792 ms) ====== [2025-05-22T05:09:07.116Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-22T05:09:07.116Z] GC before operation: completed in 9.212 ms, heap usage 7.443 MB -> 3.471 MB. [2025-05-22T05:09:09.968Z] Camera thread performed 122 scans. [2025-05-22T05:09:09.968Z] ====== philosophers (scala) [default], iteration 24 completed (2537.581 ms) ====== [2025-05-22T05:09:09.968Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-22T05:09:09.968Z] GC before operation: completed in 8.572 ms, heap usage 4.939 MB -> 3.576 MB. [2025-05-22T05:09:12.150Z] Camera thread performed 122 scans. [2025-05-22T05:09:12.150Z] ====== philosophers (scala) [default], iteration 25 completed (2488.746 ms) ====== [2025-05-22T05:09:12.150Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-22T05:09:12.150Z] GC before operation: completed in 8.405 ms, heap usage 4.192 MB -> 3.682 MB. [2025-05-22T05:09:15.014Z] Camera thread performed 122 scans. [2025-05-22T05:09:15.014Z] ====== philosophers (scala) [default], iteration 26 completed (2513.328 ms) ====== [2025-05-22T05:09:15.014Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-22T05:09:15.014Z] GC before operation: completed in 9.559 ms, heap usage 7.631 MB -> 3.366 MB. [2025-05-22T05:09:17.175Z] Camera thread performed 122 scans. [2025-05-22T05:09:17.175Z] ====== philosophers (scala) [default], iteration 27 completed (2530.275 ms) ====== [2025-05-22T05:09:17.175Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-22T05:09:17.175Z] GC before operation: completed in 8.994 ms, heap usage 4.470 MB -> 3.471 MB. [2025-05-22T05:09:20.005Z] Camera thread performed 122 scans. [2025-05-22T05:09:20.005Z] ====== philosophers (scala) [default], iteration 28 completed (2537.280 ms) ====== [2025-05-22T05:09:20.005Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-22T05:09:20.005Z] GC before operation: completed in 9.169 ms, heap usage 6.861 MB -> 3.576 MB. [2025-05-22T05:09:22.180Z] Camera thread performed 122 scans. [2025-05-22T05:09:22.180Z] ====== philosophers (scala) [default], iteration 29 completed (2515.558 ms) ====== [2025-05-22T05:09:22.499Z] ----------------------------------- [2025-05-22T05:09:22.499Z] renaissance-philosophers_0_PASSED [2025-05-22T05:09:22.499Z] ----------------------------------- [2025-05-22T05:09:22.499Z] [2025-05-22T05:09:22.499Z] TEST TEARDOWN: [2025-05-22T05:09:22.499Z] Nothing to be done for teardown. [2025-05-22T05:09:22.499Z] renaissance-philosophers_0 Finish Time: Thu May 22 05:09:22 2025 Epoch Time (ms): 1747890562405