[2025-05-01T08:42:01.650Z] Running test renaissance-als_0 ... [2025-05-01T08:42:01.650Z] =============================================== [2025-05-01T08:42:01.650Z] renaissance-als_0 Start Time: Thu May 1 08:42:01 2025 Epoch Time (ms): 1746088921508 [2025-05-01T08:42:01.650Z] variation: NoOptions [2025-05-01T08:42:01.650Z] JVM_OPTIONS: [2025-05-01T08:42:01.650Z] { \ [2025-05-01T08:42:01.650Z] echo ""; echo "TEST SETUP:"; \ [2025-05-01T08:42:01.650Z] echo "Nothing to be done for setup."; \ [2025-05-01T08:42:01.650Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17460888755342/renaissance-als_0"; \ [2025-05-01T08:42:01.650Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17460888755342/renaissance-als_0"; \ [2025-05-01T08:42:01.650Z] echo ""; echo "TESTING:"; \ [2025-05-01T08:42:01.650Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17460888755342/renaissance-als_0"/als.json" als; \ [2025-05-01T08:42:01.650Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17460888755342/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-01T08:42:01.650Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-01T08:42:01.650Z] echo "Nothing to be done for teardown."; \ [2025-05-01T08:42:01.650Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17460888755342/TestTargetResult"; [2025-05-01T08:42:01.650Z] [2025-05-01T08:42:01.650Z] TEST SETUP: [2025-05-01T08:42:01.650Z] Nothing to be done for setup. [2025-05-01T08:42:01.650Z] [2025-05-01T08:42:01.650Z] TESTING: [2025-05-01T08:42:11.421Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-05-01T08:42:19.776Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-01T08:42:19.776Z] GC before operation: completed in 74.972 ms, heap usage 205.473 MB -> 36.790 MB. [2025-05-01T08:42:44.448Z] ====== als (apache-spark) [default], iteration 0 completed (24867.347 ms) ====== [2025-05-01T08:42:44.448Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-01T08:42:45.416Z] GC before operation: completed in 237.743 ms, heap usage 502.520 MB -> 64.036 MB. [2025-05-01T08:42:53.615Z] ====== als (apache-spark) [default], iteration 1 completed (7440.118 ms) ====== [2025-05-01T08:42:53.615Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-01T08:42:53.615Z] GC before operation: completed in 201.228 ms, heap usage 471.613 MB -> 65.059 MB. [2025-05-01T08:42:59.008Z] ====== als (apache-spark) [default], iteration 2 completed (5421.917 ms) ====== [2025-05-01T08:42:59.009Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-01T08:42:59.009Z] GC before operation: completed in 149.507 ms, heap usage 1.131 GB -> 66.657 MB. [2025-05-01T08:43:04.398Z] ====== als (apache-spark) [default], iteration 3 completed (5154.220 ms) ====== [2025-05-01T08:43:04.398Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-01T08:43:04.398Z] GC before operation: completed in 152.517 ms, heap usage 421.105 MB -> 66.305 MB. [2025-05-01T08:43:09.788Z] ====== als (apache-spark) [default], iteration 4 completed (5145.341 ms) ====== [2025-05-01T08:43:09.788Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-01T08:43:09.788Z] GC before operation: completed in 154.108 ms, heap usage 494.097 MB -> 66.853 MB. [2025-05-01T08:43:15.188Z] ====== als (apache-spark) [default], iteration 5 completed (5095.884 ms) ====== [2025-05-01T08:43:15.189Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-01T08:43:16.148Z] GC before operation: completed in 171.153 ms, heap usage 985.328 MB -> 68.004 MB. [2025-05-01T08:43:22.173Z] ====== als (apache-spark) [default], iteration 6 completed (5080.722 ms) ====== [2025-05-01T08:43:22.174Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-01T08:43:22.174Z] GC before operation: completed in 208.213 ms, heap usage 184.645 MB -> 68.245 MB. [2025-05-01T08:43:27.130Z] ====== als (apache-spark) [default], iteration 7 completed (4995.802 ms) ====== [2025-05-01T08:43:27.130Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-01T08:43:27.130Z] GC before operation: completed in 154.976 ms, heap usage 1.211 GB -> 69.264 MB. [2025-05-01T08:43:32.518Z] ====== als (apache-spark) [default], iteration 8 completed (4993.581 ms) ====== [2025-05-01T08:43:32.518Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-01T08:43:32.518Z] GC before operation: completed in 184.428 ms, heap usage 1.003 GB -> 69.713 MB. [2025-05-01T08:43:37.909Z] ====== als (apache-spark) [default], iteration 9 completed (4854.160 ms) ====== [2025-05-01T08:43:37.909Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-01T08:43:37.909Z] GC before operation: completed in 155.985 ms, heap usage 655.846 MB -> 69.428 MB. [2025-05-01T08:43:43.302Z] ====== als (apache-spark) [default], iteration 10 completed (4932.394 ms) ====== [2025-05-01T08:43:43.302Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-01T08:43:43.302Z] GC before operation: completed in 212.551 ms, heap usage 139.785 MB -> 69.213 MB. [2025-05-01T08:43:48.692Z] ====== als (apache-spark) [default], iteration 11 completed (4951.175 ms) ====== [2025-05-01T08:43:48.692Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-01T08:43:48.692Z] GC before operation: completed in 153.739 ms, heap usage 831.876 MB -> 70.212 MB. [2025-05-01T08:43:54.077Z] ====== als (apache-spark) [default], iteration 12 completed (4808.960 ms) ====== [2025-05-01T08:43:54.077Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-01T08:43:54.077Z] GC before operation: completed in 158.048 ms, heap usage 538.542 MB -> 70.120 MB. [2025-05-01T08:43:58.241Z] ====== als (apache-spark) [default], iteration 13 completed (4852.792 ms) ====== [2025-05-01T08:43:58.241Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-01T08:43:59.195Z] GC before operation: completed in 199.234 ms, heap usage 672.466 MB -> 70.595 MB. [2025-05-01T08:44:04.578Z] ====== als (apache-spark) [default], iteration 14 completed (4820.062 ms) ====== [2025-05-01T08:44:04.578Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-01T08:44:04.578Z] GC before operation: completed in 167.086 ms, heap usage 327.690 MB -> 70.423 MB. [2025-05-01T08:44:08.738Z] ====== als (apache-spark) [default], iteration 15 completed (4754.306 ms) ====== [2025-05-01T08:44:08.739Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-01T08:44:09.694Z] GC before operation: completed in 163.790 ms, heap usage 262.305 MB -> 70.736 MB. [2025-05-01T08:44:13.858Z] ====== als (apache-spark) [default], iteration 16 completed (4708.002 ms) ====== [2025-05-01T08:44:13.858Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-01T08:44:14.814Z] GC before operation: completed in 165.538 ms, heap usage 920.719 MB -> 71.810 MB. [2025-05-01T08:44:18.997Z] ====== als (apache-spark) [default], iteration 17 completed (4698.629 ms) ====== [2025-05-01T08:44:18.997Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-01T08:44:18.997Z] GC before operation: completed in 157.596 ms, heap usage 687.929 MB -> 71.804 MB. [2025-05-01T08:44:24.383Z] ====== als (apache-spark) [default], iteration 18 completed (4601.553 ms) ====== [2025-05-01T08:44:24.383Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-01T08:44:24.383Z] GC before operation: completed in 169.064 ms, heap usage 1.487 GB -> 72.940 MB. [2025-05-01T08:44:29.769Z] ====== als (apache-spark) [default], iteration 19 completed (4646.880 ms) ====== [2025-05-01T08:44:29.769Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-01T08:44:29.769Z] GC before operation: completed in 156.051 ms, heap usage 1.354 GB -> 73.192 MB. [2025-05-01T08:44:35.166Z] ====== als (apache-spark) [default], iteration 20 completed (5409.006 ms) ====== [2025-05-01T08:44:35.166Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-01T08:44:35.166Z] GC before operation: completed in 220.944 ms, heap usage 857.802 MB -> 72.568 MB. [2025-05-01T08:44:40.553Z] ====== als (apache-spark) [default], iteration 21 completed (4617.422 ms) ====== [2025-05-01T08:44:40.553Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-01T08:44:40.553Z] GC before operation: completed in 171.901 ms, heap usage 297.012 MB -> 72.424 MB. [2025-05-01T08:44:45.950Z] ====== als (apache-spark) [default], iteration 22 completed (4652.782 ms) ====== [2025-05-01T08:44:45.950Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-01T08:44:45.950Z] GC before operation: completed in 173.789 ms, heap usage 553.321 MB -> 72.932 MB. [2025-05-01T08:44:50.120Z] ====== als (apache-spark) [default], iteration 23 completed (4622.980 ms) ====== [2025-05-01T08:44:50.120Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-01T08:44:50.120Z] GC before operation: completed in 177.376 ms, heap usage 309.660 MB -> 72.967 MB. [2025-05-01T08:44:55.508Z] ====== als (apache-spark) [default], iteration 24 completed (4612.844 ms) ====== [2025-05-01T08:44:55.508Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-01T08:44:55.508Z] GC before operation: completed in 201.519 ms, heap usage 623.118 MB -> 73.672 MB. [2025-05-01T08:45:00.899Z] ====== als (apache-spark) [default], iteration 25 completed (4563.737 ms) ====== [2025-05-01T08:45:00.899Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-01T08:45:00.899Z] GC before operation: completed in 204.528 ms, heap usage 329.631 MB -> 73.356 MB. [2025-05-01T08:45:05.063Z] ====== als (apache-spark) [default], iteration 26 completed (4581.967 ms) ====== [2025-05-01T08:45:05.063Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-01T08:45:05.063Z] GC before operation: completed in 207.952 ms, heap usage 676.461 MB -> 74.164 MB. [2025-05-01T08:45:10.454Z] ====== als (apache-spark) [default], iteration 27 completed (4439.494 ms) ====== [2025-05-01T08:45:10.454Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-01T08:45:10.454Z] GC before operation: completed in 176.592 ms, heap usage 1.376 GB -> 75.326 MB. [2025-05-01T08:45:15.845Z] ====== als (apache-spark) [default], iteration 28 completed (4572.412 ms) ====== [2025-05-01T08:45:15.846Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-01T08:45:15.846Z] GC before operation: completed in 185.026 ms, heap usage 287.884 MB -> 74.191 MB. [2025-05-01T08:45:20.031Z] ====== als (apache-spark) [default], iteration 29 completed (4455.246 ms) ====== [2025-05-01T08:45:20.988Z] ----------------------------------- [2025-05-01T08:45:20.988Z] renaissance-als_0_PASSED [2025-05-01T08:45:20.988Z] ----------------------------------- [2025-05-01T08:45:20.988Z] [2025-05-01T08:45:20.988Z] TEST TEARDOWN: [2025-05-01T08:45:20.988Z] Nothing to be done for teardown. [2025-05-01T08:45:20.988Z] renaissance-als_0 Finish Time: Thu May 1 08:45:20 2025 Epoch Time (ms): 1746089120315