f2fs_msg: 6 callbacks suppressed
F2FS-fs (loop3): Magic Mismatch, valid(0xf2f52010) - read(0x0)
ttyprintk ttyprintk: tty_port_close_start: tty->count = 1 port count = 4
F2FS-fs (loop3): Can't find valid F2FS filesystem in 2th superblock
======================================================
WARNING: possible circular locking dependency detected
4.19.177-syzkaller #0 Not tainted
------------------------------------------------------
syz-executor.4/15056 is trying to acquire lock:
00000000bbd35b4a (console_owner){-...}, at: console_trylock_spinning kernel/printk/printk.c:1697 [inline]
00000000bbd35b4a (console_owner){-...}, at: vprintk_emit+0x3fe/0x740 kernel/printk/printk.c:1964
but task is already holding lock:
0000000092f22286 (&(&port->lock)->rlock){-.-.}, at: tty_port_close_start.part.0+0x28/0x540 drivers/tty/tty_port.c:574
which lock already depends on the new lock.
the existing dependency chain (in reverse order) is:
-> #2 (&(&port->lock)->rlock){-.-.}:
tty_port_tty_get+0x1d/0x80 drivers/tty/tty_port.c:289
tty_port_default_wakeup+0x11/0x40 drivers/tty/tty_port.c:47
serial8250_tx_chars+0x490/0xaf0 drivers/tty/serial/8250/8250_port.c:1806
serial8250_handle_irq.part.0+0x31f/0x3d0 drivers/tty/serial/8250/8250_port.c:1893
serial8250_handle_irq drivers/tty/serial/8250/8250_port.c:1866 [inline]
serial8250_default_handle_irq+0xae/0x220 drivers/tty/serial/8250/8250_port.c:1909
serial8250_interrupt+0x101/0x240 drivers/tty/serial/8250/8250_core.c:125
__handle_irq_event_percpu+0x27e/0x8e0 kernel/irq/handle.c:149
handle_irq_event_percpu kernel/irq/handle.c:189 [inline]
handle_irq_event+0x102/0x290 kernel/irq/handle.c:206
handle_edge_irq+0x260/0xcf0 kernel/irq/chip.c:797
generic_handle_irq_desc include/linux/irqdesc.h:155 [inline]
handle_irq+0x35/0x50 arch/x86/kernel/irq_64.c:87
do_IRQ+0x93/0x1c0 arch/x86/kernel/irq.c:246
ret_from_intr+0x0/0x1e
arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline]
__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline]
_raw_spin_unlock_irqrestore+0xa3/0xe0 kernel/locking/spinlock.c:184
spin_unlock_irqrestore include/linux/spinlock.h:384 [inline]
uart_write+0x3bb/0x6f0 drivers/tty/serial/serial_core.c:612
do_output_char+0x5de/0x850 drivers/tty/n_tty.c:445
process_output drivers/tty/n_tty.c:512 [inline]
n_tty_write+0x46e/0xff0 drivers/tty/n_tty.c:2343
do_tty_write drivers/tty/tty_io.c:960 [inline]
tty_write+0x496/0x810 drivers/tty/tty_io.c:1044
redirected_tty_write+0xaa/0xb0 drivers/tty/tty_io.c:1065
do_loop_readv_writev fs/read_write.c:704 [inline]
do_loop_readv_writev fs/read_write.c:688 [inline]
do_iter_write+0x461/0x5d0 fs/read_write.c:962
vfs_writev+0x153/0x2e0 fs/read_write.c:1005
do_writev+0x136/0x330 fs/read_write.c:1040
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
-> #1 (&port_lock_key){-.-.}:
serial8250_console_write+0x89b/0xad0 drivers/tty/serial/8250/8250_port.c:3270
call_console_drivers kernel/printk/printk.c:1764 [inline]
console_unlock+0xbb6/0x1110 kernel/printk/printk.c:2460
vprintk_emit+0x2d1/0x740 kernel/printk/printk.c:1965
vprintk_func+0x79/0x180 kernel/printk/printk_safe.c:397
printk+0xba/0xed kernel/printk/printk.c:2040
register_console+0x87f/0xc90 kernel/printk/printk.c:2776
univ8250_console_init+0x3a/0x46 drivers/tty/serial/8250/8250_core.c:684
console_init+0x4cb/0x718 kernel/printk/printk.c:2862
start_kernel+0x686/0x911 init/main.c:659
secondary_startup_64+0xa4/0xb0 arch/x86/kernel/head_64.S:243
-> #0 (console_owner){-...}:
console_trylock_spinning kernel/printk/printk.c:1718 [inline]
vprintk_emit+0x43b/0x740 kernel/printk/printk.c:1964
vprintk_func+0x79/0x180 kernel/printk/printk_safe.c:397
printk+0xba/0xed kernel/printk/printk.c:2040
tty_port_close_start.part.0+0x4f3/0x540 drivers/tty/tty_port.c:576
tty_port_close_start drivers/tty/tty_port.c:648 [inline]
tty_port_close+0x46/0x160 drivers/tty/tty_port.c:641
tty_release+0x45e/0x1210 drivers/tty/tty_io.c:1678
__fput+0x2ce/0x890 fs/file_table.c:278
task_work_run+0x148/0x1c0 kernel/task_work.c:113
tracehook_notify_resume include/linux/tracehook.h:193 [inline]
exit_to_usermode_loop+0x251/0x2a0 arch/x86/entry/common.c:167
prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
syscall_return_slowpath arch/x86/entry/common.c:271 [inline]
do_syscall_64+0x538/0x620 arch/x86/entry/common.c:296
entry_SYSCALL_64_after_hwframe+0x49/0xbe
other info that might help us debug this:
Chain exists of:
console_owner --> &port_lock_key --> &(&port->lock)->rlock
Possible unsafe locking scenario:
CPU0 CPU1
---- ----
lock(&(&port->lock)->rlock);
lock(&port_lock_key);
lock(&(&port->lock)->rlock);
lock(console_owner);
*** DEADLOCK ***
2 locks held by syz-executor.4/15056:
#0: 0000000035e93d15 (&tty->legacy_mutex){+.+.}, at: tty_lock+0x6a/0xa0 drivers/tty/tty_mutex.c:19
#1: 0000000092f22286 (&(&port->lock)->rlock){-.-.}, at: tty_port_close_start.part.0+0x28/0x540 drivers/tty/tty_port.c:574
stack backtrace:
CPU: 0 PID: 15056 Comm: syz-executor.4 Not tainted 4.19.177-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0x1fc/0x2ef lib/dump_stack.c:118
print_circular_bug.constprop.0.cold+0x2d7/0x41e kernel/locking/lockdep.c:1221
check_prev_add kernel/locking/lockdep.c:1865 [inline]
check_prevs_add kernel/locking/lockdep.c:1978 [inline]
validate_chain kernel/locking/lockdep.c:2419 [inline]
__lock_acquire+0x30c9/0x3ff0 kernel/locking/lockdep.c:3415
lock_acquire+0x170/0x3c0 kernel/locking/lockdep.c:3907
console_trylock_spinning kernel/printk/printk.c:1718 [inline]
vprintk_emit+0x43b/0x740 kernel/printk/printk.c:1964
vprintk_func+0x79/0x180 kernel/printk/printk_safe.c:397
printk+0xba/0xed kernel/printk/printk.c:2040
tty_port_close_start.part.0+0x4f3/0x540 drivers/tty/tty_port.c:576
tty_port_close_start drivers/tty/tty_port.c:648 [inline]
tty_port_close+0x46/0x160 drivers/tty/tty_port.c:641
tty_release+0x45e/0x1210 drivers/tty/tty_io.c:1678
__fput+0x2ce/0x890 fs/file_table.c:278
task_work_run+0x148/0x1c0 kernel/task_work.c:113
tracehook_notify_resume include/linux/tracehook.h:193 [inline]
exit_to_usermode_loop+0x251/0x2a0 arch/x86/entry/common.c:167
prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
syscall_return_slowpath arch/x86/entry/common.c:271 [inline]
do_syscall_64+0x538/0x620 arch/x86/entry/common.c:296
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x41920b
Code: 0f 05 48 3d 00 f0 ff ff 77 45 c3 0f 1f 40 00 48 83 ec 18 89 7c 24 0c e8 63 fc ff ff 8b 7c 24 0c 41 89 c0 b8 03 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 35 44 89 c7 89 44 24 0c e8 a1 fc ff ff 8b 44
RSP: 002b:00007fffc8fa2bb0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000004 RCX: 000000000041920b
RDX: 0000000000570120 RSI: 0000000008c9f318 RDI: 0000000000000003
RBP: 0000000000000001 R08: 0000000000000000 R09: 0000001b308211ac
R10: 00007fffc8fa2ca0 R11: 0000000000000293 R12: 0000000000035fb8
R13: 00000000000003e8 R14: 000000000056bf60 R15: 0000000000035fb5
UDF-fs: warning (device loop1): udf_load_vrs: No anchor found
F2FS-fs (loop3): invalid crc value
UDF-fs: Scanning with blocksize 512 failed
F2FS-fs (loop3): f2fs_check_nid_range: out-of-range nid=1, run fsck to fix.
UDF-fs: warning (device loop1): udf_fill_super: No partition found (2)
F2FS-fs (loop3): f2fs_check_nid_range: out-of-range nid=2, run fsck to fix.
UDF-fs: warning (device loop1): udf_load_vrs: No anchor found
UDF-fs: Scanning with blocksize 512 failed
F2FS-fs (loop3): Try to recover 2th superblock, ret: 0
UDF-fs: warning (device loop1): udf_fill_super: No partition found (2)
F2FS-fs (loop3): Mounted with checkpoint version = 6ff99717
RDS: rds_bind could not find a transport for ::ffff:172.10.20.0, load rds_tcp or rds_rdma?
ip6tnl0: mtu less than device minimum
ip6tnl0: mtu less than device minimum
netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'.
netlink: 72 bytes leftover after parsing attributes in process `syz-executor.1'.
netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'.
netlink: 72 bytes leftover after parsing attributes in process `syz-executor.1'.
netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'.
netlink: 72 bytes leftover after parsing attributes in process `syz-executor.1'.
device team0 entered promiscuous mode
device team_slave_0 entered promiscuous mode
device team_slave_1 entered promiscuous mode
device team0 left promiscuous mode
device team_slave_0 left promiscuous mode
device team_slave_1 left promiscuous mode
device team0 entered promiscuous mode
device team_slave_0 entered promiscuous mode
device team_slave_1 entered promiscuous mode
device team0 left promiscuous mode
device team_slave_0 left promiscuous mode
binder: 15527:15536 ioctl c018620c 20000340 returned -22
device team_slave_1 left promiscuous mode
netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'.
netlink: 72 bytes leftover after parsing attributes in process `syz-executor.1'.
device team0 entered promiscuous mode
device team_slave_0 entered promiscuous mode
device team_slave_1 entered promiscuous mode
device team0 left promiscuous mode
device team_slave_0 left promiscuous mode
device team_slave_1 left promiscuous mode
netlink: 24 bytes leftover after parsing attributes in process `syz-executor.1'.
device team0 entered promiscuous mode
device team_slave_0 entered promiscuous mode
device team_slave_1 entered promiscuous mode
netlink: 72 bytes leftover after parsing attributes in process `syz-executor.1'.
device team0 left promiscuous mode
device team_slave_0 left promiscuous mode
device team_slave_1 left promiscuous mode
device team0 entered promiscuous mode
device team_slave_0 entered promiscuous mode
device team_slave_1 entered promiscuous mode
device team0 left promiscuous mode
device team_slave_0 left promiscuous mode
device team_slave_1 left promiscuous mode
RDS: rds_bind could not find a transport for ::ffff:10.1.1.2, load rds_tcp or rds_rdma?
hpfs: bad mount options.
caif:caif_disconnect_client(): nothing to disconnect
caif:caif_disconnect_client(): nothing to disconnect
F2FS-fs (loop4): Magic Mismatch, valid(0xf2f52010) - read(0x0)
F2FS-fs (loop4): Can't find valid F2FS filesystem in 1th superblock
F2FS-fs (loop4): Magic Mismatch, valid(0xf2f52010) - read(0x0)
F2FS-fs (loop4): Can't find valid F2FS filesystem in 2th superblock
F2FS-fs (loop4): Magic Mismatch, valid(0xf2f52010) - read(0x0)
F2FS-fs (loop4): Can't find valid F2FS filesystem in 1th superblock
F2FS-fs (loop4): Magic Mismatch, valid(0xf2f52010) - read(0x0)
F2FS-fs (loop4): Can't find valid F2FS filesystem in 2th superblock
audit: type=1800 audit(1614438081.850:22): pid=16414 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name=".log" dev="sda1" ino=16347 res=0
RDS: rds_bind could not find a transport for ::ffff:10.1.1.2, load rds_tcp or rds_rdma?
audit: type=1800 audit(1614438081.890:23): pid=16414 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.0" name=".log" dev="sda1" ino=16347 res=0
nla_parse: 2 callbacks suppressed
netlink: 8 bytes leftover after parsing attributes in process `syz-executor.0'.
netlink: 8 bytes leftover after parsing attributes in process `syz-executor.0'.