[2025-06-27T22:47:50.690Z] Running test renaissance-als_0 ... [2025-06-27T22:47:51.000Z] =============================================== [2025-06-27T22:47:51.000Z] renaissance-als_0 Start Time: Fri Jun 27 22:47:50 2025 Epoch Time (ms): 1751064470816 [2025-06-27T22:47:51.000Z] variation: NoOptions [2025-06-27T22:47:51.323Z] JVM_OPTIONS: [2025-06-27T22:47:51.323Z] { \ [2025-06-27T22:47:51.323Z] echo ""; echo "TEST SETUP:"; \ [2025-06-27T22:47:51.323Z] echo "Nothing to be done for setup."; \ [2025-06-27T22:47:51.323Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17510643569254\\renaissance-als_0"; \ [2025-06-27T22:47:51.323Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17510643569254\\renaissance-als_0"; \ [2025-06-27T22:47:51.323Z] echo ""; echo "TESTING:"; \ [2025-06-27T22:47:51.323Z] "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_17510643569254\\renaissance-als_0"\\als.json" als; \ [2025-06-27T22:47:51.323Z] 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_17510643569254\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-27T22:47:51.323Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-27T22:47:51.323Z] echo "Nothing to be done for teardown."; \ [2025-06-27T22:47:51.323Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17510643569254\\TestTargetResult"; [2025-06-27T22:47:51.323Z] [2025-06-27T22:47:51.323Z] TEST SETUP: [2025-06-27T22:47:51.323Z] Nothing to be done for setup. [2025-06-27T22:47:51.323Z] [2025-06-27T22:47:51.323Z] TESTING: [2025-06-27T22:48:06.927Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-27T22:48:09.800Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-27T22:48:09.801Z] GC before operation: completed in 37.775 ms, heap usage 53.455 MB -> 35.503 MB. [2025-06-27T22:48:39.786Z] ====== als (apache-spark) [default], iteration 0 completed (27957.144 ms) ====== [2025-06-27T22:48:39.786Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-27T22:48:39.786Z] GC before operation: completed in 88.601 ms, heap usage 128.312 MB -> 61.804 MB. [2025-06-27T22:48:55.529Z] ====== als (apache-spark) [default], iteration 1 completed (16008.710 ms) ====== [2025-06-27T22:48:55.529Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-27T22:48:55.529Z] GC before operation: completed in 76.372 ms, heap usage 265.124 MB -> 63.316 MB. [2025-06-27T22:49:11.137Z] ====== als (apache-spark) [default], iteration 2 completed (15499.444 ms) ====== [2025-06-27T22:49:11.137Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-27T22:49:11.137Z] GC before operation: completed in 74.399 ms, heap usage 345.482 MB -> 63.869 MB. [2025-06-27T22:49:26.795Z] ====== als (apache-spark) [default], iteration 3 completed (15154.341 ms) ====== [2025-06-27T22:49:26.795Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-27T22:49:26.795Z] GC before operation: completed in 81.357 ms, heap usage 501.158 MB -> 64.710 MB. [2025-06-27T22:49:42.492Z] ====== als (apache-spark) [default], iteration 4 completed (15219.333 ms) ====== [2025-06-27T22:49:42.493Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-27T22:49:42.493Z] GC before operation: completed in 79.816 ms, heap usage 150.642 MB -> 64.788 MB. [2025-06-27T22:49:58.340Z] ====== als (apache-spark) [default], iteration 5 completed (14984.066 ms) ====== [2025-06-27T22:49:58.340Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-27T22:49:58.340Z] GC before operation: completed in 85.965 ms, heap usage 299.745 MB -> 65.633 MB. [2025-06-27T22:50:14.012Z] ====== als (apache-spark) [default], iteration 6 completed (15050.846 ms) ====== [2025-06-27T22:50:14.012Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-27T22:50:14.012Z] GC before operation: completed in 101.425 ms, heap usage 183.920 MB -> 66.004 MB. [2025-06-27T22:50:29.715Z] ====== als (apache-spark) [default], iteration 7 completed (14992.816 ms) ====== [2025-06-27T22:50:29.715Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-27T22:50:29.715Z] GC before operation: completed in 101.012 ms, heap usage 498.558 MB -> 66.924 MB. [2025-06-27T22:50:42.694Z] ====== als (apache-spark) [default], iteration 8 completed (14741.077 ms) ====== [2025-06-27T22:50:42.694Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-27T22:50:42.694Z] GC before operation: completed in 80.753 ms, heap usage 155.184 MB -> 67.087 MB. [2025-06-27T22:50:58.415Z] ====== als (apache-spark) [default], iteration 9 completed (14788.833 ms) ====== [2025-06-27T22:50:58.415Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-27T22:50:58.415Z] GC before operation: completed in 89.377 ms, heap usage 113.591 MB -> 66.749 MB. [2025-06-27T22:51:14.196Z] ====== als (apache-spark) [default], iteration 10 completed (15087.813 ms) ====== [2025-06-27T22:51:14.196Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-27T22:51:14.196Z] GC before operation: completed in 73.198 ms, heap usage 363.548 MB -> 67.784 MB. [2025-06-27T22:51:27.245Z] ====== als (apache-spark) [default], iteration 11 completed (14546.285 ms) ====== [2025-06-27T22:51:27.245Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-27T22:51:27.626Z] GC before operation: completed in 82.081 ms, heap usage 526.614 MB -> 68.232 MB. [2025-06-27T22:51:43.304Z] ====== als (apache-spark) [default], iteration 12 completed (14790.610 ms) ====== [2025-06-27T22:51:43.304Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-27T22:51:43.304Z] GC before operation: completed in 75.978 ms, heap usage 362.354 MB -> 68.252 MB. [2025-06-27T22:51:59.190Z] ====== als (apache-spark) [default], iteration 13 completed (14917.358 ms) ====== [2025-06-27T22:51:59.190Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-27T22:51:59.190Z] GC before operation: completed in 75.529 ms, heap usage 352.572 MB -> 68.550 MB. [2025-06-27T22:52:12.273Z] ====== als (apache-spark) [default], iteration 14 completed (14650.215 ms) ====== [2025-06-27T22:52:12.273Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-27T22:52:12.621Z] GC before operation: completed in 81.856 ms, heap usage 150.295 MB -> 68.509 MB. [2025-06-27T22:52:28.419Z] ====== als (apache-spark) [default], iteration 15 completed (14432.564 ms) ====== [2025-06-27T22:52:28.420Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-27T22:52:28.420Z] GC before operation: completed in 83.016 ms, heap usage 813.061 MB -> 69.690 MB. [2025-06-27T22:52:44.091Z] ====== als (apache-spark) [default], iteration 16 completed (14788.550 ms) ====== [2025-06-27T22:52:44.091Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-27T22:52:44.091Z] GC before operation: completed in 86.391 ms, heap usage 388.709 MB -> 69.278 MB. [2025-06-27T22:52:57.164Z] ====== als (apache-spark) [default], iteration 17 completed (14580.224 ms) ====== [2025-06-27T22:52:57.164Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-27T22:52:57.164Z] GC before operation: completed in 75.163 ms, heap usage 232.319 MB -> 69.422 MB. [2025-06-27T22:53:12.869Z] ====== als (apache-spark) [default], iteration 18 completed (14501.486 ms) ====== [2025-06-27T22:53:12.869Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-27T22:53:12.869Z] GC before operation: completed in 79.186 ms, heap usage 204.393 MB -> 69.603 MB. [2025-06-27T22:53:28.593Z] ====== als (apache-spark) [default], iteration 19 completed (14575.860 ms) ====== [2025-06-27T22:53:28.593Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-27T22:53:28.593Z] GC before operation: completed in 83.526 ms, heap usage 499.221 MB -> 70.276 MB. [2025-06-27T22:53:41.681Z] ====== als (apache-spark) [default], iteration 20 completed (14881.685 ms) ====== [2025-06-27T22:53:41.681Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-27T22:53:41.681Z] GC before operation: completed in 82.726 ms, heap usage 207.850 MB -> 70.092 MB. [2025-06-27T22:53:57.436Z] ====== als (apache-spark) [default], iteration 21 completed (14506.484 ms) ====== [2025-06-27T22:53:57.436Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-27T22:53:57.436Z] GC before operation: completed in 78.780 ms, heap usage 155.601 MB -> 70.326 MB. [2025-06-27T22:54:12.437Z] ====== als (apache-spark) [default], iteration 22 completed (14708.571 ms) ====== [2025-06-27T22:54:12.437Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-27T22:54:12.437Z] GC before operation: completed in 87.860 ms, heap usage 250.289 MB -> 70.702 MB. [2025-06-27T22:54:28.076Z] ====== als (apache-spark) [default], iteration 23 completed (14553.386 ms) ====== [2025-06-27T22:54:28.076Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-27T22:54:28.076Z] GC before operation: completed in 77.837 ms, heap usage 460.928 MB -> 71.296 MB. [2025-06-27T22:54:41.062Z] ====== als (apache-spark) [default], iteration 24 completed (14758.646 ms) ====== [2025-06-27T22:54:41.062Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-27T22:54:41.062Z] GC before operation: completed in 77.219 ms, heap usage 122.859 MB -> 71.103 MB. [2025-06-27T22:54:57.756Z] ====== als (apache-spark) [default], iteration 25 completed (14516.309 ms) ====== [2025-06-27T22:54:57.756Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-27T22:54:57.756Z] GC before operation: completed in 82.147 ms, heap usage 372.706 MB -> 71.570 MB. [2025-06-27T22:55:10.800Z] ====== als (apache-spark) [default], iteration 26 completed (14745.802 ms) ====== [2025-06-27T22:55:10.800Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-27T22:55:10.801Z] GC before operation: completed in 77.417 ms, heap usage 203.081 MB -> 71.632 MB. [2025-06-27T22:55:26.556Z] ====== als (apache-spark) [default], iteration 27 completed (14612.274 ms) ====== [2025-06-27T22:55:26.556Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-27T22:55:26.556Z] GC before operation: completed in 77.523 ms, heap usage 207.578 MB -> 71.901 MB. [2025-06-27T22:55:42.330Z] ====== als (apache-spark) [default], iteration 28 completed (14275.871 ms) ====== [2025-06-27T22:55:42.330Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-27T22:55:42.330Z] GC before operation: completed in 77.215 ms, heap usage 423.594 MB -> 72.347 MB. [2025-06-27T22:55:55.522Z] ====== als (apache-spark) [default], iteration 29 completed (14302.476 ms) ====== [2025-06-27T22:55:55.522Z] ----------------------------------- [2025-06-27T22:55:55.522Z] renaissance-als_0_PASSED [2025-06-27T22:55:55.522Z] ----------------------------------- [2025-06-27T22:55:55.522Z] [2025-06-27T22:55:55.522Z] TEST TEARDOWN: [2025-06-27T22:55:55.522Z] Nothing to be done for teardown. [2025-06-27T22:55:55.522Z] renaissance-als_0 Finish Time: Fri Jun 27 22:55:55 2025 Epoch Time (ms): 1751064955473