[2025-07-01T06:03:08.526Z] Running test renaissance-als_0 ... [2025-07-01T06:03:08.526Z] =============================================== [2025-07-01T06:03:08.526Z] renaissance-als_0 Start Time: Mon Jun 30 23:03:08 2025 Epoch Time (ms): 1751349788242 [2025-07-01T06:03:08.526Z] variation: NoOptions [2025-07-01T06:03:08.526Z] JVM_OPTIONS: [2025-07-01T06:03:08.526Z] { \ [2025-07-01T06:03:08.526Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T06:03:08.526Z] echo "Nothing to be done for setup."; \ [2025-07-01T06:03:08.526Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1751349785180/renaissance-als_0"; \ [2025-07-01T06:03:08.526Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1751349785180/renaissance-als_0"; \ [2025-07-01T06:03:08.526Z] echo ""; echo "TESTING:"; \ [2025-07-01T06:03:08.526Z] "/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_1751349785180/renaissance-als_0"/als.json" als; \ [2025-07-01T06:03:08.526Z] 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_1751349785180/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T06:03:08.526Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T06:03:08.526Z] echo "Nothing to be done for teardown."; \ [2025-07-01T06:03:08.526Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1751349785180/TestTargetResult"; [2025-07-01T06:03:08.526Z] [2025-07-01T06:03:08.526Z] TEST SETUP: [2025-07-01T06:03:08.526Z] Nothing to be done for setup. [2025-07-01T06:03:08.526Z] [2025-07-01T06:03:08.526Z] TESTING: [2025-07-01T06:03:40.572Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-07-01T06:03:59.644Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-01T06:03:59.644Z] GC before operation: completed in 210.328 ms, heap usage 339.856 MB -> 35.205 MB. [2025-07-01T06:05:28.465Z] ====== als (apache-spark) [default], iteration 0 completed (83064.895 ms) ====== [2025-07-01T06:05:28.465Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-01T06:05:28.465Z] GC before operation: completed in 215.649 ms, heap usage 163.641 MB -> 62.043 MB. [2025-07-01T06:06:11.811Z] ====== als (apache-spark) [default], iteration 1 completed (44563.596 ms) ====== [2025-07-01T06:06:11.811Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-01T06:06:11.811Z] GC before operation: completed in 203.136 ms, heap usage 845.253 MB -> 63.426 MB. [2025-07-01T06:06:48.932Z] ====== als (apache-spark) [default], iteration 2 completed (41185.971 ms) ====== [2025-07-01T06:06:48.932Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-01T06:06:48.932Z] GC before operation: completed in 189.898 ms, heap usage 488.070 MB -> 63.642 MB. [2025-07-01T06:07:26.138Z] ====== als (apache-spark) [default], iteration 3 completed (35953.739 ms) ====== [2025-07-01T06:07:26.138Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-01T06:07:26.138Z] GC before operation: completed in 543.293 ms, heap usage 694.251 MB -> 64.343 MB. [2025-07-01T06:07:57.391Z] ====== als (apache-spark) [default], iteration 4 completed (29641.926 ms) ====== [2025-07-01T06:07:57.391Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-01T06:07:57.391Z] GC before operation: completed in 215.729 ms, heap usage 784.856 MB -> 65.009 MB. [2025-07-01T06:08:41.570Z] ====== als (apache-spark) [default], iteration 5 completed (39766.145 ms) ====== [2025-07-01T06:08:41.570Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-01T06:08:41.570Z] GC before operation: completed in 346.810 ms, heap usage 848.650 MB -> 66.909 MB. [2025-07-01T06:09:15.003Z] ====== als (apache-spark) [default], iteration 6 completed (36505.416 ms) ====== [2025-07-01T06:09:15.003Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-01T06:09:15.003Z] GC before operation: completed in 216.282 ms, heap usage 715.491 MB -> 67.237 MB. [2025-07-01T06:09:46.062Z] ====== als (apache-spark) [default], iteration 7 completed (31232.566 ms) ====== [2025-07-01T06:09:46.062Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-01T06:09:46.062Z] GC before operation: completed in 235.121 ms, heap usage 408.332 MB -> 66.152 MB. [2025-07-01T06:10:22.599Z] ====== als (apache-spark) [default], iteration 8 completed (34093.625 ms) ====== [2025-07-01T06:10:22.599Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-01T06:10:22.599Z] GC before operation: completed in 191.093 ms, heap usage 462.690 MB -> 67.027 MB. [2025-07-01T06:11:00.794Z] ====== als (apache-spark) [default], iteration 9 completed (35829.856 ms) ====== [2025-07-01T06:11:00.794Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-01T06:11:00.794Z] GC before operation: completed in 225.596 ms, heap usage 457.069 MB -> 67.365 MB. [2025-07-01T06:11:38.700Z] ====== als (apache-spark) [default], iteration 10 completed (36586.436 ms) ====== [2025-07-01T06:11:38.700Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-01T06:11:38.700Z] GC before operation: completed in 256.252 ms, heap usage 232.064 MB -> 67.109 MB. [2025-07-01T06:12:10.314Z] ====== als (apache-spark) [default], iteration 11 completed (32552.033 ms) ====== [2025-07-01T06:12:10.314Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-01T06:12:10.314Z] GC before operation: completed in 320.651 ms, heap usage 1.058 GB -> 68.498 MB. [2025-07-01T06:12:41.695Z] ====== als (apache-spark) [default], iteration 12 completed (29425.034 ms) ====== [2025-07-01T06:12:41.695Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-01T06:12:41.695Z] GC before operation: completed in 220.497 ms, heap usage 721.781 MB -> 68.344 MB. [2025-07-01T06:13:14.015Z] ====== als (apache-spark) [default], iteration 13 completed (31996.581 ms) ====== [2025-07-01T06:13:14.015Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-01T06:13:14.015Z] GC before operation: completed in 542.395 ms, heap usage 695.321 MB -> 68.573 MB. [2025-07-01T06:13:51.952Z] ====== als (apache-spark) [default], iteration 14 completed (38107.629 ms) ====== [2025-07-01T06:13:51.952Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-01T06:13:52.547Z] GC before operation: completed in 1003.812 ms, heap usage 612.232 MB -> 68.581 MB. [2025-07-01T06:14:23.860Z] ====== als (apache-spark) [default], iteration 15 completed (30367.436 ms) ====== [2025-07-01T06:14:23.860Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-01T06:14:23.860Z] GC before operation: completed in 357.962 ms, heap usage 812.864 MB -> 69.048 MB. [2025-07-01T06:15:08.579Z] ====== als (apache-spark) [default], iteration 16 completed (39310.266 ms) ====== [2025-07-01T06:15:08.579Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-01T06:15:08.579Z] GC before operation: completed in 328.110 ms, heap usage 1.584 GB -> 70.102 MB. [2025-07-01T06:15:40.783Z] ====== als (apache-spark) [default], iteration 17 completed (35649.511 ms) ====== [2025-07-01T06:15:40.784Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-01T06:15:40.784Z] GC before operation: completed in 254.483 ms, heap usage 809.248 MB -> 70.768 MB. [2025-07-01T06:16:24.149Z] ====== als (apache-spark) [default], iteration 18 completed (40060.030 ms) ====== [2025-07-01T06:16:24.149Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-01T06:16:24.149Z] GC before operation: completed in 159.175 ms, heap usage 258.562 MB -> 69.198 MB. [2025-07-01T06:17:07.704Z] ====== als (apache-spark) [default], iteration 19 completed (40226.261 ms) ====== [2025-07-01T06:17:07.704Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-01T06:17:07.704Z] GC before operation: completed in 406.893 ms, heap usage 502.822 MB -> 69.637 MB. [2025-07-01T06:17:44.833Z] ====== als (apache-spark) [default], iteration 20 completed (40076.615 ms) ====== [2025-07-01T06:17:44.833Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-01T06:17:44.833Z] GC before operation: completed in 384.595 ms, heap usage 863.224 MB -> 70.405 MB. [2025-07-01T06:18:21.923Z] ====== als (apache-spark) [default], iteration 21 completed (38468.225 ms) ====== [2025-07-01T06:18:21.923Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-01T06:18:21.923Z] GC before operation: completed in 247.823 ms, heap usage 256.244 MB -> 69.893 MB. [2025-07-01T06:19:06.569Z] ====== als (apache-spark) [default], iteration 22 completed (42132.580 ms) ====== [2025-07-01T06:19:06.569Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-01T06:19:06.569Z] GC before operation: completed in 217.858 ms, heap usage 1.389 GB -> 71.778 MB. [2025-07-01T06:19:43.597Z] ====== als (apache-spark) [default], iteration 23 completed (36735.055 ms) ====== [2025-07-01T06:19:43.597Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-01T06:19:43.597Z] GC before operation: completed in 209.115 ms, heap usage 726.858 MB -> 71.017 MB. [2025-07-01T06:20:27.495Z] ====== als (apache-spark) [default], iteration 24 completed (41005.398 ms) ====== [2025-07-01T06:20:27.495Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-01T06:20:27.495Z] GC before operation: completed in 260.948 ms, heap usage 227.317 MB -> 70.682 MB. [2025-07-01T06:21:05.190Z] ====== als (apache-spark) [default], iteration 25 completed (38928.478 ms) ====== [2025-07-01T06:21:05.191Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-01T06:21:05.191Z] GC before operation: completed in 410.391 ms, heap usage 273.908 MB -> 70.818 MB. [2025-07-01T06:21:50.482Z] ====== als (apache-spark) [default], iteration 26 completed (40015.368 ms) ====== [2025-07-01T06:21:50.482Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-01T06:21:50.482Z] GC before operation: completed in 167.881 ms, heap usage 331.086 MB -> 71.124 MB. [2025-07-01T06:22:22.241Z] ====== als (apache-spark) [default], iteration 27 completed (36344.600 ms) ====== [2025-07-01T06:22:22.241Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-01T06:22:23.396Z] GC before operation: completed in 1088.601 ms, heap usage 175.392 MB -> 71.223 MB. [2025-07-01T06:23:07.125Z] ====== als (apache-spark) [default], iteration 28 completed (38383.496 ms) ====== [2025-07-01T06:23:07.125Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-01T06:23:07.125Z] GC before operation: completed in 1019.390 ms, heap usage 403.606 MB -> 71.807 MB. [2025-07-01T06:23:44.458Z] ====== als (apache-spark) [default], iteration 29 completed (36331.412 ms) ====== [2025-07-01T06:23:44.458Z] ----------------------------------- [2025-07-01T06:23:44.458Z] renaissance-als_0_PASSED [2025-07-01T06:23:44.458Z] ----------------------------------- [2025-07-01T06:23:44.458Z] [2025-07-01T06:23:44.458Z] TEST TEARDOWN: [2025-07-01T06:23:44.458Z] Nothing to be done for teardown. [2025-07-01T06:23:44.863Z] renaissance-als_0 Finish Time: Mon Jun 30 23:23:44 2025 Epoch Time (ms): 1751351024348