[2025-11-05T22:35:00.257Z] Running test renaissance-als_0 ... [2025-11-05T22:35:00.257Z] =============================================== [2025-11-05T22:35:00.257Z] renaissance-als_0 Start Time: Wed Nov 5 22:34:59 2025 Epoch Time (ms): 1762382099641 [2025-11-05T22:35:00.257Z] variation: NoOptions [2025-11-05T22:35:00.257Z] JVM_OPTIONS: [2025-11-05T22:35:00.257Z] { \ [2025-11-05T22:35:00.257Z] echo ""; echo "TEST SETUP:"; \ [2025-11-05T22:35:00.257Z] echo "Nothing to be done for setup."; \ [2025-11-05T22:35:00.257Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17623818715098/renaissance-als_0"; \ [2025-11-05T22:35:00.257Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17623818715098/renaissance-als_0"; \ [2025-11-05T22:35:00.257Z] echo ""; echo "TESTING:"; \ [2025-11-05T22:35:00.257Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17623818715098/renaissance-als_0"/als.json" als; \ [2025-11-05T22:35:00.257Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17623818715098/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-05T22:35:00.257Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-05T22:35:00.257Z] echo "Nothing to be done for teardown."; \ [2025-11-05T22:35:00.257Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17623818715098/TestTargetResult"; [2025-11-05T22:35:00.257Z] [2025-11-05T22:35:00.257Z] TEST SETUP: [2025-11-05T22:35:00.257Z] Nothing to be done for setup. [2025-11-05T22:35:00.257Z] [2025-11-05T22:35:00.257Z] TESTING: [2025-11-05T22:35:08.409Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-05T22:35:15.137Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-05T22:35:15.137Z] GC before operation: completed in 62.257 ms, heap usage 57.000 MB -> 35.245 MB. [2025-11-05T22:35:43.682Z] ====== als (apache-spark) [default], iteration 0 completed (26581.060 ms) ====== [2025-11-05T22:35:43.682Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-05T22:35:43.682Z] GC before operation: completed in 113.700 ms, heap usage 187.496 MB -> 61.926 MB. [2025-11-05T22:35:54.405Z] ====== als (apache-spark) [default], iteration 1 completed (10469.594 ms) ====== [2025-11-05T22:35:54.405Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-05T22:35:54.405Z] GC before operation: completed in 102.559 ms, heap usage 246.914 MB -> 62.993 MB. [2025-11-05T22:36:04.491Z] ====== als (apache-spark) [default], iteration 2 completed (9333.465 ms) ====== [2025-11-05T22:36:04.491Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-05T22:36:04.491Z] GC before operation: completed in 103.179 ms, heap usage 106.947 MB -> 63.439 MB. [2025-11-05T22:36:13.468Z] ====== als (apache-spark) [default], iteration 3 completed (8993.349 ms) ====== [2025-11-05T22:36:13.468Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-05T22:36:13.468Z] GC before operation: completed in 105.294 ms, heap usage 454.514 MB -> 64.502 MB. [2025-11-05T22:36:22.513Z] ====== als (apache-spark) [default], iteration 4 completed (8771.738 ms) ====== [2025-11-05T22:36:22.513Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-05T22:36:22.513Z] GC before operation: completed in 109.654 ms, heap usage 185.008 MB -> 64.582 MB. [2025-11-05T22:36:32.373Z] ====== als (apache-spark) [default], iteration 5 completed (8718.508 ms) ====== [2025-11-05T22:36:32.373Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-05T22:36:32.373Z] GC before operation: completed in 113.521 ms, heap usage 526.138 MB -> 65.743 MB. [2025-11-05T22:36:40.585Z] ====== als (apache-spark) [default], iteration 6 completed (8778.075 ms) ====== [2025-11-05T22:36:40.585Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-05T22:36:40.585Z] GC before operation: completed in 111.231 ms, heap usage 136.262 MB -> 65.777 MB. [2025-11-05T22:36:50.365Z] ====== als (apache-spark) [default], iteration 7 completed (8603.233 ms) ====== [2025-11-05T22:36:50.365Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-05T22:36:50.365Z] GC before operation: completed in 118.466 ms, heap usage 251.345 MB -> 66.425 MB. [2025-11-05T22:36:58.550Z] ====== als (apache-spark) [default], iteration 8 completed (8708.665 ms) ====== [2025-11-05T22:36:58.550Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-05T22:36:58.550Z] GC before operation: completed in 116.064 ms, heap usage 157.780 MB -> 66.822 MB. [2025-11-05T22:37:07.767Z] ====== als (apache-spark) [default], iteration 9 completed (8480.381 ms) ====== [2025-11-05T22:37:07.767Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-05T22:37:07.767Z] GC before operation: completed in 118.419 ms, heap usage 393.796 MB -> 67.361 MB. [2025-11-05T22:37:17.508Z] ====== als (apache-spark) [default], iteration 10 completed (8415.224 ms) ====== [2025-11-05T22:37:17.508Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-05T22:37:17.508Z] GC before operation: completed in 122.075 ms, heap usage 321.712 MB -> 67.623 MB. [2025-11-05T22:37:25.631Z] ====== als (apache-spark) [default], iteration 11 completed (8437.330 ms) ====== [2025-11-05T22:37:25.631Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-05T22:37:25.631Z] GC before operation: completed in 118.680 ms, heap usage 229.281 MB -> 67.702 MB. [2025-11-05T22:37:33.772Z] ====== als (apache-spark) [default], iteration 12 completed (8462.458 ms) ====== [2025-11-05T22:37:33.772Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-05T22:37:34.725Z] GC before operation: completed in 129.822 ms, heap usage 386.820 MB -> 68.123 MB. [2025-11-05T22:37:42.871Z] ====== als (apache-spark) [default], iteration 13 completed (8399.780 ms) ====== [2025-11-05T22:37:42.871Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-05T22:37:42.871Z] GC before operation: completed in 122.983 ms, heap usage 211.402 MB -> 68.237 MB. [2025-11-05T22:37:52.870Z] ====== als (apache-spark) [default], iteration 14 completed (8388.380 ms) ====== [2025-11-05T22:37:52.870Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-05T22:37:52.870Z] GC before operation: completed in 123.211 ms, heap usage 115.389 MB -> 68.395 MB. [2025-11-05T22:38:01.238Z] ====== als (apache-spark) [default], iteration 15 completed (8298.688 ms) ====== [2025-11-05T22:38:01.238Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-05T22:38:01.238Z] GC before operation: completed in 128.076 ms, heap usage 258.542 MB -> 68.767 MB. [2025-11-05T22:38:15.820Z] ====== als (apache-spark) [default], iteration 16 completed (8391.975 ms) ====== [2025-11-05T22:38:15.820Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-05T22:38:15.820Z] GC before operation: completed in 119.264 ms, heap usage 231.234 MB -> 69.047 MB. [2025-11-05T22:38:18.266Z] ====== als (apache-spark) [default], iteration 17 completed (8387.343 ms) ====== [2025-11-05T22:38:18.266Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-05T22:38:18.266Z] GC before operation: completed in 107.865 ms, heap usage 219.369 MB -> 69.267 MB. [2025-11-05T22:38:27.175Z] ====== als (apache-spark) [default], iteration 18 completed (8380.633 ms) ====== [2025-11-05T22:38:27.175Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-05T22:38:27.175Z] GC before operation: completed in 110.910 ms, heap usage 594.056 MB -> 69.977 MB. [2025-11-05T22:38:35.610Z] ====== als (apache-spark) [default], iteration 19 completed (8284.696 ms) ====== [2025-11-05T22:38:35.610Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-05T22:38:35.610Z] GC before operation: completed in 112.480 ms, heap usage 515.091 MB -> 70.157 MB. [2025-11-05T22:38:45.428Z] ====== als (apache-spark) [default], iteration 20 completed (8514.595 ms) ====== [2025-11-05T22:38:45.428Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-05T22:38:45.428Z] GC before operation: completed in 110.383 ms, heap usage 261.352 MB -> 70.052 MB. [2025-11-05T22:38:53.618Z] ====== als (apache-spark) [default], iteration 21 completed (8344.883 ms) ====== [2025-11-05T22:38:53.618Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-05T22:38:53.618Z] GC before operation: completed in 112.942 ms, heap usage 256.406 MB -> 70.345 MB. [2025-11-05T22:39:01.895Z] ====== als (apache-spark) [default], iteration 22 completed (8172.829 ms) ====== [2025-11-05T22:39:01.895Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-05T22:39:01.895Z] GC before operation: completed in 115.096 ms, heap usage 151.020 MB -> 70.547 MB. [2025-11-05T22:39:11.792Z] ====== als (apache-spark) [default], iteration 23 completed (8629.542 ms) ====== [2025-11-05T22:39:11.793Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-05T22:39:11.793Z] GC before operation: completed in 114.603 ms, heap usage 436.232 MB -> 71.212 MB. [2025-11-05T22:39:19.946Z] ====== als (apache-spark) [default], iteration 24 completed (8236.475 ms) ====== [2025-11-05T22:39:19.946Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-05T22:39:19.946Z] GC before operation: completed in 112.864 ms, heap usage 498.415 MB -> 71.432 MB. [2025-11-05T22:39:29.004Z] ====== als (apache-spark) [default], iteration 25 completed (8378.669 ms) ====== [2025-11-05T22:39:29.004Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-05T22:39:29.004Z] GC before operation: completed in 116.449 ms, heap usage 330.415 MB -> 71.512 MB. [2025-11-05T22:39:37.273Z] ====== als (apache-spark) [default], iteration 26 completed (8312.915 ms) ====== [2025-11-05T22:39:37.273Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-05T22:39:37.273Z] GC before operation: completed in 125.959 ms, heap usage 214.391 MB -> 71.534 MB. [2025-11-05T22:39:46.091Z] ====== als (apache-spark) [default], iteration 27 completed (8142.411 ms) ====== [2025-11-05T22:39:46.091Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-05T22:39:46.091Z] GC before operation: completed in 111.523 ms, heap usage 201.842 MB -> 71.743 MB. [2025-11-05T22:39:54.732Z] ====== als (apache-spark) [default], iteration 28 completed (8704.474 ms) ====== [2025-11-05T22:39:54.732Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-05T22:39:54.732Z] GC before operation: completed in 113.160 ms, heap usage 314.314 MB -> 71.698 MB. [2025-11-05T22:40:03.117Z] ====== als (apache-spark) [default], iteration 29 completed (8113.863 ms) ====== [2025-11-05T22:40:04.124Z] ----------------------------------- [2025-11-05T22:40:04.124Z] renaissance-als_0_PASSED [2025-11-05T22:40:04.124Z] ----------------------------------- [2025-11-05T22:40:04.124Z] [2025-11-05T22:40:04.124Z] TEST TEARDOWN: [2025-11-05T22:40:04.124Z] Nothing to be done for teardown. [2025-11-05T22:40:04.124Z] renaissance-als_0 Finish Time: Wed Nov 5 22:40:03 2025 Epoch Time (ms): 1762382403316