imon 4-1:0.0: imon usb_rx_callback_intf0: status(-71): ignored
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
rcu: INFO: rcu_preempt self-detected stall on CPU
rcu: 1-....: (1 GPs behind) idle=be2c/1/0x4000000000000000 softirq=92058/92058 fqs=5247
(t=10500 jiffies g=132497 q=5655 ncpus=2)
NMI backtrace for cpu 1
CPU: 1 PID: 1179 Comm: udevd Not tainted 5.19.0-syzkaller-13666-gffcf9c5700e4 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:106
nmi_cpu_backtrace.cold+0x46/0x14f lib/nmi_backtrace.c:111
nmi_trigger_cpumask_backtrace+0x206/0x250 lib/nmi_backtrace.c:62
trigger_single_cpu_backtrace include/linux/nmi.h:166 [inline]
rcu_dump_cpu_stacks+0x2c7/0x4d0 kernel/rcu/tree_stall.h:371
print_cpu_stall kernel/rcu/tree_stall.h:660 [inline]
check_cpu_stall kernel/rcu/tree_stall.h:744 [inline]
rcu_pending kernel/rcu/tree.c:3660 [inline]
rcu_sched_clock_irq.cold+0x9f/0x79c kernel/rcu/tree.c:2342
update_process_times+0x11a/0x1a0 kernel/time/timer.c:1839
tick_sched_handle+0x9b/0x180 kernel/time/tick-sched.c:243
tick_sched_timer+0xee/0x120 kernel/time/tick-sched.c:1480
__run_hrtimer kernel/time/hrtimer.c:1685 [inline]
__hrtimer_run_queues+0x1c0/0xe40 kernel/time/hrtimer.c:1749
hrtimer_interrupt+0x31c/0x790 kernel/time/hrtimer.c:1811
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
__sysvec_apic_timer_interrupt+0x146/0x530 arch/x86/kernel/apic/apic.c:1112
sysvec_apic_timer_interrupt+0x3b/0xc0 arch/x86/kernel/apic/apic.c:1106
asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:152 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0x38/0x70 kernel/locking/spinlock.c:194
Code: 74 24 10 e8 2a ae 34 fb 48 89 ef e8 b2 2f 35 fb 81 e3 00 02 00 00 75 25 9c 58 f6 c4 02 75 2d 48 85 db 74 01 fb bf 01 00 00 00 93 8c 2a fb 65 8b 05 1c 01 0d 7a 85 c0 74 0a 5b 5d c3 e8 90 08
RSP: 0018:ffffc90000178b28 EFLAGS: 00000206
RAX: 0000000000000006 RBX: 0000000000000200 RCX: 1ffffffff1615e5e
RDX: 0000000000000000 RSI: 0000000000000102 RDI: 0000000000000001
RBP: ffff88810f218000 R08: 0000000000000001 R09: ffffffff8b07375f
R10: 0000000000000001 R11: 0000000000000001 R12: 0000000000000000
R13: ffff88810f218040 R14: dffffc0000000000 R15: ffff8881392f4100
spin_unlock_irqrestore include/linux/spinlock.h:404 [inline]
dummy_timer+0x12fc/0x32c0 drivers/usb/gadget/udc/dummy_hcd.c:2002
call_timer_fn+0x1a0/0x6b0 kernel/time/timer.c:1474
expire_timers kernel/time/timer.c:1519 [inline]
__run_timers.part.0+0x674/0xa80 kernel/time/timer.c:1790
__run_timers kernel/time/timer.c:1768 [inline]
run_timer_softirq+0xb3/0x1d0 kernel/time/timer.c:1803
__do_softirq+0x1c0/0x9a9 kernel/softirq.c:571
do_softirq.part.0+0xde/0x130 kernel/softirq.c:472
do_softirq kernel/softirq.c:464 [inline]
__local_bh_enable_ip+0x102/0x120 kernel/softirq.c:396
lock_sock include/net/sock.h:1659 [inline]
netlink_insert+0xdd/0x1690 net/netlink/af_netlink.c:577
netlink_autobind.isra.0+0x305/0x560 net/netlink/af_netlink.c:845
netlink_bind+0x6ce/0x9d0 net/netlink/af_netlink.c:1050
__sys_bind+0x1e9/0x250 net/socket.c:1776
__do_sys_bind net/socket.c:1787 [inline]
__se_sys_bind net/socket.c:1785 [inline]
__x64_sys_bind+0x6f/0xb0 net/socket.c:1785
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7f9e18eb2ce7
================================
WARNING: inconsistent lock state
5.19.0-syzkaller-13666-gffcf9c5700e4 #0 Not tainted
--------------------------------
inconsistent {HARDIRQ-ON-W} -> {IN-HARDIRQ-W} usage.
udevd/1179 [HC1[1]:SC1[1]:HE0:SE0] takes:
ffffffff87b854d8 (vmap_area_lock){?.+.}-{2:2}, at: spin_lock include/linux/spinlock.h:349 [inline]
ffffffff87b854d8 (vmap_area_lock){?.+.}-{2:2}, at: find_vmap_area+0x1c/0x130 mm/vmalloc.c:1836
{HARDIRQ-ON-W} state was registered at:
lock_acquire kernel/locking/lockdep.c:5666 [inline]
lock_acquire+0x1ab/0x570 kernel/locking/lockdep.c:5631
__raw_spin_lock include/linux/spinlock_api_smp.h:133 [inline]
_raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:154
spin_lock include/linux/spinlock.h:349 [inline]
alloc_vmap_area+0xa0b/0x1d50 mm/vmalloc.c:1617
__get_vm_area_node+0x142/0x3f0 mm/vmalloc.c:2484
get_vm_area_caller+0x43/0x50 mm/vmalloc.c:2537
__ioremap_caller.constprop.0+0x292/0x600 arch/x86/mm/ioremap.c:280
acpi_os_ioremap include/acpi/acpi_io.h:13 [inline]
acpi_map drivers/acpi/osl.c:296 [inline]
acpi_os_map_iomem+0x463/0x550 drivers/acpi/osl.c:355
acpi_tb_acquire_table+0xd8/0x209 drivers/acpi/acpica/tbdata.c:142
acpi_tb_validate_table drivers/acpi/acpica/tbdata.c:317 [inline]
acpi_tb_validate_table+0x50/0x8c drivers/acpi/acpica/tbdata.c:308
acpi_tb_verify_temp_table+0x84/0x674 drivers/acpi/acpica/tbdata.c:504
acpi_reallocate_root_table+0x374/0x3e0 drivers/acpi/acpica/tbxface.c:180
acpi_early_init+0x13a/0x438 drivers/acpi/bus.c:1214
start_kernel+0x3cf/0x48f init/main.c:1099
secondary_startup_64_no_verify+0xce/0xdb
irq event stamp: 22571301
hardirqs last enabled at (22571300): [] __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:151 [inline]
hardirqs last enabled at (22571300): [] _raw_spin_unlock_irqrestore+0x50/0x70 kernel/locking/spinlock.c:194
hardirqs last disabled at (22571301): [] sysvec_apic_timer_interrupt+0xb/0xc0 arch/x86/kernel/apic/apic.c:1106
softirqs last enabled at (22510064): [] lock_sock include/net/sock.h:1659 [inline]
softirqs last enabled at (22510064): [] netlink_insert+0xdd/0x1690 net/netlink/af_netlink.c:577
softirqs last disabled at (22510065): [] do_softirq.part.0+0xde/0x130 kernel/softirq.c:472
other info that might help us debug this:
Possible unsafe locking scenario:
CPU0
----
lock(vmap_area_lock);
lock(vmap_area_lock);
*** DEADLOCK ***
3 locks held by udevd/1179:
#0: ffff88810b12a130 (sk_lock-AF_NETLINK){+.+.}-{0:0}, at: lock_sock include/net/sock.h:1659 [inline]
#0: ffff88810b12a130 (sk_lock-AF_NETLINK){+.+.}-{0:0}, at: netlink_insert+0xdd/0x1690 net/netlink/af_netlink.c:577
#1: ffffc90000178d90 ((&dum_hcd->timer)){+.-.}-{0:0}, at: lockdep_copy_map include/linux/lockdep.h:31 [inline]
#1: ffffc90000178d90 ((&dum_hcd->timer)){+.-.}-{0:0}, at: call_timer_fn+0xd5/0x6b0 kernel/time/timer.c:1464
#2: ffffffff87a9e998 (rcu_node_0){-.-.}-{2:2}, at: rcu_dump_cpu_stacks+0xc1/0x4d0 kernel/rcu/tree_stall.h:366
stack backtrace:
CPU: 1 PID: 1179 Comm: udevd Not tainted 5.19.0-syzkaller-13666-gffcf9c5700e4 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:106
print_usage_bug kernel/locking/lockdep.c:3961 [inline]
valid_state kernel/locking/lockdep.c:3973 [inline]
mark_lock_irq kernel/locking/lockdep.c:4176 [inline]
mark_lock.part.0.cold+0x18/0xd8 kernel/locking/lockdep.c:4632
mark_lock kernel/locking/lockdep.c:4596 [inline]
mark_usage kernel/locking/lockdep.c:4524 [inline]
__lock_acquire+0x14a2/0x56d0 kernel/locking/lockdep.c:5007
lock_acquire kernel/locking/lockdep.c:5666 [inline]
lock_acquire+0x1ab/0x570 kernel/locking/lockdep.c:5631
__raw_spin_lock include/linux/spinlock_api_smp.h:133 [inline]
_raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:154
spin_lock include/linux/spinlock.h:349 [inline]
find_vmap_area+0x1c/0x130 mm/vmalloc.c:1836
check_heap_object mm/usercopy.c:176 [inline]
__check_object_size mm/usercopy.c:250 [inline]
__check_object_size+0x1f8/0x700 mm/usercopy.c:212
check_object_size include/linux/thread_info.h:199 [inline]
__copy_from_user_inatomic include/linux/uaccess.h:62 [inline]
copy_from_user_nmi arch/x86/lib/usercopy.c:47 [inline]
copy_from_user_nmi+0xcb/0x130 arch/x86/lib/usercopy.c:31
copy_code arch/x86/kernel/dumpstack.c:91 [inline]
show_opcodes+0x59/0xb0 arch/x86/kernel/dumpstack.c:121
show_iret_regs+0xd/0x33 arch/x86/kernel/dumpstack.c:149
__show_regs+0x1e/0x60 arch/x86/kernel/process_64.c:74
show_trace_log_lvl+0x25b/0x2ba arch/x86/kernel/dumpstack.c:292
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:106
nmi_cpu_backtrace.cold+0x46/0x14f lib/nmi_backtrace.c:111
nmi_trigger_cpumask_backtrace+0x206/0x250 lib/nmi_backtrace.c:62
trigger_single_cpu_backtrace include/linux/nmi.h:166 [inline]
rcu_dump_cpu_stacks+0x2c7/0x4d0 kernel/rcu/tree_stall.h:371
print_cpu_stall kernel/rcu/tree_stall.h:660 [inline]
check_cpu_stall kernel/rcu/tree_stall.h:744 [inline]
rcu_pending kernel/rcu/tree.c:3660 [inline]
rcu_sched_clock_irq.cold+0x9f/0x79c kernel/rcu/tree.c:2342
update_process_times+0x11a/0x1a0 kernel/time/timer.c:1839
tick_sched_handle+0x9b/0x180 kernel/time/tick-sched.c:243
tick_sched_timer+0xee/0x120 kernel/time/tick-sched.c:1480
__run_hrtimer kernel/time/hrtimer.c:1685 [inline]
__hrtimer_run_queues+0x1c0/0xe40 kernel/time/hrtimer.c:1749
hrtimer_interrupt+0x31c/0x790 kernel/time/hrtimer.c:1811
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1095 [inline]
__sysvec_apic_timer_interrupt+0x146/0x530 arch/x86/kernel/apic/apic.c:1112
sysvec_apic_timer_interrupt+0x3b/0xc0 arch/x86/kernel/apic/apic.c:1106
asm_sysvec_apic_timer_interrupt+0x16/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:152 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0x38/0x70 kernel/locking/spinlock.c:194
Code: 74 24 10 e8 2a ae 34 fb 48 89 ef e8 b2 2f 35 fb 81 e3 00 02 00 00 75 25 9c 58 f6 c4 02 75 2d 48 85 db 74 01 fb bf 01 00 00 00 93 8c 2a fb 65 8b 05 1c 01 0d 7a 85 c0 74 0a 5b 5d c3 e8 90 08
RSP: 0018:ffffc90000178b28 EFLAGS: 00000206
RAX: 0000000000000006 RBX: 0000000000000200 RCX: 1ffffffff1615e5e
RDX: 0000000000000000 RSI: 0000000000000102 RDI: 0000000000000001
RBP: ffff88810f218000 R08: 0000000000000001 R09: ffffffff8b07375f
R10: 0000000000000001 R11: 0000000000000001 R12: 0000000000000000
R13: ffff88810f218040 R14: dffffc0000000000 R15: ffff8881392f4100
spin_unlock_irqrestore include/linux/spinlock.h:404 [inline]
dummy_timer+0x12fc/0x32c0 drivers/usb/gadget/udc/dummy_hcd.c:2002
call_timer_fn+0x1a0/0x6b0 kernel/time/timer.c:1474
expire_timers kernel/time/timer.c:1519 [inline]
__run_timers.part.0+0x674/0xa80 kernel/time/timer.c:1790
__run_timers kernel/time/timer.c:1768 [inline]
run_timer_softirq+0xb3/0x1d0 kernel/time/timer.c:1803
__do_softirq+0x1c0/0x9a9 kernel/softirq.c:571
do_softirq.part.0+0xde/0x130 kernel/softirq.c:472
do_softirq kernel/softirq.c:464 [inline]
__local_bh_enable_ip+0x102/0x120 kernel/softirq.c:396
lock_sock include/net/sock.h:1659 [inline]
netlink_insert+0xdd/0x1690 net/netlink/af_netlink.c:577
netlink_autobind.isra.0+0x305/0x560 net/netlink/af_netlink.c:845
netlink_bind+0x6ce/0x9d0 net/netlink/af_netlink.c:1050
__sys_bind+0x1e9/0x250 net/socket.c:1776
__do_sys_bind net/socket.c:1787 [inline]
__se_sys_bind net/socket.c:1785 [inline]
__x64_sys_bind+0x6f/0xb0 net/socket.c:1785
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7f9e18eb2ce7
Code: ff ff ff ff c3 66 0f 1f 44 00 00 48 8b 15 89 01 0c 00 f7 d8 64 89 02 b8 ff ff ff ff eb bc 0f 1f 44 00 00 b8 31 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 61 01 0c 00 f7 d8 64 89 01 48
RSP: 002b:00007ffc3dd9f958 EFLAGS: 00000246 ORIG_RAX: 0000000000000031
RAX: ffffffffffffffda RBX: 000055e70f1d7c70 RCX: 00007f9e18eb2ce7
RDX: 000000000000000c RSI: 000055e70f1d7c80 RDI: 000000000000000c
RBP: 0000000000000004 R08: 0000000000000001 R09: 000055e70f1a4910
R10: 00007f9e18f73a00 R11: 0000000000000246 R12: 000055e70f1d7c70
R13: 000055e70f1c1ec0 R14: 0000000000000000 R15: 000055e70f1a4910
Code: ff ff ff ff c3 66 0f 1f 44 00 00 48 8b 15 89 01 0c 00 f7 d8 64 89 02 b8 ff ff ff ff eb bc 0f 1f 44 00 00 b8 31 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 61 01 0c 00 f7 d8 64 89 01 48
RSP: 002b:00007ffc3dd9f958 EFLAGS: 00000246 ORIG_RAX: 0000000000000031
RAX: ffffffffffffffda RBX: 000055e70f1d7c70 RCX: 00007f9e18eb2ce7
RDX: 000000000000000c RSI: 000055e70f1d7c80 RDI: 000000000000000c
RBP: 0000000000000004 R08: 0000000000000001 R09: 000055e70f1a4910
R10: 00007f9e18f73a00 R11: 0000000000000246 R12: 000055e70f1d7c70
R13: 000055e70f1c1ec0 R14: 0000000000000000 R15: 000055e70f1a4910
imon 4-1:0.0: imon usb_rx_callback_intf0: status(-71): ignored
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
imon 4-1:0.0: imon usb_rx_callback_intf0: status(-71): ignored
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: nonzero urb status received: -71
cdc_wdm 1-1:65.0: wdm_int_callback - 0 bytes
cdc_wdm 1-1:65.0: wdm_int_callback - usb_submit_urb failed with result -1