[PATCH 15/15] tools: tracing: handle timers events in xentrace and xenalyze

[Dario Faggioli <dario.faggioli@citrix.com>]

Signed-off-by: Dario Faggioli <dario.faggioli@citrix.com>
---
Cc: George Dunlap <george.dunlap@eu.citrix.com>
Cc: Ian Jackson <ian.jackson@eu.citrix.com>
Cc: Wei Liu <wei.liu2@citrix.com>
---
 tools/xentrace/formats    |   10 +++
 tools/xentrace/xenalyze.c |  141 +++++++++++++++++++++++++++++++++++++++++++++
 2 files changed, 151 insertions(+)

diff --git a/tools/xentrace/formats b/tools/xentrace/formats
index 7d59db7..c2fdead 100644
--- a/tools/xentrace/formats
+++ b/tools/xentrace/formats
@@ -226,6 +226,16 @@
 0x01004005  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  tasklet_init [ fn = 0x%(2)08x%(1)08x, is_sirq = %(3)d ]
 0x01004006  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  tasklet_migr [ ]
 
+0x01008001  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_rmentry [ t = 0x%(2)08x%(1)08x, cpu:status = %(3)08x ]
+0x01008002  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_addentry [ t = 0x%(2)08x%(1)08x, cpu:status = %(3)08x ]
+0x01008003  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_set [ t = 0x%(2)08x%(1)08x, fn = 0x%(4)08x%(3)08x exp=0x%(6)08x%(5)08x ]
+0x01008004  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_stop [ t = 0x%(2)08x%(1)08x ]
+0x01008005  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_migr [ t = 0x%(2)08x%(1)08x ]
+0x01008006  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_kill [ t = 0x%(2)08x%(1)08x ]
+0x01008007  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_exec [ t = 0x%(2)08x%(1)08x, tard=0x%(4)08x%(3)08x ]
+0x01008008  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_reprogr [ deadline=0x%(2)08x%(1)08x ]
+0x01008009  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  timer_hoverfl [ old_lim:new_lim=0x%(1)08x ]
+
 0x00084001  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  hpet create [ tn = %(1)d, irq = %(2)d, delta = 0x%(4)08x%(3)08x, period = 0x%(6)08x%(5)08x ]
 0x00084002  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  pit create [ delta = 0x%(1)016x, period = 0x%(2)016x ]
 0x00084003  CPU%(cpu)d  %(tsc)d (+%(reltsc)8d)  rtc create [ delta = 0x%(1)016x , period = 0x%(2)016x ]
diff --git a/tools/xentrace/xenalyze.c b/tools/xentrace/xenalyze.c
index eecdd61..c34479c 100644
--- a/tools/xentrace/xenalyze.c
+++ b/tools/xentrace/xenalyze.c
@@ -8850,6 +8850,143 @@ void tasklet_process(struct pcpu_info *p) {
     }
 }
 
+void timer_process(struct pcpu_info *p) {
+    struct record_info *ri = &p->ri;
+
+    switch ( ri->event )
+    {
+    case TRC_XEN_TIMER_RMENTRY:
+    {
+        struct {
+            uint64_t addr;
+            uint16_t status, cpu;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_rm_entry t=%p, cpu=%u, status=0x%x\n",
+                   ri->dump_header, (void*)r->addr, r->cpu, r->status);
+        }
+        break;
+    }
+    case TRC_XEN_TIMER_ADDENTRY:
+    {
+        struct {
+            uint64_t addr;
+            uint16_t status, cpu;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_add_entry t=%p, cpu=%u, status=0x%x\n",
+                   ri->dump_header, (void*)r->addr, r->cpu, r->status);
+        }
+        break;
+    }
+    case TRC_XEN_TIMER_SET:
+    {
+        struct {
+            uint64_t addr, addr_fn;
+            int64_t expires;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_set t=%p, fn=%p, expires_in=%ld.%luus\n",
+                   ri->dump_header, (void*)r->addr, (void*)r->addr_fn,
+                   (long int)(r->expires / 1000),
+                   (unsigned long int)labs(r->expires % 1000));
+        }
+        break;
+    }
+    case TRC_XEN_TIMER_STOP:
+    {
+        struct {
+            uint64_t addr;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_stop t=%p\n", ri->dump_header, (void*)r->addr);
+        }
+        break;
+    }
+    case TRC_XEN_TIMER_MIGRATE:
+    {
+        struct {
+            uint64_t addr;
+            uint16_t new_cpu, old_cpu;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_migrate t=%p, cpu=%u, new_cpu=%u\n",
+                   ri->dump_header, (void*)r->addr, r->old_cpu, r->new_cpu);
+        }
+        break;
+    }
+    case TRC_XEN_TIMER_KILL:
+    {
+        struct {
+            uint64_t addr;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_kill t=%p\n", ri->dump_header, (void*)r->addr);
+        }
+        break;
+    }
+    case TRC_XEN_TIMER_EXEC:
+    {
+        struct {
+            uint64_t addr;
+            int64_t tardiness;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_exec t=%p, tardiness=%ld.%luus\n",
+                   ri->dump_header, (void*)r->addr,
+                   (long int)(r->tardiness / 1000),
+                   (unsigned long int)labs(r->tardiness % 1000));
+        }
+        break;
+    }
+    case TRC_XEN_TIMER_REPRGR:
+    {
+        struct {
+            uint64_t deadline;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_reprogr deadline=%lu.%luus\n",
+                   ri->dump_header, (unsigned long int)(r->deadline / 1000),
+                   (unsigned long int)(r->deadline % 1000));
+        }
+        break;
+    }
+    case TRC_XEN_TIMER_HOVERFL:
+    {
+        struct {
+            uint16_t new_limit, old_limit;
+        } *r = (typeof(r))ri->d;
+
+        if ( opt.dump_all )
+        {
+            printf(" %s timer_heap_overflow, limit=%u, new_limit=%u\n",
+                   ri->dump_header, r->old_limit, r->new_limit);
+        }
+        break;
+    }
+    default:
+        if( opt.dump_all )
+            dump_generic(stdout, ri);
+        break;
+    }
+}
+
 #define TRC_HW_SUB_PM 1
 #define TRC_HW_SUB_IRQ 2
 void hw_process(struct pcpu_info *p)
@@ -8871,6 +9008,7 @@ void hw_process(struct pcpu_info *p)
 #define TRC_XEN_SUB_RCU 1
 #define TRC_XEN_SUB_SIRQ 2
 #define TRC_XEN_SUB_TSKLT 4
+#define TRC_XEN_SUB_TIMER 8
 void xen_process(struct pcpu_info *p)
 {
     struct record_info *ri = &p->ri;
@@ -8886,6 +9024,9 @@ void xen_process(struct pcpu_info *p)
     case TRC_XEN_SUB_TSKLT:
         tasklet_process(p);
         break;
+    case TRC_XEN_SUB_TIMER:
+        timer_process(p);
+        break;
     }
 }
 


_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xen.org
https://lists.xen.org/xen-devel