[2025-11-20T06:25:34.150Z] Running test renaissance-philosophers_0 ... [2025-11-20T06:25:34.150Z] =============================================== [2025-11-20T06:25:34.150Z] renaissance-philosophers_0 Start Time: Thu Nov 20 06:25:33 2025 Epoch Time (ms): 1763619933278 [2025-11-20T06:25:34.150Z] variation: NoOptions [2025-11-20T06:25:34.150Z] JVM_OPTIONS: [2025-11-20T06:25:34.150Z] { \ [2025-11-20T06:25:34.150Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T06:25:34.150Z] echo "Nothing to be done for setup."; \ [2025-11-20T06:25:34.150Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17636193237449/renaissance-philosophers_0"; \ [2025-11-20T06:25:34.150Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17636193237449/renaissance-philosophers_0"; \ [2025-11-20T06:25:34.150Z] echo ""; echo "TESTING:"; \ [2025-11-20T06:25:34.150Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_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_arm_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17636193237449/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-20T06:25:34.150Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17636193237449/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T06:25:34.150Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T06:25:34.150Z] echo "Nothing to be done for teardown."; \ [2025-11-20T06:25:34.150Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17636193237449/TestTargetResult"; [2025-11-20T06:25:34.150Z] [2025-11-20T06:25:34.150Z] TEST SETUP: [2025-11-20T06:25:34.150Z] Nothing to be done for setup. [2025-11-20T06:25:34.150Z] [2025-11-20T06:25:34.150Z] TESTING: [2025-11-20T06:25:35.111Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-20T06:25:35.111Z] GC before operation: completed in 23.230 ms, heap usage 17.453 MB -> 4.607 MB. [2025-11-20T06:25:39.321Z] Camera thread performed 122 scans. [2025-11-20T06:25:39.321Z] ====== philosophers (scala) [default], iteration 0 completed (4494.742 ms) ====== [2025-11-20T06:25:39.321Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-20T06:25:39.321Z] GC before operation: completed in 44.136 ms, heap usage 44.391 MB -> 5.017 MB. [2025-11-20T06:25:42.397Z] Camera thread performed 122 scans. [2025-11-20T06:25:42.397Z] ====== philosophers (scala) [default], iteration 1 completed (3127.522 ms) ====== [2025-11-20T06:25:42.397Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-20T06:25:42.397Z] GC before operation: completed in 39.129 ms, heap usage 17.908 MB -> 5.022 MB. [2025-11-20T06:25:46.618Z] Camera thread performed 122 scans. [2025-11-20T06:25:46.618Z] ====== philosophers (scala) [default], iteration 2 completed (3621.295 ms) ====== [2025-11-20T06:25:46.618Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-20T06:25:46.618Z] GC before operation: completed in 35.747 ms, heap usage 38.722 MB -> 5.028 MB. [2025-11-20T06:25:48.652Z] Camera thread performed 122 scans. [2025-11-20T06:25:48.652Z] ====== philosophers (scala) [default], iteration 3 completed (2615.801 ms) ====== [2025-11-20T06:25:48.652Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-20T06:25:48.652Z] GC before operation: completed in 29.905 ms, heap usage 14.157 MB -> 5.030 MB. [2025-11-20T06:25:52.858Z] Camera thread performed 122 scans. [2025-11-20T06:25:52.858Z] ====== philosophers (scala) [default], iteration 4 completed (3308.692 ms) ====== [2025-11-20T06:25:52.858Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-20T06:25:52.858Z] GC before operation: completed in 33.405 ms, heap usage 14.183 MB -> 5.031 MB. [2025-11-20T06:25:55.919Z] Camera thread performed 122 scans. [2025-11-20T06:25:55.919Z] ====== philosophers (scala) [default], iteration 5 completed (3398.014 ms) ====== [2025-11-20T06:25:55.919Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-20T06:25:55.919Z] GC before operation: completed in 30.777 ms, heap usage 13.601 MB -> 5.034 MB. [2025-11-20T06:26:00.104Z] Camera thread performed 122 scans. [2025-11-20T06:26:00.104Z] ====== philosophers (scala) [default], iteration 6 completed (3774.731 ms) ====== [2025-11-20T06:26:00.104Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-20T06:26:00.104Z] GC before operation: completed in 38.251 ms, heap usage 35.958 MB -> 5.034 MB. [2025-11-20T06:26:03.137Z] Camera thread performed 122 scans. [2025-11-20T06:26:03.137Z] ====== philosophers (scala) [default], iteration 7 completed (3307.393 ms) ====== [2025-11-20T06:26:03.137Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-20T06:26:03.137Z] GC before operation: completed in 39.123 ms, heap usage 29.283 MB -> 5.034 MB. [2025-11-20T06:26:06.162Z] Camera thread performed 122 scans. [2025-11-20T06:26:06.162Z] ====== philosophers (scala) [default], iteration 8 completed (2832.840 ms) ====== [2025-11-20T06:26:06.162Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-20T06:26:06.162Z] GC before operation: completed in 26.527 ms, heap usage 34.283 MB -> 5.037 MB. [2025-11-20T06:26:09.200Z] Camera thread performed 122 scans. [2025-11-20T06:26:09.200Z] ====== philosophers (scala) [default], iteration 9 completed (3221.742 ms) ====== [2025-11-20T06:26:09.200Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-20T06:26:09.200Z] GC before operation: completed in 28.742 ms, heap usage 39.202 MB -> 5.037 MB. [2025-11-20T06:26:11.176Z] Camera thread performed 122 scans. [2025-11-20T06:26:11.176Z] ====== philosophers (scala) [default], iteration 10 completed (2351.378 ms) ====== [2025-11-20T06:26:11.176Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-20T06:26:11.176Z] GC before operation: completed in 30.469 ms, heap usage 37.766 MB -> 5.037 MB. [2025-11-20T06:26:14.224Z] Camera thread performed 122 scans. [2025-11-20T06:26:14.224Z] ====== philosophers (scala) [default], iteration 11 completed (3027.926 ms) ====== [2025-11-20T06:26:14.224Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-20T06:26:14.224Z] GC before operation: completed in 24.882 ms, heap usage 18.280 MB -> 5.037 MB. [2025-11-20T06:26:17.441Z] Camera thread performed 122 scans. [2025-11-20T06:26:17.441Z] ====== philosophers (scala) [default], iteration 12 completed (3257.852 ms) ====== [2025-11-20T06:26:17.441Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-20T06:26:17.441Z] GC before operation: completed in 34.214 ms, heap usage 32.970 MB -> 5.037 MB. [2025-11-20T06:26:20.487Z] Camera thread performed 122 scans. [2025-11-20T06:26:20.487Z] ====== philosophers (scala) [default], iteration 13 completed (3062.238 ms) ====== [2025-11-20T06:26:20.487Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-20T06:26:20.487Z] GC before operation: completed in 29.491 ms, heap usage 45.086 MB -> 5.038 MB. [2025-11-20T06:26:24.664Z] Camera thread performed 122 scans. [2025-11-20T06:26:24.664Z] ====== philosophers (scala) [default], iteration 14 completed (3472.096 ms) ====== [2025-11-20T06:26:24.664Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-20T06:26:24.664Z] GC before operation: completed in 44.981 ms, heap usage 15.470 MB -> 5.039 MB. [2025-11-20T06:26:27.699Z] Camera thread performed 122 scans. [2025-11-20T06:26:27.699Z] ====== philosophers (scala) [default], iteration 15 completed (3186.043 ms) ====== [2025-11-20T06:26:27.699Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-20T06:26:27.699Z] GC before operation: completed in 43.601 ms, heap usage 30.925 MB -> 5.039 MB. [2025-11-20T06:26:30.728Z] Camera thread performed 122 scans. [2025-11-20T06:26:30.728Z] ====== philosophers (scala) [default], iteration 16 completed (3338.146 ms) ====== [2025-11-20T06:26:30.728Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-20T06:26:30.728Z] GC before operation: completed in 29.772 ms, heap usage 14.764 MB -> 5.039 MB. [2025-11-20T06:26:34.915Z] Camera thread performed 122 scans. [2025-11-20T06:26:34.915Z] ====== philosophers (scala) [default], iteration 17 completed (3367.544 ms) ====== [2025-11-20T06:26:34.915Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-20T06:26:34.915Z] GC before operation: completed in 34.118 ms, heap usage 9.871 MB -> 5.039 MB. [2025-11-20T06:26:37.965Z] Camera thread performed 122 scans. [2025-11-20T06:26:37.965Z] ====== philosophers (scala) [default], iteration 18 completed (3325.923 ms) ====== [2025-11-20T06:26:37.965Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-20T06:26:37.965Z] GC before operation: completed in 35.590 ms, heap usage 47.287 MB -> 5.039 MB. [2025-11-20T06:26:40.994Z] Camera thread performed 122 scans. [2025-11-20T06:26:40.994Z] ====== philosophers (scala) [default], iteration 19 completed (2992.428 ms) ====== [2025-11-20T06:26:40.994Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-20T06:26:40.994Z] GC before operation: completed in 35.613 ms, heap usage 23.197 MB -> 5.039 MB. [2025-11-20T06:26:44.029Z] Camera thread performed 122 scans. [2025-11-20T06:26:44.029Z] ====== philosophers (scala) [default], iteration 20 completed (3539.014 ms) ====== [2025-11-20T06:26:44.029Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-20T06:26:44.029Z] GC before operation: completed in 36.537 ms, heap usage 30.275 MB -> 5.039 MB. [2025-11-20T06:26:48.933Z] Camera thread performed 122 scans. [2025-11-20T06:26:48.933Z] ====== philosophers (scala) [default], iteration 21 completed (3184.300 ms) ====== [2025-11-20T06:26:48.933Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-20T06:26:48.933Z] GC before operation: completed in 39.981 ms, heap usage 31.012 MB -> 5.039 MB. [2025-11-20T06:26:51.047Z] Camera thread performed 122 scans. [2025-11-20T06:26:51.047Z] ====== philosophers (scala) [default], iteration 22 completed (3017.825 ms) ====== [2025-11-20T06:26:51.047Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-20T06:26:51.047Z] GC before operation: completed in 34.251 ms, heap usage 28.014 MB -> 5.040 MB. [2025-11-20T06:26:54.071Z] Camera thread performed 122 scans. [2025-11-20T06:26:54.071Z] ====== philosophers (scala) [default], iteration 23 completed (3225.052 ms) ====== [2025-11-20T06:26:54.071Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-20T06:26:54.071Z] GC before operation: completed in 42.967 ms, heap usage 36.028 MB -> 5.039 MB. [2025-11-20T06:26:57.094Z] Camera thread performed 122 scans. [2025-11-20T06:26:57.095Z] ====== philosophers (scala) [default], iteration 24 completed (3106.761 ms) ====== [2025-11-20T06:26:57.095Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-20T06:26:57.095Z] GC before operation: completed in 29.434 ms, heap usage 13.245 MB -> 5.040 MB. [2025-11-20T06:27:00.246Z] Camera thread performed 122 scans. [2025-11-20T06:27:00.246Z] ====== philosophers (scala) [default], iteration 25 completed (3207.017 ms) ====== [2025-11-20T06:27:00.246Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-20T06:27:00.246Z] GC before operation: completed in 32.271 ms, heap usage 38.545 MB -> 5.039 MB. [2025-11-20T06:27:04.405Z] Camera thread performed 122 scans. [2025-11-20T06:27:04.405Z] ====== philosophers (scala) [default], iteration 26 completed (4186.033 ms) ====== [2025-11-20T06:27:04.405Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-20T06:27:04.405Z] GC before operation: completed in 36.207 ms, heap usage 37.284 MB -> 5.040 MB. [2025-11-20T06:27:07.529Z] Camera thread performed 122 scans. [2025-11-20T06:27:07.529Z] ====== philosophers (scala) [default], iteration 27 completed (3275.800 ms) ====== [2025-11-20T06:27:07.529Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-20T06:27:07.529Z] GC before operation: completed in 22.197 ms, heap usage 38.560 MB -> 5.040 MB. [2025-11-20T06:27:11.702Z] Camera thread performed 122 scans. [2025-11-20T06:27:11.702Z] ====== philosophers (scala) [default], iteration 28 completed (3462.041 ms) ====== [2025-11-20T06:27:11.702Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-20T06:27:11.702Z] GC before operation: completed in 29.153 ms, heap usage 18.175 MB -> 5.040 MB. [2025-11-20T06:27:14.728Z] Camera thread performed 122 scans. [2025-11-20T06:27:14.728Z] ====== philosophers (scala) [default], iteration 29 completed (3382.544 ms) ====== [2025-11-20T06:27:14.728Z] ----------------------------------- [2025-11-20T06:27:14.728Z] renaissance-philosophers_0_PASSED [2025-11-20T06:27:14.728Z] ----------------------------------- [2025-11-20T06:27:14.728Z] [2025-11-20T06:27:14.728Z] TEST TEARDOWN: [2025-11-20T06:27:14.728Z] Nothing to be done for teardown. [2025-11-20T06:27:14.728Z] renaissance-philosophers_0 Finish Time: Thu Nov 20 06:27:14 2025 Epoch Time (ms): 1763620034442