[2025-10-02T16:41:44.741Z] Running test renaissance-als_0 ... [2025-10-02T16:41:44.741Z] =============================================== [2025-10-02T16:41:44.741Z] renaissance-als_0 Start Time: Thu Oct 2 16:41:43 2025 Epoch Time (ms): 1759423303622 [2025-10-02T16:41:44.741Z] variation: NoOptions [2025-10-02T16:41:44.741Z] JVM_OPTIONS: [2025-10-02T16:41:44.741Z] { \ [2025-10-02T16:41:44.741Z] echo ""; echo "TEST SETUP:"; \ [2025-10-02T16:41:44.741Z] echo "Nothing to be done for setup."; \ [2025-10-02T16:41:44.741Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17594231767248/renaissance-als_0"; \ [2025-10-02T16:41:44.741Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17594231767248/renaissance-als_0"; \ [2025-10-02T16:41:44.741Z] echo ""; echo "TESTING:"; \ [2025-10-02T16:41:44.741Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17594231767248/renaissance-als_0"/als.json" als; \ [2025-10-02T16:41:44.741Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17594231767248/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-02T16:41:44.741Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-02T16:41:44.741Z] echo "Nothing to be done for teardown."; \ [2025-10-02T16:41:44.741Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17594231767248/TestTargetResult"; [2025-10-02T16:41:44.741Z] [2025-10-02T16:41:44.741Z] TEST SETUP: [2025-10-02T16:41:44.741Z] Nothing to be done for setup. [2025-10-02T16:41:44.741Z] [2025-10-02T16:41:44.741Z] TESTING: [2025-10-02T16:41:50.173Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-02T16:41:54.363Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-02T16:41:54.363Z] GC before operation: completed in 47.295 ms, heap usage 53.500 MB -> 35.183 MB. [2025-10-02T16:42:10.273Z] ====== als (apache-spark) [default], iteration 0 completed (15014.104 ms) ====== [2025-10-02T16:42:10.273Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-02T16:42:10.273Z] GC before operation: completed in 101.149 ms, heap usage 190.533 MB -> 61.991 MB. [2025-10-02T16:42:15.715Z] ====== als (apache-spark) [default], iteration 1 completed (5837.281 ms) ====== [2025-10-02T16:42:15.715Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-02T16:42:15.715Z] GC before operation: completed in 98.473 ms, heap usage 568.543 MB -> 63.371 MB. [2025-10-02T16:42:21.162Z] ====== als (apache-spark) [default], iteration 2 completed (5554.749 ms) ====== [2025-10-02T16:42:21.162Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-02T16:42:21.162Z] GC before operation: completed in 114.404 ms, heap usage 505.248 MB -> 63.885 MB. [2025-10-02T16:42:26.602Z] ====== als (apache-spark) [default], iteration 3 completed (5304.300 ms) ====== [2025-10-02T16:42:26.602Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-02T16:42:27.577Z] GC before operation: completed in 116.977 ms, heap usage 508.184 MB -> 64.467 MB. [2025-10-02T16:42:33.012Z] ====== als (apache-spark) [default], iteration 4 completed (5164.269 ms) ====== [2025-10-02T16:42:33.012Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-02T16:42:33.012Z] GC before operation: completed in 121.469 ms, heap usage 112.177 MB -> 64.491 MB. [2025-10-02T16:42:38.466Z] ====== als (apache-spark) [default], iteration 5 completed (5004.750 ms) ====== [2025-10-02T16:42:38.466Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-02T16:42:38.466Z] GC before operation: completed in 129.112 ms, heap usage 280.461 MB -> 65.345 MB. [2025-10-02T16:42:42.668Z] ====== als (apache-spark) [default], iteration 6 completed (5013.025 ms) ====== [2025-10-02T16:42:42.668Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-02T16:42:43.634Z] GC before operation: completed in 121.255 ms, heap usage 475.037 MB -> 66.137 MB. [2025-10-02T16:42:47.839Z] ====== als (apache-spark) [default], iteration 7 completed (4899.717 ms) ====== [2025-10-02T16:42:47.839Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-02T16:42:48.805Z] GC before operation: completed in 125.209 ms, heap usage 486.796 MB -> 66.752 MB. [2025-10-02T16:42:53.489Z] ====== als (apache-spark) [default], iteration 8 completed (4961.022 ms) ====== [2025-10-02T16:42:53.489Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-02T16:42:53.489Z] GC before operation: completed in 125.651 ms, heap usage 198.584 MB -> 66.789 MB. [2025-10-02T16:42:58.930Z] ====== als (apache-spark) [default], iteration 9 completed (4896.013 ms) ====== [2025-10-02T16:42:58.930Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-02T16:42:58.930Z] GC before operation: completed in 123.823 ms, heap usage 596.107 MB -> 67.537 MB. [2025-10-02T16:43:04.365Z] ====== als (apache-spark) [default], iteration 10 completed (4906.477 ms) ====== [2025-10-02T16:43:04.365Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-02T16:43:04.365Z] GC before operation: completed in 126.863 ms, heap usage 153.398 MB -> 67.288 MB. [2025-10-02T16:43:09.802Z] ====== als (apache-spark) [default], iteration 11 completed (4798.582 ms) ====== [2025-10-02T16:43:09.802Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-02T16:43:09.802Z] GC before operation: completed in 145.258 ms, heap usage 102.968 MB -> 67.066 MB. [2025-10-02T16:43:14.021Z] ====== als (apache-spark) [default], iteration 12 completed (4951.487 ms) ====== [2025-10-02T16:43:14.021Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-02T16:43:14.986Z] GC before operation: completed in 130.726 ms, heap usage 211.684 MB -> 67.859 MB. [2025-10-02T16:43:19.194Z] ====== als (apache-spark) [default], iteration 13 completed (4736.995 ms) ====== [2025-10-02T16:43:19.194Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-02T16:43:19.194Z] GC before operation: completed in 135.507 ms, heap usage 331.584 MB -> 68.280 MB. [2025-10-02T16:43:24.633Z] ====== als (apache-spark) [default], iteration 14 completed (4814.034 ms) ====== [2025-10-02T16:43:24.633Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-02T16:43:24.633Z] GC before operation: completed in 141.562 ms, heap usage 493.795 MB -> 68.788 MB. [2025-10-02T16:43:30.078Z] ====== als (apache-spark) [default], iteration 15 completed (4864.659 ms) ====== [2025-10-02T16:43:30.078Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-02T16:43:30.078Z] GC before operation: completed in 138.391 ms, heap usage 122.522 MB -> 68.643 MB. [2025-10-02T16:43:34.287Z] ====== als (apache-spark) [default], iteration 16 completed (4745.640 ms) ====== [2025-10-02T16:43:34.287Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-02T16:43:35.256Z] GC before operation: completed in 137.582 ms, heap usage 434.574 MB -> 69.381 MB. [2025-10-02T16:43:39.628Z] ====== als (apache-spark) [default], iteration 17 completed (4845.130 ms) ====== [2025-10-02T16:43:39.628Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-02T16:43:39.628Z] GC before operation: completed in 137.491 ms, heap usage 268.165 MB -> 69.270 MB. [2025-10-02T16:43:45.065Z] ====== als (apache-spark) [default], iteration 18 completed (4779.055 ms) ====== [2025-10-02T16:43:45.066Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-02T16:43:45.066Z] GC before operation: completed in 136.480 ms, heap usage 214.374 MB -> 69.438 MB. [2025-10-02T16:43:51.217Z] ====== als (apache-spark) [default], iteration 19 completed (4703.493 ms) ====== [2025-10-02T16:43:51.217Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-02T16:43:51.217Z] GC before operation: completed in 145.683 ms, heap usage 364.668 MB -> 69.848 MB. [2025-10-02T16:43:55.434Z] ====== als (apache-spark) [default], iteration 20 completed (4867.952 ms) ====== [2025-10-02T16:43:55.434Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-02T16:43:55.434Z] GC before operation: completed in 141.142 ms, heap usage 536.924 MB -> 70.481 MB. [2025-10-02T16:43:59.645Z] ====== als (apache-spark) [default], iteration 21 completed (4639.793 ms) ====== [2025-10-02T16:43:59.645Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-02T16:44:00.615Z] GC before operation: completed in 143.055 ms, heap usage 579.928 MB -> 70.770 MB. [2025-10-02T16:44:04.830Z] ====== als (apache-spark) [default], iteration 22 completed (4711.705 ms) ====== [2025-10-02T16:44:04.830Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-02T16:44:04.830Z] GC before operation: completed in 140.614 ms, heap usage 182.130 MB -> 70.408 MB. [2025-10-02T16:44:10.281Z] ====== als (apache-spark) [default], iteration 23 completed (4750.107 ms) ====== [2025-10-02T16:44:10.281Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-02T16:44:10.281Z] GC before operation: completed in 146.635 ms, heap usage 494.556 MB -> 71.219 MB. [2025-10-02T16:44:15.720Z] ====== als (apache-spark) [default], iteration 24 completed (4807.587 ms) ====== [2025-10-02T16:44:15.720Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-02T16:44:15.720Z] GC before operation: completed in 136.745 ms, heap usage 363.706 MB -> 71.169 MB. [2025-10-02T16:44:19.927Z] ====== als (apache-spark) [default], iteration 25 completed (4727.778 ms) ====== [2025-10-02T16:44:19.927Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-02T16:44:19.927Z] GC before operation: completed in 119.843 ms, heap usage 110.825 MB -> 71.248 MB. [2025-10-02T16:44:25.374Z] ====== als (apache-spark) [default], iteration 26 completed (4552.999 ms) ====== [2025-10-02T16:44:25.374Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-02T16:44:25.374Z] GC before operation: completed in 114.701 ms, heap usage 427.855 MB -> 71.712 MB. [2025-10-02T16:44:29.580Z] ====== als (apache-spark) [default], iteration 27 completed (4649.895 ms) ====== [2025-10-02T16:44:29.581Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-02T16:44:30.548Z] GC before operation: completed in 118.286 ms, heap usage 218.058 MB -> 71.760 MB. [2025-10-02T16:44:34.753Z] ====== als (apache-spark) [default], iteration 28 completed (4689.345 ms) ====== [2025-10-02T16:44:34.753Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-02T16:44:34.753Z] GC before operation: completed in 116.061 ms, heap usage 219.797 MB -> 71.577 MB. [2025-10-02T16:44:39.259Z] ====== als (apache-spark) [default], iteration 29 completed (4509.510 ms) ====== [2025-10-02T16:44:40.224Z] ----------------------------------- [2025-10-02T16:44:40.224Z] renaissance-als_0_PASSED [2025-10-02T16:44:40.224Z] ----------------------------------- [2025-10-02T16:44:40.224Z] [2025-10-02T16:44:40.224Z] TEST TEARDOWN: [2025-10-02T16:44:40.224Z] Nothing to be done for teardown. [2025-10-02T16:44:40.224Z] renaissance-als_0 Finish Time: Thu Oct 2 16:44:39 2025 Epoch Time (ms): 1759423479595