# tracer: irqsoff # # irqsoff latency trace v1.1.5 on 6.15.1-dirty # -------------------------------------------------------------------- # latency: 208 us, #15/15, CPU#1 | (M:PREEMPT_RT VP:0, KP:0, SP:0 HP:0 #P:2) # ----------------- # | task: ktimers/1-31 (uid:0 nice:0 policy:1 rt_prio:1) # ----------------- # => started at: handle_softirqs.constprop.0 # => ended at: run_ktimerd # # # _------=> CPU# # / _-----=> irqs-off/BH-disabled # | / _----=> need-resched # || / _---=> hardirq/softirq # ||| / _--=> preempt-depth # |||| / _-=> migrate-disable # ||||| / delay # cmd pid |||||| time | caller # \ / |||||| \ | / ktimers/-31 1d.s.2 0us : handle_softirqs.constprop.0 ktimers/-31 1d.s.2 0us : irq_disable: caller=handle_softirqs.constprop.0+0x2d8/0x300 parent=0x0 ktimers/-31 1d.s.2 0us : __local_bh_enable(cnt=256, unlock=1) <-run_ktimerd ktimers/-31 1d...2 0us : __rcu_read_unlock() <-__local_bh_enable ktimers/-31 1d...2 1us+: rt_spin_unlock(lock=0xffff983497d17660) <-__local_bh_enable ktimers/-31 1d...2 99us : migrate_enable() <-rt_spin_unlock ktimers/-31 1d...1 99us : __rcu_read_unlock() <-rt_spin_unlock ktimers/-31 1d...1 99us : migrate_enable() <-run_ktimerd ktimers/-31 1d..11 100us!: preempt_disable: caller=run_ktimerd+0x4f/0x90 parent=run_ktimerd+0x4f/0x90 ktimers/-31 1d..1. 208us : preempt_enable: caller=run_ktimerd+0x4f/0x90 parent=run_ktimerd+0x4f/0x90 ktimers/-31 1d.... 209us : irq_enable: caller=run_ktimerd+0x78/0x90 parent=0x0 ktimers/-31 1d.... 209us : run_ktimerd ktimers/-31 1d..1. 209us : preempt_disable: caller=_raw_spin_lock_irqsave+0x23/0x60 parent=0x0 ktimers/-31 1d.... 209us!: tracer_hardirqs_on <-run_ktimerd ktimers/-31 1d.... 581us : => smpboot_thread_fn => kthread => ret_from_fork => ret_from_fork_asm