[2025-12-11T00:28:23.478Z] Running test renaissance-als_0 ... [2025-12-11T00:28:23.478Z] =============================================== [2025-12-11T00:28:23.794Z] renaissance-als_0 Start Time: Thu Dec 11 00:28:23 2025 Epoch Time (ms): 1765412903500 [2025-12-11T00:28:23.794Z] variation: NoOptions [2025-12-11T00:28:23.795Z] JVM_OPTIONS: [2025-12-11T00:28:23.795Z] { \ [2025-12-11T00:28:23.795Z] echo ""; echo "TEST SETUP:"; \ [2025-12-11T00:28:23.795Z] echo "Nothing to be done for setup."; \ [2025-12-11T00:28:23.795Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\renaissance-als_0"; \ [2025-12-11T00:28:23.795Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\renaissance-als_0"; \ [2025-12-11T00:28:23.795Z] echo ""; echo "TESTING:"; \ [2025-12-11T00:28:23.795Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\renaissance-als_0"\\als.json" als; \ [2025-12-11T00:28:23.795Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-11T00:28:23.795Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-11T00:28:23.795Z] echo "Nothing to be done for teardown."; \ [2025-12-11T00:28:23.795Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17654126173741\\TestTargetResult"; [2025-12-11T00:28:24.136Z] [2025-12-11T00:28:24.136Z] TEST SETUP: [2025-12-11T00:28:24.136Z] Nothing to be done for setup. [2025-12-11T00:28:24.136Z] [2025-12-11T00:28:24.136Z] TESTING: [2025-12-11T00:28:43.318Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-11T00:28:45.735Z] WARNING: An illegal reflective access operation has occurred [2025-12-11T00:28:45.735Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17654126173741/renaissance-als_0/harness-002824-16393441680724438989/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-12-11T00:28:45.735Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-12-11T00:28:45.735Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-12-11T00:28:45.735Z] WARNING: All illegal access operations will be denied in a future release [2025-12-11T00:28:47.451Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-11T00:28:47.451Z] GC before operation: completed in 135.956 ms, heap usage 285.502 MB -> 34.636 MB. [2025-12-11T00:29:46.333Z] ====== als (apache-spark) [default], iteration 0 completed (56540.289 ms) ====== [2025-12-11T00:29:46.333Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-11T00:29:46.333Z] GC before operation: completed in 200.866 ms, heap usage 123.215 MB -> 60.036 MB. [2025-12-11T00:30:21.620Z] ====== als (apache-spark) [default], iteration 1 completed (35279.654 ms) ====== [2025-12-11T00:30:21.620Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-11T00:30:21.620Z] GC before operation: completed in 215.928 ms, heap usage 242.749 MB -> 61.282 MB. [2025-12-11T00:30:55.876Z] ====== als (apache-spark) [default], iteration 2 completed (31704.365 ms) ====== [2025-12-11T00:30:55.876Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-11T00:30:55.876Z] GC before operation: completed in 197.694 ms, heap usage 246.622 MB -> 61.832 MB. [2025-12-11T00:31:30.749Z] ====== als (apache-spark) [default], iteration 3 completed (31189.057 ms) ====== [2025-12-11T00:31:30.749Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-11T00:31:30.749Z] GC before operation: completed in 234.768 ms, heap usage 227.599 MB -> 62.764 MB. [2025-12-11T00:31:59.375Z] ====== als (apache-spark) [default], iteration 4 completed (30360.627 ms) ====== [2025-12-11T00:31:59.375Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-11T00:31:59.375Z] GC before operation: completed in 194.949 ms, heap usage 144.439 MB -> 62.309 MB. [2025-12-11T00:32:28.284Z] ====== als (apache-spark) [default], iteration 5 completed (30584.638 ms) ====== [2025-12-11T00:32:28.284Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-11T00:32:28.284Z] GC before operation: completed in 215.498 ms, heap usage 137.222 MB -> 62.963 MB. [2025-12-11T00:33:03.241Z] ====== als (apache-spark) [default], iteration 6 completed (31611.459 ms) ====== [2025-12-11T00:33:03.241Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-11T00:33:03.241Z] GC before operation: completed in 207.673 ms, heap usage 240.295 MB -> 64.152 MB. [2025-12-11T00:33:32.293Z] ====== als (apache-spark) [default], iteration 7 completed (30719.706 ms) ====== [2025-12-11T00:33:32.293Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-11T00:33:32.293Z] GC before operation: completed in 178.070 ms, heap usage 224.310 MB -> 64.492 MB. [2025-12-11T00:34:07.776Z] ====== als (apache-spark) [default], iteration 8 completed (30556.564 ms) ====== [2025-12-11T00:34:07.776Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-11T00:34:07.776Z] GC before operation: completed in 205.316 ms, heap usage 97.875 MB -> 64.544 MB. [2025-12-11T00:34:37.141Z] ====== als (apache-spark) [default], iteration 9 completed (30812.254 ms) ====== [2025-12-11T00:34:37.141Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-11T00:34:37.141Z] GC before operation: completed in 184.789 ms, heap usage 259.097 MB -> 65.285 MB. [2025-12-11T00:35:05.384Z] ====== als (apache-spark) [default], iteration 10 completed (30673.485 ms) ====== [2025-12-11T00:35:05.384Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-11T00:35:05.832Z] GC before operation: completed in 191.348 ms, heap usage 214.091 MB -> 65.566 MB. [2025-12-11T00:35:40.435Z] ====== als (apache-spark) [default], iteration 11 completed (30352.622 ms) ====== [2025-12-11T00:35:40.435Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-11T00:35:40.435Z] GC before operation: completed in 205.341 ms, heap usage 305.895 MB -> 65.905 MB. [2025-12-11T00:36:09.606Z] ====== als (apache-spark) [default], iteration 12 completed (30721.146 ms) ====== [2025-12-11T00:36:09.606Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-11T00:36:09.606Z] GC before operation: completed in 203.488 ms, heap usage 242.418 MB -> 65.588 MB. [2025-12-11T00:36:39.379Z] ====== als (apache-spark) [default], iteration 13 completed (31076.982 ms) ====== [2025-12-11T00:36:39.379Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-11T00:36:39.379Z] GC before operation: completed in 189.181 ms, heap usage 114.019 MB -> 65.710 MB. [2025-12-11T00:37:14.753Z] ====== als (apache-spark) [default], iteration 14 completed (30487.393 ms) ====== [2025-12-11T00:37:14.753Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-11T00:37:14.753Z] GC before operation: completed in 194.971 ms, heap usage 143.972 MB -> 65.953 MB. [2025-12-11T00:37:43.041Z] ====== als (apache-spark) [default], iteration 15 completed (29964.820 ms) ====== [2025-12-11T00:37:43.041Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-11T00:37:43.041Z] GC before operation: completed in 195.021 ms, heap usage 144.004 MB -> 66.788 MB. [2025-12-11T00:38:11.898Z] ====== als (apache-spark) [default], iteration 16 completed (30471.551 ms) ====== [2025-12-11T00:38:11.898Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-11T00:38:11.898Z] GC before operation: completed in 211.296 ms, heap usage 157.285 MB -> 66.921 MB. [2025-12-11T00:38:46.058Z] ====== als (apache-spark) [default], iteration 17 completed (29995.173 ms) ====== [2025-12-11T00:38:46.058Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-11T00:38:46.058Z] GC before operation: completed in 194.408 ms, heap usage 125.979 MB -> 66.702 MB. [2025-12-11T00:39:14.011Z] ====== als (apache-spark) [default], iteration 18 completed (31062.786 ms) ====== [2025-12-11T00:39:14.011Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-11T00:39:14.011Z] GC before operation: completed in 212.278 ms, heap usage 124.444 MB -> 66.948 MB. [2025-12-11T00:39:47.935Z] ====== als (apache-spark) [default], iteration 19 completed (30393.725 ms) ====== [2025-12-11T00:39:47.936Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-11T00:39:47.936Z] GC before operation: completed in 267.724 ms, heap usage 240.561 MB -> 68.031 MB. [2025-12-11T00:40:22.017Z] ====== als (apache-spark) [default], iteration 20 completed (30911.009 ms) ====== [2025-12-11T00:40:22.017Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-11T00:40:22.017Z] GC before operation: completed in 213.570 ms, heap usage 185.857 MB -> 67.428 MB. [2025-12-11T00:40:50.864Z] ====== als (apache-spark) [default], iteration 21 completed (30913.943 ms) ====== [2025-12-11T00:40:50.864Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-11T00:40:50.864Z] GC before operation: completed in 199.859 ms, heap usage 146.721 MB -> 68.313 MB. [2025-12-11T00:41:19.943Z] ====== als (apache-spark) [default], iteration 22 completed (30652.609 ms) ====== [2025-12-11T00:41:19.943Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-11T00:41:19.943Z] GC before operation: completed in 205.946 ms, heap usage 241.368 MB -> 68.488 MB. [2025-12-11T00:41:55.676Z] ====== als (apache-spark) [default], iteration 23 completed (31201.168 ms) ====== [2025-12-11T00:41:55.676Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-11T00:41:55.676Z] GC before operation: completed in 191.448 ms, heap usage 244.648 MB -> 68.770 MB. [2025-12-11T00:42:24.202Z] ====== als (apache-spark) [default], iteration 24 completed (30670.518 ms) ====== [2025-12-11T00:42:24.202Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-11T00:42:24.202Z] GC before operation: completed in 217.128 ms, heap usage 109.160 MB -> 68.442 MB. [2025-12-11T00:42:58.452Z] ====== als (apache-spark) [default], iteration 25 completed (30949.383 ms) ====== [2025-12-11T00:42:58.452Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-11T00:42:58.452Z] GC before operation: completed in 250.415 ms, heap usage 172.126 MB -> 69.274 MB. [2025-12-11T00:43:26.457Z] ====== als (apache-spark) [default], iteration 26 completed (30867.286 ms) ====== [2025-12-11T00:43:26.457Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-11T00:43:26.457Z] GC before operation: completed in 204.120 ms, heap usage 220.875 MB -> 69.918 MB. [2025-12-11T00:44:01.379Z] ====== als (apache-spark) [default], iteration 27 completed (30123.432 ms) ====== [2025-12-11T00:44:01.379Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-11T00:44:01.379Z] GC before operation: completed in 200.844 ms, heap usage 251.512 MB -> 69.757 MB. [2025-12-11T00:44:29.552Z] ====== als (apache-spark) [default], iteration 28 completed (31060.647 ms) ====== [2025-12-11T00:44:29.552Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-11T00:44:29.552Z] GC before operation: completed in 218.722 ms, heap usage 173.328 MB -> 69.419 MB. [2025-12-11T00:45:03.518Z] ====== als (apache-spark) [default], iteration 29 completed (30470.193 ms) ====== [2025-12-11T00:45:03.518Z] ----------------------------------- [2025-12-11T00:45:03.518Z] renaissance-als_0_PASSED [2025-12-11T00:45:03.518Z] ----------------------------------- [2025-12-11T00:45:03.518Z] [2025-12-11T00:45:03.518Z] TEST TEARDOWN: [2025-12-11T00:45:03.518Z] Nothing to be done for teardown. [2025-12-11T00:45:03.518Z] renaissance-als_0 Finish Time: Thu Dec 11 00:44:59 2025 Epoch Time (ms): 1765413899497