lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list]
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