[2025-08-30T04:38:02.733Z] Running test renaissance-als_0 ... [2025-08-30T04:38:02.733Z] =============================================== [2025-08-30T04:38:02.733Z] renaissance-als_0 Start Time: Sat Aug 30 04:38:02 2025 Epoch Time (ms): 1756528682514 [2025-08-30T04:38:02.733Z] variation: NoOptions [2025-08-30T04:38:02.733Z] JVM_OPTIONS: [2025-08-30T04:38:02.733Z] { \ [2025-08-30T04:38:02.733Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T04:38:02.733Z] echo "Nothing to be done for setup."; \ [2025-08-30T04:38:02.733Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565286824292/renaissance-als_0"; \ [2025-08-30T04:38:02.733Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565286824292/renaissance-als_0"; \ [2025-08-30T04:38:02.733Z] echo ""; echo "TESTING:"; \ [2025-08-30T04:38:02.733Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/jdkbinary/j2sdk-image/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 "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565286824292/renaissance-als_0"/als.json" als; \ [2025-08-30T04:38:02.733Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565286824292/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T04:38:02.733Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T04:38:02.733Z] echo "Nothing to be done for teardown."; \ [2025-08-30T04:38:02.733Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565286824292/TestTargetResult"; [2025-08-30T04:38:02.733Z] [2025-08-30T04:38:02.733Z] TEST SETUP: [2025-08-30T04:38:02.733Z] Nothing to be done for setup. [2025-08-30T04:38:02.733Z] [2025-08-30T04:38:02.733Z] TESTING: [2025-08-30T04:38:18.395Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-30T04:38:26.442Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-30T04:38:26.442Z] GC before operation: completed in 75.910 ms, heap usage 56.461 MB -> 35.301 MB. [2025-08-30T04:39:04.661Z] ====== als (apache-spark) [default], iteration 0 completed (32595.829 ms) ====== [2025-08-30T04:39:04.662Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-30T04:39:04.662Z] GC before operation: completed in 178.078 ms, heap usage 259.088 MB -> 62.256 MB. [2025-08-30T04:39:12.029Z] ====== als (apache-spark) [default], iteration 1 completed (10703.964 ms) ====== [2025-08-30T04:39:12.029Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-30T04:39:12.029Z] GC before operation: completed in 158.193 ms, heap usage 161.970 MB -> 63.018 MB. [2025-08-30T04:39:23.510Z] ====== als (apache-spark) [default], iteration 2 completed (9574.538 ms) ====== [2025-08-30T04:39:23.510Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-30T04:39:23.510Z] GC before operation: completed in 160.663 ms, heap usage 99.338 MB -> 64.143 MB. [2025-08-30T04:39:31.593Z] ====== als (apache-spark) [default], iteration 3 completed (8898.085 ms) ====== [2025-08-30T04:39:31.593Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-30T04:39:31.593Z] GC before operation: completed in 171.559 ms, heap usage 179.609 MB -> 64.243 MB. [2025-08-30T04:39:41.290Z] ====== als (apache-spark) [default], iteration 4 completed (8771.720 ms) ====== [2025-08-30T04:39:41.290Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-30T04:39:41.290Z] GC before operation: completed in 179.077 ms, heap usage 488.184 MB -> 65.206 MB. [2025-08-30T04:39:50.975Z] ====== als (apache-spark) [default], iteration 5 completed (8427.576 ms) ====== [2025-08-30T04:39:50.975Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-30T04:39:50.975Z] GC before operation: completed in 186.194 ms, heap usage 298.833 MB -> 65.586 MB. [2025-08-30T04:39:59.369Z] ====== als (apache-spark) [default], iteration 6 completed (7936.217 ms) ====== [2025-08-30T04:39:59.369Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-30T04:39:59.369Z] GC before operation: completed in 181.136 ms, heap usage 575.339 MB -> 66.398 MB. [2025-08-30T04:40:07.495Z] ====== als (apache-spark) [default], iteration 7 completed (8041.496 ms) ====== [2025-08-30T04:40:07.495Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-30T04:40:07.495Z] GC before operation: completed in 189.996 ms, heap usage 570.846 MB -> 66.978 MB. [2025-08-30T04:40:15.533Z] ====== als (apache-spark) [default], iteration 8 completed (7981.649 ms) ====== [2025-08-30T04:40:15.533Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-30T04:40:16.467Z] GC before operation: completed in 189.118 ms, heap usage 267.792 MB -> 67.064 MB. [2025-08-30T04:40:24.517Z] ====== als (apache-spark) [default], iteration 9 completed (7864.656 ms) ====== [2025-08-30T04:40:24.517Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-30T04:40:24.517Z] GC before operation: completed in 194.465 ms, heap usage 531.607 MB -> 67.670 MB. [2025-08-30T04:40:32.555Z] ====== als (apache-spark) [default], iteration 10 completed (7849.195 ms) ====== [2025-08-30T04:40:32.556Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-30T04:40:32.556Z] GC before operation: completed in 180.482 ms, heap usage 428.107 MB -> 67.897 MB. [2025-08-30T04:40:40.620Z] ====== als (apache-spark) [default], iteration 11 completed (7675.637 ms) ====== [2025-08-30T04:40:40.620Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-30T04:40:40.620Z] GC before operation: completed in 199.066 ms, heap usage 305.214 MB -> 67.926 MB. [2025-08-30T04:40:48.819Z] ====== als (apache-spark) [default], iteration 12 completed (7859.295 ms) ====== [2025-08-30T04:40:48.819Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-30T04:40:49.758Z] GC before operation: completed in 191.673 ms, heap usage 472.148 MB -> 68.418 MB. [2025-08-30T04:40:57.810Z] ====== als (apache-spark) [default], iteration 13 completed (7653.657 ms) ====== [2025-08-30T04:40:57.810Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-30T04:40:57.810Z] GC before operation: completed in 204.300 ms, heap usage 551.926 MB -> 69.302 MB. [2025-08-30T04:41:05.887Z] ====== als (apache-spark) [default], iteration 14 completed (7839.984 ms) ====== [2025-08-30T04:41:05.887Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-30T04:41:05.887Z] GC before operation: completed in 189.619 ms, heap usage 350.134 MB -> 68.695 MB. [2025-08-30T04:41:13.402Z] ====== als (apache-spark) [default], iteration 15 completed (7591.570 ms) ====== [2025-08-30T04:41:13.402Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-30T04:41:14.353Z] GC before operation: completed in 200.614 ms, heap usage 328.006 MB -> 69.039 MB. [2025-08-30T04:41:22.434Z] ====== als (apache-spark) [default], iteration 16 completed (7629.039 ms) ====== [2025-08-30T04:41:22.434Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-30T04:41:22.434Z] GC before operation: completed in 197.689 ms, heap usage 212.517 MB -> 69.092 MB. [2025-08-30T04:41:30.511Z] ====== als (apache-spark) [default], iteration 17 completed (7592.378 ms) ====== [2025-08-30T04:41:30.511Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-30T04:41:30.511Z] GC before operation: completed in 202.076 ms, heap usage 115.823 MB -> 69.209 MB. [2025-08-30T04:41:38.605Z] ====== als (apache-spark) [default], iteration 18 completed (7672.909 ms) ====== [2025-08-30T04:41:38.605Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-30T04:41:38.605Z] GC before operation: completed in 200.361 ms, heap usage 162.112 MB -> 69.525 MB. [2025-08-30T04:41:46.688Z] ====== als (apache-spark) [default], iteration 19 completed (7928.278 ms) ====== [2025-08-30T04:41:46.688Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-30T04:41:46.688Z] GC before operation: completed in 196.256 ms, heap usage 363.575 MB -> 69.951 MB. [2025-08-30T04:41:54.777Z] ====== als (apache-spark) [default], iteration 20 completed (7545.231 ms) ====== [2025-08-30T04:41:54.777Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-30T04:41:54.777Z] GC before operation: completed in 202.436 ms, heap usage 438.337 MB -> 70.444 MB. [2025-08-30T04:42:02.872Z] ====== als (apache-spark) [default], iteration 21 completed (7558.211 ms) ====== [2025-08-30T04:42:02.872Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-30T04:42:02.872Z] GC before operation: completed in 221.565 ms, heap usage 173.871 MB -> 70.297 MB. [2025-08-30T04:42:10.948Z] ====== als (apache-spark) [default], iteration 22 completed (7598.457 ms) ====== [2025-08-30T04:42:10.948Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-30T04:42:10.948Z] GC before operation: completed in 209.994 ms, heap usage 221.545 MB -> 70.535 MB. [2025-08-30T04:42:19.074Z] ====== als (apache-spark) [default], iteration 23 completed (7705.547 ms) ====== [2025-08-30T04:42:19.074Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-30T04:42:19.074Z] GC before operation: completed in 214.938 ms, heap usage 200.315 MB -> 70.865 MB. [2025-08-30T04:42:27.162Z] ====== als (apache-spark) [default], iteration 24 completed (7860.352 ms) ====== [2025-08-30T04:42:27.162Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-30T04:42:28.106Z] GC before operation: completed in 208.427 ms, heap usage 295.297 MB -> 71.233 MB. [2025-08-30T04:42:36.177Z] ====== als (apache-spark) [default], iteration 25 completed (7411.040 ms) ====== [2025-08-30T04:42:36.177Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-30T04:42:36.177Z] GC before operation: completed in 216.639 ms, heap usage 161.605 MB -> 71.378 MB. [2025-08-30T04:42:44.254Z] ====== als (apache-spark) [default], iteration 26 completed (7552.584 ms) ====== [2025-08-30T04:42:44.254Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-30T04:42:44.255Z] GC before operation: completed in 223.926 ms, heap usage 605.426 MB -> 72.209 MB. [2025-08-30T04:42:52.388Z] ====== als (apache-spark) [default], iteration 27 completed (7735.341 ms) ====== [2025-08-30T04:42:52.388Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-30T04:42:52.388Z] GC before operation: completed in 212.274 ms, heap usage 194.224 MB -> 71.911 MB. [2025-08-30T04:43:00.458Z] ====== als (apache-spark) [default], iteration 28 completed (7454.533 ms) ====== [2025-08-30T04:43:00.458Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-30T04:43:00.458Z] GC before operation: completed in 220.525 ms, heap usage 194.206 MB -> 72.132 MB. [2025-08-30T04:43:08.925Z] ====== als (apache-spark) [default], iteration 29 completed (7871.217 ms) ====== [2025-08-30T04:43:08.926Z] ----------------------------------- [2025-08-30T04:43:08.926Z] renaissance-als_0_PASSED [2025-08-30T04:43:08.926Z] ----------------------------------- [2025-08-30T04:43:08.926Z] [2025-08-30T04:43:08.926Z] TEST TEARDOWN: [2025-08-30T04:43:08.926Z] Nothing to be done for teardown. [2025-08-30T04:43:08.926Z] renaissance-als_0 Finish Time: Sat Aug 30 04:43:08 2025 Epoch Time (ms): 1756528988547