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-next>] [day] [month] [year] [list] 
Message-ID: <20170524023629.GK1795@yexl-desktop>
Date:   Wed, 24 May 2017 10:36:29 +0800
From:   kernel test robot <xiaolong.ye@...el.com>
To:     Anna Schumaker <Anna.Schumaker@...app.com>
Cc:     Trond Myklebust <trond.myklebust@...marydata.com>,
        LKML <linux-kernel@...r.kernel.org>,
        Linus Torvalds <torvalds@...ux-foundation.org>, lkp@...org
Subject: [lkp-robot] [NFS]  14d1bbb0ca: BUG:scheduling_while_atomic


FYI, we noticed the following commit:

commit: 14d1bbb0ca425c4fdd9a38eaac346c028661b752 ("NFS: Create a common nfs4_match_client() function")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master

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):


+-------------------------------+------------+------------+
|                               | 5b6d3ff605 | 14d1bbb0ca |
+-------------------------------+------------+------------+
| boot_successes                | 469        | 470        |
| boot_failures                 | 0          | 31         |
| BUG:scheduling_while_atomic   | 0          | 31         |
| BUG:spinlock_recursion_on_CPU | 0          | 30         |
+-------------------------------+------------+------------+



[  299.158991] BUG: scheduling while atomic: mount.nfs/9387/0x00000002
[  299.169587] 2 locks held by mount.nfs/9387:
[  299.176165]  #0:  (nfs_clid_init_mutex){......}, at: [<ffffffff8130cc92>] nfs4_discover_server_trunking+0x47/0x1fc
[  299.201802]  #1:  (&(&nn->nfs_client_lock)->rlock){......}, at: [<ffffffff813125fa>] nfs40_walk_client_list+0x2e9/0x338
[  299.221979] CPU: 0 PID: 9387 Comm: mount.nfs Not tainted 4.11.0-rc7-00021-g14d1bbb #45
[  299.235584] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[  299.251176] Call Trace:
[  299.255192]  dump_stack+0x61/0x7e
[  299.260416]  __schedule_bug+0x65/0x74
[  299.266208]  __schedule+0x5d/0x87c
[  299.271883]  schedule+0x89/0x9a
[  299.276937]  schedule_timeout+0x232/0x289
[  299.283223]  ? detach_if_pending+0x10b/0x10b
[  299.289935]  schedule_timeout_uninterruptible+0x2a/0x2c
[  299.298266]  ? put_rpccred+0x3e/0x115
[  299.304327]  ? schedule_timeout_uninterruptible+0x2a/0x2c
[  299.312851]  msleep+0x1e/0x22
[  299.317612]  nfs4_discover_server_trunking+0x102/0x1fc
[  299.325644]  nfs4_init_client+0x13f/0x194
[  299.331870]  ? kvm_clock_read+0x25/0x35
[  299.338022]  ? kvm_sched_clock_read+0x9/0x12
[  299.344767]  ? sched_clock+0x9/0xd
[  299.350051]  nfs_get_client+0x2a8/0x2bd
[  299.356243]  nfs4_set_client+0x78/0x9e
[  299.362137]  nfs4_create_server+0xc4/0x228
[  299.370558]  nfs4_remote_mount+0x2f/0x55
[  299.376973]  mount_fs+0x14/0x8d
[  299.382182]  vfs_kern_mount+0x69/0x136
[  299.388670]  nfs_do_root_mount+0x8f/0xab
[  299.395407]  nfs4_try_mount+0x37/0x4e
[  299.401572]  nfs_fs_mount+0x754/0x785
[  299.407819]  ? nfs_get_option_ul+0x46/0x46
[  299.414593]  ? nfs_destroy_inode+0x1e/0x1e
[  299.420431]  mount_fs+0x14/0x8d
[  299.425028]  ? mount_fs+0x14/0x8d
[  299.430313]  vfs_kern_mount+0x69/0x136
[  299.436242]  do_mount+0x89a/0xb14
[  299.441746]  SyS_mount+0x77/0x9f
[  299.447161]  entry_SYSCALL_64_fastpath+0x1e/0xb2
[  299.454715] RIP: 0033:0x7f7c3cdd798a
[  299.460124] RSP: 002b:00007ffd016026d8 EFLAGS: 00000206 ORIG_RAX: 00000000000000a5
[  299.470919] RAX: ffffffffffffffda RBX: 000000000061c650 RCX: 00007f7c3cdd798a
[  299.481122] RDX: 000000000061bf30 RSI: 000000000061bf10 RDI: 000000000061bef0
[  299.491335] RBP: 00007ffd01602730 R08: 000000000061c890 R09: 000000000061c890
[  299.500242] R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000000
[  299.511452] R13: 00007ffd01602690 R14: 00007ffd01602724 R15: 0000000000000003
[  300.174710] BUG: spinlock recursion on CPU#0, mount.nfs/9387
[  300.184760]  lock: 0xffff88010f38e158, .magic: dead4ead, .owner: mount.nfs/9387, .owner_cpu: 0
[  300.227729] CPU: 0 PID: 9387 Comm: mount.nfs Tainted: G        W       4.11.0-rc7-00021-g14d1bbb #45
[  300.260978] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[  300.294748] Call Trace:
[  300.307512]  dump_stack+0x61/0x7e
[  300.317659]  spin_bug+0x9d/0xa3
[  300.327224]  do_raw_spin_lock+0x40/0x8e
[  300.342811]  _raw_spin_lock+0x55/0x6a
[  300.351000]  nfs40_walk_client_list+0xaf/0x338
[  300.369114]  nfs40_discover_server_trunking+0xe4/0x106
[  300.388266]  nfs4_discover_server_trunking+0x6a/0x1fc
[  300.396142]  nfs4_init_client+0x13f/0x194
[  300.413645]  ? kvm_clock_read+0x25/0x35
[  300.430847]  ? kvm_sched_clock_read+0x9/0x12
[  300.437500]  ? sched_clock+0x9/0xd
[  300.442904]  nfs_get_client+0x2a8/0x2bd
[  300.448779]  nfs4_set_client+0x78/0x9e
[  300.454766]  nfs4_create_server+0xc4/0x228
[  300.461212]  nfs4_remote_mount+0x2f/0x55
[  300.467248]  mount_fs+0x14/0x8d
[  300.472074]  vfs_kern_mount+0x69/0x136
[  300.478332]  nfs_do_root_mount+0x8f/0xab
[  300.484580]  nfs4_try_mount+0x37/0x4e
[  300.490157]  nfs_fs_mount+0x754/0x785
[  300.495958]  ? nfs_get_option_ul+0x46/0x46
[  300.502374]  ? nfs_destroy_inode+0x1e/0x1e
[  300.508985]  mount_fs+0x14/0x8d
[  300.513818]  ? mount_fs+0x14/0x8d
[  300.519121]  vfs_kern_mount+0x69/0x136
[  300.525093]  do_mount+0x89a/0xb14
[  300.530210]  SyS_mount+0x77/0x9f
[  300.535195]  entry_SYSCALL_64_fastpath+0x1e/0xb2
[  300.542489] RIP: 0033:0x7f7c3cdd798a
[  300.548232] RSP: 002b:00007ffd016026d8 EFLAGS: 00000206 ORIG_RAX: 00000000000000a5
[  300.581125] RAX: ffffffffffffffda RBX: 000000000061c650 RCX: 00007f7c3cdd798a
[  300.591877] RDX: 000000000061bf30 RSI: 000000000061bf10 RDI: 000000000061bef0
[  300.622505] RBP: 00007ffd01602730 R08: 000000000061c890 R09: 000000000061c890
[  300.632097] R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000000
[  300.642729] R13: 00007ffd01602690 R14: 00007ffd01602724 R15: 0000000000000003

