[2025-05-21T20:57:42.890Z] Running test renaissance-als_0 ... [2025-05-21T20:57:42.890Z] =============================================== [2025-05-21T20:57:42.890Z] renaissance-als_0 Start Time: Wed May 21 20:57:42 2025 Epoch Time (ms): 1747861062408 [2025-05-21T20:57:42.890Z] variation: NoOptions [2025-05-21T20:57:42.890Z] JVM_OPTIONS: [2025-05-21T20:57:42.890Z] { \ [2025-05-21T20:57:42.890Z] echo ""; echo "TEST SETUP:"; \ [2025-05-21T20:57:42.890Z] echo "Nothing to be done for setup."; \ [2025-05-21T20:57:42.890Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/renaissance-als_0"; \ [2025-05-21T20:57:42.890Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/renaissance-als_0"; \ [2025-05-21T20:57:42.890Z] echo ""; echo "TESTING:"; \ [2025-05-21T20:57:42.890Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/renaissance-als_0"/als.json" als; \ [2025-05-21T20:57:42.890Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-21T20:57:42.890Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-21T20:57:42.890Z] echo "Nothing to be done for teardown."; \ [2025-05-21T20:57:42.890Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17478610187960/TestTargetResult"; [2025-05-21T20:57:42.890Z] [2025-05-21T20:57:42.890Z] TEST SETUP: [2025-05-21T20:57:42.890Z] Nothing to be done for setup. [2025-05-21T20:57:42.890Z] [2025-05-21T20:57:42.890Z] TESTING: [2025-05-21T20:57:51.288Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-05-21T20:57:54.733Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-21T20:57:54.733Z] GC before operation: completed in 60.131 ms, heap usage 287.827 MB -> 34.650 MB. [2025-05-21T20:58:08.574Z] ====== als (apache-spark) [default], iteration 0 completed (13092.115 ms) ====== [2025-05-21T20:58:08.574Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-21T20:58:08.574Z] GC before operation: completed in 117.304 ms, heap usage 603.367 MB -> 61.987 MB. [2025-05-21T20:58:14.418Z] ====== als (apache-spark) [default], iteration 1 completed (5040.950 ms) ====== [2025-05-21T20:58:14.418Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-21T20:58:14.418Z] GC before operation: completed in 114.363 ms, heap usage 609.060 MB -> 62.835 MB. [2025-05-21T20:58:18.921Z] ====== als (apache-spark) [default], iteration 2 completed (4615.334 ms) ====== [2025-05-21T20:58:18.921Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-21T20:58:18.921Z] GC before operation: completed in 111.873 ms, heap usage 884.689 MB -> 63.739 MB. [2025-05-21T20:58:24.569Z] ====== als (apache-spark) [default], iteration 3 completed (4650.164 ms) ====== [2025-05-21T20:58:24.569Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-21T20:58:24.569Z] GC before operation: completed in 109.043 ms, heap usage 1.358 GB -> 64.869 MB. [2025-05-21T20:58:29.083Z] ====== als (apache-spark) [default], iteration 4 completed (4501.918 ms) ====== [2025-05-21T20:58:29.083Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-21T20:58:29.083Z] GC before operation: completed in 116.452 ms, heap usage 1.194 GB -> 65.353 MB. [2025-05-21T20:58:33.583Z] ====== als (apache-spark) [default], iteration 5 completed (4486.145 ms) ====== [2025-05-21T20:58:33.583Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-21T20:58:33.583Z] GC before operation: completed in 115.118 ms, heap usage 888.455 MB -> 65.553 MB. [2025-05-21T20:58:38.112Z] ====== als (apache-spark) [default], iteration 6 completed (4487.237 ms) ====== [2025-05-21T20:58:38.113Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-21T20:58:38.889Z] GC before operation: completed in 123.105 ms, heap usage 1.220 GB -> 66.645 MB. [2025-05-21T20:58:43.601Z] ====== als (apache-spark) [default], iteration 7 completed (4442.196 ms) ====== [2025-05-21T20:58:43.601Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-21T20:58:43.601Z] GC before operation: completed in 122.794 ms, heap usage 1.343 GB -> 67.184 MB. [2025-05-21T20:58:48.442Z] ====== als (apache-spark) [default], iteration 8 completed (4426.555 ms) ====== [2025-05-21T20:58:48.442Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-21T20:58:48.442Z] GC before operation: completed in 124.656 ms, heap usage 648.262 MB -> 67.048 MB. [2025-05-21T20:58:52.955Z] ====== als (apache-spark) [default], iteration 9 completed (4562.786 ms) ====== [2025-05-21T20:58:52.955Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-21T20:58:52.955Z] GC before operation: completed in 130.315 ms, heap usage 346.655 MB -> 66.889 MB. [2025-05-21T20:58:57.460Z] ====== als (apache-spark) [default], iteration 10 completed (4439.847 ms) ====== [2025-05-21T20:58:57.460Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-21T20:58:57.460Z] GC before operation: completed in 135.442 ms, heap usage 1.615 GB -> 68.399 MB. [2025-05-21T20:59:01.951Z] ====== als (apache-spark) [default], iteration 11 completed (4378.165 ms) ====== [2025-05-21T20:59:01.952Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-21T20:59:01.952Z] GC before operation: completed in 136.890 ms, heap usage 656.051 MB -> 67.583 MB. [2025-05-21T20:59:07.622Z] ====== als (apache-spark) [default], iteration 12 completed (4438.201 ms) ====== [2025-05-21T20:59:07.622Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-21T20:59:07.622Z] GC before operation: completed in 128.637 ms, heap usage 508.301 MB -> 67.773 MB. [2025-05-21T20:59:12.162Z] ====== als (apache-spark) [default], iteration 13 completed (4482.501 ms) ====== [2025-05-21T20:59:12.162Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-21T20:59:12.162Z] GC before operation: completed in 126.610 ms, heap usage 613.950 MB -> 68.120 MB. [2025-05-21T20:59:16.692Z] ====== als (apache-spark) [default], iteration 14 completed (4367.777 ms) ====== [2025-05-21T20:59:16.692Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-21T20:59:16.692Z] GC before operation: completed in 127.949 ms, heap usage 1.156 GB -> 68.985 MB. [2025-05-21T20:59:21.398Z] ====== als (apache-spark) [default], iteration 15 completed (4242.242 ms) ====== [2025-05-21T20:59:21.398Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-21T20:59:21.398Z] GC before operation: completed in 135.022 ms, heap usage 598.142 MB -> 68.790 MB. [2025-05-21T20:59:25.934Z] ====== als (apache-spark) [default], iteration 16 completed (4379.565 ms) ====== [2025-05-21T20:59:25.934Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-21T20:59:25.934Z] GC before operation: completed in 129.302 ms, heap usage 509.867 MB -> 68.977 MB. [2025-05-21T20:59:30.617Z] ====== als (apache-spark) [default], iteration 17 completed (4230.170 ms) ====== [2025-05-21T20:59:30.617Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-21T20:59:30.617Z] GC before operation: completed in 117.888 ms, heap usage 775.877 MB -> 69.467 MB. [2025-05-21T20:59:35.161Z] ====== als (apache-spark) [default], iteration 18 completed (4421.678 ms) ====== [2025-05-21T20:59:35.161Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-21T20:59:35.161Z] GC before operation: completed in 126.859 ms, heap usage 1.036 GB -> 70.278 MB. [2025-05-21T20:59:39.684Z] ====== als (apache-spark) [default], iteration 19 completed (4344.446 ms) ====== [2025-05-21T20:59:39.684Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-21T20:59:39.684Z] GC before operation: completed in 127.314 ms, heap usage 706.074 MB -> 69.971 MB. [2025-05-21T20:59:44.285Z] ====== als (apache-spark) [default], iteration 20 completed (4388.511 ms) ====== [2025-05-21T20:59:44.285Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-21T20:59:44.285Z] GC before operation: completed in 118.654 ms, heap usage 292.647 MB -> 69.676 MB. [2025-05-21T20:59:48.803Z] ====== als (apache-spark) [default], iteration 21 completed (4244.509 ms) ====== [2025-05-21T20:59:48.803Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-21T20:59:48.803Z] GC before operation: completed in 121.920 ms, heap usage 847.806 MB -> 70.790 MB. [2025-05-21T20:59:53.335Z] ====== als (apache-spark) [default], iteration 22 completed (4418.678 ms) ====== [2025-05-21T20:59:53.335Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-21T20:59:53.335Z] GC before operation: completed in 131.796 ms, heap usage 914.534 MB -> 71.200 MB. [2025-05-21T20:59:58.042Z] ====== als (apache-spark) [default], iteration 23 completed (4348.111 ms) ====== [2025-05-21T20:59:58.042Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-21T20:59:58.042Z] GC before operation: completed in 133.525 ms, heap usage 771.849 MB -> 71.288 MB. [2025-05-21T21:00:02.540Z] ====== als (apache-spark) [default], iteration 24 completed (4167.717 ms) ====== [2025-05-21T21:00:02.540Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-21T21:00:02.540Z] GC before operation: completed in 132.519 ms, heap usage 168.591 MB -> 70.862 MB. [2025-05-21T21:00:07.050Z] ====== als (apache-spark) [default], iteration 25 completed (4297.227 ms) ====== [2025-05-21T21:00:07.050Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-21T21:00:07.050Z] GC before operation: completed in 135.695 ms, heap usage 442.067 MB -> 71.195 MB. [2025-05-21T21:00:11.639Z] ====== als (apache-spark) [default], iteration 26 completed (4337.065 ms) ====== [2025-05-21T21:00:11.639Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-21T21:00:11.639Z] GC before operation: completed in 124.531 ms, heap usage 378.914 MB -> 71.197 MB. [2025-05-21T21:00:16.145Z] ====== als (apache-spark) [default], iteration 27 completed (4418.371 ms) ====== [2025-05-21T21:00:16.145Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-21T21:00:16.145Z] GC before operation: completed in 134.511 ms, heap usage 341.049 MB -> 71.357 MB. [2025-05-21T21:00:20.658Z] ====== als (apache-spark) [default], iteration 28 completed (4309.153 ms) ====== [2025-05-21T21:00:20.658Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-21T21:00:20.658Z] GC before operation: completed in 132.245 ms, heap usage 1.031 GB -> 72.065 MB. [2025-05-21T21:00:25.245Z] ====== als (apache-spark) [default], iteration 29 completed (4444.101 ms) ====== [2025-05-21T21:00:26.024Z] ----------------------------------- [2025-05-21T21:00:26.024Z] renaissance-als_0_PASSED [2025-05-21T21:00:26.024Z] ----------------------------------- [2025-05-21T21:00:26.024Z] [2025-05-21T21:00:26.024Z] TEST TEARDOWN: [2025-05-21T21:00:26.024Z] Nothing to be done for teardown. [2025-05-21T21:00:26.024Z] renaissance-als_0 Finish Time: Wed May 21 21:00:25 2025 Epoch Time (ms): 1747861225400