INFO: task hung in f2fs_balance_fs

10 views
Skip to first unread message

syzbot

unread,
Oct 18, 2022, 9:55:47 AM10/18/22
to syzkaller...@googlegroups.com
Hello,

syzbot found the following issue on:

HEAD commit: 3f8a27f9e27b Linux 4.19.211
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=127b8bd6880000
kernel config: https://syzkaller.appspot.com/x/.config?x=9b9277b418617afe
dashboard link: https://syzkaller.appspot.com/bug?extid=873005aface00874a914
compiler: gcc version 10.2.1 20210110 (Debian 10.2.1-6)

Unfortunately, I don't have any reproducer for this issue yet.

Downloadable assets:
disk image: https://storage.googleapis.com/syzbot-assets/98c0bdb4abb3/disk-3f8a27f9.raw.xz
vmlinux: https://storage.googleapis.com/syzbot-assets/ea228ff02669/vmlinux-3f8a27f9.xz

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+873005...@syzkaller.appspotmail.com

Bluetooth: hci6: command 0x0406 tx timeout
IPVS: ftp: loaded support on port[0] = 21
INFO: task kworker/u4:11:17446 blocked for more than 140 seconds.
Not tainted 4.19.211-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:11 D26280 17446 2 0x80000000
Workqueue: writeback wb_workfn (flush-7:4)
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
__mutex_lock_common kernel/locking/mutex.c:1016 [inline]
__mutex_lock+0x5f0/0x1190 kernel/locking/mutex.c:1078
chnl_net:caif_netlink_parms(): no params data found
f2fs_balance_fs+0x709/0xd80 fs/f2fs/segment.c:512
f2fs_write_inode+0x500/0x600 fs/f2fs/inode.c:630
write_inode fs/fs-writeback.c:1244 [inline]
__writeback_single_inode+0x733/0x11d0 fs/fs-writeback.c:1442
writeback_sb_inodes+0x537/0xef0 fs/fs-writeback.c:1647
__writeback_inodes_wb+0xc6/0x280 fs/fs-writeback.c:1716
wb_writeback+0x841/0xcc0 fs/fs-writeback.c:1822
bridge0: port 1(bridge_slave_0) entered blocking state
wb_check_old_data_flush fs/fs-writeback.c:1924 [inline]
wb_do_writeback fs/fs-writeback.c:1977 [inline]
wb_workfn+0x8ba/0x1250 fs/fs-writeback.c:2006
bridge0: port 1(bridge_slave_0) entered disabled state
process_one_work+0x864/0x1570 kernel/workqueue.c:2153
device bridge_slave_0 entered promiscuous mode
worker_thread+0x64c/0x1130 kernel/workqueue.c:2296
bridge0: port 2(bridge_slave_1) entered blocking state
bridge0: port 2(bridge_slave_1) entered disabled state
device bridge_slave_1 entered promiscuous mode
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:
bond0: Enslaving bond_slave_0 as an active interface with an up link
1 lock held by khungtaskd/1568:
#0: 00000000677e23d4 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441
bond0: Enslaving bond_slave_1 as an active interface with an up link
1 lock held by in:imklog/7810:
IPv6: ADDRCONF(NETDEV_UP): team_slave_0: link is not ready
team0: Port device team_slave_0 added
4 locks held by kworker/u4:11/17446:
#0: 00000000129cbb42 ((wq_completion)"writeback"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
#1: 000000009005769b ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
#2: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: trylock_super+0x1d/0x100 fs/super.c:412
#3: 00000000cbc4a704 (&sbi->gc_mutex){+.+.}, at: f2fs_balance_fs+0x709/0xd80 fs/f2fs/segment.c:512
3 locks held by kworker/u4:12/17491:
3 locks held by syz-executor.4/24696:
2 locks held by syz-executor.5/24765:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.5/24769:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.2/24754:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.2/24764:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.1/24759:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.1/24766:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.0/24762:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.0/24767:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.4/25056:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.4/25057:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.4/26208:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.4/26213:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.0/26381:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.0/26384:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.2/26403:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.2/26411:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.1/26405:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.1/26412:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.5/26419:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
2 locks held by syz-executor.5/26422:
#0: 00000000b8f21652 (&type->s_umount_key#69){++++}, at: iterate_supers+0xdb/0x290 fs/super.c:631
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: 0000000072ca8eb9 (&bdi->wb_switch_rwsem){+.+.}, at: sync_inodes_sb+0x180/0x9a0 fs/fs-writeback.c:2457
4 locks held by syz-executor.4/26427:

