Subject: [lkp-robot] [socket] 3bc58b1a49: BUG:using__this_cpu_add()in_preemptible[#]code:krfcommd

From: kernel test robot <[email-masked]>
Date: Fri, 17 Nov 2017 10:25:20 +0800

FYI, we noticed the following commit (built with gcc-6):

commit: 3bc58b1a49b4179db035ca1da289abc0f25a4df2 ("socket: Move the socket inuse to namespace.")
url: https://github.com/0day-ci/linux/commits/Tonghao-Zhang/socket-Move-the-socket-inuse-to-namespace/20171116-175157

in testcase: trinity
with following parameters:

	runtime: 300s

test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/

on test machine: qemu-system-x86_64 -enable-kvm -m 420M

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):

+---------------------------------------------------------+------------+------------+
|                                                         | 2798b80b38 | 3bc58b1a49 |
+---------------------------------------------------------+------------+------------+
| boot_successes                                          | 12         | 0          |
| boot_failures                                           | 0          | 14         |
| BUG:using__this_cpu_add()in_preemptible[#]code:swapper  | 0          | 6          |
| BUG:using__this_cpu_add()in_preemptible[#]code:ubus     | 0          | 5          |
| BUG:using__this_cpu_add()in_preemptible[#]code:ubusd    | 0          | 5          |
| BUG:using__this_cpu_add()in_preemptible[#]code:krfcommd | 0          | 8          |
+---------------------------------------------------------+------------+------------+

[    6.960477] BUG: using __this_cpu_add() in preemptible [00000000] code: krfcommd/178
[    6.960483] caller is __this_cpu_preempt_check+0x13/0x20
[    6.960485] CPU: 0 PID: 178 Comm: krfcommd Not tainted 4.14.0-rc7-02177-g3bc58b1 #1
[    6.960487] Call Trace:
[    6.960493]  dump_stack+0x65/0x92
[    6.960495]  check_preemption_disabled+0xf4/0x110
[    6.960497]  __this_cpu_preempt_check+0x13/0x20
[    6.960501]  sock_alloc+0x86/0xa0
[    6.960503]  __sock_create+0x99/0x1b0
[    6.960506]  ? _raw_spin_unlock_irqrestore+0x7f/0x90
[    6.960508]  sock_create_kern+0x14/0x20
[    6.960511]  rfcomm_l2sock_create+0x28/0x50
[    6.960514]  rfcomm_run+0x58/0x12a0
[    6.960517]  ? wait_woken+0x90/0x90
[    6.960520]  kthread+0x134/0x140
[    6.960522]  ? rfcomm_check_accept+0xa0/0xa0
[    6.960524]  ? __kthread_bind_mask+0x90/0x90
[    6.960526]  ret_from_fork+0x25/0x30
[    6.975298] Bluetooth: HIDP socket layer initialized
[    6.986709] RPC: Registered rdma transport module.
[    6.987631] RPC: Registered rdma backchannel transport module.
[    6.988865] NET: Registered protocol family 33
[    6.989705] Key type rxrpc registered
[    6.990451] Key type rxrpc_s registered
[    6.996746] NET: Registered protocol family 41
[    7.003443] l2tp_core: L2TP core driver, V2.0
[    7.004276] l2tp_ip: L2TP IP encapsulation support (L2TPv3)
[    7.005348] l2tp_netlink: L2TP netlink interface
[    7.006262] l2tp_eth: L2TP ethernet pseudowire support (L2TPv3)
[    7.007458] l2tp_debugfs: L2TP debugfs support
[    7.008297] l2tp_ip6: L2TP IP encapsulation support for IPv6 (L2TPv3)
[    7.009521] 8021q: 802.1Q VLAN Support v1.8
[    7.010453] sctp: Hash tables configured (bind 64/64)
[    7.011602] NET: Registered protocol family 43
[    7.012658] Key type dns_resolver registered
[    7.013544] Key type ceph registered
[    7.020091] libceph: loaded (mon/osd proto 15/24)
[    7.026824] batman_adv: B.A.T.M.A.N. advanced 2017.4 (compatibility version 15) loaded
[    7.028338] openvswitch: Open vSwitch switching datapath
[    7.029592] mpls_gso: MPLS GSO support
[    7.030507] start plist test
[    7.040725] end plist test
[    7.041253] mce: Unable to init MCE device (rc: -5)
[    7.042908] RAS: Correctable Errors collector initialized.
[    7.044011] ... APIC ID:      00000000 (0)
[    7.044794] ... APIC VERSION: 01050014
[    7.045526] 0000000000000000000000000000000000000000000000000000000000000000
[    7.046882] 0000000000000000000000000000000000000000000000000000000000000000
[    7.047273] 0000000000000000000000000000000000000000000000000000000000008000
[    7.049659] number of MP IRQ sources: 15.
[    7.050460] number of IO-APIC #0 registers: 24.
[    7.051318] testing the IO APIC.......................
[    7.052305] IO APIC #0......
[    7.052859] .... register #00: 00000000
[    7.053597] .......    : physical APIC id: 00
[    7.054417] .......    : Delivery Type: 0
[    7.055174] .......    : LTS          : 0
[    7.055934] .... register #01: 00170011
[    7.056651] .......     : max redirection entries: 17
[    7.057628] .......     : PRQ implemented: 0
[    7.058435] .......     : IO APIC version: 11
[    7.059253] .... register #02: 00000000
[    7.059975] .......     : arbitration: 00
[    7.060754] .... IRQ redirection table:
[    7.061480] IOAPIC 0:
[    7.061937]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.063418]  pin01, enabled , edge , high, V(31), IRR(0), S(0), logical , D(01), M(1)
[    7.064893]  pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(1)
[    7.066350]  pin03, enabled , edge , high, V(33), IRR(0), S(0), logical , D(01), M(1)
[    7.067843]  pin04, enabled , edge , high, V(34), IRR(0), S(0), logical , D(01), M(1)
[    7.069320]  pin05, disabled, level, high, V(35), IRR(0), S(0), logical , D(01), M(1)
[    7.071827]  pin06, enabled , edge , high, V(36), IRR(0), S(0), logical , D(01), M(1)
[    7.073289]  pin07, enabled , edge , high, V(37), IRR(0), S(0), logical , D(01), M(1)
[    7.074796]  pin08, enabled , edge , high, V(38), IRR(0), S(0), logical , D(01), M(1)
[    7.076257]  pin09, enabled , level, high, V(39), IRR(0), S(0), logical , D(01), M(1)
[    7.077753]  pin0a, disabled, level, high, V(3A), IRR(0), S(0), logical , D(01), M(1)
[    7.079218]  pin0b, disabled, level, high, V(3B), IRR(0), S(0), logical , D(01), M(1)
[    7.080706]  pin0c, enabled , edge , high, V(3C), IRR(0), S(0), logical , D(01), M(1)
[    7.082170]  pin0d, enabled , edge , high, V(3D), IRR(0), S(0), logical , D(01), M(1)
[    7.083655]  pin0e, enabled , edge , high, V(3E), IRR(0), S(0), logical , D(01), M(1)
[    7.085129]  pin0f, enabled , edge , high, V(3F), IRR(0), S(0), logical , D(01), M(1)
[    7.086588]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.088091]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.089554]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.091041]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.092499]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.094002]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.095465]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.096954]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[    7.098398] IRQ to pin mappings:
[    7.099012] IRQ0 -> 0:2
[    7.099483] IRQ1 -> 0:1
[    7.099961] IRQ3 -> 0:3
[    7.100477] IRQ4 -> 0:4
[    7.100959] IRQ5 -> 0:5
[    7.101431] IRQ6 -> 0:6
[    7.101910] IRQ7 -> 0:7
[    7.102383] IRQ8 -> 0:8
[    7.102863] IRQ9 -> 0:9
[    7.103356] IRQ10 -> 0:10
[    7.103867] IRQ11 -> 0:11

To reproduce:

        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,
Xiaolong