[2025-11-27T14:00:08.904Z] Running test renaissance-als_0 ... [2025-11-27T14:00:08.904Z] =============================================== [2025-11-27T14:00:08.904Z] renaissance-als_0 Start Time: Thu Nov 27 14:00:08 2025 Epoch Time (ms): 1764252008205 [2025-11-27T14:00:08.904Z] variation: NoOptions [2025-11-27T14:00:08.904Z] JVM_OPTIONS: [2025-11-27T14:00:08.904Z] { \ [2025-11-27T14:00:08.904Z] echo ""; echo "TEST SETUP:"; \ [2025-11-27T14:00:08.904Z] echo "Nothing to be done for setup."; \ [2025-11-27T14:00:08.904Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642520084958/renaissance-als_0"; \ [2025-11-27T14:00:08.904Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642520084958/renaissance-als_0"; \ [2025-11-27T14:00:08.904Z] echo ""; echo "TESTING:"; \ [2025-11-27T14:00:08.904Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/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_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642520084958/renaissance-als_0"/als.json" als; \ [2025-11-27T14:00:08.904Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642520084958/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-27T14:00:08.904Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-27T14:00:08.904Z] echo "Nothing to be done for teardown."; \ [2025-11-27T14:00:08.904Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642520084958/TestTargetResult"; [2025-11-27T14:00:08.904Z] [2025-11-27T14:00:08.904Z] TEST SETUP: [2025-11-27T14:00:08.904Z] Nothing to be done for setup. [2025-11-27T14:00:08.904Z] [2025-11-27T14:00:08.904Z] TESTING: [2025-11-27T14:00:13.208Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-27T14:00:17.541Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-27T14:00:17.541Z] GC before operation: completed in 38.144 ms, heap usage 91.590 MB -> 35.074 MB. [2025-11-27T14:00:39.274Z] ====== als (apache-spark) [default], iteration 0 completed (18856.625 ms) ====== [2025-11-27T14:00:39.274Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-27T14:00:39.274Z] GC before operation: completed in 86.181 ms, heap usage 398.307 MB -> 61.862 MB. [2025-11-27T14:00:46.437Z] ====== als (apache-spark) [default], iteration 1 completed (8863.097 ms) ====== [2025-11-27T14:00:46.437Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-27T14:00:46.437Z] GC before operation: completed in 84.631 ms, heap usage 619.677 MB -> 63.148 MB. [2025-11-27T14:00:54.501Z] ====== als (apache-spark) [default], iteration 2 completed (7475.545 ms) ====== [2025-11-27T14:00:54.501Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-27T14:00:54.501Z] GC before operation: completed in 75.173 ms, heap usage 633.827 MB -> 63.738 MB. [2025-11-27T14:01:01.190Z] ====== als (apache-spark) [default], iteration 3 completed (7394.729 ms) ====== [2025-11-27T14:01:01.190Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-27T14:01:01.190Z] GC before operation: completed in 82.266 ms, heap usage 327.706 MB -> 63.844 MB. [2025-11-27T14:01:09.370Z] ====== als (apache-spark) [default], iteration 4 completed (7255.491 ms) ====== [2025-11-27T14:01:09.370Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-27T14:01:09.370Z] GC before operation: completed in 82.072 ms, heap usage 492.755 MB -> 64.745 MB. [2025-11-27T14:01:16.051Z] ====== als (apache-spark) [default], iteration 5 completed (6834.914 ms) ====== [2025-11-27T14:01:16.051Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-27T14:01:16.051Z] GC before operation: completed in 79.670 ms, heap usage 295.794 MB -> 64.963 MB. [2025-11-27T14:01:22.721Z] ====== als (apache-spark) [default], iteration 6 completed (6893.513 ms) ====== [2025-11-27T14:01:22.721Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-27T14:01:22.721Z] GC before operation: completed in 80.445 ms, heap usage 497.045 MB -> 65.835 MB. [2025-11-27T14:01:30.790Z] ====== als (apache-spark) [default], iteration 7 completed (6973.220 ms) ====== [2025-11-27T14:01:30.790Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-27T14:01:30.790Z] GC before operation: completed in 77.546 ms, heap usage 285.461 MB -> 66.038 MB. [2025-11-27T14:01:37.483Z] ====== als (apache-spark) [default], iteration 8 completed (6798.337 ms) ====== [2025-11-27T14:01:37.483Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-27T14:01:37.483Z] GC before operation: completed in 82.056 ms, heap usage 118.325 MB -> 66.579 MB. [2025-11-27T14:01:45.699Z] ====== als (apache-spark) [default], iteration 9 completed (7349.174 ms) ====== [2025-11-27T14:01:45.699Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-27T14:01:45.699Z] GC before operation: completed in 89.733 ms, heap usage 357.976 MB -> 66.954 MB. [2025-11-27T14:01:52.392Z] ====== als (apache-spark) [default], iteration 10 completed (7108.943 ms) ====== [2025-11-27T14:01:52.392Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-27T14:01:52.392Z] GC before operation: completed in 91.076 ms, heap usage 612.628 MB -> 67.469 MB. [2025-11-27T14:01:59.057Z] ====== als (apache-spark) [default], iteration 11 completed (6823.857 ms) ====== [2025-11-27T14:01:59.057Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-27T14:01:59.057Z] GC before operation: completed in 90.291 ms, heap usage 695.016 MB -> 67.965 MB. [2025-11-27T14:02:07.121Z] ====== als (apache-spark) [default], iteration 12 completed (6706.672 ms) ====== [2025-11-27T14:02:07.121Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-27T14:02:07.121Z] GC before operation: completed in 90.279 ms, heap usage 166.443 MB -> 67.608 MB. [2025-11-27T14:02:13.809Z] ====== als (apache-spark) [default], iteration 13 completed (6958.892 ms) ====== [2025-11-27T14:02:13.809Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-27T14:02:13.809Z] GC before operation: completed in 89.025 ms, heap usage 626.363 MB -> 68.363 MB. [2025-11-27T14:02:20.554Z] ====== als (apache-spark) [default], iteration 14 completed (6626.403 ms) ====== [2025-11-27T14:02:20.554Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-27T14:02:20.554Z] GC before operation: completed in 95.617 ms, heap usage 535.154 MB -> 68.558 MB. [2025-11-27T14:02:27.230Z] ====== als (apache-spark) [default], iteration 15 completed (6734.883 ms) ====== [2025-11-27T14:02:27.230Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-27T14:02:27.230Z] GC before operation: completed in 90.639 ms, heap usage 255.591 MB -> 68.484 MB. [2025-11-27T14:02:33.912Z] ====== als (apache-spark) [default], iteration 16 completed (6639.219 ms) ====== [2025-11-27T14:02:33.912Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-27T14:02:33.912Z] GC before operation: completed in 91.945 ms, heap usage 773.189 MB -> 69.377 MB. [2025-11-27T14:02:41.984Z] ====== als (apache-spark) [default], iteration 17 completed (6825.541 ms) ====== [2025-11-27T14:02:41.984Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-27T14:02:41.984Z] GC before operation: completed in 94.665 ms, heap usage 496.603 MB -> 69.359 MB. [2025-11-27T14:02:49.633Z] ====== als (apache-spark) [default], iteration 18 completed (7816.166 ms) ====== [2025-11-27T14:02:49.633Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-27T14:02:49.633Z] GC before operation: completed in 89.638 ms, heap usage 244.615 MB -> 69.321 MB. [2025-11-27T14:02:56.310Z] ====== als (apache-spark) [default], iteration 19 completed (6460.474 ms) ====== [2025-11-27T14:02:56.310Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-27T14:02:56.310Z] GC before operation: completed in 88.646 ms, heap usage 304.285 MB -> 69.599 MB. [2025-11-27T14:03:03.025Z] ====== als (apache-spark) [default], iteration 20 completed (6783.364 ms) ====== [2025-11-27T14:03:03.025Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-27T14:03:03.025Z] GC before operation: completed in 93.529 ms, heap usage 655.522 MB -> 70.341 MB. [2025-11-27T14:03:09.713Z] ====== als (apache-spark) [default], iteration 21 completed (6713.664 ms) ====== [2025-11-27T14:03:09.713Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-27T14:03:09.713Z] GC before operation: completed in 94.048 ms, heap usage 845.282 MB -> 70.888 MB. [2025-11-27T14:03:17.790Z] ====== als (apache-spark) [default], iteration 22 completed (6707.377 ms) ====== [2025-11-27T14:03:17.790Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-27T14:03:17.790Z] GC before operation: completed in 100.691 ms, heap usage 482.023 MB -> 70.769 MB. [2025-11-27T14:03:24.483Z] ====== als (apache-spark) [default], iteration 23 completed (6695.899 ms) ====== [2025-11-27T14:03:24.483Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-27T14:03:24.483Z] GC before operation: completed in 93.535 ms, heap usage 163.152 MB -> 70.620 MB. [2025-11-27T14:03:31.178Z] ====== als (apache-spark) [default], iteration 24 completed (6794.331 ms) ====== [2025-11-27T14:03:31.178Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-27T14:03:31.178Z] GC before operation: completed in 94.562 ms, heap usage 530.960 MB -> 71.345 MB. [2025-11-27T14:03:37.860Z] ====== als (apache-spark) [default], iteration 25 completed (6687.378 ms) ====== [2025-11-27T14:03:37.861Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-27T14:03:37.861Z] GC before operation: completed in 96.867 ms, heap usage 208.512 MB -> 70.986 MB. [2025-11-27T14:03:44.651Z] ====== als (apache-spark) [default], iteration 26 completed (6758.679 ms) ====== [2025-11-27T14:03:44.651Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-27T14:03:44.651Z] GC before operation: completed in 106.878 ms, heap usage 481.799 MB -> 71.747 MB. [2025-11-27T14:03:51.844Z] ====== als (apache-spark) [default], iteration 27 completed (6506.358 ms) ====== [2025-11-27T14:03:51.844Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-27T14:03:51.844Z] GC before operation: completed in 93.642 ms, heap usage 257.233 MB -> 71.618 MB. [2025-11-27T14:03:58.533Z] ====== als (apache-spark) [default], iteration 28 completed (6521.804 ms) ====== [2025-11-27T14:03:58.533Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-27T14:03:58.533Z] GC before operation: completed in 97.215 ms, heap usage 733.939 MB -> 72.044 MB. [2025-11-27T14:04:05.227Z] ====== als (apache-spark) [default], iteration 29 completed (6623.798 ms) ====== [2025-11-27T14:04:05.227Z] ----------------------------------- [2025-11-27T14:04:05.227Z] renaissance-als_0_PASSED [2025-11-27T14:04:05.227Z] ----------------------------------- [2025-11-27T14:04:05.227Z] [2025-11-27T14:04:05.227Z] TEST TEARDOWN: [2025-11-27T14:04:05.227Z] Nothing to be done for teardown. [2025-11-27T14:04:05.227Z] renaissance-als_0 Finish Time: Thu Nov 27 14:04:04 2025 Epoch Time (ms): 1764252244933