======================================================
WARNING: possible circular locking dependency detected
4.15.0-rc3+ #218 Not tainted
------------------------------------------------------
syz-executor6/15154 is trying to acquire lock:
 (&ctx->mutex){+.+.}, at: [<000000006f038c06>] perf_event_ctx_lock_nested+0x21b/0x450 kernel/events/core.c:1249

but task is already holding lock:
 (&pipe->mutex/1){+.+.}, at: [<000000007818823b>] pipe_lock_nested fs/pipe.c:67 [inline]
 (&pipe->mutex/1){+.+.}, at: [<000000007818823b>] pipe_lock+0x56/0x70 fs/pipe.c:75

which lock already depends on the new lock.


the existing dependency chain (in reverse order) is:

-> #8 (&pipe->mutex/1){+.+.}:
       lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
       __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
       pipe_lock_nested fs/pipe.c:67 [inline]
       pipe_lock+0x56/0x70 fs/pipe.c:75
       iter_file_splice_write+0x264/0xf30 fs/splice.c:699
       do_splice_from fs/splice.c:851 [inline]
       do_splice fs/splice.c:1147 [inline]
       SYSC_splice fs/splice.c:1402 [inline]
       SyS_splice+0x7d5/0x1630 fs/splice.c:1382
       entry_SYSCALL_64_fastpath+0x1f/0x96

-> #7 (sb_writers){.+.+}:
       fs_reclaim_acquire+0x14/0x20 mm/page_alloc.c:3592
       slab_pre_alloc_hook mm/slab.h:416 [inline]
       slab_alloc mm/slab.c:3371 [inline]
       kmem_cache_alloc+0x29/0x760 mm/slab.c:3545
       getname_kernel+0x54/0x340 fs/namei.c:218
       kern_path_locked+0x83/0x350 fs/namei.c:2404
       handle_remove+0xbe/0xb60 drivers/base/devtmpfs.c:306

-> #6 ((completion)&req.done){+.+.}:
       lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
       complete_acquire include/linux/completion.h:40 [inline]
       __wait_for_common kernel/sched/completion.c:109 [inline]
       wait_for_common kernel/sched/completion.c:123 [inline]
       wait_for_completion+0xcb/0x7b0 kernel/sched/completion.c:144
       devtmpfs_create_node+0x32b/0x4a0 drivers/base/devtmpfs.c:115
       device_add+0x120f/0x1640 drivers/base/core.c:1824
       device_create_groups_vargs+0x1f3/0x250 drivers/base/core.c:2430
       device_create_vargs drivers/base/core.c:2470 [inline]
       device_create+0xda/0x110 drivers/base/core.c:2506
       msr_device_create+0x26/0x40 arch/x86/kernel/msr.c:188
       cpuhp_invoke_callback+0x2ea/0x1d20 kernel/cpu.c:182
       cpuhp_thread_fun+0x48e/0x7e0 kernel/cpu.c:571
       smpboot_thread_fn+0x450/0x7c0 kernel/smpboot.c:164
       kthread+0x37a/0x440 kernel/kthread.c:238
       ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:441

-> #5 (cpuhp_state-up){+.+.}:
       lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
       cpuhp_lock_acquire kernel/cpu.c:85 [inline]
       cpuhp_invoke_ap_callback kernel/cpu.c:605 [inline]
       cpuhp_issue_call+0x1e5/0x520 kernel/cpu.c:1495
       __cpuhp_setup_state_cpuslocked+0x282/0x600 kernel/cpu.c:1642
       __cpuhp_setup_state+0xb0/0x140 kernel/cpu.c:1671
       cpuhp_setup_state include/linux/cpuhotplug.h:201 [inline]
       page_writeback_init+0x4d/0x71 mm/page-writeback.c:2081
       pagecache_init+0x48/0x4f mm/filemap.c:977
       start_kernel+0x6bc/0x74f init/main.c:695
       x86_64_start_reservations+0x2a/0x2c arch/x86/kernel/head64.c:378
       x86_64_start_kernel+0x77/0x7a arch/x86/kernel/head64.c:359
       secondary_startup_64+0xa5/0xb0 arch/x86/kernel/head_64.S:237

