[2026-04-25T13:54:40.476Z] Running test renaissance-als_0 ... [2026-04-25T13:54:40.476Z] =============================================== [2026-04-25T13:54:40.476Z] renaissance-als_0 Start Time: Sat Apr 25 13:54:40 2026 Epoch Time (ms): 1777125280431 [2026-04-25T13:54:40.476Z] variation: NoOptions [2026-04-25T13:54:40.476Z] JVM_OPTIONS: [2026-04-25T13:54:40.476Z] { \ [2026-04-25T13:54:40.476Z] echo ""; echo "TEST SETUP:"; \ [2026-04-25T13:54:40.476Z] echo "Nothing to be done for setup."; \ [2026-04-25T13:54:40.476Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17771251517059/renaissance-als_0"; \ [2026-04-25T13:54:40.476Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17771251517059/renaissance-als_0"; \ [2026-04-25T13:54:40.476Z] echo ""; echo "TESTING:"; \ [2026-04-25T13:54:40.476Z] "/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_17771251517059/renaissance-als_0"/als.json" als; \ [2026-04-25T13:54:40.476Z] 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_17771251517059/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-25T13:54:40.476Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-25T13:54:40.476Z] echo "Nothing to be done for teardown."; \ [2026-04-25T13:54:40.476Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17771251517059/TestTargetResult"; [2026-04-25T13:54:40.476Z] [2026-04-25T13:54:40.476Z] TEST SETUP: [2026-04-25T13:54:40.476Z] Nothing to be done for setup. [2026-04-25T13:54:40.476Z] [2026-04-25T13:54:40.476Z] TESTING: [2026-04-25T13:54:46.301Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-25T13:54:48.516Z] WARNING: An illegal reflective access operation has occurred [2026-04-25T13:54:48.516Z] 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_17771251517059/renaissance-als_0/harness-135440-2664254677130415838/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-25T13:54:48.516Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-25T13:54:48.516Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-25T13:54:48.516Z] WARNING: All illegal access operations will be denied in a future release [2026-04-25T13:54:49.634Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-25T13:54:49.634Z] GC before operation: completed in 71.312 ms, heap usage 350.500 MB -> 34.484 MB. [2026-04-25T13:55:12.545Z] ====== als (apache-spark) [default], iteration 0 completed (22075.169 ms) ====== [2026-04-25T13:55:12.545Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-25T13:55:12.545Z] GC before operation: completed in 115.459 ms, heap usage 138.885 MB -> 60.359 MB. [2026-04-25T13:55:23.269Z] ====== als (apache-spark) [default], iteration 1 completed (9285.260 ms) ====== [2026-04-25T13:55:23.269Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-25T13:55:23.269Z] GC before operation: completed in 141.698 ms, heap usage 265.266 MB -> 61.165 MB. [2026-04-25T13:55:32.214Z] ====== als (apache-spark) [default], iteration 2 completed (8608.035 ms) ====== [2026-04-25T13:55:32.214Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-25T13:55:32.214Z] GC before operation: completed in 138.631 ms, heap usage 422.964 MB -> 61.956 MB. [2026-04-25T13:55:39.472Z] ====== als (apache-spark) [default], iteration 3 completed (8394.175 ms) ====== [2026-04-25T13:55:39.472Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-25T13:55:39.472Z] GC before operation: completed in 135.734 ms, heap usage 633.722 MB -> 62.816 MB. [2026-04-25T13:55:48.276Z] ====== als (apache-spark) [default], iteration 4 completed (8035.174 ms) ====== [2026-04-25T13:55:48.276Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-25T13:55:48.276Z] GC before operation: completed in 119.799 ms, heap usage 213.029 MB -> 63.106 MB. [2026-04-25T13:55:57.053Z] ====== als (apache-spark) [default], iteration 5 completed (8536.891 ms) ====== [2026-04-25T13:55:57.053Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-25T13:55:57.053Z] GC before operation: completed in 145.715 ms, heap usage 118.244 MB -> 63.286 MB. [2026-04-25T13:56:05.890Z] ====== als (apache-spark) [default], iteration 6 completed (7866.405 ms) ====== [2026-04-25T13:56:05.890Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-25T13:56:05.890Z] GC before operation: completed in 156.444 ms, heap usage 237.117 MB -> 63.949 MB. [2026-04-25T13:56:14.666Z] ====== als (apache-spark) [default], iteration 7 completed (8421.600 ms) ====== [2026-04-25T13:56:14.666Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-25T13:56:14.666Z] GC before operation: completed in 127.586 ms, heap usage 181.291 MB -> 64.398 MB. [2026-04-25T13:56:23.461Z] ====== als (apache-spark) [default], iteration 8 completed (8341.478 ms) ====== [2026-04-25T13:56:23.461Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-25T13:56:23.461Z] GC before operation: completed in 134.849 ms, heap usage 227.594 MB -> 65.016 MB. [2026-04-25T13:56:30.638Z] ====== als (apache-spark) [default], iteration 9 completed (7846.932 ms) ====== [2026-04-25T13:56:30.638Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-25T13:56:30.959Z] GC before operation: completed in 138.593 ms, heap usage 500.955 MB -> 65.605 MB. [2026-04-25T13:56:39.750Z] ====== als (apache-spark) [default], iteration 10 completed (7928.547 ms) ====== [2026-04-25T13:56:39.750Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-25T13:56:39.750Z] GC before operation: completed in 141.362 ms, heap usage 144.046 MB -> 65.401 MB. [2026-04-25T13:56:48.527Z] ====== als (apache-spark) [default], iteration 11 completed (8359.093 ms) ====== [2026-04-25T13:56:48.527Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-25T13:56:48.527Z] GC before operation: completed in 136.189 ms, heap usage 99.216 MB -> 65.665 MB. [2026-04-25T13:56:55.711Z] ====== als (apache-spark) [default], iteration 12 completed (7988.138 ms) ====== [2026-04-25T13:56:55.711Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-25T13:56:56.038Z] GC before operation: completed in 140.604 ms, heap usage 324.712 MB -> 66.233 MB. [2026-04-25T13:57:04.805Z] ====== als (apache-spark) [default], iteration 13 completed (8252.952 ms) ====== [2026-04-25T13:57:04.805Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-25T13:57:04.805Z] GC before operation: completed in 130.776 ms, heap usage 247.303 MB -> 66.248 MB. [2026-04-25T13:57:13.637Z] ====== als (apache-spark) [default], iteration 14 completed (8356.417 ms) ====== [2026-04-25T13:57:13.637Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-25T13:57:13.637Z] GC before operation: completed in 148.358 ms, heap usage 259.182 MB -> 66.490 MB. [2026-04-25T13:57:22.452Z] ====== als (apache-spark) [default], iteration 15 completed (8277.919 ms) ====== [2026-04-25T13:57:22.452Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-25T13:57:22.452Z] GC before operation: completed in 153.539 ms, heap usage 97.737 MB -> 66.638 MB. [2026-04-25T13:57:31.241Z] ====== als (apache-spark) [default], iteration 16 completed (8460.024 ms) ====== [2026-04-25T13:57:31.241Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-25T13:57:31.241Z] GC before operation: completed in 154.372 ms, heap usage 228.107 MB -> 66.998 MB. [2026-04-25T13:57:40.146Z] ====== als (apache-spark) [default], iteration 17 completed (8193.744 ms) ====== [2026-04-25T13:57:40.146Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-25T13:57:40.146Z] GC before operation: completed in 137.094 ms, heap usage 96.222 MB -> 67.128 MB. [2026-04-25T13:57:47.360Z] ====== als (apache-spark) [default], iteration 18 completed (7986.648 ms) ====== [2026-04-25T13:57:47.360Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-25T13:57:47.360Z] GC before operation: completed in 141.168 ms, heap usage 271.866 MB -> 67.495 MB. [2026-04-25T13:57:56.361Z] ====== als (apache-spark) [default], iteration 19 completed (7997.886 ms) ====== [2026-04-25T13:57:56.361Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-25T13:57:56.361Z] GC before operation: completed in 129.391 ms, heap usage 335.860 MB -> 67.885 MB. [2026-04-25T13:58:05.153Z] ====== als (apache-spark) [default], iteration 20 completed (8326.046 ms) ====== [2026-04-25T13:58:05.153Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-25T13:58:05.153Z] GC before operation: completed in 141.216 ms, heap usage 408.936 MB -> 68.127 MB. [2026-04-25T13:58:12.321Z] ====== als (apache-spark) [default], iteration 21 completed (7799.501 ms) ====== [2026-04-25T13:58:12.321Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-25T13:58:12.647Z] GC before operation: completed in 142.884 ms, heap usage 266.198 MB -> 68.346 MB. [2026-04-25T13:58:21.430Z] ====== als (apache-spark) [default], iteration 22 completed (8088.817 ms) ====== [2026-04-25T13:58:21.430Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-25T13:58:21.430Z] GC before operation: completed in 142.043 ms, heap usage 243.440 MB -> 68.543 MB. [2026-04-25T13:58:30.200Z] ====== als (apache-spark) [default], iteration 23 completed (8337.577 ms) ====== [2026-04-25T13:58:30.200Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-25T13:58:30.200Z] GC before operation: completed in 147.990 ms, heap usage 287.419 MB -> 68.790 MB. [2026-04-25T13:58:38.969Z] ====== als (apache-spark) [default], iteration 24 completed (7752.652 ms) ====== [2026-04-25T13:58:38.969Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-25T13:58:38.969Z] GC before operation: completed in 137.017 ms, heap usage 215.812 MB -> 69.141 MB. [2026-04-25T13:58:46.131Z] ====== als (apache-spark) [default], iteration 25 completed (8226.763 ms) ====== [2026-04-25T13:58:46.131Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-25T13:58:46.131Z] GC before operation: completed in 160.976 ms, heap usage 410.060 MB -> 69.402 MB. [2026-04-25T13:58:54.935Z] ====== als (apache-spark) [default], iteration 26 completed (7984.338 ms) ====== [2026-04-25T13:58:54.935Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-25T13:58:54.935Z] GC before operation: completed in 143.178 ms, heap usage 311.149 MB -> 69.648 MB. [2026-04-25T13:59:03.731Z] ====== als (apache-spark) [default], iteration 27 completed (7917.357 ms) ====== [2026-04-25T13:59:03.731Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-25T13:59:03.731Z] GC before operation: completed in 165.952 ms, heap usage 208.246 MB -> 69.794 MB. [2026-04-25T13:59:10.908Z] ====== als (apache-spark) [default], iteration 28 completed (8030.389 ms) ====== [2026-04-25T13:59:10.908Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-25T13:59:11.231Z] GC before operation: completed in 145.192 ms, heap usage 273.201 MB -> 69.564 MB. [2026-04-25T13:59:20.091Z] ====== als (apache-spark) [default], iteration 29 completed (7715.668 ms) ====== [2026-04-25T13:59:20.091Z] ----------------------------------- [2026-04-25T13:59:20.091Z] renaissance-als_0_PASSED [2026-04-25T13:59:20.091Z] ----------------------------------- [2026-04-25T13:59:20.091Z] [2026-04-25T13:59:20.091Z] TEST TEARDOWN: [2026-04-25T13:59:20.091Z] Nothing to be done for teardown. [2026-04-25T13:59:20.091Z] renaissance-als_0 Finish Time: Sat Apr 25 13:59:19 2026 Epoch Time (ms): 1777125559399