[2025-01-22T14:53:33.626Z] Running test renaissance-als_0 ... [2025-01-22T14:53:33.626Z] =============================================== [2025-01-22T14:53:33.952Z] renaissance-als_0 Start Time: Wed Jan 22 14:53:33 2025 Epoch Time (ms): 1737557613678 [2025-01-22T14:53:33.952Z] variation: NoOptions [2025-01-22T14:53:33.952Z] JVM_OPTIONS: [2025-01-22T14:53:33.952Z] { \ [2025-01-22T14:53:33.952Z] echo ""; echo "TEST SETUP:"; \ [2025-01-22T14:53:33.952Z] echo "Nothing to be done for setup."; \ [2025-01-22T14:53:33.952Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17375575473055\\renaissance-als_0"; \ [2025-01-22T14:53:33.952Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17375575473055\\renaissance-als_0"; \ [2025-01-22T14:53:33.952Z] echo ""; echo "TESTING:"; \ [2025-01-22T14:53:33.952Z] "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_17375575473055\\renaissance-als_0"\\als.json" als; \ [2025-01-22T14:53:33.952Z] 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_17375575473055\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-22T14:53:33.952Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-22T14:53:33.952Z] echo "Nothing to be done for teardown."; \ [2025-01-22T14:53:33.952Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17375575473055\\TestTargetResult"; [2025-01-22T14:53:33.952Z] [2025-01-22T14:53:33.952Z] TEST SETUP: [2025-01-22T14:53:33.952Z] Nothing to be done for setup. [2025-01-22T14:53:34.279Z] [2025-01-22T14:53:34.279Z] TESTING: [2025-01-22T14:53:49.892Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-22T14:53:49.892Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-01-22T14:53:52.096Z] WARNING: An illegal reflective access operation has occurred [2025-01-22T14:53:52.096Z] 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_17375575473055/renaissance-als_0/harness-145334-13929826728830400203/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-01-22T14:53:52.096Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-01-22T14:53:52.096Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-01-22T14:53:52.096Z] WARNING: All illegal access operations will be denied in a future release [2025-01-22T14:53:54.327Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-01-22T14:53:54.680Z] GC before operation: completed in 100.581 ms, heap usage 94.500 MB -> 34.405 MB. [2025-01-22T14:54:27.823Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:54:27.823Z] There is no way to check that no silent failure occurred. [2025-01-22T14:54:27.823Z] ====== als (apache-spark) [default], iteration 0 completed (30514.616 ms) ====== [2025-01-22T14:54:27.823Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-01-22T14:54:27.823Z] GC before operation: completed in 114.759 ms, heap usage 142.671 MB -> 56.434 MB. [2025-01-22T14:54:46.924Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:54:46.924Z] There is no way to check that no silent failure occurred. [2025-01-22T14:54:46.924Z] ====== als (apache-spark) [default], iteration 1 completed (19473.155 ms) ====== [2025-01-22T14:54:46.924Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-01-22T14:54:46.924Z] GC before operation: completed in 139.472 ms, heap usage 521.737 MB -> 59.478 MB. [2025-01-22T14:55:02.819Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:55:02.819Z] There is no way to check that no silent failure occurred. [2025-01-22T14:55:02.819Z] ====== als (apache-spark) [default], iteration 2 completed (17904.140 ms) ====== [2025-01-22T14:55:02.819Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-01-22T14:55:03.235Z] GC before operation: completed in 147.785 ms, heap usage 399.959 MB -> 59.586 MB. [2025-01-22T14:55:22.616Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:55:22.616Z] There is no way to check that no silent failure occurred. [2025-01-22T14:55:22.616Z] ====== als (apache-spark) [default], iteration 3 completed (18023.390 ms) ====== [2025-01-22T14:55:22.616Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-01-22T14:55:22.616Z] GC before operation: completed in 156.732 ms, heap usage 419.069 MB -> 59.965 MB. [2025-01-22T14:55:41.712Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:55:41.712Z] There is no way to check that no silent failure occurred. [2025-01-22T14:55:41.712Z] ====== als (apache-spark) [default], iteration 4 completed (17924.608 ms) ====== [2025-01-22T14:55:41.713Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-01-22T14:55:41.713Z] GC before operation: completed in 169.256 ms, heap usage 295.835 MB -> 60.203 MB. [2025-01-22T14:55:57.445Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:55:57.445Z] There is no way to check that no silent failure occurred. [2025-01-22T14:55:57.445Z] ====== als (apache-spark) [default], iteration 5 completed (17426.163 ms) ====== [2025-01-22T14:55:57.445Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-01-22T14:55:57.445Z] GC before operation: completed in 152.955 ms, heap usage 288.094 MB -> 60.542 MB. [2025-01-22T14:56:16.547Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:56:16.547Z] There is no way to check that no silent failure occurred. [2025-01-22T14:56:16.547Z] ====== als (apache-spark) [default], iteration 6 completed (17679.890 ms) ====== [2025-01-22T14:56:16.547Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-01-22T14:56:16.547Z] GC before operation: completed in 167.981 ms, heap usage 414.131 MB -> 61.004 MB. [2025-01-22T14:56:35.702Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:56:35.702Z] There is no way to check that no silent failure occurred. [2025-01-22T14:56:35.702Z] ====== als (apache-spark) [default], iteration 7 completed (18524.077 ms) ====== [2025-01-22T14:56:35.702Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-01-22T14:56:35.702Z] GC before operation: completed in 155.301 ms, heap usage 377.676 MB -> 61.350 MB. [2025-01-22T14:56:51.372Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:56:51.372Z] There is no way to check that no silent failure occurred. [2025-01-22T14:56:51.372Z] ====== als (apache-spark) [default], iteration 8 completed (17500.419 ms) ====== [2025-01-22T14:56:51.372Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-01-22T14:56:51.372Z] GC before operation: completed in 151.923 ms, heap usage 498.005 MB -> 61.887 MB. [2025-01-22T14:57:10.426Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:57:10.426Z] There is no way to check that no silent failure occurred. [2025-01-22T14:57:10.426Z] ====== als (apache-spark) [default], iteration 9 completed (17341.611 ms) ====== [2025-01-22T14:57:10.426Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-01-22T14:57:10.426Z] GC before operation: completed in 151.792 ms, heap usage 286.898 MB -> 61.926 MB. [2025-01-22T14:57:26.241Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:57:26.241Z] There is no way to check that no silent failure occurred. [2025-01-22T14:57:26.241Z] ====== als (apache-spark) [default], iteration 10 completed (17527.063 ms) ====== [2025-01-22T14:57:26.241Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-01-22T14:57:26.241Z] GC before operation: completed in 171.292 ms, heap usage 513.177 MB -> 62.552 MB. [2025-01-22T14:57:45.531Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:57:45.531Z] There is no way to check that no silent failure occurred. [2025-01-22T14:57:45.531Z] ====== als (apache-spark) [default], iteration 11 completed (17698.172 ms) ====== [2025-01-22T14:57:45.531Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-01-22T14:57:45.531Z] GC before operation: completed in 150.654 ms, heap usage 572.851 MB -> 62.959 MB. [2025-01-22T14:58:01.314Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:58:01.314Z] There is no way to check that no silent failure occurred. [2025-01-22T14:58:01.314Z] ====== als (apache-spark) [default], iteration 12 completed (17443.204 ms) ====== [2025-01-22T14:58:01.314Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-01-22T14:58:01.723Z] GC before operation: completed in 164.845 ms, heap usage 170.900 MB -> 62.912 MB. [2025-01-22T14:58:20.872Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:58:20.872Z] There is no way to check that no silent failure occurred. [2025-01-22T14:58:20.872Z] ====== als (apache-spark) [default], iteration 13 completed (17520.880 ms) ====== [2025-01-22T14:58:20.872Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-01-22T14:58:20.872Z] GC before operation: completed in 151.614 ms, heap usage 234.387 MB -> 63.168 MB. [2025-01-22T14:58:37.043Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:58:37.043Z] There is no way to check that no silent failure occurred. [2025-01-22T14:58:37.043Z] ====== als (apache-spark) [default], iteration 14 completed (17526.739 ms) ====== [2025-01-22T14:58:37.043Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-01-22T14:58:37.043Z] GC before operation: completed in 160.232 ms, heap usage 284.218 MB -> 63.629 MB. [2025-01-22T14:58:56.265Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:58:56.265Z] There is no way to check that no silent failure occurred. [2025-01-22T14:58:56.265Z] ====== als (apache-spark) [default], iteration 15 completed (17388.484 ms) ====== [2025-01-22T14:58:56.265Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-01-22T14:58:56.265Z] GC before operation: completed in 154.948 ms, heap usage 489.368 MB -> 64.183 MB. [2025-01-22T14:59:15.194Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:59:15.194Z] There is no way to check that no silent failure occurred. [2025-01-22T14:59:15.194Z] ====== als (apache-spark) [default], iteration 16 completed (18123.296 ms) ====== [2025-01-22T14:59:15.194Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-01-22T14:59:15.194Z] GC before operation: completed in 180.039 ms, heap usage 542.771 MB -> 64.690 MB. [2025-01-22T14:59:30.835Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:59:30.835Z] There is no way to check that no silent failure occurred. [2025-01-22T14:59:30.835Z] ====== als (apache-spark) [default], iteration 17 completed (18152.626 ms) ====== [2025-01-22T14:59:30.835Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-01-22T14:59:31.285Z] GC before operation: completed in 157.501 ms, heap usage 395.194 MB -> 64.825 MB. [2025-01-22T14:59:50.256Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T14:59:50.256Z] There is no way to check that no silent failure occurred. [2025-01-22T14:59:50.256Z] ====== als (apache-spark) [default], iteration 18 completed (17846.247 ms) ====== [2025-01-22T14:59:50.256Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-01-22T14:59:50.256Z] GC before operation: completed in 167.456 ms, heap usage 524.355 MB -> 65.046 MB. [2025-01-22T15:00:09.120Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:00:09.120Z] There is no way to check that no silent failure occurred. [2025-01-22T15:00:09.120Z] ====== als (apache-spark) [default], iteration 19 completed (17901.444 ms) ====== [2025-01-22T15:00:09.120Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-01-22T15:00:09.120Z] GC before operation: completed in 146.541 ms, heap usage 341.241 MB -> 65.187 MB. [2025-01-22T15:00:28.078Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:00:28.078Z] There is no way to check that no silent failure occurred. [2025-01-22T15:00:28.078Z] ====== als (apache-spark) [default], iteration 20 completed (18312.515 ms) ====== [2025-01-22T15:00:28.078Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-01-22T15:00:28.078Z] GC before operation: completed in 152.635 ms, heap usage 299.989 MB -> 65.162 MB. [2025-01-22T15:00:43.733Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:00:43.733Z] There is no way to check that no silent failure occurred. [2025-01-22T15:00:43.733Z] ====== als (apache-spark) [default], iteration 21 completed (18070.831 ms) ====== [2025-01-22T15:00:43.733Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-01-22T15:00:43.733Z] GC before operation: completed in 156.868 ms, heap usage 536.592 MB -> 65.803 MB. [2025-01-22T15:01:02.789Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:01:02.789Z] There is no way to check that no silent failure occurred. [2025-01-22T15:01:02.789Z] ====== als (apache-spark) [default], iteration 22 completed (17897.475 ms) ====== [2025-01-22T15:01:02.789Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-01-22T15:01:02.789Z] GC before operation: completed in 150.326 ms, heap usage 172.662 MB -> 65.531 MB. [2025-01-22T15:01:21.930Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:01:21.930Z] There is no way to check that no silent failure occurred. [2025-01-22T15:01:21.930Z] ====== als (apache-spark) [default], iteration 23 completed (17702.231 ms) ====== [2025-01-22T15:01:21.930Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-01-22T15:01:21.930Z] GC before operation: completed in 160.906 ms, heap usage 241.213 MB -> 65.794 MB. [2025-01-22T15:01:37.728Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:01:37.728Z] There is no way to check that no silent failure occurred. [2025-01-22T15:01:37.728Z] ====== als (apache-spark) [default], iteration 24 completed (17428.011 ms) ====== [2025-01-22T15:01:37.728Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-01-22T15:01:37.728Z] GC before operation: completed in 155.970 ms, heap usage 201.729 MB -> 65.903 MB. [2025-01-22T15:01:56.881Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:01:56.881Z] There is no way to check that no silent failure occurred. [2025-01-22T15:01:56.881Z] ====== als (apache-spark) [default], iteration 25 completed (17918.075 ms) ====== [2025-01-22T15:01:56.881Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-01-22T15:01:56.881Z] GC before operation: completed in 162.983 ms, heap usage 468.064 MB -> 66.289 MB. [2025-01-22T15:02:16.036Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:02:16.036Z] There is no way to check that no silent failure occurred. [2025-01-22T15:02:16.036Z] ====== als (apache-spark) [default], iteration 26 completed (17784.954 ms) ====== [2025-01-22T15:02:16.036Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-01-22T15:02:16.036Z] GC before operation: completed in 154.656 ms, heap usage 165.293 MB -> 66.243 MB. [2025-01-22T15:02:31.833Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:02:31.833Z] There is no way to check that no silent failure occurred. [2025-01-22T15:02:31.833Z] ====== als (apache-spark) [default], iteration 27 completed (17522.635 ms) ====== [2025-01-22T15:02:31.833Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-01-22T15:02:31.833Z] GC before operation: completed in 149.566 ms, heap usage 518.521 MB -> 66.811 MB. [2025-01-22T15:02:50.767Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:02:50.767Z] There is no way to check that no silent failure occurred. [2025-01-22T15:02:50.767Z] ====== als (apache-spark) [default], iteration 28 completed (17376.084 ms) ====== [2025-01-22T15:02:50.767Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-01-22T15:02:50.767Z] GC before operation: completed in 162.201 ms, heap usage 300.980 MB -> 66.849 MB. [2025-01-22T15:03:06.688Z] WARNING: This benchmark provides no result that can be validated. [2025-01-22T15:03:06.688Z] There is no way to check that no silent failure occurred. [2025-01-22T15:03:06.688Z] ====== als (apache-spark) [default], iteration 29 completed (17511.805 ms) ====== [2025-01-22T15:03:07.018Z] ----------------------------------- [2025-01-22T15:03:07.018Z] renaissance-als_0_PASSED [2025-01-22T15:03:07.018Z] ----------------------------------- [2025-01-22T15:03:07.328Z] [2025-01-22T15:03:07.328Z] TEST TEARDOWN: [2025-01-22T15:03:07.328Z] Nothing to be done for teardown. [2025-01-22T15:03:07.328Z] renaissance-als_0 Finish Time: Wed Jan 22 15:03:07 2025 Epoch Time (ms): 1737558187129