[2025-05-16T22:42:35.938Z] Running test renaissance-als_0 ... [2025-05-16T22:42:35.938Z] =============================================== [2025-05-16T22:42:36.239Z] renaissance-als_0 Start Time: Fri May 16 22:42:35 2025 Epoch Time (ms): 1747435355989 [2025-05-16T22:42:36.239Z] variation: NoOptions [2025-05-16T22:42:36.556Z] JVM_OPTIONS: [2025-05-16T22:42:36.556Z] { \ [2025-05-16T22:42:36.556Z] echo ""; echo "TEST SETUP:"; \ [2025-05-16T22:42:36.556Z] echo "Nothing to be done for setup."; \ [2025-05-16T22:42:36.556Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1747435294828\\renaissance-als_0"; \ [2025-05-16T22:42:36.556Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1747435294828\\renaissance-als_0"; \ [2025-05-16T22:42:36.556Z] echo ""; echo "TESTING:"; \ [2025-05-16T22:42:36.556Z] "c:/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1747435294828\\renaissance-als_0"\\als.json" als; \ [2025-05-16T22:42:36.556Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1747435294828\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-16T22:42:36.556Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-16T22:42:36.556Z] echo "Nothing to be done for teardown."; \ [2025-05-16T22:42:36.556Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1747435294828\\TestTargetResult"; [2025-05-16T22:42:36.556Z] [2025-05-16T22:42:36.556Z] TEST SETUP: [2025-05-16T22:42:36.556Z] Nothing to be done for setup. [2025-05-16T22:42:36.556Z] [2025-05-16T22:42:36.556Z] TESTING: [2025-05-16T22:42:52.043Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-16T22:42:54.870Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-16T22:42:54.870Z] GC before operation: completed in 42.875 ms, heap usage 273.500 MB -> 35.176 MB. [2025-05-16T22:43:28.063Z] ====== als (apache-spark) [default], iteration 0 completed (27807.143 ms) ====== [2025-05-16T22:43:28.063Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-16T22:43:28.063Z] GC before operation: completed in 88.444 ms, heap usage 148.684 MB -> 62.490 MB. [2025-05-16T22:43:40.913Z] ====== als (apache-spark) [default], iteration 1 completed (15611.731 ms) ====== [2025-05-16T22:43:40.913Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-16T22:43:40.913Z] GC before operation: completed in 77.643 ms, heap usage 682.801 MB -> 63.827 MB. [2025-05-16T22:43:56.559Z] ====== als (apache-spark) [default], iteration 2 completed (15231.807 ms) ====== [2025-05-16T22:43:56.559Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-16T22:43:56.559Z] GC before operation: completed in 72.835 ms, heap usage 480.209 MB -> 64.247 MB. [2025-05-16T22:44:12.213Z] ====== als (apache-spark) [default], iteration 3 completed (15238.448 ms) ====== [2025-05-16T22:44:12.213Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-16T22:44:12.213Z] GC before operation: completed in 76.083 ms, heap usage 387.487 MB -> 64.590 MB. [2025-05-16T22:44:25.083Z] ====== als (apache-spark) [default], iteration 4 completed (14978.017 ms) ====== [2025-05-16T22:44:25.083Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-16T22:44:25.083Z] GC before operation: completed in 75.639 ms, heap usage 396.384 MB -> 65.101 MB. [2025-05-16T22:44:40.799Z] ====== als (apache-spark) [default], iteration 5 completed (14993.054 ms) ====== [2025-05-16T22:44:40.799Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-16T22:44:40.799Z] GC before operation: completed in 84.295 ms, heap usage 276.408 MB -> 65.590 MB. [2025-05-16T22:44:56.411Z] ====== als (apache-spark) [default], iteration 6 completed (15253.431 ms) ====== [2025-05-16T22:44:56.411Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-16T22:44:56.411Z] GC before operation: completed in 90.994 ms, heap usage 634.942 MB -> 66.515 MB. [2025-05-16T22:45:12.068Z] ====== als (apache-spark) [default], iteration 7 completed (14647.108 ms) ====== [2025-05-16T22:45:12.068Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-16T22:45:12.068Z] GC before operation: completed in 78.631 ms, heap usage 154.777 MB -> 66.669 MB. [2025-05-16T22:45:27.606Z] ====== als (apache-spark) [default], iteration 8 completed (15126.261 ms) ====== [2025-05-16T22:45:27.606Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-16T22:45:27.606Z] GC before operation: completed in 81.490 ms, heap usage 212.033 MB -> 67.144 MB. [2025-05-16T22:45:43.110Z] ====== als (apache-spark) [default], iteration 9 completed (14581.370 ms) ====== [2025-05-16T22:45:43.110Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-16T22:45:43.110Z] GC before operation: completed in 87.102 ms, heap usage 535.540 MB -> 67.881 MB. [2025-05-16T22:45:56.089Z] ====== als (apache-spark) [default], iteration 10 completed (14619.175 ms) ====== [2025-05-16T22:45:56.089Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-16T22:45:56.089Z] GC before operation: completed in 87.016 ms, heap usage 710.298 MB -> 68.345 MB. [2025-05-16T22:46:11.904Z] ====== als (apache-spark) [default], iteration 11 completed (14946.443 ms) ====== [2025-05-16T22:46:11.904Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-16T22:46:11.904Z] GC before operation: completed in 83.372 ms, heap usage 468.582 MB -> 68.113 MB. [2025-05-16T22:46:27.750Z] ====== als (apache-spark) [default], iteration 12 completed (15081.196 ms) ====== [2025-05-16T22:46:27.750Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-16T22:46:27.750Z] GC before operation: completed in 81.668 ms, heap usage 439.526 MB -> 68.397 MB. [2025-05-16T22:46:43.508Z] ====== als (apache-spark) [default], iteration 13 completed (15092.493 ms) ====== [2025-05-16T22:46:43.508Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-16T22:46:43.508Z] GC before operation: completed in 83.378 ms, heap usage 438.864 MB -> 68.699 MB. [2025-05-16T22:46:56.494Z] ====== als (apache-spark) [default], iteration 14 completed (14765.436 ms) ====== [2025-05-16T22:46:56.494Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-16T22:46:56.849Z] GC before operation: completed in 88.157 ms, heap usage 218.549 MB -> 68.585 MB. [2025-05-16T22:47:12.923Z] ====== als (apache-spark) [default], iteration 15 completed (14564.914 ms) ====== [2025-05-16T22:47:12.923Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-16T22:47:12.923Z] GC before operation: completed in 84.237 ms, heap usage 166.915 MB -> 68.734 MB. [2025-05-16T22:47:28.540Z] ====== als (apache-spark) [default], iteration 16 completed (14892.845 ms) ====== [2025-05-16T22:47:28.540Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-16T22:47:28.540Z] GC before operation: completed in 86.889 ms, heap usage 499.392 MB -> 69.392 MB. [2025-05-16T22:47:44.264Z] ====== als (apache-spark) [default], iteration 17 completed (14987.449 ms) ====== [2025-05-16T22:47:44.264Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-16T22:47:44.264Z] GC before operation: completed in 86.139 ms, heap usage 507.571 MB -> 69.700 MB. [2025-05-16T22:47:57.287Z] ====== als (apache-spark) [default], iteration 18 completed (14988.704 ms) ====== [2025-05-16T22:47:57.287Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-16T22:47:57.287Z] GC before operation: completed in 98.635 ms, heap usage 204.475 MB -> 69.702 MB. [2025-05-16T22:48:12.904Z] ====== als (apache-spark) [default], iteration 19 completed (15490.773 ms) ====== [2025-05-16T22:48:12.904Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-16T22:48:12.904Z] GC before operation: completed in 84.655 ms, heap usage 338.876 MB -> 70.109 MB. [2025-05-16T22:48:28.557Z] ====== als (apache-spark) [default], iteration 20 completed (14571.251 ms) ====== [2025-05-16T22:48:28.557Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-16T22:48:28.557Z] GC before operation: completed in 91.507 ms, heap usage 307.489 MB -> 70.319 MB. [2025-05-16T22:48:44.268Z] ====== als (apache-spark) [default], iteration 21 completed (14517.294 ms) ====== [2025-05-16T22:48:44.268Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-16T22:48:44.268Z] GC before operation: completed in 96.375 ms, heap usage 292.971 MB -> 70.492 MB. [2025-05-16T22:48:59.962Z] ====== als (apache-spark) [default], iteration 22 completed (14926.785 ms) ====== [2025-05-16T22:48:59.962Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-16T22:48:59.962Z] GC before operation: completed in 89.996 ms, heap usage 225.004 MB -> 70.721 MB. [2025-05-16T22:49:12.807Z] ====== als (apache-spark) [default], iteration 23 completed (14575.604 ms) ====== [2025-05-16T22:49:12.807Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-16T22:49:12.807Z] GC before operation: completed in 87.706 ms, heap usage 195.825 MB -> 70.886 MB. [2025-05-16T22:49:28.480Z] ====== als (apache-spark) [default], iteration 24 completed (14446.682 ms) ====== [2025-05-16T22:49:28.480Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-16T22:49:28.480Z] GC before operation: completed in 89.095 ms, heap usage 353.953 MB -> 71.481 MB. [2025-05-16T22:49:44.053Z] ====== als (apache-spark) [default], iteration 25 completed (15014.952 ms) ====== [2025-05-16T22:49:44.053Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-16T22:49:44.053Z] GC before operation: completed in 100.663 ms, heap usage 150.002 MB -> 71.022 MB. [2025-05-16T22:49:57.157Z] ====== als (apache-spark) [default], iteration 26 completed (14794.544 ms) ====== [2025-05-16T22:49:57.157Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-16T22:49:57.534Z] GC before operation: completed in 109.999 ms, heap usage 144.049 MB -> 71.479 MB. [2025-05-16T22:50:13.080Z] ====== als (apache-spark) [default], iteration 27 completed (14968.354 ms) ====== [2025-05-16T22:50:13.080Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-16T22:50:13.080Z] GC before operation: completed in 115.099 ms, heap usage 209.738 MB -> 71.589 MB. [2025-05-16T22:50:28.719Z] ====== als (apache-spark) [default], iteration 28 completed (14628.440 ms) ====== [2025-05-16T22:50:28.719Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-16T22:50:28.719Z] GC before operation: completed in 93.600 ms, heap usage 307.702 MB -> 72.021 MB. [2025-05-16T22:50:44.423Z] ====== als (apache-spark) [default], iteration 29 completed (14592.479 ms) ====== [2025-05-16T22:50:44.423Z] ----------------------------------- [2025-05-16T22:50:44.423Z] renaissance-als_0_PASSED [2025-05-16T22:50:44.423Z] ----------------------------------- [2025-05-16T22:50:44.423Z] [2025-05-16T22:50:44.423Z] TEST TEARDOWN: [2025-05-16T22:50:44.423Z] Nothing to be done for teardown. [2025-05-16T22:50:44.423Z] renaissance-als_0 Finish Time: Fri May 16 22:50:42 2025 Epoch Time (ms): 1747435842868