# JMH 1.11.2 (released 36 days ago) # VM version: JDK 9-internal, VM 9-internal+0-2015-12-04-141002.shade.jdk9-dev # VM invoker: /home/shade/trunks/jdk9-dev/build/linux-x86_64-normal-server-release/images/jdk/bin/java # VM options: # Warmup: 5 iterations, 1 s each # Measurement: 5 iterations, 1 s each # Timeout: 10 min per iteration # Threads: 1 thread, will synchronize iterations # Benchmark mode: Average time, time/op # Benchmark: org.openjdk.UnsafeImplicitNullCheck.plain # Run progress: 0.00% complete, ETA 00:00:50 # Fork: 1 of 1 # Preparing profilers: LinuxPerfAsmProfiler # Profilers consume stdout and stderr from target VM, use -v EXTRA to copy to console # Warmup Iteration 1: 2.450 ns/op # Warmup Iteration 2: 2.437 ns/op # Warmup Iteration 3: 2.040 ns/op # Warmup Iteration 4: 1.968 ns/op # Warmup Iteration 5: 2.004 ns/op Iteration 1: 2.171 ns/op Iteration 2: 2.059 ns/op Iteration 3: 1.969 ns/op Iteration 4: 2.047 ns/op Iteration 5: 1.973 ns/op # Processing profiler results: LinuxPerfAsmProfiler Result "plain": 2.044 ±(99.9%) 0.316 ns/op [Average] (min, avg, max) = (1.969, 2.044, 2.171), stdev = 0.082 CI (99.9%): [1.727, 2.360] (assumes normal distribution) Secondary result "·asm": PrintAssembly processed: 150080 total address lines. Perf output processed (skipped 5.154 seconds): Column 1: cycles (7435 events) Column 2: instructions (7434 events) Hottest code regions (>10.00% "cycles" events): ....[Hottest Region 1].............................................................................. [0x7f4f65444b60:0x7f4f65444bcb] in org.openjdk.jmh.infra.Blackhole::consume # parm0: rdx = int # [sp+0x30] (sp of caller) 0x00007f4f65444b60: mov 0x8(%rsi),%r10d 0x00007f4f65444b64: shl $0x3,%r10 0x00007f4f65444b68: cmp %r10,%rax 0x00007f4f65444b6b: jne 0x00007f4f5d99cf60 ; {runtime_call ic_miss_stub} 0x00007f4f65444b71: data16 xchg %ax,%ax 0x00007f4f65444b74: nopl 0x0(%rax,%rax,1) 0x00007f4f65444b7c: data16 data16 xchg %ax,%ax [Verified Entry Point] 6.32% 7.30% 0x00007f4f65444b80: mov %eax,-0x14000(%rsp) 5.80% 6.34% 0x00007f4f65444b87: push %rbp 0.12% 0.04% 0x00007f4f65444b88: sub $0x20,%rsp ;*synchronization entry ; - org.openjdk.jmh.infra.Blackhole::consume@-1 (line 402) 12.29% 10.48% 0x00007f4f65444b8c: mov 0xb0(%rsi),%r10d ;*getfield i1 {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@1 (line 402) 0.34% 0.17% 0x00007f4f65444b93: mov %edx,%r8d 0.11% 0.13% 0x00007f4f65444b96: xor 0xb8(%rsi),%r8d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@15 (line 404) 6.44% 5.77% 0x00007f4f65444b9d: mov %edx,%r9d 5.07% 4.49% 0x00007f4f65444ba0: xor %r10d,%r9d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@12 (line 404) 0.30% 0.48% 0x00007f4f65444ba3: cmp %r8d,%r9d ╭ 0x00007f4f65444ba6: je 0x00007f4f65444bb4 ;*if_icmpne {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) 9.39% 8.74% │ 0x00007f4f65444ba8: add $0x20,%rsp 1.64% 1.76% │ 0x00007f4f65444bac: pop %rbp 0.93% 0.85% │ 0x00007f4f65444bad: test %eax,0x1425744d(%rip) # 0x00007f4f7969c000 │ ; {poll_return} 0.28% 0.38% │ 0x00007f4f65444bb3: retq ↘ 0x00007f4f65444bb4: mov %rsi,%rbp 0x00007f4f65444bb7: mov %edx,(%rsp) 0x00007f4f65444bba: mov %r9d,0x4(%rsp) 0x00007f4f65444bbf: mov %r8d,0x8(%rsp) 0x00007f4f65444bc4: mov $0xffffff5d,%esi 0x00007f4f65444bc9: xchg %ax,%ax 0x00007f4f65444bcb: callq 0x00007f4f5d99e860 ; ImmutableOopMap{rbp=Oop } ;*if_icmpne {reexecute=1 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) .................................................................................................... 49.02% 46.95% ....[Hottest Region 2].............................................................................. [0x7f4f65446224:0x7f4f65446289] in org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub 0x00007f4f65446224: mov 0x18(%rsp),%r10 0x00007f4f65446229: movzbl 0x94(%r10),%r10d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@29 (line 201) ; implicit exception: dispatches to 0x00007f4f65446309 0x00007f4f65446231: mov $0x1,%ebp 0x00007f4f65446236: test %r10d,%r10d ╭ 0x00007f4f65446239: jne 0x00007f4f65446274 ;*ifeq {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@32 (line 201) │ 0x00007f4f6544623b: nopl 0x0(%rax,%rax,1) ;*aload {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@13 (line 199) 0.63% 0.86% │↗ 0x00007f4f65446240: mov 0x8(%rsp),%r10 0.05% 0.17% ││ 0x00007f4f65446245: mov 0xc(%r10),%r10d ;*getfield t {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::plain@1 (line 71) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@16 (line 199) 11.54% 10.76% ││ 0x00007f4f65446249: mov 0xc(%r12,%r10,8),%edx ;*getfield f {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::plain@4 (line 71) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@16 (line 199) ││ ; implicit exception: dispatches to 0x00007f4f654462c5 7.45% 7.98% ││ 0x00007f4f6544624e: mov 0x10(%rsp),%rsi 0.39% 0.23% ││ 0x00007f4f65446253: callq 0x00007f4f5d99d160 ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*invokevirtual consume {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@19 (line 199) ││ ; {optimized virtual_call} 14.97% 16.33% ││ 0x00007f4f65446258: mov 0x18(%rsp),%r10 1.08% 1.20% ││ 0x00007f4f6544625d: movzbl 0x94(%r10),%r10d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@29 (line 201) 0.35% 0.28% ││ 0x00007f4f65446265: add $0x1,%rbp ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*ifeq {reexecute=1 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@32 (line 201) 10.80% 11.65% ││ 0x00007f4f65446269: test %eax,0x14255d91(%rip) # 0x00007f4f7969c000 ││ ; {poll} 0.74% 0.55% ││ 0x00007f4f6544626f: test %r10d,%r10d │╰ 0x00007f4f65446272: je 0x00007f4f65446240 ;*aload_2 {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@35 (line 202) ↘ 0x00007f4f65446274: movabs $0x7f4f7832e6f0,%r10 0x00007f4f6544627e: callq *%r10 ;*invokestatic nanoTime {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@36 (line 202) 0x00007f4f65446281: mov (%rsp),%r10 0x00007f4f65446285: mov %rbp,0x18(%r10) ;*putfield measuredOps {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub@51 (line 204) 0x00007f4f65446289: mov %rax,0x30(%r10) ;*putfield stopTime {reexecute=0 rethrow=0 return_oop=0} .................................................................................................... 48.00% 50.01% ....[Hottest Regions]............................................................................... 49.02% 46.95% [0x7f4f65444b60:0x7f4f65444bcb] in org.openjdk.jmh.infra.Blackhole::consume 48.00% 50.01% [0x7f4f65446224:0x7f4f65446289] in org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub 1.04% 1.02% [0xffffffff8105b83a:0xffffffff8105b83c] in [unknown] ([unknown]) 0.09% 0.05% [0xffffffff817b3c20:0xffffffff817b3c20] in [unknown] ([unknown]) 0.09% 0.01% [0xffffffff817b5e50:0xffffffff817b5e5c] in [unknown] ([unknown]) 0.08% 0.03% [0xffffffff817b3d99:0xffffffff817b3d99] in [unknown] ([unknown]) 0.07% 0.03% [0xffffffff810d3027:0xffffffff810d305c] in [unknown] ([unknown]) 0.07% 0.04% [0xffffffff817b57c4:0xffffffff817b57c4] in [unknown] ([unknown]) 0.05% 0.04% [0xffffffff811ee76c:0xffffffff811ee775] in [unknown] ([unknown]) 0.04% 0.04% [0xffffffff81165483:0xffffffff81165483] in [unknown] ([unknown]) 0.04% [0xffffffff8120a0e2:0xffffffff8120a0f4] in [unknown] ([unknown]) 0.04% 0.04% [0xffffffff817b5a06:0xffffffff817b5a06] in [unknown] ([unknown]) 0.04% 0.01% [0x7f4f5df1c280:0x7f4f7969c000] in sun.reflect.ClassFileAssembler::cpi 0.03% [0xffffffff811ec06e:0xffffffff811ec083] in [unknown] ([unknown]) 0.03% [0xffffffff8121ef99:0xffffffff8121ef99] in [unknown] ([unknown]) 0.03% [0xffffffff8122a753:0xffffffff8122a761] in [unknown] ([unknown]) 0.03% [0xffffffff813ab913:0xffffffff813ab92e] in [unknown] ([unknown]) 0.03% [0xffffffff817b5a9e:0xffffffff817b5a9e] in [unknown] ([unknown]) 0.03% 0.04% [0x7f4f5d99cf60:0x7f4f78a35d00] in sun.reflect.ClassFileAssembler::cpi 0.03% 0.03% [0x7f4f5d99cf60:0x7f4f78a35d00] in sun.reflect.ClassFileAssembler::cpi 1.13% 1.64% <...other 161 warm regions...> .................................................................................................... 100.00% 99.99% ....[Hottest Methods (after inlining)].............................................................. 49.02% 46.95% org.openjdk.jmh.infra.Blackhole::consume 48.00% 50.01% org.openjdk.generated.UnsafeImplicitNullCheck_plain_jmhTest::plain_avgt_jmhStub 2.15% 2.11% [unknown] ([unknown]) 0.79% 0.89% sun.reflect.ClassFileAssembler::cpi 0.01% 0.01% [unknown] (perf-8682.map) .................................................................................................... 100.00% 99.96% ....[Distribution by Area].......................................................................... 97.83% 97.87% 2.15% 2.11% 0.01% .................................................................................................... 100.00% 99.99% # JMH 1.11.2 (released 36 days ago) # VM version: JDK 9-internal, VM 9-internal+0-2015-12-04-141002.shade.jdk9-dev # VM invoker: /home/shade/trunks/jdk9-dev/build/linux-x86_64-normal-server-release/images/jdk/bin/java # VM options: # Warmup: 5 iterations, 1 s each # Measurement: 5 iterations, 1 s each # Timeout: 10 min per iteration # Threads: 1 thread, will synchronize iterations # Benchmark mode: Average time, time/op # Benchmark: org.openjdk.UnsafeImplicitNullCheck.plain_checked # Run progress: 20.00% complete, ETA 00:01:07 # Fork: 1 of 1 # Preparing profilers: LinuxPerfAsmProfiler # Profilers consume stdout and stderr from target VM, use -v EXTRA to copy to console # Warmup Iteration 1: 2.456 ns/op # Warmup Iteration 2: 2.432 ns/op # Warmup Iteration 3: 1.966 ns/op # Warmup Iteration 4: 1.974 ns/op # Warmup Iteration 5: 1.971 ns/op Iteration 1: 1.990 ns/op Iteration 2: 1.966 ns/op Iteration 3: 1.968 ns/op Iteration 4: 1.969 ns/op Iteration 5: 1.969 ns/op # Processing profiler results: LinuxPerfAsmProfiler Result "plain_checked": 1.972 ±(99.9%) 0.038 ns/op [Average] (min, avg, max) = (1.966, 1.972, 1.990), stdev = 0.010 CI (99.9%): [1.934, 2.011] (assumes normal distribution) Secondary result "·asm": PrintAssembly processed: 146039 total address lines. Perf output processed (skipped 5.139 seconds): Column 1: cycles (7580 events) Column 2: instructions (7570 events) Hottest code regions (>10.00% "cycles" events): ....[Hottest Region 1].............................................................................. [0x7fc67d444260:0x7fc67d4442cb] in org.openjdk.jmh.infra.Blackhole::consume # parm0: rdx = int # [sp+0x30] (sp of caller) 0x00007fc67d444260: mov 0x8(%rsi),%r10d 0x00007fc67d444264: shl $0x3,%r10 0x00007fc67d444268: cmp %r10,%rax 0x00007fc67d44426b: jne 0x00007fc67599cf60 ; {runtime_call ic_miss_stub} 0x00007fc67d444271: data16 xchg %ax,%ax 0x00007fc67d444274: nopl 0x0(%rax,%rax,1) 0x00007fc67d44427c: data16 data16 xchg %ax,%ax [Verified Entry Point] 6.60% 7.87% 0x00007fc67d444280: mov %eax,-0x14000(%rsp) 5.83% 6.61% 0x00007fc67d444287: push %rbp 0.07% 0.08% 0x00007fc67d444288: sub $0x20,%rsp ;*synchronization entry ; - org.openjdk.jmh.infra.Blackhole::consume@-1 (line 402) 12.20% 10.95% 0x00007fc67d44428c: mov 0xb0(%rsi),%r10d ;*getfield i1 {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@1 (line 402) 0.12% 0.11% 0x00007fc67d444293: mov %edx,%r8d 0.05% 0.09% 0x00007fc67d444296: xor 0xb8(%rsi),%r8d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@15 (line 404) 6.46% 6.06% 0x00007fc67d44429d: mov %edx,%r9d 5.61% 4.44% 0x00007fc67d4442a0: xor %r10d,%r9d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@12 (line 404) 0.08% 0.21% 0x00007fc67d4442a3: cmp %r8d,%r9d ╭ 0x00007fc67d4442a6: je 0x00007fc67d4442b4 ;*if_icmpne {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) 10.16% 9.46% │ 0x00007fc67d4442a8: add $0x20,%rsp 1.24% 1.20% │ 0x00007fc67d4442ac: pop %rbp 0.32% 0.30% │ 0x00007fc67d4442ad: test %eax,0x12b13d4d(%rip) # 0x00007fc68ff58000 │ ; {poll_return} 0.17% 0.12% │ 0x00007fc67d4442b3: retq ↘ 0x00007fc67d4442b4: mov %rsi,%rbp 0x00007fc67d4442b7: mov %edx,(%rsp) 0x00007fc67d4442ba: mov %r9d,0x4(%rsp) 0x00007fc67d4442bf: mov %r8d,0x8(%rsp) 0x00007fc67d4442c4: mov $0xffffff5d,%esi 0x00007fc67d4442c9: xchg %ax,%ax 0x00007fc67d4442cb: callq 0x00007fc67599e860 ; ImmutableOopMap{rbp=Oop } ;*if_icmpne {reexecute=1 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) .................................................................................................... 48.91% 47.50% ....[Hottest Region 2].............................................................................. [0x7fc67d445ca4:0x7fc67d445d09] in org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub 0x00007fc67d445ca4: mov (%rsp),%r10 0x00007fc67d445ca8: movzbl 0x94(%r10),%r11d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@29 (line 201) ; implicit exception: dispatches to 0x00007fc67d445d7d 0x00007fc67d445cb0: mov $0x1,%ebp 0x00007fc67d445cb5: test %r11d,%r11d ╭ 0x00007fc67d445cb8: jne 0x00007fc67d445cf3 ;*ifeq {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@32 (line 201) │ 0x00007fc67d445cba: nopw 0x0(%rax,%rax,1) ;*aload {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@13 (line 199) 0.34% 0.59% │↗ 0x00007fc67d445cc0: mov 0x10(%rsp),%r10 0.11% 0.13% ││ 0x00007fc67d445cc5: mov 0xc(%r10),%r11d ;*getfield t {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::plain_checked@1 (line 76) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@16 (line 199) 11.35% 11.68% ││ 0x00007fc67d445cc9: mov 0xc(%r12,%r11,8),%edx ;*getfield f {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::plain_checked@19 (line 79) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@16 (line 199) ││ ; implicit exception: dispatches to 0x00007fc67d445d45 7.55% 7.66% ││ 0x00007fc67d445cce: mov 0x18(%rsp),%rsi 0.17% 0.09% ││ 0x00007fc67d445cd3: callq 0x00007fc67599d160 ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*invokevirtual consume {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@19 (line 199) ││ ; {optimized virtual_call} 16.04% 14.76% ││ 0x00007fc67d445cd8: mov (%rsp),%r10 0.78% 0.70% ││ 0x00007fc67d445cdc: movzbl 0x94(%r10),%r10d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@29 (line 201) 0.21% 0.24% ││ 0x00007fc67d445ce4: add $0x1,%rbp ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*ifeq {reexecute=1 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@32 (line 201) 11.03% 13.13% ││ 0x00007fc67d445ce8: test %eax,0x12b12312(%rip) # 0x00007fc68ff58000 ││ ; {poll} 0.49% 0.58% ││ 0x00007fc67d445cee: test %r10d,%r10d │╰ 0x00007fc67d445cf1: je 0x00007fc67d445cc0 ;*aload_2 {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@35 (line 202) ↘ 0x00007fc67d445cf3: movabs $0x7fc68ebea6f0,%r10 0x00007fc67d445cfd: callq *%r10 ;*invokestatic nanoTime {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@36 (line 202) 0x00007fc67d445d00: mov 0x8(%rsp),%r10 0x00007fc67d445d05: mov %rbp,0x18(%r10) ;*putfield measuredOps {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub@51 (line 204) 0x00007fc67d445d09: mov %rax,0x30(%r10) ;*putfield stopTime {reexecute=0 rethrow=0 return_oop=0} .................................................................................................... 48.06% 49.56% ....[Hottest Regions]............................................................................... 48.91% 47.50% [0x7fc67d444260:0x7fc67d4442cb] in org.openjdk.jmh.infra.Blackhole::consume 48.06% 49.56% [0x7fc67d445ca4:0x7fc67d445d09] in org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub 1.02% 1.00% [0xffffffff8105b83a:0xffffffff8105b83d] in [unknown] ([unknown]) 0.05% [0xffffffff810ee609:0xffffffff810ee612] in [unknown] ([unknown]) 0.05% 0.13% [0x7fc68ec80957:0x7fc68ec809aa] in _ZN13RelocIterator10set_limitsEPhS0_ (libjvm.so) 0.05% 0.01% [0x7fc68f7cbdc5:0x7fc68f7cbdd8] in _IO_fwrite (libc-2.19.so) 0.05% 0.01% [0x7fc68f84888d:0x7fc68f84888d] in __GI___libc_write (libc-2.19.so) 0.04% 0.04% [0xffffffff8102cdb4:0xffffffff8102cdb4] in [unknown] ([unknown]) 0.04% 0.04% [0xffffffff81032b2c:0xffffffff81032b2c] in [unknown] ([unknown]) 0.04% 0.01% [0xffffffff810d303d:0xffffffff810d305c] in [unknown] ([unknown]) 0.04% 0.01% [0xffffffff812071bd:0xffffffff812071eb] in [unknown] ([unknown]) 0.04% 0.04% [0xffffffff813b2d37:0xffffffff813b2d37] in [unknown] ([unknown]) 0.04% 0.05% [0x7fc68ebf666c:0x7fc68ebf6697] in _ZN10fileStream5writeEPKcm (libjvm.so) 0.03% [0xffffffff810e25f6:0xffffffff810e260c] in [unknown] ([unknown]) 0.03% [0xffffffff81175e70:0xffffffff81175e70] in [unknown] ([unknown]) 0.03% [0xffffffff811a205d:0xffffffff811a206b] in [unknown] ([unknown]) 0.03% 0.03% [0xffffffff811ee6e8:0xffffffff811ee6f6] in [unknown] ([unknown]) 0.03% 0.01% [0xffffffff813ccd3e:0xffffffff813ccd49] in [unknown] ([unknown]) 0.03% 0.04% [0xffffffff817b3c20:0xffffffff817b3c20] in [unknown] ([unknown]) 0.03% 0.01% [0xffffffff817b3d99:0xffffffff817b3d99] in [unknown] ([unknown]) 1.39% 1.47% <...other 176 warm regions...> .................................................................................................... 100.00% 99.99% ....[Hottest Methods (after inlining)].............................................................. 48.91% 47.50% org.openjdk.jmh.infra.Blackhole::consume 48.06% 49.56% org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_jmhTest::plain_checked_avgt_jmhStub 2.14% 1.78% [unknown] ([unknown]) 0.08% 0.01% _IO_fwrite (libc-2.19.so) 0.08% 0.08% vfprintf (libc-2.19.so) 0.07% 0.03% print_insn (libhsdis-amd64.so) 0.05% 0.07% _ZN10fileStream5writeEPKcm (libjvm.so) 0.05% 0.13% _ZN13RelocIterator10set_limitsEPhS0_ (libjvm.so) 0.05% 0.01% __GI___libc_write (libc-2.19.so) 0.03% syscall (libc-2.19.so) 0.03% 0.01% _ZN13defaultStream5writeEPKcm (libjvm.so) 0.03% 0.07% _ZN9xmlStream10write_textEPKcm (libjvm.so) 0.03% 0.03% 0.04% _ZN12outputStream15update_positionEPKcm (libjvm.so) 0.03% _IO_str_init_static_internal (libc-2.19.so) 0.03% __libc_enable_asynccancel (libc-2.19.so) 0.03% _IO_do_write@@GLIBC_2.2.5 (libc-2.19.so) 0.01% 0.01% jio_print (libjvm.so) 0.01% _ZNK7RegMask4SizeEv (libjvm.so) 0.01% 0.26% 0.11% <...other 20 warm methods...> .................................................................................................... 100.00% 99.42% ....[Distribution by Area].......................................................................... 97.03% 97.12% 2.14% 1.78% 0.37% 0.25% 0.34% 0.71% 0.08% 0.08% 0.04% 0.04% .................................................................................................... 100.00% 99.99% # JMH 1.11.2 (released 36 days ago) # VM version: JDK 9-internal, VM 9-internal+0-2015-12-04-141002.shade.jdk9-dev # VM invoker: /home/shade/trunks/jdk9-dev/build/linux-x86_64-normal-server-release/images/jdk/bin/java # VM options: # Warmup: 5 iterations, 1 s each # Measurement: 5 iterations, 1 s each # Timeout: 10 min per iteration # Threads: 1 thread, will synchronize iterations # Benchmark mode: Average time, time/op # Benchmark: org.openjdk.UnsafeImplicitNullCheck.plain_checked_local # Run progress: 40.00% complete, ETA 00:00:51 # Fork: 1 of 1 # Preparing profilers: LinuxPerfAsmProfiler # Profilers consume stdout and stderr from target VM, use -v EXTRA to copy to console # Warmup Iteration 1: 2.466 ns/op # Warmup Iteration 2: 2.440 ns/op # Warmup Iteration 3: 1.970 ns/op # Warmup Iteration 4: 1.974 ns/op # Warmup Iteration 5: 1.974 ns/op Iteration 1: 1.996 ns/op Iteration 2: 1.966 ns/op Iteration 3: 1.977 ns/op Iteration 4: 1.971 ns/op Iteration 5: 1.975 ns/op # Processing profiler results: LinuxPerfAsmProfiler Result "plain_checked_local": 1.977 ±(99.9%) 0.044 ns/op [Average] (min, avg, max) = (1.966, 1.977, 1.996), stdev = 0.011 CI (99.9%): [1.933, 2.021] (assumes normal distribution) Secondary result "·asm": PrintAssembly processed: 148433 total address lines. Perf output processed (skipped 5.167 seconds): Column 1: cycles (7268 events) Column 2: instructions (7249 events) Hottest code regions (>10.00% "cycles" events): ....[Hottest Region 1].............................................................................. [0x7f1db037f260:0x7f1db037f2cb] in org.openjdk.jmh.infra.Blackhole::consume # parm0: rdx = int # [sp+0x30] (sp of caller) 0x00007f1db037f260: mov 0x8(%rsi),%r10d 0x00007f1db037f264: shl $0x3,%r10 0x00007f1db037f268: cmp %r10,%rax 0x00007f1db037f26b: jne 0x00007f1da88d7f60 ; {runtime_call ic_miss_stub} 0x00007f1db037f271: data16 xchg %ax,%ax 0x00007f1db037f274: nopl 0x0(%rax,%rax,1) 0x00007f1db037f27c: data16 data16 xchg %ax,%ax [Verified Entry Point] 6.54% 7.13% 0x00007f1db037f280: mov %eax,-0x14000(%rsp) 5.71% 5.90% 0x00007f1db037f287: push %rbp 0.11% 0.04% 0x00007f1db037f288: sub $0x20,%rsp ;*synchronization entry ; - org.openjdk.jmh.infra.Blackhole::consume@-1 (line 402) 12.62% 11.84% 0x00007f1db037f28c: mov 0xb0(%rsi),%r10d ;*getfield i1 {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@1 (line 402) 0.11% 0.11% 0x00007f1db037f293: mov %edx,%r8d 0.10% 0.10% 0x00007f1db037f296: xor 0xb8(%rsi),%r8d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@15 (line 404) 6.16% 5.52% 0x00007f1db037f29d: mov %edx,%r9d 5.82% 4.84% 0x00007f1db037f2a0: xor %r10d,%r9d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@12 (line 404) 0.15% 0.17% 0x00007f1db037f2a3: cmp %r8d,%r9d ╭ 0x00007f1db037f2a6: je 0x00007f1db037f2b4 ;*if_icmpne {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) 10.22% 10.87% │ 0x00007f1db037f2a8: add $0x20,%rsp 1.25% 1.02% │ 0x00007f1db037f2ac: pop %rbp 0.22% 0.30% │ 0x00007f1db037f2ad: test %eax,0x12215d4d(%rip) # 0x00007f1dc2595000 │ ; {poll_return} 0.21% 0.14% │ 0x00007f1db037f2b3: retq ↘ 0x00007f1db037f2b4: mov %rsi,%rbp 0x00007f1db037f2b7: mov %edx,(%rsp) 0x00007f1db037f2ba: mov %r9d,0x4(%rsp) 0x00007f1db037f2bf: mov %r8d,0x8(%rsp) 0x00007f1db037f2c4: mov $0xffffff5d,%esi 0x00007f1db037f2c9: xchg %ax,%ax 0x00007f1db037f2cb: callq 0x00007f1da88d9860 ; ImmutableOopMap{rbp=Oop } ;*if_icmpne {reexecute=1 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) .................................................................................................... 49.22% 47.98% ....[Hottest Region 2].............................................................................. [0x7f1db03815a4:0x7f1db0381609] in org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub 0x00007f1db03815a4: mov (%rsp),%r10 0x00007f1db03815a8: movzbl 0x94(%r10),%r11d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@29 (line 201) ; implicit exception: dispatches to 0x00007f1db038167d 0x00007f1db03815b0: mov $0x1,%ebp 0x00007f1db03815b5: test %r11d,%r11d ╭ 0x00007f1db03815b8: jne 0x00007f1db03815f3 ;*ifeq {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@32 (line 201) │ 0x00007f1db03815ba: nopw 0x0(%rax,%rax,1) ;*aload {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@13 (line 199) 0.40% 0.46% │↗ 0x00007f1db03815c0: mov 0x10(%rsp),%r10 0.11% 0.11% ││ 0x00007f1db03815c5: mov 0xc(%r10),%r10d ;*getfield t {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::plain_checked_local@1 (line 84) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@16 (line 199) 10.86% 11.37% ││ 0x00007f1db03815c9: mov 0xc(%r12,%r10,8),%edx ;*getfield f {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::plain_checked_local@18 (line 88) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@16 (line 199) ││ ; implicit exception: dispatches to 0x00007f1db0381645 7.42% 8.00% ││ 0x00007f1db03815ce: mov 0x18(%rsp),%rsi 0.18% 0.10% ││ 0x00007f1db03815d3: callq 0x00007f1da88d8160 ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*invokevirtual consume {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@19 (line 199) ││ ; {optimized virtual_call} 16.00% 14.76% ││ 0x00007f1db03815d8: mov (%rsp),%r10 0.84% 0.70% ││ 0x00007f1db03815dc: movzbl 0x94(%r10),%r10d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@29 (line 201) 0.36% 0.28% ││ 0x00007f1db03815e4: add $0x1,%rbp ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*ifeq {reexecute=1 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@32 (line 201) 10.43% 12.07% ││ 0x00007f1db03815e8: test %eax,0x12213a12(%rip) # 0x00007f1dc2595000 ││ ; {poll} 0.32% 0.47% ││ 0x00007f1db03815ee: test %r10d,%r10d │╰ 0x00007f1db03815f1: je 0x00007f1db03815c0 ;*aload_2 {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@35 (line 202) ↘ 0x00007f1db03815f3: movabs $0x7f1dc12276f0,%r10 0x00007f1db03815fd: callq *%r10 ;*invokestatic nanoTime {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@36 (line 202) 0x00007f1db0381600: mov 0x8(%rsp),%r10 0x00007f1db0381605: mov %rbp,0x18(%r10) ;*putfield measuredOps {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub@51 (line 204) 0x00007f1db0381609: mov %rax,0x30(%r10) ;*putfield stopTime {reexecute=0 rethrow=0 return_oop=0} .................................................................................................... 46.90% 48.31% ....[Hottest Regions]............................................................................... 49.22% 47.98% [0x7f1db037f260:0x7f1db037f2cb] in org.openjdk.jmh.infra.Blackhole::consume 46.90% 48.31% [0x7f1db03815a4:0x7f1db0381609] in org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub 2.12% 2.11% [0xffffffff8105b83a:0xffffffff8105b83a] in [unknown] ([unknown]) 0.10% 0.01% [0xffffffff817b5e50:0xffffffff817b5e6d] in [unknown] ([unknown]) 0.08% 0.08% [0xffffffff8109cfb2:0xffffffff8109cfb2] in [unknown] ([unknown]) 0.06% [0x0:0x7f1dc192ed00] in sun.reflect.ClassFileAssembler::cpi 0.06% 0.07% [0x0:0x7f1dc192ed00] in sun.reflect.ClassFileAssembler::cpi 0.04% 0.04% [0xffffffff810d303d:0xffffffff810d305f] in [unknown] ([unknown]) 0.04% [0xffffffff811eb8ae:0xffffffff811eb8b1] in [unknown] ([unknown]) 0.04% 0.01% [0x0:0x7f1dc192ed00] in sun.reflect.ClassFileAssembler::cpi 0.04% [0x7f1dc1e08e98:0x7f1dc1e08eb5] in _IO_fwrite (libc-2.19.so) 0.03% 0.01% [0xffffffff811ee751:0xffffffff811ee76c] in [unknown] ([unknown]) 0.03% [0xffffffff8129e222:0xffffffff8129e236] in [unknown] ([unknown]) 0.03% 0.01% [0x0:0x7f1dc192ed00] in sun.reflect.ClassFileAssembler::cpi 0.03% 0.03% [0x0:0x7f1dc192ed00] in sun.reflect.ClassFileAssembler::cpi 0.03% [0x0:0x7f1dc192ed00] in sun.reflect.ClassFileAssembler::cpi 0.03% [0x7f1dc1de3d67:0x7f1dc1de3d72] in vfprintf (libc-2.19.so) 0.03% 0.01% [0x7f1dc1e8588d:0x7f1dc1e8588d] in __GI___libc_write (libc-2.19.so) 0.01% [0xffffffff81088346:0xffffffff81088346] in [unknown] ([unknown]) 0.01% [0xffffffff81091060:0xffffffff81091060] in [unknown] ([unknown]) 1.07% 1.30% <...other 142 warm regions...> .................................................................................................... 99.99% 99.99% ....[Hottest Methods (after inlining)].............................................................. 49.22% 47.98% org.openjdk.jmh.infra.Blackhole::consume 46.90% 48.31% org.openjdk.generated.UnsafeImplicitNullCheck_plain_checked_local_jmhTest::plain_checked_local_avgt_jmhStub 2.94% 2.97% [unknown] ([unknown]) 0.55% 0.39% sun.reflect.ClassFileAssembler::cpi 0.11% 0.03% vfprintf (libc-2.19.so) 0.06% 0.01% _IO_fwrite (libc-2.19.so) 0.04% 0.08% 0.03% 0.03% __strchrnul (libc-2.19.so) 0.03% 0.01% __GI___libc_write (libc-2.19.so) 0.01% _IO_fflush (libc-2.19.so) 0.01% 0.11% _IO_default_xsputn (libc-2.19.so) 0.01% _IO_do_write@@GLIBC_2.2.5 (libc-2.19.so) 0.01% _IO_file_xsputn@@GLIBC_2.2.5 (libc-2.19.so) 0.01% 0.03% 0.01% dlsym (libdl-2.19.so) .................................................................................................... 99.99% 99.92% ....[Distribution by Area].......................................................................... 96.75% 96.79% 2.94% 2.97% 0.28% 0.23% 0.01% .................................................................................................... 99.99% 99.99% # JMH 1.11.2 (released 36 days ago) # VM version: JDK 9-internal, VM 9-internal+0-2015-12-04-141002.shade.jdk9-dev # VM invoker: /home/shade/trunks/jdk9-dev/build/linux-x86_64-normal-server-release/images/jdk/bin/java # VM options: # Warmup: 5 iterations, 1 s each # Measurement: 5 iterations, 1 s each # Timeout: 10 min per iteration # Threads: 1 thread, will synchronize iterations # Benchmark mode: Average time, time/op # Benchmark: org.openjdk.UnsafeImplicitNullCheck.unsafe_checked # Run progress: 60.00% complete, ETA 00:00:31 # Fork: 1 of 1 # Preparing profilers: LinuxPerfAsmProfiler # Profilers consume stdout and stderr from target VM, use -v EXTRA to copy to console # Warmup Iteration 1: 2.537 ns/op # Warmup Iteration 2: 2.577 ns/op # Warmup Iteration 3: 2.040 ns/op # Warmup Iteration 4: 2.040 ns/op # Warmup Iteration 5: 2.040 ns/op Iteration 1: 2.073 ns/op Iteration 2: 2.043 ns/op Iteration 3: 2.041 ns/op Iteration 4: 2.041 ns/op Iteration 5: 2.041 ns/op # Processing profiler results: LinuxPerfAsmProfiler Result "unsafe_checked": 2.048 ±(99.9%) 0.056 ns/op [Average] (min, avg, max) = (2.041, 2.048, 2.073), stdev = 0.014 CI (99.9%): [1.992, 2.103] (assumes normal distribution) Secondary result "·asm": PrintAssembly processed: 150982 total address lines. Perf output processed (skipped 5.181 seconds): Column 1: cycles (7547 events) Column 2: instructions (7546 events) Hottest code regions (>10.00% "cycles" events): ....[Hottest Region 1].............................................................................. [0x7ff5fd4465ac:0x7ff5fd446611] in org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@19 (line 199) ; {optimized virtual_call} 0x00007ff5fd4465ac: mov (%rsp),%r10 0x00007ff5fd4465b0: movzbl 0x94(%r10),%r10d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@29 (line 201) ; implicit exception: dispatches to 0x00007ff5fd446695 0x00007ff5fd4465b8: mov $0x1,%ebp 0x00007ff5fd4465bd: test %r10d,%r10d ╭ 0x00007ff5fd4465c0: jne 0x00007ff5fd4465fb ;*aload {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@13 (line 199) 0.42% 0.33% │↗ 0x00007ff5fd4465c2: mov 0x10(%rsp),%r10 1.35% 0.93% ││ 0x00007ff5fd4465c7: mov 0xc(%r10),%r11d ;*getfield t {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::unsafe_checked@1 (line 93) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@16 (line 199) 9.88% 11.00% ││ 0x00007ff5fd4465cb: test %r11d,%r11d ││ 0x00007ff5fd4465ce: je 0x00007ff5fd446625 0.04% 0.09% ││ 0x00007ff5fd4465d0: mov 0xc(%r12,%r11,8),%edx ;*invokevirtual getIntVolatile {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::unsafe_checked@25 (line 96) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@16 (line 199) 7.69% 9.21% ││ 0x00007ff5fd4465d5: mov 0x18(%rsp),%rsi 0.54% 0.53% ││ 0x00007ff5fd4465da: nop 4.32% 5.22% ││ 0x00007ff5fd4465db: callq 0x00007ff5f599d160 ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*invokevirtual consume {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@19 (line 199) ││ ; {optimized virtual_call} 12.72% 13.28% ││ 0x00007ff5fd4465e0: mov (%rsp),%r10 0.13% 0.19% ││ 0x00007ff5fd4465e4: movzbl 0x94(%r10),%r10d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@29 (line 201) 1.79% 1.43% ││ 0x00007ff5fd4465ec: add $0x1,%rbp ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*ifeq {reexecute=1 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@32 (line 201) 10.16% 9.78% ││ 0x00007ff5fd4465f0: test %eax,0x12ecda0a(%rip) # 0x00007ff610314000 ││ ; {poll} 0.09% 0.03% ││ 0x00007ff5fd4465f6: test %r10d,%r10d │╰ 0x00007ff5fd4465f9: je 0x00007ff5fd4465c2 ;*aload_2 {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@35 (line 202) ↘ 0x00007ff5fd4465fb: movabs $0x7ff60efa66f0,%r10 0x00007ff5fd446605: callq *%r10 ;*invokestatic nanoTime {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@36 (line 202) 0x00007ff5fd446608: mov 0x8(%rsp),%r10 0x00007ff5fd44660d: mov %rbp,0x18(%r10) ;*putfield measuredOps {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub@51 (line 204) 0x00007ff5fd446611: mov %rax,0x30(%r10) ;*putfield stopTime {reexecute=0 rethrow=0 return_oop=0} .................................................................................................... 49.15% 52.01% ....[Hottest Region 2].............................................................................. [0x7ff5fd4451e0:0x7ff5fd44524b] in org.openjdk.jmh.infra.Blackhole::consume # parm0: rdx = int # [sp+0x30] (sp of caller) 0x00007ff5fd4451e0: mov 0x8(%rsi),%r10d 0x00007ff5fd4451e4: shl $0x3,%r10 0x00007ff5fd4451e8: cmp %r10,%rax 0x00007ff5fd4451eb: jne 0x00007ff5f599cf60 ; {runtime_call ic_miss_stub} 0x00007ff5fd4451f1: data16 xchg %ax,%ax 0x00007ff5fd4451f4: nopl 0x0(%rax,%rax,1) 0x00007ff5fd4451fc: data16 data16 xchg %ax,%ax [Verified Entry Point] 7.01% 6.76% 0x00007ff5fd445200: mov %eax,-0x14000(%rsp) 5.00% 4.84% 0x00007ff5fd445207: push %rbp 0.04% 0x00007ff5fd445208: sub $0x20,%rsp ;*synchronization entry ; - org.openjdk.jmh.infra.Blackhole::consume@-1 (line 402) 6.43% 6.22% 0x00007ff5fd44520c: mov 0xb0(%rsi),%r10d ;*getfield i1 {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@1 (line 402) 4.57% 3.76% 0x00007ff5fd445213: mov %edx,%r8d 0.04% 0.04% 0x00007ff5fd445216: xor 0xb8(%rsi),%r8d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@15 (line 404) 6.61% 5.76% 0x00007ff5fd44521d: mov %edx,%r9d 0.72% 0.53% 0x00007ff5fd445220: xor %r10d,%r9d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@12 (line 404) 4.44% 4.47% 0x00007ff5fd445223: cmp %r8d,%r9d ╭ 0x00007ff5fd445226: je 0x00007ff5fd445234 ;*if_icmpne {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) 6.59% 6.27% │ 0x00007ff5fd445228: add $0x20,%rsp 0.07% 0.05% │ 0x00007ff5fd44522c: pop %rbp 0.64% 0.57% │ 0x00007ff5fd44522d: test %eax,0x12ecedcd(%rip) # 0x00007ff610314000 │ ; {poll_return} 4.64% 4.57% │ 0x00007ff5fd445233: retq ↘ 0x00007ff5fd445234: mov %rsi,%rbp 0x00007ff5fd445237: mov %edx,(%rsp) 0x00007ff5fd44523a: mov %r9d,0x4(%rsp) 0x00007ff5fd44523f: mov %r8d,0x8(%rsp) 0x00007ff5fd445244: mov $0xffffff5d,%esi 0x00007ff5fd445249: xchg %ax,%ax 0x00007ff5fd44524b: callq 0x00007ff5f599e860 ; ImmutableOopMap{rbp=Oop } ;*if_icmpne {reexecute=1 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) .................................................................................................... 46.73% 43.88% ....[Hottest Regions]............................................................................... 49.15% 52.01% [0x7ff5fd4465ac:0x7ff5fd446611] in org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub 46.73% 43.88% [0x7ff5fd4451e0:0x7ff5fd44524b] in org.openjdk.jmh.infra.Blackhole::consume 1.88% 1.87% [0xffffffff8105b83a:0xffffffff8105b83a] in [unknown] ([unknown]) 0.09% 0.01% [0xffffffff817b5e50:0xffffffff817b5e7b] in [unknown] ([unknown]) 0.09% [0x7ff60fc0488d:0x7ff60fc0489c] in __GI___libc_write (libc-2.19.so) 0.07% 0.01% [0x1:0x7ff60f121290] in sun.reflect.ClassFileAssembler::cpi 0.05% 0.04% [0xffffffff810d3020:0xffffffff810d303d] in [unknown] ([unknown]) 0.05% 0.03% [0x7ff60fc0d859:0x7ff60fc0d861] in syscall (libc-2.19.so) 0.04% 0.04% [0xffffffff8116aa9e:0xffffffff8116aa9e] in [unknown] ([unknown]) 0.04% 0.01% [0xffffffff811ee6e8:0xffffffff811ee6ef] in [unknown] ([unknown]) 0.04% 0.04% [0xffffffff81209269:0xffffffff81209288] in [unknown] ([unknown]) 0.04% 0.01% [0xffffffff817b3c10:0xffffffff817b3c20] in [unknown] ([unknown]) 0.04% 0.11% [0x1:0x7ff60f121290] in sun.reflect.ClassFileAssembler::cpi 0.04% 0.01% [0x7ff60f171c38:0x7ff60f171c4c] in _ZN9xmlStream10write_textEPKcm (libjvm.so) 0.04% [0x7ff60fb94757:0x7ff60fb9476d] in _IO_default_xsputn (libc-2.19.so) 0.03% [0xffffffff81091060:0xffffffff81091060] in [unknown] ([unknown]) 0.03% [0xffffffff81191dc8:0xffffffff81191dd0] in [unknown] ([unknown]) 0.03% 0.01% [0xffffffff811ebf06:0xffffffff811ebf21] in [unknown] ([unknown]) 0.03% [0xffffffff811ecc97:0xffffffff811ecc97] in [unknown] ([unknown]) 0.03% 0.07% [0xffffffff813b2d35:0xffffffff813b2d37] in [unknown] ([unknown]) 1.47% 1.83% <...other 187 warm regions...> .................................................................................................... 100.00% 99.99% ....[Hottest Methods (after inlining)].............................................................. 49.15% 52.01% org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_jmhTest::unsafe_checked_avgt_jmhStub 46.73% 43.88% org.openjdk.jmh.infra.Blackhole::consume 2.93% 2.98% [unknown] ([unknown]) 0.56% 0.60% sun.reflect.ClassFileAssembler::cpi 0.09% 0.03% _ZN9xmlStream10write_textEPKcm (libjvm.so) 0.09% __GI___libc_write (libc-2.19.so) 0.08% 0.04% vfprintf (libc-2.19.so) 0.05% 0.03% syscall (libc-2.19.so) 0.05% 0.01% _IO_file_xsputn@@GLIBC_2.2.5 (libc-2.19.so) 0.04% 0.05% _IO_default_xsputn (libc-2.19.so) 0.01% _IO_file_sync@@GLIBC_2.2.5 (libc-2.19.so) 0.01% _IO_no_init (libc-2.19.so) 0.01% __vsprintf_chk (libc-2.19.so) 0.01% 0.01% 0.01% _ZN9xmlStream5writeEPKcm (libjvm.so) 0.01% __memcpy_sse2_unaligned (libc-2.19.so) 0.01% 0.01% __vsnprintf_chk (libc-2.19.so) 0.01% 0.01% _ZN13xmlTextStream5flushEv (libjvm.so) 0.01% pthread_mutex_unlock (libc-2.19.so) 0.09% 0.05% <...other 7 warm methods...> .................................................................................................... 100.00% 99.71% ....[Distribution by Area].......................................................................... 96.48% 96.53% 2.93% 2.98% 0.46% 0.40% 0.12% 0.04% 0.01% 0.04% .................................................................................................... 100.00% 99.99% # JMH 1.11.2 (released 36 days ago) # VM version: JDK 9-internal, VM 9-internal+0-2015-12-04-141002.shade.jdk9-dev # VM invoker: /home/shade/trunks/jdk9-dev/build/linux-x86_64-normal-server-release/images/jdk/bin/java # VM options: # Warmup: 5 iterations, 1 s each # Measurement: 5 iterations, 1 s each # Timeout: 10 min per iteration # Threads: 1 thread, will synchronize iterations # Benchmark mode: Average time, time/op # Benchmark: org.openjdk.UnsafeImplicitNullCheck.unsafe_checked_local # Run progress: 80.00% complete, ETA 00:00:15 # Fork: 1 of 1 # Preparing profilers: LinuxPerfAsmProfiler # Profilers consume stdout and stderr from target VM, use -v EXTRA to copy to console # Warmup Iteration 1: 2.519 ns/op # Warmup Iteration 2: 2.593 ns/op # Warmup Iteration 3: 2.041 ns/op # Warmup Iteration 4: 2.111 ns/op # Warmup Iteration 5: 2.199 ns/op Iteration 1: 2.096 ns/op Iteration 2: 2.071 ns/op Iteration 3: 2.068 ns/op Iteration 4: 2.040 ns/op Iteration 5: 2.080 ns/op # Processing profiler results: LinuxPerfAsmProfiler Result "unsafe_checked_local": 2.071 ±(99.9%) 0.079 ns/op [Average] (min, avg, max) = (2.040, 2.071, 2.096), stdev = 0.020 CI (99.9%): [1.992, 2.150] (assumes normal distribution) Secondary result "·asm": PrintAssembly processed: 151258 total address lines. Perf output processed (skipped 5.145 seconds): Column 1: cycles (7311 events) Column 2: instructions (7288 events) Hottest code regions (>10.00% "cycles" events): ....[Hottest Region 1].............................................................................. [0x7f413d4473ac:0x7f413d447411] in org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@19 (line 199) ; {optimized virtual_call} 0x00007f413d4473ac: mov (%rsp),%r10 0x00007f413d4473b0: movzbl 0x94(%r10),%r10d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@29 (line 201) ; implicit exception: dispatches to 0x00007f413d447495 0x00007f413d4473b8: mov $0x1,%ebp 0x00007f413d4473bd: test %r10d,%r10d ╭ 0x00007f413d4473c0: jne 0x00007f413d4473fb ;*aload {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@13 (line 199) 0.51% 0.36% │↗ 0x00007f413d4473c2: mov 0x10(%rsp),%r10 1.27% 0.97% ││ 0x00007f413d4473c7: mov 0xc(%r10),%r10d ;*getfield t {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::unsafe_checked_local@1 (line 101) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@16 (line 199) 9.92% 10.19% ││ 0x00007f413d4473cb: test %r10d,%r10d ││ 0x00007f413d4473ce: je 0x00007f413d447425 0.03% ││ 0x00007f413d4473d0: mov 0xc(%r12,%r10,8),%edx ;*invokevirtual getIntVolatile {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.UnsafeImplicitNullCheck::unsafe_checked_local@24 (line 105) ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@16 (line 199) 7.39% 8.71% ││ 0x00007f413d4473d5: mov 0x18(%rsp),%rsi 0.44% 0.48% ││ 0x00007f413d4473da: nop 5.09% 5.23% ││ 0x00007f413d4473db: callq 0x00007f413599d160 ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*invokevirtual consume {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@19 (line 199) ││ ; {optimized virtual_call} 12.72% 13.91% ││ 0x00007f413d4473e0: mov (%rsp),%r10 0.16% 0.05% ││ 0x00007f413d4473e4: movzbl 0x94(%r10),%r10d ;*getfield isDone {reexecute=0 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@29 (line 201) 1.64% 1.65% ││ 0x00007f413d4473ec: add $0x1,%rbp ; ImmutableOopMap{[0]=Oop [8]=Oop [16]=Oop [24]=Oop } ││ ;*ifeq {reexecute=1 rethrow=0 return_oop=0} ││ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@32 (line 201) 9.94% 9.28% ││ 0x00007f413d4473f0: test %eax,0x13b4ac0a(%rip) # 0x00007f4150f92000 ││ ; {poll} 0.08% ││ 0x00007f413d4473f6: test %r10d,%r10d │╰ 0x00007f413d4473f9: je 0x00007f413d4473c2 ;*aload_2 {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@35 (line 202) ↘ 0x00007f413d4473fb: movabs $0x7f414fc246f0,%r10 0x00007f413d447405: callq *%r10 ;*invokestatic nanoTime {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@36 (line 202) 0x00007f413d447408: mov 0x8(%rsp),%r10 0x00007f413d44740d: mov %rbp,0x18(%r10) ;*putfield measuredOps {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub@51 (line 204) 0x00007f413d447411: mov %rax,0x30(%r10) ;*putfield stopTime {reexecute=0 rethrow=0 return_oop=0} .................................................................................................... 49.10% 50.92% ....[Hottest Region 2].............................................................................. [0x7f413d445ce0:0x7f413d445d4b] in org.openjdk.jmh.infra.Blackhole::consume # parm0: rdx = int # [sp+0x30] (sp of caller) 0x00007f413d445ce0: mov 0x8(%rsi),%r10d 0x00007f413d445ce4: shl $0x3,%r10 0x00007f413d445ce8: cmp %r10,%rax 0x00007f413d445ceb: jne 0x00007f413599cf60 ; {runtime_call ic_miss_stub} 0x00007f413d445cf1: data16 xchg %ax,%ax 0x00007f413d445cf4: nopl 0x0(%rax,%rax,1) 0x00007f413d445cfc: data16 data16 xchg %ax,%ax [Verified Entry Point] 5.91% 6.94% 0x00007f413d445d00: mov %eax,-0x14000(%rsp) 5.51% 5.42% 0x00007f413d445d07: push %rbp 0.01% 0.08% 0x00007f413d445d08: sub $0x20,%rsp ;*synchronization entry ; - org.openjdk.jmh.infra.Blackhole::consume@-1 (line 402) 6.83% 6.41% 0x00007f413d445d0c: mov 0xb0(%rsi),%r10d ;*getfield i1 {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@1 (line 402) 5.09% 4.31% 0x00007f413d445d13: mov %edx,%r8d 0.05% 0.10% 0x00007f413d445d16: xor 0xb8(%rsi),%r8d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@15 (line 404) 6.44% 5.41% 0x00007f413d445d1d: mov %edx,%r9d 0.40% 0.40% 0x00007f413d445d20: xor %r10d,%r9d ;*ixor {reexecute=0 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@12 (line 404) 5.06% 5.04% 0x00007f413d445d23: cmp %r8d,%r9d ╭ 0x00007f413d445d26: je 0x00007f413d445d34 ;*if_icmpne {reexecute=0 rethrow=0 return_oop=0} │ ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) 6.33% 5.79% │ 0x00007f413d445d28: add $0x20,%rsp 0.18% 0.11% │ 0x00007f413d445d2c: pop %rbp 0.60% 0.48% │ 0x00007f413d445d2d: test %eax,0x13b4c2cd(%rip) # 0x00007f4150f92000 │ ; {poll_return} 4.94% 5.05% │ 0x00007f413d445d33: retq ↘ 0x00007f413d445d34: mov %rsi,%rbp 0x00007f413d445d37: mov %edx,(%rsp) 0x00007f413d445d3a: mov %r9d,0x4(%rsp) 0x00007f413d445d3f: mov %r8d,0x8(%rsp) 0x00007f413d445d44: mov $0xffffff5d,%esi 0x00007f413d445d49: xchg %ax,%ax 0x00007f413d445d4b: callq 0x00007f413599e860 ; ImmutableOopMap{rbp=Oop } ;*if_icmpne {reexecute=1 rethrow=0 return_oop=0} ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 404) .................................................................................................... 47.35% 45.53% ....[Hottest Regions]............................................................................... 49.10% 50.92% [0x7f413d4473ac:0x7f413d447411] in org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub 47.35% 45.53% [0x7f413d445ce0:0x7f413d445d4b] in org.openjdk.jmh.infra.Blackhole::consume 1.44% 1.44% [0xffffffff8105b83a:0xffffffff8105b83d] in [unknown] ([unknown]) 0.08% 0.03% [0xffffffff817b3c20:0xffffffff817b3c23] in [unknown] ([unknown]) 0.05% 0.05% [0xffffffff810d303a:0xffffffff810d3068] in [unknown] ([unknown]) 0.04% 0.04% [0xffffffff8109d005:0xffffffff8109d005] in [unknown] ([unknown]) 0.04% 0.04% [0xffffffff8116aa9e:0xffffffff8116aa9e] in [unknown] ([unknown]) 0.04% 0.01% [0xffffffff811ee6e8:0xffffffff811ee6f6] in [unknown] ([unknown]) 0.04% [0xffffffff811ee76c:0xffffffff811ee77f] in [unknown] ([unknown]) 0.04% 0.04% [0xffffffff8120926d:0xffffffff812092a2] in [unknown] ([unknown]) 0.04% [0xffffffff8122ac20:0xffffffff8122ac29] in [unknown] ([unknown]) 0.04% [0xffffffff817b3d85:0xffffffff817b3d99] in [unknown] ([unknown]) 0.04% 0.01% [0xffffffff817b5a90:0xffffffff817b5a9e] in [unknown] ([unknown]) 0.04% 0.04% [0x7f41359b46d9:0x7f41503436dc] in sun.re::cpi 0.03% [0xffffffff81076f25:0xffffffff81076f25] in [unknown] ([unknown]) 0.03% [0xffffffff811eccab:0xffffffff811eccaf] in [unknown] ([unknown]) 0.03% 0.03% [0xffffffff817b5a13:0xffffffff817b5a13] in [unknown] ([unknown]) 0.03% [0xffffffff817b5e50:0xffffffff817b5e50] in [unknown] ([unknown]) 0.03% [0x7:0x7f414fcd34b0] in sun.re::cpi 0.03% [0x7:0x7f414fcd34b0] in sun.re::cpi 1.44% 1.80% <...other 181 warm regions...> .................................................................................................... 100.00% 99.99% ....[Hottest Methods (after inlining)].............................................................. 49.10% 50.92% org.openjdk.generated.UnsafeImplicitNullCheck_unsafe_checked_local_jmhTest::unsafe_checked_local_avgt_jmhStub 47.35% 45.53% org.openjdk.jmh.infra.Blackhole::consume 2.59% 2.44% [unknown] ([unknown]) 0.85% 1.06% sun.re::cpi 0.04% 0.01% 0.01% 0.01% 0.01% 0.01% .................................................................................................... 100.00% 99.95% ....[Distribution by Area].......................................................................... 97.41% 97.53% 2.59% 2.44% .................................................................................................... 100.00% 99.99% # Run complete. Total time: 00:01:15 Benchmark Mode Cnt Score Error Units UnsafeImplicitNullCheck.plain avgt 5 2.044 ± 0.316 ns/op UnsafeImplicitNullCheck.plain:·asm avgt NaN --- UnsafeImplicitNullCheck.plain_checked avgt 5 1.972 ± 0.038 ns/op UnsafeImplicitNullCheck.plain_checked:·asm avgt NaN --- UnsafeImplicitNullCheck.plain_checked_local avgt 5 1.977 ± 0.044 ns/op UnsafeImplicitNullCheck.plain_checked_local:·asm avgt NaN --- UnsafeImplicitNullCheck.unsafe_checked avgt 5 2.048 ± 0.056 ns/op UnsafeImplicitNullCheck.unsafe_checked:·asm avgt NaN --- UnsafeImplicitNullCheck.unsafe_checked_local avgt 5 2.071 ± 0.079 ns/op UnsafeImplicitNullCheck.unsafe_checked_local:·asm avgt NaN ---