[2025-12-16T09:51:57.090Z] Running test renaissance-als_0 ... [2025-12-16T09:51:57.090Z] =============================================== [2025-12-16T09:51:57.090Z] renaissance-als_0 Start Time: Tue Dec 16 09:51:55 2025 Epoch Time (ms): 1765878715610 [2025-12-16T09:51:57.090Z] variation: NoOptions [2025-12-16T09:51:57.090Z] JVM_OPTIONS: [2025-12-16T09:51:57.090Z] { \ [2025-12-16T09:51:57.090Z] echo ""; echo "TEST SETUP:"; \ [2025-12-16T09:51:57.090Z] echo "Nothing to be done for setup."; \ [2025-12-16T09:51:57.090Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17658785433690/renaissance-als_0"; \ [2025-12-16T09:51:57.090Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17658785433690/renaissance-als_0"; \ [2025-12-16T09:51:57.090Z] echo ""; echo "TESTING:"; \ [2025-12-16T09:51:57.090Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17658785433690/renaissance-als_0"/als.json" als; \ [2025-12-16T09:51:57.090Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17658785433690/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-16T09:51:57.090Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-16T09:51:57.090Z] echo "Nothing to be done for teardown."; \ [2025-12-16T09:51:57.090Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17658785433690/TestTargetResult"; [2025-12-16T09:51:57.090Z] [2025-12-16T09:51:57.090Z] TEST SETUP: [2025-12-16T09:51:57.090Z] Nothing to be done for setup. [2025-12-16T09:51:57.090Z] [2025-12-16T09:51:57.090Z] TESTING: [2025-12-16T09:52:00.159Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-16T09:52:00.159Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17658785433690/renaissance-als_0/launcher-095156-8520332854959295371/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-16T09:52:00.159Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-16T09:52:00.160Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-16T09:54:54.182Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-12-16T09:55:42.780Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-16T09:55:42.780Z] GC before operation: completed in 125.931 ms, heap usage 286.947 MB -> 34.711 MB. [2025-12-16T09:56:49.982Z] ====== als (apache-spark) [default], iteration 0 completed (62870.761 ms) ====== [2025-12-16T09:56:49.982Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-16T09:56:49.982Z] GC before operation: completed in 133.528 ms, heap usage 332.115 MB -> 61.365 MB. [2025-12-16T09:57:38.562Z] ====== als (apache-spark) [default], iteration 1 completed (52544.440 ms) ====== [2025-12-16T09:57:38.562Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-16T09:57:38.562Z] GC before operation: completed in 105.357 ms, heap usage 246.851 MB -> 62.434 MB. [2025-12-16T09:59:30.097Z] ====== als (apache-spark) [default], iteration 2 completed (109038.779 ms) ====== [2025-12-16T09:59:30.097Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-16T09:59:30.097Z] GC before operation: completed in 92.065 ms, heap usage 248.693 MB -> 62.780 MB. [2025-12-16T10:00:50.356Z] ====== als (apache-spark) [default], iteration 3 completed (79647.551 ms) ====== [2025-12-16T10:00:50.356Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-16T10:00:50.356Z] GC before operation: completed in 87.768 ms, heap usage 307.110 MB -> 63.603 MB. [2025-12-16T10:01:39.915Z] ====== als (apache-spark) [default], iteration 4 completed (48884.967 ms) ====== [2025-12-16T10:01:39.915Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-16T10:01:39.915Z] GC before operation: completed in 89.322 ms, heap usage 323.952 MB -> 64.358 MB. [2025-12-16T10:02:40.263Z] ====== als (apache-spark) [default], iteration 5 completed (59782.756 ms) ====== [2025-12-16T10:02:40.263Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-16T10:02:40.263Z] GC before operation: completed in 86.375 ms, heap usage 263.214 MB -> 64.665 MB. [2025-12-16T10:03:21.697Z] ====== als (apache-spark) [default], iteration 6 completed (43692.036 ms) ====== [2025-12-16T10:03:21.697Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-16T10:03:21.697Z] GC before operation: completed in 94.468 ms, heap usage 249.484 MB -> 65.339 MB. [2025-12-16T10:04:12.590Z] ====== als (apache-spark) [default], iteration 7 completed (51039.480 ms) ====== [2025-12-16T10:04:12.590Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-16T10:04:12.590Z] GC before operation: completed in 101.502 ms, heap usage 249.965 MB -> 65.744 MB. [2025-12-16T10:05:01.442Z] ====== als (apache-spark) [default], iteration 8 completed (45813.592 ms) ====== [2025-12-16T10:05:01.442Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-16T10:05:01.442Z] GC before operation: completed in 98.409 ms, heap usage 176.326 MB -> 66.161 MB. [2025-12-16T10:05:50.157Z] ====== als (apache-spark) [default], iteration 9 completed (52639.076 ms) ====== [2025-12-16T10:05:50.157Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-16T10:05:50.157Z] GC before operation: completed in 98.542 ms, heap usage 327.892 MB -> 67.037 MB. [2025-12-16T10:06:47.238Z] ====== als (apache-spark) [default], iteration 10 completed (53663.040 ms) ====== [2025-12-16T10:06:47.238Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-16T10:06:47.238Z] GC before operation: completed in 105.118 ms, heap usage 356.092 MB -> 66.595 MB. [2025-12-16T10:07:17.511Z] ====== als (apache-spark) [default], iteration 11 completed (31736.360 ms) ====== [2025-12-16T10:07:17.511Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-16T10:07:17.511Z] GC before operation: completed in 105.901 ms, heap usage 262.618 MB -> 66.905 MB. [2025-12-16T10:07:47.797Z] ====== als (apache-spark) [default], iteration 12 completed (28828.467 ms) ====== [2025-12-16T10:07:47.797Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-16T10:07:47.797Z] GC before operation: completed in 101.036 ms, heap usage 144.012 MB -> 67.078 MB. [2025-12-16T10:08:23.787Z] ====== als (apache-spark) [default], iteration 13 completed (37733.523 ms) ====== [2025-12-16T10:08:23.787Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-16T10:08:23.787Z] GC before operation: completed in 111.174 ms, heap usage 799.753 MB -> 68.333 MB. [2025-12-16T10:09:00.198Z] ====== als (apache-spark) [default], iteration 14 completed (36893.261 ms) ====== [2025-12-16T10:09:00.198Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-16T10:09:00.198Z] GC before operation: completed in 99.668 ms, heap usage 150.014 MB -> 67.711 MB. [2025-12-16T10:09:35.888Z] ====== als (apache-spark) [default], iteration 15 completed (32813.162 ms) ====== [2025-12-16T10:09:35.888Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-16T10:09:35.888Z] GC before operation: completed in 105.524 ms, heap usage 371.935 MB -> 68.102 MB. [2025-12-16T10:10:11.303Z] ====== als (apache-spark) [default], iteration 16 completed (32860.989 ms) ====== [2025-12-16T10:10:11.303Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-16T10:10:11.303Z] GC before operation: completed in 107.437 ms, heap usage 257.597 MB -> 68.220 MB. [2025-12-16T10:10:41.567Z] ====== als (apache-spark) [default], iteration 17 completed (32471.229 ms) ====== [2025-12-16T10:10:41.567Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-16T10:10:41.567Z] GC before operation: completed in 112.140 ms, heap usage 442.142 MB -> 68.808 MB. [2025-12-16T10:11:17.055Z] ====== als (apache-spark) [default], iteration 18 completed (35827.426 ms) ====== [2025-12-16T10:11:17.055Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-16T10:11:17.055Z] GC before operation: completed in 102.209 ms, heap usage 370.817 MB -> 68.816 MB. [2025-12-16T10:12:17.088Z] ====== als (apache-spark) [default], iteration 19 completed (54531.633 ms) ====== [2025-12-16T10:12:17.088Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-16T10:12:17.088Z] GC before operation: completed in 119.253 ms, heap usage 412.623 MB -> 69.292 MB. [2025-12-16T10:12:36.091Z] ====== als (apache-spark) [default], iteration 20 completed (23838.252 ms) ====== [2025-12-16T10:12:36.091Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-16T10:12:36.091Z] GC before operation: completed in 113.707 ms, heap usage 361.858 MB -> 69.422 MB. [2025-12-16T10:13:02.936Z] ====== als (apache-spark) [default], iteration 21 completed (26810.290 ms) ====== [2025-12-16T10:13:02.936Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-16T10:13:02.936Z] GC before operation: completed in 104.003 ms, heap usage 252.742 MB -> 69.621 MB. [2025-12-16T10:13:32.340Z] ====== als (apache-spark) [default], iteration 22 completed (28747.668 ms) ====== [2025-12-16T10:13:32.340Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-16T10:13:32.340Z] GC before operation: completed in 105.876 ms, heap usage 160.138 MB -> 69.644 MB. [2025-12-16T10:13:58.375Z] ====== als (apache-spark) [default], iteration 23 completed (24570.083 ms) ====== [2025-12-16T10:13:58.375Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-16T10:13:58.375Z] GC before operation: completed in 102.883 ms, heap usage 268.169 MB -> 70.098 MB. [2025-12-16T10:14:20.803Z] ====== als (apache-spark) [default], iteration 24 completed (22825.405 ms) ====== [2025-12-16T10:14:20.803Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-16T10:14:20.803Z] GC before operation: completed in 90.163 ms, heap usage 248.506 MB -> 70.246 MB. [2025-12-16T10:14:42.973Z] ====== als (apache-spark) [default], iteration 25 completed (21542.628 ms) ====== [2025-12-16T10:14:42.973Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-16T10:14:42.973Z] GC before operation: completed in 112.113 ms, heap usage 327.107 MB -> 70.606 MB. [2025-12-16T10:15:05.178Z] ====== als (apache-spark) [default], iteration 26 completed (23080.716 ms) ====== [2025-12-16T10:15:05.178Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-16T10:15:05.178Z] GC before operation: completed in 103.097 ms, heap usage 374.482 MB -> 71.000 MB. [2025-12-16T10:15:24.172Z] ====== als (apache-spark) [default], iteration 27 completed (18893.238 ms) ====== [2025-12-16T10:15:24.172Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-16T10:15:24.172Z] GC before operation: completed in 128.801 ms, heap usage 326.721 MB -> 70.699 MB. [2025-12-16T10:15:50.125Z] ====== als (apache-spark) [default], iteration 28 completed (26522.449 ms) ====== [2025-12-16T10:15:50.125Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-16T10:15:50.125Z] GC before operation: completed in 93.298 ms, heap usage 359.012 MB -> 70.961 MB. [2025-12-16T10:16:12.648Z] ====== als (apache-spark) [default], iteration 29 completed (20922.516 ms) ====== [2025-12-16T10:16:12.648Z] ----------------------------------- [2025-12-16T10:16:12.648Z] renaissance-als_0_PASSED [2025-12-16T10:16:12.648Z] ----------------------------------- [2025-12-16T10:16:12.648Z] [2025-12-16T10:16:12.648Z] TEST TEARDOWN: [2025-12-16T10:16:12.648Z] Nothing to be done for teardown. [2025-12-16T10:16:12.648Z] renaissance-als_0 Finish Time: Tue Dec 16 10:16:11 2025 Epoch Time (ms): 1765880171954