[2025-12-27T15:45:20.324Z] Running test renaissance-als_0 ... [2025-12-27T15:45:20.324Z] =============================================== [2025-12-27T15:45:20.324Z] renaissance-als_0 Start Time: Sat Dec 27 15:45:20 2025 Epoch Time (ms): 1766850320168 [2025-12-27T15:45:20.324Z] variation: NoOptions [2025-12-27T15:45:20.324Z] JVM_OPTIONS: [2025-12-27T15:45:20.324Z] { \ [2025-12-27T15:45:20.324Z] echo ""; echo "TEST SETUP:"; \ [2025-12-27T15:45:20.324Z] echo "Nothing to be done for setup."; \ [2025-12-27T15:45:20.324Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17668502117033/renaissance-als_0"; \ [2025-12-27T15:45:20.324Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17668502117033/renaissance-als_0"; \ [2025-12-27T15:45:20.324Z] echo ""; echo "TESTING:"; \ [2025-12-27T15:45:20.324Z] "/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_17668502117033/renaissance-als_0"/als.json" als; \ [2025-12-27T15:45:20.324Z] 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_17668502117033/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-27T15:45:20.324Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-27T15:45:20.324Z] echo "Nothing to be done for teardown."; \ [2025-12-27T15:45:20.324Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17668502117033/TestTargetResult"; [2025-12-27T15:45:20.324Z] [2025-12-27T15:45:20.324Z] TEST SETUP: [2025-12-27T15:45:20.325Z] Nothing to be done for setup. [2025-12-27T15:45:20.325Z] [2025-12-27T15:45:20.325Z] TESTING: [2025-12-27T15:45:21.949Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-27T15:45:21.949Z] 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_17668502117033/renaissance-als_0/launcher-154520-9792426140505491558/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-27T15:45:21.949Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-27T15:45:21.949Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-27T15:45:48.052Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-12-27T15:45:53.764Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-27T15:45:53.764Z] GC before operation: completed in 54.685 ms, heap usage 269.692 MB -> 34.536 MB. [2025-12-27T15:46:16.105Z] ====== als (apache-spark) [default], iteration 0 completed (20399.889 ms) ====== [2025-12-27T15:46:16.105Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-27T15:46:16.106Z] GC before operation: completed in 116.053 ms, heap usage 204.877 MB -> 61.340 MB. [2025-12-27T15:46:30.075Z] ====== als (apache-spark) [default], iteration 1 completed (15777.803 ms) ====== [2025-12-27T15:46:30.075Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-27T15:46:30.861Z] GC before operation: completed in 123.952 ms, heap usage 592.933 MB -> 62.626 MB. [2025-12-27T15:46:44.818Z] ====== als (apache-spark) [default], iteration 2 completed (13978.629 ms) ====== [2025-12-27T15:46:44.818Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-27T15:46:44.818Z] GC before operation: completed in 114.040 ms, heap usage 246.045 MB -> 62.739 MB. [2025-12-27T15:46:58.807Z] ====== als (apache-spark) [default], iteration 3 completed (13557.592 ms) ====== [2025-12-27T15:46:58.807Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-27T15:46:58.807Z] GC before operation: completed in 122.953 ms, heap usage 387.726 MB -> 63.657 MB. [2025-12-27T15:47:12.842Z] ====== als (apache-spark) [default], iteration 4 completed (13595.715 ms) ====== [2025-12-27T15:47:12.842Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-27T15:47:12.842Z] GC before operation: completed in 108.568 ms, heap usage 484.087 MB -> 64.312 MB. [2025-12-27T15:47:26.828Z] ====== als (apache-spark) [default], iteration 5 completed (13583.382 ms) ====== [2025-12-27T15:47:26.828Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-27T15:47:26.828Z] GC before operation: completed in 115.606 ms, heap usage 235.865 MB -> 64.516 MB. [2025-12-27T15:47:43.153Z] ====== als (apache-spark) [default], iteration 6 completed (15681.767 ms) ====== [2025-12-27T15:47:43.153Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-27T15:47:43.153Z] GC before operation: completed in 114.634 ms, heap usage 509.575 MB -> 65.341 MB. [2025-12-27T15:47:57.144Z] ====== als (apache-spark) [default], iteration 7 completed (13867.780 ms) ====== [2025-12-27T15:47:57.144Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-27T15:47:57.144Z] GC before operation: completed in 110.141 ms, heap usage 305.313 MB -> 65.548 MB. [2025-12-27T15:48:11.227Z] ====== als (apache-spark) [default], iteration 8 completed (13502.933 ms) ====== [2025-12-27T15:48:11.227Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-27T15:48:11.227Z] GC before operation: completed in 120.725 ms, heap usage 919.359 MB -> 67.011 MB. [2025-12-27T15:48:25.169Z] ====== als (apache-spark) [default], iteration 9 completed (13123.682 ms) ====== [2025-12-27T15:48:25.169Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-27T15:48:25.169Z] GC before operation: completed in 98.081 ms, heap usage 612.672 MB -> 67.420 MB. [2025-12-27T15:48:39.359Z] ====== als (apache-spark) [default], iteration 10 completed (14020.835 ms) ====== [2025-12-27T15:48:39.359Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-27T15:48:39.359Z] GC before operation: completed in 120.168 ms, heap usage 333.030 MB -> 66.834 MB. [2025-12-27T15:48:53.416Z] ====== als (apache-spark) [default], iteration 11 completed (15004.725 ms) ====== [2025-12-27T15:48:53.416Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-27T15:48:53.416Z] GC before operation: completed in 113.154 ms, heap usage 972.622 MB -> 67.974 MB. [2025-12-27T15:49:09.916Z] ====== als (apache-spark) [default], iteration 12 completed (14098.272 ms) ====== [2025-12-27T15:49:09.916Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-27T15:49:09.916Z] GC before operation: completed in 98.947 ms, heap usage 423.698 MB -> 67.599 MB. [2025-12-27T15:49:21.818Z] ====== als (apache-spark) [default], iteration 13 completed (12901.229 ms) ====== [2025-12-27T15:49:21.818Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-27T15:49:21.818Z] GC before operation: completed in 111.677 ms, heap usage 1.733 GB -> 69.306 MB. [2025-12-27T15:49:35.808Z] ====== als (apache-spark) [default], iteration 14 completed (13800.413 ms) ====== [2025-12-27T15:49:35.808Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-27T15:49:35.808Z] GC before operation: completed in 107.981 ms, heap usage 1.027 GB -> 68.771 MB. [2025-12-27T15:49:49.764Z] ====== als (apache-spark) [default], iteration 15 completed (14184.283 ms) ====== [2025-12-27T15:49:49.764Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-27T15:49:49.764Z] GC before operation: completed in 90.244 ms, heap usage 698.260 MB -> 68.784 MB. [2025-12-27T15:50:06.187Z] ====== als (apache-spark) [default], iteration 16 completed (14741.076 ms) ====== [2025-12-27T15:50:06.187Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-27T15:50:06.187Z] GC before operation: completed in 100.948 ms, heap usage 880.847 MB -> 69.081 MB. [2025-12-27T15:50:18.220Z] ====== als (apache-spark) [default], iteration 17 completed (12455.711 ms) ====== [2025-12-27T15:50:18.220Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-27T15:50:18.220Z] GC before operation: completed in 113.517 ms, heap usage 625.044 MB -> 69.186 MB. [2025-12-27T15:50:32.212Z] ====== als (apache-spark) [default], iteration 18 completed (13355.629 ms) ====== [2025-12-27T15:50:32.212Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-27T15:50:32.212Z] GC before operation: completed in 104.771 ms, heap usage 1.619 GB -> 70.476 MB. [2025-12-27T15:50:44.111Z] ====== als (apache-spark) [default], iteration 19 completed (12853.588 ms) ====== [2025-12-27T15:50:44.111Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-27T15:50:44.111Z] GC before operation: completed in 96.843 ms, heap usage 788.196 MB -> 69.742 MB. [2025-12-27T15:51:00.471Z] ====== als (apache-spark) [default], iteration 20 completed (14129.518 ms) ====== [2025-12-27T15:51:00.471Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-27T15:51:00.471Z] GC before operation: completed in 116.892 ms, heap usage 1.036 GB -> 70.286 MB. [2025-12-27T15:51:12.371Z] ====== als (apache-spark) [default], iteration 21 completed (13234.492 ms) ====== [2025-12-27T15:51:12.371Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-27T15:51:12.371Z] GC before operation: completed in 95.437 ms, heap usage 200.108 MB -> 69.430 MB. [2025-12-27T15:51:26.399Z] ====== als (apache-spark) [default], iteration 22 completed (13115.064 ms) ====== [2025-12-27T15:51:26.399Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-27T15:51:26.399Z] GC before operation: completed in 118.969 ms, heap usage 316.267 MB -> 69.758 MB. [2025-12-27T15:51:40.350Z] ====== als (apache-spark) [default], iteration 23 completed (13397.471 ms) ====== [2025-12-27T15:51:40.350Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-27T15:51:40.350Z] GC before operation: completed in 98.846 ms, heap usage 594.885 MB -> 70.438 MB. [2025-12-27T15:51:54.321Z] ====== als (apache-spark) [default], iteration 24 completed (13832.403 ms) ====== [2025-12-27T15:51:54.321Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-27T15:51:54.321Z] GC before operation: completed in 95.844 ms, heap usage 404.428 MB -> 70.821 MB. [2025-12-27T15:52:08.292Z] ====== als (apache-spark) [default], iteration 25 completed (14308.328 ms) ====== [2025-12-27T15:52:08.292Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-27T15:52:08.292Z] GC before operation: completed in 113.469 ms, heap usage 499.793 MB -> 70.920 MB. [2025-12-27T15:52:24.648Z] ====== als (apache-spark) [default], iteration 26 completed (14540.094 ms) ====== [2025-12-27T15:52:24.648Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-27T15:52:24.648Z] GC before operation: completed in 129.346 ms, heap usage 856.497 MB -> 71.584 MB. [2025-12-27T15:52:36.524Z] ====== als (apache-spark) [default], iteration 27 completed (13379.295 ms) ====== [2025-12-27T15:52:36.524Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-27T15:52:36.524Z] GC before operation: completed in 119.228 ms, heap usage 308.276 MB -> 70.730 MB. [2025-12-27T15:52:50.505Z] ====== als (apache-spark) [default], iteration 28 completed (13318.887 ms) ====== [2025-12-27T15:52:50.505Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-27T15:52:50.505Z] GC before operation: completed in 96.931 ms, heap usage 730.692 MB -> 71.627 MB. [2025-12-27T15:53:04.531Z] ====== als (apache-spark) [default], iteration 29 completed (13554.800 ms) ====== [2025-12-27T15:53:04.531Z] ----------------------------------- [2025-12-27T15:53:04.531Z] renaissance-als_0_PASSED [2025-12-27T15:53:04.531Z] ----------------------------------- [2025-12-27T15:53:04.531Z] [2025-12-27T15:53:04.531Z] TEST TEARDOWN: [2025-12-27T15:53:04.531Z] Nothing to be done for teardown. [2025-12-27T15:53:04.531Z] renaissance-als_0 Finish Time: Sat Dec 27 15:53:04 2025 Epoch Time (ms): 1766850784015