FAULT_INJECTION: forcing a failure.
name fail_usercopy, interval 1, probability 0, space 0, times 0
======================================================
WARNING: possible circular locking dependency detected
syzkaller #0 Not tainted
------------------------------------------------------
syz.8.9445/30655 is trying to acquire lock:
ffffffff8e2d19a0 (console_owner){-.-.}-{0:0}, at: console_lock_spinning_enable+0x61/0x80 kernel/printk/printk.c:1897

but task is already holding lock:
ffff888106464220 (&dev->power.lock){-...}-{3:3}, at: spin_lock_irq include/linux/spinlock.h:376 [inline]
ffff888106464220 (&dev->power.lock){-...}-{3:3}, at: __rpm_callback+0x1ba/0x610 drivers/base/power/runtime.c:429

which lock already depends on the new lock.


the existing dependency chain (in reverse order) is:

-> #2 (&dev->power.lock){-...}-{3:3}:
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0x3a/0x60 kernel/locking/spinlock.c:162
       __pm_runtime_resume+0xa9/0x170 drivers/base/power/runtime.c:1196
       pm_runtime_get include/linux/pm_runtime.h:494 [inline]
       __uart_start+0x1b0/0x500 drivers/tty/serial/serial_core.c:149
       uart_write+0x218/0xb30 drivers/tty/serial/serial_core.c:633
       process_output_block drivers/tty/n_tty.c:561 [inline]
       n_tty_write+0x41e/0x11e0 drivers/tty/n_tty.c:2377
       iterate_tty_write drivers/tty/tty_io.c:1006 [inline]
       file_tty_write.constprop.0+0x503/0x9b0 drivers/tty/tty_io.c:1081
       tty_write drivers/tty/tty_io.c:1102 [inline]
       redirected_tty_write drivers/tty/tty_io.c:1125 [inline]
       redirected_tty_write+0xd4/0x150 drivers/tty/tty_io.c:1105
       new_sync_write fs/read_write.c:593 [inline]
       vfs_write+0x7d3/0x11d0 fs/read_write.c:686
       ksys_write+0x12a/0x250 fs/read_write.c:738
       do_syscall_x64 arch/x86/entry/syscall_64.c:63 [inline]
       do_syscall_64+0xcd/0xfa0 arch/x86/entry/syscall_64.c:94
       entry_SYSCALL_64_after_hwframe+0x77/0x7f

-> #1 (&port_lock_key){-.-.}-{3:3}:
       __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline]
       _raw_spin_lock_irqsave+0x3a/0x60 kernel/locking/spinlock.c:162
       uart_port_lock_irqsave include/linux/serial_core.h:717 [inline]
       serial8250_console_write+0x181/0x1890 drivers/tty/serial/8250/8250_port.c:3301
       console_emit_next_record kernel/printk/printk.c:3111 [inline]
       console_flush_all+0x801/0xc60 kernel/printk/printk.c:3199
       __console_flush_and_unlock kernel/printk/printk.c:3258 [inline]
       console_unlock+0xd8/0x210 kernel/printk/printk.c:3298
       vprintk_emit+0x3d7/0x680 kernel/printk/printk.c:2423
       _printk+0xc7/0x100 kernel/printk/printk.c:2448
       register_console+0xc2d/0x11b0 kernel/printk/printk.c:4099
       univ8250_console_init+0x5f/0x90 drivers/tty/serial/8250/8250_core.c:511
       console_init+0x152/0x680 kernel/printk/printk.c:4298
       start_kernel+0x29f/0x4e0 init/main.c:1048
       x86_64_start_reservations+0x18/0x30 arch/x86/kernel/head64.c:310
       x86_64_start_kernel+0x130/0x190 arch/x86/kernel/head64.c:291
       common_startup_64+0x13e/0x148

