[2026-04-06T22:42:57.290Z] Running test renaissance-als_0 ... [2026-04-06T22:42:57.290Z] =============================================== [2026-04-06T22:42:57.290Z] renaissance-als_0 Start Time: Mon Apr 6 22:42:53 2026 Epoch Time (ms): 1775515373777 [2026-04-06T22:42:57.290Z] variation: NoOptions [2026-04-06T22:42:57.290Z] JVM_OPTIONS: [2026-04-06T22:42:57.290Z] { \ [2026-04-06T22:42:57.290Z] echo ""; echo "TEST SETUP:"; \ [2026-04-06T22:42:57.290Z] echo "Nothing to be done for setup."; \ [2026-04-06T22:42:57.290Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17755151433776\\renaissance-als_0"; \ [2026-04-06T22:42:57.290Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17755151433776\\renaissance-als_0"; \ [2026-04-06T22:42:57.290Z] echo ""; echo "TESTING:"; \ [2026-04-06T22:42:57.290Z] "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_17755151433776\\renaissance-als_0"\\als.json" als; \ [2026-04-06T22:42:57.290Z] 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_17755151433776\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-06T22:42:57.290Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-06T22:42:57.290Z] echo "Nothing to be done for teardown."; \ [2026-04-06T22:42:57.290Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17755151433776\\TestTargetResult"; [2026-04-06T22:42:57.290Z] [2026-04-06T22:42:57.290Z] TEST SETUP: [2026-04-06T22:42:57.290Z] Nothing to be done for setup. [2026-04-06T22:42:57.290Z] [2026-04-06T22:42:57.290Z] TESTING: [2026-04-06T22:43:06.578Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-04-06T22:43:09.551Z] WARNING: An illegal reflective access operation has occurred [2026-04-06T22:43:09.551Z] 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_17755151433776/renaissance-als_0/harness-224254-2350796525060094179/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-06T22:43:09.551Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-06T22:43:09.551Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-06T22:43:09.551Z] WARNING: All illegal access operations will be denied in a future release [2026-04-06T22:43:10.846Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-06T22:43:10.846Z] GC before operation: completed in 120.112 ms, heap usage 369.000 MB -> 35.190 MB. [2026-04-06T22:43:58.448Z] ====== als (apache-spark) [default], iteration 0 completed (42157.402 ms) ====== [2026-04-06T22:43:58.448Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-06T22:43:58.448Z] GC before operation: completed in 159.766 ms, heap usage 216.242 MB -> 60.486 MB. [2026-04-06T22:44:18.219Z] ====== als (apache-spark) [default], iteration 1 completed (23412.236 ms) ====== [2026-04-06T22:44:18.219Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-06T22:44:18.219Z] GC before operation: completed in 146.393 ms, heap usage 107.812 MB -> 60.713 MB. [2026-04-06T22:44:42.563Z] ====== als (apache-spark) [default], iteration 2 completed (22678.693 ms) ====== [2026-04-06T22:44:42.563Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-06T22:44:42.563Z] GC before operation: completed in 131.475 ms, heap usage 208.800 MB -> 61.839 MB. [2026-04-06T22:45:06.800Z] ====== als (apache-spark) [default], iteration 3 completed (22621.595 ms) ====== [2026-04-06T22:45:06.800Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-06T22:45:06.800Z] GC before operation: completed in 137.046 ms, heap usage 136.954 MB -> 62.256 MB. [2026-04-06T22:45:26.698Z] ====== als (apache-spark) [default], iteration 4 completed (21263.142 ms) ====== [2026-04-06T22:45:26.698Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-06T22:45:26.698Z] GC before operation: completed in 164.890 ms, heap usage 220.054 MB -> 62.427 MB. [2026-04-06T22:45:50.733Z] ====== als (apache-spark) [default], iteration 5 completed (21717.802 ms) ====== [2026-04-06T22:45:50.733Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-06T22:45:50.733Z] GC before operation: completed in 145.566 ms, heap usage 256.875 MB -> 63.423 MB. [2026-04-06T22:46:10.820Z] ====== als (apache-spark) [default], iteration 6 completed (21743.656 ms) ====== [2026-04-06T22:46:10.820Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-06T22:46:10.820Z] GC before operation: completed in 131.543 ms, heap usage 132.930 MB -> 63.965 MB. [2026-04-06T22:46:34.473Z] ====== als (apache-spark) [default], iteration 7 completed (20949.278 ms) ====== [2026-04-06T22:46:34.473Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-06T22:46:34.473Z] GC before operation: completed in 138.879 ms, heap usage 268.006 MB -> 64.496 MB. [2026-04-06T22:46:53.971Z] ====== als (apache-spark) [default], iteration 8 completed (20944.428 ms) ====== [2026-04-06T22:46:53.971Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-06T22:46:53.971Z] GC before operation: completed in 204.583 ms, heap usage 238.900 MB -> 64.563 MB. [2026-04-06T22:47:18.354Z] ====== als (apache-spark) [default], iteration 9 completed (21993.322 ms) ====== [2026-04-06T22:47:18.354Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-06T22:47:18.354Z] GC before operation: completed in 149.126 ms, heap usage 241.015 MB -> 65.299 MB. [2026-04-06T22:47:38.534Z] ====== als (apache-spark) [default], iteration 10 completed (22517.458 ms) ====== [2026-04-06T22:47:38.534Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-06T22:47:38.952Z] GC before operation: completed in 145.858 ms, heap usage 256.345 MB -> 65.570 MB. [2026-04-06T22:48:03.148Z] ====== als (apache-spark) [default], iteration 11 completed (21302.406 ms) ====== [2026-04-06T22:48:03.148Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-06T22:48:03.148Z] GC before operation: completed in 150.078 ms, heap usage 97.415 MB -> 65.228 MB. [2026-04-06T22:48:23.017Z] ====== als (apache-spark) [default], iteration 12 completed (21843.575 ms) ====== [2026-04-06T22:48:23.017Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-06T22:48:23.017Z] GC before operation: completed in 137.471 ms, heap usage 315.459 MB -> 66.160 MB. [2026-04-06T22:48:47.322Z] ====== als (apache-spark) [default], iteration 13 completed (21914.601 ms) ====== [2026-04-06T22:48:47.322Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-06T22:48:47.322Z] GC before operation: completed in 160.589 ms, heap usage 121.078 MB -> 65.716 MB. [2026-04-06T22:49:07.133Z] ====== als (apache-spark) [default], iteration 14 completed (21391.609 ms) ====== [2026-04-06T22:49:07.133Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-06T22:49:07.133Z] GC before operation: completed in 153.847 ms, heap usage 218.401 MB -> 66.540 MB. [2026-04-06T22:49:31.003Z] ====== als (apache-spark) [default], iteration 15 completed (21194.753 ms) ====== [2026-04-06T22:49:31.003Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-06T22:49:31.003Z] GC before operation: completed in 145.076 ms, heap usage 158.033 MB -> 66.259 MB. [2026-04-06T22:49:50.694Z] ====== als (apache-spark) [default], iteration 16 completed (21604.500 ms) ====== [2026-04-06T22:49:50.694Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-06T22:49:50.694Z] GC before operation: completed in 175.315 ms, heap usage 246.261 MB -> 67.062 MB. [2026-04-06T22:50:14.728Z] ====== als (apache-spark) [default], iteration 17 completed (21691.473 ms) ====== [2026-04-06T22:50:14.728Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-06T22:50:14.728Z] GC before operation: completed in 137.265 ms, heap usage 119.263 MB -> 66.729 MB. [2026-04-06T22:50:34.439Z] ====== als (apache-spark) [default], iteration 18 completed (21044.974 ms) ====== [2026-04-06T22:50:34.439Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-06T22:50:34.439Z] GC before operation: completed in 143.371 ms, heap usage 236.314 MB -> 67.550 MB. [2026-04-06T22:50:58.540Z] ====== als (apache-spark) [default], iteration 19 completed (21698.304 ms) ====== [2026-04-06T22:50:58.540Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-06T22:50:58.540Z] GC before operation: completed in 137.325 ms, heap usage 130.996 MB -> 67.218 MB. [2026-04-06T22:51:18.918Z] ====== als (apache-spark) [default], iteration 20 completed (22426.802 ms) ====== [2026-04-06T22:51:18.918Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-06T22:51:18.918Z] GC before operation: completed in 144.699 ms, heap usage 176.822 MB -> 67.950 MB. [2026-04-06T22:51:41.238Z] ====== als (apache-spark) [default], iteration 21 completed (21775.710 ms) ====== [2026-04-06T22:51:41.238Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-06T22:51:41.238Z] GC before operation: completed in 138.575 ms, heap usage 89.657 MB -> 67.738 MB. [2026-04-06T22:52:05.223Z] ====== als (apache-spark) [default], iteration 22 completed (21317.909 ms) ====== [2026-04-06T22:52:05.223Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-06T22:52:05.223Z] GC before operation: completed in 140.461 ms, heap usage 264.130 MB -> 68.095 MB. [2026-04-06T22:52:25.122Z] ====== als (apache-spark) [default], iteration 23 completed (21535.680 ms) ====== [2026-04-06T22:52:25.122Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-06T22:52:25.122Z] GC before operation: completed in 142.258 ms, heap usage 112.069 MB -> 68.713 MB. [2026-04-06T22:52:49.383Z] ====== als (apache-spark) [default], iteration 24 completed (21559.467 ms) ====== [2026-04-06T22:52:49.383Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-06T22:52:49.383Z] GC before operation: completed in 139.067 ms, heap usage 155.280 MB -> 68.968 MB. [2026-04-06T22:53:09.997Z] ====== als (apache-spark) [default], iteration 25 completed (22404.230 ms) ====== [2026-04-06T22:53:09.997Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-06T22:53:09.997Z] GC before operation: completed in 155.872 ms, heap usage 220.087 MB -> 68.868 MB. [2026-04-06T22:53:34.451Z] ====== als (apache-spark) [default], iteration 26 completed (22518.385 ms) ====== [2026-04-06T22:53:34.451Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-06T22:53:34.451Z] GC before operation: completed in 144.081 ms, heap usage 237.057 MB -> 69.577 MB. [2026-04-06T22:53:58.386Z] ====== als (apache-spark) [default], iteration 27 completed (22254.165 ms) ====== [2026-04-06T22:53:58.386Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-06T22:53:58.386Z] GC before operation: completed in 156.378 ms, heap usage 98.009 MB -> 69.241 MB. [2026-04-06T22:54:18.023Z] ====== als (apache-spark) [default], iteration 28 completed (21577.225 ms) ====== [2026-04-06T22:54:18.023Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-06T22:54:18.023Z] GC before operation: completed in 159.330 ms, heap usage 238.529 MB -> 69.599 MB. [2026-04-06T22:54:42.047Z] ====== als (apache-spark) [default], iteration 29 completed (21290.316 ms) ====== [2026-04-06T22:54:42.047Z] ----------------------------------- [2026-04-06T22:54:42.047Z] renaissance-als_0_PASSED [2026-04-06T22:54:42.047Z] ----------------------------------- [2026-04-06T22:54:42.047Z] [2026-04-06T22:54:42.047Z] TEST TEARDOWN: [2026-04-06T22:54:42.047Z] Nothing to be done for teardown. [2026-04-06T22:54:42.047Z] renaissance-als_0 Finish Time: Mon Apr 6 22:54:39 2026 Epoch Time (ms): 1775516079116