[<prev] [next>] [day] [month] [year] [list]
Message-ID: <20160323014435.GD27992@yexl-desktop>
Date: Wed, 23 Mar 2016 09:44:35 +0800
From: kernel test robot <xiaolong.ye@...el.com>
To: Andy Lutomirski <luto@...nel.org>
Cc: LKML <linux-kernel@...r.kernel.org>, lkp@...org
Subject: [lkp] [x86/mm] e5809dfe26: BUG: sleeping function called from
invalid context at kernel/locking/mutex.c:617
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/pcid
commit e5809dfe266f4ab66b20db540663298d909c7b8c ("x86/mm: Rework ASID")
+-----------------------------------------------------------------------------+------------+------------+
| | 80d0fe1f83 | e5809dfe26 |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 2 | 0 |
| boot_failures | 1 | 34 |
| BUG:kernel_test_crashed | 1 | |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 0 | 34 |
| backtrace:do_execve | 0 | 34 |
| backtrace:run_init_process | 0 | 34 |
| backtrace:do_group_exit | 0 | 9 |
| backtrace:SyS_exit_group | 0 | 9 |
| backtrace:_do_fork | 0 | 22 |
| backtrace:SyS_clone | 0 | 22 |
| backtrace:do_execveat_common | 0 | 1 |
| backtrace:compat_SyS_execve | 0 | 1 |
| backtrace:vm_munmap | 0 | 27 |
| backtrace:SyS_munmap | 0 | 27 |
| backtrace:SyS_execve | 0 | 7 |
+-----------------------------------------------------------------------------+------------+------------+
As below, the log "BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c" showed with your commit.
[ 19.078534] ALSA device list:
[ 19.079430] No soundcards found.
[ 19.089897] Freeing unused kernel memory: 3452K (ffffffff82453000 - ffffffff827b2000)
[ 19.094018] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617
[ 19.098702] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: init
[ 19.100989] 2 locks held by init/1:
[ 19.102597] #0: (&sig->cred_guard_mutex){+.+.+.}, at: [<ffffffff811e1ce8>] prepare_bprm_creds+0x35/0x6e
[ 19.106614] #1: (&mm->mmap_sem){+.+.+.}, at: [<ffffffff811e064e>] setup_arg_pages+0xc1/0x1e7
[ 19.110226] CPU: 0 PID: 1 Comm: init Not tainted 4.5.0-rc2-00216-ge5809df #85
[ 19.112416] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 19.115924] 0000000000000000 ffff88001200faa0 ffffffff81520f7f ffff880012018000
[ 19.119587] ffff88001200fac8 ffffffff810be484 ffffffff820d3bcf 0000000000000269
[ 19.123320] 0000000000000000 ffff88001200faf0 ffffffff810be500 ffff88000c0803a0
[ 19.127074] Call Trace:
[ 19.128472] [<ffffffff81520f7f>] dump_stack+0x4b/0x63
[ 19.130435] [<ffffffff810be484>] ___might_sleep+0x1f9/0x200
[ 19.132523] [<ffffffff810be500>] __might_sleep+0x75/0x7c
[ 19.134538] [<ffffffff81c67f69>] mutex_lock_nested+0x3e/0x35f
[ 19.136624] [<ffffffff81085ce6>] ? kvm_clock_read+0x25/0x2e
[ 19.138677] [<ffffffff81085d03>] ? kvm_sched_clock_read+0x9/0x12
[ 19.140863] [<ffffffff810548c6>] ? sched_clock+0x9/0xd
[ 19.142856] [<ffffffff8108f51d>] propagate_tlb_flush+0x27/0xa6
[ 19.145102] [<ffffffff8108f51d>] ? propagate_tlb_flush+0x27/0xa6
[ 19.147385] [<ffffffff81090099>] flush_tlb_mm_range+0x1aa/0x213
[ 19.149550] [<ffffffff811b0b80>] move_page_tables+0x518/0x549
[ 19.151667] [<ffffffff811e04e7>] shift_arg_pages+0xc0/0x166
[ 19.153752] [<ffffffff810e2576>] ? lock_acquire+0x113/0x1a1
[ 19.155826] [<ffffffff811e064e>] ? setup_arg_pages+0xc1/0x1e7
[ 19.157930] [<ffffffff811e064e>] ? setup_arg_pages+0xc1/0x1e7
[ 19.160026] [<ffffffff811e0747>] setup_arg_pages+0x1ba/0x1e7
[ 19.162111] [<ffffffff81228b1a>] load_elf_binary+0x356/0xf24
[ 19.164178] [<ffffffff81085d03>] ? kvm_sched_clock_read+0x9/0x12
[ 19.166319] [<ffffffff810548c6>] ? sched_clock+0x9/0xd
[ 19.168266] [<ffffffff810c87b1>] ? local_clock+0x20/0x22
[ 19.170279] [<ffffffff811e0975>] search_binary_handler+0x79/0x182
[ 19.172459] [<ffffffff811e230d>] do_execveat_common+0x5ec/0x859
[ 19.195109] [<ffffffff81c5e7c9>] ? rest_init+0x140/0x140
[ 19.197145] [<ffffffff811e259d>] do_execve+0x23/0x25
[ 19.199114] [<ffffffff810002e3>] run_init_process+0x2b/0x2d
[ 19.201207] [<ffffffff81c5e80b>] kernel_init+0x42/0xd9
[ 19.203208] [<ffffffff81c6c41f>] ret_from_fork+0x3f/0x70
[ 19.205244] [<ffffffff81c5e7c9>] ? rest_init+0x140/0x140
[ 19.217441] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[ 20.094744] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617
[ 20.098021] in_atomic(): 1, irqs_disabled(): 0, pid: 2071, name: udevd
[ 20.100044] 2 locks held by udevd/2071:
[ 20.101405] #0: (&cgroup_threadgroup_rwsem){.+.+.+}, at: [<ffffffff810976ee>] copy_process+0x546/0x18b8
[ 20.105297] #1: (&mm->mmap_sem){++++++}, at: [<ffffffff81097ee9>] copy_process+0xd41/0x18b8
[ 20.108847] CPU: 0 PID: 2071 Comm: udevd Not tainted 4.5.0-rc2-00216-ge5809df #85
[ 20.111694] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 20.114691] 0000000000000000 ffff88000c3dbc80 ffffffff81520f7f ffff88000c39aa40
[ 20.118032] ffff88000c3dbca8 ffffffff810be484 ffffffff820d3bcf 0000000000000269
[ 20.121303] 0000000000000000 ffff88000c3dbcd0 ffffffff810be500 ffff88000c30dfa0
[ 20.124604] Call Trace:
[ 20.125970] [<ffffffff81520f7f>] dump_stack+0x4b/0x63
[ 20.127606] [<ffffffff810be484>] ___might_sleep+0x1f9/0x200
[ 20.129410] [<ffffffff810be500>] __might_sleep+0x75/0x7c
[ 20.131124] [<ffffffff81c67f69>] mutex_lock_nested+0x3e/0x35f
[ 20.132948] [<ffffffff81085ce6>] ? kvm_clock_read+0x25/0x2e
[ 20.134798] [<ffffffff81085d03>] ? kvm_sched_clock_read+0x9/0x12
[ 20.136590] [<ffffffff810548c6>] ? sched_clock+0x9/0xd
[ 20.138323] [<ffffffff8108f51d>] propagate_tlb_flush+0x27/0xa6
[ 20.140291] [<ffffffff8108f51d>] ? propagate_tlb_flush+0x27/0xa6
[ 20.142244] [<ffffffff81090099>] flush_tlb_mm_range+0x1aa/0x213
[ 20.144105] [<ffffffff810981b0>] copy_process+0x1008/0x18b8
[ 20.145945] [<ffffffff81098bf3>] _do_fork+0xbd/0x5d4
[ 20.147686] [<ffffffff81c6cbc4>] ? retint_user+0x18/0x20
[ 20.149402] [<ffffffff810e01ef>] ? trace_hardirqs_on_caller+0x17d/0x199
[ 20.151373] [<ffffffff81001017>] ? trace_hardirqs_on_thunk+0x17/0x19
[ 20.153309] [<ffffffff81099190>] SyS_clone+0x19/0x1b
[ 20.155064] [<ffffffff81c6c0b2>] entry_SYSCALL_64_fastpath+0x12/0x72
[ 21.094778] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:617
[ 21.100371] in_atomic(): 1, irqs_disabled(): 0, pid: 2292, name: mount.sh
[ 21.103857] 2 locks held by mount.sh/2292:
[ 21.106265] #0: (&cgroup_threadgroup_rwsem){.+.+.+}, at: [<ffffffff810976ee>] copy_process+0x546/0x18b8
[ 21.112531] #1: (&mm->mmap_sem){++++++}, at: [<ffffffff81097ee9>] copy_process+0xd41/0x18b8
[ 21.118074] CPU: 0 PID: 2292 Comm: mount.sh Not tainted 4.5.0-rc2-00216-ge5809df #85
[ 21.122721] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 21.127422] 0000000000000000 ffff88000b07fc80 ffffffff81520f7f ffff88000b7f0000
[ 21.132282] ffff88000b07fca8 ffffffff810be484 ffffffff820d3bcf 0000000000000269
[ 21.137007] 0000000000000000 ffff88000b07fcd0 ffffffff810be500 ffff8800009634a0
[ 21.141808] Call Trace:
[ 21.143582] [<ffffffff81520f7f>] dump_stack+0x4b/0x63
[ 21.146393] [<ffffffff810be484>] ___might_sleep+0x1f9/0x200
[ 21.149581] [<ffffffff810be500>] __might_sleep+0x75/0x7c
[ 21.152686] [<ffffffff81c67f69>] mutex_lock_nested+0x3e/0x35f
[ 21.155795] [<ffffffff81085ce6>] ? kvm_clock_read+0x25/0x2e
[ 21.158841] [<ffffffff81085d03>] ? kvm_sched_clock_read+0x9/0x12
[ 21.162026] [<ffffffff810548c6>] ? sched_clock+0x9/0xd
[ 21.164890] [<ffffffff8108f51d>] propagate_tlb_flush+0x27/0xa6
[ 21.168325] [<ffffffff8108f51d>] ? propagate_tlb_flush+0x27/0xa6
[ 21.172010] [<ffffffff81090099>] flush_tlb_mm_range+0x1aa/0x213
[ 21.175213] [<ffffffff810981b0>] copy_process+0x1008/0x18b8
[ 21.178618] [<ffffffff81098bf3>] _do_fork+0xbd/0x5d4
[ 21.181445] [<ffffffff811a58b1>] ? __might_fault+0x40/0x8d
[ 21.184399] [<ffffffff81001044>] ? lockdep_sys_exit_thunk+0x12/0x14
[ 21.187857] [<ffffffff81099190>] SyS_clone+0x19/0x1b
[ 21.190601] [<ffffffff81c6c0b2>] entry_SYSCALL_64_fastpath+0x12/0x72
[ 21.214523] blk_update_request: I/O error, dev fd0, sector 0
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu SandyBridge -kernel /pkg/linux/x86_64-nfsroot/gcc-5/e5809dfe266f4ab66b20db540663298d909c7b8c/vmlinuz-4.5.0-rc2-00216-ge5809df -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-x86_64-34/bisect_boot-1-yocto-minimal-x86_64.cgz-x86_64-nfsroot-e5809dfe266f4ab66b20db540663298d909c7b8c-20160322-8447-s50ff4-5.yaml ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-devel/devel-catchup-201603221233 commit=e5809dfe266f4ab66b20db540663298d909c7b8c BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-5/e5809dfe266f4ab66b20db540663298d909c7b8c/vmlinuz-4.5.0-rc2-00216-ge5809df max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-x86_64/yocto-minimal-x86_64.cgz/x86_64-nfsroot/gcc-5/e5809dfe266f4ab66b20db540663298d909c7b8c/5 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-x86_64-34::dhcp drbd.minor_count=8' -initrd /fs/sda1/initrd-vm-kbuild-yocto-x86_64-34 -m 320 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sda1/disk0-vm-kbuild-yocto-x86_64-34,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-x86_64-34 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-x86_64-34 -daemonize -display none -monitor null
Thanks,
Xiaolong Ye
View attachment "config-4.5.0-rc2-00216-ge5809df" of type "text/plain" (108682 bytes)
Download attachment "dmesg.xz" of type "application/octet-stream" (15192 bytes)
Powered by blists - more mailing lists