-> #4 (cpuhp_state_mutex){+.+.}:
       lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
       __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
       __cpuhp_setup_state_cpuslocked+0x5b/0x600 kernel/cpu.c:1617
       __cpuhp_setup_state+0xb0/0x140 kernel/cpu.c:1671
       cpuhp_setup_state_nocalls include/linux/cpuhotplug.h:229 [inline]
       kvm_guest_init+0x1f3/0x20f arch/x86/kernel/kvm.c:528
       setup_arch+0x17e8/0x1a02 arch/x86/kernel/setup.c:1266
       start_kernel+0xa5/0x74f init/main.c:530
       x86_64_start_reservations+0x2a/0x2c arch/x86/kernel/head64.c:378
       x86_64_start_kernel+0x77/0x7a arch/x86/kernel/head64.c:359
       secondary_startup_64+0xa5/0xb0 arch/x86/kernel/head_64.S:237

-> #3 (cpu_hotplug_lock.rw_sem){++++}:
       lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
       percpu_down_read_preempt_disable include/linux/percpu-rwsem.h:36 [inline]
       percpu_down_read include/linux/percpu-rwsem.h:59 [inline]
       cpus_read_lock+0x42/0x90 kernel/cpu.c:293
       static_key_slow_inc+0x9d/0x3c0 kernel/jump_label.c:123
       tracepoint_add_func kernel/tracepoint.c:223 [inline]
       tracepoint_probe_register_prio+0x80d/0x9a0 kernel/tracepoint.c:283
       tracepoint_probe_register+0x2a/0x40 kernel/tracepoint.c:304
       trace_event_reg+0x167/0x320 kernel/trace/trace_events.c:305
       perf_trace_event_reg kernel/trace/trace_event_perf.c:122 [inline]
       perf_trace_event_init kernel/trace/trace_event_perf.c:197 [inline]
       perf_trace_init+0x4ef/0xab0 kernel/trace/trace_event_perf.c:221
       perf_tp_event_init+0x7d/0xf0 kernel/events/core.c:7956
       perf_try_init_event+0xc9/0x1f0 kernel/events/core.c:9182
       perf_init_event kernel/events/core.c:9220 [inline]
       perf_event_alloc+0x1cc6/0x2b00 kernel/events/core.c:9484
       SYSC_perf_event_open+0x842/0x2f10 kernel/events/core.c:9939
       SyS_perf_event_open+0x39/0x50 kernel/events/core.c:9825
       entry_SYSCALL_64_fastpath+0x1f/0x96

-> #2 (tracepoints_mutex){+.+.}:
       lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
       __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
       tracepoint_probe_register_prio+0xa0/0x9a0 kernel/tracepoint.c:279
       tracepoint_probe_register+0x2a/0x40 kernel/tracepoint.c:304
       trace_event_reg+0x167/0x320 kernel/trace/trace_events.c:305
       perf_trace_event_reg kernel/trace/trace_event_perf.c:122 [inline]
       perf_trace_event_init kernel/trace/trace_event_perf.c:197 [inline]
       perf_trace_init+0x4ef/0xab0 kernel/trace/trace_event_perf.c:221
       perf_tp_event_init+0x7d/0xf0 kernel/events/core.c:7956
       perf_try_init_event+0xc9/0x1f0 kernel/events/core.c:9182
       perf_init_event kernel/events/core.c:9220 [inline]
       perf_event_alloc+0x1cc6/0x2b00 kernel/events/core.c:9484
       SYSC_perf_event_open+0x842/0x2f10 kernel/events/core.c:9939
       SyS_perf_event_open+0x39/0x50 kernel/events/core.c:9825
       entry_SYSCALL_64_fastpath+0x1f/0x96

