[2025-12-03T22:30:37.547Z] Running test renaissance-als_0 ... [2025-12-03T22:30:37.547Z] =============================================== [2025-12-03T22:30:37.547Z] renaissance-als_0 Start Time: Wed Dec 3 22:30:37 2025 Epoch Time (ms): 1764801037204 [2025-12-03T22:30:37.547Z] variation: NoOptions [2025-12-03T22:30:37.547Z] JVM_OPTIONS: [2025-12-03T22:30:37.547Z] { \ [2025-12-03T22:30:37.547Z] echo ""; echo "TEST SETUP:"; \ [2025-12-03T22:30:37.547Z] echo "Nothing to be done for setup."; \ [2025-12-03T22:30:37.547Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17648007721367/renaissance-als_0"; \ [2025-12-03T22:30:37.547Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17648007721367/renaissance-als_0"; \ [2025-12-03T22:30:37.547Z] echo ""; echo "TESTING:"; \ [2025-12-03T22:30:37.547Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_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_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17648007721367/renaissance-als_0"/als.json" als; \ [2025-12-03T22:30:37.547Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17648007721367/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-03T22:30:37.547Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-03T22:30:37.547Z] echo "Nothing to be done for teardown."; \ [2025-12-03T22:30:37.547Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17648007721367/TestTargetResult"; [2025-12-03T22:30:37.547Z] [2025-12-03T22:30:37.547Z] TEST SETUP: [2025-12-03T22:30:37.547Z] Nothing to be done for setup. [2025-12-03T22:30:37.547Z] [2025-12-03T22:30:37.547Z] TESTING: [2025-12-03T22:30:43.889Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-03T22:30:47.966Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-03T22:30:47.966Z] GC before operation: completed in 114.429 ms, heap usage 168.025 MB -> 35.153 MB. [2025-12-03T22:31:22.909Z] ====== als (apache-spark) [default], iteration 0 completed (29344.063 ms) ====== [2025-12-03T22:31:22.909Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-03T22:31:22.909Z] GC before operation: completed in 188.823 ms, heap usage 232.797 MB -> 61.871 MB. [2025-12-03T22:31:37.897Z] ====== als (apache-spark) [default], iteration 1 completed (17712.147 ms) ====== [2025-12-03T22:31:37.897Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-03T22:31:37.897Z] GC before operation: completed in 165.295 ms, heap usage 193.861 MB -> 62.682 MB. [2025-12-03T22:31:55.492Z] ====== als (apache-spark) [default], iteration 2 completed (16236.593 ms) ====== [2025-12-03T22:31:55.492Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-03T22:31:55.492Z] GC before operation: completed in 113.826 ms, heap usage 166.770 MB -> 63.296 MB. [2025-12-03T22:32:10.678Z] ====== als (apache-spark) [default], iteration 3 completed (16151.116 ms) ====== [2025-12-03T22:32:10.678Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-03T22:32:10.678Z] GC before operation: completed in 199.144 ms, heap usage 104.608 MB -> 63.818 MB. [2025-12-03T22:32:26.232Z] ====== als (apache-spark) [default], iteration 4 completed (15551.754 ms) ====== [2025-12-03T22:32:26.232Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-03T22:32:26.232Z] GC before operation: completed in 102.677 ms, heap usage 321.883 MB -> 64.569 MB. [2025-12-03T22:32:44.354Z] ====== als (apache-spark) [default], iteration 5 completed (15635.147 ms) ====== [2025-12-03T22:32:44.354Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-03T22:32:44.354Z] GC before operation: completed in 186.857 ms, heap usage 286.646 MB -> 64.970 MB. [2025-12-03T22:32:59.498Z] ====== als (apache-spark) [default], iteration 6 completed (15743.962 ms) ====== [2025-12-03T22:32:59.498Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-03T22:32:59.498Z] GC before operation: completed in 168.056 ms, heap usage 329.060 MB -> 65.584 MB. [2025-12-03T22:33:14.584Z] ====== als (apache-spark) [default], iteration 7 completed (15046.004 ms) ====== [2025-12-03T22:33:14.584Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-03T22:33:14.584Z] GC before operation: completed in 166.774 ms, heap usage 151.546 MB -> 65.871 MB. [2025-12-03T22:33:32.778Z] ====== als (apache-spark) [default], iteration 8 completed (17661.895 ms) ====== [2025-12-03T22:33:32.778Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-03T22:33:32.778Z] GC before operation: completed in 151.120 ms, heap usage 242.653 MB -> 66.549 MB. [2025-12-03T22:33:50.703Z] ====== als (apache-spark) [default], iteration 9 completed (16125.980 ms) ====== [2025-12-03T22:33:50.703Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-03T22:33:50.703Z] GC before operation: completed in 107.313 ms, heap usage 316.082 MB -> 67.000 MB. [2025-12-03T22:34:08.567Z] ====== als (apache-spark) [default], iteration 10 completed (17258.036 ms) ====== [2025-12-03T22:34:08.567Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-03T22:34:08.567Z] GC before operation: completed in 162.635 ms, heap usage 264.050 MB -> 67.136 MB. [2025-12-03T22:34:26.584Z] ====== als (apache-spark) [default], iteration 11 completed (17801.545 ms) ====== [2025-12-03T22:34:26.584Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-03T22:34:26.584Z] GC before operation: completed in 253.283 ms, heap usage 203.455 MB -> 67.282 MB. [2025-12-03T22:34:41.763Z] ====== als (apache-spark) [default], iteration 12 completed (15891.529 ms) ====== [2025-12-03T22:34:41.763Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-03T22:34:41.763Z] GC before operation: completed in 186.723 ms, heap usage 192.984 MB -> 67.447 MB. [2025-12-03T22:34:59.943Z] ====== als (apache-spark) [default], iteration 13 completed (17017.833 ms) ====== [2025-12-03T22:34:59.943Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-03T22:34:59.943Z] GC before operation: completed in 209.600 ms, heap usage 95.980 MB -> 68.177 MB. [2025-12-03T22:35:18.409Z] ====== als (apache-spark) [default], iteration 14 completed (18138.718 ms) ====== [2025-12-03T22:35:18.409Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-03T22:35:18.409Z] GC before operation: completed in 280.542 ms, heap usage 263.591 MB -> 68.089 MB. [2025-12-03T22:35:36.564Z] ====== als (apache-spark) [default], iteration 15 completed (17351.065 ms) ====== [2025-12-03T22:35:36.564Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-03T22:35:36.564Z] GC before operation: completed in 191.557 ms, heap usage 245.202 MB -> 68.296 MB. [2025-12-03T22:35:57.977Z] ====== als (apache-spark) [default], iteration 16 completed (19654.612 ms) ====== [2025-12-03T22:35:57.977Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-03T22:35:57.977Z] GC before operation: completed in 223.056 ms, heap usage 263.852 MB -> 68.570 MB. [2025-12-03T22:36:16.070Z] ====== als (apache-spark) [default], iteration 17 completed (18355.098 ms) ====== [2025-12-03T22:36:16.070Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-03T22:36:16.070Z] GC before operation: completed in 132.172 ms, heap usage 342.282 MB -> 68.940 MB. [2025-12-03T22:36:38.237Z] ====== als (apache-spark) [default], iteration 18 completed (20944.809 ms) ====== [2025-12-03T22:36:38.237Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-03T22:36:38.237Z] GC before operation: completed in 163.426 ms, heap usage 140.408 MB -> 69.015 MB. [2025-12-03T22:37:00.099Z] ====== als (apache-spark) [default], iteration 19 completed (21493.670 ms) ====== [2025-12-03T22:37:00.099Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-03T22:37:00.099Z] GC before operation: completed in 270.048 ms, heap usage 155.337 MB -> 68.788 MB. [2025-12-03T22:37:19.392Z] ====== als (apache-spark) [default], iteration 20 completed (19862.437 ms) ====== [2025-12-03T22:37:19.392Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-03T22:37:19.392Z] GC before operation: completed in 296.639 ms, heap usage 217.326 MB -> 69.468 MB. [2025-12-03T22:37:41.360Z] ====== als (apache-spark) [default], iteration 21 completed (19845.205 ms) ====== [2025-12-03T22:37:41.360Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-03T22:37:41.360Z] GC before operation: completed in 251.263 ms, heap usage 183.935 MB -> 69.726 MB. [2025-12-03T22:38:03.636Z] ====== als (apache-spark) [default], iteration 22 completed (21356.252 ms) ====== [2025-12-03T22:38:03.636Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-03T22:38:03.636Z] GC before operation: completed in 336.325 ms, heap usage 320.742 MB -> 70.205 MB. [2025-12-03T22:38:25.058Z] ====== als (apache-spark) [default], iteration 23 completed (20243.898 ms) ====== [2025-12-03T22:38:25.058Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-03T22:38:25.058Z] GC before operation: completed in 214.200 ms, heap usage 384.603 MB -> 70.492 MB. [2025-12-03T22:38:43.308Z] ====== als (apache-spark) [default], iteration 24 completed (20062.090 ms) ====== [2025-12-03T22:38:43.308Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-03T22:38:43.308Z] GC before operation: completed in 191.714 ms, heap usage 186.004 MB -> 70.499 MB. [2025-12-03T22:39:04.850Z] ====== als (apache-spark) [default], iteration 25 completed (19185.433 ms) ====== [2025-12-03T22:39:04.850Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-03T22:39:04.850Z] GC before operation: completed in 464.352 ms, heap usage 228.088 MB -> 70.855 MB. [2025-12-03T22:39:21.672Z] ====== als (apache-spark) [default], iteration 26 completed (17709.457 ms) ====== [2025-12-03T22:39:21.672Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-03T22:39:21.672Z] GC before operation: completed in 214.821 ms, heap usage 218.648 MB -> 71.065 MB. [2025-12-03T22:39:40.890Z] ====== als (apache-spark) [default], iteration 27 completed (18674.680 ms) ====== [2025-12-03T22:39:40.891Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-03T22:39:40.891Z] GC before operation: completed in 160.511 ms, heap usage 465.719 MB -> 71.772 MB. [2025-12-03T22:39:58.990Z] ====== als (apache-spark) [default], iteration 28 completed (18493.677 ms) ====== [2025-12-03T22:39:58.990Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-03T22:39:59.720Z] GC before operation: completed in 160.302 ms, heap usage 168.616 MB -> 71.146 MB. [2025-12-03T22:40:21.154Z] ====== als (apache-spark) [default], iteration 29 completed (19624.185 ms) ====== [2025-12-03T22:40:21.154Z] ----------------------------------- [2025-12-03T22:40:21.154Z] renaissance-als_0_PASSED [2025-12-03T22:40:21.154Z] ----------------------------------- [2025-12-03T22:40:21.154Z] [2025-12-03T22:40:21.154Z] TEST TEARDOWN: [2025-12-03T22:40:21.154Z] Nothing to be done for teardown. [2025-12-03T22:40:21.154Z] renaissance-als_0 Finish Time: Wed Dec 3 22:40:19 2025 Epoch Time (ms): 1764801619883