[2025-09-04T23:35:06.845Z] Running test renaissance-philosophers_0 ... [2025-09-04T23:35:06.845Z] =============================================== [2025-09-04T23:35:06.845Z] renaissance-philosophers_0 Start Time: Thu Sep 4 23:35:06 2025 Epoch Time (ms): 1757028906719 [2025-09-04T23:35:06.845Z] variation: NoOptions [2025-09-04T23:35:07.166Z] JVM_OPTIONS: [2025-09-04T23:35:07.166Z] { \ [2025-09-04T23:35:07.166Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T23:35:07.166Z] echo "Nothing to be done for setup."; \ [2025-09-04T23:35:07.166Z] mkdir -p "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17570284053461\\renaissance-philosophers_0"; \ [2025-09-04T23:35:07.166Z] cd "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17570284053461\\renaissance-philosophers_0"; \ [2025-09-04T23:35:07.166Z] echo ""; echo "TESTING:"; \ [2025-09-04T23:35:07.166Z] "c:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17570284053461\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-09-04T23:35:07.166Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17570284053461\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T23:35:07.166Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T23:35:07.166Z] echo "Nothing to be done for teardown."; \ [2025-09-04T23:35:07.166Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17570284053461\\TestTargetResult"; [2025-09-04T23:35:07.166Z] [2025-09-04T23:35:07.166Z] TEST SETUP: [2025-09-04T23:35:07.166Z] Nothing to be done for setup. [2025-09-04T23:35:07.166Z] [2025-09-04T23:35:07.166Z] TESTING: [2025-09-04T23:35:07.858Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-09-04T23:35:07.858Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17570284053461/renaissance-philosophers_0/launcher-233507-6573864528285125717/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-09-04T23:35:07.859Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-09-04T23:35:07.859Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-09-04T23:35:08.181Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-04T23:35:08.181Z] GC before operation: completed in 17.020 ms, heap usage 29.144 MB -> 5.050 MB. [2025-09-04T23:35:10.380Z] Camera thread performed 122 scans. [2025-09-04T23:35:10.380Z] ====== philosophers (scala) [default], iteration 0 completed (1844.296 ms) ====== [2025-09-04T23:35:10.380Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-04T23:35:10.380Z] GC before operation: completed in 14.917 ms, heap usage 91.586 MB -> 5.391 MB. [2025-09-04T23:35:11.473Z] Camera thread performed 122 scans. [2025-09-04T23:35:11.473Z] ====== philosophers (scala) [default], iteration 1 completed (1402.574 ms) ====== [2025-09-04T23:35:11.473Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-04T23:35:11.473Z] GC before operation: completed in 12.474 ms, heap usage 54.061 MB -> 5.391 MB. [2025-09-04T23:35:13.053Z] Camera thread performed 122 scans. [2025-09-04T23:35:13.053Z] ====== philosophers (scala) [default], iteration 2 completed (1497.198 ms) ====== [2025-09-04T23:35:13.053Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-04T23:35:13.053Z] GC before operation: completed in 12.017 ms, heap usage 114.579 MB -> 5.393 MB. [2025-09-04T23:35:14.632Z] Camera thread performed 122 scans. [2025-09-04T23:35:14.632Z] ====== philosophers (scala) [default], iteration 3 completed (1447.140 ms) ====== [2025-09-04T23:35:14.632Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-04T23:35:14.632Z] GC before operation: completed in 11.831 ms, heap usage 88.380 MB -> 5.393 MB. [2025-09-04T23:35:16.212Z] Camera thread performed 122 scans. [2025-09-04T23:35:16.213Z] ====== philosophers (scala) [default], iteration 4 completed (1598.148 ms) ====== [2025-09-04T23:35:16.213Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-04T23:35:16.213Z] GC before operation: completed in 11.305 ms, heap usage 9.187 MB -> 5.393 MB. [2025-09-04T23:35:17.793Z] Camera thread performed 122 scans. [2025-09-04T23:35:17.793Z] ====== philosophers (scala) [default], iteration 5 completed (1568.140 ms) ====== [2025-09-04T23:35:17.793Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-04T23:35:17.793Z] GC before operation: completed in 11.509 ms, heap usage 64.171 MB -> 5.393 MB. [2025-09-04T23:35:19.372Z] Camera thread performed 122 scans. [2025-09-04T23:35:19.372Z] ====== philosophers (scala) [default], iteration 6 completed (1644.201 ms) ====== [2025-09-04T23:35:19.372Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-04T23:35:19.372Z] GC before operation: completed in 11.745 ms, heap usage 82.142 MB -> 5.394 MB. [2025-09-04T23:35:20.956Z] Camera thread performed 122 scans. [2025-09-04T23:35:20.956Z] ====== philosophers (scala) [default], iteration 7 completed (1608.460 ms) ====== [2025-09-04T23:35:20.956Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-04T23:35:20.956Z] GC before operation: completed in 11.798 ms, heap usage 46.079 MB -> 5.394 MB. [2025-09-04T23:35:22.542Z] Camera thread performed 122 scans. [2025-09-04T23:35:22.543Z] ====== philosophers (scala) [default], iteration 8 completed (1638.509 ms) ====== [2025-09-04T23:35:22.543Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-04T23:35:22.543Z] GC before operation: completed in 11.208 ms, heap usage 132.105 MB -> 5.394 MB. [2025-09-04T23:35:24.129Z] Camera thread performed 122 scans. [2025-09-04T23:35:24.129Z] ====== philosophers (scala) [default], iteration 9 completed (1633.427 ms) ====== [2025-09-04T23:35:24.129Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-04T23:35:24.129Z] GC before operation: completed in 11.118 ms, heap usage 9.968 MB -> 5.394 MB. [2025-09-04T23:35:25.720Z] Camera thread performed 122 scans. [2025-09-04T23:35:25.720Z] ====== philosophers (scala) [default], iteration 10 completed (1604.017 ms) ====== [2025-09-04T23:35:25.720Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-04T23:35:25.720Z] GC before operation: completed in 11.445 ms, heap usage 77.593 MB -> 5.395 MB. [2025-09-04T23:35:27.309Z] Camera thread performed 122 scans. [2025-09-04T23:35:27.309Z] ====== philosophers (scala) [default], iteration 11 completed (1517.818 ms) ====== [2025-09-04T23:35:27.309Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-04T23:35:27.309Z] GC before operation: completed in 11.281 ms, heap usage 26.097 MB -> 5.395 MB. [2025-09-04T23:35:28.904Z] Camera thread performed 122 scans. [2025-09-04T23:35:28.904Z] ====== philosophers (scala) [default], iteration 12 completed (1688.449 ms) ====== [2025-09-04T23:35:28.904Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-04T23:35:28.904Z] GC before operation: completed in 12.097 ms, heap usage 38.597 MB -> 5.395 MB. [2025-09-04T23:35:30.496Z] Camera thread performed 122 scans. [2025-09-04T23:35:30.496Z] ====== philosophers (scala) [default], iteration 13 completed (1600.084 ms) ====== [2025-09-04T23:35:30.496Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-04T23:35:30.819Z] GC before operation: completed in 11.493 ms, heap usage 66.069 MB -> 5.395 MB. [2025-09-04T23:35:32.422Z] Camera thread performed 122 scans. [2025-09-04T23:35:32.422Z] ====== philosophers (scala) [default], iteration 14 completed (1596.218 ms) ====== [2025-09-04T23:35:32.422Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-04T23:35:32.422Z] GC before operation: completed in 11.554 ms, heap usage 6.503 MB -> 5.396 MB. [2025-09-04T23:35:34.005Z] Camera thread performed 122 scans. [2025-09-04T23:35:34.005Z] ====== philosophers (scala) [default], iteration 15 completed (1580.378 ms) ====== [2025-09-04T23:35:34.005Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-04T23:35:34.005Z] GC before operation: completed in 11.557 ms, heap usage 104.062 MB -> 5.395 MB. [2025-09-04T23:35:35.588Z] Camera thread performed 122 scans. [2025-09-04T23:35:35.588Z] ====== philosophers (scala) [default], iteration 16 completed (1598.587 ms) ====== [2025-09-04T23:35:35.588Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-04T23:35:35.588Z] GC before operation: completed in 11.258 ms, heap usage 93.599 MB -> 5.396 MB. [2025-09-04T23:35:37.172Z] Camera thread performed 122 scans. [2025-09-04T23:35:37.172Z] ====== philosophers (scala) [default], iteration 17 completed (1644.462 ms) ====== [2025-09-04T23:35:37.172Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-04T23:35:37.172Z] GC before operation: completed in 11.006 ms, heap usage 90.600 MB -> 5.396 MB. [2025-09-04T23:35:38.756Z] Camera thread performed 122 scans. [2025-09-04T23:35:38.756Z] ====== philosophers (scala) [default], iteration 18 completed (1608.570 ms) ====== [2025-09-04T23:35:38.756Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-04T23:35:38.756Z] GC before operation: completed in 11.678 ms, heap usage 82.595 MB -> 5.396 MB. [2025-09-04T23:35:40.342Z] Camera thread performed 122 scans. [2025-09-04T23:35:40.342Z] ====== philosophers (scala) [default], iteration 19 completed (1582.872 ms) ====== [2025-09-04T23:35:40.342Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-04T23:35:40.342Z] GC before operation: completed in 11.169 ms, heap usage 115.559 MB -> 5.396 MB. [2025-09-04T23:35:41.928Z] Camera thread performed 122 scans. [2025-09-04T23:35:41.928Z] ====== philosophers (scala) [default], iteration 20 completed (1679.304 ms) ====== [2025-09-04T23:35:41.928Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-04T23:35:41.928Z] GC before operation: completed in 12.851 ms, heap usage 107.101 MB -> 5.396 MB. [2025-09-04T23:35:43.518Z] Camera thread performed 122 scans. [2025-09-04T23:35:43.518Z] ====== philosophers (scala) [default], iteration 21 completed (1633.615 ms) ====== [2025-09-04T23:35:43.518Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-04T23:35:43.840Z] GC before operation: completed in 11.722 ms, heap usage 44.573 MB -> 5.396 MB. [2025-09-04T23:35:45.424Z] Camera thread performed 122 scans. [2025-09-04T23:35:45.424Z] ====== philosophers (scala) [default], iteration 22 completed (1545.894 ms) ====== [2025-09-04T23:35:45.424Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-04T23:35:45.424Z] GC before operation: completed in 11.467 ms, heap usage 84.091 MB -> 5.396 MB. [2025-09-04T23:35:47.019Z] Camera thread performed 122 scans. [2025-09-04T23:35:47.019Z] ====== philosophers (scala) [default], iteration 23 completed (1660.528 ms) ====== [2025-09-04T23:35:47.019Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-04T23:35:47.019Z] GC before operation: completed in 11.373 ms, heap usage 37.587 MB -> 5.396 MB. [2025-09-04T23:35:48.601Z] Camera thread performed 122 scans. [2025-09-04T23:35:48.601Z] ====== philosophers (scala) [default], iteration 24 completed (1681.491 ms) ====== [2025-09-04T23:35:48.601Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-04T23:35:48.601Z] GC before operation: completed in 11.419 ms, heap usage 15.602 MB -> 5.396 MB. [2025-09-04T23:35:50.179Z] Camera thread performed 122 scans. [2025-09-04T23:35:50.180Z] ====== philosophers (scala) [default], iteration 25 completed (1631.602 ms) ====== [2025-09-04T23:35:50.180Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-04T23:35:50.180Z] GC before operation: completed in 11.455 ms, heap usage 91.093 MB -> 5.396 MB. [2025-09-04T23:35:51.760Z] Camera thread performed 122 scans. [2025-09-04T23:35:51.760Z] ====== philosophers (scala) [default], iteration 26 completed (1638.928 ms) ====== [2025-09-04T23:35:51.760Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-04T23:35:51.760Z] GC before operation: completed in 11.596 ms, heap usage 116.099 MB -> 5.396 MB. [2025-09-04T23:35:53.912Z] Camera thread performed 122 scans. [2025-09-04T23:35:53.912Z] ====== philosophers (scala) [default], iteration 27 completed (1707.015 ms) ====== [2025-09-04T23:35:53.912Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-04T23:35:53.912Z] GC before operation: completed in 12.924 ms, heap usage 20.465 MB -> 5.396 MB. [2025-09-04T23:35:55.491Z] Camera thread performed 122 scans. [2025-09-04T23:35:55.491Z] ====== philosophers (scala) [default], iteration 28 completed (1685.334 ms) ====== [2025-09-04T23:35:55.491Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-04T23:35:55.491Z] GC before operation: completed in 11.240 ms, heap usage 131.598 MB -> 5.396 MB. [2025-09-04T23:35:57.064Z] Camera thread performed 122 scans. [2025-09-04T23:35:57.064Z] ====== philosophers (scala) [default], iteration 29 completed (1683.371 ms) ====== [2025-09-04T23:35:57.064Z] ----------------------------------- [2025-09-04T23:35:57.064Z] renaissance-philosophers_0_PASSED [2025-09-04T23:35:57.064Z] ----------------------------------- [2025-09-04T23:35:57.064Z] [2025-09-04T23:35:57.064Z] TEST TEARDOWN: [2025-09-04T23:35:57.064Z] Nothing to be done for teardown. [2025-09-04T23:35:57.369Z] renaissance-philosophers_0 Finish Time: Thu Sep 4 23:35:57 2025 Epoch Time (ms): 1757028957116