syzbot


inconsistent lock state in find_vm_area

Status: upstream: reported on 2022/06/06 17:17
Reported-by: syzbot+5e0952b70fe7b7876ee7@syzkaller.appspotmail.com
First crash: 117d, last: 107d

Sample crash report:
WARNING: inconsistent lock state
5.19.0-rc2-syzkaller-00052-g979086f5e006 #0 Not tainted
--------------------------------
inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage.
syz-executor.3/23335 [HC0[0]:SC1[1]:HE0:SE0] takes:
ffffffff8beb8858 (vmap_area_lock){+.?.}-{2:2}, at: spin_lock include/linux/spinlock.h:349 [inline]
ffffffff8beb8858 (vmap_area_lock){+.?.}-{2:2}, at: find_vmap_area mm/vmalloc.c:1805 [inline]
ffffffff8beb8858 (vmap_area_lock){+.?.}-{2:2}, at: find_vm_area+0x1c/0x190 mm/vmalloc.c:2525
{SOFTIRQ-ON-W} state was registered at:
  lock_acquire kernel/locking/lockdep.c:5665 [inline]
  lock_acquire+0x1ab/0x570 kernel/locking/lockdep.c:5630
  __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+0xa49/0x1f00 mm/vmalloc.c:1586
  __get_vm_area_node+0x142/0x3f0 mm/vmalloc.c:2453
  get_vm_area_caller+0x43/0x50 mm/vmalloc.c:2506
  __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:1200
  start_kernel+0x3cf/0x48f init/main.c:1098
  secondary_startup_64_no_verify+0xce/0xdb
irq event stamp: 4633
hardirqs last  enabled at (4632): [<ffffffff897a212f>] __raw_spin_unlock_irq include/linux/spinlock_api_smp.h:159 [inline]
hardirqs last  enabled at (4632): [<ffffffff897a212f>] _raw_spin_unlock_irq+0x1f/0x40 kernel/locking/spinlock.c:202
hardirqs last disabled at (4633): [<ffffffff897a1f5e>] __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:108 [inline]
hardirqs last disabled at (4633): [<ffffffff897a1f5e>] _raw_spin_lock_irqsave+0x4e/0x50 kernel/locking/spinlock.c:162
softirqs last  enabled at (2314): [<ffffffff812cf7d3>] fpu_clone+0x3c3/0xbb0 arch/x86/kernel/fpu/core.c:608
softirqs last disabled at (4625): [<ffffffff81484a93>] invoke_softirq kernel/softirq.c:445 [inline]
softirqs last disabled at (4625): [<ffffffff81484a93>] __irq_exit_rcu+0x123/0x180 kernel/softirq.c:650

other info that might help us debug this:
 Possible unsafe locking scenario:

       CPU0
       ----
  lock(vmap_area_lock);
  <Interrupt>
    lock(vmap_area_lock);

 *** DEADLOCK ***

5 locks held by syz-executor.3/23335:
 #0: ffffc900001f0d70 ((&dum_hcd->timer)){+.-.}-{0:0}, at: lockdep_copy_map include/linux/lockdep.h:31 [inline]
 #0: ffffc900001f0d70 ((&dum_hcd->timer)){+.-.}-{0:0}, at: call_timer_fn+0xd5/0x6b0 kernel/time/timer.c:1464
 #1: ffff88804b8ad230 (&dev->event_lock#2){-.-.}-{2:2}, at: input_event drivers/input/input.c:456 [inline]
 #1: ffff88804b8ad230 (&dev->event_lock#2){-.-.}-{2:2}, at: input_event+0x7b/0xb0 drivers/input/input.c:449
 #2: ffffffff8bd86b20 (rcu_read_lock){....}-{1:2}, at: input_pass_values.part.0+0x0/0x710 drivers/input/input.c:884
 #3: ffffffff8c803118 (kbd_event_lock){..-.}-{2:2}, at: spin_lock include/linux/spinlock.h:349 [inline]
 #3: ffffffff8c803118 (kbd_event_lock){..-.}-{2:2}, at: kbd_event+0x86/0x1780 drivers/tty/vt/keyboard.c:1537
 #4: ffffffff8bd86b20 (rcu_read_lock){....}-{1:2}, at: show_state_filter+0x0/0x300 kernel/sched/core.c:8733

