[2025-10-04T02:58:58.686Z] Running test renaissance-als_0 ... [2025-10-04T02:58:58.686Z] =============================================== [2025-10-04T02:58:58.686Z] renaissance-als_0 Start Time: Sat Oct 4 02:58:57 2025 Epoch Time (ms): 1759546737914 [2025-10-04T02:58:58.686Z] variation: NoOptions [2025-10-04T02:58:58.686Z] JVM_OPTIONS: [2025-10-04T02:58:58.686Z] { \ [2025-10-04T02:58:58.686Z] echo ""; echo "TEST SETUP:"; \ [2025-10-04T02:58:58.686Z] echo "Nothing to be done for setup."; \ [2025-10-04T02:58:58.686Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17595467371532/renaissance-als_0"; \ [2025-10-04T02:58:58.686Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17595467371532/renaissance-als_0"; \ [2025-10-04T02:58:58.686Z] echo ""; echo "TESTING:"; \ [2025-10-04T02:58:58.686Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17595467371532/renaissance-als_0"/als.json" als; \ [2025-10-04T02:58:58.686Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17595467371532/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-04T02:58:58.686Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-04T02:58:58.686Z] echo "Nothing to be done for teardown."; \ [2025-10-04T02:58:58.686Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17595467371532/TestTargetResult"; [2025-10-04T02:58:58.686Z] [2025-10-04T02:58:58.686Z] TEST SETUP: [2025-10-04T02:58:58.686Z] Nothing to be done for setup. [2025-10-04T02:58:58.686Z] [2025-10-04T02:58:58.686Z] TESTING: [2025-10-04T02:59:05.219Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-04T02:59:09.447Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-04T02:59:09.447Z] GC before operation: completed in 44.929 ms, heap usage 47.997 MB -> 35.105 MB. [2025-10-04T02:59:30.910Z] ====== als (apache-spark) [default], iteration 0 completed (19068.431 ms) ====== [2025-10-04T02:59:30.910Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-04T02:59:30.910Z] GC before operation: completed in 83.381 ms, heap usage 290.011 MB -> 61.890 MB. [2025-10-04T02:59:37.536Z] ====== als (apache-spark) [default], iteration 1 completed (8446.478 ms) ====== [2025-10-04T02:59:37.537Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-04T02:59:37.537Z] GC before operation: completed in 82.996 ms, heap usage 235.372 MB -> 62.793 MB. [2025-10-04T02:59:45.484Z] ====== als (apache-spark) [default], iteration 2 completed (7145.074 ms) ====== [2025-10-04T02:59:45.484Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-04T02:59:45.484Z] GC before operation: completed in 95.877 ms, heap usage 547.030 MB -> 63.832 MB. [2025-10-04T02:59:52.420Z] ====== als (apache-spark) [default], iteration 3 completed (7198.172 ms) ====== [2025-10-04T02:59:52.420Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-04T02:59:52.420Z] GC before operation: completed in 88.348 ms, heap usage 246.802 MB -> 63.955 MB. [2025-10-04T03:00:00.360Z] ====== als (apache-spark) [default], iteration 4 completed (6823.664 ms) ====== [2025-10-04T03:00:00.360Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-04T03:00:00.360Z] GC before operation: completed in 90.301 ms, heap usage 190.993 MB -> 64.405 MB. [2025-10-04T03:00:07.022Z] ====== als (apache-spark) [default], iteration 5 completed (6744.084 ms) ====== [2025-10-04T03:00:07.022Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-04T03:00:07.022Z] GC before operation: completed in 86.107 ms, heap usage 356.278 MB -> 65.229 MB. [2025-10-04T03:00:14.997Z] ====== als (apache-spark) [default], iteration 6 completed (7507.166 ms) ====== [2025-10-04T03:00:14.997Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-04T03:00:14.997Z] GC before operation: completed in 85.445 ms, heap usage 229.187 MB -> 65.672 MB. [2025-10-04T03:00:22.931Z] ====== als (apache-spark) [default], iteration 7 completed (8237.107 ms) ====== [2025-10-04T03:00:22.931Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-04T03:00:22.931Z] GC before operation: completed in 98.927 ms, heap usage 466.043 MB -> 66.521 MB. [2025-10-04T03:00:29.495Z] ====== als (apache-spark) [default], iteration 8 completed (6623.246 ms) ====== [2025-10-04T03:00:29.495Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-04T03:00:29.495Z] GC before operation: completed in 87.933 ms, heap usage 152.652 MB -> 66.608 MB. [2025-10-04T03:00:37.486Z] ====== als (apache-spark) [default], iteration 9 completed (6568.136 ms) ====== [2025-10-04T03:00:37.486Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-04T03:00:37.486Z] GC before operation: completed in 104.034 ms, heap usage 122.937 MB -> 66.871 MB. [2025-10-04T03:00:44.043Z] ====== als (apache-spark) [default], iteration 10 completed (7521.646 ms) ====== [2025-10-04T03:00:44.043Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-04T03:00:44.766Z] GC before operation: completed in 102.253 ms, heap usage 186.172 MB -> 67.079 MB. [2025-10-04T03:00:51.430Z] ====== als (apache-spark) [default], iteration 11 completed (6716.623 ms) ====== [2025-10-04T03:00:51.430Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-04T03:00:51.430Z] GC before operation: completed in 93.392 ms, heap usage 502.554 MB -> 68.312 MB. [2025-10-04T03:00:59.377Z] ====== als (apache-spark) [default], iteration 12 completed (6822.765 ms) ====== [2025-10-04T03:00:59.377Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-04T03:00:59.377Z] GC before operation: completed in 98.185 ms, heap usage 491.525 MB -> 68.101 MB. [2025-10-04T03:01:05.206Z] ====== als (apache-spark) [default], iteration 13 completed (6689.784 ms) ====== [2025-10-04T03:01:05.206Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-04T03:01:05.206Z] GC before operation: completed in 92.034 ms, heap usage 360.022 MB -> 68.149 MB. [2025-10-04T03:01:13.143Z] ====== als (apache-spark) [default], iteration 14 completed (6711.667 ms) ====== [2025-10-04T03:01:13.143Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-04T03:01:13.143Z] GC before operation: completed in 98.631 ms, heap usage 438.275 MB -> 68.568 MB. [2025-10-04T03:01:19.697Z] ====== als (apache-spark) [default], iteration 15 completed (6479.574 ms) ====== [2025-10-04T03:01:19.697Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-04T03:01:19.697Z] GC before operation: completed in 95.007 ms, heap usage 308.546 MB -> 68.661 MB. [2025-10-04T03:01:26.258Z] ====== als (apache-spark) [default], iteration 16 completed (6579.440 ms) ====== [2025-10-04T03:01:26.258Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-04T03:01:26.258Z] GC before operation: completed in 92.746 ms, heap usage 202.448 MB -> 68.660 MB. [2025-10-04T03:01:32.826Z] ====== als (apache-spark) [default], iteration 17 completed (6621.321 ms) ====== [2025-10-04T03:01:32.826Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-04T03:01:32.826Z] GC before operation: completed in 92.954 ms, heap usage 360.283 MB -> 69.148 MB. [2025-10-04T03:01:40.767Z] ====== als (apache-spark) [default], iteration 18 completed (7978.045 ms) ====== [2025-10-04T03:01:40.767Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-04T03:01:41.629Z] GC before operation: completed in 99.567 ms, heap usage 159.433 MB -> 69.316 MB. [2025-10-04T03:01:48.282Z] ====== als (apache-spark) [default], iteration 19 completed (6416.355 ms) ====== [2025-10-04T03:01:48.282Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-04T03:01:48.282Z] GC before operation: completed in 100.466 ms, heap usage 419.186 MB -> 69.856 MB. [2025-10-04T03:01:55.240Z] ====== als (apache-spark) [default], iteration 20 completed (6785.177 ms) ====== [2025-10-04T03:01:55.240Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-04T03:01:55.240Z] GC before operation: completed in 88.391 ms, heap usage 538.364 MB -> 70.301 MB. [2025-10-04T03:02:01.810Z] ====== als (apache-spark) [default], iteration 21 completed (6433.828 ms) ====== [2025-10-04T03:02:01.810Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-04T03:02:01.810Z] GC before operation: completed in 83.213 ms, heap usage 501.904 MB -> 70.397 MB. [2025-10-04T03:02:09.769Z] ====== als (apache-spark) [default], iteration 22 completed (7583.110 ms) ====== [2025-10-04T03:02:09.769Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-04T03:02:09.769Z] GC before operation: completed in 93.545 ms, heap usage 207.713 MB -> 70.262 MB. [2025-10-04T03:02:19.408Z] ====== als (apache-spark) [default], iteration 23 completed (9381.642 ms) ====== [2025-10-04T03:02:19.408Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-04T03:02:19.408Z] GC before operation: completed in 84.212 ms, heap usage 408.772 MB -> 70.802 MB. [2025-10-04T03:02:28.905Z] ====== als (apache-spark) [default], iteration 24 completed (8817.412 ms) ====== [2025-10-04T03:02:28.905Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-04T03:02:28.905Z] GC before operation: completed in 82.332 ms, heap usage 249.767 MB -> 70.973 MB. [2025-10-04T03:02:35.487Z] ====== als (apache-spark) [default], iteration 25 completed (6475.089 ms) ====== [2025-10-04T03:02:35.487Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-04T03:02:35.487Z] GC before operation: completed in 89.207 ms, heap usage 108.673 MB -> 70.995 MB. [2025-10-04T03:02:42.053Z] ====== als (apache-spark) [default], iteration 26 completed (6404.879 ms) ====== [2025-10-04T03:02:42.053Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-04T03:02:42.053Z] GC before operation: completed in 84.440 ms, heap usage 399.857 MB -> 71.539 MB. [2025-10-04T03:02:48.622Z] ====== als (apache-spark) [default], iteration 27 completed (6460.153 ms) ====== [2025-10-04T03:02:48.622Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-04T03:02:48.622Z] GC before operation: completed in 84.580 ms, heap usage 197.259 MB -> 71.602 MB. [2025-10-04T03:02:55.188Z] ====== als (apache-spark) [default], iteration 28 completed (6474.466 ms) ====== [2025-10-04T03:02:55.188Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-04T03:02:55.188Z] GC before operation: completed in 83.881 ms, heap usage 156.692 MB -> 71.371 MB. [2025-10-04T03:03:01.753Z] ====== als (apache-spark) [default], iteration 29 completed (6270.637 ms) ====== [2025-10-04T03:03:01.753Z] ----------------------------------- [2025-10-04T03:03:01.753Z] renaissance-als_0_PASSED [2025-10-04T03:03:01.753Z] ----------------------------------- [2025-10-04T03:03:01.753Z] [2025-10-04T03:03:01.753Z] TEST TEARDOWN: [2025-10-04T03:03:01.753Z] Nothing to be done for teardown. [2025-10-04T03:03:01.753Z] renaissance-als_0 Finish Time: Sat Oct 4 03:03:01 2025 Epoch Time (ms): 1759546981186