On Mon, May 04, 2020 at 05:37:10PM -0700, Darrick J. Wong wrote:
> run fstests generic/418 at 2020-05-04 17:27:51
> rm (3338) used greatest stack depth: 11728 bytes left
> BUG: kernel NULL pointer dereference, address: 0000000000000000
> #PF: supervisor read access in kernel mode
> #PF: error_code(0x0000) - not-present page
> PGD 0 P4D 0
> Oops: 0000 [#1] PREEMPT SMP
> CPU: 1 PID: 4900 Comm: dio-invalidate- Not tainted 5.7.0-rc4-djw #rc4
> Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-1ubuntu1 04/01/2014
> RIP: 0010:iomap_set_range_uptodate+0x5d/0x170
> Code: 07 00 60 00 00 75 13 f0 80 0f 04 48 83 c4 18 5b 5d 41 5c 41 5d 41 5e 41 5f c3 48 8b 47 18 44 8d 74 16 ff 41 89 f1 4c 8b 6f 28 <4c> 8b 38 41 0f b6 af ca 00 00 00 89 e9 41 d3 e9 40 80 fd 1f 0f 87
> RSP: 0018:ffffc90004b0b8e8 EFLAGS: 00010206
> RAX: 0000000000000000 RBX: ffffea0000292440 RCX: 0000000000000000
> RDX: 0000000000000400 RSI: 0000000000000c00 RDI: ffffea0000292440
> RBP: 0000000000001000 R08: ffffc90004b0b958 R09: 0000000000000c00
> R10: 0000000000000002 R11: ffff888017806720 R12: ffffc90004b0ba20
> R13: ffff888017806720 R14: 0000000000000fff R15: ffff88801872c610
> FS: 00007f2091593740(0000) GS:ffff88801e800000(0000) knlGS:0000000000000000
> CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> CR2: 0000000000000000 CR3: 0000000017abb005 CR4: 00000000001606a0
> Call Trace:
> ? __raw_spin_lock_init+0x39/0x60
> iomap_readpage_actor+0x113/0x3f0
> iomap_readpages_actor+0x1dc/0x240
> iomap_apply+0x12d/0x4e9
> ? iomap_readpage_actor+0x3f0/0x3f0
> ? mark_held_locks+0x45/0x70
> iomap_readpages+0xc2/0x290
> ? iomap_readpage_actor+0x3f0/0x3f0
> ? xa_clear_mark+0x30/0x30
> read_pages+0x75/0x1b0
> __do_page_cache_readahead+0x1bb/0x1d0
> ondemand_readahead+0x21a/0x540
> ? pagecache_get_page+0x26/0x320
> generic_file_read_iter+0x91a/0xd10
> ? xfs_file_buffered_aio_read+0x88/0x170 [xfs]
> xfs_file_buffered_aio_read+0x65/0x170 [xfs]
> xfs_file_read_iter+0xe9/0x2a0 [xfs]
> new_sync_read+0x12d/0x1d0
> vfs_read+0xc7/0x180
> ksys_pread64+0x64/0xa0
> do_syscall_64+0x50/0x1a0
> entry_SYSCALL_64_after_hwframe+0x49/0xb3
> RIP: 0033:0x7f209179cbca
>
> Digging into this with gcc, the RIP value is:
>
> 0xffffffff813047cd is in iomap_set_range_uptodate (/storage/home/djwong/cdev/work/linux-djw/fs/iomap/buffered-io.c:147).
> 142
> 143 static void
> 144 iomap_iop_set_range_uptodate(struct page *page, unsigned off, unsigned len)
> 145 {
> 146 struct iomap_page *iop = to_iomap_page(page);
> 147 struct inode *inode = page->mapping->host;
> 148 unsigned first = off >> inode->i_blkbits;
> 149 unsigned last = (off + len - 1) >> inode->i_blkbits;
> 150 bool uptodate = true;
> 151 unsigned long flags;
>
> So now this makes me wonder, is it possible to be performing readahead
> into a page that doesn't have page->mapping set yet? I reran this a few
> times, got crashes in different places, but the common factor is that
> page->mapping is NULL, and we're doing readhead.
>
> I also tried this with the patch *not* applied and had the same
> problems, so it's not actually this patch. But there's something going
> wrong in the iomap code...
Thanks for tracking that down! I don't see a way for that to happen.
The page is originally allocated in __do_page_cache_readahead() and
(in 5.7) does not have page->mapping set. Instead, it gets put on
the page_pool list head which gets passed into iomap_readpages().
iomap_next_page() calls add_to_page_cache_lru() which either sets
page->mapping or returns an error. So I don't see how iomap_next_page()
can give us a page which doesn't have ->mapping set.
Is it possible that it's the second dereference, not the first that's
NULL? ie mapping->host is NULL?
