# JMH 1.10.1 (released 8 days ago) # VM invoker: /home/shade/Install/jdk8u40/jre/bin/java # VM options: # Warmup: 3 iterations, 1 s each # Measurement: 3 iterations, 1 s each # Timeout: 10 min per iteration # Threads: 1 thread, will synchronize iterations # Benchmark mode: Average time, time/op # Benchmark: org.openjdk.EmptyMethod.test # Run progress: 0.00% complete, ETA 00:00:06 # 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: 1.981 ns/op # Warmup Iteration 2: 1.949 ns/op # Warmup Iteration 3: 1.617 ns/op Iteration 1: 1.611 ns/op Iteration 2: 1.616 ns/op Iteration 3: 1.614 ns/op # Processing profiler results: LinuxPerfAsmProfiler Result "test": 1.614 ±(99.9%) 0.042 ns/op [Average] (min, avg, max) = (1.611, 1.614, 1.616), stdev = 0.002 CI (99.9%): [1.571, 1.656] (assumes normal distribution) Secondary result "·asm": PrintAssembly processed: 139084 total address lines. Perf output processed (skipped 3.113 seconds): Column 1: cycles (4919 events) Column 2: instructions (4962 events) Hottest code regions (>10.00% "cycles" events): ....[Hottest Region 1].............................................................................. [0x7f6e851fb760:0x7f6e851fb7c7] in org.openjdk.EmptyMethod::doNothing [Constants] # {method} {0x00007f6e8229deb0} 'doNothing' '()V' in 'org/openjdk/EmptyMethod' # [sp+0x40] (sp of caller) 0x00007f6e851fb760: mov 0x8(%rsi),%r10d 0x00007f6e851fb764: shl $0x3,%r10 0x00007f6e851fb768: cmp %rax,%r10 0x00007f6e851fb76b: jne 0x00007f6e85045e20 ; {runtime_call} 0x00007f6e851fb771: nopw 0x0(%rax,%rax,1) 0x00007f6e851fb77c: xchg %ax,%ax [Verified Entry Point] 10.47% 5.76% 0x00007f6e851fb780: mov %eax,-0x14000(%rsp) 3.62% 3.57% 0x00007f6e851fb787: push %rbp 1.48% 1.43% 0x00007f6e851fb788: sub $0x30,%rsp ;*return ; - org.openjdk.EmptyMethod::doNothing@0 (line 53) 9.96% 10.00% 0x00007f6e851fb78c: add $0x30,%rsp 2.32% 2.90% 0x00007f6e851fb790: pop %rbp 27.83% 29.93% 0x00007f6e851fb791: test %eax,0x16d4f969(%rip) # 0x00007f6e9bf4b100 ; {poll_return} 0.47% 0.46% 0x00007f6e851fb797: retq 0x00007f6e851fb798: nop 0x00007f6e851fb799: nop 0x00007f6e851fb79a: mov 0x2a8(%r15),%rax 0x00007f6e851fb7a1: mov $0x0,%r10 0x00007f6e851fb7ab: mov %r10,0x2a8(%r15) 0x00007f6e851fb7b2: mov $0x0,%r10 0x00007f6e851fb7bc: mov %r10,0x2b0(%r15) 0x00007f6e851fb7c3: add $0x30,%rsp 0x00007f6e851fb7c7: pop %rbp .................................................................................................... 56.15% 54.05% ....[Hottest Region 2].............................................................................. [0x7f6e85208668:0x7f6e852086bd] in org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub 0x00007f6e85208668: mov 0x10(%rsp),%r10 0x00007f6e8520866d: movzbl 0x94(%r10),%r10d ;*getfield isDone ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@24 (line 165) ; implicit exception: dispatches to 0x00007f6e85208705 0x00007f6e85208675: mov $0x1,%ebp 0x00007f6e8520867a: test %r10d,%r10d ╭ 0x00007f6e8520867d: jne 0x00007f6e852086a8 ;*ifeq │ ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@27 (line 165) │ 0x00007f6e8520867f: nop ;*aload_3 │ ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@13 (line 163) 8.97% 4.98% │↗ 0x00007f6e85208680: mov 0x8(%rsp),%rsi 0.02% ││ 0x00007f6e85208685: xchg %ax,%ax 2.50% 3.00% ││ 0x00007f6e85208687: callq 0x00007f6e85046020 ; OopMap{[0]=Oop [8]=Oop [16]=Oop off=140} ││ ;*invokevirtual doNothing ││ ; - org.openjdk.EmptyMethod::test@1 (line 47) ││ ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@14 (line 163) ││ ; {optimized virtual_call} 5.55% 8.95% ││ 0x00007f6e8520868c: mov 0x10(%rsp),%r10 9.21% 8.93% ││ 0x00007f6e85208691: movzbl 0x94(%r10),%r10d ;*getfield isDone ││ ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@24 (line 165) 0.55% 1.07% ││ 0x00007f6e85208699: add $0x1,%rbp ; OopMap{[0]=Oop [8]=Oop [16]=Oop off=157} ││ ;*ifeq ││ ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@27 (line 165) 2.44% 4.17% ││ 0x00007f6e8520869d: test %eax,0x16d4295d(%rip) # 0x00007f6e9bf4b000 ││ ; {poll} 0.61% 0.77% ││ 0x00007f6e852086a3: test %r10d,%r10d │╰ 0x00007f6e852086a6: je 0x00007f6e85208680 ;*aload_2 │ ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@30 (line 166) ↘ 0x00007f6e852086a8: mov $0x7f6e9ac71220,%r10 0x00007f6e852086b2: callq *%r10 ;*invokestatic nanoTime ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@31 (line 166) 0x00007f6e852086b5: mov (%rsp),%r10 0x00007f6e852086b9: mov %rbp,0x18(%r10) ;*putfield measuredOps ; - org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub@46 (line 168) 0x00007f6e852086bd: mov %rax,0x30(%r10) ;*putfield stopTime .................................................................................................... 29.84% 31.86% ....[Hottest Regions]............................................................................... 56.15% 54.05% [0x7f6e851fb760:0x7f6e851fb7c7] in org.openjdk.EmptyMethod::doNothing 29.84% 31.86% [0x7f6e85208668:0x7f6e852086bd] in org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub 9.29% 9.45% [0x7f6e852080c6:0x7f6e85208144] in org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub 2.81% 2.76% [0xffffffff8105b83a:0xffffffff8105b83a] in [unknown] ([unknown]) 0.12% 0.12% [0xffffffff81032b28:0xffffffff81032b28] in [unknown] ([unknown]) 0.10% [0xffffffff817b3c20:0xffffffff817b3c20] in [unknown] ([unknown]) 0.06% 0.06% [0xffffffff8116a4df:0xffffffff8116a4df] in [unknown] ([unknown]) 0.06% [0xffffffff811785b6:0xffffffff811785cf] in [unknown] ([unknown]) 0.06% [0xffffffff817b3d99:0xffffffff817b3d99] in [unknown] ([unknown]) 0.04% 0.02% [0xffffffff811837a8:0xffffffff811837ae] in [unknown] ([unknown]) 0.04% 0.02% [0xffffffff811eda96:0xffffffff811eda96] in [unknown] ([unknown]) 0.04% [0xffffffff811ee76c:0xffffffff811ee76c] in [unknown] ([unknown]) 0.04% [0xffffffff811feb99:0xffffffff811feb99] in [unknown] ([unknown]) 0.04% 0.12% [0x7f6e9ac82c90:0x7f6e9ac82c9e] in _ZN10fileStream5writeEPKcm (libjvm.so) 0.04% 0.06% [0x7f6e9b39ddd8:0x7f6e9b39ddd8] in _IO_fwrite (libc-2.19.so) 0.02% [0xffffffff810ab6e3:0xffffffff810ab6e3] in [unknown] ([unknown]) 0.02% [0xffffffff810b4487:0xffffffff810b4487] in [unknown] ([unknown]) 0.02% [0xffffffff810bb395:0xffffffff810bb395] in [unknown] ([unknown]) 0.02% [0xffffffff810bb9c9:0xffffffff810bb9c9] in [unknown] ([unknown]) 0.02% 0.02% [0xffffffff810d303d:0xffffffff810d305c] in [unknown] ([unknown]) 1.16% 1.43% <...other 105 warm regions...> .................................................................................................... 100.00% 99.98% ....[Hottest Methods (after inlining)].............................................................. 56.15% 54.05% org.openjdk.EmptyMethod::doNothing 39.13% 41.31% org.openjdk.generated.EmptyMethod_test::test_avgt_jmhStub 3.96% 3.63% [unknown] ([unknown]) 0.04% 0.10% _IO_fwrite (libc-2.19.so) 0.04% 0.12% _ZN10fileStream5writeEPKcm (libjvm.so) 0.04% 0.02% 0.04% print_insn (libhsdis-amd64.so) 0.04% 0.12% vfprintf (libc-2.19.so) 0.04% _IO_fflush (libc-2.19.so) 0.04% 0.04% __strchr_sse2 (libc-2.19.so) 0.02% _ZN12stringStream5writeEPKcm (libjvm.so) 0.02% _ZN13defaultStream5flushEv (libjvm.so) 0.02% 0.02% jio_print (libjvm.so) 0.02% _IO_file_sync@@GLIBC_2.2.5 (libc-2.19.so) 0.02% 0.02% _ZN13RelocIterator10initializeEP7nmethodPhS2_ (libjvm.so) 0.02% _ZN10decode_env13print_addressEPh (libjvm.so) 0.02% _ZN13defaultStream5writeEPKcm (libjvm.so) 0.02% 0.02% _ZN24DebugInformationRecorder27find_sharable_decode_offsetEi (libjvm.so) 0.02% _ZN13CompileBroker20compiler_thread_loopEv (libjvm.so) 0.26% 0.10% <...other 13 warm methods...> .................................................................................................... 100.00% 99.54% ....[Distribution by Area].......................................................................... 95.36% 95.38% 3.96% 3.63% 0.39% 0.52% 0.24% 0.44% 0.04% .................................................................................................... 100.00% 99.98% # Run complete. Total time: 00:00:08 Benchmark Mode Cnt Score Error Units EmptyMethod.test avgt 3 1.614 ± 0.042 ns/op EmptyMethod.test:·asm avgt NaN ---