[2025-11-03T10:25:01.584Z] Running test renaissance-als_0 ... [2025-11-03T10:25:01.584Z] =============================================== [2025-11-03T10:25:01.584Z] renaissance-als_0 Start Time: Mon Nov 3 10:25:00 2025 Epoch Time (ms): 1762165500715 [2025-11-03T10:25:01.584Z] variation: NoOptions [2025-11-03T10:25:01.584Z] JVM_OPTIONS: [2025-11-03T10:25:01.584Z] { \ [2025-11-03T10:25:01.584Z] echo ""; echo "TEST SETUP:"; \ [2025-11-03T10:25:01.584Z] echo "Nothing to be done for setup."; \ [2025-11-03T10:25:01.584Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17621655009371/renaissance-als_0"; \ [2025-11-03T10:25:01.584Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17621655009371/renaissance-als_0"; \ [2025-11-03T10:25:01.584Z] echo ""; echo "TESTING:"; \ [2025-11-03T10:25:01.584Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/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_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17621655009371/renaissance-als_0"/als.json" als; \ [2025-11-03T10:25:01.584Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17621655009371/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-03T10:25:01.584Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-03T10:25:01.584Z] echo "Nothing to be done for teardown."; \ [2025-11-03T10:25:01.584Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17621655009371/TestTargetResult"; [2025-11-03T10:25:01.584Z] [2025-11-03T10:25:01.584Z] TEST SETUP: [2025-11-03T10:25:01.584Z] Nothing to be done for setup. [2025-11-03T10:25:01.584Z] [2025-11-03T10:25:01.584Z] TESTING: [2025-11-03T10:25:02.532Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-11-03T10:25:02.532Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17621655009371/renaissance-als_0/launcher-102500-2543245585903684550/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-11-03T10:25:02.532Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-11-03T10:25:02.532Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-11-03T10:25:12.303Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-03T10:25:16.446Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-03T10:25:16.446Z] GC before operation: completed in 52.846 ms, heap usage 166.000 MB -> 35.359 MB. [2025-11-03T10:25:32.207Z] ====== als (apache-spark) [default], iteration 0 completed (15220.564 ms) ====== [2025-11-03T10:25:32.207Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-03T10:25:32.207Z] GC before operation: completed in 99.927 ms, heap usage 142.864 MB -> 61.867 MB. [2025-11-03T10:25:38.896Z] ====== als (apache-spark) [default], iteration 1 completed (5983.235 ms) ====== [2025-11-03T10:25:38.896Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-03T10:25:38.896Z] GC before operation: completed in 94.213 ms, heap usage 185.370 MB -> 62.781 MB. [2025-11-03T10:25:44.266Z] ====== als (apache-spark) [default], iteration 2 completed (5209.857 ms) ====== [2025-11-03T10:25:44.266Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-03T10:25:44.266Z] GC before operation: completed in 98.272 ms, heap usage 520.027 MB -> 63.944 MB. [2025-11-03T10:25:49.833Z] ====== als (apache-spark) [default], iteration 3 completed (5155.913 ms) ====== [2025-11-03T10:25:49.833Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-03T10:25:49.833Z] GC before operation: completed in 100.319 ms, heap usage 301.290 MB -> 64.123 MB. [2025-11-03T10:25:55.250Z] ====== als (apache-spark) [default], iteration 4 completed (5092.162 ms) ====== [2025-11-03T10:25:55.250Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-03T10:25:55.250Z] GC before operation: completed in 108.724 ms, heap usage 262.662 MB -> 64.594 MB. [2025-11-03T10:26:00.612Z] ====== als (apache-spark) [default], iteration 5 completed (4938.009 ms) ====== [2025-11-03T10:26:00.612Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-03T10:26:00.612Z] GC before operation: completed in 109.933 ms, heap usage 163.097 MB -> 65.133 MB. [2025-11-03T10:26:05.985Z] ====== als (apache-spark) [default], iteration 6 completed (5075.172 ms) ====== [2025-11-03T10:26:05.985Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-03T10:26:05.985Z] GC before operation: completed in 119.843 ms, heap usage 571.500 MB -> 66.105 MB. [2025-11-03T10:26:11.363Z] ====== als (apache-spark) [default], iteration 7 completed (4996.150 ms) ====== [2025-11-03T10:26:11.363Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-03T10:26:11.363Z] GC before operation: completed in 108.500 ms, heap usage 105.467 MB -> 66.245 MB. [2025-11-03T10:26:16.729Z] ====== als (apache-spark) [default], iteration 8 completed (5093.033 ms) ====== [2025-11-03T10:26:16.729Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-03T10:26:16.729Z] GC before operation: completed in 109.634 ms, heap usage 352.416 MB -> 67.031 MB. [2025-11-03T10:26:21.566Z] ====== als (apache-spark) [default], iteration 9 completed (4779.203 ms) ====== [2025-11-03T10:26:21.566Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-03T10:26:21.566Z] GC before operation: completed in 117.215 ms, heap usage 477.358 MB -> 67.437 MB. [2025-11-03T10:26:26.939Z] ====== als (apache-spark) [default], iteration 10 completed (4866.023 ms) ====== [2025-11-03T10:26:26.939Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-03T10:26:26.939Z] GC before operation: completed in 123.539 ms, heap usage 320.881 MB -> 67.433 MB. [2025-11-03T10:26:31.082Z] ====== als (apache-spark) [default], iteration 11 completed (4823.309 ms) ====== [2025-11-03T10:26:31.082Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-03T10:26:32.033Z] GC before operation: completed in 113.333 ms, heap usage 347.001 MB -> 67.766 MB. [2025-11-03T10:26:36.184Z] ====== als (apache-spark) [default], iteration 12 completed (4750.534 ms) ====== [2025-11-03T10:26:36.184Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-03T10:26:36.184Z] GC before operation: completed in 117.652 ms, heap usage 209.893 MB -> 67.793 MB. [2025-11-03T10:26:41.555Z] ====== als (apache-spark) [default], iteration 13 completed (4727.038 ms) ====== [2025-11-03T10:26:41.555Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-03T10:26:41.555Z] GC before operation: completed in 114.661 ms, heap usage 99.386 MB -> 68.021 MB. [2025-11-03T10:26:46.937Z] ====== als (apache-spark) [default], iteration 14 completed (4660.925 ms) ====== [2025-11-03T10:26:46.937Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-03T10:26:46.937Z] GC before operation: completed in 117.103 ms, heap usage 162.754 MB -> 68.219 MB. [2025-11-03T10:26:51.123Z] ====== als (apache-spark) [default], iteration 15 completed (4602.211 ms) ====== [2025-11-03T10:26:51.123Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-03T10:26:51.123Z] GC before operation: completed in 113.257 ms, heap usage 267.300 MB -> 68.684 MB. [2025-11-03T10:26:56.492Z] ====== als (apache-spark) [default], iteration 16 completed (4688.805 ms) ====== [2025-11-03T10:26:56.492Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-03T10:26:56.492Z] GC before operation: completed in 120.168 ms, heap usage 598.883 MB -> 69.359 MB. [2025-11-03T10:27:01.862Z] ====== als (apache-spark) [default], iteration 17 completed (4660.902 ms) ====== [2025-11-03T10:27:01.862Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-03T10:27:01.862Z] GC before operation: completed in 125.912 ms, heap usage 436.971 MB -> 69.524 MB. [2025-11-03T10:27:06.006Z] ====== als (apache-spark) [default], iteration 18 completed (4628.146 ms) ====== [2025-11-03T10:27:06.006Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-03T10:27:06.006Z] GC before operation: completed in 134.432 ms, heap usage 171.627 MB -> 69.203 MB. [2025-11-03T10:27:11.372Z] ====== als (apache-spark) [default], iteration 19 completed (4677.580 ms) ====== [2025-11-03T10:27:11.372Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-03T10:27:11.372Z] GC before operation: completed in 124.771 ms, heap usage 428.468 MB -> 69.932 MB. [2025-11-03T10:27:15.527Z] ====== als (apache-spark) [default], iteration 20 completed (4679.891 ms) ====== [2025-11-03T10:27:15.527Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-03T10:27:16.476Z] GC before operation: completed in 130.991 ms, heap usage 262.502 MB -> 69.900 MB. [2025-11-03T10:27:20.627Z] ====== als (apache-spark) [default], iteration 21 completed (4602.764 ms) ====== [2025-11-03T10:27:20.627Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-03T10:27:20.627Z] GC before operation: completed in 133.296 ms, heap usage 184.187 MB -> 70.080 MB. [2025-11-03T10:27:25.989Z] ====== als (apache-spark) [default], iteration 22 completed (4573.559 ms) ====== [2025-11-03T10:27:25.989Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-03T10:27:25.989Z] GC before operation: completed in 120.100 ms, heap usage 269.666 MB -> 70.443 MB. [2025-11-03T10:27:31.358Z] ====== als (apache-spark) [default], iteration 23 completed (4632.435 ms) ====== [2025-11-03T10:27:31.358Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-03T10:27:31.358Z] GC before operation: completed in 126.777 ms, heap usage 225.593 MB -> 70.623 MB. [2025-11-03T10:27:36.243Z] ====== als (apache-spark) [default], iteration 24 completed (4802.533 ms) ====== [2025-11-03T10:27:36.243Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-03T10:27:36.243Z] GC before operation: completed in 190.749 ms, heap usage 492.382 MB -> 71.223 MB. [2025-11-03T10:27:41.682Z] ====== als (apache-spark) [default], iteration 25 completed (5372.258 ms) ====== [2025-11-03T10:27:41.682Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-03T10:27:41.682Z] GC before operation: completed in 150.063 ms, heap usage 623.831 MB -> 71.651 MB. [2025-11-03T10:27:47.079Z] ====== als (apache-spark) [default], iteration 26 completed (5410.652 ms) ====== [2025-11-03T10:27:47.079Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-03T10:27:47.079Z] GC before operation: completed in 147.661 ms, heap usage 462.851 MB -> 71.800 MB. [2025-11-03T10:27:53.864Z] ====== als (apache-spark) [default], iteration 27 completed (5689.004 ms) ====== [2025-11-03T10:27:53.864Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-03T10:27:53.864Z] GC before operation: completed in 178.810 ms, heap usage 210.042 MB -> 71.631 MB. [2025-11-03T10:27:59.225Z] ====== als (apache-spark) [default], iteration 28 completed (5441.549 ms) ====== [2025-11-03T10:27:59.225Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-03T10:27:59.225Z] GC before operation: completed in 113.649 ms, heap usage 366.269 MB -> 72.187 MB. [2025-11-03T10:28:04.588Z] ====== als (apache-spark) [default], iteration 29 completed (5135.564 ms) ====== [2025-11-03T10:28:05.538Z] ----------------------------------- [2025-11-03T10:28:05.538Z] renaissance-als_0_PASSED [2025-11-03T10:28:05.538Z] ----------------------------------- [2025-11-03T10:28:05.538Z] [2025-11-03T10:28:05.538Z] TEST TEARDOWN: [2025-11-03T10:28:05.538Z] Nothing to be done for teardown. [2025-11-03T10:28:05.538Z] renaissance-als_0 Finish Time: Mon Nov 3 10:28:04 2025 Epoch Time (ms): 1762165684700