lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<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

Powered by Openwall GNU/*/Linux Powered by OpenVZ