[2025-04-08T21:18:28.151Z] Running test renaissance-als_0 ... [2025-04-08T21:18:28.151Z] =============================================== [2025-04-08T21:18:28.151Z] renaissance-als_0 Start Time: Tue Apr 8 21:18:27 2025 Epoch Time (ms): 1744147107639 [2025-04-08T21:18:28.151Z] variation: NoOptions [2025-04-08T21:18:28.151Z] JVM_OPTIONS: [2025-04-08T21:18:28.151Z] { \ [2025-04-08T21:18:28.151Z] echo ""; echo "TEST SETUP:"; \ [2025-04-08T21:18:28.151Z] echo "Nothing to be done for setup."; \ [2025-04-08T21:18:28.151Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17441471075271/renaissance-als_0"; \ [2025-04-08T21:18:28.151Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17441471075271/renaissance-als_0"; \ [2025-04-08T21:18:28.152Z] echo ""; echo "TESTING:"; \ [2025-04-08T21:18:28.152Z] "/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_17441471075271/renaissance-als_0"/als.json" als; \ [2025-04-08T21:18:28.152Z] 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_17441471075271/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-04-08T21:18:28.152Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-04-08T21:18:28.152Z] echo "Nothing to be done for teardown."; \ [2025-04-08T21:18:28.152Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17441471075271/TestTargetResult"; [2025-04-08T21:18:28.152Z] [2025-04-08T21:18:28.152Z] TEST SETUP: [2025-04-08T21:18:28.152Z] Nothing to be done for setup. [2025-04-08T21:18:28.152Z] [2025-04-08T21:18:28.152Z] TESTING: [2025-04-08T21:18:33.715Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-04-08T21:18:36.166Z] WARNING: An illegal reflective access operation has occurred [2025-04-08T21:18:36.166Z] 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_17441471075271/renaissance-als_0/harness-211828-14315267921186082922/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-04-08T21:18:36.166Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-04-08T21:18:36.166Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-04-08T21:18:36.166Z] WARNING: All illegal access operations will be denied in a future release [2025-04-08T21:18:36.938Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-04-08T21:18:36.939Z] GC before operation: completed in 80.404 ms, heap usage 255.511 MB -> 34.708 MB. [2025-04-08T21:19:07.667Z] ====== als (apache-spark) [default], iteration 0 completed (28493.216 ms) ====== [2025-04-08T21:19:07.667Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-04-08T21:19:07.667Z] GC before operation: completed in 209.208 ms, heap usage 229.659 MB -> 60.641 MB. [2025-04-08T21:19:21.445Z] ====== als (apache-spark) [default], iteration 1 completed (14048.159 ms) ====== [2025-04-08T21:19:21.445Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-04-08T21:19:21.445Z] GC before operation: completed in 218.698 ms, heap usage 262.125 MB -> 61.387 MB. [2025-04-08T21:19:35.270Z] ====== als (apache-spark) [default], iteration 2 completed (13052.501 ms) ====== [2025-04-08T21:19:35.271Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-04-08T21:19:35.271Z] GC before operation: completed in 211.927 ms, heap usage 149.072 MB -> 62.035 MB. [2025-04-08T21:19:49.064Z] ====== als (apache-spark) [default], iteration 3 completed (13169.279 ms) ====== [2025-04-08T21:19:49.064Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-04-08T21:19:49.064Z] GC before operation: completed in 238.545 ms, heap usage 206.270 MB -> 62.372 MB. [2025-04-08T21:20:02.899Z] ====== als (apache-spark) [default], iteration 4 completed (12978.451 ms) ====== [2025-04-08T21:20:02.899Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-04-08T21:20:02.899Z] GC before operation: completed in 235.751 ms, heap usage 133.117 MB -> 62.922 MB. [2025-04-08T21:20:16.646Z] ====== als (apache-spark) [default], iteration 5 completed (12515.392 ms) ====== [2025-04-08T21:20:16.646Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-04-08T21:20:16.646Z] GC before operation: completed in 269.959 ms, heap usage 225.570 MB -> 63.575 MB. [2025-04-08T21:20:30.460Z] ====== als (apache-spark) [default], iteration 6 completed (12562.084 ms) ====== [2025-04-08T21:20:30.460Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-04-08T21:20:30.460Z] GC before operation: completed in 295.121 ms, heap usage 365.025 MB -> 64.212 MB. [2025-04-08T21:20:42.099Z] ====== als (apache-spark) [default], iteration 7 completed (12521.799 ms) ====== [2025-04-08T21:20:42.099Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-04-08T21:20:42.870Z] GC before operation: completed in 283.273 ms, heap usage 276.215 MB -> 64.598 MB. [2025-04-08T21:20:56.562Z] ====== als (apache-spark) [default], iteration 8 completed (12268.181 ms) ====== [2025-04-08T21:20:56.562Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-04-08T21:20:56.562Z] GC before operation: completed in 238.913 ms, heap usage 329.585 MB -> 65.225 MB. [2025-04-08T21:21:08.191Z] ====== als (apache-spark) [default], iteration 9 completed (12408.531 ms) ====== [2025-04-08T21:21:08.191Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-04-08T21:21:08.191Z] GC before operation: completed in 256.370 ms, heap usage 331.991 MB -> 65.507 MB. [2025-04-08T21:21:21.890Z] ====== als (apache-spark) [default], iteration 10 completed (11922.423 ms) ====== [2025-04-08T21:21:21.891Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-04-08T21:21:21.891Z] GC before operation: completed in 247.603 ms, heap usage 417.104 MB -> 65.749 MB. [2025-04-08T21:21:33.521Z] ====== als (apache-spark) [default], iteration 11 completed (12244.250 ms) ====== [2025-04-08T21:21:33.521Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-04-08T21:21:33.521Z] GC before operation: completed in 238.476 ms, heap usage 198.498 MB -> 65.799 MB. [2025-04-08T21:21:47.312Z] ====== als (apache-spark) [default], iteration 12 completed (11840.841 ms) ====== [2025-04-08T21:21:47.312Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-04-08T21:21:47.312Z] GC before operation: completed in 261.879 ms, heap usage 249.986 MB -> 66.166 MB. [2025-04-08T21:21:58.941Z] ====== als (apache-spark) [default], iteration 13 completed (11796.711 ms) ====== [2025-04-08T21:21:58.941Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-04-08T21:21:58.941Z] GC before operation: completed in 222.997 ms, heap usage 137.109 MB -> 66.293 MB. [2025-04-08T21:22:10.583Z] ====== als (apache-spark) [default], iteration 14 completed (11804.392 ms) ====== [2025-04-08T21:22:10.583Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-04-08T21:22:10.583Z] GC before operation: completed in 216.654 ms, heap usage 183.427 MB -> 66.541 MB. [2025-04-08T21:22:24.872Z] ====== als (apache-spark) [default], iteration 15 completed (12174.704 ms) ====== [2025-04-08T21:22:24.872Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-04-08T21:22:24.872Z] GC before operation: completed in 231.716 ms, heap usage 236.305 MB -> 66.902 MB. [2025-04-08T21:22:36.462Z] ====== als (apache-spark) [default], iteration 16 completed (12620.025 ms) ====== [2025-04-08T21:22:36.463Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-04-08T21:22:37.228Z] GC before operation: completed in 254.692 ms, heap usage 129.140 MB -> 67.028 MB. [2025-04-08T21:22:50.992Z] ====== als (apache-spark) [default], iteration 17 completed (12496.336 ms) ====== [2025-04-08T21:22:50.992Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-04-08T21:22:50.992Z] GC before operation: completed in 257.096 ms, heap usage 138.421 MB -> 67.287 MB. [2025-04-08T21:23:02.618Z] ====== als (apache-spark) [default], iteration 18 completed (11608.117 ms) ====== [2025-04-08T21:23:02.618Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-04-08T21:23:02.618Z] GC before operation: completed in 292.145 ms, heap usage 209.994 MB -> 67.770 MB. [2025-04-08T21:23:16.304Z] ====== als (apache-spark) [default], iteration 19 completed (12521.507 ms) ====== [2025-04-08T21:23:16.304Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-04-08T21:23:16.304Z] GC before operation: completed in 265.003 ms, heap usage 236.660 MB -> 67.921 MB. [2025-04-08T21:23:30.117Z] ====== als (apache-spark) [default], iteration 20 completed (13528.066 ms) ====== [2025-04-08T21:23:30.117Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-04-08T21:23:30.117Z] GC before operation: completed in 268.146 ms, heap usage 204.943 MB -> 68.047 MB. [2025-04-08T21:23:41.832Z] ====== als (apache-spark) [default], iteration 21 completed (12036.419 ms) ====== [2025-04-08T21:23:41.832Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-04-08T21:23:41.832Z] GC before operation: completed in 260.651 ms, heap usage 125.988 MB -> 67.827 MB. [2025-04-08T21:23:55.490Z] ====== als (apache-spark) [default], iteration 22 completed (13007.346 ms) ====== [2025-04-08T21:23:55.490Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-04-08T21:23:55.490Z] GC before operation: completed in 254.351 ms, heap usage 377.260 MB -> 68.798 MB. [2025-04-08T21:24:09.223Z] ====== als (apache-spark) [default], iteration 23 completed (12294.579 ms) ====== [2025-04-08T21:24:09.223Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-04-08T21:24:09.223Z] GC before operation: completed in 233.507 ms, heap usage 381.061 MB -> 69.043 MB. [2025-04-08T21:24:21.500Z] ====== als (apache-spark) [default], iteration 24 completed (12856.865 ms) ====== [2025-04-08T21:24:21.500Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-04-08T21:24:22.278Z] GC before operation: completed in 247.827 ms, heap usage 194.446 MB -> 69.064 MB. [2025-04-08T21:24:34.034Z] ====== als (apache-spark) [default], iteration 25 completed (11930.142 ms) ====== [2025-04-08T21:24:34.034Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-04-08T21:24:34.806Z] GC before operation: completed in 248.164 ms, heap usage 171.544 MB -> 69.313 MB. [2025-04-08T21:24:48.655Z] ====== als (apache-spark) [default], iteration 26 completed (12278.839 ms) ====== [2025-04-08T21:24:48.655Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-04-08T21:24:48.655Z] GC before operation: completed in 227.488 ms, heap usage 351.634 MB -> 69.785 MB. [2025-04-08T21:25:00.386Z] ====== als (apache-spark) [default], iteration 27 completed (12376.299 ms) ====== [2025-04-08T21:25:00.386Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-04-08T21:25:00.386Z] GC before operation: completed in 270.864 ms, heap usage 233.082 MB -> 69.916 MB. [2025-04-08T21:25:14.191Z] ====== als (apache-spark) [default], iteration 28 completed (12340.607 ms) ====== [2025-04-08T21:25:14.191Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-04-08T21:25:14.191Z] GC before operation: completed in 248.460 ms, heap usage 269.625 MB -> 70.166 MB. [2025-04-08T21:25:25.926Z] ====== als (apache-spark) [default], iteration 29 completed (11955.271 ms) ====== [2025-04-08T21:25:25.926Z] ----------------------------------- [2025-04-08T21:25:25.926Z] renaissance-als_0_PASSED [2025-04-08T21:25:25.926Z] ----------------------------------- [2025-04-08T21:25:25.926Z] [2025-04-08T21:25:25.926Z] TEST TEARDOWN: [2025-04-08T21:25:25.926Z] Nothing to be done for teardown. [2025-04-08T21:25:25.926Z] renaissance-als_0 Finish Time: Tue Apr 8 21:25:25 2025 Epoch Time (ms): 1744147525658