[2026-02-12T04:32:41.384Z] Running test renaissance-als_0 ... [2026-02-12T04:32:41.384Z] =============================================== [2026-02-12T04:32:41.384Z] renaissance-als_0 Start Time: Thu Feb 12 04:32:41 2026 Epoch Time (ms): 1770870761249 [2026-02-12T04:32:41.384Z] variation: NoOptions [2026-02-12T04:32:41.724Z] JVM_OPTIONS: [2026-02-12T04:32:41.724Z] { \ [2026-02-12T04:32:41.724Z] echo ""; echo "TEST SETUP:"; \ [2026-02-12T04:32:41.724Z] echo "Nothing to be done for setup."; \ [2026-02-12T04:32:41.724Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_177087053171\\renaissance-als_0"; \ [2026-02-12T04:32:41.724Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_177087053171\\renaissance-als_0"; \ [2026-02-12T04:32:41.724Z] echo ""; echo "TESTING:"; \ [2026-02-12T04:32:41.724Z] "c:/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_177087053171\\renaissance-als_0"\\als.json" als; \ [2026-02-12T04:32:41.724Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_177087053171\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-12T04:32:41.724Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-12T04:32:41.724Z] echo "Nothing to be done for teardown."; \ [2026-02-12T04:32:41.724Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_177087053171\\TestTargetResult"; [2026-02-12T04:32:41.724Z] [2026-02-12T04:32:41.724Z] TEST SETUP: [2026-02-12T04:32:41.724Z] Nothing to be done for setup. [2026-02-12T04:32:41.724Z] [2026-02-12T04:32:41.724Z] TESTING: [2026-02-12T04:32:55.355Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-02-12T04:32:57.754Z] WARNING: An illegal reflective access operation has occurred [2026-02-12T04:32:57.754Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_177087053171/renaissance-als_0/harness-043242-8468658002765442447/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-02-12T04:32:57.754Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-02-12T04:32:57.754Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-02-12T04:32:57.754Z] WARNING: All illegal access operations will be denied in a future release [2026-02-12T04:32:58.955Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-12T04:32:58.955Z] GC before operation: completed in 76.084 ms, heap usage 261.502 MB -> 34.617 MB. [2026-02-12T04:33:48.972Z] ====== als (apache-spark) [default], iteration 0 completed (41752.508 ms) ====== [2026-02-12T04:33:48.972Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-12T04:33:48.972Z] GC before operation: completed in 131.650 ms, heap usage 126.795 MB -> 60.467 MB. [2026-02-12T04:34:08.580Z] ====== als (apache-spark) [default], iteration 1 completed (24323.214 ms) ====== [2026-02-12T04:34:08.580Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-12T04:34:08.580Z] GC before operation: completed in 127.609 ms, heap usage 229.861 MB -> 61.313 MB. [2026-02-12T04:34:32.338Z] ====== als (apache-spark) [default], iteration 2 completed (22734.727 ms) ====== [2026-02-12T04:34:32.338Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-12T04:34:32.338Z] GC before operation: completed in 126.729 ms, heap usage 230.276 MB -> 61.864 MB. [2026-02-12T04:34:55.953Z] ====== als (apache-spark) [default], iteration 3 completed (22624.150 ms) ====== [2026-02-12T04:34:55.953Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-12T04:34:55.953Z] GC before operation: completed in 166.481 ms, heap usage 224.296 MB -> 61.930 MB. [2026-02-12T04:35:19.430Z] ====== als (apache-spark) [default], iteration 4 completed (22503.842 ms) ====== [2026-02-12T04:35:19.430Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-12T04:35:19.430Z] GC before operation: completed in 140.184 ms, heap usage 106.005 MB -> 62.315 MB. [2026-02-12T04:35:39.151Z] ====== als (apache-spark) [default], iteration 5 completed (21659.492 ms) ====== [2026-02-12T04:35:39.151Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-12T04:35:39.151Z] GC before operation: completed in 141.111 ms, heap usage 453.965 MB -> 63.776 MB. [2026-02-12T04:36:03.158Z] ====== als (apache-spark) [default], iteration 6 completed (23199.271 ms) ====== [2026-02-12T04:36:03.158Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-12T04:36:03.158Z] GC before operation: completed in 204.756 ms, heap usage 220.172 MB -> 63.506 MB. [2026-02-12T04:36:27.262Z] ====== als (apache-spark) [default], iteration 7 completed (22365.819 ms) ====== [2026-02-12T04:36:27.262Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-12T04:36:27.262Z] GC before operation: completed in 155.352 ms, heap usage 227.855 MB -> 64.050 MB. [2026-02-12T04:36:50.923Z] ====== als (apache-spark) [default], iteration 8 completed (22524.755 ms) ====== [2026-02-12T04:36:50.923Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-12T04:36:50.923Z] GC before operation: completed in 149.837 ms, heap usage 265.016 MB -> 65.016 MB. [2026-02-12T04:37:14.354Z] ====== als (apache-spark) [default], iteration 9 completed (23758.298 ms) ====== [2026-02-12T04:37:14.354Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-12T04:37:14.354Z] GC before operation: completed in 148.875 ms, heap usage 253.196 MB -> 65.301 MB. [2026-02-12T04:37:38.216Z] ====== als (apache-spark) [default], iteration 10 completed (23114.331 ms) ====== [2026-02-12T04:37:38.216Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-12T04:37:38.216Z] GC before operation: completed in 138.272 ms, heap usage 238.219 MB -> 65.575 MB. [2026-02-12T04:38:01.875Z] ====== als (apache-spark) [default], iteration 11 completed (22699.388 ms) ====== [2026-02-12T04:38:01.875Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-12T04:38:01.875Z] GC before operation: completed in 170.668 ms, heap usage 101.956 MB -> 65.339 MB. [2026-02-12T04:38:25.026Z] ====== als (apache-spark) [default], iteration 12 completed (22930.049 ms) ====== [2026-02-12T04:38:25.026Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-12T04:38:25.026Z] GC before operation: completed in 149.848 ms, heap usage 228.139 MB -> 65.623 MB. [2026-02-12T04:38:44.825Z] ====== als (apache-spark) [default], iteration 13 completed (22748.603 ms) ====== [2026-02-12T04:38:44.825Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-12T04:38:45.279Z] GC before operation: completed in 153.622 ms, heap usage 255.775 MB -> 66.329 MB. [2026-02-12T04:39:08.842Z] ====== als (apache-spark) [default], iteration 14 completed (21577.170 ms) ====== [2026-02-12T04:39:08.842Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-12T04:39:08.842Z] GC before operation: completed in 161.259 ms, heap usage 217.324 MB -> 66.110 MB. [2026-02-12T04:39:32.857Z] ====== als (apache-spark) [default], iteration 15 completed (22516.259 ms) ====== [2026-02-12T04:39:32.857Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-12T04:39:32.857Z] GC before operation: completed in 161.685 ms, heap usage 296.869 MB -> 66.959 MB. [2026-02-12T04:39:52.550Z] ====== als (apache-spark) [default], iteration 16 completed (21840.553 ms) ====== [2026-02-12T04:39:52.550Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-12T04:39:52.550Z] GC before operation: completed in 136.862 ms, heap usage 133.316 MB -> 66.489 MB. [2026-02-12T04:40:16.928Z] ====== als (apache-spark) [default], iteration 17 completed (21975.875 ms) ====== [2026-02-12T04:40:16.928Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-12T04:40:16.928Z] GC before operation: completed in 173.421 ms, heap usage 237.234 MB -> 66.843 MB. [2026-02-12T04:40:40.438Z] ====== als (apache-spark) [default], iteration 18 completed (22211.040 ms) ====== [2026-02-12T04:40:40.438Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-12T04:40:40.438Z] GC before operation: completed in 134.454 ms, heap usage 224.927 MB -> 67.553 MB. [2026-02-12T04:41:00.064Z] ====== als (apache-spark) [default], iteration 19 completed (21894.711 ms) ====== [2026-02-12T04:41:00.064Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-12T04:41:00.064Z] GC before operation: completed in 163.357 ms, heap usage 241.404 MB -> 67.332 MB. [2026-02-12T04:41:24.616Z] ====== als (apache-spark) [default], iteration 20 completed (23614.272 ms) ====== [2026-02-12T04:41:24.616Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-12T04:41:24.616Z] GC before operation: completed in 152.254 ms, heap usage 253.106 MB -> 68.039 MB. [2026-02-12T04:41:49.040Z] ====== als (apache-spark) [default], iteration 21 completed (22228.781 ms) ====== [2026-02-12T04:41:49.040Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-12T04:41:49.040Z] GC before operation: completed in 165.101 ms, heap usage 96.918 MB -> 67.737 MB. [2026-02-12T04:42:12.562Z] ====== als (apache-spark) [default], iteration 22 completed (22813.524 ms) ====== [2026-02-12T04:42:12.562Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-12T04:42:12.563Z] GC before operation: completed in 149.474 ms, heap usage 106.636 MB -> 67.973 MB. [2026-02-12T04:42:35.931Z] ====== als (apache-spark) [default], iteration 23 completed (23011.978 ms) ====== [2026-02-12T04:42:35.931Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-12T04:42:35.931Z] GC before operation: completed in 149.935 ms, heap usage 89.064 MB -> 68.254 MB. [2026-02-12T04:42:55.407Z] ====== als (apache-spark) [default], iteration 24 completed (22209.424 ms) ====== [2026-02-12T04:42:55.407Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-12T04:42:55.407Z] GC before operation: completed in 152.655 ms, heap usage 246.682 MB -> 69.085 MB. [2026-02-12T04:43:19.384Z] ====== als (apache-spark) [default], iteration 25 completed (21973.853 ms) ====== [2026-02-12T04:43:19.384Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-12T04:43:19.384Z] GC before operation: completed in 151.865 ms, heap usage 258.217 MB -> 69.331 MB. [2026-02-12T04:43:43.294Z] ====== als (apache-spark) [default], iteration 26 completed (22290.092 ms) ====== [2026-02-12T04:43:43.294Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-12T04:43:43.294Z] GC before operation: completed in 157.769 ms, heap usage 109.345 MB -> 68.996 MB. [2026-02-12T04:44:02.764Z] ====== als (apache-spark) [default], iteration 27 completed (22058.688 ms) ====== [2026-02-12T04:44:02.764Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-12T04:44:03.308Z] GC before operation: completed in 155.406 ms, heap usage 277.245 MB -> 69.817 MB. [2026-02-12T04:44:27.075Z] ====== als (apache-spark) [default], iteration 28 completed (23201.268 ms) ====== [2026-02-12T04:44:27.075Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-12T04:44:27.075Z] GC before operation: completed in 154.086 ms, heap usage 273.140 MB -> 70.058 MB. [2026-02-12T04:44:50.485Z] ====== als (apache-spark) [default], iteration 29 completed (21377.074 ms) ====== [2026-02-12T04:44:50.485Z] ----------------------------------- [2026-02-12T04:44:50.485Z] renaissance-als_0_PASSED [2026-02-12T04:44:50.485Z] ----------------------------------- [2026-02-12T04:44:50.485Z] [2026-02-12T04:44:50.485Z] TEST TEARDOWN: [2026-02-12T04:44:50.485Z] Nothing to be done for teardown. [2026-02-12T04:44:50.485Z] renaissance-als_0 Finish Time: Thu Feb 12 04:44:48 2026 Epoch Time (ms): 1770871488754