[2025-06-19T03:59:39.254Z] Running test renaissance-als_0 ... [2025-06-19T03:59:39.254Z] =============================================== [2025-06-19T03:59:39.254Z] renaissance-als_0 Start Time: Thu Jun 19 03:59:38 2025 Epoch Time (ms): 1750305578671 [2025-06-19T03:59:39.254Z] variation: NoOptions [2025-06-19T03:59:39.254Z] JVM_OPTIONS: [2025-06-19T03:59:39.254Z] { \ [2025-06-19T03:59:39.254Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T03:59:39.254Z] echo "Nothing to be done for setup."; \ [2025-06-19T03:59:39.254Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17503055404050/renaissance-als_0"; \ [2025-06-19T03:59:39.254Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17503055404050/renaissance-als_0"; \ [2025-06-19T03:59:39.254Z] echo ""; echo "TESTING:"; \ [2025-06-19T03:59:39.254Z] "/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_17503055404050/renaissance-als_0"/als.json" als; \ [2025-06-19T03:59:39.254Z] 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_17503055404050/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T03:59:39.254Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T03:59:39.254Z] echo "Nothing to be done for teardown."; \ [2025-06-19T03:59:39.254Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17503055404050/TestTargetResult"; [2025-06-19T03:59:39.254Z] [2025-06-19T03:59:39.254Z] TEST SETUP: [2025-06-19T03:59:39.254Z] Nothing to be done for setup. [2025-06-19T03:59:39.254Z] [2025-06-19T03:59:39.254Z] TESTING: [2025-06-19T03:59:45.269Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-19T03:59:48.248Z] WARNING: An illegal reflective access operation has occurred [2025-06-19T03:59:48.248Z] 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_17503055404050/renaissance-als_0/harness-035939-12631103045468913552/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-06-19T03:59:48.248Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-06-19T03:59:48.248Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-06-19T03:59:48.248Z] WARNING: All illegal access operations will be denied in a future release [2025-06-19T03:59:49.576Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-19T03:59:49.576Z] GC before operation: completed in 124.943 ms, heap usage 235.000 MB -> 34.426 MB. [2025-06-19T04:00:18.373Z] ====== als (apache-spark) [default], iteration 0 completed (26269.819 ms) ====== [2025-06-19T04:00:18.373Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-19T04:00:18.373Z] GC before operation: completed in 205.082 ms, heap usage 152.918 MB -> 60.230 MB. [2025-06-19T04:00:33.008Z] ====== als (apache-spark) [default], iteration 1 completed (14942.178 ms) ====== [2025-06-19T04:00:33.008Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-19T04:00:33.008Z] GC before operation: completed in 193.694 ms, heap usage 281.854 MB -> 61.215 MB. [2025-06-19T04:00:47.619Z] ====== als (apache-spark) [default], iteration 2 completed (14102.723 ms) ====== [2025-06-19T04:00:47.619Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-19T04:00:47.619Z] GC before operation: completed in 179.294 ms, heap usage 163.925 MB -> 61.603 MB. [2025-06-19T04:01:00.498Z] ====== als (apache-spark) [default], iteration 3 completed (13423.455 ms) ====== [2025-06-19T04:01:00.498Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-19T04:01:00.498Z] GC before operation: completed in 210.726 ms, heap usage 234.278 MB -> 62.256 MB. [2025-06-19T04:01:15.125Z] ====== als (apache-spark) [default], iteration 4 completed (12288.055 ms) ====== [2025-06-19T04:01:15.125Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-19T04:01:15.125Z] GC before operation: completed in 165.470 ms, heap usage 194.721 MB -> 62.660 MB. [2025-06-19T04:01:27.604Z] ====== als (apache-spark) [default], iteration 5 completed (12322.157 ms) ====== [2025-06-19T04:01:27.604Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-19T04:01:27.604Z] GC before operation: completed in 254.908 ms, heap usage 369.672 MB -> 63.423 MB. [2025-06-19T04:01:40.002Z] ====== als (apache-spark) [default], iteration 6 completed (12422.121 ms) ====== [2025-06-19T04:01:40.002Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-19T04:01:40.002Z] GC before operation: completed in 244.227 ms, heap usage 158.737 MB -> 63.720 MB. [2025-06-19T04:01:52.263Z] ====== als (apache-spark) [default], iteration 7 completed (11746.717 ms) ====== [2025-06-19T04:01:52.263Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-19T04:01:52.263Z] GC before operation: completed in 261.416 ms, heap usage 266.255 MB -> 64.466 MB. [2025-06-19T04:02:07.250Z] ====== als (apache-spark) [default], iteration 8 completed (14507.412 ms) ====== [2025-06-19T04:02:07.250Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-19T04:02:07.250Z] GC before operation: completed in 293.364 ms, heap usage 373.116 MB -> 65.015 MB. [2025-06-19T04:02:22.090Z] ====== als (apache-spark) [default], iteration 9 completed (15203.132 ms) ====== [2025-06-19T04:02:22.090Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-19T04:02:22.090Z] GC before operation: completed in 205.018 ms, heap usage 137.405 MB -> 65.186 MB. [2025-06-19T04:02:39.895Z] ====== als (apache-spark) [default], iteration 10 completed (14896.888 ms) ====== [2025-06-19T04:02:39.895Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-19T04:02:39.895Z] GC before operation: completed in 287.337 ms, heap usage 236.961 MB -> 65.430 MB. [2025-06-19T04:02:52.320Z] ====== als (apache-spark) [default], iteration 11 completed (14209.271 ms) ====== [2025-06-19T04:02:52.320Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-19T04:02:53.026Z] GC before operation: completed in 234.259 ms, heap usage 168.584 MB -> 65.578 MB. [2025-06-19T04:03:07.778Z] ====== als (apache-spark) [default], iteration 12 completed (14643.554 ms) ====== [2025-06-19T04:03:07.778Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-19T04:03:07.778Z] GC before operation: completed in 279.795 ms, heap usage 310.428 MB -> 65.964 MB. [2025-06-19T04:03:22.454Z] ====== als (apache-spark) [default], iteration 13 completed (13894.521 ms) ====== [2025-06-19T04:03:22.454Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-19T04:03:22.454Z] GC before operation: completed in 168.167 ms, heap usage 238.366 MB -> 66.214 MB. [2025-06-19T04:03:37.218Z] ====== als (apache-spark) [default], iteration 14 completed (13179.276 ms) ====== [2025-06-19T04:03:37.218Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-19T04:03:37.218Z] GC before operation: completed in 295.059 ms, heap usage 264.144 MB -> 66.583 MB. [2025-06-19T04:03:49.538Z] ====== als (apache-spark) [default], iteration 15 completed (12705.400 ms) ====== [2025-06-19T04:03:49.538Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-19T04:03:49.538Z] GC before operation: completed in 207.924 ms, heap usage 213.181 MB -> 66.692 MB. [2025-06-19T04:04:04.705Z] ====== als (apache-spark) [default], iteration 16 completed (13216.961 ms) ====== [2025-06-19T04:04:04.705Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-19T04:04:04.705Z] GC before operation: completed in 238.388 ms, heap usage 135.025 MB -> 66.813 MB. [2025-06-19T04:04:17.140Z] ====== als (apache-spark) [default], iteration 17 completed (12943.223 ms) ====== [2025-06-19T04:04:17.140Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-19T04:04:17.140Z] GC before operation: completed in 227.094 ms, heap usage 245.369 MB -> 67.239 MB. [2025-06-19T04:04:29.478Z] ====== als (apache-spark) [default], iteration 18 completed (12709.666 ms) ====== [2025-06-19T04:04:29.478Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-19T04:04:29.478Z] GC before operation: completed in 178.602 ms, heap usage 236.668 MB -> 67.439 MB. [2025-06-19T04:04:44.055Z] ====== als (apache-spark) [default], iteration 19 completed (13316.407 ms) ====== [2025-06-19T04:04:44.055Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-19T04:04:44.055Z] GC before operation: completed in 212.352 ms, heap usage 230.538 MB -> 67.720 MB. [2025-06-19T04:04:58.821Z] ====== als (apache-spark) [default], iteration 20 completed (13699.870 ms) ====== [2025-06-19T04:04:58.821Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-19T04:04:58.821Z] GC before operation: completed in 252.950 ms, heap usage 241.491 MB -> 67.947 MB. [2025-06-19T04:05:13.924Z] ====== als (apache-spark) [default], iteration 21 completed (15016.061 ms) ====== [2025-06-19T04:05:13.924Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-19T04:05:13.924Z] GC before operation: completed in 230.532 ms, heap usage 111.366 MB -> 68.058 MB. [2025-06-19T04:05:28.738Z] ====== als (apache-spark) [default], iteration 22 completed (14728.063 ms) ====== [2025-06-19T04:05:28.738Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-19T04:05:28.738Z] GC before operation: completed in 233.638 ms, heap usage 97.093 MB -> 68.421 MB. [2025-06-19T04:05:46.246Z] ====== als (apache-spark) [default], iteration 23 completed (15453.057 ms) ====== [2025-06-19T04:05:46.246Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-19T04:05:46.246Z] GC before operation: completed in 247.060 ms, heap usage 214.924 MB -> 68.717 MB. [2025-06-19T04:05:58.855Z] ====== als (apache-spark) [default], iteration 24 completed (13932.718 ms) ====== [2025-06-19T04:05:58.855Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-19T04:05:59.511Z] GC before operation: completed in 234.916 ms, heap usage 216.059 MB -> 68.955 MB. [2025-06-19T04:06:14.356Z] ====== als (apache-spark) [default], iteration 25 completed (14879.154 ms) ====== [2025-06-19T04:06:14.356Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-19T04:06:14.998Z] GC before operation: completed in 258.824 ms, heap usage 147.291 MB -> 69.085 MB. [2025-06-19T04:06:30.117Z] ====== als (apache-spark) [default], iteration 26 completed (13899.916 ms) ====== [2025-06-19T04:06:30.117Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-19T04:06:30.117Z] GC before operation: completed in 251.264 ms, heap usage 140.987 MB -> 69.328 MB. [2025-06-19T04:06:44.765Z] ====== als (apache-spark) [default], iteration 27 completed (13541.353 ms) ====== [2025-06-19T04:06:44.765Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-19T04:06:44.765Z] GC before operation: completed in 213.014 ms, heap usage 262.689 MB -> 69.697 MB. [2025-06-19T04:06:57.155Z] ====== als (apache-spark) [default], iteration 28 completed (12936.667 ms) ====== [2025-06-19T04:06:57.155Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-19T04:06:57.155Z] GC before operation: completed in 305.420 ms, heap usage 142.321 MB -> 69.820 MB. [2025-06-19T04:07:11.952Z] ====== als (apache-spark) [default], iteration 29 completed (14165.506 ms) ====== [2025-06-19T04:07:11.952Z] ----------------------------------- [2025-06-19T04:07:11.952Z] renaissance-als_0_PASSED [2025-06-19T04:07:11.952Z] ----------------------------------- [2025-06-19T04:07:11.952Z] [2025-06-19T04:07:11.952Z] TEST TEARDOWN: [2025-06-19T04:07:11.952Z] Nothing to be done for teardown. [2025-06-19T04:07:11.952Z] renaissance-als_0 Finish Time: Thu Jun 19 04:07:11 2025 Epoch Time (ms): 1750306031748