BTRFS info (device loop5): has skinny extents IPVS: set_ctl: invalid protocol: 51 100.1.1.2:20001 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 INFO: task kworker/1:43:3866 blocked for more than 140 seconds. Not tainted 4.19.211-syzkaller #0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 kworker/1:43 D27800 3866 2 0x80000000 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 Workqueue: usb_hub_wq hub_event hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 schedule+0x8d/0x1b0 kernel/sched/core.c:3561 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 usb_kill_urb.part.0+0x197/0x220 drivers/usb/core/urb.c:697 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 usb_kill_urb+0x7c/0x90 drivers/usb/core/urb.c:692 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 usb_start_wait_urb+0x252/0x4c0 drivers/usb/core/message.c:63 BTRFS warning (device ): duplicate device /dev/loop3 devid 1 generation 8 scanned by syz-executor.3 (3044) hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 BTRFS warning (device ): duplicate device /dev/loop3 devid 1 generation 8 scanned by systemd-udevd (3106) usb_internal_control_msg drivers/usb/core/message.c:101 [inline] usb_control_msg+0x31c/0x4a0 drivers/usb/core/message.c:152 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: unknown main item tag 0x0 hid-generic 0000:0000:0000.004A: hidraw0: HID v0.00 Device [syz1] on %z5 hub_port_init+0x12dc/0x3120 drivers/usb/core/hub.c:4704 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: 1 lock held by khungtaskd/1570: #0: 000000005d124cd3 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441 1 lock held by in:imklog/7812: #0: 00000000b62d1b0d (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 2 locks held by kworker/u4:6/8561: 1 lock held by syz-executor.5/20000: #0: 000000004ea71d9a (rcu_preempt_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:297 [inline] #0: 000000004ea71d9a (rcu_preempt_state.exp_mutex){+.+.}, at: _synchronize_rcu_expedited+0x4dc/0x6f0 kernel/rcu/tree_exp.h:667 2 locks held by kworker/0:2/597: #0: 00000000f5321a95 ((wq_completion)"rcu_gp"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 BTRFS info (device loop3): enabling inode map caching #1: 000000007cda39cb ((work_completion)(&rew.rew_work)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 BTRFS warning (device loop3): excessive commit interval 622039222 5 locks held by kworker/1:43/3866: BTRFS info (device loop3): force zlib compression, level 3 #0: 0000000091b9f506 ((wq_completion)"usb_hub_wq"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 BTRFS info (device loop3): using free space tree #1: 000000008407f5a3 ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 BTRFS info (device loop3): has skinny extents kauditd_printk_skb: 31 callbacks suppressed audit: type=1800 audit(1675937701.039:675): pid=3131 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name="bus" dev="sda1" ino=14375 res=0 #2: 00000000b818c659 (&dev->mutex){....}, at: device_lock include/linux/device.h:1174 [inline] #2: 00000000b818c659 (&dev->mutex){....}, at: hub_event+0x11e/0x44a0 drivers/usb/core/hub.c:5387 #3: 000000003d17b5e9 (&port_dev->status_lock){+.+.}, at: usb_lock_port drivers/usb/core/hub.c:2983 [inline] #3: 000000003d17b5e9 (&port_dev->status_lock){+.+.}, at: hub_port_connect drivers/usb/core/hub.c:5069 [inline] #3: 000000003d17b5e9 (&port_dev->status_lock){+.+.}, at: hub_port_connect_change drivers/usb/core/hub.c:5253 [inline] #3: 000000003d17b5e9 (&port_dev->status_lock){+.+.}, at: port_event drivers/usb/core/hub.c:5361 [inline] #3: 000000003d17b5e9 (&port_dev->status_lock){+.+.}, at: hub_event+0x1cb8/0x44a0 drivers/usb/core/hub.c:5441 #4: 00000000e68f0e72 (hcd->address0_mutex){+.+.}, at: hub_port_init+0x1b9/0x3120 drivers/usb/core/hub.c:4578 1 lock held by syz-executor.0/3131: #0: 0000000077b65a26 (&type->s_umount_key#65){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631 audit: type=1800 audit(1675937701.119:676): pid=3129 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=14366 res=0 1 lock held by syz-executor.0/3156: #0: 0000000077b65a26 (&type->s_umount_key#65){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631 1 lock held by syz-executor.0/3164: #0: 00000000730166e1 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1826 [inline] #0: 00000000730166e1 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455 audit: type=1804 audit(1675937701.189:677): pid=3164 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.0" name="/root/syzkaller-testdir579112318/syzkaller.Isx06s/4487/bus" dev="sda1" ino=14375 res=1 1 lock held by syz-executor.3/3128: hid-generic 0000:0000:0000.004B: unknown main item tag 0x0 hid-generic 0000:0000:0000.004B: unknown main item tag 0x0 audit: type=1804 audit(1675937701.249:678): pid=3129 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir749275826/syzkaller.jKgach/3144/bus" dev="sda1" ino=14366 res=1 audit: type=1800 audit(1675937701.309:679): pid=3137 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.2" name="bus" dev="sda1" ino=14377 res=0 hid-generic 0000:0000:0000.004B: unknown main item tag 0x0 audit: type=1804 audit(1675937701.349:680): pid=3170 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="syz-executor.1" name="/root/syzkaller-testdir749275826/syzkaller.jKgach/3144/bus" dev="sda1" ino=14366 res=1 #0: 000000004ea71d9a (rcu_preempt_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:329 [inline] #0: 000000004ea71d9a (rcu_preempt_state.exp_mutex){+.+.}, at: _synchronize_rcu_expedited+0x256/0x6f0 kernel/rcu/tree_exp.h:667 hid-generic 0000:0000:0000.004B: unknown main item tag 0x0 hid-generic 0000:0000:0000.004B: unknown main item tag 0x0 ============================================= hid-generic 0000:0000:0000.004B: unknown main item tag 0x0 NMI backtrace for cpu 1 CPU: 1 PID: 1570 Comm: khungtaskd Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1fc/0x2ef lib/dump_stack.c:118 hid-generic 0000:0000:0000.004B: unknown main item tag 0x0 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 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 10 Comm: rcu_preempt Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 RIP: 0010:__sanitizer_cov_trace_pc+0x0/0x50 kernel/kcov.c:97 Code: ff 48 89 df e8 11 dd 35 00 e9 ab fe ff ff 4c 89 ef e8 04 dd 35 00 e9 23 fe ff ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 <48> 8b 34 24 65 48 8b 04 25 c0 df 01 00 65 8b 15 cc 59 9f 7e 81 e2 RSP: 0018:ffff8880b5a67bf0 EFLAGS: 00000246 RAX: 0000000000000000 RBX: 0000000000000000 RCX: ffffffff815315af RDX: 0000000000000000 RSI: ffff8880b5a4e280 RDI: 0000000000000005 RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000005 R11: 0000000000000000 R12: ffff8880b5a67cc0 R13: ffff8880b5a67cc0 R14: ffffed1016b4cf9c R15: ffff8880b5a67e80 FS: 0000000000000000(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 0000001b2f947000 CR3: 00000000a0def000 CR4: 00000000003426f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: get_timer_base kernel/time/timer.c:875 [inline] lock_timer_base+0xf8/0x1b0 kernel/time/timer.c:949 __mod_timer kernel/time/timer.c:1022 [inline] schedule_timeout+0x6dc/0xfe0 kernel/time/timer.c:1817 rcu_gp_kthread+0xdad/0x21c0 kernel/rcu/tree.c:2202 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415