@Test
public void testSingleAsmMethod() {
String[] lines = SINGLE_ASSEMBLY_METHOD;
performAssemblyParsingOn(lines);
IMetaMember member =
map.get("org.adoptopenjdk.jitwatch.demo.SandboxTestLoad main ([Ljava.lang.String;)V");
assertNotNull(member);
AssemblyMethod assemblyMethod = member.getAssembly();
assertNotNull(assemblyMethod);
List<AssemblyBlock> asmBlocks = assemblyMethod.getBlocks();
assertEquals(1, asmBlocks.size());
AssemblyBlock block = asmBlocks.get(0);
List<AssemblyInstruction> instructions = block.getInstructions();
assertEquals(5, instructions.size());
}
@Override
public void visit(IMetaMember metaMember) {
if (metaMember == null) {
return;
}
if (!metaMember.isCompiled()) {
return;
}
try {
String callerName = metaMember.toString();
InlineJournalVisitor inlineJournalVisitor =
new InlineJournalVisitor(failures, model, callerName);
JournalUtil.visitParseTagsOfLastTask(metaMember.getJournal(), inlineJournalVisitor);
} catch (LogParseException e) {
logger.error("Error building inlining stats", e);
}
}
@Test
public void testSingleAsmMethodInterrupted() {
String[] lines =
new String[] {
"Decoding compiled method 0x00007f7d73363f90:",
"Code:",
"[Entry Point]",
"[Constants]",
" # {method} 'add' '(II)I' in 'org",
"<writer thread='140176877946624'/>",
"<uncommon_trap thread='140176877946624' reason='unloaded' action='reinterpret' index='39' compile_id='2' compile_kind='osr' compiler='C2' unresolved='1' name='java/lang/System' stamp='0.374'>",
"<jvms bci='31' method='org/adoptopenjdk/jitwatch/demo/SandboxTestLoad main ([Ljava/lang/String;)V' bytes='57' count='10000' backedge_count='6024' iicount='1'/>",
"</uncommon_trap>",
"<writer thread='140176736904960'/>",
"/adoptopenjdk/jitwatch/demo/SandboxTest'",
" # this: rsi:rsi = 'org/adoptopenjdk/jitwatch/demo/SandboxTest'",
" # parm0: rdx = int",
" # parm1: rcx = int",
" # [sp+0x20] (sp of caller)",
" 0x00007f7d733640c0: mov 0x8(%rsi),%r10d",
" 0x00007f7d733640c4: cmp %r10,%rax",
" 0x00007f7d733640c7: jne 0x00007f7d7333b960 ; {runtime_call}",
" 0x00007f7d733640cd: data32 xchg %ax,%ax"
};
performAssemblyParsingOn(lines);
IMetaMember member = map.get("org.adoptopenjdk.jitwatch.demo.SandboxTest add (II)I");
assertNotNull(member);
AssemblyMethod assemblyMethod = member.getAssembly();
assertNotNull(assemblyMethod);
List<AssemblyBlock> asmBlocks = assemblyMethod.getBlocks();
assertEquals(1, asmBlocks.size());
AssemblyBlock block = asmBlocks.get(0);
List<AssemblyInstruction> instructions = block.getInstructions();
assertEquals(4, instructions.size());
}
private void processParseTag(Tag parseTag, IParseDictionary parseDictionary) {
String methodID = null;
for (Tag child : parseTag.getChildren()) {
String tagName = child.getName();
Map<String, String> tagAttrs = child.getAttributes();
switch (tagName) {
case TAG_METHOD:
{
methodID = tagAttrs.get(ATTR_ID);
break;
}
case TAG_CALL:
{
methodID = tagAttrs.get(ATTR_METHOD);
break;
}
case TAG_INLINE_FAIL:
{
String reason = tagAttrs.get(ATTR_REASON);
Map<String, InlineFailureInfo> inlineFailureInfos = failures.get(reason);
if (inlineFailureInfos == null) {
inlineFailureInfos = new HashMap<>();
failures.put(reason, inlineFailureInfos);
}
IMetaMember metaMember = ParseUtil.lookupMember(methodID, parseDictionary, model);
if (metaMember == null) {
logger.warn("Cannot find name of methodId: ", methodID);
} else {
String memberName = metaMember.toString();
InlineFailureInfo inlineFailureInfo = inlineFailureInfos.get(memberName);
if (inlineFailureInfo == null) {
Tag methodTag = parseDictionary.getMethod(methodID);
int byteCodeSize = Integer.parseInt(methodTag.getAttribute(ATTR_BYTES));
inlineFailureInfo = new InlineFailureInfo(memberName, byteCodeSize);
inlineFailureInfos.put(memberName, inlineFailureInfo);
}
inlineFailureInfo.addCaller(callerName);
inlineFailureInfo.incFailureCount();
}
methodID = null;
break;
}
case TAG_INLINE_SUCCESS:
{
break;
}
case TAG_PARSE:
{
processParseTag(child, parseDictionary);
break;
}
case TAG_PHASE:
{
String phaseName = tagAttrs.get(ATTR_NAME);
if (S_PARSE_HIR.equals(phaseName)) {
processParseTag(child, parseDictionary);
} else {
logger.warn("Don't know how to handle phase {}", phaseName);
}
break;
}
default:
handleOther(child);
break;
}
}
}
@Test
public void testJMHPerfAnnotations() {
String[] lines =
new String[] {
" Decoding compiled method 0x00007f25cd19c690:",
" Code:",
" [Entry Point]",
" [Constants]",
" # {method} {0x00007f25ccc5bc40} 'measureRight_avgt_jmhLoop' '(Lorg/openjdk/jmh/runner/InfraControl;Lorg/openjdk/jmh/results/RawResults;Lorg/openjdk/jmh/samples/generated/JMHSample_08_DeadCode_measureRight$JMHSample_08_DeadCode_1_jmh;L",
" <writer thread='139800227780352'/>",
" <writer thread='139800228833024'/>",
" org/openjdk/jmh/samples/generated/JMHSample_08_DeadCode_measureRight$Blackhole_1_jmh;)V' in 'org/openjdk/jmh/samples/generated/JMHSample_08_DeadCode_measureRight'",
" # this: rsi:rsi = 'org/openjdk/jmh/samples/generated/JMHSample_08_DeadCode_measureRight'",
" # parm0: rdx:rdx = 'org/openjdk/jmh/runner/InfraControl'",
" # parm1: rcx:rcx = 'org/openjdk/jmh/results/RawResults'",
" # parm2: r8:r8 = 'org/openjdk/jmh/samples/generated/JMHSample_08_DeadCode_measureRight$JMHSample_08_DeadCode_1_jmh'",
" # parm3: r9:r9 = 'org/openjdk/jmh/samples/generated/JMHSample_08_DeadCode_measureRight$Blackhole_1_jmh'",
" # [sp+0x30] (sp of caller)",
" 0x00007f25cd19c7e0: mov 0x8(%rsi),%r10d",
" 0x00007f25cd19c7e4: shl $0x3,%r10",
" 0x00007f25cd19c7e8: cmp %r10,%rax",
" 0x00007f25cd19c7eb: jne 0x00007f25cd045b60 ; {runtime_call}",
" 0x00007f25cd19c7f1: xchg %ax,%ax",
" 0x00007f25cd19c7f4: nopl 0x0(%rax,%rax,1)",
" 0x00007f25cd19c7fc: xchg %ax,%ax",
" [Verified Entry Point]",
" 1.34% 0x00007f25cd19c890: vmovsd 0xa0(%r13),%xmm0",
" 0x00007f25cd19c899: vmovsd 0x10(%r8),%xmm1 ;*getfield d1",
" ; - org.openjdk.jmh.infra.Blackhole::consume@2 (line 386)",
" ; - org.openjdk.jmh.samples.generated.JMHSample_08_DeadCode_measureRight::measureRight_avgt_jmhLoop@19 (line 160)",
" 0x00007f25cd19c89f: vmovsd 0xa8(%r13),%xmm2 ;*getfield d2",
" ; - org.openjdk.jmh.infra.Blackhole::consume@16 (line 386)",
" ; - org.openjdk.jmh.samples.generated.JMHSample_08_DeadCode_measureRight::measureRight_avgt_jmhLoop@19 (line 160)",
" 0x00007f25cd19c8a8: fldln2 ",
" 1.61% 0x00007f25cd19c8aa: sub $0x8,%rsp",
" 0x00007f25cd19c8ae: vmovsd %xmm1,(%rsp)",
" 0x00007f25cd19c8b3: fldl (%rsp)",
" 0.95% 0.02% 0x00007f25cd19c8b6: fyl2x ",
" 79.12% 95.68% 0x00007f25cd19c8b8: fstpl (%rsp)",
" 1.41% 0x00007f25cd19c8bb: vmovsd (%rsp),%xmm1",
" 5.43% 0x00007f25cd19c8c0: add $0x8,%rsp ;*invokestatic log"
};
performAssemblyParsingOn(lines);
IMetaMember member =
map.get(
"org.openjdk.jmh.samples.generated.JMHSample_08_DeadCode_measureRight measureRight_avgt_jmhLoop (Lorg.openjdk.jmh.runner.InfraControl;Lorg.openjdk.jmh.results.RawResults;Lorg.openjdk.jmh.samples.generated.JMHSample_08_DeadCode_measureRight$JMHSample_08_DeadCode_1_jmh;Lorg.openjdk.jmh.samples.generated.JMHSample_08_DeadCode_measureRight$Blackhole_1_jmh;)V");
assertNotNull(member);
AssemblyMethod assemblyMethod = member.getAssembly();
assertNotNull(assemblyMethod);
assertEquals(19, assemblyMethod.getMaxAnnotationWidth());
// System.out.println(assemblyMethod.toString());
List<AssemblyBlock> asmBlocks = assemblyMethod.getBlocks();
assertEquals(2, asmBlocks.size());
assertEquals(7, asmBlocks.get(0).getInstructions().size());
assertEquals(11, asmBlocks.get(1).getInstructions().size());
AssemblyInstruction instr9 = asmBlocks.get(1).getInstructions().get(8);
assertEquals("79.12% 95.68% ", instr9.getAnnotation());
assertEquals(Long.parseLong("00007f25cd19c8b8", 16), instr9.getAddress());
assertEquals("fstpl", instr9.getMnemonic());
List<String> operands = new ArrayList<String>();
operands.add("(%rsp)");
assertEquals(operands, instr9.getOperands());
}
@Test
public void testRegressionWorksWithJMHIndentedLogs() {
String[] lines =
new String[] {
" Loaded disassembler from /home/shade/Install/jdk8u0/jre/lib/amd64/server/libhsdis-amd64.so",
" Decoding compiled method 0x00007f25cd0fedd0:",
" Code:",
" [Disassembling for mach='i386:x86-64']",
" [Entry Point]",
" [Constants]",
" # {method} {0x00007f25cc804f80} 'hashCode' '()I' in 'java/lang/String'",
" # [sp+0x40] (sp of caller)",
" 0x00007f25cd0fef40: mov 0x8(%rsi),%r10d",
" 0x00007f25cd0fef44: shl $0x3,%r10",
" 0x00007f25cd0fef48: cmp %rax,%r10",
" 0x00007f25cd0fef4b: jne 0x00007f25cd045b60 ; {runtime_call}",
" 0x00007f25cd0fef51: nopw 0x0(%rax,%rax,1)",
" 0x00007f25cd0fef5c: xchg %ax,%ax",
" [Verified Entry Point]",
" 0x00007f25cd0fef60: mov %eax,-0x14000(%rsp)",
" 0x00007f25cd0fef67: push %rbp",
" 0x00007f25cd0fef68: sub $0x30,%rsp",
" 0x00007f25cd0fef6c: mov $0x7f25cc9d0b38,%rax ; {metadata(method data for {method} {0x00007f25cc804f80} 'hashCode' '()I' in 'java/lang/String')}",
" 0x00007f25cd0fef76: mov 0x64(%rax),%edi",
" 0x00007f25cd0fef79: add $0x8,%edi",
" 0x00007f25cd0fef7c: mov %edi,0x64(%rax)",
" 0x00007f25cd0fef7f: mov $0x7f25cc804f80,%rax ; {metadata({method} {0x00007f25cc804f80} 'hashCode' '()I' in 'java/lang/String')}",
" 0x00007f25cd0fef89: and $0x1ff8,%edi",
" 0x00007f25cd0fef8f: cmp $0x0,%edi",
" 0x00007f25cd0fef92: je 0x00007f25cd0ff0cb ;*aload_0",
" ; - java.lang.String::hashCode@0 (line 1453)",
" 0x00007f25cd0fef98: mov 0x10(%rsi),%eax ;*getfield hash",
" ; - java.lang.String::hashCode@1 (line 1453)",
" 0x00007f25cd0fef9b: cmp $0x0,%eax",
" 0x00007f25cd0fef9e: mov $0x7f25cc9d0b38,%rdi ; {metadata(method data for {method} {0x00007f25cc804f80} 'hashCode' '()I' in 'java/lang/String')}",
" 0x00007f25cd0fefa8: mov $0x90,%rbx",
" 0x00007f25cd0fefb2: jne 0x00007f25cd0fefc2",
" 0x00007f25cd0fefb8: mov $0xa0,%rbx",
" 0x00007f25cd0fefc2: mov (%rdi,%rbx,1),%rdx",
" 0x00007f25cd0fefc6: lea 0x1(%rdx),%rdx",
" 0x00007f25cd0fefca: mov %rdx,(%rdi,%rbx,1)",
" 0x00007f25cd0fefce: jne 0x00007f25cd0ff0bf ;*ifne",
" ; - java.lang.String::hashCode@6 (line 1454)"
};
performAssemblyParsingOn(lines);
IMetaMember member = map.get("java.lang.String hashCode ()I");
assertNotNull(member);
AssemblyMethod assemblyMethod = member.getAssembly();
assertNotNull(assemblyMethod);
List<AssemblyBlock> asmBlocks = assemblyMethod.getBlocks();
assertEquals(2, asmBlocks.size());
assertEquals(6, asmBlocks.get(0).getInstructions().size());
assertEquals(21, asmBlocks.get(1).getInstructions().size());
}
@Test
public void testTwoMethodsWithOneInterrupted() {
String[] lines =
new String[] {
"Decoding compiled method 0x00007f7d73364190:",
"Code:",
"[Disassembling for mach='i386:x86-64']",
"[Entry Point]",
"[Verified Entry Point]",
"[Constants]",
" # {method} 'main' '([Ljava/lang/String;)V' in 'org/adoptopenjdk/jitwatch/demo/SandboxTestLoad'",
" 0x00007f7d733642e0: callq 0x00007f7d77e276f0 ; {runtime_call}",
" 0x00007f7d733642e5: data32 data32 nopw 0x0(%rax,%rax,1)",
" 0x00007f7d733642f0: mov %eax,-0x14000(%rsp)",
" 0x00007f7d733642f7: push %rbp",
" 0x00007f7d733642f8: sub $0x20,%rsp",
"<nmethod compile_id='2' compile_kind='osr' compiler='C2' entry='0x00007f7d733642e0' size='800' address='0x00007f7d73364190' relocation_offset='288' insts_offset='336' stub_offset='528' scopes_data_offset='576' scopes_pcs_offset='632' dependencies_offset='792' oops_offset='552' method='org/adoptopenjdk/jitwatch/demo/SandboxTestLoad main ([Ljava/lang/String;)V' bytes='57' count='10000' backedge_count='5377' iicount='1' stamp='0.373'/>",
"<writer thread='140176736904960'/>",
"Decoding compiled method 0x00007f7d73363f90:",
"Code:",
"[Entry Point]",
"[Constants]",
" # {method} 'add' '(II)I' in 'org",
"<writer thread='140176877946624'/>",
"<uncommon_trap thread='140176877946624' reason='unloaded' action='reinterpret' index='39' compile_id='2' compile_kind='osr' compiler='C2' unresolved='1' name='java/lang/System' stamp='0.374'>",
"<jvms bci='31' method='org/adoptopenjdk/jitwatch/demo/SandboxTestLoad main ([Ljava/lang/String;)V' bytes='57' count='10000' backedge_count='6024' iicount='1'/>",
"</uncommon_trap>",
"<writer thread='140176736904960'/>",
"/adoptopenjdk/jitwatch/demo/SandboxTest'",
" # this: rsi:rsi = 'org/adoptopenjdk/jitwatch/demo/SandboxTest'",
" # parm0: rdx = int",
" # parm1: rcx = int",
" # [sp+0x20] (sp of caller)",
" 0x00007f7d733640c0: mov 0x8(%rsi),%r10d",
" 0x00007f7d733640c4: cmp %r10,%rax",
" 0x00007f7d733640c7: jne 0x00007f7d7333b960 ; {runtime_call}",
" 0x00007f7d733640cd: data32 xchg %ax,%ax"
};
performAssemblyParsingOn(lines);
IMetaMember member =
map.get("org.adoptopenjdk.jitwatch.demo.SandboxTestLoad main ([Ljava.lang.String;)V");
assertNotNull(member);
AssemblyMethod assemblyMethod = member.getAssembly();
assertNotNull(assemblyMethod);
List<AssemblyBlock> asmBlocks = assemblyMethod.getBlocks();
assertEquals(1, asmBlocks.size());
AssemblyBlock block = asmBlocks.get(0);
List<AssemblyInstruction> instructions = block.getInstructions();
assertEquals(5, instructions.size());
IMetaMember member2 = map.get("org.adoptopenjdk.jitwatch.demo.SandboxTest add (II)I");
assertNotNull(member2);
AssemblyMethod assemblyMethod2 = member2.getAssembly();
assertNotNull(assemblyMethod2);
List<AssemblyBlock> asmBlocks2 = assemblyMethod2.getBlocks();
assertEquals(1, asmBlocks2.size());
AssemblyBlock block2 = asmBlocks2.get(0);
List<AssemblyInstruction> instructions2 = block2.getInstructions();
assertEquals(4, instructions2.size());
}