[2025-06-07T22:16:42.074Z] Running test renaissance-als_0 ... [2025-06-07T22:16:42.074Z] =============================================== [2025-06-07T22:16:42.074Z] renaissance-als_0 Start Time: Sat Jun 7 22:16:41 2025 Epoch Time (ms): 1749334601953 [2025-06-07T22:16:42.074Z] variation: NoOptions [2025-06-07T22:16:42.074Z] JVM_OPTIONS: [2025-06-07T22:16:42.074Z] { \ [2025-06-07T22:16:42.074Z] echo ""; echo "TEST SETUP:"; \ [2025-06-07T22:16:42.074Z] echo "Nothing to be done for setup."; \ [2025-06-07T22:16:42.074Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_174933460179/renaissance-als_0"; \ [2025-06-07T22:16:42.074Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_174933460179/renaissance-als_0"; \ [2025-06-07T22:16:42.074Z] echo ""; echo "TESTING:"; \ [2025-06-07T22:16:42.074Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/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_ppc64le_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_174933460179/renaissance-als_0"/als.json" als; \ [2025-06-07T22:16:42.074Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_174933460179/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-07T22:16:42.074Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-07T22:16:42.074Z] echo "Nothing to be done for teardown."; \ [2025-06-07T22:16:42.074Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_174933460179/TestTargetResult"; [2025-06-07T22:16:42.074Z] [2025-06-07T22:16:42.074Z] TEST SETUP: [2025-06-07T22:16:42.074Z] Nothing to be done for setup. [2025-06-07T22:16:42.074Z] [2025-06-07T22:16:42.074Z] TESTING: [2025-06-07T22:16:43.017Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-06-07T22:16:43.017Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/output_174933460179/renaissance-als_0/launcher-221642-14126499094989767483/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-06-07T22:16:43.017Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-06-07T22:16:43.017Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-06-07T22:16:48.345Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-07T22:16:52.490Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-07T22:16:52.490Z] GC before operation: completed in 61.709 ms, heap usage 322.362 MB -> 35.161 MB. [2025-06-07T22:17:10.687Z] ====== als (apache-spark) [default], iteration 0 completed (16644.188 ms) ====== [2025-06-07T22:17:10.687Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-07T22:17:10.687Z] GC before operation: completed in 105.910 ms, heap usage 177.826 MB -> 62.047 MB. [2025-06-07T22:17:17.334Z] ====== als (apache-spark) [default], iteration 1 completed (7504.600 ms) ====== [2025-06-07T22:17:17.334Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-07T22:17:17.334Z] GC before operation: completed in 90.187 ms, heap usage 196.067 MB -> 62.845 MB. [2025-06-07T22:17:25.416Z] ====== als (apache-spark) [default], iteration 2 completed (7032.556 ms) ====== [2025-06-07T22:17:25.416Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-07T22:17:25.416Z] GC before operation: completed in 97.807 ms, heap usage 120.520 MB -> 63.271 MB. [2025-06-07T22:17:32.059Z] ====== als (apache-spark) [default], iteration 3 completed (7055.324 ms) ====== [2025-06-07T22:17:32.059Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-07T22:17:32.059Z] GC before operation: completed in 90.685 ms, heap usage 435.668 MB -> 64.315 MB. [2025-06-07T22:17:40.088Z] ====== als (apache-spark) [default], iteration 4 completed (6907.657 ms) ====== [2025-06-07T22:17:40.088Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-07T22:17:40.088Z] GC before operation: completed in 85.809 ms, heap usage 102.523 MB -> 64.598 MB. [2025-06-07T22:17:46.736Z] ====== als (apache-spark) [default], iteration 5 completed (7041.088 ms) ====== [2025-06-07T22:17:46.736Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-07T22:17:46.736Z] GC before operation: completed in 106.452 ms, heap usage 119.972 MB -> 65.038 MB. [2025-06-07T22:17:53.390Z] ====== als (apache-spark) [default], iteration 6 completed (6822.935 ms) ====== [2025-06-07T22:17:53.390Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-07T22:17:54.342Z] GC before operation: completed in 87.924 ms, heap usage 294.659 MB -> 65.869 MB. [2025-06-07T22:18:00.980Z] ====== als (apache-spark) [default], iteration 7 completed (6830.677 ms) ====== [2025-06-07T22:18:00.980Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-07T22:18:00.980Z] GC before operation: completed in 102.668 ms, heap usage 225.974 MB -> 66.215 MB. [2025-06-07T22:18:09.055Z] ====== als (apache-spark) [default], iteration 8 completed (6747.622 ms) ====== [2025-06-07T22:18:09.055Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-07T22:18:09.055Z] GC before operation: completed in 96.072 ms, heap usage 269.112 MB -> 66.759 MB. [2025-06-07T22:18:15.721Z] ====== als (apache-spark) [default], iteration 9 completed (6795.593 ms) ====== [2025-06-07T22:18:15.721Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-07T22:18:15.721Z] GC before operation: completed in 101.250 ms, heap usage 198.991 MB -> 67.355 MB. [2025-06-07T22:18:22.369Z] ====== als (apache-spark) [default], iteration 10 completed (6821.787 ms) ====== [2025-06-07T22:18:22.369Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-07T22:18:22.369Z] GC before operation: completed in 100.535 ms, heap usage 362.500 MB -> 67.300 MB. [2025-06-07T22:18:29.008Z] ====== als (apache-spark) [default], iteration 11 completed (6729.238 ms) ====== [2025-06-07T22:18:29.008Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-07T22:18:29.951Z] GC before operation: completed in 106.394 ms, heap usage 388.564 MB -> 67.616 MB. [2025-06-07T22:18:36.669Z] ====== als (apache-spark) [default], iteration 12 completed (6820.871 ms) ====== [2025-06-07T22:18:36.669Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-07T22:18:36.669Z] GC before operation: completed in 101.020 ms, heap usage 194.890 MB -> 67.699 MB. [2025-06-07T22:18:43.308Z] ====== als (apache-spark) [default], iteration 13 completed (6665.027 ms) ====== [2025-06-07T22:18:43.308Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-07T22:18:43.308Z] GC before operation: completed in 116.037 ms, heap usage 109.579 MB -> 67.947 MB. [2025-06-07T22:18:51.400Z] ====== als (apache-spark) [default], iteration 14 completed (6680.227 ms) ====== [2025-06-07T22:18:51.400Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-07T22:18:51.400Z] GC before operation: completed in 109.646 ms, heap usage 402.727 MB -> 68.463 MB. [2025-06-07T22:18:58.048Z] ====== als (apache-spark) [default], iteration 15 completed (6549.147 ms) ====== [2025-06-07T22:18:58.048Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-07T22:18:58.048Z] GC before operation: completed in 105.957 ms, heap usage 257.927 MB -> 68.596 MB. [2025-06-07T22:19:04.697Z] ====== als (apache-spark) [default], iteration 16 completed (6724.682 ms) ====== [2025-06-07T22:19:04.697Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-07T22:19:04.697Z] GC before operation: completed in 109.500 ms, heap usage 178.601 MB -> 68.748 MB. [2025-06-07T22:19:12.423Z] ====== als (apache-spark) [default], iteration 17 completed (6697.956 ms) ====== [2025-06-07T22:19:12.423Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-07T22:19:12.423Z] GC before operation: completed in 109.061 ms, heap usage 473.988 MB -> 69.507 MB. [2025-06-07T22:19:19.064Z] ====== als (apache-spark) [default], iteration 18 completed (6585.895 ms) ====== [2025-06-07T22:19:19.064Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-07T22:19:19.064Z] GC before operation: completed in 114.685 ms, heap usage 199.826 MB -> 69.294 MB. [2025-06-07T22:19:25.704Z] ====== als (apache-spark) [default], iteration 19 completed (6811.894 ms) ====== [2025-06-07T22:19:25.704Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-07T22:19:25.704Z] GC before operation: completed in 98.793 ms, heap usage 358.321 MB -> 69.761 MB. [2025-06-07T22:19:32.342Z] ====== als (apache-spark) [default], iteration 20 completed (6469.636 ms) ====== [2025-06-07T22:19:32.342Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-07T22:19:32.342Z] GC before operation: completed in 102.229 ms, heap usage 279.834 MB -> 69.938 MB. [2025-06-07T22:19:38.995Z] ====== als (apache-spark) [default], iteration 21 completed (6539.270 ms) ====== [2025-06-07T22:19:38.995Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-07T22:19:38.995Z] GC before operation: completed in 105.528 ms, heap usage 500.730 MB -> 70.493 MB. [2025-06-07T22:19:45.634Z] ====== als (apache-spark) [default], iteration 22 completed (6578.807 ms) ====== [2025-06-07T22:19:45.634Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-07T22:19:46.578Z] GC before operation: completed in 112.113 ms, heap usage 479.325 MB -> 70.755 MB. [2025-06-07T22:19:53.244Z] ====== als (apache-spark) [default], iteration 23 completed (6499.821 ms) ====== [2025-06-07T22:19:53.244Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-07T22:19:53.244Z] GC before operation: completed in 111.700 ms, heap usage 520.536 MB -> 71.037 MB. [2025-06-07T22:19:59.890Z] ====== als (apache-spark) [default], iteration 24 completed (6573.218 ms) ====== [2025-06-07T22:19:59.891Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-07T22:19:59.891Z] GC before operation: completed in 129.097 ms, heap usage 260.095 MB -> 70.988 MB. [2025-06-07T22:20:06.532Z] ====== als (apache-spark) [default], iteration 25 completed (6534.896 ms) ====== [2025-06-07T22:20:06.532Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-07T22:20:06.532Z] GC before operation: completed in 116.973 ms, heap usage 493.748 MB -> 71.554 MB. [2025-06-07T22:20:13.172Z] ====== als (apache-spark) [default], iteration 26 completed (6457.888 ms) ====== [2025-06-07T22:20:13.172Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-07T22:20:13.172Z] GC before operation: completed in 115.517 ms, heap usage 391.978 MB -> 71.566 MB. [2025-06-07T22:20:19.812Z] ====== als (apache-spark) [default], iteration 27 completed (6490.993 ms) ====== [2025-06-07T22:20:19.812Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-07T22:20:19.812Z] GC before operation: completed in 89.707 ms, heap usage 258.684 MB -> 71.753 MB. [2025-06-07T22:20:27.894Z] ====== als (apache-spark) [default], iteration 28 completed (6535.243 ms) ====== [2025-06-07T22:20:27.894Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-07T22:20:27.894Z] GC before operation: completed in 82.489 ms, heap usage 288.947 MB -> 71.544 MB. [2025-06-07T22:20:34.592Z] ====== als (apache-spark) [default], iteration 29 completed (6565.081 ms) ====== [2025-06-07T22:20:34.592Z] ----------------------------------- [2025-06-07T22:20:34.592Z] renaissance-als_0_PASSED [2025-06-07T22:20:34.592Z] ----------------------------------- [2025-06-07T22:20:34.592Z] [2025-06-07T22:20:34.592Z] TEST TEARDOWN: [2025-06-07T22:20:34.592Z] Nothing to be done for teardown. [2025-06-07T22:20:34.592Z] renaissance-als_0 Finish Time: Sat Jun 7 22:20:33 2025 Epoch Time (ms): 1749334833779