[2025-11-06T03:30:55.486Z] Running test renaissance-als_0 ... [2025-11-06T03:30:55.486Z] =============================================== [2025-11-06T03:30:55.486Z] renaissance-als_0 Start Time: Thu Nov 6 03:30:55 2025 Epoch Time (ms): 1762399855457 [2025-11-06T03:30:55.486Z] variation: NoOptions [2025-11-06T03:30:55.486Z] JVM_OPTIONS: [2025-11-06T03:30:55.486Z] { \ [2025-11-06T03:30:55.486Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T03:30:55.486Z] echo "Nothing to be done for setup."; \ [2025-11-06T03:30:55.486Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_1762399642806/renaissance-als_0"; \ [2025-11-06T03:30:55.486Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_1762399642806/renaissance-als_0"; \ [2025-11-06T03:30:55.486Z] echo ""; echo "TESTING:"; \ [2025-11-06T03:30:55.486Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_1/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_x86-64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_1762399642806/renaissance-als_0"/als.json" als; \ [2025-11-06T03:30:55.486Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_1762399642806/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T03:30:55.486Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T03:30:55.486Z] echo "Nothing to be done for teardown."; \ [2025-11-06T03:30:55.486Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_1762399642806/TestTargetResult"; [2025-11-06T03:30:55.486Z] [2025-11-06T03:30:55.486Z] TEST SETUP: [2025-11-06T03:30:55.486Z] Nothing to be done for setup. [2025-11-06T03:30:55.486Z] [2025-11-06T03:30:55.486Z] TESTING: [2025-11-06T03:31:01.468Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-06T03:31:05.291Z] WARNING: An illegal reflective access operation has occurred [2025-11-06T03:31:05.291Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/output_1762399642806/renaissance-als_0/harness-033056-813967231994445479/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-11-06T03:31:05.291Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-11-06T03:31:05.291Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-11-06T03:31:05.291Z] WARNING: All illegal access operations will be denied in a future release [2025-11-06T03:31:06.011Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-06T03:31:06.348Z] GC before operation: completed in 72.776 ms, heap usage 329.000 MB -> 34.308 MB. [2025-11-06T03:31:46.571Z] ====== als (apache-spark) [default], iteration 0 completed (33116.698 ms) ====== [2025-11-06T03:31:46.571Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-06T03:31:46.571Z] GC before operation: completed in 164.718 ms, heap usage 329.629 MB -> 60.556 MB. [2025-11-06T03:31:59.793Z] ====== als (apache-spark) [default], iteration 1 completed (17078.737 ms) ====== [2025-11-06T03:31:59.793Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-06T03:31:59.793Z] GC before operation: completed in 136.390 ms, heap usage 230.122 MB -> 60.932 MB. [2025-11-06T03:32:15.774Z] ====== als (apache-spark) [default], iteration 2 completed (15373.594 ms) ====== [2025-11-06T03:32:15.774Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-06T03:32:15.774Z] GC before operation: completed in 140.485 ms, heap usage 213.482 MB -> 61.482 MB. [2025-11-06T03:32:29.022Z] ====== als (apache-spark) [default], iteration 3 completed (14726.192 ms) ====== [2025-11-06T03:32:29.022Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-06T03:32:29.366Z] GC before operation: completed in 141.778 ms, heap usage 224.881 MB -> 62.021 MB. [2025-11-06T03:32:45.350Z] ====== als (apache-spark) [default], iteration 4 completed (14901.699 ms) ====== [2025-11-06T03:32:45.350Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-06T03:32:45.350Z] GC before operation: completed in 146.724 ms, heap usage 177.735 MB -> 62.419 MB. [2025-11-06T03:33:01.325Z] ====== als (apache-spark) [default], iteration 5 completed (14110.260 ms) ====== [2025-11-06T03:33:01.325Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-06T03:33:01.325Z] GC before operation: completed in 146.293 ms, heap usage 205.641 MB -> 62.951 MB. [2025-11-06T03:33:14.628Z] ====== als (apache-spark) [default], iteration 6 completed (14674.441 ms) ====== [2025-11-06T03:33:14.628Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-06T03:33:14.628Z] GC before operation: completed in 158.417 ms, heap usage 173.187 MB -> 63.476 MB. [2025-11-06T03:33:30.595Z] ====== als (apache-spark) [default], iteration 7 completed (14067.799 ms) ====== [2025-11-06T03:33:30.595Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-06T03:33:30.595Z] GC before operation: completed in 148.057 ms, heap usage 398.179 MB -> 64.239 MB. [2025-11-06T03:33:43.814Z] ====== als (apache-spark) [default], iteration 8 completed (14438.806 ms) ====== [2025-11-06T03:33:43.814Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-06T03:33:43.814Z] GC before operation: completed in 148.509 ms, heap usage 345.239 MB -> 64.757 MB. [2025-11-06T03:33:59.789Z] ====== als (apache-spark) [default], iteration 9 completed (14186.385 ms) ====== [2025-11-06T03:33:59.789Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-06T03:33:59.789Z] GC before operation: completed in 160.240 ms, heap usage 163.094 MB -> 64.807 MB. [2025-11-06T03:34:13.010Z] ====== als (apache-spark) [default], iteration 10 completed (14445.888 ms) ====== [2025-11-06T03:34:13.010Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-06T03:34:13.354Z] GC before operation: completed in 153.201 ms, heap usage 266.430 MB -> 65.183 MB. [2025-11-06T03:34:29.409Z] ====== als (apache-spark) [default], iteration 11 completed (14278.011 ms) ====== [2025-11-06T03:34:29.409Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-06T03:34:29.409Z] GC before operation: completed in 151.829 ms, heap usage 359.941 MB -> 65.660 MB. [2025-11-06T03:34:42.633Z] ====== als (apache-spark) [default], iteration 12 completed (14328.479 ms) ====== [2025-11-06T03:34:42.633Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-06T03:34:42.633Z] GC before operation: completed in 150.634 ms, heap usage 225.605 MB -> 65.692 MB. [2025-11-06T03:34:58.597Z] ====== als (apache-spark) [default], iteration 13 completed (13916.174 ms) ====== [2025-11-06T03:34:58.597Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-06T03:34:58.597Z] GC before operation: completed in 151.034 ms, heap usage 113.692 MB -> 65.813 MB. [2025-11-06T03:35:11.820Z] ====== als (apache-spark) [default], iteration 14 completed (14747.736 ms) ====== [2025-11-06T03:35:11.820Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-06T03:35:12.156Z] GC before operation: completed in 152.048 ms, heap usage 311.104 MB -> 66.289 MB. [2025-11-06T03:35:28.248Z] ====== als (apache-spark) [default], iteration 15 completed (13479.006 ms) ====== [2025-11-06T03:35:28.248Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-06T03:35:28.248Z] GC before operation: completed in 152.550 ms, heap usage 229.386 MB -> 66.421 MB. [2025-11-06T03:35:41.455Z] ====== als (apache-spark) [default], iteration 16 completed (14097.929 ms) ====== [2025-11-06T03:35:41.455Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-06T03:35:41.455Z] GC before operation: completed in 147.773 ms, heap usage 237.013 MB -> 66.665 MB. [2025-11-06T03:35:57.427Z] ====== als (apache-spark) [default], iteration 17 completed (14420.856 ms) ====== [2025-11-06T03:35:57.427Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-06T03:35:57.427Z] GC before operation: completed in 152.064 ms, heap usage 144.099 MB -> 66.784 MB. [2025-11-06T03:36:10.643Z] ====== als (apache-spark) [default], iteration 18 completed (14295.623 ms) ====== [2025-11-06T03:36:10.643Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-06T03:36:10.643Z] GC before operation: completed in 154.436 ms, heap usage 214.804 MB -> 67.037 MB. [2025-11-06T03:36:26.601Z] ====== als (apache-spark) [default], iteration 19 completed (14268.518 ms) ====== [2025-11-06T03:36:26.601Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-06T03:36:26.601Z] GC before operation: completed in 154.350 ms, heap usage 252.836 MB -> 67.425 MB. [2025-11-06T03:36:39.893Z] ====== als (apache-spark) [default], iteration 20 completed (14072.787 ms) ====== [2025-11-06T03:36:39.893Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-06T03:36:39.893Z] GC before operation: completed in 156.456 ms, heap usage 347.151 MB -> 67.781 MB. [2025-11-06T03:36:55.864Z] ====== als (apache-spark) [default], iteration 21 completed (15471.552 ms) ====== [2025-11-06T03:36:55.864Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-06T03:36:55.864Z] GC before operation: completed in 155.996 ms, heap usage 287.040 MB -> 67.898 MB. [2025-11-06T03:37:11.829Z] ====== als (apache-spark) [default], iteration 22 completed (13834.181 ms) ====== [2025-11-06T03:37:11.829Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-06T03:37:11.829Z] GC before operation: completed in 157.281 ms, heap usage 80.080 MB -> 68.125 MB. [2025-11-06T03:37:25.039Z] ====== als (apache-spark) [default], iteration 23 completed (14474.385 ms) ====== [2025-11-06T03:37:25.039Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-06T03:37:25.039Z] GC before operation: completed in 155.919 ms, heap usage 263.709 MB -> 68.431 MB. [2025-11-06T03:37:41.030Z] ====== als (apache-spark) [default], iteration 24 completed (14498.116 ms) ====== [2025-11-06T03:37:41.031Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-06T03:37:41.031Z] GC before operation: completed in 159.978 ms, heap usage 219.408 MB -> 68.636 MB. [2025-11-06T03:37:54.283Z] ====== als (apache-spark) [default], iteration 25 completed (14467.333 ms) ====== [2025-11-06T03:37:54.283Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-06T03:37:54.283Z] GC before operation: completed in 157.563 ms, heap usage 102.892 MB -> 68.769 MB. [2025-11-06T03:38:10.253Z] ====== als (apache-spark) [default], iteration 26 completed (14004.543 ms) ====== [2025-11-06T03:38:10.253Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-06T03:38:10.253Z] GC before operation: completed in 157.185 ms, heap usage 230.195 MB -> 69.135 MB. [2025-11-06T03:38:23.472Z] ====== als (apache-spark) [default], iteration 27 completed (14571.067 ms) ====== [2025-11-06T03:38:23.472Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-06T03:38:23.813Z] GC before operation: completed in 164.475 ms, heap usage 132.793 MB -> 69.260 MB. [2025-11-06T03:38:39.788Z] ====== als (apache-spark) [default], iteration 28 completed (14445.831 ms) ====== [2025-11-06T03:38:39.788Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-06T03:38:39.788Z] GC before operation: completed in 157.110 ms, heap usage 123.787 MB -> 69.046 MB. [2025-11-06T03:38:53.094Z] ====== als (apache-spark) [default], iteration 29 completed (14379.000 ms) ====== [2025-11-06T03:38:53.814Z] ----------------------------------- [2025-11-06T03:38:53.814Z] renaissance-als_0_PASSED [2025-11-06T03:38:53.814Z] ----------------------------------- [2025-11-06T03:38:53.814Z] [2025-11-06T03:38:53.814Z] TEST TEARDOWN: [2025-11-06T03:38:53.814Z] Nothing to be done for teardown. [2025-11-06T03:38:53.814Z] renaissance-als_0 Finish Time: Thu Nov 6 03:38:53 2025 Epoch Time (ms): 1762400333506