[2025-10-31T12:23:12.866Z] Running test renaissance-als_0 ... [2025-10-31T12:23:12.866Z] =============================================== [2025-10-31T12:23:12.866Z] renaissance-als_0 Start Time: Fri Oct 31 12:23:12 2025 Epoch Time (ms): 1761913392646 [2025-10-31T12:23:12.866Z] variation: NoOptions [2025-10-31T12:23:12.866Z] JVM_OPTIONS: [2025-10-31T12:23:12.866Z] { \ [2025-10-31T12:23:12.866Z] echo ""; echo "TEST SETUP:"; \ [2025-10-31T12:23:12.866Z] echo "Nothing to be done for setup."; \ [2025-10-31T12:23:12.866Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17619132149343/renaissance-als_0"; \ [2025-10-31T12:23:12.866Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17619132149343/renaissance-als_0"; \ [2025-10-31T12:23:12.866Z] echo ""; echo "TESTING:"; \ [2025-10-31T12:23:12.866Z] "/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_17619132149343/renaissance-als_0"/als.json" als; \ [2025-10-31T12:23:12.866Z] 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_17619132149343/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-31T12:23:12.866Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-31T12:23:12.866Z] echo "Nothing to be done for teardown."; \ [2025-10-31T12:23:12.866Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17619132149343/TestTargetResult"; [2025-10-31T12:23:12.866Z] [2025-10-31T12:23:12.866Z] TEST SETUP: [2025-10-31T12:23:12.866Z] Nothing to be done for setup. [2025-10-31T12:23:12.866Z] [2025-10-31T12:23:12.866Z] TESTING: [2025-10-31T12:23:13.197Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-31T12:23:13.197Z] 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_17619132149343/renaissance-als_0/launcher-122312-13753686746043711944/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-31T12:23:13.197Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-31T12:23:13.197Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-31T12:23:20.653Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-10-31T12:23:25.423Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-31T12:23:25.423Z] GC before operation: completed in 56.367 ms, heap usage 55.000 MB -> 34.780 MB. [2025-10-31T12:23:53.171Z] ====== als (apache-spark) [default], iteration 0 completed (23318.435 ms) ====== [2025-10-31T12:23:53.171Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-31T12:23:53.171Z] GC before operation: completed in 127.435 ms, heap usage 563.691 MB -> 61.798 MB. [2025-10-31T12:24:02.088Z] ====== als (apache-spark) [default], iteration 1 completed (12171.500 ms) ====== [2025-10-31T12:24:02.089Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-31T12:24:02.089Z] GC before operation: completed in 96.317 ms, heap usage 210.199 MB -> 62.103 MB. [2025-10-31T12:24:12.918Z] ====== als (apache-spark) [default], iteration 2 completed (10283.749 ms) ====== [2025-10-31T12:24:12.918Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-31T12:24:12.918Z] GC before operation: completed in 95.077 ms, heap usage 96.686 MB -> 62.224 MB. [2025-10-31T12:24:23.740Z] ====== als (apache-spark) [default], iteration 3 completed (10908.987 ms) ====== [2025-10-31T12:24:23.740Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-31T12:24:23.740Z] GC before operation: completed in 99.722 ms, heap usage 91.076 MB -> 63.411 MB. [2025-10-31T12:24:34.686Z] ====== als (apache-spark) [default], iteration 4 completed (9876.955 ms) ====== [2025-10-31T12:24:34.686Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-31T12:24:34.686Z] GC before operation: completed in 94.479 ms, heap usage 431.288 MB -> 64.228 MB. [2025-10-31T12:24:43.605Z] ====== als (apache-spark) [default], iteration 5 completed (9916.104 ms) ====== [2025-10-31T12:24:43.605Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-31T12:24:43.938Z] GC before operation: completed in 102.849 ms, heap usage 206.295 MB -> 64.403 MB. [2025-10-31T12:24:54.791Z] ====== als (apache-spark) [default], iteration 6 completed (9839.054 ms) ====== [2025-10-31T12:24:54.791Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-31T12:24:54.791Z] GC before operation: completed in 102.390 ms, heap usage 352.390 MB -> 65.293 MB. [2025-10-31T12:25:05.630Z] ====== als (apache-spark) [default], iteration 7 completed (9905.485 ms) ====== [2025-10-31T12:25:05.630Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-31T12:25:05.630Z] GC before operation: completed in 95.863 ms, heap usage 194.984 MB -> 65.536 MB. [2025-10-31T12:25:14.600Z] ====== als (apache-spark) [default], iteration 8 completed (9889.515 ms) ====== [2025-10-31T12:25:14.600Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-31T12:25:14.600Z] GC before operation: completed in 99.346 ms, heap usage 222.595 MB -> 66.220 MB. [2025-10-31T12:25:25.559Z] ====== als (apache-spark) [default], iteration 9 completed (9835.867 ms) ====== [2025-10-31T12:25:25.559Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-31T12:25:25.559Z] GC before operation: completed in 123.735 ms, heap usage 199.052 MB -> 66.235 MB. [2025-10-31T12:25:34.478Z] ====== als (apache-spark) [default], iteration 10 completed (9772.520 ms) ====== [2025-10-31T12:25:34.478Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-31T12:25:34.807Z] GC before operation: completed in 124.536 ms, heap usage 224.204 MB -> 66.616 MB. [2025-10-31T12:25:45.633Z] ====== als (apache-spark) [default], iteration 11 completed (9726.279 ms) ====== [2025-10-31T12:25:45.633Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-31T12:25:45.633Z] GC before operation: completed in 104.351 ms, heap usage 85.139 MB -> 66.719 MB. [2025-10-31T12:25:54.528Z] ====== als (apache-spark) [default], iteration 12 completed (9714.159 ms) ====== [2025-10-31T12:25:54.528Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-31T12:25:54.865Z] GC before operation: completed in 119.587 ms, heap usage 487.476 MB -> 67.578 MB. [2025-10-31T12:26:05.691Z] ====== als (apache-spark) [default], iteration 13 completed (9599.912 ms) ====== [2025-10-31T12:26:05.691Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-31T12:26:05.691Z] GC before operation: completed in 104.614 ms, heap usage 211.518 MB -> 67.305 MB. [2025-10-31T12:26:14.763Z] ====== als (apache-spark) [default], iteration 14 completed (9602.734 ms) ====== [2025-10-31T12:26:14.763Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-31T12:26:14.763Z] GC before operation: completed in 105.601 ms, heap usage 274.587 MB -> 67.691 MB. [2025-10-31T12:26:25.580Z] ====== als (apache-spark) [default], iteration 15 completed (9386.827 ms) ====== [2025-10-31T12:26:25.580Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-31T12:26:25.580Z] GC before operation: completed in 110.784 ms, heap usage 189.676 MB -> 67.791 MB. [2025-10-31T12:26:34.474Z] ====== als (apache-spark) [default], iteration 16 completed (9624.766 ms) ====== [2025-10-31T12:26:34.474Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-31T12:26:34.474Z] GC before operation: completed in 114.813 ms, heap usage 457.900 MB -> 68.508 MB. [2025-10-31T12:26:45.315Z] ====== als (apache-spark) [default], iteration 17 completed (9539.132 ms) ====== [2025-10-31T12:26:45.315Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-31T12:26:45.315Z] GC before operation: completed in 105.968 ms, heap usage 433.885 MB -> 68.743 MB. [2025-10-31T12:26:54.216Z] ====== als (apache-spark) [default], iteration 18 completed (9553.879 ms) ====== [2025-10-31T12:26:54.216Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-31T12:26:54.216Z] GC before operation: completed in 105.982 ms, heap usage 189.083 MB -> 68.564 MB. [2025-10-31T12:27:05.041Z] ====== als (apache-spark) [default], iteration 19 completed (9528.050 ms) ====== [2025-10-31T12:27:05.041Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-31T12:27:05.041Z] GC before operation: completed in 137.647 ms, heap usage 278.938 MB -> 68.984 MB. [2025-10-31T12:27:14.089Z] ====== als (apache-spark) [default], iteration 20 completed (10123.847 ms) ====== [2025-10-31T12:27:14.089Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-31T12:27:14.089Z] GC before operation: completed in 108.806 ms, heap usage 502.447 MB -> 69.595 MB. [2025-10-31T12:27:24.920Z] ====== als (apache-spark) [default], iteration 21 completed (9677.155 ms) ====== [2025-10-31T12:27:24.920Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-31T12:27:24.920Z] GC before operation: completed in 114.754 ms, heap usage 203.063 MB -> 69.474 MB. [2025-10-31T12:27:33.809Z] ====== als (apache-spark) [default], iteration 22 completed (9634.814 ms) ====== [2025-10-31T12:27:33.809Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-31T12:27:34.138Z] GC before operation: completed in 108.821 ms, heap usage 313.474 MB -> 69.919 MB. [2025-10-31T12:27:44.961Z] ====== als (apache-spark) [default], iteration 23 completed (9609.377 ms) ====== [2025-10-31T12:27:44.961Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-31T12:27:44.961Z] GC before operation: completed in 96.337 ms, heap usage 108.669 MB -> 69.914 MB. [2025-10-31T12:27:55.861Z] ====== als (apache-spark) [default], iteration 24 completed (10029.097 ms) ====== [2025-10-31T12:27:55.861Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-31T12:27:55.861Z] GC before operation: completed in 96.599 ms, heap usage 388.190 MB -> 70.427 MB. [2025-10-31T12:28:04.768Z] ====== als (apache-spark) [default], iteration 25 completed (9742.346 ms) ====== [2025-10-31T12:28:04.768Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-31T12:28:04.768Z] GC before operation: completed in 97.475 ms, heap usage 200.181 MB -> 70.568 MB. [2025-10-31T12:28:15.610Z] ====== als (apache-spark) [default], iteration 26 completed (9758.971 ms) ====== [2025-10-31T12:28:15.610Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-31T12:28:15.610Z] GC before operation: completed in 109.643 ms, heap usage 89.401 MB -> 70.214 MB. [2025-10-31T12:28:24.516Z] ====== als (apache-spark) [default], iteration 27 completed (9297.477 ms) ====== [2025-10-31T12:28:24.516Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-31T12:28:24.516Z] GC before operation: completed in 106.550 ms, heap usage 468.327 MB -> 71.328 MB. [2025-10-31T12:28:35.439Z] ====== als (apache-spark) [default], iteration 28 completed (9704.336 ms) ====== [2025-10-31T12:28:35.439Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-31T12:28:35.439Z] GC before operation: completed in 97.326 ms, heap usage 493.766 MB -> 71.659 MB. [2025-10-31T12:28:44.349Z] ====== als (apache-spark) [default], iteration 29 completed (9540.365 ms) ====== [2025-10-31T12:28:44.349Z] ----------------------------------- [2025-10-31T12:28:44.349Z] renaissance-als_0_PASSED [2025-10-31T12:28:44.349Z] ----------------------------------- [2025-10-31T12:28:44.349Z] [2025-10-31T12:28:44.349Z] TEST TEARDOWN: [2025-10-31T12:28:44.349Z] Nothing to be done for teardown. [2025-10-31T12:28:44.349Z] renaissance-als_0 Finish Time: Fri Oct 31 12:28:43 2025 Epoch Time (ms): 1761913723962