[2025-07-30T23:43:40.753Z] Running test renaissance-als_0 ... [2025-07-30T23:43:40.753Z] =============================================== [2025-07-30T23:43:40.753Z] renaissance-als_0 Start Time: Wed Jul 30 23:43:40 2025 Epoch Time (ms): 1753919020642 [2025-07-30T23:43:40.753Z] variation: NoOptions [2025-07-30T23:43:40.753Z] JVM_OPTIONS: [2025-07-30T23:43:41.595Z] { \ [2025-07-30T23:43:41.595Z] echo ""; echo "TEST SETUP:"; \ [2025-07-30T23:43:41.595Z] echo "Nothing to be done for setup."; \ [2025-07-30T23:43:41.595Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/renaissance-als_0"; \ [2025-07-30T23:43:41.595Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/renaissance-als_0"; \ [2025-07-30T23:43:41.595Z] echo ""; echo "TESTING:"; \ [2025-07-30T23:43:41.595Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/renaissance-als_0"/als.json" als; \ [2025-07-30T23:43:41.595Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-30T23:43:41.595Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-30T23:43:41.595Z] echo "Nothing to be done for teardown."; \ [2025-07-30T23:43:41.595Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17539190198694/TestTargetResult"; [2025-07-30T23:43:41.595Z] [2025-07-30T23:43:41.595Z] TEST SETUP: [2025-07-30T23:43:41.595Z] Nothing to be done for setup. [2025-07-30T23:43:41.595Z] [2025-07-30T23:43:41.595Z] TESTING: [2025-07-30T23:44:17.856Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-30T23:44:54.517Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-30T23:44:54.517Z] GC before operation: completed in 352.602 ms, heap usage 57.458 MB -> 35.132 MB. [2025-07-30T23:48:03.011Z] ====== als (apache-spark) [default], iteration 0 completed (183138.669 ms) ====== [2025-07-30T23:48:03.011Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-30T23:48:03.011Z] GC before operation: completed in 831.365 ms, heap usage 139.266 MB -> 61.695 MB. [2025-07-30T23:49:38.586Z] ====== als (apache-spark) [default], iteration 1 completed (89126.710 ms) ====== [2025-07-30T23:49:38.586Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-30T23:49:38.586Z] GC before operation: completed in 1197.217 ms, heap usage 441.251 MB -> 62.906 MB. [2025-07-30T23:50:58.826Z] ====== als (apache-spark) [default], iteration 2 completed (75319.051 ms) ====== [2025-07-30T23:50:58.826Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-30T23:50:58.826Z] GC before operation: completed in 1065.991 ms, heap usage 471.552 MB -> 63.479 MB. [2025-07-30T23:52:19.599Z] ====== als (apache-spark) [default], iteration 3 completed (71829.338 ms) ====== [2025-07-30T23:52:19.599Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-30T23:52:19.599Z] GC before operation: completed in 709.678 ms, heap usage 333.578 MB -> 63.896 MB. [2025-07-30T23:53:28.679Z] ====== als (apache-spark) [default], iteration 4 completed (71661.518 ms) ====== [2025-07-30T23:53:28.680Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-30T23:53:28.680Z] GC before operation: completed in 833.622 ms, heap usage 229.371 MB -> 64.205 MB. [2025-07-30T23:54:48.153Z] ====== als (apache-spark) [default], iteration 5 completed (72070.721 ms) ====== [2025-07-30T23:54:48.153Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-30T23:54:48.153Z] GC before operation: completed in 965.721 ms, heap usage 405.077 MB -> 64.969 MB. [2025-07-30T23:55:55.568Z] ====== als (apache-spark) [default], iteration 6 completed (70781.031 ms) ====== [2025-07-30T23:55:55.568Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-30T23:55:55.568Z] GC before operation: completed in 1021.483 ms, heap usage 277.276 MB -> 65.518 MB. [2025-07-30T23:57:14.793Z] ====== als (apache-spark) [default], iteration 7 completed (70979.957 ms) ====== [2025-07-30T23:57:14.793Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-30T23:57:14.793Z] GC before operation: completed in 1033.840 ms, heap usage 265.802 MB -> 66.076 MB. [2025-07-30T23:58:22.337Z] ====== als (apache-spark) [default], iteration 8 completed (67576.564 ms) ====== [2025-07-30T23:58:22.337Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-30T23:58:22.337Z] GC before operation: completed in 964.594 ms, heap usage 253.806 MB -> 66.487 MB. [2025-07-30T23:59:41.825Z] ====== als (apache-spark) [default], iteration 9 completed (72176.252 ms) ====== [2025-07-30T23:59:41.825Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-30T23:59:41.825Z] GC before operation: completed in 936.698 ms, heap usage 165.531 MB -> 66.680 MB. [2025-07-31T00:00:50.128Z] ====== als (apache-spark) [default], iteration 10 completed (65864.809 ms) ====== [2025-07-31T00:00:50.128Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-31T00:00:50.128Z] GC before operation: completed in 944.966 ms, heap usage 243.530 MB -> 66.970 MB. [2025-07-31T00:01:48.028Z] ====== als (apache-spark) [default], iteration 11 completed (62551.534 ms) ====== [2025-07-31T00:01:48.028Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-31T00:01:48.028Z] GC before operation: completed in 824.271 ms, heap usage 481.838 MB -> 67.619 MB. [2025-07-31T00:02:45.783Z] ====== als (apache-spark) [default], iteration 12 completed (57675.906 ms) ====== [2025-07-31T00:02:45.783Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-31T00:02:46.565Z] GC before operation: completed in 749.972 ms, heap usage 543.553 MB -> 68.514 MB. [2025-07-31T00:03:55.055Z] ====== als (apache-spark) [default], iteration 13 completed (58256.201 ms) ====== [2025-07-31T00:03:55.055Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-31T00:03:55.055Z] GC before operation: completed in 808.679 ms, heap usage 263.350 MB -> 67.904 MB. [2025-07-31T00:04:52.822Z] ====== als (apache-spark) [default], iteration 14 completed (57048.758 ms) ====== [2025-07-31T00:04:52.822Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-31T00:04:52.822Z] GC before operation: completed in 827.964 ms, heap usage 202.675 MB -> 68.394 MB. [2025-07-31T00:05:42.337Z] ====== als (apache-spark) [default], iteration 15 completed (53806.656 ms) ====== [2025-07-31T00:05:42.337Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-31T00:05:42.337Z] GC before operation: completed in 677.983 ms, heap usage 514.712 MB -> 68.665 MB. [2025-07-31T00:06:39.935Z] ====== als (apache-spark) [default], iteration 16 completed (54033.308 ms) ====== [2025-07-31T00:06:39.935Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-31T00:06:39.935Z] GC before operation: completed in 949.055 ms, heap usage 552.385 MB -> 68.451 MB. [2025-07-31T00:07:37.544Z] ====== als (apache-spark) [default], iteration 17 completed (50058.839 ms) ====== [2025-07-31T00:07:37.544Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-31T00:07:37.544Z] GC before operation: completed in 702.756 ms, heap usage 224.865 MB -> 68.779 MB. [2025-07-31T00:08:26.616Z] ====== als (apache-spark) [default], iteration 18 completed (54312.109 ms) ====== [2025-07-31T00:08:26.616Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-31T00:08:27.437Z] GC before operation: completed in 1005.896 ms, heap usage 186.241 MB -> 67.372 MB. [2025-07-31T00:09:25.243Z] ====== als (apache-spark) [default], iteration 19 completed (52865.574 ms) ====== [2025-07-31T00:09:25.243Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-31T00:09:25.243Z] GC before operation: completed in 831.365 ms, heap usage 513.959 MB -> 67.092 MB. [2025-07-31T00:10:22.747Z] ====== als (apache-spark) [default], iteration 20 completed (50357.039 ms) ====== [2025-07-31T00:10:22.747Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-31T00:10:22.747Z] GC before operation: completed in 953.195 ms, heap usage 266.635 MB -> 66.584 MB. [2025-07-31T00:11:11.889Z] ====== als (apache-spark) [default], iteration 21 completed (51978.103 ms) ====== [2025-07-31T00:11:11.889Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-31T00:11:11.889Z] GC before operation: completed in 682.288 ms, heap usage 481.589 MB -> 67.246 MB. [2025-07-31T00:12:09.943Z] ====== als (apache-spark) [default], iteration 22 completed (50879.121 ms) ====== [2025-07-31T00:12:09.943Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-31T00:12:09.943Z] GC before operation: completed in 713.498 ms, heap usage 197.064 MB -> 67.201 MB. [2025-07-31T00:12:59.068Z] ====== als (apache-spark) [default], iteration 23 completed (54441.544 ms) ====== [2025-07-31T00:12:59.068Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-31T00:12:59.861Z] GC before operation: completed in 809.456 ms, heap usage 466.757 MB -> 67.977 MB. [2025-07-31T00:13:58.336Z] ====== als (apache-spark) [default], iteration 24 completed (55505.888 ms) ====== [2025-07-31T00:13:58.336Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-31T00:13:58.336Z] GC before operation: completed in 853.203 ms, heap usage 451.836 MB -> 68.142 MB. [2025-07-31T00:14:56.527Z] ====== als (apache-spark) [default], iteration 25 completed (57001.868 ms) ====== [2025-07-31T00:14:56.527Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-31T00:14:56.527Z] GC before operation: completed in 867.746 ms, heap usage 366.476 MB -> 68.265 MB. [2025-07-31T00:15:54.675Z] ====== als (apache-spark) [default], iteration 26 completed (55416.721 ms) ====== [2025-07-31T00:15:54.675Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-31T00:15:54.675Z] GC before operation: completed in 834.003 ms, heap usage 143.522 MB -> 68.000 MB. [2025-07-31T00:16:52.341Z] ====== als (apache-spark) [default], iteration 27 completed (54847.836 ms) ====== [2025-07-31T00:16:52.341Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-31T00:16:52.341Z] GC before operation: completed in 842.627 ms, heap usage 242.326 MB -> 68.253 MB. [2025-07-31T00:17:50.010Z] ====== als (apache-spark) [default], iteration 28 completed (55555.869 ms) ====== [2025-07-31T00:17:50.010Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-31T00:17:50.010Z] GC before operation: completed in 842.167 ms, heap usage 355.110 MB -> 68.476 MB. [2025-07-31T00:18:48.580Z] ====== als (apache-spark) [default], iteration 29 completed (53457.025 ms) ====== [2025-07-31T00:18:48.580Z] ----------------------------------- [2025-07-31T00:18:48.580Z] renaissance-als_0_PASSED [2025-07-31T00:18:48.580Z] ----------------------------------- [2025-07-31T00:18:48.580Z] [2025-07-31T00:18:48.580Z] TEST TEARDOWN: [2025-07-31T00:18:48.580Z] Nothing to be done for teardown. [2025-07-31T00:18:48.580Z] renaissance-als_0 Finish Time: Thu Jul 31 00:18:45 2025 Epoch Time (ms): 1753921125665