[2025-06-19T06:50:57.064Z] Running test renaissance-philosophers_0 ... [2025-06-19T06:50:57.064Z] =============================================== [2025-06-19T06:50:57.064Z] renaissance-philosophers_0 Start Time: Thu Jun 19 06:50:56 2025 Epoch Time (ms): 1750315856398 [2025-06-19T06:50:57.065Z] variation: NoOptions [2025-06-19T06:50:57.065Z] JVM_OPTIONS: [2025-06-19T06:50:57.065Z] { \ [2025-06-19T06:50:57.065Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T06:50:57.065Z] echo "Nothing to be done for setup."; \ [2025-06-19T06:50:57.065Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1750311549623/renaissance-philosophers_0"; \ [2025-06-19T06:50:57.065Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1750311549623/renaissance-philosophers_0"; \ [2025-06-19T06:50:57.065Z] echo ""; echo "TESTING:"; \ [2025-06-19T06:50:57.065Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1750311549623/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-19T06:50:57.065Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1750311549623/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T06:50:57.065Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T06:50:57.065Z] echo "Nothing to be done for teardown."; \ [2025-06-19T06:50:57.065Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1750311549623/TestTargetResult"; [2025-06-19T06:50:57.065Z] [2025-06-19T06:50:57.065Z] TEST SETUP: [2025-06-19T06:50:57.065Z] Nothing to be done for setup. [2025-06-19T06:50:57.065Z] [2025-06-19T06:50:57.065Z] TESTING: [2025-06-19T06:50:57.388Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-19T06:50:57.388Z] GC before operation: completed in 15.606 ms, heap usage 4.934 MB -> 4.197 MB. [2025-06-19T06:51:00.325Z] Camera thread performed 122 scans. [2025-06-19T06:51:00.325Z] ====== philosophers (scala) [default], iteration 0 completed (2450.529 ms) ====== [2025-06-19T06:51:00.325Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-19T06:51:00.325Z] GC before operation: completed in 19.412 ms, heap usage 50.955 MB -> 4.619 MB. [2025-06-19T06:51:01.020Z] Camera thread performed 122 scans. [2025-06-19T06:51:01.020Z] ====== philosophers (scala) [default], iteration 1 completed (961.373 ms) ====== [2025-06-19T06:51:01.020Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-19T06:51:01.020Z] GC before operation: completed in 27.195 ms, heap usage 11.031 MB -> 4.620 MB. [2025-06-19T06:51:02.140Z] Camera thread performed 122 scans. [2025-06-19T06:51:02.140Z] ====== philosophers (scala) [default], iteration 2 completed (1010.636 ms) ====== [2025-06-19T06:51:02.140Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-19T06:51:02.140Z] GC before operation: completed in 17.283 ms, heap usage 40.730 MB -> 4.621 MB. [2025-06-19T06:51:03.263Z] Camera thread performed 122 scans. [2025-06-19T06:51:03.263Z] ====== philosophers (scala) [default], iteration 3 completed (1010.108 ms) ====== [2025-06-19T06:51:03.263Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-19T06:51:03.263Z] GC before operation: completed in 15.668 ms, heap usage 29.730 MB -> 4.621 MB. [2025-06-19T06:51:03.956Z] Camera thread performed 122 scans. [2025-06-19T06:51:03.956Z] ====== philosophers (scala) [default], iteration 4 completed (1034.759 ms) ====== [2025-06-19T06:51:03.956Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-19T06:51:04.278Z] GC before operation: completed in 16.483 ms, heap usage 7.262 MB -> 4.622 MB. [2025-06-19T06:51:05.402Z] Camera thread performed 122 scans. [2025-06-19T06:51:05.402Z] ====== philosophers (scala) [default], iteration 5 completed (1015.684 ms) ====== [2025-06-19T06:51:05.402Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-19T06:51:05.402Z] GC before operation: completed in 18.261 ms, heap usage 36.273 MB -> 4.625 MB. [2025-06-19T06:51:06.100Z] Camera thread performed 122 scans. [2025-06-19T06:51:06.100Z] ====== philosophers (scala) [default], iteration 6 completed (1004.274 ms) ====== [2025-06-19T06:51:06.100Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-19T06:51:06.100Z] GC before operation: completed in 16.397 ms, heap usage 9.243 MB -> 4.626 MB. [2025-06-19T06:51:07.281Z] Camera thread performed 122 scans. [2025-06-19T06:51:07.281Z] ====== philosophers (scala) [default], iteration 7 completed (943.830 ms) ====== [2025-06-19T06:51:07.281Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-19T06:51:07.281Z] GC before operation: completed in 17.604 ms, heap usage 42.722 MB -> 4.626 MB. [2025-06-19T06:51:08.405Z] Camera thread performed 122 scans. [2025-06-19T06:51:08.405Z] ====== philosophers (scala) [default], iteration 8 completed (1004.629 ms) ====== [2025-06-19T06:51:08.405Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-19T06:51:08.405Z] GC before operation: completed in 17.329 ms, heap usage 27.244 MB -> 4.626 MB. [2025-06-19T06:51:09.097Z] Camera thread performed 122 scans. [2025-06-19T06:51:09.097Z] ====== philosophers (scala) [default], iteration 9 completed (1004.860 ms) ====== [2025-06-19T06:51:09.097Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-19T06:51:09.097Z] GC before operation: completed in 18.918 ms, heap usage 60.747 MB -> 4.628 MB. [2025-06-19T06:51:10.224Z] Camera thread performed 122 scans. [2025-06-19T06:51:10.224Z] ====== philosophers (scala) [default], iteration 10 completed (911.852 ms) ====== [2025-06-19T06:51:10.224Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-19T06:51:10.224Z] GC before operation: completed in 17.366 ms, heap usage 35.754 MB -> 4.628 MB. [2025-06-19T06:51:11.346Z] Camera thread performed 122 scans. [2025-06-19T06:51:11.346Z] ====== philosophers (scala) [default], iteration 11 completed (985.327 ms) ====== [2025-06-19T06:51:11.346Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-19T06:51:11.346Z] GC before operation: completed in 17.429 ms, heap usage 11.757 MB -> 4.628 MB. [2025-06-19T06:51:12.038Z] Camera thread performed 122 scans. [2025-06-19T06:51:12.038Z] ====== philosophers (scala) [default], iteration 12 completed (1001.971 ms) ====== [2025-06-19T06:51:12.038Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-19T06:51:12.038Z] GC before operation: completed in 17.571 ms, heap usage 31.757 MB -> 4.628 MB. [2025-06-19T06:51:13.159Z] Camera thread performed 122 scans. [2025-06-19T06:51:13.160Z] ====== philosophers (scala) [default], iteration 13 completed (1018.547 ms) ====== [2025-06-19T06:51:13.160Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-19T06:51:13.160Z] GC before operation: completed in 15.432 ms, heap usage 13.257 MB -> 4.628 MB. [2025-06-19T06:51:14.282Z] Camera thread performed 122 scans. [2025-06-19T06:51:14.282Z] ====== philosophers (scala) [default], iteration 14 completed (1013.334 ms) ====== [2025-06-19T06:51:14.282Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-19T06:51:14.282Z] GC before operation: completed in 17.512 ms, heap usage 10.256 MB -> 4.628 MB. [2025-06-19T06:51:15.408Z] Camera thread performed 122 scans. [2025-06-19T06:51:15.408Z] ====== philosophers (scala) [default], iteration 15 completed (985.759 ms) ====== [2025-06-19T06:51:15.408Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-19T06:51:15.408Z] GC before operation: completed in 18.402 ms, heap usage 54.235 MB -> 4.628 MB. [2025-06-19T06:51:16.100Z] Camera thread performed 122 scans. [2025-06-19T06:51:16.100Z] ====== philosophers (scala) [default], iteration 16 completed (962.542 ms) ====== [2025-06-19T06:51:16.100Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-19T06:51:16.100Z] GC before operation: completed in 19.843 ms, heap usage 54.729 MB -> 4.628 MB. [2025-06-19T06:51:17.223Z] Camera thread performed 122 scans. [2025-06-19T06:51:17.223Z] ====== philosophers (scala) [default], iteration 17 completed (997.731 ms) ====== [2025-06-19T06:51:17.223Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-19T06:51:17.223Z] GC before operation: completed in 17.381 ms, heap usage 12.756 MB -> 4.628 MB. [2025-06-19T06:51:18.345Z] Camera thread performed 122 scans. [2025-06-19T06:51:18.345Z] ====== philosophers (scala) [default], iteration 18 completed (988.241 ms) ====== [2025-06-19T06:51:18.345Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-19T06:51:18.345Z] GC before operation: completed in 20.079 ms, heap usage 51.738 MB -> 4.629 MB. [2025-06-19T06:51:19.471Z] Camera thread performed 122 scans. [2025-06-19T06:51:19.471Z] ====== philosophers (scala) [default], iteration 19 completed (986.497 ms) ====== [2025-06-19T06:51:19.471Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-19T06:51:19.471Z] GC before operation: completed in 19.751 ms, heap usage 24.257 MB -> 4.629 MB. [2025-06-19T06:51:20.165Z] Camera thread performed 122 scans. [2025-06-19T06:51:20.165Z] ====== philosophers (scala) [default], iteration 20 completed (990.364 ms) ====== [2025-06-19T06:51:20.165Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-19T06:51:20.165Z] GC before operation: completed in 17.703 ms, heap usage 63.251 MB -> 4.629 MB. [2025-06-19T06:51:21.291Z] Camera thread performed 122 scans. [2025-06-19T06:51:21.291Z] ====== philosophers (scala) [default], iteration 21 completed (1008.140 ms) ====== [2025-06-19T06:51:21.291Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-19T06:51:21.291Z] GC before operation: completed in 18.068 ms, heap usage 23.263 MB -> 4.630 MB. [2025-06-19T06:51:22.416Z] Camera thread performed 122 scans. [2025-06-19T06:51:22.416Z] ====== philosophers (scala) [default], iteration 22 completed (973.026 ms) ====== [2025-06-19T06:51:22.416Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-19T06:51:22.417Z] GC before operation: completed in 18.660 ms, heap usage 34.712 MB -> 4.631 MB. [2025-06-19T06:51:23.108Z] Camera thread performed 122 scans. [2025-06-19T06:51:23.108Z] ====== philosophers (scala) [default], iteration 23 completed (1002.402 ms) ====== [2025-06-19T06:51:23.108Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-19T06:51:23.429Z] GC before operation: completed in 17.633 ms, heap usage 51.254 MB -> 4.631 MB. [2025-06-19T06:51:24.132Z] Camera thread performed 122 scans. [2025-06-19T06:51:24.132Z] ====== philosophers (scala) [default], iteration 24 completed (1006.344 ms) ====== [2025-06-19T06:51:24.133Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-19T06:51:24.454Z] GC before operation: completed in 19.212 ms, heap usage 50.760 MB -> 4.631 MB. [2025-06-19T06:51:25.218Z] Camera thread performed 122 scans. [2025-06-19T06:51:25.218Z] ====== philosophers (scala) [default], iteration 25 completed (1004.665 ms) ====== [2025-06-19T06:51:25.218Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-19T06:51:25.218Z] GC before operation: completed in 18.416 ms, heap usage 27.760 MB -> 4.631 MB. [2025-06-19T06:51:26.340Z] Camera thread performed 122 scans. [2025-06-19T06:51:26.340Z] ====== philosophers (scala) [default], iteration 26 completed (1001.707 ms) ====== [2025-06-19T06:51:26.340Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-19T06:51:26.340Z] GC before operation: completed in 18.150 ms, heap usage 34.260 MB -> 4.631 MB. [2025-06-19T06:51:27.462Z] Camera thread performed 122 scans. [2025-06-19T06:51:27.462Z] ====== philosophers (scala) [default], iteration 27 completed (1003.884 ms) ====== [2025-06-19T06:51:27.462Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-19T06:51:27.462Z] GC before operation: completed in 18.368 ms, heap usage 59.756 MB -> 4.631 MB. [2025-06-19T06:51:28.585Z] Camera thread performed 122 scans. [2025-06-19T06:51:28.585Z] ====== philosophers (scala) [default], iteration 28 completed (1006.988 ms) ====== [2025-06-19T06:51:28.585Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-19T06:51:28.585Z] GC before operation: completed in 17.470 ms, heap usage 14.743 MB -> 4.631 MB. [2025-06-19T06:51:29.286Z] Camera thread performed 122 scans. [2025-06-19T06:51:29.286Z] ====== philosophers (scala) [default], iteration 29 completed (1003.494 ms) ====== [2025-06-19T06:51:29.608Z] ----------------------------------- [2025-06-19T06:51:29.608Z] renaissance-philosophers_0_PASSED [2025-06-19T06:51:29.608Z] ----------------------------------- [2025-06-19T06:51:29.608Z] [2025-06-19T06:51:29.608Z] TEST TEARDOWN: [2025-06-19T06:51:29.608Z] Nothing to be done for teardown. [2025-06-19T06:51:29.608Z] renaissance-philosophers_0 Finish Time: Thu Jun 19 06:51:29 2025 Epoch Time (ms): 1750315889496