irqsoff-ubench1-out.txt

[root@linus tracing]# cat trace
# tracer: irqsoff
#
# irqsoff latency trace v1.1.5 on 4.3.0-rc3+
# --------------------------------------------------------------------
# latency: 9994 us, #14/14, CPU#1 | (M:desktop VP:0, KP:0, SP:0 HP:0 #P:4)
#    -----------------
#    | task: insmod-29922 (uid:0 nice:0 policy:0 rt_prio:0)
#    -----------------
#  => started at: do_one_initcall
#  => ended at:   do_one_initcall
#
#
#                  _------=> CPU#            
#                 / _-----=> irqs-off        
#                | / _----=> need-resched    
#                || / _---=> hardirq/softirq 
#                ||| / _--=> preempt-depth   
#                |||| /     delay            
#  cmd     pid   ||||| time  |   caller      
#     \   /      |||||  \    |   /         
  insmod-29922   1d...    0us :  <-do_one_initcall
  insmod-29922   1d...    0us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 1000us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 1999us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 2998us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 3998us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 4997us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 5997us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 6996us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 7995us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 8995us!: delay_tsc <-__const_udelay
  insmod-29922   1d... 9994us :  <-do_one_initcall
  insmod-29922   1d... 9995us : trace_hardirqs_on <-do_one_initcall
  insmod-29922   1d... 10003us : <stack trace>
 => 
 => do_one_initcall
 => do_init_module
 => load_module
 => SyS_finit_module
 => entry_SYSCALL_64_fastpath