[2025-06-26T17:01:16.004Z] Running test renaissance-als_0 ... [2025-06-26T17:01:16.004Z] =============================================== [2025-06-26T17:01:16.004Z] renaissance-als_0 Start Time: Thu Jun 26 17:01:15 2025 Epoch Time (ms): 1750957275094 [2025-06-26T17:01:16.004Z] variation: NoOptions [2025-06-26T17:01:16.004Z] JVM_OPTIONS: [2025-06-26T17:01:16.004Z] { \ [2025-06-26T17:01:16.004Z] echo ""; echo "TEST SETUP:"; \ [2025-06-26T17:01:16.004Z] echo "Nothing to be done for setup."; \ [2025-06-26T17:01:16.004Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509571693447/renaissance-als_0"; \ [2025-06-26T17:01:16.004Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509571693447/renaissance-als_0"; \ [2025-06-26T17:01:16.004Z] echo ""; echo "TESTING:"; \ [2025-06-26T17:01:16.004Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509571693447/renaissance-als_0"/als.json" als; \ [2025-06-26T17:01:16.004Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509571693447/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-26T17:01:16.004Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-26T17:01:16.004Z] echo "Nothing to be done for teardown."; \ [2025-06-26T17:01:16.004Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17509571693447/TestTargetResult"; [2025-06-26T17:01:16.004Z] [2025-06-26T17:01:16.004Z] TEST SETUP: [2025-06-26T17:01:16.004Z] Nothing to be done for setup. [2025-06-26T17:01:16.004Z] [2025-06-26T17:01:16.004Z] TESTING: [2025-06-26T17:01:24.673Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-06-26T17:01:31.192Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-26T17:01:31.192Z] GC before operation: completed in 49.185 ms, heap usage 64.129 MB -> 35.752 MB. [2025-06-26T17:01:55.437Z] ====== als (apache-spark) [default], iteration 0 completed (22976.765 ms) ====== [2025-06-26T17:01:55.437Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-26T17:01:55.437Z] GC before operation: completed in 195.361 ms, heap usage 309.936 MB -> 62.801 MB. [2025-06-26T17:02:03.520Z] ====== als (apache-spark) [default], iteration 1 completed (6751.979 ms) ====== [2025-06-26T17:02:03.520Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-26T17:02:03.520Z] GC before operation: completed in 159.701 ms, heap usage 403.655 MB -> 63.847 MB. [2025-06-26T17:02:08.763Z] ====== als (apache-spark) [default], iteration 2 completed (5433.745 ms) ====== [2025-06-26T17:02:08.763Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-26T17:02:08.763Z] GC before operation: completed in 134.625 ms, heap usage 602.403 MB -> 64.628 MB. [2025-06-26T17:02:14.690Z] ====== als (apache-spark) [default], iteration 3 completed (5476.385 ms) ====== [2025-06-26T17:02:14.690Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-26T17:02:14.690Z] GC before operation: completed in 146.916 ms, heap usage 475.132 MB -> 65.097 MB. [2025-06-26T17:02:20.104Z] ====== als (apache-spark) [default], iteration 4 completed (5178.763 ms) ====== [2025-06-26T17:02:20.104Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-26T17:02:21.027Z] GC before operation: completed in 188.798 ms, heap usage 314.477 MB -> 65.430 MB. [2025-06-26T17:02:26.256Z] ====== als (apache-spark) [default], iteration 5 completed (5228.964 ms) ====== [2025-06-26T17:02:26.256Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-26T17:02:26.256Z] GC before operation: completed in 179.643 ms, heap usage 458.239 MB -> 66.328 MB. [2025-06-26T17:02:31.498Z] ====== als (apache-spark) [default], iteration 6 completed (5231.191 ms) ====== [2025-06-26T17:02:31.498Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-26T17:02:31.498Z] GC before operation: completed in 197.378 ms, heap usage 376.409 MB -> 66.569 MB. [2025-06-26T17:02:36.733Z] ====== als (apache-spark) [default], iteration 7 completed (4839.685 ms) ====== [2025-06-26T17:02:36.733Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-26T17:02:36.733Z] GC before operation: completed in 144.608 ms, heap usage 210.490 MB -> 66.834 MB. [2025-06-26T17:02:42.021Z] ====== als (apache-spark) [default], iteration 8 completed (4951.039 ms) ====== [2025-06-26T17:02:42.021Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-26T17:02:42.945Z] GC before operation: completed in 154.627 ms, heap usage 256.898 MB -> 67.465 MB. [2025-06-26T17:02:48.176Z] ====== als (apache-spark) [default], iteration 9 completed (4867.534 ms) ====== [2025-06-26T17:02:48.176Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-26T17:02:48.176Z] GC before operation: completed in 162.460 ms, heap usage 555.540 MB -> 68.236 MB. [2025-06-26T17:02:53.444Z] ====== als (apache-spark) [default], iteration 10 completed (4861.744 ms) ====== [2025-06-26T17:02:53.444Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-26T17:02:53.444Z] GC before operation: completed in 169.194 ms, heap usage 570.045 MB -> 68.447 MB. [2025-06-26T17:02:58.677Z] ====== als (apache-spark) [default], iteration 11 completed (4744.496 ms) ====== [2025-06-26T17:02:58.678Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-26T17:02:58.678Z] GC before operation: completed in 190.780 ms, heap usage 128.974 MB -> 68.417 MB. [2025-06-26T17:03:03.954Z] ====== als (apache-spark) [default], iteration 12 completed (5179.857 ms) ====== [2025-06-26T17:03:03.954Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-26T17:03:03.954Z] GC before operation: completed in 170.203 ms, heap usage 424.601 MB -> 68.640 MB. [2025-06-26T17:03:08.730Z] ====== als (apache-spark) [default], iteration 13 completed (4777.145 ms) ====== [2025-06-26T17:03:08.730Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-26T17:03:08.730Z] GC before operation: completed in 160.434 ms, heap usage 308.724 MB -> 68.811 MB. [2025-06-26T17:03:13.970Z] ====== als (apache-spark) [default], iteration 14 completed (4688.286 ms) ====== [2025-06-26T17:03:13.970Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-26T17:03:13.970Z] GC before operation: completed in 161.484 ms, heap usage 487.298 MB -> 69.442 MB. [2025-06-26T17:03:19.215Z] ====== als (apache-spark) [default], iteration 15 completed (4530.485 ms) ====== [2025-06-26T17:03:19.215Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-26T17:03:19.215Z] GC before operation: completed in 173.939 ms, heap usage 203.045 MB -> 69.318 MB. [2025-06-26T17:03:24.579Z] ====== als (apache-spark) [default], iteration 16 completed (4596.453 ms) ====== [2025-06-26T17:03:24.579Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-26T17:03:24.579Z] GC before operation: completed in 200.895 ms, heap usage 215.512 MB -> 69.493 MB. [2025-06-26T17:03:29.809Z] ====== als (apache-spark) [default], iteration 17 completed (5003.008 ms) ====== [2025-06-26T17:03:29.809Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-26T17:03:29.809Z] GC before operation: completed in 171.071 ms, heap usage 361.822 MB -> 69.981 MB. [2025-06-26T17:03:35.040Z] ====== als (apache-spark) [default], iteration 18 completed (4772.113 ms) ====== [2025-06-26T17:03:35.040Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-26T17:03:35.040Z] GC before operation: completed in 183.519 ms, heap usage 467.391 MB -> 70.508 MB. [2025-06-26T17:03:40.272Z] ====== als (apache-spark) [default], iteration 19 completed (4615.100 ms) ====== [2025-06-26T17:03:40.272Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-26T17:03:40.272Z] GC before operation: completed in 199.608 ms, heap usage 927.962 MB -> 71.372 MB. [2025-06-26T17:03:45.600Z] ====== als (apache-spark) [default], iteration 20 completed (5012.533 ms) ====== [2025-06-26T17:03:45.600Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-26T17:03:45.600Z] GC before operation: completed in 197.712 ms, heap usage 1.052 GB -> 71.565 MB. [2025-06-26T17:03:49.666Z] ====== als (apache-spark) [default], iteration 21 completed (4544.496 ms) ====== [2025-06-26T17:03:49.666Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-26T17:03:50.604Z] GC before operation: completed in 201.428 ms, heap usage 195.906 MB -> 70.986 MB. [2025-06-26T17:03:54.649Z] ====== als (apache-spark) [default], iteration 22 completed (4386.120 ms) ====== [2025-06-26T17:03:54.649Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-26T17:03:54.649Z] GC before operation: completed in 155.855 ms, heap usage 1.635 GB -> 72.717 MB. [2025-06-26T17:03:59.348Z] ====== als (apache-spark) [default], iteration 23 completed (4592.912 ms) ====== [2025-06-26T17:03:59.348Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-26T17:04:00.272Z] GC before operation: completed in 157.130 ms, heap usage 176.508 MB -> 71.465 MB. [2025-06-26T17:04:04.334Z] ====== als (apache-spark) [default], iteration 24 completed (4450.977 ms) ====== [2025-06-26T17:04:04.334Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-26T17:04:04.334Z] GC before operation: completed in 168.974 ms, heap usage 507.219 MB -> 72.037 MB. [2025-06-26T17:04:09.581Z] ====== als (apache-spark) [default], iteration 25 completed (4532.147 ms) ====== [2025-06-26T17:04:09.581Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-26T17:04:09.581Z] GC before operation: completed in 160.429 ms, heap usage 267.970 MB -> 72.069 MB. [2025-06-26T17:04:14.870Z] ====== als (apache-spark) [default], iteration 26 completed (4660.291 ms) ====== [2025-06-26T17:04:14.870Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-26T17:04:14.870Z] GC before operation: completed in 160.934 ms, heap usage 703.993 MB -> 73.005 MB. [2025-06-26T17:04:20.270Z] ====== als (apache-spark) [default], iteration 27 completed (4744.133 ms) ====== [2025-06-26T17:04:20.270Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-26T17:04:20.271Z] GC before operation: completed in 192.343 ms, heap usage 1.330 GB -> 73.510 MB. [2025-06-26T17:04:24.320Z] ====== als (apache-spark) [default], iteration 28 completed (4644.228 ms) ====== [2025-06-26T17:04:24.320Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-26T17:04:25.248Z] GC before operation: completed in 176.287 ms, heap usage 305.389 MB -> 72.500 MB. [2025-06-26T17:04:29.308Z] ====== als (apache-spark) [default], iteration 29 completed (4568.798 ms) ====== [2025-06-26T17:04:30.241Z] ----------------------------------- [2025-06-26T17:04:30.241Z] renaissance-als_0_PASSED [2025-06-26T17:04:30.241Z] ----------------------------------- [2025-06-26T17:04:30.241Z] [2025-06-26T17:04:30.241Z] TEST TEARDOWN: [2025-06-26T17:04:30.241Z] Nothing to be done for teardown. [2025-06-26T17:04:31.355Z] renaissance-als_0 Finish Time: Thu Jun 26 17:04:30 2025 Epoch Time (ms): 1750957470163