IPv6: ADDRCONF(NETDEV_UP): team_slave_1: link is not ready
f2fs_msg: 5014 callbacks suppressed
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
team0: Port device team_slave_1 added
=============================================

batman_adv: batadv0: Adding interface: batadv_slave_0
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
batman_adv: batadv0: The MTU of interface batadv_slave_0 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem.
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
batman_adv: batadv0: Adding interface: batadv_slave_1
NMI backtrace for cpu 0
batman_adv: batadv0: The MTU of interface batadv_slave_1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem.
CPU: 0 PID: 1568 Comm: khungtaskd Not tainted 4.19.211-syzkaller #0
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/22/2022
batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active
Call Trace:
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0x1fc/0x2ef lib/dump_stack.c:118
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
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
Sending NMI from CPU 0 to CPUs 1:
IPv6: ADDRCONF(NETDEV_UP): bridge_slave_0: link is not ready
NMI backtrace for cpu 1
CPU: 1 PID: 26427 Comm: syz-executor.4 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/22/2022
RIP: 0010:io_serial_in+0x60/0x80 drivers/tty/serial/8250/8250_port.c:455
Code: 0f b6 8d e1 00 00 00 48 8d 7d 38 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 d3 e3 80 3c 02 00 75 13 03 5d 38 89 da ec <5b> 0f b6 c0 5d c3 e8 c5 80 d7 fd eb c9 e8 0e 83 d7 fd eb e6 66 66
RSP: 0018:ffff8880a15ae980 EFLAGS: 00000002
RAX: dffffc0000000000 RBX: 00000000000003fd RCX: 0000000000000000
RDX: 00000000000003fd RSI: ffffffff83c0deec RDI: ffffffff8dc90258
RBP: ffffffff8dc90220 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000005 R11: 0000000000000000 R12: 0000000000000020
R13: fffffbfff1b9208c R14: fffffbfff1b9204d R15: 0000000000000000
FS: 0000555557229400(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fc8c8039108 CR3: 0000000094998000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
serial_in drivers/tty/serial/8250/8250.h:111 [inline]
wait_for_xmitr+0x9a/0x230 drivers/tty/serial/8250/8250_port.c:2049
serial8250_console_putchar+0x1b/0x50 drivers/tty/serial/8250/8250_port.c:3242
uart_console_write+0x59/0x100 drivers/tty/serial/serial_core.c:1888
serial8250_console_write+0x6aa/0xb70 drivers/tty/serial/8250/8250_port.c:3308
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:405
printk+0xba/0xed kernel/printk/printk.c:2040
addrconf_notify+0xdce/0x21f0 net/ipv6/addrconf.c:3493
notifier_call_chain+0xc0/0x230 kernel/notifier.c:93
call_netdevice_notifiers net/core/dev.c:1762 [inline]
__dev_notify_flags+0x110/0x2b0 net/core/dev.c:7717
dev_change_flags+0xf0/0x140 net/core/dev.c:7753
do_setlink+0x14f1/0x3540 net/core/rtnetlink.c:2449
rtnl_newlink+0xda9/0x15c0 net/core/rtnetlink.c:3077
rtnetlink_rcv_msg+0x453/0xb80 net/core/rtnetlink.c:4782
netlink_rcv_skb+0x160/0x440 net/netlink/af_netlink.c:2463
netlink_unicast_kernel net/netlink/af_netlink.c:1325 [inline]
netlink_unicast+0x4d5/0x690 net/netlink/af_netlink.c:1351
netlink_sendmsg+0x6c3/0xc50 net/netlink/af_netlink.c:1917
sock_sendmsg_nosec net/socket.c:651 [inline]
sock_sendmsg+0xc3/0x120 net/socket.c:661
__sys_sendto+0x21a/0x320 net/socket.c:1899
__do_sys_sendto net/socket.c:1911 [inline]
__se_sys_sendto net/socket.c:1907 [inline]
__x64_sys_sendto+0xdd/0x1b0 net/socket.c:1907
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7f62b4df24fc
Code: fa fa ff ff 44 8b 4c 24 2c 4c 8b 44 24 20 89 c5 44 8b 54 24 28 48 8b 54 24 18 b8 2c 00 00 00 48 8b 74 24 10 8b 7c 24 08 0f 05 <48> 3d 00 f0 ff ff 77 34 89 ef 48 89 44 24 08 e8 20 fb ff ff 48 8b
RSP: 002b:00007ffc847e6da0 EFLAGS: 00000293 ORIG_RAX: 000000000000002c
RAX: ffffffffffffffda RBX: 00007f62b5e99320 RCX: 00007f62b4df24fc
RDX: 0000000000000020 RSI: 00007f62b5e99370 RDI: 0000000000000003
RBP: 0000000000000000 R08: 00007ffc847e6df4 R09: 000000000000000c
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000
R13: 00007f62b5e99370 R14: 0000000000000003 R15: 0000000000000000
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
IPv6: ADDRCONF(NETDEV_UP): bridge_slave_1: link is not ready
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]


