[2025-08-25T23:54:39.201Z] Running test renaissance-als_0 ... [2025-08-25T23:54:39.201Z] =============================================== [2025-08-25T23:54:39.201Z] renaissance-als_0 Start Time: Tue Aug 26 00:54:39 2025 Epoch Time (ms): 1756166079029 [2025-08-25T23:54:39.201Z] variation: NoOptions [2025-08-25T23:54:39.201Z] JVM_OPTIONS: [2025-08-25T23:54:39.201Z] { \ [2025-08-25T23:54:39.201Z] echo ""; echo "TEST SETUP:"; \ [2025-08-25T23:54:39.201Z] echo "Nothing to be done for setup."; \ [2025-08-25T23:54:39.201Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17561660785495/renaissance-als_0"; \ [2025-08-25T23:54:39.201Z] cd "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17561660785495/renaissance-als_0"; \ [2025-08-25T23:54:39.201Z] echo ""; echo "TESTING:"; \ [2025-08-25T23:54:39.201Z] "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17561660785495/renaissance-als_0"/als.json" als; \ [2025-08-25T23:54:39.201Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17561660785495/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-25T23:54:39.201Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-25T23:54:39.201Z] echo "Nothing to be done for teardown."; \ [2025-08-25T23:54:39.201Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17561660785495/TestTargetResult"; [2025-08-25T23:54:39.201Z] [2025-08-25T23:54:39.201Z] TEST SETUP: [2025-08-25T23:54:39.201Z] Nothing to be done for setup. [2025-08-25T23:54:39.201Z] [2025-08-25T23:54:39.201Z] TESTING: [2025-08-25T23:54:46.717Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-08-25T23:54:49.864Z] WARNING: An illegal reflective access operation has occurred [2025-08-25T23:54:49.864Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/output_17561660785495/renaissance-als_0/harness-005439-4870835455880961858/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-25T23:54:49.864Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-25T23:54:49.864Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-25T23:54:49.864Z] WARNING: All illegal access operations will be denied in a future release [2025-08-25T23:54:51.084Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-25T23:54:51.084Z] GC before operation: completed in 48.510 ms, heap usage 143.805 MB -> 35.077 MB. [2025-08-26T00:01:01.730Z] ====== als (apache-spark) [default], iteration 0 completed (358785.909 ms) ====== [2025-08-26T00:01:01.730Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-26T00:01:01.730Z] GC before operation: completed in 159.895 ms, heap usage 448.190 MB -> 63.011 MB. [2025-08-26T00:06:57.292Z] ====== als (apache-spark) [default], iteration 1 completed (352583.543 ms) ====== [2025-08-26T00:06:57.292Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-26T00:06:57.292Z] GC before operation: completed in 193.715 ms, heap usage 540.008 MB -> 63.741 MB. [2025-08-26T00:12:37.776Z] ====== als (apache-spark) [default], iteration 2 completed (349936.082 ms) ====== [2025-08-26T00:12:37.776Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-26T00:12:37.776Z] GC before operation: completed in 173.004 ms, heap usage 405.920 MB -> 64.198 MB. [2025-08-26T00:18:33.347Z] ====== als (apache-spark) [default], iteration 3 completed (348189.490 ms) ====== [2025-08-26T00:18:33.347Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-26T00:18:33.347Z] GC before operation: completed in 189.739 ms, heap usage 437.070 MB -> 64.830 MB. [2025-08-26T00:24:13.796Z] ====== als (apache-spark) [default], iteration 4 completed (347807.439 ms) ====== [2025-08-26T00:24:13.796Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-26T00:24:13.796Z] GC before operation: completed in 169.353 ms, heap usage 409.619 MB -> 65.271 MB. [2025-08-26T00:30:09.782Z] ====== als (apache-spark) [default], iteration 5 completed (352867.139 ms) ====== [2025-08-26T00:30:09.782Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-26T00:30:09.782Z] GC before operation: completed in 173.849 ms, heap usage 791.377 MB -> 65.848 MB. [2025-08-26T00:35:50.249Z] ====== als (apache-spark) [default], iteration 6 completed (344293.575 ms) ====== [2025-08-26T00:35:50.249Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-26T00:35:50.249Z] GC before operation: completed in 182.539 ms, heap usage 417.592 MB -> 66.358 MB. [2025-08-26T00:41:45.852Z] ====== als (apache-spark) [default], iteration 7 completed (347681.062 ms) ====== [2025-08-26T00:41:45.852Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-26T00:41:45.852Z] GC before operation: completed in 201.447 ms, heap usage 682.430 MB -> 67.156 MB. [2025-08-26T00:47:26.491Z] ====== als (apache-spark) [default], iteration 8 completed (347589.745 ms) ====== [2025-08-26T00:47:26.491Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-26T00:47:26.491Z] GC before operation: completed in 197.337 ms, heap usage 693.826 MB -> 67.669 MB. [2025-08-26T00:53:22.073Z] ====== als (apache-spark) [default], iteration 9 completed (347983.204 ms) ====== [2025-08-26T00:53:22.073Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-26T00:53:22.073Z] GC before operation: completed in 184.371 ms, heap usage 410.000 MB -> 67.818 MB. [2025-08-26T00:59:02.541Z] ====== als (apache-spark) [default], iteration 10 completed (347751.608 ms) ====== [2025-08-26T00:59:02.541Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-26T00:59:02.541Z] GC before operation: completed in 200.425 ms, heap usage 752.960 MB -> 64.476 MB. [2025-08-26T01:04:58.147Z] ====== als (apache-spark) [default], iteration 11 completed (348321.323 ms) ====== [2025-08-26T01:04:58.147Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-26T01:04:58.147Z] GC before operation: completed in 195.434 ms, heap usage 408.530 MB -> 64.436 MB. [2025-08-26T01:10:38.631Z] ====== als (apache-spark) [default], iteration 12 completed (347871.172 ms) ====== [2025-08-26T01:10:38.631Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-26T01:10:38.631Z] GC before operation: completed in 177.965 ms, heap usage 403.912 MB -> 64.618 MB. [2025-08-26T01:16:34.192Z] ====== als (apache-spark) [default], iteration 13 completed (345094.426 ms) ====== [2025-08-26T01:16:34.192Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-26T01:16:34.192Z] GC before operation: completed in 228.031 ms, heap usage 835.196 MB -> 65.261 MB. [2025-08-26T01:22:14.701Z] ====== als (apache-spark) [default], iteration 14 completed (347509.328 ms) ====== [2025-08-26T01:22:14.701Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-26T01:22:14.701Z] GC before operation: completed in 184.046 ms, heap usage 383.996 MB -> 65.115 MB. [2025-08-26T01:28:10.384Z] ====== als (apache-spark) [default], iteration 15 completed (348767.285 ms) ====== [2025-08-26T01:28:10.384Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-26T01:28:10.384Z] GC before operation: completed in 180.501 ms, heap usage 739.621 MB -> 64.818 MB. [2025-08-26T01:33:50.852Z] ====== als (apache-spark) [default], iteration 16 completed (346756.021 ms) ====== [2025-08-26T01:33:50.852Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-26T01:33:50.852Z] GC before operation: completed in 199.607 ms, heap usage 714.170 MB -> 65.737 MB. [2025-08-26T01:39:46.444Z] ====== als (apache-spark) [default], iteration 17 completed (348593.522 ms) ====== [2025-08-26T01:39:46.444Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-26T01:39:46.444Z] GC before operation: completed in 199.434 ms, heap usage 436.640 MB -> 65.951 MB. [2025-08-26T01:45:26.906Z] ====== als (apache-spark) [default], iteration 18 completed (347141.024 ms) ====== [2025-08-26T01:45:26.906Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-26T01:45:26.906Z] GC before operation: completed in 203.958 ms, heap usage 1.170 GB -> 66.304 MB. [2025-08-26T01:51:22.481Z] ====== als (apache-spark) [default], iteration 19 completed (350280.021 ms) ====== [2025-08-26T01:51:22.481Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-26T01:51:22.481Z] GC before operation: completed in 190.455 ms, heap usage 420.588 MB -> 66.370 MB. [2025-08-26T01:57:18.048Z] ====== als (apache-spark) [default], iteration 20 completed (350118.427 ms) ====== [2025-08-26T01:57:18.048Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-26T01:57:18.048Z] GC before operation: completed in 184.618 ms, heap usage 440.981 MB -> 65.790 MB. [2025-08-26T02:02:58.513Z] ====== als (apache-spark) [default], iteration 21 completed (350264.730 ms) ====== [2025-08-26T02:02:58.513Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-26T02:02:58.513Z] GC before operation: completed in 242.183 ms, heap usage 686.722 MB -> 67.216 MB. [2025-08-26T02:08:54.134Z] ====== als (apache-spark) [default], iteration 22 completed (350628.838 ms) ====== [2025-08-26T02:08:54.134Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-26T02:08:54.134Z] GC before operation: completed in 220.334 ms, heap usage 390.990 MB -> 67.206 MB. [2025-08-26T02:14:49.721Z] ====== als (apache-spark) [default], iteration 23 completed (349961.887 ms) ====== [2025-08-26T02:14:49.721Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-26T02:14:49.721Z] GC before operation: completed in 230.906 ms, heap usage 966.006 MB -> 66.947 MB. [2025-08-26T02:20:30.198Z] ====== als (apache-spark) [default], iteration 24 completed (348802.841 ms) ====== [2025-08-26T02:20:30.198Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-26T02:20:30.198Z] GC before operation: completed in 203.324 ms, heap usage 484.928 MB -> 67.170 MB. [2025-08-26T02:26:25.794Z] ====== als (apache-spark) [default], iteration 25 completed (350212.004 ms) ====== [2025-08-26T02:26:25.794Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-26T02:26:25.794Z] GC before operation: completed in 205.351 ms, heap usage 893.066 MB -> 65.799 MB. [2025-08-26T02:32:06.243Z] ====== als (apache-spark) [default], iteration 26 completed (349312.298 ms) ====== [2025-08-26T02:32:06.243Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-26T02:32:06.243Z] GC before operation: completed in 195.822 ms, heap usage 400.242 MB -> 67.134 MB. [2025-08-26T02:38:01.823Z] ====== als (apache-spark) [default], iteration 27 completed (349354.913 ms) ====== [2025-08-26T02:38:01.823Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-26T02:38:01.823Z] GC before operation: completed in 235.317 ms, heap usage 1.269 GB -> 67.505 MB. [2025-08-26T02:43:57.401Z] ====== als (apache-spark) [default], iteration 28 completed (349336.478 ms) ====== [2025-08-26T02:43:57.401Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-26T02:43:57.401Z] GC before operation: completed in 194.129 ms, heap usage 436.463 MB -> 67.629 MB. [2025-08-26T02:49:37.952Z] ====== als (apache-spark) [default], iteration 29 completed (349282.472 ms) ====== [2025-08-26T02:49:37.952Z] ----------------------------------- [2025-08-26T02:49:37.952Z] renaissance-als_0_PASSED [2025-08-26T02:49:37.952Z] ----------------------------------- [2025-08-26T02:49:37.952Z] [2025-08-26T02:49:37.952Z] TEST TEARDOWN: [2025-08-26T02:49:37.952Z] Nothing to be done for teardown. [2025-08-26T02:49:37.952Z] renaissance-als_0 Finish Time: Tue Aug 26 03:49:35 2025 Epoch Time (ms): 1756176575505