INFO: task hung in chown_common (2)
4 views
Skip to first unread message
syzbot
Jan 5, 2023, 5:16:52 AM1/5/23
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=176df78c480000
kernel config: https://syzkaller.appspot.com/x/.config?x=9b9277b418617afe
dashboard link: https://syzkaller.appspot.com/bug?extid=c227bc0dde2614d98479
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+c227bc...@syzkaller.appspotmail.com
device veth0_vlan left promiscuous mode
Bluetooth: hci2: command 0x0409 tx timeout
ieee802154 phy0 wpan0: encryption failed: -22
ieee802154 phy1 wpan1: encryption failed: -22
Bluetooth: hci2: command 0x041b tx timeout
INFO: task syz-executor.4:14449 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.
syz-executor.4 D29520 14449 14181 0x00000004
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
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline]
rwsem_down_write_failed+0x3aa/0x760 kernel/locking/rwsem-xadd.c:618
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:117
__down_write arch/x86/include/asm/rwsem.h:142 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:72
inode_lock include/linux/fs.h:748 [inline]
chown_common+0x2d0/0x550 fs/open.c:648
do_fchownat+0x126/0x1e0 fs/open.c:681
__do_sys_chown fs/open.c:701 [inline]
__se_sys_chown fs/open.c:699 [inline]
__x64_sys_chown+0x77/0xb0 fs/open.c:699
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fd437c770c9
Code: Bad RIP value.
RSP: 002b:00007fd436144168 EFLAGS: 00000246 ORIG_RAX: 000000000000005c
RAX: ffffffffffffffda RBX: 00007fd437d97390 RCX: 00007fd437c770c9
RDX: 000000000000ee00 RSI: 0000000000000000 RDI: 0000000020000000
RBP: 00007fd437cd2ae9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fff33fe3a5f R14: 00007fd436144300 R15: 0000000000022000
Showing all locks held in the system:
4 locks held by kworker/u4:3/1317:
1 lock held by khungtaskd/1565:
#0: 000000009b492327 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441
1 lock held by in:imklog/7756:
#0: 00000000b32a4b56 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
3 locks held by kworker/0:42/21655:
#0: 00000000246b46c6 ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
#1: 000000001708b970 ((linkwatch_work).work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
#2: 000000002266119d (rtnl_mutex){+.+.}, at: linkwatch_event+0xb/0x60 net/core/link_watch.c:236
3 locks held by kworker/0:43/21657:
#0: 00000000246b46c6 ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
#1: 00000000477cdb17 (deferred_process_work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
#2: 000000002266119d (rtnl_mutex){+.+.}, at: switchdev_deferred_process_work+0xa/0x20 net/switchdev/switchdev.c:150
2 locks held by syz-executor.4/14016:
#0: 00000000baa59c9e (sb_writers#30){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline]
#0: 00000000baa59c9e (sb_writers#30){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360
#1: 000000005af123a8 (&sb->s_type->i_mutex_key#36){++++}, at: inode_lock include/linux/fs.h:748 [inline]
#1: 000000005af123a8 (&sb->s_type->i_mutex_key#36){++++}, at: chown_common+0x2d0/0x550 fs/open.c:648
2 locks held by syz-executor.4/14442:
#0: 00000000cbeb5073 (sb_writers#30){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline]
#0: 00000000cbeb5073 (sb_writers#30){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360
#1: 000000002334c25b (&sb->s_type->i_mutex_key#36){++++}, at: inode_lock include/linux/fs.h:748 [inline]
#1: 000000002334c25b (&sb->s_type->i_mutex_key#36){++++}, at: chown_common+0x2d0/0x550 fs/open.c:648
2 locks held by syz-executor.4/14449:
#0: 00000000cbeb5073 (sb_writers#30){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline]
#0: 00000000cbeb5073 (sb_writers#30){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360
#1: 000000002334c25b (&sb->s_type->i_mutex_key#36){++++}, at: inode_lock include/linux/fs.h:748 [inline]
#1: 000000002334c25b (&sb->s_type->i_mutex_key#36){++++}, at: chown_common+0x2d0/0x550 fs/open.c:648
1 lock held by syz-executor.0/23865:
#0: 000000002266119d (rtnl_mutex){+.+.}, at: rtnl_lock net/core/rtnetlink.c:77 [inline]
#0: 000000002266119d (rtnl_mutex){+.+.}, at: rtnetlink_rcv_msg+0x3fe/0xb80 net/core/rtnetlink.c:4779
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 1565 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: 1317 Comm: kworker/u4:3 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022
Workqueue: netns cleanup_net
RIP: 0010:get_current arch/x86/include/asm/current.h:15 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x4/0x50 kernel/kcov.c:100
Code: e8 11 dd 35 00 e9 ab fe ff ff 4c 89 ef e8 04 dd 35 00 e9 23 fe ff ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 48 8b 34 24 <65> 48 8b 04 25 c0 df 01 00 65 8b 15 cc 59 9f 7e 81 e2 00 01 1f 00
RSP: 0018:ffff8880b32b7648 EFLAGS: 00000082
RAX: 0000000000000286 RBX: ffff88809f70d0e0 RCX: 0000000000000000
RDX: 0000000000000004 RSI: ffffffff8167ad6d RDI: ffff8880b59c3e40
RBP: ffff8880b59c3e40 R08: 0000000000000000 R09: fffffbfff140e0fc
R10: ffffffff8a0707e3 R11: 0000000000000000 R12: 0000000000000286
R13: ffff88809f70d380 R14: ffff88809f70d110 R15: 0000000000000000
FS: 0000000000000000(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f899d22f000 CR3: 00000000a1213000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
trace_hardirqs_off+0xd/0x200 kernel/trace/trace_preemptirq.c:36
kmem_cache_free+0x56/0x260 mm/slab.c:3761
kernfs_put.part.0+0x1f8/0x590 fs/kernfs/dir.c:544
kernfs_put+0x42/0x50 fs/kernfs/dir.c:515
__kernfs_remove+0x6fe/0xa80 fs/kernfs/dir.c:1338
kernfs_remove_by_name_ns+0x51/0xb0 fs/kernfs/dir.c:1510
kernfs_remove_by_name include/linux/kernfs.h:539 [inline]
sysfs_unmerge_group+0xe3/0x160 fs/sysfs/group.c:343
rpm_sysfs_remove drivers/base/power/sysfs.c:725 [inline]
dpm_sysfs_remove+0x36/0x60 drivers/base/power/sysfs.c:732
device_del+0x129/0xaf0 drivers/base/core.c:2319
rollback_registered_many+0x661/0xe70 net/core/dev.c:8220
unregister_netdevice_many.part.0+0x1a/0x300 net/core/dev.c:9324
unregister_netdevice_many net/core/dev.c:9323 [inline]
default_device_exit_batch+0x2fa/0x3c0 net/core/dev.c:9795
ops_exit_list+0xf9/0x150 net/core/net_namespace.c:156
cleanup_net+0x3b4/0x8b0 net/core/net_namespace.c:554
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
---
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 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=176df78c480000
kernel config: https://syzkaller.appspot.com/x/.config?x=9b9277b418617afe
dashboard link: https://syzkaller.appspot.com/bug?extid=c227bc0dde2614d98479
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+c227bc...@syzkaller.appspotmail.com
device veth0_vlan left promiscuous mode
Bluetooth: hci2: command 0x0409 tx timeout
ieee802154 phy0 wpan0: encryption failed: -22
ieee802154 phy1 wpan1: encryption failed: -22
Bluetooth: hci2: command 0x041b tx timeout
INFO: task syz-executor.4:14449 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.
syz-executor.4 D29520 14449 14181 0x00000004
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
__rwsem_down_write_failed_common kernel/locking/rwsem-xadd.c:589 [inline]
rwsem_down_write_failed+0x3aa/0x760 kernel/locking/rwsem-xadd.c:618
call_rwsem_down_write_failed+0x13/0x20 arch/x86/lib/rwsem.S:117
__down_write arch/x86/include/asm/rwsem.h:142 [inline]
down_write+0x4f/0x90 kernel/locking/rwsem.c:72
inode_lock include/linux/fs.h:748 [inline]
chown_common+0x2d0/0x550 fs/open.c:648
do_fchownat+0x126/0x1e0 fs/open.c:681
__do_sys_chown fs/open.c:701 [inline]
__se_sys_chown fs/open.c:699 [inline]
__x64_sys_chown+0x77/0xb0 fs/open.c:699
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fd437c770c9
Code: Bad RIP value.
RSP: 002b:00007fd436144168 EFLAGS: 00000246 ORIG_RAX: 000000000000005c
RAX: ffffffffffffffda RBX: 00007fd437d97390 RCX: 00007fd437c770c9
RDX: 000000000000ee00 RSI: 0000000000000000 RDI: 0000000020000000
RBP: 00007fd437cd2ae9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fff33fe3a5f R14: 00007fd436144300 R15: 0000000000022000
Showing all locks held in the system:
4 locks held by kworker/u4:3/1317:
1 lock held by khungtaskd/1565:
#0: 000000009b492327 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441
1 lock held by in:imklog/7756:
#0: 00000000b32a4b56 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
3 locks held by kworker/0:42/21655:
#0: 00000000246b46c6 ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
#1: 000000001708b970 ((linkwatch_work).work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
#2: 000000002266119d (rtnl_mutex){+.+.}, at: linkwatch_event+0xb/0x60 net/core/link_watch.c:236
3 locks held by kworker/0:43/21657:
#0: 00000000246b46c6 ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124
#1: 00000000477cdb17 (deferred_process_work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128
#2: 000000002266119d (rtnl_mutex){+.+.}, at: switchdev_deferred_process_work+0xa/0x20 net/switchdev/switchdev.c:150
2 locks held by syz-executor.4/14016:
#0: 00000000baa59c9e (sb_writers#30){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline]
#0: 00000000baa59c9e (sb_writers#30){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360
#1: 000000005af123a8 (&sb->s_type->i_mutex_key#36){++++}, at: inode_lock include/linux/fs.h:748 [inline]
#1: 000000005af123a8 (&sb->s_type->i_mutex_key#36){++++}, at: chown_common+0x2d0/0x550 fs/open.c:648
2 locks held by syz-executor.4/14442:
#0: 00000000cbeb5073 (sb_writers#30){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline]
#0: 00000000cbeb5073 (sb_writers#30){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360
#1: 000000002334c25b (&sb->s_type->i_mutex_key#36){++++}, at: inode_lock include/linux/fs.h:748 [inline]
#1: 000000002334c25b (&sb->s_type->i_mutex_key#36){++++}, at: chown_common+0x2d0/0x550 fs/open.c:648
2 locks held by syz-executor.4/14449:
#0: 00000000cbeb5073 (sb_writers#30){.+.+}, at: sb_start_write include/linux/fs.h:1579 [inline]
#0: 00000000cbeb5073 (sb_writers#30){.+.+}, at: mnt_want_write+0x3a/0xb0 fs/namespace.c:360
#1: 000000002334c25b (&sb->s_type->i_mutex_key#36){++++}, at: inode_lock include/linux/fs.h:748 [inline]
#1: 000000002334c25b (&sb->s_type->i_mutex_key#36){++++}, at: chown_common+0x2d0/0x550 fs/open.c:648
1 lock held by syz-executor.0/23865:
#0: 000000002266119d (rtnl_mutex){+.+.}, at: rtnl_lock net/core/rtnetlink.c:77 [inline]
#0: 000000002266119d (rtnl_mutex){+.+.}, at: rtnetlink_rcv_msg+0x3fe/0xb80 net/core/rtnetlink.c:4779
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 1565 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: 1317 Comm: kworker/u4:3 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022
Workqueue: netns cleanup_net
RIP: 0010:get_current arch/x86/include/asm/current.h:15 [inline]
RIP: 0010:__sanitizer_cov_trace_pc+0x4/0x50 kernel/kcov.c:100
Code: e8 11 dd 35 00 e9 ab fe ff ff 4c 89 ef e8 04 dd 35 00 e9 23 fe ff ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 48 8b 34 24 <65> 48 8b 04 25 c0 df 01 00 65 8b 15 cc 59 9f 7e 81 e2 00 01 1f 00
RSP: 0018:ffff8880b32b7648 EFLAGS: 00000082
RAX: 0000000000000286 RBX: ffff88809f70d0e0 RCX: 0000000000000000
RDX: 0000000000000004 RSI: ffffffff8167ad6d RDI: ffff8880b59c3e40
RBP: ffff8880b59c3e40 R08: 0000000000000000 R09: fffffbfff140e0fc
R10: ffffffff8a0707e3 R11: 0000000000000000 R12: 0000000000000286
R13: ffff88809f70d380 R14: ffff88809f70d110 R15: 0000000000000000
FS: 0000000000000000(0000) GS:ffff8880ba100000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f899d22f000 CR3: 00000000a1213000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
trace_hardirqs_off+0xd/0x200 kernel/trace/trace_preemptirq.c:36
kmem_cache_free+0x56/0x260 mm/slab.c:3761
kernfs_put.part.0+0x1f8/0x590 fs/kernfs/dir.c:544
kernfs_put+0x42/0x50 fs/kernfs/dir.c:515
__kernfs_remove+0x6fe/0xa80 fs/kernfs/dir.c:1338
kernfs_remove_by_name_ns+0x51/0xb0 fs/kernfs/dir.c:1510
kernfs_remove_by_name include/linux/kernfs.h:539 [inline]
sysfs_unmerge_group+0xe3/0x160 fs/sysfs/group.c:343
rpm_sysfs_remove drivers/base/power/sysfs.c:725 [inline]
dpm_sysfs_remove+0x36/0x60 drivers/base/power/sysfs.c:732
device_del+0x129/0xaf0 drivers/base/core.c:2319
rollback_registered_many+0x661/0xe70 net/core/dev.c:8220
unregister_netdevice_many.part.0+0x1a/0x300 net/core/dev.c:9324
unregister_netdevice_many net/core/dev.c:9323 [inline]
default_device_exit_batch+0x2fa/0x3c0 net/core/dev.c:9795
ops_exit_list+0xf9/0x150 net/core/net_namespace.c:156
cleanup_net+0x3b4/0x8b0 net/core/net_namespace.c:554
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
---
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.
Reply all
Reply to author
Forward
0 new messages