[2025-12-13T13:37:10.412Z] Running test renaissance-als_0 ... [2025-12-13T13:37:10.412Z] =============================================== [2025-12-13T13:37:10.412Z] renaissance-als_0 Start Time: Sat Dec 13 13:37:09 2025 Epoch Time (ms): 1765633029880 [2025-12-13T13:37:10.412Z] variation: NoOptions [2025-12-13T13:37:10.412Z] JVM_OPTIONS: [2025-12-13T13:37:10.412Z] { \ [2025-12-13T13:37:10.412Z] echo ""; echo "TEST SETUP:"; \ [2025-12-13T13:37:10.412Z] echo "Nothing to be done for setup."; \ [2025-12-13T13:37:10.412Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17656329078695/renaissance-als_0"; \ [2025-12-13T13:37:10.412Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17656329078695/renaissance-als_0"; \ [2025-12-13T13:37:10.412Z] echo ""; echo "TESTING:"; \ [2025-12-13T13:37:10.412Z] "/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_17656329078695/renaissance-als_0"/als.json" als; \ [2025-12-13T13:37:10.412Z] 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_17656329078695/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-13T13:37:10.412Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-13T13:37:10.412Z] echo "Nothing to be done for teardown."; \ [2025-12-13T13:37:10.412Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17656329078695/TestTargetResult"; [2025-12-13T13:37:10.412Z] [2025-12-13T13:37:10.412Z] TEST SETUP: [2025-12-13T13:37:10.412Z] Nothing to be done for setup. [2025-12-13T13:37:10.412Z] [2025-12-13T13:37:10.412Z] TESTING: [2025-12-13T13:37:12.042Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-13T13:37:12.042Z] 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_17656329078695/renaissance-als_0/launcher-133710-8656367879437094244/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-13T13:37:12.042Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-13T13:37:12.042Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-13T13:37:38.068Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-12-13T13:37:42.664Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-13T13:37:42.664Z] GC before operation: completed in 62.253 ms, heap usage 311.392 MB -> 34.742 MB. [2025-12-13T13:38:05.049Z] ====== als (apache-spark) [default], iteration 0 completed (21786.479 ms) ====== [2025-12-13T13:38:05.049Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-13T13:38:05.049Z] GC before operation: completed in 119.648 ms, heap usage 602.006 MB -> 62.020 MB. [2025-12-13T13:38:19.211Z] ====== als (apache-spark) [default], iteration 1 completed (13197.375 ms) ====== [2025-12-13T13:38:19.211Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-13T13:38:19.211Z] GC before operation: completed in 119.472 ms, heap usage 174.316 MB -> 62.094 MB. [2025-12-13T13:38:33.201Z] ====== als (apache-spark) [default], iteration 2 completed (13347.532 ms) ====== [2025-12-13T13:38:33.201Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-13T13:38:33.201Z] GC before operation: completed in 128.787 ms, heap usage 309.286 MB -> 62.865 MB. [2025-12-13T13:38:47.191Z] ====== als (apache-spark) [default], iteration 3 completed (13544.176 ms) ====== [2025-12-13T13:38:47.191Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-13T13:38:47.191Z] GC before operation: completed in 116.144 ms, heap usage 1.313 GB -> 64.774 MB. [2025-12-13T13:39:01.224Z] ====== als (apache-spark) [default], iteration 4 completed (14968.977 ms) ====== [2025-12-13T13:39:01.224Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-13T13:39:01.224Z] GC before operation: completed in 117.443 ms, heap usage 1.573 GB -> 65.641 MB. [2025-12-13T13:39:17.612Z] ====== als (apache-spark) [default], iteration 5 completed (14360.720 ms) ====== [2025-12-13T13:39:17.612Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-13T13:39:17.612Z] GC before operation: completed in 112.538 ms, heap usage 512.853 MB -> 65.178 MB. [2025-12-13T13:39:29.544Z] ====== als (apache-spark) [default], iteration 6 completed (13611.376 ms) ====== [2025-12-13T13:39:29.544Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-13T13:39:30.326Z] GC before operation: completed in 131.200 ms, heap usage 906.984 MB -> 66.395 MB. [2025-12-13T13:39:44.838Z] ====== als (apache-spark) [default], iteration 7 completed (13481.427 ms) ====== [2025-12-13T13:39:44.838Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-13T13:39:44.838Z] GC before operation: completed in 113.213 ms, heap usage 138.664 MB -> 65.657 MB. [2025-12-13T13:39:58.754Z] ====== als (apache-spark) [default], iteration 8 completed (13745.051 ms) ====== [2025-12-13T13:39:58.754Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-13T13:39:58.754Z] GC before operation: completed in 122.240 ms, heap usage 315.613 MB -> 66.204 MB. [2025-12-13T13:40:12.983Z] ====== als (apache-spark) [default], iteration 9 completed (14081.668 ms) ====== [2025-12-13T13:40:12.983Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-13T13:40:12.983Z] GC before operation: completed in 108.843 ms, heap usage 540.188 MB -> 66.894 MB. [2025-12-13T13:40:27.125Z] ====== als (apache-spark) [default], iteration 10 completed (13500.440 ms) ====== [2025-12-13T13:40:27.125Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-13T13:40:27.125Z] GC before operation: completed in 114.259 ms, heap usage 589.815 MB -> 67.549 MB. [2025-12-13T13:40:41.118Z] ====== als (apache-spark) [default], iteration 11 completed (14030.340 ms) ====== [2025-12-13T13:40:41.118Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-13T13:40:41.118Z] GC before operation: completed in 121.223 ms, heap usage 843.258 MB -> 67.979 MB. [2025-12-13T13:40:55.173Z] ====== als (apache-spark) [default], iteration 12 completed (13480.975 ms) ====== [2025-12-13T13:40:55.173Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-13T13:40:55.173Z] GC before operation: completed in 98.551 ms, heap usage 857.431 MB -> 68.353 MB. [2025-12-13T13:41:09.816Z] ====== als (apache-spark) [default], iteration 13 completed (13893.769 ms) ====== [2025-12-13T13:41:09.816Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-13T13:41:09.816Z] GC before operation: completed in 98.435 ms, heap usage 132.169 MB -> 67.236 MB. [2025-12-13T13:41:23.706Z] ====== als (apache-spark) [default], iteration 14 completed (14613.198 ms) ====== [2025-12-13T13:41:23.706Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-13T13:41:23.706Z] GC before operation: completed in 123.107 ms, heap usage 702.553 MB -> 68.648 MB. [2025-12-13T13:41:37.710Z] ====== als (apache-spark) [default], iteration 15 completed (13120.877 ms) ====== [2025-12-13T13:41:37.710Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-13T13:41:37.710Z] GC before operation: completed in 112.946 ms, heap usage 625.830 MB -> 68.702 MB. [2025-12-13T13:41:51.705Z] ====== als (apache-spark) [default], iteration 16 completed (13362.767 ms) ====== [2025-12-13T13:41:51.705Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-13T13:41:51.705Z] GC before operation: completed in 100.716 ms, heap usage 298.299 MB -> 68.795 MB. [2025-12-13T13:42:03.742Z] ====== als (apache-spark) [default], iteration 17 completed (12606.885 ms) ====== [2025-12-13T13:42:03.742Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-13T13:42:03.742Z] GC before operation: completed in 131.256 ms, heap usage 530.532 MB -> 69.159 MB. [2025-12-13T13:42:17.744Z] ====== als (apache-spark) [default], iteration 18 completed (13199.901 ms) ====== [2025-12-13T13:42:17.744Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-13T13:42:17.744Z] GC before operation: completed in 100.322 ms, heap usage 810.979 MB -> 70.038 MB. [2025-12-13T13:42:31.887Z] ====== als (apache-spark) [default], iteration 19 completed (14512.142 ms) ====== [2025-12-13T13:42:31.887Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-13T13:42:31.887Z] GC before operation: completed in 109.818 ms, heap usage 552.398 MB -> 69.814 MB. [2025-12-13T13:42:46.230Z] ====== als (apache-spark) [default], iteration 20 completed (13385.104 ms) ====== [2025-12-13T13:42:46.231Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-13T13:42:46.231Z] GC before operation: completed in 101.694 ms, heap usage 914.649 MB -> 70.611 MB. [2025-12-13T13:42:58.092Z] ====== als (apache-spark) [default], iteration 21 completed (13044.802 ms) ====== [2025-12-13T13:42:58.092Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-13T13:42:58.894Z] GC before operation: completed in 116.566 ms, heap usage 723.892 MB -> 70.583 MB. [2025-12-13T13:43:12.871Z] ====== als (apache-spark) [default], iteration 22 completed (13666.886 ms) ====== [2025-12-13T13:43:12.871Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-13T13:43:12.871Z] GC before operation: completed in 129.007 ms, heap usage 253.532 MB -> 70.178 MB. [2025-12-13T13:43:26.823Z] ====== als (apache-spark) [default], iteration 23 completed (13035.180 ms) ====== [2025-12-13T13:43:26.823Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-13T13:43:26.823Z] GC before operation: completed in 109.513 ms, heap usage 531.521 MB -> 70.728 MB. [2025-12-13T13:43:40.725Z] ====== als (apache-spark) [default], iteration 24 completed (14970.697 ms) ====== [2025-12-13T13:43:40.725Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-13T13:43:41.535Z] GC before operation: completed in 113.358 ms, heap usage 209.003 MB -> 70.543 MB. [2025-12-13T13:43:55.425Z] ====== als (apache-spark) [default], iteration 25 completed (13305.858 ms) ====== [2025-12-13T13:43:55.425Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-13T13:43:55.425Z] GC before operation: completed in 129.394 ms, heap usage 517.848 MB -> 71.409 MB. [2025-12-13T13:44:09.378Z] ====== als (apache-spark) [default], iteration 26 completed (13957.813 ms) ====== [2025-12-13T13:44:09.378Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-13T13:44:09.378Z] GC before operation: completed in 117.713 ms, heap usage 568.233 MB -> 71.667 MB. [2025-12-13T13:44:23.560Z] ====== als (apache-spark) [default], iteration 27 completed (13383.754 ms) ====== [2025-12-13T13:44:23.560Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-13T13:44:23.560Z] GC before operation: completed in 105.711 ms, heap usage 645.691 MB -> 71.368 MB. [2025-12-13T13:44:37.518Z] ====== als (apache-spark) [default], iteration 28 completed (14063.516 ms) ====== [2025-12-13T13:44:37.518Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-13T13:44:37.518Z] GC before operation: completed in 120.639 ms, heap usage 297.781 MB -> 71.284 MB. [2025-12-13T13:44:51.437Z] ====== als (apache-spark) [default], iteration 29 completed (13924.682 ms) ====== [2025-12-13T13:44:51.437Z] ----------------------------------- [2025-12-13T13:44:51.437Z] renaissance-als_0_PASSED [2025-12-13T13:44:51.437Z] ----------------------------------- [2025-12-13T13:44:51.437Z] [2025-12-13T13:44:51.437Z] TEST TEARDOWN: [2025-12-13T13:44:51.437Z] Nothing to be done for teardown. [2025-12-13T13:44:51.437Z] renaissance-als_0 Finish Time: Sat Dec 13 13:44:51 2025 Epoch Time (ms): 1765633491199