[2025-12-03T22:00:12.201Z] Running test renaissance-als_0 ... [2025-12-03T22:00:12.201Z] =============================================== [2025-12-03T22:00:12.201Z] renaissance-als_0 Start Time: Wed Dec 3 17:00:11 2025 Epoch Time (ms): 1764799211878 [2025-12-03T22:00:12.201Z] variation: NoOptions [2025-12-03T22:00:12.201Z] JVM_OPTIONS: [2025-12-03T22:00:12.201Z] { \ [2025-12-03T22:00:12.201Z] echo ""; echo "TEST SETUP:"; \ [2025-12-03T22:00:12.201Z] echo "Nothing to be done for setup."; \ [2025-12-03T22:00:12.201Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17647992112327/renaissance-als_0"; \ [2025-12-03T22:00:12.201Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17647992112327/renaissance-als_0"; \ [2025-12-03T22:00:12.201Z] echo ""; echo "TESTING:"; \ [2025-12-03T22:00:12.201Z] "/Users/admin/workspace/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17647992112327/renaissance-als_0"/als.json" als; \ [2025-12-03T22:00:12.201Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17647992112327/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-03T22:00:12.201Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-03T22:00:12.201Z] echo "Nothing to be done for teardown."; \ [2025-12-03T22:00:12.201Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17647992112327/TestTargetResult"; [2025-12-03T22:00:12.201Z] [2025-12-03T22:00:12.201Z] TEST SETUP: [2025-12-03T22:00:12.201Z] Nothing to be done for setup. [2025-12-03T22:00:12.201Z] [2025-12-03T22:00:12.201Z] TESTING: [2025-12-03T22:00:15.344Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-03T22:00:17.090Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-03T22:00:17.090Z] GC before operation: completed in 17.329 ms, heap usage 86.144 MB -> 35.152 MB. [2025-12-03T22:00:26.405Z] ====== als (apache-spark) [default], iteration 0 completed (9072.928 ms) ====== [2025-12-03T22:00:26.405Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-03T22:00:26.405Z] GC before operation: completed in 50.555 ms, heap usage 755.488 MB -> 62.655 MB. [2025-12-03T22:00:30.769Z] ====== als (apache-spark) [default], iteration 1 completed (3998.966 ms) ====== [2025-12-03T22:00:30.769Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-03T22:00:30.769Z] GC before operation: completed in 53.961 ms, heap usage 976.306 MB -> 63.887 MB. [2025-12-03T22:00:34.886Z] ====== als (apache-spark) [default], iteration 2 completed (4055.701 ms) ====== [2025-12-03T22:00:34.886Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-03T22:00:34.886Z] GC before operation: completed in 55.209 ms, heap usage 368.369 MB -> 63.738 MB. [2025-12-03T22:00:38.933Z] ====== als (apache-spark) [default], iteration 3 completed (4039.922 ms) ====== [2025-12-03T22:00:38.933Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-03T22:00:38.933Z] GC before operation: completed in 49.692 ms, heap usage 672.232 MB -> 64.826 MB. [2025-12-03T22:00:43.061Z] ====== als (apache-spark) [default], iteration 4 completed (3413.219 ms) ====== [2025-12-03T22:00:43.061Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-03T22:00:43.061Z] GC before operation: completed in 60.027 ms, heap usage 533.909 MB -> 65.226 MB. [2025-12-03T22:00:46.303Z] ====== als (apache-spark) [default], iteration 5 completed (3194.234 ms) ====== [2025-12-03T22:00:46.303Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-03T22:00:46.303Z] GC before operation: completed in 33.494 ms, heap usage 348.486 MB -> 65.455 MB. [2025-12-03T22:00:49.518Z] ====== als (apache-spark) [default], iteration 6 completed (3286.573 ms) ====== [2025-12-03T22:00:49.518Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-03T22:00:49.518Z] GC before operation: completed in 37.758 ms, heap usage 345.707 MB -> 66.077 MB. [2025-12-03T22:00:52.786Z] ====== als (apache-spark) [default], iteration 7 completed (3116.495 ms) ====== [2025-12-03T22:00:52.786Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-03T22:00:52.786Z] GC before operation: completed in 42.381 ms, heap usage 382.558 MB -> 66.661 MB. [2025-12-03T22:00:55.955Z] ====== als (apache-spark) [default], iteration 8 completed (3261.958 ms) ====== [2025-12-03T22:00:55.955Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-03T22:00:55.955Z] GC before operation: completed in 35.735 ms, heap usage 295.318 MB -> 67.104 MB. [2025-12-03T22:00:59.192Z] ====== als (apache-spark) [default], iteration 9 completed (3168.761 ms) ====== [2025-12-03T22:00:59.192Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-03T22:00:59.192Z] GC before operation: completed in 44.828 ms, heap usage 336.773 MB -> 67.428 MB. [2025-12-03T22:01:02.390Z] ====== als (apache-spark) [default], iteration 10 completed (3015.576 ms) ====== [2025-12-03T22:01:02.390Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-03T22:01:02.390Z] GC before operation: completed in 44.041 ms, heap usage 209.590 MB -> 67.396 MB. [2025-12-03T22:01:05.562Z] ====== als (apache-spark) [default], iteration 11 completed (3232.430 ms) ====== [2025-12-03T22:01:05.562Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-03T22:01:05.562Z] GC before operation: completed in 44.550 ms, heap usage 757.012 MB -> 68.576 MB. [2025-12-03T22:01:08.715Z] ====== als (apache-spark) [default], iteration 12 completed (2823.268 ms) ====== [2025-12-03T22:01:08.715Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-03T22:01:08.715Z] GC before operation: completed in 49.518 ms, heap usage 486.772 MB -> 68.530 MB. [2025-12-03T22:01:11.957Z] ====== als (apache-spark) [default], iteration 13 completed (2927.729 ms) ====== [2025-12-03T22:01:11.957Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-03T22:01:11.957Z] GC before operation: completed in 41.039 ms, heap usage 412.258 MB -> 68.485 MB. [2025-12-03T22:01:14.408Z] ====== als (apache-spark) [default], iteration 14 completed (2855.301 ms) ====== [2025-12-03T22:01:14.409Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-03T22:01:14.778Z] GC before operation: completed in 45.588 ms, heap usage 110.380 MB -> 68.755 MB. [2025-12-03T22:01:17.219Z] ====== als (apache-spark) [default], iteration 15 completed (2541.949 ms) ====== [2025-12-03T22:01:17.219Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-03T22:01:17.219Z] GC before operation: completed in 40.422 ms, heap usage 338.232 MB -> 69.036 MB. [2025-12-03T22:01:19.682Z] ====== als (apache-spark) [default], iteration 16 completed (2415.538 ms) ====== [2025-12-03T22:01:19.682Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-03T22:01:19.682Z] GC before operation: completed in 40.199 ms, heap usage 178.447 MB -> 69.181 MB. [2025-12-03T22:01:22.913Z] ====== als (apache-spark) [default], iteration 17 completed (2661.325 ms) ====== [2025-12-03T22:01:22.913Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-03T22:01:22.913Z] GC before operation: completed in 43.231 ms, heap usage 293.219 MB -> 69.486 MB. [2025-12-03T22:01:25.392Z] ====== als (apache-spark) [default], iteration 18 completed (2531.846 ms) ====== [2025-12-03T22:01:25.392Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-03T22:01:25.392Z] GC before operation: completed in 42.446 ms, heap usage 161.392 MB -> 69.642 MB. [2025-12-03T22:01:28.178Z] ====== als (apache-spark) [default], iteration 19 completed (3080.387 ms) ====== [2025-12-03T22:01:28.178Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-03T22:01:28.537Z] GC before operation: completed in 52.214 ms, heap usage 427.967 MB -> 70.265 MB. [2025-12-03T22:01:31.694Z] ====== als (apache-spark) [default], iteration 20 completed (3282.532 ms) ====== [2025-12-03T22:01:31.694Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-03T22:01:31.694Z] GC before operation: completed in 66.447 ms, heap usage 282.091 MB -> 70.351 MB. [2025-12-03T22:01:34.804Z] ====== als (apache-spark) [default], iteration 21 completed (3057.795 ms) ====== [2025-12-03T22:01:34.804Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-03T22:01:35.192Z] GC before operation: completed in 59.802 ms, heap usage 612.466 MB -> 71.054 MB. [2025-12-03T22:01:39.232Z] ====== als (apache-spark) [default], iteration 22 completed (3492.910 ms) ====== [2025-12-03T22:01:39.232Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-03T22:01:39.233Z] GC before operation: completed in 53.599 ms, heap usage 599.514 MB -> 71.261 MB. [2025-12-03T22:01:42.456Z] ====== als (apache-spark) [default], iteration 23 completed (3359.811 ms) ====== [2025-12-03T22:01:42.456Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-03T22:01:42.456Z] GC before operation: completed in 50.587 ms, heap usage 184.232 MB -> 70.842 MB. [2025-12-03T22:01:45.596Z] ====== als (apache-spark) [default], iteration 24 completed (3005.787 ms) ====== [2025-12-03T22:01:45.596Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-03T22:01:45.596Z] GC before operation: completed in 48.466 ms, heap usage 420.792 MB -> 71.368 MB. [2025-12-03T22:01:48.879Z] ====== als (apache-spark) [default], iteration 25 completed (3069.041 ms) ====== [2025-12-03T22:01:48.879Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-03T22:01:48.879Z] GC before operation: completed in 53.575 ms, heap usage 235.953 MB -> 71.443 MB. [2025-12-03T22:01:52.229Z] ====== als (apache-spark) [default], iteration 26 completed (3654.899 ms) ====== [2025-12-03T22:01:52.229Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-03T22:01:52.229Z] GC before operation: completed in 48.551 ms, heap usage 623.540 MB -> 72.191 MB. [2025-12-03T22:01:55.397Z] ====== als (apache-spark) [default], iteration 27 completed (3174.420 ms) ====== [2025-12-03T22:01:55.397Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-03T22:01:55.748Z] GC before operation: completed in 45.003 ms, heap usage 615.576 MB -> 72.536 MB. [2025-12-03T22:01:59.782Z] ====== als (apache-spark) [default], iteration 28 completed (3516.460 ms) ====== [2025-12-03T22:01:59.782Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-03T22:01:59.782Z] GC before operation: completed in 58.454 ms, heap usage 619.960 MB -> 72.774 MB. [2025-12-03T22:02:03.030Z] ====== als (apache-spark) [default], iteration 29 completed (3514.791 ms) ====== [2025-12-03T22:02:03.030Z] ----------------------------------- [2025-12-03T22:02:03.030Z] renaissance-als_0_PASSED [2025-12-03T22:02:03.030Z] ----------------------------------- [2025-12-03T22:02:03.030Z] [2025-12-03T22:02:03.030Z] TEST TEARDOWN: [2025-12-03T22:02:03.030Z] Nothing to be done for teardown. [2025-12-03T22:02:03.030Z] renaissance-als_0 Finish Time: Wed Dec 3 17:02:02 2025 Epoch Time (ms): 1764799322943