[2025-12-24T23:41:39.279Z] Running test renaissance-als_0 ... [2025-12-24T23:41:39.279Z] =============================================== [2025-12-24T23:41:39.279Z] renaissance-als_0 Start Time: Wed Dec 24 23:41:38 2025 Epoch Time (ms): 1766619698911 [2025-12-24T23:41:39.279Z] variation: NoOptions [2025-12-24T23:41:39.279Z] JVM_OPTIONS: [2025-12-24T23:41:39.279Z] { \ [2025-12-24T23:41:39.279Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T23:41:39.279Z] echo "Nothing to be done for setup."; \ [2025-12-24T23:41:39.279Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666196986535/renaissance-als_0"; \ [2025-12-24T23:41:39.279Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666196986535/renaissance-als_0"; \ [2025-12-24T23:41:39.279Z] echo ""; echo "TESTING:"; \ [2025-12-24T23:41:39.279Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666196986535/renaissance-als_0"/als.json" als; \ [2025-12-24T23:41:39.279Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666196986535/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T23:41:39.279Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T23:41:39.279Z] echo "Nothing to be done for teardown."; \ [2025-12-24T23:41:39.279Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17666196986535/TestTargetResult"; [2025-12-24T23:41:39.279Z] [2025-12-24T23:41:39.279Z] TEST SETUP: [2025-12-24T23:41:39.280Z] Nothing to be done for setup. [2025-12-24T23:41:39.280Z] [2025-12-24T23:41:39.280Z] TESTING: [2025-12-24T23:41:58.172Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-24T23:42:10.026Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-24T23:42:10.026Z] GC before operation: completed in 76.233 ms, heap usage 53.426 MB -> 35.123 MB. [2025-12-24T23:42:43.265Z] ====== als (apache-spark) [default], iteration 0 completed (32415.979 ms) ====== [2025-12-24T23:42:43.265Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-24T23:42:43.265Z] GC before operation: completed in 207.451 ms, heap usage 594.961 MB -> 62.344 MB. [2025-12-24T23:42:53.084Z] ====== als (apache-spark) [default], iteration 1 completed (9439.487 ms) ====== [2025-12-24T23:42:53.084Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-24T23:42:53.084Z] GC before operation: completed in 154.887 ms, heap usage 261.133 MB -> 62.974 MB. [2025-12-24T23:43:01.302Z] ====== als (apache-spark) [default], iteration 2 completed (8072.134 ms) ====== [2025-12-24T23:43:01.302Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-24T23:43:02.289Z] GC before operation: completed in 182.491 ms, heap usage 493.615 MB -> 63.864 MB. [2025-12-24T23:43:09.754Z] ====== als (apache-spark) [default], iteration 3 completed (7715.494 ms) ====== [2025-12-24T23:43:09.754Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-24T23:43:09.754Z] GC before operation: completed in 175.035 ms, heap usage 466.242 MB -> 64.467 MB. [2025-12-24T23:43:18.058Z] ====== als (apache-spark) [default], iteration 4 completed (7082.829 ms) ====== [2025-12-24T23:43:18.058Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-24T23:43:18.058Z] GC before operation: completed in 164.843 ms, heap usage 434.384 MB -> 65.008 MB. [2025-12-24T23:43:26.246Z] ====== als (apache-spark) [default], iteration 5 completed (7221.259 ms) ====== [2025-12-24T23:43:26.246Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-24T23:43:26.246Z] GC before operation: completed in 157.381 ms, heap usage 477.417 MB -> 65.577 MB. [2025-12-24T23:43:32.975Z] ====== als (apache-spark) [default], iteration 6 completed (6743.134 ms) ====== [2025-12-24T23:43:32.975Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-24T23:43:32.975Z] GC before operation: completed in 175.221 ms, heap usage 230.193 MB -> 65.855 MB. [2025-12-24T23:43:39.727Z] ====== als (apache-spark) [default], iteration 7 completed (6902.364 ms) ====== [2025-12-24T23:43:39.727Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-24T23:43:39.727Z] GC before operation: completed in 190.712 ms, heap usage 245.007 MB -> 66.318 MB. [2025-12-24T23:43:47.901Z] ====== als (apache-spark) [default], iteration 8 completed (6846.007 ms) ====== [2025-12-24T23:43:47.901Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-24T23:43:47.901Z] GC before operation: completed in 199.462 ms, heap usage 653.822 MB -> 67.405 MB. [2025-12-24T23:43:54.698Z] ====== als (apache-spark) [default], iteration 9 completed (6709.570 ms) ====== [2025-12-24T23:43:54.698Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-24T23:43:54.698Z] GC before operation: completed in 185.667 ms, heap usage 718.786 MB -> 67.826 MB. [2025-12-24T23:44:02.898Z] ====== als (apache-spark) [default], iteration 10 completed (7190.832 ms) ====== [2025-12-24T23:44:02.898Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-24T23:44:02.898Z] GC before operation: completed in 185.990 ms, heap usage 945.642 MB -> 68.412 MB. [2025-12-24T23:44:09.612Z] ====== als (apache-spark) [default], iteration 11 completed (6642.500 ms) ====== [2025-12-24T23:44:09.612Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-24T23:44:09.612Z] GC before operation: completed in 186.831 ms, heap usage 806.417 MB -> 68.379 MB. [2025-12-24T23:44:17.137Z] ====== als (apache-spark) [default], iteration 12 completed (6591.784 ms) ====== [2025-12-24T23:44:17.137Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-24T23:44:17.137Z] GC before operation: completed in 188.951 ms, heap usage 265.532 MB -> 68.064 MB. [2025-12-24T23:44:22.526Z] ====== als (apache-spark) [default], iteration 13 completed (6068.153 ms) ====== [2025-12-24T23:44:22.526Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-24T23:44:22.526Z] GC before operation: completed in 167.517 ms, heap usage 156.490 MB -> 68.123 MB. [2025-12-24T23:44:29.246Z] ====== als (apache-spark) [default], iteration 14 completed (5906.383 ms) ====== [2025-12-24T23:44:29.246Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-24T23:44:29.246Z] GC before operation: completed in 177.914 ms, heap usage 104.830 MB -> 68.285 MB. [2025-12-24T23:44:35.969Z] ====== als (apache-spark) [default], iteration 15 completed (6018.660 ms) ====== [2025-12-24T23:44:35.969Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-24T23:44:35.969Z] GC before operation: completed in 188.873 ms, heap usage 159.772 MB -> 68.690 MB. [2025-12-24T23:44:41.459Z] ====== als (apache-spark) [default], iteration 16 completed (5911.316 ms) ====== [2025-12-24T23:44:41.459Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-24T23:44:42.410Z] GC before operation: completed in 191.307 ms, heap usage 125.508 MB -> 69.030 MB. [2025-12-24T23:44:49.156Z] ====== als (apache-spark) [default], iteration 17 completed (6320.116 ms) ====== [2025-12-24T23:44:49.156Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-24T23:44:49.156Z] GC before operation: completed in 196.774 ms, heap usage 452.431 MB -> 69.633 MB. [2025-12-24T23:44:55.882Z] ====== als (apache-spark) [default], iteration 18 completed (6385.573 ms) ====== [2025-12-24T23:44:55.882Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-24T23:44:55.882Z] GC before operation: completed in 201.955 ms, heap usage 365.934 MB -> 69.799 MB. [2025-12-24T23:45:02.615Z] ====== als (apache-spark) [default], iteration 19 completed (6339.954 ms) ====== [2025-12-24T23:45:02.615Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-24T23:45:02.615Z] GC before operation: completed in 188.206 ms, heap usage 623.229 MB -> 70.283 MB. [2025-12-24T23:45:09.383Z] ====== als (apache-spark) [default], iteration 20 completed (7037.150 ms) ====== [2025-12-24T23:45:09.383Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-24T23:45:10.354Z] GC before operation: completed in 205.408 ms, heap usage 125.238 MB -> 69.958 MB. [2025-12-24T23:45:17.113Z] ====== als (apache-spark) [default], iteration 21 completed (6635.026 ms) ====== [2025-12-24T23:45:17.113Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-24T23:45:17.113Z] GC before operation: completed in 201.653 ms, heap usage 745.701 MB -> 70.982 MB. [2025-12-24T23:45:25.325Z] ====== als (apache-spark) [default], iteration 22 completed (7261.172 ms) ====== [2025-12-24T23:45:25.325Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-24T23:45:25.325Z] GC before operation: completed in 193.513 ms, heap usage 514.071 MB -> 70.992 MB. [2025-12-24T23:45:32.803Z] ====== als (apache-spark) [default], iteration 23 completed (6918.648 ms) ====== [2025-12-24T23:45:32.803Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-24T23:45:32.803Z] GC before operation: completed in 214.771 ms, heap usage 372.865 MB -> 71.007 MB. [2025-12-24T23:45:39.538Z] ====== als (apache-spark) [default], iteration 24 completed (6636.432 ms) ====== [2025-12-24T23:45:39.538Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-24T23:45:39.538Z] GC before operation: completed in 199.534 ms, heap usage 869.377 MB -> 71.947 MB. [2025-12-24T23:45:46.285Z] ====== als (apache-spark) [default], iteration 25 completed (6547.590 ms) ====== [2025-12-24T23:45:46.285Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-24T23:45:46.285Z] GC before operation: completed in 219.920 ms, heap usage 609.986 MB -> 71.856 MB. [2025-12-24T23:45:53.012Z] ====== als (apache-spark) [default], iteration 26 completed (6158.557 ms) ====== [2025-12-24T23:45:53.012Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-24T23:45:53.012Z] GC before operation: completed in 195.494 ms, heap usage 681.985 MB -> 72.203 MB. [2025-12-24T23:45:59.761Z] ====== als (apache-spark) [default], iteration 27 completed (6130.662 ms) ====== [2025-12-24T23:45:59.761Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-24T23:45:59.761Z] GC before operation: completed in 201.412 ms, heap usage 153.847 MB -> 71.866 MB. [2025-12-24T23:46:06.502Z] ====== als (apache-spark) [default], iteration 28 completed (6090.459 ms) ====== [2025-12-24T23:46:06.502Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-24T23:46:06.502Z] GC before operation: completed in 190.740 ms, heap usage 466.965 MB -> 72.606 MB. [2025-12-24T23:46:13.241Z] ====== als (apache-spark) [default], iteration 29 completed (6086.848 ms) ====== [2025-12-24T23:46:13.241Z] ----------------------------------- [2025-12-24T23:46:13.241Z] renaissance-als_0_PASSED [2025-12-24T23:46:13.241Z] ----------------------------------- [2025-12-24T23:46:13.241Z] [2025-12-24T23:46:13.241Z] TEST TEARDOWN: [2025-12-24T23:46:13.241Z] Nothing to be done for teardown. [2025-12-24T23:46:13.241Z] renaissance-als_0 Finish Time: Wed Dec 24 23:46:13 2025 Epoch Time (ms): 1766619973061