[2025-07-01T17:58:37.459Z] Running test renaissance-als_0 ... [2025-07-01T17:58:37.459Z] =============================================== [2025-07-01T17:58:37.784Z] renaissance-als_0 Start Time: Tue Jul 1 17:58:37 2025 Epoch Time (ms): 1751392717503 [2025-07-01T17:58:37.784Z] variation: NoOptions [2025-07-01T17:58:37.784Z] JVM_OPTIONS: [2025-07-01T17:58:37.784Z] { \ [2025-07-01T17:58:37.784Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T17:58:37.784Z] echo "Nothing to be done for setup."; \ [2025-07-01T17:58:37.784Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\renaissance-als_0"; \ [2025-07-01T17:58:37.784Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\renaissance-als_0"; \ [2025-07-01T17:58:37.784Z] echo ""; echo "TESTING:"; \ [2025-07-01T17:58:37.784Z] "c:/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\renaissance-als_0"\\als.json" als; \ [2025-07-01T17:58:37.784Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T17:58:37.784Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T17:58:37.784Z] echo "Nothing to be done for teardown."; \ [2025-07-01T17:58:37.784Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\TestTargetResult"; [2025-07-01T17:58:38.110Z] [2025-07-01T17:58:38.110Z] TEST SETUP: [2025-07-01T17:58:38.110Z] Nothing to be done for setup. [2025-07-01T17:58:38.110Z] [2025-07-01T17:58:38.110Z] TESTING: [2025-07-01T17:58:51.009Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T17:58:54.711Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-01T17:58:54.711Z] GC before operation: completed in 36.638 ms, heap usage 52.970 MB -> 35.603 MB. [2025-07-01T17:59:22.612Z] ====== als (apache-spark) [default], iteration 0 completed (26113.013 ms) ====== [2025-07-01T17:59:22.612Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-01T17:59:22.612Z] GC before operation: completed in 83.674 ms, heap usage 107.878 MB -> 62.815 MB. [2025-07-01T17:59:38.369Z] ====== als (apache-spark) [default], iteration 1 completed (15079.982 ms) ====== [2025-07-01T17:59:38.369Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-01T17:59:38.370Z] GC before operation: completed in 71.847 ms, heap usage 227.997 MB -> 63.390 MB. [2025-07-01T17:59:51.337Z] ====== als (apache-spark) [default], iteration 2 completed (14356.104 ms) ====== [2025-07-01T17:59:51.337Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-01T17:59:51.337Z] GC before operation: completed in 78.687 ms, heap usage 101.580 MB -> 63.502 MB. [2025-07-01T18:00:07.067Z] ====== als (apache-spark) [default], iteration 3 completed (14185.086 ms) ====== [2025-07-01T18:00:07.067Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-01T18:00:07.067Z] GC before operation: completed in 78.939 ms, heap usage 501.303 MB -> 64.900 MB. [2025-07-01T18:00:20.216Z] ====== als (apache-spark) [default], iteration 4 completed (13979.278 ms) ====== [2025-07-01T18:00:20.216Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-01T18:00:20.216Z] GC before operation: completed in 75.474 ms, heap usage 331.136 MB -> 65.282 MB. [2025-07-01T18:00:36.101Z] ====== als (apache-spark) [default], iteration 5 completed (13947.565 ms) ====== [2025-07-01T18:00:36.101Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-01T18:00:36.101Z] GC before operation: completed in 77.313 ms, heap usage 137.280 MB -> 65.148 MB. [2025-07-01T18:00:49.066Z] ====== als (apache-spark) [default], iteration 6 completed (13962.235 ms) ====== [2025-07-01T18:00:49.066Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-01T18:00:49.066Z] GC before operation: completed in 79.940 ms, heap usage 457.866 MB -> 66.669 MB. [2025-07-01T18:01:02.695Z] ====== als (apache-spark) [default], iteration 7 completed (13797.606 ms) ====== [2025-07-01T18:01:02.695Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-01T18:01:02.695Z] GC before operation: completed in 97.417 ms, heap usage 520.736 MB -> 67.082 MB. [2025-07-01T18:01:18.496Z] ====== als (apache-spark) [default], iteration 8 completed (14338.588 ms) ====== [2025-07-01T18:01:18.497Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-01T18:01:18.497Z] GC before operation: completed in 81.714 ms, heap usage 468.696 MB -> 67.583 MB. [2025-07-01T18:01:31.608Z] ====== als (apache-spark) [default], iteration 9 completed (13896.816 ms) ====== [2025-07-01T18:01:31.608Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-01T18:01:31.608Z] GC before operation: completed in 82.646 ms, heap usage 443.757 MB -> 67.954 MB. [2025-07-01T18:01:47.376Z] ====== als (apache-spark) [default], iteration 10 completed (13782.492 ms) ====== [2025-07-01T18:01:47.376Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-01T18:01:47.376Z] GC before operation: completed in 79.984 ms, heap usage 235.566 MB -> 67.898 MB. [2025-07-01T18:02:00.426Z] ====== als (apache-spark) [default], iteration 11 completed (13763.404 ms) ====== [2025-07-01T18:02:00.426Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-01T18:02:00.426Z] GC before operation: completed in 104.883 ms, heap usage 354.281 MB -> 68.226 MB. [2025-07-01T18:02:13.649Z] ====== als (apache-spark) [default], iteration 12 completed (13570.624 ms) ====== [2025-07-01T18:02:13.649Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-01T18:02:13.649Z] GC before operation: completed in 79.569 ms, heap usage 322.655 MB -> 68.454 MB. [2025-07-01T18:02:26.791Z] ====== als (apache-spark) [default], iteration 13 completed (13968.464 ms) ====== [2025-07-01T18:02:26.791Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-01T18:02:27.190Z] GC before operation: completed in 74.606 ms, heap usage 114.249 MB -> 68.493 MB. [2025-07-01T18:02:42.982Z] ====== als (apache-spark) [default], iteration 14 completed (13752.070 ms) ====== [2025-07-01T18:02:42.982Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-01T18:02:42.982Z] GC before operation: completed in 75.236 ms, heap usage 345.773 MB -> 68.932 MB. [2025-07-01T18:02:56.094Z] ====== als (apache-spark) [default], iteration 15 completed (13443.308 ms) ====== [2025-07-01T18:02:56.094Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-01T18:02:56.094Z] GC before operation: completed in 73.301 ms, heap usage 151.082 MB -> 68.991 MB. [2025-07-01T18:03:09.109Z] ====== als (apache-spark) [default], iteration 16 completed (13794.883 ms) ====== [2025-07-01T18:03:09.109Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-01T18:03:09.109Z] GC before operation: completed in 74.967 ms, heap usage 439.993 MB -> 69.666 MB. [2025-07-01T18:03:24.893Z] ====== als (apache-spark) [default], iteration 17 completed (13694.106 ms) ====== [2025-07-01T18:03:24.893Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-01T18:03:24.893Z] GC before operation: completed in 75.163 ms, heap usage 131.395 MB -> 69.425 MB. [2025-07-01T18:03:38.001Z] ====== als (apache-spark) [default], iteration 18 completed (13584.112 ms) ====== [2025-07-01T18:03:38.001Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-01T18:03:38.001Z] GC before operation: completed in 72.508 ms, heap usage 233.223 MB -> 69.841 MB. [2025-07-01T18:03:51.017Z] ====== als (apache-spark) [default], iteration 19 completed (13639.015 ms) ====== [2025-07-01T18:03:51.017Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-01T18:03:51.017Z] GC before operation: completed in 73.869 ms, heap usage 400.571 MB -> 70.220 MB. [2025-07-01T18:04:04.381Z] ====== als (apache-spark) [default], iteration 20 completed (14118.823 ms) ====== [2025-07-01T18:04:04.381Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-01T18:04:04.779Z] GC before operation: completed in 73.235 ms, heap usage 155.962 MB -> 70.291 MB. [2025-07-01T18:04:20.555Z] ====== als (apache-spark) [default], iteration 21 completed (13421.288 ms) ====== [2025-07-01T18:04:20.555Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-01T18:04:20.555Z] GC before operation: completed in 75.068 ms, heap usage 268.665 MB -> 70.695 MB. [2025-07-01T18:04:33.587Z] ====== als (apache-spark) [default], iteration 22 completed (13613.230 ms) ====== [2025-07-01T18:04:33.587Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-01T18:04:33.587Z] GC before operation: completed in 75.824 ms, heap usage 362.788 MB -> 71.069 MB. [2025-07-01T18:04:46.720Z] ====== als (apache-spark) [default], iteration 23 completed (13728.468 ms) ====== [2025-07-01T18:04:46.720Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-01T18:04:46.720Z] GC before operation: completed in 75.757 ms, heap usage 368.572 MB -> 71.358 MB. [2025-07-01T18:04:59.725Z] ====== als (apache-spark) [default], iteration 24 completed (13723.942 ms) ====== [2025-07-01T18:04:59.725Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-01T18:05:00.057Z] GC before operation: completed in 76.832 ms, heap usage 461.900 MB -> 71.807 MB. [2025-07-01T18:05:15.784Z] ====== als (apache-spark) [default], iteration 25 completed (13745.671 ms) ====== [2025-07-01T18:05:15.784Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-01T18:05:15.784Z] GC before operation: completed in 74.149 ms, heap usage 484.410 MB -> 72.103 MB. [2025-07-01T18:05:28.770Z] ====== als (apache-spark) [default], iteration 26 completed (13521.324 ms) ====== [2025-07-01T18:05:28.770Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-01T18:05:28.770Z] GC before operation: completed in 73.028 ms, heap usage 306.545 MB -> 72.149 MB. [2025-07-01T18:05:41.772Z] ====== als (apache-spark) [default], iteration 27 completed (13465.054 ms) ====== [2025-07-01T18:05:41.772Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-01T18:05:41.772Z] GC before operation: completed in 72.643 ms, heap usage 131.258 MB -> 72.146 MB. [2025-07-01T18:05:54.911Z] ====== als (apache-spark) [default], iteration 28 completed (13395.632 ms) ====== [2025-07-01T18:05:54.911Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-01T18:05:54.911Z] GC before operation: completed in 72.648 ms, heap usage 251.084 MB -> 72.536 MB. [2025-07-01T18:06:10.773Z] ====== als (apache-spark) [default], iteration 29 completed (13498.243 ms) ====== [2025-07-01T18:06:10.773Z] ----------------------------------- [2025-07-01T18:06:10.773Z] renaissance-als_0_PASSED [2025-07-01T18:06:10.773Z] ----------------------------------- [2025-07-01T18:06:10.773Z] [2025-07-01T18:06:10.773Z] TEST TEARDOWN: [2025-07-01T18:06:10.773Z] Nothing to be done for teardown. [2025-07-01T18:06:10.773Z] renaissance-als_0 Finish Time: Tue Jul 1 18:06:09 2025 Epoch Time (ms): 1751393169334