INFO: task syz-executor.2:25198 can't die for more than 143 seconds. task:syz-executor.2 state:D stack:28544 pid:25198 ppid: 8527 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:3774 [inline] __schedule+0x893/0x2170 kernel/sched/core.c:4523 schedule+0xcf/0x270 kernel/sched/core.c:4601 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:4660 __mutex_lock_common kernel/locking/mutex.c:1033 [inline] __mutex_lock+0x81a/0x1110 kernel/locking/mutex.c:1103 device_lock include/linux/device.h:731 [inline] usbdev_open+0x19d/0x930 drivers/usb/core/devio.c:1029 chrdev_open+0x266/0x770 fs/char_dev.c:414 do_dentry_open+0x4b9/0x11b0 fs/open.c:817 do_open fs/namei.c:3252 [inline] path_openat+0x1b9a/0x2730 fs/namei.c:3369 do_filp_open+0x17e/0x3c0 fs/namei.c:3396 do_sys_openat2+0x16d/0x420 fs/open.c:1168 do_sys_open fs/open.c:1184 [inline] __do_sys_open fs/open.c:1192 [inline] __se_sys_open fs/open.c:1188 [inline] __x64_sys_open+0x119/0x1c0 fs/open.c:1188 do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46 entry_SYSCALL_64_after_hwframe+0x44/0xa9 RIP: 0033:0x4179b1 Code: Unable to access opcode bytes at RIP 0x417987. RSP: 002b:00007f7230a1e820 EFLAGS: 00000293 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 6666666666666667 RCX: 00000000004179b1 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f7230a1e850 RBP: 000000000118bf60 R08: 000000000000000f R09: 0000000000000000 R10: 00007f7230a1f9d0 R11: 0000000000000293 R12: 000000000118bf2c R13: 00007ffd7777f8ef R14: 00007f7230a1f9c0 R15: 000000000118bf2c INFO: task syz-executor.2:25198 blocked for more than 143 seconds. Not tainted 5.10.0-rc2-next-20201104-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.2 state:D stack:28544 pid:25198 ppid: 8527 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:3774 [inline] __schedule+0x893/0x2170 kernel/sched/core.c:4523 schedule+0xcf/0x270 kernel/sched/core.c:4601 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:4660 __mutex_lock_common kernel/locking/mutex.c:1033 [inline] __mutex_lock+0x81a/0x1110 kernel/locking/mutex.c:1103 device_lock include/linux/device.h:731 [inline] usbdev_open+0x19d/0x930 drivers/usb/core/devio.c:1029 chrdev_open+0x266/0x770 fs/char_dev.c:414 do_dentry_open+0x4b9/0x11b0 fs/open.c:817 do_open fs/namei.c:3252 [inline] path_openat+0x1b9a/0x2730 fs/namei.c:3369 do_filp_open+0x17e/0x3c0 fs/namei.c:3396 do_sys_openat2+0x16d/0x420 fs/open.c:1168 do_sys_open fs/open.c:1184 [inline] __do_sys_open fs/open.c:1192 [inline] __se_sys_open fs/open.c:1188 [inline] __x64_sys_open+0x119/0x1c0 fs/open.c:1188 do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46 entry_SYSCALL_64_after_hwframe+0x44/0xa9 RIP: 0033:0x4179b1 Code: Unable to access opcode bytes at RIP 0x417987. RSP: 002b:00007f7230a1e820 EFLAGS: 00000293 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 6666666666666667 RCX: 00000000004179b1 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f7230a1e850 RBP: 000000000118bf60 R08: 000000000000000f R09: 0000000000000000 R10: 00007f7230a1f9d0 R11: 0000000000000293 R12: 000000000118bf2c R13: 00007ffd7777f8ef R14: 00007f7230a1f9c0 R15: 000000000118bf2c Showing all locks held in the system: 3 locks held by kworker/u4:6/245: #0: ffff8880b9e34d18 (&rq->lock){-.-.}-{2:2}, at: newidle_balance+0x789/0xe50 kernel/sched/fair.c:10576 #1: ffff8880b9e1ff88 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x305/0x440 kernel/sched/psi.c:833 #2: ffff8880b9e246d8 (&base->lock){-.-.}-{2:2}, at: lock_timer_base+0x5a/0x1f0 kernel/time/timer.c:944 1 lock held by khungtaskd/1659: #0: ffffffff8b338160 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6253 1 lock held by in:imklog/8202: #0: ffff88801d15eaf0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:932 1 lock held by rs:main Q:Reg/8203: #0: ffff8880b9e34d18 (&rq->lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1292 [inline] #0: ffff8880b9e34d18 (&rq->lock){-.-.}-{2:2}, at: __schedule+0x21a/0x2170 kernel/sched/core.c:4441 5 locks held by kworker/0:6/13525: #0: ffff888014ef0538 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: ffff888014ef0538 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic64_set include/asm-generic/atomic-instrumented.h:856 [inline] #0: ffff888014ef0538 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic_long_set include/asm-generic/atomic-long.h:41 [inline] #0: ffff888014ef0538 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:616 [inline] #0: ffff888014ef0538 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:643 [inline] #0: ffff888014ef0538 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x821/0x15a0 kernel/workqueue.c:2243 #1: ffffc9000256fda8 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x854/0x15a0 kernel/workqueue.c:2247 #2: ffff8881444d6218 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:731 [inline] #2: ffff8881444d6218 (&dev->mutex){....}-{3:3}, at: hub_event+0x1c5/0x42d0 drivers/usb/core/hub.c:5537 #3: ffff88801c862588 (&port_dev->status_lock){+.+.}-{3:3}, at: usb_lock_port drivers/usb/core/hub.c:3030 [inline] #3: ffff88801c862588 (&port_dev->status_lock){+.+.}-{3:3}, at: hub_port_connect drivers/usb/core/hub.c:5154 [inline] #3: ffff88801c862588 (&port_dev->status_lock){+.+.}-{3:3}, at: hub_port_connect_change drivers/usb/core/hub.c:5363 [inline] #3: ffff88801c862588 (&port_dev->status_lock){+.+.}-{3:3}, at: port_event drivers/usb/core/hub.c:5509 [inline] #3: ffff88801c862588 (&port_dev->status_lock){+.+.}-{3:3}, at: hub_event+0x216c/0x42d0 drivers/usb/core/hub.c:5591 #4: ffff88801c698f68 (hcd->address0_mutex){+.+.}-{3:3}, at: hub_port_init+0x1b2/0x2dd0 drivers/usb/core/hub.c:4582 1 lock held by syz-executor.2/25198: #0: ffff8881444d6218 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:731 [inline] #0: ffff8881444d6218 (&dev->mutex){....}-{3:3}, at: usbdev_open+0x19d/0x930 drivers/usb/core/devio.c:1029 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1659 Comm: khungtaskd Not tainted 5.10.0-rc2-next-20201104-syzkaller #0 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+0x107/0x163 lib/dump_stack.c:118 nmi_cpu_backtrace.cold+0x44/0xd7 lib/nmi_backtrace.c:105 nmi_trigger_cpumask_backtrace+0x1b3/0x230 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:147 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:253 [inline] watchdog+0xd89/0xf30 kernel/hung_task.c:338 kthread+0x3af/0x4a0 kernel/kthread.c:292 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:296 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 8519 Comm: syz-fuzzer Not tainted 5.10.0-rc2-next-20201104-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 RIP: 0010:unwind_next_frame+0x9a7/0x1f90 arch/x86/kernel/unwind_orc.c:521 Code: 85 17 13 00 00 83 e0 07 40 38 c6 40 0f 9e c7 40 84 f6 0f 95 c0 40 84 c7 0f 85 fe 12 00 00 49 0f bf 00 48 01 d0 48 89 44 24 60 1c fd ff ff 48 b8 00 00 00 00 00 fc ff df 4c 89 f2 48 c1 ea 03 RSP: 0018:ffffc900017de918 EFLAGS: 00000282 RAX: ffffc900017dff58 RBX: 1ffff920002fbd2b RCX: ffffffff8df5e0db RDX: ffffc900017dff48 RSI: 0000000000000000 RDI: 0000000000000001 RBP: 0000000000000001 R08: ffffffff8df5e0d6 R09: ffffffff8df5e0da R10: 0000000000082081 R11: 0000000000000001 R12: ffffc900017dea38 R13: ffffc900017dea25 R14: ffffc900017dea40 R15: ffffc900017de9f0 FS: 000000c00003f210(0000) GS:ffff8880b9e00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f98facbc000 CR3: 000000001c712000 CR4: 00000000001506f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600 Call Trace: arch_stack_walk+0x7d/0xe0 arch/x86/kernel/stacktrace.c:25 stack_trace_save+0x8c/0xc0 kernel/stacktrace.c:121 kasan_save_stack+0x1b/0x40 mm/kasan/common.c:48 kasan_set_track+0x1c/0x30 mm/kasan/common.c:56 kasan_set_free_info+0x1b/0x30 mm/kasan/generic.c:355 __kasan_slab_free+0x102/0x140 mm/kasan/common.c:422 slab_free_hook mm/slub.c:1544 [inline] slab_free_freelist_hook+0x5d/0x150 mm/slub.c:1577 slab_free mm/slub.c:3140 [inline] kmem_cache_free+0x82/0x350 mm/slub.c:3156 kfree_skbmem+0xef/0x1b0 net/core/skbuff.c:627 __kfree_skb net/core/skbuff.c:684 [inline] consume_skb net/core/skbuff.c:839 [inline] consume_skb+0xcf/0x160 net/core/skbuff.c:833 __dev_kfree_skb_any+0x6b/0x80 net/core/dev.c:3109 dev_consume_skb_any include/linux/netdevice.h:3833 [inline] napi_consume_skb+0x260/0x390 net/core/skbuff.c:901 free_old_xmit_skbs+0xe4/0x270 drivers/net/virtio_net.c:1394 start_xmit+0x123/0x1690 drivers/net/virtio_net.c:1590 __netdev_start_xmit include/linux/netdevice.h:4744 [inline] netdev_start_xmit include/linux/netdevice.h:4758 [inline] xmit_one net/core/dev.c:3564 [inline] dev_hard_start_xmit+0x1eb/0x920 net/core/dev.c:3580 sch_direct_xmit+0x2e1/0xbd0 net/sched/sch_generic.c:313 qdisc_restart net/sched/sch_generic.c:376 [inline] __qdisc_run+0x4ba/0x15e0 net/sched/sch_generic.c:384 qdisc_run include/net/pkt_sched.h:131 [inline] qdisc_run include/net/pkt_sched.h:123 [inline] __dev_xmit_skb net/core/dev.c:3755 [inline] __dev_queue_xmit+0x1453/0x2da0 net/core/dev.c:4108 neigh_hh_output include/net/neighbour.h:498 [inline] neigh_output include/net/neighbour.h:507 [inline] ip_finish_output2+0xe95/0x2120 net/ipv4/ip_output.c:230 __ip_finish_output net/ipv4/ip_output.c:308 [inline] __ip_finish_output+0x7cf/0xd10 net/ipv4/ip_output.c:290 ip_finish_output+0x34/0x1f0 net/ipv4/ip_output.c:318 NF_HOOK_COND include/linux/netfilter.h:290 [inline] ip_output+0x196/0x310 net/ipv4/ip_output.c:432 dst_output include/net/dst.h:441 [inline] ip_local_out net/ipv4/ip_output.c:126 [inline] __ip_queue_xmit+0x8e9/0x1a00 net/ipv4/ip_output.c:532 __tcp_transmit_skb+0x18cc/0x3760 net/ipv4/tcp_output.c:1404 __tcp_send_ack.part.0+0x3d6/0x750 net/ipv4/tcp_output.c:3967 __tcp_send_ack net/ipv4/tcp_output.c:3973 [inline] tcp_send_ack+0x7d/0xa0 net/ipv4/tcp_output.c:3973 __tcp_ack_snd_check+0x156/0x9c0 net/ipv4/tcp_input.c:5400 tcp_ack_snd_check net/ipv4/tcp_input.c:5445 [inline] tcp_rcv_established+0x92a/0x1eb0 net/ipv4/tcp_input.c:5870 tcp_v4_do_rcv+0x5d1/0x870 net/ipv4/tcp_ipv4.c:1652 sk_backlog_rcv include/net/sock.h:1010 [inline] __release_sock+0x134/0x3a0 net/core/sock.c:2523 release_sock+0x54/0x1b0 net/core/sock.c:3053 tcp_recvmsg+0x8ca/0x2aa0 net/ipv4/tcp.c:2253 inet_recvmsg+0x11b/0x5d0 net/ipv4/af_inet.c:848 sock_recvmsg_nosec net/socket.c:885 [inline] sock_recvmsg net/socket.c:903 [inline] sock_recvmsg net/socket.c:899 [inline] sock_read_iter+0x33c/0x470 net/socket.c:976 call_read_iter include/linux/fs.h:1880 [inline] new_sync_read+0x5b7/0x6e0 fs/read_write.c:415 vfs_read+0x35c/0x570 fs/read_write.c:496 ksys_read+0x1ee/0x250 fs/read_write.c:634 do_syscall_64+0x2d/0x70 arch/x86/entry/common.c:46 entry_SYSCALL_64_after_hwframe+0x44/0xa9 RIP: 0033:0x4b3d8b Code: ff e9 69 ff ff ff cc cc cc cc cc cc cc cc cc e8 db a1 f8 ff 48 8b 7c 24 10 48 8b 74 24 18 48 8b 54 24 20 48 8b 44 24 08 0f 05 <48> 3d 01 f0 ff ff 76 20 48 c7 44 24 28 ff ff ff ff 48 c7 44 24 30 RSP: 002b:000000c00034f920 EFLAGS: 00000206 ORIG_RAX: 0000000000000000 RAX: ffffffffffffffda RBX: 000000c00002c000 RCX: 00000000004b3d8b RDX: 0000000000001000 RSI: 000000c0002c8000 RDI: 0000000000000006 RBP: 000000c00034f970 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000206 R12: 000000000000011e R13: 000000c000246000 R14: 0000000000000007 R15: 00000000000089ac