[2025-06-30T19:46:29.657Z] Running test renaissance-als_0 ... [2025-06-30T19:46:29.657Z] =============================================== [2025-06-30T19:46:29.657Z] renaissance-als_0 Start Time: Mon Jun 30 19:46:29 2025 Epoch Time (ms): 1751312789291 [2025-06-30T19:46:29.657Z] variation: NoOptions [2025-06-30T19:46:29.657Z] JVM_OPTIONS: [2025-06-30T19:46:29.657Z] { \ [2025-06-30T19:46:29.657Z] echo ""; echo "TEST SETUP:"; \ [2025-06-30T19:46:29.657Z] echo "Nothing to be done for setup."; \ [2025-06-30T19:46:29.657Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17513127088099/renaissance-als_0"; \ [2025-06-30T19:46:29.657Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17513127088099/renaissance-als_0"; \ [2025-06-30T19:46:29.657Z] echo ""; echo "TESTING:"; \ [2025-06-30T19:46:29.657Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17513127088099/renaissance-als_0"/als.json" als; \ [2025-06-30T19:46:29.657Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17513127088099/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-30T19:46:29.657Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-30T19:46:29.657Z] echo "Nothing to be done for teardown."; \ [2025-06-30T19:46:29.657Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17513127088099/TestTargetResult"; [2025-06-30T19:46:29.657Z] [2025-06-30T19:46:29.657Z] TEST SETUP: [2025-06-30T19:46:29.657Z] Nothing to be done for setup. [2025-06-30T19:46:29.657Z] [2025-06-30T19:46:29.657Z] TESTING: [2025-06-30T19:46:38.056Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-06-30T19:46:42.645Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-30T19:46:42.645Z] GC before operation: completed in 48.161 ms, heap usage 146.219 MB -> 35.058 MB. [2025-06-30T19:46:56.484Z] ====== als (apache-spark) [default], iteration 0 completed (12384.164 ms) ====== [2025-06-30T19:46:56.484Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-30T19:46:56.484Z] GC before operation: completed in 129.045 ms, heap usage 297.060 MB -> 62.058 MB. [2025-06-30T19:47:01.002Z] ====== als (apache-spark) [default], iteration 1 completed (4825.630 ms) ====== [2025-06-30T19:47:01.002Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-30T19:47:01.002Z] GC before operation: completed in 122.794 ms, heap usage 1.254 GB -> 64.182 MB. [2025-06-30T19:47:05.519Z] ====== als (apache-spark) [default], iteration 2 completed (4700.126 ms) ====== [2025-06-30T19:47:05.520Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-30T19:47:05.520Z] GC before operation: completed in 116.044 ms, heap usage 523.998 MB -> 63.884 MB. [2025-06-30T19:47:11.160Z] ====== als (apache-spark) [default], iteration 3 completed (4596.360 ms) ====== [2025-06-30T19:47:11.160Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-30T19:47:11.160Z] GC before operation: completed in 118.642 ms, heap usage 1.055 GB -> 65.085 MB. [2025-06-30T19:47:15.652Z] ====== als (apache-spark) [default], iteration 4 completed (4446.047 ms) ====== [2025-06-30T19:47:15.652Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-30T19:47:15.652Z] GC before operation: completed in 114.277 ms, heap usage 1.107 GB -> 65.644 MB. [2025-06-30T19:47:20.146Z] ====== als (apache-spark) [default], iteration 5 completed (4370.674 ms) ====== [2025-06-30T19:47:20.146Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-30T19:47:20.146Z] GC before operation: completed in 112.145 ms, heap usage 1.278 GB -> 66.307 MB. [2025-06-30T19:47:24.652Z] ====== als (apache-spark) [default], iteration 6 completed (4415.038 ms) ====== [2025-06-30T19:47:24.652Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-30T19:47:24.652Z] GC before operation: completed in 93.552 ms, heap usage 474.441 MB -> 65.953 MB. [2025-06-30T19:47:30.304Z] ====== als (apache-spark) [default], iteration 7 completed (4478.275 ms) ====== [2025-06-30T19:47:30.304Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-30T19:47:30.304Z] GC before operation: completed in 95.452 ms, heap usage 309.420 MB -> 66.109 MB. [2025-06-30T19:47:34.815Z] ====== als (apache-spark) [default], iteration 8 completed (4458.995 ms) ====== [2025-06-30T19:47:34.815Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-30T19:47:34.815Z] GC before operation: completed in 85.987 ms, heap usage 263.888 MB -> 66.805 MB. [2025-06-30T19:47:39.332Z] ====== als (apache-spark) [default], iteration 9 completed (4427.228 ms) ====== [2025-06-30T19:47:39.332Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-30T19:47:39.332Z] GC before operation: completed in 100.050 ms, heap usage 281.270 MB -> 67.390 MB. [2025-06-30T19:47:43.846Z] ====== als (apache-spark) [default], iteration 10 completed (4527.563 ms) ====== [2025-06-30T19:47:43.846Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-30T19:47:43.846Z] GC before operation: completed in 106.391 ms, heap usage 251.922 MB -> 67.590 MB. [2025-06-30T19:47:48.349Z] ====== als (apache-spark) [default], iteration 11 completed (4390.995 ms) ====== [2025-06-30T19:47:48.349Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-30T19:47:48.349Z] GC before operation: completed in 106.603 ms, heap usage 175.542 MB -> 67.821 MB. [2025-06-30T19:47:52.869Z] ====== als (apache-spark) [default], iteration 12 completed (4334.657 ms) ====== [2025-06-30T19:47:52.869Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-30T19:47:52.869Z] GC before operation: completed in 91.877 ms, heap usage 179.571 MB -> 68.134 MB. [2025-06-30T19:47:57.417Z] ====== als (apache-spark) [default], iteration 13 completed (4365.890 ms) ====== [2025-06-30T19:47:57.417Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-30T19:47:57.417Z] GC before operation: completed in 93.114 ms, heap usage 141.984 MB -> 68.351 MB. [2025-06-30T19:48:03.084Z] ====== als (apache-spark) [default], iteration 14 completed (4439.611 ms) ====== [2025-06-30T19:48:03.084Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-30T19:48:03.084Z] GC before operation: completed in 89.080 ms, heap usage 427.842 MB -> 68.881 MB. [2025-06-30T19:48:06.558Z] ====== als (apache-spark) [default], iteration 15 completed (4290.444 ms) ====== [2025-06-30T19:48:06.558Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-30T19:48:07.341Z] GC before operation: completed in 83.931 ms, heap usage 142.783 MB -> 68.969 MB. [2025-06-30T19:48:11.827Z] ====== als (apache-spark) [default], iteration 16 completed (4334.612 ms) ====== [2025-06-30T19:48:11.827Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-30T19:48:11.827Z] GC before operation: completed in 96.369 ms, heap usage 133.908 MB -> 69.149 MB. [2025-06-30T19:48:16.336Z] ====== als (apache-spark) [default], iteration 17 completed (4462.926 ms) ====== [2025-06-30T19:48:16.336Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-30T19:48:16.336Z] GC before operation: completed in 91.766 ms, heap usage 472.828 MB -> 69.908 MB. [2025-06-30T19:48:20.826Z] ====== als (apache-spark) [default], iteration 18 completed (4428.409 ms) ====== [2025-06-30T19:48:20.826Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-30T19:48:20.826Z] GC before operation: completed in 101.678 ms, heap usage 279.795 MB -> 69.529 MB. [2025-06-30T19:48:25.324Z] ====== als (apache-spark) [default], iteration 19 completed (4418.041 ms) ====== [2025-06-30T19:48:25.324Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-30T19:48:26.104Z] GC before operation: completed in 91.346 ms, heap usage 150.994 MB -> 69.606 MB. [2025-06-30T19:48:30.631Z] ====== als (apache-spark) [default], iteration 20 completed (4434.690 ms) ====== [2025-06-30T19:48:30.631Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-30T19:48:30.631Z] GC before operation: completed in 101.315 ms, heap usage 177.101 MB -> 69.737 MB. [2025-06-30T19:48:35.147Z] ====== als (apache-spark) [default], iteration 21 completed (4408.773 ms) ====== [2025-06-30T19:48:35.147Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-30T19:48:35.147Z] GC before operation: completed in 89.283 ms, heap usage 425.891 MB -> 70.478 MB. [2025-06-30T19:48:39.750Z] ====== als (apache-spark) [default], iteration 22 completed (4375.578 ms) ====== [2025-06-30T19:48:39.750Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-30T19:48:39.750Z] GC before operation: completed in 101.546 ms, heap usage 178.280 MB -> 70.410 MB. [2025-06-30T19:48:44.287Z] ====== als (apache-spark) [default], iteration 23 completed (4439.640 ms) ====== [2025-06-30T19:48:44.287Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-30T19:48:44.287Z] GC before operation: completed in 96.490 ms, heap usage 227.510 MB -> 70.863 MB. [2025-06-30T19:48:48.795Z] ====== als (apache-spark) [default], iteration 24 completed (4357.119 ms) ====== [2025-06-30T19:48:48.795Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-30T19:48:48.795Z] GC before operation: completed in 93.467 ms, heap usage 256.424 MB -> 71.112 MB. [2025-06-30T19:48:53.373Z] ====== als (apache-spark) [default], iteration 25 completed (4487.953 ms) ====== [2025-06-30T19:48:53.373Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-30T19:48:54.155Z] GC before operation: completed in 89.592 ms, heap usage 374.850 MB -> 71.608 MB. [2025-06-30T19:48:58.654Z] ====== als (apache-spark) [default], iteration 26 completed (4338.140 ms) ====== [2025-06-30T19:48:58.654Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-30T19:48:58.654Z] GC before operation: completed in 103.360 ms, heap usage 156.053 MB -> 71.813 MB. [2025-06-30T19:49:03.153Z] ====== als (apache-spark) [default], iteration 27 completed (4386.986 ms) ====== [2025-06-30T19:49:03.153Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-30T19:49:03.153Z] GC before operation: completed in 82.161 ms, heap usage 475.909 MB -> 71.998 MB. [2025-06-30T19:49:07.654Z] ====== als (apache-spark) [default], iteration 28 completed (4376.056 ms) ====== [2025-06-30T19:49:07.654Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-30T19:49:07.654Z] GC before operation: completed in 106.986 ms, heap usage 152.262 MB -> 71.702 MB. [2025-06-30T19:49:12.157Z] ====== als (apache-spark) [default], iteration 29 completed (4388.047 ms) ====== [2025-06-30T19:49:12.935Z] ----------------------------------- [2025-06-30T19:49:12.935Z] renaissance-als_0_PASSED [2025-06-30T19:49:12.935Z] ----------------------------------- [2025-06-30T19:49:12.935Z] [2025-06-30T19:49:12.935Z] TEST TEARDOWN: [2025-06-30T19:49:12.935Z] Nothing to be done for teardown. [2025-06-30T19:49:12.935Z] renaissance-als_0 Finish Time: Mon Jun 30 19:49:12 2025 Epoch Time (ms): 1751312952563