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: <87vb57yk6w.fsf@yhuang-dev.intel.com>
Date:	Mon, 29 Feb 2016 16:50:15 +0800
From:	kernel test robot <ying.huang@...ux.intel.com>
TO:	Lokesh Jaliminche <lokesh.jaliminche@...il.com>
CC:	Theodore Ts'o <tytso@....edu>
Subject: [lkp] [ext4] 1f2d779fed: +34.0% 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 1f2d779fed21806ffed84aa65617c6125f480e85 ("ext4: optimize group search for inode allocation")


=========================================================================================
compiler/cpufreq_governor/disk/filesize/fs/iterations/kconfig/nr_directories/nr_files_per_directory/nr_threads/rootfs/sync_method/tbox_group/test_size/testcase:
  gcc-4.9/performance/1HDD/9B/ext4/1x/x86_64-rhel/16d/256fpd/32t/debian-x86_64-2015-02-07.cgz/fsyncBeforeClose/lkp-ne04/400M/fsmark

commit: 
  29c6eaffc8868ef6fa71997d0ea507a02c52712c
  1f2d779fed21806ffed84aa65617c6125f480e85

29c6eaffc8868ef6 1f2d779fed21806ffed84aa656 
---------------- -------------------------- 
         %stddev     %change         %stddev
             \          |                \  
   1479204 ± 11%     +47.7%    2184108 ±  7%  fsmark.app_overhead
    148.78 ±  3%     +34.0%     199.38 ±  2%  fsmark.files_per_sec
    693.79 ±  3%     -26.1%     512.52 ±  2%  fsmark.time.elapsed_time
    693.79 ±  3%     -26.1%     512.52 ±  2%  fsmark.time.elapsed_time.max
    264.00 ±  0%  +11372.0%      30286 ± 15%  fsmark.time.file_system_inputs
      2.00 ±  0%    +100.0%       4.00 ±  0%  fsmark.time.percent_of_cpu_this_job_got
    408852 ±  1%     +19.0%     486551 ±  0%  fsmark.time.voluntary_context_switches
     42686 ±  4%      -9.2%      38753 ±  1%  softirqs.RCU
     43495 ±  3%      -6.4%      40722 ±  4%  softirqs.SCHED
    717.23 ±  3%     -24.2%     543.65 ±  2%  uptime.boot
      9657 ±  4%     -28.5%       6902 ±  3%  uptime.idle
      3817 ±  1%     +26.2%       4819 ±  2%  vmstat.system.cs
    431.50 ±  1%     +31.4%     567.00 ±  4%  vmstat.system.in
    620517 ±  4%     -23.2%     476762 ±  1%  numa-numastat.node0.local_node
    620519 ±  4%     -23.2%     476763 ±  1%  numa-numastat.node0.numa_hit
    783065 ±  5%     -24.2%     593500 ±  3%  numa-numastat.node1.local_node
    783066 ±  5%     -24.2%     593500 ±  3%  numa-numastat.node1.numa_hit
  36911353 ±  8%     +61.1%   59481022 ±  5%  cpuidle.C1E-NHM.time
      9758 ±  5%     +41.5%      13810 ±  2%  cpuidle.C1E-NHM.usage
 4.406e+08 ±  5%     +34.7%  5.934e+08 ±  5%  cpuidle.C3-NHM.time
    112418 ±  3%     +50.3%     168929 ±  1%  cpuidle.C3-NHM.usage
 1.049e+10 ±  3%     -29.3%  7.418e+09 ±  3%  cpuidle.C6-NHM.time
     81277 ±  3%     -20.9%      64279 ±  2%  cpuidle.POLL.usage
      1059 ±  5%      -8.8%     965.75 ±  7%  slabinfo.blkdev_requests.num_objs
    638.25 ±  4%     +34.7%     859.75 ±  8%  slabinfo.dquot.active_objs
    638.50 ±  4%     +38.5%     884.25 ±  7%  slabinfo.dquot.num_objs
      2026 ± 13%     -26.9%       1480 ±  9%  slabinfo.jbd2_journal_head.active_objs
      2061 ± 12%     -25.8%       1529 ±  9%  slabinfo.jbd2_journal_head.num_objs
      2780 ±  2%     +11.5%       3099 ±  3%  slabinfo.kmalloc-256.active_objs
      0.82 ±  2%     +40.4%       1.16 ±  4%  turbostat.%Busy
     22.25 ±  3%     +40.4%      31.25 ±  6%  turbostat.Avg_MHz
      2.69 ±  5%     +49.3%       4.01 ±  6%  turbostat.CPU%c1
      6.79 ±  2%     +73.8%      11.81 ±  8%  turbostat.CPU%c3
     28.16 ±  3%     +21.5%      34.22 ±  3%  turbostat.Pkg%pc3
     33.39 ±  2%     -41.9%      19.41 ± 12%  turbostat.Pkg%pc6
    693.79 ±  3%     -26.1%     512.52 ±  2%  time.elapsed_time
    693.79 ±  3%     -26.1%     512.52 ±  2%  time.elapsed_time.max
    264.00 ±  0%  +11372.0%      30286 ± 15%  time.file_system_inputs
      2.00 ±  0%    +100.0%       4.00 ±  0%  time.percent_of_cpu_this_job_got
     17.25 ±  1%     +21.9%      21.03 ±  2%  time.system_time
      1.14 ±  2%     +33.8%       1.53 ± 12%  time.user_time
    408852 ±  1%     +19.0%     486551 ±  0%  time.voluntary_context_switches
   1400397 ±  3%     -23.9%    1066153 ±  2%  proc-vmstat.numa_hit
   1400394 ±  3%     -23.9%    1066151 ±  2%  proc-vmstat.numa_local
    247146 ±  4%     -23.1%     190110 ±  1%  proc-vmstat.pgalloc_dma32
   1235243 ±  4%     -23.6%     943649 ±  2%  proc-vmstat.pgalloc_normal
   1461923 ±  4%     -25.7%    1086000 ±  2%  proc-vmstat.pgfault
   1314409 ±  4%     -26.9%     961033 ±  2%  proc-vmstat.pgfree
    132.00 ±  0%  +11365.9%      15135 ± 15%  proc-vmstat.pgpgin
   3926356 ± 18%     -38.5%    2413806 ± 16%  proc-vmstat.pgpgout
    141.50 ± 16%   +1078.3%       1667 ± 52%  numa-vmstat.node0.nr_shmem
     22694 ±  0%     -17.7%      18679 ± 11%  numa-vmstat.node0.nr_slab_reclaimable
      6899 ±  1%     -12.3%       6049 ±  1%  numa-vmstat.node0.nr_slab_unreclaimable
    447024 ±  4%     -13.7%     385841 ±  4%  numa-vmstat.node0.numa_hit
    384765 ±  5%     -15.8%     323917 ±  4%  numa-vmstat.node0.numa_local
     63402 ±  1%     +19.2%      75581 ± 11%  numa-vmstat.node1.nr_file_pages
      2160 ±  0%     -71.6%     613.25 ±143%  numa-vmstat.node1.nr_inactive_anon
     48098 ±  2%     +28.5%      61803 ± 12%  numa-vmstat.node1.nr_inactive_file
      2373 ±  0%     -31.3%       1630 ± 25%  numa-vmstat.node1.nr_mapped
      2207 ±  1%     -70.1%     659.50 ±133%  numa-vmstat.node1.nr_shmem
      5466 ±  4%     +78.8%       9772 ± 24%  numa-vmstat.node1.nr_slab_reclaimable
      4392 ±  1%     +20.0%       5272 ±  1%  numa-vmstat.node1.nr_slab_unreclaimable
    523487 ±  1%     -20.1%     418465 ±  6%  numa-vmstat.node1.numa_hit
    519756 ±  1%     -20.2%     414604 ±  6%  numa-vmstat.node1.numa_local
     90778 ±  0%     -17.7%      74720 ± 11%  numa-meminfo.node0.SReclaimable
     27598 ±  1%     -12.3%      24198 ±  1%  numa-meminfo.node0.SUnreclaim
    567.50 ± 16%   +1075.3%       6670 ± 52%  numa-meminfo.node0.Shmem
    118377 ±  0%     -16.4%      98919 ±  9%  numa-meminfo.node0.Slab
    253611 ±  1%     +19.2%     302328 ± 11%  numa-meminfo.node1.FilePages
    201041 ±  2%     +24.2%     249668 ± 13%  numa-meminfo.node1.Inactive
      8645 ±  0%     -71.6%       2454 ±143%  numa-meminfo.node1.Inactive(anon)
    192396 ±  2%     +28.5%     247214 ± 12%  numa-meminfo.node1.Inactive(file)
      2197 ± 15%     +25.2%       2752 ±  7%  numa-meminfo.node1.KernelStack
      9494 ±  0%     -31.3%       6524 ± 25%  numa-meminfo.node1.Mapped
    345480 ±  1%     +20.3%     415555 ± 10%  numa-meminfo.node1.MemUsed
     21865 ±  4%     +78.8%      39089 ± 24%  numa-meminfo.node1.SReclaimable
     17570 ±  1%     +20.0%      21091 ±  1%  numa-meminfo.node1.SUnreclaim
      8830 ±  1%     -70.1%       2640 ±133%  numa-meminfo.node1.Shmem
     39436 ±  2%     +52.6%      60181 ± 16%  numa-meminfo.node1.Slab
     25609 ±155%     -99.0%     247.00 ±173%  latency_stats.avg.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_dirty_inode.__mark_inode_dirty.generic_update_time.file_update_time.__generic_file_write_iter.ext4_file_write_iter.__vfs_write
     34613 ± 17%     -93.2%       2366 ±100%  latency_stats.avg.submit_bio_wait.blkdev_issue_flush.ext4_sync_file.vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
     62402 ± 14%    -100.0%       0.00 ± -1%  latency_stats.avg.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.__ext4_new_inode.ext4_create.vfs_create
     85292 ± 16%    -100.0%       0.00 ± -1%  latency_stats.avg.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_da_write_begin.generic_perform_write.__generic_file_write_iter
    233836 ±  9%    -100.0%       0.00 ± -1%  latency_stats.avg.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_dirty_inode.__mark_inode_dirty.generic_update_time
     98586 ± 15%    -100.0%       0.00 ± -1%  latency_stats.avg.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_writepages.do_writepages.__filemap_fdatawrite_range
    138727 ± 20%     -99.7%     356.50 ± 34%  latency_stats.avg.wait_transaction_locked.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_dirty_inode.__mark_inode_dirty.generic_update_time.file_update_time.__generic_file_write_iter.ext4_file_write_iter.__vfs_write
      0.00 ± -1%      +Inf%       7289 ± 37%  latency_stats.hits.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.add_dirent_to_buf.ext4_dx_add_entry.ext4_add_entry.ext4_add_nondir.ext4_create.vfs_create.path_openat
     27954 ±  1%     -86.6%       3734 ± 49%  latency_stats.hits.ext4_mb_initialize_context.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.ext4_sync_file.vfs_fsync_range.do_fsync.SyS_fsync
    290546 ± 16%     -85.7%      41420 ± 36%  latency_stats.max.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.__ext4_new_inode.ext4_create.vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
    265287 ±  9%     -85.9%      37329 ± 63%  latency_stats.max.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_mb_mark_diskspace_used.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.ext4_sync_file
      0.00 ± -1%      +Inf%      16683 ± 61%  latency_stats.max.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.add_dirent_to_buf.ext4_add_entry.ext4_add_nondir.ext4_create.vfs_create.path_openat.do_filp_open
      0.00 ± -1%      +Inf%      29279 ± 73%  latency_stats.max.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.add_dirent_to_buf.ext4_dx_add_entry.ext4_add_entry.ext4_add_nondir.ext4_create.vfs_create.path_openat
     63561 ±165%     -99.6%     267.75 ±173%  latency_stats.max.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_dirty_inode.__mark_inode_dirty.generic_update_time.file_update_time.__generic_file_write_iter.ext4_file_write_iter.__vfs_write
     62202 ± 74%     -95.4%       2841 ± 21%  latency_stats.max.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_dirty_inode.__mark_inode_dirty.generic_write_end.ext4_da_write_end.generic_perform_write.__generic_file_write_iter.ext4_file_write_iter
      0.00 ± -1%      +Inf%      37841 ± 39%  latency_stats.max.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_ext_tree_init.__ext4_new_inode.ext4_create.vfs_create.path_openat.do_filp_open.do_sys_open
    279828 ± 21%     -94.5%      15429 ± 97%  latency_stats.max.ext4_mb_initialize_context.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.ext4_sync_file.vfs_fsync_range.do_fsync.SyS_fsync
    176558 ± 21%     -95.8%       7488 ±102%  latency_stats.max.submit_bio_wait.blkdev_issue_flush.ext4_sync_file.vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
    381747 ± 21%    -100.0%       0.00 ± -1%  latency_stats.max.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.__ext4_new_inode.ext4_create.vfs_create
    165796 ± 24%    -100.0%       0.00 ± -1%  latency_stats.max.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_da_write_begin.generic_perform_write.__generic_file_write_iter
    406817 ± 14%    -100.0%       0.00 ± -1%  latency_stats.max.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_dirty_inode.__mark_inode_dirty.generic_update_time
    165247 ± 40%    -100.0%       0.00 ± -1%  latency_stats.max.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_writepages.do_writepages.__filemap_fdatawrite_range
    324551 ± 12%     -91.2%      28575 ± 43%  latency_stats.max.wait_transaction_locked.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.__ext4_new_inode.ext4_create.vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open
    458237 ± 15%     -99.5%       2140 ± 76%  latency_stats.max.wait_transaction_locked.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_dirty_inode.__mark_inode_dirty.generic_update_time.file_update_time.__generic_file_write_iter.ext4_file_write_iter.__vfs_write
    234367 ± 47%     -94.6%      12766 ± 80%  latency_stats.max.wait_transaction_locked.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.ext4_sync_file.vfs_fsync_range.do_fsync
      0.00 ± -1%      +Inf%      23646 ±123%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_mb_mark_diskspace_used.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_getblk.ext4_bread.ext4_append.do_split.ext4_dx_add_entry
      0.00 ± -1%      +Inf%      30398 ± 50%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_mb_mark_diskspace_used.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_getblk.ext4_bread.ext4_append.ext4_mkdir.vfs_mkdir
      0.00 ± -1%      +Inf%      56525 ±105%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_mb_mark_diskspace_used.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_getblk.ext4_bread.ext4_append.make_indexed_dir.ext4_add_entry
  41228828 ±  7%     -92.4%    3149423 ± 67%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_mb_mark_diskspace_used.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.ext4_sync_file
      0.00 ± -1%      +Inf%    1241212 ± 55%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.add_dirent_to_buf.ext4_add_entry.ext4_add_nondir.ext4_create.vfs_create.path_openat.do_filp_open
      0.00 ± -1%      +Inf%    2727180 ± 46%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.add_dirent_to_buf.ext4_dx_add_entry.ext4_add_entry.ext4_add_nondir.ext4_create.vfs_create.path_openat
      0.00 ± -1%      +Inf%      88155 ± 72%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_dirty_inode.__mark_inode_dirty.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_getblk.ext4_bread
    200757 ±159%     -99.5%     988.25 ±173%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_dirty_inode.__mark_inode_dirty.generic_update_time.file_update_time.__generic_file_write_iter.ext4_file_write_iter.__vfs_write
   1755344 ±  9%     -94.1%     103667 ± 53%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_dirty_inode.__mark_inode_dirty.generic_write_end.ext4_da_write_end.generic_perform_write.__generic_file_write_iter.ext4_file_write_iter
      0.00 ± -1%      +Inf%    4885655 ± 35%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_ext_tree_init.__ext4_new_inode.ext4_create.vfs_create.path_openat.do_filp_open.do_sys_open
      0.00 ± -1%      +Inf%      35213 ± 40%  latency_stats.sum.do_get_write_access.jbd2_journal_get_write_access.__ext4_journal_get_write_access.ext4_reserve_inode_write.ext4_mark_inode_dirty.ext4_ext_tree_init.__ext4_new_inode.ext4_mkdir.vfs_mkdir.SyS_mkdir.entry_SYSCALL_64_fastpath
  35021878 ± 12%     -97.3%     959921 ± 59%  latency_stats.sum.ext4_mb_initialize_context.ext4_mb_new_blocks.ext4_ext_map_blocks.ext4_map_blocks.ext4_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.ext4_sync_file.vfs_fsync_range.do_fsync.SyS_fsync
   2610110 ± 10%     -99.6%      10320 ±115%  latency_stats.sum.submit_bio_wait.blkdev_issue_flush.ext4_sync_file.vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath
   7233874 ± 35%    -100.0%       0.00 ± -1%  latency_stats.sum.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.__ext4_new_inode.ext4_create.vfs_create
   1159776 ± 50%    -100.0%       0.00 ± -1%  latency_stats.sum.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_da_write_begin.generic_perform_write.__generic_file_write_iter
    822013 ± 35%    -100.0%       0.00 ± -1%  latency_stats.sum.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_dirty_inode.__mark_inode_dirty.generic_update_time
    810979 ± 49%    -100.0%       0.00 ± -1%  latency_stats.sum.submit_bio_wait.blkdev_issue_flush.jbd2_cleanup_journal_tail.jbd2_log_do_checkpoint.__jbd2_log_wait_for_space.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_writepages.do_writepages.__filemap_fdatawrite_range
   3757907 ±  7%     -99.8%       7957 ± 37%  latency_stats.sum.wait_transaction_locked.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_dirty_inode.__mark_inode_dirty.generic_update_time.file_update_time.__generic_file_write_iter.ext4_file_write_iter.__vfs_write
   1020128 ± 78%     -94.6%      54940 ± 73%  latency_stats.sum.wait_transaction_locked.add_transaction_credits.start_this_handle.jbd2__journal_start.__ext4_journal_start_sb.ext4_writepages.do_writepages.__filemap_fdatawrite_range.filemap_write_and_wait_range.ext4_sync_file.vfs_fsync_range.do_fsync
      5691 ±  8%     -22.7%       4398 ± 14%  sched_debug.cfs_rq:/.exec_clock.0
    854.81 ± 10%    +157.0%       2196 ± 27%  sched_debug.cfs_rq:/.exec_clock.11
      2946 ±  3%     -16.0%       2475 ±  4%  sched_debug.cfs_rq:/.exec_clock.4
      2075 ±  6%     -19.3%       1673 ± 10%  sched_debug.cfs_rq:/.exec_clock.8
      2.75 ± 30%    +318.2%      11.50 ± 58%  sched_debug.cfs_rq:/.load_avg.1
      5.25 ± 75%    +266.7%      19.25 ± 20%  sched_debug.cfs_rq:/.load_avg.7
     12.00 ± 31%     -70.8%       3.50 ± 24%  sched_debug.cfs_rq:/.load_avg.8
      1.25 ± 34%    +860.0%      12.00 ± 33%  sched_debug.cfs_rq:/.load_avg.9
      0.22 ± 37%    +116.7%       0.47 ± 19%  sched_debug.cfs_rq:/.load_avg.min
      6649 ± 29%     -31.8%       4537 ± 15%  sched_debug.cfs_rq:/.min_vruntime.8
      7953 ±  4%     -12.7%       6940 ±  3%  sched_debug.cfs_rq:/.min_vruntime.avg
     19991 ±  6%     -22.3%      15531 ±  5%  sched_debug.cfs_rq:/.min_vruntime.max
      4514 ±  8%     -25.9%       3343 ±  9%  sched_debug.cfs_rq:/.min_vruntime.stddev
      0.75 ±110%    +433.3%       4.00 ± 30%  sched_debug.cfs_rq:/.nr_spread_over.13
      4514 ±  8%     -25.9%       3343 ±  9%  sched_debug.cfs_rq:/.spread0.stddev
     22.00 ± 19%    +104.5%      45.00 ± 19%  sched_debug.cfs_rq:/.util_avg.2
    361962 ±  3%     -24.9%     271884 ±  0%  sched_debug.cpu.clock.0
    361962 ±  3%     -24.9%     271883 ±  0%  sched_debug.cpu.clock.1
    361968 ±  3%     -24.9%     271888 ±  0%  sched_debug.cpu.clock.10
    361969 ±  3%     -24.9%     271884 ±  0%  sched_debug.cpu.clock.11
    361969 ±  3%     -24.9%     271889 ±  0%  sched_debug.cpu.clock.12
    361963 ±  3%     -24.9%     271887 ±  0%  sched_debug.cpu.clock.13
    361971 ±  3%     -24.9%     271890 ±  0%  sched_debug.cpu.clock.14
    361966 ±  3%     -24.9%     271893 ±  0%  sched_debug.cpu.clock.15
    361965 ±  3%     -24.9%     271886 ±  0%  sched_debug.cpu.clock.2
    361945 ±  3%     -24.9%     271880 ±  0%  sched_debug.cpu.clock.3
    361968 ±  3%     -24.9%     271885 ±  0%  sched_debug.cpu.clock.4
    361964 ±  3%     -24.9%     271890 ±  0%  sched_debug.cpu.clock.5
    361971 ±  3%     -24.9%     271886 ±  0%  sched_debug.cpu.clock.6
    361967 ±  3%     -24.9%     271890 ±  0%  sched_debug.cpu.clock.7
    361963 ±  3%     -24.9%     271886 ±  0%  sched_debug.cpu.clock.8
    361965 ±  3%     -24.9%     271881 ±  0%  sched_debug.cpu.clock.9
    361965 ±  3%     -24.9%     271886 ±  0%  sched_debug.cpu.clock.avg
    361973 ±  3%     -24.9%     271893 ±  0%  sched_debug.cpu.clock.max
    361913 ±  3%     -24.9%     271854 ±  0%  sched_debug.cpu.clock.min
     15.52 ± 18%     -27.6%      11.24 ± 25%  sched_debug.cpu.clock.stddev
    361962 ±  3%     -24.9%     271884 ±  0%  sched_debug.cpu.clock_task.0
    361962 ±  3%     -24.9%     271883 ±  0%  sched_debug.cpu.clock_task.1
    361968 ±  3%     -24.9%     271888 ±  0%  sched_debug.cpu.clock_task.10
    361969 ±  3%     -24.9%     271884 ±  0%  sched_debug.cpu.clock_task.11
    361969 ±  3%     -24.9%     271889 ±  0%  sched_debug.cpu.clock_task.12
    361963 ±  3%     -24.9%     271887 ±  0%  sched_debug.cpu.clock_task.13
    361971 ±  3%     -24.9%     271890 ±  0%  sched_debug.cpu.clock_task.14
    361966 ±  3%     -24.9%     271893 ±  0%  sched_debug.cpu.clock_task.15
    361965 ±  3%     -24.9%     271886 ±  0%  sched_debug.cpu.clock_task.2
    361945 ±  3%     -24.9%     271880 ±  0%  sched_debug.cpu.clock_task.3
    361968 ±  3%     -24.9%     271885 ±  0%  sched_debug.cpu.clock_task.4
    361964 ±  3%     -24.9%     271890 ±  0%  sched_debug.cpu.clock_task.5
    361971 ±  3%     -24.9%     271886 ±  0%  sched_debug.cpu.clock_task.6
    361967 ±  3%     -24.9%     271890 ±  0%  sched_debug.cpu.clock_task.7
    361963 ±  3%     -24.9%     271886 ±  0%  sched_debug.cpu.clock_task.8
    361965 ±  3%     -24.9%     271881 ±  0%  sched_debug.cpu.clock_task.9
    361965 ±  3%     -24.9%     271886 ±  0%  sched_debug.cpu.clock_task.avg
    361973 ±  3%     -24.9%     271893 ±  0%  sched_debug.cpu.clock_task.max
    361913 ±  3%     -24.9%     271854 ±  0%  sched_debug.cpu.clock_task.min
     15.52 ± 18%     -27.6%      11.24 ± 25%  sched_debug.cpu.clock_task.stddev
    594.58 ± 12%     -30.7%     412.29 ± 12%  sched_debug.cpu.curr->pid.avg
      8265 ±  6%     -36.1%       5285 ± 12%  sched_debug.cpu.curr->pid.max
      2062 ±  7%     -34.6%       1348 ± 11%  sched_debug.cpu.curr->pid.stddev
     25768 ±  4%     -11.4%      22823 ±  7%  sched_debug.cpu.nr_load_updates.6
      7155 ±  5%     -21.9%       5586 ± 14%  sched_debug.cpu.nr_load_updates.stddev
      7911 ±  9%    +225.8%      25779 ± 60%  sched_debug.cpu.nr_switches.11
     61043 ±  5%     -20.1%      48763 ± 23%  sched_debug.cpu.nr_switches.14
     11503 ± 41%    +164.9%      30476 ± 57%  sched_debug.cpu.nr_switches.15
      7955 ±  3%    +138.1%      18945 ± 39%  sched_debug.cpu.nr_switches.9
      7498 ±  6%    +134.5%      17581 ± 40%  sched_debug.cpu.nr_switches.min
     61163 ±  7%     -18.1%      50102 ±  9%  sched_debug.cpu.nr_switches.stddev
    -33543 ± -4%     -41.2%     -19708 ±-38%  sched_debug.cpu.nr_uninterruptible.0
      3853 ±  6%     -52.0%       1849 ± 41%  sched_debug.cpu.nr_uninterruptible.10
     86.75 ± 59%    +195.4%     256.25 ± 22%  sched_debug.cpu.nr_uninterruptible.11
      3849 ± 10%     -48.7%       1974 ± 45%  sched_debug.cpu.nr_uninterruptible.12
     83.25 ± 66%    +253.8%     294.50 ± 31%  sched_debug.cpu.nr_uninterruptible.13
      3930 ±  7%     -48.4%       2029 ± 42%  sched_debug.cpu.nr_uninterruptible.14
     77.00 ± 35%    +241.6%     263.00 ± 25%  sched_debug.cpu.nr_uninterruptible.15
      4437 ± 11%     -55.8%       1959 ± 55%  sched_debug.cpu.nr_uninterruptible.2
      4509 ±  5%     -51.1%       2206 ± 51%  sched_debug.cpu.nr_uninterruptible.4
      4507 ±  9%     -50.6%       2226 ± 51%  sched_debug.cpu.nr_uninterruptible.6
      7142 ±  6%     -29.1%       5065 ± 29%  sched_debug.cpu.nr_uninterruptible.8
     87.25 ± 42%    +204.3%     265.50 ± 24%  sched_debug.cpu.nr_uninterruptible.9
      7146 ±  6%     -29.1%       5065 ± 29%  sched_debug.cpu.nr_uninterruptible.max
    -33542 ± -4%     -41.2%     -19707 ±-38%  sched_debug.cpu.nr_uninterruptible.min
      8953 ±  4%     -41.4%       5250 ± 38%  sched_debug.cpu.nr_uninterruptible.stddev
      7958 ±  9%    +224.4%      25817 ± 60%  sched_debug.cpu.sched_count.11
     11549 ± 40%    +169.2%      31095 ± 59%  sched_debug.cpu.sched_count.15
      8003 ±  3%    +137.1%      18980 ± 39%  sched_debug.cpu.sched_count.9
      7638 ±  6%    +130.6%      17616 ± 40%  sched_debug.cpu.sched_count.min
      3539 ±  9%    +217.3%      11231 ± 61%  sched_debug.cpu.sched_goidle.11
      5137 ± 46%    +159.0%      13303 ± 59%  sched_debug.cpu.sched_goidle.15
      3543 ±  4%    +119.8%       7789 ± 37%  sched_debug.cpu.sched_goidle.9
      3327 ±  5%    +112.9%       7083 ± 37%  sched_debug.cpu.sched_goidle.min
     28088 ±  7%     -18.9%      22770 ±  7%  sched_debug.cpu.sched_goidle.stddev
     40517 ± 77%     -70.7%      11888 ± 56%  sched_debug.cpu.ttwu_count.15
    119597 ±  3%     -30.9%      82689 ± 19%  sched_debug.cpu.ttwu_local.0
      4054 ±  8%     +44.6%       5862 ± 23%  sched_debug.cpu.ttwu_local.1
      5950 ±  3%     -32.2%       4036 ± 21%  sched_debug.cpu.ttwu_local.10
      1259 ±  9%    +154.6%       3206 ± 43%  sched_debug.cpu.ttwu_local.13
      1530 ±  8%    +126.2%       3461 ± 40%  sched_debug.cpu.ttwu_local.15
      8444 ±  6%     -25.1%       6328 ± 12%  sched_debug.cpu.ttwu_local.2
      3189 ± 15%     +53.0%       4878 ± 31%  sched_debug.cpu.ttwu_local.3
      6155 ±  9%     -20.3%       4905 ±  6%  sched_debug.cpu.ttwu_local.4
      6013 ± 12%     -32.9%       4033 ±  8%  sched_debug.cpu.ttwu_local.6
      9433 ±  2%     -19.1%       7632 ±  8%  sched_debug.cpu.ttwu_local.8
      1303 ± 12%    +131.5%       3016 ± 38%  sched_debug.cpu.ttwu_local.9
     11588 ±  2%     -18.7%       9421 ±  5%  sched_debug.cpu.ttwu_local.avg
    119620 ±  3%     -30.9%      82707 ± 19%  sched_debug.cpu.ttwu_local.max
      1185 ± 12%     +85.3%       2196 ± 26%  sched_debug.cpu.ttwu_local.min
     28011 ±  3%     -32.1%      19019 ± 21%  sched_debug.cpu.ttwu_local.stddev
    361971 ±  3%     -24.9%     271891 ±  0%  sched_debug.cpu_clk
    360052 ±  3%     -24.9%     270234 ±  0%  sched_debug.ktime
    361971 ±  3%     -24.9%     271891 ±  0%  sched_debug.sched_clk


