[2025-06-12T14:28:34.957Z] Running test renaissance-als_0 ... [2025-06-12T14:28:34.957Z] =============================================== [2025-06-12T14:28:34.957Z] renaissance-als_0 Start Time: Thu Jun 12 15:28:34 2025 Epoch Time (ms): 1749738514712 [2025-06-12T14:28:34.957Z] variation: NoOptions [2025-06-12T14:28:34.957Z] JVM_OPTIONS: [2025-06-12T14:28:34.957Z] { \ [2025-06-12T14:28:34.957Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T14:28:34.957Z] echo "Nothing to be done for setup."; \ [2025-06-12T14:28:34.957Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17497385146476/renaissance-als_0"; \ [2025-06-12T14:28:34.957Z] cd "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17497385146476/renaissance-als_0"; \ [2025-06-12T14:28:34.957Z] echo ""; echo "TESTING:"; \ [2025-06-12T14:28:34.957Z] "/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_17497385146476/renaissance-als_0"/als.json" als; \ [2025-06-12T14:28:34.957Z] 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_17497385146476/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T14:28:34.957Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T14:28:34.957Z] echo "Nothing to be done for teardown."; \ [2025-06-12T14:28:34.957Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17497385146476/TestTargetResult"; [2025-06-12T14:28:34.957Z] [2025-06-12T14:28:34.957Z] TEST SETUP: [2025-06-12T14:28:34.957Z] Nothing to be done for setup. [2025-06-12T14:28:34.957Z] [2025-06-12T14:28:34.957Z] TESTING: [2025-06-12T14:28:42.484Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-06-12T14:28:45.586Z] WARNING: An illegal reflective access operation has occurred [2025-06-12T14:28:45.586Z] 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_17497385146476/renaissance-als_0/harness-152835-12106663163183297173/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-06-12T14:28:45.586Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-06-12T14:28:45.586Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-06-12T14:28:45.586Z] WARNING: All illegal access operations will be denied in a future release [2025-06-12T14:28:46.804Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-12T14:28:46.804Z] GC before operation: completed in 57.392 ms, heap usage 121.500 MB -> 35.085 MB. [2025-06-12T14:34:57.468Z] ====== als (apache-spark) [default], iteration 0 completed (362052.642 ms) ====== [2025-06-12T14:34:57.468Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-12T14:34:57.468Z] GC before operation: completed in 180.441 ms, heap usage 903.183 MB -> 63.955 MB. [2025-06-12T14:40:53.034Z] ====== als (apache-spark) [default], iteration 1 completed (358187.900 ms) ====== [2025-06-12T14:40:53.034Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-12T14:40:53.034Z] GC before operation: completed in 158.079 ms, heap usage 429.574 MB -> 64.461 MB. [2025-06-12T14:46:50.092Z] ====== als (apache-spark) [default], iteration 2 completed (356662.804 ms) ====== [2025-06-12T14:46:50.092Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-12T14:46:50.092Z] GC before operation: completed in 189.386 ms, heap usage 470.903 MB -> 65.073 MB. [2025-06-12T14:53:00.806Z] ====== als (apache-spark) [default], iteration 3 completed (362431.364 ms) ====== [2025-06-12T14:53:00.806Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-12T14:53:00.806Z] GC before operation: completed in 163.255 ms, heap usage 426.452 MB -> 65.754 MB. [2025-06-12T14:58:56.336Z] ====== als (apache-spark) [default], iteration 4 completed (355631.770 ms) ====== [2025-06-12T14:58:56.336Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-12T14:58:56.336Z] GC before operation: completed in 106.238 ms, heap usage 77.042 MB -> 64.223 MB. [2025-06-12T15:04:51.964Z] ====== als (apache-spark) [default], iteration 5 completed (355795.760 ms) ====== [2025-06-12T15:04:51.964Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-12T15:04:51.964Z] GC before operation: completed in 182.051 ms, heap usage 379.383 MB -> 66.702 MB. [2025-06-12T15:10:48.359Z] ====== als (apache-spark) [default], iteration 6 completed (354002.147 ms) ====== [2025-06-12T15:10:48.359Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-12T15:10:48.359Z] GC before operation: completed in 170.101 ms, heap usage 400.114 MB -> 67.131 MB. [2025-06-12T15:16:43.883Z] ====== als (apache-spark) [default], iteration 7 completed (356801.550 ms) ====== [2025-06-12T15:16:43.884Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-12T15:16:43.884Z] GC before operation: completed in 158.667 ms, heap usage 420.200 MB -> 67.672 MB. [2025-06-12T15:22:39.468Z] ====== als (apache-spark) [default], iteration 8 completed (353986.243 ms) ====== [2025-06-12T15:22:39.468Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-12T15:22:39.468Z] GC before operation: completed in 164.672 ms, heap usage 405.473 MB -> 68.355 MB. [2025-06-12T15:28:35.035Z] ====== als (apache-spark) [default], iteration 9 completed (355483.422 ms) ====== [2025-06-12T15:28:35.035Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-12T15:28:35.035Z] GC before operation: completed in 164.729 ms, heap usage 429.533 MB -> 68.472 MB. [2025-06-12T15:34:15.461Z] ====== als (apache-spark) [default], iteration 10 completed (353114.118 ms) ====== [2025-06-12T15:34:15.461Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-12T15:34:15.461Z] GC before operation: completed in 174.795 ms, heap usage 347.302 MB -> 65.527 MB. [2025-06-12T15:40:11.065Z] ====== als (apache-spark) [default], iteration 11 completed (353935.335 ms) ====== [2025-06-12T15:40:11.065Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-12T15:40:11.065Z] GC before operation: completed in 195.118 ms, heap usage 371.396 MB -> 65.062 MB. [2025-06-12T15:46:06.752Z] ====== als (apache-spark) [default], iteration 12 completed (351426.013 ms) ====== [2025-06-12T15:46:06.752Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-12T15:46:06.752Z] GC before operation: completed in 184.094 ms, heap usage 405.311 MB -> 65.346 MB. [2025-06-12T15:52:02.406Z] ====== als (apache-spark) [default], iteration 13 completed (352918.241 ms) ====== [2025-06-12T15:52:02.406Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-12T15:52:02.406Z] GC before operation: completed in 171.965 ms, heap usage 410.592 MB -> 65.593 MB. [2025-06-12T15:57:57.978Z] ====== als (apache-spark) [default], iteration 14 completed (351169.106 ms) ====== [2025-06-12T15:57:57.978Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-12T15:57:57.978Z] GC before operation: completed in 196.561 ms, heap usage 431.742 MB -> 65.844 MB. [2025-06-12T16:03:53.580Z] ====== als (apache-spark) [default], iteration 15 completed (353158.825 ms) ====== [2025-06-12T16:03:53.580Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-12T16:03:53.580Z] GC before operation: completed in 179.123 ms, heap usage 1.002 GB -> 66.012 MB. [2025-06-12T16:09:34.012Z] ====== als (apache-spark) [default], iteration 16 completed (351349.539 ms) ====== [2025-06-12T16:09:34.012Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-12T16:09:34.012Z] GC before operation: completed in 189.110 ms, heap usage 388.756 MB -> 66.368 MB. [2025-06-12T16:15:29.615Z] ====== als (apache-spark) [default], iteration 17 completed (352750.206 ms) ====== [2025-06-12T16:15:29.615Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-12T16:15:29.615Z] GC before operation: completed in 219.504 ms, heap usage 1.040 GB -> 66.813 MB. [2025-06-12T16:21:25.136Z] ====== als (apache-spark) [default], iteration 18 completed (357465.788 ms) ====== [2025-06-12T16:21:25.136Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-12T16:21:25.136Z] GC before operation: completed in 192.299 ms, heap usage 415.858 MB -> 66.860 MB. [2025-06-12T16:27:20.759Z] ====== als (apache-spark) [default], iteration 19 completed (352564.127 ms) ====== [2025-06-12T16:27:20.759Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-12T16:27:20.759Z] GC before operation: completed in 195.701 ms, heap usage 986.716 MB -> 67.219 MB. [2025-06-12T16:33:16.286Z] ====== als (apache-spark) [default], iteration 20 completed (355002.901 ms) ====== [2025-06-12T16:33:16.286Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-12T16:33:16.286Z] GC before operation: completed in 164.985 ms, heap usage 544.461 MB -> 67.166 MB. [2025-06-12T16:39:11.822Z] ====== als (apache-spark) [default], iteration 21 completed (351694.484 ms) ====== [2025-06-12T16:39:11.822Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-12T16:39:11.822Z] GC before operation: completed in 213.115 ms, heap usage 1.240 GB -> 67.741 MB. [2025-06-12T16:45:07.393Z] ====== als (apache-spark) [default], iteration 22 completed (353409.692 ms) ====== [2025-06-12T16:45:07.393Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-12T16:45:07.393Z] GC before operation: completed in 182.530 ms, heap usage 472.726 MB -> 67.875 MB. [2025-06-12T16:51:02.936Z] ====== als (apache-spark) [default], iteration 23 completed (351792.066 ms) ====== [2025-06-12T16:51:02.936Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-12T16:51:02.936Z] GC before operation: completed in 199.509 ms, heap usage 752.425 MB -> 68.241 MB. [2025-06-12T16:56:43.423Z] ====== als (apache-spark) [default], iteration 24 completed (353527.227 ms) ====== [2025-06-12T16:56:43.423Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-12T16:56:43.423Z] GC before operation: completed in 188.804 ms, heap usage 410.251 MB -> 68.377 MB. [2025-06-12T17:02:38.947Z] ====== als (apache-spark) [default], iteration 25 completed (353388.862 ms) ====== [2025-06-12T17:02:38.947Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-12T17:02:38.947Z] GC before operation: completed in 186.149 ms, heap usage 838.474 MB -> 68.225 MB. [2025-06-12T17:08:34.523Z] ====== als (apache-spark) [default], iteration 26 completed (353598.180 ms) ====== [2025-06-12T17:08:34.523Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-12T17:08:34.523Z] GC before operation: completed in 217.353 ms, heap usage 558.485 MB -> 68.433 MB. [2025-06-12T17:14:30.102Z] ====== als (apache-spark) [default], iteration 27 completed (352579.266 ms) ====== [2025-06-12T17:14:30.102Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-12T17:14:30.102Z] GC before operation: completed in 210.760 ms, heap usage 792.831 MB -> 68.371 MB. [2025-06-12T17:20:27.289Z] ====== als (apache-spark) [default], iteration 28 completed (353340.785 ms) ====== [2025-06-12T17:20:27.289Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-12T17:20:27.289Z] GC before operation: completed in 185.330 ms, heap usage 389.596 MB -> 68.357 MB. [2025-06-12T17:26:22.815Z] ====== als (apache-spark) [default], iteration 29 completed (354432.185 ms) ====== [2025-06-12T17:26:22.815Z] ----------------------------------- [2025-06-12T17:26:22.815Z] renaissance-als_0_PASSED [2025-06-12T17:26:22.815Z] ----------------------------------- [2025-06-12T17:26:22.815Z] [2025-06-12T17:26:22.815Z] TEST TEARDOWN: [2025-06-12T17:26:22.815Z] Nothing to be done for teardown. [2025-06-12T17:26:22.815Z] renaissance-als_0 Finish Time: Thu Jun 12 18:26:11 2025 Epoch Time (ms): 1749749171430