Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S932593AbWEZL6J (ORCPT ); Fri, 26 May 2006 07:58:09 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S932479AbWEZLx1 (ORCPT ); Fri, 26 May 2006 07:53:27 -0400 Received: from smtp.ustc.edu.cn ([202.38.64.16]:40155 "HELO ustc.edu.cn") by vger.kernel.org with SMTP id S932475AbWEZLxQ (ORCPT ); Fri, 26 May 2006 07:53:16 -0400 Message-ID: <348644394.05317@ustc.edu.cn> X-EYOUMAIL-SMTPAUTH: wfg@mail.ustc.edu.cn Message-Id: <20060526115318.520512078@localhost.localdomain> References: <20060526113906.084341801@localhost.localdomain> Date: Fri, 26 May 2006 19:39:39 +0800 From: Wu Fengguang To: Andrew Morton Cc: linux-kernel@vger.kernel.org, Wu Fengguang Subject: [PATCH 33/33] readahead: debug traces showing read patterns Content-Disposition: inline; filename=readahead-debug-traces-access-pattern.patch Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org Content-Length: 2908 Lines: 94 Print all relavant read requests to help discover the access pattern. If you are experiencing performance problems, or want to help improve the read-ahead logic, please send me the trace data. Thanks. - Preparations # Compile kernel with option CONFIG_DEBUG_READAHEAD mkdir /debug mount -t debug none /debug - For each session with distinct access pattern echo > /debug/readahead # reset the counters # echo > /var/log/kern.log # you may want to backup it first echo 8 > /debug/readahead/debug_level # show verbose printk traces # do one benchmark/task echo 0 > /debug/readahead/debug_level # revert to normal value cp /debug/readahead/events readahead-events-`date +'%F_%R'` bzip2 -c /var/log/kern.log > kern.log-`date +'%F_%R'`.bz2 Signed-off-by: Wu Fengguang --- mm/filemap.c | 23 ++++++++++++++++++++++- 1 files changed, 22 insertions(+), 1 deletion(-) --- linux-2.6.17-rc4-mm3.orig/mm/filemap.c +++ linux-2.6.17-rc4-mm3/mm/filemap.c @@ -45,6 +45,12 @@ static ssize_t generic_file_direct_IO(int rw, struct kiocb *iocb, const struct iovec *iov, loff_t offset, unsigned long nr_segs); +#ifdef CONFIG_DEBUG_READAHEAD +extern u32 debug_level; +#else +#define debug_level 0 +#endif /* CONFIG_DEBUG_READAHEAD */ + /* * Shared mappings implemented 30.11.1994. It's not fully working yet, * though. @@ -829,6 +835,10 @@ void do_generic_mapping_read(struct addr if (!isize) goto out; + if (debug_level >= 5) + printk(KERN_DEBUG "read-file(ino=%lu, req=%lu+%lu)\n", + inode->i_ino, index, last_index - index); + end_index = (isize - 1) >> PAGE_CACHE_SHIFT; for (;;) { struct page *page; @@ -883,6 +893,11 @@ find_page: if (prefer_adaptive_readahead()) readahead_cache_hit(&ra, page); + if (debug_level >= 7) + printk(KERN_DEBUG "read-page(ino=%lu, idx=%lu, io=%s)\n", + inode->i_ino, index, + PageUptodate(page) ? "hit" : "miss"); + if (!PageUptodate(page)) goto page_not_up_to_date; page_ok: @@ -1334,7 +1349,6 @@ retry_all: if (!prefer_adaptive_readahead() && VM_SequentialReadHint(area)) page_cache_readahead(mapping, ra, file, pgoff, 1); - /* * Do we have something in the page cache already? */ @@ -1397,6 +1411,13 @@ retry_find: if (prefer_adaptive_readahead()) readahead_cache_hit(ra, page); + if (debug_level >= 6) + printk(KERN_DEBUG "read-mmap(ino=%lu, idx=%lu, hint=%s, io=%s)\n", + inode->i_ino, pgoff, + VM_RandomReadHint(area) ? "random" : + (VM_SequentialReadHint(area) ? "sequential" : "none"), + PageUptodate(page) ? "hit" : "miss"); + /* * Ok, found a page in the page cache, now we need to check * that it's up-to-date. -- - To unsubscribe from this list: send the line "unsubscribe linux-kernel" in the body of a message to majordomo@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html Please read the FAQ at http://www.tux.org/lkml/