preemption latency trace v1.0.3
-------------------------------
 latency: 4019 us, entries: 38 (38)
    -----------------
    | task: amlat/4371, uid:0 nice:0 policy:1 rt_prio:99
    -----------------
 => started at: n_tty_receive_buf+0xeca/0xfe0
 => ended at:   n_tty_receive_buf+0xfc1/0xfe0
=======>
00000001 0.000ms (+0.000ms): n_tty_receive_buf (pty_write)
00010001 3.992ms (+3.992ms): do_IRQ (n_tty_receive_buf)
00010002 3.992ms (+0.000ms): mask_and_ack_8259A (do_IRQ)
00010002 3.995ms (+0.002ms): generic_redirect_hardirq (do_IRQ)
00010001 3.995ms (+0.000ms): generic_handle_IRQ_event (do_IRQ)
00010001 3.996ms (+0.000ms): timer_interrupt (generic_handle_IRQ_event)
00010002 3.996ms (+0.000ms): mark_offset_tsc (timer_interrupt)
00010002 4.003ms (+0.006ms): do_timer (timer_interrupt)
00010002 4.003ms (+0.000ms): update_process_times (do_timer)
00010002 4.004ms (+0.000ms): update_one_process (update_process_times)
00010002 4.004ms (+0.000ms): run_local_timers (update_process_times)
00010002 4.005ms (+0.000ms): raise_softirq (update_process_times)
00010002 4.005ms (+0.000ms): scheduler_tick (update_process_times)
00010002 4.006ms (+0.000ms): sched_clock (scheduler_tick)
00010002 4.008ms (+0.002ms): update_wall_time (do_timer)
00010002 4.008ms (+0.000ms): update_wall_time_one_tick (update_wall_time)
00010002 4.009ms (+0.000ms): update_wall_time_one_tick (update_wall_time)
00010002 4.009ms (+0.000ms): update_wall_time_one_tick (update_wall_time)
00010002 4.009ms (+0.000ms): update_wall_time_one_tick (update_wall_time)
00010002 4.010ms (+0.000ms): profile_tick (timer_interrupt)
00010002 4.010ms (+0.000ms): profile_hook (profile_tick)
00010003 4.011ms (+0.000ms): notifier_call_chain (profile_hook)
00010002 4.011ms (+0.000ms): profile_hit (timer_interrupt)
00010002 4.012ms (+0.000ms): generic_note_interrupt (do_IRQ)
00010002 4.012ms (+0.000ms): end_8259A_irq (do_IRQ)
00010002 4.013ms (+0.000ms): enable_8259A_irq (do_IRQ)
00000002 4.014ms (+0.001ms): do_softirq (do_IRQ)
00000002 4.015ms (+0.000ms): __do_softirq (do_softirq)
00000002 4.015ms (+0.000ms): wake_up_process (do_softirq)
00000002 4.015ms (+0.000ms): try_to_wake_up (wake_up_process)
00000002 4.016ms (+0.000ms): task_rq_lock (try_to_wake_up)
00000003 4.016ms (+0.000ms): activate_task (try_to_wake_up)
00000003 4.016ms (+0.000ms): sched_clock (activate_task)
00000003 4.017ms (+0.000ms): recalc_task_prio (activate_task)
00000003 4.018ms (+0.000ms): effective_prio (recalc_task_prio)
00000003 4.018ms (+0.000ms): enqueue_task (activate_task)
00000001 4.020ms (+0.001ms): sub_preempt_count (n_tty_receive_buf)
00000001 4.020ms (+0.000ms): update_max_trace (check_preempt_timing)