[2024-12-06T22:27:49.362Z] Running test renaissance-als_0 ... [2024-12-06T22:27:49.362Z] =============================================== [2024-12-06T22:27:49.362Z] renaissance-als_0 Start Time: Fri Dec 6 22:27:48 2024 Epoch Time (ms): 1733524068809 [2024-12-06T22:27:49.362Z] variation: NoOptions [2024-12-06T22:27:49.362Z] JVM_OPTIONS: [2024-12-06T22:27:49.362Z] { \ [2024-12-06T22:27:49.362Z] echo ""; echo "TEST SETUP:"; \ [2024-12-06T22:27:49.362Z] echo "Nothing to be done for setup."; \ [2024-12-06T22:27:49.362Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17335240229460/renaissance-als_0"; \ [2024-12-06T22:27:49.362Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17335240229460/renaissance-als_0"; \ [2024-12-06T22:27:49.362Z] echo ""; echo "TESTING:"; \ [2024-12-06T22:27:49.362Z] "/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_17335240229460/renaissance-als_0"/als.json" als; \ [2024-12-06T22:27:49.362Z] 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_17335240229460/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-06T22:27:49.362Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-06T22:27:49.362Z] echo "Nothing to be done for teardown."; \ [2024-12-06T22:27:49.362Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17335240229460/TestTargetResult"; [2024-12-06T22:27:49.362Z] [2024-12-06T22:27:49.362Z] TEST SETUP: [2024-12-06T22:27:49.362Z] Nothing to be done for setup. [2024-12-06T22:27:49.362Z] [2024-12-06T22:27:49.362Z] TESTING: [2024-12-06T22:27:55.012Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-06T22:27:56.617Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2024-12-06T22:28:01.086Z] WARNING: An illegal reflective access operation has occurred [2024-12-06T22:28:01.086Z] 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_17335240229460/renaissance-als_0/harness-222749-11462458686619082655/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-12-06T22:28:01.086Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-12-06T22:28:01.086Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-12-06T22:28:01.086Z] WARNING: All illegal access operations will be denied in a future release [2024-12-06T22:28:03.566Z] ====== als (apache-spark) [default], iteration 0 started ====== [2024-12-06T22:28:03.566Z] GC before operation: completed in 72.672 ms, heap usage 224.303 MB -> 35.337 MB. [2024-12-06T22:28:17.351Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:28:17.351Z] There is no way to check that no silent failure occurred. [2024-12-06T22:28:17.351Z] ====== als (apache-spark) [default], iteration 0 completed (13797.401 ms) ====== [2024-12-06T22:28:17.351Z] ====== als (apache-spark) [default], iteration 1 started ====== [2024-12-06T22:28:17.351Z] GC before operation: completed in 110.382 ms, heap usage 695.437 MB -> 58.017 MB. [2024-12-06T22:28:22.966Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:28:22.966Z] There is no way to check that no silent failure occurred. [2024-12-06T22:28:22.966Z] ====== als (apache-spark) [default], iteration 1 completed (5635.547 ms) ====== [2024-12-06T22:28:22.966Z] ====== als (apache-spark) [default], iteration 2 started ====== [2024-12-06T22:28:22.966Z] GC before operation: completed in 116.840 ms, heap usage 504.552 MB -> 60.303 MB. [2024-12-06T22:28:28.589Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:28:28.589Z] There is no way to check that no silent failure occurred. [2024-12-06T22:28:28.589Z] ====== als (apache-spark) [default], iteration 2 completed (5508.836 ms) ====== [2024-12-06T22:28:28.589Z] ====== als (apache-spark) [default], iteration 3 started ====== [2024-12-06T22:28:28.589Z] GC before operation: completed in 103.766 ms, heap usage 502.313 MB -> 60.779 MB. [2024-12-06T22:28:34.201Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:28:34.201Z] There is no way to check that no silent failure occurred. [2024-12-06T22:28:34.201Z] ====== als (apache-spark) [default], iteration 3 completed (5407.158 ms) ====== [2024-12-06T22:28:34.201Z] ====== als (apache-spark) [default], iteration 4 started ====== [2024-12-06T22:28:34.201Z] GC before operation: completed in 133.997 ms, heap usage 1.210 GB -> 61.941 MB. [2024-12-06T22:28:39.825Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:28:39.825Z] There is no way to check that no silent failure occurred. [2024-12-06T22:28:39.825Z] ====== als (apache-spark) [default], iteration 4 completed (5319.244 ms) ====== [2024-12-06T22:28:39.825Z] ====== als (apache-spark) [default], iteration 5 started ====== [2024-12-06T22:28:39.825Z] GC before operation: completed in 121.555 ms, heap usage 254.325 MB -> 61.163 MB. [2024-12-06T22:28:45.458Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:28:45.458Z] There is no way to check that no silent failure occurred. [2024-12-06T22:28:45.458Z] ====== als (apache-spark) [default], iteration 5 completed (5215.399 ms) ====== [2024-12-06T22:28:45.458Z] ====== als (apache-spark) [default], iteration 6 started ====== [2024-12-06T22:28:45.458Z] GC before operation: completed in 109.627 ms, heap usage 261.571 MB -> 61.442 MB. [2024-12-06T22:28:51.069Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:28:51.069Z] There is no way to check that no silent failure occurred. [2024-12-06T22:28:51.069Z] ====== als (apache-spark) [default], iteration 6 completed (5297.469 ms) ====== [2024-12-06T22:28:51.069Z] ====== als (apache-spark) [default], iteration 7 started ====== [2024-12-06T22:28:51.069Z] GC before operation: completed in 128.168 ms, heap usage 609.345 MB -> 62.336 MB. [2024-12-06T22:28:55.545Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:28:55.545Z] There is no way to check that no silent failure occurred. [2024-12-06T22:28:55.545Z] ====== als (apache-spark) [default], iteration 7 completed (5137.188 ms) ====== [2024-12-06T22:28:55.545Z] ====== als (apache-spark) [default], iteration 8 started ====== [2024-12-06T22:28:55.545Z] GC before operation: completed in 106.509 ms, heap usage 494.029 MB -> 62.537 MB. [2024-12-06T22:29:01.161Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:01.161Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:01.161Z] ====== als (apache-spark) [default], iteration 8 completed (5117.564 ms) ====== [2024-12-06T22:29:01.161Z] ====== als (apache-spark) [default], iteration 9 started ====== [2024-12-06T22:29:01.161Z] GC before operation: completed in 129.270 ms, heap usage 717.057 MB -> 63.209 MB. [2024-12-06T22:29:06.768Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:06.768Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:06.768Z] ====== als (apache-spark) [default], iteration 9 completed (5117.598 ms) ====== [2024-12-06T22:29:06.768Z] ====== als (apache-spark) [default], iteration 10 started ====== [2024-12-06T22:29:06.768Z] GC before operation: completed in 125.155 ms, heap usage 353.202 MB -> 62.928 MB. [2024-12-06T22:29:11.429Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:11.429Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:11.429Z] ====== als (apache-spark) [default], iteration 10 completed (5102.932 ms) ====== [2024-12-06T22:29:11.429Z] ====== als (apache-spark) [default], iteration 11 started ====== [2024-12-06T22:29:11.429Z] GC before operation: completed in 126.208 ms, heap usage 890.515 MB -> 64.143 MB. [2024-12-06T22:29:17.059Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:17.059Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:17.059Z] ====== als (apache-spark) [default], iteration 11 completed (5139.667 ms) ====== [2024-12-06T22:29:17.059Z] ====== als (apache-spark) [default], iteration 12 started ====== [2024-12-06T22:29:17.059Z] GC before operation: completed in 127.470 ms, heap usage 614.859 MB -> 64.011 MB. [2024-12-06T22:29:21.536Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:21.536Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:21.536Z] ====== als (apache-spark) [default], iteration 12 completed (5143.172 ms) ====== [2024-12-06T22:29:21.536Z] ====== als (apache-spark) [default], iteration 13 started ====== [2024-12-06T22:29:22.310Z] GC before operation: completed in 142.163 ms, heap usage 1.036 GB -> 64.866 MB. [2024-12-06T22:29:27.946Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:27.946Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:27.946Z] ====== als (apache-spark) [default], iteration 13 completed (5191.179 ms) ====== [2024-12-06T22:29:27.946Z] ====== als (apache-spark) [default], iteration 14 started ====== [2024-12-06T22:29:27.946Z] GC before operation: completed in 144.858 ms, heap usage 530.646 MB -> 64.634 MB. [2024-12-06T22:29:32.419Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:32.419Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:32.419Z] ====== als (apache-spark) [default], iteration 14 completed (5126.655 ms) ====== [2024-12-06T22:29:32.419Z] ====== als (apache-spark) [default], iteration 15 started ====== [2024-12-06T22:29:32.419Z] GC before operation: completed in 130.301 ms, heap usage 438.155 MB -> 64.878 MB. [2024-12-06T22:29:38.040Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:38.040Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:38.040Z] ====== als (apache-spark) [default], iteration 15 completed (5121.072 ms) ====== [2024-12-06T22:29:38.040Z] ====== als (apache-spark) [default], iteration 16 started ====== [2024-12-06T22:29:38.040Z] GC before operation: completed in 129.118 ms, heap usage 582.494 MB -> 65.367 MB. [2024-12-06T22:29:43.653Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:43.653Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:43.653Z] ====== als (apache-spark) [default], iteration 16 completed (5133.432 ms) ====== [2024-12-06T22:29:43.653Z] ====== als (apache-spark) [default], iteration 17 started ====== [2024-12-06T22:29:43.653Z] GC before operation: completed in 138.576 ms, heap usage 532.828 MB -> 65.669 MB. [2024-12-06T22:29:48.126Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:48.126Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:48.126Z] ====== als (apache-spark) [default], iteration 17 completed (5104.618 ms) ====== [2024-12-06T22:29:48.126Z] ====== als (apache-spark) [default], iteration 18 started ====== [2024-12-06T22:29:48.126Z] GC before operation: completed in 133.400 ms, heap usage 899.296 MB -> 66.337 MB. [2024-12-06T22:29:53.734Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:53.734Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:53.734Z] ====== als (apache-spark) [default], iteration 18 completed (5046.851 ms) ====== [2024-12-06T22:29:53.735Z] ====== als (apache-spark) [default], iteration 19 started ====== [2024-12-06T22:29:53.735Z] GC before operation: completed in 129.049 ms, heap usage 493.080 MB -> 65.965 MB. [2024-12-06T22:29:59.373Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:29:59.373Z] There is no way to check that no silent failure occurred. [2024-12-06T22:29:59.373Z] ====== als (apache-spark) [default], iteration 19 completed (5142.753 ms) ====== [2024-12-06T22:29:59.373Z] ====== als (apache-spark) [default], iteration 20 started ====== [2024-12-06T22:29:59.373Z] GC before operation: completed in 133.957 ms, heap usage 410.211 MB -> 66.344 MB. [2024-12-06T22:30:03.851Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:03.851Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:03.851Z] ====== als (apache-spark) [default], iteration 20 completed (5123.198 ms) ====== [2024-12-06T22:30:03.851Z] ====== als (apache-spark) [default], iteration 21 started ====== [2024-12-06T22:30:03.851Z] GC before operation: completed in 150.224 ms, heap usage 736.583 MB -> 66.670 MB. [2024-12-06T22:30:09.489Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:09.489Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:09.489Z] ====== als (apache-spark) [default], iteration 21 completed (5175.647 ms) ====== [2024-12-06T22:30:09.489Z] ====== als (apache-spark) [default], iteration 22 started ====== [2024-12-06T22:30:09.489Z] GC before operation: completed in 138.359 ms, heap usage 536.744 MB -> 66.840 MB. [2024-12-06T22:30:15.101Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:15.101Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:15.101Z] ====== als (apache-spark) [default], iteration 22 completed (5176.322 ms) ====== [2024-12-06T22:30:15.101Z] ====== als (apache-spark) [default], iteration 23 started ====== [2024-12-06T22:30:15.101Z] GC before operation: completed in 142.658 ms, heap usage 289.791 MB -> 66.692 MB. [2024-12-06T22:30:19.568Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:19.568Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:19.568Z] ====== als (apache-spark) [default], iteration 23 completed (5109.452 ms) ====== [2024-12-06T22:30:19.568Z] ====== als (apache-spark) [default], iteration 24 started ====== [2024-12-06T22:30:20.340Z] GC before operation: completed in 133.846 ms, heap usage 619.999 MB -> 67.456 MB. [2024-12-06T22:30:24.808Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:24.808Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:24.808Z] ====== als (apache-spark) [default], iteration 24 completed (5087.114 ms) ====== [2024-12-06T22:30:24.808Z] ====== als (apache-spark) [default], iteration 25 started ====== [2024-12-06T22:30:25.582Z] GC before operation: completed in 131.642 ms, heap usage 737.422 MB -> 67.730 MB. [2024-12-06T22:30:30.055Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:30.055Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:30.055Z] ====== als (apache-spark) [default], iteration 25 completed (5037.275 ms) ====== [2024-12-06T22:30:30.055Z] ====== als (apache-spark) [default], iteration 26 started ====== [2024-12-06T22:30:30.055Z] GC before operation: completed in 123.301 ms, heap usage 314.073 MB -> 67.264 MB. [2024-12-06T22:30:35.666Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:35.666Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:35.666Z] ====== als (apache-spark) [default], iteration 26 completed (5065.068 ms) ====== [2024-12-06T22:30:35.666Z] ====== als (apache-spark) [default], iteration 27 started ====== [2024-12-06T22:30:35.666Z] GC before operation: completed in 136.577 ms, heap usage 338.629 MB -> 67.596 MB. [2024-12-06T22:30:41.273Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:41.273Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:41.273Z] ====== als (apache-spark) [default], iteration 27 completed (5028.717 ms) ====== [2024-12-06T22:30:41.273Z] ====== als (apache-spark) [default], iteration 28 started ====== [2024-12-06T22:30:41.273Z] GC before operation: completed in 120.381 ms, heap usage 484.871 MB -> 67.982 MB. [2024-12-06T22:30:45.764Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:45.764Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:45.764Z] ====== als (apache-spark) [default], iteration 28 completed (5141.972 ms) ====== [2024-12-06T22:30:45.764Z] ====== als (apache-spark) [default], iteration 29 started ====== [2024-12-06T22:30:45.764Z] GC before operation: completed in 142.265 ms, heap usage 318.743 MB -> 68.013 MB. [2024-12-06T22:30:51.396Z] WARNING: This benchmark provides no result that can be validated. [2024-12-06T22:30:51.396Z] There is no way to check that no silent failure occurred. [2024-12-06T22:30:51.396Z] ====== als (apache-spark) [default], iteration 29 completed (5024.677 ms) ====== [2024-12-06T22:30:51.396Z] ----------------------------------- [2024-12-06T22:30:51.396Z] renaissance-als_0_PASSED [2024-12-06T22:30:51.396Z] ----------------------------------- [2024-12-06T22:30:51.396Z] [2024-12-06T22:30:51.396Z] TEST TEARDOWN: [2024-12-06T22:30:51.396Z] Nothing to be done for teardown. [2024-12-06T22:30:52.168Z] renaissance-als_0 Finish Time: Fri Dec 6 22:30:51 2024 Epoch Time (ms): 1733524251307