[2025-06-19T12:16:34.595Z] Running test renaissance-philosophers_0 ... [2025-06-19T12:16:34.917Z] =============================================== [2025-06-19T12:16:34.917Z] renaissance-philosophers_0 Start Time: Thu Jun 19 12:16:34 2025 Epoch Time (ms): 1750335394719 [2025-06-19T12:16:34.917Z] variation: NoOptions [2025-06-19T12:16:34.917Z] JVM_OPTIONS: [2025-06-19T12:16:34.917Z] { \ [2025-06-19T12:16:34.917Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T12:16:34.917Z] echo "Nothing to be done for setup."; \ [2025-06-19T12:16:34.917Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17503349033555\\renaissance-philosophers_0"; \ [2025-06-19T12:16:34.917Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17503349033555\\renaissance-philosophers_0"; \ [2025-06-19T12:16:34.917Z] echo ""; echo "TESTING:"; \ [2025-06-19T12:16:34.917Z] "c:/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17503349033555\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-06-19T12:16:34.917Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17503349033555\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T12:16:34.917Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T12:16:34.917Z] echo "Nothing to be done for teardown."; \ [2025-06-19T12:16:34.917Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-32_windows/aqa-tests/\\TKG\\output_17503349033555\\TestTargetResult"; [2025-06-19T12:16:35.242Z] [2025-06-19T12:16:35.242Z] TEST SETUP: [2025-06-19T12:16:35.242Z] Nothing to be done for setup. [2025-06-19T12:16:35.242Z] [2025-06-19T12:16:35.242Z] TESTING: [2025-06-19T12:16:36.341Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-19T12:16:36.341Z] GC before operation: completed in 12.117 ms, heap usage 7.050 MB -> 4.181 MB. [2025-06-19T12:16:39.993Z] Camera thread performed 122 scans. [2025-06-19T12:16:39.993Z] ====== philosophers (scala) [default], iteration 0 completed (3567.558 ms) ====== [2025-06-19T12:16:39.993Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-19T12:16:39.993Z] GC before operation: completed in 13.233 ms, heap usage 7.644 MB -> 4.751 MB. [2025-06-19T12:16:42.839Z] Camera thread performed 122 scans. [2025-06-19T12:16:42.839Z] ====== philosophers (scala) [default], iteration 1 completed (3148.753 ms) ====== [2025-06-19T12:16:42.839Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-19T12:16:42.839Z] GC before operation: completed in 10.617 ms, heap usage 7.497 MB -> 4.927 MB. [2025-06-19T12:16:46.472Z] Camera thread performed 122 scans. [2025-06-19T12:16:46.472Z] ====== philosophers (scala) [default], iteration 2 completed (3011.004 ms) ====== [2025-06-19T12:16:46.472Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-19T12:16:46.472Z] GC before operation: completed in 14.341 ms, heap usage 8.141 MB -> 4.424 MB. [2025-06-19T12:16:49.336Z] Camera thread performed 122 scans. [2025-06-19T12:16:49.336Z] ====== philosophers (scala) [default], iteration 3 completed (3003.103 ms) ====== [2025-06-19T12:16:49.336Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-19T12:16:49.336Z] GC before operation: completed in 11.930 ms, heap usage 8.139 MB -> 4.416 MB. [2025-06-19T12:16:52.181Z] Camera thread performed 122 scans. [2025-06-19T12:16:52.181Z] ====== philosophers (scala) [default], iteration 4 completed (3231.095 ms) ====== [2025-06-19T12:16:52.181Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-19T12:16:52.181Z] GC before operation: completed in 11.587 ms, heap usage 6.871 MB -> 3.876 MB. [2025-06-19T12:16:55.836Z] Camera thread performed 122 scans. [2025-06-19T12:16:55.836Z] ====== philosophers (scala) [default], iteration 5 completed (2998.475 ms) ====== [2025-06-19T12:16:55.836Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-19T12:16:55.836Z] GC before operation: completed in 9.105 ms, heap usage 6.416 MB -> 3.877 MB. [2025-06-19T12:16:58.681Z] Camera thread performed 122 scans. [2025-06-19T12:16:58.681Z] ====== philosophers (scala) [default], iteration 6 completed (2966.674 ms) ====== [2025-06-19T12:16:58.681Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-19T12:16:58.681Z] GC before operation: completed in 10.360 ms, heap usage 7.133 MB -> 3.347 MB. [2025-06-19T12:17:01.525Z] Camera thread performed 122 scans. [2025-06-19T12:17:01.525Z] ====== philosophers (scala) [default], iteration 7 completed (3184.505 ms) ====== [2025-06-19T12:17:01.525Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-19T12:17:01.525Z] GC before operation: completed in 8.949 ms, heap usage 6.584 MB -> 3.524 MB. [2025-06-19T12:17:04.371Z] Camera thread performed 122 scans. [2025-06-19T12:17:04.371Z] ====== philosophers (scala) [default], iteration 8 completed (3038.662 ms) ====== [2025-06-19T12:17:04.371Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-19T12:17:04.371Z] GC before operation: completed in 8.894 ms, heap usage 6.232 MB -> 3.701 MB. [2025-06-19T12:17:07.217Z] Camera thread performed 122 scans. [2025-06-19T12:17:07.217Z] ====== philosophers (scala) [default], iteration 9 completed (2895.159 ms) ====== [2025-06-19T12:17:07.217Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-19T12:17:07.538Z] GC before operation: completed in 9.893 ms, heap usage 7.449 MB -> 3.876 MB. [2025-06-19T12:17:10.397Z] Camera thread performed 122 scans. [2025-06-19T12:17:10.397Z] ====== philosophers (scala) [default], iteration 10 completed (2925.183 ms) ====== [2025-06-19T12:17:10.398Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-19T12:17:10.398Z] GC before operation: completed in 9.496 ms, heap usage 5.345 MB -> 3.347 MB. [2025-06-19T12:17:13.254Z] Camera thread performed 122 scans. [2025-06-19T12:17:13.254Z] ====== philosophers (scala) [default], iteration 11 completed (3011.929 ms) ====== [2025-06-19T12:17:13.254Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-19T12:17:13.254Z] GC before operation: completed in 9.725 ms, heap usage 4.683 MB -> 3.524 MB. [2025-06-19T12:17:16.906Z] Camera thread performed 122 scans. [2025-06-19T12:17:16.906Z] ====== philosophers (scala) [default], iteration 12 completed (3021.934 ms) ====== [2025-06-19T12:17:16.906Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-19T12:17:16.906Z] GC before operation: completed in 9.065 ms, heap usage 6.277 MB -> 3.700 MB. [2025-06-19T12:17:19.862Z] Camera thread performed 122 scans. [2025-06-19T12:17:19.862Z] ====== philosophers (scala) [default], iteration 13 completed (3098.756 ms) ====== [2025-06-19T12:17:19.862Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-19T12:17:19.862Z] GC before operation: completed in 9.091 ms, heap usage 7.516 MB -> 3.877 MB. [2025-06-19T12:17:22.720Z] Camera thread performed 122 scans. [2025-06-19T12:17:22.720Z] ====== philosophers (scala) [default], iteration 14 completed (3053.973 ms) ====== [2025-06-19T12:17:22.720Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-19T12:17:22.720Z] GC before operation: completed in 8.827 ms, heap usage 5.385 MB -> 3.348 MB. [2025-06-19T12:17:25.574Z] Camera thread performed 122 scans. [2025-06-19T12:17:25.574Z] ====== philosophers (scala) [default], iteration 15 completed (3058.633 ms) ====== [2025-06-19T12:17:25.574Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-19T12:17:25.574Z] GC before operation: completed in 9.784 ms, heap usage 7.609 MB -> 3.525 MB. [2025-06-19T12:17:29.229Z] Camera thread performed 122 scans. [2025-06-19T12:17:29.230Z] ====== philosophers (scala) [default], iteration 16 completed (3002.367 ms) ====== [2025-06-19T12:17:29.230Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-19T12:17:29.230Z] GC before operation: completed in 9.147 ms, heap usage 7.701 MB -> 3.702 MB. [2025-06-19T12:17:31.402Z] Camera thread performed 122 scans. [2025-06-19T12:17:31.402Z] ====== philosophers (scala) [default], iteration 17 completed (2715.999 ms) ====== [2025-06-19T12:17:31.402Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-19T12:17:31.402Z] GC before operation: completed in 9.262 ms, heap usage 4.615 MB -> 3.878 MB. [2025-06-19T12:17:35.051Z] Camera thread performed 122 scans. [2025-06-19T12:17:35.051Z] ====== philosophers (scala) [default], iteration 18 completed (3101.713 ms) ====== [2025-06-19T12:17:35.051Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-19T12:17:35.051Z] GC before operation: completed in 9.235 ms, heap usage 6.124 MB -> 3.348 MB. [2025-06-19T12:17:37.216Z] Camera thread performed 122 scans. [2025-06-19T12:17:37.216Z] ====== philosophers (scala) [default], iteration 19 completed (2781.154 ms) ====== [2025-06-19T12:17:37.216Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-19T12:17:37.216Z] GC before operation: completed in 8.663 ms, heap usage 4.548 MB -> 3.524 MB. [2025-06-19T12:17:40.864Z] Camera thread performed 122 scans. [2025-06-19T12:17:40.864Z] ====== philosophers (scala) [default], iteration 20 completed (3179.254 ms) ====== [2025-06-19T12:17:40.864Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-19T12:17:40.864Z] GC before operation: completed in 8.801 ms, heap usage 4.872 MB -> 3.701 MB. [2025-06-19T12:17:43.722Z] Camera thread performed 122 scans. [2025-06-19T12:17:43.722Z] ====== philosophers (scala) [default], iteration 21 completed (2871.751 ms) ====== [2025-06-19T12:17:43.722Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-19T12:17:43.722Z] GC before operation: completed in 9.040 ms, heap usage 6.733 MB -> 3.878 MB. [2025-06-19T12:17:46.562Z] Camera thread performed 122 scans. [2025-06-19T12:17:46.562Z] ====== philosophers (scala) [default], iteration 22 completed (2967.374 ms) ====== [2025-06-19T12:17:46.562Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-19T12:17:46.562Z] GC before operation: completed in 9.126 ms, heap usage 6.985 MB -> 3.348 MB. [2025-06-19T12:17:49.409Z] Camera thread performed 122 scans. [2025-06-19T12:17:49.409Z] ====== philosophers (scala) [default], iteration 23 completed (3066.895 ms) ====== [2025-06-19T12:17:49.409Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-19T12:17:49.409Z] GC before operation: completed in 9.195 ms, heap usage 5.052 MB -> 3.524 MB. [2025-06-19T12:17:53.053Z] Camera thread performed 122 scans. [2025-06-19T12:17:53.053Z] ====== philosophers (scala) [default], iteration 24 completed (3087.343 ms) ====== [2025-06-19T12:17:53.053Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-19T12:17:53.053Z] GC before operation: completed in 8.932 ms, heap usage 4.571 MB -> 3.701 MB. [2025-06-19T12:17:55.923Z] Camera thread performed 122 scans. [2025-06-19T12:17:55.923Z] ====== philosophers (scala) [default], iteration 25 completed (2982.110 ms) ====== [2025-06-19T12:17:55.923Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-19T12:17:55.923Z] GC before operation: completed in 9.858 ms, heap usage 8.163 MB -> 3.878 MB. [2025-06-19T12:17:58.766Z] Camera thread performed 122 scans. [2025-06-19T12:17:58.766Z] ====== philosophers (scala) [default], iteration 26 completed (2990.363 ms) ====== [2025-06-19T12:17:58.766Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-19T12:17:58.766Z] GC before operation: completed in 8.857 ms, heap usage 4.775 MB -> 3.348 MB. [2025-06-19T12:18:01.619Z] Camera thread performed 122 scans. [2025-06-19T12:18:01.619Z] ====== philosophers (scala) [default], iteration 27 completed (2976.525 ms) ====== [2025-06-19T12:18:01.619Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-19T12:18:01.619Z] GC before operation: completed in 8.583 ms, heap usage 4.055 MB -> 3.525 MB. [2025-06-19T12:18:04.475Z] Camera thread performed 122 scans. [2025-06-19T12:18:04.475Z] ====== philosophers (scala) [default], iteration 28 completed (3008.697 ms) ====== [2025-06-19T12:18:04.475Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-19T12:18:04.475Z] GC before operation: completed in 10.095 ms, heap usage 6.210 MB -> 3.702 MB. [2025-06-19T12:18:08.111Z] Camera thread performed 122 scans. [2025-06-19T12:18:08.111Z] ====== philosophers (scala) [default], iteration 29 completed (3024.553 ms) ====== [2025-06-19T12:18:08.111Z] ----------------------------------- [2025-06-19T12:18:08.111Z] renaissance-philosophers_0_PASSED [2025-06-19T12:18:08.111Z] ----------------------------------- [2025-06-19T12:18:08.111Z] [2025-06-19T12:18:08.111Z] TEST TEARDOWN: [2025-06-19T12:18:08.111Z] Nothing to be done for teardown. [2025-06-19T12:18:08.111Z] renaissance-philosophers_0 Finish Time: Thu Jun 19 12:18:07 2025 Epoch Time (ms): 1750335487574