[<prev] [next>] [day] [month] [year] [list]
Message-ID: <87lhbdx6ck.fsf@yhuang-dev.intel.com>
Date: Thu, 08 Oct 2015 13:43:55 +0800
From: kernel test robot <ying.huang@...ux.intel.com>
TO: Jaegeuk Kim <jaegeuk@...nel.org>
CC: LKML <linux-kernel@...r.kernel.org>
Subject: [lkp] [f2fs] 15bec0ff5a: -7.5% fsmark.files_per_sec
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 15bec0ff5a9ba6d203178fa8772259df6207942a ("f2fs: do not skip dentry block writes")
=========================================================================================
tbox_group/testcase/rootfs/kconfig/compiler/cpufreq_governor/iterations/nr_threads/disk/fs/filesize/test_size/sync_method/nr_directories/nr_files_per_directory:
nhm4/fsmark/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/performance/1x/32t/1HDD/f2fs/16MB/60G/NoSync/16d/256fpd
commit:
1583667acb21aba71a8ba16a5f1596bba1cdbbfa
15bec0ff5a9ba6d203178fa8772259df6207942a
1583667acb21aba7 15bec0ff5a9ba6d203178fa877
---------------- --------------------------
%stddev %change %stddev
\ | \
9.60 ± 0% -7.5% 8.88 ± 2% fsmark.files_per_sec
25828 ± 14% +43.3% 37001 ± 9% fsmark.time.involuntary_context_switches
346865 ± 1% +5.1% 364446 ± 2% fsmark.time.voluntary_context_switches
149762 ± 1% -10.8% 133532 ± 1% meminfo.Writeback
25828 ± 14% +43.3% 37001 ± 9% time.involuntary_context_switches
10567 ± 0% -1.4% 10423 ± 0% vmstat.system.in
35272 ± 1% +13.7% 40088 ± 2% softirqs.BLOCK
55788 ± 2% -9.2% 50649 ± 2% softirqs.RCU
86.40 ± 0% +2.9% 88.90 ± 0% turbostat.Avg_MHz
15.04 ± 4% +51.2% 22.74 ± 7% turbostat.CPU%c6
95420434 ± 2% +11.1% 1.06e+08 ± 3% cpuidle.C1-NHM.time
76306793 ± 3% -25.7% 56703791 ± 6% cpuidle.C1E-NHM.time
136043 ± 3% +10.9% 150901 ± 1% cpuidle.C6-NHM.usage
85.60 ± 40% +149.5% 213.60 ± 32% proc-vmstat.allocstall
761.80 ±114% +1601.0% 12958 ± 32% proc-vmstat.nr_vmscan_immediate_reclaim
37430 ± 1% -10.8% 33381 ± 1% proc-vmstat.nr_writeback
4375 ± 2% +45.5% 6367 ± 10% proc-vmstat.pgactivate
307.90 ±138% +4385.4% 13810 ± 25% proc-vmstat.pgrotated
11195 ± 43% +150.1% 27994 ± 34% proc-vmstat.pgsteal_direct_dma32
274804 ± 7% -13.2% 238436 ± 6% sched_debug.cfs_rq[1]:/.min_vruntime
2498 ± 9% +44.4% 3607 ± 5% sched_debug.cfs_rq[4]:/.exec_clock
2769 ± 7% +39.7% 3869 ± 16% sched_debug.cfs_rq[5]:/.exec_clock
2667 ± 12% +40.3% 3741 ± 6% sched_debug.cfs_rq[6]:/.exec_clock
2631 ± 9% +43.1% 3765 ± 7% sched_debug.cfs_rq[7]:/.exec_clock
-95.80 ±-32% +95.2% -187.00 ±-30% sched_debug.cpu#0.nr_uninterruptible
11444 ± 7% +57.4% 18012 ± 46% sched_debug.cpu#5.nr_load_updates
43.30 ± 29% +228.2% 142.10 ± 27% sched_debug.cpu#5.nr_uninterruptible
9204 ± 10% +3829.2% 361677 ±193% sched_debug.cpu#5.ttwu_local
0.00 ± -1% +Inf% 726873 ± 91% latency_stats.avg.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].f2fs_new_inode.[f2fs].f2fs_create.[f2fs].vfs_create
0.00 ± -1% +Inf% 957001 ± 34% latency_stats.avg.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs]
0.00 ± -1% +Inf% 600712 ±106% latency_stats.avg.get_request.blk_queue_bio.generic_make_request.submit_bio.f2fs_submit_page_bio.[f2fs].get_read_data_page.[f2fs].find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open
0.00 ± -1% +Inf% 60887 ± 77% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_lruvec.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin.f2fs_write_begin.[f2fs]
83681 ± 43% +128.1% 190851 ± 70% latency_stats.avg.wait_on_page_bit.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write
0.00 ± -1% +Inf% 339404 ±117% latency_stats.avg.wait_on_page_bit.find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 765971 ± 90% latency_stats.max.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].f2fs_new_inode.[f2fs].f2fs_create.[f2fs].vfs_create
0.00 ± -1% +Inf% 1376989 ± 27% latency_stats.max.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs]
0.00 ± -1% +Inf% 808830 ±126% latency_stats.max.get_request.blk_queue_bio.generic_make_request.submit_bio.f2fs_submit_page_bio.[f2fs].get_read_data_page.[f2fs].find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open
0.00 ± -1% +Inf% 60934 ± 77% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_lruvec.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin.f2fs_write_begin.[f2fs]
113863 ±265% -76.8% 26423 ± 86% latency_stats.max.wait_on_page_bit.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].set_data_blkaddr.[f2fs].reserve_new_block.[f2fs].f2fs_reserve_block.[f2fs].get_new_data_page.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open
0.00 ± -1% +Inf% 646891 ±130% latency_stats.max.wait_on_page_bit.find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
1804998 ± 87% +5901.9% 1.083e+08 ± 9% latency_stats.sum.call_rwsem_down_read_failed.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
337670 ± 90% +85.5% 626367 ±147% latency_stats.sum.call_rwsem_down_read_failed.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs].f2fs_write_begin.[f2fs].generic_perform_write.__generic_file_write_iter.generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write
17619 ± 18% +761.1% 151720 ± 14% latency_stats.sum.call_rwsem_down_read_failed.get_node_info.[f2fs].new_node_page.[f2fs].new_inode_page.[f2fs].init_inode_metadata.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open
800.10 ±125% +7429.5% 60243 ± 27% latency_stats.sum.call_rwsem_down_write_failed.get_node_info.[f2fs].new_node_page.[f2fs].new_inode_page.[f2fs].init_inode_metadata.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open
18545 ± 17% +670.2% 142844 ± 16% latency_stats.sum.call_rwsem_down_write_failed.set_node_addr.[f2fs].new_node_page.[f2fs].new_inode_page.[f2fs].init_inode_metadata.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open
0.00 ± -1% +Inf% 1001773 ± 98% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].f2fs_new_inode.[f2fs].f2fs_create.[f2fs].vfs_create
0.00 ± -1% +Inf% 3896545 ± 63% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].ra_meta_pages.[f2fs].build_free_nids.[f2fs].alloc_nid.[f2fs].get_dnode_of_data.[f2fs].f2fs_reserve_block.[f2fs].f2fs_get_block.[f2fs]
0.00 ± -1% +Inf% 1037029 ±109% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.f2fs_submit_page_bio.[f2fs].get_read_data_page.[f2fs].find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open
0.00 ± -1% +Inf% 1365444 ±172% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_lruvec.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin.f2fs_write_begin.[f2fs]
1.318e+08 ± 62% +1624.3% 2.272e+09 ± 9% latency_stats.sum.wait_on_page_bit.f2fs_wait_on_page_writeback.[f2fs].f2fs_wait_on_page_writeback.[f2fs].__f2fs_add_link.[f2fs].f2fs_create.[f2fs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 1314116 ±111% latency_stats.sum.wait_on_page_bit.find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
nhm4: Nehalem
Memory: 4G
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Thanks,
Ying Huang
View attachment "job.yaml" of type "text/plain" (3694 bytes)
View attachment "reproduce" of type "text/plain" (1164 bytes)
Powered by blists - more mailing lists