preemption latency trace v1.1.5 on 2.6.20.7-rt8 -------------------------------------------------------------------- latency: 393 us, #510/510, CPU#0 | (M:rt VP:0, KP:0, SP:1 HP:1) ----------------- | task: check_dektec_in-1145 (uid:0 nice:0 policy:2 rt_prio:80) ----------------- _------=> CPU# / _-----=> irqs-off | / _----=> need-resched || / _---=> hardirq/softirq ||| / _--=> preempt-depth |||| / ||||| delay cmd pid ||||| time | caller \ / ||||| \ | / check_de-1145 0D... 0us : user_trace_start (sys_prctl) check_de-1145 0.... 0us : rt_up (user_trace_start) check_de-1145 0...1 0us : rt_mutex_unlock (rt_up) check_de-1145 0D... 0us < (0) check_de-1145 0.... 1us > sys_ioctl (00000004 8020aa42 000000d8) check_de-1145 0.... 1us : sys_ioctl (sysenter_past_esp) check_de-1145 0.... 1us : fget_light (sys_ioctl) check_de-1145 0.... 1us : vfs_ioctl (sys_ioctl) check_de-1145 0.... 2us : do_ioctl (vfs_ioctl) check_de-1145 0.... 2us : lock_kernel (do_ioctl) check_de-1145 0.... 2us : rt_down (lock_kernel) check_de-1145 0.... 2us : rt_mutex_lock (rt_down) check_de-1145 0.... 2us : Dta1xxIoCtl (do_ioctl) check_de-1145 0.... 3us : copy_from_user (Dta1xxIoCtl) check_de-1145 0.... 3us : __copy_from_user_ll (copy_from_user) check_de-1145 0.... 3us : Dta1xxRxIoCtlDma (Dta1xxIoCtl) check_de-1145 0.... 3us : Dta1xxDmaTransfer (Dta1xxRxIoCtlDma) check_de-1145 0.... 4us : rt_down_interruptible (Dta1xxDmaTransfer) check_de-1145 0.... 4us : rt_mutex_lock_interruptible (rt_down_interruptible) check_de-1145 0.... 4us : prepare_to_wait (Dta1xxDmaTransfer) check_de-1145 0.... 4us : __lock_text_start (prepare_to_wait) check_de-1145 0.... 5us : rt_spin_unlock (prepare_to_wait) check_de-1145 0.... 5us : schedule (Dta1xxDmaTransfer) check_de-1145 0D... 5us : __sched_text_start (schedule) check_de-1145 0D..1 5us : __release_kernel_lock (__sched_text_start) check_de-1145 0D..1 6us : rt_up (__release_kernel_lock) check_de-1145 0D..2 6us : rt_mutex_unlock (rt_up) check_de-1145 0D..1 6us : __sched_text_start (c02ad123 0 0) check_de-1145 0D..1 6us : sched_clock (__sched_text_start) check_de-1145 0D..2 6us : deactivate_task (__sched_text_start) check_de-1145 0D..2 7us : deactivate_task (180 2) check_de-1145 0D..2 7us : dequeue_task (deactivate_task) check_de-1145 0D..2 7us : __switch_to (__sched_text_start) IRQ-10-913 0D..2 8us : __sched_text_start (180 150) IRQ-10-913 0D..1 8us : trace_stop_sched_switched (__sched_text_start) IRQ-10-913 0D... 8us : cond_resched_hardirq_context (thread_simple_irq) IRQ-10-913 0D..1 9us : note_interrupt (thread_simple_irq) IRQ-10-913 0D..1 9us : enable_8259A_irq (thread_level_irq) IRQ-10-913 0.... 10us : cond_resched (do_irqd) IRQ-10-913 0.... 11us : schedule (do_irqd) IRQ-10-913 0D... 11us : __sched_text_start (schedule) IRQ-10-913 0D..1 11us : __sched_text_start (c02ad123 0 0) IRQ-10-913 0D..1 11us : sched_clock (__sched_text_start) IRQ-10-913 0D..2 12us : deactivate_task (__sched_text_start) IRQ-10-913 0D..2 12us : deactivate_task (150 1) IRQ-10-913 0D..2 12us : dequeue_task (deactivate_task) IRQ-10-913 0D..2 13us : __switch_to (__sched_text_start) -0 0D..2 13us : __sched_text_start (150 20) -0 0D..1 13us : trace_stop_sched_switched (__sched_text_start) -0 0...1 13us!: default_idle (cpu_idle) -0 0D..1 229us : smp_apic_timer_interrupt (c0100ea8 1 0) -0 0D..1 229us : irq_enter (smp_apic_timer_interrupt) -0 0D.h1 229us : local_apic_timer_interrupt (smp_apic_timer_interrupt) -0 0D.h1 230us : hrtimer_interrupt (local_apic_timer_interrupt) -0 0D.h1 230us : ktime_get (hrtimer_interrupt) -0 0D.h1 230us : ktime_get_ts (ktime_get) -0 0D.h1 230us : __get_nsec_offset (ktime_get_ts) -0 0D.h1 230us : set_normalized_timespec (ktime_get_ts) -0 0D.h1 231us : hrtimer_interrupt (3548a872a49b 0) -0 0D.h2 231us : hrtimer_interrupt (3548a8729900 c03c2e20) -0 0D.h2 231us : __remove_hrtimer (hrtimer_interrupt) -0 0D.h2 231us : rb_next (__remove_hrtimer) -0 0D.h2 232us : rb_erase (__remove_hrtimer) -0 0D.h2 232us : tick_sched_timer (hrtimer_interrupt) -0 0D.h2 232us : ktime_get (tick_sched_timer) -0 0D.h2 232us : ktime_get_ts (ktime_get) -0 0D.h2 233us : __get_nsec_offset (ktime_get_ts) -0 0D.h2 233us : set_normalized_timespec (ktime_get_ts) -0 0D.h2 233us : tick_do_update_jiffies64 (tick_sched_timer) -0 0D.h3 233us : do_timer (tick_do_update_jiffies64) -0 0D.h1 234us : update_process_times (tick_sched_timer) -0 0D.h1 234us : account_system_time (update_process_times) -0 0D.h1 234us : scheduler_tick (update_process_times) -0 0D.h1 234us : sched_clock (scheduler_tick) -0 0D.h1 234us : run_local_timers (update_process_times) -0 0D.h1 235us : raise_softirq (run_local_timers) -0 0D.h1 235us : wakeup_softirqd (raise_softirq) -0 0D.h1 235us : wake_up_process (wakeup_softirqd) -0 0D.h1 235us : try_to_wake_up (wake_up_process) -0 0D.h1 236us : try_to_wake_up (c01153d8 0 0) -0 0D.h1 236us : task_rq_lock (try_to_wake_up) -0 0D.h2 236us : activate_task (try_to_wake_up) -0 0D.h2 236us : __activate_task (activate_task) -0 0D.h2 236us : __activate_task (150 0) -0 0D.h2 237us : enqueue_task (__activate_task) -0 0D.h2 237us : __trace_start_sched_wakeup (try_to_wake_up) -0 0DNh2 237us : try_to_wake_up (150 20) -0 0DNh1 237us : preempt_schedule (try_to_wake_up) -0 0DNh1 238us : run_posix_cpu_timers (update_process_times) -0 0DNh2 238us : hrtimer_forward (tick_sched_timer) -0 0DNh2 238us : enqueue_hrtimer (hrtimer_interrupt) -0 0DNh2 238us : enqueue_hrtimer (3548a90b2f80 c03c2e20) -0 0DNh2 239us : rb_insert_color (enqueue_hrtimer) -0 0DNh1 239us : preempt_schedule (hrtimer_interrupt) -0 0DNh1 239us : tick_program_event (hrtimer_interrupt) -0 0DNh1 239us : ktime_get (tick_program_event) -0 0DNh1 240us : ktime_get_ts (ktime_get) -0 0DNh1 240us : __get_nsec_offset (ktime_get_ts) -0 0DNh1 240us : set_normalized_timespec (ktime_get_ts) -0 0DNh1 240us : clockevents_program_event (tick_program_event) -0 0DNh1 241us : lapic_next_event (clockevents_program_event) -0 0DNh1 241us : irq_exit (smp_apic_timer_interrupt) -0 0DN.2 241us : do_softirq (irq_exit) -0 0DN.2 241us : __do_softirq (do_softirq) -0 0DN.2 241us : trigger_softirqs (__do_softirq) -0 0DN.2 242us : wakeup_softirqd (trigger_softirqs) -0 0DN.1 243us : irq_enter (do_IRQ) -0 0DNh1 243us : do_IRQ (c0100ea8 10 0) -0 0DNh1 243us : handle_level_irq (do_IRQ) -0 0DNh2 243us+: mask_and_ack_8259A (handle_level_irq) -0 0DNh2 251us : preempt_schedule (mask_and_ack_8259A) -0 0DNh2 251us : redirect_hardirq (handle_level_irq) -0 0DNh2 251us : wake_up_process (redirect_hardirq) -0 0DNh2 251us : try_to_wake_up (wake_up_process) -0 0DNh2 251us : try_to_wake_up (c01153d8 0 0) -0 0DNh2 252us : task_rq_lock (try_to_wake_up) -0 0DNh3 252us : activate_task (try_to_wake_up) -0 0DNh3 252us : __activate_task (activate_task) -0 0DNh3 252us : __activate_task (150 1) -0 0DNh3 252us : enqueue_task (__activate_task) -0 0DNh3 253us : __trace_start_sched_wakeup (try_to_wake_up) -0 0DNh3 253us : try_to_wake_up (150 20) -0 0DNh2 253us : preempt_schedule (try_to_wake_up) -0 0DNh1 253us : preempt_schedule (handle_level_irq) -0 0DNh1 254us : irq_exit (do_IRQ) -0 0DN.2 254us : do_softirq (irq_exit) -0 0DN.2 254us : __do_softirq (do_softirq) -0 0DN.2 254us : trigger_softirqs (__do_softirq) -0 0DN.2 254us : wakeup_softirqd (trigger_softirqs) -0 0DN.. 255us : __sched_text_start (cpu_idle) -0 0DN.1 255us : __sched_text_start (c0100f30 0 0) -0 0DN.1 255us : sched_clock (__sched_text_start) -0 0D..2 256us : __switch_to (__sched_text_start) softirq--4 0D..2 256us : __sched_text_start <-0> (20 150) softirq--4 0D..1 256us : trace_stop_sched_switched (__sched_text_start) softirq--4 0.... 257us : run_timer_softirq (ksoftirqd) softirq--4 0D..1 257us : update_wall_time (run_timer_softirq) softirq--4 0D..1 258us : current_tick_length (update_wall_time) softirq--4 0D..1 258us : clocksource_adjust (update_wall_time) softirq--4 0D..1 258us : change_clocksource (update_wall_time) softirq--4 0D..1 259us : clocksource_get_next (change_clocksource) softirq--4 0.... 259us : hrtimer_run_queues (run_timer_softirq) softirq--4 0.... 259us : __lock_text_start (run_timer_softirq) softirq--4 0.... 259us : rt_spin_unlock (run_timer_softirq) softirq--4 0.... 260us : speedo_timer (run_timer_softirq) softirq--4 0.... 260us : mdio_read (speedo_timer) softirq--4 0.... 260us : iowrite32 (mdio_read) softirq--4 0.... 260us : ioread32 (mdio_read) softirq--4 0.... 261us : ioread32 (mdio_read) softirq--4 0.... 261us : ioread32 (mdio_read) softirq--4 0.... 262us : ioread32 (mdio_read) softirq--4 0.... 262us : ioread32 (mdio_read) softirq--4 0.... 262us : ioread32 (mdio_read) softirq--4 0.... 263us : ioread32 (mdio_read) softirq--4 0.... 263us : ioread32 (mdio_read) softirq--4 0.... 264us : ioread32 (mdio_read) softirq--4 0.... 264us : ioread32 (mdio_read) softirq--4 0.... 265us : ioread32 (mdio_read) softirq--4 0.... 265us : ioread32 (mdio_read) softirq--4 0.... 266us : ioread32 (mdio_read) softirq--4 0.... 266us : ioread32 (mdio_read) softirq--4 0.... 266us : ioread32 (mdio_read) softirq--4 0.... 267us : ioread32 (mdio_read) softirq--4 0.... 267us : ioread32 (mdio_read) softirq--4 0.... 268us : ioread32 (mdio_read) softirq--4 0.... 268us : ioread32 (mdio_read) softirq--4 0.... 269us : ioread32 (mdio_read) softirq--4 0.... 269us : ioread32 (mdio_read) softirq--4 0.... 270us : ioread32 (mdio_read) softirq--4 0.... 270us : ioread32 (mdio_read) softirq--4 0.... 271us : ioread32 (mdio_read) softirq--4 0.... 271us : ioread32 (mdio_read) softirq--4 0.... 271us : ioread32 (mdio_read) softirq--4 0.... 272us : ioread32 (mdio_read) softirq--4 0.... 272us : ioread32 (mdio_read) softirq--4 0.... 273us : ioread32 (mdio_read) softirq--4 0.... 273us : ioread32 (mdio_read) softirq--4 0.... 274us : ioread32 (mdio_read) softirq--4 0.... 274us : ioread32 (mdio_read) softirq--4 0.... 275us : ioread32 (mdio_read) softirq--4 0.... 275us : ioread32 (mdio_read) softirq--4 0.... 275us : ioread32 (mdio_read) softirq--4 0.... 276us : ioread32 (mdio_read) softirq--4 0.... 276us : ioread32 (mdio_read) softirq--4 0.... 277us : ioread32 (mdio_read) softirq--4 0.... 277us : ioread32 (mdio_read) softirq--4 0.... 278us : ioread32 (mdio_read) softirq--4 0.... 278us : ioread32 (mdio_read) softirq--4 0.... 279us : ioread32 (mdio_read) softirq--4 0.... 279us : ioread32 (mdio_read) softirq--4 0.... 280us : ioread32 (mdio_read) softirq--4 0.... 280us : ioread32 (mdio_read) softirq--4 0.... 280us : ioread32 (mdio_read) softirq--4 0.... 281us : ioread32 (mdio_read) softirq--4 0.... 281us : ioread32 (mdio_read) softirq--4 0.... 282us : ioread32 (mdio_read) softirq--4 0.... 282us : ioread32 (mdio_read) softirq--4 0.... 283us : ioread32 (mdio_read) softirq--4 0.... 283us : ioread32 (mdio_read) softirq--4 0.... 284us : ioread32 (mdio_read) softirq--4 0.... 284us : ioread32 (mdio_read) softirq--4 0.... 284us : ioread32 (mdio_read) softirq--4 0.... 285us : ioread32 (mdio_read) softirq--4 0.... 285us : ioread32 (mdio_read) softirq--4 0.... 286us : ioread32 (mdio_read) softirq--4 0.... 286us : ioread32 (mdio_read) softirq--4 0.... 287us : ioread32 (mdio_read) softirq--4 0.... 287us : ioread32 (mdio_read) softirq--4 0.... 288us : ioread32 (mdio_read) softirq--4 0.... 288us : ioread32 (mdio_read) softirq--4 0.... 289us : ioread32 (mdio_read) softirq--4 0.... 289us : ioread32 (mdio_read) softirq--4 0.... 289us : ioread32 (mdio_read) softirq--4 0.... 290us : ioread32 (mdio_read) softirq--4 0.... 290us : ioread32 (mdio_read) softirq--4 0.... 291us : ioread32 (mdio_read) softirq--4 0.... 291us : ioread32 (mdio_read) softirq--4 0.... 292us : mii_check_link (speedo_timer) softirq--4 0.... 292us : mii_link_ok (mii_check_link) softirq--4 0.... 292us : mdio_read (mii_link_ok) softirq--4 0.... 292us : iowrite32 (mdio_read) softirq--4 0.... 293us : ioread32 (mdio_read) softirq--4 0.... 293us : ioread32 (mdio_read) softirq--4 0.... 293us : ioread32 (mdio_read) softirq--4 0.... 294us : ioread32 (mdio_read) softirq--4 0.... 294us : ioread32 (mdio_read) softirq--4 0.... 295us : ioread32 (mdio_read) softirq--4 0.... 295us : ioread32 (mdio_read) softirq--4 0.... 296us : ioread32 (mdio_read) softirq--4 0.... 296us : ioread32 (mdio_read) softirq--4 0.... 297us : ioread32 (mdio_read) softirq--4 0.... 297us : ioread32 (mdio_read) softirq--4 0.... 297us : ioread32 (mdio_read) softirq--4 0.... 298us : ioread32 (mdio_read) softirq--4 0.... 298us : ioread32 (mdio_read) softirq--4 0.... 299us : ioread32 (mdio_read) softirq--4 0.... 299us : ioread32 (mdio_read) softirq--4 0.... 300us : ioread32 (mdio_read) softirq--4 0.... 300us : ioread32 (mdio_read) softirq--4 0.... 301us : ioread32 (mdio_read) softirq--4 0.... 301us : ioread32 (mdio_read) softirq--4 0.... 302us : ioread32 (mdio_read) softirq--4 0.... 302us : ioread32 (mdio_read) softirq--4 0.... 302us : ioread32 (mdio_read) softirq--4 0.... 303us : ioread32 (mdio_read) softirq--4 0.... 303us : ioread32 (mdio_read) softirq--4 0.... 304us : ioread32 (mdio_read) softirq--4 0.... 304us : ioread32 (mdio_read) softirq--4 0.... 305us : ioread32 (mdio_read) softirq--4 0.... 305us : ioread32 (mdio_read) softirq--4 0.... 306us : ioread32 (mdio_read) softirq--4 0.... 306us : ioread32 (mdio_read) softirq--4 0.... 306us : ioread32 (mdio_read) softirq--4 0.... 307us : ioread32 (mdio_read) softirq--4 0.... 307us : ioread32 (mdio_read) softirq--4 0.... 308us : ioread32 (mdio_read) softirq--4 0.... 308us : ioread32 (mdio_read) softirq--4 0.... 309us : ioread32 (mdio_read) softirq--4 0.... 309us : ioread32 (mdio_read) softirq--4 0.... 310us : ioread32 (mdio_read) softirq--4 0.... 310us : ioread32 (mdio_read) softirq--4 0.... 311us : ioread32 (mdio_read) softirq--4 0.... 311us : ioread32 (mdio_read) softirq--4 0.... 311us : ioread32 (mdio_read) softirq--4 0.... 312us : ioread32 (mdio_read) softirq--4 0.... 312us : ioread32 (mdio_read) softirq--4 0.... 313us : ioread32 (mdio_read) softirq--4 0.... 313us : ioread32 (mdio_read) softirq--4 0.... 314us : ioread32 (mdio_read) softirq--4 0.... 314us : ioread32 (mdio_read) softirq--4 0.... 315us : ioread32 (mdio_read) softirq--4 0.... 315us : ioread32 (mdio_read) softirq--4 0.... 315us : ioread32 (mdio_read) softirq--4 0.... 316us : ioread32 (mdio_read) softirq--4 0.... 316us : ioread32 (mdio_read) softirq--4 0.... 317us : ioread32 (mdio_read) softirq--4 0.... 317us : ioread32 (mdio_read) softirq--4 0.... 318us : ioread32 (mdio_read) softirq--4 0.... 318us : ioread32 (mdio_read) softirq--4 0.... 319us : ioread32 (mdio_read) softirq--4 0.... 319us : ioread32 (mdio_read) softirq--4 0.... 320us : ioread32 (mdio_read) softirq--4 0.... 320us : ioread32 (mdio_read) softirq--4 0.... 320us : ioread32 (mdio_read) softirq--4 0.... 321us : ioread32 (mdio_read) softirq--4 0.... 321us : ioread32 (mdio_read) softirq--4 0.... 322us : ioread32 (mdio_read) softirq--4 0.... 322us : ioread32 (mdio_read) softirq--4 0.... 323us : ioread32 (mdio_read) softirq--4 0.... 323us : ioread32 (mdio_read) softirq--4 0.... 324us : ioread32 (mdio_read) softirq--4 0.... 324us : mdio_read (mii_link_ok) softirq--4 0.... 324us : iowrite32 (mdio_read) softirq--4 0.... 324us : ioread32 (mdio_read) softirq--4 0.... 325us : ioread32 (mdio_read) softirq--4 0.... 325us : ioread32 (mdio_read) softirq--4 0.... 326us : ioread32 (mdio_read) softirq--4 0.... 326us : ioread32 (mdio_read) softirq--4 0.... 327us : ioread32 (mdio_read) softirq--4 0.... 327us : ioread32 (mdio_read) softirq--4 0.... 328us : ioread32 (mdio_read) softirq--4 0.... 328us : ioread32 (mdio_read) softirq--4 0.... 328us : ioread32 (mdio_read) softirq--4 0.... 329us : ioread32 (mdio_read) softirq--4 0.... 329us : ioread32 (mdio_read) softirq--4 0.... 330us : ioread32 (mdio_read) softirq--4 0.... 330us : ioread32 (mdio_read) softirq--4 0.... 331us : ioread32 (mdio_read) softirq--4 0.... 331us : ioread32 (mdio_read) softirq--4 0.... 332us : ioread32 (mdio_read) softirq--4 0.... 332us : ioread32 (mdio_read) softirq--4 0.... 333us : ioread32 (mdio_read) softirq--4 0.... 333us : ioread32 (mdio_read) softirq--4 0.... 333us : ioread32 (mdio_read) softirq--4 0.... 334us : ioread32 (mdio_read) softirq--4 0.... 334us : ioread32 (mdio_read) softirq--4 0.... 335us : ioread32 (mdio_read) softirq--4 0.... 335us : ioread32 (mdio_read) softirq--4 0.... 336us : ioread32 (mdio_read) softirq--4 0.... 336us : ioread32 (mdio_read) softirq--4 0.... 337us : ioread32 (mdio_read) softirq--4 0.... 337us : ioread32 (mdio_read) softirq--4 0.... 337us : ioread32 (mdio_read) softirq--4 0.... 338us : ioread32 (mdio_read) softirq--4 0.... 338us : ioread32 (mdio_read) softirq--4 0.... 339us : ioread32 (mdio_read) softirq--4 0.... 339us : ioread32 (mdio_read) softirq--4 0.... 340us : ioread32 (mdio_read) softirq--4 0.... 340us : ioread32 (mdio_read) softirq--4 0.... 341us : ioread32 (mdio_read) softirq--4 0.... 341us : ioread32 (mdio_read) softirq--4 0.... 342us : ioread32 (mdio_read) softirq--4 0.... 342us : ioread32 (mdio_read) softirq--4 0.... 342us : ioread32 (mdio_read) softirq--4 0.... 343us : ioread32 (mdio_read) softirq--4 0.... 343us : ioread32 (mdio_read) softirq--4 0.... 344us : ioread32 (mdio_read) softirq--4 0.... 344us : ioread32 (mdio_read) softirq--4 0.... 345us : ioread32 (mdio_read) softirq--4 0.... 345us : ioread32 (mdio_read) softirq--4 0.... 346us : ioread32 (mdio_read) softirq--4 0.... 346us : ioread32 (mdio_read) softirq--4 0.... 346us : ioread32 (mdio_read) softirq--4 0.... 347us : ioread32 (mdio_read) softirq--4 0.... 347us : ioread32 (mdio_read) softirq--4 0.... 348us : ioread32 (mdio_read) softirq--4 0.... 348us : ioread32 (mdio_read) softirq--4 0.... 349us : ioread32 (mdio_read) softirq--4 0.... 349us : ioread32 (mdio_read) softirq--4 0.... 350us : ioread32 (mdio_read) softirq--4 0.... 350us : ioread32 (mdio_read) softirq--4 0.... 351us : ioread32 (mdio_read) softirq--4 0.... 351us : ioread32 (mdio_read) softirq--4 0.... 351us : ioread32 (mdio_read) softirq--4 0.... 352us : ioread32 (mdio_read) softirq--4 0.... 352us : ioread32 (mdio_read) softirq--4 0.... 353us : ioread32 (mdio_read) softirq--4 0.... 353us : ioread32 (mdio_read) softirq--4 0.... 354us : ioread32 (mdio_read) softirq--4 0.... 354us : ioread32 (mdio_read) softirq--4 0.... 355us : ioread32 (mdio_read) softirq--4 0.... 355us : ioread32 (mdio_read) softirq--4 0.... 355us : ioread32 (mdio_read) softirq--4 0.... 356us : __mod_timer (speedo_timer) softirq--4 0.... 356us : lock_timer_base (__mod_timer) softirq--4 0.... 357us : __lock_text_start (lock_timer_base) softirq--4 0.... 357us : internal_add_timer (__mod_timer) softirq--4 0.... 357us : rt_spin_unlock (__mod_timer) softirq--4 0.... 357us : cond_resched_softirq_context (run_timer_softirq) softirq--4 0.... 357us : __lock_text_start (run_timer_softirq) softirq--4 0.... 358us : __wake_up (run_timer_softirq) softirq--4 0.... 358us : __lock_text_start (__wake_up) softirq--4 0.... 358us : __wake_up_common (__wake_up) softirq--4 0.... 358us : rt_spin_unlock (__wake_up) softirq--4 0.... 358us : rt_spin_unlock (run_timer_softirq) softirq--4 0.... 359us : cond_resched (ksoftirqd) softirq--4 0.... 359us : __wake_up (ksoftirqd) softirq--4 0.... 359us : __lock_text_start (__wake_up) softirq--4 0.... 359us : __wake_up_common (__wake_up) softirq--4 0.... 360us : rt_spin_unlock (__wake_up) softirq--4 0.... 360us : kthread_should_stop (ksoftirqd) softirq--4 0.... 360us : schedule (ksoftirqd) softirq--4 0D... 360us : __sched_text_start (schedule) softirq--4 0D..1 360us : __sched_text_start (c02ad123 0 0) softirq--4 0D..1 361us : sched_clock (__sched_text_start) softirq--4 0D..2 361us : deactivate_task (__sched_text_start) softirq--4 0D..2 361us : deactivate_task (150 2) softirq--4 0D..2 361us : dequeue_task (deactivate_task) softirq--4 0D..2 362us : __switch_to (__sched_text_start) IRQ-10-913 0D..2 362us : __sched_text_start (150 150) IRQ-10-913 0D..1 362us : trace_stop_sched_switched (__sched_text_start) IRQ-10-913 0.... 363us : kthread_should_stop (do_irqd) IRQ-10-913 0.... 363us : do_hardirq (do_irqd) IRQ-10-913 0D..1 363us : thread_level_irq (do_hardirq) IRQ-10-913 0D..1 363us : thread_simple_irq (thread_level_irq) IRQ-10-913 0D... 364us : handle_IRQ_event (thread_simple_irq) IRQ-10-913 0.... 364us : speedo_interrupt (handle_IRQ_event) IRQ-10-913 0.... 364us : ioread16 (speedo_interrupt) IRQ-10-913 0.... 365us : iowrite16 (speedo_interrupt) IRQ-10-913 0.... 365us+: Dta1xxIsr (handle_IRQ_event) IRQ-10-913 0.... 367us : __wake_up (Dta1xxIsr) IRQ-10-913 0.... 367us : __lock_text_start (__wake_up) IRQ-10-913 0.... 367us : __wake_up_common (__wake_up) IRQ-10-913 0.... 367us : autoremove_wake_function (__wake_up_common) IRQ-10-913 0.... 368us : default_wake_function (autoremove_wake_function) IRQ-10-913 0.... 368us : try_to_wake_up (default_wake_function) IRQ-10-913 0.... 368us : try_to_wake_up (c0115ec0 0 0) IRQ-10-913 0.... 368us : task_rq_lock (try_to_wake_up) IRQ-10-913 0D..1 369us : activate_task (try_to_wake_up) IRQ-10-913 0D..1 369us : __activate_task (activate_task) IRQ-10-913 0D..1 369us : __activate_task (180 1) IRQ-10-913 0D..1 369us : enqueue_task (__activate_task) IRQ-10-913 0D..1 369us : __trace_start_sched_wakeup (try_to_wake_up) IRQ-10-913 0DN.1 370us : try_to_wake_up (180 150) IRQ-10-913 0.N.. 370us : preempt_schedule (try_to_wake_up) IRQ-10-913 0DN.. 370us : __sched_text_start (preempt_schedule) IRQ-10-913 0DN.1 370us : __sched_text_start (c02ad281 0 0) IRQ-10-913 0DN.1 370us : sched_clock (__sched_text_start) IRQ-10-913 0D..2 371us : __switch_to (__sched_text_start) check_de-1145 0D..2 371us : __sched_text_start (150 180) check_de-1145 0D..1 372us : trace_stop_sched_switched (__sched_text_start) check_de-1145 0D... 372us : __reacquire_kernel_lock (__sched_text_start) check_de-1145 0.... 372us : rt_down (__reacquire_kernel_lock) check_de-1145 0.... 372us : rt_mutex_lock (rt_down) check_de-1145 0.... 373us : prepare_to_wait (Dta1xxDmaTransfer) check_de-1145 0.... 373us : __lock_text_start (prepare_to_wait) check_de-1145 0.... 373us : rt_spin_lock_slowlock (__lock_text_start) check_de-1145 0D..1 373us : try_to_take_rt_mutex (rt_spin_lock_slowlock) check_de-1145 0D..1 373us : try_to_take_rt_mutex (rt_spin_lock_slowlock) check_de-1145 0D..1 374us : task_blocks_on_rt_mutex (rt_spin_lock_slowlock) check_de-1145 0D..2 374us : __rt_mutex_adjust_prio (task_blocks_on_rt_mutex) check_de-1145 0D..2 374us : rt_mutex_getprio (__rt_mutex_adjust_prio) check_de-1145 0D..2 374us : plist_add (task_blocks_on_rt_mutex) check_de-1145 0D..2 375us : plist_del (task_blocks_on_rt_mutex) check_de-1145 0D..2 375us : plist_add (task_blocks_on_rt_mutex) check_de-1145 0D..2 375us : __rt_mutex_adjust_prio (task_blocks_on_rt_mutex) check_de-1145 0D..2 375us : rt_mutex_getprio (__rt_mutex_adjust_prio) check_de-1145 0D..2 375us : rt_mutex_setprio (__rt_mutex_adjust_prio) check_de-1145 0D..2 376us : task_rq_lock (rt_mutex_setprio) check_de-1145 0D..3 376us : dequeue_task (rt_mutex_setprio) check_de-1145 0D..3 376us : rt_mutex_setprio (150 180) check_de-1145 0D..3 376us : enqueue_task (rt_mutex_setprio) check_de-1145 0D..3 377us : rt_mutex_setprio (0 0 0) check_de-1145 0.... 377us : schedule (rt_spin_lock_slowlock) check_de-1145 0D... 377us : __sched_text_start (schedule) check_de-1145 0D..1 377us : __sched_text_start (c02ad123 0 0) check_de-1145 0D..1 377us : sched_clock (__sched_text_start) check_de-1145 0D..2 378us : deactivate_task (__sched_text_start) check_de-1145 0D..2 378us : deactivate_task (180 2) check_de-1145 0D..2 378us : dequeue_task (deactivate_task) check_de-1145 0D..2 379us : __switch_to (__sched_text_start) IRQ-10-913 0D..2 379us : __sched_text_start (180 180) IRQ-10-913 0D..1 379us : trace_stop_sched_switched (__sched_text_start) IRQ-10-913 0.... 380us : rt_spin_unlock (__wake_up) IRQ-10-913 0.... 380us : rt_spin_lock_slowunlock (rt_spin_unlock) IRQ-10-913 0D..1 380us : wakeup_next_waiter (rt_spin_lock_slowunlock) IRQ-10-913 0D..2 380us : plist_del (wakeup_next_waiter) IRQ-10-913 0D..2 380us : plist_del (wakeup_next_waiter) IRQ-10-913 0D..2 381us : rt_mutex_set_owner (wakeup_next_waiter) IRQ-10-913 0D..1 381us : wake_up_process_mutex (wakeup_next_waiter) IRQ-10-913 0D..1 381us : try_to_wake_up (wake_up_process_mutex) IRQ-10-913 0D..1 381us : try_to_wake_up (c011543b 0 0) IRQ-10-913 0D..1 381us : task_rq_lock (try_to_wake_up) IRQ-10-913 0D..2 382us : __activate_task (try_to_wake_up) IRQ-10-913 0D..2 382us : __activate_task (180 1) IRQ-10-913 0D..2 382us : enqueue_task (__activate_task) IRQ-10-913 0.... 382us : rt_mutex_adjust_prio (rt_spin_lock_slowunlock) IRQ-10-913 0D..1 383us : __rt_mutex_adjust_prio (rt_mutex_adjust_prio) IRQ-10-913 0D..1 383us : rt_mutex_getprio (__rt_mutex_adjust_prio) IRQ-10-913 0D..1 383us : rt_mutex_setprio (__rt_mutex_adjust_prio) IRQ-10-913 0D..1 383us : task_rq_lock (rt_mutex_setprio) IRQ-10-913 0D..2 383us : dequeue_task (rt_mutex_setprio) IRQ-10-913 0D..2 384us : rt_mutex_setprio (180 150) IRQ-10-913 0D..2 384us : enqueue_task (rt_mutex_setprio) IRQ-10-913 0DN.2 384us : rt_mutex_setprio (0 1 0) IRQ-10-913 0DN.1 384us : preempt_schedule (rt_mutex_setprio) IRQ-10-913 0.N.. 385us : preempt_schedule (rt_mutex_adjust_prio) IRQ-10-913 0DN.. 385us : __sched_text_start (preempt_schedule) IRQ-10-913 0DN.1 385us : __sched_text_start (c02ad281 0 0) IRQ-10-913 0DN.1 385us : sched_clock (__sched_text_start) IRQ-10-913 0D..2 386us : __switch_to (__sched_text_start) check_de-1145 0D..2 386us : __sched_text_start (150 180) check_de-1145 0D..1 386us : trace_stop_sched_switched (__sched_text_start) check_de-1145 0D..1 386us : try_to_take_rt_mutex (rt_spin_lock_slowlock) check_de-1145 0D..1 387us : rt_mutex_set_owner (try_to_take_rt_mutex) check_de-1145 0D..1 387us : fixup_rt_mutex_waiters (rt_spin_lock_slowlock) check_de-1145 0.... 387us : rt_spin_unlock (prepare_to_wait) check_de-1145 0.... 387us : finish_wait (Dta1xxDmaTransfer) check_de-1145 0.... 388us : __lock_text_start (finish_wait) check_de-1145 0.... 388us : rt_spin_unlock (finish_wait) check_de-1145 0.... 388us : rt_up (Dta1xxDmaTransfer) check_de-1145 0...1 388us : rt_mutex_unlock (rt_up) check_de-1145 0.... 388us : copy_to_user (Dta1xxRxIoCtlDma) check_de-1145 0.... 389us+: __copy_to_user_ll (copy_to_user) check_de-1145 0.... 391us : unlock_kernel (do_ioctl) check_de-1145 0.... 391us : rt_up (unlock_kernel) check_de-1145 0...1 391us : rt_mutex_unlock (rt_up) check_de-1145 0D... 392us < (0) check_de-1145 0.... 392us > sys_prctl (00000000 00000000 000000d8) check_de-1145 0.... 393us : sys_prctl (sysenter_past_esp) check_de-1145 0.... 393us : user_trace_stop (sys_prctl) check_de-1145 0D... 393us : user_trace_stop (sys_prctl) vim:ft=help