[2025-05-17T04:52:16.669Z] Running test renaissance-als_0 ... [2025-05-17T04:52:16.669Z] =============================================== [2025-05-17T04:52:16.669Z] renaissance-als_0 Start Time: Sat May 17 04:52:16 2025 Epoch Time (ms): 1747457536492 [2025-05-17T04:52:16.669Z] variation: NoOptions [2025-05-17T04:52:16.669Z] JVM_OPTIONS: [2025-05-17T04:52:16.669Z] { \ [2025-05-17T04:52:16.669Z] echo ""; echo "TEST SETUP:"; \ [2025-05-17T04:52:16.669Z] echo "Nothing to be done for setup."; \ [2025-05-17T04:52:16.669Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17474575368695/renaissance-als_0"; \ [2025-05-17T04:52:16.669Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17474575368695/renaissance-als_0"; \ [2025-05-17T04:52:16.669Z] echo ""; echo "TESTING:"; \ [2025-05-17T04:52:16.669Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17474575368695/renaissance-als_0"/als.json" als; \ [2025-05-17T04:52:16.669Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17474575368695/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-17T04:52:16.669Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-17T04:52:16.669Z] echo "Nothing to be done for teardown."; \ [2025-05-17T04:52:16.669Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17474575368695/TestTargetResult"; [2025-05-17T04:52:16.669Z] [2025-05-17T04:52:16.669Z] TEST SETUP: [2025-05-17T04:52:16.669Z] Nothing to be done for setup. [2025-05-17T04:52:16.669Z] [2025-05-17T04:52:16.669Z] TESTING: [2025-05-17T04:52:22.535Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-17T04:52:24.822Z] WARNING: An illegal reflective access operation has occurred [2025-05-17T04:52:24.822Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17474575368695/renaissance-als_0/harness-045217-17353204998389442084/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-05-17T04:52:24.822Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-05-17T04:52:24.822Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-05-17T04:52:24.822Z] WARNING: All illegal access operations will be denied in a future release [2025-05-17T04:52:26.118Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-17T04:52:26.741Z] GC before operation: completed in 99.139 ms, heap usage 387.502 MB -> 34.500 MB. [2025-05-17T04:52:54.575Z] ====== als (apache-spark) [default], iteration 0 completed (25643.274 ms) ====== [2025-05-17T04:52:54.575Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-17T04:52:54.575Z] GC before operation: completed in 136.727 ms, heap usage 208.509 MB -> 60.304 MB. [2025-05-17T04:53:06.737Z] ====== als (apache-spark) [default], iteration 1 completed (12703.738 ms) ====== [2025-05-17T04:53:06.737Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-17T04:53:06.737Z] GC before operation: completed in 120.152 ms, heap usage 184.745 MB -> 61.014 MB. [2025-05-17T04:53:18.971Z] ====== als (apache-spark) [default], iteration 2 completed (10941.780 ms) ====== [2025-05-17T04:53:18.971Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-17T04:53:18.971Z] GC before operation: completed in 131.132 ms, heap usage 136.516 MB -> 61.558 MB. [2025-05-17T04:53:29.197Z] ====== als (apache-spark) [default], iteration 3 completed (10526.092 ms) ====== [2025-05-17T04:53:29.197Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-17T04:53:29.197Z] GC before operation: completed in 131.645 ms, heap usage 256.547 MB -> 62.212 MB. [2025-05-17T04:53:39.810Z] ====== als (apache-spark) [default], iteration 4 completed (10353.546 ms) ====== [2025-05-17T04:53:39.810Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-17T04:53:39.810Z] GC before operation: completed in 140.847 ms, heap usage 304.216 MB -> 62.725 MB. [2025-05-17T04:53:50.038Z] ====== als (apache-spark) [default], iteration 5 completed (9996.579 ms) ====== [2025-05-17T04:53:50.038Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-17T04:53:50.038Z] GC before operation: completed in 138.672 ms, heap usage 134.662 MB -> 63.137 MB. [2025-05-17T04:54:00.280Z] ====== als (apache-spark) [default], iteration 6 completed (9552.760 ms) ====== [2025-05-17T04:54:00.280Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-17T04:54:00.280Z] GC before operation: completed in 134.162 ms, heap usage 420.757 MB -> 63.880 MB. [2025-05-17T04:54:10.530Z] ====== als (apache-spark) [default], iteration 7 completed (10547.336 ms) ====== [2025-05-17T04:54:10.530Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-17T04:54:10.530Z] GC before operation: completed in 132.042 ms, heap usage 262.558 MB -> 64.310 MB. [2025-05-17T04:54:20.790Z] ====== als (apache-spark) [default], iteration 8 completed (10056.783 ms) ====== [2025-05-17T04:54:20.790Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-17T04:54:20.790Z] GC before operation: completed in 171.520 ms, heap usage 127.731 MB -> 64.729 MB. [2025-05-17T04:54:31.179Z] ====== als (apache-spark) [default], iteration 9 completed (10177.448 ms) ====== [2025-05-17T04:54:31.179Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-17T04:54:31.806Z] GC before operation: completed in 142.504 ms, heap usage 131.301 MB -> 65.008 MB. [2025-05-17T04:54:42.126Z] ====== als (apache-spark) [default], iteration 10 completed (10153.189 ms) ====== [2025-05-17T04:54:42.126Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-17T04:54:42.126Z] GC before operation: completed in 160.242 ms, heap usage 150.596 MB -> 65.254 MB. [2025-05-17T04:54:52.411Z] ====== als (apache-spark) [default], iteration 11 completed (9975.315 ms) ====== [2025-05-17T04:54:52.411Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-17T04:54:52.411Z] GC before operation: completed in 140.951 ms, heap usage 218.351 MB -> 65.633 MB. [2025-05-17T04:55:02.653Z] ====== als (apache-spark) [default], iteration 12 completed (9919.091 ms) ====== [2025-05-17T04:55:02.653Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-17T04:55:02.653Z] GC before operation: completed in 140.142 ms, heap usage 141.910 MB -> 65.821 MB. [2025-05-17T04:55:12.883Z] ====== als (apache-spark) [default], iteration 13 completed (9908.526 ms) ====== [2025-05-17T04:55:12.883Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-17T04:55:12.883Z] GC before operation: completed in 137.046 ms, heap usage 93.966 MB -> 66.007 MB. [2025-05-17T04:55:23.146Z] ====== als (apache-spark) [default], iteration 14 completed (9736.485 ms) ====== [2025-05-17T04:55:23.146Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-17T04:55:23.146Z] GC before operation: completed in 137.316 ms, heap usage 106.401 MB -> 66.249 MB. [2025-05-17T04:55:33.759Z] ====== als (apache-spark) [default], iteration 15 completed (9713.733 ms) ====== [2025-05-17T04:55:33.759Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-17T04:55:33.759Z] GC before operation: completed in 132.809 ms, heap usage 143.252 MB -> 66.542 MB. [2025-05-17T04:55:42.449Z] ====== als (apache-spark) [default], iteration 16 completed (9490.539 ms) ====== [2025-05-17T04:55:42.449Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-17T04:55:43.075Z] GC before operation: completed in 141.193 ms, heap usage 160.001 MB -> 66.755 MB. [2025-05-17T04:55:53.326Z] ====== als (apache-spark) [default], iteration 17 completed (9952.237 ms) ====== [2025-05-17T04:55:53.326Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-17T04:55:53.326Z] GC before operation: completed in 142.428 ms, heap usage 112.894 MB -> 66.998 MB. [2025-05-17T04:56:03.564Z] ====== als (apache-spark) [default], iteration 18 completed (9781.893 ms) ====== [2025-05-17T04:56:03.564Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-17T04:56:03.564Z] GC before operation: completed in 157.926 ms, heap usage 143.068 MB -> 67.270 MB. [2025-05-17T04:56:13.799Z] ====== als (apache-spark) [default], iteration 19 completed (9838.265 ms) ====== [2025-05-17T04:56:13.799Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-17T04:56:13.799Z] GC before operation: completed in 146.362 ms, heap usage 129.178 MB -> 67.521 MB. [2025-05-17T04:56:24.035Z] ====== als (apache-spark) [default], iteration 20 completed (9852.389 ms) ====== [2025-05-17T04:56:24.035Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-17T04:56:24.035Z] GC before operation: completed in 141.756 ms, heap usage 295.415 MB -> 67.880 MB. [2025-05-17T04:56:34.778Z] ====== als (apache-spark) [default], iteration 21 completed (10361.116 ms) ====== [2025-05-17T04:56:34.778Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-17T04:56:34.778Z] GC before operation: completed in 135.482 ms, heap usage 247.135 MB -> 68.124 MB. [2025-05-17T04:56:45.055Z] ====== als (apache-spark) [default], iteration 22 completed (9556.406 ms) ====== [2025-05-17T04:56:45.055Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-17T04:56:45.055Z] GC before operation: completed in 143.929 ms, heap usage 114.579 MB -> 68.254 MB. [2025-05-17T04:56:55.309Z] ====== als (apache-spark) [default], iteration 23 completed (9809.040 ms) ====== [2025-05-17T04:56:55.309Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-17T04:56:55.309Z] GC before operation: completed in 142.635 ms, heap usage 143.093 MB -> 68.522 MB. [2025-05-17T04:57:05.549Z] ====== als (apache-spark) [default], iteration 24 completed (9955.786 ms) ====== [2025-05-17T04:57:05.549Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-17T04:57:05.549Z] GC before operation: completed in 148.553 ms, heap usage 350.318 MB -> 69.001 MB. [2025-05-17T04:57:15.797Z] ====== als (apache-spark) [default], iteration 25 completed (10204.558 ms) ====== [2025-05-17T04:57:15.797Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-17T04:57:15.797Z] GC before operation: completed in 148.841 ms, heap usage 243.459 MB -> 69.131 MB. [2025-05-17T04:57:24.487Z] ====== als (apache-spark) [default], iteration 26 completed (9232.285 ms) ====== [2025-05-17T04:57:24.487Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-17T04:57:25.112Z] GC before operation: completed in 147.594 ms, heap usage 343.522 MB -> 69.483 MB. [2025-05-17T04:57:35.749Z] ====== als (apache-spark) [default], iteration 27 completed (10330.139 ms) ====== [2025-05-17T04:57:35.749Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-17T04:57:35.749Z] GC before operation: completed in 141.638 ms, heap usage 234.483 MB -> 69.619 MB. [2025-05-17T04:57:45.986Z] ====== als (apache-spark) [default], iteration 28 completed (9999.550 ms) ====== [2025-05-17T04:57:45.986Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-17T04:57:45.986Z] GC before operation: completed in 146.713 ms, heap usage 187.636 MB -> 69.743 MB. [2025-05-17T04:57:56.286Z] ====== als (apache-spark) [default], iteration 29 completed (9640.363 ms) ====== [2025-05-17T04:57:56.286Z] ----------------------------------- [2025-05-17T04:57:56.286Z] renaissance-als_0_PASSED [2025-05-17T04:57:56.286Z] ----------------------------------- [2025-05-17T04:57:56.286Z] [2025-05-17T04:57:56.286Z] TEST TEARDOWN: [2025-05-17T04:57:56.286Z] Nothing to be done for teardown. [2025-05-17T04:57:56.286Z] renaissance-als_0 Finish Time: Sat May 17 04:57:55 2025 Epoch Time (ms): 1747457875907