[2025-06-19T11:18:29.041Z] Running test renaissance-als_0 ... [2025-06-19T11:18:29.041Z] =============================================== [2025-06-19T11:18:29.041Z] renaissance-als_0 Start Time: Thu Jun 19 12:18:28 2025 Epoch Time (ms): 1750331908783 [2025-06-19T11:18:29.041Z] variation: NoOptions [2025-06-19T11:18:29.041Z] JVM_OPTIONS: [2025-06-19T11:18:29.041Z] { \ [2025-06-19T11:18:29.041Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T11:18:29.041Z] echo "Nothing to be done for setup."; \ [2025-06-19T11:18:29.041Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_1750331908998/renaissance-als_0"; \ [2025-06-19T11:18:29.041Z] cd "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_1750331908998/renaissance-als_0"; \ [2025-06-19T11:18:29.041Z] echo ""; echo "TESTING:"; \ [2025-06-19T11:18:29.041Z] "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_1750331908998/renaissance-als_0"/als.json" als; \ [2025-06-19T11:18:29.041Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_1750331908998/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T11:18:29.041Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T11:18:29.041Z] echo "Nothing to be done for teardown."; \ [2025-06-19T11:18:29.041Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_1750331908998/TestTargetResult"; [2025-06-19T11:18:29.041Z] [2025-06-19T11:18:29.041Z] TEST SETUP: [2025-06-19T11:18:29.041Z] Nothing to be done for setup. [2025-06-19T11:18:29.041Z] [2025-06-19T11:18:29.041Z] TESTING: [2025-06-19T11:18:36.536Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-06-19T11:18:38.914Z] WARNING: An illegal reflective access operation has occurred [2025-06-19T11:18:38.914Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/output_1750331908998/renaissance-als_0/harness-121829-5875663129643128966/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-06-19T11:18:38.914Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-06-19T11:18:38.915Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-06-19T11:18:38.915Z] WARNING: All illegal access operations will be denied in a future release [2025-06-19T11:18:40.665Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-19T11:18:40.665Z] GC before operation: completed in 47.934 ms, heap usage 92.894 MB -> 35.063 MB. [2025-06-19T11:24:51.322Z] ====== als (apache-spark) [default], iteration 0 completed (359751.006 ms) ====== [2025-06-19T11:24:51.322Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-19T11:24:51.322Z] GC before operation: completed in 149.518 ms, heap usage 496.024 MB -> 63.145 MB. [2025-06-19T11:30:47.031Z] ====== als (apache-spark) [default], iteration 1 completed (356313.859 ms) ====== [2025-06-19T11:30:47.031Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-19T11:30:47.031Z] GC before operation: completed in 162.327 ms, heap usage 390.911 MB -> 63.756 MB. [2025-06-19T11:36:42.651Z] ====== als (apache-spark) [default], iteration 2 completed (353497.973 ms) ====== [2025-06-19T11:36:42.651Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-19T11:36:42.651Z] GC before operation: completed in 174.122 ms, heap usage 442.715 MB -> 64.198 MB. [2025-06-19T11:42:38.278Z] ====== als (apache-spark) [default], iteration 3 completed (354134.106 ms) ====== [2025-06-19T11:42:38.278Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-19T11:42:38.278Z] GC before operation: completed in 148.894 ms, heap usage 407.675 MB -> 64.817 MB. [2025-06-19T11:48:33.921Z] ====== als (apache-spark) [default], iteration 4 completed (358833.406 ms) ====== [2025-06-19T11:48:33.921Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-19T11:48:33.921Z] GC before operation: completed in 147.933 ms, heap usage 428.666 MB -> 65.330 MB. [2025-06-19T11:54:30.141Z] ====== als (apache-spark) [default], iteration 5 completed (360627.103 ms) ====== [2025-06-19T11:54:30.141Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-19T11:54:30.141Z] GC before operation: completed in 149.766 ms, heap usage 566.524 MB -> 65.927 MB. [2025-06-19T12:00:25.767Z] ====== als (apache-spark) [default], iteration 6 completed (355517.047 ms) ====== [2025-06-19T12:00:25.767Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-19T12:00:25.767Z] GC before operation: completed in 162.749 ms, heap usage 406.224 MB -> 66.343 MB. [2025-06-19T12:06:21.377Z] ====== als (apache-spark) [default], iteration 7 completed (353809.533 ms) ====== [2025-06-19T12:06:21.377Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-19T12:06:21.377Z] GC before operation: completed in 161.961 ms, heap usage 784.223 MB -> 66.995 MB. [2025-06-19T12:12:16.948Z] ====== als (apache-spark) [default], iteration 8 completed (352484.739 ms) ====== [2025-06-19T12:12:16.948Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-19T12:12:16.948Z] GC before operation: completed in 166.169 ms, heap usage 449.452 MB -> 67.393 MB. [2025-06-19T12:18:12.459Z] ====== als (apache-spark) [default], iteration 9 completed (352399.573 ms) ====== [2025-06-19T12:18:12.459Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-19T12:18:12.459Z] GC before operation: completed in 160.321 ms, heap usage 404.369 MB -> 67.832 MB. [2025-06-19T12:24:08.113Z] ====== als (apache-spark) [default], iteration 10 completed (351377.768 ms) ====== [2025-06-19T12:24:08.113Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-19T12:24:08.113Z] GC before operation: completed in 214.936 ms, heap usage 903.539 MB -> 64.769 MB. [2025-06-19T12:29:48.538Z] ====== als (apache-spark) [default], iteration 11 completed (350109.610 ms) ====== [2025-06-19T12:29:48.538Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-19T12:29:48.538Z] GC before operation: completed in 169.001 ms, heap usage 414.843 MB -> 64.344 MB. [2025-06-19T12:35:44.117Z] ====== als (apache-spark) [default], iteration 12 completed (348348.106 ms) ====== [2025-06-19T12:35:44.117Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-19T12:35:44.117Z] GC before operation: completed in 177.888 ms, heap usage 1.083 GB -> 64.863 MB. [2025-06-19T12:41:24.574Z] ====== als (apache-spark) [default], iteration 13 completed (346550.791 ms) ====== [2025-06-19T12:41:24.574Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-19T12:41:24.574Z] GC before operation: completed in 177.490 ms, heap usage 1.046 GB -> 64.952 MB. [2025-06-19T12:47:20.109Z] ====== als (apache-spark) [default], iteration 14 completed (346923.424 ms) ====== [2025-06-19T12:47:20.109Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-19T12:47:20.109Z] GC before operation: completed in 162.382 ms, heap usage 410.772 MB -> 65.090 MB. [2025-06-19T12:53:00.525Z] ====== als (apache-spark) [default], iteration 15 completed (348031.204 ms) ====== [2025-06-19T12:53:00.525Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-19T12:53:00.525Z] GC before operation: completed in 154.626 ms, heap usage 959.945 MB -> 65.110 MB. [2025-06-19T12:58:56.114Z] ====== als (apache-spark) [default], iteration 16 completed (353451.325 ms) ====== [2025-06-19T12:58:56.114Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-19T12:58:56.114Z] GC before operation: completed in 165.866 ms, heap usage 365.610 MB -> 65.606 MB. [2025-06-19T13:04:51.729Z] ====== als (apache-spark) [default], iteration 17 completed (352301.273 ms) ====== [2025-06-19T13:04:51.729Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-19T13:04:51.729Z] GC before operation: completed in 162.875 ms, heap usage 497.278 MB -> 65.965 MB. [2025-06-19T13:10:32.236Z] ====== als (apache-spark) [default], iteration 18 completed (350674.950 ms) ====== [2025-06-19T13:10:32.236Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-19T13:10:32.236Z] GC before operation: completed in 162.670 ms, heap usage 468.364 MB -> 66.100 MB. [2025-06-19T13:16:27.778Z] ====== als (apache-spark) [default], iteration 19 completed (350701.912 ms) ====== [2025-06-19T13:16:27.779Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-19T13:16:27.779Z] GC before operation: completed in 186.897 ms, heap usage 967.597 MB -> 66.491 MB. [2025-06-19T13:22:23.362Z] ====== als (apache-spark) [default], iteration 20 completed (351371.561 ms) ====== [2025-06-19T13:22:23.362Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-19T13:22:23.362Z] GC before operation: completed in 167.275 ms, heap usage 762.473 MB -> 66.220 MB. [2025-06-19T13:28:18.902Z] ====== als (apache-spark) [default], iteration 21 completed (350450.064 ms) ====== [2025-06-19T13:28:18.902Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-19T13:28:18.902Z] GC before operation: completed in 166.041 ms, heap usage 410.247 MB -> 66.888 MB. [2025-06-19T13:33:59.372Z] ====== als (apache-spark) [default], iteration 22 completed (350097.684 ms) ====== [2025-06-19T13:33:59.372Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-19T13:33:59.372Z] GC before operation: completed in 182.626 ms, heap usage 831.274 MB -> 67.257 MB. [2025-06-19T13:39:55.000Z] ====== als (apache-spark) [default], iteration 23 completed (351109.203 ms) ====== [2025-06-19T13:39:55.000Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-19T13:39:55.000Z] GC before operation: completed in 176.869 ms, heap usage 467.054 MB -> 66.854 MB. [2025-06-19T13:45:50.596Z] ====== als (apache-spark) [default], iteration 24 completed (353540.910 ms) ====== [2025-06-19T13:45:50.596Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-19T13:45:50.596Z] GC before operation: completed in 179.507 ms, heap usage 908.620 MB -> 67.361 MB. [2025-06-19T13:51:46.178Z] ====== als (apache-spark) [default], iteration 25 completed (354982.753 ms) ====== [2025-06-19T13:51:46.178Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-19T13:51:46.178Z] GC before operation: completed in 173.926 ms, heap usage 710.147 MB -> 66.235 MB. [2025-06-19T13:57:41.830Z] ====== als (apache-spark) [default], iteration 26 completed (356392.923 ms) ====== [2025-06-19T13:57:41.830Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-19T13:57:41.830Z] GC before operation: completed in 178.791 ms, heap usage 715.554 MB -> 67.247 MB. [2025-06-19T14:03:37.391Z] ====== als (apache-spark) [default], iteration 27 completed (358152.724 ms) ====== [2025-06-19T14:03:37.391Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-19T14:03:37.391Z] GC before operation: completed in 188.103 ms, heap usage 1.031 GB -> 67.565 MB. [2025-06-19T14:09:48.068Z] ====== als (apache-spark) [default], iteration 28 completed (364714.355 ms) ====== [2025-06-19T14:09:48.068Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-19T14:09:48.068Z] GC before operation: completed in 175.566 ms, heap usage 1.028 GB -> 67.809 MB. [2025-06-19T14:15:43.657Z] ====== als (apache-spark) [default], iteration 29 completed (363441.388 ms) ====== [2025-06-19T14:15:43.657Z] ----------------------------------- [2025-06-19T14:15:43.657Z] renaissance-als_0_PASSED [2025-06-19T14:15:43.657Z] ----------------------------------- [2025-06-19T14:15:43.657Z] [2025-06-19T14:15:43.657Z] TEST TEARDOWN: [2025-06-19T14:15:43.657Z] Nothing to be done for teardown. [2025-06-19T14:15:43.657Z] renaissance-als_0 Finish Time: Thu Jun 19 15:15:40 2025 Epoch Time (ms): 1750342540967