[2025-07-15T10:48:02.837Z] Running test renaissance-als_0 ... [2025-07-15T10:48:02.837Z] =============================================== [2025-07-15T10:48:02.837Z] renaissance-als_0 Start Time: Tue Jul 15 10:48:01 2025 Epoch Time (ms): 1752576481997 [2025-07-15T10:48:02.837Z] variation: NoOptions [2025-07-15T10:48:02.837Z] JVM_OPTIONS: [2025-07-15T10:48:02.837Z] { \ [2025-07-15T10:48:02.837Z] echo ""; echo "TEST SETUP:"; \ [2025-07-15T10:48:02.837Z] echo "Nothing to be done for setup."; \ [2025-07-15T10:48:02.837Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17525764372529/renaissance-als_0"; \ [2025-07-15T10:48:02.837Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17525764372529/renaissance-als_0"; \ [2025-07-15T10:48:02.837Z] echo ""; echo "TESTING:"; \ [2025-07-15T10:48:02.837Z] "/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_17525764372529/renaissance-als_0"/als.json" als; \ [2025-07-15T10:48:02.837Z] 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_17525764372529/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-15T10:48:02.837Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-15T10:48:02.837Z] echo "Nothing to be done for teardown."; \ [2025-07-15T10:48:02.837Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17525764372529/TestTargetResult"; [2025-07-15T10:48:02.837Z] [2025-07-15T10:48:02.837Z] TEST SETUP: [2025-07-15T10:48:02.837Z] Nothing to be done for setup. [2025-07-15T10:48:02.837Z] [2025-07-15T10:48:02.837Z] TESTING: [2025-07-15T10:48:08.715Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-07-15T10:48:13.466Z] WARNING: An illegal reflective access operation has occurred [2025-07-15T10:48:13.466Z] 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_17525764372529/renaissance-als_0/harness-104802-10543134405281704717/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-07-15T10:48:13.466Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-07-15T10:48:13.466Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-07-15T10:48:13.466Z] WARNING: All illegal access operations will be denied in a future release [2025-07-15T10:48:15.105Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-15T10:48:15.105Z] GC before operation: completed in 153.161 ms, heap usage 340.502 MB -> 34.296 MB. [2025-07-15T10:48:55.313Z] ====== als (apache-spark) [default], iteration 0 completed (37896.345 ms) ====== [2025-07-15T10:48:55.313Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-15T10:48:55.313Z] GC before operation: completed in 168.205 ms, heap usage 232.081 MB -> 59.985 MB. [2025-07-15T10:49:14.323Z] ====== als (apache-spark) [default], iteration 1 completed (19896.355 ms) ====== [2025-07-15T10:49:14.323Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-15T10:49:14.323Z] GC before operation: completed in 160.364 ms, heap usage 343.946 MB -> 61.025 MB. [2025-07-15T10:49:33.511Z] ====== als (apache-spark) [default], iteration 2 completed (17536.705 ms) ====== [2025-07-15T10:49:33.511Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-15T10:49:33.511Z] GC before operation: completed in 164.943 ms, heap usage 155.910 MB -> 61.379 MB. [2025-07-15T10:49:52.619Z] ====== als (apache-spark) [default], iteration 3 completed (17066.425 ms) ====== [2025-07-15T10:49:52.619Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-15T10:49:52.619Z] GC before operation: completed in 170.983 ms, heap usage 155.153 MB -> 61.889 MB. [2025-07-15T10:50:08.378Z] ====== als (apache-spark) [default], iteration 4 completed (16213.571 ms) ====== [2025-07-15T10:50:08.378Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-15T10:50:08.378Z] GC before operation: completed in 172.412 ms, heap usage 142.193 MB -> 62.405 MB. [2025-07-15T10:50:24.172Z] ====== als (apache-spark) [default], iteration 5 completed (16112.686 ms) ====== [2025-07-15T10:50:24.172Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-15T10:50:24.172Z] GC before operation: completed in 182.290 ms, heap usage 119.910 MB -> 62.945 MB. [2025-07-15T10:50:43.374Z] ====== als (apache-spark) [default], iteration 6 completed (17128.670 ms) ====== [2025-07-15T10:50:43.375Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-15T10:50:43.375Z] GC before operation: completed in 181.786 ms, heap usage 246.041 MB -> 63.583 MB. [2025-07-15T10:50:59.149Z] ====== als (apache-spark) [default], iteration 7 completed (17090.794 ms) ====== [2025-07-15T10:50:59.149Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-15T10:50:59.149Z] GC before operation: completed in 185.690 ms, heap usage 399.880 MB -> 64.234 MB. [2025-07-15T10:51:18.193Z] ====== als (apache-spark) [default], iteration 8 completed (16174.860 ms) ====== [2025-07-15T10:51:18.193Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-15T10:51:18.193Z] GC before operation: completed in 173.879 ms, heap usage 270.134 MB -> 64.737 MB. [2025-07-15T10:51:34.072Z] ====== als (apache-spark) [default], iteration 9 completed (16236.955 ms) ====== [2025-07-15T10:51:34.072Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-15T10:51:34.072Z] GC before operation: completed in 183.268 ms, heap usage 342.213 MB -> 65.023 MB. [2025-07-15T10:51:49.991Z] ====== als (apache-spark) [default], iteration 10 completed (16345.747 ms) ====== [2025-07-15T10:51:49.991Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-15T10:51:49.991Z] GC before operation: completed in 203.324 ms, heap usage 305.357 MB -> 65.165 MB. [2025-07-15T10:52:09.048Z] ====== als (apache-spark) [default], iteration 11 completed (16667.478 ms) ====== [2025-07-15T10:52:09.048Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-15T10:52:09.048Z] GC before operation: completed in 176.464 ms, heap usage 214.320 MB -> 65.423 MB. [2025-07-15T10:52:24.891Z] ====== als (apache-spark) [default], iteration 12 completed (15648.710 ms) ====== [2025-07-15T10:52:24.891Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-15T10:52:24.891Z] GC before operation: completed in 184.436 ms, heap usage 153.435 MB -> 65.553 MB. [2025-07-15T10:52:40.742Z] ====== als (apache-spark) [default], iteration 13 completed (15936.633 ms) ====== [2025-07-15T10:52:40.742Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-15T10:52:40.742Z] GC before operation: completed in 181.163 ms, heap usage 267.977 MB -> 65.871 MB. [2025-07-15T10:52:56.624Z] ====== als (apache-spark) [default], iteration 14 completed (15602.826 ms) ====== [2025-07-15T10:52:56.624Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-15T10:52:56.624Z] GC before operation: completed in 189.762 ms, heap usage 248.354 MB -> 66.068 MB. [2025-07-15T10:53:15.820Z] ====== als (apache-spark) [default], iteration 15 completed (17927.311 ms) ====== [2025-07-15T10:53:15.820Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-15T10:53:15.820Z] GC before operation: completed in 182.533 ms, heap usage 248.624 MB -> 66.446 MB. [2025-07-15T10:53:31.595Z] ====== als (apache-spark) [default], iteration 16 completed (15990.879 ms) ====== [2025-07-15T10:53:31.595Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-15T10:53:31.595Z] GC before operation: completed in 191.108 ms, heap usage 262.424 MB -> 66.661 MB. [2025-07-15T10:53:50.676Z] ====== als (apache-spark) [default], iteration 17 completed (17015.665 ms) ====== [2025-07-15T10:53:50.676Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-15T10:53:50.676Z] GC before operation: completed in 183.479 ms, heap usage 128.842 MB -> 66.779 MB. [2025-07-15T10:54:06.469Z] ====== als (apache-spark) [default], iteration 18 completed (16306.266 ms) ====== [2025-07-15T10:54:06.469Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-15T10:54:06.469Z] GC before operation: completed in 184.093 ms, heap usage 358.714 MB -> 67.248 MB. [2025-07-15T10:54:22.412Z] ====== als (apache-spark) [default], iteration 19 completed (16679.167 ms) ====== [2025-07-15T10:54:22.412Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-15T10:54:22.412Z] GC before operation: completed in 181.055 ms, heap usage 261.879 MB -> 67.388 MB. [2025-07-15T10:54:41.503Z] ====== als (apache-spark) [default], iteration 20 completed (16931.314 ms) ====== [2025-07-15T10:54:41.503Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-15T10:54:41.503Z] GC before operation: completed in 183.119 ms, heap usage 150.271 MB -> 67.511 MB. [2025-07-15T10:54:57.373Z] ====== als (apache-spark) [default], iteration 21 completed (17292.204 ms) ====== [2025-07-15T10:54:57.373Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-15T10:54:57.373Z] GC before operation: completed in 184.259 ms, heap usage 234.303 MB -> 67.903 MB. [2025-07-15T10:55:15.087Z] ====== als (apache-spark) [default], iteration 22 completed (16616.994 ms) ====== [2025-07-15T10:55:15.087Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-15T10:55:15.087Z] GC before operation: completed in 188.468 ms, heap usage 249.885 MB -> 68.139 MB. [2025-07-15T10:55:30.895Z] ====== als (apache-spark) [default], iteration 23 completed (16190.095 ms) ====== [2025-07-15T10:55:30.895Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-15T10:55:31.227Z] GC before operation: completed in 189.421 ms, heap usage 253.617 MB -> 68.422 MB. [2025-07-15T10:55:50.423Z] ====== als (apache-spark) [default], iteration 24 completed (16566.913 ms) ====== [2025-07-15T10:55:50.423Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-15T10:55:50.423Z] GC before operation: completed in 197.714 ms, heap usage 101.888 MB -> 68.086 MB. [2025-07-15T10:56:06.366Z] ====== als (apache-spark) [default], iteration 25 completed (15964.779 ms) ====== [2025-07-15T10:56:06.366Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-15T10:56:06.366Z] GC before operation: completed in 189.207 ms, heap usage 210.455 MB -> 68.791 MB. [2025-07-15T10:56:22.175Z] ====== als (apache-spark) [default], iteration 26 completed (15985.524 ms) ====== [2025-07-15T10:56:22.175Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-15T10:56:22.175Z] GC before operation: completed in 184.771 ms, heap usage 208.201 MB -> 69.155 MB. [2025-07-15T10:56:38.001Z] ====== als (apache-spark) [default], iteration 27 completed (16009.454 ms) ====== [2025-07-15T10:56:38.001Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-15T10:56:38.001Z] GC before operation: completed in 196.572 ms, heap usage 210.001 MB -> 69.398 MB. [2025-07-15T10:56:57.119Z] ====== als (apache-spark) [default], iteration 28 completed (16551.712 ms) ====== [2025-07-15T10:56:57.119Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-15T10:56:57.119Z] GC before operation: completed in 182.428 ms, heap usage 238.624 MB -> 69.183 MB. [2025-07-15T10:57:13.065Z] ====== als (apache-spark) [default], iteration 29 completed (15886.207 ms) ====== [2025-07-15T10:57:13.065Z] ----------------------------------- [2025-07-15T10:57:13.065Z] renaissance-als_0_PASSED [2025-07-15T10:57:13.065Z] ----------------------------------- [2025-07-15T10:57:13.065Z] [2025-07-15T10:57:13.065Z] TEST TEARDOWN: [2025-07-15T10:57:13.065Z] Nothing to be done for teardown. [2025-07-15T10:57:13.065Z] renaissance-als_0 Finish Time: Tue Jul 15 10:57:11 2025 Epoch Time (ms): 1752577031490