From: Esben Nielsen <simlo@phys.au.dk>
To: Ingo Molnar <mingo@elte.hu>
Cc: Michal Schmidt <xschmi00@stud.feec.vutbr.cz>,
linux-kernel@vger.kernel.org,
"Eugeny S. Mints" <emints@ru.mvista.com>,
Daniel Walker <dwalker@mvista.com>
Subject: Re: [patch] Real-Time Preemption, -RT-2.6.12-rc6-V0.7.48-00
Date: Fri, 10 Jun 2005 10:56:33 +0200 (METDST) [thread overview]
Message-ID: <Pine.OSF.4.05.10506101051100.5132-100000@da410.phys.au.dk> (raw)
In-Reply-To: <20050609121326.GB17414@elte.hu>
Does latency tracing work correctly?
After leaving my labtop "working" all night I see the /proc/latency_trace
below. It doesn't look right. Normally the worst case latency is ~70 us
but this tells me it is 1.1ms!! Looking at the trace it looks like it was
really just < 70 us and just an error in the trace. It does look like 3
irq-off periods have been merged into one long period overestimating the
worst case latency by a huge factor.
Esben
preemption latency trace v1.1.4 on 2.6.12-rc6-RT-V0.7.48-04
--------------------------------------------------------------------
latency: 1178 us, #121/121, CPU#0 | (M:rt VP:0, KP:1, SP:1 HP:1 #P:1)
-----------------
| task: ksoftirqd/0-2 (uid:0 nice:-10 policy:0 rt_prio:0)
-----------------
_------=> CPU#
/ _-----=> irqs-off
| / _----=> need-resched
|| / _---=> hardirq/softirq
||| / _--=> preempt-depth
|||| /
||||| delay
cmd pid ||||| time | caller
\ / ||||| \ | /
automoun-9953 0dnh3 0us : <6f747561> (<6e756f6d>)
automoun-9953 0dnh3 0us : __trace_start_sched_wakeup (try_to_wake_up)
automoun-9953 0dnh2 0us : preempt_schedule (try_to_wake_up)
automoun-9953 0dnh2 1us : try_to_wake_up <<...>-2> (73 79)
automoun-9953 0dnh1 1us : preempt_schedule (try_to_wake_up)
automoun-9953 0dnh1 1us : wake_up_process (do_softirq)
automoun-9953 0dnh1 2us : local_irq_restore (do_softirq)
automoun-9953 0dnh1 2us : check_soft_flags (local_irq_restore)
automoun-9953 0dnh. 3us+< (608)
automoun-9953 0dn.. 10us : vgacon_dummy (vt_console_print)
automoun-9953 0dn.. 11us : lf (vt_console_print)
automoun-9953 0dn.. 11us : scrup (lf)
automoun-9953 0dn.. 11us!: vgacon_scroll (scrup)
automoun-9953 0dnh. 972us : do_IRQ (c02174dc 0 0)
automoun-9953 0dnh. 973us : __local_irq_save (__do_IRQ)
automoun-9953 0dnh1 973us : mask_and_ack_8259A (__do_IRQ)
automoun-9953 0dnh1 974us : __local_irq_save (mask_and_ack_8259A)
automoun-9953 0dnh1 975us : local_irq_restore (mask_and_ack_8259A)
automoun-9953 0dnh1 976us : check_soft_flags (local_irq_restore)
automoun-9953 0dnh1 976us : preempt_schedule (mask_and_ack_8259A)
automoun-9953 0dnh1 976us : redirect_hardirq (__do_IRQ)
automoun-9953 0dnh. 977us : preempt_schedule (__do_IRQ)
automoun-9953 0dnh. 977us : handle_IRQ_event (__do_IRQ)
automoun-9953 0dnh. 977us : timer_interrupt (handle_IRQ_event)
automoun-9953 0dnh1 978us : mark_offset_tsc (timer_interrupt)
automoun-9953 0dnh2 978us+: __local_irq_save (mark_offset_tsc)
automoun-9953 0dnh2 981us : local_irq_restore (mark_offset_tsc)
automoun-9953 0dnh2 982us : check_soft_flags (local_irq_restore)
automoun-9953 0dnh2 982us : preempt_schedule (mark_offset_tsc)
automoun-9953 0dnh1 983us : preempt_schedule (mark_offset_tsc)
automoun-9953 0dnh1 983us : preempt_schedule (mark_offset_tsc)
automoun-9953 0dnh1 983us : do_timer (timer_interrupt)
automoun-9953 0dnh1 984us : softlockup_tick (timer_interrupt)
automoun-9953 0dnh1 984us : update_process_times (timer_interrupt)
automoun-9953 0dnh1 984us : account_system_time (update_process_times)
automoun-9953 0dnh1 985us : update_mem_hiwater (update_process_times)
automoun-9953 0dnh1 985us : run_local_timers (update_process_times)
automoun-9953 0dnh1 985us : raise_softirq (update_process_times)
automoun-9953 0dnh1 985us : __local_irq_save (raise_softirq)
automoun-9953 0dnh1 986us : local_irq_restore (raise_softirq)
automoun-9953 0dnh1 986us : check_soft_flags (local_irq_restore)
automoun-9953 0dnh1 986us : rcu_check_callbacks (update_process_times)
automoun-9953 0dnh1 987us : idle_cpu (rcu_check_callbacks)
automoun-9953 0dnh1 987us : scheduler_tick (timer_interrupt)
automoun-9953 0dnh1 987us : sched_clock (scheduler_tick)
automoun-9953 0dnh1 988us : preempt_schedule (scheduler_tick)
automoun-9953 0dnh1 988us : profile_hit (timer_interrupt)
automoun-9953 0dnh. 988us : preempt_schedule (timer_interrupt)
automoun-9953 0dnh. 989us : local_irq_disable (handle_IRQ_event)
automoun-9953 0dnh1 989us : note_interrupt (__do_IRQ)
automoun-9953 0dnh1 990us : end_8259A_irq (__do_IRQ)
automoun-9953 0dnh1 990us : enable_8259A_irq (__do_IRQ)
automoun-9953 0dnh1 990us : __local_irq_save (enable_8259A_irq)
automoun-9953 0dnh1 991us : local_irq_restore (enable_8259A_irq)
automoun-9953 0dnh1 991us : check_soft_flags (local_irq_restore)
automoun-9953 0dnh1 992us : preempt_schedule (enable_8259A_irq)
automoun-9953 0dnh. 992us : preempt_schedule (__do_IRQ)
automoun-9953 0dnh. 992us : local_irq_restore (__do_IRQ)
automoun-9953 0dnh. 993us : check_soft_flags (local_irq_restore)
automoun-9953 0dnh. 993us : irq_exit (do_IRQ)
automoun-9953 0dnh1 993us : do_softirq (irq_exit)
automoun-9953 0dnh1 994us : __local_irq_save (do_softirq)
automoun-9953 0dnh1 994us : __do_softirq (do_softirq)
automoun-9953 0dnh1 994us : local_irq_restore (do_softirq)
automoun-9953 0dnh1 995us : check_soft_flags (local_irq_restore)
automoun-9953 0dnh. 995us!< (66144)
automoun-9953 0dn.. 1138us+: __local_irq_save (vgacon_scroll)
automoun-9953 0dn.. 1140us : local_irq_restore (vgacon_scroll)
automoun-9953 0dn.. 1140us : check_soft_flags (local_irq_restore)
automoun-9953 0dn.. 1141us+: preempt_schedule (vgacon_scroll)
automoun-9953 0dn.. 1145us : vgacon_dummy (vt_console_print)
automoun-9953 0dn.. 1145us : lf (vt_console_print)
automoun-9953 0dn.. 1145us : scrup (lf)
automoun-9953 0dn.. 1146us+: vgacon_scroll (scrup)
automoun-9953 0dn.. 1152us : __local_irq_save (vgacon_scroll)
automoun-9953 0dn.. 1154us : local_irq_restore (vgacon_scroll)
automoun-9953 0dn.. 1154us : check_soft_flags (local_irq_restore)
automoun-9953 0dn.. 1154us : preempt_schedule (vgacon_scroll)
automoun-9953 0dn.. 1155us : set_cursor (vt_console_print)
automoun-9953 0dn.. 1155us : add_softcursor (set_cursor)
automoun-9953 0dn.. 1156us : vgacon_cursor (set_cursor)
automoun-9953 0dn.. 1156us : __local_irq_save (vgacon_cursor)
automoun-9953 0dn.. 1157us : local_irq_restore (vgacon_cursor)
automoun-9953 0dn.. 1158us : check_soft_flags (local_irq_restore)
automoun-9953 0dn.. 1158us : preempt_schedule (vgacon_cursor)
automoun-9953 0dn.. 1158us : vgacon_set_cursor_size (vgacon_cursor)
automoun-9953 0dn.. 1159us : _call_console_drivers (call_console_drivers)
automoun-9953 0dn.. 1159us : local_irq_restore (release_console_sem)
automoun-9953 0dn.. 1159us : check_soft_flags (local_irq_restore)
automoun-9953 0Dn.. 1160us : preempt_schedule (release_console_sem)
automoun-9953 0Dn.. 1160us : irqs_disabled (preempt_schedule)
automoun-9953 0Dnh. 1160us : local_irq_disable (preempt_schedule)
automoun-9953 0dnh. 1161us : __schedule (preempt_schedule)
automoun-9953 0dnh. 1161us : profile_hit (__schedule)
automoun-9953 0dnh1 1162us : sched_clock (__schedule)
automoun-9953 0dnh1 1162us : local_irq_disable (__schedule)
automoun-9953 0dnh2 1163us+: trace_array (__schedule)
automoun-9953 0dnh2 1166us : trace_array <<...>-2> (73 6e)
automoun-9953 0dnh2 1167us : trace_array <automoun-9953> (79 74)
automoun-9953 0dnh2 1167us : trace_array <<...>-3278> (7d 78)
automoun-9953 0dnh2 1167us : trace_array <<...>-3155> (7d 78)
automoun-9953 0dnh2 1168us : trace_array <<...>-4062> (7d 78)
automoun-9953 0dnh2 1168us : trace_array <<...>-4562> (7d 78)
automoun-9953 0dnh2 1168us : trace_array <<...>-9929> (7d 78)
automoun-9953 0dnh2 1168us : trace_array <<...>-3282> (7d 78)
automoun-9953 0dnh2 1169us : trace_array <<...>-4787> (7d 78)
automoun-9953 0dnh2 1169us : trace_array <<...>-3269> (7d 78)
automoun-9953 0dnh2 1169us : trace_array <<...>-3443> (7d 78)
automoun-9953 0dnh2 1170us : trace_array <<...>-9949> (7d 78)
automoun-9953 0dnh2 1170us : trace_array <<...>-4633> (7d 78)
automoun-9953 0dnh2 1170us : trace_array <<...>-3105> (7d 78)
automoun-9953 0dnh2 1171us : trace_array <<...>-9946> (7d 78)
automoun-9953 0dnh2 1171us : trace_array <<...>-839> (7d 78)
automoun-9953 0dnh2 1171us : trace_array <<...>-2952> (7d 78)
automoun-9953 0dnh2 1172us+: trace_array (__schedule)
<...>-2 0dnh2 1176us : __switch_to (__schedule)
<...>-2 0dnh2 1176us : __schedule <automoun-9953> (79 73)
<...>-2 0dnh1 1177us : trace_stop_sched_switched (__schedule)
<...>-2 0dnh1 1177us : trace_stop_sched_switched <<...>-2> (73 0)
<...>-2 0dnh1 1178us : trace_stop_sched_switched (__schedule)
vim:ft=help
On Thu, 9 Jun 2005, Ingo Molnar wrote:
>
> * Michal Schmidt <xschmi00@stud.feec.vutbr.cz> wrote:
>
> > Ingo Molnar wrote:
> > >thanks - i have added it to my tree and have uploaded the -48-03 release
> > >with your patch included.
> >
> > This hunk should not be in the patch:
>
> indeed - new patch uploaded.
>
> Ingo
> -
> To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at http://vger.kernel.org/majordomo-info.html
> Please read the FAQ at http://www.tux.org/lkml/
>
next prev parent reply other threads:[~2005-06-10 8:57 UTC|newest]
Thread overview: 139+ messages / expand[flat|nested] mbox.gz Atom feed top
2005-06-08 11:28 [patch] Real-Time Preemption, -RT-2.6.12-rc6-V0.7.48-00 Ingo Molnar
2005-06-08 14:18 ` Michal Schmidt
2005-06-08 14:40 ` Keith Owens
2005-06-08 14:41 ` Paulo Marques
2005-06-08 15:04 ` Michal Schmidt
2005-06-08 15:23 ` Keith Owens
2005-06-08 15:56 ` Paulo Marques
2005-06-08 14:32 ` Michal Schmidt
2005-06-08 15:48 ` K.R. Foley
2005-06-08 16:08 ` K.R. Foley
2005-06-08 16:26 ` K.R. Foley
2005-06-08 17:26 ` K.R. Foley
2005-06-08 19:18 ` Ingo Molnar
2005-06-09 11:39 ` Ingo Molnar
2005-06-10 13:34 ` K.R. Foley
2005-06-09 8:47 ` Serge Noiraud
2005-06-09 11:55 ` Ingo Molnar
2005-06-09 15:12 ` Serge Noiraud
2005-06-09 16:34 ` Daniel Walker
2005-06-13 8:53 ` Serge Noiraud
2005-06-13 9:07 ` Ingo Molnar
2005-06-08 17:51 ` Michal Schmidt
2005-06-09 11:45 ` Ingo Molnar
2005-06-09 12:09 ` Michal Schmidt
2005-06-09 12:13 ` Ingo Molnar
2005-06-09 12:48 ` Michal Schmidt
2005-06-10 8:56 ` Esben Nielsen [this message]
2005-06-08 19:58 ` Daniel Walker
2005-06-09 21:24 ` Michal Schmidt
2005-06-09 21:34 ` Michal Schmidt
2005-06-11 7:32 ` Ingo Molnar
2005-06-11 8:10 ` Ingo Molnar
2005-06-11 16:35 ` Peter Zijlstra
2005-06-11 18:48 ` Ingo Molnar
2005-06-11 21:14 ` Peter Zijlstra
2005-06-12 1:40 ` Gene Heskett
2005-06-12 6:49 ` Ingo Molnar
2005-06-12 9:02 ` Gene Heskett
2005-06-12 10:35 ` Ingo Molnar
2005-06-12 13:40 ` Gene Heskett
2005-06-12 13:49 ` Ingo Molnar
2005-06-12 14:23 ` Gene Heskett
2005-06-13 2:11 ` Gene Heskett
2005-06-13 6:09 ` Ingo Molnar
2005-06-13 8:37 ` Gene Heskett
2005-06-14 18:24 ` K.R. Foley
2005-06-14 18:54 ` Ingo Molnar
2005-06-14 22:45 ` Daniel Walker
2005-06-15 6:12 ` Ingo Molnar
2005-06-15 9:13 ` Esben Nielsen
2005-06-14 20:37 ` Paul E. McKenney
2005-06-16 3:51 ` K.R. Foley
2005-06-16 7:29 ` Ingo Molnar
2005-06-16 11:22 ` K.R. Foley
2005-06-16 17:32 ` Ingo Molnar
2005-06-16 17:59 ` K.R. Foley
2005-06-16 20:37 ` K.R. Foley
2005-06-17 11:08 ` Ingo Molnar
2005-06-16 20:43 ` Ingo Molnar
2005-06-17 2:06 ` Gene Heskett
2005-06-17 4:53 ` Gene Heskett
2005-06-17 11:18 ` Ingo Molnar
2005-06-17 14:12 ` K.R. Foley
2005-06-17 15:33 ` K.R. Foley
2005-06-17 19:28 ` William Weston
2005-06-17 21:30 ` William Weston
2005-06-18 12:28 ` Ingo Molnar
2005-06-21 1:18 ` William Weston
2005-06-23 2:05 ` William Weston
2005-06-23 7:56 ` Ingo Molnar
2005-06-23 13:45 ` Gene Heskett
2005-06-21 13:10 ` Ingo Molnar
2005-06-21 19:08 ` William Weston
2005-06-21 20:17 ` Benjamin LaHaise
2005-06-21 20:37 ` William Weston
2005-06-21 20:48 ` Benjamin LaHaise
2005-06-22 2:42 ` Gene Heskett
2005-06-22 7:40 ` Ingo Molnar
2005-06-22 13:27 ` Gene Heskett
2005-06-22 13:51 ` Gene Heskett
2005-06-22 14:08 ` Gene Heskett
2005-06-25 4:41 ` Ingo Molnar
2005-06-25 4:47 ` Ingo Molnar
2005-06-25 5:39 ` Gene Heskett
2005-06-25 7:26 ` Gene Heskett
2005-06-25 9:12 ` Ingo Molnar
2005-06-25 13:19 ` Gene Heskett
2005-06-25 14:39 ` Gene Heskett
2005-06-27 19:01 ` Real-Time Preemption, -RT-2.6.12-final-V0.7.50-24 Chuck Harding
2005-06-27 19:42 ` Chuck Harding
2005-06-27 21:08 ` Gene Heskett
2005-06-27 20:09 ` Daniel Walker
2005-06-27 20:28 ` Chuck Harding
2005-06-28 0:50 ` Daniel Walker
2005-06-28 7:53 ` Steven Rostedt
2005-06-28 8:18 ` Ingo Molnar
2005-06-28 8:34 ` Steven Rostedt
2005-06-28 9:12 ` Ingo Molnar
2005-06-28 15:26 ` Michal Schmidt
2005-06-28 15:31 ` Ingo Molnar
2005-06-28 18:50 ` Chuck Harding
2005-06-28 22:16 ` Chuck Harding
2005-06-29 0:32 ` Chuck Harding
2005-06-28 15:54 ` Daniel Walker
2005-06-21 13:12 ` [patch] Real-Time Preemption, -RT-2.6.12-rc6-V0.7.48-00 Ingo Molnar
[not found] ` <Pine.LNX.4.58.0506211228210.16701@echo.lysdexia.org>
2005-06-22 8:03 ` Ingo Molnar
2005-06-22 8:24 ` Ingo Molnar
2005-06-22 10:08 ` Ingo Molnar
2005-06-22 21:53 ` William Weston
2005-06-22 22:00 ` Ingo Molnar
2005-06-22 23:06 ` William Weston
2005-06-23 0:10 ` Ingo Molnar
2005-06-23 22:11 ` William Weston
2005-06-24 1:46 ` William Weston
2005-06-24 7:06 ` Ingo Molnar
2005-06-24 22:31 ` William Weston
2005-06-25 4:14 ` Ingo Molnar
2005-06-27 4:21 ` William Weston
2005-06-27 8:15 ` Ingo Molnar
2005-06-28 3:32 ` William Weston
2005-06-28 8:10 ` Ingo Molnar
2005-06-28 11:15 ` Ingo Molnar
[not found] ` <Pine.LNX.4.58.0506281745040.10406@echo.lysdexia.org>
2005-06-29 12:54 ` Ingo Molnar
2005-06-30 0:29 ` William Weston
2005-06-27 5:43 ` Gene Heskett
2005-06-27 8:17 ` Ingo Molnar
2005-06-27 17:29 ` Gene Heskett
2005-06-27 19:54 ` Ingo Molnar
2005-06-27 21:17 ` Gene Heskett
2005-06-28 8:28 ` Ingo Molnar
2005-06-08 14:02 kus Kusche Klaus
2005-06-08 14:14 ` Thomas Gleixner
2005-06-08 15:49 ` Daniel Walker
2005-06-08 15:50 ` Ingo Molnar
2005-06-08 16:23 ` Thomas Gleixner
2005-06-08 17:29 ` Daniel Walker
2005-06-08 19:24 ` Ingo Molnar
2005-06-12 9:29 Zoltan Boszormenyi
2005-06-12 10:39 ` Ingo Molnar
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=Pine.OSF.4.05.10506101051100.5132-100000@da410.phys.au.dk \
--to=simlo@phys.au.dk \
--cc=dwalker@mvista.com \
--cc=emints@ru.mvista.com \
--cc=linux-kernel@vger.kernel.org \
--cc=mingo@elte.hu \
--cc=xschmi00@stud.feec.vutbr.cz \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).