[2026-03-20T00:25:24.531Z] Running test renaissance-als_0 ... [2026-03-20T00:25:24.531Z] =============================================== [2026-03-20T00:25:24.531Z] renaissance-als_0 Start Time: Fri Mar 20 00:25:01 2026 Epoch Time (ms): 1773966301433 [2026-03-20T00:25:24.531Z] variation: NoOptions [2026-03-20T00:25:24.531Z] JVM_OPTIONS: [2026-03-20T00:25:24.531Z] { \ [2026-03-20T00:25:24.531Z] echo ""; echo "TEST SETUP:"; \ [2026-03-20T00:25:24.531Z] echo "Nothing to be done for setup."; \ [2026-03-20T00:25:24.531Z] mkdir -p "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17739661343113/renaissance-als_0"; \ [2026-03-20T00:25:24.531Z] cd "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17739661343113/renaissance-als_0"; \ [2026-03-20T00:25:24.531Z] echo ""; echo "TESTING:"; \ [2026-03-20T00:25:24.531Z] "/home/jenkins/workspace/Test_openjdk26_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_openjdk26_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17739661343113/renaissance-als_0"/als.json" als; \ [2026-03-20T00:25:24.531Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17739661343113/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-20T00:25:24.531Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-20T00:25:24.531Z] echo "Nothing to be done for teardown."; \ [2026-03-20T00:25:24.531Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk26_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17739661343113/TestTargetResult"; [2026-03-20T00:25:24.531Z] [2026-03-20T00:25:24.531Z] TEST SETUP: [2026-03-20T00:25:24.531Z] Nothing to be done for setup. [2026-03-20T00:25:24.531Z] [2026-03-20T00:25:24.531Z] TESTING: [2026-03-20T00:25:24.531Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-03-20T00:25:24.531Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (resource:/shared/scala3-library_3-3.3.4.jar) [2026-03-20T00:25:24.531Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-03-20T00:25:24.531Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-03-20T00:26:35.423Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2026-03-20T00:27:12.517Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-20T00:27:12.517Z] GC before operation: completed in 59.077 ms, heap usage 300.859 MB -> 34.962 MB. [2026-03-20T00:28:50.154Z] ====== als (apache-spark) [default], iteration 0 completed (91570.520 ms) ====== [2026-03-20T00:28:50.154Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-20T00:28:50.154Z] GC before operation: completed in 137.514 ms, heap usage 409.261 MB -> 62.446 MB. [2026-03-20T00:29:10.622Z] ====== als (apache-spark) [default], iteration 1 completed (29649.531 ms) ====== [2026-03-20T00:29:10.622Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-20T00:29:11.578Z] GC before operation: completed in 132.667 ms, heap usage 702.541 MB -> 63.828 MB. [2026-03-20T00:29:50.015Z] ====== als (apache-spark) [default], iteration 2 completed (34932.688 ms) ====== [2026-03-20T00:29:50.015Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-20T00:29:50.015Z] GC before operation: completed in 119.319 ms, heap usage 872.287 MB -> 64.621 MB. [2026-03-20T00:30:18.643Z] ====== als (apache-spark) [default], iteration 3 completed (31658.432 ms) ====== [2026-03-20T00:30:18.643Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-20T00:30:18.643Z] GC before operation: completed in 135.349 ms, heap usage 648.555 MB -> 64.861 MB. [2026-03-20T00:30:54.032Z] ====== als (apache-spark) [default], iteration 4 completed (33679.266 ms) ====== [2026-03-20T00:30:54.032Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-20T00:30:54.032Z] GC before operation: completed in 118.025 ms, heap usage 668.032 MB -> 65.689 MB. [2026-03-20T00:31:22.625Z] ====== als (apache-spark) [default], iteration 5 completed (28675.118 ms) ====== [2026-03-20T00:31:22.625Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-20T00:31:22.625Z] GC before operation: completed in 123.039 ms, heap usage 655.177 MB -> 66.018 MB. [2026-03-20T00:31:55.744Z] ====== als (apache-spark) [default], iteration 6 completed (33491.672 ms) ====== [2026-03-20T00:31:55.744Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-20T00:31:55.744Z] GC before operation: completed in 157.858 ms, heap usage 560.591 MB -> 66.576 MB. [2026-03-20T00:32:41.668Z] ====== als (apache-spark) [default], iteration 7 completed (41973.294 ms) ====== [2026-03-20T00:32:41.668Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-20T00:32:41.668Z] GC before operation: completed in 175.149 ms, heap usage 635.535 MB -> 67.105 MB. [2026-03-20T00:33:20.059Z] ====== als (apache-spark) [default], iteration 8 completed (39286.247 ms) ====== [2026-03-20T00:33:20.059Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-20T00:33:20.059Z] GC before operation: completed in 143.406 ms, heap usage 528.938 MB -> 67.496 MB. [2026-03-20T00:33:35.906Z] ====== als (apache-spark) [default], iteration 9 completed (17309.848 ms) ====== [2026-03-20T00:33:35.906Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-20T00:33:35.906Z] GC before operation: completed in 139.409 ms, heap usage 646.622 MB -> 68.029 MB. [2026-03-20T00:34:10.456Z] ====== als (apache-spark) [default], iteration 10 completed (30518.023 ms) ====== [2026-03-20T00:34:10.456Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-20T00:34:10.456Z] GC before operation: completed in 138.153 ms, heap usage 427.928 MB -> 67.682 MB. [2026-03-20T00:34:43.650Z] ====== als (apache-spark) [default], iteration 11 completed (35622.334 ms) ====== [2026-03-20T00:34:43.650Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-20T00:34:43.650Z] GC before operation: completed in 160.312 ms, heap usage 1.819 GB -> 69.686 MB. [2026-03-20T00:35:12.254Z] ====== als (apache-spark) [default], iteration 12 completed (27802.733 ms) ====== [2026-03-20T00:35:12.254Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-20T00:35:12.254Z] GC before operation: completed in 169.076 ms, heap usage 1.790 GB -> 70.177 MB. [2026-03-20T00:35:57.206Z] ====== als (apache-spark) [default], iteration 13 completed (41692.568 ms) ====== [2026-03-20T00:35:57.206Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-20T00:35:57.206Z] GC before operation: completed in 163.038 ms, heap usage 578.936 MB -> 68.946 MB. [2026-03-20T00:36:30.337Z] ====== als (apache-spark) [default], iteration 14 completed (34608.256 ms) ====== [2026-03-20T00:36:30.337Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-20T00:36:30.337Z] GC before operation: completed in 143.995 ms, heap usage 622.826 MB -> 69.333 MB. [2026-03-20T00:36:58.912Z] ====== als (apache-spark) [default], iteration 15 completed (30581.440 ms) ====== [2026-03-20T00:36:58.912Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-20T00:36:58.912Z] GC before operation: completed in 141.364 ms, heap usage 536.430 MB -> 69.469 MB. [2026-03-20T00:37:38.276Z] ====== als (apache-spark) [default], iteration 16 completed (36039.883 ms) ====== [2026-03-20T00:37:38.276Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-20T00:37:38.276Z] GC before operation: completed in 167.504 ms, heap usage 461.234 MB -> 69.593 MB. [2026-03-20T00:38:02.947Z] ====== als (apache-spark) [default], iteration 17 completed (25646.051 ms) ====== [2026-03-20T00:38:02.947Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-20T00:38:02.947Z] GC before operation: completed in 121.842 ms, heap usage 497.521 MB -> 69.669 MB. [2026-03-20T00:38:36.083Z] ====== als (apache-spark) [default], iteration 18 completed (31790.196 ms) ====== [2026-03-20T00:38:36.084Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-20T00:38:36.084Z] GC before operation: completed in 106.962 ms, heap usage 530.279 MB -> 69.988 MB. [2026-03-20T00:38:54.438Z] ====== als (apache-spark) [default], iteration 19 completed (21860.933 ms) ====== [2026-03-20T00:38:54.438Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-20T00:38:54.438Z] GC before operation: completed in 121.847 ms, heap usage 381.351 MB -> 70.057 MB. [2026-03-20T00:39:14.017Z] ====== als (apache-spark) [default], iteration 20 completed (18745.183 ms) ====== [2026-03-20T00:39:14.017Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-20T00:39:14.017Z] GC before operation: completed in 142.231 ms, heap usage 702.485 MB -> 70.748 MB. [2026-03-20T00:39:35.301Z] ====== als (apache-spark) [default], iteration 21 completed (19769.294 ms) ====== [2026-03-20T00:39:35.301Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-20T00:39:35.301Z] GC before operation: completed in 133.035 ms, heap usage 530.708 MB -> 70.748 MB. [2026-03-20T00:40:03.874Z] ====== als (apache-spark) [default], iteration 22 completed (29746.661 ms) ====== [2026-03-20T00:40:03.874Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-20T00:40:03.874Z] GC before operation: completed in 118.038 ms, heap usage 449.432 MB -> 70.912 MB. [2026-03-20T00:40:25.161Z] ====== als (apache-spark) [default], iteration 23 completed (21446.231 ms) ====== [2026-03-20T00:40:25.161Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-20T00:40:26.120Z] GC before operation: completed in 112.376 ms, heap usage 481.280 MB -> 71.417 MB. [2026-03-20T00:40:55.965Z] ====== als (apache-spark) [default], iteration 24 completed (26707.124 ms) ====== [2026-03-20T00:40:55.965Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-20T00:40:55.965Z] GC before operation: completed in 117.805 ms, heap usage 1.787 GB -> 73.016 MB. [2026-03-20T00:41:17.262Z] ====== als (apache-spark) [default], iteration 25 completed (23761.923 ms) ====== [2026-03-20T00:41:17.262Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-20T00:41:17.262Z] GC before operation: completed in 160.645 ms, heap usage 410.101 MB -> 71.757 MB. [2026-03-20T00:41:41.968Z] ====== als (apache-spark) [default], iteration 26 completed (24848.759 ms) ====== [2026-03-20T00:41:41.968Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-20T00:41:41.968Z] GC before operation: completed in 169.833 ms, heap usage 478.690 MB -> 71.993 MB. [2026-03-20T00:42:27.696Z] ====== als (apache-spark) [default], iteration 27 completed (43595.749 ms) ====== [2026-03-20T00:42:27.696Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-20T00:42:27.696Z] GC before operation: completed in 173.882 ms, heap usage 473.074 MB -> 71.549 MB. [2026-03-20T00:43:00.906Z] ====== als (apache-spark) [default], iteration 28 completed (31869.746 ms) ====== [2026-03-20T00:43:00.906Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-20T00:43:00.906Z] GC before operation: completed in 143.601 ms, heap usage 536.157 MB -> 72.057 MB. [2026-03-20T00:43:29.467Z] ====== als (apache-spark) [default], iteration 29 completed (28264.323 ms) ====== [2026-03-20T00:43:42.997Z] ----------------------------------- [2026-03-20T00:43:42.997Z] renaissance-als_0_PASSED [2026-03-20T00:43:42.997Z] ----------------------------------- [2026-03-20T00:43:42.997Z] [2026-03-20T00:43:42.997Z] TEST TEARDOWN: [2026-03-20T00:43:42.997Z] Nothing to be done for teardown. [2026-03-20T00:43:42.997Z] renaissance-als_0 Finish Time: Fri Mar 20 00:43:43 2026 Epoch Time (ms): 1773967423304