[2025-08-14T13:44:38.669Z] Running test renaissance-als_0 ... [2025-08-14T13:44:38.992Z] =============================================== [2025-08-14T13:44:38.992Z] renaissance-als_0 Start Time: Thu Aug 14 13:44:38 2025 Epoch Time (ms): 1755179078773 [2025-08-14T13:44:38.992Z] variation: NoOptions [2025-08-14T13:44:39.314Z] JVM_OPTIONS: [2025-08-14T13:44:39.314Z] { \ [2025-08-14T13:44:39.314Z] echo ""; echo "TEST SETUP:"; \ [2025-08-14T13:44:39.314Z] echo "Nothing to be done for setup."; \ [2025-08-14T13:44:39.314Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17551788759177\\renaissance-als_0"; \ [2025-08-14T13:44:39.314Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17551788759177\\renaissance-als_0"; \ [2025-08-14T13:44:39.314Z] echo ""; echo "TESTING:"; \ [2025-08-14T13:44:39.314Z] "c:/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17551788759177\\renaissance-als_0"\\als.json" als; \ [2025-08-14T13:44:39.314Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17551788759177\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-14T13:44:39.314Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-14T13:44:39.314Z] echo "Nothing to be done for teardown."; \ [2025-08-14T13:44:39.314Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17551788759177\\TestTargetResult"; [2025-08-14T13:44:39.314Z] [2025-08-14T13:44:39.314Z] TEST SETUP: [2025-08-14T13:44:39.314Z] Nothing to be done for setup. [2025-08-14T13:44:39.314Z] [2025-08-14T13:44:39.314Z] TESTING: [2025-08-14T13:44:54.876Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-14T13:44:57.039Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-14T13:44:57.039Z] GC before operation: completed in 38.763 ms, heap usage 50.858 MB -> 35.519 MB. [2025-08-14T13:45:30.377Z] ====== als (apache-spark) [default], iteration 0 completed (27712.046 ms) ====== [2025-08-14T13:45:30.377Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-14T13:45:30.377Z] GC before operation: completed in 84.010 ms, heap usage 183.870 MB -> 62.455 MB. [2025-08-14T13:45:43.423Z] ====== als (apache-spark) [default], iteration 1 completed (15826.143 ms) ====== [2025-08-14T13:45:43.423Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-14T13:45:43.423Z] GC before operation: completed in 75.429 ms, heap usage 296.101 MB -> 63.491 MB. [2025-08-14T13:45:59.216Z] ====== als (apache-spark) [default], iteration 2 completed (15252.358 ms) ====== [2025-08-14T13:45:59.216Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-14T13:45:59.216Z] GC before operation: completed in 77.351 ms, heap usage 207.689 MB -> 63.826 MB. [2025-08-14T13:46:12.278Z] ====== als (apache-spark) [default], iteration 3 completed (15036.398 ms) ====== [2025-08-14T13:46:12.278Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-14T13:46:12.278Z] GC before operation: completed in 80.460 ms, heap usage 397.411 MB -> 64.646 MB. [2025-08-14T13:46:28.219Z] ====== als (apache-spark) [default], iteration 4 completed (15167.239 ms) ====== [2025-08-14T13:46:28.219Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-14T13:46:28.219Z] GC before operation: completed in 80.528 ms, heap usage 484.180 MB -> 65.427 MB. [2025-08-14T13:46:44.194Z] ====== als (apache-spark) [default], iteration 5 completed (15001.752 ms) ====== [2025-08-14T13:46:44.194Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-14T13:46:44.194Z] GC before operation: completed in 81.096 ms, heap usage 493.591 MB -> 66.021 MB. [2025-08-14T13:46:59.974Z] ====== als (apache-spark) [default], iteration 6 completed (14847.555 ms) ====== [2025-08-14T13:46:59.974Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-14T13:46:59.974Z] GC before operation: completed in 84.749 ms, heap usage 561.925 MB -> 66.599 MB. [2025-08-14T13:47:15.646Z] ====== als (apache-spark) [default], iteration 7 completed (14843.519 ms) ====== [2025-08-14T13:47:15.647Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-14T13:47:15.647Z] GC before operation: completed in 83.710 ms, heap usage 213.018 MB -> 66.691 MB. [2025-08-14T13:47:28.652Z] ====== als (apache-spark) [default], iteration 8 completed (15096.405 ms) ====== [2025-08-14T13:47:28.652Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-14T13:47:28.653Z] GC before operation: completed in 84.646 ms, heap usage 388.105 MB -> 67.342 MB. [2025-08-14T13:47:44.348Z] ====== als (apache-spark) [default], iteration 9 completed (14691.084 ms) ====== [2025-08-14T13:47:44.348Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-14T13:47:44.348Z] GC before operation: completed in 87.246 ms, heap usage 296.427 MB -> 67.658 MB. [2025-08-14T13:48:00.064Z] ====== als (apache-spark) [default], iteration 10 completed (14707.967 ms) ====== [2025-08-14T13:48:00.064Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-14T13:48:00.064Z] GC before operation: completed in 94.371 ms, heap usage 472.513 MB -> 68.201 MB. [2025-08-14T13:48:15.783Z] ====== als (apache-spark) [default], iteration 11 completed (14776.942 ms) ====== [2025-08-14T13:48:15.783Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-14T13:48:15.783Z] GC before operation: completed in 102.275 ms, heap usage 457.425 MB -> 68.443 MB. [2025-08-14T13:48:28.847Z] ====== als (apache-spark) [default], iteration 12 completed (14641.393 ms) ====== [2025-08-14T13:48:28.847Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-14T13:48:28.847Z] GC before operation: completed in 88.847 ms, heap usage 458.874 MB -> 68.681 MB. [2025-08-14T13:48:44.668Z] ====== als (apache-spark) [default], iteration 13 completed (14635.757 ms) ====== [2025-08-14T13:48:44.668Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-14T13:48:44.668Z] GC before operation: completed in 85.750 ms, heap usage 113.243 MB -> 68.517 MB. [2025-08-14T13:49:00.395Z] ====== als (apache-spark) [default], iteration 14 completed (14780.110 ms) ====== [2025-08-14T13:49:00.395Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-14T13:49:00.395Z] GC before operation: completed in 83.809 ms, heap usage 449.873 MB -> 69.301 MB. [2025-08-14T13:49:13.393Z] ====== als (apache-spark) [default], iteration 15 completed (14603.499 ms) ====== [2025-08-14T13:49:13.394Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-14T13:49:13.394Z] GC before operation: completed in 75.235 ms, heap usage 208.841 MB -> 69.005 MB. [2025-08-14T13:49:29.125Z] ====== als (apache-spark) [default], iteration 16 completed (14417.768 ms) ====== [2025-08-14T13:49:29.125Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-14T13:49:29.125Z] GC before operation: completed in 75.852 ms, heap usage 306.429 MB -> 69.456 MB. [2025-08-14T13:49:44.900Z] ====== als (apache-spark) [default], iteration 17 completed (14676.773 ms) ====== [2025-08-14T13:49:44.900Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-14T13:49:44.900Z] GC before operation: completed in 76.131 ms, heap usage 299.675 MB -> 69.713 MB. [2025-08-14T13:49:57.841Z] ====== als (apache-spark) [default], iteration 18 completed (14528.028 ms) ====== [2025-08-14T13:49:57.841Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-14T13:49:57.841Z] GC before operation: completed in 77.527 ms, heap usage 236.209 MB -> 69.927 MB. [2025-08-14T13:50:13.578Z] ====== als (apache-spark) [default], iteration 19 completed (14486.480 ms) ====== [2025-08-14T13:50:13.578Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-14T13:50:13.578Z] GC before operation: completed in 84.415 ms, heap usage 481.742 MB -> 70.503 MB. [2025-08-14T13:50:29.331Z] ====== als (apache-spark) [default], iteration 20 completed (15014.115 ms) ====== [2025-08-14T13:50:29.331Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-14T13:50:29.331Z] GC before operation: completed in 78.879 ms, heap usage 458.070 MB -> 70.810 MB. [2025-08-14T13:50:42.495Z] ====== als (apache-spark) [default], iteration 21 completed (14612.944 ms) ====== [2025-08-14T13:50:42.495Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-14T13:50:42.495Z] GC before operation: completed in 79.845 ms, heap usage 246.970 MB -> 70.696 MB. [2025-08-14T13:50:58.293Z] ====== als (apache-spark) [default], iteration 22 completed (14709.213 ms) ====== [2025-08-14T13:50:58.293Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-14T13:50:58.293Z] GC before operation: completed in 81.828 ms, heap usage 370.598 MB -> 71.145 MB. [2025-08-14T13:51:14.030Z] ====== als (apache-spark) [default], iteration 23 completed (14819.828 ms) ====== [2025-08-14T13:51:14.030Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-14T13:51:14.030Z] GC before operation: completed in 82.091 ms, heap usage 501.576 MB -> 71.548 MB. [2025-08-14T13:51:29.709Z] ====== als (apache-spark) [default], iteration 24 completed (14793.988 ms) ====== [2025-08-14T13:51:29.709Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-14T13:51:29.709Z] GC before operation: completed in 79.243 ms, heap usage 242.418 MB -> 71.534 MB. [2025-08-14T13:51:42.950Z] ====== als (apache-spark) [default], iteration 25 completed (14531.032 ms) ====== [2025-08-14T13:51:42.950Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-14T13:51:42.950Z] GC before operation: completed in 81.199 ms, heap usage 368.284 MB -> 71.832 MB. [2025-08-14T13:51:58.922Z] ====== als (apache-spark) [default], iteration 26 completed (14432.711 ms) ====== [2025-08-14T13:51:58.922Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-14T13:51:58.922Z] GC before operation: completed in 80.883 ms, heap usage 284.772 MB -> 72.008 MB. [2025-08-14T13:52:11.840Z] ====== als (apache-spark) [default], iteration 27 completed (14640.088 ms) ====== [2025-08-14T13:52:11.840Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-14T13:52:11.840Z] GC before operation: completed in 79.787 ms, heap usage 230.497 MB -> 72.229 MB. [2025-08-14T13:52:27.707Z] ====== als (apache-spark) [default], iteration 28 completed (14440.679 ms) ====== [2025-08-14T13:52:27.707Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-14T13:52:27.707Z] GC before operation: completed in 90.412 ms, heap usage 334.305 MB -> 72.639 MB. [2025-08-14T13:52:43.439Z] ====== als (apache-spark) [default], iteration 29 completed (14481.966 ms) ====== [2025-08-14T13:52:43.439Z] ----------------------------------- [2025-08-14T13:52:43.439Z] renaissance-als_0_PASSED [2025-08-14T13:52:43.439Z] ----------------------------------- [2025-08-14T13:52:43.439Z] [2025-08-14T13:52:43.439Z] TEST TEARDOWN: [2025-08-14T13:52:43.439Z] Nothing to be done for teardown. [2025-08-14T13:52:43.439Z] renaissance-als_0 Finish Time: Thu Aug 14 13:52:41 2025 Epoch Time (ms): 1755179561656