[2025-09-07T06:31:38.180Z] Running test renaissance-als_0 ... [2025-09-07T06:31:38.180Z] =============================================== [2025-09-07T06:31:38.180Z] renaissance-als_0 Start Time: Sun Sep 7 06:31:37 2025 Epoch Time (ms): 1757226697904 [2025-09-07T06:31:38.180Z] variation: NoOptions [2025-09-07T06:31:38.180Z] JVM_OPTIONS: [2025-09-07T06:31:38.180Z] { \ [2025-09-07T06:31:38.180Z] echo ""; echo "TEST SETUP:"; \ [2025-09-07T06:31:38.180Z] echo "Nothing to be done for setup."; \ [2025-09-07T06:31:38.180Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17572265733309/renaissance-als_0"; \ [2025-09-07T06:31:38.180Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17572265733309/renaissance-als_0"; \ [2025-09-07T06:31:38.180Z] echo ""; echo "TESTING:"; \ [2025-09-07T06:31:38.180Z] "/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_17572265733309/renaissance-als_0"/als.json" als; \ [2025-09-07T06:31:38.180Z] 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_17572265733309/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-07T06:31:38.180Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-07T06:31:38.180Z] echo "Nothing to be done for teardown."; \ [2025-09-07T06:31:38.180Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17572265733309/TestTargetResult"; [2025-09-07T06:31:38.180Z] [2025-09-07T06:31:38.180Z] TEST SETUP: [2025-09-07T06:31:38.180Z] Nothing to be done for setup. [2025-09-07T06:31:38.180Z] [2025-09-07T06:31:38.180Z] TESTING: [2025-09-07T06:31:39.780Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-09-07T06:31:39.780Z] 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_17572265733309/renaissance-als_0/launcher-063138-17314491267018120910/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-09-07T06:31:39.780Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-09-07T06:31:39.780Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-09-07T06:32:11.579Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-09-07T06:32:17.208Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-07T06:32:17.208Z] GC before operation: completed in 67.659 ms, heap usage 659.118 MB -> 34.386 MB. [2025-09-07T06:32:43.099Z] ====== als (apache-spark) [default], iteration 0 completed (23909.172 ms) ====== [2025-09-07T06:32:43.099Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-07T06:32:43.099Z] GC before operation: completed in 169.032 ms, heap usage 717.753 MB -> 61.633 MB. [2025-09-07T06:32:56.917Z] ====== als (apache-spark) [default], iteration 1 completed (13023.202 ms) ====== [2025-09-07T06:32:56.917Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-07T06:32:56.917Z] GC before operation: completed in 155.725 ms, heap usage 617.022 MB -> 62.405 MB. [2025-09-07T06:33:10.815Z] ====== als (apache-spark) [default], iteration 2 completed (13644.127 ms) ====== [2025-09-07T06:33:10.815Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-07T06:33:10.815Z] GC before operation: completed in 143.997 ms, heap usage 169.211 MB -> 62.603 MB. [2025-09-07T06:33:24.645Z] ====== als (apache-spark) [default], iteration 3 completed (13564.491 ms) ====== [2025-09-07T06:33:24.645Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-07T06:33:24.645Z] GC before operation: completed in 122.638 ms, heap usage 466.249 MB -> 63.483 MB. [2025-09-07T06:33:38.511Z] ====== als (apache-spark) [default], iteration 4 completed (14889.242 ms) ====== [2025-09-07T06:33:38.511Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-07T06:33:38.511Z] GC before operation: completed in 121.984 ms, heap usage 249.120 MB -> 63.940 MB. [2025-09-07T06:33:54.700Z] ====== als (apache-spark) [default], iteration 5 completed (14562.612 ms) ====== [2025-09-07T06:33:54.700Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-07T06:33:54.700Z] GC before operation: completed in 116.267 ms, heap usage 733.753 MB -> 65.170 MB. [2025-09-07T06:34:07.036Z] ====== als (apache-spark) [default], iteration 6 completed (13680.771 ms) ====== [2025-09-07T06:34:07.036Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-07T06:34:07.814Z] GC before operation: completed in 122.523 ms, heap usage 678.949 MB -> 65.745 MB. [2025-09-07T06:34:21.662Z] ====== als (apache-spark) [default], iteration 7 completed (13878.380 ms) ====== [2025-09-07T06:34:21.662Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-07T06:34:21.662Z] GC before operation: completed in 126.913 ms, heap usage 600.447 MB -> 66.109 MB. [2025-09-07T06:34:35.639Z] ====== als (apache-spark) [default], iteration 8 completed (13482.784 ms) ====== [2025-09-07T06:34:35.639Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-07T06:34:35.639Z] GC before operation: completed in 145.533 ms, heap usage 2.332 GB -> 68.338 MB. [2025-09-07T06:34:51.807Z] ====== als (apache-spark) [default], iteration 9 completed (15514.572 ms) ====== [2025-09-07T06:34:51.807Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-07T06:34:51.807Z] GC before operation: completed in 141.758 ms, heap usage 803.766 MB -> 67.278 MB. [2025-09-07T06:35:05.644Z] ====== als (apache-spark) [default], iteration 10 completed (14047.773 ms) ====== [2025-09-07T06:35:05.644Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-07T06:35:05.644Z] GC before operation: completed in 154.284 ms, heap usage 203.536 MB -> 66.519 MB. [2025-09-07T06:35:19.445Z] ====== als (apache-spark) [default], iteration 11 completed (13665.821 ms) ====== [2025-09-07T06:35:19.445Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-07T06:35:20.233Z] GC before operation: completed in 126.533 ms, heap usage 817.547 MB -> 67.709 MB. [2025-09-07T06:35:34.057Z] ====== als (apache-spark) [default], iteration 12 completed (13827.143 ms) ====== [2025-09-07T06:35:34.057Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-07T06:35:34.057Z] GC before operation: completed in 146.209 ms, heap usage 651.660 MB -> 67.635 MB. [2025-09-07T06:35:47.874Z] ====== als (apache-spark) [default], iteration 13 completed (13856.105 ms) ====== [2025-09-07T06:35:47.874Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-07T06:35:47.874Z] GC before operation: completed in 124.856 ms, heap usage 865.168 MB -> 68.217 MB. [2025-09-07T06:36:04.466Z] ====== als (apache-spark) [default], iteration 14 completed (15899.915 ms) ====== [2025-09-07T06:36:04.466Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-07T06:36:04.466Z] GC before operation: completed in 135.349 ms, heap usage 358.415 MB -> 67.767 MB. [2025-09-07T06:36:20.659Z] ====== als (apache-spark) [default], iteration 15 completed (16205.314 ms) ====== [2025-09-07T06:36:20.659Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-07T06:36:21.439Z] GC before operation: completed in 145.377 ms, heap usage 453.838 MB -> 68.273 MB. [2025-09-07T06:36:35.282Z] ====== als (apache-spark) [default], iteration 16 completed (13900.756 ms) ====== [2025-09-07T06:36:35.282Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-07T06:36:35.282Z] GC before operation: completed in 146.221 ms, heap usage 685.688 MB -> 68.694 MB. [2025-09-07T06:36:51.518Z] ====== als (apache-spark) [default], iteration 17 completed (14769.005 ms) ====== [2025-09-07T06:36:51.518Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-07T06:36:51.518Z] GC before operation: completed in 150.438 ms, heap usage 706.924 MB -> 69.085 MB. [2025-09-07T06:37:05.340Z] ====== als (apache-spark) [default], iteration 18 completed (13864.457 ms) ====== [2025-09-07T06:37:05.340Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-07T06:37:05.340Z] GC before operation: completed in 154.634 ms, heap usage 864.002 MB -> 69.462 MB. [2025-09-07T06:37:21.556Z] ====== als (apache-spark) [default], iteration 19 completed (16736.298 ms) ====== [2025-09-07T06:37:21.556Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-07T06:37:21.556Z] GC before operation: completed in 140.447 ms, heap usage 174.319 MB -> 68.779 MB. [2025-09-07T06:37:35.392Z] ====== als (apache-spark) [default], iteration 20 completed (13477.127 ms) ====== [2025-09-07T06:37:35.392Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-07T06:37:35.392Z] GC before operation: completed in 145.975 ms, heap usage 124.743 MB -> 69.033 MB. [2025-09-07T06:37:49.272Z] ====== als (apache-spark) [default], iteration 21 completed (13798.014 ms) ====== [2025-09-07T06:37:49.272Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-07T06:37:49.272Z] GC before operation: completed in 144.746 ms, heap usage 774.373 MB -> 70.026 MB. [2025-09-07T06:38:03.523Z] ====== als (apache-spark) [default], iteration 22 completed (13655.073 ms) ====== [2025-09-07T06:38:03.523Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-07T06:38:03.523Z] GC before operation: completed in 146.153 ms, heap usage 1.054 GB -> 70.838 MB. [2025-09-07T06:38:19.707Z] ====== als (apache-spark) [default], iteration 23 completed (15173.766 ms) ====== [2025-09-07T06:38:19.707Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-07T06:38:19.707Z] GC before operation: completed in 166.627 ms, heap usage 589.203 MB -> 70.480 MB. [2025-09-07T06:38:35.895Z] ====== als (apache-spark) [default], iteration 24 completed (14668.769 ms) ====== [2025-09-07T06:38:35.895Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-07T06:38:35.895Z] GC before operation: completed in 157.917 ms, heap usage 970.874 MB -> 71.287 MB. [2025-09-07T06:38:49.765Z] ====== als (apache-spark) [default], iteration 25 completed (13884.726 ms) ====== [2025-09-07T06:38:49.765Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-07T06:38:49.765Z] GC before operation: completed in 172.275 ms, heap usage 1.878 GB -> 72.543 MB. [2025-09-07T06:39:01.539Z] ====== als (apache-spark) [default], iteration 26 completed (13271.989 ms) ====== [2025-09-07T06:39:01.539Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-07T06:39:02.329Z] GC before operation: completed in 160.576 ms, heap usage 1.073 GB -> 71.888 MB. [2025-09-07T06:39:16.193Z] ====== als (apache-spark) [default], iteration 27 completed (13608.785 ms) ====== [2025-09-07T06:39:16.193Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-07T06:39:16.193Z] GC before operation: completed in 159.537 ms, heap usage 920.468 MB -> 71.951 MB. [2025-09-07T06:39:32.380Z] ====== als (apache-spark) [default], iteration 28 completed (15773.037 ms) ====== [2025-09-07T06:39:32.380Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-07T06:39:32.380Z] GC before operation: completed in 156.810 ms, heap usage 478.535 MB -> 71.600 MB. [2025-09-07T06:39:46.412Z] ====== als (apache-spark) [default], iteration 29 completed (13347.174 ms) ====== [2025-09-07T06:39:46.412Z] ----------------------------------- [2025-09-07T06:39:46.412Z] renaissance-als_0_PASSED [2025-09-07T06:39:46.412Z] ----------------------------------- [2025-09-07T06:39:46.412Z] [2025-09-07T06:39:46.412Z] TEST TEARDOWN: [2025-09-07T06:39:46.412Z] Nothing to be done for teardown. [2025-09-07T06:39:46.412Z] renaissance-als_0 Finish Time: Sun Sep 7 06:39:45 2025 Epoch Time (ms): 1757227185762