[2025-05-22T21:41:19.325Z] Running test renaissance-als_0 ... [2025-05-22T21:41:19.325Z] =============================================== [2025-05-22T21:41:19.325Z] renaissance-als_0 Start Time: Thu May 22 21:41:19 2025 Epoch Time (ms): 1747950079208 [2025-05-22T21:41:19.325Z] variation: NoOptions [2025-05-22T21:41:19.325Z] JVM_OPTIONS: [2025-05-22T21:41:19.325Z] { \ [2025-05-22T21:41:19.325Z] echo ""; echo "TEST SETUP:"; \ [2025-05-22T21:41:19.325Z] echo "Nothing to be done for setup."; \ [2025-05-22T21:41:19.325Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/renaissance-als_0"; \ [2025-05-22T21:41:19.325Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/renaissance-als_0"; \ [2025-05-22T21:41:19.325Z] echo ""; echo "TESTING:"; \ [2025-05-22T21:41:19.325Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_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_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/renaissance-als_0"/als.json" als; \ [2025-05-22T21:41:19.325Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-22T21:41:19.325Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-22T21:41:19.325Z] echo "Nothing to be done for teardown."; \ [2025-05-22T21:41:19.325Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17479500784375/TestTargetResult"; [2025-05-22T21:41:19.325Z] [2025-05-22T21:41:19.325Z] TEST SETUP: [2025-05-22T21:41:19.325Z] Nothing to be done for setup. [2025-05-22T21:41:19.325Z] [2025-05-22T21:41:19.325Z] TESTING: [2025-05-22T21:41:20.893Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-05-22T21:41:20.893Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17479500784375/renaissance-als_0/launcher-214119-16527172255041731793/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-05-22T21:41:20.893Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-05-22T21:41:20.893Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-05-22T21:41:31.468Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-22T21:41:37.624Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-22T21:41:37.624Z] GC before operation: completed in 100.962 ms, heap usage 54.455 MB -> 35.138 MB. [2025-05-22T21:42:46.585Z] ====== als (apache-spark) [default], iteration 0 completed (61193.626 ms) ====== [2025-05-22T21:42:46.585Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-22T21:42:46.585Z] GC before operation: completed in 212.964 ms, heap usage 256.629 MB -> 61.616 MB. [2025-05-22T21:42:54.866Z] ====== als (apache-spark) [default], iteration 1 completed (13423.829 ms) ====== [2025-05-22T21:42:54.866Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-22T21:42:54.866Z] GC before operation: completed in 202.583 ms, heap usage 127.856 MB -> 62.353 MB. [2025-05-22T21:43:10.980Z] ====== als (apache-spark) [default], iteration 2 completed (14696.340 ms) ====== [2025-05-22T21:43:10.980Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-22T21:43:10.980Z] GC before operation: completed in 282.515 ms, heap usage 516.505 MB -> 63.492 MB. [2025-05-22T21:43:31.948Z] ====== als (apache-spark) [default], iteration 3 completed (20132.768 ms) ====== [2025-05-22T21:43:31.948Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-22T21:43:31.948Z] GC before operation: completed in 213.638 ms, heap usage 435.463 MB -> 64.008 MB. [2025-05-22T21:43:43.613Z] ====== als (apache-spark) [default], iteration 4 completed (11906.787 ms) ====== [2025-05-22T21:43:43.614Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-22T21:43:43.614Z] GC before operation: completed in 166.726 ms, heap usage 324.736 MB -> 64.408 MB. [2025-05-22T21:43:55.292Z] ====== als (apache-spark) [default], iteration 5 completed (11871.705 ms) ====== [2025-05-22T21:43:55.293Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-22T21:43:55.293Z] GC before operation: completed in 206.629 ms, heap usage 389.143 MB -> 64.898 MB. [2025-05-22T21:44:06.929Z] ====== als (apache-spark) [default], iteration 6 completed (11250.214 ms) ====== [2025-05-22T21:44:06.929Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-22T21:44:06.929Z] GC before operation: completed in 170.717 ms, heap usage 184.959 MB -> 65.273 MB. [2025-05-22T21:44:18.555Z] ====== als (apache-spark) [default], iteration 7 completed (9967.318 ms) ====== [2025-05-22T21:44:18.555Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-22T21:44:18.555Z] GC before operation: completed in 160.809 ms, heap usage 557.579 MB -> 66.375 MB. [2025-05-22T21:44:28.590Z] ====== als (apache-spark) [default], iteration 8 completed (9728.841 ms) ====== [2025-05-22T21:44:28.590Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-22T21:44:28.590Z] GC before operation: completed in 166.497 ms, heap usage 188.163 MB -> 66.424 MB. [2025-05-22T21:44:40.239Z] ====== als (apache-spark) [default], iteration 9 completed (11206.742 ms) ====== [2025-05-22T21:44:40.239Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-22T21:44:40.239Z] GC before operation: completed in 223.212 ms, heap usage 481.147 MB -> 66.997 MB. [2025-05-22T21:44:51.926Z] ====== als (apache-spark) [default], iteration 10 completed (11802.455 ms) ====== [2025-05-22T21:44:51.926Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-22T21:44:51.926Z] GC before operation: completed in 179.997 ms, heap usage 242.676 MB -> 66.956 MB. [2025-05-22T21:45:05.632Z] ====== als (apache-spark) [default], iteration 11 completed (12642.182 ms) ====== [2025-05-22T21:45:05.632Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-22T21:45:05.632Z] GC before operation: completed in 219.422 ms, heap usage 162.972 MB -> 67.127 MB. [2025-05-22T21:45:17.275Z] ====== als (apache-spark) [default], iteration 12 completed (11530.703 ms) ====== [2025-05-22T21:45:17.275Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-22T21:45:17.275Z] GC before operation: completed in 216.022 ms, heap usage 176.770 MB -> 67.466 MB. [2025-05-22T21:45:30.981Z] ====== als (apache-spark) [default], iteration 13 completed (11664.734 ms) ====== [2025-05-22T21:45:30.981Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-22T21:45:30.981Z] GC before operation: completed in 211.559 ms, heap usage 178.219 MB -> 67.671 MB. [2025-05-22T21:45:40.828Z] ====== als (apache-spark) [default], iteration 14 completed (11242.810 ms) ====== [2025-05-22T21:45:40.828Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-22T21:45:41.601Z] GC before operation: completed in 205.034 ms, heap usage 470.451 MB -> 68.432 MB. [2025-05-22T21:45:53.233Z] ====== als (apache-spark) [default], iteration 15 completed (10923.804 ms) ====== [2025-05-22T21:45:53.233Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-22T21:45:53.233Z] GC before operation: completed in 245.397 ms, heap usage 302.488 MB -> 68.433 MB. [2025-05-22T21:46:04.867Z] ====== als (apache-spark) [default], iteration 16 completed (11015.132 ms) ====== [2025-05-22T21:46:04.867Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-22T21:46:04.867Z] GC before operation: completed in 206.695 ms, heap usage 462.538 MB -> 68.853 MB. [2025-05-22T21:46:16.517Z] ====== als (apache-spark) [default], iteration 17 completed (11132.115 ms) ====== [2025-05-22T21:46:16.517Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-22T21:46:16.517Z] GC before operation: completed in 184.943 ms, heap usage 537.090 MB -> 69.166 MB. [2025-05-22T21:46:28.802Z] ====== als (apache-spark) [default], iteration 18 completed (12030.602 ms) ====== [2025-05-22T21:46:28.802Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-22T21:46:28.802Z] GC before operation: completed in 185.921 ms, heap usage 122.376 MB -> 69.003 MB. [2025-05-22T21:46:40.443Z] ====== als (apache-spark) [default], iteration 19 completed (10601.020 ms) ====== [2025-05-22T21:46:40.443Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-22T21:46:40.443Z] GC before operation: completed in 312.043 ms, heap usage 269.078 MB -> 69.353 MB. [2025-05-22T21:46:52.148Z] ====== als (apache-spark) [default], iteration 20 completed (10714.248 ms) ====== [2025-05-22T21:46:52.148Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-22T21:46:52.148Z] GC before operation: completed in 258.195 ms, heap usage 431.279 MB -> 69.907 MB. [2025-05-22T21:47:05.854Z] ====== als (apache-spark) [default], iteration 21 completed (13977.703 ms) ====== [2025-05-22T21:47:05.854Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-22T21:47:05.854Z] GC before operation: completed in 256.449 ms, heap usage 472.993 MB -> 70.169 MB. [2025-05-22T21:47:21.978Z] ====== als (apache-spark) [default], iteration 22 completed (14675.864 ms) ====== [2025-05-22T21:47:21.978Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-22T21:47:21.978Z] GC before operation: completed in 254.051 ms, heap usage 565.073 MB -> 70.549 MB. [2025-05-22T21:47:38.082Z] ====== als (apache-spark) [default], iteration 23 completed (15194.773 ms) ====== [2025-05-22T21:47:38.082Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-22T21:47:38.082Z] GC before operation: completed in 237.990 ms, heap usage 312.232 MB -> 70.494 MB. [2025-05-22T21:47:49.809Z] ====== als (apache-spark) [default], iteration 24 completed (12625.210 ms) ====== [2025-05-22T21:47:49.809Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-22T21:47:49.809Z] GC before operation: completed in 258.575 ms, heap usage 336.027 MB -> 70.789 MB. [2025-05-22T21:48:03.569Z] ====== als (apache-spark) [default], iteration 25 completed (13307.341 ms) ====== [2025-05-22T21:48:03.569Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-22T21:48:04.332Z] GC before operation: completed in 826.194 ms, heap usage 485.974 MB -> 71.229 MB. [2025-05-22T21:48:18.052Z] ====== als (apache-spark) [default], iteration 26 completed (13125.568 ms) ====== [2025-05-22T21:48:18.052Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-22T21:48:18.052Z] GC before operation: completed in 293.988 ms, heap usage 131.164 MB -> 71.195 MB. [2025-05-22T21:48:32.308Z] ====== als (apache-spark) [default], iteration 27 completed (14305.013 ms) ====== [2025-05-22T21:48:32.308Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-22T21:48:33.072Z] GC before operation: completed in 324.945 ms, heap usage 504.771 MB -> 71.759 MB. [2025-05-22T21:48:46.838Z] ====== als (apache-spark) [default], iteration 28 completed (12926.280 ms) ====== [2025-05-22T21:48:46.838Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-22T21:48:46.838Z] GC before operation: completed in 256.629 ms, heap usage 351.381 MB -> 71.371 MB. [2025-05-22T21:49:00.587Z] ====== als (apache-spark) [default], iteration 29 completed (12769.770 ms) ====== [2025-05-22T21:49:00.587Z] ----------------------------------- [2025-05-22T21:49:00.587Z] renaissance-als_0_PASSED [2025-05-22T21:49:00.587Z] ----------------------------------- [2025-05-22T21:49:00.587Z] [2025-05-22T21:49:00.587Z] TEST TEARDOWN: [2025-05-22T21:49:00.587Z] Nothing to be done for teardown. [2025-05-22T21:49:00.587Z] renaissance-als_0 Finish Time: Thu May 22 21:49:00 2025 Epoch Time (ms): 1747950540082