[2025-07-01T12:45:09.278Z] Running test renaissance-als_0 ... [2025-07-01T12:45:09.278Z] =============================================== [2025-07-01T12:45:09.278Z] renaissance-als_0 Start Time: Tue Jul 1 12:45:08 2025 Epoch Time (ms): 1751373908934 [2025-07-01T12:45:09.278Z] variation: NoOptions [2025-07-01T12:45:09.278Z] JVM_OPTIONS: [2025-07-01T12:45:09.278Z] { \ [2025-07-01T12:45:09.278Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T12:45:09.278Z] echo "Nothing to be done for setup."; \ [2025-07-01T12:45:09.278Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-als_0"; \ [2025-07-01T12:45:09.278Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-als_0"; \ [2025-07-01T12:45:09.278Z] echo ""; echo "TESTING:"; \ [2025-07-01T12:45:09.278Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-als_0"/als.json" als; \ [2025-07-01T12:45:09.278Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T12:45:09.278Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T12:45:09.278Z] echo "Nothing to be done for teardown."; \ [2025-07-01T12:45:09.278Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513739089780/TestTargetResult"; [2025-07-01T12:45:09.278Z] [2025-07-01T12:45:09.278Z] TEST SETUP: [2025-07-01T12:45:09.278Z] Nothing to be done for setup. [2025-07-01T12:45:09.278Z] [2025-07-01T12:45:09.278Z] TESTING: [2025-07-01T12:45:20.969Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T12:45:30.706Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-01T12:45:30.706Z] GC before operation: completed in 273.816 ms, heap usage 56.000 MB -> 35.131 MB. [2025-07-01T12:46:27.558Z] ====== als (apache-spark) [default], iteration 0 completed (52001.671 ms) ====== [2025-07-01T12:46:27.558Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-01T12:46:27.558Z] GC before operation: completed in 239.461 ms, heap usage 140.660 MB -> 61.755 MB. [2025-07-01T12:46:46.341Z] ====== als (apache-spark) [default], iteration 1 completed (21381.052 ms) ====== [2025-07-01T12:46:46.341Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-01T12:46:46.341Z] GC before operation: completed in 230.159 ms, heap usage 508.334 MB -> 63.050 MB. [2025-07-01T12:47:05.618Z] ====== als (apache-spark) [default], iteration 2 completed (18339.050 ms) ====== [2025-07-01T12:47:05.618Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-01T12:47:05.618Z] GC before operation: completed in 235.691 ms, heap usage 283.063 MB -> 63.218 MB. [2025-07-01T12:47:24.373Z] ====== als (apache-spark) [default], iteration 3 completed (17629.332 ms) ====== [2025-07-01T12:47:24.373Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-01T12:47:24.373Z] GC before operation: completed in 241.052 ms, heap usage 459.141 MB -> 64.185 MB. [2025-07-01T12:47:43.125Z] ====== als (apache-spark) [default], iteration 4 completed (17668.806 ms) ====== [2025-07-01T12:47:43.125Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-01T12:47:43.125Z] GC before operation: completed in 244.611 ms, heap usage 184.473 MB -> 64.264 MB. [2025-07-01T12:48:01.909Z] ====== als (apache-spark) [default], iteration 5 completed (17491.997 ms) ====== [2025-07-01T12:48:01.909Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-01T12:48:01.909Z] GC before operation: completed in 215.237 ms, heap usage 294.879 MB -> 64.947 MB. [2025-07-01T12:48:18.601Z] ====== als (apache-spark) [default], iteration 6 completed (16668.918 ms) ====== [2025-07-01T12:48:18.601Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-01T12:48:18.601Z] GC before operation: completed in 222.431 ms, heap usage 156.814 MB -> 65.364 MB. [2025-07-01T12:48:34.639Z] ====== als (apache-spark) [default], iteration 7 completed (16794.568 ms) ====== [2025-07-01T12:48:34.639Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-01T12:48:35.396Z] GC before operation: completed in 267.862 ms, heap usage 326.640 MB -> 66.118 MB. [2025-07-01T12:48:54.150Z] ====== als (apache-spark) [default], iteration 8 completed (16626.649 ms) ====== [2025-07-01T12:48:54.150Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-01T12:48:54.150Z] GC before operation: completed in 235.251 ms, heap usage 202.513 MB -> 66.530 MB. [2025-07-01T12:49:10.144Z] ====== als (apache-spark) [default], iteration 9 completed (16250.223 ms) ====== [2025-07-01T12:49:10.144Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-01T12:49:10.144Z] GC before operation: completed in 246.428 ms, heap usage 349.348 MB -> 66.868 MB. [2025-07-01T12:49:26.697Z] ====== als (apache-spark) [default], iteration 10 completed (16950.924 ms) ====== [2025-07-01T12:49:26.698Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-01T12:49:26.698Z] GC before operation: completed in 282.033 ms, heap usage 504.147 MB -> 67.358 MB. [2025-07-01T12:49:45.449Z] ====== als (apache-spark) [default], iteration 11 completed (16644.792 ms) ====== [2025-07-01T12:49:45.449Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-01T12:49:45.449Z] GC before operation: completed in 340.188 ms, heap usage 490.032 MB -> 67.595 MB. [2025-07-01T12:50:01.406Z] ====== als (apache-spark) [default], iteration 12 completed (16413.644 ms) ====== [2025-07-01T12:50:01.406Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-01T12:50:01.406Z] GC before operation: completed in 232.761 ms, heap usage 300.047 MB -> 67.483 MB. [2025-07-01T12:50:20.120Z] ====== als (apache-spark) [default], iteration 13 completed (16175.932 ms) ====== [2025-07-01T12:50:20.120Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-01T12:50:20.120Z] GC before operation: completed in 274.141 ms, heap usage 154.408 MB -> 67.676 MB. [2025-07-01T12:50:36.675Z] ====== als (apache-spark) [default], iteration 14 completed (16264.970 ms) ====== [2025-07-01T12:50:36.675Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-01T12:50:36.675Z] GC before operation: completed in 229.409 ms, heap usage 165.379 MB -> 68.020 MB. [2025-07-01T12:50:52.629Z] ====== als (apache-spark) [default], iteration 15 completed (14813.017 ms) ====== [2025-07-01T12:50:52.629Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-01T12:50:52.629Z] GC before operation: completed in 327.417 ms, heap usage 359.709 MB -> 68.536 MB. [2025-07-01T12:51:06.144Z] ====== als (apache-spark) [default], iteration 16 completed (14613.454 ms) ====== [2025-07-01T12:51:06.144Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-01T12:51:06.144Z] GC before operation: completed in 223.143 ms, heap usage 253.792 MB -> 68.663 MB. [2025-07-01T12:51:22.022Z] ====== als (apache-spark) [default], iteration 17 completed (15052.872 ms) ====== [2025-07-01T12:51:22.022Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-01T12:51:22.022Z] GC before operation: completed in 232.195 ms, heap usage 497.800 MB -> 69.301 MB. [2025-07-01T12:51:37.905Z] ====== als (apache-spark) [default], iteration 18 completed (14771.556 ms) ====== [2025-07-01T12:51:37.905Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-01T12:51:37.905Z] GC before operation: completed in 265.555 ms, heap usage 459.288 MB -> 70.079 MB. [2025-07-01T12:51:53.960Z] ====== als (apache-spark) [default], iteration 19 completed (15242.751 ms) ====== [2025-07-01T12:51:53.960Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-01T12:51:53.960Z] GC before operation: completed in 214.471 ms, heap usage 218.822 MB -> 69.406 MB. [2025-07-01T12:52:09.871Z] ====== als (apache-spark) [default], iteration 20 completed (15373.738 ms) ====== [2025-07-01T12:52:09.871Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-01T12:52:09.871Z] GC before operation: completed in 297.904 ms, heap usage 139.852 MB -> 69.550 MB. [2025-07-01T12:52:25.761Z] ====== als (apache-spark) [default], iteration 21 completed (14494.378 ms) ====== [2025-07-01T12:52:25.761Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-01T12:52:25.761Z] GC before operation: completed in 282.895 ms, heap usage 351.236 MB -> 70.181 MB. [2025-07-01T12:52:39.283Z] ====== als (apache-spark) [default], iteration 22 completed (14628.460 ms) ====== [2025-07-01T12:52:39.283Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-01T12:52:40.042Z] GC before operation: completed in 254.043 ms, heap usage 173.980 MB -> 70.207 MB. [2025-07-01T12:52:58.630Z] ====== als (apache-spark) [default], iteration 23 completed (16550.668 ms) ====== [2025-07-01T12:52:58.630Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-01T12:52:58.630Z] GC before operation: completed in 247.208 ms, heap usage 360.882 MB -> 70.545 MB. [2025-07-01T12:53:17.253Z] ====== als (apache-spark) [default], iteration 24 completed (17916.913 ms) ====== [2025-07-01T12:53:17.253Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-01T12:53:17.253Z] GC before operation: completed in 228.343 ms, heap usage 131.227 MB -> 70.579 MB. [2025-07-01T12:53:31.158Z] ====== als (apache-spark) [default], iteration 25 completed (15027.208 ms) ====== [2025-07-01T12:53:31.158Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-01T12:53:31.158Z] GC before operation: completed in 248.960 ms, heap usage 541.132 MB -> 71.404 MB. [2025-07-01T12:53:44.642Z] ====== als (apache-spark) [default], iteration 26 completed (13530.027 ms) ====== [2025-07-01T12:53:44.642Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-01T12:53:45.393Z] GC before operation: completed in 243.797 ms, heap usage 435.052 MB -> 71.586 MB. [2025-07-01T12:53:59.288Z] ====== als (apache-spark) [default], iteration 27 completed (13123.857 ms) ====== [2025-07-01T12:53:59.288Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-01T12:53:59.288Z] GC before operation: completed in 149.796 ms, heap usage 208.466 MB -> 71.476 MB. [2025-07-01T12:54:12.833Z] ====== als (apache-spark) [default], iteration 28 completed (12512.194 ms) ====== [2025-07-01T12:54:12.833Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-01T12:54:12.833Z] GC before operation: completed in 202.626 ms, heap usage 143.302 MB -> 71.636 MB. [2025-07-01T12:54:29.097Z] ====== als (apache-spark) [default], iteration 29 completed (15455.204 ms) ====== [2025-07-01T12:54:29.097Z] ----------------------------------- [2025-07-01T12:54:29.097Z] renaissance-als_0_PASSED [2025-07-01T12:54:29.097Z] ----------------------------------- [2025-07-01T12:54:29.097Z] [2025-07-01T12:54:29.097Z] TEST TEARDOWN: [2025-07-01T12:54:29.097Z] Nothing to be done for teardown. [2025-07-01T12:54:29.097Z] renaissance-als_0 Finish Time: Tue Jul 1 12:54:28 2025 Epoch Time (ms): 1751374468448