stack backtrace:
CPU: 1 PID: 23335 Comm: syz-executor.3 Not tainted 5.19.0-rc2-syzkaller-00052-g979086f5e006 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 <IRQ>
 __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:4527 [inline]
 __lock_acquire+0x11e7/0x5660 kernel/locking/lockdep.c:5007
 lock_acquire kernel/locking/lockdep.c:5665 [inline]
 lock_acquire+0x1ab/0x570 kernel/locking/lockdep.c:5630
 __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 mm/vmalloc.c:1805 [inline]
 find_vm_area+0x1c/0x190 mm/vmalloc.c:2525
 check_heap_object mm/usercopy.c:176 [inline]
 __check_object_size mm/usercopy.c:252 [inline]
 __check_object_size+0x1fb/0x7a0 mm/usercopy.c:214
 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
 sched_show_task kernel/sched/core.c:8771 [inline]
 sched_show_task+0x44c/0x5c0 kernel/sched/core.c:8745
 show_state_filter+0x13e/0x300 kernel/sched/core.c:8816
 k_spec drivers/tty/vt/keyboard.c:667 [inline]
 k_spec+0xe1/0x130 drivers/tty/vt/keyboard.c:656
 kbd_keycode drivers/tty/vt/keyboard.c:1524 [inline]
 kbd_event+0xcdd/0x1780 drivers/tty/vt/keyboard.c:1543
 input_to_handler+0x3b9/0x4c0 drivers/input/input.c:129
 input_pass_values.part.0+0x230/0x710 drivers/input/input.c:156
 input_pass_values drivers/input/input.c:426 [inline]
 input_handle_event+0x67e/0x1440 drivers/input/input.c:426
 input_event drivers/input/input.c:457 [inline]
 input_event+0x8e/0xb0 drivers/input/input.c:449
 hidinput_hid_event+0x79d/0x2010 drivers/hid/hid-input.c:1631
 hid_process_event+0x491/0x570 drivers/hid/hid-core.c:1527
 hid_input_array_field+0x4d7/0x660 drivers/hid/hid-core.c:1639
 hid_process_report drivers/hid/hid-core.c:1681 [inline]
 hid_report_raw_event+0xa8a/0x1280 drivers/hid/hid-core.c:1998
 hid_input_report+0x360/0x4c0 drivers/hid/hid-core.c:2065
 hid_irq_in+0x50e/0x690 drivers/hid/usbhid/hid-core.c:284
 __usb_hcd_giveback_urb+0x2b0/0x5c0 drivers/usb/core/hcd.c:1670
 usb_hcd_giveback_urb+0x367/0x410 drivers/usb/core/hcd.c:1747
 dummy_timer+0x11f9/0x32b0 drivers/usb/gadget/udc/dummy_hcd.c:1988
 call_timer_fn+0x1a5/0x6b0 kernel/time/timer.c:1474
 expire_timers kernel/time/timer.c:1519 [inline]
 __run_timers.part.0+0x679/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+0x29b/0x9c2 kernel/softirq.c:571
 invoke_softirq kernel/softirq.c:445 [inline]
 __irq_exit_rcu+0x123/0x180 kernel/softirq.c:650
 irq_exit_rcu+0x5/0x20 kernel/softirq.c:662
 sysvec_apic_timer_interrupt+0x93/0xc0 arch/x86/kernel/apic/apic.c:1106
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x1b/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:finish_task_switch.isra.0+0x323/0xb80 kernel/sched/core.c:4996
Code: d3 c0 24 08 85 c0 75 02 0f 0b 48 85 db 0f 85 25 01 00 00 4c 89 f7 e8 fc f7 ff ff e8 77 6b 2e 00 fb 65 48 8b 1c 25 80 6f 02 00 <48> 8d bb 80 15 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1
RSP: 0018:ffffc9000557fad0 EFLAGS: 00000206
RAX: 000000000000120f RBX: ffff88807c355880 RCX: 1ffffffff1b769b9
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
RBP: ffffc9000557fb18 R08: 0000000000000001 R09: 0000000000000001
R10: ffffed10173473e0 R11: 0000000000000001 R12: ffff8880b9b3ac58
R13: ffff8880201cd880 R14: ffff8880b9b39f00 R15: ffff8880b9b3a938
 context_switch kernel/sched/core.c:5119 [inline]
 __schedule+0xa08/0x4b30 kernel/sched/core.c:6428
 schedule+0xd2/0x1f0 kernel/sched/core.c:6500
 freezable_schedule include/linux/freezer.h:172 [inline]
 do_nanosleep+0x24e/0x690 kernel/time/hrtimer.c:2044
 hrtimer_nanosleep+0x1f9/0x4a0 kernel/time/hrtimer.c:2097
 common_nsleep+0xa2/0xc0 kernel/time/posix-timers.c:1227
 __do_sys_clock_nanosleep kernel/time/posix-timers.c:1267 [inline]
 __se_sys_clock_nanosleep kernel/time/posix-timers.c:1245 [inline]
 __x64_sys_clock_nanosleep+0x2f4/0x430 kernel/time/posix-timers.c:1245
 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+0x46/0xb0
