[2025-11-27T00:51:53.906Z] Running test renaissance-als_0 ... [2025-11-27T00:51:53.906Z] =============================================== [2025-11-27T00:51:53.906Z] renaissance-als_0 Start Time: Thu Nov 27 00:51:53 2025 Epoch Time (ms): 1764204713470 [2025-11-27T00:51:53.906Z] variation: NoOptions [2025-11-27T00:51:53.906Z] JVM_OPTIONS: [2025-11-27T00:51:53.906Z] { \ [2025-11-27T00:51:53.906Z] echo ""; echo "TEST SETUP:"; \ [2025-11-27T00:51:53.906Z] echo "Nothing to be done for setup."; \ [2025-11-27T00:51:53.906Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642047138919/renaissance-als_0"; \ [2025-11-27T00:51:53.906Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642047138919/renaissance-als_0"; \ [2025-11-27T00:51:53.906Z] echo ""; echo "TESTING:"; \ [2025-11-27T00:51:53.906Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/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_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642047138919/renaissance-als_0"/als.json" als; \ [2025-11-27T00:51:53.906Z] 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_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642047138919/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-27T00:51:53.906Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-27T00:51:53.906Z] echo "Nothing to be done for teardown."; \ [2025-11-27T00:51:53.906Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17642047138919/TestTargetResult"; [2025-11-27T00:51:53.906Z] [2025-11-27T00:51:53.906Z] TEST SETUP: [2025-11-27T00:51:53.906Z] Nothing to be done for setup. [2025-11-27T00:51:53.906Z] [2025-11-27T00:51:53.906Z] TESTING: [2025-11-27T00:52:05.546Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-27T00:52:18.853Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-27T00:52:18.853Z] GC before operation: completed in 112.462 ms, heap usage 49.843 MB -> 35.234 MB. [2025-11-27T00:53:06.697Z] ====== als (apache-spark) [default], iteration 0 completed (47532.220 ms) ====== [2025-11-27T00:53:06.697Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-27T00:53:06.697Z] GC before operation: completed in 260.636 ms, heap usage 229.267 MB -> 62.007 MB. [2025-11-27T00:53:31.917Z] ====== als (apache-spark) [default], iteration 1 completed (21398.049 ms) ====== [2025-11-27T00:53:31.917Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-27T00:53:31.917Z] GC before operation: completed in 179.362 ms, heap usage 485.724 MB -> 63.308 MB. [2025-11-27T00:53:47.597Z] ====== als (apache-spark) [default], iteration 2 completed (17234.610 ms) ====== [2025-11-27T00:53:47.597Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-27T00:53:47.597Z] GC before operation: completed in 238.560 ms, heap usage 480.599 MB -> 63.994 MB. [2025-11-27T00:54:06.021Z] ====== als (apache-spark) [default], iteration 3 completed (18312.307 ms) ====== [2025-11-27T00:54:06.021Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-27T00:54:06.021Z] GC before operation: completed in 280.810 ms, heap usage 335.156 MB -> 64.132 MB. [2025-11-27T00:54:24.762Z] ====== als (apache-spark) [default], iteration 4 completed (16153.951 ms) ====== [2025-11-27T00:54:24.762Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-27T00:54:24.762Z] GC before operation: completed in 221.850 ms, heap usage 258.750 MB -> 64.632 MB. [2025-11-27T00:54:46.301Z] ====== als (apache-spark) [default], iteration 5 completed (21769.068 ms) ====== [2025-11-27T00:54:46.301Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-27T00:54:46.301Z] GC before operation: completed in 283.349 ms, heap usage 102.590 MB -> 64.563 MB. [2025-11-27T00:55:04.701Z] ====== als (apache-spark) [default], iteration 6 completed (17297.609 ms) ====== [2025-11-27T00:55:04.701Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-27T00:55:04.701Z] GC before operation: completed in 265.475 ms, heap usage 542.236 MB -> 66.128 MB. [2025-11-27T00:55:23.103Z] ====== als (apache-spark) [default], iteration 7 completed (16781.504 ms) ====== [2025-11-27T00:55:23.103Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-27T00:55:23.103Z] GC before operation: completed in 251.342 ms, heap usage 359.781 MB -> 66.445 MB. [2025-11-27T00:55:42.387Z] ====== als (apache-spark) [default], iteration 8 completed (18587.621 ms) ====== [2025-11-27T00:55:42.387Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-27T00:55:42.387Z] GC before operation: completed in 313.137 ms, heap usage 210.647 MB -> 66.817 MB. [2025-11-27T00:55:58.046Z] ====== als (apache-spark) [default], iteration 9 completed (16571.949 ms) ====== [2025-11-27T00:55:58.046Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-27T00:55:58.046Z] GC before operation: completed in 258.451 ms, heap usage 323.059 MB -> 67.185 MB. [2025-11-27T00:56:16.968Z] ====== als (apache-spark) [default], iteration 10 completed (16700.879 ms) ====== [2025-11-27T00:56:16.968Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-27T00:56:16.968Z] GC before operation: completed in 264.023 ms, heap usage 354.451 MB -> 67.334 MB. [2025-11-27T00:56:32.661Z] ====== als (apache-spark) [default], iteration 11 completed (15872.350 ms) ====== [2025-11-27T00:56:32.661Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-27T00:56:32.661Z] GC before operation: completed in 270.512 ms, heap usage 279.797 MB -> 67.550 MB. [2025-11-27T00:56:48.350Z] ====== als (apache-spark) [default], iteration 12 completed (15687.402 ms) ====== [2025-11-27T00:56:48.350Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-27T00:56:48.350Z] GC before operation: completed in 211.135 ms, heap usage 362.494 MB -> 67.891 MB. [2025-11-27T00:57:04.204Z] ====== als (apache-spark) [default], iteration 13 completed (15703.628 ms) ====== [2025-11-27T00:57:04.204Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-27T00:57:04.204Z] GC before operation: completed in 221.286 ms, heap usage 371.952 MB -> 68.142 MB. [2025-11-27T00:57:26.705Z] ====== als (apache-spark) [default], iteration 14 completed (19285.387 ms) ====== [2025-11-27T00:57:26.705Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-27T00:57:26.705Z] GC before operation: completed in 213.478 ms, heap usage 368.796 MB -> 68.458 MB. [2025-11-27T00:57:42.462Z] ====== als (apache-spark) [default], iteration 15 completed (16032.427 ms) ====== [2025-11-27T00:57:42.462Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-27T00:57:42.462Z] GC before operation: completed in 224.797 ms, heap usage 161.452 MB -> 68.521 MB. [2025-11-27T00:57:55.811Z] ====== als (apache-spark) [default], iteration 16 completed (14379.143 ms) ====== [2025-11-27T00:57:55.811Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-27T00:57:55.811Z] GC before operation: completed in 198.255 ms, heap usage 170.679 MB -> 68.738 MB. [2025-11-27T00:58:12.323Z] ====== als (apache-spark) [default], iteration 17 completed (14442.976 ms) ====== [2025-11-27T00:58:12.323Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-27T00:58:12.323Z] GC before operation: completed in 269.840 ms, heap usage 510.624 MB -> 69.561 MB. [2025-11-27T00:58:28.036Z] ====== als (apache-spark) [default], iteration 18 completed (15404.858 ms) ====== [2025-11-27T00:58:28.036Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-27T00:58:28.036Z] GC before operation: completed in 260.831 ms, heap usage 204.865 MB -> 69.313 MB. [2025-11-27T00:58:43.697Z] ====== als (apache-spark) [default], iteration 19 completed (16494.288 ms) ====== [2025-11-27T00:58:43.697Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-27T00:58:44.438Z] GC before operation: completed in 296.341 ms, heap usage 123.195 MB -> 69.612 MB. [2025-11-27T00:59:00.197Z] ====== als (apache-spark) [default], iteration 20 completed (15005.668 ms) ====== [2025-11-27T00:59:00.197Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-27T00:59:00.197Z] GC before operation: completed in 254.386 ms, heap usage 350.872 MB -> 70.019 MB. [2025-11-27T00:59:15.873Z] ====== als (apache-spark) [default], iteration 21 completed (15860.646 ms) ====== [2025-11-27T00:59:15.873Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-27T00:59:16.615Z] GC before operation: completed in 227.800 ms, heap usage 200.799 MB -> 69.995 MB. [2025-11-27T00:59:32.253Z] ====== als (apache-spark) [default], iteration 22 completed (14173.872 ms) ====== [2025-11-27T00:59:32.253Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-27T00:59:32.253Z] GC before operation: completed in 202.076 ms, heap usage 286.217 MB -> 70.432 MB. [2025-11-27T00:59:45.968Z] ====== als (apache-spark) [default], iteration 23 completed (14724.660 ms) ====== [2025-11-27T00:59:45.968Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-27T00:59:45.968Z] GC before operation: completed in 225.308 ms, heap usage 448.650 MB -> 71.008 MB. [2025-11-27T01:00:01.522Z] ====== als (apache-spark) [default], iteration 24 completed (14816.360 ms) ====== [2025-11-27T01:00:01.522Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-27T01:00:01.522Z] GC before operation: completed in 209.907 ms, heap usage 195.817 MB -> 70.807 MB. [2025-11-27T01:00:20.002Z] ====== als (apache-spark) [default], iteration 25 completed (16343.112 ms) ====== [2025-11-27T01:00:20.002Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-27T01:00:20.002Z] GC before operation: completed in 239.897 ms, heap usage 110.506 MB -> 71.085 MB. [2025-11-27T01:00:35.744Z] ====== als (apache-spark) [default], iteration 26 completed (15725.282 ms) ====== [2025-11-27T01:00:35.744Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-27T01:00:35.744Z] GC before operation: completed in 325.324 ms, heap usage 557.743 MB -> 71.863 MB. [2025-11-27T01:00:51.304Z] ====== als (apache-spark) [default], iteration 27 completed (15780.450 ms) ====== [2025-11-27T01:00:51.304Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-27T01:00:51.304Z] GC before operation: completed in 245.509 ms, heap usage 199.880 MB -> 71.746 MB. [2025-11-27T01:01:06.820Z] ====== als (apache-spark) [default], iteration 28 completed (14488.776 ms) ====== [2025-11-27T01:01:06.820Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-27T01:01:06.820Z] GC before operation: completed in 210.250 ms, heap usage 233.169 MB -> 71.971 MB. [2025-11-27T01:01:22.411Z] ====== als (apache-spark) [default], iteration 29 completed (13585.627 ms) ====== [2025-11-27T01:01:22.412Z] ----------------------------------- [2025-11-27T01:01:22.412Z] renaissance-als_0_PASSED [2025-11-27T01:01:22.412Z] ----------------------------------- [2025-11-27T01:01:22.412Z] [2025-11-27T01:01:22.412Z] TEST TEARDOWN: [2025-11-27T01:01:22.412Z] Nothing to be done for teardown. [2025-11-27T01:01:22.412Z] renaissance-als_0 Finish Time: Thu Nov 27 01:01:20 2025 Epoch Time (ms): 1764205280803