[<prev] [next>] [day] [month] [year] [list]
Message-ID: <20200304001407.GX6548@shao2-debian>
Date: Wed, 4 Mar 2020 08:14:07 +0800
From: kernel test robot <lkp@...el.com>
To: Madhuparna Bhowmik <madhuparnabhowmik10@...il.com>
Cc: 0day robot <lkp@...el.com>, Amol Grover <frextrite@...il.com>,
LKML <linux-kernel@...r.kernel.org>, lkp@...ts.01.org
Subject: 06ec0a154a: WARNING:suspicious_RCU_usage
FYI, we noticed the following commit (built with gcc-7):
commit: 06ec0a154aae414939ba8980e89d1c1c1b0e6301 ("Default enable RCU list lockdep debugging with PROVE_RCU")
https://github.com/0day-ci/linux/commits/madhuparnabhowmik10-gmail-com/Default-enable-RCU-list-lockdep-debugging-with-PROVE_RCU/20200229-061537
in testcase: boot
on test machine: qemu-system-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 8G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+---------------------------------------------------------------+------------+------------+
| | 8a0161f673 | 06ec0a154a |
+---------------------------------------------------------------+------------+------------+
| boot_successes | 8 | 0 |
| boot_failures | 0 | 12 |
| WARNING:suspicious_RCU_usage | 0 | 12 |
| net/ipv6/ip6mr.c:#RCU-list_traversed_in_non-reader_section | 0 | 12 |
| net/caif/caif_dev.c:#RCU-list_traversed_in_non-reader_section | 0 | 12 |
| kernel/kprobes.c:#RCU-list_traversed_in_non-reader_section | 0 | 10 |
| BUG:kernel_hang_in_test_stage | 0 | 2 |
+---------------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp@...el.com>
[ 10.754253] WARNING: suspicious RCU usage
[ 10.754958] 5.6.0-rc1-00177-g06ec0a154aae4 #1 Not tainted
[ 10.755812] -----------------------------
[ 10.756492] net/ipv6/ip6mr.c:123 RCU-list traversed in non-reader section!!
[ 10.757959]
[ 10.757959] other info that might help us debug this:
[ 10.757959]
[ 10.759444]
[ 10.759444] rcu_scheduler_active = 2, debug_locks = 1
[ 10.760722] 1 lock held by swapper/0/1:
[ 10.761467] #0: 541e76b8 (pernet_ops_rwsem){+.+.}, at: register_pernet_subsys+0x10/0x30
[ 10.762876]
[ 10.762876] stack backtrace:
[ 10.763628] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 5.6.0-rc1-00177-g06ec0a154aae4 #1
[ 10.764910] Call Trace:
[ 10.765349] dump_stack+0x6e/0x96
[ 10.765968] lockdep_rcu_suspicious+0xd2/0xe0
[ 10.766789] ip6mr_get_table+0x3f/0x80
[ 10.767522] ip6mr_new_table+0xe/0x40
[ 10.768212] ip6mr_net_init+0x6d/0x125
[ 10.768915] ? ipv6_sysctl_net_init+0x17a/0x17a
[ 10.769785] ops_init+0x10f/0x140
[ 10.770582] ? unix_sysctl_register+0x6c/0x6c
[ 10.770692] ? unix_sysctl_register+0x6c/0x6c
[ 10.770692] register_pernet_operations+0x87/0xb0
[ 10.770692] ? unix_sysctl_register+0x6c/0x6c
[ 10.770692] register_pernet_subsys+0x1c/0x30
[ 10.770692] ip6_mr_init+0x3a/0xd6
[ 10.770692] inet6_init+0xec/0x33d
[ 10.770692] do_one_initcall+0xd1/0x250
[ 10.770692] ? rcu_read_lock_sched_held+0x2f/0x60
[ 10.770692] ? trace_initcall_level+0x78/0xa7
[ 10.770692] ? kernel_init_freeable+0x116/0x192
[ 10.770692] kernel_init_freeable+0x12e/0x192
[ 10.770692] ? rest_init+0x1f0/0x1f0
[ 10.770692] kernel_init+0x8/0xf0
[ 10.770692] ret_from_fork+0x19/0x24
[ 10.842703] Segment Routing with IPv6
[ 10.843452] NET: Registered protocol family 5
[ 10.844922] NET: Unregistered protocol family 5
[ 10.845951] NET: Registered protocol family 33
[ 10.846726] Key type rxrpc registered
[ 10.847347] Key type rxrpc_s registered
[ 10.850775] mpoa:atm_mpoa_init: mpc.c: initialized
[ 10.851743] NET4: DECnet for Linux: V.2.5.68s (C) 1995-2003 Linux DECnet Project Team
[ 10.853386] DECnet: Routing cache hash table of 1024 buckets, 36Kbytes
[ 10.854616] NET: Registered protocol family 12
[ 10.855390] NET: Registered protocol family 35
[ 10.856325] 8021q: 802.1Q VLAN Support v1.8
[ 10.862180] DCCP: Activated CCID 2 (TCP-like)
[ 10.863047] DCCP: Activated CCID 3 (TCP-Friendly Rate Control)
[ 10.864242] tipc: Activated (version 2.0.0)
[ 10.865138] NET: Registered protocol family 30
[ 10.866283] tipc: Started in single node mode
[ 10.867142] 9pnet: Installing 9P2000 support
[ 10.868133]
[ 10.868467] =============================
[ 10.869082] WARNING: suspicious RCU usage
[ 10.869817] 5.6.0-rc1-00177-g06ec0a154aae4 #1 Not tainted
[ 10.870804] -----------------------------
[ 10.871557] net/caif/caif_dev.c:115 RCU-list traversed in non-reader section!!
[ 10.873127]
[ 10.873127] other info that might help us debug this:
[ 10.873127]
[ 10.874525]
[ 10.874525] rcu_scheduler_active = 2, debug_locks = 1
[ 10.875604] 2 locks held by swapper/0/1:
[ 10.876318] #0: 541e76b8 (pernet_ops_rwsem){+.+.}, at: register_netdevice_notifier+0x15/0x100
[ 10.877790] #1: 541e8d54 (rtnl_mutex){+.+.}, at: rtnl_lock+0xf/0x20
[ 10.878998]
[ 10.878998] stack backtrace:
[ 10.879816] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 5.6.0-rc1-00177-g06ec0a154aae4 #1
[ 10.880750] Call Trace:
[ 10.880750] dump_stack+0x6e/0x96
[ 10.880750] lockdep_rcu_suspicious+0xd2/0xe0
[ 10.880750] caif_get+0x13c/0x180
[ 10.880750] caif_device_notify+0x20d/0x6f0
[ 10.880750] call_netdevice_register_net_notifiers+0x3e/0xc0
[ 10.880750] register_netdevice_notifier+0x59/0x100
[ 10.880750] ? p9_virtio_init+0x41/0x41
[ 10.880750] ? p9_virtio_init+0x41/0x41
[ 10.880750] caif_device_init+0x1e/0x2d
[ 10.880750] do_one_initcall+0xd1/0x250
[ 10.880750] ? rcu_read_lock_sched_held+0x2f/0x60
[ 10.880750] ? trace_initcall_level+0x78/0xa7
[ 10.880750] ? kernel_init_freeable+0x116/0x192
[ 10.880750] kernel_init_freeable+0x12e/0x192
[ 10.880750] ? rest_init+0x1f0/0x1f0
[ 10.880750] kernel_init+0x8/0xf0
[ 10.880750] ret_from_fork+0x19/0x24
[ 10.893952] NET: Registered protocol family 37
[ 10.894753] openvswitch: Open vSwitch switching datapath
[ 10.896071] mpls_gso: MPLS GSO support
[ 10.896758] start plist test
[ 10.899263] end plist test
[ 10.900204] IPI shorthand broadcast: enabled
[ 10.901155] ... APIC ID: 00000000 (0)
[ 10.901978] ... APIC VERSION: 00050014
[ 10.902656] 0000000000000000000000000000000000000000000000000000000000000000
[ 10.903905] 0000000000000000000000000000000000000000000000000000000000000000
[ 10.905266] 0000000000000000000000000000000000000000000000000000000000000000
[ 10.906617] number of MP IRQ sources: 15.
[ 10.907266] number of IO-APIC #0 registers: 24.
[ 10.908022] testing the IO APIC.......................
[ 10.908902] IO APIC #0......
[ 10.909376] .... register #00: 00000000
[ 10.910092] ....... : physical APIC id: 00
[ 10.910945] ....... : Delivery Type: 0
[ 10.911726] ....... : LTS : 0
[ 10.912510] .... register #01: 00170011
[ 10.913243] ....... : max redirection entries: 17
[ 10.914134] ....... : PRQ implemented: 0
[ 10.914858] ....... : IO APIC version: 11
[ 10.915577] .... register #02: 00000000
[ 10.916231] ....... : arbitration: 00
[ 10.916951] .... IRQ redirection table:
[ 10.917659] IOAPIC 0:
[ 10.918104] pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.919476] pin01, enabled , edge , high, V(22), IRR(0), S(0), logical , D(02), M(0)
[ 10.920921] pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(0)
[ 10.922276] pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.923527] pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.924912] pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.926370] pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.927905] pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.929379] pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.930915] pin09, enabled , level, high, V(21), IRR(0), S(0), logical , D(02), M(0)
[ 10.932457] pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.933924] pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.935210] pin0c, enabled , edge , high, V(21), IRR(0), S(0), logical , D(01), M(0)
[ 10.936524] pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.937869] pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.939215] pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.940653] pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.942155] pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.943615] pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.945095] pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.946458] pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.947867] pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.949236] pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.950605] pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[ 10.951903] IRQ to pin mappings:
[ 10.952484] IRQ0 -> 0:2
[ 10.952933] IRQ1 -> 0:1
[ 10.953381] IRQ3 -> 0:3
[ 10.953822] IRQ4 -> 0:4
[ 10.954228] IRQ5 -> 0:5
[ 10.954645] IRQ6 -> 0:6
[ 10.955081] IRQ7 -> 0:7
[ 10.955525] IRQ8 -> 0:8
[ 10.955960] IRQ9 -> 0:9
[ 10.956387] IRQ10 -> 0:10
[ 10.956822] IRQ11 -> 0:11
[ 10.957273] IRQ12 -> 0:12
[ 10.957760] IRQ13 -> 0:13
[ 10.958269] IRQ14 -> 0:14
[ 10.958746] IRQ15 -> 0:15
[ 10.959231] .................................... done.
To reproduce:
# build kernel
cd linux
cp config-5.6.0-rc1-00177-g06ec0a154aae4 .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=i386 olddefconfig prepare modules_prepare bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
lkp
View attachment "config-5.6.0-rc1-00177-g06ec0a154aae4" of type "text/plain" (128616 bytes)
View attachment "job-script" of type "text/plain" (4675 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (15532 bytes)
Powered by blists - more mailing lists