[2024-09-26T00:50:02.886Z] Running test renaissance-als_0 ... [2024-09-26T00:50:02.886Z] =============================================== [2024-09-26T00:50:02.886Z] renaissance-als_0 Start Time: Thu Sep 26 00:50:02 2024 Epoch Time (ms): 1727311802182 [2024-09-26T00:50:02.886Z] variation: NoOptions [2024-09-26T00:50:02.886Z] JVM_OPTIONS: [2024-09-26T00:50:02.886Z] { \ [2024-09-26T00:50:02.886Z] echo ""; echo "TEST SETUP:"; \ [2024-09-26T00:50:02.886Z] echo "Nothing to be done for setup."; \ [2024-09-26T00:50:02.886Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17273117559386/renaissance-als_0"; \ [2024-09-26T00:50:02.886Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17273117559386/renaissance-als_0"; \ [2024-09-26T00:50:02.886Z] echo ""; echo "TESTING:"; \ [2024-09-26T00:50:02.886Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17273117559386/renaissance-als_0"/als.json" als; \ [2024-09-26T00:50:02.886Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17273117559386/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-26T00:50:02.886Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-26T00:50:02.886Z] echo "Nothing to be done for teardown."; \ [2024-09-26T00:50:02.886Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17273117559386/TestTargetResult"; [2024-09-26T00:50:02.886Z] [2024-09-26T00:50:02.886Z] TEST SETUP: [2024-09-26T00:50:02.886Z] Nothing to be done for setup. [2024-09-26T00:50:02.886Z] [2024-09-26T00:50:02.886Z] TESTING: [2024-09-26T00:50:07.391Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-26T00:50:10.778Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2024-09-26T00:50:14.182Z] WARNING: An illegal reflective access operation has occurred [2024-09-26T00:50:14.182Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17273117559386/renaissance-als_0/harness-005003-7001568159581553507/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-26T00:50:14.182Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-26T00:50:14.182Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-26T00:50:14.182Z] WARNING: All illegal access operations will be denied in a future release [2024-09-26T00:50:16.625Z] ====== als (apache-spark) [default], iteration 0 started ====== [2024-09-26T00:50:17.392Z] GC before operation: completed in 80.178 ms, heap usage 223.021 MB -> 35.356 MB. [2024-09-26T00:50:33.399Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:50:33.399Z] There is no way to check that no silent failure occurred. [2024-09-26T00:50:33.399Z] ====== als (apache-spark) [default], iteration 0 completed (14703.026 ms) ====== [2024-09-26T00:50:33.399Z] ====== als (apache-spark) [default], iteration 1 started ====== [2024-09-26T00:50:33.399Z] GC before operation: completed in 111.587 ms, heap usage 575.997 MB -> 57.867 MB. [2024-09-26T00:50:37.837Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:50:37.837Z] There is no way to check that no silent failure occurred. [2024-09-26T00:50:37.837Z] ====== als (apache-spark) [default], iteration 1 completed (5647.112 ms) ====== [2024-09-26T00:50:37.837Z] ====== als (apache-spark) [default], iteration 2 started ====== [2024-09-26T00:50:37.837Z] GC before operation: completed in 120.213 ms, heap usage 1.043 GB -> 61.011 MB. [2024-09-26T00:50:43.394Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:50:43.394Z] There is no way to check that no silent failure occurred. [2024-09-26T00:50:43.394Z] ====== als (apache-spark) [default], iteration 2 completed (5555.740 ms) ====== [2024-09-26T00:50:43.394Z] ====== als (apache-spark) [default], iteration 3 started ====== [2024-09-26T00:50:43.394Z] GC before operation: completed in 130.158 ms, heap usage 767.575 MB -> 61.107 MB. [2024-09-26T00:50:48.962Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:50:48.962Z] There is no way to check that no silent failure occurred. [2024-09-26T00:50:48.962Z] ====== als (apache-spark) [default], iteration 3 completed (5444.377 ms) ====== [2024-09-26T00:50:48.962Z] ====== als (apache-spark) [default], iteration 4 started ====== [2024-09-26T00:50:48.962Z] GC before operation: completed in 127.555 ms, heap usage 218.106 MB -> 60.885 MB. [2024-09-26T00:50:54.508Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:50:54.508Z] There is no way to check that no silent failure occurred. [2024-09-26T00:50:54.508Z] ====== als (apache-spark) [default], iteration 4 completed (5427.346 ms) ====== [2024-09-26T00:50:54.508Z] ====== als (apache-spark) [default], iteration 5 started ====== [2024-09-26T00:50:54.508Z] GC before operation: completed in 116.495 ms, heap usage 738.698 MB -> 61.680 MB. [2024-09-26T00:51:00.063Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:00.063Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:00.063Z] ====== als (apache-spark) [default], iteration 5 completed (5350.645 ms) ====== [2024-09-26T00:51:00.063Z] ====== als (apache-spark) [default], iteration 6 started ====== [2024-09-26T00:51:00.063Z] GC before operation: completed in 120.924 ms, heap usage 411.015 MB -> 61.929 MB. [2024-09-26T00:51:05.721Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:05.721Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:05.721Z] ====== als (apache-spark) [default], iteration 6 completed (5376.696 ms) ====== [2024-09-26T00:51:05.721Z] ====== als (apache-spark) [default], iteration 7 started ====== [2024-09-26T00:51:05.721Z] GC before operation: completed in 109.987 ms, heap usage 460.211 MB -> 62.096 MB. [2024-09-26T00:51:11.277Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:11.277Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:11.277Z] ====== als (apache-spark) [default], iteration 7 completed (5338.023 ms) ====== [2024-09-26T00:51:11.277Z] ====== als (apache-spark) [default], iteration 8 started ====== [2024-09-26T00:51:11.277Z] GC before operation: completed in 120.629 ms, heap usage 588.787 MB -> 62.611 MB. [2024-09-26T00:51:16.830Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:16.830Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:16.830Z] ====== als (apache-spark) [default], iteration 8 completed (5288.314 ms) ====== [2024-09-26T00:51:16.830Z] ====== als (apache-spark) [default], iteration 9 started ====== [2024-09-26T00:51:16.830Z] GC before operation: completed in 142.542 ms, heap usage 669.602 MB -> 62.973 MB. [2024-09-26T00:51:21.248Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:21.248Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:21.248Z] ====== als (apache-spark) [default], iteration 9 completed (5140.322 ms) ====== [2024-09-26T00:51:21.248Z] ====== als (apache-spark) [default], iteration 10 started ====== [2024-09-26T00:51:21.248Z] GC before operation: completed in 114.654 ms, heap usage 463.572 MB -> 63.166 MB. [2024-09-26T00:51:26.801Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:26.801Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:26.801Z] ====== als (apache-spark) [default], iteration 10 completed (5223.221 ms) ====== [2024-09-26T00:51:26.801Z] ====== als (apache-spark) [default], iteration 11 started ====== [2024-09-26T00:51:26.801Z] GC before operation: completed in 116.507 ms, heap usage 436.621 MB -> 63.406 MB. [2024-09-26T00:51:32.373Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:32.373Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:32.373Z] ====== als (apache-spark) [default], iteration 11 completed (5195.445 ms) ====== [2024-09-26T00:51:32.373Z] ====== als (apache-spark) [default], iteration 12 started ====== [2024-09-26T00:51:32.373Z] GC before operation: completed in 119.624 ms, heap usage 235.917 MB -> 63.510 MB. [2024-09-26T00:51:37.924Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:37.924Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:37.924Z] ====== als (apache-spark) [default], iteration 12 completed (5120.388 ms) ====== [2024-09-26T00:51:37.924Z] ====== als (apache-spark) [default], iteration 13 started ====== [2024-09-26T00:51:37.924Z] GC before operation: completed in 132.399 ms, heap usage 328.602 MB -> 64.093 MB. [2024-09-26T00:51:42.347Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:42.347Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:42.347Z] ====== als (apache-spark) [default], iteration 13 completed (5151.260 ms) ====== [2024-09-26T00:51:42.347Z] ====== als (apache-spark) [default], iteration 14 started ====== [2024-09-26T00:51:43.108Z] GC before operation: completed in 129.131 ms, heap usage 773.797 MB -> 64.997 MB. [2024-09-26T00:51:47.529Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:47.529Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:47.529Z] ====== als (apache-spark) [default], iteration 14 completed (5155.647 ms) ====== [2024-09-26T00:51:47.529Z] ====== als (apache-spark) [default], iteration 15 started ====== [2024-09-26T00:51:48.301Z] GC before operation: completed in 149.564 ms, heap usage 1.312 GB -> 65.805 MB. [2024-09-26T00:51:53.852Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:53.852Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:53.852Z] ====== als (apache-spark) [default], iteration 15 completed (5077.212 ms) ====== [2024-09-26T00:51:53.852Z] ====== als (apache-spark) [default], iteration 16 started ====== [2024-09-26T00:51:53.852Z] GC before operation: completed in 123.342 ms, heap usage 472.830 MB -> 65.255 MB. [2024-09-26T00:51:58.278Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:51:58.278Z] There is no way to check that no silent failure occurred. [2024-09-26T00:51:58.278Z] ====== als (apache-spark) [default], iteration 16 completed (5201.700 ms) ====== [2024-09-26T00:51:58.278Z] ====== als (apache-spark) [default], iteration 17 started ====== [2024-09-26T00:51:58.278Z] GC before operation: completed in 119.227 ms, heap usage 558.547 MB -> 65.641 MB. [2024-09-26T00:52:03.899Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:03.899Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:03.899Z] ====== als (apache-spark) [default], iteration 17 completed (5178.892 ms) ====== [2024-09-26T00:52:03.899Z] ====== als (apache-spark) [default], iteration 18 started ====== [2024-09-26T00:52:03.899Z] GC before operation: completed in 150.316 ms, heap usage 412.142 MB -> 65.858 MB. [2024-09-26T00:52:09.453Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:09.453Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:09.453Z] ====== als (apache-spark) [default], iteration 18 completed (5090.631 ms) ====== [2024-09-26T00:52:09.453Z] ====== als (apache-spark) [default], iteration 19 started ====== [2024-09-26T00:52:09.453Z] GC before operation: completed in 113.573 ms, heap usage 612.754 MB -> 66.181 MB. [2024-09-26T00:52:15.010Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:15.010Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:15.010Z] ====== als (apache-spark) [default], iteration 19 completed (5257.307 ms) ====== [2024-09-26T00:52:15.010Z] ====== als (apache-spark) [default], iteration 20 started ====== [2024-09-26T00:52:15.010Z] GC before operation: completed in 135.694 ms, heap usage 443.162 MB -> 66.224 MB. [2024-09-26T00:52:19.432Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:19.432Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:19.432Z] ====== als (apache-spark) [default], iteration 20 completed (5126.336 ms) ====== [2024-09-26T00:52:19.432Z] ====== als (apache-spark) [default], iteration 21 started ====== [2024-09-26T00:52:19.432Z] GC before operation: completed in 141.858 ms, heap usage 639.628 MB -> 66.675 MB. [2024-09-26T00:52:24.988Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:24.988Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:24.988Z] ====== als (apache-spark) [default], iteration 21 completed (4910.178 ms) ====== [2024-09-26T00:52:24.988Z] ====== als (apache-spark) [default], iteration 22 started ====== [2024-09-26T00:52:24.988Z] GC before operation: completed in 128.356 ms, heap usage 649.048 MB -> 67.069 MB. [2024-09-26T00:52:30.533Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:30.533Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:30.533Z] ====== als (apache-spark) [default], iteration 22 completed (5114.996 ms) ====== [2024-09-26T00:52:30.533Z] ====== als (apache-spark) [default], iteration 23 started ====== [2024-09-26T00:52:30.533Z] GC before operation: completed in 116.799 ms, heap usage 210.977 MB -> 66.447 MB. [2024-09-26T00:52:34.996Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:34.996Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:34.996Z] ====== als (apache-spark) [default], iteration 23 completed (4965.166 ms) ====== [2024-09-26T00:52:34.996Z] ====== als (apache-spark) [default], iteration 24 started ====== [2024-09-26T00:52:34.996Z] GC before operation: completed in 130.677 ms, heap usage 319.579 MB -> 66.997 MB. [2024-09-26T00:52:40.585Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:40.585Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:40.585Z] ====== als (apache-spark) [default], iteration 24 completed (5076.131 ms) ====== [2024-09-26T00:52:40.585Z] ====== als (apache-spark) [default], iteration 25 started ====== [2024-09-26T00:52:40.585Z] GC before operation: completed in 131.893 ms, heap usage 379.308 MB -> 67.348 MB. [2024-09-26T00:52:44.997Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:44.997Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:44.997Z] ====== als (apache-spark) [default], iteration 25 completed (4928.079 ms) ====== [2024-09-26T00:52:44.997Z] ====== als (apache-spark) [default], iteration 26 started ====== [2024-09-26T00:52:45.765Z] GC before operation: completed in 128.644 ms, heap usage 904.321 MB -> 67.849 MB. [2024-09-26T00:52:50.177Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:50.177Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:50.177Z] ====== als (apache-spark) [default], iteration 26 completed (4991.258 ms) ====== [2024-09-26T00:52:50.177Z] ====== als (apache-spark) [default], iteration 27 started ====== [2024-09-26T00:52:50.177Z] GC before operation: completed in 119.137 ms, heap usage 572.839 MB -> 67.912 MB. [2024-09-26T00:52:55.737Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:52:55.737Z] There is no way to check that no silent failure occurred. [2024-09-26T00:52:55.737Z] ====== als (apache-spark) [default], iteration 27 completed (5165.299 ms) ====== [2024-09-26T00:52:55.737Z] ====== als (apache-spark) [default], iteration 28 started ====== [2024-09-26T00:52:55.737Z] GC before operation: completed in 145.447 ms, heap usage 401.357 MB -> 67.705 MB. [2024-09-26T00:53:01.326Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:53:01.326Z] There is no way to check that no silent failure occurred. [2024-09-26T00:53:01.326Z] ====== als (apache-spark) [default], iteration 28 completed (5194.614 ms) ====== [2024-09-26T00:53:01.326Z] ====== als (apache-spark) [default], iteration 29 started ====== [2024-09-26T00:53:01.326Z] GC before operation: completed in 149.640 ms, heap usage 340.678 MB -> 68.020 MB. [2024-09-26T00:53:05.914Z] WARNING: This benchmark provides no result that can be validated. [2024-09-26T00:53:05.914Z] There is no way to check that no silent failure occurred. [2024-09-26T00:53:05.914Z] ====== als (apache-spark) [default], iteration 29 completed (4962.125 ms) ====== [2024-09-26T00:53:06.686Z] ----------------------------------- [2024-09-26T00:53:06.686Z] renaissance-als_0_PASSED [2024-09-26T00:53:06.686Z] ----------------------------------- [2024-09-26T00:53:06.686Z] [2024-09-26T00:53:06.686Z] TEST TEARDOWN: [2024-09-26T00:53:06.686Z] Nothing to be done for teardown. [2024-09-26T00:53:06.686Z] renaissance-als_0 Finish Time: Thu Sep 26 00:53:06 2024 Epoch Time (ms): 1727311986419