[2025-05-07T21:39:38.169Z] Running test renaissance-philosophers_0 ... [2025-05-07T21:39:38.169Z] =============================================== [2025-05-07T21:39:38.169Z] renaissance-philosophers_0 Start Time: Wed May 7 21:39:37 2025 Epoch Time (ms): 1746653977691 [2025-05-07T21:39:38.169Z] variation: NoOptions [2025-05-07T21:39:38.169Z] JVM_OPTIONS: [2025-05-07T21:39:38.169Z] { \ [2025-05-07T21:39:38.169Z] echo ""; echo "TEST SETUP:"; \ [2025-05-07T21:39:38.169Z] echo "Nothing to be done for setup."; \ [2025-05-07T21:39:38.169Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466531222037/renaissance-philosophers_0"; \ [2025-05-07T21:39:38.169Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466531222037/renaissance-philosophers_0"; \ [2025-05-07T21:39:38.169Z] echo ""; echo "TESTING:"; \ [2025-05-07T21:39:38.169Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466531222037/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-07T21:39:38.169Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466531222037/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-07T21:39:38.169Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-07T21:39:38.170Z] echo "Nothing to be done for teardown."; \ [2025-05-07T21:39:38.170Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466531222037/TestTargetResult"; [2025-05-07T21:39:38.170Z] [2025-05-07T21:39:38.170Z] TEST SETUP: [2025-05-07T21:39:38.170Z] Nothing to be done for setup. [2025-05-07T21:39:38.170Z] [2025-05-07T21:39:38.170Z] TESTING: [2025-05-07T21:39:40.622Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-07T21:39:40.622Z] GC before operation: completed in 41.169 ms, heap usage 14.729 MB -> 4.902 MB. [2025-05-07T21:39:48.927Z] Camera thread performed 122 scans. [2025-05-07T21:39:48.928Z] ====== philosophers (scala) [default], iteration 0 completed (8112.185 ms) ====== [2025-05-07T21:39:48.928Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-07T21:39:48.928Z] GC before operation: completed in 50.689 ms, heap usage 57.041 MB -> 5.272 MB. [2025-05-07T21:39:52.387Z] Camera thread performed 122 scans. [2025-05-07T21:39:52.387Z] ====== philosophers (scala) [default], iteration 1 completed (3301.718 ms) ====== [2025-05-07T21:39:52.387Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-07T21:39:52.387Z] GC before operation: completed in 49.913 ms, heap usage 29.679 MB -> 5.273 MB. [2025-05-07T21:39:54.893Z] Camera thread performed 122 scans. [2025-05-07T21:39:54.893Z] ====== philosophers (scala) [default], iteration 2 completed (2667.322 ms) ====== [2025-05-07T21:39:54.893Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-07T21:39:54.893Z] GC before operation: completed in 58.035 ms, heap usage 34.554 MB -> 5.273 MB. [2025-05-07T21:39:57.380Z] Camera thread performed 122 scans. [2025-05-07T21:39:57.380Z] ====== philosophers (scala) [default], iteration 3 completed (2584.939 ms) ====== [2025-05-07T21:39:57.380Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-07T21:39:57.380Z] GC before operation: completed in 41.351 ms, heap usage 33.705 MB -> 5.273 MB. [2025-05-07T21:39:59.829Z] Camera thread performed 122 scans. [2025-05-07T21:39:59.829Z] ====== philosophers (scala) [default], iteration 4 completed (2575.960 ms) ====== [2025-05-07T21:39:59.829Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-07T21:40:00.591Z] GC before operation: completed in 56.865 ms, heap usage 25.963 MB -> 5.273 MB. [2025-05-07T21:40:03.041Z] Camera thread performed 122 scans. [2025-05-07T21:40:03.041Z] ====== philosophers (scala) [default], iteration 5 completed (2996.271 ms) ====== [2025-05-07T21:40:03.041Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-07T21:40:03.041Z] GC before operation: completed in 52.298 ms, heap usage 36.695 MB -> 5.273 MB. [2025-05-07T21:40:06.448Z] Camera thread performed 122 scans. [2025-05-07T21:40:06.448Z] ====== philosophers (scala) [default], iteration 6 completed (3380.729 ms) ====== [2025-05-07T21:40:06.448Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-07T21:40:06.448Z] GC before operation: completed in 34.186 ms, heap usage 27.968 MB -> 5.275 MB. [2025-05-07T21:40:09.895Z] Camera thread performed 122 scans. [2025-05-07T21:40:09.895Z] ====== philosophers (scala) [default], iteration 7 completed (2624.497 ms) ====== [2025-05-07T21:40:09.895Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-07T21:40:09.895Z] GC before operation: completed in 60.573 ms, heap usage 30.714 MB -> 5.275 MB. [2025-05-07T21:40:12.379Z] Camera thread performed 122 scans. [2025-05-07T21:40:12.379Z] ====== philosophers (scala) [default], iteration 8 completed (3039.954 ms) ====== [2025-05-07T21:40:12.379Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-07T21:40:12.379Z] GC before operation: completed in 57.184 ms, heap usage 24.414 MB -> 5.274 MB. [2025-05-07T21:40:15.905Z] Camera thread performed 122 scans. [2025-05-07T21:40:15.905Z] ====== philosophers (scala) [default], iteration 9 completed (2964.290 ms) ====== [2025-05-07T21:40:15.905Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-07T21:40:15.905Z] GC before operation: completed in 49.314 ms, heap usage 20.019 MB -> 5.275 MB. [2025-05-07T21:40:18.388Z] Camera thread performed 122 scans. [2025-05-07T21:40:18.388Z] ====== philosophers (scala) [default], iteration 10 completed (2502.470 ms) ====== [2025-05-07T21:40:18.388Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-07T21:40:18.388Z] GC before operation: completed in 39.709 ms, heap usage 29.056 MB -> 5.275 MB. [2025-05-07T21:40:20.837Z] Camera thread performed 122 scans. [2025-05-07T21:40:20.837Z] ====== philosophers (scala) [default], iteration 11 completed (2711.009 ms) ====== [2025-05-07T21:40:20.837Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-07T21:40:20.837Z] GC before operation: completed in 36.236 ms, heap usage 17.045 MB -> 5.276 MB. [2025-05-07T21:40:23.419Z] Camera thread performed 122 scans. [2025-05-07T21:40:23.419Z] ====== philosophers (scala) [default], iteration 12 completed (2450.611 ms) ====== [2025-05-07T21:40:23.419Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-07T21:40:23.419Z] GC before operation: completed in 37.624 ms, heap usage 8.418 MB -> 5.277 MB. [2025-05-07T21:40:25.908Z] Camera thread performed 122 scans. [2025-05-07T21:40:25.908Z] ====== philosophers (scala) [default], iteration 13 completed (2485.873 ms) ====== [2025-05-07T21:40:25.908Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-07T21:40:25.908Z] GC before operation: completed in 42.930 ms, heap usage 36.880 MB -> 5.277 MB. [2025-05-07T21:40:28.356Z] Camera thread performed 122 scans. [2025-05-07T21:40:28.356Z] ====== philosophers (scala) [default], iteration 14 completed (2595.048 ms) ====== [2025-05-07T21:40:28.356Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-07T21:40:28.356Z] GC before operation: completed in 42.824 ms, heap usage 43.468 MB -> 5.277 MB. [2025-05-07T21:40:30.808Z] Camera thread performed 122 scans. [2025-05-07T21:40:30.808Z] ====== philosophers (scala) [default], iteration 15 completed (2685.407 ms) ====== [2025-05-07T21:40:30.808Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-07T21:40:31.569Z] GC before operation: completed in 43.319 ms, heap usage 36.079 MB -> 5.277 MB. [2025-05-07T21:40:34.024Z] Camera thread performed 122 scans. [2025-05-07T21:40:34.024Z] ====== philosophers (scala) [default], iteration 16 completed (2527.547 ms) ====== [2025-05-07T21:40:34.024Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-07T21:40:34.024Z] GC before operation: completed in 40.964 ms, heap usage 23.923 MB -> 5.277 MB. [2025-05-07T21:40:36.568Z] Camera thread performed 122 scans. [2025-05-07T21:40:36.568Z] ====== philosophers (scala) [default], iteration 17 completed (2811.242 ms) ====== [2025-05-07T21:40:36.568Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-07T21:40:36.568Z] GC before operation: completed in 50.287 ms, heap usage 30.695 MB -> 5.276 MB. [2025-05-07T21:40:40.026Z] Camera thread performed 122 scans. [2025-05-07T21:40:40.026Z] ====== philosophers (scala) [default], iteration 18 completed (3274.668 ms) ====== [2025-05-07T21:40:40.026Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-07T21:40:40.026Z] GC before operation: completed in 56.567 ms, heap usage 20.725 MB -> 5.311 MB. [2025-05-07T21:40:43.506Z] Camera thread performed 122 scans. [2025-05-07T21:40:43.506Z] ====== philosophers (scala) [default], iteration 19 completed (3268.432 ms) ====== [2025-05-07T21:40:43.506Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-07T21:40:43.506Z] GC before operation: completed in 60.928 ms, heap usage 7.240 MB -> 5.277 MB. [2025-05-07T21:40:47.044Z] Camera thread performed 122 scans. [2025-05-07T21:40:47.044Z] ====== philosophers (scala) [default], iteration 20 completed (3279.214 ms) ====== [2025-05-07T21:40:47.044Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-07T21:40:47.044Z] GC before operation: completed in 46.856 ms, heap usage 8.929 MB -> 5.278 MB. [2025-05-07T21:40:49.529Z] Camera thread performed 122 scans. [2025-05-07T21:40:49.529Z] ====== philosophers (scala) [default], iteration 21 completed (3175.179 ms) ====== [2025-05-07T21:40:49.529Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-07T21:40:49.529Z] GC before operation: completed in 73.367 ms, heap usage 43.702 MB -> 5.277 MB. [2025-05-07T21:40:52.992Z] Camera thread performed 122 scans. [2025-05-07T21:40:52.992Z] ====== philosophers (scala) [default], iteration 22 completed (3324.390 ms) ====== [2025-05-07T21:40:52.992Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-07T21:40:52.992Z] GC before operation: completed in 41.912 ms, heap usage 48.878 MB -> 5.277 MB. [2025-05-07T21:40:56.444Z] Camera thread performed 122 scans. [2025-05-07T21:40:56.444Z] ====== philosophers (scala) [default], iteration 23 completed (2823.524 ms) ====== [2025-05-07T21:40:56.444Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-07T21:40:56.444Z] GC before operation: completed in 39.258 ms, heap usage 33.109 MB -> 5.277 MB. [2025-05-07T21:40:58.925Z] Camera thread performed 122 scans. [2025-05-07T21:40:58.925Z] ====== philosophers (scala) [default], iteration 24 completed (2471.806 ms) ====== [2025-05-07T21:40:58.925Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-07T21:40:58.925Z] GC before operation: completed in 37.283 ms, heap usage 10.767 MB -> 5.278 MB. [2025-05-07T21:41:01.415Z] Camera thread performed 122 scans. [2025-05-07T21:41:01.415Z] ====== philosophers (scala) [default], iteration 25 completed (2308.270 ms) ====== [2025-05-07T21:41:01.415Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-07T21:41:01.415Z] GC before operation: completed in 42.668 ms, heap usage 52.025 MB -> 5.277 MB. [2025-05-07T21:41:03.875Z] Camera thread performed 122 scans. [2025-05-07T21:41:03.875Z] ====== philosophers (scala) [default], iteration 26 completed (2625.976 ms) ====== [2025-05-07T21:41:03.875Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-07T21:41:03.875Z] GC before operation: completed in 73.628 ms, heap usage 28.046 MB -> 5.277 MB. [2025-05-07T21:41:06.345Z] Camera thread performed 122 scans. [2025-05-07T21:41:06.345Z] ====== philosophers (scala) [default], iteration 27 completed (2577.118 ms) ====== [2025-05-07T21:41:06.345Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-07T21:41:06.345Z] GC before operation: completed in 39.801 ms, heap usage 49.192 MB -> 5.277 MB. [2025-05-07T21:41:08.818Z] Camera thread performed 122 scans. [2025-05-07T21:41:08.818Z] ====== philosophers (scala) [default], iteration 28 completed (2751.264 ms) ====== [2025-05-07T21:41:08.818Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-07T21:41:08.818Z] GC before operation: completed in 47.020 ms, heap usage 22.790 MB -> 5.278 MB. [2025-05-07T21:41:12.295Z] Camera thread performed 122 scans. [2025-05-07T21:41:12.295Z] ====== philosophers (scala) [default], iteration 29 completed (3081.332 ms) ====== [2025-05-07T21:41:13.067Z] ----------------------------------- [2025-05-07T21:41:13.067Z] renaissance-philosophers_0_PASSED [2025-05-07T21:41:13.067Z] ----------------------------------- [2025-05-07T21:41:13.067Z] [2025-05-07T21:41:13.067Z] TEST TEARDOWN: [2025-05-07T21:41:13.067Z] Nothing to be done for teardown. [2025-05-07T21:41:13.067Z] renaissance-philosophers_0 Finish Time: Wed May 7 21:41:12 2025 Epoch Time (ms): 1746654072516