[2025-12-24T22:58:04.376Z] Running test renaissance-als_0 ... [2025-12-24T22:58:04.376Z] =============================================== [2025-12-24T22:58:04.377Z] renaissance-als_0 Start Time: Wed Dec 24 22:58:03 2025 Epoch Time (ms): 1766617083724 [2025-12-24T22:58:04.377Z] variation: NoOptions [2025-12-24T22:58:04.377Z] JVM_OPTIONS: [2025-12-24T22:58:04.377Z] { \ [2025-12-24T22:58:04.377Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T22:58:04.377Z] echo "Nothing to be done for setup."; \ [2025-12-24T22:58:04.377Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17666167571812/renaissance-als_0"; \ [2025-12-24T22:58:04.377Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17666167571812/renaissance-als_0"; \ [2025-12-24T22:58:04.377Z] echo ""; echo "TESTING:"; \ [2025-12-24T22:58:04.377Z] "/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_17666167571812/renaissance-als_0"/als.json" als; \ [2025-12-24T22:58:04.377Z] 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_17666167571812/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T22:58:04.377Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T22:58:04.377Z] echo "Nothing to be done for teardown."; \ [2025-12-24T22:58:04.377Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17666167571812/TestTargetResult"; [2025-12-24T22:58:04.377Z] [2025-12-24T22:58:04.377Z] TEST SETUP: [2025-12-24T22:58:04.377Z] Nothing to be done for setup. [2025-12-24T22:58:04.377Z] [2025-12-24T22:58:04.377Z] TESTING: [2025-12-24T22:58:13.316Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-24T22:58:20.072Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-24T22:58:20.072Z] GC before operation: completed in 79.805 ms, heap usage 51.991 MB -> 35.187 MB. [2025-12-24T22:58:48.891Z] ====== als (apache-spark) [default], iteration 0 completed (27334.031 ms) ====== [2025-12-24T22:58:48.891Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-24T22:58:48.891Z] GC before operation: completed in 170.674 ms, heap usage 211.896 MB -> 61.987 MB. [2025-12-24T22:59:02.567Z] ====== als (apache-spark) [default], iteration 1 completed (12603.374 ms) ====== [2025-12-24T22:59:02.567Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-24T22:59:02.567Z] GC before operation: completed in 157.857 ms, heap usage 148.347 MB -> 62.979 MB. [2025-12-24T22:59:14.418Z] ====== als (apache-spark) [default], iteration 2 completed (11941.569 ms) ====== [2025-12-24T22:59:14.418Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-24T22:59:14.418Z] GC before operation: completed in 209.530 ms, heap usage 447.723 MB -> 63.950 MB. [2025-12-24T22:59:26.719Z] ====== als (apache-spark) [default], iteration 3 completed (11438.856 ms) ====== [2025-12-24T22:59:26.719Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-24T22:59:26.719Z] GC before operation: completed in 159.235 ms, heap usage 219.936 MB -> 64.104 MB. [2025-12-24T22:59:36.619Z] ====== als (apache-spark) [default], iteration 4 completed (9733.490 ms) ====== [2025-12-24T22:59:36.619Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-24T22:59:36.619Z] GC before operation: completed in 173.435 ms, heap usage 370.973 MB -> 64.896 MB. [2025-12-24T22:59:46.491Z] ====== als (apache-spark) [default], iteration 5 completed (10003.888 ms) ====== [2025-12-24T22:59:46.491Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-24T22:59:46.491Z] GC before operation: completed in 188.436 ms, heap usage 307.446 MB -> 65.439 MB. [2025-12-24T22:59:58.345Z] ====== als (apache-spark) [default], iteration 6 completed (10758.259 ms) ====== [2025-12-24T22:59:58.345Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-24T22:59:58.345Z] GC before operation: completed in 226.262 ms, heap usage 152.146 MB -> 65.790 MB. [2025-12-24T23:00:12.968Z] ====== als (apache-spark) [default], iteration 7 completed (12682.190 ms) ====== [2025-12-24T23:00:12.968Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-24T23:00:12.968Z] GC before operation: completed in 436.360 ms, heap usage 221.238 MB -> 66.457 MB. [2025-12-24T23:00:23.651Z] ====== als (apache-spark) [default], iteration 8 completed (11956.940 ms) ====== [2025-12-24T23:00:23.651Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-24T23:00:25.475Z] GC before operation: completed in 284.252 ms, heap usage 438.962 MB -> 67.361 MB. [2025-12-24T23:00:36.152Z] ====== als (apache-spark) [default], iteration 9 completed (10701.999 ms) ====== [2025-12-24T23:00:36.152Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-24T23:00:36.152Z] GC before operation: completed in 190.531 ms, heap usage 94.531 MB -> 68.096 MB. [2025-12-24T23:00:46.175Z] ====== als (apache-spark) [default], iteration 10 completed (10223.933 ms) ====== [2025-12-24T23:00:46.175Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-24T23:00:46.175Z] GC before operation: completed in 181.730 ms, heap usage 442.842 MB -> 67.775 MB. [2025-12-24T23:00:58.057Z] ====== als (apache-spark) [default], iteration 11 completed (10143.645 ms) ====== [2025-12-24T23:00:58.057Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-24T23:00:58.057Z] GC before operation: completed in 362.173 ms, heap usage 305.079 MB -> 67.840 MB. [2025-12-24T23:01:08.090Z] ====== als (apache-spark) [default], iteration 12 completed (9524.232 ms) ====== [2025-12-24T23:01:08.090Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-24T23:01:08.090Z] GC before operation: completed in 192.581 ms, heap usage 496.621 MB -> 68.312 MB. [2025-12-24T23:01:18.370Z] ====== als (apache-spark) [default], iteration 13 completed (10394.037 ms) ====== [2025-12-24T23:01:18.370Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-24T23:01:18.370Z] GC before operation: completed in 189.365 ms, heap usage 408.146 MB -> 68.428 MB. [2025-12-24T23:01:29.287Z] ====== als (apache-spark) [default], iteration 14 completed (10443.340 ms) ====== [2025-12-24T23:01:29.287Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-24T23:01:29.287Z] GC before operation: completed in 239.629 ms, heap usage 257.798 MB -> 68.455 MB. [2025-12-24T23:01:41.027Z] ====== als (apache-spark) [default], iteration 15 completed (10926.219 ms) ====== [2025-12-24T23:01:41.027Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-24T23:01:41.027Z] GC before operation: completed in 195.263 ms, heap usage 315.760 MB -> 68.872 MB. [2025-12-24T23:01:52.720Z] ====== als (apache-spark) [default], iteration 16 completed (10621.747 ms) ====== [2025-12-24T23:01:52.720Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-24T23:01:52.720Z] GC before operation: completed in 210.819 ms, heap usage 566.970 MB -> 69.528 MB. [2025-12-24T23:02:02.691Z] ====== als (apache-spark) [default], iteration 17 completed (9785.922 ms) ====== [2025-12-24T23:02:02.691Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-24T23:02:02.691Z] GC before operation: completed in 151.566 ms, heap usage 382.324 MB -> 69.359 MB. [2025-12-24T23:02:10.926Z] ====== als (apache-spark) [default], iteration 18 completed (8873.793 ms) ====== [2025-12-24T23:02:10.926Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-24T23:02:10.926Z] GC before operation: completed in 192.237 ms, heap usage 498.612 MB -> 69.855 MB. [2025-12-24T23:02:19.835Z] ====== als (apache-spark) [default], iteration 19 completed (8885.982 ms) ====== [2025-12-24T23:02:19.835Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-24T23:02:20.799Z] GC before operation: completed in 183.851 ms, heap usage 138.332 MB -> 69.693 MB. [2025-12-24T23:02:30.744Z] ====== als (apache-spark) [default], iteration 20 completed (10151.381 ms) ====== [2025-12-24T23:02:30.744Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-24T23:02:30.744Z] GC before operation: completed in 246.215 ms, heap usage 509.123 MB -> 70.352 MB. [2025-12-24T23:02:42.593Z] ====== als (apache-spark) [default], iteration 21 completed (10180.751 ms) ====== [2025-12-24T23:02:42.593Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-24T23:02:42.593Z] GC before operation: completed in 163.383 ms, heap usage 156.483 MB -> 70.212 MB. [2025-12-24T23:02:52.472Z] ====== als (apache-spark) [default], iteration 22 completed (10581.909 ms) ====== [2025-12-24T23:02:52.472Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-24T23:02:52.472Z] GC before operation: completed in 212.993 ms, heap usage 452.340 MB -> 70.925 MB. [2025-12-24T23:03:04.114Z] ====== als (apache-spark) [default], iteration 23 completed (10225.641 ms) ====== [2025-12-24T23:03:04.114Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-24T23:03:04.114Z] GC before operation: completed in 196.044 ms, heap usage 529.909 MB -> 71.342 MB. [2025-12-24T23:03:14.108Z] ====== als (apache-spark) [default], iteration 24 completed (9878.396 ms) ====== [2025-12-24T23:03:14.108Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-24T23:03:14.108Z] GC before operation: completed in 168.423 ms, heap usage 223.521 MB -> 71.149 MB. [2025-12-24T23:03:24.838Z] ====== als (apache-spark) [default], iteration 25 completed (10057.465 ms) ====== [2025-12-24T23:03:24.838Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-24T23:03:24.838Z] GC before operation: completed in 189.806 ms, heap usage 277.599 MB -> 71.502 MB. [2025-12-24T23:03:34.701Z] ====== als (apache-spark) [default], iteration 26 completed (9446.710 ms) ====== [2025-12-24T23:03:34.701Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-24T23:03:34.702Z] GC before operation: completed in 194.138 ms, heap usage 434.873 MB -> 71.994 MB. [2025-12-24T23:03:44.800Z] ====== als (apache-spark) [default], iteration 27 completed (9771.879 ms) ====== [2025-12-24T23:03:44.800Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-24T23:03:44.800Z] GC before operation: completed in 162.161 ms, heap usage 166.588 MB -> 71.860 MB. [2025-12-24T23:03:54.864Z] ====== als (apache-spark) [default], iteration 28 completed (9180.224 ms) ====== [2025-12-24T23:03:54.864Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-24T23:03:54.864Z] GC before operation: completed in 141.612 ms, heap usage 153.755 MB -> 71.594 MB. [2025-12-24T23:04:04.785Z] ====== als (apache-spark) [default], iteration 29 completed (9452.870 ms) ====== [2025-12-24T23:04:05.770Z] ----------------------------------- [2025-12-24T23:04:05.770Z] renaissance-als_0_PASSED [2025-12-24T23:04:05.770Z] ----------------------------------- [2025-12-24T23:04:05.770Z] [2025-12-24T23:04:05.770Z] TEST TEARDOWN: [2025-12-24T23:04:05.770Z] Nothing to be done for teardown. [2025-12-24T23:04:05.770Z] renaissance-als_0 Finish Time: Wed Dec 24 23:04:05 2025 Epoch Time (ms): 1766617445332