[2025-11-06T00:37:20.314Z] Running test renaissance-als_0 ... [2025-11-06T00:37:20.314Z] =============================================== [2025-11-06T00:37:20.314Z] renaissance-als_0 Start Time: Thu Nov 6 00:37:19 2025 Epoch Time (ms): 1762389439778 [2025-11-06T00:37:20.314Z] variation: NoOptions [2025-11-06T00:37:20.314Z] JVM_OPTIONS: [2025-11-06T00:37:20.314Z] { \ [2025-11-06T00:37:20.314Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T00:37:20.314Z] echo "Nothing to be done for setup."; \ [2025-11-06T00:37:20.314Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17623894393850/renaissance-als_0"; \ [2025-11-06T00:37:20.314Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17623894393850/renaissance-als_0"; \ [2025-11-06T00:37:20.314Z] echo ""; echo "TESTING:"; \ [2025-11-06T00:37:20.314Z] "/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_17623894393850/renaissance-als_0"/als.json" als; \ [2025-11-06T00:37:20.314Z] 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_17623894393850/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T00:37:20.314Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T00:37:20.314Z] echo "Nothing to be done for teardown."; \ [2025-11-06T00:37:20.314Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17623894393850/TestTargetResult"; [2025-11-06T00:37:20.314Z] [2025-11-06T00:37:20.314Z] TEST SETUP: [2025-11-06T00:37:20.314Z] Nothing to be done for setup. [2025-11-06T00:37:20.314Z] [2025-11-06T00:37:20.314Z] TESTING: [2025-11-06T00:37:31.711Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-06T00:37:39.818Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-06T00:37:39.818Z] GC before operation: completed in 119.811 ms, heap usage 54.397 MB -> 35.057 MB. [2025-11-06T00:38:28.028Z] ====== als (apache-spark) [default], iteration 0 completed (47620.469 ms) ====== [2025-11-06T00:38:28.028Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-06T00:38:28.028Z] GC before operation: completed in 224.802 ms, heap usage 93.317 MB -> 61.900 MB. [2025-11-06T00:38:59.052Z] ====== als (apache-spark) [default], iteration 1 completed (28840.585 ms) ====== [2025-11-06T00:38:59.052Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-06T00:38:59.052Z] GC before operation: completed in 240.342 ms, heap usage 530.757 MB -> 63.250 MB. [2025-11-06T00:39:24.460Z] ====== als (apache-spark) [default], iteration 2 completed (23522.197 ms) ====== [2025-11-06T00:39:24.460Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-06T00:39:24.460Z] GC before operation: completed in 225.353 ms, heap usage 486.187 MB -> 63.745 MB. [2025-11-06T00:39:40.374Z] ====== als (apache-spark) [default], iteration 3 completed (17517.294 ms) ====== [2025-11-06T00:39:40.374Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-06T00:39:40.374Z] GC before operation: completed in 203.070 ms, heap usage 442.595 MB -> 64.345 MB. [2025-11-06T00:40:02.172Z] ====== als (apache-spark) [default], iteration 4 completed (19558.129 ms) ====== [2025-11-06T00:40:02.173Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-06T00:40:02.173Z] GC before operation: completed in 229.467 ms, heap usage 171.812 MB -> 64.456 MB. [2025-11-06T00:40:18.054Z] ====== als (apache-spark) [default], iteration 5 completed (15163.094 ms) ====== [2025-11-06T00:40:18.054Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-06T00:40:18.054Z] GC before operation: completed in 201.178 ms, heap usage 230.039 MB -> 65.125 MB. [2025-11-06T00:40:34.113Z] ====== als (apache-spark) [default], iteration 6 completed (16308.774 ms) ====== [2025-11-06T00:40:34.113Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-06T00:40:34.113Z] GC before operation: completed in 244.189 ms, heap usage 358.368 MB -> 65.699 MB. [2025-11-06T00:40:52.747Z] ====== als (apache-spark) [default], iteration 7 completed (16839.164 ms) ====== [2025-11-06T00:40:52.747Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-06T00:40:52.747Z] GC before operation: completed in 268.810 ms, heap usage 158.864 MB -> 66.035 MB. [2025-11-06T00:41:11.349Z] ====== als (apache-spark) [default], iteration 8 completed (17836.361 ms) ====== [2025-11-06T00:41:11.349Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-06T00:41:11.349Z] GC before operation: completed in 298.160 ms, heap usage 490.281 MB -> 67.013 MB. [2025-11-06T00:41:33.069Z] ====== als (apache-spark) [default], iteration 9 completed (20323.181 ms) ====== [2025-11-06T00:41:33.069Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-06T00:41:33.069Z] GC before operation: completed in 220.160 ms, heap usage 366.460 MB -> 67.100 MB. [2025-11-06T00:41:46.551Z] ====== als (apache-spark) [default], iteration 10 completed (13921.386 ms) ====== [2025-11-06T00:41:46.551Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-06T00:41:46.551Z] GC before operation: completed in 198.454 ms, heap usage 135.885 MB -> 67.030 MB. [2025-11-06T00:42:02.892Z] ====== als (apache-spark) [default], iteration 11 completed (16469.773 ms) ====== [2025-11-06T00:42:02.892Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-06T00:42:02.892Z] GC before operation: completed in 337.623 ms, heap usage 462.413 MB -> 67.725 MB. [2025-11-06T00:42:18.785Z] ====== als (apache-spark) [default], iteration 12 completed (13789.006 ms) ====== [2025-11-06T00:42:18.786Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-06T00:42:18.786Z] GC before operation: completed in 199.152 ms, heap usage 483.604 MB -> 67.980 MB. [2025-11-06T00:42:34.895Z] ====== als (apache-spark) [default], iteration 13 completed (16066.552 ms) ====== [2025-11-06T00:42:34.895Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-06T00:42:34.895Z] GC before operation: completed in 187.735 ms, heap usage 353.543 MB -> 68.063 MB. [2025-11-06T00:42:59.341Z] ====== als (apache-spark) [default], iteration 14 completed (22906.771 ms) ====== [2025-11-06T00:42:59.341Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-06T00:42:59.341Z] GC before operation: completed in 255.648 ms, heap usage 446.367 MB -> 68.531 MB. [2025-11-06T00:43:22.120Z] ====== als (apache-spark) [default], iteration 15 completed (22403.674 ms) ====== [2025-11-06T00:43:22.120Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-06T00:43:22.120Z] GC before operation: completed in 164.280 ms, heap usage 191.128 MB -> 68.425 MB. [2025-11-06T00:43:33.353Z] ====== als (apache-spark) [default], iteration 16 completed (13121.691 ms) ====== [2025-11-06T00:43:33.353Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-06T00:43:34.083Z] GC before operation: completed in 167.351 ms, heap usage 555.968 MB -> 69.311 MB. [2025-11-06T00:43:45.523Z] ====== als (apache-spark) [default], iteration 17 completed (11248.947 ms) ====== [2025-11-06T00:43:45.523Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-06T00:43:45.523Z] GC before operation: completed in 228.504 ms, heap usage 203.217 MB -> 68.844 MB. [2025-11-06T00:43:58.783Z] ====== als (apache-spark) [default], iteration 18 completed (12146.966 ms) ====== [2025-11-06T00:43:58.783Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-06T00:43:58.783Z] GC before operation: completed in 151.889 ms, heap usage 324.457 MB -> 69.788 MB. [2025-11-06T00:44:12.078Z] ====== als (apache-spark) [default], iteration 19 completed (12319.714 ms) ====== [2025-11-06T00:44:12.078Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-06T00:44:12.078Z] GC before operation: completed in 197.377 ms, heap usage 351.428 MB -> 69.568 MB. [2025-11-06T00:44:25.748Z] ====== als (apache-spark) [default], iteration 20 completed (12679.442 ms) ====== [2025-11-06T00:44:25.748Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-06T00:44:25.748Z] GC before operation: completed in 322.075 ms, heap usage 548.075 MB -> 70.185 MB. [2025-11-06T00:44:39.129Z] ====== als (apache-spark) [default], iteration 21 completed (14137.012 ms) ====== [2025-11-06T00:44:39.129Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-06T00:44:39.129Z] GC before operation: completed in 203.767 ms, heap usage 452.598 MB -> 70.346 MB. [2025-11-06T00:44:52.481Z] ====== als (apache-spark) [default], iteration 22 completed (12867.303 ms) ====== [2025-11-06T00:44:52.481Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-06T00:44:52.481Z] GC before operation: completed in 186.078 ms, heap usage 114.718 MB -> 70.179 MB. [2025-11-06T00:45:06.218Z] ====== als (apache-spark) [default], iteration 23 completed (11968.609 ms) ====== [2025-11-06T00:45:06.218Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-06T00:45:06.218Z] GC before operation: completed in 186.421 ms, heap usage 189.445 MB -> 70.464 MB. [2025-11-06T00:45:19.473Z] ====== als (apache-spark) [default], iteration 24 completed (12411.793 ms) ====== [2025-11-06T00:45:19.473Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-06T00:45:19.473Z] GC before operation: completed in 180.257 ms, heap usage 208.325 MB -> 70.678 MB. [2025-11-06T00:45:32.705Z] ====== als (apache-spark) [default], iteration 25 completed (13510.513 ms) ====== [2025-11-06T00:45:32.705Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-06T00:45:32.705Z] GC before operation: completed in 173.548 ms, heap usage 231.095 MB -> 71.106 MB. [2025-11-06T00:45:48.407Z] ====== als (apache-spark) [default], iteration 26 completed (15889.103 ms) ====== [2025-11-06T00:45:48.407Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-06T00:45:48.407Z] GC before operation: completed in 190.901 ms, heap usage 335.975 MB -> 71.543 MB. [2025-11-06T00:46:03.937Z] ====== als (apache-spark) [default], iteration 27 completed (13286.997 ms) ====== [2025-11-06T00:46:03.937Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-06T00:46:03.937Z] GC before operation: completed in 258.564 ms, heap usage 313.227 MB -> 71.239 MB. [2025-11-06T00:46:19.500Z] ====== als (apache-spark) [default], iteration 28 completed (15124.243 ms) ====== [2025-11-06T00:46:19.500Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-06T00:46:19.500Z] GC before operation: completed in 187.642 ms, heap usage 501.502 MB -> 71.695 MB. [2025-11-06T00:46:30.833Z] ====== als (apache-spark) [default], iteration 29 completed (12654.140 ms) ====== [2025-11-06T00:46:31.559Z] ----------------------------------- [2025-11-06T00:46:31.559Z] renaissance-als_0_PASSED [2025-11-06T00:46:31.559Z] ----------------------------------- [2025-11-06T00:46:31.559Z] [2025-11-06T00:46:31.559Z] TEST TEARDOWN: [2025-11-06T00:46:31.559Z] Nothing to be done for teardown. [2025-11-06T00:46:31.559Z] renaissance-als_0 Finish Time: Thu Nov 6 00:46:31 2025 Epoch Time (ms): 1762389991298