[2025-07-01T10:52:22.696Z] Running test renaissance-als_0 ... [2025-07-01T10:52:22.696Z] =============================================== [2025-07-01T10:52:22.696Z] renaissance-als_0 Start Time: Tue Jul 1 10:52:22 2025 Epoch Time (ms): 1751367142501 [2025-07-01T10:52:22.696Z] variation: NoOptions [2025-07-01T10:52:22.696Z] JVM_OPTIONS: [2025-07-01T10:52:22.696Z] { \ [2025-07-01T10:52:22.696Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T10:52:22.696Z] echo "Nothing to be done for setup."; \ [2025-07-01T10:52:22.696Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17513670376690/renaissance-als_0"; \ [2025-07-01T10:52:22.696Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17513670376690/renaissance-als_0"; \ [2025-07-01T10:52:22.696Z] echo ""; echo "TESTING:"; \ [2025-07-01T10:52:22.696Z] "/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_17513670376690/renaissance-als_0"/als.json" als; \ [2025-07-01T10:52:22.696Z] 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_17513670376690/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T10:52:22.696Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T10:52:22.696Z] echo "Nothing to be done for teardown."; \ [2025-07-01T10:52:22.696Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17513670376690/TestTargetResult"; [2025-07-01T10:52:22.696Z] [2025-07-01T10:52:22.696Z] TEST SETUP: [2025-07-01T10:52:22.696Z] Nothing to be done for setup. [2025-07-01T10:52:22.696Z] [2025-07-01T10:52:22.696Z] TESTING: [2025-07-01T10:52:29.827Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T10:52:34.479Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-01T10:52:34.479Z] GC before operation: completed in 64.756 ms, heap usage 89.488 MB -> 35.157 MB. [2025-07-01T10:53:05.323Z] ====== als (apache-spark) [default], iteration 0 completed (26354.603 ms) ====== [2025-07-01T10:53:05.323Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-01T10:53:05.323Z] GC before operation: completed in 145.196 ms, heap usage 387.362 MB -> 62.203 MB. [2025-07-01T10:53:13.939Z] ====== als (apache-spark) [default], iteration 1 completed (11633.860 ms) ====== [2025-07-01T10:53:13.939Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-01T10:53:13.939Z] GC before operation: completed in 129.128 ms, heap usage 141.710 MB -> 62.910 MB. [2025-07-01T10:53:24.195Z] ====== als (apache-spark) [default], iteration 2 completed (9550.358 ms) ====== [2025-07-01T10:53:24.195Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-01T10:53:24.195Z] GC before operation: completed in 115.112 ms, heap usage 398.477 MB -> 63.762 MB. [2025-07-01T10:53:35.175Z] ====== als (apache-spark) [default], iteration 3 completed (9720.123 ms) ====== [2025-07-01T10:53:35.176Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-01T10:53:35.176Z] GC before operation: completed in 170.542 ms, heap usage 201.744 MB -> 63.992 MB. [2025-07-01T10:53:43.780Z] ====== als (apache-spark) [default], iteration 4 completed (8976.361 ms) ====== [2025-07-01T10:53:43.780Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-01T10:53:43.780Z] GC before operation: completed in 134.009 ms, heap usage 384.012 MB -> 64.720 MB. [2025-07-01T10:53:52.558Z] ====== als (apache-spark) [default], iteration 5 completed (8828.078 ms) ====== [2025-07-01T10:53:52.558Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-01T10:53:52.558Z] GC before operation: completed in 136.378 ms, heap usage 173.966 MB -> 65.052 MB. [2025-07-01T10:54:01.281Z] ====== als (apache-spark) [default], iteration 6 completed (8908.736 ms) ====== [2025-07-01T10:54:01.281Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-01T10:54:02.092Z] GC before operation: completed in 139.156 ms, heap usage 226.038 MB -> 65.787 MB. [2025-07-01T10:54:10.710Z] ====== als (apache-spark) [default], iteration 7 completed (8424.056 ms) ====== [2025-07-01T10:54:10.710Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-01T10:54:10.710Z] GC before operation: completed in 125.309 ms, heap usage 188.592 MB -> 66.131 MB. [2025-07-01T10:54:20.936Z] ====== als (apache-spark) [default], iteration 8 completed (9509.291 ms) ====== [2025-07-01T10:54:20.936Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-01T10:54:20.936Z] GC before operation: completed in 197.272 ms, heap usage 513.564 MB -> 67.184 MB. [2025-07-01T10:54:29.567Z] ====== als (apache-spark) [default], iteration 9 completed (8609.555 ms) ====== [2025-07-01T10:54:29.567Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-01T10:54:29.567Z] GC before operation: completed in 156.174 ms, heap usage 356.198 MB -> 67.271 MB. [2025-07-01T10:54:38.478Z] ====== als (apache-spark) [default], iteration 10 completed (8361.335 ms) ====== [2025-07-01T10:54:38.478Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-01T10:54:38.478Z] GC before operation: completed in 125.510 ms, heap usage 209.831 MB -> 67.143 MB. [2025-07-01T10:54:47.631Z] ====== als (apache-spark) [default], iteration 11 completed (8078.976 ms) ====== [2025-07-01T10:54:47.631Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-01T10:54:47.631Z] GC before operation: completed in 160.153 ms, heap usage 355.515 MB -> 67.637 MB. [2025-07-01T10:54:55.046Z] ====== als (apache-spark) [default], iteration 12 completed (8479.722 ms) ====== [2025-07-01T10:54:55.046Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-01T10:54:55.853Z] GC before operation: completed in 152.524 ms, heap usage 225.854 MB -> 67.844 MB. [2025-07-01T10:55:06.117Z] ====== als (apache-spark) [default], iteration 13 completed (9435.352 ms) ====== [2025-07-01T10:55:06.117Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-01T10:55:06.117Z] GC before operation: completed in 199.037 ms, heap usage 202.591 MB -> 67.969 MB. [2025-07-01T10:55:14.735Z] ====== als (apache-spark) [default], iteration 14 completed (9138.532 ms) ====== [2025-07-01T10:55:14.735Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-01T10:55:15.541Z] GC before operation: completed in 144.771 ms, heap usage 190.457 MB -> 68.148 MB. [2025-07-01T10:55:24.191Z] ====== als (apache-spark) [default], iteration 15 completed (8706.229 ms) ====== [2025-07-01T10:55:24.191Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-01T10:55:24.191Z] GC before operation: completed in 156.760 ms, heap usage 120.523 MB -> 68.974 MB. [2025-07-01T10:55:34.463Z] ====== als (apache-spark) [default], iteration 16 completed (8961.824 ms) ====== [2025-07-01T10:55:34.463Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-01T10:55:34.463Z] GC before operation: completed in 149.225 ms, heap usage 268.132 MB -> 68.860 MB. [2025-07-01T10:55:42.139Z] ====== als (apache-spark) [default], iteration 17 completed (8260.907 ms) ====== [2025-07-01T10:55:42.139Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-01T10:55:42.139Z] GC before operation: completed in 182.446 ms, heap usage 281.348 MB -> 69.121 MB. [2025-07-01T10:55:50.718Z] ====== als (apache-spark) [default], iteration 18 completed (8639.516 ms) ====== [2025-07-01T10:55:50.718Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-01T10:55:50.718Z] GC before operation: completed in 144.251 ms, heap usage 333.123 MB -> 69.510 MB. [2025-07-01T10:56:00.953Z] ====== als (apache-spark) [default], iteration 19 completed (8837.053 ms) ====== [2025-07-01T10:56:00.953Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-01T10:56:00.953Z] GC before operation: completed in 178.076 ms, heap usage 309.183 MB -> 69.758 MB. [2025-07-01T10:56:09.610Z] ====== als (apache-spark) [default], iteration 20 completed (8999.520 ms) ====== [2025-07-01T10:56:09.610Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-01T10:56:09.610Z] GC before operation: completed in 236.896 ms, heap usage 331.604 MB -> 70.006 MB. [2025-07-01T10:56:19.833Z] ====== als (apache-spark) [default], iteration 21 completed (9162.150 ms) ====== [2025-07-01T10:56:19.833Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-01T10:56:19.833Z] GC before operation: completed in 141.396 ms, heap usage 265.183 MB -> 70.109 MB. [2025-07-01T10:56:27.025Z] ====== als (apache-spark) [default], iteration 22 completed (7829.520 ms) ====== [2025-07-01T10:56:27.025Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-01T10:56:27.025Z] GC before operation: completed in 157.530 ms, heap usage 119.184 MB -> 70.083 MB. [2025-07-01T10:56:34.708Z] ====== als (apache-spark) [default], iteration 23 completed (6222.104 ms) ====== [2025-07-01T10:56:34.708Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-01T10:56:34.708Z] GC before operation: completed in 140.710 ms, heap usage 332.661 MB -> 70.837 MB. [2025-07-01T10:56:40.539Z] ====== als (apache-spark) [default], iteration 24 completed (6845.572 ms) ====== [2025-07-01T10:56:40.539Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-01T10:56:41.344Z] GC before operation: completed in 134.808 ms, heap usage 337.568 MB -> 71.062 MB. [2025-07-01T10:56:50.016Z] ====== als (apache-spark) [default], iteration 25 completed (7836.115 ms) ====== [2025-07-01T10:56:50.016Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-01T10:56:50.016Z] GC before operation: completed in 176.294 ms, heap usage 553.344 MB -> 71.662 MB. [2025-07-01T10:56:57.157Z] ====== als (apache-spark) [default], iteration 26 completed (7510.231 ms) ====== [2025-07-01T10:56:57.157Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-01T10:56:57.157Z] GC before operation: completed in 129.205 ms, heap usage 467.144 MB -> 71.854 MB. [2025-07-01T10:57:04.285Z] ====== als (apache-spark) [default], iteration 27 completed (6451.794 ms) ====== [2025-07-01T10:57:04.285Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-01T10:57:04.285Z] GC before operation: completed in 134.533 ms, heap usage 161.126 MB -> 71.732 MB. [2025-07-01T10:57:11.446Z] ====== als (apache-spark) [default], iteration 28 completed (7595.713 ms) ====== [2025-07-01T10:57:11.446Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-01T10:57:11.446Z] GC before operation: completed in 148.224 ms, heap usage 398.168 MB -> 71.666 MB. [2025-07-01T10:57:18.582Z] ====== als (apache-spark) [default], iteration 29 completed (7121.548 ms) ====== [2025-07-01T10:57:20.244Z] ----------------------------------- [2025-07-01T10:57:20.244Z] renaissance-als_0_PASSED [2025-07-01T10:57:20.244Z] ----------------------------------- [2025-07-01T10:57:20.244Z] [2025-07-01T10:57:20.244Z] TEST TEARDOWN: [2025-07-01T10:57:20.244Z] Nothing to be done for teardown. [2025-07-01T10:57:20.244Z] renaissance-als_0 Finish Time: Tue Jul 1 10:57:19 2025 Epoch Time (ms): 1751367439382