============================================ WARNING: possible recursive locking detected binder: 19314:19318 ioctl 40046205 bf42 returned -22 binder: BINDER_SET_CONTEXT_MGR already set binder: 19314:19318 ioctl 40046207 0 returned -16 binder: 19314:19318 BC_CLEAR_DEATH_NOTIFICATION death notification cookie mismatch 0000000000000000 != 0000000000000003 binder: 19314:19318 ERROR: BC_REGISTER_LOOPER called after BC_ENTER_LOOPER binder: 19318 RLIMIT_NICE not set binder: 19314:19318 ioctl 40046205 bf42 returned -22 binder: BINDER_SET_CONTEXT_MGR already set binder: 19314:19318 ioctl 40046207 0 returned -16 binder: 19314:19324 BC_CLEAR_DEATH_NOTIFICATION death notification cookie mismatch 0000000000000000 != 0000000000000003 binder: 19314:19324 ERROR: BC_REGISTER_LOOPER called after BC_ENTER_LOOPER binder: 19324 RLIMIT_NICE not set 4.15.0-rc8+ #269 Not tainted -------------------------------------------- syz-executor0/19315 is trying to acquire lock: (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_dev_lock_vqs drivers/vhost/vhost.c:907 [inline] (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_process_iotlb_msg drivers/vhost/vhost.c:997 [inline] (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_chr_write_iter+0x278/0x1580 drivers/vhost/vhost.c:1046 but task is already holding lock: (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_dev_lock_vqs drivers/vhost/vhost.c:907 [inline] (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_process_iotlb_msg drivers/vhost/vhost.c:997 [inline] (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_chr_write_iter+0x278/0x1580 drivers/vhost/vhost.c:1046 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(&vq->mutex); lock(&vq->mutex); *** DEADLOCK *** May be due to missing lock nesting notation 1 lock held by syz-executor0/19315: #0: (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_dev_lock_vqs drivers/vhost/vhost.c:907 [inline] #0: (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_process_iotlb_msg drivers/vhost/vhost.c:997 [inline] #0: (&vq->mutex){+.+.}, at: [<00000000f8463b71>] vhost_chr_write_iter+0x278/0x1580 drivers/vhost/vhost.c:1046 stack backtrace: CPU: 1 PID: 19315 Comm: syz-executor0 Not tainted 4.15.0-rc8+ #269 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x194/0x257 lib/dump_stack.c:53 print_deadlock_bug kernel/locking/lockdep.c:1756 [inline] check_deadlock kernel/locking/lockdep.c:1800 [inline] validate_chain kernel/locking/lockdep.c:2396 [inline] __lock_acquire+0xe8f/0x3e00 kernel/locking/lockdep.c:3426 lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:3914 __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0x16f/0x1a80 kernel/locking/mutex.c:893 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:908 vhost_dev_lock_vqs drivers/vhost/vhost.c:907 [inline] vhost_process_iotlb_msg drivers/vhost/vhost.c:997 [inline] vhost_chr_write_iter+0x278/0x1580 drivers/vhost/vhost.c:1046 vhost_net_chr_write_iter+0x59/0x70 drivers/vhost/net.c:1353 call_write_iter include/linux/fs.h:1772 [inline] new_sync_write fs/read_write.c:469 [inline] __vfs_write+0x684/0x970 fs/read_write.c:482 vfs_write+0x189/0x510 fs/read_write.c:544 SYSC_write fs/read_write.c:589 [inline] SyS_write+0xef/0x220 fs/read_write.c:581 entry_SYSCALL_64_fastpath+0x29/0xa0 RIP: 0033:0x452ee9 RSP: 002b:00007fef645b4c58 EFLAGS: 00000212 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 000000000071bea0 RCX: 0000000000452ee9 RDX: 0000000000000050 RSI: 00000000208baf98 RDI: 0000000000000013 RBP: 000000000000042c R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000212 R12: 00000000006f54c0 R13: 00000000ffffffff R14: 00007fef645b56d4 R15: 0000000000000000 ptrace attach of "/root/syz-executor0"[15571] was attempted by "/root/syz-executor0"[19410] audit: type=1326 audit(1516425731.771:2208): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.798:2209): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=86 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.798:2210): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.801:2211): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=161 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.802:2212): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.803:2213): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.803:2214): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=9 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.807:2215): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.815:2216): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=32 compat=0 ip=0x452ee9 code=0x7ffc0000 audit: type=1326 audit(1516425731.815:2217): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=19441 comm="syz-executor3" exe="/root/syz-executor3" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452ee9 code=0x7ffc0000 FAULT_INJECTION: forcing a failure. name failslab, interval 1, probability 0, space 0, times 1 CPU: 0 PID: 19723 Comm: syz-executor4 Not tainted 4.15.0-rc8+ #269 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x194/0x257 lib/dump_stack.c:53 fail_dump lib/fault-inject.c:51 [inline] should_fail+0x8c0/0xa40 lib/fault-inject.c:149 should_failslab+0xec/0x120 mm/failslab.c:32 slab_pre_alloc_hook mm/slab.h:421 [inline] slab_alloc mm/slab.c:3368 [inline] __do_kmalloc mm/slab.c:3706 [inline] __kmalloc+0x63/0x760 mm/slab.c:3717 kmalloc include/linux/slab.h:504 [inline] sock_kmalloc+0x112/0x190 net/core/sock.c:1979 hash_alloc_result.part.5+0x99/0x120 crypto/algif_hash.c:51 hash_alloc_result crypto/algif_hash.c:70 [inline] hash_recvmsg+0x4f3/0x9b0 crypto/algif_hash.c:210 sock_recvmsg_nosec net/socket.c:811 [inline] sock_recvmsg+0xc9/0x110 net/socket.c:818 ___sys_recvmsg+0x2a4/0x640 net/socket.c:2187 __sys_recvmsg+0xe2/0x210 net/socket.c:2232 SYSC_recvmsg net/socket.c:2244 [inline] SyS_recvmsg+0x2d/0x50 net/socket.c:2239 entry_SYSCALL_64_fastpath+0x29/0xa0 RIP: 0033:0x452ee9 RSP: 002b:00007fd9752d2c58 EFLAGS: 00000212 ORIG_RAX: 000000000000002f RAX: ffffffffffffffda RBX: 00007fd9752d2aa0 RCX: 0000000000452ee9 RDX: 0000000000000000 RSI: 0000000020f4cfc8 RDI: 0000000000000014 RBP: 00007fd9752d2a90 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000212 R12: 00000000004b7c76 R13: 00007fd9752d2bc8 R14: 00000000004b7c76 R15: 0000000000000000 binder: 19765:19769 ERROR: BC_REGISTER_LOOPER called without request binder: release 19765:19785 transaction 88 out, still active binder: undelivered TRANSACTION_COMPLETE binder: 19769 RLIMIT_NICE not set binder: 19769 RLIMIT_NICE not set binder: 19769 RLIMIT_NICE not set binder: 19765:19769 transaction failed 29189/0, size 0-16 line 2728 binder: send failed reply for transaction 88, target dead binder_alloc: binder_alloc_mmap_handler: 19765 20000000-20002000 already mapped failed -16 binder: BINDER_SET_CONTEXT_MGR already set binder: 19765:19785 ERROR: BC_REGISTER_LOOPER called without request binder: 19765:19769 ioctl 40046207 0 returned -16 binder_alloc: 19765: binder_alloc_buf, no vma binder: 19765:19785 transaction failed 29189/-3, size 0-0 line 2903 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29190 dccp_invalid_packet: pskb_may_pull failed dccp_invalid_packet: pskb_may_pull failed sctp: [Deprecated]: syz-executor3 (pid 19937) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead sctp: [Deprecated]: syz-executor3 (pid 19943) Use of struct sctp_assoc_value in delayed_ack socket option. Use struct sctp_sack_info instead QAT: Invalid ioctl QAT: Invalid ioctl FAULT_INJECTION: forcing a failure. name failslab, interval 1, probability 0, space 0, times 0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=256 sclass=netlink_route_socket pig=19990 comm=syz-executor0 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=256 sclass=netlink_route_socket pig=19990 comm=syz-executor0 CPU: 0 PID: 19981 Comm: syz-executor7 Not tainted 4.15.0-rc8+ #269 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x194/0x257 lib/dump_stack.c:53 fail_dump lib/fault-inject.c:51 [inline] should_fail+0x8c0/0xa40 lib/fault-inject.c:149 should_failslab+0xec/0x120 mm/failslab.c:32 slab_pre_alloc_hook mm/slab.h:421 [inline] slab_alloc mm/slab.c:3368 [inline] kmem_cache_alloc_trace+0x4b/0x750 mm/slab.c:3608 kmalloc include/linux/slab.h:499 [inline] vhost_new_umem_range+0xc3/0x740 drivers/vhost/vhost.c:921 vhost_process_iotlb_msg drivers/vhost/vhost.c:1009 [inline] vhost_chr_write_iter+0xc4b/0x1580 drivers/vhost/vhost.c:1046 vhost_net_chr_write_iter+0x59/0x70 drivers/vhost/net.c:1353 call_write_iter include/linux/fs.h:1772 [inline] new_sync_write fs/read_write.c:469 [inline] __vfs_write+0x684/0x970 fs/read_write.c:482 vfs_write+0x189/0x510 fs/read_write.c:544 SYSC_write fs/read_write.c:589 [inline] SyS_write+0xef/0x220 fs/read_write.c:581 entry_SYSCALL_64_fastpath+0x29/0xa0 RIP: 0033:0x452ee9 RSP: 002b:00007f09a229ec58 EFLAGS: 00000212 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 00007f09a229eaa0 RCX: 0000000000452ee9 RDX: 0000000000000068 RSI: 00000000202ff000 RDI: 0000000000000013 RBP: 00007f09a229ea90 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000212 R12: 00000000004b7c76 R13: 00007f09a229ebc8 R14: 00000000004b7c76 R15: 0000000000000000 SELinux: unrecognized netlink message: protocol=0 nlmsg_type=7924 sclass=netlink_route_socket pig=20036 comm=syz-executor3 netlink: 2037 bytes leftover after parsing attributes in process `syz-executor3'. SELinux: unrecognized netlink message: protocol=0 nlmsg_type=7924 sclass=netlink_route_socket pig=20054 comm=syz-executor3 netlink: 2037 bytes leftover after parsing attributes in process `syz-executor3'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. netlink: 3 bytes leftover after parsing attributes in process `syz-executor4'. rdma_op 00000000083f6cc8 conn xmit_rdma (null) sock: sock_set_timeout: `syz-executor5' (pid 21091) tries to set negative timeout FAULT_INJECTION: forcing a failure. name failslab, interval 1, probability 0, space 0, times 0 CPU: 0 PID: 21396 Comm: syz-executor3 Not tainted 4.15.0-rc8+ #269 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x194/0x257 lib/dump_stack.c:53 fail_dump lib/fault-inject.c:51 [inline] should_fail+0x8c0/0xa40 lib/fault-inject.c:149 should_failslab+0xec/0x120 mm/failslab.c:32 slab_pre_alloc_hook mm/slab.h:421 [inline] slab_alloc mm/slab.c:3368 [inline] kmem_cache_alloc_trace+0x4b/0x750 mm/slab.c:3608 kmalloc include/linux/slab.h:499 [inline] vhost_new_umem_range+0xc3/0x740 drivers/vhost/vhost.c:921 vhost_process_iotlb_msg drivers/vhost/vhost.c:1009 [inline] vhost_chr_write_iter+0xc4b/0x1580 drivers/vhost/vhost.c:1046 vhost_net_chr_write_iter+0x59/0x70 drivers/vhost/net.c:1353 call_write_iter include/linux/fs.h:1772 [inline] new_sync_write fs/read_write.c:469 [inline] __vfs_write+0x684/0x970 fs/read_write.c:482 vfs_write+0x189/0x510 fs/read_write.c:544 SYSC_write fs/read_write.c:589 [inline] SyS_write+0xef/0x220 fs/read_write.c:581 entry_SYSCALL_64_fastpath+0x29/0xa0 RIP: 0033:0x452ee9 RSP: 002b:00007f32cfca6c58 EFLAGS: 00000212 ORIG_RAX: 0000000000000001 RAX: ffffffffffffffda RBX: 00007f32cfca6aa0 RCX: 0000000000452ee9 RDX: 0000000000000068 RSI: 00000000208baf98 RDI: 0000000000000013 RBP: 00007f32cfca6a90 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000212 R12: 00000000004b7c76 R13: 00007f32cfca6bc8 R14: 00000000004b7c76 R15: 0000000000000000 SELinux: policydb version 127508616 does not match my version range 15-31 SELinux: failed to load policy rpcbind: RPC call returned error 22 rpcbind: RPC call returned error 22 kauditd_printk_skb: 19 callbacks suppressed audit: type=1400 audit(1516425739.594:2235): avc: denied { create } for pid=21599 comm="syz-executor1" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_connector_socket permissive=1 audit: type=1400 audit(1516425739.632:2236): avc: denied { read } for pid=21599 comm="syz-executor1" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_connector_socket permissive=1 audit: type=1400 audit(1516425739.663:2237): avc: denied { write } for pid=21599 comm="syz-executor1" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_connector_socket permissive=1 audit: type=1400 audit(1516425739.859:2238): avc: denied { accept } for pid=21691 comm="syz-executor3" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_crypto_socket permissive=1 audit: type=1400 audit(1516425739.868:2239): avc: denied { getopt } for pid=21691 comm="syz-executor3" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_crypto_socket permissive=1 audit: type=1400 audit(1516425740.153:2240): avc: denied { map } for pid=21792 comm="syz-executor1" path="socket:[54329]" dev="sockfs" ino=54329 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=tcp_socket permissive=1 binder: 21885:21888 ioctl c018620b 20008000 returned -14 QAT: Invalid ioctl binder: 21885:21888 ERROR: BC_REGISTER_LOOPER called without request binder: 21888 RLIMIT_NICE not set binder: 21888 RLIMIT_NICE not set binder: 21888 RLIMIT_NICE not set binder: 21885:21888 unknown command 0 binder: 21885:21888 ioctl c0306201 204ec000 returned -22 QAT: Invalid ioctl binder: undelivered TRANSACTION_COMPLETE binder: undelivered transaction 93, process died. binder: 21885:21888 ERROR: BC_REGISTER_LOOPER called without request binder: 21888 RLIMIT_NICE not set binder: 21885:21888 got reply transaction with no transaction stack binder: 21885:21888 transaction failed 29201/-71, size 0-0 line 2703 binder: undelivered TRANSACTION_ERROR: 29201 binder: release 21885:21908 transaction 95 out, still active binder: send failed reply for transaction 95, target dead NFS: bad mount option value specified: vä NFS: bad mount option value specified: vä