[2025-03-05T23:03:49.245Z] Running test renaissance-als_0 ... [2025-03-05T23:03:49.245Z] =============================================== [2025-03-05T23:03:49.245Z] renaissance-als_0 Start Time: Wed Mar 5 23:03:49 2025 Epoch Time (ms): 1741215829033 [2025-03-05T23:03:49.245Z] variation: NoOptions [2025-03-05T23:03:49.245Z] JVM_OPTIONS: [2025-03-05T23:03:49.245Z] { \ [2025-03-05T23:03:49.245Z] echo ""; echo "TEST SETUP:"; \ [2025-03-05T23:03:49.245Z] echo "Nothing to be done for setup."; \ [2025-03-05T23:03:49.245Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1741215762743/renaissance-als_0"; \ [2025-03-05T23:03:49.245Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1741215762743/renaissance-als_0"; \ [2025-03-05T23:03:49.245Z] echo ""; echo "TESTING:"; \ [2025-03-05T23:03:49.245Z] "/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_1741215762743/renaissance-als_0"/als.json" als; \ [2025-03-05T23:03:49.245Z] 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_1741215762743/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-05T23:03:49.245Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-05T23:03:49.245Z] echo "Nothing to be done for teardown."; \ [2025-03-05T23:03:49.245Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_1741215762743/TestTargetResult"; [2025-03-05T23:03:49.245Z] [2025-03-05T23:03:49.245Z] TEST SETUP: [2025-03-05T23:03:49.245Z] Nothing to be done for setup. [2025-03-05T23:03:49.245Z] [2025-03-05T23:03:49.245Z] TESTING: [2025-03-05T23:04:37.815Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-03-05T23:04:43.855Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-03-05T23:04:52.171Z] WARNING: An illegal reflective access operation has occurred [2025-03-05T23:04:52.171Z] 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_1741215762743/renaissance-als_0/harness-230351-11489794606011812457/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-03-05T23:04:52.171Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-05T23:04:52.171Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-05T23:04:52.171Z] WARNING: All illegal access operations will be denied in a future release [2025-03-05T23:04:56.748Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-03-05T23:04:56.748Z] GC before operation: completed in 64.665 ms, heap usage 147.760 MB -> 34.571 MB. [2025-03-05T23:05:26.951Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:05:26.951Z] There is no way to check that no silent failure occurred. [2025-03-05T23:05:26.951Z] ====== als (apache-spark) [default], iteration 0 completed (27991.637 ms) ====== [2025-03-05T23:05:26.951Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-03-05T23:05:26.951Z] GC before operation: completed in 126.585 ms, heap usage 242.244 MB -> 56.612 MB. [2025-03-05T23:05:45.928Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:05:45.928Z] There is no way to check that no silent failure occurred. [2025-03-05T23:05:45.928Z] ====== als (apache-spark) [default], iteration 1 completed (20493.212 ms) ====== [2025-03-05T23:05:45.928Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-03-05T23:05:45.928Z] GC before operation: completed in 151.434 ms, heap usage 411.493 MB -> 59.447 MB. [2025-03-05T23:06:08.036Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:06:08.036Z] There is no way to check that no silent failure occurred. [2025-03-05T23:06:08.036Z] ====== als (apache-spark) [default], iteration 2 completed (22845.140 ms) ====== [2025-03-05T23:06:08.036Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-03-05T23:06:08.036Z] GC before operation: completed in 179.835 ms, heap usage 1.098 GB -> 60.711 MB. [2025-03-05T23:06:32.583Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:06:32.583Z] There is no way to check that no silent failure occurred. [2025-03-05T23:06:32.583Z] ====== als (apache-spark) [default], iteration 3 completed (21844.044 ms) ====== [2025-03-05T23:06:32.583Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-03-05T23:06:32.583Z] GC before operation: completed in 138.601 ms, heap usage 744.365 MB -> 60.576 MB. [2025-03-05T23:06:48.755Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:06:48.755Z] There is no way to check that no silent failure occurred. [2025-03-05T23:06:48.755Z] ====== als (apache-spark) [default], iteration 4 completed (18817.830 ms) ====== [2025-03-05T23:06:48.755Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-03-05T23:06:48.755Z] GC before operation: completed in 146.322 ms, heap usage 291.379 MB -> 60.437 MB. [2025-03-05T23:07:14.594Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:07:14.594Z] There is no way to check that no silent failure occurred. [2025-03-05T23:07:14.595Z] ====== als (apache-spark) [default], iteration 5 completed (24116.743 ms) ====== [2025-03-05T23:07:14.595Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-03-05T23:07:14.595Z] GC before operation: completed in 180.006 ms, heap usage 353.552 MB -> 60.795 MB. [2025-03-05T23:07:44.886Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:07:44.886Z] There is no way to check that no silent failure occurred. [2025-03-05T23:07:44.886Z] ====== als (apache-spark) [default], iteration 6 completed (28962.376 ms) ====== [2025-03-05T23:07:44.886Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-03-05T23:07:44.886Z] GC before operation: completed in 172.696 ms, heap usage 533.550 MB -> 61.425 MB. [2025-03-05T23:08:07.182Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:08:07.182Z] There is no way to check that no silent failure occurred. [2025-03-05T23:08:07.182Z] ====== als (apache-spark) [default], iteration 7 completed (22354.566 ms) ====== [2025-03-05T23:08:07.182Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-03-05T23:08:07.182Z] GC before operation: completed in 157.498 ms, heap usage 294.313 MB -> 61.469 MB. [2025-03-05T23:08:48.880Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:08:48.880Z] There is no way to check that no silent failure occurred. [2025-03-05T23:08:48.880Z] ====== als (apache-spark) [default], iteration 8 completed (39229.061 ms) ====== [2025-03-05T23:08:48.880Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-03-05T23:08:48.880Z] GC before operation: completed in 145.241 ms, heap usage 238.847 MB -> 61.632 MB. [2025-03-05T23:09:19.088Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:09:19.088Z] There is no way to check that no silent failure occurred. [2025-03-05T23:09:19.088Z] ====== als (apache-spark) [default], iteration 9 completed (34548.084 ms) ====== [2025-03-05T23:09:19.088Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-03-05T23:09:19.088Z] GC before operation: completed in 136.295 ms, heap usage 241.340 MB -> 62.127 MB. [2025-03-05T23:10:00.465Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:10:00.465Z] There is no way to check that no silent failure occurred. [2025-03-05T23:10:00.465Z] ====== als (apache-spark) [default], iteration 10 completed (40164.624 ms) ====== [2025-03-05T23:10:00.465Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-03-05T23:10:00.465Z] GC before operation: completed in 186.068 ms, heap usage 250.334 MB -> 62.407 MB. [2025-03-05T23:10:35.808Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:10:35.808Z] There is no way to check that no silent failure occurred. [2025-03-05T23:10:35.808Z] ====== als (apache-spark) [default], iteration 11 completed (31933.951 ms) ====== [2025-03-05T23:10:35.808Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-03-05T23:10:35.808Z] GC before operation: completed in 140.556 ms, heap usage 297.657 MB -> 63.651 MB. [2025-03-05T23:10:49.623Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:10:49.623Z] There is no way to check that no silent failure occurred. [2025-03-05T23:10:49.623Z] ====== als (apache-spark) [default], iteration 12 completed (16644.498 ms) ====== [2025-03-05T23:10:49.623Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-03-05T23:10:49.623Z] GC before operation: completed in 187.859 ms, heap usage 477.127 MB -> 63.426 MB. [2025-03-05T23:11:03.428Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:11:03.428Z] There is no way to check that no silent failure occurred. [2025-03-05T23:11:03.428Z] ====== als (apache-spark) [default], iteration 13 completed (13820.168 ms) ====== [2025-03-05T23:11:03.428Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-03-05T23:11:03.428Z] GC before operation: completed in 174.799 ms, heap usage 557.867 MB -> 63.870 MB. [2025-03-05T23:11:25.538Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:11:25.538Z] There is no way to check that no silent failure occurred. [2025-03-05T23:11:25.538Z] ====== als (apache-spark) [default], iteration 14 completed (23077.028 ms) ====== [2025-03-05T23:11:25.538Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-03-05T23:11:25.538Z] GC before operation: completed in 205.206 ms, heap usage 886.533 MB -> 64.652 MB. [2025-03-05T23:11:47.688Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:11:47.688Z] There is no way to check that no silent failure occurred. [2025-03-05T23:11:47.688Z] ====== als (apache-spark) [default], iteration 15 completed (20272.231 ms) ====== [2025-03-05T23:11:47.688Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-03-05T23:11:47.688Z] GC before operation: completed in 172.892 ms, heap usage 563.409 MB -> 64.576 MB. [2025-03-05T23:12:06.626Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:12:06.626Z] There is no way to check that no silent failure occurred. [2025-03-05T23:12:06.626Z] ====== als (apache-spark) [default], iteration 16 completed (20187.763 ms) ====== [2025-03-05T23:12:06.626Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-03-05T23:12:06.626Z] GC before operation: completed in 175.969 ms, heap usage 1018.380 MB -> 65.444 MB. [2025-03-05T23:12:29.140Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:12:29.140Z] There is no way to check that no silent failure occurred. [2025-03-05T23:12:29.140Z] ====== als (apache-spark) [default], iteration 17 completed (21914.749 ms) ====== [2025-03-05T23:12:29.140Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-03-05T23:12:29.140Z] GC before operation: completed in 198.006 ms, heap usage 380.231 MB -> 65.251 MB. [2025-03-05T23:12:51.470Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:12:51.470Z] There is no way to check that no silent failure occurred. [2025-03-05T23:12:51.470Z] ====== als (apache-spark) [default], iteration 18 completed (22629.304 ms) ====== [2025-03-05T23:12:51.470Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-03-05T23:12:51.470Z] GC before operation: completed in 193.979 ms, heap usage 232.149 MB -> 64.926 MB. [2025-03-05T23:13:17.325Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:13:17.325Z] There is no way to check that no silent failure occurred. [2025-03-05T23:13:17.325Z] ====== als (apache-spark) [default], iteration 19 completed (22910.004 ms) ====== [2025-03-05T23:13:17.325Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-03-05T23:13:17.325Z] GC before operation: completed in 198.932 ms, heap usage 417.852 MB -> 65.484 MB. [2025-03-05T23:13:39.464Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:13:39.464Z] There is no way to check that no silent failure occurred. [2025-03-05T23:13:39.464Z] ====== als (apache-spark) [default], iteration 20 completed (22592.246 ms) ====== [2025-03-05T23:13:39.464Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-03-05T23:13:39.464Z] GC before operation: completed in 185.772 ms, heap usage 308.717 MB -> 65.460 MB. [2025-03-05T23:14:05.460Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:14:05.460Z] There is no way to check that no silent failure occurred. [2025-03-05T23:14:05.460Z] ====== als (apache-spark) [default], iteration 21 completed (27011.331 ms) ====== [2025-03-05T23:14:05.460Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-03-05T23:14:05.460Z] GC before operation: completed in 192.729 ms, heap usage 286.034 MB -> 65.801 MB. [2025-03-05T23:14:31.384Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:14:31.384Z] There is no way to check that no silent failure occurred. [2025-03-05T23:14:31.384Z] ====== als (apache-spark) [default], iteration 22 completed (25986.087 ms) ====== [2025-03-05T23:14:31.384Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-03-05T23:14:31.384Z] GC before operation: completed in 210.148 ms, heap usage 638.748 MB -> 66.269 MB. [2025-03-05T23:14:53.500Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:14:53.500Z] There is no way to check that no silent failure occurred. [2025-03-05T23:14:53.500Z] ====== als (apache-spark) [default], iteration 23 completed (20679.594 ms) ====== [2025-03-05T23:14:53.500Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-03-05T23:14:53.500Z] GC before operation: completed in 216.274 ms, heap usage 1.029 GB -> 67.078 MB. [2025-03-05T23:15:23.680Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:15:23.680Z] There is no way to check that no silent failure occurred. [2025-03-05T23:15:23.680Z] ====== als (apache-spark) [default], iteration 24 completed (28548.464 ms) ====== [2025-03-05T23:15:23.680Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-03-05T23:15:23.680Z] GC before operation: completed in 243.331 ms, heap usage 581.039 MB -> 66.622 MB. [2025-03-05T23:15:44.815Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:15:44.815Z] There is no way to check that no silent failure occurred. [2025-03-05T23:15:44.815Z] ====== als (apache-spark) [default], iteration 25 completed (20604.396 ms) ====== [2025-03-05T23:15:44.815Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-03-05T23:15:44.815Z] GC before operation: completed in 235.282 ms, heap usage 838.106 MB -> 67.068 MB. [2025-03-05T23:16:03.717Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:16:03.717Z] There is no way to check that no silent failure occurred. [2025-03-05T23:16:03.717Z] ====== als (apache-spark) [default], iteration 26 completed (21316.999 ms) ====== [2025-03-05T23:16:03.717Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-03-05T23:16:03.717Z] GC before operation: completed in 214.107 ms, heap usage 831.924 MB -> 67.545 MB. [2025-03-05T23:16:39.025Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:16:39.025Z] There is no way to check that no silent failure occurred. [2025-03-05T23:16:39.025Z] ====== als (apache-spark) [default], iteration 27 completed (34811.979 ms) ====== [2025-03-05T23:16:39.025Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-03-05T23:16:39.025Z] GC before operation: completed in 190.997 ms, heap usage 570.719 MB -> 67.115 MB. [2025-03-05T23:16:57.964Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:16:57.964Z] There is no way to check that no silent failure occurred. [2025-03-05T23:16:57.964Z] ====== als (apache-spark) [default], iteration 28 completed (20096.542 ms) ====== [2025-03-05T23:16:57.964Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-03-05T23:16:57.964Z] GC before operation: completed in 217.729 ms, heap usage 519.674 MB -> 67.460 MB. [2025-03-05T23:17:20.067Z] WARNING: This benchmark provides no result that can be validated. [2025-03-05T23:17:20.067Z] There is no way to check that no silent failure occurred. [2025-03-05T23:17:20.067Z] ====== als (apache-spark) [default], iteration 29 completed (21494.579 ms) ====== [2025-03-05T23:17:20.067Z] ----------------------------------- [2025-03-05T23:17:20.067Z] renaissance-als_0_PASSED [2025-03-05T23:17:20.067Z] ----------------------------------- [2025-03-05T23:17:20.067Z] [2025-03-05T23:17:20.067Z] TEST TEARDOWN: [2025-03-05T23:17:20.067Z] Nothing to be done for teardown. [2025-03-05T23:17:20.067Z] renaissance-als_0 Finish Time: Wed Mar 5 23:17:19 2025 Epoch Time (ms): 1741216639867