[2025-05-08T01:08:53.697Z] Running test renaissance-als_0 ... [2025-05-08T01:08:53.697Z] =============================================== [2025-05-08T01:08:53.697Z] renaissance-als_0 Start Time: Wed May 7 20:08:52 2025 Epoch Time (ms): 1746666532759 [2025-05-08T01:08:53.697Z] variation: NoOptions [2025-05-08T01:08:53.697Z] JVM_OPTIONS: [2025-05-08T01:08:53.697Z] { \ [2025-05-08T01:08:53.697Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T01:08:53.697Z] echo "Nothing to be done for setup."; \ [2025-05-08T01:08:53.697Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466665312221/renaissance-als_0"; \ [2025-05-08T01:08:53.697Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466665312221/renaissance-als_0"; \ [2025-05-08T01:08:53.697Z] echo ""; echo "TESTING:"; \ [2025-05-08T01:08:53.697Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466665312221/renaissance-als_0"/als.json" als; \ [2025-05-08T01:08:53.697Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466665312221/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T01:08:53.697Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T01:08:53.697Z] echo "Nothing to be done for teardown."; \ [2025-05-08T01:08:53.697Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17466665312221/TestTargetResult"; [2025-05-08T01:08:54.380Z] [2025-05-08T01:08:54.380Z] TEST SETUP: [2025-05-08T01:08:54.380Z] Nothing to be done for setup. [2025-05-08T01:08:54.380Z] [2025-05-08T01:08:54.380Z] TESTING: [2025-05-08T01:09:00.632Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-05-08T01:09:04.657Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-08T01:09:04.657Z] GC before operation: completed in 43.921 ms, heap usage 71.464 MB -> 35.552 MB. [2025-05-08T01:09:22.381Z] ====== als (apache-spark) [default], iteration 0 completed (14946.014 ms) ====== [2025-05-08T01:09:22.381Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-08T01:09:22.381Z] GC before operation: completed in 115.704 ms, heap usage 975.556 MB -> 63.458 MB. [2025-05-08T01:09:27.459Z] ====== als (apache-spark) [default], iteration 1 completed (6013.754 ms) ====== [2025-05-08T01:09:27.459Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-08T01:09:27.459Z] GC before operation: completed in 129.452 ms, heap usage 437.362 MB -> 63.764 MB. [2025-05-08T01:09:33.718Z] ====== als (apache-spark) [default], iteration 2 completed (6077.624 ms) ====== [2025-05-08T01:09:33.718Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-08T01:09:33.718Z] GC before operation: completed in 88.024 ms, heap usage 990.959 MB -> 65.061 MB. [2025-05-08T01:09:38.797Z] ====== als (apache-spark) [default], iteration 3 completed (5634.976 ms) ====== [2025-05-08T01:09:38.797Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-08T01:09:39.479Z] GC before operation: completed in 94.589 ms, heap usage 657.039 MB -> 65.227 MB. [2025-05-08T01:09:44.557Z] ====== als (apache-spark) [default], iteration 4 completed (5483.883 ms) ====== [2025-05-08T01:09:44.557Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-08T01:09:45.248Z] GC before operation: completed in 86.077 ms, heap usage 467.659 MB -> 65.672 MB. [2025-05-08T01:09:50.329Z] ====== als (apache-spark) [default], iteration 5 completed (5067.728 ms) ====== [2025-05-08T01:09:50.329Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-08T01:09:50.329Z] GC before operation: completed in 81.760 ms, heap usage 150.582 MB -> 65.737 MB. [2025-05-08T01:09:55.415Z] ====== als (apache-spark) [default], iteration 6 completed (5128.843 ms) ====== [2025-05-08T01:09:55.415Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-08T01:09:55.415Z] GC before operation: completed in 117.398 ms, heap usage 677.669 MB -> 66.907 MB. [2025-05-08T01:10:01.688Z] ====== als (apache-spark) [default], iteration 7 completed (6014.066 ms) ====== [2025-05-08T01:10:01.688Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-08T01:10:01.688Z] GC before operation: completed in 108.306 ms, heap usage 187.689 MB -> 66.932 MB. [2025-05-08T01:10:07.952Z] ====== als (apache-spark) [default], iteration 8 completed (5437.189 ms) ====== [2025-05-08T01:10:07.952Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-08T01:10:07.952Z] GC before operation: completed in 115.828 ms, heap usage 363.625 MB -> 67.706 MB. [2025-05-08T01:10:13.038Z] ====== als (apache-spark) [default], iteration 9 completed (5625.729 ms) ====== [2025-05-08T01:10:13.038Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-08T01:10:13.724Z] GC before operation: completed in 98.103 ms, heap usage 772.372 MB -> 68.411 MB. [2025-05-08T01:10:19.983Z] ====== als (apache-spark) [default], iteration 10 completed (6059.159 ms) ====== [2025-05-08T01:10:19.983Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-08T01:10:19.983Z] GC before operation: completed in 90.746 ms, heap usage 898.921 MB -> 68.800 MB. [2025-05-08T01:10:26.241Z] ====== als (apache-spark) [default], iteration 11 completed (5382.816 ms) ====== [2025-05-08T01:10:26.241Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-08T01:10:26.241Z] GC before operation: completed in 100.210 ms, heap usage 680.317 MB -> 68.845 MB. [2025-05-08T01:10:30.507Z] ====== als (apache-spark) [default], iteration 12 completed (5247.578 ms) ====== [2025-05-08T01:10:30.507Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-08T01:10:31.189Z] GC before operation: completed in 92.944 ms, heap usage 695.186 MB -> 69.120 MB. [2025-05-08T01:10:36.281Z] ====== als (apache-spark) [default], iteration 13 completed (5279.102 ms) ====== [2025-05-08T01:10:36.281Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-08T01:10:36.281Z] GC before operation: completed in 100.811 ms, heap usage 125.639 MB -> 68.792 MB. [2025-05-08T01:10:42.540Z] ====== als (apache-spark) [default], iteration 14 completed (5322.507 ms) ====== [2025-05-08T01:10:42.540Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-08T01:10:42.540Z] GC before operation: completed in 97.476 ms, heap usage 287.804 MB -> 69.069 MB. [2025-05-08T01:10:47.649Z] ====== als (apache-spark) [default], iteration 15 completed (5200.387 ms) ====== [2025-05-08T01:10:47.649Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-08T01:10:47.649Z] GC before operation: completed in 92.780 ms, heap usage 362.108 MB -> 69.525 MB. [2025-05-08T01:10:52.737Z] ====== als (apache-spark) [default], iteration 16 completed (5113.250 ms) ====== [2025-05-08T01:10:52.737Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-08T01:10:52.737Z] GC before operation: completed in 111.192 ms, heap usage 400.040 MB -> 69.669 MB. [2025-05-08T01:10:59.006Z] ====== als (apache-spark) [default], iteration 17 completed (5672.326 ms) ====== [2025-05-08T01:10:59.006Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-08T01:10:59.006Z] GC before operation: completed in 112.296 ms, heap usage 223.069 MB -> 69.817 MB. [2025-05-08T01:11:04.094Z] ====== als (apache-spark) [default], iteration 18 completed (5108.971 ms) ====== [2025-05-08T01:11:04.094Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-08T01:11:04.094Z] GC before operation: completed in 97.112 ms, heap usage 868.270 MB -> 70.896 MB. [2025-05-08T01:11:10.355Z] ====== als (apache-spark) [default], iteration 19 completed (5756.692 ms) ====== [2025-05-08T01:11:10.355Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-08T01:11:10.355Z] GC before operation: completed in 106.805 ms, heap usage 196.078 MB -> 70.363 MB. [2025-05-08T01:11:15.435Z] ====== als (apache-spark) [default], iteration 20 completed (5427.651 ms) ====== [2025-05-08T01:11:15.435Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-08T01:11:15.435Z] GC before operation: completed in 120.191 ms, heap usage 305.041 MB -> 70.716 MB. [2025-05-08T01:11:21.693Z] ====== als (apache-spark) [default], iteration 21 completed (5119.914 ms) ====== [2025-05-08T01:11:21.693Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-08T01:11:21.693Z] GC before operation: completed in 102.425 ms, heap usage 621.576 MB -> 71.552 MB. [2025-05-08T01:11:26.779Z] ====== als (apache-spark) [default], iteration 22 completed (5241.018 ms) ====== [2025-05-08T01:11:26.779Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-08T01:11:26.779Z] GC before operation: completed in 104.452 ms, heap usage 537.493 MB -> 71.654 MB. [2025-05-08T01:11:31.857Z] ====== als (apache-spark) [default], iteration 23 completed (5197.986 ms) ====== [2025-05-08T01:11:31.857Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-08T01:11:31.857Z] GC before operation: completed in 99.181 ms, heap usage 767.138 MB -> 72.217 MB. [2025-05-08T01:11:38.119Z] ====== als (apache-spark) [default], iteration 24 completed (5179.581 ms) ====== [2025-05-08T01:11:38.119Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-08T01:11:38.119Z] GC before operation: completed in 97.883 ms, heap usage 457.441 MB -> 72.124 MB. [2025-05-08T01:11:42.200Z] ====== als (apache-spark) [default], iteration 25 completed (5018.497 ms) ====== [2025-05-08T01:11:42.200Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-08T01:11:42.885Z] GC before operation: completed in 98.207 ms, heap usage 684.342 MB -> 72.530 MB. [2025-05-08T01:11:47.963Z] ====== als (apache-spark) [default], iteration 26 completed (5231.124 ms) ====== [2025-05-08T01:11:47.963Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-08T01:11:47.963Z] GC before operation: completed in 111.633 ms, heap usage 456.153 MB -> 72.707 MB. [2025-05-08T01:11:53.045Z] ====== als (apache-spark) [default], iteration 27 completed (5102.203 ms) ====== [2025-05-08T01:11:53.045Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-08T01:11:53.729Z] GC before operation: completed in 155.438 ms, heap usage 496.447 MB -> 72.996 MB. [2025-05-08T01:11:58.809Z] ====== als (apache-spark) [default], iteration 28 completed (5215.933 ms) ====== [2025-05-08T01:11:58.809Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-08T01:11:58.809Z] GC before operation: completed in 108.881 ms, heap usage 214.157 MB -> 72.879 MB. [2025-05-08T01:12:05.073Z] ====== als (apache-spark) [default], iteration 29 completed (5573.788 ms) ====== [2025-05-08T01:12:05.758Z] ----------------------------------- [2025-05-08T01:12:05.758Z] renaissance-als_0_PASSED [2025-05-08T01:12:05.758Z] ----------------------------------- [2025-05-08T01:12:05.758Z] [2025-05-08T01:12:05.758Z] TEST TEARDOWN: [2025-05-08T01:12:05.758Z] Nothing to be done for teardown. [2025-05-08T01:12:05.758Z] renaissance-als_0 Finish Time: Wed May 7 20:12:04 2025 Epoch Time (ms): 1746666724432