/mnt/ftrace # ./ftracetest test.d/event/toplevel-enable.tc
=== Ftrace unit tests ===
[1] event tracing - enable/disable with top level files[ 282.060356]
[ 282.060593] WARNING: can't dereference registers at 00000000f3c7f62b for ip interrupt_entry+0xc4/0xe0
[ 282.063200] =============================
[ 282.064082] WARNING: suspicious RCU usage
[ 282.064963] 4.18.0-rc6+ #15 Tainted: G W
[ 282.066048] -----------------------------
[ 282.066923] /home/mhiramat/ksrc/linux/kernel/trace/trace_events.c:242 suspicious rcu_dereference_check() usage!
[ 282.068974]
[ 282.068974] other info that might help us debug this:
[ 282.068974]
[ 282.070770]
[ 282.070770] RCU used illegally from idle CPU!
[ 282.070770] rcu_scheduler_active = 2, debug_locks = 1
[ 282.072938] RCU used illegally from extended quiescent state!
[ 282.074183] no locks held by swapper/0/0.
[ 282.075071]
[ 282.075071] stack backtrace:
[ 282.076121] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 4.18.0-rc6+ #15
[ 282.077782] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1.fc26 04/01/2014
[ 282.079604] Call Trace:
[ 282.080212]
[ 282.080755] dump_stack+0x85/0xcb
[ 282.081523] trace_event_ignore_this_pid+0x66/0x70
[ 282.082541] trace_event_raw_event_preemptirq_template+0xa2/0xb0
[ 282.083774] ? interrupt_entry+0xc4/0xe0
[ 282.084665] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 282.085669] trace_hardirqs_off_caller+0x90/0xd0
[ 282.086597] trace_hardirqs_off_thunk+0x1a/0x1c
[ 282.087433] ? call_function_interrupt+0xa/0x20
[ 282.088201] interrupt_entry+0xc4/0xe0
[ 282.088848] ? call_function_interrupt+0xa/0x20
[ 282.089579]
[ 282.090029] ? native_safe_halt+0x2/0x10
[ 282.090695] ? default_idle+0x1f/0x160
[ 282.091330] ? default_idle_call+0x24/0x40
[ 282.091997] ? do_idle+0x210/0x250
[ 282.092658] ? cpu_startup_entry+0x6f/0x80
[ 282.093338] ? start_kernel+0x49d/0x4bd
[ 282.093987] ? secondary_startup_64+0xa5/0xb0
[ 282.094748]
[ 282.094749] =============================
[ 282.094751] WARNING: suspicious RCU usage
[ 282.094752] 4.18.0-rc6+ #15 Tainted: G W
[ 282.094753] -----------------------------
[ 282.094754] /home/mhiramat/ksrc/linux/include/linux/rcupdate.h:633 rcu_read_lock() used illegally while idle!
[ 282.094754]
[ 282.094755] other info that might help us debug this:
[ 282.094756]
[ 282.094757]
[ 282.094760] RCU used illegally from idle CPU!
[ 282.094761] rcu_scheduler_active = 2, debug_locks = 1
[ 282.094763] RCU used illegally from extended quiescent state!
[ 282.094764] 4 locks held by swapper/0/0:
[ 282.094765] #0: 00000000129c45e9 (console_lock){+.+.}, at: vprintk_emit+0x23c/0x470
[ 282.094771] #1: 0000000098f3ac4c (console_owner){-...}, at: console_unlock+0x135/0x600
[ 282.094778] #2: 00000000168da4c3 (printing_lock){....}, at: vt_console_print+0x78/0x420
[ 282.094784] #3: 0000000033907247 (rcu_read_lock){....}, at: atomic_notifier_call_chain+0x5/0x100
[ 282.094791]
[ 282.094792] stack backtrace:
[ 282.094792] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 4.18.0-rc6+ #15
[ 282.094793] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1.fc26 04/01/2014
[ 282.094794] Call Trace:
[ 282.094795]
[ 282.094795] dump_stack+0x85/0xcb
[ 282.094796] atomic_notifier_call_chain+0xcc/0x100
[ 282.094797] vt_console_print+0x173/0x420
[ 282.094798] console_unlock+0x4b1/0x600
[ 282.094799] vprintk_emit+0x249/0x470
[ 282.094799] printk+0x52/0x6e
[ 282.094800] lockdep_rcu_suspicious+0x1b/0xf0
[ 282.094801] ? interrupt_entry+0xc4/0xe0
[ 282.094801] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 282.094802] trace_event_ignore_this_pid+0x66/0x70
[ 282.094803] trace_event_raw_event_preemptirq_template+0xa2/0xb0
[ 282.094804] ? interrupt_entry+0xc4/0xe0
[ 282.094804] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 282.094805] trace_hardirqs_off_caller+0x90/0xd0
[ 282.094806] trace_hardirqs_off_thunk+0x1a/0x1c
[ 282.094806] ? call_function_interrupt+0xa/0x20
[ 282.094807] interrupt_entry+0xc4/0xe0
[ 282.094808] ? call_function_interrupt+0xa/0x20
[ 282.094809]
[ 282.094809] ? native_safe_halt+0x2/0x10
[ 282.094810] ? default_idle+0x1f/0x160
[ 282.094811] ? default_idle_call+0x24/0x40
[ 282.094811] ? do_idle+0x210/0x250
[ 282.094812] ? cpu_startup_entry+0x6f/0x80
[ 282.094813] ? start_kernel+0x49d/0x4bd
[ 282.094814] ? secondary_startup_64+0xa5/0xb0
[ 282.094814]
[ 282.094815] =============================
[ 282.094816] WARNING: suspicious RCU usage
[ 282.094816] 4.18.0-rc6+ #15 Tainted: G W
[ 282.094817] -----------------------------
[ 282.094818] /home/mhiramat/ksrc/linux/include/linux/rcupdate.h:682 rcu_read_unlock() used illegally while idle!
[ 282.094819]
[ 282.094819] other info that might help us debug this:
[ 282.094820]
[ 282.094821]
[ 282.094821] RCU used illegally from idle CPU!
[ 282.094822] rcu_scheduler_active = 2, debug_locks = 1
[ 282.094823] RCU used illegally from extended quiescent state!
[ 282.094823] 4 locks held by swapper/0/0:
[ 282.094824] #0: 00000000129c45e9 (console_lock){+.+.}, at: vprintk_emit+0x23c/0x470
[ 282.094828] #1: 0000000098f3ac4c (console_owner){-...}, at: console_unlock+0x135/0x600
[ 282.094831] #2: 00000000168da4c3 (printing_lock){....}, at: vt_console_print+0x78/0x420
[ 282.094836] #3: 0000000033907247 (rcu_read_lock){....}, at: atomic_notifier_call_chain+0x5/0x100
[ 282.094839]
[ 282.094840] stack backtrace:
[ 282.094841] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 4.18.0-rc6+ #15
[ 282.094842] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1.fc26 04/01/2014
[ 282.094842] Call Trace:
[ 282.094843]
[ 282.094844] dump_stack+0x85/0xcb
[ 282.094845] atomic_notifier_call_chain+0xf6/0x100
[ 282.094845] vt_console_print+0x173/0x420
[ 282.094846] console_unlock+0x4b1/0x600
[ 282.094847] vprintk_emit+0x249/0x470
[ 282.094847] printk+0x52/0x6e
[ 282.094848] lockdep_rcu_suspicious+0x1b/0xf0
[ 282.094849] ? interrupt_entry+0xc4/0xe0
[ 282.094850] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 282.094850] trace_event_ignore_this_pid+0x66/0x70
[ 282.094851] trace_event_raw_event_preemptirq_template+0xa2/0xb0
[ 282.094852] ? interrupt_entry+0xc4/0xe0
[ 282.094853] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 282.094853] trace_hardirqs_off_caller+0x90/0xd0
[ 282.094854] trace_hardirqs_off_thunk+0x1a/0x1c
[ 282.094855] ? call_function_interrupt+0xa/0x20
[ 282.094855] interrupt_entry+0xc4/0xe0
[ 282.094856] ? call_function_interrupt+0xa/0x20
[ 282.094857]
[ 282.094858] ? native_safe_halt+0x2/0x10
[ 282.094858] ? default_idle+0x1f/0x160
[ 282.094859] ? default_idle_call+0x24/0x40
[ 282.094860] ? do_idle+0x210/0x250
[ 282.094860] ? cpu_startup_entry+0x6f/0x80
[ 282.094861] ? start_kernel+0x49d/0x4bd
[ 282.094862] ? secondary_startup_64+0xa5/0xb0
[PASS]
# of passed: 1
# of failed: 0
# of unresolved: 0
# of untested: 0
# of unsupported: 0
# of xfailed: 0
# of undefined(test bug): 0