[2026-04-25T15:29:23.408Z] Running test renaissance-als_0 ... [2026-04-25T15:29:23.408Z] =============================================== [2026-04-25T15:29:23.408Z] renaissance-als_0 Start Time: Sat Apr 25 15:29:23 2026 Epoch Time (ms): 1777130963137 [2026-04-25T15:29:23.408Z] variation: NoOptions [2026-04-25T15:29:23.408Z] JVM_OPTIONS: [2026-04-25T15:29:23.408Z] { \ [2026-04-25T15:29:23.408Z] echo ""; echo "TEST SETUP:"; \ [2026-04-25T15:29:23.408Z] echo "Nothing to be done for setup."; \ [2026-04-25T15:29:23.408Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777130557340/renaissance-als_0"; \ [2026-04-25T15:29:23.408Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777130557340/renaissance-als_0"; \ [2026-04-25T15:29:23.408Z] echo ""; echo "TESTING:"; \ [2026-04-25T15:29:23.408Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_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_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777130557340/renaissance-als_0"/als.json" als; \ [2026-04-25T15:29:23.408Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777130557340/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-25T15:29:23.408Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-25T15:29:23.408Z] echo "Nothing to be done for teardown."; \ [2026-04-25T15:29:23.408Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1777130557340/TestTargetResult"; [2026-04-25T15:29:23.408Z] [2026-04-25T15:29:23.408Z] TEST SETUP: [2026-04-25T15:29:23.408Z] Nothing to be done for setup. [2026-04-25T15:29:23.408Z] [2026-04-25T15:29:23.408Z] TESTING: [2026-04-25T15:29:33.257Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-04-25T15:29:36.644Z] WARNING: An illegal reflective access operation has occurred [2026-04-25T15:29:36.644Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_1777130557340/renaissance-als_0/harness-152925-12318046287234300055/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-25T15:29:36.645Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-25T15:29:36.645Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-25T15:29:36.645Z] WARNING: All illegal access operations will be denied in a future release [2026-04-25T15:29:38.208Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-25T15:29:38.208Z] GC before operation: completed in 134.229 ms, heap usage 52.002 MB -> 34.248 MB. [2026-04-25T15:30:19.886Z] ====== als (apache-spark) [default], iteration 0 completed (35915.261 ms) ====== [2026-04-25T15:30:19.886Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-25T15:30:19.886Z] GC before operation: completed in 355.958 ms, heap usage 324.348 MB -> 60.192 MB. [2026-04-25T15:30:38.935Z] ====== als (apache-spark) [default], iteration 1 completed (22097.665 ms) ====== [2026-04-25T15:30:38.935Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-25T15:30:38.935Z] GC before operation: completed in 423.194 ms, heap usage 307.997 MB -> 60.999 MB. [2026-04-25T15:30:58.020Z] ====== als (apache-spark) [default], iteration 2 completed (19474.991 ms) ====== [2026-04-25T15:30:58.020Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-25T15:30:58.020Z] GC before operation: completed in 324.063 ms, heap usage 336.188 MB -> 61.664 MB. [2026-04-25T15:31:16.854Z] ====== als (apache-spark) [default], iteration 3 completed (17754.381 ms) ====== [2026-04-25T15:31:16.854Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-25T15:31:16.854Z] GC before operation: completed in 283.061 ms, heap usage 221.850 MB -> 62.091 MB. [2026-04-25T15:31:33.142Z] ====== als (apache-spark) [default], iteration 4 completed (15643.859 ms) ====== [2026-04-25T15:31:33.142Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-25T15:31:33.142Z] GC before operation: completed in 356.268 ms, heap usage 142.508 MB -> 62.465 MB. [2026-04-25T15:31:53.011Z] ====== als (apache-spark) [default], iteration 5 completed (17837.960 ms) ====== [2026-04-25T15:31:53.011Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-25T15:31:53.011Z] GC before operation: completed in 317.794 ms, heap usage 85.389 MB -> 62.538 MB. [2026-04-25T15:32:09.615Z] ====== als (apache-spark) [default], iteration 6 completed (16454.130 ms) ====== [2026-04-25T15:32:09.615Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-25T15:32:09.615Z] GC before operation: completed in 318.670 ms, heap usage 137.230 MB -> 63.531 MB. [2026-04-25T15:32:28.655Z] ====== als (apache-spark) [default], iteration 7 completed (17505.037 ms) ====== [2026-04-25T15:32:28.655Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-25T15:32:28.655Z] GC before operation: completed in 241.404 ms, heap usage 259.219 MB -> 64.173 MB. [2026-04-25T15:32:45.193Z] ====== als (apache-spark) [default], iteration 8 completed (17466.262 ms) ====== [2026-04-25T15:32:45.193Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-25T15:32:45.951Z] GC before operation: completed in 355.527 ms, heap usage 120.250 MB -> 64.571 MB. [2026-04-25T15:33:01.925Z] ====== als (apache-spark) [default], iteration 9 completed (15586.311 ms) ====== [2026-04-25T15:33:01.925Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-25T15:33:01.925Z] GC before operation: completed in 223.341 ms, heap usage 218.690 MB -> 65.356 MB. [2026-04-25T15:33:20.795Z] ====== als (apache-spark) [default], iteration 10 completed (17603.573 ms) ====== [2026-04-25T15:33:20.795Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-25T15:33:20.795Z] GC before operation: completed in 297.266 ms, heap usage 291.523 MB -> 65.228 MB. [2026-04-25T15:33:39.671Z] ====== als (apache-spark) [default], iteration 11 completed (17245.523 ms) ====== [2026-04-25T15:33:39.671Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-25T15:33:39.671Z] GC before operation: completed in 304.730 ms, heap usage 146.232 MB -> 65.373 MB. [2026-04-25T15:33:58.935Z] ====== als (apache-spark) [default], iteration 12 completed (18565.665 ms) ====== [2026-04-25T15:33:58.935Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-25T15:33:58.935Z] GC before operation: completed in 257.232 ms, heap usage 158.551 MB -> 65.617 MB. [2026-04-25T15:34:18.469Z] ====== als (apache-spark) [default], iteration 13 completed (19185.233 ms) ====== [2026-04-25T15:34:18.469Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-25T15:34:18.469Z] GC before operation: completed in 284.770 ms, heap usage 138.374 MB -> 65.857 MB. [2026-04-25T15:34:37.967Z] ====== als (apache-spark) [default], iteration 14 completed (18459.952 ms) ====== [2026-04-25T15:34:37.967Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-25T15:34:37.967Z] GC before operation: completed in 406.782 ms, heap usage 121.352 MB -> 65.642 MB. [2026-04-25T15:34:54.305Z] ====== als (apache-spark) [default], iteration 15 completed (17525.177 ms) ====== [2026-04-25T15:34:54.305Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-25T15:34:54.305Z] GC before operation: completed in 285.750 ms, heap usage 168.737 MB -> 66.394 MB. [2026-04-25T15:35:13.698Z] ====== als (apache-spark) [default], iteration 16 completed (17753.915 ms) ====== [2026-04-25T15:35:13.698Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-25T15:35:13.698Z] GC before operation: completed in 283.571 ms, heap usage 235.685 MB -> 66.724 MB. [2026-04-25T15:35:36.138Z] ====== als (apache-spark) [default], iteration 17 completed (20093.246 ms) ====== [2026-04-25T15:35:36.138Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-25T15:35:36.138Z] GC before operation: completed in 315.419 ms, heap usage 262.333 MB -> 66.962 MB. [2026-04-25T15:35:55.040Z] ====== als (apache-spark) [default], iteration 18 completed (18529.738 ms) ====== [2026-04-25T15:35:55.040Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-25T15:35:55.040Z] GC before operation: completed in 424.389 ms, heap usage 130.946 MB -> 67.120 MB. [2026-04-25T15:36:11.995Z] ====== als (apache-spark) [default], iteration 19 completed (18454.101 ms) ====== [2026-04-25T15:36:11.995Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-25T15:36:12.801Z] GC before operation: completed in 412.214 ms, heap usage 162.958 MB -> 67.360 MB. [2026-04-25T15:36:32.035Z] ====== als (apache-spark) [default], iteration 20 completed (17942.377 ms) ====== [2026-04-25T15:36:32.035Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-25T15:36:32.035Z] GC before operation: completed in 284.918 ms, heap usage 134.209 MB -> 67.632 MB. [2026-04-25T15:36:51.176Z] ====== als (apache-spark) [default], iteration 21 completed (17529.578 ms) ====== [2026-04-25T15:36:51.176Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-25T15:36:51.176Z] GC before operation: completed in 405.267 ms, heap usage 148.120 MB -> 67.848 MB. [2026-04-25T15:37:10.184Z] ====== als (apache-spark) [default], iteration 22 completed (18151.079 ms) ====== [2026-04-25T15:37:10.184Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-25T15:37:10.184Z] GC before operation: completed in 309.968 ms, heap usage 225.317 MB -> 68.203 MB. [2026-04-25T15:37:26.755Z] ====== als (apache-spark) [default], iteration 23 completed (17514.168 ms) ====== [2026-04-25T15:37:26.755Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-25T15:37:26.755Z] GC before operation: completed in 386.089 ms, heap usage 285.909 MB -> 68.512 MB. [2026-04-25T15:37:46.025Z] ====== als (apache-spark) [default], iteration 24 completed (17174.539 ms) ====== [2026-04-25T15:37:46.025Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-25T15:37:46.025Z] GC before operation: completed in 254.317 ms, heap usage 232.619 MB -> 68.729 MB. [2026-04-25T15:38:02.315Z] ====== als (apache-spark) [default], iteration 25 completed (15998.791 ms) ====== [2026-04-25T15:38:02.315Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-25T15:38:02.315Z] GC before operation: completed in 370.876 ms, heap usage 159.016 MB -> 68.855 MB. [2026-04-25T15:38:21.877Z] ====== als (apache-spark) [default], iteration 26 completed (17461.326 ms) ====== [2026-04-25T15:38:21.877Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-25T15:38:21.877Z] GC before operation: completed in 229.728 ms, heap usage 321.792 MB -> 69.323 MB. [2026-04-25T15:38:38.513Z] ====== als (apache-spark) [default], iteration 27 completed (18338.517 ms) ====== [2026-04-25T15:38:38.513Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-25T15:38:39.315Z] GC before operation: completed in 360.133 ms, heap usage 151.280 MB -> 69.341 MB. [2026-04-25T15:38:55.849Z] ====== als (apache-spark) [default], iteration 28 completed (16371.571 ms) ====== [2026-04-25T15:38:55.849Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-25T15:38:55.849Z] GC before operation: completed in 293.879 ms, heap usage 311.341 MB -> 69.347 MB. [2026-04-25T15:39:14.990Z] ====== als (apache-spark) [default], iteration 29 completed (16011.911 ms) ====== [2026-04-25T15:39:14.990Z] ----------------------------------- [2026-04-25T15:39:14.990Z] renaissance-als_0_PASSED [2026-04-25T15:39:14.990Z] ----------------------------------- [2026-04-25T15:39:14.990Z] [2026-04-25T15:39:14.990Z] TEST TEARDOWN: [2026-04-25T15:39:14.990Z] Nothing to be done for teardown. [2026-04-25T15:39:14.990Z] renaissance-als_0 Finish Time: Sat Apr 25 15:39:13 2026 Epoch Time (ms): 1777131553846