-> #0 (console_owner){-.-.}-{0:0}:
       check_prev_add kernel/locking/lockdep.c:3165 [inline]
       check_prevs_add kernel/locking/lockdep.c:3284 [inline]
       validate_chain kernel/locking/lockdep.c:3908 [inline]
       __lock_acquire+0x126f/0x1c90 kernel/locking/lockdep.c:5237
       lock_acquire kernel/locking/lockdep.c:5868 [inline]
       lock_acquire+0x179/0x350 kernel/locking/lockdep.c:5825
       console_lock_spinning_enable+0x72/0x80 kernel/printk/printk.c:1897
       console_emit_next_record kernel/printk/printk.c:3105 [inline]
       console_flush_all+0x7aa/0xc60 kernel/printk/printk.c:3199
       __console_flush_and_unlock kernel/printk/printk.c:3258 [inline]
       console_unlock+0xd8/0x210 kernel/printk/printk.c:3298
       vprintk_emit+0x3d7/0x680 kernel/printk/printk.c:2423
       _printk+0xc7/0x100 kernel/printk/printk.c:2448
       fail_dump lib/fault-inject.c:66 [inline]
       should_fail_ex+0x4e7/0x640 lib/fault-inject.c:174
       __copy_to_user_inatomic include/linux/uaccess.h:132 [inline]
       copy_to_user_nofault+0xac/0x1c0 mm/maccess.c:155
       ____bpf_probe_write_user kernel/trace/bpf_trace.c:347 [inline]
       bpf_probe_write_user+0xaf/0xf0 kernel/trace/bpf_trace.c:325
       bpf_prog_f26ae291ee3ebee3+0x41/0x49
       bpf_dispatcher_nop_func include/linux/bpf.h:1350 [inline]
       __bpf_prog_run include/linux/filter.h:721 [inline]
       bpf_prog_run include/linux/filter.h:728 [inline]
       __bpf_trace_run kernel/trace/bpf_trace.c:2075 [inline]
       bpf_trace_run3+0x248/0x5a0 kernel/trace/bpf_trace.c:2117
       __bpf_trace_rpm_return_int+0x101/0x140 include/trace/events/rpm.h:84
       __do_trace_rpm_return_int include/trace/events/rpm.h:84 [inline]
       trace_rpm_return_int+0x152/0x220 include/trace/events/rpm.h:84
       rpm_idle+0x434/0x780 drivers/base/power/runtime.c:561
       rpm_resume+0x3c3/0x1320 drivers/base/power/runtime.c:954
       __pm_runtime_resume+0xb6/0x170 drivers/base/power/runtime.c:1197
       pm_runtime_get_active include/linux/pm_runtime.h:520 [inline]
       pm_runtime_resume_and_get include/linux/pm_runtime.h:539 [inline]
       usb_autoresume_device+0x23/0xe0 drivers/usb/core/driver.c:1760
       usbdev_open+0x228/0x8b0 drivers/usb/core/devio.c:1058
       chrdev_open+0x234/0x6a0 fs/char_dev.c:414
       do_dentry_open+0x982/0x1530 fs/open.c:965
       vfs_open+0x82/0x3f0 fs/open.c:1097
       do_open fs/namei.c:3975 [inline]
       path_openat+0x1de4/0x2cb0 fs/namei.c:4134
       do_filp_open+0x20b/0x470 fs/namei.c:4161
       do_sys_openat2+0x11b/0x1d0 fs/open.c:1437
       do_sys_open fs/open.c:1452 [inline]
       __do_sys_openat fs/open.c:1468 [inline]
       __se_sys_openat fs/open.c:1463 [inline]
       __x64_sys_openat+0x174/0x210 fs/open.c:1463
       do_syscall_x64 arch/x86/entry/syscall_64.c:63 [inline]
       do_syscall_64+0xcd/0xfa0 arch/x86/entry/syscall_64.c:94
       entry_SYSCALL_64_after_hwframe+0x77/0x7f

other info that might help us debug this:

