[2025-04-25T03:50:37.580Z] Running test renaissance-philosophers_0 ... [2025-04-25T03:50:37.906Z] =============================================== [2025-04-25T03:50:37.906Z] renaissance-philosophers_0 Start Time: Fri Apr 25 03:50:37 2025 Epoch Time (ms): 1745553037794 [2025-04-25T03:50:37.906Z] variation: NoOptions [2025-04-25T03:50:38.231Z] JVM_OPTIONS: [2025-04-25T03:50:38.231Z] { \ [2025-04-25T03:50:38.231Z] echo ""; echo "TEST SETUP:"; \ [2025-04-25T03:50:38.231Z] echo "Nothing to be done for setup."; \ [2025-04-25T03:50:38.231Z] mkdir -p "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17455527822372\\renaissance-philosophers_0"; \ [2025-04-25T03:50:38.231Z] cd "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17455527822372\\renaissance-philosophers_0"; \ [2025-04-25T03:50:38.231Z] echo ""; echo "TESTING:"; \ [2025-04-25T03:50:38.231Z] "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_17455527822372\\renaissance-philosophers_0"\\philosophers.json" philosophers; \ [2025-04-25T03:50:38.231Z] 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_17455527822372\\renaissance-philosophers_0"; else echo "-----------------------------------"; echo "renaissance-philosophers_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-25T03:50:38.231Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-25T03:50:38.231Z] echo "Nothing to be done for teardown."; \ [2025-04-25T03:50:38.231Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17455527822372\\TestTargetResult"; [2025-04-25T03:50:38.231Z] [2025-04-25T03:50:38.231Z] TEST SETUP: [2025-04-25T03:50:38.231Z] Nothing to be done for setup. [2025-04-25T03:50:38.231Z] [2025-04-25T03:50:38.231Z] TESTING: [2025-04-25T03:50:38.901Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-04-25T03:50:38.901Z] 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_17455527822372/renaissance-philosophers_0/launcher-035038-5540633790913954188/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-04-25T03:50:38.901Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-04-25T03:50:38.901Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-04-25T03:50:39.266Z] ====== philosophers (scala) [default], iteration 0 started ====== [2025-04-25T03:50:39.266Z] GC before operation: completed in 15.264 ms, heap usage 28.623 MB -> 5.005 MB. [2025-04-25T03:50:41.004Z] Camera thread performed 122 scans. [2025-04-25T03:50:41.004Z] ====== philosophers (scala) [default], iteration 0 completed (1552.608 ms) ====== [2025-04-25T03:50:41.004Z] ====== philosophers (scala) [default], iteration 1 started ====== [2025-04-25T03:50:41.004Z] GC before operation: completed in 12.671 ms, heap usage 48.035 MB -> 5.358 MB. [2025-04-25T03:50:42.112Z] Camera thread performed 122 scans. [2025-04-25T03:50:42.112Z] ====== philosophers (scala) [default], iteration 1 completed (1161.300 ms) ====== [2025-04-25T03:50:42.112Z] ====== philosophers (scala) [default], iteration 2 started ====== [2025-04-25T03:50:42.112Z] GC before operation: completed in 11.726 ms, heap usage 42.520 MB -> 5.358 MB. [2025-04-25T03:50:43.227Z] Camera thread performed 122 scans. [2025-04-25T03:50:43.227Z] ====== philosophers (scala) [default], iteration 2 completed (1293.400 ms) ====== [2025-04-25T03:50:43.227Z] ====== philosophers (scala) [default], iteration 3 started ====== [2025-04-25T03:50:43.227Z] GC before operation: completed in 10.485 ms, heap usage 46.565 MB -> 5.360 MB. [2025-04-25T03:50:44.337Z] Camera thread performed 122 scans. [2025-04-25T03:50:44.337Z] ====== philosophers (scala) [default], iteration 3 completed (1242.211 ms) ====== [2025-04-25T03:50:44.337Z] ====== philosophers (scala) [default], iteration 4 started ====== [2025-04-25T03:50:44.337Z] GC before operation: completed in 10.645 ms, heap usage 18.037 MB -> 5.360 MB. [2025-04-25T03:50:45.937Z] Camera thread performed 122 scans. [2025-04-25T03:50:45.937Z] ====== philosophers (scala) [default], iteration 4 completed (1325.291 ms) ====== [2025-04-25T03:50:45.937Z] ====== philosophers (scala) [default], iteration 5 started ====== [2025-04-25T03:50:45.937Z] GC before operation: completed in 10.136 ms, heap usage 10.077 MB -> 5.360 MB. [2025-04-25T03:50:47.044Z] Camera thread performed 122 scans. [2025-04-25T03:50:47.044Z] ====== philosophers (scala) [default], iteration 5 completed (1309.345 ms) ====== [2025-04-25T03:50:47.044Z] ====== philosophers (scala) [default], iteration 6 started ====== [2025-04-25T03:50:47.044Z] GC before operation: completed in 10.634 ms, heap usage 53.610 MB -> 5.360 MB. [2025-04-25T03:50:48.644Z] Camera thread performed 122 scans. [2025-04-25T03:50:48.644Z] ====== philosophers (scala) [default], iteration 6 completed (1332.797 ms) ====== [2025-04-25T03:50:48.644Z] ====== philosophers (scala) [default], iteration 7 started ====== [2025-04-25T03:50:48.644Z] GC before operation: completed in 10.733 ms, heap usage 13.594 MB -> 5.361 MB. [2025-04-25T03:50:49.752Z] Camera thread performed 122 scans. [2025-04-25T03:50:49.752Z] ====== philosophers (scala) [default], iteration 7 completed (1311.751 ms) ====== [2025-04-25T03:50:49.752Z] ====== philosophers (scala) [default], iteration 8 started ====== [2025-04-25T03:50:49.752Z] GC before operation: completed in 10.309 ms, heap usage 17.016 MB -> 5.361 MB. [2025-04-25T03:50:51.352Z] Camera thread performed 122 scans. [2025-04-25T03:50:51.352Z] ====== philosophers (scala) [default], iteration 8 completed (1311.700 ms) ====== [2025-04-25T03:50:51.352Z] ====== philosophers (scala) [default], iteration 9 started ====== [2025-04-25T03:50:51.352Z] GC before operation: completed in 11.260 ms, heap usage 74.383 MB -> 5.361 MB. [2025-04-25T03:50:52.458Z] Camera thread performed 122 scans. [2025-04-25T03:50:52.458Z] ====== philosophers (scala) [default], iteration 9 completed (1459.429 ms) ====== [2025-04-25T03:50:52.458Z] ====== philosophers (scala) [default], iteration 10 started ====== [2025-04-25T03:50:52.458Z] GC before operation: completed in 10.323 ms, heap usage 6.431 MB -> 5.361 MB. [2025-04-25T03:50:54.042Z] Camera thread performed 122 scans. [2025-04-25T03:50:54.042Z] ====== philosophers (scala) [default], iteration 10 completed (1320.873 ms) ====== [2025-04-25T03:50:54.042Z] ====== philosophers (scala) [default], iteration 11 started ====== [2025-04-25T03:50:54.042Z] GC before operation: completed in 10.727 ms, heap usage 81.578 MB -> 5.362 MB. [2025-04-25T03:50:55.150Z] Camera thread performed 122 scans. [2025-04-25T03:50:55.150Z] ====== philosophers (scala) [default], iteration 11 completed (1324.296 ms) ====== [2025-04-25T03:50:55.150Z] ====== philosophers (scala) [default], iteration 12 started ====== [2025-04-25T03:50:55.150Z] GC before operation: completed in 10.842 ms, heap usage 6.068 MB -> 5.362 MB. [2025-04-25T03:50:56.751Z] Camera thread performed 122 scans. [2025-04-25T03:50:56.751Z] ====== philosophers (scala) [default], iteration 12 completed (1314.560 ms) ====== [2025-04-25T03:50:56.751Z] ====== philosophers (scala) [default], iteration 13 started ====== [2025-04-25T03:50:56.751Z] GC before operation: completed in 10.631 ms, heap usage 32.066 MB -> 5.362 MB. [2025-04-25T03:50:57.859Z] Camera thread performed 122 scans. [2025-04-25T03:50:57.859Z] ====== philosophers (scala) [default], iteration 13 completed (1327.491 ms) ====== [2025-04-25T03:50:57.859Z] ====== philosophers (scala) [default], iteration 14 started ====== [2025-04-25T03:50:57.859Z] GC before operation: completed in 10.494 ms, heap usage 77.567 MB -> 5.362 MB. [2025-04-25T03:50:59.457Z] Camera thread performed 122 scans. [2025-04-25T03:50:59.457Z] ====== philosophers (scala) [default], iteration 14 completed (1263.557 ms) ====== [2025-04-25T03:50:59.457Z] ====== philosophers (scala) [default], iteration 15 started ====== [2025-04-25T03:50:59.457Z] GC before operation: completed in 10.264 ms, heap usage 26.988 MB -> 5.362 MB. [2025-04-25T03:51:00.563Z] Camera thread performed 122 scans. [2025-04-25T03:51:00.563Z] ====== philosophers (scala) [default], iteration 15 completed (1193.794 ms) ====== [2025-04-25T03:51:00.563Z] ====== philosophers (scala) [default], iteration 16 started ====== [2025-04-25T03:51:00.563Z] GC before operation: completed in 10.430 ms, heap usage 26.936 MB -> 5.362 MB. [2025-04-25T03:51:01.670Z] Camera thread performed 122 scans. [2025-04-25T03:51:01.670Z] ====== philosophers (scala) [default], iteration 16 completed (1195.675 ms) ====== [2025-04-25T03:51:01.670Z] ====== philosophers (scala) [default], iteration 17 started ====== [2025-04-25T03:51:01.670Z] GC before operation: completed in 10.222 ms, heap usage 63.966 MB -> 5.362 MB. [2025-04-25T03:51:02.775Z] Camera thread performed 122 scans. [2025-04-25T03:51:02.775Z] ====== philosophers (scala) [default], iteration 17 completed (1273.912 ms) ====== [2025-04-25T03:51:02.775Z] ====== philosophers (scala) [default], iteration 18 started ====== [2025-04-25T03:51:02.775Z] GC before operation: completed in 10.209 ms, heap usage 18.407 MB -> 5.362 MB. [2025-04-25T03:51:04.373Z] Camera thread performed 122 scans. [2025-04-25T03:51:04.373Z] ====== philosophers (scala) [default], iteration 18 completed (1210.683 ms) ====== [2025-04-25T03:51:04.373Z] ====== philosophers (scala) [default], iteration 19 started ====== [2025-04-25T03:51:04.373Z] GC before operation: completed in 10.531 ms, heap usage 8.491 MB -> 5.363 MB. [2025-04-25T03:51:05.480Z] Camera thread performed 122 scans. [2025-04-25T03:51:05.480Z] ====== philosophers (scala) [default], iteration 19 completed (1284.944 ms) ====== [2025-04-25T03:51:05.480Z] ====== philosophers (scala) [default], iteration 20 started ====== [2025-04-25T03:51:05.480Z] GC before operation: completed in 10.968 ms, heap usage 57.057 MB -> 5.362 MB. [2025-04-25T03:51:06.587Z] Camera thread performed 122 scans. [2025-04-25T03:51:06.588Z] ====== philosophers (scala) [default], iteration 20 completed (1306.535 ms) ====== [2025-04-25T03:51:06.588Z] ====== philosophers (scala) [default], iteration 21 started ====== [2025-04-25T03:51:06.588Z] GC before operation: completed in 10.524 ms, heap usage 61.053 MB -> 5.363 MB. [2025-04-25T03:51:08.189Z] Camera thread performed 122 scans. [2025-04-25T03:51:08.189Z] ====== philosophers (scala) [default], iteration 21 completed (1415.432 ms) ====== [2025-04-25T03:51:08.189Z] ====== philosophers (scala) [default], iteration 22 started ====== [2025-04-25T03:51:08.189Z] GC before operation: completed in 9.976 ms, heap usage 52.969 MB -> 5.363 MB. [2025-04-25T03:51:09.295Z] Camera thread performed 122 scans. [2025-04-25T03:51:09.296Z] ====== philosophers (scala) [default], iteration 22 completed (1296.164 ms) ====== [2025-04-25T03:51:09.296Z] ====== philosophers (scala) [default], iteration 23 started ====== [2025-04-25T03:51:09.296Z] GC before operation: completed in 10.500 ms, heap usage 54.972 MB -> 5.363 MB. [2025-04-25T03:51:10.895Z] Camera thread performed 122 scans. [2025-04-25T03:51:10.895Z] ====== philosophers (scala) [default], iteration 23 completed (1275.526 ms) ====== [2025-04-25T03:51:10.895Z] ====== philosophers (scala) [default], iteration 24 started ====== [2025-04-25T03:51:10.895Z] GC before operation: completed in 10.106 ms, heap usage 34.987 MB -> 5.363 MB. [2025-04-25T03:51:12.002Z] Camera thread performed 122 scans. [2025-04-25T03:51:12.002Z] ====== philosophers (scala) [default], iteration 24 completed (1363.126 ms) ====== [2025-04-25T03:51:12.002Z] ====== philosophers (scala) [default], iteration 25 started ====== [2025-04-25T03:51:12.002Z] GC before operation: completed in 10.776 ms, heap usage 65.967 MB -> 5.363 MB. [2025-04-25T03:51:13.601Z] Camera thread performed 122 scans. [2025-04-25T03:51:13.601Z] ====== philosophers (scala) [default], iteration 25 completed (1276.311 ms) ====== [2025-04-25T03:51:13.601Z] ====== philosophers (scala) [default], iteration 26 started ====== [2025-04-25T03:51:13.601Z] GC before operation: completed in 11.044 ms, heap usage 74.070 MB -> 5.363 MB. [2025-04-25T03:51:14.710Z] Camera thread performed 122 scans. [2025-04-25T03:51:14.710Z] ====== philosophers (scala) [default], iteration 26 completed (1290.878 ms) ====== [2025-04-25T03:51:14.710Z] ====== philosophers (scala) [default], iteration 27 started ====== [2025-04-25T03:51:14.710Z] GC before operation: completed in 10.447 ms, heap usage 84.065 MB -> 5.363 MB. [2025-04-25T03:51:16.308Z] Camera thread performed 122 scans. [2025-04-25T03:51:16.308Z] ====== philosophers (scala) [default], iteration 27 completed (1431.674 ms) ====== [2025-04-25T03:51:16.308Z] ====== philosophers (scala) [default], iteration 28 started ====== [2025-04-25T03:51:16.308Z] GC before operation: completed in 10.378 ms, heap usage 82.445 MB -> 5.363 MB. [2025-04-25T03:51:17.416Z] Camera thread performed 122 scans. [2025-04-25T03:51:17.416Z] ====== philosophers (scala) [default], iteration 28 completed (1346.995 ms) ====== [2025-04-25T03:51:17.416Z] ====== philosophers (scala) [default], iteration 29 started ====== [2025-04-25T03:51:17.416Z] GC before operation: completed in 10.284 ms, heap usage 5.577 MB -> 5.363 MB. [2025-04-25T03:51:19.003Z] Camera thread performed 122 scans. [2025-04-25T03:51:19.004Z] ====== philosophers (scala) [default], iteration 29 completed (1242.374 ms) ====== [2025-04-25T03:51:19.004Z] ----------------------------------- [2025-04-25T03:51:19.004Z] renaissance-philosophers_0_PASSED [2025-04-25T03:51:19.004Z] ----------------------------------- [2025-04-25T03:51:19.004Z] [2025-04-25T03:51:19.004Z] TEST TEARDOWN: [2025-04-25T03:51:19.004Z] Nothing to be done for teardown. [2025-04-25T03:51:19.004Z] renaissance-philosophers_0 Finish Time: Fri Apr 25 03:51:18 2025 Epoch Time (ms): 1745553078832