[2025-04-25T01:27:44.190Z] Running test renaissance-philosophers_0 ... [2025-04-25T01:27:44.190Z] =============================================== [2025-04-25T01:27:44.190Z] renaissance-philosophers_0 Start Time: Fri Apr 25 01:27:43 2025 Epoch Time (ms): 1745544463277 [2025-04-25T01:27:44.190Z] variation: NoOptions [2025-04-25T01:27:44.190Z] JVM_OPTIONS: [2025-04-25T01:27:44.190Z] { \ [2025-04-25T01:27:44.190Z] echo ""; echo "TEST SETUP:"; \ [2025-04-25T01:27:44.190Z] echo "Nothing to be done for setup."; \ [2025-04-25T01:27:44.190Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17455441769149/renaissance-philosophers_0"; \ [2025-04-25T01:27:44.190Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17455441769149/renaissance-philosophers_0"; \ [2025-04-25T01:27:44.190Z] echo ""; echo "TESTING:"; \ [2025-04-25T01:27:44.190Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_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_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17455441769149/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-04-25T01:27:44.190Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17455441769149/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-25T01:27:44.190Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-25T01:27:44.190Z] echo "Nothing to be done for teardown."; \ [2025-04-25T01:27:44.190Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17455441769149/TestTargetResult"; [2025-04-25T01:27:44.190Z] [2025-04-25T01:27:44.190Z] TEST SETUP: [2025-04-25T01:27:44.190Z] Nothing to be done for setup. [2025-04-25T01:27:44.190Z] [2025-04-25T01:27:44.190Z] TESTING: [2025-04-25T01:27:44.190Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-04-25T01:27:44.190Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_17455441769149/renaissance-philosophers_0/launcher-012743-11804885226862079088/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-04-25T01:27:44.190Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-04-25T01:27:44.190Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-04-25T01:27:45.143Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-04-25T01:27:45.143Z] GC before operation: completed in 17.087 ms, heap usage 27.115 MB -> 4.780 MB. [2025-04-25T01:27:50.503Z] Camera thread performed 122 scans. [2025-04-25T01:27:50.503Z] ====== philosophers (scala) [default], iteration 0 completed (5068.955 ms) ====== [2025-04-25T01:27:50.503Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-04-25T01:27:50.503Z] GC before operation: completed in 16.282 ms, heap usage 23.911 MB -> 5.132 MB. [2025-04-25T01:27:55.192Z] Camera thread performed 122 scans. [2025-04-25T01:27:55.192Z] ====== philosophers (scala) [default], iteration 1 completed (4866.358 ms) ====== [2025-04-25T01:27:55.192Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-04-25T01:27:55.192Z] GC before operation: completed in 11.614 ms, heap usage 17.393 MB -> 5.133 MB. [2025-04-25T01:27:59.513Z] Camera thread performed 122 scans. [2025-04-25T01:27:59.513Z] ====== philosophers (scala) [default], iteration 2 completed (5030.135 ms) ====== [2025-04-25T01:27:59.513Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-04-25T01:27:59.513Z] GC before operation: completed in 13.502 ms, heap usage 21.289 MB -> 5.134 MB. [2025-04-25T01:28:06.192Z] Camera thread performed 122 scans. [2025-04-25T01:28:06.192Z] ====== philosophers (scala) [default], iteration 3 completed (6126.437 ms) ====== [2025-04-25T01:28:06.192Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-04-25T01:28:06.192Z] GC before operation: completed in 12.263 ms, heap usage 32.184 MB -> 5.134 MB. [2025-04-25T01:28:11.583Z] Camera thread performed 122 scans. [2025-04-25T01:28:11.583Z] ====== philosophers (scala) [default], iteration 4 completed (5674.796 ms) ====== [2025-04-25T01:28:11.583Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-04-25T01:28:11.583Z] GC before operation: completed in 12.768 ms, heap usage 33.773 MB -> 5.134 MB. [2025-04-25T01:28:16.943Z] Camera thread performed 122 scans. [2025-04-25T01:28:16.943Z] ====== philosophers (scala) [default], iteration 5 completed (5664.975 ms) ====== [2025-04-25T01:28:16.943Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-04-25T01:28:16.943Z] GC before operation: completed in 14.732 ms, heap usage 45.188 MB -> 5.134 MB. [2025-04-25T01:28:23.627Z] Camera thread performed 122 scans. [2025-04-25T01:28:23.628Z] ====== philosophers (scala) [default], iteration 6 completed (5755.960 ms) ====== [2025-04-25T01:28:23.628Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-04-25T01:28:23.628Z] GC before operation: completed in 16.154 ms, heap usage 26.832 MB -> 5.136 MB. [2025-04-25T01:28:28.986Z] Camera thread performed 122 scans. [2025-04-25T01:28:28.986Z] ====== philosophers (scala) [default], iteration 7 completed (5824.799 ms) ====== [2025-04-25T01:28:28.986Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-04-25T01:28:28.986Z] GC before operation: completed in 14.509 ms, heap usage 34.809 MB -> 5.135 MB. [2025-04-25T01:28:34.445Z] Camera thread performed 122 scans. [2025-04-25T01:28:34.445Z] ====== philosophers (scala) [default], iteration 8 completed (6041.107 ms) ====== [2025-04-25T01:28:34.445Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-04-25T01:28:35.396Z] GC before operation: completed in 14.393 ms, heap usage 13.667 MB -> 5.135 MB. [2025-04-25T01:28:40.760Z] Camera thread performed 122 scans. [2025-04-25T01:28:40.760Z] ====== philosophers (scala) [default], iteration 9 completed (5921.317 ms) ====== [2025-04-25T01:28:40.760Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-04-25T01:28:40.760Z] GC before operation: completed in 15.291 ms, heap usage 34.907 MB -> 5.135 MB. [2025-04-25T01:28:46.182Z] Camera thread performed 122 scans. [2025-04-25T01:28:46.182Z] ====== philosophers (scala) [default], iteration 10 completed (5458.654 ms) ====== [2025-04-25T01:28:46.182Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-04-25T01:28:46.182Z] GC before operation: completed in 13.481 ms, heap usage 46.927 MB -> 5.136 MB. [2025-04-25T01:28:52.871Z] Camera thread performed 122 scans. [2025-04-25T01:28:52.871Z] ====== philosophers (scala) [default], iteration 11 completed (5878.642 ms) ====== [2025-04-25T01:28:52.871Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-04-25T01:28:52.871Z] GC before operation: completed in 16.936 ms, heap usage 8.307 MB -> 5.136 MB. [2025-04-25T01:28:58.235Z] Camera thread performed 122 scans. [2025-04-25T01:28:58.235Z] ====== philosophers (scala) [default], iteration 12 completed (5825.163 ms) ====== [2025-04-25T01:28:58.235Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-04-25T01:28:58.235Z] GC before operation: completed in 14.946 ms, heap usage 39.279 MB -> 5.137 MB. [2025-04-25T01:29:03.601Z] Camera thread performed 122 scans. [2025-04-25T01:29:03.602Z] ====== philosophers (scala) [default], iteration 13 completed (5884.635 ms) ====== [2025-04-25T01:29:03.602Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-04-25T01:29:03.602Z] GC before operation: completed in 20.413 ms, heap usage 29.357 MB -> 5.137 MB. [2025-04-25T01:29:10.013Z] Camera thread performed 122 scans. [2025-04-25T01:29:10.013Z] ====== philosophers (scala) [default], iteration 14 completed (5928.135 ms) ====== [2025-04-25T01:29:10.013Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-04-25T01:29:10.013Z] GC before operation: completed in 19.492 ms, heap usage 35.559 MB -> 5.137 MB. [2025-04-25T01:29:15.378Z] Camera thread performed 122 scans. [2025-04-25T01:29:15.378Z] ====== philosophers (scala) [default], iteration 15 completed (5338.940 ms) ====== [2025-04-25T01:29:15.378Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-04-25T01:29:15.378Z] GC before operation: completed in 15.995 ms, heap usage 28.210 MB -> 5.137 MB. [2025-04-25T01:29:20.751Z] Camera thread performed 122 scans. [2025-04-25T01:29:20.751Z] ====== philosophers (scala) [default], iteration 16 completed (5040.600 ms) ====== [2025-04-25T01:29:20.751Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-04-25T01:29:20.751Z] GC before operation: completed in 16.772 ms, heap usage 31.687 MB -> 5.137 MB. [2025-04-25T01:29:27.487Z] Camera thread performed 122 scans. [2025-04-25T01:29:27.487Z] ====== philosophers (scala) [default], iteration 17 completed (6377.207 ms) ====== [2025-04-25T01:29:27.487Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-04-25T01:29:27.487Z] GC before operation: completed in 18.436 ms, heap usage 15.776 MB -> 5.137 MB. [2025-04-25T01:29:32.847Z] Camera thread performed 122 scans. [2025-04-25T01:29:32.847Z] ====== philosophers (scala) [default], iteration 18 completed (5479.396 ms) ====== [2025-04-25T01:29:32.847Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-04-25T01:29:32.847Z] GC before operation: completed in 23.925 ms, heap usage 16.319 MB -> 5.137 MB. [2025-04-25T01:29:38.213Z] Camera thread performed 122 scans. [2025-04-25T01:29:38.213Z] ====== philosophers (scala) [default], iteration 19 completed (5387.505 ms) ====== [2025-04-25T01:29:38.213Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-04-25T01:29:38.213Z] GC before operation: completed in 12.178 ms, heap usage 24.861 MB -> 5.172 MB. [2025-04-25T01:29:42.390Z] Camera thread performed 122 scans. [2025-04-25T01:29:42.390Z] ====== philosophers (scala) [default], iteration 20 completed (4992.221 ms) ====== [2025-04-25T01:29:42.390Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-04-25T01:29:42.390Z] GC before operation: completed in 10.079 ms, heap usage 8.874 MB -> 5.137 MB. [2025-04-25T01:29:49.069Z] Camera thread performed 122 scans. [2025-04-25T01:29:49.069Z] ====== philosophers (scala) [default], iteration 21 completed (5608.097 ms) ====== [2025-04-25T01:29:49.070Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-04-25T01:29:49.070Z] GC before operation: completed in 10.853 ms, heap usage 15.374 MB -> 5.137 MB. [2025-04-25T01:29:54.442Z] Camera thread performed 122 scans. [2025-04-25T01:29:54.442Z] ====== philosophers (scala) [default], iteration 22 completed (5672.693 ms) ====== [2025-04-25T01:29:54.442Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-04-25T01:29:54.442Z] GC before operation: completed in 11.861 ms, heap usage 11.718 MB -> 5.137 MB. [2025-04-25T01:29:59.853Z] Camera thread performed 122 scans. [2025-04-25T01:29:59.853Z] ====== philosophers (scala) [default], iteration 23 completed (6321.703 ms) ====== [2025-04-25T01:29:59.853Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-04-25T01:29:59.853Z] GC before operation: completed in 11.531 ms, heap usage 26.410 MB -> 5.137 MB. [2025-04-25T01:30:06.538Z] Camera thread performed 122 scans. [2025-04-25T01:30:06.538Z] ====== philosophers (scala) [default], iteration 24 completed (5782.608 ms) ====== [2025-04-25T01:30:06.538Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-04-25T01:30:06.538Z] GC before operation: completed in 15.274 ms, heap usage 30.387 MB -> 5.137 MB. [2025-04-25T01:30:11.903Z] Camera thread performed 122 scans. [2025-04-25T01:30:11.903Z] ====== philosophers (scala) [default], iteration 25 completed (5857.387 ms) ====== [2025-04-25T01:30:11.903Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-04-25T01:30:11.903Z] GC before operation: completed in 14.057 ms, heap usage 29.745 MB -> 5.137 MB. [2025-04-25T01:30:17.262Z] Camera thread performed 122 scans. [2025-04-25T01:30:17.262Z] ====== philosophers (scala) [default], iteration 26 completed (5702.677 ms) ====== [2025-04-25T01:30:17.262Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-04-25T01:30:17.262Z] GC before operation: completed in 13.543 ms, heap usage 5.909 MB -> 5.138 MB. [2025-04-25T01:30:22.631Z] Camera thread performed 122 scans. [2025-04-25T01:30:22.631Z] ====== philosophers (scala) [default], iteration 27 completed (5056.892 ms) ====== [2025-04-25T01:30:22.631Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-04-25T01:30:22.631Z] GC before operation: completed in 14.574 ms, heap usage 26.714 MB -> 5.137 MB. [2025-04-25T01:30:27.645Z] Camera thread performed 122 scans. [2025-04-25T01:30:27.645Z] ====== philosophers (scala) [default], iteration 28 completed (5220.407 ms) ====== [2025-04-25T01:30:27.645Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-04-25T01:30:27.646Z] GC before operation: completed in 12.235 ms, heap usage 6.903 MB -> 5.138 MB. [2025-04-25T01:30:33.002Z] Camera thread performed 122 scans. [2025-04-25T01:30:33.002Z] ====== philosophers (scala) [default], iteration 29 completed (4307.707 ms) ====== [2025-04-25T01:30:33.002Z] ----------------------------------- [2025-04-25T01:30:33.002Z] renaissance-philosophers_0_PASSED [2025-04-25T01:30:33.002Z] ----------------------------------- [2025-04-25T01:30:33.002Z] [2025-04-25T01:30:33.002Z] TEST TEARDOWN: [2025-04-25T01:30:33.002Z] Nothing to be done for teardown. [2025-04-25T01:30:33.002Z] renaissance-philosophers_0 Finish Time: Fri Apr 25 01:30:31 2025 Epoch Time (ms): 1745544631963