[2025-11-06T01:16:51.352Z] Running test renaissance-philosophers_0 ... [2025-11-06T01:16:51.352Z] =============================================== [2025-11-06T01:16:51.352Z] renaissance-philosophers_0 Start Time: Thu Nov 6 01:16:51 2025 Epoch Time (ms): 1762391811060 [2025-11-06T01:16:51.352Z] variation: NoOptions [2025-11-06T01:16:51.352Z] JVM_OPTIONS: [2025-11-06T01:16:51.352Z] { \ [2025-11-06T01:16:51.352Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T01:16:51.352Z] echo "Nothing to be done for setup."; \ [2025-11-06T01:16:51.352Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17623914057138/renaissance-philosophers_0"; \ [2025-11-06T01:16:51.352Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17623914057138/renaissance-philosophers_0"; \ [2025-11-06T01:16:51.352Z] echo ""; echo "TESTING:"; \ [2025-11-06T01:16:51.352Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17623914057138/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-11-06T01:16:51.352Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17623914057138/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T01:16:51.352Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T01:16:51.352Z] echo "Nothing to be done for teardown."; \ [2025-11-06T01:16:51.352Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_arm_linux/aqa-tests/TKG/../TKG/output_17623914057138/TestTargetResult"; [2025-11-06T01:16:51.352Z] [2025-11-06T01:16:51.352Z] TEST SETUP: [2025-11-06T01:16:51.352Z] Nothing to be done for setup. [2025-11-06T01:16:51.352Z] [2025-11-06T01:16:51.352Z] TESTING: [2025-11-06T01:16:52.310Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-11-06T01:16:52.310Z] GC before operation: completed in 17.526 ms, heap usage 16.023 MB -> 4.710 MB. [2025-11-06T01:16:57.728Z] Camera thread performed 122 scans. [2025-11-06T01:16:57.728Z] ====== philosophers (scala) [default], iteration 0 completed (4288.544 ms) ====== [2025-11-06T01:16:57.728Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-11-06T01:16:57.728Z] GC before operation: completed in 15.732 ms, heap usage 16.439 MB -> 5.102 MB. [2025-11-06T01:16:59.701Z] Camera thread performed 122 scans. [2025-11-06T01:16:59.701Z] ====== philosophers (scala) [default], iteration 1 completed (2688.903 ms) ====== [2025-11-06T01:16:59.701Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-11-06T01:16:59.701Z] GC before operation: completed in 9.305 ms, heap usage 21.339 MB -> 5.108 MB. [2025-11-06T01:17:01.674Z] Camera thread performed 122 scans. [2025-11-06T01:17:01.674Z] ====== philosophers (scala) [default], iteration 2 completed (2373.502 ms) ====== [2025-11-06T01:17:01.674Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-11-06T01:17:01.674Z] GC before operation: completed in 10.581 ms, heap usage 40.932 MB -> 5.111 MB. [2025-11-06T01:17:04.722Z] Camera thread performed 122 scans. [2025-11-06T01:17:04.722Z] ====== philosophers (scala) [default], iteration 3 completed (2402.563 ms) ====== [2025-11-06T01:17:04.722Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-11-06T01:17:04.722Z] GC before operation: completed in 11.822 ms, heap usage 28.488 MB -> 5.111 MB. [2025-11-06T01:17:06.696Z] Camera thread performed 122 scans. [2025-11-06T01:17:06.696Z] ====== philosophers (scala) [default], iteration 4 completed (2349.674 ms) ====== [2025-11-06T01:17:06.696Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-11-06T01:17:06.696Z] GC before operation: completed in 8.313 ms, heap usage 47.342 MB -> 5.111 MB. [2025-11-06T01:17:09.790Z] Camera thread performed 122 scans. [2025-11-06T01:17:09.790Z] ====== philosophers (scala) [default], iteration 5 completed (2466.303 ms) ====== [2025-11-06T01:17:09.790Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-11-06T01:17:09.790Z] GC before operation: completed in 10.158 ms, heap usage 41.209 MB -> 5.111 MB. [2025-11-06T01:17:12.174Z] Camera thread performed 122 scans. [2025-11-06T01:17:12.174Z] ====== philosophers (scala) [default], iteration 6 completed (2508.813 ms) ====== [2025-11-06T01:17:12.174Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-11-06T01:17:12.174Z] GC before operation: completed in 9.789 ms, heap usage 6.287 MB -> 5.113 MB. [2025-11-06T01:17:14.144Z] Camera thread performed 122 scans. [2025-11-06T01:17:14.144Z] ====== philosophers (scala) [default], iteration 7 completed (2557.000 ms) ====== [2025-11-06T01:17:14.144Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-11-06T01:17:14.144Z] GC before operation: completed in 8.708 ms, heap usage 33.355 MB -> 5.113 MB. [2025-11-06T01:17:17.187Z] Camera thread performed 122 scans. [2025-11-06T01:17:17.187Z] ====== philosophers (scala) [default], iteration 8 completed (2535.256 ms) ====== [2025-11-06T01:17:17.187Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-11-06T01:17:17.187Z] GC before operation: completed in 8.518 ms, heap usage 46.052 MB -> 5.113 MB. [2025-11-06T01:17:20.230Z] Camera thread performed 122 scans. [2025-11-06T01:17:20.230Z] ====== philosophers (scala) [default], iteration 9 completed (2718.234 ms) ====== [2025-11-06T01:17:20.230Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-11-06T01:17:20.230Z] GC before operation: completed in 9.449 ms, heap usage 35.168 MB -> 5.113 MB. [2025-11-06T01:17:22.203Z] Camera thread performed 122 scans. [2025-11-06T01:17:22.203Z] ====== philosophers (scala) [default], iteration 10 completed (2436.975 ms) ====== [2025-11-06T01:17:22.203Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-11-06T01:17:22.203Z] GC before operation: completed in 9.255 ms, heap usage 37.623 MB -> 5.114 MB. [2025-11-06T01:17:25.246Z] Camera thread performed 122 scans. [2025-11-06T01:17:25.246Z] ====== philosophers (scala) [default], iteration 11 completed (2680.294 ms) ====== [2025-11-06T01:17:25.246Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-11-06T01:17:25.246Z] GC before operation: completed in 10.047 ms, heap usage 40.173 MB -> 5.114 MB. [2025-11-06T01:17:27.226Z] Camera thread performed 122 scans. [2025-11-06T01:17:27.226Z] ====== philosophers (scala) [default], iteration 12 completed (2204.542 ms) ====== [2025-11-06T01:17:27.226Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-11-06T01:17:27.226Z] GC before operation: completed in 16.870 ms, heap usage 37.929 MB -> 5.115 MB. [2025-11-06T01:17:29.208Z] Camera thread performed 122 scans. [2025-11-06T01:17:29.208Z] ====== philosophers (scala) [default], iteration 13 completed (2429.591 ms) ====== [2025-11-06T01:17:29.208Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-11-06T01:17:29.208Z] GC before operation: completed in 14.352 ms, heap usage 26.659 MB -> 5.114 MB. [2025-11-06T01:17:32.269Z] Camera thread performed 122 scans. [2025-11-06T01:17:32.269Z] ====== philosophers (scala) [default], iteration 14 completed (2304.451 ms) ====== [2025-11-06T01:17:32.269Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-11-06T01:17:32.269Z] GC before operation: completed in 9.747 ms, heap usage 8.198 MB -> 5.115 MB. [2025-11-06T01:17:34.248Z] Camera thread performed 122 scans. [2025-11-06T01:17:34.248Z] ====== philosophers (scala) [default], iteration 15 completed (2163.631 ms) ====== [2025-11-06T01:17:34.248Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-11-06T01:17:34.248Z] GC before operation: completed in 18.748 ms, heap usage 42.576 MB -> 5.115 MB. [2025-11-06T01:17:36.228Z] Camera thread performed 122 scans. [2025-11-06T01:17:36.228Z] ====== philosophers (scala) [default], iteration 16 completed (2568.702 ms) ====== [2025-11-06T01:17:36.228Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-11-06T01:17:36.228Z] GC before operation: completed in 8.597 ms, heap usage 26.628 MB -> 5.116 MB. [2025-11-06T01:17:39.305Z] Camera thread performed 122 scans. [2025-11-06T01:17:39.305Z] ====== philosophers (scala) [default], iteration 17 completed (2401.668 ms) ====== [2025-11-06T01:17:39.305Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-11-06T01:17:39.305Z] GC before operation: completed in 8.439 ms, heap usage 40.179 MB -> 5.116 MB. [2025-11-06T01:17:41.279Z] Camera thread performed 122 scans. [2025-11-06T01:17:41.279Z] ====== philosophers (scala) [default], iteration 18 completed (2756.801 ms) ====== [2025-11-06T01:17:41.279Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-11-06T01:17:42.238Z] GC before operation: completed in 11.362 ms, heap usage 48.242 MB -> 5.116 MB. [2025-11-06T01:17:44.206Z] Camera thread performed 122 scans. [2025-11-06T01:17:44.206Z] ====== philosophers (scala) [default], iteration 19 completed (2763.007 ms) ====== [2025-11-06T01:17:44.206Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-11-06T01:17:44.206Z] GC before operation: completed in 12.277 ms, heap usage 45.322 MB -> 5.116 MB. [2025-11-06T01:17:47.248Z] Camera thread performed 122 scans. [2025-11-06T01:17:47.248Z] ====== philosophers (scala) [default], iteration 20 completed (2376.242 ms) ====== [2025-11-06T01:17:47.248Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-11-06T01:17:47.248Z] GC before operation: completed in 10.322 ms, heap usage 10.196 MB -> 5.116 MB. [2025-11-06T01:17:49.295Z] Camera thread performed 122 scans. [2025-11-06T01:17:49.295Z] ====== philosophers (scala) [default], iteration 21 completed (2540.212 ms) ====== [2025-11-06T01:17:49.295Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-11-06T01:17:49.295Z] GC before operation: completed in 10.432 ms, heap usage 49.648 MB -> 5.116 MB. [2025-11-06T01:17:52.336Z] Camera thread performed 122 scans. [2025-11-06T01:17:52.336Z] ====== philosophers (scala) [default], iteration 22 completed (2424.905 ms) ====== [2025-11-06T01:17:52.336Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-11-06T01:17:52.336Z] GC before operation: completed in 10.350 ms, heap usage 55.122 MB -> 5.116 MB. [2025-11-06T01:17:55.373Z] Camera thread performed 122 scans. [2025-11-06T01:17:55.373Z] ====== philosophers (scala) [default], iteration 23 completed (3051.036 ms) ====== [2025-11-06T01:17:55.373Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-11-06T01:17:55.373Z] GC before operation: completed in 13.964 ms, heap usage 8.846 MB -> 5.116 MB. [2025-11-06T01:17:57.343Z] Camera thread performed 122 scans. [2025-11-06T01:17:57.343Z] ====== philosophers (scala) [default], iteration 24 completed (2214.967 ms) ====== [2025-11-06T01:17:57.343Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-11-06T01:17:57.343Z] GC before operation: completed in 8.169 ms, heap usage 34.584 MB -> 5.116 MB. [2025-11-06T01:17:59.403Z] Camera thread performed 122 scans. [2025-11-06T01:17:59.403Z] ====== philosophers (scala) [default], iteration 25 completed (2570.388 ms) ====== [2025-11-06T01:17:59.403Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-11-06T01:17:59.403Z] GC before operation: completed in 11.035 ms, heap usage 29.714 MB -> 5.116 MB. [2025-11-06T01:18:02.448Z] Camera thread performed 122 scans. [2025-11-06T01:18:02.448Z] ====== philosophers (scala) [default], iteration 26 completed (2406.000 ms) ====== [2025-11-06T01:18:02.448Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-11-06T01:18:02.448Z] GC before operation: completed in 8.136 ms, heap usage 11.212 MB -> 5.116 MB. [2025-11-06T01:18:04.437Z] Camera thread performed 122 scans. [2025-11-06T01:18:04.437Z] ====== philosophers (scala) [default], iteration 27 completed (2526.536 ms) ====== [2025-11-06T01:18:04.437Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-11-06T01:18:04.437Z] GC before operation: completed in 11.495 ms, heap usage 37.975 MB -> 5.116 MB. [2025-11-06T01:18:07.532Z] Camera thread performed 122 scans. [2025-11-06T01:18:07.532Z] ====== philosophers (scala) [default], iteration 28 completed (2461.677 ms) ====== [2025-11-06T01:18:07.532Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-11-06T01:18:07.532Z] GC before operation: completed in 11.357 ms, heap usage 32.884 MB -> 5.117 MB. [2025-11-06T01:18:09.504Z] Camera thread performed 122 scans. [2025-11-06T01:18:09.504Z] ====== philosophers (scala) [default], iteration 29 completed (2433.861 ms) ====== [2025-11-06T01:18:10.468Z] ----------------------------------- [2025-11-06T01:18:10.468Z] renaissance-philosophers_0_PASSED [2025-11-06T01:18:10.468Z] ----------------------------------- [2025-11-06T01:18:10.468Z] [2025-11-06T01:18:10.468Z] TEST TEARDOWN: [2025-11-06T01:18:10.468Z] Nothing to be done for teardown. [2025-11-06T01:18:10.468Z] renaissance-philosophers_0 Finish Time: Thu Nov 6 01:18:09 2025 Epoch Time (ms): 1762391889425