[2024-08-22T08:00:40.120Z] Running test renaissance-als_0 ... [2024-08-22T08:00:40.120Z] =============================================== [2024-08-22T08:00:40.423Z] renaissance-als_0 Start Time: Thu Aug 22 08:00:40 2024 Epoch Time (ms): 1724313640055 [2024-08-22T08:00:40.423Z] variation: NoOptions [2024-08-22T08:00:40.727Z] JVM_OPTIONS: [2024-08-22T08:00:40.727Z] { \ [2024-08-22T08:00:40.727Z] echo ""; echo "TEST SETUP:"; \ [2024-08-22T08:00:40.727Z] echo "Nothing to be done for setup."; \ [2024-08-22T08:00:40.727Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1724313576892\\renaissance-als_0"; \ [2024-08-22T08:00:40.727Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1724313576892\\renaissance-als_0"; \ [2024-08-22T08:00:40.727Z] echo ""; echo "TESTING:"; \ [2024-08-22T08:00:40.727Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1724313576892\\renaissance-als_0"\\als.json" als; \ [2024-08-22T08:00:40.727Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1724313576892\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-22T08:00:40.727Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-22T08:00:40.727Z] echo "Nothing to be done for teardown."; \ [2024-08-22T08:00:40.727Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1724313576892\\TestTargetResult"; [2024-08-22T08:00:41.042Z] [2024-08-22T08:00:41.042Z] TEST SETUP: [2024-08-22T08:00:41.042Z] Nothing to be done for setup. [2024-08-22T08:00:41.042Z] [2024-08-22T08:00:41.042Z] TESTING: [2024-08-22T08:00:53.953Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-22T08:00:53.953Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-22T08:00:56.807Z] WARNING: An illegal reflective access operation has occurred [2024-08-22T08:00:56.807Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_1724313576892/renaissance-als_0/harness-080041-17918484517304521308/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-22T08:00:56.807Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-22T08:00:56.807Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-22T08:00:56.807Z] WARNING: All illegal access operations will be denied in a future release [2024-08-22T08:00:58.385Z] ====== als (apache-spark) [default], iteration 0 started ====== [2024-08-22T08:00:58.385Z] GC before operation: completed in 86.299 ms, heap usage 99.000 MB -> 34.296 MB. [2024-08-22T08:01:26.396Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:01:26.396Z] There is no way to check that no silent failure occurred. [2024-08-22T08:01:26.396Z] ====== als (apache-spark) [default], iteration 0 completed (26250.129 ms) ====== [2024-08-22T08:01:26.396Z] ====== als (apache-spark) [default], iteration 1 started ====== [2024-08-22T08:01:26.396Z] GC before operation: completed in 116.048 ms, heap usage 166.365 MB -> 56.358 MB. [2024-08-22T08:01:42.182Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:01:42.182Z] There is no way to check that no silent failure occurred. [2024-08-22T08:01:42.182Z] ====== als (apache-spark) [default], iteration 1 completed (16525.190 ms) ====== [2024-08-22T08:01:42.182Z] ====== als (apache-spark) [default], iteration 2 started ====== [2024-08-22T08:01:42.182Z] GC before operation: completed in 143.709 ms, heap usage 217.978 MB -> 59.003 MB. [2024-08-22T08:01:58.370Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:01:58.370Z] There is no way to check that no silent failure occurred. [2024-08-22T08:01:58.370Z] ====== als (apache-spark) [default], iteration 2 completed (16228.940 ms) ====== [2024-08-22T08:01:58.370Z] ====== als (apache-spark) [default], iteration 3 started ====== [2024-08-22T08:01:58.370Z] GC before operation: completed in 121.847 ms, heap usage 372.651 MB -> 59.601 MB. [2024-08-22T08:02:13.982Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:02:13.982Z] There is no way to check that no silent failure occurred. [2024-08-22T08:02:13.982Z] ====== als (apache-spark) [default], iteration 3 completed (16196.726 ms) ====== [2024-08-22T08:02:13.982Z] ====== als (apache-spark) [default], iteration 4 started ====== [2024-08-22T08:02:14.532Z] GC before operation: completed in 129.224 ms, heap usage 218.475 MB -> 59.659 MB. [2024-08-22T08:02:30.427Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:02:30.427Z] There is no way to check that no silent failure occurred. [2024-08-22T08:02:30.427Z] ====== als (apache-spark) [default], iteration 4 completed (15828.051 ms) ====== [2024-08-22T08:02:30.427Z] ====== als (apache-spark) [default], iteration 5 started ====== [2024-08-22T08:02:30.427Z] GC before operation: completed in 126.894 ms, heap usage 241.873 MB -> 60.187 MB. [2024-08-22T08:02:46.217Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:02:46.217Z] There is no way to check that no silent failure occurred. [2024-08-22T08:02:46.217Z] ====== als (apache-spark) [default], iteration 5 completed (15356.300 ms) ====== [2024-08-22T08:02:46.217Z] ====== als (apache-spark) [default], iteration 6 started ====== [2024-08-22T08:02:46.217Z] GC before operation: completed in 129.333 ms, heap usage 405.642 MB -> 60.752 MB. [2024-08-22T08:03:02.008Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:03:02.008Z] There is no way to check that no silent failure occurred. [2024-08-22T08:03:02.008Z] ====== als (apache-spark) [default], iteration 6 completed (15360.916 ms) ====== [2024-08-22T08:03:02.008Z] ====== als (apache-spark) [default], iteration 7 started ====== [2024-08-22T08:03:02.009Z] GC before operation: completed in 124.046 ms, heap usage 500.081 MB -> 61.205 MB. [2024-08-22T08:03:17.723Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:03:17.724Z] There is no way to check that no silent failure occurred. [2024-08-22T08:03:17.724Z] ====== als (apache-spark) [default], iteration 7 completed (15621.459 ms) ====== [2024-08-22T08:03:17.724Z] ====== als (apache-spark) [default], iteration 8 started ====== [2024-08-22T08:03:17.724Z] GC before operation: completed in 129.233 ms, heap usage 401.490 MB -> 61.447 MB. [2024-08-22T08:03:33.350Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:03:33.350Z] There is no way to check that no silent failure occurred. [2024-08-22T08:03:33.350Z] ====== als (apache-spark) [default], iteration 8 completed (15778.195 ms) ====== [2024-08-22T08:03:33.350Z] ====== als (apache-spark) [default], iteration 9 started ====== [2024-08-22T08:03:33.350Z] GC before operation: completed in 129.749 ms, heap usage 227.579 MB -> 61.473 MB. [2024-08-22T08:03:49.007Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:03:49.007Z] There is no way to check that no silent failure occurred. [2024-08-22T08:03:49.007Z] ====== als (apache-spark) [default], iteration 9 completed (15351.203 ms) ====== [2024-08-22T08:03:49.007Z] ====== als (apache-spark) [default], iteration 10 started ====== [2024-08-22T08:03:49.007Z] GC before operation: completed in 146.179 ms, heap usage 395.747 MB -> 62.100 MB. [2024-08-22T08:04:04.704Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:04:04.704Z] There is no way to check that no silent failure occurred. [2024-08-22T08:04:04.704Z] ====== als (apache-spark) [default], iteration 10 completed (15507.138 ms) ====== [2024-08-22T08:04:04.704Z] ====== als (apache-spark) [default], iteration 11 started ====== [2024-08-22T08:04:04.704Z] GC before operation: completed in 151.047 ms, heap usage 482.560 MB -> 62.560 MB. [2024-08-22T08:04:20.399Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:04:20.399Z] There is no way to check that no silent failure occurred. [2024-08-22T08:04:20.399Z] ====== als (apache-spark) [default], iteration 11 completed (15610.763 ms) ====== [2024-08-22T08:04:20.399Z] ====== als (apache-spark) [default], iteration 12 started ====== [2024-08-22T08:04:20.399Z] GC before operation: completed in 148.696 ms, heap usage 276.478 MB -> 62.609 MB. [2024-08-22T08:04:36.027Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:04:36.027Z] There is no way to check that no silent failure occurred. [2024-08-22T08:04:36.027Z] ====== als (apache-spark) [default], iteration 12 completed (15437.471 ms) ====== [2024-08-22T08:04:36.027Z] ====== als (apache-spark) [default], iteration 13 started ====== [2024-08-22T08:04:36.027Z] GC before operation: completed in 141.360 ms, heap usage 218.558 MB -> 62.763 MB. [2024-08-22T08:04:51.691Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:04:51.691Z] There is no way to check that no silent failure occurred. [2024-08-22T08:04:51.691Z] ====== als (apache-spark) [default], iteration 13 completed (15292.601 ms) ====== [2024-08-22T08:04:51.691Z] ====== als (apache-spark) [default], iteration 14 started ====== [2024-08-22T08:04:51.691Z] GC before operation: completed in 134.347 ms, heap usage 328.426 MB -> 63.394 MB. [2024-08-22T08:05:07.385Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:05:07.385Z] There is no way to check that no silent failure occurred. [2024-08-22T08:05:07.385Z] ====== als (apache-spark) [default], iteration 14 completed (15433.430 ms) ====== [2024-08-22T08:05:07.385Z] ====== als (apache-spark) [default], iteration 15 started ====== [2024-08-22T08:05:07.385Z] GC before operation: completed in 149.938 ms, heap usage 437.735 MB -> 63.835 MB. [2024-08-22T08:05:23.043Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:05:23.043Z] There is no way to check that no silent failure occurred. [2024-08-22T08:05:23.043Z] ====== als (apache-spark) [default], iteration 15 completed (15524.583 ms) ====== [2024-08-22T08:05:23.043Z] ====== als (apache-spark) [default], iteration 16 started ====== [2024-08-22T08:05:23.043Z] GC before operation: completed in 131.949 ms, heap usage 514.551 MB -> 64.290 MB. [2024-08-22T08:05:38.603Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:05:38.604Z] There is no way to check that no silent failure occurred. [2024-08-22T08:05:38.604Z] ====== als (apache-spark) [default], iteration 16 completed (15335.007 ms) ====== [2024-08-22T08:05:38.604Z] ====== als (apache-spark) [default], iteration 17 started ====== [2024-08-22T08:05:38.604Z] GC before operation: completed in 127.356 ms, heap usage 239.510 MB -> 64.207 MB. [2024-08-22T08:05:54.276Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:05:54.276Z] There is no way to check that no silent failure occurred. [2024-08-22T08:05:54.276Z] ====== als (apache-spark) [default], iteration 17 completed (15492.466 ms) ====== [2024-08-22T08:05:54.276Z] ====== als (apache-spark) [default], iteration 18 started ====== [2024-08-22T08:05:54.276Z] GC before operation: completed in 145.207 ms, heap usage 284.853 MB -> 64.733 MB. [2024-08-22T08:06:09.944Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:06:09.944Z] There is no way to check that no silent failure occurred. [2024-08-22T08:06:09.944Z] ====== als (apache-spark) [default], iteration 18 completed (15374.105 ms) ====== [2024-08-22T08:06:09.944Z] ====== als (apache-spark) [default], iteration 19 started ====== [2024-08-22T08:06:09.944Z] GC before operation: completed in 132.887 ms, heap usage 512.505 MB -> 65.053 MB. [2024-08-22T08:06:25.492Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:06:25.492Z] There is no way to check that no silent failure occurred. [2024-08-22T08:06:25.492Z] ====== als (apache-spark) [default], iteration 19 completed (15315.279 ms) ====== [2024-08-22T08:06:25.492Z] ====== als (apache-spark) [default], iteration 20 started ====== [2024-08-22T08:06:25.492Z] GC before operation: completed in 124.398 ms, heap usage 222.329 MB -> 64.970 MB. [2024-08-22T08:06:41.097Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:06:41.097Z] There is no way to check that no silent failure occurred. [2024-08-22T08:06:41.097Z] ====== als (apache-spark) [default], iteration 20 completed (15496.635 ms) ====== [2024-08-22T08:06:41.097Z] ====== als (apache-spark) [default], iteration 21 started ====== [2024-08-22T08:06:41.097Z] GC before operation: completed in 125.239 ms, heap usage 174.550 MB -> 64.982 MB. [2024-08-22T08:06:56.965Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:06:56.965Z] There is no way to check that no silent failure occurred. [2024-08-22T08:06:56.965Z] ====== als (apache-spark) [default], iteration 21 completed (15743.063 ms) ====== [2024-08-22T08:06:56.965Z] ====== als (apache-spark) [default], iteration 22 started ====== [2024-08-22T08:06:56.965Z] GC before operation: completed in 141.411 ms, heap usage 344.560 MB -> 65.751 MB. [2024-08-22T08:07:12.680Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:07:12.680Z] There is no way to check that no silent failure occurred. [2024-08-22T08:07:12.680Z] ====== als (apache-spark) [default], iteration 22 completed (15502.227 ms) ====== [2024-08-22T08:07:12.680Z] ====== als (apache-spark) [default], iteration 23 started ====== [2024-08-22T08:07:12.680Z] GC before operation: completed in 135.063 ms, heap usage 390.472 MB -> 65.763 MB. [2024-08-22T08:07:28.292Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:07:28.292Z] There is no way to check that no silent failure occurred. [2024-08-22T08:07:28.292Z] ====== als (apache-spark) [default], iteration 23 completed (15208.235 ms) ====== [2024-08-22T08:07:28.292Z] ====== als (apache-spark) [default], iteration 24 started ====== [2024-08-22T08:07:28.292Z] GC before operation: completed in 135.411 ms, heap usage 315.922 MB -> 65.992 MB. [2024-08-22T08:07:43.864Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:07:43.864Z] There is no way to check that no silent failure occurred. [2024-08-22T08:07:43.864Z] ====== als (apache-spark) [default], iteration 24 completed (15501.820 ms) ====== [2024-08-22T08:07:43.864Z] ====== als (apache-spark) [default], iteration 25 started ====== [2024-08-22T08:07:43.864Z] GC before operation: completed in 131.971 ms, heap usage 317.599 MB -> 66.132 MB. [2024-08-22T08:07:59.497Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:07:59.497Z] There is no way to check that no silent failure occurred. [2024-08-22T08:07:59.497Z] ====== als (apache-spark) [default], iteration 25 completed (15589.266 ms) ====== [2024-08-22T08:07:59.497Z] ====== als (apache-spark) [default], iteration 26 started ====== [2024-08-22T08:07:59.497Z] GC before operation: completed in 125.506 ms, heap usage 339.358 MB -> 66.279 MB. [2024-08-22T08:08:15.086Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:08:15.086Z] There is no way to check that no silent failure occurred. [2024-08-22T08:08:15.086Z] ====== als (apache-spark) [default], iteration 26 completed (15333.697 ms) ====== [2024-08-22T08:08:15.086Z] ====== als (apache-spark) [default], iteration 27 started ====== [2024-08-22T08:08:15.086Z] GC before operation: completed in 159.533 ms, heap usage 438.493 MB -> 66.641 MB. [2024-08-22T08:08:30.719Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:08:30.719Z] There is no way to check that no silent failure occurred. [2024-08-22T08:08:30.719Z] ====== als (apache-spark) [default], iteration 27 completed (15133.906 ms) ====== [2024-08-22T08:08:30.719Z] ====== als (apache-spark) [default], iteration 28 started ====== [2024-08-22T08:08:30.719Z] GC before operation: completed in 133.930 ms, heap usage 194.773 MB -> 66.409 MB. [2024-08-22T08:08:43.629Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:08:43.630Z] There is no way to check that no silent failure occurred. [2024-08-22T08:08:43.630Z] ====== als (apache-spark) [default], iteration 28 completed (15098.180 ms) ====== [2024-08-22T08:08:43.630Z] ====== als (apache-spark) [default], iteration 29 started ====== [2024-08-22T08:08:44.211Z] GC before operation: completed in 129.657 ms, heap usage 152.357 MB -> 66.673 MB. [2024-08-22T08:08:59.916Z] WARNING: This benchmark provides no result that can be validated. [2024-08-22T08:08:59.916Z] There is no way to check that no silent failure occurred. [2024-08-22T08:08:59.916Z] ====== als (apache-spark) [default], iteration 29 completed (15139.710 ms) ====== [2024-08-22T08:08:59.916Z] ----------------------------------- [2024-08-22T08:08:59.916Z] renaissance-als_0_PASSED [2024-08-22T08:08:59.916Z] ----------------------------------- [2024-08-22T08:08:59.916Z] [2024-08-22T08:08:59.916Z] TEST TEARDOWN: [2024-08-22T08:08:59.916Z] Nothing to be done for teardown. [2024-08-22T08:09:00.228Z] renaissance-als_0 Finish Time: Thu Aug 22 08:08:59 2024 Epoch Time (ms): 1724314139950