[2026-01-15T01:53:24.296Z] Running test renaissance-als_0 ... [2026-01-15T01:53:24.296Z] =============================================== [2026-01-15T01:53:24.296Z] renaissance-als_0 Start Time: Thu Jan 15 01:53:24 2026 Epoch Time (ms): 1768442004233 [2026-01-15T01:53:24.658Z] variation: NoOptions [2026-01-15T01:53:24.658Z] JVM_OPTIONS: [2026-01-15T01:53:24.658Z] { \ [2026-01-15T01:53:24.658Z] echo ""; echo "TEST SETUP:"; \ [2026-01-15T01:53:24.658Z] echo "Nothing to be done for setup."; \ [2026-01-15T01:53:24.658Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17684418026734\\renaissance-als_0"; \ [2026-01-15T01:53:24.658Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17684418026734\\renaissance-als_0"; \ [2026-01-15T01:53:24.658Z] echo ""; echo "TESTING:"; \ [2026-01-15T01:53:24.658Z] "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_17684418026734\\renaissance-als_0"\\als.json" als; \ [2026-01-15T01:53:24.658Z] 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_17684418026734\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-15T01:53:24.658Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-15T01:53:24.658Z] echo "Nothing to be done for teardown."; \ [2026-01-15T01:53:24.658Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17684418026734\\TestTargetResult"; [2026-01-15T01:53:24.658Z] [2026-01-15T01:53:24.658Z] TEST SETUP: [2026-01-15T01:53:24.658Z] Nothing to be done for setup. [2026-01-15T01:53:24.658Z] [2026-01-15T01:53:24.658Z] TESTING: [2026-01-15T01:53:40.714Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-15T01:53:43.061Z] WARNING: An illegal reflective access operation has occurred [2026-01-15T01:53:43.061Z] 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_17684418026734/renaissance-als_0/harness-015325-18287403376866104537/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-15T01:53:43.061Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-15T01:53:43.061Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-15T01:53:43.061Z] WARNING: All illegal access operations will be denied in a future release [2026-01-15T01:53:44.277Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-15T01:53:44.277Z] GC before operation: completed in 105.642 ms, heap usage 321.000 MB -> 34.993 MB. [2026-01-15T01:54:18.449Z] ====== als (apache-spark) [default], iteration 0 completed (32479.942 ms) ====== [2026-01-15T01:54:18.449Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-15T01:54:18.449Z] GC before operation: completed in 119.737 ms, heap usage 229.577 MB -> 61.000 MB. [2026-01-15T01:54:38.313Z] ====== als (apache-spark) [default], iteration 1 completed (18533.306 ms) ====== [2026-01-15T01:54:38.313Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-15T01:54:38.314Z] GC before operation: completed in 130.699 ms, heap usage 133.737 MB -> 61.684 MB. [2026-01-15T01:54:54.709Z] ====== als (apache-spark) [default], iteration 2 completed (17270.168 ms) ====== [2026-01-15T01:54:54.709Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-15T01:54:54.709Z] GC before operation: completed in 153.353 ms, heap usage 93.080 MB -> 62.198 MB. [2026-01-15T01:55:14.321Z] ====== als (apache-spark) [default], iteration 3 completed (17229.134 ms) ====== [2026-01-15T01:55:14.322Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-15T01:55:14.322Z] GC before operation: completed in 150.978 ms, heap usage 89.387 MB -> 62.266 MB. [2026-01-15T01:55:30.567Z] ====== als (apache-spark) [default], iteration 4 completed (17977.065 ms) ====== [2026-01-15T01:55:30.567Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-15T01:55:30.567Z] GC before operation: completed in 138.609 ms, heap usage 226.541 MB -> 63.378 MB. [2026-01-15T01:55:50.018Z] ====== als (apache-spark) [default], iteration 5 completed (17162.176 ms) ====== [2026-01-15T01:55:50.018Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-15T01:55:50.018Z] GC before operation: completed in 139.132 ms, heap usage 261.898 MB -> 63.943 MB. [2026-01-15T01:56:06.408Z] ====== als (apache-spark) [default], iteration 6 completed (17815.435 ms) ====== [2026-01-15T01:56:06.408Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-15T01:56:06.408Z] GC before operation: completed in 141.800 ms, heap usage 226.919 MB -> 64.413 MB. [2026-01-15T01:56:25.878Z] ====== als (apache-spark) [default], iteration 7 completed (17067.446 ms) ====== [2026-01-15T01:56:25.878Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-15T01:56:25.878Z] GC before operation: completed in 184.583 ms, heap usage 202.684 MB -> 64.487 MB. [2026-01-15T01:56:45.394Z] ====== als (apache-spark) [default], iteration 8 completed (18890.637 ms) ====== [2026-01-15T01:56:45.394Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-15T01:56:45.394Z] GC before operation: completed in 154.922 ms, heap usage 162.919 MB -> 65.349 MB. [2026-01-15T01:57:01.624Z] ====== als (apache-spark) [default], iteration 9 completed (17249.170 ms) ====== [2026-01-15T01:57:01.624Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-15T01:57:01.624Z] GC before operation: completed in 179.694 ms, heap usage 290.536 MB -> 65.744 MB. [2026-01-15T01:57:21.311Z] ====== als (apache-spark) [default], iteration 10 completed (18389.242 ms) ====== [2026-01-15T01:57:21.311Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-15T01:57:21.311Z] GC before operation: completed in 146.732 ms, heap usage 246.842 MB -> 66.107 MB. [2026-01-15T01:57:37.703Z] ====== als (apache-spark) [default], iteration 11 completed (17795.646 ms) ====== [2026-01-15T01:57:37.703Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-15T01:57:37.703Z] GC before operation: completed in 145.760 ms, heap usage 239.911 MB -> 66.119 MB. [2026-01-15T01:57:57.250Z] ====== als (apache-spark) [default], iteration 12 completed (17396.511 ms) ====== [2026-01-15T01:57:57.251Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-15T01:57:57.251Z] GC before operation: completed in 143.727 ms, heap usage 248.473 MB -> 66.491 MB. [2026-01-15T01:58:13.526Z] ====== als (apache-spark) [default], iteration 13 completed (18071.105 ms) ====== [2026-01-15T01:58:13.526Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-15T01:58:13.526Z] GC before operation: completed in 147.015 ms, heap usage 127.257 MB -> 66.626 MB. [2026-01-15T01:58:33.020Z] ====== als (apache-spark) [default], iteration 14 completed (17221.589 ms) ====== [2026-01-15T01:58:33.020Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-15T01:58:33.020Z] GC before operation: completed in 165.098 ms, heap usage 130.227 MB -> 66.872 MB. [2026-01-15T01:58:49.140Z] ====== als (apache-spark) [default], iteration 15 completed (16945.574 ms) ====== [2026-01-15T01:58:49.140Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-15T01:58:49.140Z] GC before operation: completed in 151.301 ms, heap usage 275.969 MB -> 67.251 MB. [2026-01-15T01:59:08.902Z] ====== als (apache-spark) [default], iteration 16 completed (17977.199 ms) ====== [2026-01-15T01:59:08.902Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-15T01:59:08.902Z] GC before operation: completed in 169.475 ms, heap usage 263.596 MB -> 67.503 MB. [2026-01-15T01:59:25.222Z] ====== als (apache-spark) [default], iteration 17 completed (17416.309 ms) ====== [2026-01-15T01:59:25.222Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-15T01:59:25.222Z] GC before operation: completed in 145.333 ms, heap usage 262.382 MB -> 67.775 MB. [2026-01-15T01:59:44.731Z] ====== als (apache-spark) [default], iteration 18 completed (17895.679 ms) ====== [2026-01-15T01:59:44.731Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-15T01:59:44.731Z] GC before operation: completed in 148.039 ms, heap usage 196.863 MB -> 67.912 MB. [2026-01-15T02:00:01.136Z] ====== als (apache-spark) [default], iteration 19 completed (17543.380 ms) ====== [2026-01-15T02:00:01.136Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-15T02:00:01.136Z] GC before operation: completed in 173.176 ms, heap usage 94.008 MB -> 68.152 MB. [2026-01-15T02:00:20.730Z] ====== als (apache-spark) [default], iteration 20 completed (17503.782 ms) ====== [2026-01-15T02:00:20.730Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-15T02:00:20.730Z] GC before operation: completed in 168.201 ms, heap usage 267.326 MB -> 68.515 MB. [2026-01-15T02:00:37.022Z] ====== als (apache-spark) [default], iteration 21 completed (17413.565 ms) ====== [2026-01-15T02:00:37.022Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-15T02:00:37.022Z] GC before operation: completed in 154.000 ms, heap usage 371.270 MB -> 68.869 MB. [2026-01-15T02:00:56.567Z] ====== als (apache-spark) [default], iteration 22 completed (17113.749 ms) ====== [2026-01-15T02:00:56.568Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-15T02:00:56.568Z] GC before operation: completed in 152.103 ms, heap usage 238.191 MB -> 69.031 MB. [2026-01-15T02:01:12.745Z] ====== als (apache-spark) [default], iteration 23 completed (17763.912 ms) ====== [2026-01-15T02:01:12.745Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-15T02:01:12.745Z] GC before operation: completed in 145.871 ms, heap usage 171.949 MB -> 69.163 MB. [2026-01-15T02:01:29.086Z] ====== als (apache-spark) [default], iteration 24 completed (16975.040 ms) ====== [2026-01-15T02:01:29.086Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-15T02:01:29.086Z] GC before operation: completed in 165.197 ms, heap usage 215.564 MB -> 69.415 MB. [2026-01-15T02:01:48.732Z] ====== als (apache-spark) [default], iteration 25 completed (17615.366 ms) ====== [2026-01-15T02:01:48.732Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-15T02:01:48.732Z] GC before operation: completed in 145.585 ms, heap usage 236.239 MB -> 69.781 MB. [2026-01-15T02:02:04.961Z] ====== als (apache-spark) [default], iteration 26 completed (17139.509 ms) ====== [2026-01-15T02:02:04.961Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-15T02:02:04.961Z] GC before operation: completed in 158.106 ms, heap usage 370.105 MB -> 70.130 MB. [2026-01-15T02:02:24.390Z] ====== als (apache-spark) [default], iteration 27 completed (17399.945 ms) ====== [2026-01-15T02:02:24.390Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-15T02:02:24.390Z] GC before operation: completed in 142.201 ms, heap usage 118.636 MB -> 70.146 MB. [2026-01-15T02:02:40.631Z] ====== als (apache-spark) [default], iteration 28 completed (17325.241 ms) ====== [2026-01-15T02:02:40.631Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-15T02:02:40.631Z] GC before operation: completed in 152.968 ms, heap usage 142.322 MB -> 70.387 MB. [2026-01-15T02:02:56.976Z] ====== als (apache-spark) [default], iteration 29 completed (16979.644 ms) ====== [2026-01-15T02:02:57.732Z] ----------------------------------- [2026-01-15T02:02:57.732Z] renaissance-als_0_PASSED [2026-01-15T02:02:57.732Z] ----------------------------------- [2026-01-15T02:02:57.732Z] [2026-01-15T02:02:57.732Z] TEST TEARDOWN: [2026-01-15T02:02:57.732Z] Nothing to be done for teardown. [2026-01-15T02:02:57.732Z] renaissance-als_0 Finish Time: Thu Jan 15 02:02:57 2026 Epoch Time (ms): 1768442577691