[2025-06-12T02:56:12.040Z] Running test renaissance-philosophers_0 ... [2025-06-12T02:56:12.040Z] =============================================== [2025-06-12T02:56:12.040Z] renaissance-philosophers_0 Start Time: Thu Jun 12 02:56:11 2025 Epoch Time (ms): 1749696971870 [2025-06-12T02:56:12.040Z] variation: NoOptions [2025-06-12T02:56:12.040Z] JVM_OPTIONS: [2025-06-12T02:56:12.040Z] { \ [2025-06-12T02:56:12.040Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T02:56:12.040Z] echo "Nothing to be done for setup."; \ [2025-06-12T02:56:12.040Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-philosophers_0"; \ [2025-06-12T02:56:12.040Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-philosophers_0"; \ [2025-06-12T02:56:12.040Z] echo ""; echo "TESTING:"; \ [2025-06-12T02:56:12.040Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-06-12T02:56:12.040Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T02:56:12.040Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T02:56:12.040Z] echo "Nothing to be done for teardown."; \ [2025-06-12T02:56:12.040Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/TestTargetResult"; [2025-06-12T02:56:12.040Z] [2025-06-12T02:56:12.040Z] TEST SETUP: [2025-06-12T02:56:12.040Z] Nothing to be done for setup. [2025-06-12T02:56:12.040Z] [2025-06-12T02:56:12.040Z] TESTING: [2025-06-12T02:56:12.807Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-06-12T02:56:12.807Z] GC before operation: completed in 12.196 ms, heap usage 16.183 MB -> 4.671 MB. [2025-06-12T02:56:15.719Z] Camera thread performed 122 scans. [2025-06-12T02:56:15.719Z] ====== philosophers (scala) [default], iteration 0 completed (2445.286 ms) ====== [2025-06-12T02:56:15.719Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-06-12T02:56:15.719Z] GC before operation: completed in 12.454 ms, heap usage 10.195 MB -> 5.018 MB. [2025-06-12T02:56:18.627Z] Camera thread performed 122 scans. [2025-06-12T02:56:18.627Z] ====== philosophers (scala) [default], iteration 1 completed (3059.041 ms) ====== [2025-06-12T02:56:18.627Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-06-12T02:56:18.627Z] GC before operation: completed in 11.520 ms, heap usage 7.146 MB -> 5.018 MB. [2025-06-12T02:56:20.252Z] Camera thread performed 122 scans. [2025-06-12T02:56:20.252Z] ====== philosophers (scala) [default], iteration 2 completed (1643.133 ms) ====== [2025-06-12T02:56:20.252Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-06-12T02:56:20.252Z] GC before operation: completed in 14.180 ms, heap usage 22.100 MB -> 5.019 MB. [2025-06-12T02:56:21.379Z] Camera thread performed 122 scans. [2025-06-12T02:56:21.379Z] ====== philosophers (scala) [default], iteration 3 completed (1227.586 ms) ====== [2025-06-12T02:56:21.379Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-06-12T02:56:21.379Z] GC before operation: completed in 12.245 ms, heap usage 8.867 MB -> 5.019 MB. [2025-06-12T02:56:22.512Z] Camera thread performed 122 scans. [2025-06-12T02:56:22.512Z] ====== philosophers (scala) [default], iteration 4 completed (1123.921 ms) ====== [2025-06-12T02:56:22.512Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-06-12T02:56:22.512Z] GC before operation: completed in 11.682 ms, heap usage 27.129 MB -> 5.019 MB. [2025-06-12T02:56:23.632Z] Camera thread performed 122 scans. [2025-06-12T02:56:23.632Z] ====== philosophers (scala) [default], iteration 5 completed (1087.839 ms) ====== [2025-06-12T02:56:23.632Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-06-12T02:56:23.632Z] GC before operation: completed in 11.475 ms, heap usage 10.212 MB -> 5.019 MB. [2025-06-12T02:56:24.762Z] Camera thread performed 122 scans. [2025-06-12T02:56:24.762Z] ====== philosophers (scala) [default], iteration 6 completed (937.405 ms) ====== [2025-06-12T02:56:24.762Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-06-12T02:56:24.762Z] GC before operation: completed in 12.172 ms, heap usage 13.228 MB -> 5.019 MB. [2025-06-12T02:56:25.457Z] Camera thread performed 122 scans. [2025-06-12T02:56:25.457Z] ====== philosophers (scala) [default], iteration 7 completed (930.003 ms) ====== [2025-06-12T02:56:25.457Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-06-12T02:56:25.457Z] GC before operation: completed in 12.463 ms, heap usage 40.204 MB -> 5.019 MB. [2025-06-12T02:56:26.576Z] Camera thread performed 122 scans. [2025-06-12T02:56:26.576Z] ====== philosophers (scala) [default], iteration 8 completed (924.650 ms) ====== [2025-06-12T02:56:26.576Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-06-12T02:56:26.576Z] GC before operation: completed in 12.441 ms, heap usage 44.666 MB -> 5.020 MB. [2025-06-12T02:56:27.267Z] Camera thread performed 122 scans. [2025-06-12T02:56:27.267Z] ====== philosophers (scala) [default], iteration 9 completed (919.154 ms) ====== [2025-06-12T02:56:27.267Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-06-12T02:56:27.267Z] GC before operation: completed in 12.340 ms, heap usage 44.652 MB -> 5.020 MB. [2025-06-12T02:56:28.414Z] Camera thread performed 122 scans. [2025-06-12T02:56:28.414Z] ====== philosophers (scala) [default], iteration 10 completed (924.741 ms) ====== [2025-06-12T02:56:28.414Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-06-12T02:56:28.414Z] GC before operation: completed in 11.681 ms, heap usage 49.152 MB -> 5.020 MB. [2025-06-12T02:56:29.103Z] Camera thread performed 122 scans. [2025-06-12T02:56:29.103Z] ====== philosophers (scala) [default], iteration 11 completed (899.497 ms) ====== [2025-06-12T02:56:29.103Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-06-12T02:56:29.103Z] GC before operation: completed in 11.831 ms, heap usage 13.654 MB -> 5.020 MB. [2025-06-12T02:56:30.225Z] Camera thread performed 122 scans. [2025-06-12T02:56:30.225Z] ====== philosophers (scala) [default], iteration 12 completed (900.775 ms) ====== [2025-06-12T02:56:30.225Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-06-12T02:56:30.225Z] GC before operation: completed in 12.672 ms, heap usage 41.655 MB -> 5.021 MB. [2025-06-12T02:56:30.918Z] Camera thread performed 122 scans. [2025-06-12T02:56:30.918Z] ====== philosophers (scala) [default], iteration 13 completed (922.057 ms) ====== [2025-06-12T02:56:30.918Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-06-12T02:56:30.918Z] GC before operation: completed in 11.945 ms, heap usage 16.113 MB -> 5.020 MB. [2025-06-12T02:56:32.039Z] Camera thread performed 122 scans. [2025-06-12T02:56:32.039Z] ====== philosophers (scala) [default], iteration 14 completed (887.549 ms) ====== [2025-06-12T02:56:32.039Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-06-12T02:56:32.039Z] GC before operation: completed in 11.497 ms, heap usage 8.119 MB -> 5.021 MB. [2025-06-12T02:56:32.731Z] Camera thread performed 122 scans. [2025-06-12T02:56:32.731Z] ====== philosophers (scala) [default], iteration 15 completed (918.410 ms) ====== [2025-06-12T02:56:32.731Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-06-12T02:56:32.731Z] GC before operation: completed in 12.360 ms, heap usage 30.151 MB -> 5.021 MB. [2025-06-12T02:56:33.855Z] Camera thread performed 122 scans. [2025-06-12T02:56:33.855Z] ====== philosophers (scala) [default], iteration 16 completed (935.664 ms) ====== [2025-06-12T02:56:33.855Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-06-12T02:56:33.855Z] GC before operation: completed in 12.138 ms, heap usage 21.155 MB -> 5.021 MB. [2025-06-12T02:56:34.977Z] Camera thread performed 122 scans. [2025-06-12T02:56:34.977Z] ====== philosophers (scala) [default], iteration 17 completed (909.237 ms) ====== [2025-06-12T02:56:34.977Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-06-12T02:56:34.977Z] GC before operation: completed in 12.560 ms, heap usage 51.152 MB -> 5.021 MB. [2025-06-12T02:56:35.668Z] Camera thread performed 122 scans. [2025-06-12T02:56:35.668Z] ====== philosophers (scala) [default], iteration 18 completed (921.549 ms) ====== [2025-06-12T02:56:35.668Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-06-12T02:56:35.668Z] GC before operation: completed in 12.584 ms, heap usage 53.657 MB -> 5.022 MB. [2025-06-12T02:56:36.859Z] Camera thread performed 122 scans. [2025-06-12T02:56:36.859Z] ====== philosophers (scala) [default], iteration 19 completed (943.320 ms) ====== [2025-06-12T02:56:36.859Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-06-12T02:56:36.859Z] GC before operation: completed in 13.761 ms, heap usage 47.149 MB -> 5.022 MB. [2025-06-12T02:56:37.549Z] Camera thread performed 122 scans. [2025-06-12T02:56:37.549Z] ====== philosophers (scala) [default], iteration 20 completed (929.830 ms) ====== [2025-06-12T02:56:37.549Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-06-12T02:56:37.549Z] GC before operation: completed in 13.460 ms, heap usage 51.153 MB -> 5.022 MB. [2025-06-12T02:56:38.672Z] Camera thread performed 122 scans. [2025-06-12T02:56:38.672Z] ====== philosophers (scala) [default], iteration 21 completed (932.023 ms) ====== [2025-06-12T02:56:38.672Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-06-12T02:56:38.672Z] GC before operation: completed in 12.490 ms, heap usage 45.146 MB -> 5.022 MB. [2025-06-12T02:56:39.367Z] Camera thread performed 122 scans. [2025-06-12T02:56:39.367Z] ====== philosophers (scala) [default], iteration 22 completed (932.178 ms) ====== [2025-06-12T02:56:39.367Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-06-12T02:56:39.367Z] GC before operation: completed in 11.795 ms, heap usage 22.656 MB -> 5.022 MB. [2025-06-12T02:56:40.490Z] Camera thread performed 122 scans. [2025-06-12T02:56:40.490Z] ====== philosophers (scala) [default], iteration 23 completed (895.548 ms) ====== [2025-06-12T02:56:40.490Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-06-12T02:56:40.490Z] GC before operation: completed in 12.374 ms, heap usage 47.117 MB -> 5.022 MB. [2025-06-12T02:56:41.179Z] Camera thread performed 122 scans. [2025-06-12T02:56:41.179Z] ====== philosophers (scala) [default], iteration 24 completed (926.631 ms) ====== [2025-06-12T02:56:41.179Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-06-12T02:56:41.179Z] GC before operation: completed in 12.288 ms, heap usage 58.148 MB -> 5.022 MB. [2025-06-12T02:56:42.303Z] Camera thread performed 122 scans. [2025-06-12T02:56:42.303Z] ====== philosophers (scala) [default], iteration 25 completed (928.663 ms) ====== [2025-06-12T02:56:42.303Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-06-12T02:56:42.303Z] GC before operation: completed in 12.933 ms, heap usage 36.153 MB -> 5.022 MB. [2025-06-12T02:56:43.423Z] Camera thread performed 122 scans. [2025-06-12T02:56:43.423Z] ====== philosophers (scala) [default], iteration 26 completed (928.834 ms) ====== [2025-06-12T02:56:43.423Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-06-12T02:56:43.423Z] GC before operation: completed in 13.349 ms, heap usage 38.153 MB -> 5.022 MB. [2025-06-12T02:56:44.115Z] Camera thread performed 122 scans. [2025-06-12T02:56:44.115Z] ====== philosophers (scala) [default], iteration 27 completed (950.532 ms) ====== [2025-06-12T02:56:44.115Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-06-12T02:56:44.115Z] GC before operation: completed in 12.032 ms, heap usage 25.656 MB -> 5.022 MB. [2025-06-12T02:56:45.235Z] Camera thread performed 122 scans. [2025-06-12T02:56:45.235Z] ====== philosophers (scala) [default], iteration 28 completed (945.630 ms) ====== [2025-06-12T02:56:45.235Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-06-12T02:56:45.235Z] GC before operation: completed in 13.226 ms, heap usage 11.654 MB -> 5.023 MB. [2025-06-12T02:56:45.924Z] Camera thread performed 122 scans. [2025-06-12T02:56:45.924Z] ====== philosophers (scala) [default], iteration 29 completed (906.186 ms) ====== [2025-06-12T02:56:46.245Z] ----------------------------------- [2025-06-12T02:56:46.245Z] renaissance-philosophers_0_PASSED [2025-06-12T02:56:46.245Z] ----------------------------------- [2025-06-12T02:56:46.245Z] [2025-06-12T02:56:46.245Z] TEST TEARDOWN: [2025-06-12T02:56:46.245Z] Nothing to be done for teardown. [2025-06-12T02:56:46.245Z] renaissance-philosophers_0 Finish Time: Thu Jun 12 02:56:46 2025 Epoch Time (ms): 1749697006069