INFO: task kworker/1:8:14156 blocked for more than 140 seconds. Not tainted 4.19.211-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:8 D27040 14156 2 0x80000000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 schedule+0x8d/0x1b0 kernel/sched/core.c:3561 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 usb_get_descriptor+0xc5/0x1b0 drivers/usb/core/message.c:654 usb_get_device_descriptor+0x81/0xf0 drivers/usb/core/message.c:926 hub_port_init+0x74b/0x3120 drivers/usb/core/hub.c:4799 hub_port_connect drivers/usb/core/hub.c:5070 [inline] hub_port_connect_change drivers/usb/core/hub.c:5253 [inline] port_event drivers/usb/core/hub.c:5361 [inline] hub_event+0x1ccd/0x44a0 drivers/usb/core/hub.c:5441 process_one_work+0x864/0x1570 kernel/workqueue.c:2153 worker_thread+0x64c/0x1130 kernel/workqueue.c:2296 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Showing all locks held in the system: 2 locks held by kworker/u4:1/23: 1 lock held by khungtaskd/1570: #0: 00000000e152627d (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441 1 lock held by in:imklog/7830: #0: 00000000e63f6bc1 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 2 locks held by agetty/8054: #0: 0000000041a1cfb0 (&tty->ldisc_sem){++++}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:272 #1: 000000009cda715b (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x217/0x1950 drivers/tty/n_tty.c:2154 1 lock held by syz-executor.3/8188: #0: 0000000075bba218 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 0000000075bba218 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 4 locks held by kworker/u4:7/8880: 2 locks held by kworker/u4:8/13975: #0: 00000000a61f7fa1 ((wq_completion)"events_unbound"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 #1: 00000000e3dd142e ((work_completion)(&sub_info->work)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 5 locks held by kworker/1:8/14156: #0: 00000000877cd0c3 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 #1: 000000002dd5aff0 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 #2: 00000000ec1b087f (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline] #2: 00000000ec1b087f (&dev->mutex){....}, at: hub_event+0x11e/0x44a0 drivers/usb/core/hub.c:5387 #3: 00000000d48514f4 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2983 [inline] #3: 00000000d48514f4 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5069 [inline] #3: 00000000d48514f4 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5253 [inline] #3: 00000000d48514f4 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5361 [inline] #3: 00000000d48514f4 (&port_dev->status_lock){+.+.}, at: hub_event+0x1cb8/0x44a0 drivers/usb/core/hub.c:5441 #4: 00000000def334f1 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b9/0x3120 drivers/usb/core/hub.c:4578 3 locks held by syz-executor.2/27524: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000fb0747d1 (&cfs_rq->removed.lock){-.-.}, at: update_cfs_rq_load_avg kernel/sched/fair.c:3490 [inline] #1: 00000000fb0747d1 (&cfs_rq->removed.lock){-.-.}, at: update_load_avg kernel/sched/fair.c:3612 [inline] #1: 00000000fb0747d1 (&cfs_rq->removed.lock){-.-.}, at: dequeue_entity+0x52d/0x2800 kernel/sched/fair.c:4058 #2: 0000000075bba218 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #2: 0000000075bba218 (&rq->lock){-.-.}, at: ttwu_queue kernel/sched/core.c:1841 [inline] #2: 0000000075bba218 (&rq->lock){-.-.}, at: try_to_wake_up+0x6d0/0x1050 kernel/sched/core.c:2052 1 lock held by syz-executor.2/27547: #0: 0000000075bba218 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 0000000075bba218 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 1 lock held by syz-executor.5/27557: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 2 locks held by syz-executor.5/27573: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000e152627d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000e152627d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 1 lock held by syz-executor.0/27591: #0: 0000000075bba218 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 0000000075bba218 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 3 locks held by syz-executor.2/27600: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000e152627d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000e152627d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 #2: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #2: 00000000343d8fcf (&rq->lock){-.-.}, at: ttwu_queue kernel/sched/core.c:1841 [inline] #2: 00000000343d8fcf (&rq->lock){-.-.}, at: try_to_wake_up+0x6d0/0x1050 kernel/sched/core.c:2052 3 locks held by syz-executor.4/27631: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000e152627d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000e152627d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 #2: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #2: 00000000343d8fcf (&rq->lock){-.-.}, at: ttwu_queue kernel/sched/core.c:1841 [inline] #2: 00000000343d8fcf (&rq->lock){-.-.}, at: try_to_wake_up+0x6d0/0x1050 kernel/sched/core.c:2052 1 lock held by syz-executor.3/27652: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 2 locks held by syz-executor.5/27663: 2 locks held by syz-executor.0/27664: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000e152627d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000e152627d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 2 locks held by syz-executor.3/27683: 2 locks held by syz-executor.0/27684: 2 locks held by syz-executor.2/27689: 2 locks held by syz-executor.1/27690: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000e152627d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000e152627d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 3 locks held by syz-executor.3/27707: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000e152627d (rcu_read_lock){....}, at: lock_page_memcg+0x0/0x220 include/linux/page_counter.h:64 #2: 0000000071c4aea6 (&sig->wait_chldexit){....}, at: __wake_up_common_lock+0xb0/0x170 kernel/sched/wait.c:120 3 locks held by syz-executor.1/27730: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 #1: 00000000e152627d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000e152627d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 #2: 0000000075bba218 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #2: 0000000075bba218 (&rq->lock){-.-.}, at: ttwu_queue kernel/sched/core.c:1841 [inline] #2: 0000000075bba218 (&rq->lock){-.-.}, at: try_to_wake_up+0x6d0/0x1050 kernel/sched/core.c:2052 1 lock held by syz-executor.5/27751: #0: 0000000075bba218 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 0000000075bba218 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 2 locks held by syz-executor.3/27766: #0: 00000000343d8fcf (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000343d8fcf (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 sctp: [Deprecated]: syz-executor.5 (pid 27778) Use of int in max_burst socket option. Use struct sctp_assoc_value instead #1: 00000000e152627d (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline] #1: 00000000e152627d (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1570 Comm: khungtaskd Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1fc/0x2ef lib/dump_stack.c:118 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline] watchdog+0x991/0xe60 kernel/hung_task.c:287 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 18 Comm: ksoftirqd/1 Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022 RIP: 0010:get_current arch/x86/include/asm/current.h:15 [inline] RIP: 0010:lock_is_held_type+0x11/0x210 kernel/locking/lockdep.c:3939 Code: 00 e9 41 ff ff ff e8 0e ad 4d 00 eb ad 66 66 2e 0f 1f 84 00 00 00 00 00 90 48 b8 00 00 00 00 00 fc ff df 41 54 55 48 89 fd 53 <65> 48 8b 1c 25 c0 df 01 00 48 8d bb 84 08 00 00 48 83 ec 08 48 89 RSP: 0018:ffff8880b5acf9d0 EFLAGS: 00000202 RAX: dffffc0000000000 RBX: 0000000000000003 RCX: 0000000000000000 RDX: 1ffffffff148be2c RSI: 00000000ffffffff RDI: ffffffff89f85f20 RBP: ffffffff89f85f20 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000005 R11: 0000000000000000 R12: ffff88809bf6f224 R13: 000000000000fb88 R14: ffffffff8676a635 R15: ffff88809bf6f150 FS: 0000000000000000(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f19c29a7988 CR3: 00000000ab267000 CR4: 00000000003426e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_is_held include/linux/lockdep.h:344 [inline] rcu_read_lock_sched_held+0x16c/0x1d0 kernel/rcu/update.c:118 trace_kfree_skb include/trace/events/skb.h:15 [inline] kfree_skb+0x32e/0x3d0 net/core/skbuff.c:671 __netif_receive_skb_core+0x535/0x3270 net/core/dev.c:4928 __netif_receive_skb_one_core+0xae/0x180 net/core/dev.c:4952 __netif_receive_skb+0x27/0x1c0 net/core/dev.c:5066 process_backlog+0x241/0x700 net/core/dev.c:5849 napi_poll net/core/dev.c:6280 [inline] net_rx_action+0x4ac/0xfb0 net/core/dev.c:6346 __do_softirq+0x265/0x980 kernel/softirq.c:292 run_ksoftirqd+0x57/0x110 kernel/softirq.c:653 smpboot_thread_fn+0x655/0x9e0 kernel/smpboot.c:164 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415