Chain exists of:
  console_owner --> &port_lock_key --> &dev->power.lock

 Possible unsafe locking scenario:

       CPU0                    CPU1
       ----                    ----
  lock(&dev->power.lock);
                               lock(&port_lock_key);
                               lock(&dev->power.lock);
  lock(console_owner);

 *** DEADLOCK ***

5 locks held by syz.8.9445/30655:
 #0: ffff888106464198 (&dev->mutex){....}-{4:4}, at: device_lock include/linux/device.h:914 [inline]
 #0: ffff888106464198 (&dev->mutex){....}-{4:4}, at: usbdev_open+0x1b6/0x8b0 drivers/usb/core/devio.c:1054
 #1: ffff888106464220 (&dev->power.lock){-...}-{3:3}, at: spin_lock_irq include/linux/spinlock.h:376 [inline]
 #1: ffff888106464220 (&dev->power.lock){-...}-{3:3}, at: __rpm_callback+0x1ba/0x610 drivers/base/power/runtime.c:429
 #2: ffffffff8e3c4760 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire include/linux/rcupdate.h:331 [inline]
 #2: ffffffff8e3c4760 (rcu_read_lock){....}-{1:3}, at: rcu_read_lock include/linux/rcupdate.h:867 [inline]
 #2: ffffffff8e3c4760 (rcu_read_lock){....}-{1:3}, at: __bpf_trace_run kernel/trace/bpf_trace.c:2074 [inline]
 #2: ffffffff8e3c4760 (rcu_read_lock){....}-{1:3}, at: bpf_trace_run3+0x1cb/0x5a0 kernel/trace/bpf_trace.c:2117
 #3: ffffffff8e3b1de0 (console_lock){+.+.}-{0:0}, at: _printk+0xc7/0x100 kernel/printk/printk.c:2448
 #4: ffffffff8e3b1e50 (console_srcu){....}-{0:0}, at: rcu_try_lock_acquire include/linux/rcupdate.h:336 [inline]
 #4: ffffffff8e3b1e50 (console_srcu){....}-{0:0}, at: srcu_read_lock_nmisafe include/linux/srcu.h:335 [inline]
 #4: ffffffff8e3b1e50 (console_srcu){....}-{0:0}, at: console_srcu_read_lock kernel/printk/printk.c:289 [inline]
 #4: ffffffff8e3b1e50 (console_srcu){....}-{0:0}, at: console_flush_all+0x158/0xc60 kernel/printk/printk.c:3176

