Messages in this thread | | | From | kernel test robot <> | Subject | [lkp] [mm] 46cfb125d6: No primary result change, 11.3% fsmark.time.minor_page_faults | Date | Thu, 08 Oct 2015 14:10:51 +0800 |
| |
FYI, we noticed the below changes on
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master commit 46cfb125d673f2f30acf06b3c02485bf693d54e9 ("mm: increase SWAP_CLUSTER_MAX to batch TLB flushes")
========================================================================================= 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/btrfs/16MB/60G/fsyncBeforeClose/16d/256fpd
commit: 284f74b8c06045dcc1b5caaf5f412219f18cfc60 46cfb125d673f2f30acf06b3c02485bf693d54e9
284f74b8c06045dc 46cfb125d673f2f30acf06b3c0 ---------------- -------------------------- %stddev %change %stddev \ | \ 14126 ± 3% +11.3% 15720 ± 4% fsmark.time.minor_page_faults 17.00 ± 0% +5.9% 18.00 ± 0% fsmark.time.percent_of_cpu_this_job_got 88.97 ± 0% +8.8% 96.80 ± 0% fsmark.time.system_time 52682 ± 0% -18.1% 43155 ± 0% softirqs.BLOCK 14126 ± 3% +11.3% 15720 ± 4% time.minor_page_faults 25179737 ± 8% -18.5% 20528816 ± 5% cpuidle.C1E-NHM.time 133847 ± 3% -14.1% 114908 ± 4% cpuidle.C3-NHM.usage 92678 ± 2% -20.8% 73391 ± 1% meminfo.Dirty 124930 ± 1% +21.4% 151629 ± 0% meminfo.Writeback 3.45 ± 1% +3.4% 3.56 ± 0% turbostat.%Busy 111.50 ± 1% +3.8% 115.75 ± 0% turbostat.Avg_MHz 101775 ±157% +329.9% 437551 ±169% latency_stats.max.btrfs_tree_lock.[btrfs].btrfs_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].btrfs_new_inode.[btrfs].btrfs_create.[btrfs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath 8005 ± 19% +5364.1% 437413 ±169% latency_stats.max.btrfs_tree_lock.[btrfs].btrfs_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].insert_with_overflow.[btrfs].btrfs_insert_dir_item.[btrfs].btrfs_add_link.[btrfs].btrfs_create.[btrfs].vfs_create.path_openat.do_filp_open.do_sys_open 469382 ± 66% -98.7% 6266 ± 18% latency_stats.max.btrfs_tree_lock.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_inode.[btrfs].__btrfs_update_delayed_inode.[btrfs].btrfs_commit_inode_delayed_items.[btrfs].btrfs_log_inode.[btrfs].btrfs_log_inode_parent.[btrfs].btrfs_log_dentry_safe.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync 363661 ±103% -100.0% 84.50 ± 19% latency_stats.max.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].copy_items.[btrfs].btrfs_log_inode.[btrfs].btrfs_log_inode_parent.[btrfs].btrfs_log_dentry_safe.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync 404767 ±170% -98.6% 5611 ±114% latency_stats.max.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_dir_item.[btrfs].btrfs_lookup_dentry.[btrfs].btrfs_lookup.[btrfs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath 35516 ±145% +1126.2% 435514 ±170% latency_stats.max.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_xattr.[btrfs].__btrfs_getxattr.[btrfs].btrfs_getxattr.[btrfs].cap_inode_need_killpriv.security_inode_need_killpriv.dentry_needs_remove_privs.file_remove_privs.btrfs_file_write_iter.[btrfs].__vfs_write 1107650 ±130% -72.4% 305514 ± 96% latency_stats.max.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_range.btrfs_wait_marked_extents.[btrfs].btrfs_write_and_wait_transaction.[btrfs].btrfs_commit_transaction.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync.entry_SYSCALL_64_fastpath 199353 ± 84% +377.4% 951697 ±154% latency_stats.sum.btrfs_tree_lock.[btrfs].btrfs_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].btrfs_new_inode.[btrfs].btrfs_create.[btrfs].vfs_create.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath 62503 ± 33% +657.0% 473167 ±155% latency_stats.sum.btrfs_tree_lock.[btrfs].btrfs_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].insert_with_overflow.[btrfs].btrfs_insert_dir_item.[btrfs].btrfs_add_link.[btrfs].btrfs_create.[btrfs].vfs_create.path_openat.do_filp_open.do_sys_open 5568453 ± 73% -75.9% 1342137 ± 11% latency_stats.sum.btrfs_tree_lock.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_inode.[btrfs].__btrfs_update_delayed_inode.[btrfs].btrfs_commit_inode_delayed_items.[btrfs].btrfs_log_inode.[btrfs].btrfs_log_inode_parent.[btrfs].btrfs_log_dentry_safe.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync 1647770 ±118% -99.5% 7733 ± 11% latency_stats.sum.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_insert_empty_items.[btrfs].copy_items.[btrfs].btrfs_log_inode.[btrfs].btrfs_log_inode_parent.[btrfs].btrfs_log_dentry_safe.[btrfs].btrfs_sync_file.[btrfs].vfs_fsync_range.do_fsync.SyS_fsync 4780008 ±170% -99.2% 36367 ± 61% latency_stats.sum.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_dir_item.[btrfs].btrfs_lookup_dentry.[btrfs].btrfs_lookup.[btrfs].lookup_real.path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath 88081 ±118% +919.9% 898339 ±164% latency_stats.sum.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_xattr.[btrfs].__btrfs_getxattr.[btrfs].btrfs_getxattr.[btrfs].cap_inode_need_killpriv.security_inode_need_killpriv.dentry_needs_remove_privs.file_remove_privs.btrfs_file_write_iter.[btrfs].__vfs_write 20472 ± 0% -24.3% 15507 ± 0% proc-vmstat.allocstall 1021 ± 34% -73.5% 271.00 ± 26% proc-vmstat.kswapd_high_wmark_hit_quickly 23142 ± 2% -20.9% 18301 ± 1% proc-vmstat.nr_dirty 2441 ± 28% -82.8% 419.50 ± 29% proc-vmstat.nr_vmscan_immediate_reclaim 31236 ± 1% +21.5% 37946 ± 1% proc-vmstat.nr_writeback 8479 ± 5% +18.8% 10075 ± 7% proc-vmstat.numa_hint_faults 8479 ± 5% +18.8% 10075 ± 7% proc-vmstat.numa_hint_faults_local 9293 ± 4% +18.1% 10977 ± 6% proc-vmstat.numa_pte_updates 683.50 ± 95% -86.0% 96.00 ± 67% proc-vmstat.pgdeactivate 1298 ± 11% -39.7% 783.50 ± 45% proc-vmstat.pgmigrate_success 796.75 ± 81% -71.9% 224.25 ± 30% proc-vmstat.pgrefill_dma 2969680 ± 0% +110.2% 6241700 ± 0% proc-vmstat.pgscan_direct_dma32 9735 ± 15% -86.3% 1332 ± 20% proc-vmstat.pgscan_kswapd_dma 12295232 ± 0% -26.7% 9017741 ± 0% proc-vmstat.pgscan_kswapd_dma32 2893713 ± 1% +113.0% 6162694 ± 0% proc-vmstat.pgsteal_direct_dma32 12278505 ± 0% -26.7% 9003970 ± 0% proc-vmstat.pgsteal_kswapd_dma32 7.00 ±141% +225.0% 22.75 ± 30% sched_debug.cfs_rq[0]:/.load 88.75 ± 47% +166.2% 236.25 ± 19% sched_debug.cfs_rq[0]:/.tg_load_avg 0.50 ±100% +3150.0% 16.25 ± 47% sched_debug.cfs_rq[1]:/.runnable_load_avg 86.25 ± 42% +175.1% 237.25 ± 19% sched_debug.cfs_rq[1]:/.tg_load_avg 87.25 ± 41% +175.4% 240.25 ± 20% sched_debug.cfs_rq[2]:/.tg_load_avg 22.75 ± 35% +217.6% 72.25 ± 44% sched_debug.cfs_rq[2]:/.util_avg 7.00 ± 82% +128.6% 16.00 ± 32% sched_debug.cfs_rq[3]:/.load_avg -533786 ± -7% -15.0% -453453 ± -6% sched_debug.cfs_rq[3]:/.spread0 88.75 ± 40% +169.6% 239.25 ± 19% sched_debug.cfs_rq[3]:/.tg_load_avg 7.00 ± 82% +135.7% 16.50 ± 35% sched_debug.cfs_rq[3]:/.tg_load_avg_contrib 23.50 ± 58% +217.0% 74.50 ± 44% sched_debug.cfs_rq[3]:/.util_avg 89.00 ± 40% +168.8% 239.25 ± 19% sched_debug.cfs_rq[4]:/.tg_load_avg 89.50 ± 39% +169.6% 241.25 ± 18% sched_debug.cfs_rq[5]:/.tg_load_avg 28.75 ± 90% +173.0% 78.50 ± 61% sched_debug.cfs_rq[5]:/.util_avg 5799 ± 5% +14.2% 6619 ± 2% sched_debug.cfs_rq[6]:/.exec_clock 90.50 ± 39% +167.7% 242.25 ± 19% sched_debug.cfs_rq[6]:/.tg_load_avg 13.00 ±105% +142.3% 31.50 ± 60% sched_debug.cfs_rq[7]:/.load_avg 93.00 ± 40% +162.4% 244.00 ± 19% sched_debug.cfs_rq[7]:/.tg_load_avg 13.00 ±105% +165.4% 34.50 ± 64% sched_debug.cfs_rq[7]:/.tg_load_avg_contrib 0.75 ±110% +733.3% 6.25 ± 60% sched_debug.cpu#0.cpu_load[3] 0.75 ±110% +1033.3% 8.50 ± 45% sched_debug.cpu#0.cpu_load[4] 34.33 ±141% +759.2% 295.00 ± 42% sched_debug.cpu#0.curr->pid 5.75 ±153% +791.3% 51.25 ± 83% sched_debug.cpu#0.load -821.75 ± -3% -13.2% -713.25 ± -2% sched_debug.cpu#0.nr_uninterruptible 1.00 ±100% +750.0% 8.50 ± 48% sched_debug.cpu#1.cpu_load[1] 1.75 ± 84% +400.0% 8.75 ± 48% sched_debug.cpu#1.cpu_load[2] 30329 ± 51% -46.2% 16309 ± 5% sched_debug.cpu#2.nr_load_updates 4.00 ± 98% +918.8% 40.75 ± 69% sched_debug.cpu#6.cpu_load[0] 2.25 ± 85% +1400.0% 33.75 ± 85% sched_debug.cpu#6.cpu_load[1] 1.75 ± 84% +1557.1% 29.00 ± 90% sched_debug.cpu#6.cpu_load[2] 1.25 ± 87% +1960.0% 25.75 ± 93% sched_debug.cpu#6.cpu_load[3] 149.50 ± 20% -29.3% 105.75 ± 15% sched_debug.cpu#6.nr_uninterruptible 36817 ± 8% +4975.8% 1868779 ±169% sched_debug.cpu#7.nr_switches 37382 ± 9% +4900.3% 1869233 ±169% sched_debug.cpu#7.sched_count 16197 ± 9% +5605.7% 924151 ±169% sched_debug.cpu#7.sched_goidle 0.49 ± 2% +1020.2% 5.53 ±103% sched_debug.rt_rq[0]:/.rt_time
nhm4: Nehalem Memory: 4G
fsmark.time.system_time
100 ++--------------------------------------------------------------------+ | O | 98 O+ O O O O O O | | O O O O O O O | 96 ++ O O O O | | | 94 ++ | | | 92 ++ | | .*. .*.*.. .*.. | 90 ++ .*.*. *. *.*..* * | *..*.*..*. .*. .*. + .*.*..*.*..*.*..*.* 88 ++ *. *..* *. | | | 86 ++--------------------------------------------------------------------+
[*] 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 --- LKP_SERVER: inn LKP_CGI_PORT: 80 LKP_CIFS_PORT: 139 testcase: fsmark default-monitors: wait: activate-monitor kmsg: uptime: iostat: vmstat: numa-numastat: numa-vmstat: numa-meminfo: proc-vmstat: proc-stat: interval: 10 meminfo: slabinfo: interrupts: lock_stat: latency_stats: softirqs: bdi_dev_mapping: diskstats: nfsstat: cpuidle: cpufreq-stats: turbostat: pmeter: sched_debug: interval: 60 cpufreq_governor: performance default-watchdogs: oom-killer: watchdog: commit: 0293645856ec527639b5902f021fa5aeba93e305 model: Nehalem nr_cpu: 8 memory: 4G hdd_partitions: "/dev/disk/by-id/ata-WDC_WD1003FBYZ-010FB0_WD-WCAW36812041-part1" swap_partitions: "/dev/disk/by-id/ata-WDC_WD1003FBYZ-010FB0_WD-WCAW36812041-part2" rootfs_partition: "/dev/disk/by-id/ata-WDC_WD1003FBYZ-010FB0_WD-WCAW36812041-part3" netconsole_port: 6649 category: benchmark iterations: 1x nr_threads: 32t disk: 1HDD fs: btrfs fs2: fsmark: filesize: 16MB test_size: 60G sync_method: fsyncBeforeClose nr_directories: 16d nr_files_per_directory: 256fpd queue: cyclic testbox: nhm4 tbox_group: nhm4 kconfig: x86_64-rhel enqueue_time: 2015-09-28 12:38:00.849493513 +08:00 id: 4a38a4ae1915fa1269a4ae8d24ddb1aa2fcbddcc user: lkp compiler: gcc-4.9 head_commit: 0293645856ec527639b5902f021fa5aeba93e305 base_commit: 9ffecb10283508260936b96022d4ee43a7798b4c branch: linux-next/master kernel: "/pkg/linux/x86_64-rhel/gcc-4.9/0293645856ec527639b5902f021fa5aeba93e305/vmlinuz-4.3.0-rc3-next-20150929" rootfs: debian-x86_64-2015-02-07.cgz result_root: "/result/fsmark/performance-1x-32t-1HDD-btrfs-16MB-60G-fsyncBeforeClose-16d-256fpd/nhm4/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/0293645856ec527639b5902f021fa5aeba93e305/0" job_file: "/lkp/scheduled/nhm4/cyclic_fsmark-performance-1x-32t-1HDD-btrfs-16MB-60G-fsyncBeforeClose-16d-256fpd-x86_64-rhel-CYCLIC_HEAD-0293645856ec527639b5902f021fa5aeba93e305-20150928-126591-ryfw91-0.yaml" dequeue_time: 2015-09-30 09:55:25.788034495 +08:00 max_uptime: 1670.2800000000002 initrd: "/osimage/debian/debian-x86_64-2015-02-07.cgz" bootloader_append: - root=/dev/ram0 - user=lkp - job=/lkp/scheduled/nhm4/cyclic_fsmark-performance-1x-32t-1HDD-btrfs-16MB-60G-fsyncBeforeClose-16d-256fpd-x86_64-rhel-CYCLIC_HEAD-0293645856ec527639b5902f021fa5aeba93e305-20150928-126591-ryfw91-0.yaml - ARCH=x86_64 - kconfig=x86_64-rhel - branch=linux-next/master - commit=0293645856ec527639b5902f021fa5aeba93e305 - BOOT_IMAGE=/pkg/linux/x86_64-rhel/gcc-4.9/0293645856ec527639b5902f021fa5aeba93e305/vmlinuz-4.3.0-rc3-next-20150929 - max_uptime=1670 - RESULT_ROOT=/result/fsmark/performance-1x-32t-1HDD-btrfs-16MB-60G-fsyncBeforeClose-16d-256fpd/nhm4/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/0293645856ec527639b5902f021fa5aeba93e305/0 - LKP_SERVER=inn - |- libata.force=1.5Gbps
earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal
rw lkp_initrd: "/lkp/lkp/lkp-x86_64.cgz" modules_initrd: "/pkg/linux/x86_64-rhel/gcc-4.9/0293645856ec527639b5902f021fa5aeba93e305/modules.cgz" bm_initrd: "/osimage/deps/debian-x86_64-2015-02-07.cgz/lkp.cgz,/osimage/deps/debian-x86_64-2015-02-07.cgz/run-ipconfig.cgz,/osimage/deps/debian-x86_64-2015-02-07.cgz/turbostat.cgz,/lkp/benchmarks/turbostat.cgz,/osimage/deps/debian-x86_64-2015-02-07.cgz/fs.cgz,/osimage/deps/debian-x86_64-2015-02-07.cgz/fs2.cgz,/lkp/benchmarks/fsmark.cgz" job_state: finished loadavg: 27.79 25.76 13.60 1/183 7493 start_time: '1443578151' end_time: '1443578667' version: "/lkp/lkp/.src-20150930-095509" echo performance > /sys/devices/system/cpu/cpu0/cpufreq/scaling_governor echo performance > /sys/devices/system/cpu/cpu1/cpufreq/scaling_governor echo performance > /sys/devices/system/cpu/cpu2/cpufreq/scaling_governor echo performance > /sys/devices/system/cpu/cpu3/cpufreq/scaling_governor echo performance > /sys/devices/system/cpu/cpu4/cpufreq/scaling_governor echo performance > /sys/devices/system/cpu/cpu5/cpufreq/scaling_governor echo performance > /sys/devices/system/cpu/cpu6/cpufreq/scaling_governor echo performance > /sys/devices/system/cpu/cpu7/cpufreq/scaling_governor mkfs -t btrfs /dev/sdb1 mount -t btrfs /dev/sdb1 /fs/sdb1 ./fs_mark -d /fs/sdb1/1 -d /fs/sdb1/2 -d /fs/sdb1/3 -d /fs/sdb1/4 -d /fs/sdb1/5 -d /fs/sdb1/6 -d /fs/sdb1/7 -d /fs/sdb1/8 -d /fs/sdb1/9 -d /fs/sdb1/10 -d /fs/sdb1/11 -d /fs/sdb1/12 -d /fs/sdb1/13 -d /fs/sdb1/14 -d /fs/sdb1/15 -d /fs/sdb1/16 -d /fs/sdb1/17 -d /fs/sdb1/18 -d /fs/sdb1/19 -d /fs/sdb1/20 -d /fs/sdb1/21 -d /fs/sdb1/22 -d /fs/sdb1/23 -d /fs/sdb1/24 -d /fs/sdb1/25 -d /fs/sdb1/26 -d /fs/sdb1/27 -d /fs/sdb1/28 -d /fs/sdb1/29 -d /fs/sdb1/30 -d /fs/sdb1/31 -d /fs/sdb1/32 -D 16 -N 256 -n 120 -L 1 -S 1 -s 16777216
| |