[2025-11-12T23:26:22.432Z] Running test renaissance-als_0 ... [2025-11-12T23:26:22.432Z] =============================================== [2025-11-12T23:26:23.397Z] renaissance-als_0 Start Time: Wed Nov 12 23:26:22 2025 Epoch Time (ms): 1762989982348 [2025-11-12T23:26:23.397Z] variation: NoOptions [2025-11-12T23:26:23.397Z] JVM_OPTIONS: [2025-11-12T23:26:23.397Z] { \ [2025-11-12T23:26:23.397Z] echo ""; echo "TEST SETUP:"; \ [2025-11-12T23:26:23.397Z] echo "Nothing to be done for setup."; \ [2025-11-12T23:26:23.397Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629898258623/renaissance-als_0"; \ [2025-11-12T23:26:23.397Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629898258623/renaissance-als_0"; \ [2025-11-12T23:26:23.397Z] echo ""; echo "TESTING:"; \ [2025-11-12T23:26:23.397Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629898258623/renaissance-als_0"/als.json" als; \ [2025-11-12T23:26:23.397Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629898258623/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-12T23:26:23.397Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-12T23:26:23.397Z] echo "Nothing to be done for teardown."; \ [2025-11-12T23:26:23.397Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629898258623/TestTargetResult"; [2025-11-12T23:26:23.397Z] [2025-11-12T23:26:23.397Z] TEST SETUP: [2025-11-12T23:26:23.397Z] Nothing to be done for setup. [2025-11-12T23:26:23.397Z] [2025-11-12T23:26:23.397Z] TESTING: [2025-11-12T23:26:27.598Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-12T23:26:31.793Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-12T23:26:31.793Z] GC before operation: completed in 40.391 ms, heap usage 157.950 MB -> 35.100 MB. [2025-11-12T23:26:50.388Z] ====== als (apache-spark) [default], iteration 0 completed (16765.616 ms) ====== [2025-11-12T23:26:50.388Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-12T23:26:50.388Z] GC before operation: completed in 91.180 ms, heap usage 280.429 MB -> 62.064 MB. [2025-11-12T23:26:57.147Z] ====== als (apache-spark) [default], iteration 1 completed (7583.318 ms) ====== [2025-11-12T23:26:57.147Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-12T23:26:58.108Z] GC before operation: completed in 89.750 ms, heap usage 509.474 MB -> 63.225 MB. [2025-11-12T23:27:05.338Z] ====== als (apache-spark) [default], iteration 2 completed (7058.658 ms) ====== [2025-11-12T23:27:05.338Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-12T23:27:05.338Z] GC before operation: completed in 94.416 ms, heap usage 287.636 MB -> 63.494 MB. [2025-11-12T23:27:12.100Z] ====== als (apache-spark) [default], iteration 3 completed (7029.643 ms) ====== [2025-11-12T23:27:12.100Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-12T23:27:12.100Z] GC before operation: completed in 96.190 ms, heap usage 241.324 MB -> 64.087 MB. [2025-11-12T23:27:20.320Z] ====== als (apache-spark) [default], iteration 4 completed (6760.370 ms) ====== [2025-11-12T23:27:20.320Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-12T23:27:20.320Z] GC before operation: completed in 99.257 ms, heap usage 465.548 MB -> 64.996 MB. [2025-11-12T23:27:26.068Z] ====== als (apache-spark) [default], iteration 5 completed (6665.090 ms) ====== [2025-11-12T23:27:26.068Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-12T23:27:27.029Z] GC before operation: completed in 87.762 ms, heap usage 258.749 MB -> 65.328 MB. [2025-11-12T23:27:35.227Z] ====== als (apache-spark) [default], iteration 6 completed (6648.832 ms) ====== [2025-11-12T23:27:35.227Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-12T23:27:35.227Z] GC before operation: completed in 99.369 ms, heap usage 587.395 MB -> 66.225 MB. [2025-11-12T23:27:40.648Z] ====== als (apache-spark) [default], iteration 7 completed (6607.076 ms) ====== [2025-11-12T23:27:40.648Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-12T23:27:40.648Z] GC before operation: completed in 105.914 ms, heap usage 345.287 MB -> 66.466 MB. [2025-11-12T23:27:47.728Z] ====== als (apache-spark) [default], iteration 8 completed (6586.775 ms) ====== [2025-11-12T23:27:47.728Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-12T23:27:47.728Z] GC before operation: completed in 98.774 ms, heap usage 590.098 MB -> 67.310 MB. [2025-11-12T23:27:54.559Z] ====== als (apache-spark) [default], iteration 9 completed (6662.587 ms) ====== [2025-11-12T23:27:54.559Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-12T23:27:54.559Z] GC before operation: completed in 96.873 ms, heap usage 488.412 MB -> 67.953 MB. [2025-11-12T23:28:01.501Z] ====== als (apache-spark) [default], iteration 10 completed (6566.677 ms) ====== [2025-11-12T23:28:01.501Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-12T23:28:01.501Z] GC before operation: completed in 92.478 ms, heap usage 320.858 MB -> 67.468 MB. [2025-11-12T23:28:08.246Z] ====== als (apache-spark) [default], iteration 11 completed (6597.884 ms) ====== [2025-11-12T23:28:08.246Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-12T23:28:08.246Z] GC before operation: completed in 84.083 ms, heap usage 385.148 MB -> 67.848 MB. [2025-11-12T23:28:15.016Z] ====== als (apache-spark) [default], iteration 12 completed (6588.451 ms) ====== [2025-11-12T23:28:15.016Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-12T23:28:15.017Z] GC before operation: completed in 96.162 ms, heap usage 95.251 MB -> 68.327 MB. [2025-11-12T23:28:21.781Z] ====== als (apache-spark) [default], iteration 13 completed (6472.859 ms) ====== [2025-11-12T23:28:21.781Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-12T23:28:21.781Z] GC before operation: completed in 96.353 ms, heap usage 166.199 MB -> 68.042 MB. [2025-11-12T23:28:28.683Z] ====== als (apache-spark) [default], iteration 14 completed (6689.176 ms) ====== [2025-11-12T23:28:28.683Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-12T23:28:28.683Z] GC before operation: completed in 96.902 ms, heap usage 486.634 MB -> 68.770 MB. [2025-11-12T23:28:35.457Z] ====== als (apache-spark) [default], iteration 15 completed (6436.347 ms) ====== [2025-11-12T23:28:35.457Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-12T23:28:35.457Z] GC before operation: completed in 100.974 ms, heap usage 538.796 MB -> 69.085 MB. [2025-11-12T23:28:42.217Z] ====== als (apache-spark) [default], iteration 16 completed (6464.602 ms) ====== [2025-11-12T23:28:42.217Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-12T23:28:42.217Z] GC before operation: completed in 91.576 ms, heap usage 206.022 MB -> 68.874 MB. [2025-11-12T23:28:49.208Z] ====== als (apache-spark) [default], iteration 17 completed (6501.950 ms) ====== [2025-11-12T23:28:49.208Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-12T23:28:49.208Z] GC before operation: completed in 101.697 ms, heap usage 432.279 MB -> 69.515 MB. [2025-11-12T23:28:55.970Z] ====== als (apache-spark) [default], iteration 18 completed (6486.000 ms) ====== [2025-11-12T23:28:55.970Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-12T23:28:55.970Z] GC before operation: completed in 97.496 ms, heap usage 490.240 MB -> 69.803 MB. [2025-11-12T23:29:02.648Z] ====== als (apache-spark) [default], iteration 19 completed (6421.555 ms) ====== [2025-11-12T23:29:02.648Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-12T23:29:02.648Z] GC before operation: completed in 99.657 ms, heap usage 281.873 MB -> 69.786 MB. [2025-11-12T23:29:09.397Z] ====== als (apache-spark) [default], iteration 20 completed (6570.596 ms) ====== [2025-11-12T23:29:09.397Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-12T23:29:09.397Z] GC before operation: completed in 87.893 ms, heap usage 436.864 MB -> 70.347 MB. [2025-11-12T23:29:16.149Z] ====== als (apache-spark) [default], iteration 21 completed (6485.929 ms) ====== [2025-11-12T23:29:16.150Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-12T23:29:16.150Z] GC before operation: completed in 81.355 ms, heap usage 364.184 MB -> 70.438 MB. [2025-11-12T23:29:22.898Z] ====== als (apache-spark) [default], iteration 22 completed (6257.111 ms) ====== [2025-11-12T23:29:22.898Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-12T23:29:22.898Z] GC before operation: completed in 84.601 ms, heap usage 482.703 MB -> 70.914 MB. [2025-11-12T23:29:29.828Z] ====== als (apache-spark) [default], iteration 23 completed (6364.592 ms) ====== [2025-11-12T23:29:29.828Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-12T23:29:29.828Z] GC before operation: completed in 99.862 ms, heap usage 285.669 MB -> 70.840 MB. [2025-11-12T23:29:35.246Z] ====== als (apache-spark) [default], iteration 24 completed (6301.383 ms) ====== [2025-11-12T23:29:35.246Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-12T23:29:36.206Z] GC before operation: completed in 88.231 ms, heap usage 559.570 MB -> 71.537 MB. [2025-11-12T23:29:42.953Z] ====== als (apache-spark) [default], iteration 25 completed (6356.551 ms) ====== [2025-11-12T23:29:42.953Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-12T23:29:42.953Z] GC before operation: completed in 90.298 ms, heap usage 162.143 MB -> 71.247 MB. [2025-11-12T23:29:48.433Z] ====== als (apache-spark) [default], iteration 26 completed (6238.235 ms) ====== [2025-11-12T23:29:48.433Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-12T23:29:49.394Z] GC before operation: completed in 88.599 ms, heap usage 484.012 MB -> 71.935 MB. [2025-11-12T23:29:56.192Z] ====== als (apache-spark) [default], iteration 27 completed (6383.427 ms) ====== [2025-11-12T23:29:56.192Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-12T23:29:56.192Z] GC before operation: completed in 88.440 ms, heap usage 541.013 MB -> 72.299 MB. [2025-11-12T23:30:02.950Z] ====== als (apache-spark) [default], iteration 28 completed (6426.490 ms) ====== [2025-11-12T23:30:02.950Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-12T23:30:02.950Z] GC before operation: completed in 92.970 ms, heap usage 499.852 MB -> 72.404 MB. [2025-11-12T23:30:08.420Z] ====== als (apache-spark) [default], iteration 29 completed (6279.668 ms) ====== [2025-11-12T23:30:09.383Z] ----------------------------------- [2025-11-12T23:30:09.383Z] renaissance-als_0_PASSED [2025-11-12T23:30:09.383Z] ----------------------------------- [2025-11-12T23:30:09.383Z] [2025-11-12T23:30:09.383Z] TEST TEARDOWN: [2025-11-12T23:30:09.383Z] Nothing to be done for teardown. [2025-11-12T23:30:09.383Z] renaissance-als_0 Finish Time: Wed Nov 12 23:30:08 2025 Epoch Time (ms): 1762990208543