stack backtrace:
CPU: 1 UID: 0 PID: 30655 Comm: syz.8.9445 Not tainted syzkaller #0 PREEMPT(full) 
Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.3-debian-1.16.3-2~bpo12+1 04/01/2014
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:94 [inline]
 dump_stack_lvl+0x116/0x1f0 lib/dump_stack.c:120
 print_circular_bug+0x275/0x350 kernel/locking/lockdep.c:2043
 check_noncircular+0x14c/0x170 kernel/locking/lockdep.c:2175
 check_prev_add kernel/locking/lockdep.c:3165 [inline]
 check_prevs_add kernel/locking/lockdep.c:3284 [inline]
 validate_chain kernel/locking/lockdep.c:3908 [inline]
 __lock_acquire+0x126f/0x1c90 kernel/locking/lockdep.c:5237
 lock_acquire kernel/locking/lockdep.c:5868 [inline]
 lock_acquire+0x179/0x350 kernel/locking/lockdep.c:5825
 console_lock_spinning_enable+0x72/0x80 kernel/printk/printk.c:1897
 console_emit_next_record kernel/printk/printk.c:3105 [inline]
 console_flush_all+0x7aa/0xc60 kernel/printk/printk.c:3199
 __console_flush_and_unlock kernel/printk/printk.c:3258 [inline]
 console_unlock+0xd8/0x210 kernel/printk/printk.c:3298
 vprintk_emit+0x3d7/0x680 kernel/printk/printk.c:2423
 _printk+0xc7/0x100 kernel/printk/printk.c:2448
 fail_dump lib/fault-inject.c:66 [inline]
 should_fail_ex+0x4e7/0x640 lib/fault-inject.c:174
 __copy_to_user_inatomic include/linux/uaccess.h:132 [inline]
 copy_to_user_nofault+0xac/0x1c0 mm/maccess.c:155
 ____bpf_probe_write_user kernel/trace/bpf_trace.c:347 [inline]
 bpf_probe_write_user+0xaf/0xf0 kernel/trace/bpf_trace.c:325
 bpf_prog_f26ae291ee3ebee3+0x41/0x49
 bpf_dispatcher_nop_func include/linux/bpf.h:1350 [inline]
 __bpf_prog_run include/linux/filter.h:721 [inline]
 bpf_prog_run include/linux/filter.h:728 [inline]
 __bpf_trace_run kernel/trace/bpf_trace.c:2075 [inline]
 bpf_trace_run3+0x248/0x5a0 kernel/trace/bpf_trace.c:2117
 __bpf_trace_rpm_return_int+0x101/0x140 include/trace/events/rpm.h:84
 __do_trace_rpm_return_int include/trace/events/rpm.h:84 [inline]
 trace_rpm_return_int+0x152/0x220 include/trace/events/rpm.h:84
 rpm_idle+0x434/0x780 drivers/base/power/runtime.c:561
 rpm_resume+0x3c3/0x1320 drivers/base/power/runtime.c:954
 __pm_runtime_resume+0xb6/0x170 drivers/base/power/runtime.c:1197
 pm_runtime_get_active include/linux/pm_runtime.h:520 [inline]
 pm_runtime_resume_and_get include/linux/pm_runtime.h:539 [inline]
 usb_autoresume_device+0x23/0xe0 drivers/usb/core/driver.c:1760
 usbdev_open+0x228/0x8b0 drivers/usb/core/devio.c:1058
 chrdev_open+0x234/0x6a0 fs/char_dev.c:414
 do_dentry_open+0x982/0x1530 fs/open.c:965
 vfs_open+0x82/0x3f0 fs/open.c:1097
 do_open fs/namei.c:3975 [inline]
 path_openat+0x1de4/0x2cb0 fs/namei.c:4134
 do_filp_open+0x20b/0x470 fs/namei.c:4161
 do_sys_openat2+0x11b/0x1d0 fs/open.c:1437
 do_sys_open fs/open.c:1452 [inline]
 __do_sys_openat fs/open.c:1468 [inline]
 __se_sys_openat fs/open.c:1463 [inline]
 __x64_sys_openat+0x174/0x210 fs/open.c:1463
 do_syscall_x64 arch/x86/entry/syscall_64.c:63 [inline]
 do_syscall_64+0xcd/0xfa0 arch/x86/entry/syscall_64.c:94
 entry_SYSCALL_64_after_hwframe+0x77/0x7f
RIP: 0033:0x7f346fd8df10
Code: 48 89 44 24 20 75 93 44 89 54 24 0c e8 69 95 02 00 44 8b 54 24 0c 89 da 48 89 ee 41 89 c0 bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 38 44 89 c7 89 44 24 0c e8 bc 95 02 00 8b 44
RSP: 002b:00007f3470b3eb70 EFLAGS: 00000293 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 000000000000c340 RCX: 00007f346fd8df10
RDX: 000000000000c340 RSI: 00007f3470b3ec10 RDI: 00000000ffffff9c
RBP: 00007f3470b3ec10 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000293 R12: cccccccccccccccd
R13: 00007f346ffe6038 R14: 00007f346ffe5fa0 R15: 00007ffd8efb48f8
 </TASK>