RIP: 0033:0x7f23facade31
Code: 24 0c 89 3c 24 48 89 4c 24 18 e8 aa e7 ff ff 4c 8b 54 24 18 48 8b 54 24 10 41 89 c0 8b 74 24 0c 8b 3c 24 b8 e6 00 00 00 0f 05 <44> 89 c7 48 89 04 24 e8 e3 e7 ff ff 48 8b 04 24 eb 97 66 2e 0f 1f
RSP: 002b:00007f23fb2cfb30 EFLAGS: 00000293 ORIG_RAX: 00000000000000e6
RAX: ffffffffffffffda RBX: 00007f23fad9bf60 RCX: 00007f23facade31
RDX: 00007f23fb2cfb70 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007f23fad9d960 R08: 0000000000000000 R09: 00007fff45c93080
R10: 0000000000000000 R11: 0000000000000293 R12: 00000000000a3057
R13: 00007f23fb2cfc80 R14: 00007f23fad9c100 R15: 000000000000015e
 </TASK>
Code: 24 0c 89 3c 24 48 89 4c 24 18 e8 aa e7 ff ff 4c 8b 54 24 18 48 8b 54 24 10 41 89 c0 8b 74 24 0c 8b 3c 24 b8 e6 00 00 00 0f 05 <44> 89 c7 48 89 04 24 e8 e3 e7 ff ff 48 8b 04 24 eb 97 66 2e 0f 1f
RSP: 002b:00007f23fb2cfb30 EFLAGS: 00000293 ORIG_RAX: 00000000000000e6
RAX: ffffffffffffffda RBX: 00007f23fad9bf60 RCX: 00007f23facade31
RDX: 00007f23fb2cfb70 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007f23fad9d960 R08: 0000000000000000 R09: 00007fff45c93080
R10: 0000000000000000 R11: 0000000000000293 R12: 00000000000a3057
R13: 00007f23fb2cfc80 R14: 00007f23fad9c100 R15: 000000000000015e
 </TASK>
task:syz-executor.3  state:S stack:28408 pid:23336 ppid:  3644 flags:0x00000000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5116 [inline]
 __schedule+0xa00/0x4b30 kernel/sched/core.c:6428
 schedule+0xd2/0x1f0 kernel/sched/core.c:6500
 schedule_timeout+0x1db/0x2a0 kernel/time/timer.c:1911
 ___down_common kernel/locking/semaphore.c:225 [inline]
 __down_common+0x363/0x770 kernel/locking/semaphore.c:246
 down_interruptible+0x7b/0xa0 kernel/locking/semaphore.c:87
 raw_event_queue_fetch drivers/usb/gadget/legacy/raw_gadget.c:95 [inline]
 raw_ioctl_event_fetch drivers/usb/gadget/legacy/raw_gadget.c:557 [inline]
 raw_ioctl+0xfdc/0x2730 drivers/usb/gadget/legacy/raw_gadget.c:1225
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:870 [inline]
 __se_sys_ioctl fs/ioctl.c:856 [inline]
 __x64_sys_ioctl+0x193/0x200 fs/ioctl.c:856
 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+0x46/0xb0
RIP: 0033:0x7f23fac88ec7
RSP: 002b:00007f23fbd420d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007f23fad9bf60 RCX: 00007f23fac88ec7
RDX: 00007f23fbd43110 RSI: 0000000080085502 RDI: 0000000000000003
RBP: 0000000000000003 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000003 R11: 0000000000000246 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000022000
 </TASK>
