[2025-06-18T20:41:55.617Z] Running test renaissance-als_0 ... [2025-06-18T20:41:55.617Z] =============================================== [2025-06-18T20:41:55.617Z] renaissance-als_0 Start Time: Wed Jun 18 13:41:55 2025 Epoch Time (ms): 1750279315107 [2025-06-18T20:41:55.617Z] variation: NoOptions [2025-06-18T20:41:55.617Z] JVM_OPTIONS: [2025-06-18T20:41:55.617Z] { \ [2025-06-18T20:41:55.617Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T20:41:55.617Z] echo "Nothing to be done for setup."; \ [2025-06-18T20:41:55.617Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17502793121144/renaissance-als_0"; \ [2025-06-18T20:41:55.617Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17502793121144/renaissance-als_0"; \ [2025-06-18T20:41:55.617Z] echo ""; echo "TESTING:"; \ [2025-06-18T20:41:55.617Z] "/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_17502793121144/renaissance-als_0"/als.json" als; \ [2025-06-18T20:41:55.617Z] 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_17502793121144/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T20:41:55.617Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T20:41:55.617Z] echo "Nothing to be done for teardown."; \ [2025-06-18T20:41:55.617Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17502793121144/TestTargetResult"; [2025-06-18T20:41:55.617Z] [2025-06-18T20:41:55.617Z] TEST SETUP: [2025-06-18T20:41:55.617Z] Nothing to be done for setup. [2025-06-18T20:41:55.617Z] [2025-06-18T20:41:55.617Z] TESTING: [2025-06-18T20:42:21.357Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-18T20:42:37.352Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-18T20:42:37.856Z] GC before operation: completed in 706.047 ms, heap usage 316.120 MB -> 35.344 MB. [2025-06-18T20:44:21.198Z] ====== als (apache-spark) [default], iteration 0 completed (92640.467 ms) ====== [2025-06-18T20:44:21.198Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-18T20:44:21.198Z] GC before operation: completed in 902.736 ms, heap usage 733.622 MB -> 62.618 MB. [2025-06-18T20:44:59.621Z] ====== als (apache-spark) [default], iteration 1 completed (45839.087 ms) ====== [2025-06-18T20:44:59.621Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-18T20:44:59.621Z] GC before operation: completed in 697.935 ms, heap usage 294.819 MB -> 62.952 MB. [2025-06-18T20:45:54.188Z] ====== als (apache-spark) [default], iteration 2 completed (49531.393 ms) ====== [2025-06-18T20:45:54.188Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-18T20:45:54.188Z] GC before operation: completed in 256.208 ms, heap usage 1012.956 MB -> 64.552 MB. [2025-06-18T20:46:38.844Z] ====== als (apache-spark) [default], iteration 3 completed (45878.295 ms) ====== [2025-06-18T20:46:38.844Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-18T20:46:38.844Z] GC before operation: completed in 722.975 ms, heap usage 1.145 GB -> 65.269 MB. [2025-06-18T20:47:23.453Z] ====== als (apache-spark) [default], iteration 4 completed (38109.627 ms) ====== [2025-06-18T20:47:23.453Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-18T20:47:23.453Z] GC before operation: completed in 168.972 ms, heap usage 417.691 MB -> 64.934 MB. [2025-06-18T20:48:01.578Z] ====== als (apache-spark) [default], iteration 5 completed (40382.873 ms) ====== [2025-06-18T20:48:01.578Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-18T20:48:01.578Z] GC before operation: completed in 225.348 ms, heap usage 248.578 MB -> 65.368 MB. [2025-06-18T20:48:46.558Z] ====== als (apache-spark) [default], iteration 6 completed (42033.610 ms) ====== [2025-06-18T20:48:46.558Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-18T20:48:46.558Z] GC before operation: completed in 214.506 ms, heap usage 993.197 MB -> 66.603 MB. [2025-06-18T20:49:25.005Z] ====== als (apache-spark) [default], iteration 7 completed (42425.748 ms) ====== [2025-06-18T20:49:25.005Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-18T20:49:26.875Z] GC before operation: completed in 363.276 ms, heap usage 232.347 MB -> 66.206 MB. [2025-06-18T20:50:12.116Z] ====== als (apache-spark) [default], iteration 8 completed (44906.421 ms) ====== [2025-06-18T20:50:12.116Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-18T20:50:12.116Z] GC before operation: completed in 293.887 ms, heap usage 132.551 MB -> 66.431 MB. [2025-06-18T20:50:57.391Z] ====== als (apache-spark) [default], iteration 9 completed (43976.906 ms) ====== [2025-06-18T20:50:57.391Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-18T20:50:57.391Z] GC before operation: completed in 260.168 ms, heap usage 235.446 MB -> 67.163 MB. [2025-06-18T20:51:41.894Z] ====== als (apache-spark) [default], iteration 10 completed (44757.883 ms) ====== [2025-06-18T20:51:41.894Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-18T20:51:42.459Z] GC before operation: completed in 235.787 ms, heap usage 215.901 MB -> 67.388 MB. [2025-06-18T20:52:35.226Z] ====== als (apache-spark) [default], iteration 11 completed (45218.121 ms) ====== [2025-06-18T20:52:35.226Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-18T20:52:35.226Z] GC before operation: completed in 332.421 ms, heap usage 461.828 MB -> 68.174 MB. [2025-06-18T20:53:12.060Z] ====== als (apache-spark) [default], iteration 12 completed (42839.152 ms) ====== [2025-06-18T20:53:12.060Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-18T20:53:12.060Z] GC before operation: completed in 344.023 ms, heap usage 420.962 MB -> 68.292 MB. [2025-06-18T20:53:57.736Z] ====== als (apache-spark) [default], iteration 13 completed (43429.640 ms) ====== [2025-06-18T20:53:57.736Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-18T20:53:57.736Z] GC before operation: completed in 375.824 ms, heap usage 472.282 MB -> 68.729 MB. [2025-06-18T20:54:35.254Z] ====== als (apache-spark) [default], iteration 14 completed (37367.575 ms) ====== [2025-06-18T20:54:35.254Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-18T20:54:35.254Z] GC before operation: completed in 225.228 ms, heap usage 453.005 MB -> 68.881 MB. [2025-06-18T20:55:20.339Z] ====== als (apache-spark) [default], iteration 15 completed (41252.398 ms) ====== [2025-06-18T20:55:20.339Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-18T20:55:20.339Z] GC before operation: completed in 243.270 ms, heap usage 768.318 MB -> 69.499 MB. [2025-06-18T20:56:13.670Z] ====== als (apache-spark) [default], iteration 16 completed (48861.831 ms) ====== [2025-06-18T20:56:13.670Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-18T20:56:13.670Z] GC before operation: completed in 616.288 ms, heap usage 575.699 MB -> 69.676 MB. [2025-06-18T20:56:52.015Z] ====== als (apache-spark) [default], iteration 17 completed (41795.862 ms) ====== [2025-06-18T20:56:52.015Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-18T20:56:52.015Z] GC before operation: completed in 241.882 ms, heap usage 938.633 MB -> 70.232 MB. [2025-06-18T20:57:28.638Z] ====== als (apache-spark) [default], iteration 18 completed (33864.010 ms) ====== [2025-06-18T20:57:28.638Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-18T20:57:28.638Z] GC before operation: completed in 246.587 ms, heap usage 275.458 MB -> 69.669 MB. [2025-06-18T20:58:06.016Z] ====== als (apache-spark) [default], iteration 19 completed (38914.041 ms) ====== [2025-06-18T20:58:06.016Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-18T20:58:06.016Z] GC before operation: completed in 281.441 ms, heap usage 477.015 MB -> 70.446 MB. [2025-06-18T20:58:38.621Z] ====== als (apache-spark) [default], iteration 20 completed (34443.169 ms) ====== [2025-06-18T20:58:38.621Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-18T20:58:39.210Z] GC before operation: completed in 245.949 ms, heap usage 889.727 MB -> 70.854 MB. [2025-06-18T20:59:16.778Z] ====== als (apache-spark) [default], iteration 21 completed (32394.245 ms) ====== [2025-06-18T20:59:16.778Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-18T20:59:16.778Z] GC before operation: completed in 610.841 ms, heap usage 320.279 MB -> 70.288 MB. [2025-06-18T21:00:01.596Z] ====== als (apache-spark) [default], iteration 22 completed (45285.484 ms) ====== [2025-06-18T21:00:01.596Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-18T21:00:02.105Z] GC before operation: completed in 1399.024 ms, heap usage 965.363 MB -> 71.400 MB. [2025-06-18T21:00:38.836Z] ====== als (apache-spark) [default], iteration 23 completed (33429.551 ms) ====== [2025-06-18T21:00:38.836Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-18T21:00:38.836Z] GC before operation: completed in 407.153 ms, heap usage 250.564 MB -> 70.823 MB. [2025-06-18T21:01:16.127Z] ====== als (apache-spark) [default], iteration 24 completed (35747.999 ms) ====== [2025-06-18T21:01:16.127Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-18T21:01:16.127Z] GC before operation: completed in 248.415 ms, heap usage 416.728 MB -> 71.215 MB. [2025-06-18T21:02:09.323Z] ====== als (apache-spark) [default], iteration 25 completed (48270.568 ms) ====== [2025-06-18T21:02:09.323Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-18T21:02:09.323Z] GC before operation: completed in 987.660 ms, heap usage 454.739 MB -> 71.588 MB. [2025-06-18T21:02:48.303Z] ====== als (apache-spark) [default], iteration 26 completed (42683.256 ms) ====== [2025-06-18T21:02:48.303Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-18T21:02:48.303Z] GC before operation: completed in 262.277 ms, heap usage 205.387 MB -> 71.749 MB. [2025-06-18T21:03:26.168Z] ====== als (apache-spark) [default], iteration 27 completed (38528.463 ms) ====== [2025-06-18T21:03:26.168Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-18T21:03:26.168Z] GC before operation: completed in 309.471 ms, heap usage 630.638 MB -> 72.622 MB. [2025-06-18T21:04:19.331Z] ====== als (apache-spark) [default], iteration 28 completed (46502.547 ms) ====== [2025-06-18T21:04:19.331Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-18T21:04:19.331Z] GC before operation: completed in 235.578 ms, heap usage 283.067 MB -> 72.461 MB. [2025-06-18T21:04:51.030Z] ====== als (apache-spark) [default], iteration 29 completed (36772.444 ms) ====== [2025-06-18T21:04:51.030Z] ----------------------------------- [2025-06-18T21:04:51.030Z] renaissance-als_0_PASSED [2025-06-18T21:04:51.030Z] ----------------------------------- [2025-06-18T21:04:51.030Z] [2025-06-18T21:04:51.030Z] TEST TEARDOWN: [2025-06-18T21:04:51.030Z] Nothing to be done for teardown. [2025-06-18T21:04:51.030Z] renaissance-als_0 Finish Time: Wed Jun 18 14:04:50 2025 Epoch Time (ms): 1750280690561