Messages in this thread | | | Date | Tue, 24 Jul 2012 17:07:20 +0800 | From | Fengguang Wu <> | Subject | Testing tracer wakeup_rt: .. no entries found ..FAILED! |
| |
On Tue, Jul 24, 2012 at 05:03:30PM +0800, Fengguang Wu wrote: > Hi Steven, > > This looks like some old bug, so I directly report to you w/o trying > to bisect it. It only happens on the attached i386 randconfig and > happens in about half of the kvm boots. > > [ 1.380369] Testing tracer irqsoff: [ 1.524917] > [ 1.525217] =============================== > [ 1.525868] [ INFO: suspicious RCU usage. ] > [ 1.526556] 3.5.0+ #1289 Not tainted > [ 1.527124] ------------------------------- > [ 1.527799] /c/kernel-tests/src/linux/include/linux/rcupdate.h:730 rcu_read_lock() used illegally while idle! > [ 1.529375] > [ 1.529375] other info that might help us debug this: > [ 1.529375] > [ 1.530667] > [ 1.530667] RCU used illegally from idle CPU! > [ 1.530667] rcu_scheduler_active = 1, debug_locks = 1 > [ 1.532383] RCU used illegally from extended quiescent state! > [ 1.533297] 2 locks held by swapper/0/0: > > [ 1.533924] #0: [ 1.534271] (max_trace_lock){......}, at: [<410e9d67>] check_critical_timing+0x67/0x1b0 > [ 1.534883] #1: (rcu_read_lock){.+.+..}, at: [<410e1ea0>] __update_max_tr+0x0/0x200 > > [ 1.534883] stack backtrace: > [ 1.534883] Pid: 0, comm: swapper/0 Not tainted 3.5.0+ #1289 > [ 1.534883] Call Trace: > [ 1.534883] [<41093a76>] lockdep_rcu_suspicious+0xc6/0x100 > [ 1.534883] [<410e2071>] __update_max_tr+0x1d1/0x200 > [ 1.534883] [<410e1ea0>] ? tracing_record_cmdline+0x130/0x130 > [ 1.534883] [<410e30f5>] update_max_tr_single+0x1f5/0x240 > [ 1.534883] [<410e294c>] ? __trace_stack+0x1c/0x30 > [ 1.534883] [<410e9e96>] check_critical_timing+0x196/0x1b0 > [ 1.534883] [<4100b858>] ? default_idle+0x468/0x4c0 > [ 1.534883] [<4100b858>] ? default_idle+0x468/0x4c0 > [ 1.534883] [<410ea47f>] time_hardirqs_on+0xff/0x110 > [ 1.534883] [<410961eb>] ? trace_hardirqs_on+0xb/0x10 > [ 1.534883] [<4100b858>] ? default_idle+0x468/0x4c0 > [ 1.534883] [<41096031>] trace_hardirqs_on_caller+0x11/0x1c0 > [ 1.534883] [<410961eb>] trace_hardirqs_on+0xb/0x10 > [ 1.534883] [<4100b858>] default_idle+0x468/0x4c0 > [ 1.534883] [<4100c4e6>] cpu_idle+0x186/0x190 > [ 1.534883] [<412953c3>] rest_init+0x127/0x134 > [ 1.534883] [<4129529c>] ? __read_lock_failed+0x14/0x14 > [ 1.534883] [<4141b9e0>] start_kernel+0x36f/0x375 > [ 1.534883] [<4141b4a6>] ? repair_env_string+0x51/0x51 > [ 1.534883] [<4141b2d4>] i386_start_kernel+0x8a/0x8f > [ 1.534883] > [ 1.534883] ===============================
And this warning shows up in one of the dozens of boots, for the same kconfig.
[ 2.320434] Testing tracer wakeup: PASSED [ 2.840288] Testing tracer wakeup_rt: .. no entries found ..FAILED! [ 3.280861] ------------[ cut here ]------------ [ 3.281967] WARNING: at /c/kernel-tests/src/linux/kernel/trace/trace.c:834 register_tracer+0x1b0/0x270() [ 3.284162] Hardware name: Bochs [ 3.284933] Modules linked in: [ 3.285695] Pid: 1, comm: swapper/0 Not tainted 3.5.0+ #1371 [ 3.287032] Call Trace: [ 3.287626] [<41035c32>] warn_slowpath_common+0x72/0xa0 [ 3.288938] [<410e7dd0>] ? register_tracer+0x1b0/0x270 [ 3.290280] [<410e7dd0>] ? register_tracer+0x1b0/0x270 [ 3.291516] [<41035c82>] warn_slowpath_null+0x22/0x30 [ 3.292723] [<410e7dd0>] register_tracer+0x1b0/0x270 [ 3.293921] [<41434c7a>] ? init_irqsoff_tracer+0x11/0x11 [ 3.295269] [<41434c95>] init_wakeup_tracer+0x1b/0x1d [ 3.296464] [<41001112>] do_one_initcall+0x112/0x160 [ 3.297639] [<4141fadd>] kernel_init+0xf7/0x18e [ 3.298724] [<4141f455>] ? do_early_param+0x7a/0x7a [ 3.299879] [<4141f9e6>] ? start_kernel+0x375/0x375 [ 3.301093] [<412b15c2>] kernel_thread_helper+0x6/0x10 [ 3.302352] ---[ end trace 57f7151f6a5def05 ]---
Thanks, Fengguang
| |