-> #1 (event_mutex){+.+.}:
       lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
       __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
       perf_trace_init+0x58/0xab0 kernel/trace/trace_event_perf.c:216
       perf_tp_event_init+0x7d/0xf0 kernel/events/core.c:7956
       perf_try_init_event+0xc9/0x1f0 kernel/events/core.c:9182
       perf_init_event kernel/events/core.c:9204 [inline]
       perf_event_alloc+0x1005/0x2b00 kernel/events/core.c:9484
       inherit_event.isra.92+0x15b/0x920 kernel/events/core.c:10698
       inherit_group kernel/events/core.c:10789 [inline]
       inherit_task_group.isra.94.part.95+0x73/0x240 kernel/events/core.c:10847
       inherit_task_group kernel/events/core.c:10827 [inline]
       perf_event_init_context kernel/events/core.c:10898 [inline]
       perf_event_init_task+0x348/0x890 kernel/events/core.c:10966
       copy_process.part.36+0x173b/0x4ae0 kernel/fork.c:1727
       copy_process kernel/fork.c:1566 [inline]
       _do_fork+0x1ef/0xff0 kernel/fork.c:2045
       SYSC_clone kernel/fork.c:2155 [inline]
       SyS_clone+0x37/0x50 kernel/fork.c:2149
       do_syscall_64+0x26c/0x920 arch/x86/entry/common.c:285
       return_from_SYSCALL_64+0x0/0x75

-> #0 (&ctx->mutex){+.+.}:
       check_prevs_add kernel/locking/lockdep.c:2031 [inline]
       validate_chain kernel/locking/lockdep.c:2473 [inline]
       __lock_acquire+0x3498/0x47f0 kernel/locking/lockdep.c:3500
       lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
       __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
       perf_event_ctx_lock_nested+0x21b/0x450 kernel/events/core.c:1249
       perf_event_ctx_lock kernel/events/core.c:1262 [inline]
       perf_read+0xb9/0x970 kernel/events/core.c:4507
       do_loop_readv_writev fs/read_write.c:673 [inline]
       do_iter_read+0x3db/0x5b0 fs/read_write.c:897
       vfs_readv+0x121/0x1c0 fs/read_write.c:959
       kernel_readv fs/splice.c:361 [inline]
       default_file_splice_read+0x508/0xae0 fs/splice.c:416
       do_splice_to+0x110/0x170 fs/splice.c:880
       do_splice fs/splice.c:1173 [inline]
       SYSC_splice fs/splice.c:1402 [inline]
       SyS_splice+0x11a8/0x1630 fs/splice.c:1382
       entry_SYSCALL_64_fastpath+0x1f/0x96

other info that might help us debug this:

Chain exists of:
  &ctx->mutex --> sb_writers --> &pipe->mutex/1

 Possible unsafe locking scenario:

       CPU0                    CPU1
       ----                    ----
  lock(&pipe->mutex/1);
                               lock(sb_writers);
                               lock(&pipe->mutex/1);
  lock(&ctx->mutex);

 *** DEADLOCK ***

1 lock held by syz-executor6/15154:
 #0:  (&pipe->mutex/1){+.+.}, at: [<000000007818823b>] pipe_lock_nested fs/pipe.c:67 [inline]
 #0:  (&pipe->mutex/1){+.+.}, at: [<000000007818823b>] pipe_lock+0x56/0x70 fs/pipe.c:75

stack backtrace:
CPU: 0 PID: 15154 Comm: syz-executor6 Not tainted 4.15.0-rc3+ #218
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_circular_bug+0x42d/0x610 kernel/locking/lockdep.c:1271
 check_prev_add+0x666/0x15f0 kernel/locking/lockdep.c:1914
 check_prevs_add kernel/locking/lockdep.c:2031 [inline]
 validate_chain kernel/locking/lockdep.c:2473 [inline]
 __lock_acquire+0x3498/0x47f0 kernel/locking/lockdep.c:3500
 lock_acquire+0x1d5/0x580 kernel/locking/lockdep.c:4004
 __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
 perf_event_ctx_lock_nested+0x21b/0x450 kernel/events/core.c:1249
 perf_event_ctx_lock kernel/events/core.c:1262 [inline]
 perf_read+0xb9/0x970 kernel/events/core.c:4507
 do_loop_readv_writev fs/read_write.c:673 [inline]
 do_iter_read+0x3db/0x5b0 fs/read_write.c:897
 vfs_readv+0x121/0x1c0 fs/read_write.c:959
 kernel_readv fs/splice.c:361 [inline]
 default_file_splice_read+0x508/0xae0 fs/splice.c:416
 do_splice_to+0x110/0x170 fs/splice.c:880
 do_splice fs/splice.c:1173 [inline]
 SYSC_splice fs/splice.c:1402 [inline]
 SyS_splice+0x11a8/0x1630 fs/splice.c:1382
 entry_SYSCALL_64_fastpath+0x1f/0x96
