[2025-09-03T21:38:14.527Z] Running test renaissance-als_0 ... [2025-09-03T21:38:14.527Z] =============================================== [2025-09-03T21:38:14.527Z] renaissance-als_0 Start Time: Wed Sep 3 17:38:14 2025 Epoch Time (ms): 1756935494226 [2025-09-03T21:38:14.527Z] variation: NoOptions [2025-09-03T21:38:14.527Z] JVM_OPTIONS: [2025-09-03T21:38:14.527Z] { \ [2025-09-03T21:38:14.527Z] echo ""; echo "TEST SETUP:"; \ [2025-09-03T21:38:14.527Z] echo "Nothing to be done for setup."; \ [2025-09-03T21:38:14.527Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17569354943316/renaissance-als_0"; \ [2025-09-03T21:38:14.527Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17569354943316/renaissance-als_0"; \ [2025-09-03T21:38:14.527Z] echo ""; echo "TESTING:"; \ [2025-09-03T21:38:14.527Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/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/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17569354943316/renaissance-als_0"/als.json" als; \ [2025-09-03T21:38:14.527Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17569354943316/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-03T21:38:14.527Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-03T21:38:14.527Z] echo "Nothing to be done for teardown."; \ [2025-09-03T21:38:14.527Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17569354943316/TestTargetResult"; [2025-09-03T21:38:14.527Z] [2025-09-03T21:38:14.527Z] TEST SETUP: [2025-09-03T21:38:14.527Z] Nothing to be done for setup. [2025-09-03T21:38:14.527Z] [2025-09-03T21:38:14.527Z] TESTING: [2025-09-03T21:38:17.674Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-09-03T21:38:20.090Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-03T21:38:20.090Z] GC before operation: completed in 31.095 ms, heap usage 45.404 MB -> 35.260 MB. [2025-09-03T21:38:29.377Z] ====== als (apache-spark) [default], iteration 0 completed (8139.380 ms) ====== [2025-09-03T21:38:29.377Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-03T21:38:29.377Z] GC before operation: completed in 42.322 ms, heap usage 117.725 MB -> 62.118 MB. [2025-09-03T21:38:32.524Z] ====== als (apache-spark) [default], iteration 1 completed (4030.827 ms) ====== [2025-09-03T21:38:32.524Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-03T21:38:32.524Z] GC before operation: completed in 56.542 ms, heap usage 181.105 MB -> 62.610 MB. [2025-09-03T21:38:36.530Z] ====== als (apache-spark) [default], iteration 2 completed (3442.457 ms) ====== [2025-09-03T21:38:36.530Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-03T21:38:36.530Z] GC before operation: completed in 48.356 ms, heap usage 327.030 MB -> 63.512 MB. [2025-09-03T21:38:40.659Z] ====== als (apache-spark) [default], iteration 3 completed (4073.989 ms) ====== [2025-09-03T21:38:40.659Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-03T21:38:40.659Z] GC before operation: completed in 81.220 ms, heap usage 201.558 MB -> 63.904 MB. [2025-09-03T21:38:44.725Z] ====== als (apache-spark) [default], iteration 4 completed (4063.563 ms) ====== [2025-09-03T21:38:44.725Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-03T21:38:44.725Z] GC before operation: completed in 69.756 ms, heap usage 468.818 MB -> 65.398 MB. [2025-09-03T21:38:48.845Z] ====== als (apache-spark) [default], iteration 5 completed (4140.015 ms) ====== [2025-09-03T21:38:48.845Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-03T21:38:48.845Z] GC before operation: completed in 75.267 ms, heap usage 92.550 MB -> 64.567 MB. [2025-09-03T21:38:53.949Z] ====== als (apache-spark) [default], iteration 6 completed (4144.647 ms) ====== [2025-09-03T21:38:53.949Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-03T21:38:53.949Z] GC before operation: completed in 59.465 ms, heap usage 443.271 MB -> 66.529 MB. [2025-09-03T21:38:58.014Z] ====== als (apache-spark) [default], iteration 7 completed (4031.761 ms) ====== [2025-09-03T21:38:58.014Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-03T21:38:58.014Z] GC before operation: completed in 54.455 ms, heap usage 332.792 MB -> 66.316 MB. [2025-09-03T21:39:02.073Z] ====== als (apache-spark) [default], iteration 8 completed (3945.100 ms) ====== [2025-09-03T21:39:02.073Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-03T21:39:02.073Z] GC before operation: completed in 103.877 ms, heap usage 233.902 MB -> 66.791 MB. [2025-09-03T21:39:06.253Z] ====== als (apache-spark) [default], iteration 9 completed (4189.765 ms) ====== [2025-09-03T21:39:06.254Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-03T21:39:06.254Z] GC before operation: completed in 62.248 ms, heap usage 507.811 MB -> 67.342 MB. [2025-09-03T21:39:10.371Z] ====== als (apache-spark) [default], iteration 10 completed (3999.543 ms) ====== [2025-09-03T21:39:10.371Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-03T21:39:10.372Z] GC before operation: completed in 64.465 ms, heap usage 241.640 MB -> 67.260 MB. [2025-09-03T21:39:14.491Z] ====== als (apache-spark) [default], iteration 11 completed (4043.208 ms) ====== [2025-09-03T21:39:14.491Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-03T21:39:14.491Z] GC before operation: completed in 75.337 ms, heap usage 203.279 MB -> 67.428 MB. [2025-09-03T21:39:18.537Z] ====== als (apache-spark) [default], iteration 12 completed (4166.998 ms) ====== [2025-09-03T21:39:18.537Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-03T21:39:18.899Z] GC before operation: completed in 69.528 ms, heap usage 424.491 MB -> 68.058 MB. [2025-09-03T21:39:23.082Z] ====== als (apache-spark) [default], iteration 13 completed (4103.452 ms) ====== [2025-09-03T21:39:23.082Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-03T21:39:23.082Z] GC before operation: completed in 84.308 ms, heap usage 178.587 MB -> 67.893 MB. [2025-09-03T21:39:27.164Z] ====== als (apache-spark) [default], iteration 14 completed (3888.015 ms) ====== [2025-09-03T21:39:27.164Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-03T21:39:27.164Z] GC before operation: completed in 61.594 ms, heap usage 213.765 MB -> 68.268 MB. [2025-09-03T21:39:31.173Z] ====== als (apache-spark) [default], iteration 15 completed (3989.966 ms) ====== [2025-09-03T21:39:31.173Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-03T21:39:31.173Z] GC before operation: completed in 63.302 ms, heap usage 501.271 MB -> 68.831 MB. [2025-09-03T21:39:35.247Z] ====== als (apache-spark) [default], iteration 16 completed (3843.936 ms) ====== [2025-09-03T21:39:35.247Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-03T21:39:35.247Z] GC before operation: completed in 58.115 ms, heap usage 126.278 MB -> 68.677 MB. [2025-09-03T21:39:39.294Z] ====== als (apache-spark) [default], iteration 17 completed (3798.188 ms) ====== [2025-09-03T21:39:39.294Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-03T21:39:39.295Z] GC before operation: completed in 70.283 ms, heap usage 185.117 MB -> 69.071 MB. [2025-09-03T21:39:43.296Z] ====== als (apache-spark) [default], iteration 18 completed (3798.839 ms) ====== [2025-09-03T21:39:43.296Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-03T21:39:43.296Z] GC before operation: completed in 70.620 ms, heap usage 210.243 MB -> 69.216 MB. [2025-09-03T21:39:47.342Z] ====== als (apache-spark) [default], iteration 19 completed (3766.439 ms) ====== [2025-09-03T21:39:47.342Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-03T21:39:47.342Z] GC before operation: completed in 51.940 ms, heap usage 144.297 MB -> 69.479 MB. [2025-09-03T21:39:51.385Z] ====== als (apache-spark) [default], iteration 20 completed (4031.570 ms) ====== [2025-09-03T21:39:51.385Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-03T21:39:51.385Z] GC before operation: completed in 60.297 ms, heap usage 362.494 MB -> 69.957 MB. [2025-09-03T21:39:55.492Z] ====== als (apache-spark) [default], iteration 21 completed (4008.063 ms) ====== [2025-09-03T21:39:55.492Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-03T21:39:55.492Z] GC before operation: completed in 63.776 ms, heap usage 203.573 MB -> 70.031 MB. [2025-09-03T21:39:59.514Z] ====== als (apache-spark) [default], iteration 22 completed (3787.132 ms) ====== [2025-09-03T21:39:59.514Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-03T21:39:59.514Z] GC before operation: completed in 68.227 ms, heap usage 275.611 MB -> 70.380 MB. [2025-09-03T21:40:03.628Z] ====== als (apache-spark) [default], iteration 23 completed (3989.202 ms) ====== [2025-09-03T21:40:03.628Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-03T21:40:03.628Z] GC before operation: completed in 61.750 ms, heap usage 428.265 MB -> 70.970 MB. [2025-09-03T21:40:07.703Z] ====== als (apache-spark) [default], iteration 24 completed (3756.796 ms) ====== [2025-09-03T21:40:07.703Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-03T21:40:07.703Z] GC before operation: completed in 51.275 ms, heap usage 123.456 MB -> 70.812 MB. [2025-09-03T21:40:11.826Z] ====== als (apache-spark) [default], iteration 25 completed (3665.080 ms) ====== [2025-09-03T21:40:11.827Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-03T21:40:11.827Z] GC before operation: completed in 60.673 ms, heap usage 323.377 MB -> 71.406 MB. [2025-09-03T21:40:15.019Z] ====== als (apache-spark) [default], iteration 26 completed (3715.831 ms) ====== [2025-09-03T21:40:15.019Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-03T21:40:15.375Z] GC before operation: completed in 60.344 ms, heap usage 274.245 MB -> 71.451 MB. [2025-09-03T21:40:19.436Z] ====== als (apache-spark) [default], iteration 27 completed (3547.517 ms) ====== [2025-09-03T21:40:19.436Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-03T21:40:19.436Z] GC before operation: completed in 70.519 ms, heap usage 220.265 MB -> 71.694 MB. [2025-09-03T21:40:23.624Z] ====== als (apache-spark) [default], iteration 28 completed (3978.265 ms) ====== [2025-09-03T21:40:23.624Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-03T21:40:23.624Z] GC before operation: completed in 73.036 ms, heap usage 322.396 MB -> 72.013 MB. [2025-09-03T21:40:26.829Z] ====== als (apache-spark) [default], iteration 29 completed (3751.119 ms) ====== [2025-09-03T21:40:27.179Z] ----------------------------------- [2025-09-03T21:40:27.179Z] renaissance-als_0_PASSED [2025-09-03T21:40:27.179Z] ----------------------------------- [2025-09-03T21:40:27.179Z] [2025-09-03T21:40:27.179Z] TEST TEARDOWN: [2025-09-03T21:40:27.179Z] Nothing to be done for teardown. [2025-09-03T21:40:27.541Z] renaissance-als_0 Finish Time: Wed Sep 3 17:40:27 2025 Epoch Time (ms): 1756935627166