From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1753790AbcC1GXo (ORCPT ); Mon, 28 Mar 2016 02:23:44 -0400 Received: from mga11.intel.com ([192.55.52.93]:3449 "EHLO mga11.intel.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1753271AbcC1GXg (ORCPT ); Mon, 28 Mar 2016 02:23:36 -0400 X-ExtLoop1: 1 X-IronPort-AV: E=Sophos;i="5.24,405,1455004800"; d="yaml'?scan'208";a="74326362" Date: Mon, 28 Mar 2016 14:22:56 +0800 From: kernel test robot To: Rik van Riel Cc: "Rafael J. Wysocki" , LKML , lkp@01.org Subject: [lkp] [cpuidle] e132b9b3bc: No primary change, turbostat.%Busy -65.1% change Message-ID: <20160328062256.GC3882@yexl-desktop> Reply-To: kernel test robot MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="vOmOzSkFvhd7u8Ms" Content-Disposition: inline Content-Transfer-Encoding: 8bit User-Agent: Heirloom mailx 12.5 6/20/10 Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org --vOmOzSkFvhd7u8Ms Content-Type: text/plain; charset=iso-8859-1 Content-Disposition: inline Content-Transfer-Encoding: 8bit FYI, we noticed that turbostat.%Busy -65.1% change with your commit. https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master commit e132b9b3bc7f19e9b158e42b323881d5dee5ecf3 ("cpuidle: menu: use high confidence factors only when considering polling") ========================================================================================= 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/5K/btrfs/1x/x86_64-rhel/16d/256fpd/32t/debian-x86_64-2015-02-07.cgz/fsyncBeforeClose/lkp-ws02/400M/fsmark commit: 3b99669b75db04e411bb298591224a9e8e4f57fb e132b9b3bc7f19e9b158e42b323881d5dee5ecf3 3b99669b75db04e4 e132b9b3bc7f19e9b158e42b32 ---------------- -------------------------- %stddev %change %stddev \ | \ 505.00 ± 7% +83.6% 927.00 ± 4% vmstat.memory.buff 6392 ± 35% +62.4% 10382 ± 0% numa-meminfo.node0.Mapped 2646 ±130% +226.2% 8631 ± 0% numa-meminfo.node0.Shmem 9065 ± 25% -44.8% 5008 ± 1% numa-meminfo.node1.Mapped 26.78 ± 1% -65.1% 9.34 ± 0% turbostat.%Busy 709.50 ± 1% -65.2% 246.75 ± 0% turbostat.Avg_MHz 40.46 ± 1% +39.7% 56.54 ± 1% turbostat.CPU%c1 1597 ± 35% +62.4% 2594 ± 0% numa-vmstat.node0.nr_mapped 661.25 ±130% +226.3% 2157 ± 0% numa-vmstat.node0.nr_shmem 106.00 ± 39% +220.0% 339.25 ± 61% numa-vmstat.node0.numa_other 2266 ± 25% -44.8% 1251 ± 1% numa-vmstat.node1.nr_mapped 4.795e+08 ± 4% +117.9% 1.045e+09 ± 2% cpuidle.C1-NHM.time 463937 ± 2% +73.2% 803714 ± 2% cpuidle.C1-NHM.usage 1.699e+08 ± 3% -8.6% 1.553e+08 ± 1% cpuidle.C1E-NHM.time 7.062e+08 ± 0% -84.0% 1.131e+08 ± 5% cpuidle.POLL.time 440162 ± 1% -79.7% 89501 ± 6% cpuidle.POLL.usage 0.00 ± -1% +Inf% 8824 ± 70% latency_stats.avg.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013.do_one_initcall.do_init_module 0.00 ± -1% +Inf% 12106 ± 71% latency_stats.avg.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_get_page2_descriptor.[ses].ses_get_power_status.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013 0.00 ± -1% +Inf% 15174 ± 70% latency_stats.max.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013.do_one_initcall.do_init_module 0.00 ± -1% +Inf% 108570 ± 80% latency_stats.max.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_get_page2_descriptor.[ses].ses_get_power_status.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013 0.00 ± -1% +Inf% 92833 ± 71% latency_stats.sum.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013.do_one_initcall.do_init_module 0.00 ± -1% +Inf% 811913 ± 70% latency_stats.sum.blk_execute_rq.scsi_execute.scsi_execute_req_flags.ses_recv_diag.[ses].ses_get_page2_descriptor.[ses].ses_get_power_status.[ses].ses_enclosure_data_process.[ses].ses_match_to_enclosure.[ses].ses_intf_add.[ses].class_interface_register.scsi_register_interface.0xffffffffa0006013 -9221 ±-11% -19.9% -7385 ±-11% sched_debug.cfs_rq:/.spread0.avg 591.90 ± 62% +112.9% 1260 ± 30% sched_debug.cfs_rq:/.spread0.max -14064 ± -6% -11.1% -12500 ± -6% sched_debug.cfs_rq:/.spread0.min 306.42 ± 40% -41.9% 178.00 ± 8% sched_debug.cpu.load.max 75.40 ± 31% -33.6% 50.09 ± 13% sched_debug.cpu.load.stddev 714.67 ± 1% -9.9% 644.00 ± 5% sched_debug.cpu.nr_uninterruptible.max 1149 ± 9% -15.9% 967.25 ± 3% slabinfo.avc_xperms_node.active_objs 1149 ± 9% -15.9% 967.25 ± 3% slabinfo.avc_xperms_node.num_objs 1020 ± 8% +28.2% 1308 ± 3% slabinfo.btrfs_trans_handle.active_objs 1020 ± 8% +28.2% 1308 ± 3% slabinfo.btrfs_trans_handle.num_objs 351.75 ± 11% +39.2% 489.50 ± 8% slabinfo.btrfs_transaction.active_objs 351.75 ± 11% +39.2% 489.50 ± 8% slabinfo.btrfs_transaction.num_objs 544.00 ± 10% +20.6% 656.00 ± 12% slabinfo.kmem_cache_node.active_objs 544.00 ± 10% +20.6% 656.00 ± 12% slabinfo.kmem_cache_node.num_objs lkp-ws02: Westmere-EP Memory: 16G turbostat.Avg_MHz 800 ++--------------------------------------------------------------------+ **.****.****.* **.****.****.** *.****.****.****.****.****.** *. * | 700 ++ * * * ** *.*** | | 600 ++ | | | 500 ++ | | | 400 ++ | | | 300 ++ OO O O O | | O OO O O O OO OOOO OOO | 200 ++ | OO OOOO OOO | 100 ++--------------------------------------------------------------------+ turbostat._Busy 30 ++---------------------------------------------------------------------+ **.****.****.* **.***.****.*** .***.* *.****.****.***.****.* * * | | * * ** * *.* **.** 25 ++ | | | | | 20 ++ | | | 15 ++ | | | | | 10 ++ O OOOO OOO OOOO | | OOOO OOO O | OO OOOO O O | 5 ++-------O-------------------------------------------------------------+ turbostat.CPU_c1 65 ++---------------------------------------------------------------------+ | O | 60 OO OO O O | | O O | | OOO OO O O OOOO OOO O | 55 ++ O O O | | O O | 50 ++ | | | 45 ++ | | | **.****. *. ***.***.****.****.***.* **.****.****.***.****.****.****. | 40 ++ *** * * ** | | 35 ++---------------------------------------------------------------------+ [*] 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, Xiaolong Ye --vOmOzSkFvhd7u8Ms Content-Type: text/plain; charset=us-ascii Content-Disposition: attachment; filename="job.yaml" --- LKP_SERVER: inn LKP_CGI_PORT: 80 LKP_CIFS_PORT: 139 testcase: fsmark default-monitors: wait: activate-monitor kmsg: uptime: iostat: heartbeat: 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: default-watchdogs: oom-killer: watchdog: commit: e132b9b3bc7f19e9b158e42b323881d5dee5ecf3 model: Westmere-EP memory: 16G nr_hdd_partitions: 10 hdd_partitions: "/dev/disk/by-id/scsi-35000c500*-part1" swap_partitions: rootfs_partition: "/dev/disk/by-id/ata-WDC_WD1002FAEX-00Z3A0_WD-WCATR5408564-part3" category: benchmark iterations: 1x nr_threads: 32t disk: 1HDD fs: btrfs fs2: fsmark: filesize: 5K test_size: 400M sync_method: fsyncBeforeClose nr_directories: 16d nr_files_per_directory: 256fpd queue: bisect testbox: lkp-ws02 tbox_group: lkp-ws02 kconfig: x86_64-rhel enqueue_time: 2016-03-27 16:21:07.892606177 +08:00 compiler: gcc-4.9 rootfs: debian-x86_64-2015-02-07.cgz id: 052d3b30a115d16c93009b42c032314044e0f600 user: lkp head_commit: 390fc3e59c3a8d9938e58a7c995caa36bf868958 base_commit: b562e44f507e863c6792946e4e1b1449fbbac85d branch: linux-devel/devel-hourly-2016032007 result_root: "/result/fsmark/1x-32t-1HDD-btrfs-5K-400M-fsyncBeforeClose-16d-256fpd/lkp-ws02/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/e132b9b3bc7f19e9b158e42b323881d5dee5ecf3/0" job_file: "/lkp/scheduled/lkp-ws02/bisect_fsmark-1x-32t-1HDD-btrfs-5K-400M-fsyncBeforeClose-16d-256fpd-debian-x86_64-2015-02-07.cgz-x86_64-rhel-e132b9b3bc7f19e9b158e42b323881d5dee5ecf3-20160327-11856-1ve4oen-0.yaml" nr_cpu: "$(nproc)" max_uptime: 967.0 initrd: "/osimage/debian/debian-x86_64-2015-02-07.cgz" bootloader_append: - root=/dev/ram0 - user=lkp - job=/lkp/scheduled/lkp-ws02/bisect_fsmark-1x-32t-1HDD-btrfs-5K-400M-fsyncBeforeClose-16d-256fpd-debian-x86_64-2015-02-07.cgz-x86_64-rhel-e132b9b3bc7f19e9b158e42b323881d5dee5ecf3-20160327-11856-1ve4oen-0.yaml - ARCH=x86_64 - kconfig=x86_64-rhel - branch=linux-devel/devel-hourly-2016032007 - commit=e132b9b3bc7f19e9b158e42b323881d5dee5ecf3 - BOOT_IMAGE=/pkg/linux/x86_64-rhel/gcc-4.9/e132b9b3bc7f19e9b158e42b323881d5dee5ecf3/vmlinuz-4.5.0-rc4-00003-ge132b9b - max_uptime=967 - RESULT_ROOT=/result/fsmark/1x-32t-1HDD-btrfs-5K-400M-fsyncBeforeClose-16d-256fpd/lkp-ws02/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/e132b9b3bc7f19e9b158e42b323881d5dee5ecf3/0 - LKP_SERVER=inn - |- ipmi_watchdog.start_now=1 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/e132b9b3bc7f19e9b158e42b323881d5dee5ecf3/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" linux_headers_initrd: "/pkg/linux/x86_64-rhel/gcc-4.9/e132b9b3bc7f19e9b158e42b323881d5dee5ecf3/linux-headers.cgz" repeat_to: 2 kernel: "/pkg/linux/x86_64-rhel/gcc-4.9/e132b9b3bc7f19e9b158e42b323881d5dee5ecf3/vmlinuz-4.5.0-rc4-00003-ge132b9b" dequeue_time: 2016-03-27 16:26:57.703409607 +08:00 job_state: finished loadavg: 26.72 11.99 4.56 1/396 4953 start_time: '1459067277' end_time: '1459067418' version: "/lkp/lkp/.src-20160325-205817" --vOmOzSkFvhd7u8Ms Content-Type: text/plain; charset=us-ascii Content-Disposition: attachment; filename=reproduce 2016-03-27 16:27:56 mkfs -t btrfs /dev/sdd1 2016-03-27 16:27:56 mount -t btrfs /dev/sdd1 /fs/sdd1 2016-03-27 16:27:57 ./fs_mark -d /fs/sdd1/1 -d /fs/sdd1/2 -d /fs/sdd1/3 -d /fs/sdd1/4 -d /fs/sdd1/5 -d /fs/sdd1/6 -d /fs/sdd1/7 -d /fs/sdd1/8 -d /fs/sdd1/9 -d /fs/sdd1/10 -d /fs/sdd1/11 -d /fs/sdd1/12 -d /fs/sdd1/13 -d /fs/sdd1/14 -d /fs/sdd1/15 -d /fs/sdd1/16 -d /fs/sdd1/17 -d /fs/sdd1/18 -d /fs/sdd1/19 -d /fs/sdd1/20 -d /fs/sdd1/21 -d /fs/sdd1/22 -d /fs/sdd1/23 -d /fs/sdd1/24 -d /fs/sdd1/25 -d /fs/sdd1/26 -d /fs/sdd1/27 -d /fs/sdd1/28 -d /fs/sdd1/29 -d /fs/sdd1/30 -d /fs/sdd1/31 -d /fs/sdd1/32 -D 16 -N 256 -n 2560 -L 1 -S 1 -s 5120 --vOmOzSkFvhd7u8Ms--