Message-ID: <96DE704B-2EB0-43D5-B34C-3323840F1BB2@oracle.com>
Date: Fri, 20 Sep 2024 18:51:15 +0000
From: Chuck Lever III <chuck.lever@...cle.com>
To: Neil Brown <neilb@...e.de>
CC: Jeff Layton <jlayton@...nel.org>,
        syzbot
	<syzbot+24cd636199753ac5e0ca@...kaller.appspotmail.com>,
        Dai Ngo
	<dai.ngo@...cle.com>,
        Linux Kernel Mailing List
	<linux-kernel@...r.kernel.org>,
        Linux NFS Mailing List
	<linux-nfs@...r.kernel.org>,
        Olga Kornievskaia <okorniev@...hat.com>,
        "syzkaller-bugs@...glegroups.com" <syzkaller-bugs@...glegroups.com>,
        Tom
 Talpey <tom@...pey.com>
Subject: Re: [syzbot] [nfs?] KASAN: slab-use-after-free Read in
 rhashtable_walk_enter



> On Sep 19, 2024, at 10:57 PM, NeilBrown <neilb@...e.de> wrote:
> 
> On Thu, 19 Sep 2024, Jeff Layton wrote:
>> On Wed, 2024-09-18 at 08:38 -0700, syzbot wrote:
>>> Hello,
>>> 
>>> syzbot found the following issue on:
>>> 
>>> HEAD commit:    a430d95c5efa Merge tag 'lsm-pr-20240911' of git://git.kern..
>>> git tree:       upstream
>>> console output: https://syzkaller.appspot.com/x/log.txt?x=17c7469f980000
>>> kernel config:  https://syzkaller.appspot.com/x/.config?x=a69c66e868285a9d
>>> dashboard link: https://syzkaller.appspot.com/bug?extid=24cd636199753ac5e0ca
>>> compiler:       gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40
>>> 
>>> Unfortunately, I don't have any reproducer for this issue yet.
>>> 
>>> Downloadable assets:
>>> disk image: https://storage.googleapis.com/syzbot-assets/7f3aff905e91/disk-a430d95c.raw.xz
>>> vmlinux: https://storage.googleapis.com/syzbot-assets/a468ce8431f0/vmlinux-a430d95c.xz
>>> kernel image: https://storage.googleapis.com/syzbot-assets/80d4f1150155/bzImage-a430d95c.xz
>>> 
>>> IMPORTANT: if you fix the issue, please add the following tag to the commit:
>>> Reported-by: syzbot+24cd636199753ac5e0ca@...kaller.appspotmail.com
>>> 
>>> svc: failed to register nfsdv3 RPC service (errno 111).
>>> svc: failed to register nfsaclv3 RPC service (errno 111).
>>> ==================================================================
>>> BUG: KASAN: slab-use-after-free in list_add include/linux/list.h:169 [inline]
>>> BUG: KASAN: slab-use-after-free in rhashtable_walk_enter+0x333/0x370 lib/rhashtable.c:684
>>> Read of size 8 at addr ffff8880773fa010 by task syz.2.11924/9970
>>> 
>>> CPU: 0 UID: 0 PID: 9970 Comm: syz.2.11924 Not tainted 6.11.0-syzkaller-02574-ga430d95c5efa #0
>>> Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/06/2024
>>> Call Trace:
>>> <TASK>
>>> __dump_stack lib/dump_stack.c:93 [inline]
>>> dump_stack_lvl+0x116/0x1f0 lib/dump_stack.c:119
>>> print_address_description mm/kasan/report.c:377 [inline]
>>> print_report+0xc3/0x620 mm/kasan/report.c:488
>>> kasan_report+0xd9/0x110 mm/kasan/report.c:601
>>> list_add include/linux/list.h:169 [inline]
>>> rhashtable_walk_enter+0x333/0x370 lib/rhashtable.c:684
>>> rhltable_walk_enter include/linux/rhashtable.h:1262 [inline]
>>> __nfsd_file_cache_purge+0xad/0x490 fs/nfsd/filecache.c:805
>>> nfsd_file_cache_shutdown+0xcf/0x480 fs/nfsd/filecache.c:897
>>> nfsd_shutdown_generic fs/nfsd/nfssvc.c:329 [inline]
>>> nfsd_shutdown_generic fs/nfsd/nfssvc.c:323 [inline]
>>> nfsd_startup_net fs/nfsd/nfssvc.c:444 [inline]
>>> nfsd_svc+0x6d4/0x970 fs/nfsd/nfssvc.c:817
>>> nfsd_nl_threads_set_doit+0x52c/0xbc0 fs/nfsd/nfsctl.c:1714
>>> genl_family_rcv_msg_doit+0x202/0x2f0 net/netlink/genetlink.c:1115
>>> genl_family_rcv_msg net/netlink/genetlink.c:1195 [inline]
>>> genl_rcv_msg+0x565/0x800 net/netlink/genetlink.c:1210
>>> netlink_rcv_skb+0x165/0x410 net/netlink/af_netlink.c:2550
>>> genl_rcv+0x28/0x40 net/netlink/genetlink.c:1219
>>> netlink_unicast_kernel net/netlink/af_netlink.c:1331 [inline]
>>> netlink_unicast+0x53c/0x7f0 net/netlink/af_netlink.c:1357
>>> netlink_sendmsg+0x8b8/0xd70 net/netlink/af_netlink.c:1901
>>> sock_sendmsg_nosec net/socket.c:730 [inline]
>>> __sock_sendmsg net/socket.c:745 [inline]
>>> ____sys_sendmsg+0x9ae/0xb40 net/socket.c:2603
>>> ___sys_sendmsg+0x135/0x1e0 net/socket.c:2657
>>> __sys_sendmsg+0x117/0x1f0 net/socket.c:2686
>>> do_syscall_x64 arch/x86/entry/common.c:52 [inline]
>>> do_syscall_64+0xcd/0x250 arch/x86/entry/common.c:83
>>> entry_SYSCALL_64_after_hwframe+0x77/0x7f
>>> RIP: 0033:0x7fd947f7def9
>>> 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 a8 ff ff ff f7 d8 64 89 01 48
>>> RSP: 002b:00007fd948e38038 EFLAGS: 00000246 ORIG_RAX: 000000000000002e
>>> RAX: ffffffffffffffda RBX: 00007fd948135f80 RCX: 00007fd947f7def9
>>> RDX: 0000000000000004 RSI: 0000000020000280 RDI: 0000000000000003
>>> RBP: 00007fd947ff0b76 R08: 0000000000000000 R09: 0000000000000000
>>> R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
>>> R13: 0000000000000000 R14: 00007fd948135f80 R15: 00007ffc6cab9d78
>>> </TASK>
>>> 
>>> Allocated by task 8716:
>>> kasan_save_stack+0x33/0x60 mm/kasan/common.c:47
>>> kasan_save_track+0x14/0x30 mm/kasan/common.c:68
>>> poison_kmalloc_redzone mm/kasan/common.c:370 [inline]
>>> __kasan_kmalloc+0xaa/0xb0 mm/kasan/common.c:387
>>> kasan_kmalloc include/linux/kasan.h:211 [inline]
>>> __do_kmalloc_node mm/slub.c:4159 [inline]
>>> __kmalloc_node_track_caller_noprof+0x20f/0x440 mm/slub.c:4178
>>> kmalloc_reserve+0xef/0x2c0 net/core/skbuff.c:609
>>> __alloc_skb+0x164/0x380 net/core/skbuff.c:678
>>> alloc_skb include/linux/skbuff.h:1322 [inline]
>>> nsim_dev_trap_skb_build drivers/net/netdevsim/dev.c:748 [inline]
>>> nsim_dev_trap_report drivers/net/netdevsim/dev.c:805 [inline]
>>> nsim_dev_trap_report_work+0x2a4/0xc80 drivers/net/netdevsim/dev.c:850
>>> process_one_work+0x958/0x1ad0 kernel/workqueue.c:3231
>>> process_scheduled_works kernel/workqueue.c:3312 [inline]
>>> worker_thread+0x6c8/0xf00 kernel/workqueue.c:3393
>>> kthread+0x2c1/0x3a0 kernel/kthread.c:389
>>> ret_from_fork+0x45/0x80 arch/x86/kernel/process.c:147
>>> ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244
>>> 
>>> Freed by task 8716:
>>> kasan_save_stack+0x33/0x60 mm/kasan/common.c:47
>>> kasan_save_track+0x14/0x30 mm/kasan/common.c:68
>>> kasan_save_free_info+0x3b/0x60 mm/kasan/generic.c:579
>>> poison_slab_object+0xf7/0x160 mm/kasan/common.c:240
>>> __kasan_slab_free+0x32/0x50 mm/kasan/common.c:256
>>> kasan_slab_free include/linux/kasan.h:184 [inline]
>>> slab_free_hook mm/slub.c:2250 [inline]
>>> slab_free mm/slub.c:4474 [inline]
>>> kfree+0x12a/0x3b0 mm/slub.c:4595
>>> skb_kfree_head net/core/skbuff.c:1086 [inline]
>>> skb_free_head+0x108/0x1d0 net/core/skbuff.c:1098
>>> skb_release_data+0x75d/0x990 net/core/skbuff.c:1125
>>> skb_release_all net/core/skbuff.c:1190 [inline]
>>> __kfree_skb net/core/skbuff.c:1204 [inline]
>>> consume_skb net/core/skbuff.c:1436 [inline]
>>> consume_skb+0xbf/0x100 net/core/skbuff.c:1430
>>> nsim_dev_trap_report drivers/net/netdevsim/dev.c:821 [inline]
>>> nsim_dev_trap_report_work+0x878/0xc80 drivers/net/netdevsim/dev.c:850
>>> process_one_work+0x958/0x1ad0 kernel/workqueue.c:3231
>>> process_scheduled_works kernel/workqueue.c:3312 [inline]
>>> worker_thread+0x6c8/0xf00 kernel/workqueue.c:3393
>>> kthread+0x2c1/0x3a0 kernel/kthread.c:389
>>> ret_from_fork+0x45/0x80 arch/x86/kernel/process.c:147
>>> ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244
>>> 
>>> The buggy address belongs to the object at ffff8880773fa000
>>> which belongs to the cache kmalloc-4k of size 4096
>>> The buggy address is located 16 bytes inside of
>>> freed 4096-byte region [ffff8880773fa000, ffff8880773fb000)
>>> 
>>> The buggy address belongs to the physical page:
>>> page: refcount:1 mapcount:0 mapping:0000000000000000 index:0x0 pfn:0x773f8
>>> head: order:3 mapcount:0 entire_mapcount:0 nr_pages_mapped:0 pincount:0
>>> flags: 0xfff00000000040(head|node=0|zone=1|lastcpupid=0x7ff)
>>> page_type: 0xfdffffff(slab)
>>> raw: 00fff00000000040 ffff88801ac42140 ffffea0001e63600 dead000000000002
>>> raw: 0000000000000000 0000000000040004 00000001fdffffff 0000000000000000
>>> head: 00fff00000000040 ffff88801ac42140 ffffea0001e63600 dead000000000002
>>> head: 0000000000000000 0000000000040004 00000001fdffffff 0000000000000000
>>> head: 00fff00000000003 ffffea0001dcfe01 ffffffffffffffff 0000000000000000
>>> head: 0000000700000008 0000000000000000 00000000ffffffff 0000000000000000
>>> page dumped because: kasan: bad access detected
>>> page_owner tracks the page as allocated
>>> page last allocated via order 3, migratetype Unmovable, gfp_mask 0xd2040(__GFP_IO|__GFP_NOWARN|__GFP_NORETRY|__GFP_COMP|__GFP_NOMEMALLOC), pid 5240, tgid 5240 (syz-executor), ts 74499202771, free_ts 74134964798
>>> set_page_owner include/linux/page_owner.h:32 [inline]
>>> post_alloc_hook+0x2d1/0x350 mm/page_alloc.c:1500
>>> prep_new_page mm/page_alloc.c:1508 [inline]
>>> get_page_from_freelist+0x1351/0x2e50 mm/page_alloc.c:3446
>>> __alloc_pages_noprof+0x22b/0x2460 mm/page_alloc.c:4702
>>> __alloc_pages_node_noprof include/linux/gfp.h:269 [inline]
>>> alloc_pages_node_noprof include/linux/gfp.h:296 [inline]
>>> alloc_slab_page+0x4e/0xf0 mm/slub.c:2319
>>> allocate_slab mm/slub.c:2482 [inline]
>>> new_slab+0x84/0x260 mm/slub.c:2535
>>> ___slab_alloc+0xdac/0x1870 mm/slub.c:3721
>>> __slab_alloc.constprop.0+0x56/0xb0 mm/slub.c:3811
>>> __slab_alloc_node mm/slub.c:3864 [inline]
>>> slab_alloc_node mm/slub.c:4026 [inline]
>>> __do_kmalloc_node mm/slub.c:4158 [inline]
>>> __kmalloc_noprof+0x379/0x410 mm/slub.c:4171
>>> kmalloc_noprof include/linux/slab.h:694 [inline]
>>> tomoyo_realpath_from_path+0xbf/0x710 security/tomoyo/realpath.c:251
>>> tomoyo_get_realpath security/tomoyo/file.c:151 [inline]
>>> tomoyo_path_number_perm+0x245/0x5b0 security/tomoyo/file.c:723
>>> security_file_ioctl+0x9b/0x240 security/security.c:2908
>>> __do_sys_ioctl fs/ioctl.c:901 [inline]
>>> __se_sys_ioctl fs/ioctl.c:893 [inline]
>>> __x64_sys_ioctl+0xbb/0x210 fs/ioctl.c:893
>>> do_syscall_x64 arch/x86/entry/common.c:52 [inline]
>>> do_syscall_64+0xcd/0x250 arch/x86/entry/common.c:83
>>> entry_SYSCALL_64_after_hwframe+0x77/0x7f
>>> page last free pid 5233 tgid 5233 stack trace:
>>> reset_page_owner include/linux/page_owner.h:25 [inline]
>>> free_pages_prepare mm/page_alloc.c:1101 [inline]
>>> free_unref_folios+0x9e9/0x1390 mm/page_alloc.c:2667
>>> folios_put_refs+0x560/0x760 mm/swap.c:1039
>>> free_pages_and_swap_cache+0x36d/0x510 mm/swap_state.c:332
>>> __tlb_batch_free_encoded_pages+0xf9/0x290 mm/mmu_gather.c:136
>>> tlb_batch_pages_flush mm/mmu_gather.c:149 [inline]
>>> tlb_flush_mmu_free mm/mmu_gather.c:366 [inline]
>>> tlb_flush_mmu mm/mmu_gather.c:373 [inline]
>>> tlb_finish_mmu+0x168/0x7b0 mm/mmu_gather.c:465
>>> unmap_region+0x342/0x420 mm/mmap.c:2441
>>> do_vmi_align_munmap+0x1107/0x19c0 mm/mmap.c:2754
>>> do_vmi_munmap+0x231/0x410 mm/mmap.c:2830
>>> __vm_munmap+0x142/0x330 mm/mmap.c:3109
>>> __do_sys_munmap mm/mmap.c:3126 [inline]
>>> __se_sys_munmap mm/mmap.c:3123 [inline]
>>> __x64_sys_munmap+0x61/0x90 mm/mmap.c:3123
>>> do_syscall_x64 arch/x86/entry/common.c:52 [inline]
>>> do_syscall_64+0xcd/0x250 arch/x86/entry/common.c:83
>>> entry_SYSCALL_64_after_hwframe+0x77/0x7f
>>> 
>>> Memory state around the buggy address:
>>> ffff8880773f9f00: fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc
>>> ffff8880773f9f80: fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc fc
>>>> ffff8880773fa000: fa fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb
>>>                         ^
>>> ffff8880773fa080: fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb
>>> ffff8880773fa100: fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb fb
>>> ==================================================================
>>> 
>>> 
>>> ---
>>> 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 syzkaller@...glegroups.com.
>>> 
>>> syzbot will keep track of this issue. See:
>>> https://goo.gl/tpsmEJ#status for how to communicate with syzbot.
>>> 
>>> If the report is already addressed, let syzbot know by replying with:
>>> #syz fix: exact-commit-title
>>> 
>>> If you want to overwrite report's subsystems, reply with:
>>> #syz set subsystems: new-subsystem
>>> (See the list of subsystem names on the web dashboard)
>>> 
>>> If the report is a duplicate of another one, reply with:
>>> #syz dup: exact-subject-of-another-report
>>> 
>>> If you want to undo deduplication, reply with:
>>> #syz undup
>> 
>> So we're tearing down the server and cleaning out the nfsd_file hash,
>> and we hit a UAF. That probably means that we freed a nfsd_file without
>> removing it from the hash? Maybe we should add a WARN_ON() in
>> nfsd_file_slab_free that checks whether the item is still hashed?
>> 
>> It is strange though. struct nfsd_file is 112 bytes on my machine, but
>> the warning is about a 4k allocation. I guess that just means that the
>> page got recycled into a different slabcache.
> 
> The code that is crashing hasn't come close to touching anything that is
> thought to be an nfsd_file.
> The error is detected in the list_add() in rhashtable_walk_enter() when
> the new on-stack iterator is being attached to the bucket_table that is being
> iterated.  So that bucket_table must (now) be an invalid address.
> 
> The handling of NFSD_FILE_CACHE_UP is strange.  nfsd_file_cache_init()
> sets it, but doesn't clear it on failure.  So if nfsd_file_cache_init()
> fails for some reason, nfsd_file_cache_shutdown() would still try to
> clean up if it was called.
> 
> So suppose nfsd_startup_generic() is called.  It increments nfsd_users
> from 0 so continues to nfsd_file_cache_init() which fails for some
> reason after initialising nfsd_file_rhltable and then destroying it.
> This will leave nfsd_file_rhltable.tbl as a pointer to a large
> allocation which has been freed.  nfsd_startup_generic() will then
> decrement nfsd_users back to zero, but NFSD_FILE_CACHE_UP will still be
> set.
> 
> When nfsd_startup_generic() is called again, nfsd_file_cache_init() will
> skip initialisation because NFSD_FILE_CACHE_UP is set.  When
> nfsd_file_cache_shutdown() is then called it will clean up an rhltable
> that has already been destroyed.  We get exactly the reported symptom.
> 
> I *think* nfsd_file_cache_init() can only fail with -ENOMEM and I would
> expect to see a warning when that happened.  In any case
> nfsd_file_cache_init() uses pr_err() for any failure except
> rhltable_init(), and that only fails if the params are inconsistent.
> 
> So I think there are problems with NFSD_FILE_CACHE_UP settings and I
> think they could trigger this bug if a kmalloc failed, but I don't think
> that a kmalloc failed and I think there must be some other explanation
> here.

Also, the FILE_CACHE_UP logic has been around for several releases.
Why is this UAF showing up only now? The "unable to register"
messages suggest a possible reason.

--
Chuck Lever

Powered by blists - more mailing lists