# Detecting actual CPU count: 8 detected # JMH 1.17.2 (released 97 days ago) # VM version: JDK 9-internal, VM 9-internal+0-adhoc.zgu.jdk9 # VM invoker: /home/zgu/workspace/shenandoah-bug/jdk9/build/linux-x86_64-normal-server-release/jdk/bin/java # VM options: -XX:+UseShenandoahGC -XX:ConcGCThreads=4 -XX:ParallelGCThreads=4 -XX:+UnlockExperimentalVMOptions -Xmx1g -Xlog:gc+stats # Warmup: 10 iterations, 3 s each # Measurement: 10 iterations, 1 s each # Timeout: 10 min per iteration # Threads: 8 threads, will synchronize iterations # Benchmark mode: Throughput, ops/time # Benchmark: org.openjdk.specjvm2008.Compiler.sunflow # Run progress: 0.00% complete, ETA 00:00:40 # Fork: 1 of 1 # Warmup Iteration 1: 97.629 ops/min # Warmup Iteration 2: 157.382 ops/min # Warmup Iteration 3: 190.185 ops/min # Warmup Iteration 4: 200.339 ops/min # Warmup Iteration 5: 246.714 ops/min # Warmup Iteration 6: 273.570 ops/min # Warmup Iteration 7: 265.250 ops/min # Warmup Iteration 8: 264.885 ops/min # Warmup Iteration 9: 271.865 ops/min # Warmup Iteration 10: 256.289 ops/min Iteration 1: 277.694 ops/min Iteration 2: 277.783 ops/min Iteration 3: 279.148 ops/min Iteration 4: 284.278 ops/min Iteration 5: 272.526 ops/min Iteration 6: 267.727 ops/min Iteration 7: 280.659 ops/min Iteration 8: 283.247 ops/min Iteration 9: 267.887 ops/min Iteration 10: 273.164 ops/min [123.242s][info][gc,stats] [123.242s][info][gc,stats] GC STATISTICS: [123.242s][info][gc,stats] "gross" pauses include time to safepoint. "net" pauses are times spent in GC. [123.242s][info][gc,stats] "a" is average time for each phase, look at levels to see if average makes sense. [123.242s][info][gc,stats] "lvls" are quantiles: 0% (minimum), 25%, 50% (median), 75%, 100% (maximum). [123.242s][info][gc,stats] [123.242s][info][gc,stats] Total Pauses (gross) = 31.66 s (a = 41708 us) (n = 759) (lvls, us = 756, 1680, 7012, 83984, 191688) [123.242s][info][gc,stats] Total Pauses (net) = 1.98 s (a = 2613 us) (n = 759) (lvls, us = 78, 768, 984, 1465, 67572) [123.242s][info][gc,stats] Initial Mark Pauses (gross) = 0.83 s (a = 2177 us) (n = 381) (lvls, us = 756, 1289, 1680, 3008, 28170) [123.242s][info][gc,stats] Initial Mark Pauses (net) = 0.36 s (a = 954 us) (n = 381) (lvls, us = 527, 875, 977, 1035, 1881) [123.242s][info][gc,stats] Accumulate Stats = 0.00 s (a = 5 us) (n = 381) (lvls, us = 3, 4, 5, 5, 16) [123.242s][info][gc,stats] Make Parsable = 0.00 s (a = 3 us) (n = 381) (lvls, us = 1, 3, 3, 4, 17) [123.242s][info][gc,stats] Clear Liveness = 0.05 s (a = 139 us) (n = 381) (lvls, us = 23, 129, 154, 162, 226) [123.242s][info][gc,stats] Scan Roots = 0.30 s (a = 799 us) (n = 381) (lvls, us = 377, 725, 813, 869, 1822) [123.242s][info][gc,stats] S: Thread Roots = 0.04 s (a = 108 us) (n = 384) (lvls, us = 43, 64, 79, 121, 1143) [123.242s][info][gc,stats] S: String Table Roots = 0.11 s (a = 289 us) (n = 384) (lvls, us = 0, 322, 354, 375, 465) [123.242s][info][gc,stats] S: Universe Roots = 0.00 s (a = 2 us) (n = 384) (lvls, us = 1, 2, 2, 2, 10) [123.242s][info][gc,stats] S: JNI Roots = 0.00 s (a = 7 us) (n = 384) (lvls, us = 4, 6, 7, 8, 21) [123.242s][info][gc,stats] S: JNI Weak Roots = 0.03 s (a = 67 us) (n = 384) (lvls, us = 0, 63, 76, 91, 155) [123.242s][info][gc,stats] S: Synchronizer Roots = 0.00 s (a = 4 us) (n = 384) (lvls, us = 3, 3, 4, 4, 7) [123.242s][info][gc,stats] S: Flat Profiler Roots = 0.00 s (a = 1 us) (n = 384) (lvls, us = 0, 0, 1, 1, 1) [123.242s][info][gc,stats] S: Management Roots = 0.00 s (a = 1 us) (n = 384) (lvls, us = 0, 1, 1, 1, 2) [123.242s][info][gc,stats] S: System Dict Roots = 0.01 s (a = 29 us) (n = 384) (lvls, us = 9, 12, 14, 18, 139) [123.242s][info][gc,stats] S: CLDG Roots = 0.09 s (a = 233 us) (n = 384) (lvls, us = 87, 215, 238, 258, 546) [123.242s][info][gc,stats] S: JVMTI Roots = 0.00 s (a = 1 us) (n = 384) (lvls, us = 0, 0, 0, 1, 14) [123.242s][info][gc,stats] Resize TLABs = 0.00 s (a = 5 us) (n = 384) (lvls, us = 2, 3, 4, 5, 28) [123.242s][info][gc,stats] Final Mark Pauses (gross) = 30.83 s (a = 81552 us) (n = 378) (lvls, us = 2949, 65234, 83984, 99609, 191687) [123.242s][info][gc,stats] Final Mark Pauses (net) = 1.62 s (a = 4285 us) (n = 378) (lvls, us = 77, 717, 1250, 6016, 67572) [123.242s][info][gc,stats] Update Roots = 0.20 s (a = 516 us) (n = 378) (lvls, us = 0, 461, 498, 547, 1301) [123.242s][info][gc,stats] U: Thread Roots = 0.03 s (a = 82 us) (n = 377) (lvls, us = 25, 51, 58, 71, 788) [123.242s][info][gc,stats] U: String Table Roots = 0.07 s (a = 183 us) (n = 377) (lvls, us = 99, 170, 180, 191, 272) [123.242s][info][gc,stats] U: Universe Roots = 0.00 s (a = 1 us) (n = 377) (lvls, us = 0, 1, 1, 1, 15) [123.242s][info][gc,stats] U: JNI Roots = 0.00 s (a = 4 us) (n = 377) (lvls, us = 2, 3, 4, 5, 12) [123.242s][info][gc,stats] U: JNI Weak Roots = 0.01 s (a = 17 us) (n = 377) (lvls, us = 7, 13, 17, 19, 38) [123.242s][info][gc,stats] U: Synchronizer Roots = 0.00 s (a = 4 us) (n = 377) (lvls, us = 3, 4, 4, 4, 11) [123.242s][info][gc,stats] U: Flat Profiler Roots = 0.00 s (a = 1 us) (n = 377) (lvls, us = 0, 0, 0, 1, 1) [123.242s][info][gc,stats] U: Management Roots = 0.00 s (a = 1 us) (n = 377) (lvls, us = 1, 1, 1, 1, 2) [123.242s][info][gc,stats] U: System Dict Roots = 0.00 s (a = 9 us) (n = 377) (lvls, us = 6, 7, 9, 9, 20) [123.242s][info][gc,stats] U: CLDG Roots = 0.06 s (a = 151 us) (n = 377) (lvls, us = 88, 133, 148, 168, 319) [123.242s][info][gc,stats] U: JVMTI Roots = 0.00 s (a = 1 us) (n = 377) (lvls, us = 0, 0, 0, 1, 1) [123.242s][info][gc,stats] Drain SATB = 0.75 s (a = 1980 us) (n = 378) (lvls, us = 55, 94, 115, 184, 66127) [123.242s][info][gc,stats] Weak References = 0.15 s (a = 403 us) (n = 378) (lvls, us = 0, 0, 0, 0, 5192) [123.242s][info][gc,stats] Class Unloading = 0.52 s (a = 1382 us) (n = 378) (lvls, us = 15, 69, 78, 100, 8686) [123.242s][info][gc,stats] Prepare Evacuation = 26.64 s (a = 70471 us) (n = 378) (lvls, us = 1289, 57422, 74023, 89453, 174223) [123.242s][info][gc,stats] Initial Evacuation = 2.08 s (a = 5515 us) (n = 378) (lvls, us = 1328, 5137, 5723, 6133, 8143) [123.242s][info][gc,stats] E: Thread Roots = 0.03 s (a = 82 us) (n = 378) (lvls, us = 41, 61, 66, 77, 858) [123.242s][info][gc,stats] E: Code Cache Roots = 0.72 s (a = 1893 us) (n = 378) (lvls, us = 314, 1602, 2051, 2188, 3143) [123.242s][info][gc,stats] Concurrent Marking = 54.61 s (a = 143343 us) (n = 381) (lvls, us = 17969, 115234, 144531, 173828, 291817) [123.242s][info][gc,stats] Concurrent Evacuation = 5.61 s (a = 14844 us) (n = 378) (lvls, us = 498, 4434, 9219, 19922, 465884) [123.242s][info][gc,stats] Reset Bitmaps = 0.39 s (a = 1021 us) (n = 378) (lvls, us = 67, 533, 654, 883, 21604) [123.242s][info][gc,stats] Full GC = 1.11 s (a = 370012 us) (n = 3) (lvls, us = 337891, 337891, 337891, 378906, 391537) [123.242s][info][gc,stats] Heap Dumps = 0.00 s (a = 2 us) (n = 6) (lvls, us = 0, 0, 0, 0, 11) [123.242s][info][gc,stats] Prepare = 0.00 s (a = 1228 us) (n = 3) (lvls, us = 953, 953, 953, 1211, 1509) [123.242s][info][gc,stats] Mark = 0.22 s (a = 74327 us) (n = 3) (lvls, us = 52734, 52734, 52734, 84375, 85619) [123.242s][info][gc,stats] Drain Queues = 0.19 s (a = 63041 us) (n = 3) (lvls, us = 42578, 42578, 42578, 72852, 73452) [123.242s][info][gc,stats] Weak References = 0.01 s (a = 2643 us) (n = 3) (lvls, us = 2246, 2246, 2246, 2344, 3312) [123.242s][info][gc,stats] Class Unloading = 0.02 s (a = 7697 us) (n = 3) (lvls, us = 7051, 7051, 7051, 7227, 8798) [123.242s][info][gc,stats] Calculate Addresses = 0.05 s (a = 17381 us) (n = 3) (lvls, us = 14258, 14258, 14258, 17188, 20561) [123.242s][info][gc,stats] Adjust Pointers = 0.14 s (a = 45495 us) (n = 3) (lvls, us = 38281, 38281, 38281, 47461, 50488) [123.242s][info][gc,stats] Copy Objects = 0.69 s (a = 231560 us) (n = 3) (lvls, us = 226562, 226562, 226562, 230469, 236554) [123.242s][info][gc,stats] [123.242s][info][gc,stats] 3 allocation failure and 0 user requested GCs [123.242s][info][gc,stats] 335 successful and 43 degenerated concurrent markings [123.242s][info][gc,stats] Result "sunflow": 276.411 ±(99.9%) 8.893 ops/min [Average] (min, avg, max) = (267.727, 276.411, 284.278), stdev = 5.882 CI (99.9%): [267.518, 285.304] (assumes normal distribution) # Run complete. Total time: 00:02:03 Benchmark Mode Cnt Score Error Units Compiler.sunflow thrpt 10 276.411 ± 8.893 ops/min