[2025-08-01T21:45:49.024Z] Running test renaissance-philosophers_0 ... [2025-08-01T21:45:49.024Z] =============================================== [2025-08-01T21:45:49.024Z] renaissance-philosophers_0 Start Time: Fri Aug 1 16:45:46 2025 Epoch Time (ms): 1754084746717 [2025-08-01T21:45:49.024Z] variation: NoOptions [2025-08-01T21:45:49.024Z] JVM_OPTIONS: [2025-08-01T21:45:49.024Z] { \ [2025-08-01T21:45:49.024Z] echo ""; echo "TEST SETUP:"; \ [2025-08-01T21:45:49.024Z] echo "Nothing to be done for setup."; \ [2025-08-01T21:45:49.024Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17540827195805/renaissance-philosophers_0"; \ [2025-08-01T21:45:49.024Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17540827195805/renaissance-philosophers_0"; \ [2025-08-01T21:45:49.024Z] echo ""; echo "TESTING:"; \ [2025-08-01T21:45:49.024Z] "/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_17540827195805/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-08-01T21:45:49.024Z] 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_17540827195805/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-01T21:45:49.024Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-01T21:45:49.024Z] echo "Nothing to be done for teardown."; \ [2025-08-01T21:45:49.024Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17540827195805/TestTargetResult"; [2025-08-01T21:45:49.024Z] [2025-08-01T21:45:49.024Z] TEST SETUP: [2025-08-01T21:45:49.024Z] Nothing to be done for setup. [2025-08-01T21:45:49.024Z] [2025-08-01T21:45:49.024Z] TESTING: [2025-08-01T21:45:50.403Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-08-01T21:45:50.403Z] GC before operation: completed in 31.340 ms, heap usage 6.591 MB -> 4.414 MB. [2025-08-01T21:45:55.430Z] Camera thread performed 122 scans. [2025-08-01T21:45:55.430Z] ====== philosophers (scala) [default], iteration 0 completed (5421.399 ms) ====== [2025-08-01T21:45:55.430Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-08-01T21:45:55.430Z] GC before operation: completed in 37.433 ms, heap usage 42.295 MB -> 4.843 MB. [2025-08-01T21:45:58.433Z] Camera thread performed 122 scans. [2025-08-01T21:45:58.433Z] ====== philosophers (scala) [default], iteration 1 completed (2461.271 ms) ====== [2025-08-01T21:45:58.433Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-08-01T21:45:58.433Z] GC before operation: completed in 50.033 ms, heap usage 96.227 MB -> 4.847 MB. [2025-08-01T21:46:00.600Z] Camera thread performed 122 scans. [2025-08-01T21:46:00.600Z] ====== philosophers (scala) [default], iteration 2 completed (2516.703 ms) ====== [2025-08-01T21:46:00.600Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-08-01T21:46:00.601Z] GC before operation: completed in 45.485 ms, heap usage 107.109 MB -> 4.848 MB. [2025-08-01T21:46:02.779Z] Camera thread performed 122 scans. [2025-08-01T21:46:02.779Z] ====== philosophers (scala) [default], iteration 3 completed (2449.318 ms) ====== [2025-08-01T21:46:02.779Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-08-01T21:46:02.779Z] GC before operation: completed in 44.082 ms, heap usage 55.228 MB -> 4.848 MB. [2025-08-01T21:46:05.814Z] Camera thread performed 122 scans. [2025-08-01T21:46:05.814Z] ====== philosophers (scala) [default], iteration 4 completed (2385.020 ms) ====== [2025-08-01T21:46:05.814Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-08-01T21:46:05.814Z] GC before operation: completed in 44.369 ms, heap usage 36.658 MB -> 4.848 MB. [2025-08-01T21:46:07.976Z] Camera thread performed 122 scans. [2025-08-01T21:46:07.976Z] ====== philosophers (scala) [default], iteration 5 completed (2720.713 ms) ====== [2025-08-01T21:46:07.976Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-08-01T21:46:07.976Z] GC before operation: completed in 42.963 ms, heap usage 15.791 MB -> 4.849 MB. [2025-08-01T21:46:11.015Z] Camera thread performed 122 scans. [2025-08-01T21:46:11.015Z] ====== philosophers (scala) [default], iteration 6 completed (2454.377 ms) ====== [2025-08-01T21:46:11.015Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-08-01T21:46:11.015Z] GC before operation: completed in 30.495 ms, heap usage 47.213 MB -> 4.849 MB. [2025-08-01T21:46:13.210Z] Camera thread performed 122 scans. [2025-08-01T21:46:13.210Z] ====== philosophers (scala) [default], iteration 7 completed (2658.438 ms) ====== [2025-08-01T21:46:13.210Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-08-01T21:46:13.210Z] GC before operation: completed in 37.732 ms, heap usage 41.935 MB -> 4.849 MB. [2025-08-01T21:46:16.241Z] Camera thread performed 122 scans. [2025-08-01T21:46:16.241Z] ====== philosophers (scala) [default], iteration 8 completed (2347.340 ms) ====== [2025-08-01T21:46:16.241Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-08-01T21:46:16.241Z] GC before operation: completed in 41.184 ms, heap usage 69.253 MB -> 4.850 MB. [2025-08-01T21:46:18.429Z] Camera thread performed 122 scans. [2025-08-01T21:46:18.429Z] ====== philosophers (scala) [default], iteration 9 completed (2384.019 ms) ====== [2025-08-01T21:46:18.429Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-08-01T21:46:18.429Z] GC before operation: completed in 31.340 ms, heap usage 5.703 MB -> 4.850 MB. [2025-08-01T21:46:20.611Z] Camera thread performed 122 scans. [2025-08-01T21:46:20.611Z] ====== philosophers (scala) [default], iteration 10 completed (2459.843 ms) ====== [2025-08-01T21:46:20.611Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-08-01T21:46:20.611Z] GC before operation: completed in 38.466 ms, heap usage 92.251 MB -> 4.850 MB. [2025-08-01T21:46:23.648Z] Camera thread performed 122 scans. [2025-08-01T21:46:23.648Z] ====== philosophers (scala) [default], iteration 11 completed (2719.285 ms) ====== [2025-08-01T21:46:23.648Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-08-01T21:46:23.648Z] GC before operation: completed in 34.802 ms, heap usage 18.256 MB -> 4.850 MB. [2025-08-01T21:46:26.684Z] Camera thread performed 122 scans. [2025-08-01T21:46:26.684Z] ====== philosophers (scala) [default], iteration 12 completed (2640.972 ms) ====== [2025-08-01T21:46:26.684Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-08-01T21:46:26.684Z] GC before operation: completed in 47.367 ms, heap usage 102.038 MB -> 4.850 MB. [2025-08-01T21:46:28.859Z] Camera thread performed 122 scans. [2025-08-01T21:46:28.859Z] ====== philosophers (scala) [default], iteration 13 completed (2580.831 ms) ====== [2025-08-01T21:46:28.859Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-08-01T21:46:28.859Z] GC before operation: completed in 47.501 ms, heap usage 75.094 MB -> 4.850 MB. [2025-08-01T21:46:32.347Z] Camera thread performed 122 scans. [2025-08-01T21:46:32.347Z] ====== philosophers (scala) [default], iteration 14 completed (2755.159 ms) ====== [2025-08-01T21:46:32.347Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-08-01T21:46:32.347Z] GC before operation: completed in 33.264 ms, heap usage 7.982 MB -> 4.850 MB. [2025-08-01T21:46:34.512Z] Camera thread performed 122 scans. [2025-08-01T21:46:34.512Z] ====== philosophers (scala) [default], iteration 15 completed (2609.455 ms) ====== [2025-08-01T21:46:34.512Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-08-01T21:46:34.512Z] GC before operation: completed in 33.868 ms, heap usage 40.738 MB -> 4.850 MB. [2025-08-01T21:46:36.668Z] Camera thread performed 122 scans. [2025-08-01T21:46:36.668Z] ====== philosophers (scala) [default], iteration 16 completed (2408.556 ms) ====== [2025-08-01T21:46:36.668Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-08-01T21:46:36.668Z] GC before operation: completed in 48.505 ms, heap usage 96.797 MB -> 4.850 MB. [2025-08-01T21:46:39.680Z] Camera thread performed 122 scans. [2025-08-01T21:46:39.680Z] ====== philosophers (scala) [default], iteration 17 completed (2966.840 ms) ====== [2025-08-01T21:46:39.680Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-08-01T21:46:39.680Z] GC before operation: completed in 41.179 ms, heap usage 36.062 MB -> 4.850 MB. [2025-08-01T21:46:42.756Z] Camera thread performed 122 scans. [2025-08-01T21:46:42.756Z] ====== philosophers (scala) [default], iteration 18 completed (2677.671 ms) ====== [2025-08-01T21:46:42.756Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-08-01T21:46:42.756Z] GC before operation: completed in 35.629 ms, heap usage 60.256 MB -> 4.851 MB. [2025-08-01T21:46:44.913Z] Camera thread performed 122 scans. [2025-08-01T21:46:44.913Z] ====== philosophers (scala) [default], iteration 19 completed (2586.045 ms) ====== [2025-08-01T21:46:44.913Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-08-01T21:46:44.913Z] GC before operation: completed in 36.504 ms, heap usage 23.795 MB -> 4.851 MB. [2025-08-01T21:46:47.952Z] Camera thread performed 122 scans. [2025-08-01T21:46:47.952Z] ====== philosophers (scala) [default], iteration 20 completed (2363.042 ms) ====== [2025-08-01T21:46:47.952Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-08-01T21:46:47.952Z] GC before operation: completed in 47.163 ms, heap usage 55.924 MB -> 4.851 MB. [2025-08-01T21:46:50.103Z] Camera thread performed 122 scans. [2025-08-01T21:46:50.103Z] ====== philosophers (scala) [default], iteration 21 completed (2288.299 ms) ====== [2025-08-01T21:46:50.103Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-08-01T21:46:50.103Z] GC before operation: completed in 42.036 ms, heap usage 18.106 MB -> 4.851 MB. [2025-08-01T21:46:52.265Z] Camera thread performed 122 scans. [2025-08-01T21:46:52.265Z] ====== philosophers (scala) [default], iteration 22 completed (2435.685 ms) ====== [2025-08-01T21:46:52.265Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-08-01T21:46:52.265Z] GC before operation: completed in 49.047 ms, heap usage 93.536 MB -> 4.851 MB. [2025-08-01T21:46:55.275Z] Camera thread performed 122 scans. [2025-08-01T21:46:55.275Z] ====== philosophers (scala) [default], iteration 23 completed (2557.214 ms) ====== [2025-08-01T21:46:55.275Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-08-01T21:46:55.275Z] GC before operation: completed in 38.645 ms, heap usage 37.751 MB -> 4.851 MB. [2025-08-01T21:46:57.431Z] Camera thread performed 122 scans. [2025-08-01T21:46:57.431Z] ====== philosophers (scala) [default], iteration 24 completed (2692.653 ms) ====== [2025-08-01T21:46:57.431Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-08-01T21:46:57.431Z] GC before operation: completed in 33.408 ms, heap usage 26.887 MB -> 4.851 MB. [2025-08-01T21:47:00.467Z] Camera thread performed 122 scans. [2025-08-01T21:47:00.467Z] ====== philosophers (scala) [default], iteration 25 completed (2595.090 ms) ====== [2025-08-01T21:47:00.467Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-08-01T21:47:00.467Z] GC before operation: completed in 46.476 ms, heap usage 41.286 MB -> 4.851 MB. [2025-08-01T21:47:02.673Z] Camera thread performed 122 scans. [2025-08-01T21:47:02.673Z] ====== philosophers (scala) [default], iteration 26 completed (2664.703 ms) ====== [2025-08-01T21:47:02.673Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-08-01T21:47:02.673Z] GC before operation: completed in 34.986 ms, heap usage 44.730 MB -> 4.851 MB. [2025-08-01T21:47:05.812Z] Camera thread performed 122 scans. [2025-08-01T21:47:05.812Z] ====== philosophers (scala) [default], iteration 27 completed (2344.669 ms) ====== [2025-08-01T21:47:05.812Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-08-01T21:47:05.812Z] GC before operation: completed in 38.704 ms, heap usage 49.557 MB -> 4.851 MB. [2025-08-01T21:47:08.019Z] Camera thread performed 122 scans. [2025-08-01T21:47:08.019Z] ====== philosophers (scala) [default], iteration 28 completed (2436.273 ms) ====== [2025-08-01T21:47:08.019Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-08-01T21:47:08.019Z] GC before operation: completed in 39.741 ms, heap usage 84.170 MB -> 4.852 MB. [2025-08-01T21:47:10.177Z] Camera thread performed 122 scans. [2025-08-01T21:47:10.177Z] ====== philosophers (scala) [default], iteration 29 completed (2593.339 ms) ====== [2025-08-01T21:47:10.841Z] ----------------------------------- [2025-08-01T21:47:10.841Z] renaissance-philosophers_0_PASSED [2025-08-01T21:47:10.841Z] ----------------------------------- [2025-08-01T21:47:10.841Z] [2025-08-01T21:47:10.841Z] TEST TEARDOWN: [2025-08-01T21:47:10.841Z] Nothing to be done for teardown. [2025-08-01T21:47:10.841Z] renaissance-philosophers_0 Finish Time: Fri Aug 1 16:47:08 2025 Epoch Time (ms): 1754084828244