lkp-ne04: Nehalem-EP
Memory: 12G



                                  turbostat._Busy

  1.5 ++--------------------------------------------------------------------+
      |                                               O     O               |
  1.4 ++                    O                                               |
  1.3 ++                                                                    |
      |       O                  O       O                       O          |
  1.2 ++         O                    O                        O       O    |
      |                             O       O       O    O          O       O
  1.1 O+ O O          O       O               O                          O  |
      |            O     O                       O                          |
    1 ++                                                                    |
      |                                                                     |
  0.9 ++                .*..                *.  .*..        *..             |
  0.8 *+.*.*..    .*..*.    *.*..  .*.    ..  *.    *.*.. ..   *.*..*..*.*..*
      |       *..*               *.   *..*               *                  |
  0.7 ++--------------------------------------------------------------------+


                                     uptime.idle

  10500 ++------------------------------------------------------------------+
        *            *..                                           .*..     |
  10000 ++   *..   ..   *       *.*..  *..*..         .*..*.  .*..*    *.   |
   9500 +++ +    .*      +    ..      +      *.*..*.*.      *.           *..|
        |  *    *         *..*       *                                      *
   9000 ++                                                                  |
   8500 ++                                                                  |
        |                                                                   |
   8000 ++   O               O                         O    O               |
   7500 O+      O O       O          O    O       O O     O    O            |
        |  O                      O    O       O                  O         O
   7000 ++           O  O       O                                   O       |
   6500 ++                                                             O O  |
        |                                                                   |
   6000 ++-----------------------------------O------------------------------+

                       fsmark.time.voluntary_context_switches

  500000 ++-----------------------------------------------------------------+
  490000 ++ O           O     O         O  O O                           O  |
         O    O  O         O         O            O            O  O O  O    |
  480000 ++        O  O         O  O            O    O O  O  O              O
  470000 ++                                                                 |
         |                                                                  |
  460000 ++                                                                 |
  450000 ++                                                                 |
  440000 ++                                                                 |
         |                                                                  |
  430000 ++                                                                 |
  420000 ++                                 .*..  *..             *.        |
         *.. .*..    .*.*..  .*.*..*.*..*..*     +    .*..*..   ..  *..     |
  410000 ++ *    *.*.      *.                   *    *       *.*       *.*..|
  400000 ++-----------------------------------------------------------------*


                           fsmark.time.file_system_inputs

  40000 ++------------------------------------------------------------------+
        |                                                                O  |
  35000 ++                                                                  |
  30000 ++                                   O                         O    |
        |  O         O  O    O         O  O       O                         |
  25000 O+   O  O         O     O    O         O    O     O    O  O O       O
        |         O               O                                         |
  20000 ++                                             O    O               |
        |                                                                   |
  15000 ++                                                                  |
  10000 ++                                                                  |
        |                                                                   |
   5000 ++                                                                  |
        |                                                                   |
      0 *+-*-*--*-*--*--*-*--*--*-*--*-*--*--*-*--*-*--*--*-*--*--*-*--*-*--*


                                 proc-vmstat.pgpgin

  20000 ++------------------------------------------------------------------+
  18000 ++                                                               O  |
        |                                                                   |
  16000 ++                                   O                         O    |
  14000 ++ O         O  O    O         O  O       O                         |
        O    O  O         O     O    O         O    O     O    O  O O       O
  12000 ++        O               O                                         |
  10000 ++                                             O    O               |
   8000 ++                                                                  |
        |                                                                   |
   6000 ++                                                                  |
   4000 ++                                                                  |
        |                                                                   |
   2000 ++                                                                  |
      0 *+-*-*--*-*--*--*-*--*--*-*--*-*--*--*-*--*-*--*--*-*--*--*-*--*-*--*


                              proc-vmstat.pgalloc_normal

  1.35e+06 ++---------------------------------------------------------------+
   1.3e+06 ++                                                        *      |
           *            *.               *..*                   *. .. +     |
  1.25e+06 ++   *..   ..  *..           +    +         .*..*. ..  *    *..  |
   1.2e+06 +++ +    .*       *.*..*.*..*      *..*.*..*      *            *.|
           |  *    *                                                        *
  1.15e+06 ++                                                               |
   1.1e+06 ++                                                               |
  1.05e+06 ++   O  O           O            O                               |
           O  O      O       O      O  O O         O  O O  O O  O           |
     1e+06 ++                     O              O                          O
    950000 ++           O O                                       O  O      |
           |                                                           O  O |
    900000 ++                                 O                             |
    850000 ++---------------------------------------------------------------+


	[*] bisect-good sample
	[O] bisect-bad  sample

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" (3894 bytes)

View attachment "reproduce" of type "text/plain" (2134 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