[2026-01-21T06:52:05.494Z] Running test renaissance-als_0 ... [2026-01-21T06:52:05.494Z] =============================================== [2026-01-21T06:52:05.494Z] renaissance-als_0 Start Time: Wed Jan 21 06:52:05 2026 Epoch Time (ms): 1768978325349 [2026-01-21T06:52:05.494Z] variation: NoOptions [2026-01-21T06:52:05.494Z] JVM_OPTIONS: [2026-01-21T06:52:05.494Z] { \ [2026-01-21T06:52:05.494Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T06:52:05.494Z] echo "Nothing to be done for setup."; \ [2026-01-21T06:52:05.494Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17689783258914/renaissance-als_0"; \ [2026-01-21T06:52:05.494Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17689783258914/renaissance-als_0"; \ [2026-01-21T06:52:05.494Z] echo ""; echo "TESTING:"; \ [2026-01-21T06:52:05.494Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17689783258914/renaissance-als_0"/als.json" als; \ [2026-01-21T06:52:05.494Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17689783258914/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T06:52:05.494Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T06:52:05.494Z] echo "Nothing to be done for teardown."; \ [2026-01-21T06:52:05.494Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17689783258914/TestTargetResult"; [2026-01-21T06:52:05.494Z] [2026-01-21T06:52:05.494Z] TEST SETUP: [2026-01-21T06:52:05.494Z] Nothing to be done for setup. [2026-01-21T06:52:05.494Z] [2026-01-21T06:52:05.494Z] TESTING: [2026-01-21T06:52:10.968Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-21T06:52:14.796Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T06:52:14.796Z] GC before operation: completed in 47.783 ms, heap usage 50.500 MB -> 35.234 MB. [2026-01-21T06:52:40.300Z] ====== als (apache-spark) [default], iteration 0 completed (23379.594 ms) ====== [2026-01-21T06:52:40.300Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T06:52:40.300Z] GC before operation: completed in 84.721 ms, heap usage 206.216 MB -> 62.010 MB. [2026-01-21T06:52:45.820Z] ====== als (apache-spark) [default], iteration 1 completed (7991.246 ms) ====== [2026-01-21T06:52:45.820Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T06:52:46.578Z] GC before operation: completed in 80.097 ms, heap usage 224.132 MB -> 62.858 MB. [2026-01-21T06:52:53.392Z] ====== als (apache-spark) [default], iteration 2 completed (7273.454 ms) ====== [2026-01-21T06:52:53.392Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T06:52:54.141Z] GC before operation: completed in 84.962 ms, heap usage 366.374 MB -> 63.765 MB. [2026-01-21T06:53:01.402Z] ====== als (apache-spark) [default], iteration 3 completed (7225.694 ms) ====== [2026-01-21T06:53:01.402Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T06:53:01.402Z] GC before operation: completed in 86.665 ms, heap usage 488.720 MB -> 64.559 MB. [2026-01-21T06:53:08.170Z] ====== als (apache-spark) [default], iteration 4 completed (6980.045 ms) ====== [2026-01-21T06:53:08.170Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T06:53:08.170Z] GC before operation: completed in 81.580 ms, heap usage 134.122 MB -> 64.717 MB. [2026-01-21T06:53:16.503Z] ====== als (apache-spark) [default], iteration 5 completed (7898.074 ms) ====== [2026-01-21T06:53:16.503Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T06:53:16.503Z] GC before operation: completed in 84.745 ms, heap usage 119.851 MB -> 65.317 MB. [2026-01-21T06:53:23.283Z] ====== als (apache-spark) [default], iteration 6 completed (6597.648 ms) ====== [2026-01-21T06:53:23.283Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T06:53:23.283Z] GC before operation: completed in 81.680 ms, heap usage 176.739 MB -> 65.941 MB. [2026-01-21T06:53:31.452Z] ====== als (apache-spark) [default], iteration 7 completed (6735.215 ms) ====== [2026-01-21T06:53:31.452Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T06:53:31.452Z] GC before operation: completed in 83.675 ms, heap usage 184.517 MB -> 66.435 MB. [2026-01-21T06:53:36.972Z] ====== als (apache-spark) [default], iteration 8 completed (6586.624 ms) ====== [2026-01-21T06:53:36.972Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T06:53:37.742Z] GC before operation: completed in 98.487 ms, heap usage 480.811 MB -> 67.294 MB. [2026-01-21T06:53:43.777Z] ====== als (apache-spark) [default], iteration 9 completed (6627.788 ms) ====== [2026-01-21T06:53:43.777Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T06:53:44.539Z] GC before operation: completed in 96.946 ms, heap usage 331.516 MB -> 67.308 MB. [2026-01-21T06:53:51.336Z] ====== als (apache-spark) [default], iteration 10 completed (6644.706 ms) ====== [2026-01-21T06:53:51.337Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T06:53:51.337Z] GC before operation: completed in 101.197 ms, heap usage 274.522 MB -> 67.456 MB. [2026-01-21T06:53:58.216Z] ====== als (apache-spark) [default], iteration 11 completed (6641.532 ms) ====== [2026-01-21T06:53:58.216Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T06:53:58.216Z] GC before operation: completed in 122.478 ms, heap usage 405.682 MB -> 67.808 MB. [2026-01-21T06:54:08.542Z] ====== als (apache-spark) [default], iteration 12 completed (9633.446 ms) ====== [2026-01-21T06:54:08.542Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T06:54:08.542Z] GC before operation: completed in 104.524 ms, heap usage 491.014 MB -> 68.345 MB. [2026-01-21T06:54:16.826Z] ====== als (apache-spark) [default], iteration 13 completed (7705.896 ms) ====== [2026-01-21T06:54:16.827Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T06:54:16.827Z] GC before operation: completed in 106.071 ms, heap usage 403.546 MB -> 68.864 MB. [2026-01-21T06:54:22.547Z] ====== als (apache-spark) [default], iteration 14 completed (6641.860 ms) ====== [2026-01-21T06:54:22.547Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T06:54:22.547Z] GC before operation: completed in 95.818 ms, heap usage 447.246 MB -> 68.820 MB. [2026-01-21T06:54:29.457Z] ====== als (apache-spark) [default], iteration 15 completed (6413.204 ms) ====== [2026-01-21T06:54:29.457Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T06:54:29.457Z] GC before operation: completed in 101.512 ms, heap usage 541.960 MB -> 69.179 MB. [2026-01-21T06:54:36.396Z] ====== als (apache-spark) [default], iteration 16 completed (6458.969 ms) ====== [2026-01-21T06:54:36.396Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T06:54:36.396Z] GC before operation: completed in 93.153 ms, heap usage 128.379 MB -> 68.946 MB. [2026-01-21T06:54:43.338Z] ====== als (apache-spark) [default], iteration 17 completed (6417.080 ms) ====== [2026-01-21T06:54:43.338Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T06:54:43.338Z] GC before operation: completed in 100.490 ms, heap usage 264.860 MB -> 69.355 MB. [2026-01-21T06:54:50.242Z] ====== als (apache-spark) [default], iteration 18 completed (6494.576 ms) ====== [2026-01-21T06:54:50.242Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T06:54:50.242Z] GC before operation: completed in 97.734 ms, heap usage 456.797 MB -> 69.987 MB. [2026-01-21T06:54:55.911Z] ====== als (apache-spark) [default], iteration 19 completed (6539.809 ms) ====== [2026-01-21T06:54:55.911Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T06:54:57.233Z] GC before operation: completed in 95.453 ms, heap usage 469.258 MB -> 70.673 MB. [2026-01-21T06:55:04.194Z] ====== als (apache-spark) [default], iteration 20 completed (6917.934 ms) ====== [2026-01-21T06:55:04.194Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T06:55:04.194Z] GC before operation: completed in 92.099 ms, heap usage 84.483 MB -> 70.027 MB. [2026-01-21T06:55:11.047Z] ====== als (apache-spark) [default], iteration 21 completed (7153.966 ms) ====== [2026-01-21T06:55:11.047Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T06:55:11.047Z] GC before operation: completed in 89.386 ms, heap usage 266.923 MB -> 70.386 MB. [2026-01-21T06:55:17.978Z] ====== als (apache-spark) [default], iteration 22 completed (6507.394 ms) ====== [2026-01-21T06:55:17.978Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T06:55:17.978Z] GC before operation: completed in 92.711 ms, heap usage 488.147 MB -> 71.112 MB. [2026-01-21T06:55:24.899Z] ====== als (apache-spark) [default], iteration 23 completed (6551.485 ms) ====== [2026-01-21T06:55:24.899Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T06:55:24.899Z] GC before operation: completed in 96.091 ms, heap usage 265.449 MB -> 71.169 MB. [2026-01-21T06:55:31.838Z] ====== als (apache-spark) [default], iteration 24 completed (6520.298 ms) ====== [2026-01-21T06:55:31.838Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T06:55:31.838Z] GC before operation: completed in 114.209 ms, heap usage 366.390 MB -> 71.489 MB. [2026-01-21T06:55:38.836Z] ====== als (apache-spark) [default], iteration 25 completed (6632.041 ms) ====== [2026-01-21T06:55:38.836Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T06:55:38.836Z] GC before operation: completed in 89.086 ms, heap usage 119.213 MB -> 71.299 MB. [2026-01-21T06:55:45.003Z] ====== als (apache-spark) [default], iteration 26 completed (6364.745 ms) ====== [2026-01-21T06:55:45.003Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T06:55:45.003Z] GC before operation: completed in 88.089 ms, heap usage 214.684 MB -> 71.636 MB. [2026-01-21T06:55:51.875Z] ====== als (apache-spark) [default], iteration 27 completed (6492.942 ms) ====== [2026-01-21T06:55:51.875Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T06:55:51.875Z] GC before operation: completed in 94.061 ms, heap usage 543.955 MB -> 72.343 MB. [2026-01-21T06:55:58.724Z] ====== als (apache-spark) [default], iteration 28 completed (6731.726 ms) ====== [2026-01-21T06:55:58.724Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T06:55:58.724Z] GC before operation: completed in 91.721 ms, heap usage 358.761 MB -> 71.849 MB. [2026-01-21T06:56:05.560Z] ====== als (apache-spark) [default], iteration 29 completed (6509.652 ms) ====== [2026-01-21T06:56:06.323Z] ----------------------------------- [2026-01-21T06:56:06.323Z] renaissance-als_0_PASSED [2026-01-21T06:56:06.323Z] ----------------------------------- [2026-01-21T06:56:06.323Z] [2026-01-21T06:56:06.323Z] TEST TEARDOWN: [2026-01-21T06:56:06.323Z] Nothing to be done for teardown. [2026-01-21T06:56:06.323Z] renaissance-als_0 Finish Time: Wed Jan 21 06:56:05 2026 Epoch Time (ms): 1768978565755