[2025-06-27T20:41:59.251Z] Running test renaissance-als_0 ... [2025-06-27T20:41:59.251Z] =============================================== [2025-06-27T20:41:59.251Z] renaissance-als_0 Start Time: Fri Jun 27 20:41:58 2025 Epoch Time (ms): 1751056918504 [2025-06-27T20:41:59.251Z] variation: NoOptions [2025-06-27T20:41:59.251Z] JVM_OPTIONS: [2025-06-27T20:41:59.251Z] { \ [2025-06-27T20:41:59.251Z] echo ""; echo "TEST SETUP:"; \ [2025-06-27T20:41:59.251Z] echo "Nothing to be done for setup."; \ [2025-06-27T20:41:59.251Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17510568032680/renaissance-als_0"; \ [2025-06-27T20:41:59.251Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17510568032680/renaissance-als_0"; \ [2025-06-27T20:41:59.251Z] echo ""; echo "TESTING:"; \ [2025-06-27T20:41:59.251Z] "/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_17510568032680/renaissance-als_0"/als.json" als; \ [2025-06-27T20:41:59.251Z] 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_17510568032680/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-27T20:41:59.251Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-27T20:41:59.251Z] echo "Nothing to be done for teardown."; \ [2025-06-27T20:41:59.251Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17510568032680/TestTargetResult"; [2025-06-27T20:41:59.251Z] [2025-06-27T20:41:59.251Z] TEST SETUP: [2025-06-27T20:41:59.251Z] Nothing to be done for setup. [2025-06-27T20:41:59.251Z] [2025-06-27T20:41:59.251Z] TESTING: [2025-06-27T20:42:04.536Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-27T20:42:08.637Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-27T20:42:08.637Z] GC before operation: completed in 44.628 ms, heap usage 199.010 MB -> 35.140 MB. [2025-06-27T20:42:26.758Z] ====== als (apache-spark) [default], iteration 0 completed (17236.120 ms) ====== [2025-06-27T20:42:26.758Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-27T20:42:26.758Z] GC before operation: completed in 103.713 ms, heap usage 208.352 MB -> 61.822 MB. [2025-06-27T20:42:34.813Z] ====== als (apache-spark) [default], iteration 1 completed (7743.062 ms) ====== [2025-06-27T20:42:34.813Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-27T20:42:34.813Z] GC before operation: completed in 94.899 ms, heap usage 299.198 MB -> 62.850 MB. [2025-06-27T20:42:42.799Z] ====== als (apache-spark) [default], iteration 2 completed (7071.759 ms) ====== [2025-06-27T20:42:42.799Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-27T20:42:42.799Z] GC before operation: completed in 84.666 ms, heap usage 237.271 MB -> 63.363 MB. [2025-06-27T20:42:49.399Z] ====== als (apache-spark) [default], iteration 3 completed (6999.454 ms) ====== [2025-06-27T20:42:49.399Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-27T20:42:49.399Z] GC before operation: completed in 91.543 ms, heap usage 153.106 MB -> 63.941 MB. [2025-06-27T20:42:57.494Z] ====== als (apache-spark) [default], iteration 4 completed (6914.015 ms) ====== [2025-06-27T20:42:57.494Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-27T20:42:57.494Z] GC before operation: completed in 101.784 ms, heap usage 459.318 MB -> 64.863 MB. [2025-06-27T20:43:04.099Z] ====== als (apache-spark) [default], iteration 5 completed (6813.937 ms) ====== [2025-06-27T20:43:04.099Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-27T20:43:04.099Z] GC before operation: completed in 97.587 ms, heap usage 267.084 MB -> 65.114 MB. [2025-06-27T20:43:10.728Z] ====== als (apache-spark) [default], iteration 6 completed (6797.814 ms) ====== [2025-06-27T20:43:10.728Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-27T20:43:10.728Z] GC before operation: completed in 104.544 ms, heap usage 505.173 MB -> 66.031 MB. [2025-06-27T20:43:18.764Z] ====== als (apache-spark) [default], iteration 7 completed (6869.589 ms) ====== [2025-06-27T20:43:18.764Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-27T20:43:18.764Z] GC before operation: completed in 91.089 ms, heap usage 247.485 MB -> 66.220 MB. [2025-06-27T20:43:25.366Z] ====== als (apache-spark) [default], iteration 8 completed (6771.470 ms) ====== [2025-06-27T20:43:25.366Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-27T20:43:25.366Z] GC before operation: completed in 89.479 ms, heap usage 275.117 MB -> 66.770 MB. [2025-06-27T20:43:31.965Z] ====== als (apache-spark) [default], iteration 9 completed (6738.430 ms) ====== [2025-06-27T20:43:31.965Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-27T20:43:32.901Z] GC before operation: completed in 104.425 ms, heap usage 326.690 MB -> 67.640 MB. [2025-06-27T20:43:40.715Z] ====== als (apache-spark) [default], iteration 10 completed (6767.966 ms) ====== [2025-06-27T20:43:40.715Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-27T20:43:40.715Z] GC before operation: completed in 101.562 ms, heap usage 191.659 MB -> 67.099 MB. [2025-06-27T20:43:47.353Z] ====== als (apache-spark) [default], iteration 11 completed (6800.071 ms) ====== [2025-06-27T20:43:47.353Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-27T20:43:47.353Z] GC before operation: completed in 103.038 ms, heap usage 206.501 MB -> 67.437 MB. [2025-06-27T20:43:53.942Z] ====== als (apache-spark) [default], iteration 12 completed (6673.207 ms) ====== [2025-06-27T20:43:53.942Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-27T20:43:53.942Z] GC before operation: completed in 127.186 ms, heap usage 280.326 MB -> 67.748 MB. [2025-06-27T20:44:00.535Z] ====== als (apache-spark) [default], iteration 13 completed (6444.253 ms) ====== [2025-06-27T20:44:00.535Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-27T20:44:00.535Z] GC before operation: completed in 94.617 ms, heap usage 208.706 MB -> 67.909 MB. [2025-06-27T20:44:07.127Z] ====== als (apache-spark) [default], iteration 14 completed (6643.549 ms) ====== [2025-06-27T20:44:07.127Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-27T20:44:07.127Z] GC before operation: completed in 114.299 ms, heap usage 295.758 MB -> 68.292 MB. [2025-06-27T20:44:15.198Z] ====== als (apache-spark) [default], iteration 15 completed (6811.551 ms) ====== [2025-06-27T20:44:15.198Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-27T20:44:15.198Z] GC before operation: completed in 103.119 ms, heap usage 490.122 MB -> 68.914 MB. [2025-06-27T20:44:21.788Z] ====== als (apache-spark) [default], iteration 16 completed (6562.278 ms) ====== [2025-06-27T20:44:21.788Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-27T20:44:21.788Z] GC before operation: completed in 101.381 ms, heap usage 561.255 MB -> 69.300 MB. [2025-06-27T20:44:28.386Z] ====== als (apache-spark) [default], iteration 17 completed (6714.225 ms) ====== [2025-06-27T20:44:28.386Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-27T20:44:28.386Z] GC before operation: completed in 108.140 ms, heap usage 497.755 MB -> 69.378 MB. [2025-06-27T20:44:35.001Z] ====== als (apache-spark) [default], iteration 18 completed (6614.594 ms) ====== [2025-06-27T20:44:35.001Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-27T20:44:35.001Z] GC before operation: completed in 102.552 ms, heap usage 158.426 MB -> 69.274 MB. [2025-06-27T20:44:42.109Z] ====== als (apache-spark) [default], iteration 19 completed (6582.682 ms) ====== [2025-06-27T20:44:42.109Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-27T20:44:42.109Z] GC before operation: completed in 96.743 ms, heap usage 199.746 MB -> 69.484 MB. [2025-06-27T20:44:48.700Z] ====== als (apache-spark) [default], iteration 20 completed (6647.282 ms) ====== [2025-06-27T20:44:48.700Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-27T20:44:49.639Z] GC before operation: completed in 106.175 ms, heap usage 200.111 MB -> 69.744 MB. [2025-06-27T20:44:56.247Z] ====== als (apache-spark) [default], iteration 21 completed (6706.659 ms) ====== [2025-06-27T20:44:56.247Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-27T20:44:56.247Z] GC before operation: completed in 120.776 ms, heap usage 206.934 MB -> 69.939 MB. [2025-06-27T20:45:02.850Z] ====== als (apache-spark) [default], iteration 22 completed (6615.453 ms) ====== [2025-06-27T20:45:02.850Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-27T20:45:02.850Z] GC before operation: completed in 104.929 ms, heap usage 201.807 MB -> 70.244 MB. [2025-06-27T20:45:11.051Z] ====== als (apache-spark) [default], iteration 23 completed (6669.729 ms) ====== [2025-06-27T20:45:11.051Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-27T20:45:11.051Z] GC before operation: completed in 115.097 ms, heap usage 471.430 MB -> 70.968 MB. [2025-06-27T20:45:17.648Z] ====== als (apache-spark) [default], iteration 24 completed (6743.254 ms) ====== [2025-06-27T20:45:17.648Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-27T20:45:17.648Z] GC before operation: completed in 106.864 ms, heap usage 131.671 MB -> 70.796 MB. [2025-06-27T20:45:23.694Z] ====== als (apache-spark) [default], iteration 25 completed (6650.442 ms) ====== [2025-06-27T20:45:23.694Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-27T20:45:24.629Z] GC before operation: completed in 108.785 ms, heap usage 493.739 MB -> 71.501 MB. [2025-06-27T20:45:31.221Z] ====== als (apache-spark) [default], iteration 26 completed (6474.545 ms) ====== [2025-06-27T20:45:31.221Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-27T20:45:31.221Z] GC before operation: completed in 91.018 ms, heap usage 207.685 MB -> 71.311 MB. [2025-06-27T20:45:37.819Z] ====== als (apache-spark) [default], iteration 27 completed (6636.198 ms) ====== [2025-06-27T20:45:37.819Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-27T20:45:37.819Z] GC before operation: completed in 88.285 ms, heap usage 184.722 MB -> 71.578 MB. [2025-06-27T20:45:44.383Z] ====== als (apache-spark) [default], iteration 28 completed (6547.777 ms) ====== [2025-06-27T20:45:44.383Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-27T20:45:44.383Z] GC before operation: completed in 98.548 ms, heap usage 560.744 MB -> 72.273 MB. [2025-06-27T20:45:52.507Z] ====== als (apache-spark) [default], iteration 29 completed (6552.900 ms) ====== [2025-06-27T20:45:52.507Z] ----------------------------------- [2025-06-27T20:45:52.507Z] renaissance-als_0_PASSED [2025-06-27T20:45:52.507Z] ----------------------------------- [2025-06-27T20:45:52.507Z] [2025-06-27T20:45:52.507Z] TEST TEARDOWN: [2025-06-27T20:45:52.507Z] Nothing to be done for teardown. [2025-06-27T20:45:52.507Z] renaissance-als_0 Finish Time: Fri Jun 27 20:45:51 2025 Epoch Time (ms): 1751057151389