[2025-08-02T03:31:37.968Z] Running test renaissance-als_0 ... [2025-08-02T03:31:37.968Z] =============================================== [2025-08-02T03:31:38.276Z] renaissance-als_0 Start Time: Sat Aug 2 03:31:37 2025 Epoch Time (ms): 1754105497994 [2025-08-02T03:31:38.276Z] variation: NoOptions [2025-08-02T03:31:38.276Z] JVM_OPTIONS: [2025-08-02T03:31:38.276Z] { \ [2025-08-02T03:31:38.276Z] echo ""; echo "TEST SETUP:"; \ [2025-08-02T03:31:38.276Z] echo "Nothing to be done for setup."; \ [2025-08-02T03:31:38.276Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\renaissance-als_0"; \ [2025-08-02T03:31:38.276Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\renaissance-als_0"; \ [2025-08-02T03:31:38.276Z] echo ""; echo "TESTING:"; \ [2025-08-02T03:31:38.276Z] "c:/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\renaissance-als_0"\\als.json" als; \ [2025-08-02T03:31:38.276Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-02T03:31:38.276Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-02T03:31:38.276Z] echo "Nothing to be done for teardown."; \ [2025-08-02T03:31:38.276Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17541054958678\\TestTargetResult"; [2025-08-02T03:31:38.597Z] [2025-08-02T03:31:38.597Z] TEST SETUP: [2025-08-02T03:31:38.597Z] Nothing to be done for setup. [2025-08-02T03:31:38.597Z] [2025-08-02T03:31:38.597Z] TESTING: [2025-08-02T03:31:57.446Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-02T03:31:59.657Z] WARNING: An illegal reflective access operation has occurred [2025-08-02T03:31:59.657Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_rerun/aqa-tests/TKG/output_17541054958678/renaissance-als_0/harness-033139-18317123702994076968/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-02T03:31:59.657Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-02T03:31:59.657Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-02T03:31:59.657Z] WARNING: All illegal access operations will be denied in a future release [2025-08-02T03:32:01.257Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-02T03:32:01.257Z] GC before operation: completed in 107.454 ms, heap usage 313.500 MB -> 34.989 MB. [2025-08-02T03:32:41.374Z] ====== als (apache-spark) [default], iteration 0 completed (36260.526 ms) ====== [2025-08-02T03:32:41.374Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-02T03:32:41.374Z] GC before operation: completed in 127.021 ms, heap usage 169.153 MB -> 60.861 MB. [2025-08-02T03:33:00.396Z] ====== als (apache-spark) [default], iteration 1 completed (20678.601 ms) ====== [2025-08-02T03:33:00.396Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-02T03:33:00.396Z] GC before operation: completed in 148.006 ms, heap usage 285.605 MB -> 61.778 MB. [2025-08-02T03:33:23.353Z] ====== als (apache-spark) [default], iteration 2 completed (21885.931 ms) ====== [2025-08-02T03:33:23.353Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-02T03:33:23.353Z] GC before operation: completed in 190.004 ms, heap usage 547.029 MB -> 62.617 MB. [2025-08-02T03:33:42.345Z] ====== als (apache-spark) [default], iteration 3 completed (20307.748 ms) ====== [2025-08-02T03:33:42.345Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-02T03:33:42.345Z] GC before operation: completed in 185.426 ms, heap usage 123.759 MB -> 62.237 MB. [2025-08-02T03:34:05.208Z] ====== als (apache-spark) [default], iteration 4 completed (19685.632 ms) ====== [2025-08-02T03:34:05.208Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-02T03:34:05.208Z] GC before operation: completed in 167.566 ms, heap usage 241.739 MB -> 62.877 MB. [2025-08-02T03:34:24.150Z] ====== als (apache-spark) [default], iteration 5 completed (19695.101 ms) ====== [2025-08-02T03:34:24.150Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-02T03:34:24.150Z] GC before operation: completed in 184.405 ms, heap usage 265.492 MB -> 63.850 MB. [2025-08-02T03:34:43.087Z] ====== als (apache-spark) [default], iteration 6 completed (19576.967 ms) ====== [2025-08-02T03:34:43.087Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-02T03:34:43.087Z] GC before operation: completed in 180.429 ms, heap usage 227.349 MB -> 64.630 MB. [2025-08-02T03:35:06.384Z] ====== als (apache-spark) [default], iteration 7 completed (20156.941 ms) ====== [2025-08-02T03:35:06.384Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-02T03:35:06.384Z] GC before operation: completed in 197.874 ms, heap usage 236.267 MB -> 64.930 MB. [2025-08-02T03:35:25.646Z] ====== als (apache-spark) [default], iteration 8 completed (19554.530 ms) ====== [2025-08-02T03:35:25.646Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-02T03:35:25.646Z] GC before operation: completed in 179.651 ms, heap usage 245.367 MB -> 65.457 MB. [2025-08-02T03:35:45.083Z] ====== als (apache-spark) [default], iteration 9 completed (19467.485 ms) ====== [2025-08-02T03:35:45.083Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-02T03:35:45.083Z] GC before operation: completed in 168.143 ms, heap usage 395.228 MB -> 65.842 MB. [2025-08-02T03:36:04.010Z] ====== als (apache-spark) [default], iteration 10 completed (19517.209 ms) ====== [2025-08-02T03:36:04.010Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-02T03:36:04.010Z] GC before operation: completed in 157.131 ms, heap usage 219.496 MB -> 66.089 MB. [2025-08-02T03:36:23.186Z] ====== als (apache-spark) [default], iteration 11 completed (19484.063 ms) ====== [2025-08-02T03:36:23.186Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-02T03:36:23.186Z] GC before operation: completed in 157.131 ms, heap usage 239.329 MB -> 66.244 MB. [2025-08-02T03:36:45.978Z] ====== als (apache-spark) [default], iteration 12 completed (19327.356 ms) ====== [2025-08-02T03:36:45.978Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-02T03:36:45.978Z] GC before operation: completed in 149.237 ms, heap usage 369.421 MB -> 66.717 MB. [2025-08-02T03:37:08.855Z] ====== als (apache-spark) [default], iteration 13 completed (23902.438 ms) ====== [2025-08-02T03:37:08.855Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-02T03:37:08.855Z] GC before operation: completed in 162.624 ms, heap usage 357.097 MB -> 66.837 MB. [2025-08-02T03:37:27.862Z] ====== als (apache-spark) [default], iteration 14 completed (19923.498 ms) ====== [2025-08-02T03:37:27.862Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-02T03:37:27.862Z] GC before operation: completed in 246.564 ms, heap usage 103.190 MB -> 66.400 MB. [2025-08-02T03:37:47.090Z] ====== als (apache-spark) [default], iteration 15 completed (19324.628 ms) ====== [2025-08-02T03:37:47.090Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-02T03:37:47.090Z] GC before operation: completed in 178.718 ms, heap usage 398.230 MB -> 67.374 MB. [2025-08-02T03:38:10.035Z] ====== als (apache-spark) [default], iteration 16 completed (21892.694 ms) ====== [2025-08-02T03:38:10.035Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-02T03:38:10.035Z] GC before operation: completed in 182.241 ms, heap usage 259.258 MB -> 67.561 MB. [2025-08-02T03:38:29.120Z] ====== als (apache-spark) [default], iteration 17 completed (19528.666 ms) ====== [2025-08-02T03:38:29.120Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-02T03:38:29.120Z] GC before operation: completed in 199.695 ms, heap usage 254.859 MB -> 67.764 MB. [2025-08-02T03:38:52.095Z] ====== als (apache-spark) [default], iteration 18 completed (20305.890 ms) ====== [2025-08-02T03:38:52.095Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-02T03:38:52.095Z] GC before operation: completed in 168.192 ms, heap usage 275.532 MB -> 68.013 MB. [2025-08-02T03:39:10.980Z] ====== als (apache-spark) [default], iteration 19 completed (20565.057 ms) ====== [2025-08-02T03:39:10.981Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-02T03:39:10.981Z] GC before operation: completed in 178.466 ms, heap usage 235.352 MB -> 68.260 MB. [2025-08-02T03:39:33.876Z] ====== als (apache-spark) [default], iteration 20 completed (20258.491 ms) ====== [2025-08-02T03:39:33.876Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-02T03:39:33.876Z] GC before operation: completed in 151.356 ms, heap usage 271.172 MB -> 68.497 MB. [2025-08-02T03:39:52.734Z] ====== als (apache-spark) [default], iteration 21 completed (19627.655 ms) ====== [2025-08-02T03:39:52.734Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-02T03:39:52.734Z] GC before operation: completed in 167.189 ms, heap usage 174.187 MB -> 68.627 MB. [2025-08-02T03:40:11.704Z] ====== als (apache-spark) [default], iteration 22 completed (19622.982 ms) ====== [2025-08-02T03:40:11.704Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-02T03:40:11.704Z] GC before operation: completed in 167.217 ms, heap usage 265.699 MB -> 69.023 MB. [2025-08-02T03:40:34.635Z] ====== als (apache-spark) [default], iteration 23 completed (19593.304 ms) ====== [2025-08-02T03:40:34.635Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-02T03:40:34.635Z] GC before operation: completed in 168.079 ms, heap usage 317.601 MB -> 69.399 MB. [2025-08-02T03:40:53.732Z] ====== als (apache-spark) [default], iteration 24 completed (19833.305 ms) ====== [2025-08-02T03:40:53.732Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-02T03:40:53.732Z] GC before operation: completed in 167.961 ms, heap usage 268.765 MB -> 69.526 MB. [2025-08-02T03:41:12.683Z] ====== als (apache-spark) [default], iteration 25 completed (19744.677 ms) ====== [2025-08-02T03:41:12.683Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-02T03:41:12.683Z] GC before operation: completed in 154.628 ms, heap usage 276.822 MB -> 69.901 MB. [2025-08-02T03:41:32.142Z] ====== als (apache-spark) [default], iteration 26 completed (19828.866 ms) ====== [2025-08-02T03:41:32.142Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-02T03:41:32.142Z] GC before operation: completed in 164.885 ms, heap usage 246.925 MB -> 70.086 MB. [2025-08-02T03:41:55.418Z] ====== als (apache-spark) [default], iteration 27 completed (20012.643 ms) ====== [2025-08-02T03:41:55.418Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-02T03:41:55.418Z] GC before operation: completed in 158.043 ms, heap usage 294.138 MB -> 70.270 MB. [2025-08-02T03:42:14.367Z] ====== als (apache-spark) [default], iteration 28 completed (19308.431 ms) ====== [2025-08-02T03:42:14.367Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-02T03:42:14.367Z] GC before operation: completed in 182.197 ms, heap usage 320.056 MB -> 70.157 MB. [2025-08-02T03:42:33.198Z] ====== als (apache-spark) [default], iteration 29 completed (19339.743 ms) ====== [2025-08-02T03:42:33.198Z] ----------------------------------- [2025-08-02T03:42:33.198Z] renaissance-als_0_PASSED [2025-08-02T03:42:33.198Z] ----------------------------------- [2025-08-02T03:42:33.198Z] [2025-08-02T03:42:33.198Z] TEST TEARDOWN: [2025-08-02T03:42:33.198Z] Nothing to be done for teardown. [2025-08-02T03:42:33.198Z] renaissance-als_0 Finish Time: Sat Aug 2 03:42:32 2025 Epoch Time (ms): 1754106152695