---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

syzbot

unread,
Oct 22, 2022, 11:19:43 AM10/22/22
to syzkaller...@googlegroups.com
Hello,

syzbot found the following issue on:

HEAD commit: 9d5c0b3a8e1a Linux 4.14.295
git tree: linux-4.14.y
console output: https://syzkaller.appspot.com/x/log.txt?x=155f68bc880000
kernel config: https://syzkaller.appspot.com/x/.config?x=746c079015a92425
dashboard link: https://syzkaller.appspot.com/bug?extid=400da1030cd5825f95f1
compiler: gcc version 10.2.1 20210110 (Debian 10.2.1-6)

Unfortunately, I don't have any reproducer for this issue yet.

Downloadable assets:
disk image: https://storage.googleapis.com/syzbot-assets/ed6fcf5895a2/disk-9d5c0b3a.raw.xz
vmlinux: https://storage.googleapis.com/syzbot-assets/341aa3534116/vmlinux-9d5c0b3a.xz

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+400da1...@syzkaller.appspotmail.com

F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop3): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
INFO: task kworker/u4:4:8330 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:4 D27464 8330 2 0x80000000
Workqueue: writeback wb_workfn (flush-7:3)
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3489
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
f2fs_balance_fs+0x412/0x5a0 fs/f2fs/segment.c:435
__write_data_page+0x646/0x1480 fs/f2fs/data.c:1615
f2fs_write_cache_pages+0x40d/0xe50 fs/f2fs/data.c:1744
__f2fs_write_data_pages+0x2e2/0xb90 fs/f2fs/data.c:1838
do_writepages+0xc3/0x240 mm/page-writeback.c:2361
__writeback_single_inode+0xda/0x1010 fs/fs-writeback.c:1382
writeback_sb_inodes+0x48b/0xd30 fs/fs-writeback.c:1645
__writeback_inodes_wb+0xbf/0x230 fs/fs-writeback.c:1716
wb_writeback+0x710/0xb80 fs/fs-writeback.c:1822
wb_do_writeback fs/fs-writeback.c:1952 [inline]
wb_workfn+0x2bd/0xf50 fs/fs-writeback.c:1988
process_one_work+0x793/0x14a0 kernel/workqueue.c:2117
f2fs_msg: 7237 callbacks suppressed
F2FS-fs (loop3): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251
F2FS-fs (loop3): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop3): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop3): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop3): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
kthread+0x30d/0x420 kernel/kthread.c:232
F2FS-fs (loop3): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
F2FS-fs (loop4): inconsistent node block, nid:6, node_footer[nid:0,ino:0,ofs:0,cpver:0,blkaddr:0]
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
INFO: task kworker/u4:6:9343 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:6 D26368 9343 2 0x80000000
Workqueue: writeback wb_workfn (flush-7:4)
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3489
__mutex_lock_common kernel/locking/mutex.c:833 [inline]
__mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893
f2fs_balance_fs+0x412/0x5a0 fs/f2fs/segment.c:435
__write_data_page+0x646/0x1480 fs/f2fs/data.c:1615
f2fs_write_cache_pages+0x40d/0xe50 fs/f2fs/data.c:1744
__f2fs_write_data_pages+0x2e2/0xb90 fs/f2fs/data.c:1838
do_writepages+0xc3/0x240 mm/page-writeback.c:2361
__writeback_single_inode+0xda/0x1010 fs/fs-writeback.c:1382
writeback_sb_inodes+0x48b/0xd30 fs/fs-writeback.c:1645
__writeback_inodes_wb+0xbf/0x230 fs/fs-writeback.c:1716
wb_writeback+0x710/0xb80 fs/fs-writeback.c:1822
wb_do_writeback fs/fs-writeback.c:1952 [inline]
wb_workfn+0x2bd/0xf50 fs/fs-writeback.c:1988
process_one_work+0x793/0x14a0 kernel/workqueue.c:2117
worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
INFO: task syz-executor.5:12134 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.5 D28016 12134 7987 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x343/0x6d0 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:56
bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
sync_inodes_one_sb+0x3d/0x50 fs/sync.c:74
iterate_supers+0x124/0x490 fs/super.c:613
sys_sync+0x78/0x130 fs/sync.c:113
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7fd5855e85f9
RSP: 002b:00007fd583f5b168 EFLAGS: 00000246 ORIG_RAX: 00000000000000a2
RAX: ffffffffffffffda RBX: 00007fd585708f80 RCX: 00007fd5855e85f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007fd5856437b0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffdbc9bf62f R14: 00007fd583f5b300 R15: 0000000000022000
INFO: task syz-executor.5:12148 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.5 D30144 12148 7987 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
wb_wait_for_completion fs/fs-writeback.c:222 [inline]
wb_wait_for_completion+0x118/0x170 fs/fs-writeback.c:218
sync_inodes_sb+0x173/0x880 fs/fs-writeback.c:2447
sync_inodes_one_sb+0x3d/0x50 fs/sync.c:74
iterate_supers+0x124/0x490 fs/super.c:613
sys_sync+0x78/0x130 fs/sync.c:113
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7fd5855e85f9
RSP: 002b:00007fd583f3a168 EFLAGS: 00000246 ORIG_RAX: 00000000000000a2
RAX: ffffffffffffffda RBX: 00007fd585709050 RCX: 00007fd5855e85f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007fd5856437b0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffdbc9bf62f R14: 00007fd583f3a300 R15: 0000000000022000
INFO: task syz-executor.5:12167 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.5 D30144 12167 7987 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x343/0x6d0 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:56
bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
sync_inodes_one_sb+0x3d/0x50 fs/sync.c:74
iterate_supers+0x124/0x490 fs/super.c:613
sys_sync+0x78/0x130 fs/sync.c:113
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7fd5855e85f9
RSP: 002b:00007fd583f19168 EFLAGS: 00000246 ORIG_RAX: 00000000000000a2
RAX: ffffffffffffffda RBX: 00007fd585709120 RCX: 00007fd5855e85f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007fd5856437b0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffdbc9bf62f R14: 00007fd583f19300 R15: 0000000000022000
INFO: task syz-executor.2:12170 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D30448 12170 7992 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x343/0x6d0 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:56
bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
sync_inodes_one_sb+0x3d/0x50 fs/sync.c:74
iterate_supers+0x124/0x490 fs/super.c:613
sys_sync+0x78/0x130 fs/sync.c:113
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7f78a20f25f9
RSP: 002b:00007f78a0a44168 EFLAGS: 00000246 ORIG_RAX: 00000000000000a2
RAX: ffffffffffffffda RBX: 00007f78a2213050 RCX: 00007f78a20f25f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007f78a214d7b0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffecd8d8e5f R14: 00007f78a0a44300 R15: 0000000000022000
INFO: task syz-executor.2:12178 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D30144 12178 7992 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x343/0x6d0 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:56
bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
sync_inodes_one_sb+0x3d/0x50 fs/sync.c:74
iterate_supers+0x124/0x490 fs/super.c:613
sys_sync+0x78/0x130 fs/sync.c:113
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7f78a20f25f9
RSP: 002b:00007f78a0a23168 EFLAGS: 00000246 ORIG_RAX: 00000000000000a2
RAX: ffffffffffffffda RBX: 00007f78a2213120 RCX: 00007f78a20f25f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007f78a214d7b0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffecd8d8e5f R14: 00007f78a0a23300 R15: 0000000000022000
INFO: task syz-executor.2:12181 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2 D30248 12181 7992 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x343/0x6d0 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:56
bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
sync_inodes_one_sb+0x3d/0x50 fs/sync.c:74
iterate_supers+0x124/0x490 fs/super.c:613
sys_sync+0x78/0x130 fs/sync.c:113
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7f78a20f25f9
RSP: 002b:00007f78a0a02168 EFLAGS: 00000246 ORIG_RAX: 00000000000000a2
RAX: ffffffffffffffda RBX: 00007f78a22131f0 RCX: 00007f78a20f25f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007f78a214d7b0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffecd8d8e5f R14: 00007f78a0a02300 R15: 0000000000022000
INFO: task syz-executor.3:12190 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D28016 12190 7989 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x343/0x6d0 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:56
bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
sync_inodes_one_sb+0x3d/0x50 fs/sync.c:74
iterate_supers+0x124/0x490 fs/super.c:613
sys_sync+0x78/0x130 fs/sync.c:113
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7fc525e785f9
RSP: 002b:00007fc5247eb168 EFLAGS: 00000246 ORIG_RAX: 00000000000000a2
RAX: ffffffffffffffda RBX: 00007fc525f98f80 RCX: 00007fc525e785f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007fc525ed37b0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffc183da00f R14: 00007fc5247eb300 R15: 0000000000022000
INFO: task syz-executor.3:12199 blocked for more than 140 seconds.
Not tainted 4.14.295-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.3 D30448 12199 7989 0x00000004
Call Trace:
context_switch kernel/sched/core.c:2811 [inline]
__schedule+0x88b/0x1de0 kernel/sched/core.c:3387
schedule+0x8d/0x1b0 kernel/sched/core.c:3431
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:588 [inline]
rwsem_down_write_failed+0x343/0x6d0 kernel/locking/rwsem-xadd.c:617
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:105
__down_write arch/x86/include/asm/rwsem.h:126 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:56
bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
sync_inodes_one_sb+0x3d/0x50 fs/sync.c:74
iterate_supers+0x124/0x490 fs/super.c:613
sys_sync+0x78/0x130 fs/sync.c:113
do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292
entry_SYSCALL_64_after_hwframe+0x46/0xbb
RIP: 0033:0x7fc525e785f9
RSP: 002b:00007fc5247ca168 EFLAGS: 00000246 ORIG_RAX: 00000000000000a2
RAX: ffffffffffffffda RBX: 00007fc525f99050 RCX: 00007fc525e785f9
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007fc525ed37b0 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffc183da00f R14: 00007fc5247ca300 R15: 0000000000022000

