[<prev] [next>] [day] [month] [year] [list]
Message-ID: <87lh6nn3z6.fsf@yhuang-dev.intel.com>
Date: Sun, 14 Feb 2016 15:30:37 +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] 974b5afbf1: +187.0% fsmark.time.voluntary_context_switches
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/jaegeuk/f2fs dev-test
commit 974b5afbf16ecd3e01cefd5d52e3090b6d967693 ("f2fs: use writepages->lock for WB_SYNC_ALL")
=========================================================================================
compiler/disk/filesize/fs/iterations/kconfig/nr_directories/nr_files_per_directory/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase:
gcc-4.9/1HDD/16MB/f2fs/1x/x86_64-rhel/16d/256fpd/32t/debian-x86_64-2015-02-07.cgz/fsyncBeforeClose/nhm4/60G/fsmark
commit:
ac247fd0403387a3f264b86b41cdc71543e66400
974b5afbf16ecd3e01cefd5d52e3090b6d967693
ac247fd0403387a3 974b5afbf16ecd3e01cefd5d52
---------------- --------------------------
%stddev %change %stddev
\ | \
40098 ± 2% +25.7% 50389 ± 3% fsmark.time.involuntary_context_switches
19.30 ± 2% +18.1% 22.80 ± 1% fsmark.time.percent_of_cpu_this_job_got
97.36 ± 1% +18.1% 115.00 ± 1% fsmark.time.system_time
804635 ± 1% +187.0% 2309592 ± 5% fsmark.time.voluntary_context_switches
1904 ± 1% -18.8% 1546 ± 3% uptime.idle
38568 ± 2% +19.0% 45897 ± 3% softirqs.RCU
45610 ± 1% +26.9% 57875 ± 1% softirqs.SCHED
78751 ± 1% +39.3% 109673 ± 0% softirqs.TIMER
65587 ± 0% +76.5% 115770 ± 0% vmstat.memory.free
4605 ± 1% +159.8% 11962 ± 3% vmstat.system.cs
564.10 ± 1% +75.7% 991.10 ± 0% vmstat.system.in
65578 ± 0% +76.3% 115584 ± 0% meminfo.MemFree
69193 ± 0% +46.4% 101331 ± 2% meminfo.SReclaimable
79277 ± 0% +53.9% 121991 ± 0% meminfo.SUnreclaim
148470 ± 0% +50.4% 223322 ± 1% meminfo.Slab
1066 ± 2% +45.8% 1554 ± 1% time.file_system_inputs
40098 ± 2% +25.7% 50389 ± 3% time.involuntary_context_switches
19.30 ± 2% +18.1% 22.80 ± 1% time.percent_of_cpu_this_job_got
97.36 ± 1% +18.1% 115.00 ± 1% time.system_time
804635 ± 1% +187.0% 2309592 ± 5% time.voluntary_context_switches
4.80 ± 3% +240.9% 16.37 ± 3% turbostat.%Busy
150.30 ± 3% +259.0% 539.60 ± 3% turbostat.Avg_MHz
10.10 ± 2% +69.4% 17.11 ± 2% turbostat.CPU%c1
24.51 ± 2% +12.3% 27.54 ± 2% turbostat.CPU%c3
60.59 ± 0% -35.7% 38.99 ± 2% turbostat.CPU%c6
56.00 ± 1% +12.9% 63.20 ± 1% turbostat.CoreTmp
1.042e+08 ± 3% -32.6% 70243667 ± 5% cpuidle.C1E-NHM.time
5.49e+08 ± 2% +23.1% 6.76e+08 ± 2% cpuidle.C3-NHM.time
155243 ± 2% +22.3% 189817 ± 3% cpuidle.C3-NHM.usage
3.052e+09 ± 0% -17.6% 2.515e+09 ± 1% cpuidle.C6-NHM.time
132307 ± 2% +20.6% 159621 ± 2% cpuidle.C6-NHM.usage
64832253 ± 9% +626.5% 4.71e+08 ± 4% cpuidle.POLL.time
95963 ± 2% +987.2% 1043299 ± 8% cpuidle.POLL.usage
582.20 ± 0% +1e+05% 583505 ± 7% slabinfo.f2fs_extent_node.active_objs
7.20 ± 5% +1.1e+05% 7994 ± 7% slabinfo.f2fs_extent_node.active_slabs
582.20 ± 0% +1e+05% 583664 ± 7% slabinfo.f2fs_extent_node.num_objs
7.20 ± 5% +1.1e+05% 7994 ± 7% slabinfo.f2fs_extent_node.num_slabs
10171 ± 6% +22.6% 12471 ± 10% slabinfo.free_nid.active_objs
10194 ± 6% +23.4% 12580 ± 9% slabinfo.free_nid.num_objs
1834 ± 1% +543.2% 11798 ± 0% slabinfo.kmalloc-256.active_objs
78.70 ± 6% +406.9% 398.90 ± 1% slabinfo.kmalloc-256.active_slabs
2524 ± 6% +403.5% 12710 ± 1% slabinfo.kmalloc-256.num_objs
78.70 ± 6% +406.9% 398.90 ± 1% slabinfo.kmalloc-256.num_slabs
355.80 ± 0% +2796.0% 10303 ± 1% slabinfo.kmalloc-4096.active_objs
55.00 ± 1% +8795.8% 4892 ± 3% slabinfo.kmalloc-4096.active_slabs
378.60 ± 0% +2639.0% 10369 ± 1% slabinfo.kmalloc-4096.num_objs
55.00 ± 1% +8795.8% 4892 ± 3% slabinfo.kmalloc-4096.num_slabs
2929 ± 11% +1926.0% 59360 ± 5% proc-vmstat.allocstall
160.70 ± 28% +461.8% 902.80 ± 39% proc-vmstat.compact_isolated
116.60 ± 21% +1575.8% 1954 ±124% proc-vmstat.compact_migrate_scanned
1657 ± 10% +10214.9% 170917 ± 14% proc-vmstat.kswapd_high_wmark_hit_quickly
14489 ± 3% +974.7% 155724 ± 12% proc-vmstat.kswapd_low_wmark_hit_quickly
16393 ± 0% +76.3% 28901 ± 0% proc-vmstat.nr_free_pages
17297 ± 0% +46.4% 25331 ± 2% proc-vmstat.nr_slab_reclaimable
19818 ± 0% +53.9% 30499 ± 0% proc-vmstat.nr_slab_unreclaimable
16253363 ± 0% +21.1% 19685684 ± 0% proc-vmstat.numa_hit
16253363 ± 0% +21.1% 19685684 ± 0% proc-vmstat.numa_local
16974 ± 2% +1830.1% 327619 ± 13% proc-vmstat.pageoutrun
16298906 ± 0% +44.0% 23466530 ± 0% proc-vmstat.pgalloc_dma32
15711596 ± 0% +45.8% 22903686 ± 0% proc-vmstat.pgfree
480139 ± 8% +1593.6% 8131796 ± 5% proc-vmstat.pgscan_direct_dma32
14970914 ± 0% -51.0% 7341759 ± 5% proc-vmstat.pgscan_kswapd_dma32
396381 ± 11% +1928.7% 8041357 ± 5% proc-vmstat.pgsteal_direct_dma32
14805624 ± 0% -51.4% 7188819 ± 5% proc-vmstat.pgsteal_kswapd_dma32
207014 ± 0% +6368.5% 13390822 ± 1% proc-vmstat.slabs_scanned
0.00 ± -1% +Inf% 19563 ± 50% latency_stats.avg.allocate_data_block.[f2fs].do_write_page.[f2fs].write_data_page.[f2fs].do_write_data_page.[f2fs].f2fs_write_data_page.[f2fs].__f2fs_writepage.[f2fs].f2fs_write_cache_pages.[f2fs].f2fs_write_data_pages.[f2fs].do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.f2fs_sync_file.[f2fs]
0.00 ± -1% +Inf% 31033 ± 94% latency_stats.avg.allocate_data_block.[f2fs].do_write_page.[f2fs].write_node_page.[f2fs].f2fs_write_node_page.[f2fs].sync_node_pages.[f2fs].f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
43514 ±179% -24.3% 32919 ± 42% latency_stats.avg.call_rwsem_down_write_failed.block_operations.[f2fs].write_checkpoint.[f2fs].f2fs_sync_fs.[f2fs].f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 39044 ± 40% latency_stats.avg.call_rwsem_down_write_failed.f2fs_submit_merged_bio.[f2fs].f2fs_write_data_pages.[f2fs].do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
9117306 ±127% -99.9% 10078 ±263% latency_stats.avg.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
33.75 ±173% +1.9e+05% 62857 ±114% latency_stats.avg.pipe_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 78722 ± 14% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_kmem_pages_node.copy_process._do_fork.SyS_clone
0.00 ± -1% +Inf% 73283 ± 1% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin
0.00 ± -1% +Inf% 84609 ± 12% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_meta_page.[f2fs]
0.00 ± -1% +Inf% 75164 ± 12% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.new_node_page.[f2fs]
0.00 ± -1% +Inf% 85332 ± 1% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.new_slab.___slab_alloc.__slab_alloc
0.00 ± -1% +Inf% 65254 ± 66% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.pte_alloc_one.__pte_alloc.handle_mm_fault
0.00 ± -1% +Inf% 75091 ± 35% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_vma.handle_mm_fault.__do_page_fault.do_page_fault
0.00 ± -1% +Inf% 64635 ± 50% latency_stats.avg.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_vma.wp_page_copy.do_wp_page.handle_mm_fault
57408 ± 1% -93.3% 3824 ± 10% latency_stats.avg.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_range.filemap_write_and_wait_range.f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
49060 ± 2% +1508.8% 789275 ± 10% latency_stats.hits.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_range.filemap_write_and_wait_range.f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 129504 ± 33% latency_stats.max.allocate_data_block.[f2fs].do_write_page.[f2fs].write_data_page.[f2fs].do_write_data_page.[f2fs].f2fs_write_data_page.[f2fs].__f2fs_writepage.[f2fs].f2fs_write_cache_pages.[f2fs].f2fs_write_data_pages.[f2fs].do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.f2fs_sync_file.[f2fs]
0.00 ± -1% +Inf% 38782 ± 81% latency_stats.max.allocate_data_block.[f2fs].do_write_page.[f2fs].write_node_page.[f2fs].f2fs_write_node_page.[f2fs].sync_node_pages.[f2fs].f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 86260 ± 46% latency_stats.max.call_rwsem_down_write_failed.f2fs_submit_merged_bio.[f2fs].f2fs_write_data_pages.[f2fs].do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
13770 ± 38% +2493.6% 357160 ± 18% latency_stats.max.call_rwsem_down_write_failed.f2fs_submit_page_mbio.[f2fs].do_write_page.[f2fs].write_data_page.[f2fs].do_write_data_page.[f2fs].f2fs_write_data_page.[f2fs].__f2fs_writepage.[f2fs].f2fs_write_cache_pages.[f2fs].f2fs_write_data_pages.[f2fs].do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range
71537 ±135% -90.4% 6880 ± 17% latency_stats.max.call_rwsem_down_write_failed.get_node_info.[f2fs].new_node_page.[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
15813502 ±166% -99.9% 10078 ±263% latency_stats.max.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
33.75 ±173% +2.2e+05% 73000 ±113% latency_stats.max.pipe_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 97634 ± 0% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_kmem_pages_node.copy_process._do_fork.SyS_clone
0.00 ± -1% +Inf% 99094 ± 0% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin
0.00 ± -1% +Inf% 97469 ± 0% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_meta_page.[f2fs]
0.00 ± -1% +Inf% 95084 ± 5% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.new_node_page.[f2fs]
0.00 ± -1% +Inf% 98808 ± 0% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.new_slab.___slab_alloc.__slab_alloc
0.00 ± -1% +Inf% 66744 ± 65% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.pte_alloc_one.__pte_alloc.handle_mm_fault
0.00 ± -1% +Inf% 87727 ± 33% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_vma.handle_mm_fault.__do_page_fault.do_page_fault
0.00 ± -1% +Inf% 69586 ± 50% latency_stats.max.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_vma.wp_page_copy.do_wp_page.handle_mm_fault
20738812 ± 27% -87.5% 2591032 ±122% latency_stats.sum.alloc_nid.[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.vfs_write.SyS_write
0.00 ± -1% +Inf% 423935 ± 53% latency_stats.sum.allocate_data_block.[f2fs].do_write_page.[f2fs].write_data_page.[f2fs].do_write_data_page.[f2fs].f2fs_write_data_page.[f2fs].__f2fs_writepage.[f2fs].f2fs_write_cache_pages.[f2fs].f2fs_write_data_pages.[f2fs].do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.f2fs_sync_file.[f2fs]
0.00 ± -1% +Inf% 51307 ± 98% latency_stats.sum.allocate_data_block.[f2fs].do_write_page.[f2fs].write_node_page.[f2fs].f2fs_write_node_page.[f2fs].sync_node_pages.[f2fs].f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
1.391e+08 ± 38% +329.4% 5.974e+08 ± 19% latency_stats.sum.balance_dirty_pages.balance_dirty_pages_ratelimited.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.f2fs_file_write_iter.[f2fs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 188844 ± 44% latency_stats.sum.call_rwsem_down_write_failed.f2fs_submit_merged_bio.[f2fs].f2fs_write_data_pages.[f2fs].do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
13770 ± 38% +4.1e+05% 56903822 ± 3% latency_stats.sum.call_rwsem_down_write_failed.f2fs_submit_page_mbio.[f2fs].do_write_page.[f2fs].write_data_page.[f2fs].do_write_data_page.[f2fs].f2fs_write_data_page.[f2fs].__f2fs_writepage.[f2fs].f2fs_write_cache_pages.[f2fs].f2fs_write_data_pages.[f2fs].do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range
0.00 ± -1% +Inf% 190702 ±103% latency_stats.sum.call_rwsem_down_write_failed.get_node_info.[f2fs].read_node_page.[f2fs].__get_node_page.[f2fs].get_node_page.[f2fs].get_dnode_of_data.[f2fs].get_read_data_page.[f2fs].find_data_page.[f2fs].f2fs_find_entry.[f2fs].f2fs_lookup.[f2fs].lookup_real.path_openat
33143824 ± 9% -84.9% 5019833 ± 18% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_merged_bio.[f2fs].sync_node_pages.[f2fs].f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
2913424 ± 31% -71.7% 823962 ± 30% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.__submit_merged_bio.[f2fs].f2fs_submit_page_mbio.[f2fs].do_write_page.[f2fs].write_node_page.[f2fs].f2fs_write_node_page.[f2fs].sync_node_pages.[f2fs].f2fs_sync_file.[f2fs].vfs_fsync_range
356283 ±145% -54.1% 163527 ± 96% latency_stats.sum.get_request.blk_queue_bio.generic_make_request.submit_bio.submit_bio_wait.f2fs_issue_flush.[f2fs].f2fs_sync_file.[f2fs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
20099124 ±162% -99.9% 10078 ±263% latency_stats.sum.nfs_wait_on_request.nfs_updatepage.nfs_write_end.generic_perform_write.__generic_file_write_iter.generic_file_write_iter.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
33.75 ±173% +2.2e+05% 73005 ±113% latency_stats.sum.pipe_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 615502 ± 53% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_kmem_pages_node.copy_process._do_fork.SyS_clone
0.00 ± -1% +Inf% 98280568 ± 12% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_cache_page_write_begin
0.00 ± -1% +Inf% 301619 ± 56% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.grab_meta_page.[f2fs]
0.00 ± -1% +Inf% 481793 ± 63% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.__page_cache_alloc.pagecache_get_page.new_node_page.[f2fs]
0.00 ± -1% +Inf% 26057234 ± 5% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.new_slab.___slab_alloc.__slab_alloc
0.00 ± -1% +Inf% 101284 ± 93% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_current.pte_alloc_one.__pte_alloc.handle_mm_fault
0.00 ± -1% +Inf% 333615 ± 52% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_vma.handle_mm_fault.__do_page_fault.do_page_fault
0.00 ± -1% +Inf% 138162 ± 93% latency_stats.sum.wait_iff_congested.shrink_inactive_list.shrink_zone_memcg.shrink_zone.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.alloc_pages_vma.wp_page_copy.do_wp_page.handle_mm_fault
13154 ± 1% +23.4% 16233 ± 6% sched_debug.cfs_rq:/.exec_clock.0
8553 ± 0% +54.7% 13230 ± 6% sched_debug.cfs_rq:/.exec_clock.1
8198 ± 1% +51.5% 12425 ± 1% sched_debug.cfs_rq:/.exec_clock.2
8134 ± 1% +51.6% 12333 ± 1% sched_debug.cfs_rq:/.exec_clock.3
6757 ± 1% +23.1% 8318 ± 2% sched_debug.cfs_rq:/.exec_clock.4
6611 ± 1% +28.2% 8477 ± 2% sched_debug.cfs_rq:/.exec_clock.5
6746 ± 2% +23.9% 8357 ± 2% sched_debug.cfs_rq:/.exec_clock.6
6753 ± 2% +24.8% 8425 ± 1% sched_debug.cfs_rq:/.exec_clock.7
8113 ± 0% +35.3% 10975 ± 1% sched_debug.cfs_rq:/.exec_clock.avg
13154 ± 1% +24.5% 16373 ± 4% sched_debug.cfs_rq:/.exec_clock.max
6542 ± 1% +24.7% 8158 ± 1% sched_debug.cfs_rq:/.exec_clock.min
2058 ± 4% +41.6% 2913 ± 5% sched_debug.cfs_rq:/.exec_clock.stddev
72862 ± 1% +11.4% 81181 ± 3% sched_debug.cfs_rq:/.min_vruntime.5
5374 ± 3% -28.0% 3867 ± 16% sched_debug.cfs_rq:/.min_vruntime.stddev
-15402 ± -7% -52.8% -7264 ±-35% sched_debug.cfs_rq:/.spread0.4
-16718 ± -6% -61.4% -6457 ±-62% sched_debug.cfs_rq:/.spread0.5
-15098 ± -7% -63.1% -5565 ±-67% sched_debug.cfs_rq:/.spread0.7
-11694 ± -4% -50.9% -5743 ±-55% sched_debug.cfs_rq:/.spread0.avg
-17636 ± -4% -34.4% -11575 ±-32% sched_debug.cfs_rq:/.spread0.min
5375 ± 3% -28.0% 3867 ± 16% sched_debug.cfs_rq:/.spread0.stddev
676391 ± 8% -47.7% 353495 ± 32% sched_debug.cpu.avg_idle.min
108056 ± 15% +92.1% 207595 ± 16% sched_debug.cpu.avg_idle.stddev
45103 ± 2% +21.1% 54620 ± 3% sched_debug.cpu.nr_load_updates.0
18846 ± 4% +70.1% 32053 ± 4% sched_debug.cpu.nr_load_updates.1
17706 ± 5% +52.2% 26944 ± 3% sched_debug.cpu.nr_load_updates.2
17047 ± 2% +56.1% 26602 ± 3% sched_debug.cpu.nr_load_updates.3
10781 ± 2% +25.4% 13521 ± 2% sched_debug.cpu.nr_load_updates.4
11231 ± 2% +25.5% 14091 ± 3% sched_debug.cpu.nr_load_updates.5
10556 ± 1% +26.0% 13300 ± 2% sched_debug.cpu.nr_load_updates.6
10601 ± 2% +27.7% 13541 ± 3% sched_debug.cpu.nr_load_updates.7
17734 ± 1% +37.2% 24335 ± 1% sched_debug.cpu.nr_load_updates.avg
45103 ± 2% +21.2% 54662 ± 3% sched_debug.cpu.nr_load_updates.max
10419 ± 1% +26.4% 13166 ± 1% sched_debug.cpu.nr_load_updates.min
10889 ± 3% +24.3% 13541 ± 3% sched_debug.cpu.nr_load_updates.stddev
230386 ± 11% +42.9% 329109 ± 8% sched_debug.cpu.nr_switches.0
202377 ± 8% +251.4% 711088 ± 4% sched_debug.cpu.nr_switches.1
171137 ± 19% +180.6% 480135 ± 8% sched_debug.cpu.nr_switches.2
203086 ± 18% +117.9% 442482 ± 6% sched_debug.cpu.nr_switches.3
83980 ± 6% +109.3% 175809 ± 14% sched_debug.cpu.nr_switches.4
89866 ± 11% +75.8% 158005 ± 7% sched_debug.cpu.nr_switches.5
83814 ± 10% +88.5% 158008 ± 10% sched_debug.cpu.nr_switches.6
85831 ± 14% +78.4% 153106 ± 13% sched_debug.cpu.nr_switches.7
143810 ± 1% +126.7% 325968 ± 3% sched_debug.cpu.nr_switches.avg
250243 ± 5% +184.3% 711514 ± 4% sched_debug.cpu.nr_switches.max
76332 ± 3% +87.6% 143189 ± 8% sched_debug.cpu.nr_switches.min
65017 ± 6% +198.8% 194260 ± 5% sched_debug.cpu.nr_switches.stddev
203.80 ± 36% +113.9% 436.00 ± 9% sched_debug.cpu.nr_uninterruptible.4
271442 ± 9% +36.5% 370389 ± 7% sched_debug.cpu.sched_count.0
202559 ± 8% +251.6% 712283 ± 4% sched_debug.cpu.sched_count.1
171330 ± 19% +180.8% 481155 ± 8% sched_debug.cpu.sched_count.2
203293 ± 18% +118.1% 443428 ± 6% sched_debug.cpu.sched_count.3
84236 ± 6% +110.5% 177352 ± 14% sched_debug.cpu.sched_count.4
90110 ± 11% +77.1% 159606 ± 7% sched_debug.cpu.sched_count.5
84044 ± 10% +89.9% 159621 ± 10% sched_debug.cpu.sched_count.6
86056 ± 14% +79.6% 154524 ± 13% sched_debug.cpu.sched_count.7
149134 ± 1% +122.8% 332295 ± 3% sched_debug.cpu.sched_count.avg
277382 ± 7% +158.6% 717324 ± 4% sched_debug.cpu.sched_count.max
76562 ± 3% +89.1% 144747 ± 8% sched_debug.cpu.sched_count.min
73405 ± 6% +167.7% 196494 ± 5% sched_debug.cpu.sched_count.stddev
93022 ± 14% +46.7% 136420 ± 10% sched_debug.cpu.sched_goidle.0
80762 ± 10% +310.6% 331612 ± 4% sched_debug.cpu.sched_goidle.1
65323 ± 24% +230.1% 215622 ± 9% sched_debug.cpu.sched_goidle.2
81365 ± 22% +142.9% 197613 ± 7% sched_debug.cpu.sched_goidle.3
24975 ± 10% +175.0% 68669 ± 17% sched_debug.cpu.sched_goidle.4
28091 ± 18% +113.2% 59878 ± 9% sched_debug.cpu.sched_goidle.5
25071 ± 17% +139.1% 59944 ± 12% sched_debug.cpu.sched_goidle.6
25926 ± 22% +122.1% 57579 ± 17% sched_debug.cpu.sched_goidle.7
53067 ± 1% +165.5% 140918 ± 4% sched_debug.cpu.sched_goidle.avg
103919 ± 6% +219.2% 331734 ± 4% sched_debug.cpu.sched_goidle.max
21303 ± 5% +146.7% 52553 ± 10% sched_debug.cpu.sched_goidle.min
30666 ± 6% +209.8% 95012 ± 5% sched_debug.cpu.sched_goidle.stddev
114436 ± 5% +504.9% 692179 ± 7% sched_debug.cpu.ttwu_count.0
85402 ± 9% +51.2% 129168 ± 8% sched_debug.cpu.ttwu_count.1
97257 ± 16% +34.8% 131088 ± 10% sched_debug.cpu.ttwu_count.2
77150 ± 10% +62.7% 125500 ± 8% sched_debug.cpu.ttwu_count.3
87047 ± 1% +107.1% 180272 ± 3% sched_debug.cpu.ttwu_count.avg
125384 ± 6% +452.1% 692194 ± 7% sched_debug.cpu.ttwu_count.max
57245 ± 8% +22.4% 70061 ± 8% sched_debug.cpu.ttwu_count.min
22286 ± 16% +775.5% 195117 ± 8% sched_debug.cpu.ttwu_count.stddev
65970 ± 1% +25.1% 82512 ± 3% sched_debug.cpu.ttwu_local.0
36267 ± 2% +19.0% 43155 ± 6% sched_debug.cpu.ttwu_local.1
36396 ± 3% +17.8% 42878 ± 8% sched_debug.cpu.ttwu_local.2
36182 ± 3% +15.9% 41920 ± 5% sched_debug.cpu.ttwu_local.3
34843 ± 0% +14.8% 39990 ± 3% sched_debug.cpu.ttwu_local.avg
65970 ± 1% +25.1% 82533 ± 3% sched_debug.cpu.ttwu_local.max
12737 ± 2% +38.6% 17657 ± 4% sched_debug.cpu.ttwu_local.stddev
nhm4: Nehalem
Memory: 4G
fsmark.time.system_time
120 ++--------------------------------------------------------------------+
| O |
115 ++ OOOO O O O |
| O O OOO O O O |
| OO OO OO OO O O O O O O |
110 +OO O O OO O O O O O |
O O O O |
105 ++ |
| * |
100 ++ :: *. * |
|**.*: **.** **. ** * ** .* * * **.** : ** * * *. *|
* * * * * * *** + * * + * : ** : :.* ***.* ** * *
95 ++ * * * * +: * |
| * |
90 ++--------------------------------------------------------------------+
fsmark.time.voluntary_context_switches
3e+06 ++----------------------------------------------------------------+
|O O O |
| O O OO O O O |
2.5e+06 O+O OO O OO O O O O OO O O |
| O O O O OO O OO O OOO O O |
| O O O O O |
2e+06 ++ |
| |
1.5e+06 ++ |
| |
| |
1e+06 ++ |
****.*******.********.*******.*******.*******.********.*******.****
| |
500000 ++----------------------------------------------------------------+
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" (3855 bytes)
Download attachment "reproduce.sh" of type "application/x-sh" (639 bytes)
Powered by blists - more mailing lists