[2025-09-25T22:48:10.648Z] Running test renaissance-philosophers_0 ... [2025-09-25T22:48:10.648Z] =============================================== [2025-09-25T22:48:10.648Z] renaissance-philosophers_0 Start Time: Thu Sep 25 22:48:09 2025 Epoch Time (ms): 1758840489645 [2025-09-25T22:48:10.648Z] variation: NoOptions [2025-09-25T22:48:10.648Z] JVM_OPTIONS: [2025-09-25T22:48:10.648Z] { \ [2025-09-25T22:48:10.648Z] echo ""; echo "TEST SETUP:"; \ [2025-09-25T22:48:10.648Z] echo "Nothing to be done for setup."; \ [2025-09-25T22:48:10.648Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17588399546624/renaissance-philosophers_0"; \ [2025-09-25T22:48:10.648Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17588399546624/renaissance-philosophers_0"; \ [2025-09-25T22:48:10.648Z] echo ""; echo "TESTING:"; \ [2025-09-25T22:48:10.648Z] "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-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_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17588399546624/renaissance-philosophers_0"/philosophers.json" philosophers; \ [2025-09-25T22:48:10.649Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17588399546624/renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-25T22:48:10.649Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-25T22:48:10.649Z] echo "Nothing to be done for teardown."; \ [2025-09-25T22:48:10.649Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17588399546624/TestTargetResult"; [2025-09-25T22:48:10.649Z] [2025-09-25T22:48:10.649Z] TEST SETUP: [2025-09-25T22:48:10.649Z] Nothing to be done for setup. [2025-09-25T22:48:10.649Z] [2025-09-25T22:48:10.649Z] TESTING: [2025-09-25T22:48:10.649Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-09-25T22:48:10.649Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_17588399546624/renaissance-philosophers_0/launcher-224809-12548653552822512829/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-09-25T22:48:10.649Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-09-25T22:48:10.649Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-09-25T22:48:11.605Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-25T22:48:11.605Z] GC before operation: completed in 23.354 ms, heap usage 13.197 MB -> 4.832 MB. [2025-09-25T22:48:13.565Z] Camera thread performed 122 scans. [2025-09-25T22:48:13.565Z] ====== philosophers (scala) [default], iteration 0 completed (2532.915 ms) ====== [2025-09-25T22:48:13.565Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-25T22:48:13.565Z] GC before operation: completed in 35.321 ms, heap usage 107.899 MB -> 5.174 MB. [2025-09-25T22:48:15.527Z] Camera thread performed 122 scans. [2025-09-25T22:48:15.527Z] ====== philosophers (scala) [default], iteration 1 completed (1930.267 ms) ====== [2025-09-25T22:48:15.527Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-25T22:48:15.527Z] GC before operation: completed in 27.882 ms, heap usage 145.352 MB -> 5.174 MB. [2025-09-25T22:48:18.571Z] Camera thread performed 122 scans. [2025-09-25T22:48:18.571Z] ====== philosophers (scala) [default], iteration 2 completed (2537.063 ms) ====== [2025-09-25T22:48:18.571Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-25T22:48:18.571Z] GC before operation: completed in 19.036 ms, heap usage 89.298 MB -> 5.175 MB. [2025-09-25T22:48:20.530Z] Camera thread performed 122 scans. [2025-09-25T22:48:20.530Z] ====== philosophers (scala) [default], iteration 3 completed (2600.939 ms) ====== [2025-09-25T22:48:20.530Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-25T22:48:20.530Z] GC before operation: completed in 18.982 ms, heap usage 19.500 MB -> 5.176 MB. [2025-09-25T22:48:23.559Z] Camera thread performed 122 scans. [2025-09-25T22:48:23.559Z] ====== philosophers (scala) [default], iteration 4 completed (2480.913 ms) ====== [2025-09-25T22:48:23.559Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-25T22:48:23.559Z] GC before operation: completed in 22.139 ms, heap usage 101.445 MB -> 5.176 MB. [2025-09-25T22:48:26.572Z] Camera thread performed 122 scans. [2025-09-25T22:48:26.572Z] ====== philosophers (scala) [default], iteration 5 completed (2815.786 ms) ====== [2025-09-25T22:48:26.572Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-25T22:48:26.572Z] GC before operation: completed in 19.822 ms, heap usage 22.354 MB -> 5.176 MB. [2025-09-25T22:48:29.605Z] Camera thread performed 122 scans. [2025-09-25T22:48:29.605Z] ====== philosophers (scala) [default], iteration 6 completed (2819.439 ms) ====== [2025-09-25T22:48:29.605Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-25T22:48:29.605Z] GC before operation: completed in 20.681 ms, heap usage 128.849 MB -> 5.176 MB. [2025-09-25T22:48:30.557Z] Camera thread performed 122 scans. [2025-09-25T22:48:30.557Z] ====== philosophers (scala) [default], iteration 7 completed (1773.524 ms) ====== [2025-09-25T22:48:30.557Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-25T22:48:30.557Z] GC before operation: completed in 21.322 ms, heap usage 134.383 MB -> 5.177 MB. [2025-09-25T22:48:33.574Z] Camera thread performed 122 scans. [2025-09-25T22:48:33.574Z] ====== philosophers (scala) [default], iteration 8 completed (2600.306 ms) ====== [2025-09-25T22:48:33.574Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-25T22:48:33.574Z] GC before operation: completed in 24.107 ms, heap usage 85.321 MB -> 5.178 MB. [2025-09-25T22:48:36.632Z] Camera thread performed 122 scans. [2025-09-25T22:48:36.632Z] ====== philosophers (scala) [default], iteration 9 completed (2521.087 ms) ====== [2025-09-25T22:48:36.632Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-25T22:48:36.632Z] GC before operation: completed in 22.453 ms, heap usage 142.787 MB -> 5.177 MB. [2025-09-25T22:48:38.585Z] Camera thread performed 122 scans. [2025-09-25T22:48:38.585Z] ====== philosophers (scala) [default], iteration 10 completed (2783.776 ms) ====== [2025-09-25T22:48:38.585Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-25T22:48:38.585Z] GC before operation: completed in 21.359 ms, heap usage 5.797 MB -> 5.178 MB. [2025-09-25T22:48:41.642Z] Camera thread performed 122 scans. [2025-09-25T22:48:41.642Z] ====== philosophers (scala) [default], iteration 11 completed (2485.061 ms) ====== [2025-09-25T22:48:41.642Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-25T22:48:41.642Z] GC before operation: completed in 23.881 ms, heap usage 7.297 MB -> 5.178 MB. [2025-09-25T22:48:43.595Z] Camera thread performed 122 scans. [2025-09-25T22:48:43.596Z] ====== philosophers (scala) [default], iteration 12 completed (2247.499 ms) ====== [2025-09-25T22:48:43.596Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-25T22:48:43.596Z] GC before operation: completed in 19.133 ms, heap usage 15.351 MB -> 5.178 MB. [2025-09-25T22:48:46.612Z] Camera thread performed 122 scans. [2025-09-25T22:48:46.612Z] ====== philosophers (scala) [default], iteration 13 completed (2341.544 ms) ====== [2025-09-25T22:48:46.612Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-25T22:48:46.612Z] GC before operation: completed in 18.855 ms, heap usage 18.294 MB -> 5.178 MB. [2025-09-25T22:48:48.299Z] Camera thread performed 122 scans. [2025-09-25T22:48:48.299Z] ====== philosophers (scala) [default], iteration 14 completed (2654.573 ms) ====== [2025-09-25T22:48:48.299Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-25T22:48:48.299Z] GC before operation: completed in 19.834 ms, heap usage 80.819 MB -> 5.179 MB. [2025-09-25T22:48:51.320Z] Camera thread performed 122 scans. [2025-09-25T22:48:51.320Z] ====== philosophers (scala) [default], iteration 15 completed (2676.409 ms) ====== [2025-09-25T22:48:51.320Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-25T22:48:51.320Z] GC before operation: completed in 21.895 ms, heap usage 19.292 MB -> 5.179 MB. [2025-09-25T22:48:54.336Z] Camera thread performed 122 scans. [2025-09-25T22:48:54.336Z] ====== philosophers (scala) [default], iteration 16 completed (2856.872 ms) ====== [2025-09-25T22:48:54.336Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-25T22:48:54.336Z] GC before operation: completed in 18.944 ms, heap usage 30.296 MB -> 5.179 MB. [2025-09-25T22:48:57.367Z] Camera thread performed 122 scans. [2025-09-25T22:48:57.367Z] ====== philosophers (scala) [default], iteration 17 completed (3110.681 ms) ====== [2025-09-25T22:48:57.367Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-25T22:48:57.367Z] GC before operation: completed in 21.139 ms, heap usage 51.393 MB -> 5.179 MB. [2025-09-25T22:49:00.467Z] Camera thread performed 122 scans. [2025-09-25T22:49:00.467Z] ====== philosophers (scala) [default], iteration 18 completed (2603.083 ms) ====== [2025-09-25T22:49:00.467Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-25T22:49:00.467Z] GC before operation: completed in 20.666 ms, heap usage 81.341 MB -> 5.179 MB. [2025-09-25T22:49:02.425Z] Camera thread performed 122 scans. [2025-09-25T22:49:02.425Z] ====== philosophers (scala) [default], iteration 19 completed (2642.857 ms) ====== [2025-09-25T22:49:02.425Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-25T22:49:02.425Z] GC before operation: completed in 23.354 ms, heap usage 18.884 MB -> 5.179 MB. [2025-09-25T22:49:05.449Z] Camera thread performed 122 scans. [2025-09-25T22:49:05.449Z] ====== philosophers (scala) [default], iteration 20 completed (2547.549 ms) ====== [2025-09-25T22:49:05.449Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-25T22:49:05.449Z] GC before operation: completed in 24.597 ms, heap usage 44.716 MB -> 5.179 MB. [2025-09-25T22:49:07.408Z] Camera thread performed 122 scans. [2025-09-25T22:49:07.408Z] ====== philosophers (scala) [default], iteration 21 completed (2535.391 ms) ====== [2025-09-25T22:49:07.408Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-25T22:49:07.408Z] GC before operation: completed in 26.039 ms, heap usage 82.821 MB -> 5.179 MB. [2025-09-25T22:49:10.428Z] Camera thread performed 122 scans. [2025-09-25T22:49:10.428Z] ====== philosophers (scala) [default], iteration 22 completed (2554.473 ms) ====== [2025-09-25T22:49:10.428Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-25T22:49:10.428Z] GC before operation: completed in 19.738 ms, heap usage 32.213 MB -> 5.180 MB. [2025-09-25T22:49:12.560Z] Camera thread performed 122 scans. [2025-09-25T22:49:12.560Z] ====== philosophers (scala) [default], iteration 23 completed (2513.169 ms) ====== [2025-09-25T22:49:12.560Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-25T22:49:12.560Z] GC before operation: completed in 22.017 ms, heap usage 109.348 MB -> 5.179 MB. [2025-09-25T22:49:15.577Z] Camera thread performed 122 scans. [2025-09-25T22:49:15.578Z] ====== philosophers (scala) [default], iteration 24 completed (2696.884 ms) ====== [2025-09-25T22:49:15.578Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-25T22:49:15.578Z] GC before operation: completed in 21.621 ms, heap usage 105.315 MB -> 5.179 MB. [2025-09-25T22:49:18.597Z] Camera thread performed 122 scans. [2025-09-25T22:49:18.597Z] ====== philosophers (scala) [default], iteration 25 completed (2706.018 ms) ====== [2025-09-25T22:49:18.597Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-25T22:49:18.597Z] GC before operation: completed in 21.539 ms, heap usage 51.291 MB -> 5.179 MB. [2025-09-25T22:49:21.621Z] Camera thread performed 122 scans. [2025-09-25T22:49:21.622Z] ====== philosophers (scala) [default], iteration 26 completed (2762.822 ms) ====== [2025-09-25T22:49:21.622Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-25T22:49:21.622Z] GC before operation: completed in 18.672 ms, heap usage 18.890 MB -> 5.180 MB. [2025-09-25T22:49:24.640Z] Camera thread performed 122 scans. [2025-09-25T22:49:24.640Z] ====== philosophers (scala) [default], iteration 27 completed (2909.175 ms) ====== [2025-09-25T22:49:24.640Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-25T22:49:24.640Z] GC before operation: completed in 21.572 ms, heap usage 129.849 MB -> 5.180 MB. [2025-09-25T22:49:26.595Z] Camera thread performed 122 scans. [2025-09-25T22:49:26.596Z] ====== philosophers (scala) [default], iteration 28 completed (2693.834 ms) ====== [2025-09-25T22:49:26.596Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-25T22:49:26.596Z] GC before operation: completed in 23.005 ms, heap usage 147.842 MB -> 5.180 MB. [2025-09-25T22:49:29.610Z] Camera thread performed 122 scans. [2025-09-25T22:49:29.610Z] ====== philosophers (scala) [default], iteration 29 completed (2401.032 ms) ====== [2025-09-25T22:49:29.610Z] ----------------------------------- [2025-09-25T22:49:29.610Z] renaissance-philosophers_0_PASSED [2025-09-25T22:49:29.610Z] ----------------------------------- [2025-09-25T22:49:29.610Z] [2025-09-25T22:49:29.610Z] TEST TEARDOWN: [2025-09-25T22:49:29.610Z] Nothing to be done for teardown. [2025-09-25T22:49:29.610Z] renaissance-philosophers_0 Finish Time: Thu Sep 25 22:49:29 2025 Epoch Time (ms): 1758840569020