Showing all locks held in the system:
1 lock held by khungtaskd/1534:
#0: (tasklist_lock){.+.+}, at: [<ffffffff87024c59>] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548
4 locks held by kworker/u4:4/8330:
#0: ("writeback"){+.+.}, at: [<ffffffff81364eb0>] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088
#1: ((&(&wb->dwork)->work)){+.+.}, at: [<ffffffff81364ee6>] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092
#2: (&type->s_umount_key#53){++++}, at: [<ffffffff81879d1b>] trylock_super+0x1b/0xe0 fs/super.c:402
#3: (&sbi->gc_mutex){+.+.}, at: [<ffffffff82cc81d2>] f2fs_balance_fs+0x412/0x5a0 fs/f2fs/segment.c:435
4 locks held by kworker/u4:6/9343:
#0: ("writeback"){+.+.}, at: [<ffffffff81364eb0>] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088
#1: ((&(&wb->dwork)->work)){+.+.}, at: [<ffffffff81364ee6>] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092
#2: (&type->s_umount_key#53){++++}, at: [<ffffffff81879d1b>] trylock_super+0x1b/0xe0 fs/super.c:402
#3: (&sbi->gc_mutex){+.+.}, at: [<ffffffff82cc81d2>] f2fs_balance_fs+0x412/0x5a0 fs/f2fs/segment.c:435
2 locks held by syz-executor.5/12134:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.5/12148:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.5/12167:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.2/12170:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.2/12178:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.2/12181:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/12190:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/12199:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/12200:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.2/12884:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.2/12885:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.2/12886:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/13238:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/13239:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/13240:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/13808:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/13812:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445
2 locks held by syz-executor.3/13813:
#0: (&type->s_umount_key#53){++++}, at: [<ffffffff81879eba>] iterate_supers+0xda/0x490 fs/super.c:611
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] bdi_down_write_wb_switch_rwsem fs/fs-writeback.c:338 [inline]
#1: (&bdi->wb_switch_rwsem){+.+.}, at: [<ffffffff8190da86>] sync_inodes_sb+0x156/0x880 fs/fs-writeback.c:2445

