intel_iommu: convert dbg macros to traces for inv

VT-d codes are still using static DEBUG_INTEL_IOMMU macro. That's not
good, and we should end the day when we need to recompile the code
before getting useful debugging information for vt-d. Time to switch to
the trace system. This is the first patch to do it.

Signed-off-by: Peter Xu <peterx@redhat.com>
Reviewed-by: Jason Wang <jasowang@redhat.com>
Reviewed-by: David Gibson <david@gibson.dropbear.id.au>
Reviewed-by: Michael S. Tsirkin <mst@redhat.com>
Signed-off-by: Michael S. Tsirkin <mst@redhat.com>
master
Peter Xu 2017-02-07 16:28:09 +08:00 committed by Michael S. Tsirkin
parent 6e9055641b
commit bc535e59c4
2 changed files with 56 additions and 57 deletions

View File

@ -35,6 +35,7 @@
#include "sysemu/kvm.h" #include "sysemu/kvm.h"
#include "hw/i386/apic_internal.h" #include "hw/i386/apic_internal.h"
#include "kvm_i386.h" #include "kvm_i386.h"
#include "trace.h"
/*#define DEBUG_INTEL_IOMMU*/ /*#define DEBUG_INTEL_IOMMU*/
#ifdef DEBUG_INTEL_IOMMU #ifdef DEBUG_INTEL_IOMMU
@ -474,22 +475,19 @@ static void vtd_handle_inv_queue_error(IntelIOMMUState *s)
/* Set the IWC field and try to generate an invalidation completion interrupt */ /* Set the IWC field and try to generate an invalidation completion interrupt */
static void vtd_generate_completion_event(IntelIOMMUState *s) static void vtd_generate_completion_event(IntelIOMMUState *s)
{ {
VTD_DPRINTF(INV, "completes an invalidation wait command with "
"Interrupt Flag");
if (vtd_get_long_raw(s, DMAR_ICS_REG) & VTD_ICS_IWC) { if (vtd_get_long_raw(s, DMAR_ICS_REG) & VTD_ICS_IWC) {
VTD_DPRINTF(INV, "there is a previous interrupt condition to be " trace_vtd_inv_desc_wait_irq("One pending, skip current");
"serviced by software, "
"new invalidation event is not generated");
return; return;
} }
vtd_set_clear_mask_long(s, DMAR_ICS_REG, 0, VTD_ICS_IWC); vtd_set_clear_mask_long(s, DMAR_ICS_REG, 0, VTD_ICS_IWC);
vtd_set_clear_mask_long(s, DMAR_IECTL_REG, 0, VTD_IECTL_IP); vtd_set_clear_mask_long(s, DMAR_IECTL_REG, 0, VTD_IECTL_IP);
if (vtd_get_long_raw(s, DMAR_IECTL_REG) & VTD_IECTL_IM) { if (vtd_get_long_raw(s, DMAR_IECTL_REG) & VTD_IECTL_IM) {
VTD_DPRINTF(INV, "IM filed in IECTL_REG is set, new invalidation " trace_vtd_inv_desc_wait_irq("IM in IECTL_REG is set, "
"event is not generated"); "new event not generated");
return; return;
} else { } else {
/* Generate the interrupt event */ /* Generate the interrupt event */
trace_vtd_inv_desc_wait_irq("Generating complete event");
vtd_generate_interrupt(s, DMAR_IEADDR_REG, DMAR_IEDATA_REG); vtd_generate_interrupt(s, DMAR_IEADDR_REG, DMAR_IEDATA_REG);
vtd_set_clear_mask_long(s, DMAR_IECTL_REG, VTD_IECTL_IP, 0); vtd_set_clear_mask_long(s, DMAR_IECTL_REG, VTD_IECTL_IP, 0);
} }
@ -923,6 +921,7 @@ static void vtd_interrupt_remap_table_setup(IntelIOMMUState *s)
static void vtd_context_global_invalidate(IntelIOMMUState *s) static void vtd_context_global_invalidate(IntelIOMMUState *s)
{ {
trace_vtd_inv_desc_cc_global();
s->context_cache_gen++; s->context_cache_gen++;
if (s->context_cache_gen == VTD_CONTEXT_CACHE_GEN_MAX) { if (s->context_cache_gen == VTD_CONTEXT_CACHE_GEN_MAX) {
vtd_reset_context_cache(s); vtd_reset_context_cache(s);
@ -962,9 +961,11 @@ static void vtd_context_device_invalidate(IntelIOMMUState *s,
uint16_t mask; uint16_t mask;
VTDBus *vtd_bus; VTDBus *vtd_bus;
VTDAddressSpace *vtd_as; VTDAddressSpace *vtd_as;
uint16_t devfn; uint8_t bus_n, devfn;
uint16_t devfn_it; uint16_t devfn_it;
trace_vtd_inv_desc_cc_devices(source_id, func_mask);
switch (func_mask & 3) { switch (func_mask & 3) {
case 0: case 0:
mask = 0; /* No bits in the SID field masked */ mask = 0; /* No bits in the SID field masked */
@ -980,16 +981,16 @@ static void vtd_context_device_invalidate(IntelIOMMUState *s,
break; break;
} }
mask = ~mask; mask = ~mask;
VTD_DPRINTF(INV, "device-selective invalidation source 0x%"PRIx16
" mask %"PRIu16, source_id, mask); bus_n = VTD_SID_TO_BUS(source_id);
vtd_bus = vtd_find_as_from_bus_num(s, VTD_SID_TO_BUS(source_id)); vtd_bus = vtd_find_as_from_bus_num(s, bus_n);
if (vtd_bus) { if (vtd_bus) {
devfn = VTD_SID_TO_DEVFN(source_id); devfn = VTD_SID_TO_DEVFN(source_id);
for (devfn_it = 0; devfn_it < X86_IOMMU_PCI_DEVFN_MAX; ++devfn_it) { for (devfn_it = 0; devfn_it < X86_IOMMU_PCI_DEVFN_MAX; ++devfn_it) {
vtd_as = vtd_bus->dev_as[devfn_it]; vtd_as = vtd_bus->dev_as[devfn_it];
if (vtd_as && ((devfn_it & mask) == (devfn & mask))) { if (vtd_as && ((devfn_it & mask) == (devfn & mask))) {
VTD_DPRINTF(INV, "invalidate context-cahce of devfn 0x%"PRIx16, trace_vtd_inv_desc_cc_device(bus_n, VTD_PCI_SLOT(devfn_it),
devfn_it); VTD_PCI_FUNC(devfn_it));
vtd_as->context_cache_entry.context_cache_gen = 0; vtd_as->context_cache_entry.context_cache_gen = 0;
} }
} }
@ -1302,9 +1303,7 @@ static bool vtd_process_wait_desc(IntelIOMMUState *s, VTDInvDesc *inv_desc)
{ {
if ((inv_desc->hi & VTD_INV_DESC_WAIT_RSVD_HI) || if ((inv_desc->hi & VTD_INV_DESC_WAIT_RSVD_HI) ||
(inv_desc->lo & VTD_INV_DESC_WAIT_RSVD_LO)) { (inv_desc->lo & VTD_INV_DESC_WAIT_RSVD_LO)) {
VTD_DPRINTF(GENERAL, "error: non-zero reserved field in Invalidation " trace_vtd_inv_desc_wait_invalid(inv_desc->hi, inv_desc->lo);
"Wait Descriptor hi 0x%"PRIx64 " lo 0x%"PRIx64,
inv_desc->hi, inv_desc->lo);
return false; return false;
} }
if (inv_desc->lo & VTD_INV_DESC_WAIT_SW) { if (inv_desc->lo & VTD_INV_DESC_WAIT_SW) {
@ -1316,21 +1315,18 @@ static bool vtd_process_wait_desc(IntelIOMMUState *s, VTDInvDesc *inv_desc)
/* FIXME: need to be masked with HAW? */ /* FIXME: need to be masked with HAW? */
dma_addr_t status_addr = inv_desc->hi; dma_addr_t status_addr = inv_desc->hi;
VTD_DPRINTF(INV, "status data 0x%x, status addr 0x%"PRIx64, trace_vtd_inv_desc_wait_sw(status_addr, status_data);
status_data, status_addr);
status_data = cpu_to_le32(status_data); status_data = cpu_to_le32(status_data);
if (dma_memory_write(&address_space_memory, status_addr, &status_data, if (dma_memory_write(&address_space_memory, status_addr, &status_data,
sizeof(status_data))) { sizeof(status_data))) {
VTD_DPRINTF(GENERAL, "error: fail to perform a coherent write"); trace_vtd_inv_desc_wait_write_fail(inv_desc->hi, inv_desc->lo);
return false; return false;
} }
} else if (inv_desc->lo & VTD_INV_DESC_WAIT_IF) { } else if (inv_desc->lo & VTD_INV_DESC_WAIT_IF) {
/* Interrupt flag */ /* Interrupt flag */
VTD_DPRINTF(INV, "Invalidation Wait Descriptor interrupt completion");
vtd_generate_completion_event(s); vtd_generate_completion_event(s);
} else { } else {
VTD_DPRINTF(GENERAL, "error: invalid Invalidation Wait Descriptor: " trace_vtd_inv_desc_wait_invalid(inv_desc->hi, inv_desc->lo);
"hi 0x%"PRIx64 " lo 0x%"PRIx64, inv_desc->hi, inv_desc->lo);
return false; return false;
} }
return true; return true;
@ -1339,30 +1335,29 @@ static bool vtd_process_wait_desc(IntelIOMMUState *s, VTDInvDesc *inv_desc)
static bool vtd_process_context_cache_desc(IntelIOMMUState *s, static bool vtd_process_context_cache_desc(IntelIOMMUState *s,
VTDInvDesc *inv_desc) VTDInvDesc *inv_desc)
{ {
uint16_t sid, fmask;
if ((inv_desc->lo & VTD_INV_DESC_CC_RSVD) || inv_desc->hi) { if ((inv_desc->lo & VTD_INV_DESC_CC_RSVD) || inv_desc->hi) {
VTD_DPRINTF(GENERAL, "error: non-zero reserved field in Context-cache " trace_vtd_inv_desc_cc_invalid(inv_desc->hi, inv_desc->lo);
"Invalidate Descriptor");
return false; return false;
} }
switch (inv_desc->lo & VTD_INV_DESC_CC_G) { switch (inv_desc->lo & VTD_INV_DESC_CC_G) {
case VTD_INV_DESC_CC_DOMAIN: case VTD_INV_DESC_CC_DOMAIN:
VTD_DPRINTF(INV, "domain-selective invalidation domain 0x%"PRIx16, trace_vtd_inv_desc_cc_domain(
(uint16_t)VTD_INV_DESC_CC_DID(inv_desc->lo)); (uint16_t)VTD_INV_DESC_CC_DID(inv_desc->lo));
/* Fall through */ /* Fall through */
case VTD_INV_DESC_CC_GLOBAL: case VTD_INV_DESC_CC_GLOBAL:
VTD_DPRINTF(INV, "global invalidation");
vtd_context_global_invalidate(s); vtd_context_global_invalidate(s);
break; break;
case VTD_INV_DESC_CC_DEVICE: case VTD_INV_DESC_CC_DEVICE:
vtd_context_device_invalidate(s, VTD_INV_DESC_CC_SID(inv_desc->lo), sid = VTD_INV_DESC_CC_SID(inv_desc->lo);
VTD_INV_DESC_CC_FM(inv_desc->lo)); fmask = VTD_INV_DESC_CC_FM(inv_desc->lo);
vtd_context_device_invalidate(s, sid, fmask);
break; break;
default: default:
VTD_DPRINTF(GENERAL, "error: invalid granularity in Context-cache " trace_vtd_inv_desc_cc_invalid(inv_desc->hi, inv_desc->lo);
"Invalidate Descriptor hi 0x%"PRIx64 " lo 0x%"PRIx64,
inv_desc->hi, inv_desc->lo);
return false; return false;
} }
return true; return true;
@ -1376,22 +1371,19 @@ static bool vtd_process_iotlb_desc(IntelIOMMUState *s, VTDInvDesc *inv_desc)
if ((inv_desc->lo & VTD_INV_DESC_IOTLB_RSVD_LO) || if ((inv_desc->lo & VTD_INV_DESC_IOTLB_RSVD_LO) ||
(inv_desc->hi & VTD_INV_DESC_IOTLB_RSVD_HI)) { (inv_desc->hi & VTD_INV_DESC_IOTLB_RSVD_HI)) {
VTD_DPRINTF(GENERAL, "error: non-zero reserved field in IOTLB " trace_vtd_inv_desc_iotlb_invalid(inv_desc->hi, inv_desc->lo);
"Invalidate Descriptor hi 0x%"PRIx64 " lo 0x%"PRIx64,
inv_desc->hi, inv_desc->lo);
return false; return false;
} }
switch (inv_desc->lo & VTD_INV_DESC_IOTLB_G) { switch (inv_desc->lo & VTD_INV_DESC_IOTLB_G) {
case VTD_INV_DESC_IOTLB_GLOBAL: case VTD_INV_DESC_IOTLB_GLOBAL:
VTD_DPRINTF(INV, "global invalidation"); trace_vtd_inv_desc_iotlb_global();
vtd_iotlb_global_invalidate(s); vtd_iotlb_global_invalidate(s);
break; break;
case VTD_INV_DESC_IOTLB_DOMAIN: case VTD_INV_DESC_IOTLB_DOMAIN:
domain_id = VTD_INV_DESC_IOTLB_DID(inv_desc->lo); domain_id = VTD_INV_DESC_IOTLB_DID(inv_desc->lo);
VTD_DPRINTF(INV, "domain-selective invalidation domain 0x%"PRIx16, trace_vtd_inv_desc_iotlb_domain(domain_id);
domain_id);
vtd_iotlb_domain_invalidate(s, domain_id); vtd_iotlb_domain_invalidate(s, domain_id);
break; break;
@ -1399,20 +1391,16 @@ static bool vtd_process_iotlb_desc(IntelIOMMUState *s, VTDInvDesc *inv_desc)
domain_id = VTD_INV_DESC_IOTLB_DID(inv_desc->lo); domain_id = VTD_INV_DESC_IOTLB_DID(inv_desc->lo);
addr = VTD_INV_DESC_IOTLB_ADDR(inv_desc->hi); addr = VTD_INV_DESC_IOTLB_ADDR(inv_desc->hi);
am = VTD_INV_DESC_IOTLB_AM(inv_desc->hi); am = VTD_INV_DESC_IOTLB_AM(inv_desc->hi);
VTD_DPRINTF(INV, "page-selective invalidation domain 0x%"PRIx16 trace_vtd_inv_desc_iotlb_pages(domain_id, addr, am);
" addr 0x%"PRIx64 " mask %"PRIu8, domain_id, addr, am);
if (am > VTD_MAMV) { if (am > VTD_MAMV) {
VTD_DPRINTF(GENERAL, "error: supported max address mask value is " trace_vtd_inv_desc_iotlb_invalid(inv_desc->hi, inv_desc->lo);
"%"PRIu8, (uint8_t)VTD_MAMV);
return false; return false;
} }
vtd_iotlb_page_invalidate(s, domain_id, addr, am); vtd_iotlb_page_invalidate(s, domain_id, addr, am);
break; break;
default: default:
VTD_DPRINTF(GENERAL, "error: invalid granularity in IOTLB Invalidate " trace_vtd_inv_desc_iotlb_invalid(inv_desc->hi, inv_desc->lo);
"Descriptor hi 0x%"PRIx64 " lo 0x%"PRIx64,
inv_desc->hi, inv_desc->lo);
return false; return false;
} }
return true; return true;
@ -1511,33 +1499,28 @@ static bool vtd_process_inv_desc(IntelIOMMUState *s)
switch (desc_type) { switch (desc_type) {
case VTD_INV_DESC_CC: case VTD_INV_DESC_CC:
VTD_DPRINTF(INV, "Context-cache Invalidate Descriptor hi 0x%"PRIx64 trace_vtd_inv_desc("context-cache", inv_desc.hi, inv_desc.lo);
" lo 0x%"PRIx64, inv_desc.hi, inv_desc.lo);
if (!vtd_process_context_cache_desc(s, &inv_desc)) { if (!vtd_process_context_cache_desc(s, &inv_desc)) {
return false; return false;
} }
break; break;
case VTD_INV_DESC_IOTLB: case VTD_INV_DESC_IOTLB:
VTD_DPRINTF(INV, "IOTLB Invalidate Descriptor hi 0x%"PRIx64 trace_vtd_inv_desc("iotlb", inv_desc.hi, inv_desc.lo);
" lo 0x%"PRIx64, inv_desc.hi, inv_desc.lo);
if (!vtd_process_iotlb_desc(s, &inv_desc)) { if (!vtd_process_iotlb_desc(s, &inv_desc)) {
return false; return false;
} }
break; break;
case VTD_INV_DESC_WAIT: case VTD_INV_DESC_WAIT:
VTD_DPRINTF(INV, "Invalidation Wait Descriptor hi 0x%"PRIx64 trace_vtd_inv_desc("wait", inv_desc.hi, inv_desc.lo);
" lo 0x%"PRIx64, inv_desc.hi, inv_desc.lo);
if (!vtd_process_wait_desc(s, &inv_desc)) { if (!vtd_process_wait_desc(s, &inv_desc)) {
return false; return false;
} }
break; break;
case VTD_INV_DESC_IEC: case VTD_INV_DESC_IEC:
VTD_DPRINTF(INV, "Invalidation Interrupt Entry Cache " trace_vtd_inv_desc("iec", inv_desc.hi, inv_desc.lo);
"Descriptor hi 0x%"PRIx64 " lo 0x%"PRIx64,
inv_desc.hi, inv_desc.lo);
if (!vtd_process_inv_iec_desc(s, &inv_desc)) { if (!vtd_process_inv_iec_desc(s, &inv_desc)) {
return false; return false;
} }
@ -1552,9 +1535,7 @@ static bool vtd_process_inv_desc(IntelIOMMUState *s)
break; break;
default: default:
VTD_DPRINTF(GENERAL, "error: unkonw Invalidation Descriptor type " trace_vtd_inv_desc_invalid(inv_desc.hi, inv_desc.lo);
"hi 0x%"PRIx64 " lo 0x%"PRIx64 " type %"PRIu8,
inv_desc.hi, inv_desc.lo, desc_type);
return false; return false;
} }
s->iq_head++; s->iq_head++;

View File

@ -3,6 +3,24 @@
# hw/i386/x86-iommu.c # hw/i386/x86-iommu.c
x86_iommu_iec_notify(bool global, uint32_t index, uint32_t mask) "Notify IEC invalidation: global=%d index=%" PRIu32 " mask=%" PRIu32 x86_iommu_iec_notify(bool global, uint32_t index, uint32_t mask) "Notify IEC invalidation: global=%d index=%" PRIu32 " mask=%" PRIu32
# hw/i386/intel_iommu.c
vtd_switch_address_space(uint8_t bus, uint8_t slot, uint8_t fn, bool on) "Device %02x:%02x.%x switching address space (iommu enabled=%d)"
vtd_inv_desc(const char *type, uint64_t hi, uint64_t lo) "invalidate desc type %s high 0x%"PRIx64" low 0x%"PRIx64
vtd_inv_desc_invalid(uint64_t hi, uint64_t lo) "invalid inv desc hi 0x%"PRIx64" lo 0x%"PRIx64
vtd_inv_desc_cc_domain(uint16_t domain) "context invalidate domain 0x%"PRIx16
vtd_inv_desc_cc_global(void) "context invalidate globally"
vtd_inv_desc_cc_device(uint8_t bus, uint8_t dev, uint8_t fn) "context invalidate device %02"PRIx8":%02"PRIx8".%02"PRIx8
vtd_inv_desc_cc_devices(uint16_t sid, uint16_t fmask) "context invalidate devices sid 0x%"PRIx16" fmask 0x%"PRIx16
vtd_inv_desc_cc_invalid(uint64_t hi, uint64_t lo) "invalid context-cache desc hi 0x%"PRIx64" lo 0x%"PRIx64
vtd_inv_desc_iotlb_global(void) "iotlb invalidate global"
vtd_inv_desc_iotlb_domain(uint16_t domain) "iotlb invalidate whole domain 0x%"PRIx16
vtd_inv_desc_iotlb_pages(uint16_t domain, uint64_t addr, uint8_t mask) "iotlb invalidate domain 0x%"PRIx16" addr 0x%"PRIx64" mask 0x%"PRIx8
vtd_inv_desc_iotlb_invalid(uint64_t hi, uint64_t lo) "invalid iotlb desc hi 0x%"PRIx64" lo 0x%"PRIx64
vtd_inv_desc_wait_sw(uint64_t addr, uint32_t data) "wait invalidate status write addr 0x%"PRIx64" data 0x%"PRIx32
vtd_inv_desc_wait_irq(const char *msg) "%s"
vtd_inv_desc_wait_invalid(uint64_t hi, uint64_t lo) "invalid wait desc hi 0x%"PRIx64" lo 0x%"PRIx64
vtd_inv_desc_wait_write_fail(uint64_t hi, uint64_t lo) "write fail for wait desc hi 0x%"PRIx64" lo 0x%"PRIx64
# hw/i386/amd_iommu.c # hw/i386/amd_iommu.c
amdvi_evntlog_fail(uint64_t addr, uint32_t head) "error: fail to write at addr 0x%"PRIx64" + offset 0x%"PRIx32 amdvi_evntlog_fail(uint64_t addr, uint32_t head) "error: fail to write at addr 0x%"PRIx64" + offset 0x%"PRIx32
amdvi_cache_update(uint16_t domid, uint8_t bus, uint8_t slot, uint8_t func, uint64_t gpa, uint64_t txaddr) " update iotlb domid 0x%"PRIx16" devid: %02x:%02x.%x gpa 0x%"PRIx64" hpa 0x%"PRIx64 amdvi_cache_update(uint16_t domid, uint8_t bus, uint8_t slot, uint8_t func, uint64_t gpa, uint64_t txaddr) " update iotlb domid 0x%"PRIx16" devid: %02x:%02x.%x gpa 0x%"PRIx64" hpa 0x%"PRIx64