[2026-01-10T02:03:20.969Z] Running test renaissance-als_0 ... [2026-01-10T02:03:20.969Z] =============================================== [2026-01-10T02:03:20.969Z] renaissance-als_0 Start Time: Sat Jan 10 02:03:20 2026 Epoch Time (ms): 1768010600298 [2026-01-10T02:03:20.969Z] variation: NoOptions [2026-01-10T02:03:20.969Z] JVM_OPTIONS: [2026-01-10T02:03:20.969Z] { \ [2026-01-10T02:03:20.969Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T02:03:20.969Z] echo "Nothing to be done for setup."; \ [2026-01-10T02:03:20.969Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17680103925443/renaissance-als_0"; \ [2026-01-10T02:03:20.969Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17680103925443/renaissance-als_0"; \ [2026-01-10T02:03:20.969Z] echo ""; echo "TESTING:"; \ [2026-01-10T02:03:20.969Z] "/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_17680103925443/renaissance-als_0"/als.json" als; \ [2026-01-10T02:03:20.969Z] 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_17680103925443/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T02:03:20.969Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T02:03:20.969Z] echo "Nothing to be done for teardown."; \ [2026-01-10T02:03:20.969Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17680103925443/TestTargetResult"; [2026-01-10T02:03:20.969Z] [2026-01-10T02:03:20.969Z] TEST SETUP: [2026-01-10T02:03:20.969Z] Nothing to be done for setup. [2026-01-10T02:03:20.969Z] [2026-01-10T02:03:20.969Z] TESTING: [2026-01-10T02:03:26.954Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-10T02:03:30.782Z] WARNING: An illegal reflective access operation has occurred [2026-01-10T02:03:30.782Z] 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_17680103925443/renaissance-als_0/harness-020320-3776722465983133394/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-10T02:03:30.782Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-10T02:03:30.782Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-10T02:03:30.782Z] WARNING: All illegal access operations will be denied in a future release [2026-01-10T02:03:32.118Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T02:03:32.118Z] GC before operation: completed in 89.621 ms, heap usage 376.500 MB -> 34.316 MB. [2026-01-10T02:04:05.264Z] ====== als (apache-spark) [default], iteration 0 completed (30648.881 ms) ====== [2026-01-10T02:04:05.264Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T02:04:05.264Z] GC before operation: completed in 155.128 ms, heap usage 467.627 MB -> 60.241 MB. [2026-01-10T02:04:22.515Z] ====== als (apache-spark) [default], iteration 1 completed (17199.598 ms) ====== [2026-01-10T02:04:22.515Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T02:04:22.515Z] GC before operation: completed in 261.158 ms, heap usage 207.204 MB -> 61.046 MB. [2026-01-10T02:04:37.171Z] ====== als (apache-spark) [default], iteration 2 completed (13603.937 ms) ====== [2026-01-10T02:04:37.171Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T02:04:37.171Z] GC before operation: completed in 157.922 ms, heap usage 252.003 MB -> 61.776 MB. [2026-01-10T02:04:52.263Z] ====== als (apache-spark) [default], iteration 3 completed (14086.845 ms) ====== [2026-01-10T02:04:52.263Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T02:04:52.263Z] GC before operation: completed in 149.984 ms, heap usage 230.370 MB -> 62.021 MB. [2026-01-10T02:05:07.000Z] ====== als (apache-spark) [default], iteration 4 completed (14615.225 ms) ====== [2026-01-10T02:05:07.000Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T02:05:07.000Z] GC before operation: completed in 145.758 ms, heap usage 90.631 MB -> 62.395 MB. [2026-01-10T02:05:19.357Z] ====== als (apache-spark) [default], iteration 5 completed (13466.438 ms) ====== [2026-01-10T02:05:19.357Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T02:05:19.357Z] GC before operation: completed in 149.902 ms, heap usage 218.343 MB -> 63.121 MB. [2026-01-10T02:05:33.953Z] ====== als (apache-spark) [default], iteration 6 completed (13715.644 ms) ====== [2026-01-10T02:05:33.953Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T02:05:33.953Z] GC before operation: completed in 147.791 ms, heap usage 257.017 MB -> 63.571 MB. [2026-01-10T02:05:48.617Z] ====== als (apache-spark) [default], iteration 7 completed (13364.100 ms) ====== [2026-01-10T02:05:48.617Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T02:05:48.617Z] GC before operation: completed in 149.432 ms, heap usage 129.117 MB -> 64.047 MB. [2026-01-10T02:06:01.019Z] ====== als (apache-spark) [default], iteration 8 completed (13340.824 ms) ====== [2026-01-10T02:06:01.019Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T02:06:01.662Z] GC before operation: completed in 163.155 ms, heap usage 175.392 MB -> 64.711 MB. [2026-01-10T02:06:16.264Z] ====== als (apache-spark) [default], iteration 9 completed (13920.925 ms) ====== [2026-01-10T02:06:16.264Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T02:06:16.264Z] GC before operation: completed in 155.779 ms, heap usage 243.398 MB -> 64.910 MB. [2026-01-10T02:06:30.863Z] ====== als (apache-spark) [default], iteration 10 completed (13867.879 ms) ====== [2026-01-10T02:06:30.863Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T02:06:30.863Z] GC before operation: completed in 160.196 ms, heap usage 240.365 MB -> 65.156 MB. [2026-01-10T02:06:45.462Z] ====== als (apache-spark) [default], iteration 11 completed (13688.555 ms) ====== [2026-01-10T02:06:45.462Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T02:06:45.462Z] GC before operation: completed in 157.513 ms, heap usage 239.096 MB -> 65.416 MB. [2026-01-10T02:06:58.210Z] ====== als (apache-spark) [default], iteration 12 completed (13484.457 ms) ====== [2026-01-10T02:06:58.210Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T02:06:58.210Z] GC before operation: completed in 152.897 ms, heap usage 263.062 MB -> 65.693 MB. [2026-01-10T02:07:12.803Z] ====== als (apache-spark) [default], iteration 13 completed (13704.889 ms) ====== [2026-01-10T02:07:12.803Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T02:07:12.803Z] GC before operation: completed in 151.473 ms, heap usage 229.051 MB -> 65.923 MB. [2026-01-10T02:07:27.413Z] ====== als (apache-spark) [default], iteration 14 completed (13210.122 ms) ====== [2026-01-10T02:07:27.413Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T02:07:27.413Z] GC before operation: completed in 160.169 ms, heap usage 218.034 MB -> 66.166 MB. [2026-01-10T02:07:39.770Z] ====== als (apache-spark) [default], iteration 15 completed (13217.656 ms) ====== [2026-01-10T02:07:39.770Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T02:07:40.415Z] GC before operation: completed in 155.491 ms, heap usage 231.642 MB -> 66.459 MB. [2026-01-10T02:07:55.014Z] ====== als (apache-spark) [default], iteration 16 completed (13450.508 ms) ====== [2026-01-10T02:07:55.014Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T02:07:55.014Z] GC before operation: completed in 155.962 ms, heap usage 246.006 MB -> 66.673 MB. [2026-01-10T02:08:09.646Z] ====== als (apache-spark) [default], iteration 17 completed (14096.410 ms) ====== [2026-01-10T02:08:09.646Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T02:08:09.646Z] GC before operation: completed in 153.181 ms, heap usage 102.212 MB -> 66.794 MB. [2026-01-10T02:08:24.232Z] ====== als (apache-spark) [default], iteration 18 completed (13723.595 ms) ====== [2026-01-10T02:08:24.232Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T02:08:24.232Z] GC before operation: completed in 159.438 ms, heap usage 236.355 MB -> 67.156 MB. [2026-01-10T02:08:38.928Z] ====== als (apache-spark) [default], iteration 19 completed (13960.900 ms) ====== [2026-01-10T02:08:38.928Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T02:08:38.928Z] GC before operation: completed in 152.820 ms, heap usage 264.799 MB -> 67.434 MB. [2026-01-10T02:08:51.713Z] ====== als (apache-spark) [default], iteration 20 completed (13826.280 ms) ====== [2026-01-10T02:08:51.713Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T02:08:51.713Z] GC before operation: completed in 161.567 ms, heap usage 256.703 MB -> 67.642 MB. [2026-01-10T02:09:06.346Z] ====== als (apache-spark) [default], iteration 21 completed (14108.362 ms) ====== [2026-01-10T02:09:06.346Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T02:09:06.346Z] GC before operation: completed in 156.090 ms, heap usage 228.810 MB -> 67.890 MB. [2026-01-10T02:09:20.951Z] ====== als (apache-spark) [default], iteration 22 completed (14013.344 ms) ====== [2026-01-10T02:09:20.951Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T02:09:20.951Z] GC before operation: completed in 165.469 ms, heap usage 244.282 MB -> 68.129 MB. [2026-01-10T02:09:33.485Z] ====== als (apache-spark) [default], iteration 23 completed (13076.100 ms) ====== [2026-01-10T02:09:33.485Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T02:09:34.129Z] GC before operation: completed in 162.440 ms, heap usage 162.663 MB -> 68.323 MB. [2026-01-10T02:09:48.752Z] ====== als (apache-spark) [default], iteration 24 completed (13318.240 ms) ====== [2026-01-10T02:09:48.752Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T02:09:48.752Z] GC before operation: completed in 157.280 ms, heap usage 239.377 MB -> 68.648 MB. [2026-01-10T02:10:01.236Z] ====== als (apache-spark) [default], iteration 25 completed (13559.625 ms) ====== [2026-01-10T02:10:01.236Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T02:10:01.880Z] GC before operation: completed in 158.790 ms, heap usage 143.635 MB -> 68.778 MB. [2026-01-10T02:10:16.512Z] ====== als (apache-spark) [default], iteration 26 completed (13662.358 ms) ====== [2026-01-10T02:10:16.512Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T02:10:16.512Z] GC before operation: completed in 164.597 ms, heap usage 208.255 MB -> 69.151 MB. [2026-01-10T02:10:31.153Z] ====== als (apache-spark) [default], iteration 27 completed (13178.695 ms) ====== [2026-01-10T02:10:31.153Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T02:10:31.153Z] GC before operation: completed in 167.731 ms, heap usage 219.976 MB -> 69.387 MB. [2026-01-10T02:10:43.551Z] ====== als (apache-spark) [default], iteration 28 completed (13721.574 ms) ====== [2026-01-10T02:10:43.551Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T02:10:43.551Z] GC before operation: completed in 163.348 ms, heap usage 238.070 MB -> 69.632 MB. [2026-01-10T02:10:58.569Z] ====== als (apache-spark) [default], iteration 29 completed (13235.059 ms) ====== [2026-01-10T02:10:58.569Z] ----------------------------------- [2026-01-10T02:10:58.569Z] renaissance-als_0_PASSED [2026-01-10T02:10:58.569Z] ----------------------------------- [2026-01-10T02:10:58.569Z] [2026-01-10T02:10:58.569Z] TEST TEARDOWN: [2026-01-10T02:10:58.569Z] Nothing to be done for teardown. [2026-01-10T02:10:58.569Z] renaissance-als_0 Finish Time: Sat Jan 10 02:10:57 2026 Epoch Time (ms): 1768011057194