Elapsed time: 320

initrds=(
	/osimage/debian/debian-x86_64-2016-08-31.cgz
	/lkp/scheduled/vm-lkp-wsx03-4G-6/boot-1-debian-x86_64-2016-08-31.cgz-14d1bbb0ca425c4fdd9a38eaac346c028661b752-20170518-14972-19ln527-189.cgz
	/lkp/lkp/lkp-x86_64.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-05-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
)

cat "${initrds[@]}" > /fs/sdc1/initrd-vm-lkp-wsx03-4G-6

kvm=(
	qemu-system-x86_64
	-enable-kvm
	-cpu host
	-kernel /pkg/linux/x86_64-acpi-redef/gcc-6/14d1bbb0ca425c4fdd9a38eaac346c028661b752/vmlinuz-4.11.0-rc7-00021-g14d1bbb
	-initrd /fs/sdc1/initrd-vm-lkp-wsx03-4G-6
	-m 4096
	-smp 2
	-device e1000,netdev=net0
	-netdev user,id=net0,hostfwd=tcp::23665-:22
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-drive file=/fs/sdc1/disk0-vm-lkp-wsx03-4G-6,media=disk,if=virtio
	-drive file=/fs/sdc1/disk1-vm-lkp-wsx03-4G-6,media=disk,if=virtio
	-pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-4G-6
	-serial file:/dev/shm/kboot/vm-lkp-wsx03-4G-6/serial
	-serial file:/dev/shm/kboot/vm-lkp-wsx03-4G-6/kmsg
	-daemonize
	-display none
	-monitor null
)

append=(
	ip=::::vm-lkp-wsx03-4G-6::dhcp
	root=/dev/ram0
	user=lkp
	job=/lkp/scheduled/vm-lkp-wsx03-4G-6/boot-1-debian-x86_64-2016-08-31.cgz-14d1bbb0ca425c4fdd9a38eaac346c028661b752-20170518-14972-19ln527-189.yaml
	ARCH=x86_64
	kconfig=x86_64-acpi-redef
	branch=linux-devel/devel-spot-201705140518
	commit=14d1bbb0ca425c4fdd9a38eaac346c028661b752
	BOOT_IMAGE=/pkg/linux/x86_64-acpi-redef/gcc-6/14d1bbb0ca425c4fdd9a38eaac346c028661b752/vmlinuz-4.11.0-rc7-00021-g14d1bbb
	max_uptime=600
	RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-4G/debian-x86_64-2016-08-31.cgz/x86_64-acpi-redef/gcc-6/14d1bbb0ca425c4fdd9a38eaac346c028661b752/189
	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


To reproduce:

        git clone https://github.com/01org/lkp-tests.git
        cd lkp-tests
        bin/lkp qemu -k <bzImage> job-script  # job-script is attached in this email



Thanks,
Xiaolong

View attachment "config-4.11.0-rc7-00021-g14d1bbb" of type "text/plain" (129630 bytes)

View attachment "job-script" of type "text/plain" (4187 bytes)

Download attachment "dmesg.xz" of type "application/octet-stream" (18944 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