[2025-08-30T00:05:33.081Z] Running test renaissance-als_0 ... [2025-08-30T00:05:33.081Z] =============================================== [2025-08-30T00:05:33.081Z] renaissance-als_0 Start Time: Fri Aug 29 17:05:32 2025 Epoch Time (ms): 1756512332845 [2025-08-30T00:05:33.081Z] variation: NoOptions [2025-08-30T00:05:33.577Z] JVM_OPTIONS: [2025-08-30T00:05:33.577Z] { \ [2025-08-30T00:05:33.577Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T00:05:33.577Z] echo "Nothing to be done for setup."; \ [2025-08-30T00:05:33.577Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/renaissance-als_0"; \ [2025-08-30T00:05:33.577Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/renaissance-als_0"; \ [2025-08-30T00:05:33.577Z] echo ""; echo "TESTING:"; \ [2025-08-30T00:05:33.577Z] "/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_17565123298003/renaissance-als_0"/als.json" als; \ [2025-08-30T00:05:33.577Z] 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_17565123298003/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T00:05:33.577Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T00:05:33.577Z] echo "Nothing to be done for teardown."; \ [2025-08-30T00:05:33.577Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/TestTargetResult"; [2025-08-30T00:05:33.577Z] [2025-08-30T00:05:33.577Z] TEST SETUP: [2025-08-30T00:05:33.577Z] Nothing to be done for setup. [2025-08-30T00:05:33.577Z] [2025-08-30T00:05:33.577Z] TESTING: [2025-08-30T00:05:56.123Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-30T00:06:04.734Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-30T00:06:04.734Z] GC before operation: completed in 234.495 ms, heap usage 300.000 MB -> 35.036 MB. [2025-08-30T00:08:05.131Z] ====== als (apache-spark) [default], iteration 0 completed (101929.524 ms) ====== [2025-08-30T00:08:05.131Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-30T00:08:05.131Z] GC before operation: completed in 882.692 ms, heap usage 161.597 MB -> 61.337 MB. [2025-08-30T00:08:49.942Z] ====== als (apache-spark) [default], iteration 1 completed (53192.777 ms) ====== [2025-08-30T00:08:49.942Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-30T00:08:49.942Z] GC before operation: completed in 600.534 ms, heap usage 1.376 GB -> 64.009 MB. [2025-08-30T00:09:20.889Z] ====== als (apache-spark) [default], iteration 2 completed (30913.613 ms) ====== [2025-08-30T00:09:20.889Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-30T00:09:20.889Z] GC before operation: completed in 256.968 ms, heap usage 751.171 MB -> 63.906 MB. [2025-08-30T00:09:51.921Z] ====== als (apache-spark) [default], iteration 3 completed (34114.308 ms) ====== [2025-08-30T00:09:51.921Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-30T00:09:51.921Z] GC before operation: completed in 210.197 ms, heap usage 631.425 MB -> 64.209 MB. [2025-08-30T00:10:31.330Z] ====== als (apache-spark) [default], iteration 4 completed (33630.942 ms) ====== [2025-08-30T00:10:31.330Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-30T00:10:31.330Z] GC before operation: completed in 292.883 ms, heap usage 556.731 MB -> 64.843 MB. [2025-08-30T00:11:02.990Z] ====== als (apache-spark) [default], iteration 5 completed (29903.643 ms) ====== [2025-08-30T00:11:02.990Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-30T00:11:02.991Z] GC before operation: completed in 363.267 ms, heap usage 580.144 MB -> 65.258 MB. [2025-08-30T00:11:28.349Z] ====== als (apache-spark) [default], iteration 6 completed (27891.293 ms) ====== [2025-08-30T00:11:28.349Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-30T00:11:28.349Z] GC before operation: completed in 296.701 ms, heap usage 460.797 MB -> 65.734 MB. [2025-08-30T00:12:12.261Z] ====== als (apache-spark) [default], iteration 7 completed (40904.402 ms) ====== [2025-08-30T00:12:12.261Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-30T00:12:12.261Z] GC before operation: completed in 1036.762 ms, heap usage 864.892 MB -> 66.678 MB. [2025-08-30T00:12:50.861Z] ====== als (apache-spark) [default], iteration 8 completed (41892.720 ms) ====== [2025-08-30T00:12:50.861Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-30T00:12:51.331Z] GC before operation: completed in 340.897 ms, heap usage 207.815 MB -> 67.235 MB. [2025-08-30T00:13:27.509Z] ====== als (apache-spark) [default], iteration 9 completed (32335.989 ms) ====== [2025-08-30T00:13:27.509Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-30T00:13:27.509Z] GC before operation: completed in 196.822 ms, heap usage 433.774 MB -> 66.767 MB. [2025-08-30T00:13:57.919Z] ====== als (apache-spark) [default], iteration 10 completed (29093.280 ms) ====== [2025-08-30T00:13:57.919Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-30T00:13:57.919Z] GC before operation: completed in 315.213 ms, heap usage 649.120 MB -> 67.557 MB. [2025-08-30T00:14:23.508Z] ====== als (apache-spark) [default], iteration 11 completed (29062.763 ms) ====== [2025-08-30T00:14:23.508Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-30T00:14:24.190Z] GC before operation: completed in 211.462 ms, heap usage 190.092 MB -> 67.204 MB. [2025-08-30T00:15:01.623Z] ====== als (apache-spark) [default], iteration 12 completed (34918.679 ms) ====== [2025-08-30T00:15:01.623Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-30T00:15:01.623Z] GC before operation: completed in 422.189 ms, heap usage 1.437 GB -> 68.867 MB. [2025-08-30T00:15:32.835Z] ====== als (apache-spark) [default], iteration 13 completed (29169.382 ms) ====== [2025-08-30T00:15:32.835Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-30T00:15:32.835Z] GC before operation: completed in 333.458 ms, heap usage 767.364 MB -> 68.324 MB. [2025-08-30T00:15:58.784Z] ====== als (apache-spark) [default], iteration 14 completed (27460.969 ms) ====== [2025-08-30T00:15:58.784Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-30T00:15:58.784Z] GC before operation: completed in 274.125 ms, heap usage 1012.906 MB -> 68.850 MB. [2025-08-30T00:16:29.321Z] ====== als (apache-spark) [default], iteration 15 completed (29142.117 ms) ====== [2025-08-30T00:16:29.321Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-30T00:16:29.321Z] GC before operation: completed in 186.310 ms, heap usage 706.363 MB -> 68.847 MB. [2025-08-30T00:16:55.586Z] ====== als (apache-spark) [default], iteration 16 completed (27501.642 ms) ====== [2025-08-30T00:16:55.586Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-30T00:16:55.586Z] GC before operation: completed in 237.969 ms, heap usage 528.663 MB -> 68.794 MB. [2025-08-30T00:17:22.021Z] ====== als (apache-spark) [default], iteration 17 completed (26485.190 ms) ====== [2025-08-30T00:17:22.021Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-30T00:17:22.501Z] GC before operation: completed in 156.021 ms, heap usage 1.046 GB -> 69.655 MB. [2025-08-30T00:17:53.492Z] ====== als (apache-spark) [default], iteration 18 completed (26474.337 ms) ====== [2025-08-30T00:17:53.492Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-30T00:17:53.492Z] GC before operation: completed in 332.162 ms, heap usage 777.971 MB -> 69.560 MB. [2025-08-30T00:18:20.111Z] ====== als (apache-spark) [default], iteration 19 completed (27852.744 ms) ====== [2025-08-30T00:18:20.111Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-30T00:18:20.111Z] GC before operation: completed in 344.007 ms, heap usage 876.886 MB -> 70.087 MB. [2025-08-30T00:18:50.954Z] ====== als (apache-spark) [default], iteration 20 completed (32439.811 ms) ====== [2025-08-30T00:18:50.954Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-30T00:18:51.384Z] GC before operation: completed in 334.903 ms, heap usage 786.952 MB -> 70.252 MB. [2025-08-30T00:19:24.361Z] ====== als (apache-spark) [default], iteration 21 completed (29316.932 ms) ====== [2025-08-30T00:19:24.361Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-30T00:19:24.361Z] GC before operation: completed in 197.533 ms, heap usage 348.707 MB -> 69.841 MB. [2025-08-30T00:19:50.858Z] ====== als (apache-spark) [default], iteration 22 completed (26594.636 ms) ====== [2025-08-30T00:19:50.858Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-30T00:19:50.858Z] GC before operation: completed in 285.866 ms, heap usage 147.116 MB -> 69.850 MB. [2025-08-30T00:20:17.121Z] ====== als (apache-spark) [default], iteration 23 completed (28559.854 ms) ====== [2025-08-30T00:20:17.121Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-30T00:20:17.718Z] GC before operation: completed in 385.536 ms, heap usage 409.603 MB -> 70.299 MB. [2025-08-30T00:20:49.114Z] ====== als (apache-spark) [default], iteration 24 completed (30752.012 ms) ====== [2025-08-30T00:20:49.114Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-30T00:20:49.114Z] GC before operation: completed in 375.684 ms, heap usage 544.185 MB -> 70.815 MB. [2025-08-30T00:21:20.260Z] ====== als (apache-spark) [default], iteration 25 completed (27389.234 ms) ====== [2025-08-30T00:21:20.260Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-30T00:21:20.260Z] GC before operation: completed in 274.286 ms, heap usage 1.054 GB -> 71.691 MB. [2025-08-30T00:21:45.710Z] ====== als (apache-spark) [default], iteration 26 completed (24532.939 ms) ====== [2025-08-30T00:21:45.710Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-30T00:21:45.710Z] GC before operation: completed in 274.404 ms, heap usage 853.110 MB -> 71.747 MB. [2025-08-30T00:22:11.542Z] ====== als (apache-spark) [default], iteration 27 completed (25491.457 ms) ====== [2025-08-30T00:22:11.542Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-30T00:22:11.542Z] GC before operation: completed in 358.239 ms, heap usage 141.785 MB -> 70.702 MB. [2025-08-30T00:22:38.071Z] ====== als (apache-spark) [default], iteration 28 completed (28445.494 ms) ====== [2025-08-30T00:22:38.071Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-30T00:22:38.071Z] GC before operation: completed in 243.351 ms, heap usage 852.488 MB -> 72.317 MB. [2025-08-30T00:23:05.076Z] ====== als (apache-spark) [default], iteration 29 completed (25427.657 ms) ====== [2025-08-30T00:23:05.460Z] ----------------------------------- [2025-08-30T00:23:05.460Z] renaissance-als_0_PASSED [2025-08-30T00:23:05.460Z] ----------------------------------- [2025-08-30T00:23:05.460Z] [2025-08-30T00:23:05.460Z] TEST TEARDOWN: [2025-08-30T00:23:05.460Z] Nothing to be done for teardown. [2025-08-30T00:23:05.862Z] renaissance-als_0 Finish Time: Fri Aug 29 17:23:05 2025 Epoch Time (ms): 1756513385335