[2025-06-29T20:52:19.524Z] Running test renaissance-als_0 ... [2025-06-29T20:52:19.524Z] =============================================== [2025-06-29T20:52:19.524Z] renaissance-als_0 Start Time: Sun Jun 29 20:52:19 2025 Epoch Time (ms): 1751230339090 [2025-06-29T20:52:19.524Z] variation: NoOptions [2025-06-29T20:52:19.524Z] JVM_OPTIONS: [2025-06-29T20:52:19.524Z] { \ [2025-06-29T20:52:19.524Z] echo ""; echo "TEST SETUP:"; \ [2025-06-29T20:52:19.524Z] echo "Nothing to be done for setup."; \ [2025-06-29T20:52:19.524Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17512298367162/renaissance-als_0"; \ [2025-06-29T20:52:19.524Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17512298367162/renaissance-als_0"; \ [2025-06-29T20:52:19.524Z] echo ""; echo "TESTING:"; \ [2025-06-29T20:52:19.524Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-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_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17512298367162/renaissance-als_0"/als.json" als; \ [2025-06-29T20:52:19.524Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17512298367162/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-29T20:52:19.524Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-29T20:52:19.524Z] echo "Nothing to be done for teardown."; \ [2025-06-29T20:52:19.524Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17512298367162/TestTargetResult"; [2025-06-29T20:52:19.524Z] [2025-06-29T20:52:19.524Z] TEST SETUP: [2025-06-29T20:52:19.524Z] Nothing to be done for setup. [2025-06-29T20:52:19.524Z] [2025-06-29T20:52:19.524Z] TESTING: [2025-06-29T20:52:45.388Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-29T20:53:05.022Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-29T20:53:05.022Z] GC before operation: completed in 182.562 ms, heap usage 95.000 MB -> 35.186 MB. [2025-06-29T20:55:10.145Z] ====== als (apache-spark) [default], iteration 0 completed (117985.100 ms) ====== [2025-06-29T20:55:10.145Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-29T20:55:10.145Z] GC before operation: completed in 656.730 ms, heap usage 121.105 MB -> 61.949 MB. [2025-06-29T20:55:59.813Z] ====== als (apache-spark) [default], iteration 1 completed (50024.288 ms) ====== [2025-06-29T20:55:59.813Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-29T20:55:59.813Z] GC before operation: completed in 577.335 ms, heap usage 552.343 MB -> 63.085 MB. [2025-06-29T20:56:48.692Z] ====== als (apache-spark) [default], iteration 2 completed (43020.141 ms) ====== [2025-06-29T20:56:48.692Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-29T20:56:48.692Z] GC before operation: completed in 449.386 ms, heap usage 198.380 MB -> 63.207 MB. [2025-06-29T20:57:30.779Z] ====== als (apache-spark) [default], iteration 3 completed (44826.723 ms) ====== [2025-06-29T20:57:30.779Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-29T20:57:30.779Z] GC before operation: completed in 592.549 ms, heap usage 308.176 MB -> 63.881 MB. [2025-06-29T20:58:13.354Z] ====== als (apache-spark) [default], iteration 4 completed (41388.847 ms) ====== [2025-06-29T20:58:13.355Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-29T20:58:13.355Z] GC before operation: completed in 395.671 ms, heap usage 244.768 MB -> 64.061 MB. [2025-06-29T20:59:02.315Z] ====== als (apache-spark) [default], iteration 5 completed (41653.010 ms) ====== [2025-06-29T20:59:02.315Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-29T20:59:02.315Z] GC before operation: completed in 679.971 ms, heap usage 280.146 MB -> 65.202 MB. [2025-06-29T20:59:38.269Z] ====== als (apache-spark) [default], iteration 6 completed (39989.999 ms) ====== [2025-06-29T20:59:38.269Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-29T20:59:38.269Z] GC before operation: completed in 589.852 ms, heap usage 545.019 MB -> 65.973 MB. [2025-06-29T21:00:20.244Z] ====== als (apache-spark) [default], iteration 7 completed (39458.575 ms) ====== [2025-06-29T21:00:20.245Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-29T21:00:20.245Z] GC before operation: completed in 774.826 ms, heap usage 189.428 MB -> 65.990 MB. [2025-06-29T21:01:02.564Z] ====== als (apache-spark) [default], iteration 8 completed (38813.453 ms) ====== [2025-06-29T21:01:02.564Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-29T21:01:02.565Z] GC before operation: completed in 590.784 ms, heap usage 502.847 MB -> 66.962 MB. [2025-06-29T21:01:38.100Z] ====== als (apache-spark) [default], iteration 9 completed (36585.266 ms) ====== [2025-06-29T21:01:38.100Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-29T21:01:38.100Z] GC before operation: completed in 592.006 ms, heap usage 549.157 MB -> 67.272 MB. [2025-06-29T21:02:19.441Z] ====== als (apache-spark) [default], iteration 10 completed (35703.682 ms) ====== [2025-06-29T21:02:19.441Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-29T21:02:19.441Z] GC before operation: completed in 549.775 ms, heap usage 477.487 MB -> 67.523 MB. [2025-06-29T21:02:55.509Z] ====== als (apache-spark) [default], iteration 11 completed (35600.368 ms) ====== [2025-06-29T21:02:55.509Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-29T21:02:55.509Z] GC before operation: completed in 647.745 ms, heap usage 250.375 MB -> 67.416 MB. [2025-06-29T21:03:37.397Z] ====== als (apache-spark) [default], iteration 12 completed (38341.567 ms) ====== [2025-06-29T21:03:37.398Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-29T21:03:37.398Z] GC before operation: completed in 612.415 ms, heap usage 367.783 MB -> 67.819 MB. [2025-06-29T21:04:13.334Z] ====== als (apache-spark) [default], iteration 13 completed (36328.672 ms) ====== [2025-06-29T21:04:13.334Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-29T21:04:13.334Z] GC before operation: completed in 524.226 ms, heap usage 200.178 MB -> 67.810 MB. [2025-06-29T21:04:50.559Z] ====== als (apache-spark) [default], iteration 14 completed (35590.332 ms) ====== [2025-06-29T21:04:50.559Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-29T21:04:50.559Z] GC before operation: completed in 730.895 ms, heap usage 243.693 MB -> 68.176 MB. [2025-06-29T21:05:26.573Z] ====== als (apache-spark) [default], iteration 15 completed (37440.850 ms) ====== [2025-06-29T21:05:26.573Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-29T21:05:26.573Z] GC before operation: completed in 607.511 ms, heap usage 350.125 MB -> 68.567 MB. [2025-06-29T21:06:08.118Z] ====== als (apache-spark) [default], iteration 16 completed (37140.603 ms) ====== [2025-06-29T21:06:08.118Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-29T21:06:08.118Z] GC before operation: completed in 680.791 ms, heap usage 165.129 MB -> 68.628 MB. [2025-06-29T21:06:44.673Z] ====== als (apache-spark) [default], iteration 17 completed (37205.475 ms) ====== [2025-06-29T21:06:44.673Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-29T21:06:44.673Z] GC before operation: completed in 777.387 ms, heap usage 555.335 MB -> 69.388 MB. [2025-06-29T21:07:27.166Z] ====== als (apache-spark) [default], iteration 18 completed (38469.588 ms) ====== [2025-06-29T21:07:27.166Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-29T21:07:27.166Z] GC before operation: completed in 649.355 ms, heap usage 497.150 MB -> 69.671 MB. [2025-06-29T21:08:02.908Z] ====== als (apache-spark) [default], iteration 19 completed (37387.408 ms) ====== [2025-06-29T21:08:02.908Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-29T21:08:03.687Z] GC before operation: completed in 813.548 ms, heap usage 501.543 MB -> 69.825 MB. [2025-06-29T21:08:45.625Z] ====== als (apache-spark) [default], iteration 20 completed (39669.883 ms) ====== [2025-06-29T21:08:45.625Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-29T21:08:45.625Z] GC before operation: completed in 624.841 ms, heap usage 153.489 MB -> 69.354 MB. [2025-06-29T21:09:28.009Z] ====== als (apache-spark) [default], iteration 21 completed (38509.666 ms) ====== [2025-06-29T21:09:28.009Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-29T21:09:28.009Z] GC before operation: completed in 720.878 ms, heap usage 467.843 MB -> 70.342 MB. [2025-06-29T21:10:03.987Z] ====== als (apache-spark) [default], iteration 22 completed (37664.942 ms) ====== [2025-06-29T21:10:03.987Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-29T21:10:03.987Z] GC before operation: completed in 723.000 ms, heap usage 467.906 MB -> 70.620 MB. [2025-06-29T21:10:46.052Z] ====== als (apache-spark) [default], iteration 23 completed (39713.284 ms) ====== [2025-06-29T21:10:46.052Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-29T21:10:46.052Z] GC before operation: completed in 748.631 ms, heap usage 502.504 MB -> 70.909 MB. [2025-06-29T21:11:27.959Z] ====== als (apache-spark) [default], iteration 24 completed (38581.696 ms) ====== [2025-06-29T21:11:27.959Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-29T21:11:27.959Z] GC before operation: completed in 716.339 ms, heap usage 474.937 MB -> 71.136 MB. [2025-06-29T21:12:03.954Z] ====== als (apache-spark) [default], iteration 25 completed (36720.425 ms) ====== [2025-06-29T21:12:03.954Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-29T21:12:03.954Z] GC before operation: completed in 671.533 ms, heap usage 503.591 MB -> 71.434 MB. [2025-06-29T21:12:40.423Z] ====== als (apache-spark) [default], iteration 26 completed (35420.035 ms) ====== [2025-06-29T21:12:40.423Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-29T21:12:40.423Z] GC before operation: completed in 558.501 ms, heap usage 288.375 MB -> 71.328 MB. [2025-06-29T21:13:22.237Z] ====== als (apache-spark) [default], iteration 27 completed (36978.549 ms) ====== [2025-06-29T21:13:22.237Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-29T21:13:22.237Z] GC before operation: completed in 628.256 ms, heap usage 209.819 MB -> 71.555 MB. [2025-06-29T21:13:58.317Z] ====== als (apache-spark) [default], iteration 28 completed (36319.720 ms) ====== [2025-06-29T21:13:58.317Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-29T21:13:58.317Z] GC before operation: completed in 842.156 ms, heap usage 474.987 MB -> 71.724 MB. [2025-06-29T21:14:34.520Z] ====== als (apache-spark) [default], iteration 29 completed (34990.713 ms) ====== [2025-06-29T21:14:35.288Z] ----------------------------------- [2025-06-29T21:14:35.288Z] renaissance-als_0_PASSED [2025-06-29T21:14:35.288Z] ----------------------------------- [2025-06-29T21:14:35.288Z] [2025-06-29T21:14:35.288Z] TEST TEARDOWN: [2025-06-29T21:14:35.288Z] Nothing to be done for teardown. [2025-06-29T21:14:35.288Z] renaissance-als_0 Finish Time: Sun Jun 29 21:14:34 2025 Epoch Time (ms): 1751231674866