task:syz-executor.3  state:S stack:28408 pid:23423 ppid:  3644 flags:0x00000000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5116 [inline]
 __schedule+0xa00/0x4b30 kernel/sched/core.c:6428
 schedule+0xd2/0x1f0 kernel/sched/core.c:6500
 schedule_timeout+0x1db/0x2a0 kernel/time/timer.c:1911
 do_wait_for_common kernel/sched/completion.c:85 [inline]
 __wait_for_common+0x378/0x530 kernel/sched/completion.c:106
 wait_for_common kernel/sched/completion.c:117 [inline]
 wait_for_completion_interruptible+0x1b/0x30 kernel/sched/completion.c:206
 raw_process_ep_io+0x5e0/0xb10 drivers/usb/gadget/legacy/raw_gadget.c:1040
 raw_ioctl_ep_write drivers/usb/gadget/legacy/raw_gadget.c:1068 [inline]
 raw_ioctl+0x943/0x2730 drivers/usb/gadget/legacy/raw_gadget.c:1240
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:870 [inline]
 __se_sys_ioctl fs/ioctl.c:856 [inline]
 __x64_sys_ioctl+0x193/0x200 fs/ioctl.c:856
 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+0x46/0xb0
RIP: 0033:0x7f23fac88ec7
RSP: 002b:00007f23fbd220f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00000000000000bd RCX: 00007f23fac88ec7
RDX: 00007f23fbd22120 RSI: 0000000040085507 RDI: 0000000000000003
RBP: 0000000020000000 R08: 00007f23fb2d0020 R09: 0000000000000001
R10: 0000000000000003 R11: 0000000000000246 R12: 0000000000000003
R13: 0000000000000000 R14: 00007f23fbd23300 R15: 0000000000022000
 </TASK>
task:syz-executor.3  state:S stack:29432 pid:23424 ppid:  3644 flags:0x00000000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5116 [inline]
 __schedule+0xa00/0x4b30 kernel/sched/core.c:6428
 schedule+0xd2/0x1f0 kernel/sched/core.c:6500
 freezable_schedule include/linux/freezer.h:172 [inline]
 futex_wait_queue+0x144/0x3b0 kernel/futex/waitwake.c:355
 futex_wait+0x2c9/0x670 kernel/futex/waitwake.c:656
 do_futex+0x1af/0x300 kernel/futex/syscalls.c:106
 __do_sys_futex kernel/futex/syscalls.c:183 [inline]
 __se_sys_futex kernel/futex/syscalls.c:164 [inline]
 __x64_sys_futex+0x1b0/0x4a0 kernel/futex/syscalls.c:164
 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+0x46/0xb0
RIP: 0033:0x7f23fac89109
RSP: 002b:00007f23fbd02218 EFLAGS: 00000246 ORIG_RAX: 00000000000000ca
RAX: ffffffffffffffda RBX: 00007f23fad9c108 RCX: 00007f23fac89109
RDX: 0000000000000000 RSI: 0000000000000080 RDI: 00007f23fad9c108
RBP: 00007f23fad9c100 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007f23fad9c10c
R13: 00007f23fb2cfb1f R14: 00007f23fbd02300 R15: 0000000000022000
 </TASK>
task:syz-executor.2  state:R  running task     stack:27816 pid:23406 ppid:  3749 flags:0x00004000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5116 [inline]
 __schedule+0xa00/0x4b30 kernel/sched/core.c:6428
 preempt_schedule_irq+0x4e/0x90 kernel/sched/core.c:6736
 irqentry_exit+0x31/0x80 kernel/entry/common.c:428
 asm_sysvec_apic_timer_interrupt+0x1b/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:arch_stack_walk+0x6/0xe0 arch/x86/kernel/stacktrace.c:17
