[2025-01-22T08:37:07.622Z] Running test renaissance-als_0 ... [2025-01-22T08:37:07.622Z] =============================================== [2025-01-22T08:37:07.622Z] renaissance-als_0 Start Time: Wed Jan 22 08:37:07 2025 Epoch Time (ms): 1737535027108 [2025-01-22T08:37:07.622Z] variation: NoOptions [2025-01-22T08:37:07.622Z] JVM_OPTIONS: [2025-01-22T08:37:07.622Z] { \ [2025-01-22T08:37:07.622Z] echo ""; echo "TEST SETUP:"; \ [2025-01-22T08:37:07.622Z] echo "Nothing to be done for setup."; \ [2025-01-22T08:37:07.622Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17375349804926/renaissance-als_0"; \ [2025-01-22T08:37:07.622Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17375349804926/renaissance-als_0"; \ [2025-01-22T08:37:07.622Z] echo ""; echo "TESTING:"; \ [2025-01-22T08:37:07.622Z] "/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_17375349804926/renaissance-als_0"/als.json" als; \ [2025-01-22T08:37:07.622Z] 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_17375349804926/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-22T08:37:07.622Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-22T08:37:07.622Z] echo "Nothing to be done for teardown."; \ [2025-01-22T08:37:07.622Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17375349804926/TestTargetResult"; [2025-01-22T08:37:07.622Z] [2025-01-22T08:37:07.622Z] TEST SETUP: [2025-01-22T08:37:07.622Z] Nothing to be done for setup. [2025-01-22T08:37:07.622Z] [2025-01-22T08:37:07.622Z] TESTING: [2025-01-22T08:37:13.220Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-22T08:37:15.691Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-01-22T08:37:19.123Z] WARNING: An illegal reflective access operation has occurred [2025-01-22T08:37:19.123Z] 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_17375349804926/renaissance-als_0/harness-083708-11921615936482442429/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-01-22T08:37:19.123Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-01-22T08:37:19.123Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-01-22T08:37:19.123Z] WARNING: All illegal access operations will be denied in a future release [2025-01-22T08:37:21.599Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-01-22T08:37:21.599Z] GC before operation: completed in 68.238 ms, heap usage 217.797 MB -> 35.347 MB. [2025-01-22T08:37:37.734Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:37:37.734Z] There is no way to check that no silent failure occurred. [2025-01-22T08:37:37.734Z] ====== als (apache-spark) [default], iteration 0 completed (14860.432 ms) ====== [2025-01-22T08:37:37.734Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-01-22T08:37:37.734Z] GC before operation: completed in 113.326 ms, heap usage 313.172 MB -> 57.583 MB. [2025-01-22T08:37:42.193Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:37:42.193Z] There is no way to check that no silent failure occurred. [2025-01-22T08:37:42.193Z] ====== als (apache-spark) [default], iteration 1 completed (5664.604 ms) ====== [2025-01-22T08:37:42.193Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-01-22T08:37:42.193Z] GC before operation: completed in 124.040 ms, heap usage 481.704 MB -> 60.454 MB. [2025-01-22T08:37:47.798Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:37:47.798Z] There is no way to check that no silent failure occurred. [2025-01-22T08:37:47.798Z] ====== als (apache-spark) [default], iteration 2 completed (5537.192 ms) ====== [2025-01-22T08:37:47.798Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-01-22T08:37:48.570Z] GC before operation: completed in 130.265 ms, heap usage 694.848 MB -> 60.968 MB. [2025-01-22T08:37:54.194Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:37:54.194Z] There is no way to check that no silent failure occurred. [2025-01-22T08:37:54.194Z] ====== als (apache-spark) [default], iteration 3 completed (5520.334 ms) ====== [2025-01-22T08:37:54.194Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-01-22T08:37:54.194Z] GC before operation: completed in 135.662 ms, heap usage 568.856 MB -> 61.308 MB. [2025-01-22T08:37:59.949Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:37:59.949Z] There is no way to check that no silent failure occurred. [2025-01-22T08:37:59.949Z] ====== als (apache-spark) [default], iteration 4 completed (5442.683 ms) ====== [2025-01-22T08:37:59.949Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-01-22T08:37:59.949Z] GC before operation: completed in 133.916 ms, heap usage 814.059 MB -> 61.796 MB. [2025-01-22T08:38:05.570Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:05.570Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:05.570Z] ====== als (apache-spark) [default], iteration 5 completed (5337.422 ms) ====== [2025-01-22T08:38:05.570Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-01-22T08:38:05.570Z] GC before operation: completed in 128.120 ms, heap usage 910.598 MB -> 62.412 MB. [2025-01-22T08:38:10.035Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:10.035Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:10.035Z] ====== als (apache-spark) [default], iteration 6 completed (5115.082 ms) ====== [2025-01-22T08:38:10.035Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-01-22T08:38:10.035Z] GC before operation: completed in 136.737 ms, heap usage 1.074 GB -> 62.994 MB. [2025-01-22T08:38:15.649Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:15.649Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:15.649Z] ====== als (apache-spark) [default], iteration 7 completed (5221.617 ms) ====== [2025-01-22T08:38:15.649Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-01-22T08:38:15.649Z] GC before operation: completed in 130.892 ms, heap usage 221.548 MB -> 62.090 MB. [2025-01-22T08:38:21.259Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:21.259Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:21.259Z] ====== als (apache-spark) [default], iteration 8 completed (5148.684 ms) ====== [2025-01-22T08:38:21.259Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-01-22T08:38:21.259Z] GC before operation: completed in 122.153 ms, heap usage 752.715 MB -> 63.406 MB. [2025-01-22T08:38:25.735Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:25.735Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:25.735Z] ====== als (apache-spark) [default], iteration 9 completed (5098.215 ms) ====== [2025-01-22T08:38:25.735Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-01-22T08:38:25.735Z] GC before operation: completed in 114.411 ms, heap usage 703.838 MB -> 63.510 MB. [2025-01-22T08:38:31.338Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:31.338Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:31.338Z] ====== als (apache-spark) [default], iteration 10 completed (5153.680 ms) ====== [2025-01-22T08:38:31.338Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-01-22T08:38:31.338Z] GC before operation: completed in 139.775 ms, heap usage 197.230 MB -> 63.195 MB. [2025-01-22T08:38:36.956Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:36.956Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:36.956Z] ====== als (apache-spark) [default], iteration 11 completed (5032.960 ms) ====== [2025-01-22T08:38:36.956Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-01-22T08:38:36.956Z] GC before operation: completed in 130.098 ms, heap usage 530.716 MB -> 64.089 MB. [2025-01-22T08:38:41.425Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:41.425Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:41.425Z] ====== als (apache-spark) [default], iteration 12 completed (5171.316 ms) ====== [2025-01-22T08:38:41.425Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-01-22T08:38:41.425Z] GC before operation: completed in 132.839 ms, heap usage 172.496 MB -> 63.890 MB. [2025-01-22T08:38:47.030Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:47.030Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:47.030Z] ====== als (apache-spark) [default], iteration 13 completed (5218.050 ms) ====== [2025-01-22T08:38:47.030Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-01-22T08:38:47.030Z] GC before operation: completed in 136.652 ms, heap usage 340.952 MB -> 64.414 MB. [2025-01-22T08:38:52.643Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:52.643Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:52.643Z] ====== als (apache-spark) [default], iteration 14 completed (5043.386 ms) ====== [2025-01-22T08:38:52.643Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-01-22T08:38:52.643Z] GC before operation: completed in 146.283 ms, heap usage 910.524 MB -> 65.576 MB. [2025-01-22T08:38:57.377Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:38:57.377Z] There is no way to check that no silent failure occurred. [2025-01-22T08:38:57.377Z] ====== als (apache-spark) [default], iteration 15 completed (5118.282 ms) ====== [2025-01-22T08:38:57.377Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-01-22T08:38:57.377Z] GC before operation: completed in 126.580 ms, heap usage 641.603 MB -> 65.596 MB. [2025-01-22T08:39:02.979Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:02.979Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:02.979Z] ====== als (apache-spark) [default], iteration 16 completed (5135.150 ms) ====== [2025-01-22T08:39:02.979Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-01-22T08:39:02.979Z] GC before operation: completed in 144.688 ms, heap usage 1003.109 MB -> 66.309 MB. [2025-01-22T08:39:08.591Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:08.591Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:08.591Z] ====== als (apache-spark) [default], iteration 17 completed (5248.810 ms) ====== [2025-01-22T08:39:08.591Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-01-22T08:39:08.591Z] GC before operation: completed in 143.509 ms, heap usage 787.811 MB -> 66.463 MB. [2025-01-22T08:39:14.195Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:14.195Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:14.195Z] ====== als (apache-spark) [default], iteration 18 completed (5241.569 ms) ====== [2025-01-22T08:39:14.195Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-01-22T08:39:14.195Z] GC before operation: completed in 142.289 ms, heap usage 752.110 MB -> 66.475 MB. [2025-01-22T08:39:18.664Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:18.664Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:18.664Z] ====== als (apache-spark) [default], iteration 19 completed (5126.589 ms) ====== [2025-01-22T08:39:18.664Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-01-22T08:39:18.664Z] GC before operation: completed in 125.226 ms, heap usage 555.216 MB -> 66.535 MB. [2025-01-22T08:39:24.275Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:24.275Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:24.275Z] ====== als (apache-spark) [default], iteration 20 completed (5168.499 ms) ====== [2025-01-22T08:39:24.275Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-01-22T08:39:24.275Z] GC before operation: completed in 139.758 ms, heap usage 399.343 MB -> 66.489 MB. [2025-01-22T08:39:29.886Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:29.886Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:29.886Z] ====== als (apache-spark) [default], iteration 21 completed (5172.197 ms) ====== [2025-01-22T08:39:29.886Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-01-22T08:39:29.886Z] GC before operation: completed in 150.598 ms, heap usage 1.011 GB -> 67.579 MB. [2025-01-22T08:39:34.368Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:34.368Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:34.368Z] ====== als (apache-spark) [default], iteration 22 completed (5099.386 ms) ====== [2025-01-22T08:39:34.368Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-01-22T08:39:35.138Z] GC before operation: completed in 129.694 ms, heap usage 626.043 MB -> 67.191 MB. [2025-01-22T08:39:39.599Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:39.599Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:39.599Z] ====== als (apache-spark) [default], iteration 23 completed (5102.964 ms) ====== [2025-01-22T08:39:39.599Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-01-22T08:39:40.368Z] GC before operation: completed in 122.086 ms, heap usage 179.708 MB -> 66.921 MB. [2025-01-22T08:39:44.848Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:44.848Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:44.848Z] ====== als (apache-spark) [default], iteration 24 completed (5038.994 ms) ====== [2025-01-22T08:39:44.848Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-01-22T08:39:44.848Z] GC before operation: completed in 122.171 ms, heap usage 322.595 MB -> 67.114 MB. [2025-01-22T08:39:50.221Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:50.221Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:50.221Z] ====== als (apache-spark) [default], iteration 25 completed (5103.080 ms) ====== [2025-01-22T08:39:50.221Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-01-22T08:39:50.221Z] GC before operation: completed in 136.705 ms, heap usage 248.129 MB -> 67.090 MB. [2025-01-22T08:39:55.844Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:39:55.844Z] There is no way to check that no silent failure occurred. [2025-01-22T08:39:55.844Z] ====== als (apache-spark) [default], iteration 26 completed (5093.054 ms) ====== [2025-01-22T08:39:55.844Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-01-22T08:39:55.844Z] GC before operation: completed in 159.273 ms, heap usage 435.677 MB -> 67.709 MB. [2025-01-22T08:40:00.308Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:40:00.308Z] There is no way to check that no silent failure occurred. [2025-01-22T08:40:00.308Z] ====== als (apache-spark) [default], iteration 27 completed (5021.472 ms) ====== [2025-01-22T08:40:00.308Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-01-22T08:40:01.078Z] GC before operation: completed in 124.042 ms, heap usage 213.154 MB -> 67.438 MB. [2025-01-22T08:40:05.550Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:40:05.550Z] There is no way to check that no silent failure occurred. [2025-01-22T08:40:05.550Z] ====== als (apache-spark) [default], iteration 28 completed (5059.233 ms) ====== [2025-01-22T08:40:05.550Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-01-22T08:40:06.326Z] GC before operation: completed in 148.167 ms, heap usage 280.389 MB -> 67.961 MB. [2025-01-22T08:40:10.785Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T08:40:10.785Z] There is no way to check that no silent failure occurred. [2025-01-22T08:40:10.785Z] ====== als (apache-spark) [default], iteration 29 completed (5063.713 ms) ====== [2025-01-22T08:40:11.557Z] ----------------------------------- [2025-01-22T08:40:11.557Z] renaissance-als_0_PASSED [2025-01-22T08:40:11.557Z] ----------------------------------- [2025-01-22T08:40:11.557Z] [2025-01-22T08:40:11.557Z] TEST TEARDOWN: [2025-01-22T08:40:11.557Z] Nothing to be done for teardown. [2025-01-22T08:40:11.557Z] renaissance-als_0 Finish Time: Wed Jan 22 08:40:11 2025 Epoch Time (ms): 1737535211324