RIP: 0033:0x452a39
RSP: 002b:00007f7e5ddd1c58 EFLAGS: 00000212 ORIG_RAX: 0000000000000113
RAX: ffffffffffffffda RBX: 00007f7e5ddd2700 RCX: 0000000000452a39
RDX: 0000000000000017 RSI: 0000000000000000 RDI: 0000000000000018
RBP: 0000000000000000 R08: 0000000100000001 R09: 000000000000000e
R10: 0000000000000000 R11: 0000000000000212 R12: 0000000000000000
R13: 0000000000a6f7ff R14: 00007f7e5ddd29c0 R15: 0000000000000000
device gre0 entered promiscuous mode
netlink: 9 bytes leftover after parsing attributes in process `syz-executor6'.
kauditd_printk_skb: 42 callbacks suppressed
audit: type=1400 audit(1513109417.037:1746): avc:  denied  { net_bind_service } for  pid=15283 comm="syz-executor2" capability=10  scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=cap_userns permissive=1
netlink: 9 bytes leftover after parsing attributes in process `syz-executor6'.
netlink: 5 bytes leftover after parsing attributes in process `syz-executor1'.
netlink: 11 bytes leftover after parsing attributes in process `syz-executor0'.
sock: process `syz-executor4' is using obsolete setsockopt SO_BSDCOMPAT
selinux_nlmsg_perm: 1 callbacks suppressed
SELinux: unrecognized netlink message: protocol=6 nlmsg_type=770 sclass=netlink_xfrm_socket pig=15303 comm=syz-executor4
netlink: 5 bytes leftover after parsing attributes in process `syz-executor1'.
kvm_hv_set_msr: 102 callbacks suppressed
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x4000008f data 0x0
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x4000008e data 0x0
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x4000008d data 0x0
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x4000008c data 0x0
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x4000008b data 0x0
netlink: 109 bytes leftover after parsing attributes in process `syz-executor0'.
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x4000008a data 0x0
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x40000089 data 0x0
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x40000088 data 0x0
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x40000087 data 0x0
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x40000086 data 0x0
SELinux: unrecognized netlink message: protocol=6 nlmsg_type=770 sclass=netlink_xfrm_socket pig=15319 comm=syz-executor4
QAT: Invalid ioctl
QAT: Invalid ioctl
audit: type=1400 audit(1513109417.132:1747): avc:  denied  { bind } for  pid=15304 comm="syz-executor0" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_netfilter_socket permissive=1
audit: type=1400 audit(1513109417.143:1748): avc:  denied  { getattr } for  pid=15304 comm="syz-executor0" scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tclass=netlink_netfilter_socket permissive=1
kvm [15263]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x40000020 data 0x0
sctp: [Deprecated]: syz-executor6 (pid 15419) Use of int in maxseg socket option.
Use struct sctp_assoc_value instead
sctp: [Deprecated]: syz-executor6 (pid 15432) Use of int in maxseg socket option.
Use struct sctp_assoc_value instead
binder: 15453:15457 ioctl 40046205 4 returned -22
9pnet_virtio: no channels available for device ./file1
binder: 15453:15467 ERROR: BC_REGISTER_LOOPER called without request
binder: 15467 RLIMIT_NICE not set
QAT: Invalid ioctl
binder: 15453:15479 BC_ACQUIRE_DONE u0000000000000000 no match
binder: 15453:15479 Acquire 1 refcount change on invalid ref 0 ret -22
binder: 15453:15479 IncRefs 0 refcount change on invalid ref 0 ret -22
binder: 15453:15479 BC_CLEAR_DEATH_NOTIFICATION invalid ref 3
binder: 15453:15479 BC_DEAD_BINDER_DONE 0000000000000003 not found
binder: 15453:15479 ERROR: BC_REGISTER_LOOPER called without request
binder: 15453:15479 DecRefs 0 refcount change on invalid ref 3 ret -22
binder: 15479 RLIMIT_NICE not set
9pnet_virtio: no channels available for device ./file1
binder: 15453:15482 ioctl 40046205 4 returned -22
binder: 15453:15482 ERROR: BC_REGISTER_LOOPER called without request
binder: 15482 RLIMIT_NICE not set
binder: 15453:15482 BC_ACQUIRE_DONE u0000000000000000 no match
binder: 15453:15482 Acquire 1 refcount change on invalid ref 0 ret -22
binder: 15453:15482 IncRefs 0 refcount change on invalid ref 0 ret -22
binder: 15453:15482 BC_CLEAR_DEATH_NOTIFICATION invalid ref 3
binder: 15453:15482 BC_DEAD_BINDER_DONE 0000000000000003 not found
binder: 15453:15482 ERROR: BC_REGISTER_LOOPER called without request
binder: 15453:15482 DecRefs 0 refcount change on invalid ref 3 ret -22
binder: 15482 RLIMIT_NICE not set
device gre0 entered promiscuous mode
print_req_error: I/O error, dev loop6, sector 0
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
device eql entered promiscuous mode
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
Buffer I/O error on dev loop6, logical block 0, async page read
loop: Write error at byte offset 0, length 512.
loop: Write error at byte offset 0, length 512.
nla_parse: 3 callbacks suppressed
netlink: 5 bytes leftover after parsing attributes in process `syz-executor3'.
netlink: 5 bytes leftover after parsing attributes in process `syz-executor3'.
netlink: 'syz-executor0': attribute type 29 has an invalid length.
netlink: 5 bytes leftover after parsing attributes in process `syz-executor0'.
netlink: 'syz-executor0': attribute type 29 has an invalid length.
netlink: 5 bytes leftover after parsing attributes in process `syz-executor0'.
netlink: 11 bytes leftover after parsing attributes in process `syz-executor2'.
netlink: 11 bytes leftover after parsing attributes in process `syz-executor2'.
netlink: 8 bytes leftover after parsing attributes in process `syz-executor3'.
netlink: 8 bytes leftover after parsing attributes in process `syz-executor3'.
binder: 15770:15771 BC_FREE_BUFFER u0000020400000000 no match
binder: 15772:15777 BC_DEAD_BINDER_DONE 0000000000000003 not found
binder: 15777 RLIMIT_NICE not set
binder: 15772:15783 transaction failed 29189/-22, size 0-0 line 2775
binder: undelivered TRANSACTION_ERROR: 29189
binder: BINDER_SET_CONTEXT_MGR already set
binder: 15770:15790 ioctl 40046207 0 returned -16
binder: 15772:15783 DecRefs 0 refcount change on invalid ref 0 ret -22
binder: 15770:15771 BC_FREE_BUFFER u0000020400000000 no match
binder_alloc: 15770: binder_alloc_buf, no vma
binder: 15770:15771 transaction failed 29189/-3, size 0-0 line 2890
binder: undelivered TRANSACTION_ERROR: 29189
binder: release 15770:15771 transaction 111 out, still active
binder: undelivered TRANSACTION_COMPLETE
binder: send failed reply for transaction 111, target dead
QAT: Invalid ioctl
kvm [15781]: vcpu0, guest rIP: 0xfff0 unimplemented MMIO_CONF_BASE wrmsr: 0xfffffffffffffffd
binder: 15772:15809 got reply transaction with no transaction stack
binder: 15772:15809 transaction failed 29201/-71, size 32-8 line 2690
binder: 15772:15809 BC_DEAD_BINDER_DONE 0000000000000003 not found
binder: 15809 RLIMIT_NICE not set
binder: 15772:15783 transaction failed 29189/-22, size 0-0 line 2775
QAT: Invalid ioctl
kvm [15781]: vcpu0, guest rIP: 0xfff0 unimplemented MMIO_CONF_BASE wrmsr: 0xfffffffffffffffd
binder: 15772:15816 DecRefs 0 refcount change on invalid ref 0 ret -22
binder_alloc: binder_alloc_mmap_handler: 15829 2023f000-2063f000 already mapped failed -16
QAT: Invalid ioctl
QAT: Invalid ioctl
binder: tried to use weak ref as strong ref
binder: 15833:15834 Release 1 refcount change on invalid ref 2 ret -22
binder: 15833:15834 Release 1 refcount change on invalid ref 2097152 ret -22
binder: 15833:15834 BC_ACQUIRE_DONE node 116 has no pending acquire request
binder: 15833:15834 BC_REQUEST_DEATH_NOTIFICATION invalid ref 0
binder: 15833:15834 BC_ACQUIRE_DONE uffffffffffffffff no match
netlink: 1 bytes leftover after parsing attributes in process `syz-executor7'.
binder: BINDER_SET_CONTEXT_MGR already set
binder: 15833:15834 ioctl 40046207 0 returned -16
QAT: Invalid ioctl
binder_alloc: 15833: binder_alloc_buf, no vma
binder: 15833:15834 transaction failed 29189/-3, size 80-16 line 2890
binder_alloc: binder_alloc_mmap_handler: 15829 20004000-20005000 already mapped failed -16
QAT: Invalid ioctl
netlink: 1 bytes leftover after parsing attributes in process `syz-executor7'.
binder: undelivered TRANSACTION_ERROR: 29189
binder: release 15833:15834 transaction 117 out, still active
binder: unexpected work type, 4, not freed
binder: unexpected work type, 4, not freed
binder: undelivered TRANSACTION_COMPLETE
binder: send failed reply for transaction 117, target dead
binder: undelivered TRANSACTION_ERROR: 29189
9pnet_virtio: no channels available for device ./file1
9pnet_virtio: no channels available for device ./file1
netlink: 'syz-executor7': attribute type 5 has an invalid length.
loop: Write error at byte offset 0, length 512.
netlink: 'syz-executor7': attribute type 5 has an invalid length.
loop: Write error at byte offset 0, length 512.
audit: type=1400 audit(1513109421.140:1749): avc:  denied  { map } for  pid=16108 comm="syz-executor6" path="/dev/ptmx" dev="devtmpfs" ino=8861 scontext=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 tcontext=system_u:object_r:ptmx_t:s0 tclass=chr_file permissive=1
binder: 16179:16181 ioctl 1 20000000 returned -22
binder: 16179:16181 ERROR: BC_REGISTER_LOOPER called without request
binder: 16179:16181 got transaction to invalid handle
binder: 16179:16181 transaction failed 29201/-22, size 88-24 line 2775
binder: 16179:16181 ioctl c0306201 20900fd0 returned -14
binder: BINDER_SET_CONTEXT_MGR already set
binder: 16179:16243 ioctl 1 20000000 returned -22
binder: 16179:16238 ERROR: BC_REGISTER_LOOPER called without request
binder: 16179:16238 got transaction to invalid handle
binder: 16179:16238 transaction failed 29201/-22, size 88-24 line 2775
binder_alloc: 16179: binder_alloc_buf, no vma
binder: 16179:16181 ioctl 40046207 0 returned -16
binder: 16179:16221 transaction failed 29189/-3, size 0-0 line 2890
binder: 16179:16221 ioctl c0306201 20007000 returned -14
binder: undelivered TRANSACTION_ERROR: 29201
binder: release 16179:16181 transaction 124 out, still active
binder: undelivered TRANSACTION_COMPLETE
kvm [16146]: vcpu0, guest rIP: 0x9115 Hyper-V uhandled wrmsr: 0x40000020 data 0xe0000011
binder: undelivered TRANSACTION_ERROR: 29201
binder: send failed reply for transaction 124, target dead
binder: undelivered TRANSACTION_ERROR: 29189
audit: type=1326 audit(1513109422.382:1750): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452a39 code=0x7ffc0000
audit: type=1326 audit(1513109422.416:1751): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452a39 code=0x7ffc0000
audit: type=1326 audit(1513109422.416:1752): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=228 compat=0 ip=0x4557da code=0x7ffc0000
audit: type=1326 audit(1513109422.420:1753): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452a39 code=0x7ffc0000
audit: type=1326 audit(1513109422.420:1754): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=190 compat=0 ip=0x452a39 code=0x7ffc0000
audit: type=1326 audit(1513109422.420:1755): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452a39 code=0x7ffc0000
audit: type=1326 audit(1513109422.424:1756): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452a39 code=0x7ffc0000
audit: type=1326 audit(1513109422.424:1757): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452a39 code=0x7ffc0000
audit: type=1326 audit(1513109422.424:1758): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=228 compat=0 ip=0x4557da code=0x7ffc0000
audit: type=1326 audit(1513109422.424:1759): auid=4294967295 uid=0 gid=0 ses=4294967295 subj=unconfined_u:system_r:insmod_t:s0-s0:c0.c1023 pid=16405 comm="syz-executor6" exe="/root/syz-executor6" sig=0 arch=c000003e syscall=202 compat=0 ip=0x452a39 code=0x7ffc0000
binder: 16447:16449 got reply transaction with no transaction stack
binder: 16447:16449 transaction failed 29201/-71, size 0-1936206469 line 2690
binder: 16449 RLIMIT_NICE not set
binder: 16447:16452 BC_INCREFS_DONE u0000000000000000 node 129 cookie mismatch 0000000000000003 != 0000000000000000
binder: 16447:16452 got transaction to invalid handle
binder: 16447:16452 transaction failed 29201/-22, size 0-8 line 2775
binder: 16449 RLIMIT_NICE not set
binder: 16447:16449 BC_CLEAR_DEATH_NOTIFICATION invalid ref 2
binder: 16447:16449 got transaction with fd, -1, but target does not allow fds
binder: 16447:16449 transaction failed 29201/-1, size 48-32 line 2995
binder: 16447:16452 got reply transaction with no transaction stack
binder: 16447:16452 transaction failed 29201/-71, size 0-1936206469 line 2690
binder: BINDER_SET_CONTEXT_MGR already set
binder: 16447:16452 ioctl 40046207 0 returned -16
binder_alloc: 16447: binder_alloc_buf, no vma
binder: 16447:16467 transaction failed 29189/-3, size 0-0 line 2890
binder: 16447:16465 BC_INCREFS_DONE u0000000000000000 no match
binder: 16447:16465 got transaction to invalid handle
binder: 16447:16465 transaction failed 29201/-22, size 0-8 line 2775
binder: 16447:16449 BC_CLEAR_DEATH_NOTIFICATION invalid ref 2
binder: 16447:16449 BC_FREE_BUFFER u0000000020000000 no match
binder_alloc: 16447: binder_alloc_buf, no vma
binder: 16447:16449 transaction failed 29189/-3, size 48-32 line 2890
binder: undelivered TRANSACTION_ERROR: 29189
binder: undelivered TRANSACTION_ERROR: 29189
binder: undelivered TRANSACTION_ERROR: 29201
binder: 16486:16487 ERROR: BC_REGISTER_LOOPER called without request
binder: 16487 RLIMIT_NICE not set
binder: 16487 RLIMIT_NICE not set
binder: 16487 RLIMIT_NICE not set
binder_alloc: binder_alloc_mmap_handler: 16486 20000000-20002000 already mapped failed -16
binder: BINDER_SET_CONTEXT_MGR already set
binder: 16486:16487 ioctl 40046207 0 returned -16
binder: 16486:16497 ERROR: BC_REGISTER_LOOPER called without request
binder: 16497 RLIMIT_NICE not set
binder_alloc: 16486: binder_alloc_buf, no vma
binder: 16486:16487 transaction failed 29189/-3, size 0-0 line 2890
binder: undelivered TRANSACTION_ERROR: 29189
binder: undelivered TRANSACTION_COMPLETE
binder: undelivered TRANSACTION_COMPLETE
binder: undelivered transaction 139, process died.
binder: 16525:16527 DecRefs 0 refcount change on invalid ref 268435456 ret -22
binder: 16525:16527 Acquire 1 refcount change on invalid ref 0 ret -22
binder: 16525:16527 BC_INCREFS_DONE u0000000000000000 no match
binder: 16525:16527 Release 1 refcount change on invalid ref 4 ret -22
binder: 16525:16534 ERROR: BC_REGISTER_LOOPER called without request
binder: 16525:16534 BC_INCREFS_DONE u0000000000000000 node 141 cookie mismatch 0000000000000002 != 0000000000000000
binder: 16525:16534 BC_CLEAR_DEATH_NOTIFICATION invalid ref 0
binder: 16525:16534 DecRefs 0 refcount change on invalid ref 4 ret -22
binder: 16525:16534 Release 1 refcount change on invalid ref 1 ret -22
binder: 16525:16534 ERROR: BC_REGISTER_LOOPER called without request
binder: 16534 RLIMIT_NICE not set
binder: 16525:16548 BC_INCREFS_DONE u0000000000000000 node 141 cookie mismatch 0000000000000003 != 0000000000000000
binder: 16525:16548 got transaction to invalid handle
binder: 16525:16548 transaction failed 29201/-22, size 40-0 line 2775
binder: undelivered transaction 144, put_user failed
binder: 16525:16534 ioctl c0306201 2000efd0 returned -14
binder: 16525:16534 DecRefs 0 refcount change on invalid ref 268435456 ret -22
binder: 16525:16534 BC_INCREFS_DONE u0000000000000000 no match
binder: 16525:16548 ERROR: BC_REGISTER_LOOPER called without request
binder: 16525:16548 BC_INCREFS_DONE u0000000000000000 no match
binder: 16525:16548 BC_CLEAR_DEATH_NOTIFICATION death notification not active
binder: 16525:16548 DecRefs 0 refcount change on invalid ref 4 ret -22
binder: 16525:16548 Release 1 refcount change on invalid ref 1 ret -22
binder: 16525:16548 ERROR: BC_REGISTER_LOOPER called without request
binder: 16548 RLIMIT_NICE not set
binder: BINDER_SET_CONTEXT_MGR already set
binder: 16525:16534 ioctl 40046207 0 returned -16
dccp_close: ABORT with 38 bytes unread
binder: 16525:16566 BC_INCREFS_DONE u0000000000000000 no match
binder: 16525:16566 got transaction to invalid handle
binder: 16525:16566 transaction failed 29201/-22, size 40-0 line 2775
binder_alloc: 16525: binder_alloc_buf, no vma
binder: 16525:16566 transaction failed 29189/-3, size 0-0 line 2890
binder: 16525:16527 unknown command 0
binder: 16525:16527 ioctl c0306201 20010000 returned -22
binder: undelivered TRANSACTION_ERROR: 29189
binder: undelivered TRANSACTION_COMPLETE
device gre0 entered promiscuous mode
sctp: [Deprecated]: syz-executor3 (pid 16728) Use of struct sctp_assoc_value in delayed_ack socket option.
Use struct sctp_sack_info instead
sctp: [Deprecated]: syz-executor3 (pid 16744) Use of struct sctp_assoc_value in delayed_ack socket option.
Use struct sctp_sack_info instead
nla_parse: 16 callbacks suppressed
netlink: 5 bytes leftover after parsing attributes in process `syz-executor3'.
print_req_error: 259 callbacks suppressed
print_req_error: I/O error, dev loop6, sector 0
netlink: 5 bytes leftover after parsing attributes in process `syz-executor3'.
print_req_error: I/O error, dev loop6, sector 0
buffer_io_error: 248 callbacks suppressed
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
print_req_error: I/O error, dev loop6, sector 0
Buffer I/O error on dev loop6, logical block 0, async page read
Buffer I/O error on dev loop6, logical block 0, async page read
devpts: called with bogus options
devpts: called with bogus options
netlink: 8 bytes leftover after parsing attributes in process `syz-executor5'.
netlink: 8 bytes leftover after parsing attributes in process `syz-executor6'.
netlink: 8 bytes leftover after parsing attributes in process `syz-executor5'.
netlink: 8 bytes leftover after parsing attributes in process `syz-executor6'.