Code: 31 4c 08 e6 89 eb c6 e6 8b eb c2 e6 82 89 fa ee ee 8d 57 01 ee ee eb cd e6 81 eb f1 e6 87 eb ed e6 83 eb e9 55 48 89 e5 41 56 <49> 89 d6 41 55 49 89 cd 41 54 49 89 f4 53 48 89 fb 48 83 ec 68 65
RSP: 0018:ffffc900058df710 EFLAGS: 00000246
RAX: 0000000000000000 RBX: fffff52000b1bee5 RCX: 0000000000000000
RDX: ffff8880201cd880 RSI: ffffc900058df748 RDI: ffffffff81696040
RBP: ffffc900058df718 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000001 R12: 0000000000002800
R13: dffffc0000000000 R14: dffffc0000000000 R15: 0000009ad25d86fe
 stack_trace_save+0x8c/0xc0 kernel/stacktrace.c:122
 save_stack+0x151/0x1e0 mm/page_owner.c:127
 __reset_page_owner+0x5d/0x180 mm/page_owner.c:148
 reset_page_owner include/linux/page_owner.h:24 [inline]
 free_pages_prepare mm/page_alloc.c:1371 [inline]
 free_pcp_prepare+0x549/0xd20 mm/page_alloc.c:1421
 free_unref_page_prepare mm/page_alloc.c:3343 [inline]
 free_unref_page_list+0x16f/0xf80 mm/page_alloc.c:3475
 release_pages+0xff1/0x2290 mm/swap.c:980
 __pagevec_release+0x77/0x100 mm/swap.c:1000
 pagevec_release include/linux/pagevec.h:82 [inline]
 folio_batch_release include/linux/pagevec.h:146 [inline]
 truncate_inode_pages_range+0x319/0x1530 mm/truncate.c:373
 kill_bdev block/bdev.c:77 [inline]
 blkdev_flush_mapping+0x18f/0x3c0 block/bdev.c:663
 blkdev_put_whole+0xd1/0xf0 block/bdev.c:694
 blkdev_put+0x226/0x770 block/bdev.c:954
 blkdev_close+0x64/0x80 block/fops.c:495
 __fput+0x277/0x9d0 fs/file_table.c:317
 task_work_run+0xdd/0x1a0 kernel/task_work.c:177
 resume_user_mode_work include/linux/resume_user_mode.h:49 [inline]
 exit_to_user_mode_loop kernel/entry/common.c:169 [inline]
 exit_to_user_mode_prepare+0x23c/0x250 kernel/entry/common.c:201
 __syscall_exit_to_user_mode_work kernel/entry/common.c:283 [inline]
 syscall_exit_to_user_mode+0x19/0x50 kernel/entry/common.c:294
 do_syscall_64+0x42/0xb0 arch/x86/entry/common.c:86
 entry_SYSCALL_64_after_hwframe+0x46/0xb0
RIP: 0033:0x7ff050c3bd4b
RSP: 002b:00007ff0512cfb80 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000004 RCX: 00007ff050c3bd4b
RDX: 0000001b2df20000 RSI: 00007ff050c00000 RDI: 0000000000000003
RBP: 00007ff050d9d960 R08: 0000000000000000 R09: 00000000f8c351c7
R10: 00007fffe0193090 R11: 0000000000000293 R12: 00000000000a24d5
R13: 00007ff0512cfc80 R14: 00007ff0512cfca0 R15: 0000000000000032
 </TASK>
task:syz-executor.2  state:R  running task     stack:28280 pid:23408 ppid:  3749 flags:0x00004000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5116 [inline]
 __schedule+0xa00/0x4b30 kernel/sched/core.c:6428
 preempt_schedule_irq+0x4e/0x90 kernel/sched/core.c:6736
 irqentry_exit+0x31/0x80 kernel/entry/common.c:428
 asm_sysvec_reschedule_ipi+0x1b/0x20 arch/x86/include/asm/idtentry.h:654
RIP: 0010:preempt_count arch/x86/include/asm/preempt.h:27 [inline]
RIP: 0010:check_kcov_mode kernel/kcov.c:166 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x0/0x60 kernel/kcov.c:200
Code: 48 89 ef 5d e9 81 21 4c 00 5d be 03 00 00 00 e9 36 ca 81 02 66 0f 1f 44 00 00 48 8b be a8 01 00 00 e8 b4 ff ff ff 31 c0 c3 90 <65> 8b 05 39 fd 87 7e 89 c1 48 8b 34 24 81 e1 00 01 00 00 65 48 8b
RSP: 0018:ffffc900058afc08 EFLAGS: 00000246
RAX: 0000000000040000 RBX: 0000000000000200 RCX: ffffc9000ee6d000
RDX: 0000000000040000 RSI: ffffffff814dc80d RDI: 0000000000000007
RBP: 0000000000000008 R08: 0000000000000007 R09: 0000000000000000
R10: 0000000000000200 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000001 R14: ffff888011865000 R15: 0000000000001000
 queue_work_on+0xb3/0x110 kernel/workqueue.c:1550
 queue_work include/linux/workqueue.h:502 [inline]
 snd_virmidi_output_trigger+0xe7/0x130 sound/core/seq/seq_virmidi.c:171
 snd_rawmidi_output_trigger sound/core/rawmidi.c:186 [inline]
 snd_rawmidi_output_trigger sound/core/rawmidi.c:182 [inline]
 snd_rawmidi_kernel_write1+0x548/0x870 sound/core/rawmidi.c:1502
 snd_rawmidi_write+0x273/0xb90 sound/core/rawmidi.c:1555
 vfs_write+0x269/0xac0 fs/read_write.c:589
 ksys_write+0x1e8/0x250 fs/read_write.c:644
 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+0x46/0xb0
