[2025-05-29T00:11:17.688Z] Running test renaissance-philosophers_0 ... [2025-05-29T00:11:17.688Z] =============================================== [2025-05-29T00:11:17.688Z] renaissance-philosophers_0 Start Time: Thu May 29 00:11:17 2025 Epoch Time (ms): 1748477477378 [2025-05-29T00:11:17.688Z] variation: NoOptions [2025-05-29T00:11:17.688Z] JVM_OPTIONS: [2025-05-29T00:11:17.688Z] { \ [2025-05-29T00:11:17.688Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T00:11:17.688Z] echo "Nothing to be done for setup."; \ [2025-05-29T00:11:17.688Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/renaissance-philosophers_0"; \ [2025-05-29T00:11:17.688Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/renaissance-philosophers_0"; \ [2025-05-29T00:11:17.688Z] echo ""; echo "TESTING:"; \ [2025-05-29T00:11:17.688Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-05-29T00:11:17.688Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T00:11:17.688Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T00:11:17.688Z] echo "Nothing to be done for teardown."; \ [2025-05-29T00:11:17.688Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17484759497998/TestTargetResult"; [2025-05-29T00:11:17.688Z] [2025-05-29T00:11:17.688Z] TEST SETUP: [2025-05-29T00:11:17.688Z] Nothing to be done for setup. [2025-05-29T00:11:17.688Z] [2025-05-29T00:11:17.688Z] TESTING: [2025-05-29T00:11:18.630Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-05-29T00:11:18.630Z] GC before operation: completed in 21.298 ms, heap usage 6.470 MB -> 4.393 MB. [2025-05-29T00:11:25.250Z] Camera thread performed 122 scans. [2025-05-29T00:11:25.250Z] ====== philosophers (scala) [default], iteration 0 completed (5772.676 ms) ====== [2025-05-29T00:11:25.250Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-05-29T00:11:25.250Z] GC before operation: completed in 44.552 ms, heap usage 158.292 MB -> 4.822 MB. [2025-05-29T00:11:28.230Z] Camera thread performed 122 scans. [2025-05-29T00:11:28.230Z] ====== philosophers (scala) [default], iteration 1 completed (3708.844 ms) ====== [2025-05-29T00:11:28.231Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-05-29T00:11:28.231Z] GC before operation: completed in 25.500 ms, heap usage 30.715 MB -> 4.823 MB. [2025-05-29T00:11:32.332Z] Camera thread performed 122 scans. [2025-05-29T00:11:32.332Z] ====== philosophers (scala) [default], iteration 2 completed (3824.005 ms) ====== [2025-05-29T00:11:32.332Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-05-29T00:11:32.332Z] GC before operation: completed in 25.481 ms, heap usage 98.194 MB -> 4.826 MB. [2025-05-29T00:11:36.434Z] Camera thread performed 122 scans. [2025-05-29T00:11:36.434Z] ====== philosophers (scala) [default], iteration 3 completed (3912.587 ms) ====== [2025-05-29T00:11:36.434Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-05-29T00:11:36.434Z] GC before operation: completed in 28.030 ms, heap usage 110.455 MB -> 4.827 MB. [2025-05-29T00:11:39.885Z] Camera thread performed 122 scans. [2025-05-29T00:11:39.885Z] ====== philosophers (scala) [default], iteration 4 completed (3415.517 ms) ====== [2025-05-29T00:11:39.885Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-05-29T00:11:39.885Z] GC before operation: completed in 27.401 ms, heap usage 79.827 MB -> 4.827 MB. [2025-05-29T00:11:43.986Z] Camera thread performed 122 scans. [2025-05-29T00:11:43.986Z] ====== philosophers (scala) [default], iteration 5 completed (3911.379 ms) ====== [2025-05-29T00:11:43.986Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-05-29T00:11:43.986Z] GC before operation: completed in 25.786 ms, heap usage 34.760 MB -> 4.827 MB. [2025-05-29T00:11:45.915Z] Camera thread performed 122 scans. [2025-05-29T00:11:45.915Z] ====== philosophers (scala) [default], iteration 6 completed (2615.820 ms) ====== [2025-05-29T00:11:45.915Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-05-29T00:11:45.915Z] GC before operation: completed in 26.486 ms, heap usage 95.022 MB -> 4.828 MB. [2025-05-29T00:11:48.895Z] Camera thread performed 122 scans. [2025-05-29T00:11:48.895Z] ====== philosophers (scala) [default], iteration 7 completed (2505.861 ms) ====== [2025-05-29T00:11:48.895Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-05-29T00:11:48.895Z] GC before operation: completed in 26.139 ms, heap usage 62.696 MB -> 4.828 MB. [2025-05-29T00:11:51.934Z] Camera thread performed 122 scans. [2025-05-29T00:11:51.934Z] ====== philosophers (scala) [default], iteration 8 completed (3222.609 ms) ====== [2025-05-29T00:11:51.934Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-05-29T00:11:51.934Z] GC before operation: completed in 31.237 ms, heap usage 29.227 MB -> 4.828 MB. [2025-05-29T00:11:56.050Z] Camera thread performed 122 scans. [2025-05-29T00:11:56.050Z] ====== philosophers (scala) [default], iteration 9 completed (4111.635 ms) ====== [2025-05-29T00:11:56.050Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-05-29T00:11:56.050Z] GC before operation: completed in 25.777 ms, heap usage 61.734 MB -> 4.828 MB. [2025-05-29T00:12:00.166Z] Camera thread performed 122 scans. [2025-05-29T00:12:00.166Z] ====== philosophers (scala) [default], iteration 10 completed (3447.993 ms) ====== [2025-05-29T00:12:00.166Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-05-29T00:12:00.166Z] GC before operation: completed in 26.746 ms, heap usage 77.715 MB -> 4.828 MB. [2025-05-29T00:12:04.271Z] Camera thread performed 122 scans. [2025-05-29T00:12:04.271Z] ====== philosophers (scala) [default], iteration 11 completed (3971.605 ms) ====== [2025-05-29T00:12:04.271Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-05-29T00:12:04.271Z] GC before operation: completed in 24.233 ms, heap usage 32.233 MB -> 4.828 MB. [2025-05-29T00:12:06.203Z] Camera thread performed 122 scans. [2025-05-29T00:12:06.203Z] ====== philosophers (scala) [default], iteration 12 completed (2483.982 ms) ====== [2025-05-29T00:12:06.203Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-05-29T00:12:06.204Z] GC before operation: completed in 27.532 ms, heap usage 41.230 MB -> 4.828 MB. [2025-05-29T00:12:09.185Z] Camera thread performed 122 scans. [2025-05-29T00:12:09.185Z] ====== philosophers (scala) [default], iteration 13 completed (2595.111 ms) ====== [2025-05-29T00:12:09.185Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-05-29T00:12:09.185Z] GC before operation: completed in 27.375 ms, heap usage 12.742 MB -> 4.828 MB. [2025-05-29T00:12:11.115Z] Camera thread performed 122 scans. [2025-05-29T00:12:11.115Z] ====== philosophers (scala) [default], iteration 14 completed (2707.742 ms) ====== [2025-05-29T00:12:11.115Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-05-29T00:12:11.115Z] GC before operation: completed in 30.656 ms, heap usage 62.208 MB -> 4.828 MB. [2025-05-29T00:12:14.097Z] Camera thread performed 122 scans. [2025-05-29T00:12:14.097Z] ====== philosophers (scala) [default], iteration 15 completed (2521.380 ms) ====== [2025-05-29T00:12:14.097Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-05-29T00:12:14.097Z] GC before operation: completed in 33.761 ms, heap usage 166.225 MB -> 4.828 MB. [2025-05-29T00:12:17.079Z] Camera thread performed 122 scans. [2025-05-29T00:12:17.079Z] ====== philosophers (scala) [default], iteration 16 completed (2596.599 ms) ====== [2025-05-29T00:12:17.079Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-05-29T00:12:17.079Z] GC before operation: completed in 30.918 ms, heap usage 31.221 MB -> 4.829 MB. [2025-05-29T00:12:19.009Z] Camera thread performed 122 scans. [2025-05-29T00:12:19.009Z] ====== philosophers (scala) [default], iteration 17 completed (2306.067 ms) ====== [2025-05-29T00:12:19.009Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-05-29T00:12:19.009Z] GC before operation: completed in 32.091 ms, heap usage 118.210 MB -> 4.829 MB. [2025-05-29T00:12:21.093Z] Camera thread performed 122 scans. [2025-05-29T00:12:21.093Z] ====== philosophers (scala) [default], iteration 18 completed (2255.804 ms) ====== [2025-05-29T00:12:21.093Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-05-29T00:12:21.093Z] GC before operation: completed in 26.265 ms, heap usage 39.726 MB -> 4.829 MB. [2025-05-29T00:12:24.073Z] Camera thread performed 122 scans. [2025-05-29T00:12:24.073Z] ====== philosophers (scala) [default], iteration 19 completed (2617.363 ms) ====== [2025-05-29T00:12:24.073Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-05-29T00:12:24.073Z] GC before operation: completed in 26.230 ms, heap usage 13.200 MB -> 4.829 MB. [2025-05-29T00:12:26.004Z] Camera thread performed 122 scans. [2025-05-29T00:12:26.004Z] ====== philosophers (scala) [default], iteration 20 completed (2440.115 ms) ====== [2025-05-29T00:12:26.004Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-05-29T00:12:26.943Z] GC before operation: completed in 27.670 ms, heap usage 96.626 MB -> 4.829 MB. [2025-05-29T00:12:28.877Z] Camera thread performed 122 scans. [2025-05-29T00:12:28.877Z] ====== philosophers (scala) [default], iteration 21 completed (2517.720 ms) ====== [2025-05-29T00:12:28.877Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-05-29T00:12:28.877Z] GC before operation: completed in 33.525 ms, heap usage 173.132 MB -> 4.829 MB. [2025-05-29T00:12:31.859Z] Camera thread performed 122 scans. [2025-05-29T00:12:31.859Z] ====== philosophers (scala) [default], iteration 22 completed (2584.303 ms) ====== [2025-05-29T00:12:31.859Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-05-29T00:12:31.859Z] GC before operation: completed in 27.692 ms, heap usage 91.619 MB -> 4.829 MB. [2025-05-29T00:12:33.790Z] Camera thread performed 122 scans. [2025-05-29T00:12:33.790Z] ====== philosophers (scala) [default], iteration 23 completed (2474.898 ms) ====== [2025-05-29T00:12:33.790Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-05-29T00:12:33.790Z] GC before operation: completed in 32.208 ms, heap usage 176.136 MB -> 4.829 MB. [2025-05-29T00:12:36.772Z] Camera thread performed 122 scans. [2025-05-29T00:12:36.773Z] ====== philosophers (scala) [default], iteration 24 completed (2904.306 ms) ====== [2025-05-29T00:12:36.773Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-05-29T00:12:36.773Z] GC before operation: completed in 29.779 ms, heap usage 56.732 MB -> 4.829 MB. [2025-05-29T00:12:39.753Z] Camera thread performed 122 scans. [2025-05-29T00:12:39.753Z] ====== philosophers (scala) [default], iteration 25 completed (2371.316 ms) ====== [2025-05-29T00:12:39.753Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-05-29T00:12:39.753Z] GC before operation: completed in 29.463 ms, heap usage 76.165 MB -> 4.829 MB. [2025-05-29T00:12:41.686Z] Camera thread performed 122 scans. [2025-05-29T00:12:41.686Z] ====== philosophers (scala) [default], iteration 26 completed (2370.588 ms) ====== [2025-05-29T00:12:41.686Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-05-29T00:12:41.686Z] GC before operation: completed in 31.308 ms, heap usage 100.219 MB -> 4.829 MB. [2025-05-29T00:12:44.308Z] Camera thread performed 122 scans. [2025-05-29T00:12:44.308Z] ====== philosophers (scala) [default], iteration 27 completed (2579.587 ms) ====== [2025-05-29T00:12:44.308Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-05-29T00:12:44.308Z] GC before operation: completed in 28.685 ms, heap usage 44.224 MB -> 4.829 MB. [2025-05-29T00:12:47.292Z] Camera thread performed 122 scans. [2025-05-29T00:12:47.292Z] ====== philosophers (scala) [default], iteration 28 completed (2409.415 ms) ====== [2025-05-29T00:12:47.292Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-05-29T00:12:47.292Z] GC before operation: completed in 30.737 ms, heap usage 169.630 MB -> 4.829 MB. [2025-05-29T00:12:49.225Z] Camera thread performed 122 scans. [2025-05-29T00:12:49.225Z] ====== philosophers (scala) [default], iteration 29 completed (2524.249 ms) ====== [2025-05-29T00:12:49.744Z] ----------------------------------- [2025-05-29T00:12:49.744Z] renaissance-philosophers_0_PASSED [2025-05-29T00:12:49.744Z] ----------------------------------- [2025-05-29T00:12:49.744Z] [2025-05-29T00:12:49.744Z] TEST TEARDOWN: [2025-05-29T00:12:49.744Z] Nothing to be done for teardown. [2025-05-29T00:12:49.744Z] renaissance-philosophers_0 Finish Time: Thu May 29 00:12:49 2025 Epoch Time (ms): 1748477569210