netdev - bd2e1bcd9a: BUG:sleeping_function_called_from_invalid_context_at

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>] [<thread-prev] [day] [month] [year] [list]
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)