[2026-03-05T05:50:15.799Z] Running test renaissance-als_0 ... [2026-03-05T05:50:15.799Z] =============================================== [2026-03-05T05:50:16.136Z] renaissance-als_0 Start Time: Thu Mar 5 05:50:15 2026 Epoch Time (ms): 1772689815707 [2026-03-05T05:50:16.136Z] variation: NoOptions [2026-03-05T05:50:16.136Z] JVM_OPTIONS: [2026-03-05T05:50:16.136Z] { \ [2026-03-05T05:50:16.136Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T05:50:16.136Z] echo "Nothing to be done for setup."; \ [2026-03-05T05:50:16.136Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17726895134936\\renaissance-als_0"; \ [2026-03-05T05:50:16.136Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17726895134936\\renaissance-als_0"; \ [2026-03-05T05:50:16.136Z] echo ""; echo "TESTING:"; \ [2026-03-05T05:50:16.136Z] "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_17726895134936\\renaissance-als_0"\\als.json" als; \ [2026-03-05T05:50:16.136Z] 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_17726895134936\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T05:50:16.136Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T05:50:16.136Z] echo "Nothing to be done for teardown."; \ [2026-03-05T05:50:16.136Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17726895134936\\TestTargetResult"; [2026-03-05T05:50:16.136Z] [2026-03-05T05:50:16.136Z] TEST SETUP: [2026-03-05T05:50:16.136Z] Nothing to be done for setup. [2026-03-05T05:50:16.136Z] [2026-03-05T05:50:16.136Z] TESTING: [2026-03-05T05:50:35.456Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-03-05T05:50:40.567Z] WARNING: An illegal reflective access operation has occurred [2026-03-05T05:50:40.567Z] 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_17726895134936/renaissance-als_0/harness-055017-6614976101326983005/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-03-05T05:50:40.567Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-03-05T05:50:40.567Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-03-05T05:50:40.567Z] WARNING: All illegal access operations will be denied in a future release [2026-03-05T05:50:41.887Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-05T05:50:42.249Z] GC before operation: completed in 174.622 ms, heap usage 350.500 MB -> 34.644 MB. [2026-03-05T05:51:43.136Z] ====== als (apache-spark) [default], iteration 0 completed (58601.905 ms) ====== [2026-03-05T05:51:43.136Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-05T05:51:43.136Z] GC before operation: completed in 245.024 ms, heap usage 154.607 MB -> 60.316 MB. [2026-03-05T05:52:19.452Z] ====== als (apache-spark) [default], iteration 1 completed (35723.181 ms) ====== [2026-03-05T05:52:19.452Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-05T05:52:19.452Z] GC before operation: completed in 203.715 ms, heap usage 364.954 MB -> 61.403 MB. [2026-03-05T05:52:54.990Z] ====== als (apache-spark) [default], iteration 2 completed (33700.847 ms) ====== [2026-03-05T05:52:54.990Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-05T05:52:54.990Z] GC before operation: completed in 171.619 ms, heap usage 132.049 MB -> 61.263 MB. [2026-03-05T05:53:29.599Z] ====== als (apache-spark) [default], iteration 3 completed (30993.848 ms) ====== [2026-03-05T05:53:29.599Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-05T05:53:29.599Z] GC before operation: completed in 255.037 ms, heap usage 419.562 MB -> 62.479 MB. [2026-03-05T05:53:58.161Z] ====== als (apache-spark) [default], iteration 4 completed (32743.346 ms) ====== [2026-03-05T05:53:58.161Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-05T05:53:58.161Z] GC before operation: completed in 201.937 ms, heap usage 253.962 MB -> 62.944 MB. [2026-03-05T05:54:32.511Z] ====== als (apache-spark) [default], iteration 5 completed (31472.192 ms) ====== [2026-03-05T05:54:32.511Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-05T05:54:32.511Z] GC before operation: completed in 189.510 ms, heap usage 242.598 MB -> 63.425 MB. [2026-03-05T05:55:07.227Z] ====== als (apache-spark) [default], iteration 6 completed (30846.785 ms) ====== [2026-03-05T05:55:07.227Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-05T05:55:07.227Z] GC before operation: completed in 214.603 ms, heap usage 125.249 MB -> 64.274 MB. [2026-03-05T05:55:36.689Z] ====== als (apache-spark) [default], iteration 7 completed (31835.629 ms) ====== [2026-03-05T05:55:36.690Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-05T05:55:36.690Z] GC before operation: completed in 200.245 ms, heap usage 149.278 MB -> 64.474 MB. [2026-03-05T05:56:12.572Z] ====== als (apache-spark) [default], iteration 8 completed (33064.365 ms) ====== [2026-03-05T05:56:12.572Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-05T05:56:12.572Z] GC before operation: completed in 183.260 ms, heap usage 108.023 MB -> 64.520 MB. [2026-03-05T05:56:41.620Z] ====== als (apache-spark) [default], iteration 9 completed (31754.844 ms) ====== [2026-03-05T05:56:41.620Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-05T05:56:41.620Z] GC before operation: completed in 197.352 ms, heap usage 178.248 MB -> 65.176 MB. [2026-03-05T05:57:16.887Z] ====== als (apache-spark) [default], iteration 10 completed (30007.313 ms) ====== [2026-03-05T05:57:16.887Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-05T05:57:16.887Z] GC before operation: completed in 297.378 ms, heap usage 220.318 MB -> 65.570 MB. [2026-03-05T05:57:45.809Z] ====== als (apache-spark) [default], iteration 11 completed (31685.377 ms) ====== [2026-03-05T05:57:45.809Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-05T05:57:45.809Z] GC before operation: completed in 193.890 ms, heap usage 222.320 MB -> 65.799 MB. [2026-03-05T05:58:20.397Z] ====== als (apache-spark) [default], iteration 12 completed (32503.429 ms) ====== [2026-03-05T05:58:20.397Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-05T05:58:20.397Z] GC before operation: completed in 211.569 ms, heap usage 234.322 MB -> 66.048 MB. [2026-03-05T05:58:54.347Z] ====== als (apache-spark) [default], iteration 13 completed (32036.007 ms) ====== [2026-03-05T05:58:54.347Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-05T05:58:54.347Z] GC before operation: completed in 198.280 ms, heap usage 260.992 MB -> 66.291 MB. [2026-03-05T05:59:22.400Z] ====== als (apache-spark) [default], iteration 14 completed (31338.096 ms) ====== [2026-03-05T05:59:22.400Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-05T05:59:22.400Z] GC before operation: completed in 218.731 ms, heap usage 193.241 MB -> 66.415 MB. [2026-03-05T05:59:56.000Z] ====== als (apache-spark) [default], iteration 15 completed (31192.002 ms) ====== [2026-03-05T05:59:56.000Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-05T05:59:56.000Z] GC before operation: completed in 193.378 ms, heap usage 214.899 MB -> 66.798 MB. [2026-03-05T06:00:30.281Z] ====== als (apache-spark) [default], iteration 16 completed (31035.790 ms) ====== [2026-03-05T06:00:30.281Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-05T06:00:30.281Z] GC before operation: completed in 263.936 ms, heap usage 108.003 MB -> 66.463 MB. [2026-03-05T06:00:58.985Z] ====== als (apache-spark) [default], iteration 17 completed (31969.728 ms) ====== [2026-03-05T06:00:58.985Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-05T06:00:58.985Z] GC before operation: completed in 230.708 ms, heap usage 196.423 MB -> 67.193 MB. [2026-03-05T06:01:34.174Z] ====== als (apache-spark) [default], iteration 18 completed (31121.844 ms) ====== [2026-03-05T06:01:34.174Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-05T06:01:34.174Z] GC before operation: completed in 210.248 ms, heap usage 231.325 MB -> 67.557 MB. [2026-03-05T06:02:07.934Z] ====== als (apache-spark) [default], iteration 19 completed (32291.766 ms) ====== [2026-03-05T06:02:07.934Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-05T06:02:07.934Z] GC before operation: completed in 214.874 ms, heap usage 171.951 MB -> 67.802 MB. [2026-03-05T06:02:36.306Z] ====== als (apache-spark) [default], iteration 20 completed (32337.995 ms) ====== [2026-03-05T06:02:36.306Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-05T06:02:36.306Z] GC before operation: completed in 238.988 ms, heap usage 118.717 MB -> 67.492 MB. [2026-03-05T06:03:12.896Z] ====== als (apache-spark) [default], iteration 21 completed (31869.413 ms) ====== [2026-03-05T06:03:12.896Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-05T06:03:12.896Z] GC before operation: completed in 218.249 ms, heap usage 180.449 MB -> 68.198 MB. [2026-03-05T06:03:41.975Z] ====== als (apache-spark) [default], iteration 22 completed (31622.189 ms) ====== [2026-03-05T06:03:41.975Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-05T06:03:41.975Z] GC before operation: completed in 195.382 ms, heap usage 160.306 MB -> 68.439 MB. [2026-03-05T06:04:16.883Z] ====== als (apache-spark) [default], iteration 23 completed (32599.962 ms) ====== [2026-03-05T06:04:16.883Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-05T06:04:16.883Z] GC before operation: completed in 234.931 ms, heap usage 180.334 MB -> 68.715 MB. [2026-03-05T06:04:51.521Z] ====== als (apache-spark) [default], iteration 24 completed (31526.735 ms) ====== [2026-03-05T06:04:51.521Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-05T06:04:51.521Z] GC before operation: completed in 206.226 ms, heap usage 146.733 MB -> 68.500 MB. [2026-03-05T06:05:20.640Z] ====== als (apache-spark) [default], iteration 25 completed (31709.859 ms) ====== [2026-03-05T06:05:20.640Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-05T06:05:20.640Z] GC before operation: completed in 234.048 ms, heap usage 274.988 MB -> 69.335 MB. [2026-03-05T06:05:55.257Z] ====== als (apache-spark) [default], iteration 26 completed (32563.690 ms) ====== [2026-03-05T06:05:55.257Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-05T06:05:55.257Z] GC before operation: completed in 252.528 ms, heap usage 147.835 MB -> 69.000 MB. [2026-03-05T06:06:30.018Z] ====== als (apache-spark) [default], iteration 27 completed (32213.930 ms) ====== [2026-03-05T06:06:30.018Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-05T06:06:30.018Z] GC before operation: completed in 229.103 ms, heap usage 267.217 MB -> 69.821 MB. [2026-03-05T06:06:58.903Z] ====== als (apache-spark) [default], iteration 28 completed (31720.255 ms) ====== [2026-03-05T06:06:58.903Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-05T06:06:58.903Z] GC before operation: completed in 205.997 ms, heap usage 213.622 MB -> 69.600 MB. [2026-03-05T06:07:28.484Z] ====== als (apache-spark) [default], iteration 29 completed (31012.727 ms) ====== [2026-03-05T06:07:29.186Z] ----------------------------------- [2026-03-05T06:07:29.186Z] renaissance-als_0_PASSED [2026-03-05T06:07:29.186Z] ----------------------------------- [2026-03-05T06:07:29.186Z] [2026-03-05T06:07:29.186Z] TEST TEARDOWN: [2026-03-05T06:07:29.186Z] Nothing to be done for teardown. [2026-03-05T06:07:29.505Z] renaissance-als_0 Finish Time: Thu Mar 5 06:07:29 2026 Epoch Time (ms): 1772690849265