[2025-05-28T22:01:28.541Z] Running test renaissance-als_0 ... [2025-05-28T22:01:28.541Z] =============================================== [2025-05-28T22:01:28.541Z] renaissance-als_0 Start Time: Wed May 28 22:01:27 2025 Epoch Time (ms): 1748469687993 [2025-05-28T22:01:28.541Z] variation: NoOptions [2025-05-28T22:01:28.541Z] JVM_OPTIONS: [2025-05-28T22:01:28.541Z] { \ [2025-05-28T22:01:28.541Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T22:01:28.541Z] echo "Nothing to be done for setup."; \ [2025-05-28T22:01:28.541Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484696472907/renaissance-als_0"; \ [2025-05-28T22:01:28.541Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484696472907/renaissance-als_0"; \ [2025-05-28T22:01:28.542Z] echo ""; echo "TESTING:"; \ [2025-05-28T22:01:28.542Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484696472907/renaissance-als_0"/als.json" als; \ [2025-05-28T22:01:28.542Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484696472907/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T22:01:28.542Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T22:01:28.542Z] echo "Nothing to be done for teardown."; \ [2025-05-28T22:01:28.542Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17484696472907/TestTargetResult"; [2025-05-28T22:01:28.542Z] [2025-05-28T22:01:28.542Z] TEST SETUP: [2025-05-28T22:01:28.542Z] Nothing to be done for setup. [2025-05-28T22:01:28.542Z] [2025-05-28T22:01:28.542Z] TESTING: [2025-05-28T22:01:44.356Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-28T22:01:47.270Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-28T22:01:47.595Z] GC before operation: completed in 73.100 ms, heap usage 161.857 MB -> 34.963 MB. [2025-05-28T22:02:20.827Z] ====== als (apache-spark) [default], iteration 0 completed (30808.282 ms) ====== [2025-05-28T22:02:20.827Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-28T22:02:20.827Z] GC before operation: completed in 122.635 ms, heap usage 210.642 MB -> 61.459 MB. [2025-05-28T22:02:36.601Z] ====== als (apache-spark) [default], iteration 1 completed (16824.129 ms) ====== [2025-05-28T22:02:36.601Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-28T22:02:36.601Z] GC before operation: completed in 116.815 ms, heap usage 377.363 MB -> 62.674 MB. [2025-05-28T22:02:52.503Z] ====== als (apache-spark) [default], iteration 2 completed (15575.604 ms) ====== [2025-05-28T22:02:52.503Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-28T22:02:52.838Z] GC before operation: completed in 190.859 ms, heap usage 219.615 MB -> 62.924 MB. [2025-05-28T22:03:11.892Z] ====== als (apache-spark) [default], iteration 3 completed (15928.844 ms) ====== [2025-05-28T22:03:11.892Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-28T22:03:11.892Z] GC before operation: completed in 129.460 ms, heap usage 117.656 MB -> 63.512 MB. [2025-05-28T22:03:24.935Z] ====== als (apache-spark) [default], iteration 4 completed (15390.207 ms) ====== [2025-05-28T22:03:24.935Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-28T22:03:24.935Z] GC before operation: completed in 114.985 ms, heap usage 130.632 MB -> 64.069 MB. [2025-05-28T22:03:40.691Z] ====== als (apache-spark) [default], iteration 5 completed (14118.410 ms) ====== [2025-05-28T22:03:40.691Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-28T22:03:40.691Z] GC before operation: completed in 123.425 ms, heap usage 322.078 MB -> 64.902 MB. [2025-05-28T22:03:56.446Z] ====== als (apache-spark) [default], iteration 6 completed (14159.909 ms) ====== [2025-05-28T22:03:56.446Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-28T22:03:56.446Z] GC before operation: completed in 166.713 ms, heap usage 157.862 MB -> 65.392 MB. [2025-05-28T22:04:09.532Z] ====== als (apache-spark) [default], iteration 7 completed (13793.453 ms) ====== [2025-05-28T22:04:09.532Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-28T22:04:09.532Z] GC before operation: completed in 167.079 ms, heap usage 715.836 MB -> 66.443 MB. [2025-05-28T22:04:22.625Z] ====== als (apache-spark) [default], iteration 8 completed (14066.033 ms) ====== [2025-05-28T22:04:22.625Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-28T22:04:22.950Z] GC before operation: completed in 129.123 ms, heap usage 130.506 MB -> 66.347 MB. [2025-05-28T22:04:38.718Z] ====== als (apache-spark) [default], iteration 9 completed (13893.644 ms) ====== [2025-05-28T22:04:38.718Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-28T22:04:38.718Z] GC before operation: completed in 124.979 ms, heap usage 125.903 MB -> 66.626 MB. [2025-05-28T22:04:51.763Z] ====== als (apache-spark) [default], iteration 10 completed (13959.396 ms) ====== [2025-05-28T22:04:51.764Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-28T22:04:51.764Z] GC before operation: completed in 133.082 ms, heap usage 507.140 MB -> 67.312 MB. [2025-05-28T22:05:07.576Z] ====== als (apache-spark) [default], iteration 11 completed (13492.337 ms) ====== [2025-05-28T22:05:07.576Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-28T22:05:07.576Z] GC before operation: completed in 145.509 ms, heap usage 266.623 MB -> 67.232 MB. [2025-05-28T22:05:20.707Z] ====== als (apache-spark) [default], iteration 12 completed (13808.710 ms) ====== [2025-05-28T22:05:20.707Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-28T22:05:20.707Z] GC before operation: completed in 128.392 ms, heap usage 197.024 MB -> 67.305 MB. [2025-05-28T22:05:36.593Z] ====== als (apache-spark) [default], iteration 13 completed (13967.936 ms) ====== [2025-05-28T22:05:36.593Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-28T22:05:36.593Z] GC before operation: completed in 114.905 ms, heap usage 103.715 MB -> 67.109 MB. [2025-05-28T22:05:49.619Z] ====== als (apache-spark) [default], iteration 14 completed (14165.271 ms) ====== [2025-05-28T22:05:49.619Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-28T22:05:49.619Z] GC before operation: completed in 124.013 ms, heap usage 368.182 MB -> 68.056 MB. [2025-05-28T22:06:02.643Z] ====== als (apache-spark) [default], iteration 15 completed (13803.653 ms) ====== [2025-05-28T22:06:02.643Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-28T22:06:02.643Z] GC before operation: completed in 114.331 ms, heap usage 214.405 MB -> 68.026 MB. [2025-05-28T22:06:18.426Z] ====== als (apache-spark) [default], iteration 16 completed (13717.286 ms) ====== [2025-05-28T22:06:18.426Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-28T22:06:18.426Z] GC before operation: completed in 124.647 ms, heap usage 186.522 MB -> 68.336 MB. [2025-05-28T22:06:31.468Z] ====== als (apache-spark) [default], iteration 17 completed (13527.779 ms) ====== [2025-05-28T22:06:31.468Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-28T22:06:31.468Z] GC before operation: completed in 158.355 ms, heap usage 173.784 MB -> 68.509 MB. [2025-05-28T22:06:47.242Z] ====== als (apache-spark) [default], iteration 18 completed (13748.206 ms) ====== [2025-05-28T22:06:47.242Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-28T22:06:47.242Z] GC before operation: completed in 118.195 ms, heap usage 188.467 MB -> 68.847 MB. [2025-05-28T22:07:00.385Z] ====== als (apache-spark) [default], iteration 19 completed (13746.506 ms) ====== [2025-05-28T22:07:00.385Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-28T22:07:00.385Z] GC before operation: completed in 116.268 ms, heap usage 350.355 MB -> 69.355 MB. [2025-05-28T22:07:16.201Z] ====== als (apache-spark) [default], iteration 20 completed (14476.296 ms) ====== [2025-05-28T22:07:16.201Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-28T22:07:16.201Z] GC before operation: completed in 117.743 ms, heap usage 364.219 MB -> 69.597 MB. [2025-05-28T22:07:29.260Z] ====== als (apache-spark) [default], iteration 21 completed (14257.417 ms) ====== [2025-05-28T22:07:29.260Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-28T22:07:29.260Z] GC before operation: completed in 119.016 ms, heap usage 370.798 MB -> 69.909 MB. [2025-05-28T22:07:45.034Z] ====== als (apache-spark) [default], iteration 22 completed (13770.939 ms) ====== [2025-05-28T22:07:45.034Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-28T22:07:45.034Z] GC before operation: completed in 133.442 ms, heap usage 367.851 MB -> 70.165 MB. [2025-05-28T22:07:58.213Z] ====== als (apache-spark) [default], iteration 23 completed (13575.511 ms) ====== [2025-05-28T22:07:58.213Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-28T22:07:58.213Z] GC before operation: completed in 138.371 ms, heap usage 204.990 MB -> 70.165 MB. [2025-05-28T22:08:11.263Z] ====== als (apache-spark) [default], iteration 24 completed (14012.567 ms) ====== [2025-05-28T22:08:11.263Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-28T22:08:11.263Z] GC before operation: completed in 126.421 ms, heap usage 114.072 MB -> 70.023 MB. [2025-05-28T22:08:27.086Z] ====== als (apache-spark) [default], iteration 25 completed (13577.533 ms) ====== [2025-05-28T22:08:27.086Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-28T22:08:27.086Z] GC before operation: completed in 124.938 ms, heap usage 225.909 MB -> 70.809 MB. [2025-05-28T22:08:40.114Z] ====== als (apache-spark) [default], iteration 26 completed (13807.416 ms) ====== [2025-05-28T22:08:40.114Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-28T22:08:40.114Z] GC before operation: completed in 125.217 ms, heap usage 215.342 MB -> 70.977 MB. [2025-05-28T22:08:53.211Z] ====== als (apache-spark) [default], iteration 27 completed (13733.106 ms) ====== [2025-05-28T22:08:53.211Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-28T22:08:53.565Z] GC before operation: completed in 131.002 ms, heap usage 191.748 MB -> 71.296 MB. [2025-05-28T22:09:09.414Z] ====== als (apache-spark) [default], iteration 28 completed (13823.672 ms) ====== [2025-05-28T22:09:09.414Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-28T22:09:09.414Z] GC before operation: completed in 128.673 ms, heap usage 508.789 MB -> 72.034 MB. [2025-05-28T22:09:22.461Z] ====== als (apache-spark) [default], iteration 29 completed (13324.762 ms) ====== [2025-05-28T22:09:22.461Z] ----------------------------------- [2025-05-28T22:09:22.461Z] renaissance-als_0_PASSED [2025-05-28T22:09:22.461Z] ----------------------------------- [2025-05-28T22:09:22.461Z] [2025-05-28T22:09:22.461Z] TEST TEARDOWN: [2025-05-28T22:09:22.461Z] Nothing to be done for teardown. [2025-05-28T22:09:22.461Z] renaissance-als_0 Finish Time: Wed May 28 22:09:21 2025 Epoch Time (ms): 1748470161405