[2025-09-18T16:51:59.404Z] Running test renaissance-philosophers_0 ... [2025-09-18T16:51:59.404Z] =============================================== [2025-09-18T16:51:59.732Z] renaissance-philosophers_0 Start Time: Thu Sep 18 16:51:59 2025 Epoch Time (ms): 1758214319541 [2025-09-18T16:52:00.095Z] variation: NoOptions [2025-09-18T16:52:00.095Z] JVM_OPTIONS: [2025-09-18T16:52:00.095Z] { \ [2025-09-18T16:52:00.095Z] echo ""; echo "TEST SETUP:"; \ [2025-09-18T16:52:00.095Z] echo "Nothing to be done for setup."; \ [2025-09-18T16:52:00.095Z] mkdir -p "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17582116194314\\renaissance-philosophers_0"; \ [2025-09-18T16:52:00.095Z] cd "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17582116194314\\renaissance-philosophers_0"; \ [2025-09-18T16:52:00.095Z] echo ""; echo "TESTING:"; \ [2025-09-18T16:52:00.095Z] "c:/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17582116194314\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-09-18T16:52:00.095Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-philosophers_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17582116194314\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-18T16:52:00.095Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-18T16:52:00.096Z] echo "Nothing to be done for teardown."; \ [2025-09-18T16:52:00.096Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17582116194314\\TestTargetResult"; [2025-09-18T16:52:00.096Z] [2025-09-18T16:52:00.096Z] TEST SETUP: [2025-09-18T16:52:00.096Z] Nothing to be done for setup. [2025-09-18T16:52:00.096Z] [2025-09-18T16:52:00.096Z] TESTING: [2025-09-18T16:52:00.841Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-09-18T16:52:00.841Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17582116194314/renaissance-philosophers_0/launcher-165200-200078553120883398/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-09-18T16:52:00.841Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-09-18T16:52:00.841Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-09-18T16:52:01.285Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-09-18T16:52:01.285Z] GC before operation: completed in 16.350 ms, heap usage 29.144 MB -> 5.034 MB. [2025-09-18T16:52:03.123Z] Camera thread performed 122 scans. [2025-09-18T16:52:03.123Z] ====== philosophers (scala) [default], iteration 0 completed (1723.027 ms) ====== [2025-09-18T16:52:03.123Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-09-18T16:52:03.123Z] GC before operation: completed in 14.475 ms, heap usage 48.469 MB -> 5.377 MB. [2025-09-18T16:52:04.267Z] Camera thread performed 122 scans. [2025-09-18T16:52:04.267Z] ====== philosophers (scala) [default], iteration 1 completed (1351.031 ms) ====== [2025-09-18T16:52:04.267Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-09-18T16:52:04.267Z] GC before operation: completed in 11.257 ms, heap usage 10.508 MB -> 5.377 MB. [2025-09-18T16:52:05.449Z] Camera thread performed 122 scans. [2025-09-18T16:52:05.449Z] ====== philosophers (scala) [default], iteration 2 completed (1340.432 ms) ====== [2025-09-18T16:52:05.449Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-09-18T16:52:05.449Z] GC before operation: completed in 10.969 ms, heap usage 26.377 MB -> 5.379 MB. [2025-09-18T16:52:07.141Z] Camera thread performed 122 scans. [2025-09-18T16:52:07.141Z] ====== philosophers (scala) [default], iteration 3 completed (1292.257 ms) ====== [2025-09-18T16:52:07.141Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-09-18T16:52:07.141Z] GC before operation: completed in 10.427 ms, heap usage 17.579 MB -> 5.379 MB. [2025-09-18T16:52:08.318Z] Camera thread performed 122 scans. [2025-09-18T16:52:08.318Z] ====== philosophers (scala) [default], iteration 4 completed (1367.136 ms) ====== [2025-09-18T16:52:08.318Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-09-18T16:52:08.318Z] GC before operation: completed in 11.152 ms, heap usage 71.552 MB -> 5.379 MB. [2025-09-18T16:52:09.501Z] Camera thread performed 122 scans. [2025-09-18T16:52:09.501Z] ====== philosophers (scala) [default], iteration 5 completed (1377.156 ms) ====== [2025-09-18T16:52:09.501Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-09-18T16:52:09.501Z] GC before operation: completed in 10.409 ms, heap usage 9.053 MB -> 5.379 MB. [2025-09-18T16:52:11.186Z] Camera thread performed 122 scans. [2025-09-18T16:52:11.186Z] ====== philosophers (scala) [default], iteration 6 completed (1314.884 ms) ====== [2025-09-18T16:52:11.186Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-09-18T16:52:11.186Z] GC before operation: completed in 10.548 ms, heap usage 22.637 MB -> 5.380 MB. [2025-09-18T16:52:12.362Z] Camera thread performed 122 scans. [2025-09-18T16:52:12.362Z] ====== philosophers (scala) [default], iteration 7 completed (1310.932 ms) ====== [2025-09-18T16:52:12.362Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-09-18T16:52:12.362Z] GC before operation: completed in 11.348 ms, heap usage 14.438 MB -> 5.380 MB. [2025-09-18T16:52:13.533Z] Camera thread performed 122 scans. [2025-09-18T16:52:13.533Z] ====== philosophers (scala) [default], iteration 8 completed (1339.768 ms) ====== [2025-09-18T16:52:13.533Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-09-18T16:52:13.533Z] GC before operation: completed in 10.696 ms, heap usage 55.025 MB -> 5.380 MB. [2025-09-18T16:52:15.216Z] Camera thread performed 122 scans. [2025-09-18T16:52:15.216Z] ====== philosophers (scala) [default], iteration 9 completed (1409.663 ms) ====== [2025-09-18T16:52:15.216Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-09-18T16:52:15.216Z] GC before operation: completed in 10.636 ms, heap usage 25.597 MB -> 5.380 MB. [2025-09-18T16:52:16.375Z] Camera thread performed 122 scans. [2025-09-18T16:52:16.375Z] ====== philosophers (scala) [default], iteration 10 completed (1336.051 ms) ====== [2025-09-18T16:52:16.375Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-09-18T16:52:16.375Z] GC before operation: completed in 11.001 ms, heap usage 64.971 MB -> 5.380 MB. [2025-09-18T16:52:18.068Z] Camera thread performed 122 scans. [2025-09-18T16:52:18.068Z] ====== philosophers (scala) [default], iteration 11 completed (1371.033 ms) ====== [2025-09-18T16:52:18.068Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-09-18T16:52:18.068Z] GC before operation: completed in 11.932 ms, heap usage 45.393 MB -> 5.381 MB. [2025-09-18T16:52:19.250Z] Camera thread performed 122 scans. [2025-09-18T16:52:19.250Z] ====== philosophers (scala) [default], iteration 12 completed (1362.662 ms) ====== [2025-09-18T16:52:19.250Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-09-18T16:52:19.250Z] GC before operation: completed in 10.602 ms, heap usage 25.031 MB -> 5.381 MB. [2025-09-18T16:52:20.426Z] Camera thread performed 122 scans. [2025-09-18T16:52:20.426Z] ====== philosophers (scala) [default], iteration 13 completed (1340.106 ms) ====== [2025-09-18T16:52:20.426Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-09-18T16:52:20.426Z] GC before operation: completed in 10.489 ms, heap usage 55.009 MB -> 5.381 MB. [2025-09-18T16:52:22.114Z] Camera thread performed 122 scans. [2025-09-18T16:52:22.114Z] ====== philosophers (scala) [default], iteration 14 completed (1351.123 ms) ====== [2025-09-18T16:52:22.114Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-09-18T16:52:22.114Z] GC before operation: completed in 12.178 ms, heap usage 83.965 MB -> 5.381 MB. [2025-09-18T16:52:23.293Z] Camera thread performed 122 scans. [2025-09-18T16:52:23.294Z] ====== philosophers (scala) [default], iteration 15 completed (1362.692 ms) ====== [2025-09-18T16:52:23.294Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-09-18T16:52:23.294Z] GC before operation: completed in 10.361 ms, heap usage 53.363 MB -> 5.381 MB. [2025-09-18T16:52:24.467Z] Camera thread performed 122 scans. [2025-09-18T16:52:24.468Z] ====== philosophers (scala) [default], iteration 16 completed (1329.078 ms) ====== [2025-09-18T16:52:24.468Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-09-18T16:52:24.468Z] GC before operation: completed in 10.913 ms, heap usage 22.254 MB -> 5.382 MB. [2025-09-18T16:52:26.154Z] Camera thread performed 122 scans. [2025-09-18T16:52:26.154Z] ====== philosophers (scala) [default], iteration 17 completed (1279.469 ms) ====== [2025-09-18T16:52:26.154Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-09-18T16:52:26.154Z] GC before operation: completed in 11.341 ms, heap usage 12.045 MB -> 5.381 MB. [2025-09-18T16:52:27.352Z] Camera thread performed 122 scans. [2025-09-18T16:52:27.352Z] ====== philosophers (scala) [default], iteration 18 completed (1367.078 ms) ====== [2025-09-18T16:52:27.352Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-09-18T16:52:27.352Z] GC before operation: completed in 10.703 ms, heap usage 17.985 MB -> 5.382 MB. [2025-09-18T16:52:28.524Z] Camera thread performed 122 scans. [2025-09-18T16:52:28.524Z] ====== philosophers (scala) [default], iteration 19 completed (1385.620 ms) ====== [2025-09-18T16:52:28.524Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-09-18T16:52:28.524Z] GC before operation: completed in 12.022 ms, heap usage 44.393 MB -> 5.382 MB. [2025-09-18T16:52:30.209Z] Camera thread performed 122 scans. [2025-09-18T16:52:30.209Z] ====== philosophers (scala) [default], iteration 20 completed (1355.632 ms) ====== [2025-09-18T16:52:30.209Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-09-18T16:52:30.209Z] GC before operation: completed in 10.680 ms, heap usage 40.006 MB -> 5.382 MB. [2025-09-18T16:52:31.384Z] Camera thread performed 122 scans. [2025-09-18T16:52:31.384Z] ====== philosophers (scala) [default], iteration 21 completed (1337.889 ms) ====== [2025-09-18T16:52:31.384Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-09-18T16:52:31.384Z] GC before operation: completed in 10.585 ms, heap usage 21.025 MB -> 5.382 MB. [2025-09-18T16:52:32.559Z] Camera thread performed 122 scans. [2025-09-18T16:52:32.559Z] ====== philosophers (scala) [default], iteration 22 completed (1304.228 ms) ====== [2025-09-18T16:52:32.559Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-09-18T16:52:32.559Z] GC before operation: completed in 10.597 ms, heap usage 31.594 MB -> 5.382 MB. [2025-09-18T16:52:34.244Z] Camera thread performed 122 scans. [2025-09-18T16:52:34.244Z] ====== philosophers (scala) [default], iteration 23 completed (1381.829 ms) ====== [2025-09-18T16:52:34.244Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-09-18T16:52:34.244Z] GC before operation: completed in 11.281 ms, heap usage 60.004 MB -> 5.382 MB. [2025-09-18T16:52:35.419Z] Camera thread performed 122 scans. [2025-09-18T16:52:35.419Z] ====== philosophers (scala) [default], iteration 24 completed (1346.311 ms) ====== [2025-09-18T16:52:35.419Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-09-18T16:52:35.419Z] GC before operation: completed in 11.649 ms, heap usage 68.590 MB -> 5.382 MB. [2025-09-18T16:52:37.104Z] Camera thread performed 122 scans. [2025-09-18T16:52:37.104Z] ====== philosophers (scala) [default], iteration 25 completed (1433.680 ms) ====== [2025-09-18T16:52:37.104Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-09-18T16:52:37.104Z] GC before operation: completed in 10.468 ms, heap usage 66.376 MB -> 5.382 MB. [2025-09-18T16:52:38.278Z] Camera thread performed 122 scans. [2025-09-18T16:52:38.278Z] ====== philosophers (scala) [default], iteration 26 completed (1353.700 ms) ====== [2025-09-18T16:52:38.278Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-09-18T16:52:38.278Z] GC before operation: completed in 10.847 ms, heap usage 17.043 MB -> 5.382 MB. [2025-09-18T16:52:39.453Z] Camera thread performed 122 scans. [2025-09-18T16:52:39.453Z] ====== philosophers (scala) [default], iteration 27 completed (1380.595 ms) ====== [2025-09-18T16:52:39.453Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-09-18T16:52:39.812Z] GC before operation: completed in 10.660 ms, heap usage 29.428 MB -> 5.382 MB. [2025-09-18T16:52:40.967Z] Camera thread performed 122 scans. [2025-09-18T16:52:40.967Z] ====== philosophers (scala) [default], iteration 28 completed (1347.880 ms) ====== [2025-09-18T16:52:40.968Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-09-18T16:52:40.968Z] GC before operation: completed in 10.976 ms, heap usage 15.470 MB -> 5.382 MB. [2025-09-18T16:52:42.642Z] Camera thread performed 122 scans. [2025-09-18T16:52:42.642Z] ====== philosophers (scala) [default], iteration 29 completed (1346.918 ms) ====== [2025-09-18T16:52:42.642Z] ----------------------------------- [2025-09-18T16:52:42.642Z] renaissance-philosophers_0_PASSED [2025-09-18T16:52:42.642Z] ----------------------------------- [2025-09-18T16:52:42.642Z] [2025-09-18T16:52:42.643Z] TEST TEARDOWN: [2025-09-18T16:52:42.643Z] Nothing to be done for teardown. [2025-09-18T16:52:42.643Z] renaissance-philosophers_0 Finish Time: Thu Sep 18 16:52:42 2025 Epoch Time (ms): 1758214362414