[2025-08-30T02:42:17.957Z] Running test renaissance-als_0 ... [2025-08-30T02:42:17.957Z] =============================================== [2025-08-30T02:42:17.957Z] renaissance-als_0 Start Time: Sat Aug 30 02:42:17 2025 Epoch Time (ms): 1756521737166 [2025-08-30T02:42:17.957Z] variation: NoOptions [2025-08-30T02:42:17.957Z] JVM_OPTIONS: [2025-08-30T02:42:17.957Z] { \ [2025-08-30T02:42:17.957Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T02:42:17.957Z] echo "Nothing to be done for setup."; \ [2025-08-30T02:42:17.957Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_1756521736814/renaissance-als_0"; \ [2025-08-30T02:42:17.957Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_1756521736814/renaissance-als_0"; \ [2025-08-30T02:42:17.957Z] echo ""; echo "TESTING:"; \ [2025-08-30T02:42:17.957Z] "/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_1756521736814/renaissance-als_0"/als.json" als; \ [2025-08-30T02:42:17.957Z] 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_1756521736814/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T02:42:17.957Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T02:42:17.957Z] echo "Nothing to be done for teardown."; \ [2025-08-30T02:42:17.957Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_1756521736814/TestTargetResult"; [2025-08-30T02:42:17.957Z] [2025-08-30T02:42:17.957Z] TEST SETUP: [2025-08-30T02:42:17.957Z] Nothing to be done for setup. [2025-08-30T02:42:17.957Z] [2025-08-30T02:42:17.957Z] TESTING: [2025-08-30T02:42:40.064Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-30T02:42:51.887Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-30T02:42:51.887Z] GC before operation: completed in 108.753 ms, heap usage 167.500 MB -> 35.183 MB. [2025-08-30T02:43:40.643Z] ====== als (apache-spark) [default], iteration 0 completed (41786.010 ms) ====== [2025-08-30T02:43:40.643Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-30T02:43:40.643Z] GC before operation: completed in 303.626 ms, heap usage 329.489 MB -> 61.877 MB. [2025-08-30T02:43:59.898Z] ====== als (apache-spark) [default], iteration 1 completed (21703.259 ms) ====== [2025-08-30T02:43:59.898Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-30T02:43:59.898Z] GC before operation: completed in 258.235 ms, heap usage 203.425 MB -> 62.763 MB. [2025-08-30T02:44:22.126Z] ====== als (apache-spark) [default], iteration 2 completed (21126.421 ms) ====== [2025-08-30T02:44:22.126Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-30T02:44:22.126Z] GC before operation: completed in 237.698 ms, heap usage 250.703 MB -> 63.538 MB. [2025-08-30T02:44:39.222Z] ====== als (apache-spark) [default], iteration 3 completed (16989.320 ms) ====== [2025-08-30T02:44:39.222Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-30T02:44:39.222Z] GC before operation: completed in 213.922 ms, heap usage 149.404 MB -> 63.884 MB. [2025-08-30T02:44:58.276Z] ====== als (apache-spark) [default], iteration 4 completed (18726.609 ms) ====== [2025-08-30T02:44:58.276Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-30T02:44:58.276Z] GC before operation: completed in 262.309 ms, heap usage 443.371 MB -> 65.350 MB. [2025-08-30T02:45:14.596Z] ====== als (apache-spark) [default], iteration 5 completed (16419.573 ms) ====== [2025-08-30T02:45:14.596Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-30T02:45:14.596Z] GC before operation: completed in 269.834 ms, heap usage 179.959 MB -> 64.938 MB. [2025-08-30T02:45:30.855Z] ====== als (apache-spark) [default], iteration 6 completed (16066.835 ms) ====== [2025-08-30T02:45:30.855Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-30T02:45:31.652Z] GC before operation: completed in 293.539 ms, heap usage 497.651 MB -> 65.959 MB. [2025-08-30T02:45:48.209Z] ====== als (apache-spark) [default], iteration 7 completed (16174.035 ms) ====== [2025-08-30T02:45:48.209Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-30T02:45:48.209Z] GC before operation: completed in 230.719 ms, heap usage 285.512 MB -> 66.119 MB. [2025-08-30T02:46:07.207Z] ====== als (apache-spark) [default], iteration 8 completed (17361.103 ms) ====== [2025-08-30T02:46:07.208Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-30T02:46:07.208Z] GC before operation: completed in 257.240 ms, heap usage 209.294 MB -> 66.584 MB. [2025-08-30T02:46:23.436Z] ====== als (apache-spark) [default], iteration 9 completed (16094.529 ms) ====== [2025-08-30T02:46:23.436Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-30T02:46:23.436Z] GC before operation: completed in 293.516 ms, heap usage 503.936 MB -> 67.317 MB. [2025-08-30T02:46:42.400Z] ====== als (apache-spark) [default], iteration 10 completed (17184.224 ms) ====== [2025-08-30T02:46:42.401Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-30T02:46:42.401Z] GC before operation: completed in 242.128 ms, heap usage 537.995 MB -> 67.666 MB. [2025-08-30T02:46:56.438Z] ====== als (apache-spark) [default], iteration 11 completed (15231.958 ms) ====== [2025-08-30T02:46:56.438Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-30T02:46:57.234Z] GC before operation: completed in 224.911 ms, heap usage 206.475 MB -> 67.342 MB. [2025-08-30T02:47:13.913Z] ====== als (apache-spark) [default], iteration 12 completed (15677.737 ms) ====== [2025-08-30T02:47:13.913Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-30T02:47:13.913Z] GC before operation: completed in 286.548 ms, heap usage 243.994 MB -> 67.782 MB. [2025-08-30T02:47:30.261Z] ====== als (apache-spark) [default], iteration 13 completed (15214.764 ms) ====== [2025-08-30T02:47:30.261Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-30T02:47:30.261Z] GC before operation: completed in 288.148 ms, heap usage 280.809 MB -> 68.129 MB. [2025-08-30T02:47:46.505Z] ====== als (apache-spark) [default], iteration 14 completed (16188.759 ms) ====== [2025-08-30T02:47:46.505Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-30T02:47:46.505Z] GC before operation: completed in 305.851 ms, heap usage 193.972 MB -> 68.114 MB. [2025-08-30T02:48:02.804Z] ====== als (apache-spark) [default], iteration 15 completed (15429.021 ms) ====== [2025-08-30T02:48:02.804Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-30T02:48:02.804Z] GC before operation: completed in 250.612 ms, heap usage 387.315 MB -> 68.633 MB. [2025-08-30T02:48:19.001Z] ====== als (apache-spark) [default], iteration 16 completed (15324.180 ms) ====== [2025-08-30T02:48:19.001Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-30T02:48:19.001Z] GC before operation: completed in 248.670 ms, heap usage 185.713 MB -> 68.657 MB. [2025-08-30T02:48:35.212Z] ====== als (apache-spark) [default], iteration 17 completed (15931.631 ms) ====== [2025-08-30T02:48:35.212Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-30T02:48:35.212Z] GC before operation: completed in 288.381 ms, heap usage 543.083 MB -> 69.504 MB. [2025-08-30T02:48:51.364Z] ====== als (apache-spark) [default], iteration 18 completed (15844.815 ms) ====== [2025-08-30T02:48:51.364Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-30T02:48:51.364Z] GC before operation: completed in 261.679 ms, heap usage 302.490 MB -> 69.332 MB. [2025-08-30T02:49:07.505Z] ====== als (apache-spark) [default], iteration 19 completed (15932.471 ms) ====== [2025-08-30T02:49:07.505Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-30T02:49:07.505Z] GC before operation: completed in 210.445 ms, heap usage 278.272 MB -> 69.611 MB. [2025-08-30T02:49:23.942Z] ====== als (apache-spark) [default], iteration 20 completed (15517.268 ms) ====== [2025-08-30T02:49:23.942Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-30T02:49:23.942Z] GC before operation: completed in 246.895 ms, heap usage 481.165 MB -> 70.153 MB. [2025-08-30T02:49:40.411Z] ====== als (apache-spark) [default], iteration 21 completed (15551.796 ms) ====== [2025-08-30T02:49:40.411Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-30T02:49:40.411Z] GC before operation: completed in 242.574 ms, heap usage 498.054 MB -> 70.371 MB. [2025-08-30T02:49:57.516Z] ====== als (apache-spark) [default], iteration 22 completed (16119.424 ms) ====== [2025-08-30T02:49:57.516Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-30T02:49:57.516Z] GC before operation: completed in 240.440 ms, heap usage 262.225 MB -> 70.246 MB. [2025-08-30T02:50:14.118Z] ====== als (apache-spark) [default], iteration 23 completed (15812.300 ms) ====== [2025-08-30T02:50:14.118Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-30T02:50:14.118Z] GC before operation: completed in 245.634 ms, heap usage 213.366 MB -> 70.416 MB. [2025-08-30T02:50:30.629Z] ====== als (apache-spark) [default], iteration 24 completed (15709.090 ms) ====== [2025-08-30T02:50:30.629Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-30T02:50:30.629Z] GC before operation: completed in 265.633 ms, heap usage 546.209 MB -> 71.277 MB. [2025-08-30T02:50:47.150Z] ====== als (apache-spark) [default], iteration 25 completed (15533.322 ms) ====== [2025-08-30T02:50:47.150Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-30T02:50:47.150Z] GC before operation: completed in 233.235 ms, heap usage 289.178 MB -> 71.145 MB. [2025-08-30T02:51:01.254Z] ====== als (apache-spark) [default], iteration 26 completed (15218.464 ms) ====== [2025-08-30T02:51:01.254Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-30T02:51:01.254Z] GC before operation: completed in 229.154 ms, heap usage 295.630 MB -> 71.335 MB. [2025-08-30T02:51:17.728Z] ====== als (apache-spark) [default], iteration 27 completed (15787.324 ms) ====== [2025-08-30T02:51:17.728Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-30T02:51:17.728Z] GC before operation: completed in 228.967 ms, heap usage 187.424 MB -> 71.438 MB. [2025-08-30T02:51:34.865Z] ====== als (apache-spark) [default], iteration 28 completed (15920.194 ms) ====== [2025-08-30T02:51:34.865Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-30T02:51:34.865Z] GC before operation: completed in 262.466 ms, heap usage 264.743 MB -> 71.801 MB. [2025-08-30T02:51:51.344Z] ====== als (apache-spark) [default], iteration 29 completed (14670.003 ms) ====== [2025-08-30T02:51:51.344Z] ----------------------------------- [2025-08-30T02:51:51.344Z] renaissance-als_0_PASSED [2025-08-30T02:51:51.344Z] ----------------------------------- [2025-08-30T02:51:51.344Z] [2025-08-30T02:51:51.344Z] TEST TEARDOWN: [2025-08-30T02:51:51.344Z] Nothing to be done for teardown. [2025-08-30T02:51:51.344Z] renaissance-als_0 Finish Time: Sat Aug 30 02:51:49 2025 Epoch Time (ms): 1756522309731