[2026-01-03T13:21:58.922Z] Running test renaissance-als_0 ... [2026-01-03T13:21:58.922Z] =============================================== [2026-01-03T13:21:58.922Z] renaissance-als_0 Start Time: Sat Jan 3 13:21:58 2026 Epoch Time (ms): 1767446518408 [2026-01-03T13:21:58.922Z] variation: NoOptions [2026-01-03T13:21:58.922Z] JVM_OPTIONS: [2026-01-03T13:21:58.922Z] { \ [2026-01-03T13:21:58.922Z] echo ""; echo "TEST SETUP:"; \ [2026-01-03T13:21:58.922Z] echo "Nothing to be done for setup."; \ [2026-01-03T13:21:58.922Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17674463309759/renaissance-als_0"; \ [2026-01-03T13:21:58.922Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17674463309759/renaissance-als_0"; \ [2026-01-03T13:21:58.922Z] echo ""; echo "TESTING:"; \ [2026-01-03T13:21:58.922Z] "/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_17674463309759/renaissance-als_0"/als.json" als; \ [2026-01-03T13:21:58.922Z] 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_17674463309759/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-03T13:21:58.922Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-03T13:21:58.922Z] echo "Nothing to be done for teardown."; \ [2026-01-03T13:21:58.922Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17674463309759/TestTargetResult"; [2026-01-03T13:21:58.922Z] [2026-01-03T13:21:58.922Z] TEST SETUP: [2026-01-03T13:21:58.922Z] Nothing to be done for setup. [2026-01-03T13:21:58.922Z] [2026-01-03T13:21:58.922Z] TESTING: [2026-01-03T13:21:58.922Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-03T13:21:58.922Z] 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_17674463309759/renaissance-als_0/launcher-132158-3911934789052109795/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-03T13:21:58.922Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-03T13:21:58.922Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-03T13:22:09.377Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-03T13:22:13.208Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-03T13:22:13.208Z] GC before operation: completed in 54.662 ms, heap usage 52.000 MB -> 34.878 MB. [2026-01-03T13:22:41.348Z] ====== als (apache-spark) [default], iteration 0 completed (27736.220 ms) ====== [2026-01-03T13:22:41.348Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-03T13:22:41.994Z] GC before operation: completed in 112.778 ms, heap usage 146.786 MB -> 61.385 MB. [2026-01-03T13:22:56.631Z] ====== als (apache-spark) [default], iteration 1 completed (13838.186 ms) ====== [2026-01-03T13:22:56.631Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-03T13:22:56.631Z] GC before operation: completed in 108.116 ms, heap usage 485.943 MB -> 62.696 MB. [2026-01-03T13:23:09.384Z] ====== als (apache-spark) [default], iteration 2 completed (13265.520 ms) ====== [2026-01-03T13:23:09.384Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-03T13:23:09.384Z] GC before operation: completed in 102.234 ms, heap usage 305.660 MB -> 63.100 MB. [2026-01-03T13:23:23.978Z] ====== als (apache-spark) [default], iteration 3 completed (13101.100 ms) ====== [2026-01-03T13:23:23.978Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-03T13:23:23.978Z] GC before operation: completed in 105.753 ms, heap usage 438.939 MB -> 63.866 MB. [2026-01-03T13:23:36.321Z] ====== als (apache-spark) [default], iteration 4 completed (12621.259 ms) ====== [2026-01-03T13:23:36.321Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-03T13:23:36.321Z] GC before operation: completed in 104.000 ms, heap usage 336.022 MB -> 64.233 MB. [2026-01-03T13:23:48.710Z] ====== als (apache-spark) [default], iteration 5 completed (12546.293 ms) ====== [2026-01-03T13:23:48.710Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-03T13:23:48.710Z] GC before operation: completed in 127.808 ms, heap usage 221.595 MB -> 64.730 MB. [2026-01-03T13:24:01.167Z] ====== als (apache-spark) [default], iteration 6 completed (11971.743 ms) ====== [2026-01-03T13:24:01.167Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-03T13:24:01.167Z] GC before operation: completed in 109.431 ms, heap usage 227.590 MB -> 65.279 MB. [2026-01-03T13:24:13.539Z] ====== als (apache-spark) [default], iteration 7 completed (12073.234 ms) ====== [2026-01-03T13:24:13.539Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-03T13:24:13.539Z] GC before operation: completed in 109.405 ms, heap usage 307.531 MB -> 66.018 MB. [2026-01-03T13:24:28.139Z] ====== als (apache-spark) [default], iteration 8 completed (12573.398 ms) ====== [2026-01-03T13:24:28.139Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-03T13:24:28.139Z] GC before operation: completed in 123.335 ms, heap usage 227.226 MB -> 66.335 MB. [2026-01-03T13:24:38.563Z] ====== als (apache-spark) [default], iteration 9 completed (11766.648 ms) ====== [2026-01-03T13:24:38.563Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-03T13:24:38.563Z] GC before operation: completed in 118.949 ms, heap usage 458.089 MB -> 66.891 MB. [2026-01-03T13:24:51.389Z] ====== als (apache-spark) [default], iteration 10 completed (12072.428 ms) ====== [2026-01-03T13:24:51.389Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-03T13:24:51.389Z] GC before operation: completed in 122.287 ms, heap usage 500.602 MB -> 67.176 MB. [2026-01-03T13:25:03.772Z] ====== als (apache-spark) [default], iteration 11 completed (11829.266 ms) ====== [2026-01-03T13:25:03.772Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-03T13:25:03.772Z] GC before operation: completed in 151.210 ms, heap usage 309.925 MB -> 67.303 MB. [2026-01-03T13:25:16.270Z] ====== als (apache-spark) [default], iteration 12 completed (11641.890 ms) ====== [2026-01-03T13:25:16.270Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-03T13:25:16.270Z] GC before operation: completed in 117.134 ms, heap usage 296.420 MB -> 67.462 MB. [2026-01-03T13:25:28.749Z] ====== als (apache-spark) [default], iteration 13 completed (11731.408 ms) ====== [2026-01-03T13:25:28.749Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-03T13:25:28.749Z] GC before operation: completed in 120.127 ms, heap usage 326.494 MB -> 67.733 MB. [2026-01-03T13:25:39.226Z] ====== als (apache-spark) [default], iteration 14 completed (11770.504 ms) ====== [2026-01-03T13:25:39.226Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-03T13:25:39.872Z] GC before operation: completed in 117.529 ms, heap usage 318.938 MB -> 67.944 MB. [2026-01-03T13:25:52.278Z] ====== als (apache-spark) [default], iteration 15 completed (11691.789 ms) ====== [2026-01-03T13:25:52.278Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-03T13:25:52.278Z] GC before operation: completed in 115.876 ms, heap usage 180.145 MB -> 67.972 MB. [2026-01-03T13:26:04.766Z] ====== als (apache-spark) [default], iteration 16 completed (11513.758 ms) ====== [2026-01-03T13:26:04.766Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-03T13:26:04.766Z] GC before operation: completed in 117.517 ms, heap usage 205.137 MB -> 68.219 MB. [2026-01-03T13:26:17.172Z] ====== als (apache-spark) [default], iteration 17 completed (11924.690 ms) ====== [2026-01-03T13:26:17.172Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-03T13:26:17.172Z] GC before operation: completed in 119.445 ms, heap usage 324.215 MB -> 68.679 MB. [2026-01-03T13:26:27.814Z] ====== als (apache-spark) [default], iteration 18 completed (11684.910 ms) ====== [2026-01-03T13:26:27.814Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-03T13:26:27.814Z] GC before operation: completed in 118.305 ms, heap usage 344.488 MB -> 68.926 MB. [2026-01-03T13:26:40.175Z] ====== als (apache-spark) [default], iteration 19 completed (11565.175 ms) ====== [2026-01-03T13:26:40.175Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-03T13:26:40.175Z] GC before operation: completed in 114.303 ms, heap usage 179.030 MB -> 69.016 MB. [2026-01-03T13:26:52.550Z] ====== als (apache-spark) [default], iteration 20 completed (11914.059 ms) ====== [2026-01-03T13:26:52.550Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-03T13:26:52.550Z] GC before operation: completed in 117.969 ms, heap usage 400.658 MB -> 69.495 MB. [2026-01-03T13:27:04.929Z] ====== als (apache-spark) [default], iteration 21 completed (11990.324 ms) ====== [2026-01-03T13:27:04.929Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-03T13:27:04.929Z] GC before operation: completed in 113.459 ms, heap usage 296.360 MB -> 69.761 MB. [2026-01-03T13:27:17.288Z] ====== als (apache-spark) [default], iteration 22 completed (11722.544 ms) ====== [2026-01-03T13:27:17.288Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-03T13:27:17.288Z] GC before operation: completed in 122.819 ms, heap usage 449.698 MB -> 70.178 MB. [2026-01-03T13:27:29.707Z] ====== als (apache-spark) [default], iteration 23 completed (11605.362 ms) ====== [2026-01-03T13:27:29.707Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-03T13:27:29.707Z] GC before operation: completed in 126.876 ms, heap usage 448.290 MB -> 70.587 MB. [2026-01-03T13:27:42.046Z] ====== als (apache-spark) [default], iteration 24 completed (11914.065 ms) ====== [2026-01-03T13:27:42.046Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-03T13:27:42.046Z] GC before operation: completed in 118.452 ms, heap usage 266.137 MB -> 70.377 MB. [2026-01-03T13:27:54.406Z] ====== als (apache-spark) [default], iteration 25 completed (12016.859 ms) ====== [2026-01-03T13:27:54.406Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-03T13:27:54.406Z] GC before operation: completed in 114.479 ms, heap usage 161.825 MB -> 70.581 MB. [2026-01-03T13:28:05.424Z] ====== als (apache-spark) [default], iteration 26 completed (11961.843 ms) ====== [2026-01-03T13:28:05.424Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-03T13:28:05.424Z] GC before operation: completed in 122.580 ms, heap usage 531.537 MB -> 71.417 MB. [2026-01-03T13:28:17.875Z] ====== als (apache-spark) [default], iteration 27 completed (11597.963 ms) ====== [2026-01-03T13:28:17.875Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-03T13:28:17.875Z] GC before operation: completed in 117.489 ms, heap usage 383.197 MB -> 71.376 MB. [2026-01-03T13:28:30.303Z] ====== als (apache-spark) [default], iteration 28 completed (11836.080 ms) ====== [2026-01-03T13:28:30.303Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-03T13:28:30.303Z] GC before operation: completed in 120.880 ms, heap usage 96.638 MB -> 71.287 MB. [2026-01-03T13:28:40.711Z] ====== als (apache-spark) [default], iteration 29 completed (11484.666 ms) ====== [2026-01-03T13:28:41.356Z] ----------------------------------- [2026-01-03T13:28:41.356Z] renaissance-als_0_PASSED [2026-01-03T13:28:41.356Z] ----------------------------------- [2026-01-03T13:28:41.356Z] [2026-01-03T13:28:41.356Z] TEST TEARDOWN: [2026-01-03T13:28:41.356Z] Nothing to be done for teardown. [2026-01-03T13:28:41.356Z] renaissance-als_0 Finish Time: Sat Jan 3 13:28:40 2026 Epoch Time (ms): 1767446920887