[2025-07-30T20:56:18.746Z] Running test renaissance-als_0 ... [2025-07-30T20:56:18.746Z] =============================================== [2025-07-30T20:56:18.746Z] renaissance-als_0 Start Time: Wed Jul 30 20:56:18 2025 Epoch Time (ms): 1753908978218 [2025-07-30T20:56:18.746Z] variation: NoOptions [2025-07-30T20:56:18.746Z] JVM_OPTIONS: [2025-07-30T20:56:18.746Z] { \ [2025-07-30T20:56:18.746Z] echo ""; echo "TEST SETUP:"; \ [2025-07-30T20:56:18.746Z] echo "Nothing to be done for setup."; \ [2025-07-30T20:56:18.746Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17539088974113/renaissance-als_0"; \ [2025-07-30T20:56:18.746Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17539088974113/renaissance-als_0"; \ [2025-07-30T20:56:18.746Z] echo ""; echo "TESTING:"; \ [2025-07-30T20:56:18.746Z] "/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_17539088974113/renaissance-als_0"/als.json" als; \ [2025-07-30T20:56:18.746Z] 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_17539088974113/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-30T20:56:18.746Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-30T20:56:18.746Z] echo "Nothing to be done for teardown."; \ [2025-07-30T20:56:18.746Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17539088974113/TestTargetResult"; [2025-07-30T20:56:18.746Z] [2025-07-30T20:56:18.746Z] TEST SETUP: [2025-07-30T20:56:18.746Z] Nothing to be done for setup. [2025-07-30T20:56:18.746Z] [2025-07-30T20:56:18.746Z] TESTING: [2025-07-30T20:56:25.815Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-07-30T20:56:31.513Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-30T20:56:31.513Z] GC before operation: completed in 58.746 ms, heap usage 255.752 MB -> 34.904 MB. [2025-07-30T20:56:45.447Z] ====== als (apache-spark) [default], iteration 0 completed (13045.124 ms) ====== [2025-07-30T20:56:45.447Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-30T20:56:45.447Z] GC before operation: completed in 119.473 ms, heap usage 440.347 MB -> 61.958 MB. [2025-07-30T20:56:50.106Z] ====== als (apache-spark) [default], iteration 1 completed (4892.960 ms) ====== [2025-07-30T20:56:50.106Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-30T20:56:50.106Z] GC before operation: completed in 115.567 ms, heap usage 3.105 GB -> 64.886 MB. [2025-07-30T20:56:54.753Z] ====== als (apache-spark) [default], iteration 2 completed (4662.897 ms) ====== [2025-07-30T20:56:54.753Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-30T20:56:54.753Z] GC before operation: completed in 96.176 ms, heap usage 472.620 MB -> 63.709 MB. [2025-07-30T20:57:00.383Z] ====== als (apache-spark) [default], iteration 3 completed (4565.261 ms) ====== [2025-07-30T20:57:00.384Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-30T20:57:00.384Z] GC before operation: completed in 109.982 ms, heap usage 291.332 MB -> 63.873 MB. [2025-07-30T20:57:04.873Z] ====== als (apache-spark) [default], iteration 4 completed (4421.762 ms) ====== [2025-07-30T20:57:04.873Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-30T20:57:04.873Z] GC before operation: completed in 94.988 ms, heap usage 498.385 MB -> 64.850 MB. [2025-07-30T20:57:09.349Z] ====== als (apache-spark) [default], iteration 5 completed (4481.886 ms) ====== [2025-07-30T20:57:09.349Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-30T20:57:09.349Z] GC before operation: completed in 108.088 ms, heap usage 426.811 MB -> 65.345 MB. [2025-07-30T20:57:13.829Z] ====== als (apache-spark) [default], iteration 6 completed (4437.141 ms) ====== [2025-07-30T20:57:13.829Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-30T20:57:14.605Z] GC before operation: completed in 112.535 ms, heap usage 262.021 MB -> 65.547 MB. [2025-07-30T20:57:19.311Z] ====== als (apache-spark) [default], iteration 7 completed (4481.603 ms) ====== [2025-07-30T20:57:19.311Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-30T20:57:19.311Z] GC before operation: completed in 120.558 ms, heap usage 194.456 MB -> 65.979 MB. [2025-07-30T20:57:23.802Z] ====== als (apache-spark) [default], iteration 8 completed (4449.368 ms) ====== [2025-07-30T20:57:23.802Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-30T20:57:23.802Z] GC before operation: completed in 121.159 ms, heap usage 272.181 MB -> 66.617 MB. [2025-07-30T20:57:28.287Z] ====== als (apache-spark) [default], iteration 9 completed (4479.969 ms) ====== [2025-07-30T20:57:28.287Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-30T20:57:28.287Z] GC before operation: completed in 117.255 ms, heap usage 216.237 MB -> 66.938 MB. [2025-07-30T20:57:33.919Z] ====== als (apache-spark) [default], iteration 10 completed (4386.202 ms) ====== [2025-07-30T20:57:33.919Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-30T20:57:33.919Z] GC before operation: completed in 126.729 ms, heap usage 145.929 MB -> 67.203 MB. [2025-07-30T20:57:38.401Z] ====== als (apache-spark) [default], iteration 11 completed (4428.427 ms) ====== [2025-07-30T20:57:38.401Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-30T20:57:38.401Z] GC before operation: completed in 112.620 ms, heap usage 473.682 MB -> 68.009 MB. [2025-07-30T20:57:42.924Z] ====== als (apache-spark) [default], iteration 12 completed (4355.173 ms) ====== [2025-07-30T20:57:42.924Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-30T20:57:42.924Z] GC before operation: completed in 117.599 ms, heap usage 478.186 MB -> 68.302 MB. [2025-07-30T20:57:47.415Z] ====== als (apache-spark) [default], iteration 13 completed (4357.307 ms) ====== [2025-07-30T20:57:47.415Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-30T20:57:47.415Z] GC before operation: completed in 119.167 ms, heap usage 369.816 MB -> 68.357 MB. [2025-07-30T20:57:51.912Z] ====== als (apache-spark) [default], iteration 14 completed (4443.342 ms) ====== [2025-07-30T20:57:51.912Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-30T20:57:51.912Z] GC before operation: completed in 116.347 ms, heap usage 132.948 MB -> 68.847 MB. [2025-07-30T20:57:56.414Z] ====== als (apache-spark) [default], iteration 15 completed (4361.061 ms) ====== [2025-07-30T20:57:56.414Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-30T20:57:56.414Z] GC before operation: completed in 94.951 ms, heap usage 726.975 MB -> 69.623 MB. [2025-07-30T20:58:02.035Z] ====== als (apache-spark) [default], iteration 16 completed (4430.061 ms) ====== [2025-07-30T20:58:02.035Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-30T20:58:02.035Z] GC before operation: completed in 105.530 ms, heap usage 160.538 MB -> 69.001 MB. [2025-07-30T20:58:06.713Z] ====== als (apache-spark) [default], iteration 17 completed (4343.932 ms) ====== [2025-07-30T20:58:06.713Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-30T20:58:06.713Z] GC before operation: completed in 102.572 ms, heap usage 134.587 MB -> 68.994 MB. [2025-07-30T20:58:11.192Z] ====== als (apache-spark) [default], iteration 18 completed (4452.696 ms) ====== [2025-07-30T20:58:11.192Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-30T20:58:11.192Z] GC before operation: completed in 91.233 ms, heap usage 358.983 MB -> 69.487 MB. [2025-07-30T20:58:15.675Z] ====== als (apache-spark) [default], iteration 19 completed (4417.661 ms) ====== [2025-07-30T20:58:15.676Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-30T20:58:15.676Z] GC before operation: completed in 100.315 ms, heap usage 133.287 MB -> 69.068 MB. [2025-07-30T20:58:20.161Z] ====== als (apache-spark) [default], iteration 20 completed (4440.317 ms) ====== [2025-07-30T20:58:20.161Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-30T20:58:20.161Z] GC before operation: completed in 101.484 ms, heap usage 279.692 MB -> 70.037 MB. [2025-07-30T20:58:24.638Z] ====== als (apache-spark) [default], iteration 21 completed (4398.379 ms) ====== [2025-07-30T20:58:24.638Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-30T20:58:24.638Z] GC before operation: completed in 103.405 ms, heap usage 434.462 MB -> 70.534 MB. [2025-07-30T20:58:29.127Z] ====== als (apache-spark) [default], iteration 22 completed (4265.555 ms) ====== [2025-07-30T20:58:29.127Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-30T20:58:29.127Z] GC before operation: completed in 110.901 ms, heap usage 441.767 MB -> 70.795 MB. [2025-07-30T20:58:33.607Z] ====== als (apache-spark) [default], iteration 23 completed (4383.133 ms) ====== [2025-07-30T20:58:33.607Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-30T20:58:33.607Z] GC before operation: completed in 117.736 ms, heap usage 704.810 MB -> 71.475 MB. [2025-07-30T20:58:38.096Z] ====== als (apache-spark) [default], iteration 24 completed (4230.915 ms) ====== [2025-07-30T20:58:38.096Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-30T20:58:38.096Z] GC before operation: completed in 102.667 ms, heap usage 1.137 GB -> 72.132 MB. [2025-07-30T20:58:42.580Z] ====== als (apache-spark) [default], iteration 25 completed (4263.351 ms) ====== [2025-07-30T20:58:42.580Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-30T20:58:42.580Z] GC before operation: completed in 95.852 ms, heap usage 264.008 MB -> 71.235 MB. [2025-07-30T20:58:48.225Z] ====== als (apache-spark) [default], iteration 26 completed (4438.481 ms) ====== [2025-07-30T20:58:48.225Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-30T20:58:48.225Z] GC before operation: completed in 96.387 ms, heap usage 186.135 MB -> 71.359 MB. [2025-07-30T20:58:51.828Z] ====== als (apache-spark) [default], iteration 27 completed (4376.667 ms) ====== [2025-07-30T20:58:51.828Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-30T20:58:52.604Z] GC before operation: completed in 91.549 ms, heap usage 207.641 MB -> 71.217 MB. [2025-07-30T20:58:57.275Z] ====== als (apache-spark) [default], iteration 28 completed (4337.696 ms) ====== [2025-07-30T20:58:57.275Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-30T20:58:57.275Z] GC before operation: completed in 95.903 ms, heap usage 256.640 MB -> 71.580 MB. [2025-07-30T20:59:01.749Z] ====== als (apache-spark) [default], iteration 29 completed (4262.792 ms) ====== [2025-07-30T20:59:01.749Z] ----------------------------------- [2025-07-30T20:59:01.749Z] renaissance-als_0_PASSED [2025-07-30T20:59:01.749Z] ----------------------------------- [2025-07-30T20:59:01.749Z] [2025-07-30T20:59:01.749Z] TEST TEARDOWN: [2025-07-30T20:59:01.749Z] Nothing to be done for teardown. [2025-07-30T20:59:01.749Z] renaissance-als_0 Finish Time: Wed Jul 30 20:59:01 2025 Epoch Time (ms): 1753909141424