=============================================

NMI backtrace for cpu 0
CPU: 0 PID: 1534 Comm: khungtaskd Not tainted 4.14.295-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/11/2022
Call Trace:
__dump_stack lib/dump_stack.c:17 [inline]
dump_stack+0x1b2/0x281 lib/dump_stack.c:58
nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x13a/0x180 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline]
watchdog+0x5b9/0xb40 kernel/hung_task.c:274
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 160 Comm: kworker/u4:3 Not tainted 4.14.295-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/11/2022
Workqueue: bat_events batadv_nc_worker
task: ffff8880b5158580 task.stack: ffff8880b5318000
RIP: 0010:debug_lockdep_rcu_enabled+0x69/0xe0 kernel/rcu/update.c:299
RSP: 0018:ffff8880b531fbf0 EFLAGS: 00000097
RAX: 0000000000000003 RBX: ffff8880b5158580 RCX: 0000000000000001
RDX: 0000000000000004 RSI: 0000000000000002 RDI: ffff8880b5158e04
RBP: ffff88804968e020 R08: 0000000000000001 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000001
FS: 0000000000000000(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f414fa2f000 CR3: 00000000a184b000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
trace_lock_acquire include/trace/events/lock.h:13 [inline]
lock_acquire+0x307/0x3f0 kernel/locking/lockdep.c:3997
__raw_spin_lock_bh include/linux/spinlock_api_smp.h:135 [inline]
_raw_spin_lock_bh+0x2f/0x40 kernel/locking/spinlock.c:176
spin_lock_bh include/linux/spinlock.h:322 [inline]
batadv_nc_purge_paths+0xce/0x300 net/batman-adv/network-coding.c:452
batadv_nc_worker+0x660/0xc50 net/batman-adv/network-coding.c:731
process_one_work+0x793/0x14a0 kernel/workqueue.c:2117
worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251
kthread+0x30d/0x420 kernel/kthread.c:232
ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404
Code: 3d 89 48 ba 00 00 00 00 00 fc ff df 48 89 c1 83 e0 07 48 c1 e9 03 83 c0 03 0f b6 14 11 38 d0 7c 04 84 d2 75 64 8b 0d a7 0c f7 07 <85> c9 74 45 65 48 8b 1c 25 c0 7f 02 00 48 8d bb 84 08 00 00 48

syzbot

unread,
Nov 14, 2022, 2:26:44 AM11/14/22
to syzkaller...@googlegroups.com
syzbot has found a reproducer for the following issue on:

HEAD commit: 3f8a27f9e27b Linux 4.19.211
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=1182791e880000
kernel config: https://syzkaller.appspot.com/x/.config?x=9b9277b418617afe
dashboard link: https://syzkaller.appspot.com/bug?extid=873005aface00874a914
compiler: gcc version 10.2.1 20210110 (Debian 10.2.1-6)
syz repro: https://syzkaller.appspot.com/x/repro.syz?x=15efa369880000
C reproducer: https://syzkaller.appspot.com/x/repro.c?x=176f96ae880000
mounted in repro: https://storage.googleapis.com/syzbot-assets/124cafb0e442/mount_0.gz

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+873005...@syzkaller.appspotmail.com

F2FS-fs (loop0): sanity_check_inode: inode (ino=8) has corrupted i_extra_isize: 36, max: 24
F2FS-fs (loop0): sanity_check_inode: inode (ino=8) has corrupted i_extra_isize: 36, max: 24
F2FS-fs (loop0): sanity_check_inode: inode (ino=8) has corrupted i_extra_isize: 36, max: 24
INFO: task kworker/u4:0:8089 blocked for more than 140 seconds.
Not tainted 4.19.211-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u4:0 D26320 8089 2 0x80000000
Workqueue: writeback wb_workfn (flush-7:0)
Call Trace:
context_switch kernel/sched/core.c:2828 [inline]
__schedule+0x887/0x2040 kernel/sched/core.c:3517
schedule+0x8d/0x1b0 kernel/sched/core.c:3561
schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619
__mutex_lock_common kernel/locking/mutex.c:1016 [inline]
__mutex_lock+0x5f0/0x1190 kernel/locking/mutex.c:1078
f2fs_balance_fs+0x709/0xd80 fs/f2fs/segment.c:512
f2fs_write_inode+0x500/0x600 fs/f2fs/inode.c:630
write_inode fs/fs-writeback.c:1244 [inline]
__writeback_single_inode+0x733/0x11d0 fs/fs-writeback.c:1442
writeback_sb_inodes+0x537/0xef0 fs/fs-writeback.c:1647
__writeback_inodes_wb+0xc6/0x280 fs/fs-writeback.c:1716
wb_writeback+0x841/0xcc0 fs/fs-writeback.c:1822
wb_check_old_data_flush fs/fs-writeback.c:1924 [inline]
wb_do_writeback fs/fs-writeback.c:1977 [inline]
wb_workfn+0x8ba/0x1250 fs/fs-writeback.c:2006
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/1571:
#0: 00000000cee841c7 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441
1 lock held by in:imklog/7864:
#0: 0000000059469809 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
2 locks held by syz-executor333/8079:
4 locks held by kworker/u4:0/8089:
#0: 000000002632df1e ((wq_completion)"writeback"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
#1: 00000000b3f67e59 ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
#2: 0000000021ba791c (&type->s_umount_key#61){.+.+}, at: trylock_super+0x1d/0x100 fs/super.c:412
#3: 000000009912a5aa (&sbi->gc_mutex){+.+.}, at: f2fs_balance_fs+0x709/0xd80 fs/f2fs/segment.c:512

=============================================

NMI backtrace for cpu 0
CPU: 0 PID: 1571 Comm: khungtaskd Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022
Call Trace:
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0x1fc/0x2ef lib/dump_stack.c:118
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 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 8079 Comm: syz-executor333 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022
RIP: 0010:__lock_acquire+0x4a9/0x3ff0 kernel/locking/lockdep.c:3361
Code: bd 74 08 00 00 01 49 8d 7a 24 48 89 fa 83 d8 ff c1 e3 0f c1 e0 0d c1 e5 10 0f b7 db 09 d8 81 e5 00 00 03 00 41 c1 e4 13 09 c5 <44> 89 f8 48 c1 ea 03 c1 e0 12 25 00 00 04 00 09 c5 41 8b 42 20 41
RSP: 0018:ffff888094c66788 EFLAGS: 00000006
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
RDX: ffff88809d4d0f3c RSI: 1ffff11013a9a1e2 RDI: ffff88809d4d0f3c
RBP: 0000000000010000 R08: 00000000000005cb R09: 0000000000000002
R10: ffff88809d4d0f18 R11: 0000000000000000 R12: 0000000000000000
R13: ffff88809d4d0640 R14: ffff88809d4d0f30 R15: 0000000000000001
FS: 0000555556e9e300(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f30e9e8f018 CR3: 00000000b0598000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
lock_acquire+0x170/0x3c0 kernel/locking/lockdep.c:3908
down_read+0x36/0x80 kernel/locking/rwsem.c:24
check_valid_map fs/f2fs/gc.c:454 [inline]
gc_data_segment fs/f2fs/gc.c:929 [inline]
do_garbage_collect fs/f2fs/gc.c:1109 [inline]
f2fs_gc+0x1cf3/0x8c90 fs/f2fs/gc.c:1196
f2fs_balance_fs+0x71a/0xd80 fs/f2fs/segment.c:513
__write_data_page+0xab8/0x22d0 fs/f2fs/data.c:1975
f2fs_write_cache_pages+0x96e/0x13e0 fs/f2fs/data.c:2107
__f2fs_write_data_pages fs/f2fs/data.c:2217 [inline]
f2fs_write_data_pages+0xcc3/0x1060 fs/f2fs/data.c:2244
do_writepages+0xe5/0x290 mm/page-writeback.c:2344
__filemap_fdatawrite_range+0x27d/0x350 mm/filemap.c:446
file_write_and_wait_range+0x93/0x100 mm/filemap.c:776
f2fs_do_sync_file+0x2eb/0x2550 fs/f2fs/file.c:228
f2fs_sync_file+0x136/0x190 fs/f2fs/file.c:334
vfs_fsync_range+0x13a/0x220 fs/sync.c:197
generic_write_sync include/linux/fs.h:2750 [inline]
f2fs_file_write_iter+0x64b/0xbe0 fs/f2fs/file.c:3044
call_write_iter include/linux/fs.h:1821 [inline]
do_iter_readv_writev+0x668/0x790 fs/read_write.c:681
do_iter_write+0x182/0x5d0 fs/read_write.c:960
vfs_iter_write+0x70/0xa0 fs/read_write.c:973
iter_file_splice_write+0x60d/0xbb0 fs/splice.c:750
do_splice_from fs/splice.c:852 [inline]
direct_splice_actor+0x115/0x160 fs/splice.c:1025
splice_direct_to_actor+0x33f/0x8d0 fs/splice.c:980
do_splice_direct+0x1a7/0x270 fs/splice.c:1068
do_sendfile+0x550/0xc30 fs/read_write.c:1447
__do_sys_sendfile64 fs/read_write.c:1508 [inline]
__se_sys_sendfile64+0x147/0x160 fs/read_write.c:1494
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7f98959d2549
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 c0 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fff0dd05ba8 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 0000000020010ba0 RCX: 00007f98959d2549
RDX: 0000000000000000 RSI: 0000000000000007 RDI: 0000000000000006
RBP: 0000000000000000 R08: 00007f9895a41ec0 R09: 00007f9895a41ec0
R10: 0001000000201005 R11: 0000000000000246 R12: 0000000000000000
R13: 0000000000000000 R14: 431bde82d7b634db R15: 0000000000000000

syzbot

unread,
Mar 17, 2023, 11:56:38 PM3/17/23
to syzkaller...@googlegroups.com
Auto-closing this bug as obsolete.
Crashes did not happen for a while, no reproducer and no activity.
Reply all
Reply to author
Forward
0 new messages