From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (qmail 84530 invoked by alias); 21 Sep 2017 20:45:49 -0000 Mailing-List: contact systemtap-help@sourceware.org; run by ezmlm Precedence: bulk List-Id: List-Subscribe: List-Post: List-Help: , Sender: systemtap-owner@sourceware.org Received: (qmail 76806 invoked by uid 48); 21 Sep 2017 20:45:44 -0000 From: "dsmith at redhat dot com" To: systemtap@sourceware.org Subject: [Bug runtime/22182] New: On rawhide, we're getting lots of 'suspicious RCU usage' kernel warnings Date: Thu, 21 Sep 2017 20:45:00 -0000 X-Bugzilla-Reason: AssignedTo X-Bugzilla-Type: new X-Bugzilla-Watch-Reason: None X-Bugzilla-Product: systemtap X-Bugzilla-Component: runtime X-Bugzilla-Version: unspecified X-Bugzilla-Keywords: X-Bugzilla-Severity: normal X-Bugzilla-Who: dsmith at redhat dot com X-Bugzilla-Status: NEW X-Bugzilla-Resolution: X-Bugzilla-Priority: P2 X-Bugzilla-Assigned-To: systemtap at sourceware dot org X-Bugzilla-Target-Milestone: --- X-Bugzilla-Flags: X-Bugzilla-Changed-Fields: bug_id short_desc product version bug_status bug_severity priority component assigned_to reporter target_milestone Message-ID: Content-Type: text/plain; charset="UTF-8" Content-Transfer-Encoding: quoted-printable X-Bugzilla-URL: http://sourceware.org/bugzilla/ Auto-Submitted: auto-generated MIME-Version: 1.0 X-SW-Source: 2017-q3/txt/msg00229.txt.bz2 https://sourceware.org/bugzilla/show_bug.cgi?id=3D22182 Bug ID: 22182 Summary: On rawhide, we're getting lots of 'suspicious RCU usage' kernel warnings Product: systemtap Version: unspecified Status: NEW Severity: normal Priority: P2 Component: runtime Assignee: systemtap at sourceware dot org Reporter: dsmith at redhat dot com Target Milestone: --- On rawhide (4.14.0-0.rc0.git6.1.fc28.x86_64+debug), we're getting lots of "suspicious RCU usage" kernel warnings when running the testsuite. Here is = the start of the warnings: =3D=3D=3D=3D [ 5970.181257] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 5970.181669] WARNING: suspicious RCU usage [ 5970.182094] 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 Tainted: G = OE=20 [ 5970.182831] ----------------------------- [ 5970.183246] /usr/local/share/systemtap/runtime/linux/runtime_context.h:76 suspicious rcu_dereference_check() usage! [ 5970.184300]=20 [ 5970.184300] other info that might help us debug this: [ 5970.184300]=20 [ 5970.185116]=20 [ 5970.185116] RCU used illegally from idle CPU! [ 5970.185116] rcu_scheduler_active =3D 2, debug_locks =3D 1 [ 5970.186250] RCU used illegally from extended quiescent state! [ 5970.186835] no locks held by swapper/0/0. [ 5970.187255]=20 [ 5970.187255] stack backtrace: [ 5970.187709] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G OE=20=20 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 [ 5970.188704] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [ 5970.189297] Call Trace: [ 5970.189559] [ 5970.189782]=20 [ 5970.189969] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 5970.190377] WARNING: suspicious RCU usage [ 5970.190787] 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 Tainted: G = OE=20 [ 5970.191522] ----------------------------- [ 5970.191937] ./include/linux/rcupdate.h:632 rcu_read_lock() used illegally while idle! [ 5970.192720]=20 [ 5970.192720] other info that might help us debug this: [ 5970.192720]=20 [ 5970.193537]=20 [ 5970.193537] RCU used illegally from idle CPU! [ 5970.193537] rcu_scheduler_active =3D 2, debug_locks =3D 1 [ 5970.194631] RCU used illegally from extended quiescent state! [ 5970.195219] 1 lock held by swapper/0/0: [ 5970.195613] #0: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.196522]=20 [ 5970.196522] stack backtrace: [ 5970.197091] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G OE=20=20 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 [ 5970.198088] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [ 5970.198671] Call Trace: [ 5970.198942] [ 5970.199174]=20 [ 5970.199351] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 5970.199762] WARNING: suspicious RCU usage [ 5970.200180] 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 Tainted: G = OE=20 [ 5970.200903] ----------------------------- [ 5970.201322] ./include/linux/rcupdate.h:683 rcu_read_unlock() used illega= lly while idle! [ 5970.202128]=20 [ 5970.202128] other info that might help us debug this: [ 5970.202128]=20 [ 5970.202943]=20 [ 5970.202943] RCU used illegally from idle CPU! [ 5970.202943] rcu_scheduler_active =3D 2, debug_locks =3D 1 [ 5970.204043] RCU used illegally from extended quiescent state! [ 5970.204629] 2 locks held by swapper/0/0: [ 5970.205045] #0: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.205954] #1: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.206847]=20 [ 5970.206847] stack backtrace: [ 5970.207304] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G OE=20=20 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 [ 5970.208333] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [ 5970.208973] Call Trace: [ 5970.209234] [ 5970.209452]=20 [ 5970.209631] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 5970.210052] WARNING: suspicious RCU usage [ 5970.210474] 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 Tainted: G = OE=20 [ 5970.211217] ----------------------------- [ 5970.211639] ./include/linux/rcupdate.h:632 rcu_read_lock() used illegally while idle! [ 5970.212498]=20 [ 5970.212498] other info that might help us debug this: [ 5970.212498]=20 [ 5970.213324]=20 [ 5970.213324] RCU used illegally from idle CPU! [ 5970.213324] rcu_scheduler_active =3D 2, debug_locks =3D 1 [ 5970.214467] RCU used illegally from extended quiescent state! [ 5970.215061] 3 locks held by swapper/0/0: [ 5970.215464] #0: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.216380] #1: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.217332] #2: (rcu_read_lock){....}, at: [] is_bpf_text_address+0x5/0xf0 [ 5970.218180]=20 [ 5970.218180] stack backtrace: [ 5970.218639] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G OE=20=20 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 [ 5970.219614] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [ 5970.220203] Call Trace: [ 5970.220458] [ 5970.220678]=20 [ 5970.220858] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 5970.221275] WARNING: suspicious RCU usage [ 5970.221680] 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 Tainted: G = OE=20 [ 5970.222417] ----------------------------- [ 5970.222829] ./include/linux/rcupdate.h:683 rcu_read_unlock() used illega= lly while idle! [ 5970.223646]=20 [ 5970.223646] other info that might help us debug this: [ 5970.223646]=20 [ 5970.224464]=20 [ 5970.224464] RCU used illegally from idle CPU! [ 5970.224464] rcu_scheduler_active =3D 2, debug_locks =3D 1 [ 5970.225557] RCU used illegally from extended quiescent state! [ 5970.226054] 4 locks held by swapper/0/0: [ 5970.226510] #0: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.227670] #1: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.228694] #2: (rcu_read_lock){....}, at: [] is_bpf_text_address+0x5/0xf0 [ 5970.229587] #3: (rcu_read_lock){....}, at: [] is_bpf_text_address+0x5/0xf0 [ 5970.230607]=20 [ 5970.230607] stack backtrace: [ 5970.231257] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G OE=20=20 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 [ 5970.232535] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [ 5970.233121] Call Trace: [ 5970.233379] [ 5970.233617] dump_stack+0x8e/0xd6 [ 5970.233976] lockdep_rcu_suspicious+0xc5/0x100 [ 5970.234436] is_bpf_text_address+0xe0/0xf0 [ 5970.234863] __kernel_text_address+0x8b/0xb0 [ 5970.235312] show_trace_log_lvl+0x195/0x410 [ 5970.235755] ? dump_stack+0x8e/0xd6 [ 5970.236128] show_stack+0x34/0x50 [ 5970.236474] dump_stack+0x8e/0xd6 [ 5970.236825] lockdep_rcu_suspicious+0xc5/0x100 [ 5970.237286] is_bpf_text_address+0xb6/0xf0 [ 5970.237711] __kernel_text_address+0x8b/0xb0 [ 5970.238158] show_trace_log_lvl+0x195/0x410 [ 5970.238599] ? dump_stack+0x8e/0xd6 [ 5970.238968] show_stack+0x34/0x50 [ 5970.239312] dump_stack+0x8e/0xd6 [ 5970.239657] lockdep_rcu_suspicious+0xc5/0x100 [ 5970.240125] __is_insn_slot_addr+0x118/0x120 [ 5970.240568] __kernel_text_address+0x6c/0xb0 [ 5970.241011] show_trace_log_lvl+0x195/0x410 [ 5970.241453] ? dump_stack+0x8e/0xd6 [ 5970.241823] show_stack+0x34/0x50 [ 5970.242174] dump_stack+0x8e/0xd6 [ 5970.242520] lockdep_rcu_suspicious+0xc5/0x100 [ 5970.242989] __is_insn_slot_addr+0xeb/0x120 [ 5970.243423] __kernel_text_address+0x6c/0xb0 [ 5970.243866] show_trace_log_lvl+0x195/0x410 [ 5970.244313] ? dump_stack+0x8e/0xd6 [ 5970.244684]=20 [ 5970.244847] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 5970.245268] WARNING: suspicious RCU usage [ 5970.245681] 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 Tainted: G = OE=20 [ 5970.246417] ----------------------------- [ 5970.246829] arch/x86/kernel/traps.c:293 entry code didn't wake RCU! [ 5970.247468]=20 [ 5970.247468] other info that might help us debug this: [ 5970.247468]=20 [ 5970.248265]=20 [ 5970.248265] RCU used illegally from idle CPU! [ 5970.248265] rcu_scheduler_active =3D 2, debug_locks =3D 1 [ 5970.249366] RCU used illegally from extended quiescent state! [ 5970.249961] 4 locks held by swapper/0/0: [ 5970.250368] #0: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.251274] #1: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.252171] #2: (rcu_read_lock){....}, at: [] is_bpf_text_address+0x5/0xf0 [ 5970.253063] #3: (rcu_read_lock){....}, at: [] is_bpf_text_address+0x5/0xf0 [ 5970.253947]=20 [ 5970.253947] stack backtrace: [ 5970.254397] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G OE=20=20 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 [ 5970.255387] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [ 5970.255973] Call Trace: [ 5970.256234] [ 5970.256458] dump_stack+0x8e/0xd6 [ 5970.256809] lockdep_rcu_suspicious+0xc5/0x100 [ 5970.257276] do_error_trap+0x145/0x160 [ 5970.257667] ? kvm_sched_clock_read+0x25/0x40 [ 5970.258126] ? console_unlock+0x2e5/0x560 [ 5970.258546] ? trace_hardirqs_off_thunk+0x1a/0x1c [ 5970.259039]=20 [ 5970.259204] =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D [ 5970.259619] WARNING: suspicious RCU usage [ 5970.260037] 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 Tainted: G = OE=20 [ 5970.260769] ----------------------------- [ 5970.261193] kernel/notifier.c:548 notify_die called but RCU thinks we're quiescent! [ 5970.262087]=20 [ 5970.262087] other info that might help us debug this: [ 5970.262087]=20 [ 5970.262894]=20 [ 5970.262894] RCU used illegally from idle CPU! [ 5970.262894] rcu_scheduler_active =3D 2, debug_locks =3D 1 [ 5970.263984] RCU used illegally from extended quiescent state! [ 5970.264568] 4 locks held by swapper/0/0: [ 5970.264975] #0: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.265861] #1: (rcu_read_lock){....}, at: [] __is_insn_slot_addr+0x5/0x120 [ 5970.266750] #2: (rcu_read_lock){....}, at: [] is_bpf_text_address+0x5/0xf0 [ 5970.267632] #3: (rcu_read_lock){....}, at: [] is_bpf_text_address+0x5/0xf0 [ 5970.268521]=20 [ 5970.268521] stack backtrace: [ 5970.268976] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G OE=20=20 4.14.0-0.rc0.git6.1.fc28.x86_64+debug #1 [ 5970.269962] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2011 [ 5970.270546] Call Trace: [ 5970.270800] [ 5970.271031] dump_stack+0x8e/0xd6 [ 5970.271383] lockdep_rcu_suspicious+0xc5/0x100 [ 5970.271840] notify_die+0x90/0xa0 [ 5970.272195] do_error_trap+0x66/0x160 [ 5970.272575] ? kvm_sched_clock_read+0x25/0x40 [ 5970.273029] ? console_unlock+0x2e5/0x560 [ 5970.273448] ? trace_hardirqs_off_thunk+0x1a/0x1c =3D=3D=3D=3D --=20 You are receiving this mail because: You are the assignee for the bug.