[<prev] [next>] [<thread-prev] [day] [month] [year] [list]
Message-ID: <20171106081449.c4jqh75hvf2a6i3i@inn>
Date: Mon, 6 Nov 2017 16:14:49 +0800
From: kernel test robot <fengguang.wu@...el.com>
To: Liu Yu <liuyu924@...il.com>
Cc: netdev@...r.kernel.org,
"\\\"David S. Miller\\\" <davem@...emloft.net>;Alexey Kuznetsov
<kuznet@....inr.ac.ru>;Hideaki YOSHIFUJI" <yoshfuji@...ux-ipv6.org>,
lkp@...org
Subject: bd2e1bcd9a:
BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h
FYI, we noticed the following commit (built with gcc-6):
commit: bd2e1bcd9a94fc6b6298185901d11b93a425c0d2 ("reduce the spinlock conflict during massive connect")
url: https://github.com/0day-ci/linux/commits/Liu-Yu/reduce-the-spinlock-conflict-during-massive-connect/20171106-122318
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------------------+------------+------------+
| | d09b9e60e0 | bd2e1bcd9a |
+----------------------------------------------------------------+------------+------------+
| boot_successes | 16 | 2 |
| boot_failures | 0 | 18 |
| BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 18 |
| BUG:scheduling_while_atomic | 0 | 17 |
| WARNING:at_lib/usercopy.c:#_copy_from_user | 0 | 2 |
| RIP:_copy_from_user | 0 | 2 |
| WARNING:at_lib/usercopy.c:#_copy_to_user | 0 | 1 |
| RIP:_copy_to_user | 0 | 1 |
+----------------------------------------------------------------+------------+------------+
[ 105.469197] BUG: sleeping function called from invalid context at mm/slab.h:421
[ 105.494605] in_atomic(): 1, irqs_disabled(): 0, pid: 6780, name: curl
[ 105.511100] CPU: 0 PID: 6780 Comm: curl Not tainted 4.14.0-rc7-00137-gbd2e1bc #3
[ 105.534270] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 105.558797] Call Trace:
[ 105.568778] dump_stack+0x61/0x7e
[ 105.580063] ___might_sleep+0x109/0x118
[ 105.592180] __might_sleep+0x6d/0x74
[ 105.603872] kmem_cache_alloc_node+0x48/0x1ae
[ 105.618591] ? __alloc_skb+0x54/0x176
[ 105.630514] __alloc_skb+0x54/0x176
[ 105.642438] sk_stream_alloc_skb+0xbb/0x190
[ 105.655276] tcp_connect+0x43f/0x875
[ 105.667036] tcp_v4_connect+0x3ed/0x42f
[ 105.679221] __inet_stream_connect+0xb8/0x2a9
[ 105.692220] ? __might_sleep+0x6d/0x74
[ 105.704232] inet_stream_connect+0x38/0x4d
[ 105.716741] SyS_connect+0x71/0x9e
[ 105.728281] ? do_fcntl+0x22a/0x5bc
[ 105.739823] ? SyS_fcntl+0x62/0x81
[ 105.749991] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-wsx03-4G-1/boot-1-debian-x86_64-2016-08-31.cgz-bd2e1bcd9a94fc6b6298185901d11b93a425c0d2-20171106-24199-1a1edlc-0.yaml&job_state=running -o /dev/null
[ 105.750021]
[ 105.818051] entry_SYSCALL_64_fastpath+0x1a/0xa5
[ 105.831399] RIP: 0033:0x7f31d95a735d
[ 105.843422] RSP: 002b:00007fff4286da60 EFLAGS: 00000293 ORIG_RAX: 000000000000002a
[ 105.866660] RAX: ffffffffffffffda RBX: 00007f31d5325700 RCX: 00007f31d95a735d
[ 105.884312] RDX: 0000000000000010 RSI: 00007fff4286dbe0 RDI: 0000000000000003
[ 105.901886] RBP: 00007fff4286db10 R08: 0000000000000006 R09: 0000001e2374d719
[ 105.937825] R10: 000ddda859800000 R11: 0000000000000293 R12: 0000000000000000
[ 105.955477] R13: 00007fff4286db0f R14: 0000000000000003 R15: 00007fff4286dc20
[ 105.989027] BUG: scheduling while atomic: curl/6780/0x7ffffe01
[ 106.004606] Modules linked in: sr_mod cdrom sg ata_generic
[ 106.136117] CPU: 0 PID: 6780 Comm: curl Tainted: G W 4.14.0-rc7-00137-gbd2e1bc #3
[ 106.161194] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 106.185595] Call Trace:
[ 106.195548] dump_stack+0x61/0x7e
[ 106.206987] __schedule_bug+0x5e/0x6c
[ 106.218979] __schedule+0x59/0x6da
[ 106.230447] ? SyS_connect+0x71/0x9e
[ 106.242308] schedule+0x89/0x9a
[ 106.253358] exit_to_usermode_loop+0x32/0x94
[ 106.266390] syscall_return_slowpath+0x8b/0xa0
[ 106.279643] entry_SYSCALL_64_fastpath+0xa3/0xa5
[ 106.293116] RIP: 0033:0x7f31d95a735d
[ 106.304881] RSP: 002b:00007fff4286da60 EFLAGS: 00000293 ORIG_RAX: 000000000000002a
[ 106.328284] RAX: ffffffffffffff8d RBX: 000055c8cd3f0370 RCX: 00007f31d95a735d
[ 106.347238] RDX: 0000000000000010 RSI: 00007fff4286dbe0 RDI: 0000000000000003
[ 106.365325] RBP: 00007fff4286dbd0 R08: 0000000000000006 R09: 0000001e2374d719
[ 106.383307] R10: 000ddda859800000 R11: 0000000000000293 R12: 000055c8cd3da340
[ 106.401357] R13: 000055c8cd3f0568 R14: 0000000000000000 R15: 00007fff4286dac8
[ 112.200411] kill 6800 dmesg --follow --decode
[ 112.200438]
[ 112.290411] wait for background monitors: 6805 oom-killer
[ 112.290439]
[ 116.748110] BUG: sleeping function called from invalid context at mm/slab.h:421
[ 116.782508] in_atomic(): 1, irqs_disabled(): 0, pid: 6880, name: curl
[ 116.801463] CPU: 1 PID: 6880 Comm: curl Tainted: G W 4.14.0-rc7-00137-gbd2e1bc #3
[ 116.829685] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 116.857307] Call Trace:
[ 116.868349] dump_stack+0x61/0x7e
[ 116.881222] ___might_sleep+0x109/0x118
[ 116.896745] __might_sleep+0x6d/0x74
[ 116.909801] kmem_cache_alloc_node+0x48/0x1ae
[ 116.924614] ? __alloc_skb+0x54/0x176
[ 116.938221] __alloc_skb+0x54/0x176
Elapsed time: 120
initrds=(
/osimage/debian/debian-x86_64-2016-08-31.cgz
/lkp/scheduled/vm-lkp-wsx03-4G-1/boot-1-debian-x86_64-2016-08-31.cgz-bd2e1bcd9a94fc6b6298185901d11b93a425c0d2-20171106-24199-1a1edlc-0.cgz
/lkp/lkp/lkp-x86_64.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-08-01.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz
/pkg/linux/x86_64-kexec/gcc-6/bd2e1bcd9a94fc6b6298185901d11b93a425c0d2/modules.cgz
)
cat "${initrds[@]}" > /fs/sdc1/initrd-vm-lkp-wsx03-4G-1
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu host
-kernel /pkg/linux/x86_64-kexec/gcc-6/bd2e1bcd9a94fc6b6298185901d11b93a425c0d2/vmlinuz-4.14.0-rc7-00137-gbd2e1bc
-initrd /fs/sdc1/initrd-vm-lkp-wsx03-4G-1
-m 4096
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0,hostfwd=tcp::23660-:22
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-drive file=/fs/sdc1/disk0-vm-lkp-wsx03-4G-1,media=disk,if=virtio
-drive file=/fs/sdc1/disk1-vm-lkp-wsx03-4G-1,media=disk,if=virtio
-pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-4G-1
-serial file:/dev/shm/kboot/vm-lkp-wsx03-4G-1/serial
-serial file:/dev/shm/kboot/vm-lkp-wsx03-4G-1/kmsg
-daemonize
-display none
-monitor null
)
append=(
ip=::::vm-lkp-wsx03-4G-1::dhcp
root=/dev/ram0
user=lkp
job=/lkp/scheduled/vm-lkp-wsx03-4G-1/boot-1-debian-x86_64-2016-08-31.cgz-bd2e1bcd9a94fc6b6298185901d11b93a425c0d2-20171106-24199-1a1edlc-0.yaml
ARCH=x86_64
kconfig=x86_64-kexec
branch=linux-review/Liu-Yu/reduce-the-spinlock-conflict-during-massive-connect/20171106-122318
commit=bd2e1bcd9a94fc6b6298185901d11b93a425c0d2
BOOT_IMAGE=/pkg/linux/x86_64-kexec/gcc-6/bd2e1bcd9a94fc6b6298185901d11b93a425c0d2/vmlinuz-4.14.0-rc7-00137-gbd2e1bc
max_uptime=600
RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-4G/debian-x86_64-2016-08-31.cgz/x86_64-kexec/gcc-6/bd2e1bcd9a94fc6b6298185901d11b93a425c0d2/0
LKP_SERVER=inn
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
net.ifnames=0
printk.devkmsg=on
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
drbd.minor_count=8
systemd.log_level=err
ignore_loglevel
console=tty0
earlyprintk=ttyS0,115200
console=ttyS0,115200
vga=normal
rw
)
"${kvm[@]}" -append "${append[*]}"
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,
lkp
View attachment "config-4.14.0-rc7-00137-gbd2e1bc" of type "text/plain" (108571 bytes)
View attachment "job-script" of type "text/plain" (4359 bytes)
Download attachment "dmesg.xz" of type "application/x-xz" (14140 bytes)
Powered by blists - more mailing lists