[2026-04-14T07:05:41.749Z] Running test renaissance-als_0 ... [2026-04-14T07:05:41.749Z] =============================================== [2026-04-14T07:05:41.749Z] renaissance-als_0 Start Time: Tue Apr 14 07:05:41 2026 Epoch Time (ms): 1776150341699 [2026-04-14T07:05:42.099Z] variation: NoOptions [2026-04-14T07:05:42.099Z] JVM_OPTIONS: [2026-04-14T07:05:42.099Z] { \ [2026-04-14T07:05:42.099Z] echo ""; echo "TEST SETUP:"; \ [2026-04-14T07:05:42.099Z] echo "Nothing to be done for setup."; \ [2026-04-14T07:05:42.099Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17761501068677\\renaissance-als_0"; \ [2026-04-14T07:05:42.099Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17761501068677\\renaissance-als_0"; \ [2026-04-14T07:05:42.099Z] echo ""; echo "TESTING:"; \ [2026-04-14T07:05:42.099Z] "c:/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17761501068677\\renaissance-als_0"\\als.json" als; \ [2026-04-14T07:05:42.099Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17761501068677\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-14T07:05:42.099Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-14T07:05:42.099Z] echo "Nothing to be done for teardown."; \ [2026-04-14T07:05:42.099Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17761501068677\\TestTargetResult"; [2026-04-14T07:05:42.099Z] [2026-04-14T07:05:42.099Z] TEST SETUP: [2026-04-14T07:05:42.099Z] Nothing to be done for setup. [2026-04-14T07:05:42.099Z] [2026-04-14T07:05:42.099Z] TESTING: [2026-04-14T07:05:55.496Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-04-14T07:05:57.849Z] WARNING: An illegal reflective access operation has occurred [2026-04-14T07:05:57.849Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17761501068677/renaissance-als_0/harness-070542-714618588395508426/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-14T07:05:57.849Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-14T07:05:57.849Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-14T07:05:57.849Z] WARNING: All illegal access operations will be denied in a future release [2026-04-14T07:05:59.119Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-14T07:05:59.119Z] GC before operation: completed in 109.839 ms, heap usage 382.000 MB -> 35.177 MB. [2026-04-14T07:06:48.540Z] ====== als (apache-spark) [default], iteration 0 completed (45079.645 ms) ====== [2026-04-14T07:06:48.540Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-14T07:06:48.540Z] GC before operation: completed in 162.868 ms, heap usage 106.435 MB -> 60.488 MB. [2026-04-14T07:07:12.143Z] ====== als (apache-spark) [default], iteration 1 completed (23160.328 ms) ====== [2026-04-14T07:07:12.143Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-14T07:07:12.143Z] GC before operation: completed in 138.349 ms, heap usage 95.999 MB -> 61.406 MB. [2026-04-14T07:07:35.464Z] ====== als (apache-spark) [default], iteration 2 completed (22988.050 ms) ====== [2026-04-14T07:07:35.464Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-14T07:07:35.464Z] GC before operation: completed in 151.916 ms, heap usage 111.827 MB -> 61.286 MB. [2026-04-14T07:07:55.173Z] ====== als (apache-spark) [default], iteration 3 completed (21873.454 ms) ====== [2026-04-14T07:07:55.173Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-14T07:07:55.173Z] GC before operation: completed in 151.454 ms, heap usage 114.108 MB -> 61.810 MB. [2026-04-14T07:08:18.862Z] ====== als (apache-spark) [default], iteration 4 completed (22016.485 ms) ====== [2026-04-14T07:08:18.862Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-14T07:08:18.862Z] GC before operation: completed in 186.236 ms, heap usage 244.813 MB -> 62.444 MB. [2026-04-14T07:08:42.424Z] ====== als (apache-spark) [default], iteration 5 completed (22072.554 ms) ====== [2026-04-14T07:08:42.424Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-14T07:08:42.424Z] GC before operation: completed in 129.988 ms, heap usage 91.023 MB -> 62.861 MB. [2026-04-14T07:09:05.879Z] ====== als (apache-spark) [default], iteration 6 completed (22232.216 ms) ====== [2026-04-14T07:09:05.879Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-14T07:09:05.879Z] GC before operation: completed in 186.996 ms, heap usage 111.753 MB -> 63.383 MB. [2026-04-14T07:09:25.627Z] ====== als (apache-spark) [default], iteration 7 completed (21961.194 ms) ====== [2026-04-14T07:09:25.627Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-14T07:09:25.627Z] GC before operation: completed in 134.256 ms, heap usage 207.194 MB -> 64.499 MB. [2026-04-14T07:09:49.667Z] ====== als (apache-spark) [default], iteration 8 completed (21771.485 ms) ====== [2026-04-14T07:09:49.668Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-14T07:09:49.668Z] GC before operation: completed in 147.980 ms, heap usage 83.133 MB -> 64.435 MB. [2026-04-14T07:10:13.505Z] ====== als (apache-spark) [default], iteration 9 completed (22016.473 ms) ====== [2026-04-14T07:10:13.505Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-14T07:10:13.505Z] GC before operation: completed in 140.444 ms, heap usage 203.927 MB -> 65.295 MB. [2026-04-14T07:10:32.798Z] ====== als (apache-spark) [default], iteration 10 completed (21718.751 ms) ====== [2026-04-14T07:10:32.798Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-14T07:10:32.798Z] GC before operation: completed in 206.569 ms, heap usage 267.190 MB -> 66.001 MB. [2026-04-14T07:10:56.205Z] ====== als (apache-spark) [default], iteration 11 completed (20968.226 ms) ====== [2026-04-14T07:10:56.205Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-14T07:10:56.205Z] GC before operation: completed in 177.848 ms, heap usage 228.572 MB -> 65.845 MB. [2026-04-14T07:11:19.938Z] ====== als (apache-spark) [default], iteration 12 completed (22520.428 ms) ====== [2026-04-14T07:11:19.938Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-14T07:11:19.938Z] GC before operation: completed in 154.596 ms, heap usage 223.856 MB -> 66.043 MB. [2026-04-14T07:11:39.664Z] ====== als (apache-spark) [default], iteration 13 completed (22636.388 ms) ====== [2026-04-14T07:11:39.664Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-14T07:11:39.664Z] GC before operation: completed in 143.427 ms, heap usage 97.046 MB -> 65.710 MB. [2026-04-14T07:12:03.664Z] ====== als (apache-spark) [default], iteration 14 completed (21577.731 ms) ====== [2026-04-14T07:12:03.664Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-14T07:12:03.664Z] GC before operation: completed in 140.629 ms, heap usage 124.677 MB -> 65.955 MB. [2026-04-14T07:12:27.368Z] ====== als (apache-spark) [default], iteration 15 completed (22156.750 ms) ====== [2026-04-14T07:12:27.369Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-14T07:12:27.369Z] GC before operation: completed in 159.788 ms, heap usage 149.668 MB -> 66.217 MB. [2026-04-14T07:12:46.967Z] ====== als (apache-spark) [default], iteration 16 completed (21037.727 ms) ====== [2026-04-14T07:12:46.967Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-14T07:12:46.967Z] GC before operation: completed in 146.841 ms, heap usage 225.722 MB -> 67.043 MB. [2026-04-14T07:13:10.455Z] ====== als (apache-spark) [default], iteration 17 completed (21760.594 ms) ====== [2026-04-14T07:13:10.455Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-14T07:13:10.455Z] GC before operation: completed in 147.935 ms, heap usage 106.482 MB -> 66.704 MB. [2026-04-14T07:13:30.262Z] ====== als (apache-spark) [default], iteration 18 completed (21110.057 ms) ====== [2026-04-14T07:13:30.262Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-14T07:13:30.262Z] GC before operation: completed in 152.820 ms, heap usage 224.289 MB -> 67.528 MB. [2026-04-14T07:13:53.997Z] ====== als (apache-spark) [default], iteration 19 completed (22059.263 ms) ====== [2026-04-14T07:13:53.997Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-14T07:13:53.997Z] GC before operation: completed in 150.016 ms, heap usage 192.673 MB -> 67.654 MB. [2026-04-14T07:14:17.532Z] ====== als (apache-spark) [default], iteration 20 completed (22348.706 ms) ====== [2026-04-14T07:14:17.532Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-14T07:14:17.532Z] GC before operation: completed in 160.298 ms, heap usage 234.845 MB -> 68.014 MB. [2026-04-14T07:14:37.055Z] ====== als (apache-spark) [default], iteration 21 completed (22229.074 ms) ====== [2026-04-14T07:14:37.055Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-14T07:14:37.656Z] GC before operation: completed in 174.927 ms, heap usage 91.375 MB -> 67.686 MB. [2026-04-14T07:15:01.394Z] ====== als (apache-spark) [default], iteration 22 completed (22547.254 ms) ====== [2026-04-14T07:15:01.394Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-14T07:15:01.394Z] GC before operation: completed in 168.597 ms, heap usage 215.399 MB -> 68.535 MB. [2026-04-14T07:15:25.218Z] ====== als (apache-spark) [default], iteration 23 completed (22289.907 ms) ====== [2026-04-14T07:15:25.218Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-14T07:15:25.218Z] GC before operation: completed in 194.569 ms, heap usage 239.849 MB -> 68.347 MB. [2026-04-14T07:15:48.744Z] ====== als (apache-spark) [default], iteration 24 completed (21989.950 ms) ====== [2026-04-14T07:15:48.744Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-14T07:15:48.744Z] GC before operation: completed in 163.195 ms, heap usage 263.843 MB -> 69.056 MB. [2026-04-14T07:16:08.208Z] ====== als (apache-spark) [default], iteration 25 completed (21902.797 ms) ====== [2026-04-14T07:16:08.208Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-14T07:16:08.208Z] GC before operation: completed in 151.785 ms, heap usage 141.656 MB -> 68.719 MB. [2026-04-14T07:16:31.996Z] ====== als (apache-spark) [default], iteration 26 completed (22088.987 ms) ====== [2026-04-14T07:16:31.996Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-14T07:16:31.996Z] GC before operation: completed in 174.762 ms, heap usage 168.466 MB -> 68.966 MB. [2026-04-14T07:16:55.768Z] ====== als (apache-spark) [default], iteration 27 completed (21557.479 ms) ====== [2026-04-14T07:16:55.768Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-14T07:16:55.768Z] GC before operation: completed in 176.999 ms, heap usage 170.731 MB -> 69.213 MB. [2026-04-14T07:17:15.337Z] ====== als (apache-spark) [default], iteration 28 completed (22790.139 ms) ====== [2026-04-14T07:17:15.337Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-14T07:17:15.337Z] GC before operation: completed in 180.895 ms, heap usage 200.097 MB -> 69.452 MB. [2026-04-14T07:17:39.396Z] ====== als (apache-spark) [default], iteration 29 completed (22630.312 ms) ====== [2026-04-14T07:17:39.396Z] ----------------------------------- [2026-04-14T07:17:39.396Z] renaissance-als_0_PASSED [2026-04-14T07:17:39.396Z] ----------------------------------- [2026-04-14T07:17:39.396Z] [2026-04-14T07:17:39.396Z] TEST TEARDOWN: [2026-04-14T07:17:39.396Z] Nothing to be done for teardown. [2026-04-14T07:17:39.396Z] renaissance-als_0 Finish Time: Tue Apr 14 07:17:38 2026 Epoch Time (ms): 1776151058787