From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S263943AbUHMLyA (ORCPT ); Fri, 13 Aug 2004 07:54:00 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S264373AbUHMLyA (ORCPT ); Fri, 13 Aug 2004 07:54:00 -0400 Received: from pop.gmx.de ([213.165.64.20]:22707 "HELO mail.gmx.net") by vger.kernel.org with SMTP id S263943AbUHMLxz (ORCPT ); Fri, 13 Aug 2004 07:53:55 -0400 X-Authenticated: #4399952 Date: Fri, 13 Aug 2004 14:03:21 +0200 From: Florian Schmidt To: Ingo Molnar Cc: linux-kernel@vger.kernel.org, Lee Revell , Felipe Alfaro Solana Subject: Re: [patch] Latency Tracer, voluntary-preempt-2.6.8-rc4-O6 Message-Id: <20040813140321.78da570d@mango.fruits.de> In-Reply-To: <20040813105406.GJ8135@elte.hu> References: <20040726083537.GA24948@elte.hu> <1090832436.6936.105.camel@mindpipe> <20040726124059.GA14005@elte.hu> <20040726204720.GA26561@elte.hu> <20040729222657.GA10449@elte.hu> <20040801193043.GA20277@elte.hu> <20040809104649.GA13299@elte.hu> <20040810132654.GA28915@elte.hu> <20040812235116.GA27838@elte.hu> <20040813124249.13066d94@mango.fruits.de> <20040813105406.GJ8135@elte.hu> X-Mailer: Sylpheed-Claws 0.9.12 (GTK+ 1.2.10; i386-pc-linux-gnu) Mime-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 7bit Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org On Fri, 13 Aug 2004 12:54:06 +0200 Ingo Molnar wrote: > > (ksoftirqd/0/2): 307 us critical section violates 250 us threshold. > > => started at: <___do_softirq+0x20/0x90> > > => ended at: > > this is too opaque - could you try -O7, enable tracing and save a > /proc/latency_trace instance of such a latency? It looks like some > sort of softirq latency - perhaps one particular driver's timer fn > causes it- we'll be able to tell more from the trace. Hi, this looks like one of them: mango:~# cat /proc/latency_trace preemption latency trace v1.0 ----------------------------- latency: 308 us, entries: 12 (12) process: ksoftirqd/0/2, uid: 0 nice: -10, policy: 0, rt_priority: 0 =======> 0.000ms (+0.000ms): run_timer_softirq (___do_softirq) 0.000ms (+0.000ms): sis900_timer (run_timer_softirq) 0.001ms (+0.000ms): mdio_read (sis900_timer) 0.002ms (+0.000ms): mdio_reset (mdio_read) 0.071ms (+0.069ms): mdio_idle (mdio_read) 0.151ms (+0.079ms): mdio_read (sis900_timer) 0.151ms (+0.000ms): mdio_reset (mdio_read) 0.220ms (+0.069ms): mdio_idle (mdio_read) 0.300ms (+0.079ms): __mod_timer (sis900_timer) 0.300ms (+0.000ms): internal_add_timer (__mod_timer) 0.300ms (+0.000ms): cond_resched_softirq (run_timer_softirq) 0.301ms (+0.000ms): check_preempt_timing (touch_preempt_timing) Flo -- Palimm Palimm! http://affenbande.org/~tapas/