[2026-02-20T22:22:20.784Z] Running test renaissance-als_0 ... [2026-02-20T22:22:20.784Z] =============================================== [2026-02-20T22:22:20.784Z] renaissance-als_0 Start Time: Fri Feb 20 22:22:20 2026 Epoch Time (ms): 1771626140693 [2026-02-20T22:22:20.784Z] variation: NoOptions [2026-02-20T22:22:20.784Z] JVM_OPTIONS: [2026-02-20T22:22:20.784Z] { \ [2026-02-20T22:22:20.784Z] echo ""; echo "TEST SETUP:"; \ [2026-02-20T22:22:20.784Z] echo "Nothing to be done for setup."; \ [2026-02-20T22:22:20.784Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17716260031451/renaissance-als_0"; \ [2026-02-20T22:22:20.784Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17716260031451/renaissance-als_0"; \ [2026-02-20T22:22:20.784Z] echo ""; echo "TESTING:"; \ [2026-02-20T22:22:20.784Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17716260031451/renaissance-als_0"/als.json" als; \ [2026-02-20T22:22:20.784Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17716260031451/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-20T22:22:20.784Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-20T22:22:20.784Z] echo "Nothing to be done for teardown."; \ [2026-02-20T22:22:20.784Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17716260031451/TestTargetResult"; [2026-02-20T22:22:20.784Z] [2026-02-20T22:22:20.784Z] TEST SETUP: [2026-02-20T22:22:20.784Z] Nothing to be done for setup. [2026-02-20T22:22:20.784Z] [2026-02-20T22:22:20.784Z] TESTING: [2026-02-20T22:22:26.764Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-20T22:22:29.047Z] WARNING: An illegal reflective access operation has occurred [2026-02-20T22:22:29.047Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17716260031451/renaissance-als_0/harness-222221-7895189058646492920/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-20T22:22:29.047Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-20T22:22:29.047Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-20T22:22:29.047Z] WARNING: All illegal access operations will be denied in a future release [2026-02-20T22:22:30.207Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-20T22:22:30.207Z] GC before operation: completed in 129.343 ms, heap usage 348.500 MB -> 34.490 MB. [2026-02-20T22:22:53.560Z] ====== als (apache-spark) [default], iteration 0 completed (20248.121 ms) ====== [2026-02-20T22:22:53.560Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-20T22:22:53.560Z] GC before operation: completed in 174.135 ms, heap usage 351.464 MB -> 60.563 MB. [2026-02-20T22:23:00.951Z] ====== als (apache-spark) [default], iteration 1 completed (9252.318 ms) ====== [2026-02-20T22:23:00.951Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-20T22:23:00.951Z] GC before operation: completed in 204.845 ms, heap usage 405.169 MB -> 61.381 MB. [2026-02-20T22:23:09.930Z] ====== als (apache-spark) [default], iteration 2 completed (8390.538 ms) ====== [2026-02-20T22:23:09.930Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-20T22:23:09.930Z] GC before operation: completed in 233.229 ms, heap usage 776.256 MB -> 62.467 MB. [2026-02-20T22:23:18.889Z] ====== als (apache-spark) [default], iteration 3 completed (8086.071 ms) ====== [2026-02-20T22:23:18.889Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-20T22:23:18.889Z] GC before operation: completed in 215.333 ms, heap usage 426.472 MB -> 62.477 MB. [2026-02-20T22:23:27.780Z] ====== als (apache-spark) [default], iteration 4 completed (8168.937 ms) ====== [2026-02-20T22:23:27.781Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-20T22:23:27.781Z] GC before operation: completed in 248.941 ms, heap usage 487.164 MB -> 63.224 MB. [2026-02-20T22:23:35.352Z] ====== als (apache-spark) [default], iteration 5 completed (8030.889 ms) ====== [2026-02-20T22:23:35.352Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-20T22:23:35.352Z] GC before operation: completed in 220.442 ms, heap usage 647.597 MB -> 63.848 MB. [2026-02-20T22:23:44.908Z] ====== als (apache-spark) [default], iteration 6 completed (8251.324 ms) ====== [2026-02-20T22:23:44.908Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-20T22:23:44.908Z] GC before operation: completed in 228.429 ms, heap usage 1.118 GB -> 64.939 MB. [2026-02-20T22:23:52.308Z] ====== als (apache-spark) [default], iteration 7 completed (8142.881 ms) ====== [2026-02-20T22:23:52.308Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-20T22:23:52.647Z] GC before operation: completed in 235.286 ms, heap usage 250.132 MB -> 64.476 MB. [2026-02-20T22:24:01.587Z] ====== als (apache-spark) [default], iteration 8 completed (7826.505 ms) ====== [2026-02-20T22:24:01.587Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-20T22:24:01.587Z] GC before operation: completed in 228.608 ms, heap usage 800.880 MB -> 65.650 MB. [2026-02-20T22:24:08.903Z] ====== als (apache-spark) [default], iteration 9 completed (8069.729 ms) ====== [2026-02-20T22:24:08.903Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-20T22:24:09.235Z] GC before operation: completed in 258.317 ms, heap usage 431.027 MB -> 65.387 MB. [2026-02-20T22:24:18.196Z] ====== als (apache-spark) [default], iteration 10 completed (7910.100 ms) ====== [2026-02-20T22:24:18.196Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-20T22:24:18.196Z] GC before operation: completed in 236.314 ms, heap usage 129.459 MB -> 65.576 MB. [2026-02-20T22:24:25.555Z] ====== als (apache-spark) [default], iteration 11 completed (7850.295 ms) ====== [2026-02-20T22:24:25.555Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-20T22:24:25.884Z] GC before operation: completed in 240.215 ms, heap usage 946.391 MB -> 66.595 MB. [2026-02-20T22:24:34.771Z] ====== als (apache-spark) [default], iteration 12 completed (7653.369 ms) ====== [2026-02-20T22:24:34.771Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-20T22:24:34.771Z] GC before operation: completed in 187.973 ms, heap usage 206.135 MB -> 65.908 MB. [2026-02-20T22:24:42.029Z] ====== als (apache-spark) [default], iteration 13 completed (7754.749 ms) ====== [2026-02-20T22:24:42.029Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-20T22:24:42.029Z] GC before operation: completed in 231.179 ms, heap usage 506.029 MB -> 66.569 MB. [2026-02-20T22:24:50.938Z] ====== als (apache-spark) [default], iteration 14 completed (7593.109 ms) ====== [2026-02-20T22:24:50.938Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-20T22:24:50.939Z] GC before operation: completed in 214.250 ms, heap usage 498.835 MB -> 66.920 MB. [2026-02-20T22:24:58.270Z] ====== als (apache-spark) [default], iteration 15 completed (7639.548 ms) ====== [2026-02-20T22:24:58.270Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-20T22:24:58.270Z] GC before operation: completed in 208.731 ms, heap usage 801.009 MB -> 67.394 MB. [2026-02-20T22:25:07.237Z] ====== als (apache-spark) [default], iteration 16 completed (7676.223 ms) ====== [2026-02-20T22:25:07.237Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-20T22:25:07.237Z] GC before operation: completed in 238.755 ms, heap usage 780.207 MB -> 67.636 MB. [2026-02-20T22:25:14.540Z] ====== als (apache-spark) [default], iteration 17 completed (7694.723 ms) ====== [2026-02-20T22:25:14.540Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-20T22:25:14.540Z] GC before operation: completed in 197.533 ms, heap usage 620.962 MB -> 67.688 MB. [2026-02-20T22:25:23.437Z] ====== als (apache-spark) [default], iteration 18 completed (7869.229 ms) ====== [2026-02-20T22:25:23.437Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-20T22:25:23.437Z] GC before operation: completed in 193.073 ms, heap usage 160.586 MB -> 67.382 MB. [2026-02-20T22:25:30.759Z] ====== als (apache-spark) [default], iteration 19 completed (7745.796 ms) ====== [2026-02-20T22:25:30.759Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-20T22:25:30.759Z] GC before operation: completed in 171.998 ms, heap usage 443.217 MB -> 67.878 MB. [2026-02-20T22:25:39.664Z] ====== als (apache-spark) [default], iteration 20 completed (7928.264 ms) ====== [2026-02-20T22:25:39.664Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-20T22:25:39.664Z] GC before operation: completed in 200.087 ms, heap usage 346.725 MB -> 68.121 MB. [2026-02-20T22:25:46.938Z] ====== als (apache-spark) [default], iteration 21 completed (7602.568 ms) ====== [2026-02-20T22:25:46.938Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-20T22:25:46.938Z] GC before operation: completed in 198.832 ms, heap usage 627.906 MB -> 68.695 MB. [2026-02-20T22:25:55.946Z] ====== als (apache-spark) [default], iteration 22 completed (7815.112 ms) ====== [2026-02-20T22:25:55.946Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-20T22:25:55.946Z] GC before operation: completed in 216.680 ms, heap usage 746.192 MB -> 69.167 MB. [2026-02-20T22:26:03.371Z] ====== als (apache-spark) [default], iteration 23 completed (8025.833 ms) ====== [2026-02-20T22:26:03.371Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-20T22:26:03.371Z] GC before operation: completed in 197.762 ms, heap usage 506.633 MB -> 69.123 MB. [2026-02-20T22:26:12.275Z] ====== als (apache-spark) [default], iteration 24 completed (7863.991 ms) ====== [2026-02-20T22:26:12.275Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-20T22:26:12.275Z] GC before operation: completed in 207.877 ms, heap usage 477.133 MB -> 69.368 MB. [2026-02-20T22:26:19.656Z] ====== als (apache-spark) [default], iteration 25 completed (7833.196 ms) ====== [2026-02-20T22:26:19.656Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-20T22:26:19.656Z] GC before operation: completed in 194.372 ms, heap usage 564.792 MB -> 69.737 MB. [2026-02-20T22:26:28.595Z] ====== als (apache-spark) [default], iteration 26 completed (8055.670 ms) ====== [2026-02-20T22:26:28.595Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-20T22:26:28.595Z] GC before operation: completed in 166.472 ms, heap usage 127.937 MB -> 69.427 MB. [2026-02-20T22:26:35.924Z] ====== als (apache-spark) [default], iteration 27 completed (7503.127 ms) ====== [2026-02-20T22:26:35.924Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-20T22:26:35.924Z] GC before operation: completed in 176.464 ms, heap usage 273.476 MB -> 69.788 MB. [2026-02-20T22:26:44.891Z] ====== als (apache-spark) [default], iteration 28 completed (7480.117 ms) ====== [2026-02-20T22:26:44.891Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-20T22:26:44.891Z] GC before operation: completed in 194.423 ms, heap usage 518.482 MB -> 69.991 MB. [2026-02-20T22:26:52.163Z] ====== als (apache-spark) [default], iteration 29 completed (7504.906 ms) ====== [2026-02-20T22:26:52.163Z] ----------------------------------- [2026-02-20T22:26:52.164Z] renaissance-als_0_PASSED [2026-02-20T22:26:52.164Z] ----------------------------------- [2026-02-20T22:26:52.164Z] [2026-02-20T22:26:52.164Z] TEST TEARDOWN: [2026-02-20T22:26:52.164Z] Nothing to be done for teardown. [2026-02-20T22:26:52.164Z] renaissance-als_0 Finish Time: Fri Feb 20 22:26:51 2026 Epoch Time (ms): 1771626411840