[2025-03-27T06:22:54.752Z] Running test renaissance-philosophers_0 ... [2025-03-27T06:22:54.752Z] =============================================== [2025-03-27T06:22:54.752Z] renaissance-philosophers_0 Start Time: Thu Mar 27 06:22:54 2025 Epoch Time (ms): 1743056574067 [2025-03-27T06:22:54.752Z] variation: NoOptions [2025-03-27T06:22:54.752Z] JVM_OPTIONS: [2025-03-27T06:22:54.752Z] { \ [2025-03-27T06:22:54.752Z] echo ""; echo "TEST SETUP:"; \ [2025-03-27T06:22:54.752Z] echo "Nothing to be done for setup."; \ [2025-03-27T06:22:54.752Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-philosophers_0"; \ [2025-03-27T06:22:54.752Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-philosophers_0"; \ [2025-03-27T06:22:54.752Z] echo ""; echo "TESTING:"; \ [2025-03-27T06:22:54.752Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-03-27T06:22:54.752Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-27T06:22:54.752Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-27T06:22:54.752Z] echo "Nothing to be done for teardown."; \ [2025-03-27T06:22:54.752Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/TestTargetResult"; [2025-03-27T06:22:54.752Z] [2025-03-27T06:22:54.752Z] TEST SETUP: [2025-03-27T06:22:54.752Z] Nothing to be done for setup. [2025-03-27T06:22:54.752Z] [2025-03-27T06:22:54.752Z] TESTING: [2025-03-27T06:22:56.175Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-03-27T06:22:56.175Z] GC before operation: completed in 16.930 ms, heap usage 5.528 MB -> 4.818 MB. [2025-03-27T06:22:59.171Z] Camera thread performed 122 scans. [2025-03-27T06:22:59.171Z] ====== philosophers (scala) [default], iteration 0 completed (3050.197 ms) ====== [2025-03-27T06:22:59.171Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-03-27T06:22:59.171Z] GC before operation: completed in 23.990 ms, heap usage 33.370 MB -> 5.179 MB. [2025-03-27T06:23:00.527Z] Camera thread performed 122 scans. [2025-03-27T06:23:00.527Z] ====== philosophers (scala) [default], iteration 1 completed (1160.868 ms) ====== [2025-03-27T06:23:00.527Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-03-27T06:23:00.527Z] GC before operation: completed in 20.382 ms, heap usage 22.260 MB -> 5.200 MB. [2025-03-27T06:23:01.165Z] Camera thread performed 122 scans. [2025-03-27T06:23:01.165Z] ====== philosophers (scala) [default], iteration 2 completed (671.217 ms) ====== [2025-03-27T06:23:01.165Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-03-27T06:23:01.165Z] GC before operation: completed in 21.091 ms, heap usage 7.473 MB -> 5.179 MB. [2025-03-27T06:23:02.566Z] Camera thread performed 122 scans. [2025-03-27T06:23:02.566Z] ====== philosophers (scala) [default], iteration 3 completed (747.982 ms) ====== [2025-03-27T06:23:02.566Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-03-27T06:23:02.566Z] GC before operation: completed in 16.103 ms, heap usage 24.901 MB -> 5.200 MB. [2025-03-27T06:23:02.566Z] Camera thread performed 122 scans. [2025-03-27T06:23:02.566Z] ====== philosophers (scala) [default], iteration 4 completed (782.571 ms) ====== [2025-03-27T06:23:02.566Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-03-27T06:23:02.566Z] GC before operation: completed in 26.349 ms, heap usage 18.893 MB -> 5.201 MB. [2025-03-27T06:23:03.304Z] Camera thread performed 122 scans. [2025-03-27T06:23:03.304Z] ====== philosophers (scala) [default], iteration 5 completed (716.802 ms) ====== [2025-03-27T06:23:03.304Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-03-27T06:23:03.304Z] GC before operation: completed in 16.110 ms, heap usage 10.861 MB -> 5.178 MB. [2025-03-27T06:23:04.035Z] Camera thread performed 122 scans. [2025-03-27T06:23:04.035Z] ====== philosophers (scala) [default], iteration 6 completed (646.936 ms) ====== [2025-03-27T06:23:04.035Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-03-27T06:23:04.035Z] GC before operation: completed in 33.162 ms, heap usage 56.285 MB -> 5.180 MB. [2025-03-27T06:23:04.777Z] Camera thread performed 122 scans. [2025-03-27T06:23:04.777Z] ====== philosophers (scala) [default], iteration 7 completed (615.681 ms) ====== [2025-03-27T06:23:04.777Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-03-27T06:23:04.777Z] GC before operation: completed in 18.518 ms, heap usage 33.312 MB -> 5.179 MB. [2025-03-27T06:23:05.493Z] Camera thread performed 122 scans. [2025-03-27T06:23:05.493Z] ====== philosophers (scala) [default], iteration 8 completed (859.465 ms) ====== [2025-03-27T06:23:05.493Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-03-27T06:23:05.493Z] GC before operation: completed in 24.785 ms, heap usage 26.811 MB -> 5.201 MB. [2025-03-27T06:23:06.128Z] Camera thread performed 122 scans. [2025-03-27T06:23:06.128Z] ====== philosophers (scala) [default], iteration 9 completed (952.952 ms) ====== [2025-03-27T06:23:06.128Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-03-27T06:23:06.128Z] GC before operation: completed in 25.605 ms, heap usage 40.263 MB -> 5.201 MB. [2025-03-27T06:23:07.627Z] Camera thread performed 122 scans. [2025-03-27T06:23:07.627Z] ====== philosophers (scala) [default], iteration 10 completed (763.118 ms) ====== [2025-03-27T06:23:07.627Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-03-27T06:23:07.627Z] GC before operation: completed in 23.317 ms, heap usage 51.780 MB -> 5.201 MB. [2025-03-27T06:23:08.296Z] Camera thread performed 122 scans. [2025-03-27T06:23:08.296Z] ====== philosophers (scala) [default], iteration 11 completed (1219.660 ms) ====== [2025-03-27T06:23:08.296Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-03-27T06:23:08.296Z] GC before operation: completed in 51.785 ms, heap usage 61.318 MB -> 5.180 MB. [2025-03-27T06:23:09.711Z] Camera thread performed 122 scans. [2025-03-27T06:23:09.711Z] ====== philosophers (scala) [default], iteration 12 completed (1348.263 ms) ====== [2025-03-27T06:23:09.711Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-03-27T06:23:09.711Z] GC before operation: completed in 23.144 ms, heap usage 38.167 MB -> 5.201 MB. [2025-03-27T06:23:11.085Z] Camera thread performed 122 scans. [2025-03-27T06:23:11.085Z] ====== philosophers (scala) [default], iteration 13 completed (858.513 ms) ====== [2025-03-27T06:23:11.085Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-03-27T06:23:11.085Z] GC before operation: completed in 33.619 ms, heap usage 42.264 MB -> 5.180 MB. [2025-03-27T06:23:11.953Z] Camera thread performed 122 scans. [2025-03-27T06:23:11.953Z] ====== philosophers (scala) [default], iteration 14 completed (748.636 ms) ====== [2025-03-27T06:23:11.953Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-03-27T06:23:11.953Z] GC before operation: completed in 35.193 ms, heap usage 8.787 MB -> 5.180 MB. [2025-03-27T06:23:13.963Z] Camera thread performed 122 scans. [2025-03-27T06:23:13.963Z] ====== philosophers (scala) [default], iteration 15 completed (2563.824 ms) ====== [2025-03-27T06:23:13.963Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-03-27T06:23:13.963Z] GC before operation: completed in 119.886 ms, heap usage 68.322 MB -> 5.180 MB. [2025-03-27T06:23:15.607Z] Camera thread performed 122 scans. [2025-03-27T06:23:15.607Z] ====== philosophers (scala) [default], iteration 16 completed (1570.849 ms) ====== [2025-03-27T06:23:15.607Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-03-27T06:23:15.607Z] GC before operation: completed in 78.489 ms, heap usage 6.787 MB -> 5.180 MB. [2025-03-27T06:23:17.099Z] Camera thread performed 122 scans. [2025-03-27T06:23:17.099Z] ====== philosophers (scala) [default], iteration 17 completed (808.488 ms) ====== [2025-03-27T06:23:17.099Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-03-27T06:23:17.099Z] GC before operation: completed in 22.458 ms, heap usage 8.281 MB -> 5.180 MB. [2025-03-27T06:23:18.742Z] Camera thread performed 122 scans. [2025-03-27T06:23:18.742Z] ====== philosophers (scala) [default], iteration 18 completed (1899.971 ms) ====== [2025-03-27T06:23:18.742Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-03-27T06:23:18.742Z] GC before operation: completed in 49.293 ms, heap usage 71.796 MB -> 5.181 MB. [2025-03-27T06:23:19.419Z] Camera thread performed 122 scans. [2025-03-27T06:23:19.419Z] ====== philosophers (scala) [default], iteration 19 completed (676.589 ms) ====== [2025-03-27T06:23:19.419Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-03-27T06:23:19.419Z] GC before operation: completed in 32.306 ms, heap usage 64.807 MB -> 5.181 MB. [2025-03-27T06:23:20.178Z] Camera thread performed 122 scans. [2025-03-27T06:23:20.178Z] ====== philosophers (scala) [default], iteration 20 completed (793.910 ms) ====== [2025-03-27T06:23:20.178Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-03-27T06:23:20.178Z] GC before operation: completed in 15.192 ms, heap usage 32.814 MB -> 5.203 MB. [2025-03-27T06:23:20.873Z] Camera thread performed 122 scans. [2025-03-27T06:23:20.873Z] ====== philosophers (scala) [default], iteration 21 completed (842.845 ms) ====== [2025-03-27T06:23:20.873Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-03-27T06:23:20.873Z] GC before operation: completed in 17.328 ms, heap usage 35.841 MB -> 5.203 MB. [2025-03-27T06:23:22.326Z] Camera thread performed 122 scans. [2025-03-27T06:23:22.326Z] ====== philosophers (scala) [default], iteration 22 completed (1062.784 ms) ====== [2025-03-27T06:23:22.326Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-03-27T06:23:22.326Z] GC before operation: completed in 23.559 ms, heap usage 29.690 MB -> 5.181 MB. [2025-03-27T06:23:22.977Z] Camera thread performed 122 scans. [2025-03-27T06:23:22.977Z] ====== philosophers (scala) [default], iteration 23 completed (629.542 ms) ====== [2025-03-27T06:23:22.977Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-03-27T06:23:22.977Z] GC before operation: completed in 12.823 ms, heap usage 45.772 MB -> 5.181 MB. [2025-03-27T06:23:23.614Z] Camera thread performed 122 scans. [2025-03-27T06:23:23.614Z] ====== philosophers (scala) [default], iteration 24 completed (618.834 ms) ====== [2025-03-27T06:23:23.614Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-03-27T06:23:23.614Z] GC before operation: completed in 33.616 ms, heap usage 42.285 MB -> 5.182 MB. [2025-03-27T06:23:24.369Z] Camera thread performed 122 scans. [2025-03-27T06:23:24.369Z] ====== philosophers (scala) [default], iteration 25 completed (681.866 ms) ====== [2025-03-27T06:23:24.369Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-03-27T06:23:24.369Z] GC before operation: completed in 65.248 ms, heap usage 45.271 MB -> 5.182 MB. [2025-03-27T06:23:25.912Z] Camera thread performed 122 scans. [2025-03-27T06:23:25.912Z] ====== philosophers (scala) [default], iteration 26 completed (1344.171 ms) ====== [2025-03-27T06:23:25.912Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-03-27T06:23:25.912Z] GC before operation: completed in 102.660 ms, heap usage 13.271 MB -> 5.182 MB. [2025-03-27T06:23:26.567Z] Camera thread performed 122 scans. [2025-03-27T06:23:26.567Z] ====== philosophers (scala) [default], iteration 27 completed (814.139 ms) ====== [2025-03-27T06:23:26.567Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-03-27T06:23:26.567Z] GC before operation: completed in 30.028 ms, heap usage 54.299 MB -> 5.182 MB. [2025-03-27T06:23:27.259Z] Camera thread performed 122 scans. [2025-03-27T06:23:27.259Z] ====== philosophers (scala) [default], iteration 28 completed (879.470 ms) ====== [2025-03-27T06:23:27.259Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-03-27T06:23:27.259Z] GC before operation: completed in 43.717 ms, heap usage 53.187 MB -> 5.182 MB. [2025-03-27T06:23:27.944Z] Camera thread performed 122 scans. [2025-03-27T06:23:27.944Z] ====== philosophers (scala) [default], iteration 29 completed (621.381 ms) ====== [2025-03-27T06:23:28.573Z] ----------------------------------- [2025-03-27T06:23:28.573Z] renaissance-philosophers_0_PASSED [2025-03-27T06:23:28.573Z] ----------------------------------- [2025-03-27T06:23:28.573Z] [2025-03-27T06:23:28.573Z] TEST TEARDOWN: [2025-03-27T06:23:28.573Z] Nothing to be done for teardown. [2025-03-27T06:23:28.573Z] renaissance-philosophers_0 Finish Time: Thu Mar 27 06:23:27 2025 Epoch Time (ms): 1743056607931