[2025-05-16T21:16:50.783Z] Running test renaissance-als_0 ... [2025-05-16T21:16:50.783Z] =============================================== [2025-05-16T21:16:50.783Z] renaissance-als_0 Start Time: Fri May 16 21:16:50 2025 Epoch Time (ms): 1747430210605 [2025-05-16T21:16:50.783Z] variation: NoOptions [2025-05-16T21:16:50.783Z] JVM_OPTIONS: [2025-05-16T21:16:50.783Z] { \ [2025-05-16T21:16:50.783Z] echo ""; echo "TEST SETUP:"; \ [2025-05-16T21:16:50.783Z] echo "Nothing to be done for setup."; \ [2025-05-16T21:16:50.783Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/renaissance-als_0"; \ [2025-05-16T21:16:50.783Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/renaissance-als_0"; \ [2025-05-16T21:16:50.783Z] echo ""; echo "TESTING:"; \ [2025-05-16T21:16:50.783Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/renaissance-als_0"/als.json" als; \ [2025-05-16T21:16:50.783Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-16T21:16:50.783Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-16T21:16:50.783Z] echo "Nothing to be done for teardown."; \ [2025-05-16T21:16:50.783Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17474301673089/TestTargetResult"; [2025-05-16T21:16:50.783Z] [2025-05-16T21:16:50.783Z] TEST SETUP: [2025-05-16T21:16:50.783Z] Nothing to be done for setup. [2025-05-16T21:16:50.783Z] [2025-05-16T21:16:50.783Z] TESTING: [2025-05-16T21:16:56.209Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-16T21:17:00.392Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-16T21:17:00.392Z] GC before operation: completed in 45.881 ms, heap usage 238.226 MB -> 35.006 MB. [2025-05-16T21:17:18.920Z] ====== als (apache-spark) [default], iteration 0 completed (16713.636 ms) ====== [2025-05-16T21:17:18.920Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-16T21:17:18.920Z] GC before operation: completed in 100.455 ms, heap usage 670.271 MB -> 62.479 MB. [2025-05-16T21:17:27.189Z] ====== als (apache-spark) [default], iteration 1 completed (8201.236 ms) ====== [2025-05-16T21:17:27.189Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-16T21:17:27.189Z] GC before operation: completed in 86.706 ms, heap usage 566.642 MB -> 63.072 MB. [2025-05-16T21:17:33.967Z] ====== als (apache-spark) [default], iteration 2 completed (7378.981 ms) ====== [2025-05-16T21:17:33.967Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-16T21:17:33.967Z] GC before operation: completed in 100.426 ms, heap usage 441.595 MB -> 63.646 MB. [2025-05-16T21:17:42.180Z] ====== als (apache-spark) [default], iteration 3 completed (7349.619 ms) ====== [2025-05-16T21:17:42.180Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-16T21:17:42.180Z] GC before operation: completed in 77.616 ms, heap usage 189.660 MB -> 64.084 MB. [2025-05-16T21:17:48.894Z] ====== als (apache-spark) [default], iteration 4 completed (7040.686 ms) ====== [2025-05-16T21:17:48.895Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-16T21:17:48.895Z] GC before operation: completed in 93.827 ms, heap usage 278.867 MB -> 64.560 MB. [2025-05-16T21:17:57.204Z] ====== als (apache-spark) [default], iteration 5 completed (7235.404 ms) ====== [2025-05-16T21:17:57.204Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-16T21:17:57.204Z] GC before operation: completed in 86.339 ms, heap usage 385.268 MB -> 65.332 MB. [2025-05-16T21:18:05.391Z] ====== als (apache-spark) [default], iteration 6 completed (7289.590 ms) ====== [2025-05-16T21:18:05.391Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-16T21:18:05.391Z] GC before operation: completed in 83.930 ms, heap usage 277.768 MB -> 65.710 MB. [2025-05-16T21:18:12.088Z] ====== als (apache-spark) [default], iteration 7 completed (6989.421 ms) ====== [2025-05-16T21:18:12.088Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-16T21:18:12.088Z] GC before operation: completed in 78.643 ms, heap usage 208.330 MB -> 66.421 MB. [2025-05-16T21:18:18.815Z] ====== als (apache-spark) [default], iteration 8 completed (6968.504 ms) ====== [2025-05-16T21:18:18.815Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-16T21:18:18.815Z] GC before operation: completed in 89.114 ms, heap usage 127.729 MB -> 66.818 MB. [2025-05-16T21:18:27.050Z] ====== als (apache-spark) [default], iteration 9 completed (7060.419 ms) ====== [2025-05-16T21:18:27.050Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-16T21:18:27.050Z] GC before operation: completed in 85.798 ms, heap usage 273.562 MB -> 67.663 MB. [2025-05-16T21:18:33.749Z] ====== als (apache-spark) [default], iteration 10 completed (6993.267 ms) ====== [2025-05-16T21:18:33.749Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-16T21:18:33.749Z] GC before operation: completed in 89.236 ms, heap usage 246.570 MB -> 67.342 MB. [2025-05-16T21:18:41.948Z] ====== als (apache-spark) [default], iteration 11 completed (7254.874 ms) ====== [2025-05-16T21:18:41.948Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-16T21:18:41.948Z] GC before operation: completed in 105.920 ms, heap usage 251.244 MB -> 67.516 MB. [2025-05-16T21:18:49.704Z] ====== als (apache-spark) [default], iteration 12 completed (6977.893 ms) ====== [2025-05-16T21:18:49.704Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-16T21:18:49.704Z] GC before operation: completed in 89.309 ms, heap usage 273.181 MB -> 67.901 MB. [2025-05-16T21:18:55.458Z] ====== als (apache-spark) [default], iteration 13 completed (6840.623 ms) ====== [2025-05-16T21:18:55.458Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-16T21:18:55.458Z] GC before operation: completed in 87.134 ms, heap usage 198.766 MB -> 68.030 MB. [2025-05-16T21:19:03.591Z] ====== als (apache-spark) [default], iteration 14 completed (6895.635 ms) ====== [2025-05-16T21:19:03.591Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-16T21:19:03.591Z] GC before operation: completed in 103.734 ms, heap usage 312.971 MB -> 68.428 MB. [2025-05-16T21:19:10.324Z] ====== als (apache-spark) [default], iteration 15 completed (7517.138 ms) ====== [2025-05-16T21:19:10.324Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-16T21:19:11.279Z] GC before operation: completed in 94.017 ms, heap usage 553.281 MB -> 68.951 MB. [2025-05-16T21:19:18.027Z] ====== als (apache-spark) [default], iteration 16 completed (6896.712 ms) ====== [2025-05-16T21:19:18.027Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-16T21:19:18.027Z] GC before operation: completed in 89.267 ms, heap usage 275.861 MB -> 68.835 MB. [2025-05-16T21:19:24.748Z] ====== als (apache-spark) [default], iteration 17 completed (6934.781 ms) ====== [2025-05-16T21:19:24.748Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-16T21:19:25.707Z] GC before operation: completed in 102.228 ms, heap usage 268.188 MB -> 69.070 MB. [2025-05-16T21:19:32.439Z] ====== als (apache-spark) [default], iteration 18 completed (6955.611 ms) ====== [2025-05-16T21:19:32.439Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-16T21:19:32.439Z] GC before operation: completed in 93.805 ms, heap usage 258.000 MB -> 69.592 MB. [2025-05-16T21:19:40.627Z] ====== als (apache-spark) [default], iteration 19 completed (7124.609 ms) ====== [2025-05-16T21:19:40.627Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-16T21:19:40.627Z] GC before operation: completed in 91.204 ms, heap usage 472.042 MB -> 69.955 MB. [2025-05-16T21:19:48.198Z] ====== als (apache-spark) [default], iteration 20 completed (6917.875 ms) ====== [2025-05-16T21:19:48.198Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-16T21:19:48.198Z] GC before operation: completed in 102.593 ms, heap usage 250.746 MB -> 70.037 MB. [2025-05-16T21:19:54.131Z] ====== als (apache-spark) [default], iteration 21 completed (7022.244 ms) ====== [2025-05-16T21:19:54.131Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-16T21:19:54.131Z] GC before operation: completed in 83.110 ms, heap usage 214.764 MB -> 70.257 MB. [2025-05-16T21:20:02.280Z] ====== als (apache-spark) [default], iteration 22 completed (6694.490 ms) ====== [2025-05-16T21:20:02.280Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-16T21:20:02.280Z] GC before operation: completed in 95.009 ms, heap usage 410.402 MB -> 70.690 MB. [2025-05-16T21:20:08.991Z] ====== als (apache-spark) [default], iteration 23 completed (6638.543 ms) ====== [2025-05-16T21:20:08.991Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-16T21:20:08.991Z] GC before operation: completed in 93.183 ms, heap usage 288.487 MB -> 70.834 MB. [2025-05-16T21:20:15.698Z] ====== als (apache-spark) [default], iteration 24 completed (6632.915 ms) ====== [2025-05-16T21:20:15.698Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-16T21:20:15.698Z] GC before operation: completed in 93.470 ms, heap usage 129.474 MB -> 70.433 MB. [2025-05-16T21:20:22.432Z] ====== als (apache-spark) [default], iteration 25 completed (6661.166 ms) ====== [2025-05-16T21:20:22.432Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-16T21:20:22.432Z] GC before operation: completed in 87.715 ms, heap usage 354.613 MB -> 71.386 MB. [2025-05-16T21:20:29.229Z] ====== als (apache-spark) [default], iteration 26 completed (6836.620 ms) ====== [2025-05-16T21:20:29.229Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-16T21:20:29.229Z] GC before operation: completed in 84.209 ms, heap usage 185.674 MB -> 71.301 MB. [2025-05-16T21:20:37.443Z] ====== als (apache-spark) [default], iteration 27 completed (6956.245 ms) ====== [2025-05-16T21:20:37.443Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-16T21:20:37.443Z] GC before operation: completed in 117.493 ms, heap usage 207.493 MB -> 71.659 MB. [2025-05-16T21:20:44.301Z] ====== als (apache-spark) [default], iteration 28 completed (7119.488 ms) ====== [2025-05-16T21:20:44.301Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-16T21:20:44.301Z] GC before operation: completed in 93.631 ms, heap usage 175.418 MB -> 72.035 MB. [2025-05-16T21:20:51.178Z] ====== als (apache-spark) [default], iteration 29 completed (6957.018 ms) ====== [2025-05-16T21:20:52.135Z] ----------------------------------- [2025-05-16T21:20:52.135Z] renaissance-als_0_PASSED [2025-05-16T21:20:52.135Z] ----------------------------------- [2025-05-16T21:20:52.135Z] [2025-05-16T21:20:52.135Z] TEST TEARDOWN: [2025-05-16T21:20:52.135Z] Nothing to be done for teardown. [2025-05-16T21:20:52.135Z] renaissance-als_0 Finish Time: Fri May 16 21:20:51 2025 Epoch Time (ms): 1747430451243