[2026-03-05T05:10:18.559Z] Running test renaissance-als_0 ... [2026-03-05T05:10:18.559Z] =============================================== [2026-03-05T05:10:18.559Z] renaissance-als_0 Start Time: Thu Mar 5 05:10:18 2026 Epoch Time (ms): 1772687418454 [2026-03-05T05:10:18.559Z] variation: NoOptions [2026-03-05T05:10:18.934Z] JVM_OPTIONS: [2026-03-05T05:10:18.934Z] { \ [2026-03-05T05:10:18.934Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T05:10:18.934Z] echo "Nothing to be done for setup."; \ [2026-03-05T05:10:18.934Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17726871194443\\renaissance-als_0"; \ [2026-03-05T05:10:18.934Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17726871194443\\renaissance-als_0"; \ [2026-03-05T05:10:18.934Z] echo ""; echo "TESTING:"; \ [2026-03-05T05:10:18.934Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17726871194443\\renaissance-als_0"\\als.json" als; \ [2026-03-05T05:10:18.934Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17726871194443\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T05:10:18.934Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T05:10:18.934Z] echo "Nothing to be done for teardown."; \ [2026-03-05T05:10:18.934Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17726871194443\\TestTargetResult"; [2026-03-05T05:10:18.934Z] [2026-03-05T05:10:18.934Z] TEST SETUP: [2026-03-05T05:10:18.934Z] Nothing to be done for setup. [2026-03-05T05:10:18.934Z] [2026-03-05T05:10:18.934Z] TESTING: [2026-03-05T05:10:32.338Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-03-05T05:10:34.858Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-05T05:10:34.858Z] GC before operation: completed in 46.891 ms, heap usage 119.797 MB -> 34.971 MB. [2026-03-05T05:11:16.660Z] ====== als (apache-spark) [default], iteration 0 completed (38056.961 ms) ====== [2026-03-05T05:11:16.660Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-05T05:11:16.660Z] GC before operation: completed in 200.814 ms, heap usage 127.365 MB -> 62.204 MB. [2026-03-05T05:11:36.501Z] ====== als (apache-spark) [default], iteration 1 completed (21730.375 ms) ====== [2026-03-05T05:11:36.501Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-05T05:11:36.501Z] GC before operation: completed in 97.349 ms, heap usage 676.768 MB -> 62.843 MB. [2026-03-05T05:12:00.339Z] ====== als (apache-spark) [default], iteration 2 completed (20884.256 ms) ====== [2026-03-05T05:12:00.339Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-05T05:12:00.339Z] GC before operation: completed in 94.413 ms, heap usage 944.150 MB -> 64.193 MB. [2026-03-05T05:12:19.968Z] ====== als (apache-spark) [default], iteration 3 completed (20306.008 ms) ====== [2026-03-05T05:12:19.968Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-05T05:12:19.968Z] GC before operation: completed in 83.375 ms, heap usage 142.127 MB -> 63.818 MB. [2026-03-05T05:12:39.560Z] ====== als (apache-spark) [default], iteration 4 completed (19946.387 ms) ====== [2026-03-05T05:12:39.560Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-05T05:12:39.560Z] GC before operation: completed in 97.775 ms, heap usage 727.461 MB -> 65.183 MB. [2026-03-05T05:12:59.232Z] ====== als (apache-spark) [default], iteration 5 completed (19906.293 ms) ====== [2026-03-05T05:12:59.232Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-05T05:12:59.232Z] GC before operation: completed in 131.475 ms, heap usage 285.165 MB -> 64.636 MB. [2026-03-05T05:13:18.883Z] ====== als (apache-spark) [default], iteration 6 completed (19941.126 ms) ====== [2026-03-05T05:13:18.883Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-05T05:13:19.459Z] GC before operation: completed in 99.414 ms, heap usage 318.174 MB -> 65.082 MB. [2026-03-05T05:13:39.158Z] ====== als (apache-spark) [default], iteration 7 completed (20192.086 ms) ====== [2026-03-05T05:13:39.158Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-05T05:13:39.689Z] GC before operation: completed in 93.426 ms, heap usage 356.807 MB -> 66.191 MB. [2026-03-05T05:13:59.678Z] ====== als (apache-spark) [default], iteration 8 completed (20124.730 ms) ====== [2026-03-05T05:13:59.678Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-05T05:14:00.289Z] GC before operation: completed in 165.094 ms, heap usage 830.933 MB -> 67.418 MB. [2026-03-05T05:14:20.007Z] ====== als (apache-spark) [default], iteration 9 completed (19368.706 ms) ====== [2026-03-05T05:14:20.007Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-05T05:14:20.007Z] GC before operation: completed in 110.419 ms, heap usage 653.019 MB -> 67.373 MB. [2026-03-05T05:14:39.975Z] ====== als (apache-spark) [default], iteration 10 completed (19648.369 ms) ====== [2026-03-05T05:14:39.975Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-05T05:14:39.975Z] GC before operation: completed in 114.174 ms, heap usage 682.689 MB -> 67.669 MB. [2026-03-05T05:14:59.948Z] ====== als (apache-spark) [default], iteration 11 completed (19746.233 ms) ====== [2026-03-05T05:14:59.948Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-05T05:14:59.948Z] GC before operation: completed in 114.740 ms, heap usage 729.724 MB -> 68.116 MB. [2026-03-05T05:15:19.594Z] ====== als (apache-spark) [default], iteration 12 completed (19356.174 ms) ====== [2026-03-05T05:15:19.594Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-05T05:15:19.594Z] GC before operation: completed in 110.522 ms, heap usage 537.293 MB -> 68.185 MB. [2026-03-05T05:15:39.492Z] ====== als (apache-spark) [default], iteration 13 completed (19730.466 ms) ====== [2026-03-05T05:15:39.492Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-05T05:15:39.492Z] GC before operation: completed in 112.465 ms, heap usage 427.170 MB -> 68.075 MB. [2026-03-05T05:15:59.157Z] ====== als (apache-spark) [default], iteration 14 completed (19486.260 ms) ====== [2026-03-05T05:15:59.157Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-05T05:15:59.157Z] GC before operation: completed in 101.393 ms, heap usage 256.041 MB -> 67.738 MB. [2026-03-05T05:16:18.707Z] ====== als (apache-spark) [default], iteration 15 completed (19265.203 ms) ====== [2026-03-05T05:16:18.708Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-05T05:16:18.708Z] GC before operation: completed in 101.468 ms, heap usage 234.222 MB -> 68.394 MB. [2026-03-05T05:16:38.520Z] ====== als (apache-spark) [default], iteration 16 completed (19282.839 ms) ====== [2026-03-05T05:16:38.520Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-05T05:16:38.520Z] GC before operation: completed in 104.966 ms, heap usage 609.081 MB -> 69.119 MB. [2026-03-05T05:16:58.113Z] ====== als (apache-spark) [default], iteration 17 completed (19361.591 ms) ====== [2026-03-05T05:16:58.113Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-05T05:16:58.113Z] GC before operation: completed in 107.630 ms, heap usage 563.658 MB -> 69.432 MB. [2026-03-05T05:17:17.808Z] ====== als (apache-spark) [default], iteration 18 completed (19177.896 ms) ====== [2026-03-05T05:17:17.809Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-05T05:17:17.809Z] GC before operation: completed in 146.915 ms, heap usage 628.727 MB -> 69.647 MB. [2026-03-05T05:17:37.430Z] ====== als (apache-spark) [default], iteration 19 completed (19433.942 ms) ====== [2026-03-05T05:17:37.430Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-05T05:17:37.430Z] GC before operation: completed in 106.980 ms, heap usage 221.744 MB -> 69.450 MB. [2026-03-05T05:17:57.225Z] ====== als (apache-spark) [default], iteration 20 completed (20051.723 ms) ====== [2026-03-05T05:17:57.225Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-05T05:17:57.225Z] GC before operation: completed in 102.981 ms, heap usage 205.503 MB -> 69.729 MB. [2026-03-05T05:18:16.862Z] ====== als (apache-spark) [default], iteration 21 completed (19130.449 ms) ====== [2026-03-05T05:18:16.862Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-05T05:18:16.862Z] GC before operation: completed in 108.920 ms, heap usage 677.023 MB -> 70.639 MB. [2026-03-05T05:18:36.309Z] ====== als (apache-spark) [default], iteration 22 completed (19430.155 ms) ====== [2026-03-05T05:18:36.309Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-05T05:18:36.309Z] GC before operation: completed in 104.545 ms, heap usage 530.851 MB -> 70.668 MB. [2026-03-05T05:18:56.029Z] ====== als (apache-spark) [default], iteration 23 completed (19513.196 ms) ====== [2026-03-05T05:18:56.029Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-05T05:18:56.426Z] GC before operation: completed in 109.793 ms, heap usage 224.670 MB -> 70.049 MB. [2026-03-05T05:19:16.219Z] ====== als (apache-spark) [default], iteration 24 completed (19525.327 ms) ====== [2026-03-05T05:19:16.219Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-05T05:19:16.219Z] GC before operation: completed in 112.183 ms, heap usage 282.215 MB -> 70.289 MB. [2026-03-05T05:19:35.852Z] ====== als (apache-spark) [default], iteration 25 completed (19208.366 ms) ====== [2026-03-05T05:19:35.852Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-05T05:19:35.852Z] GC before operation: completed in 112.428 ms, heap usage 488.932 MB -> 71.326 MB. [2026-03-05T05:19:55.710Z] ====== als (apache-spark) [default], iteration 26 completed (19314.607 ms) ====== [2026-03-05T05:19:55.710Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-05T05:19:55.710Z] GC before operation: completed in 113.661 ms, heap usage 286.620 MB -> 71.375 MB. [2026-03-05T05:20:15.354Z] ====== als (apache-spark) [default], iteration 27 completed (19977.083 ms) ====== [2026-03-05T05:20:15.354Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-05T05:20:15.354Z] GC before operation: completed in 101.337 ms, heap usage 345.366 MB -> 71.762 MB. [2026-03-05T05:20:35.239Z] ====== als (apache-spark) [default], iteration 28 completed (19710.232 ms) ====== [2026-03-05T05:20:35.239Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-05T05:20:35.239Z] GC before operation: completed in 110.058 ms, heap usage 116.270 MB -> 71.280 MB. [2026-03-05T05:20:55.872Z] ====== als (apache-spark) [default], iteration 29 completed (19612.198 ms) ====== [2026-03-05T05:20:55.873Z] [2026-03-05T05:20:55.384+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2026-03-05T05:20:55.873Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk17_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_17726871194443\renaissance-als_0\harness-051019-14845010255546179754: harness-051019-14845010255546179754\apache-spark\als\hadoop\bin\hadoop.dll [2026-03-05T05:20:55.873Z] ----------------------------------- [2026-03-05T05:20:55.873Z] renaissance-als_0_PASSED [2026-03-05T05:20:55.873Z] ----------------------------------- [2026-03-05T05:20:55.873Z] [2026-03-05T05:20:55.873Z] TEST TEARDOWN: [2026-03-05T05:20:55.873Z] Nothing to be done for teardown. [2026-03-05T05:20:55.873Z] renaissance-als_0 Finish Time: Thu Mar 5 05:20:55 2026 Epoch Time (ms): 1772688055749