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