RIP: 0033:0x7ff050c89109
RSP: 002b:00007ff051cfe168 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 00007ff050d9bf60 RCX: 00007ff050c89109
RDX: 00000000fffffd2c RSI: 0000000020000000 RDI: 0000000000000005
RBP: 00007ff050ce305d R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ff0512cfb1f R14: 00007ff051cfe300 R15: 0000000000022000
 </TASK>
task:syz-executor.2  state:S stack:25000 pid:23418 ppid:  3749 flags:0x00000000
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5116 [inline]
 __schedule+0xa00/0x4b30 kernel/sched/core.c:6428
 schedule+0xd2/0x1f0 kernel/sched/core.c:6500
 freezable_schedule include/linux/freezer.h:172 [inline]
 futex_wait_queue+0x144/0x3b0 kernel/futex/waitwake.c:355
 futex_wait+0x2c9/0x670 kernel/futex/waitwake.c:656
 do_futex+0x1af/0x300 kernel/futex/syscalls.c:106
 __do_sys_futex kernel/futex/syscalls.c:183 [inline]
 __se_sys_futex kernel/futex/syscalls.c:164 [inline]
 __x64_sys_futex+0x1b0/0x4a0 kernel/futex/syscalls.c:164
 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+0x46/0xb0
RIP: 0033:0x7ff050c89109
RSP: 002b:00007ff04fbfe218 EFLAGS: 00000246 ORIG_RAX: 00000000000000ca
RAX: ffffffffffffffda RBX: 00007ff050d9c038 RCX: 00007ff050c89109
RDX: 0000000000000000 RSI: 0000000000000080 RDI: 00007ff050d9c038
RBP: 00007ff050d9c030 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00007ff050d9c03c
R13: 00007ff0512cfb1f R14: 00007ff04fbfe300 R15: 0000000000022000
 </TASK>
INFO: lockdep is turned off.
----------------
Code disassembly (best guess):
   0:	d3 c0                	rol    %cl,%eax
   2:	24 08                	and    $0x8,%al
   4:	85 c0                	test   %eax,%eax
   6:	75 02                	jne    0xa
   8:	0f 0b                	ud2
   a:	48 85 db             	test   %rbx,%rbx
   d:	0f 85 25 01 00 00    	jne    0x138
  13:	4c 89 f7             	mov    %r14,%rdi
  16:	e8 fc f7 ff ff       	callq  0xfffff817
  1b:	e8 77 6b 2e 00       	callq  0x2e6b97
  20:	fb                   	sti
  21:	65 48 8b 1c 25 80 6f 	mov    %gs:0x26f80,%rbx
  28:	02 00
* 2a:	48 8d bb 80 15 00 00 	lea    0x1580(%rbx),%rdi <-- trapping instruction
  31:	48 b8 00 00 00 00 00 	movabs $0xdffffc0000000000,%rax
  38:	fc ff df
  3b:	48 89 fa             	mov    %rdi,%rdx
  3e:	48                   	rex.W
  3f:	c1                   	.byte 0xc1

Crashes (2):
Manager Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Title
ci-upstream-kasan-gce 2022/06/16 07:14 upstream 979086f5e006 1719ee24 .config log report info inconsistent lock state in find_vm_area
ci-upstream-kasan-gce-root 2022/06/06 05:44 upstream 44688ffd111a c8857892 .config log report info inconsistent lock state in find_vm_area
* Struck through repros no longer work on HEAD.