[2026-04-06T21:39:27.960Z] Running test renaissance-als_0 ... [2026-04-06T21:39:27.960Z] =============================================== [2026-04-06T21:39:27.960Z] renaissance-als_0 Start Time: Mon Apr 6 21:39:27 2026 Epoch Time (ms): 1775511567760 [2026-04-06T21:39:27.960Z] variation: NoOptions [2026-04-06T21:39:27.960Z] JVM_OPTIONS: [2026-04-06T21:39:27.960Z] { \ [2026-04-06T21:39:27.960Z] echo ""; echo "TEST SETUP:"; \ [2026-04-06T21:39:27.960Z] echo "Nothing to be done for setup."; \ [2026-04-06T21:39:27.960Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17755115672902/renaissance-als_0"; \ [2026-04-06T21:39:27.960Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17755115672902/renaissance-als_0"; \ [2026-04-06T21:39:27.960Z] echo ""; echo "TESTING:"; \ [2026-04-06T21:39:27.960Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17755115672902/renaissance-als_0"/als.json" als; \ [2026-04-06T21:39:27.960Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17755115672902/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-06T21:39:27.960Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-06T21:39:27.960Z] echo "Nothing to be done for teardown."; \ [2026-04-06T21:39:27.960Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17755115672902/TestTargetResult"; [2026-04-06T21:39:27.960Z] [2026-04-06T21:39:27.960Z] TEST SETUP: [2026-04-06T21:39:27.960Z] Nothing to be done for setup. [2026-04-06T21:39:27.960Z] [2026-04-06T21:39:27.960Z] TESTING: [2026-04-06T21:39:37.791Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-06T21:39:40.386Z] WARNING: An illegal reflective access operation has occurred [2026-04-06T21:39:40.386Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17755115672902/renaissance-als_0/harness-213928-11852512693419746772/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-06T21:39:40.386Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-06T21:39:40.386Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-06T21:39:40.386Z] WARNING: All illegal access operations will be denied in a future release [2026-04-06T21:39:41.993Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-06T21:39:41.993Z] GC before operation: completed in 127.524 ms, heap usage 463.502 MB -> 34.531 MB. [2026-04-06T21:40:17.421Z] ====== als (apache-spark) [default], iteration 0 completed (32916.928 ms) ====== [2026-04-06T21:40:17.421Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-06T21:40:17.421Z] GC before operation: completed in 132.738 ms, heap usage 118.022 MB -> 60.362 MB. [2026-04-06T21:40:29.261Z] ====== als (apache-spark) [default], iteration 1 completed (12095.941 ms) ====== [2026-04-06T21:40:29.261Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-06T21:40:29.261Z] GC before operation: completed in 142.228 ms, heap usage 209.604 MB -> 61.115 MB. [2026-04-06T21:40:41.188Z] ====== als (apache-spark) [default], iteration 2 completed (10986.138 ms) ====== [2026-04-06T21:40:41.188Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-06T21:40:41.188Z] GC before operation: completed in 300.952 ms, heap usage 192.234 MB -> 61.791 MB. [2026-04-06T21:40:52.904Z] ====== als (apache-spark) [default], iteration 3 completed (11123.490 ms) ====== [2026-04-06T21:40:52.905Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-06T21:40:52.905Z] GC before operation: completed in 187.566 ms, heap usage 329.184 MB -> 62.419 MB. [2026-04-06T21:41:04.635Z] ====== als (apache-spark) [default], iteration 4 completed (12092.031 ms) ====== [2026-04-06T21:41:04.635Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-06T21:41:04.635Z] GC before operation: completed in 232.487 ms, heap usage 207.986 MB -> 62.729 MB. [2026-04-06T21:41:15.057Z] ====== als (apache-spark) [default], iteration 5 completed (10372.842 ms) ====== [2026-04-06T21:41:15.057Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-06T21:41:15.057Z] GC before operation: completed in 142.117 ms, heap usage 143.786 MB -> 63.272 MB. [2026-04-06T21:41:32.337Z] ====== als (apache-spark) [default], iteration 6 completed (16980.178 ms) ====== [2026-04-06T21:41:32.337Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-06T21:41:32.337Z] GC before operation: completed in 162.667 ms, heap usage 224.862 MB -> 63.913 MB. [2026-04-06T21:41:44.104Z] ====== als (apache-spark) [default], iteration 7 completed (10116.171 ms) ====== [2026-04-06T21:41:44.104Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-06T21:41:44.104Z] GC before operation: completed in 202.830 ms, heap usage 115.816 MB -> 63.874 MB. [2026-04-06T21:41:52.384Z] ====== als (apache-spark) [default], iteration 8 completed (8535.788 ms) ====== [2026-04-06T21:41:52.384Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-06T21:41:52.384Z] GC before operation: completed in 187.697 ms, heap usage 175.400 MB -> 64.853 MB. [2026-04-06T21:42:00.733Z] ====== als (apache-spark) [default], iteration 9 completed (7926.982 ms) ====== [2026-04-06T21:42:00.733Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-06T21:42:00.733Z] GC before operation: completed in 171.828 ms, heap usage 233.296 MB -> 65.230 MB. [2026-04-06T21:42:09.001Z] ====== als (apache-spark) [default], iteration 10 completed (7796.310 ms) ====== [2026-04-06T21:42:09.001Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-06T21:42:09.001Z] GC before operation: completed in 163.958 ms, heap usage 261.902 MB -> 65.505 MB. [2026-04-06T21:42:20.689Z] ====== als (apache-spark) [default], iteration 11 completed (11761.213 ms) ====== [2026-04-06T21:42:20.689Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-06T21:42:21.466Z] GC before operation: completed in 168.358 ms, heap usage 249.743 MB -> 65.745 MB. [2026-04-06T21:42:29.787Z] ====== als (apache-spark) [default], iteration 12 completed (8609.695 ms) ====== [2026-04-06T21:42:29.787Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-06T21:42:30.571Z] GC before operation: completed in 212.382 ms, heap usage 229.529 MB -> 66.098 MB. [2026-04-06T21:42:42.580Z] ====== als (apache-spark) [default], iteration 13 completed (10786.552 ms) ====== [2026-04-06T21:42:42.580Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-06T21:42:42.580Z] GC before operation: completed in 263.240 ms, heap usage 249.889 MB -> 66.237 MB. [2026-04-06T21:42:50.845Z] ====== als (apache-spark) [default], iteration 14 completed (8873.670 ms) ====== [2026-04-06T21:42:50.845Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-06T21:42:50.845Z] GC before operation: completed in 139.923 ms, heap usage 229.789 MB -> 66.488 MB. [2026-04-06T21:42:59.105Z] ====== als (apache-spark) [default], iteration 15 completed (7663.475 ms) ====== [2026-04-06T21:42:59.105Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-06T21:42:59.105Z] GC before operation: completed in 147.834 ms, heap usage 270.641 MB -> 66.753 MB. [2026-04-06T21:43:10.789Z] ====== als (apache-spark) [default], iteration 16 completed (11293.749 ms) ====== [2026-04-06T21:43:10.789Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-06T21:43:10.789Z] GC before operation: completed in 353.487 ms, heap usage 472.249 MB -> 67.349 MB. [2026-04-06T21:43:27.000Z] ====== als (apache-spark) [default], iteration 17 completed (14100.801 ms) ====== [2026-04-06T21:43:27.000Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-06T21:43:27.000Z] GC before operation: completed in 334.022 ms, heap usage 360.087 MB -> 67.350 MB. [2026-04-06T21:43:41.240Z] ====== als (apache-spark) [default], iteration 18 completed (14157.541 ms) ====== [2026-04-06T21:43:41.240Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-06T21:43:41.240Z] GC before operation: completed in 351.847 ms, heap usage 165.952 MB -> 67.406 MB. [2026-04-06T21:43:57.402Z] ====== als (apache-spark) [default], iteration 19 completed (16135.479 ms) ====== [2026-04-06T21:43:57.402Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-06T21:43:57.402Z] GC before operation: completed in 362.041 ms, heap usage 206.571 MB -> 67.619 MB. [2026-04-06T21:44:14.098Z] ====== als (apache-spark) [default], iteration 20 completed (15924.549 ms) ====== [2026-04-06T21:44:14.098Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-06T21:44:14.098Z] GC before operation: completed in 433.553 ms, heap usage 195.772 MB -> 67.868 MB. [2026-04-06T21:44:30.274Z] ====== als (apache-spark) [default], iteration 21 completed (13818.134 ms) ====== [2026-04-06T21:44:30.274Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-06T21:44:30.274Z] GC before operation: completed in 339.523 ms, heap usage 138.108 MB -> 68.114 MB. [2026-04-06T21:44:49.404Z] ====== als (apache-spark) [default], iteration 22 completed (17922.050 ms) ====== [2026-04-06T21:44:49.404Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-06T21:44:49.404Z] GC before operation: completed in 481.584 ms, heap usage 416.399 MB -> 68.611 MB. [2026-04-06T21:45:08.359Z] ====== als (apache-spark) [default], iteration 23 completed (19346.566 ms) ====== [2026-04-06T21:45:08.359Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-06T21:45:08.359Z] GC before operation: completed in 412.441 ms, heap usage 184.543 MB -> 68.636 MB. [2026-04-06T21:45:27.326Z] ====== als (apache-spark) [default], iteration 24 completed (18619.162 ms) ====== [2026-04-06T21:45:27.326Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-06T21:45:27.326Z] GC before operation: completed in 363.196 ms, heap usage 277.554 MB -> 69.007 MB. [2026-04-06T21:45:47.214Z] ====== als (apache-spark) [default], iteration 25 completed (19524.085 ms) ====== [2026-04-06T21:45:47.214Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-06T21:45:47.995Z] GC before operation: completed in 394.457 ms, heap usage 486.670 MB -> 69.598 MB. [2026-04-06T21:46:10.108Z] ====== als (apache-spark) [default], iteration 26 completed (20796.803 ms) ====== [2026-04-06T21:46:10.108Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-06T21:46:10.108Z] GC before operation: completed in 379.265 ms, heap usage 249.805 MB -> 69.551 MB. [2026-04-06T21:46:29.068Z] ====== als (apache-spark) [default], iteration 27 completed (18774.774 ms) ====== [2026-04-06T21:46:29.068Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-06T21:46:29.848Z] GC before operation: completed in 422.556 ms, heap usage 273.544 MB -> 69.741 MB. [2026-04-06T21:46:48.824Z] ====== als (apache-spark) [default], iteration 28 completed (18842.985 ms) ====== [2026-04-06T21:46:48.824Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-06T21:46:49.619Z] GC before operation: completed in 375.904 ms, heap usage 341.054 MB -> 69.688 MB. [2026-04-06T21:47:11.174Z] ====== als (apache-spark) [default], iteration 29 completed (19505.296 ms) ====== [2026-04-06T21:47:11.174Z] ----------------------------------- [2026-04-06T21:47:11.174Z] renaissance-als_0_PASSED [2026-04-06T21:47:11.174Z] ----------------------------------- [2026-04-06T21:47:11.174Z] [2026-04-06T21:47:11.174Z] TEST TEARDOWN: [2026-04-06T21:47:11.174Z] Nothing to be done for teardown. [2026-04-06T21:47:11.174Z] renaissance-als_0 Finish Time: Mon Apr 6 21:47:09 2026 Epoch Time (ms): 1775512029841