[2025-06-30T17:19:07.113Z] Running test renaissance-als_0 ... [2025-06-30T17:19:07.113Z] =============================================== [2025-06-30T17:19:07.113Z] renaissance-als_0 Start Time: Mon Jun 30 10:19:06 2025 Epoch Time (ms): 1751303946702 [2025-06-30T17:19:07.113Z] variation: NoOptions [2025-06-30T17:19:07.113Z] JVM_OPTIONS: [2025-06-30T17:19:07.113Z] { \ [2025-06-30T17:19:07.113Z] echo ""; echo "TEST SETUP:"; \ [2025-06-30T17:19:07.113Z] echo "Nothing to be done for setup."; \ [2025-06-30T17:19:07.113Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513039444718/renaissance-als_0"; \ [2025-06-30T17:19:07.113Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513039444718/renaissance-als_0"; \ [2025-06-30T17:19:07.113Z] echo ""; echo "TESTING:"; \ [2025-06-30T17:19:07.113Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513039444718/renaissance-als_0"/als.json" als; \ [2025-06-30T17:19:07.113Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513039444718/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-30T17:19:07.113Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-30T17:19:07.113Z] echo "Nothing to be done for teardown."; \ [2025-06-30T17:19:07.113Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17513039444718/TestTargetResult"; [2025-06-30T17:19:07.113Z] [2025-06-30T17:19:07.113Z] TEST SETUP: [2025-06-30T17:19:07.113Z] Nothing to be done for setup. [2025-06-30T17:19:07.113Z] [2025-06-30T17:19:07.113Z] TESTING: [2025-06-30T17:19:22.133Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-30T17:19:33.478Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-30T17:19:33.478Z] GC before operation: completed in 112.757 ms, heap usage 378.678 MB -> 35.000 MB. [2025-06-30T17:20:46.473Z] ====== als (apache-spark) [default], iteration 0 completed (65369.851 ms) ====== [2025-06-30T17:20:46.473Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-30T17:20:46.473Z] GC before operation: completed in 308.960 ms, heap usage 691.033 MB -> 62.338 MB. [2025-06-30T17:21:17.832Z] ====== als (apache-spark) [default], iteration 1 completed (35202.034 ms) ====== [2025-06-30T17:21:17.832Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-30T17:21:17.832Z] GC before operation: completed in 291.310 ms, heap usage 425.093 MB -> 62.930 MB. [2025-06-30T17:21:55.374Z] ====== als (apache-spark) [default], iteration 2 completed (36207.962 ms) ====== [2025-06-30T17:21:55.374Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-30T17:21:55.374Z] GC before operation: completed in 225.478 ms, heap usage 860.174 MB -> 64.341 MB. [2025-06-30T17:22:32.178Z] ====== als (apache-spark) [default], iteration 3 completed (34034.979 ms) ====== [2025-06-30T17:22:32.178Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-30T17:22:32.178Z] GC before operation: completed in 332.878 ms, heap usage 904.377 MB -> 64.947 MB. [2025-06-30T17:23:02.798Z] ====== als (apache-spark) [default], iteration 4 completed (31273.881 ms) ====== [2025-06-30T17:23:02.798Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-30T17:23:02.798Z] GC before operation: completed in 356.034 ms, heap usage 529.841 MB -> 65.031 MB. [2025-06-30T17:23:39.859Z] ====== als (apache-spark) [default], iteration 5 completed (35259.511 ms) ====== [2025-06-30T17:23:39.859Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-30T17:23:39.859Z] GC before operation: completed in 287.764 ms, heap usage 553.205 MB -> 65.486 MB. [2025-06-30T17:24:10.982Z] ====== als (apache-spark) [default], iteration 6 completed (32942.333 ms) ====== [2025-06-30T17:24:10.982Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-30T17:24:10.982Z] GC before operation: completed in 397.566 ms, heap usage 1.307 GB -> 66.897 MB. [2025-06-30T17:24:48.109Z] ====== als (apache-spark) [default], iteration 7 completed (34461.571 ms) ====== [2025-06-30T17:24:48.109Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-30T17:24:48.109Z] GC before operation: completed in 261.012 ms, heap usage 1.305 GB -> 67.504 MB. [2025-06-30T17:25:19.162Z] ====== als (apache-spark) [default], iteration 8 completed (31770.674 ms) ====== [2025-06-30T17:25:19.162Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-30T17:25:19.162Z] GC before operation: completed in 380.369 ms, heap usage 319.879 MB -> 66.984 MB. [2025-06-30T17:25:49.713Z] ====== als (apache-spark) [default], iteration 9 completed (30342.288 ms) ====== [2025-06-30T17:25:49.713Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-30T17:25:49.713Z] GC before operation: completed in 306.202 ms, heap usage 1.313 GB -> 68.214 MB. [2025-06-30T17:26:25.960Z] ====== als (apache-spark) [default], iteration 10 completed (33409.223 ms) ====== [2025-06-30T17:26:25.961Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-30T17:26:25.961Z] GC before operation: completed in 248.356 ms, heap usage 311.421 MB -> 67.334 MB. [2025-06-30T17:27:02.657Z] ====== als (apache-spark) [default], iteration 11 completed (33511.594 ms) ====== [2025-06-30T17:27:02.657Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-30T17:27:02.657Z] GC before operation: completed in 317.505 ms, heap usage 634.577 MB -> 67.960 MB. [2025-06-30T17:27:29.161Z] ====== als (apache-spark) [default], iteration 12 completed (29680.505 ms) ====== [2025-06-30T17:27:29.161Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-30T17:27:29.161Z] GC before operation: completed in 399.230 ms, heap usage 954.606 MB -> 68.749 MB. [2025-06-30T17:28:05.283Z] ====== als (apache-spark) [default], iteration 13 completed (33071.761 ms) ====== [2025-06-30T17:28:05.283Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-30T17:28:05.283Z] GC before operation: completed in 536.067 ms, heap usage 817.814 MB -> 68.526 MB. [2025-06-30T17:28:35.951Z] ====== als (apache-spark) [default], iteration 14 completed (30656.433 ms) ====== [2025-06-30T17:28:35.951Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-30T17:28:35.951Z] GC before operation: completed in 158.924 ms, heap usage 252.340 MB -> 68.452 MB. [2025-06-30T17:29:11.018Z] ====== als (apache-spark) [default], iteration 15 completed (32330.194 ms) ====== [2025-06-30T17:29:11.018Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-30T17:29:11.018Z] GC before operation: completed in 314.127 ms, heap usage 720.212 MB -> 70.099 MB. [2025-06-30T17:29:41.919Z] ====== als (apache-spark) [default], iteration 16 completed (31483.341 ms) ====== [2025-06-30T17:29:41.919Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-30T17:29:41.919Z] GC before operation: completed in 246.135 ms, heap usage 553.497 MB -> 69.197 MB. [2025-06-30T17:30:13.158Z] ====== als (apache-spark) [default], iteration 17 completed (31773.779 ms) ====== [2025-06-30T17:30:13.158Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-30T17:30:13.158Z] GC before operation: completed in 312.243 ms, heap usage 582.964 MB -> 69.527 MB. [2025-06-30T17:30:43.379Z] ====== als (apache-spark) [default], iteration 18 completed (30219.093 ms) ====== [2025-06-30T17:30:43.379Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-30T17:30:43.379Z] GC before operation: completed in 288.587 ms, heap usage 736.044 MB -> 69.942 MB. [2025-06-30T17:31:14.325Z] ====== als (apache-spark) [default], iteration 19 completed (30874.671 ms) ====== [2025-06-30T17:31:14.325Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-30T17:31:14.325Z] GC before operation: completed in 160.302 ms, heap usage 385.923 MB -> 69.585 MB. [2025-06-30T17:31:50.754Z] ====== als (apache-spark) [default], iteration 20 completed (32454.195 ms) ====== [2025-06-30T17:31:50.754Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-30T17:31:50.754Z] GC before operation: completed in 331.658 ms, heap usage 142.532 MB -> 69.672 MB. [2025-06-30T17:32:21.519Z] ====== als (apache-spark) [default], iteration 21 completed (31151.491 ms) ====== [2025-06-30T17:32:21.519Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-30T17:32:21.519Z] GC before operation: completed in 206.316 ms, heap usage 266.377 MB -> 69.947 MB. [2025-06-30T17:32:52.667Z] ====== als (apache-spark) [default], iteration 22 completed (30148.930 ms) ====== [2025-06-30T17:32:52.667Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-30T17:32:52.667Z] GC before operation: completed in 254.083 ms, heap usage 489.418 MB -> 70.616 MB. [2025-06-30T17:33:23.488Z] ====== als (apache-spark) [default], iteration 23 completed (31714.778 ms) ====== [2025-06-30T17:33:23.488Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-30T17:33:23.488Z] GC before operation: completed in 256.239 ms, heap usage 604.997 MB -> 70.969 MB. [2025-06-30T17:33:59.892Z] ====== als (apache-spark) [default], iteration 24 completed (33304.584 ms) ====== [2025-06-30T17:33:59.892Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-30T17:33:59.892Z] GC before operation: completed in 203.686 ms, heap usage 465.613 MB -> 71.251 MB. [2025-06-30T17:34:31.082Z] ====== als (apache-spark) [default], iteration 25 completed (33536.994 ms) ====== [2025-06-30T17:34:31.082Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-30T17:34:31.082Z] GC before operation: completed in 329.632 ms, heap usage 519.125 MB -> 71.286 MB. [2025-06-30T17:35:07.494Z] ====== als (apache-spark) [default], iteration 26 completed (32773.307 ms) ====== [2025-06-30T17:35:07.494Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-30T17:35:07.494Z] GC before operation: completed in 268.545 ms, heap usage 382.426 MB -> 71.314 MB. [2025-06-30T17:35:38.702Z] ====== als (apache-spark) [default], iteration 27 completed (33562.780 ms) ====== [2025-06-30T17:35:38.702Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-30T17:35:38.702Z] GC before operation: completed in 375.199 ms, heap usage 152.723 MB -> 71.372 MB. [2025-06-30T17:36:15.621Z] ====== als (apache-spark) [default], iteration 28 completed (34407.975 ms) ====== [2025-06-30T17:36:15.621Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-30T17:36:15.621Z] GC before operation: completed in 253.531 ms, heap usage 492.598 MB -> 71.625 MB. [2025-06-30T17:36:47.775Z] ====== als (apache-spark) [default], iteration 29 completed (33225.305 ms) ====== [2025-06-30T17:36:47.775Z] ----------------------------------- [2025-06-30T17:36:47.775Z] renaissance-als_0_PASSED [2025-06-30T17:36:47.775Z] ----------------------------------- [2025-06-30T17:36:47.775Z] [2025-06-30T17:36:47.775Z] TEST TEARDOWN: [2025-06-30T17:36:47.775Z] Nothing to be done for teardown. [2025-06-30T17:36:47.775Z] renaissance-als_0 Finish Time: Mon Jun 30 10:36:47 2025 Epoch Time (ms): 1751305007520