[2025-12-11T23:40:10.316Z] Running test renaissance-philosophers_0 ... [2025-12-11T23:40:10.316Z] =============================================== [2025-12-11T23:40:10.316Z] renaissance-philosophers_0 Start Time: Thu Dec 11 23:40:10 2025 Epoch Time (ms): 1765496410105 [2025-12-11T23:40:10.316Z] variation: NoOptions [2025-12-11T23:40:10.316Z] JVM_OPTIONS: [2025-12-11T23:40:10.316Z] { \ [2025-12-11T23:40:10.316Z] echo ""; echo "TEST SETUP:"; \ [2025-12-11T23:40:10.316Z] echo "Nothing to be done for setup."; \ [2025-12-11T23:40:10.316Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/renaissance-philosophers_0"; \ [2025-12-11T23:40:10.316Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/renaissance-philosophers_0"; \ [2025-12-11T23:40:10.316Z] echo ""; echo "TESTING:"; \ [2025-12-11T23:40:10.316Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-12-11T23:40:10.316Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-11T23:40:10.316Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-11T23:40:10.316Z] echo "Nothing to be done for teardown."; \ [2025-12-11T23:40:10.316Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17654933397325/TestTargetResult"; [2025-12-11T23:40:10.316Z] [2025-12-11T23:40:10.316Z] TEST SETUP: [2025-12-11T23:40:10.316Z] Nothing to be done for setup. [2025-12-11T23:40:10.316Z] [2025-12-11T23:40:10.316Z] TESTING: [2025-12-11T23:40:12.271Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-12-11T23:40:12.271Z] GC before operation: completed in 17.695 ms, heap usage 14.765 MB -> 4.847 MB. [2025-12-11T23:40:17.678Z] Camera thread performed 122 scans. [2025-12-11T23:40:17.678Z] ====== philosophers (scala) [default], iteration 0 completed (5557.759 ms) ====== [2025-12-11T23:40:17.678Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-12-11T23:40:17.678Z] GC before operation: completed in 17.209 ms, heap usage 45.460 MB -> 5.201 MB. [2025-12-11T23:40:20.702Z] Camera thread performed 122 scans. [2025-12-11T23:40:20.702Z] ====== philosophers (scala) [default], iteration 1 completed (2965.025 ms) ====== [2025-12-11T23:40:20.702Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-12-11T23:40:20.702Z] GC before operation: completed in 14.397 ms, heap usage 84.333 MB -> 5.202 MB. [2025-12-11T23:40:22.662Z] Camera thread performed 122 scans. [2025-12-11T23:40:22.662Z] ====== philosophers (scala) [default], iteration 2 completed (2523.565 ms) ====== [2025-12-11T23:40:22.662Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-12-11T23:40:22.662Z] GC before operation: completed in 15.057 ms, heap usage 26.191 MB -> 5.203 MB. [2025-12-11T23:40:25.691Z] Camera thread performed 122 scans. [2025-12-11T23:40:25.691Z] ====== philosophers (scala) [default], iteration 3 completed (2389.729 ms) ====== [2025-12-11T23:40:25.691Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-12-11T23:40:25.691Z] GC before operation: completed in 14.383 ms, heap usage 55.030 MB -> 5.203 MB. [2025-12-11T23:40:27.647Z] Camera thread performed 122 scans. [2025-12-11T23:40:27.647Z] ====== philosophers (scala) [default], iteration 4 completed (2464.479 ms) ====== [2025-12-11T23:40:27.647Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-12-11T23:40:27.647Z] GC before operation: completed in 14.793 ms, heap usage 42.506 MB -> 5.204 MB. [2025-12-11T23:40:30.678Z] Camera thread performed 122 scans. [2025-12-11T23:40:30.678Z] ====== philosophers (scala) [default], iteration 5 completed (2290.485 ms) ====== [2025-12-11T23:40:30.678Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-12-11T23:40:30.678Z] GC before operation: completed in 15.395 ms, heap usage 46.974 MB -> 5.204 MB. [2025-12-11T23:40:32.638Z] Camera thread performed 122 scans. [2025-12-11T23:40:32.638Z] ====== philosophers (scala) [default], iteration 6 completed (2455.286 ms) ====== [2025-12-11T23:40:32.638Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-12-11T23:40:32.638Z] GC before operation: completed in 15.527 ms, heap usage 60.807 MB -> 5.204 MB. [2025-12-11T23:40:36.043Z] Camera thread performed 122 scans. [2025-12-11T23:40:36.043Z] ====== philosophers (scala) [default], iteration 7 completed (2466.189 ms) ====== [2025-12-11T23:40:36.043Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-12-11T23:40:36.043Z] GC before operation: completed in 14.528 ms, heap usage 26.420 MB -> 5.205 MB. [2025-12-11T23:40:38.007Z] Camera thread performed 122 scans. [2025-12-11T23:40:38.007Z] ====== philosophers (scala) [default], iteration 8 completed (2457.653 ms) ====== [2025-12-11T23:40:38.007Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-12-11T23:40:38.007Z] GC before operation: completed in 13.746 ms, heap usage 29.245 MB -> 5.206 MB. [2025-12-11T23:40:39.966Z] Camera thread performed 122 scans. [2025-12-11T23:40:39.966Z] ====== philosophers (scala) [default], iteration 9 completed (2488.460 ms) ====== [2025-12-11T23:40:39.966Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-12-11T23:40:39.966Z] GC before operation: completed in 15.924 ms, heap usage 39.209 MB -> 5.205 MB. [2025-12-11T23:40:43.171Z] Camera thread performed 122 scans. [2025-12-11T23:40:43.171Z] ====== philosophers (scala) [default], iteration 10 completed (2457.780 ms) ====== [2025-12-11T23:40:43.171Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-12-11T23:40:43.171Z] GC before operation: completed in 16.865 ms, heap usage 64.897 MB -> 5.206 MB. [2025-12-11T23:40:45.135Z] Camera thread performed 122 scans. [2025-12-11T23:40:45.135Z] ====== philosophers (scala) [default], iteration 11 completed (2525.781 ms) ====== [2025-12-11T23:40:45.135Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-12-11T23:40:45.135Z] GC before operation: completed in 13.982 ms, heap usage 23.847 MB -> 5.206 MB. [2025-12-11T23:40:48.159Z] Camera thread performed 122 scans. [2025-12-11T23:40:48.159Z] ====== philosophers (scala) [default], iteration 12 completed (2425.258 ms) ====== [2025-12-11T23:40:48.159Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-12-11T23:40:48.159Z] GC before operation: completed in 14.419 ms, heap usage 84.220 MB -> 5.206 MB. [2025-12-11T23:40:50.130Z] Camera thread performed 122 scans. [2025-12-11T23:40:50.130Z] ====== philosophers (scala) [default], iteration 13 completed (2501.274 ms) ====== [2025-12-11T23:40:50.130Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-12-11T23:40:50.130Z] GC before operation: completed in 13.896 ms, heap usage 40.426 MB -> 5.206 MB. [2025-12-11T23:40:52.452Z] Camera thread performed 122 scans. [2025-12-11T23:40:52.452Z] ====== philosophers (scala) [default], iteration 14 completed (2445.728 ms) ====== [2025-12-11T23:40:52.452Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-12-11T23:40:52.452Z] GC before operation: completed in 14.472 ms, heap usage 11.931 MB -> 5.207 MB. [2025-12-11T23:40:55.516Z] Camera thread performed 122 scans. [2025-12-11T23:40:55.516Z] ====== philosophers (scala) [default], iteration 15 completed (2542.682 ms) ====== [2025-12-11T23:40:55.516Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-12-11T23:40:55.516Z] GC before operation: completed in 15.502 ms, heap usage 80.830 MB -> 5.206 MB. [2025-12-11T23:40:57.480Z] Camera thread performed 122 scans. [2025-12-11T23:40:57.480Z] ====== philosophers (scala) [default], iteration 16 completed (2447.392 ms) ====== [2025-12-11T23:40:57.480Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-12-11T23:40:57.480Z] GC before operation: completed in 16.974 ms, heap usage 53.362 MB -> 5.207 MB. [2025-12-11T23:41:00.507Z] Camera thread performed 122 scans. [2025-12-11T23:41:00.507Z] ====== philosophers (scala) [default], iteration 17 completed (2456.135 ms) ====== [2025-12-11T23:41:00.507Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-12-11T23:41:00.507Z] GC before operation: completed in 14.536 ms, heap usage 78.222 MB -> 5.207 MB. [2025-12-11T23:41:02.465Z] Camera thread performed 122 scans. [2025-12-11T23:41:02.465Z] ====== philosophers (scala) [default], iteration 18 completed (2505.786 ms) ====== [2025-12-11T23:41:02.465Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-12-11T23:41:02.465Z] GC before operation: completed in 14.607 ms, heap usage 28.913 MB -> 5.207 MB. [2025-12-11T23:41:05.535Z] Camera thread performed 122 scans. [2025-12-11T23:41:05.535Z] ====== philosophers (scala) [default], iteration 19 completed (2475.917 ms) ====== [2025-12-11T23:41:05.535Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-12-11T23:41:05.535Z] GC before operation: completed in 14.126 ms, heap usage 43.291 MB -> 5.207 MB. [2025-12-11T23:41:07.873Z] Camera thread performed 122 scans. [2025-12-11T23:41:07.873Z] ====== philosophers (scala) [default], iteration 20 completed (2393.410 ms) ====== [2025-12-11T23:41:07.873Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-12-11T23:41:07.873Z] GC before operation: completed in 14.440 ms, heap usage 49.845 MB -> 5.207 MB. [2025-12-11T23:41:09.836Z] Camera thread performed 122 scans. [2025-12-11T23:41:09.836Z] ====== philosophers (scala) [default], iteration 21 completed (2436.546 ms) ====== [2025-12-11T23:41:09.836Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-12-11T23:41:09.836Z] GC before operation: completed in 14.830 ms, heap usage 42.266 MB -> 5.207 MB. [2025-12-11T23:41:12.879Z] Camera thread performed 122 scans. [2025-12-11T23:41:12.879Z] ====== philosophers (scala) [default], iteration 22 completed (2456.915 ms) ====== [2025-12-11T23:41:12.879Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-12-11T23:41:12.879Z] GC before operation: completed in 14.186 ms, heap usage 55.850 MB -> 5.207 MB. [2025-12-11T23:41:14.880Z] Camera thread performed 122 scans. [2025-12-11T23:41:14.880Z] ====== philosophers (scala) [default], iteration 23 completed (2474.845 ms) ====== [2025-12-11T23:41:14.880Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-12-11T23:41:14.880Z] GC before operation: completed in 14.190 ms, heap usage 84.212 MB -> 5.207 MB. [2025-12-11T23:41:17.909Z] Camera thread performed 122 scans. [2025-12-11T23:41:17.909Z] ====== philosophers (scala) [default], iteration 24 completed (2524.053 ms) ====== [2025-12-11T23:41:17.909Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-12-11T23:41:17.909Z] GC before operation: completed in 15.100 ms, heap usage 62.444 MB -> 5.207 MB. [2025-12-11T23:41:19.939Z] Camera thread performed 122 scans. [2025-12-11T23:41:19.939Z] ====== philosophers (scala) [default], iteration 25 completed (2565.044 ms) ====== [2025-12-11T23:41:19.939Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-12-11T23:41:19.939Z] GC before operation: completed in 14.247 ms, heap usage 14.292 MB -> 5.207 MB. [2025-12-11T23:41:22.979Z] Camera thread performed 122 scans. [2025-12-11T23:41:22.979Z] ====== philosophers (scala) [default], iteration 26 completed (2516.878 ms) ====== [2025-12-11T23:41:22.979Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-12-11T23:41:22.979Z] GC before operation: completed in 17.152 ms, heap usage 56.853 MB -> 5.207 MB. [2025-12-11T23:41:24.942Z] Camera thread performed 122 scans. [2025-12-11T23:41:24.942Z] ====== philosophers (scala) [default], iteration 27 completed (2512.848 ms) ====== [2025-12-11T23:41:24.942Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-12-11T23:41:24.942Z] GC before operation: completed in 14.389 ms, heap usage 57.803 MB -> 5.207 MB. [2025-12-11T23:41:27.973Z] Camera thread performed 122 scans. [2025-12-11T23:41:27.973Z] ====== philosophers (scala) [default], iteration 28 completed (2618.456 ms) ====== [2025-12-11T23:41:27.973Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-12-11T23:41:27.973Z] GC before operation: completed in 14.408 ms, heap usage 6.259 MB -> 5.208 MB. [2025-12-11T23:41:29.928Z] Camera thread performed 122 scans. [2025-12-11T23:41:29.929Z] ====== philosophers (scala) [default], iteration 29 completed (2523.781 ms) ====== [2025-12-11T23:41:30.882Z] ----------------------------------- [2025-12-11T23:41:30.882Z] renaissance-philosophers_0_PASSED [2025-12-11T23:41:30.882Z] ----------------------------------- [2025-12-11T23:41:30.882Z] [2025-12-11T23:41:30.882Z] TEST TEARDOWN: [2025-12-11T23:41:30.882Z] Nothing to be done for teardown. [2025-12-11T23:41:30.882Z] renaissance-philosophers_0 Finish Time: Thu Dec 11 23:41:30 2025 Epoch Time (ms): 1765496490094