CPU: 1 UID: 0 PID: 30655 Comm: syz.8.9445 Not tainted syzkaller #0 PREEMPT(full) 
Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.3-debian-1.16.3-2~bpo12+1 04/01/2014
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:94 [inline]
 dump_stack_lvl+0x116/0x1f0 lib/dump_stack.c:120
 fail_dump lib/fault-inject.c:73 [inline]
 should_fail_ex+0x512/0x640 lib/fault-inject.c:174
 __copy_to_user_inatomic include/linux/uaccess.h:132 [inline]
 copy_to_user_nofault+0xac/0x1c0 mm/maccess.c:155
 ____bpf_probe_write_user kernel/trace/bpf_trace.c:347 [inline]
 bpf_probe_write_user+0xaf/0xf0 kernel/trace/bpf_trace.c:325
 bpf_prog_f26ae291ee3ebee3+0x41/0x49
 bpf_dispatcher_nop_func include/linux/bpf.h:1350 [inline]
 __bpf_prog_run include/linux/filter.h:721 [inline]
 bpf_prog_run include/linux/filter.h:728 [inline]
 __bpf_trace_run kernel/trace/bpf_trace.c:2075 [inline]
 bpf_trace_run3+0x248/0x5a0 kernel/trace/bpf_trace.c:2117
 __bpf_trace_rpm_return_int+0x101/0x140 include/trace/events/rpm.h:84
 __do_trace_rpm_return_int include/trace/events/rpm.h:84 [inline]
 trace_rpm_return_int+0x152/0x220 include/trace/events/rpm.h:84
 rpm_idle+0x434/0x780 drivers/base/power/runtime.c:561
 rpm_resume+0x3c3/0x1320 drivers/base/power/runtime.c:954
 __pm_runtime_resume+0xb6/0x170 drivers/base/power/runtime.c:1197
 pm_runtime_get_active include/linux/pm_runtime.h:520 [inline]
 pm_runtime_resume_and_get include/linux/pm_runtime.h:539 [inline]
 usb_autoresume_device+0x23/0xe0 drivers/usb/core/driver.c:1760
 usbdev_open+0x228/0x8b0 drivers/usb/core/devio.c:1058
 chrdev_open+0x234/0x6a0 fs/char_dev.c:414
 do_dentry_open+0x982/0x1530 fs/open.c:965
 vfs_open+0x82/0x3f0 fs/open.c:1097
 do_open fs/namei.c:3975 [inline]
 path_openat+0x1de4/0x2cb0 fs/namei.c:4134
 do_filp_open+0x20b/0x470 fs/namei.c:4161
 do_sys_openat2+0x11b/0x1d0 fs/open.c:1437
 do_sys_open fs/open.c:1452 [inline]
 __do_sys_openat fs/open.c:1468 [inline]
 __se_sys_openat fs/open.c:1463 [inline]
 __x64_sys_openat+0x174/0x210 fs/open.c:1463
 do_syscall_x64 arch/x86/entry/syscall_64.c:63 [inline]
 do_syscall_64+0xcd/0xfa0 arch/x86/entry/syscall_64.c:94
 entry_SYSCALL_64_after_hwframe+0x77/0x7f
RIP: 0033:0x7f346fd8df10
Code: 48 89 44 24 20 75 93 44 89 54 24 0c e8 69 95 02 00 44 8b 54 24 0c 89 da 48 89 ee 41 89 c0 bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 38 44 89 c7 89 44 24 0c e8 bc 95 02 00 8b 44
RSP: 002b:00007f3470b3eb70 EFLAGS: 00000293 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 000000000000c340 RCX: 00007f346fd8df10
RDX: 000000000000c340 RSI: 00007f3470b3ec10 RDI: 00000000ffffff9c
RBP: 00007f3470b3ec10 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000293 R12: cccccccccccccccd
R13: 00007f346ffe6038 R14: 00007f346ffe5fa0 R15: 00007ffd8efb48f8
 </TASK>