[2026-03-18T00:36:47.537Z] Running test renaissance-als_0 ... [2026-03-18T00:36:47.537Z] =============================================== [2026-03-18T00:36:47.893Z] renaissance-als_0 Start Time: Wed Mar 18 00:36:47 2026 Epoch Time (ms): 1773794207578 [2026-03-18T00:36:47.893Z] variation: NoOptions [2026-03-18T00:36:47.893Z] JVM_OPTIONS: [2026-03-18T00:36:47.893Z] { \ [2026-03-18T00:36:47.893Z] echo ""; echo "TEST SETUP:"; \ [2026-03-18T00:36:47.893Z] echo "Nothing to be done for setup."; \ [2026-03-18T00:36:47.893Z] mkdir -p "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17737942052811\\renaissance-als_0"; \ [2026-03-18T00:36:47.893Z] cd "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17737942052811\\renaissance-als_0"; \ [2026-03-18T00:36:47.893Z] echo ""; echo "TESTING:"; \ [2026-03-18T00:36:47.893Z] "c:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows_rerun/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_openjdk26_hs_extended.perf_x86-64_windows_rerun/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17737942052811\\renaissance-als_0"\\als.json" als; \ [2026-03-18T00:36:47.893Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows_rerun/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17737942052811\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-18T00:36:47.893Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-18T00:36:47.893Z] echo "Nothing to be done for teardown."; \ [2026-03-18T00:36:47.893Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk26_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17737942052811\\TestTargetResult"; [2026-03-18T00:36:47.893Z] [2026-03-18T00:36:47.893Z] TEST SETUP: [2026-03-18T00:36:47.893Z] Nothing to be done for setup. [2026-03-18T00:36:47.893Z] [2026-03-18T00:36:47.893Z] TESTING: [2026-03-18T00:36:48.706Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-03-18T00:36:48.706Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (resource:/shared/scala3-library_3-3.3.4.jar) [2026-03-18T00:36:48.706Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-03-18T00:36:48.706Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-03-18T00:37:05.086Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-03-18T00:37:07.304Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-18T00:37:07.304Z] GC before operation: completed in 50.034 ms, heap usage 53.516 MB -> 36.157 MB. [2026-03-18T00:37:49.711Z] ====== als (apache-spark) [default], iteration 0 completed (39008.562 ms) ====== [2026-03-18T00:37:49.711Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-18T00:37:49.711Z] GC before operation: completed in 190.859 ms, heap usage 562.622 MB -> 63.714 MB. [2026-03-18T00:38:13.361Z] ====== als (apache-spark) [default], iteration 1 completed (22758.657 ms) ====== [2026-03-18T00:38:13.361Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-18T00:38:13.361Z] GC before operation: completed in 113.552 ms, heap usage 255.516 MB -> 63.840 MB. [2026-03-18T00:38:33.161Z] ====== als (apache-spark) [default], iteration 2 completed (21418.214 ms) ====== [2026-03-18T00:38:33.161Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-18T00:38:33.161Z] GC before operation: completed in 94.704 ms, heap usage 118.452 MB -> 64.812 MB. [2026-03-18T00:38:57.076Z] ====== als (apache-spark) [default], iteration 3 completed (21076.878 ms) ====== [2026-03-18T00:38:57.076Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-18T00:38:57.076Z] GC before operation: completed in 118.428 ms, heap usage 572.313 MB -> 65.971 MB. [2026-03-18T00:39:16.530Z] ====== als (apache-spark) [default], iteration 4 completed (20559.255 ms) ====== [2026-03-18T00:39:16.530Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-18T00:39:16.530Z] GC before operation: completed in 90.703 ms, heap usage 223.133 MB -> 66.081 MB. [2026-03-18T00:39:36.119Z] ====== als (apache-spark) [default], iteration 5 completed (20941.296 ms) ====== [2026-03-18T00:39:36.119Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-18T00:39:36.119Z] GC before operation: completed in 100.231 ms, heap usage 420.180 MB -> 66.342 MB. [2026-03-18T00:39:59.888Z] ====== als (apache-spark) [default], iteration 6 completed (20687.264 ms) ====== [2026-03-18T00:39:59.888Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-18T00:39:59.888Z] GC before operation: completed in 114.886 ms, heap usage 774.910 MB -> 67.887 MB. [2026-03-18T00:40:19.525Z] ====== als (apache-spark) [default], iteration 7 completed (20804.697 ms) ====== [2026-03-18T00:40:19.525Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-18T00:40:19.525Z] GC before operation: completed in 111.259 ms, heap usage 871.230 MB -> 68.474 MB. [2026-03-18T00:40:39.467Z] ====== als (apache-spark) [default], iteration 8 completed (21122.711 ms) ====== [2026-03-18T00:40:39.467Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-18T00:40:39.467Z] GC before operation: completed in 95.026 ms, heap usage 256.149 MB -> 68.242 MB. [2026-03-18T00:41:03.487Z] ====== als (apache-spark) [default], iteration 9 completed (20511.001 ms) ====== [2026-03-18T00:41:03.487Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-18T00:41:03.487Z] GC before operation: completed in 104.968 ms, heap usage 797.425 MB -> 69.270 MB. [2026-03-18T00:41:23.149Z] ====== als (apache-spark) [default], iteration 10 completed (20595.349 ms) ====== [2026-03-18T00:41:23.149Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-18T00:41:23.149Z] GC before operation: completed in 113.301 ms, heap usage 175.401 MB -> 68.268 MB. [2026-03-18T00:41:43.032Z] ====== als (apache-spark) [default], iteration 11 completed (20939.454 ms) ====== [2026-03-18T00:41:43.032Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-18T00:41:43.032Z] GC before operation: completed in 95.962 ms, heap usage 115.430 MB -> 68.382 MB. [2026-03-18T00:42:02.878Z] ====== als (apache-spark) [default], iteration 12 completed (20168.197 ms) ====== [2026-03-18T00:42:02.878Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-18T00:42:02.878Z] GC before operation: completed in 102.175 ms, heap usage 552.802 MB -> 69.765 MB. [2026-03-18T00:42:26.552Z] ====== als (apache-spark) [default], iteration 13 completed (20524.282 ms) ====== [2026-03-18T00:42:26.552Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-18T00:42:26.552Z] GC before operation: completed in 114.905 ms, heap usage 814.985 MB -> 70.213 MB. [2026-03-18T00:42:46.082Z] ====== als (apache-spark) [default], iteration 14 completed (20354.693 ms) ====== [2026-03-18T00:42:46.083Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-18T00:42:46.083Z] GC before operation: completed in 107.329 ms, heap usage 354.282 MB -> 69.968 MB. [2026-03-18T00:43:05.701Z] ====== als (apache-spark) [default], iteration 15 completed (20289.859 ms) ====== [2026-03-18T00:43:05.701Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-18T00:43:05.701Z] GC before operation: completed in 119.188 ms, heap usage 111.434 MB -> 69.464 MB. [2026-03-18T00:43:29.325Z] ====== als (apache-spark) [default], iteration 16 completed (21096.043 ms) ====== [2026-03-18T00:43:29.325Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-18T00:43:29.325Z] GC before operation: completed in 111.967 ms, heap usage 843.649 MB -> 71.052 MB. [2026-03-18T00:43:49.112Z] ====== als (apache-spark) [default], iteration 17 completed (20942.004 ms) ====== [2026-03-18T00:43:49.112Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-18T00:43:49.112Z] GC before operation: completed in 108.852 ms, heap usage 556.329 MB -> 71.157 MB. [2026-03-18T00:44:09.181Z] ====== als (apache-spark) [default], iteration 18 completed (21004.698 ms) ====== [2026-03-18T00:44:09.181Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-18T00:44:09.181Z] GC before operation: completed in 101.700 ms, heap usage 241.942 MB -> 70.944 MB. [2026-03-18T00:44:32.958Z] ====== als (apache-spark) [default], iteration 19 completed (20506.973 ms) ====== [2026-03-18T00:44:32.958Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-18T00:44:32.958Z] GC before operation: completed in 106.467 ms, heap usage 543.944 MB -> 71.639 MB. [2026-03-18T00:44:52.484Z] ====== als (apache-spark) [default], iteration 20 completed (21331.532 ms) ====== [2026-03-18T00:44:52.484Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-18T00:44:52.484Z] GC before operation: completed in 92.142 ms, heap usage 104.164 MB -> 71.035 MB. [2026-03-18T00:45:12.062Z] ====== als (apache-spark) [default], iteration 21 completed (20241.376 ms) ====== [2026-03-18T00:45:12.062Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-18T00:45:12.062Z] GC before operation: completed in 93.510 ms, heap usage 108.566 MB -> 71.521 MB. [2026-03-18T00:45:31.796Z] ====== als (apache-spark) [default], iteration 22 completed (20069.985 ms) ====== [2026-03-18T00:45:31.796Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-18T00:45:31.796Z] GC before operation: completed in 136.005 ms, heap usage 523.348 MB -> 72.312 MB. [2026-03-18T00:45:55.628Z] ====== als (apache-spark) [default], iteration 23 completed (20450.426 ms) ====== [2026-03-18T00:45:55.628Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-18T00:45:55.628Z] GC before operation: completed in 102.234 ms, heap usage 286.482 MB -> 72.934 MB. [2026-03-18T00:46:15.090Z] ====== als (apache-spark) [default], iteration 24 completed (20313.331 ms) ====== [2026-03-18T00:46:15.090Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-18T00:46:15.090Z] GC before operation: completed in 98.276 ms, heap usage 279.284 MB -> 72.412 MB. [2026-03-18T00:46:34.755Z] ====== als (apache-spark) [default], iteration 25 completed (19841.305 ms) ====== [2026-03-18T00:46:34.755Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-18T00:46:34.755Z] GC before operation: completed in 96.276 ms, heap usage 142.623 MB -> 72.614 MB. [2026-03-18T00:46:53.956Z] ====== als (apache-spark) [default], iteration 26 completed (20119.000 ms) ====== [2026-03-18T00:46:53.956Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-18T00:46:53.956Z] GC before operation: completed in 100.846 ms, heap usage 352.019 MB -> 73.192 MB. [2026-03-18T00:47:17.474Z] ====== als (apache-spark) [default], iteration 27 completed (20091.320 ms) ====== [2026-03-18T00:47:17.474Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-18T00:47:17.474Z] GC before operation: completed in 104.690 ms, heap usage 229.598 MB -> 73.334 MB. [2026-03-18T00:47:37.890Z] ====== als (apache-spark) [default], iteration 28 completed (20311.336 ms) ====== [2026-03-18T00:47:37.890Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-18T00:47:37.890Z] GC before operation: completed in 108.367 ms, heap usage 137.503 MB -> 72.890 MB. [2026-03-18T00:47:56.066Z] ====== als (apache-spark) [default], iteration 29 completed (20681.254 ms) ====== [2026-03-18T00:47:56.066Z] NOTE: Could not clean and remove directory 'harness-003648-1244900347317496994', because [2026-03-18T00:47:56.066Z] NOTE: ... some files in subdirectory 'apache-spark\als\hadoop\bin' could not be deleted [2026-03-18T00:47:56.791Z] ----------------------------------- [2026-03-18T00:47:56.791Z] renaissance-als_0_PASSED [2026-03-18T00:47:56.791Z] ----------------------------------- [2026-03-18T00:47:56.791Z] [2026-03-18T00:47:56.791Z] TEST TEARDOWN: [2026-03-18T00:47:56.791Z] Nothing to be done for teardown. [2026-03-18T00:47:56.791Z] renaissance-als_0 Finish Time: Wed Mar 18 00:47:56 2026 Epoch Time (ms): 1773794876622