binder: 3385:3389 transaction failed 29189/-3, size 0-0 line 3035 INFO: task syz-executor.1:27559 blocked for more than 140 seconds. Not tainted 5.0.0+ #7 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kobject: 'kvm' (00000000e4af41d9): kobject_uevent_env syz-executor.1 D28512 27559 23779 0x80000006 kobject: 'kvm' (00000000e4af41d9): fill_kobj_path: path = '/devices/virtual/misc/kvm' Call Trace: context_switch kernel/sched/core.c:2844 [inline] __schedule+0x817/0x1cc0 kernel/sched/core.c:3485 schedule+0x92/0x180 kernel/sched/core.c:3529 schedule_timeout+0x8ca/0xfd0 kernel/time/timer.c:1779 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common kernel/sched/completion.c:115 [inline] wait_for_completion+0x29c/0x440 kernel/sched/completion.c:136 binder_release_work: 5 callbacks suppressed binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29201 binder: undelivered TRANSACTION_ERROR: 29201 crypto_wait_req include/linux/crypto.h:680 [inline] crypto_wait_req include/linux/crypto.h:675 [inline] tls_sw_free_resources_tx+0x3c2/0xa30 net/tls/tls_sw.c:2041 binder: undelivered TRANSACTION_ERROR: 29189 tls_sk_proto_close+0x644/0x7a0 net/tls/tls_main.c:268 binder: send failed reply for transaction 2042 to 3364:3366 audit: type=1800 audit(1551847757.931:380): pid=3423 uid=0 auid=4294967295 ses=4294967295 subj=_ op=collect_data cause=failed(directio) comm="syz-executor.2" name="file0" dev="sda1" ino=17609 res=0 binder: 3419:3421 got reply transaction with bad transaction stack, transaction 2050 has target 3419:0 binder: undelivered TRANSACTION_COMPLETE binder: undelivered TRANSACTION_ERROR: 29201 audit: type=1804 audit(1551847757.931:381): pid=3423 uid=0 auid=4294967295 ses=4294967295 subj=_ op=invalid_pcr cause=open_writers comm="syz-executor.2" name="/root/syzkaller-testdir620796598/syzkaller.KZiDP9/680/file0" dev="sda1" ino=17609 res=1 binder: 3419:3421 transaction failed 29201/-71, size 0-0 line 2816 binder: undelivered TRANSACTION_ERROR: 29189 inet_release+0x105/0x1f0 net/ipv4/af_inet.c:428 inet6_release+0x53/0x80 net/ipv6/af_inet6.c:473 audit: type=1800 audit(1551847757.931:382): pid=3423 uid=0 auid=4294967295 ses=4294967295 subj=_ op=collect_data cause=failed(directio) comm="syz-executor.2" name="file0" dev="sda1" ino=17609 res=0 binder_alloc: binder_alloc_mmap_handler: 3419 20001000-20004000 already mapped failed -16 __sock_release+0xd3/0x2b0 net/socket.c:579 binder: BINDER_SET_CONTEXT_MGR already set binder: 3419:3421 ioctl 40046207 0 returned -16 sock_close+0x1b/0x30 net/socket.c:1162 __fput+0x2df/0x8d0 fs/file_table.c:278 ____fput+0x16/0x20 fs/file_table.c:309 task_work_run+0x14a/0x1c0 kernel/task_work.c:113 exit_task_work include/linux/task_work.h:22 [inline] do_exit+0x92c/0x2fd0 kernel/exit.c:875 binder_alloc: 3419: binder_alloc_buf, no vma binder: 3419:3429 transaction failed 29189/-3, size 0-0 line 3035 do_group_exit+0x135/0x370 kernel/exit.c:979 get_signal+0x399/0x1d50 kernel/signal.c:2575 binder: send failed reply for transaction 2050 to 3419:3421 binder: undelivered TRANSACTION_COMPLETE do_signal+0x87/0x1940 arch/x86/kernel/signal.c:816 binder: undelivered TRANSACTION_ERROR: 29201 binder: undelivered TRANSACTION_ERROR: 29189 binder: undelivered TRANSACTION_ERROR: 29189 kobject: 'kvm' (00000000e4af41d9): kobject_uevent_env kobject: 'kvm' (00000000e4af41d9): fill_kobj_path: path = '/devices/virtual/misc/kvm' kobject: 'kvm' (00000000e4af41d9): kobject_uevent_env kobject: 'kvm' (00000000e4af41d9): fill_kobj_path: path = '/devices/virtual/misc/kvm' exit_to_usermode_loop+0x244/0x2c0 arch/x86/entry/common.c:162 prepare_exit_to_usermode arch/x86/entry/common.c:197 [inline] syscall_return_slowpath arch/x86/entry/common.c:268 [inline] do_syscall_64+0x52d/0x610 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x457f29 Code: ad b8 fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 0f 83 7b b8 fb ff c3 66 2e 0f 1f 84 00 00 00 00 RSP: 002b:00007f9f3844fcf8 EFLAGS: 00000246 ORIG_RAX: 00000000000000ca RAX: fffffffffffffe00 RBX: 000000000073bf08 RCX: 0000000000457f29 RDX: 0000000000000000 RSI: 0000000000000080 RDI: 000000000073bf08 RBP: 000000000073bf00 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000073bf0c R13: 00007ffeac56e25f R14: 00007f9f384509c0 R15: 000000000073bf0c Showing all locks held in the system: kobject: 'rx-0' (00000000ed9b18cf): kobject_cleanup, parent 00000000196f8243 kobject: 'rx-0' (00000000ed9b18cf): auto cleanup 'remove' event 2 locks held by kworker/0:1/12: 2 locks held by kworker/u4:1/21: kobject: 'rx-0' (00000000ed9b18cf): kobject_uevent_env #0: 0000000043c353c3 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1149 [inline] #0: 0000000043c353c3 (&rq->lock){-.-.}, at: __schedule+0x1fc/0x1cc0 kernel/sched/core.c:3423 kobject: 'rx-0' (00000000ed9b18cf): kobject_uevent_env: uevent_suppress caused the event to drop! #1: 000000008025e704 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 000000008025e704 (rcu_read_lock){....}, at: update_curr+0x2ce/0x8a0 kernel/sched/fair.c:829 kobject: 'rx-0' (00000000ed9b18cf): auto cleanup kobject_del 4 locks held by kworker/u4:2/66: 1 lock held by khungtaskd/1040: kobject: 'rx-0' (00000000ed9b18cf): calling ktype release kobject: 'loop4' (0000000083362e4d): kobject_uevent_env #0: 000000008025e704 (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x27e kernel/locking/lockdep.c:4389 kobject: 'rx-0': free name kobject: 'loop4' (0000000083362e4d): fill_kobj_path: path = '/devices/virtual/block/loop4' audit: type=1804 audit(1551847758.771:383): pid=3425 uid=0 auid=4294967295 ses=4294967295 subj=_ op=invalid_pcr cause=open_writers comm="syz-executor.2" name="/root/syzkaller-testdir620796598/syzkaller.KZiDP9/680/file0" dev="sda1" ino=17609 res=1 kobject: 'tx-0' (000000002acda88c): kobject_cleanup, parent 00000000196f8243 2 locks held by rs:main Q:Reg/7557: 1 lock held by rsyslogd/7559: #0: 00000000fd1f21b4 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xee/0x110 fs/file.c:795 kobject: 'tx-0' (000000002acda88c): auto cleanup 'remove' event 2 locks held by getty/7650: kobject: 'tx-0' (000000002acda88c): kobject_uevent_env #0: 000000003d53cdde (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 kobject: 'tx-0' (000000002acda88c): kobject_uevent_env: uevent_suppress caused the event to drop! audit: type=1800 audit(1551847758.781:384): pid=3425 uid=0 auid=4294967295 ses=4294967295 subj=_ op=collect_data cause=failed(directio) comm="syz-executor.2" name="file0" dev="sda1" ino=17609 res=0 kobject: 'tx-0' (000000002acda88c): auto cleanup kobject_del #1: 000000000d6996c3 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2154 kobject: 'tx-0' (000000002acda88c): calling ktype release kobject: 'tx-0': free name 2 locks held by getty/7651: #0: 000000006e28c5fc (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 kobject: 'queues' (00000000196f8243): kobject_cleanup, parent (null) #1: 000000006300d016 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2154 kobject: 'queues' (00000000196f8243): calling ktype release 2 locks held by getty/7652: kobject: 'queues' (00000000196f8243): kset_release kobject: 'queues': free name kobject: 'ip6gre0' (0000000013b90761): kobject_uevent_env #0: 00000000743418e0 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 kobject: 'ip6gre0' (0000000013b90761): kobject_uevent_env: uevent_suppress caused the event to drop! #1: 00000000a4af81fc (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2154 2 locks held by getty/7653: #0: 0000000073b446ea (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 00000000c54dd009 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2154 2 locks held by getty/7654: #0: 0000000047a78f18 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 00000000bd778c12 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2154 2 locks held by getty/7655: #0: 00000000e05fd427 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 000000007c75e6ba (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2154 2 locks held by getty/7656: #0: 00000000dd84b258 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:341 #1: 0000000089c3f2e3 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70 drivers/tty/n_tty.c:2154 2 locks held by kworker/u4:5/7848: #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:40 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:59 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: set_work_data kernel/workqueue.c:617 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: process_one_work+0x87e/0x1790 kernel/workqueue.c:2144 #1: 000000008f76043a (connector_reaper_work){+.+.}, at: process_one_work+0x8b4/0x1790 kernel/workqueue.c:2148 2 locks held by kworker/u4:6/26280: #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:40 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:59 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: set_work_data kernel/workqueue.c:617 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline] #0: 0000000061fe6c95 ((wq_completion)"events_unbound"){+.+.}, at: process_one_work+0x87e/0x1790 kernel/workqueue.c:2144 #1: 000000006ead8b4d ((reaper_work).work){+.+.}, at: process_one_work+0x8b4/0x1790 kernel/workqueue.c:2148 2 locks held by syz-executor.1/27559: #0: 0000000081f30905 (&sb->s_type->i_mutex_key#10){+.+.}, at: inode_lock include/linux/fs.h:763 [inline] #0: 0000000081f30905 (&sb->s_type->i_mutex_key#10){+.+.}, at: __sock_release+0x89/0x2b0 net/socket.c:578 #1: 00000000ea4c4304 (sk_lock-AF_INET6){+.+.}, at: lock_sock include/net/sock.h:1507 [inline] #1: 00000000ea4c4304 (sk_lock-AF_INET6){+.+.}, at: tls_sk_proto_close+0xd6/0x7a0 net/tls/tls_main.c:250 3 locks held by kworker/1:1/2381: #0: 0000000085cdf321 ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: __write_once_size include/linux/compiler.h:220 [inline] #0: 0000000085cdf321 ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: 0000000085cdf321 ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: atomic64_set include/asm-generic/atomic-instrumented.h:40 [inline] #0: 0000000085cdf321 ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: atomic_long_set include/asm-generic/atomic-long.h:59 [inline] #0: 0000000085cdf321 ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: set_work_data kernel/workqueue.c:617 [inline] #0: 0000000085cdf321 ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline] #0: 0000000085cdf321 ((wq_completion)"%s"("ipv6_addrconf")){+.+.}, at: process_one_work+0x87e/0x1790 kernel/workqueue.c:2144 #1: 0000000086fd32ce ((work_completion)(&(&ifa->dad_work)->work)){+.+.}, at: process_one_work+0x8b4/0x1790 kernel/workqueue.c:2148 #2: 000000009dacbee4 (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 net/core/rtnetlink.c:76 1 lock held by syz-executor.3/3371: #0: 000000009dacbee4 (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 net/core/rtnetlink.c:76 1 lock held by syz-executor.1/3416: #0: 000000009dacbee4 (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 net/core/rtnetlink.c:76 1 lock held by syz-executor.1/3430: #0: 000000009dacbee4 (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 net/core/rtnetlink.c:76 1 lock held by syz-executor.5/3426: #0: 000000009dacbee4 (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 net/core/rtnetlink.c:76 1 lock held by syz-executor.5/3427: #0: 000000009dacbee4 (rtnl_mutex){+.+.}, at: rtnl_lock+0x17/0x20 net/core/rtnetlink.c:76 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1040 Comm: khungtaskd Not tainted 5.0.0+ #7 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x172/0x1f0 lib/dump_stack.c:113 nmi_cpu_backtrace.cold+0x63/0xa4 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x1be/0x236 lib/nmi_backtrace.c:62 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline] watchdog+0x9df/0xee0 kernel/hung_task.c:287 kthread+0x357/0x430 kernel/kthread.c:246 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 27202 Comm: kworker/u4:7 Not tainted 5.0.0+ #7 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Workqueue: bat_events batadv_nc_worker RIP: 0010:trace_hardirqs_on+0x59/0x230 kernel/trace/trace_preemptirq.c:30 Code: 65 8b 1d ba 9e 8c 7e 81 e3 00 00 10 00 31 ff 89 de e8 db 96 fa ff 85 db 74 2d e8 52 95 fa ff 65 c7 05 37 2d 8d 7e 00 00 00 00 42 95 fa ff 48 8b 7d 08 e8 a9 0a e2 ff 48 83 c4 08 5b 41 5c 41 RSP: 0018:ffff888091227be0 EFLAGS: 00000093 RAX: ffff8880a86c62c0 RBX: 0000000000000000 RCX: ffffffff81754f25 RDX: 0000000000000000 RSI: ffffffff81754f2e RDI: 0000000000000005 RBP: ffff888091227c10 R08: ffff8880a86c62c0 R09: ffffed100951f3c7 R10: ffffed100951f3c6 R11: ffff88804a8f9e33 R12: ffffffff86ea5d67 R13: ffffffff8706b6e1 R14: ffffffff8144bf4a R15: 0000000000000012 FS: 0000000000000000(0000) GS:ffff8880ae800000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fbfe95e5db8 CR3: 0000000096f78000 CR4: 00000000001426f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600 Call Trace: __local_bh_enable_ip+0x15a/0x270 kernel/softirq.c:194 __raw_spin_unlock_bh include/linux/spinlock_api_smp.h:176 [inline] _raw_spin_unlock_bh+0x31/0x40 kernel/locking/spinlock.c:200 spin_unlock_bh include/linux/spinlock.h:374 [inline] batadv_nc_purge_paths+0x267/0x370 net/batman-adv/network-coding.c:482 batadv_nc_worker+0x2cf/0x760 net/batman-adv/network-coding.c:733 process_one_work+0x98e/0x1790 kernel/workqueue.c:2173 worker_thread+0x98/0xe40 kernel/workqueue.c:2319 kthread+0x357/0x430 kernel/kthread.c:246 ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:352