[2025-08-25T21:26:51.167Z] Running test renaissance-als_0 ... [2025-08-25T21:26:51.167Z] =============================================== [2025-08-25T21:26:51.167Z] renaissance-als_0 Start Time: Mon Aug 25 21:26:50 2025 Epoch Time (ms): 1756157210958 [2025-08-25T21:26:51.167Z] variation: NoOptions [2025-08-25T21:26:51.167Z] JVM_OPTIONS: [2025-08-25T21:26:51.167Z] { \ [2025-08-25T21:26:51.167Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T21:26:51.167Z] echo "Nothing to be done for setup."; \ [2025-08-25T21:26:51.167Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/renaissance-als_0"; \ [2025-08-25T21:26:51.167Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/renaissance-als_0"; \ [2025-08-25T21:26:51.167Z] echo ""; echo "TESTING:"; \ [2025-08-25T21:26:51.167Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/renaissance-als_0"/als.json" als; \ [2025-08-25T21:26:51.167Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T21:26:51.167Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T21:26:51.167Z] echo "Nothing to be done for teardown."; \ [2025-08-25T21:26:51.167Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17561569457495/TestTargetResult"; [2025-08-25T21:26:51.167Z] [2025-08-25T21:26:51.167Z] TEST SETUP: [2025-08-25T21:26:51.167Z] Nothing to be done for setup. [2025-08-25T21:26:51.167Z] [2025-08-25T21:26:51.167Z] TESTING: [2025-08-25T21:27:00.870Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-25T21:27:05.024Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T21:27:05.024Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17561569457495/renaissance-als_0/harness-212652-10999438411350406598/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T21:27:05.024Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T21:27:05.024Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T21:27:05.024Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T21:27:05.973Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-25T21:27:06.920Z] GC before operation: completed in 122.797 ms, heap usage 255.000 MB -> 34.637 MB. [2025-08-25T21:27:40.120Z] ====== als (apache-spark) [default], iteration 0 completed (31686.010 ms) ====== [2025-08-25T21:27:40.120Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-25T21:27:40.120Z] GC before operation: completed in 176.970 ms, heap usage 129.167 MB -> 60.514 MB. [2025-08-25T21:27:52.162Z] ====== als (apache-spark) [default], iteration 1 completed (12608.669 ms) ====== [2025-08-25T21:27:52.162Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-25T21:27:53.114Z] GC before operation: completed in 201.451 ms, heap usage 150.519 MB -> 61.317 MB. [2025-08-25T21:28:04.619Z] ====== als (apache-spark) [default], iteration 2 completed (11103.695 ms) ====== [2025-08-25T21:28:04.619Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-25T21:28:04.619Z] GC before operation: completed in 203.995 ms, heap usage 159.487 MB -> 61.903 MB. [2025-08-25T21:28:16.124Z] ====== als (apache-spark) [default], iteration 3 completed (10692.138 ms) ====== [2025-08-25T21:28:16.124Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-25T21:28:16.124Z] GC before operation: completed in 202.518 ms, heap usage 247.444 MB -> 62.504 MB. [2025-08-25T21:28:27.608Z] ====== als (apache-spark) [default], iteration 4 completed (10846.282 ms) ====== [2025-08-25T21:28:27.608Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-25T21:28:27.608Z] GC before operation: completed in 192.117 ms, heap usage 317.668 MB -> 63.135 MB. [2025-08-25T21:28:37.411Z] ====== als (apache-spark) [default], iteration 5 completed (10151.550 ms) ====== [2025-08-25T21:28:37.411Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-25T21:28:38.357Z] GC before operation: completed in 191.581 ms, heap usage 351.712 MB -> 63.671 MB. [2025-08-25T21:28:49.840Z] ====== als (apache-spark) [default], iteration 6 completed (10749.696 ms) ====== [2025-08-25T21:28:49.840Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-25T21:28:49.840Z] GC before operation: completed in 217.328 ms, heap usage 364.687 MB -> 64.205 MB. [2025-08-25T21:29:01.371Z] ====== als (apache-spark) [default], iteration 7 completed (10894.369 ms) ====== [2025-08-25T21:29:01.371Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-25T21:29:01.371Z] GC before operation: completed in 189.648 ms, heap usage 184.357 MB -> 64.524 MB. [2025-08-25T21:29:11.850Z] ====== als (apache-spark) [default], iteration 8 completed (10291.999 ms) ====== [2025-08-25T21:29:11.851Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-25T21:29:11.851Z] GC before operation: completed in 210.383 ms, heap usage 280.445 MB -> 65.147 MB. [2025-08-25T21:29:23.347Z] ====== als (apache-spark) [default], iteration 9 completed (10422.145 ms) ====== [2025-08-25T21:29:23.347Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-25T21:29:23.347Z] GC before operation: completed in 195.401 ms, heap usage 249.441 MB -> 65.399 MB. [2025-08-25T21:29:33.072Z] ====== als (apache-spark) [default], iteration 10 completed (10247.174 ms) ====== [2025-08-25T21:29:33.072Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-25T21:29:34.026Z] GC before operation: completed in 206.653 ms, heap usage 261.744 MB -> 65.652 MB. [2025-08-25T21:29:45.510Z] ====== als (apache-spark) [default], iteration 11 completed (10423.843 ms) ====== [2025-08-25T21:29:45.510Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-25T21:29:45.510Z] GC before operation: completed in 195.935 ms, heap usage 261.203 MB -> 65.917 MB. [2025-08-25T21:29:55.220Z] ====== als (apache-spark) [default], iteration 12 completed (10332.399 ms) ====== [2025-08-25T21:29:55.220Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-25T21:29:55.220Z] GC before operation: completed in 194.521 ms, heap usage 234.051 MB -> 66.164 MB. [2025-08-25T21:30:06.751Z] ====== als (apache-spark) [default], iteration 13 completed (10461.685 ms) ====== [2025-08-25T21:30:06.751Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-25T21:30:06.751Z] GC before operation: completed in 211.890 ms, heap usage 274.134 MB -> 66.415 MB. [2025-08-25T21:30:16.846Z] ====== als (apache-spark) [default], iteration 14 completed (10024.696 ms) ====== [2025-08-25T21:30:16.846Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-25T21:30:16.846Z] GC before operation: completed in 204.559 ms, heap usage 221.531 MB -> 66.665 MB. [2025-08-25T21:30:28.345Z] ====== als (apache-spark) [default], iteration 15 completed (10405.683 ms) ====== [2025-08-25T21:30:28.345Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-25T21:30:28.345Z] GC before operation: completed in 200.530 ms, heap usage 233.871 MB -> 66.934 MB. [2025-08-25T21:30:39.818Z] ====== als (apache-spark) [default], iteration 16 completed (10419.747 ms) ====== [2025-08-25T21:30:39.818Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-25T21:30:39.818Z] GC before operation: completed in 207.256 ms, heap usage 168.814 MB -> 67.091 MB. [2025-08-25T21:30:49.529Z] ====== als (apache-spark) [default], iteration 17 completed (10153.143 ms) ====== [2025-08-25T21:30:49.529Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-25T21:30:49.529Z] GC before operation: completed in 199.261 ms, heap usage 223.039 MB -> 67.464 MB. [2025-08-25T21:31:01.004Z] ====== als (apache-spark) [default], iteration 18 completed (10181.231 ms) ====== [2025-08-25T21:31:01.004Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-25T21:31:01.004Z] GC before operation: completed in 199.983 ms, heap usage 274.256 MB -> 67.697 MB. [2025-08-25T21:31:12.520Z] ====== als (apache-spark) [default], iteration 19 completed (10515.953 ms) ====== [2025-08-25T21:31:12.520Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-25T21:31:12.520Z] GC before operation: completed in 189.008 ms, heap usage 158.844 MB -> 67.824 MB. [2025-08-25T21:31:22.272Z] ====== als (apache-spark) [default], iteration 20 completed (10276.040 ms) ====== [2025-08-25T21:31:22.272Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-25T21:31:22.272Z] GC before operation: completed in 206.338 ms, heap usage 370.393 MB -> 68.300 MB. [2025-08-25T21:31:34.446Z] ====== als (apache-spark) [default], iteration 21 completed (10379.081 ms) ====== [2025-08-25T21:31:34.446Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-25T21:31:34.446Z] GC before operation: completed in 208.997 ms, heap usage 223.895 MB -> 68.322 MB. [2025-08-25T21:31:44.408Z] ====== als (apache-spark) [default], iteration 22 completed (10024.454 ms) ====== [2025-08-25T21:31:44.408Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-25T21:31:44.408Z] GC before operation: completed in 210.316 ms, heap usage 190.476 MB -> 68.606 MB. [2025-08-25T21:31:54.110Z] ====== als (apache-spark) [default], iteration 23 completed (9902.725 ms) ====== [2025-08-25T21:31:54.110Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-25T21:31:54.110Z] GC before operation: completed in 217.456 ms, heap usage 227.856 MB -> 68.983 MB. [2025-08-25T21:32:05.576Z] ====== als (apache-spark) [default], iteration 24 completed (10399.107 ms) ====== [2025-08-25T21:32:05.576Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-25T21:32:05.576Z] GC before operation: completed in 198.488 ms, heap usage 98.966 MB -> 69.100 MB. [2025-08-25T21:32:15.289Z] ====== als (apache-spark) [default], iteration 25 completed (10145.578 ms) ====== [2025-08-25T21:32:15.289Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-25T21:32:16.248Z] GC before operation: completed in 219.240 ms, heap usage 411.103 MB -> 69.561 MB. [2025-08-25T21:32:27.969Z] ====== als (apache-spark) [default], iteration 26 completed (10551.850 ms) ====== [2025-08-25T21:32:27.969Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-25T21:32:27.969Z] GC before operation: completed in 220.904 ms, heap usage 412.631 MB -> 69.807 MB. [2025-08-25T21:32:37.734Z] ====== als (apache-spark) [default], iteration 27 completed (10206.918 ms) ====== [2025-08-25T21:32:37.734Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-25T21:32:37.734Z] GC before operation: completed in 206.213 ms, heap usage 222.260 MB -> 69.941 MB. [2025-08-25T21:32:48.447Z] ====== als (apache-spark) [default], iteration 28 completed (10278.261 ms) ====== [2025-08-25T21:32:48.448Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-25T21:32:48.448Z] GC before operation: completed in 211.238 ms, heap usage 217.848 MB -> 70.189 MB. [2025-08-25T21:32:59.930Z] ====== als (apache-spark) [default], iteration 29 completed (9768.659 ms) ====== [2025-08-25T21:32:59.930Z] ----------------------------------- [2025-08-25T21:32:59.930Z] renaissance-als_0_PASSED [2025-08-25T21:32:59.930Z] ----------------------------------- [2025-08-25T21:32:59.930Z] [2025-08-25T21:32:59.930Z] TEST TEARDOWN: [2025-08-25T21:32:59.930Z] Nothing to be done for teardown. [2025-08-25T21:32:59.930Z] renaissance-als_0 Finish Time: Mon Aug 25 21:32:59 2025 Epoch Time (ms): 1756157579122