From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1761325AbZBYJbJ (ORCPT ); Wed, 25 Feb 2009 04:31:09 -0500 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1755616AbZBYJaz (ORCPT ); Wed, 25 Feb 2009 04:30:55 -0500 Received: from casper.infradead.org ([85.118.1.10]:34996 "EHLO casper.infradead.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1755203AbZBYJay (ORCPT ); Wed, 25 Feb 2009 04:30:54 -0500 Subject: Re: [PATCH 2/4] tracing: add event trace infrastructure From: Peter Zijlstra To: Andrew Morton Cc: Pekka Enberg , Ingo Molnar , Steven Rostedt , LKML , Thomas Gleixner , Frederic Weisbecker , Theodore Tso , Arjan van de Ven , Pekka Paalanen , Arnaldo Carvalho de Melo , Jason Baron , Martin Bligh , Mathieu Desnoyers , "Frank Ch. Eigler" , KOSAKI Motohiro , Jens Axboe , Masami Hiramatsu , Steven Rostedt In-Reply-To: <20090225012250.db68e480.akpm@linux-foundation.org> References: <20090225025608.956691460@goodmis.org> <20090225025753.798204550@goodmis.org> <20090224194548.3effb746.akpm@linux-foundation.org> <20090224203308.8d623e0b.akpm@linux-foundation.org> <20090225081118.GC15303@elte.hu> <20090225002852.5ef5b869.akpm@linux-foundation.org> <84144f020902250100k41e55dd7w8a9c8d2ca96908ea@mail.gmail.com> <20090225012250.db68e480.akpm@linux-foundation.org> Content-Type: text/plain Date: Wed, 25 Feb 2009 10:26:22 +0100 Message-Id: <1235553982.4645.3111.camel@laptop> Mime-Version: 1.0 X-Mailer: Evolution 2.25.91 Content-Transfer-Encoding: 7bit Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org On Wed, 2009-02-25 at 01:22 -0800, Andrew Morton wrote: > Parse this: > > irqsoff latency trace v1.1.5 on 2.6.26-rc8 > -------------------------------------------------------------------- > latency: 97 us, #3/3, CPU#0 | (M:preempt VP:0, KP:0, SP:0 HP:0 #P:2) > ----------------- > | task: swapper-0 (uid:0 nice:0 policy:0 rt_prio:0) > ----------------- > => started at: apic_timer_interrupt > => ended at: do_softirq > > # _------=> CPU# > # / _-----=> irqs-off > # | / _----=> need-resched > # || / _---=> hardirq/softirq > # ||| / _--=> preempt-depth > # |||| / > # ||||| delay > # cmd pid ||||| time | caller > # \ / ||||| \ | / > -0 0d..1 0us+: trace_hardirqs_off_thunk (apic_timer_interrupt) > -0 0d.s. 97us : __do_softirq (do_softirq) > -0 0d.s1 98us : trace_hardirqs_on (do_softirq) > > your time starts now. awk '/^#/ { start=1; next } { if (!start) next; .... }'