[LKP] [PM / sleep] 38106313324: -48.0% turbostat.PkgWatt

From: Huang Ying
Date: Tue Apr 21 2015 - 02:59:46 EST

FYI, we noticed the below changes on

git://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
commit 3810631332465d967ba5e27ea2c7dff2c9afac6c ("PM / sleep: Re-implement suspend-to-idle handling")


testbox/testcase/testparams: lkp-sb03/suspend/300s-30x-freeze

18320f2a6871aaf2 3810631332465d967ba5e27ea2
---------------- --------------------------
%stddev %change %stddev
\ | \
212 Â 5% -23.3% 162 Â 6% numa-vmstat.node1.nr_kernel_stack
3418 Â 5% -23.6% 2610 Â 6% numa-meminfo.node1.KernelStack
32165483 Â 26% -31.3% 22108958 Â 7% cpuidle.C1E-SNB.time
9.401e+09 Â 1% +36.4% 1.283e+10 Â 0% cpuidle.C7-SNB.time
1137 Â 3% -7.0% 1057 Â 5% slabinfo.blkdev_requests.active_objs
1137 Â 3% -7.0% 1057 Â 5% slabinfo.blkdev_requests.num_objs
50.02 Â 1% -69.5% 15.26 Â 4% turbostat.CPU%c1
49.38 Â 1% +70.5% 84.19 Â 0% turbostat.CPU%c7
12.77 Â 3% -59.0% 5.24 Â 1% turbostat.CorWatt
7.83 Â 11% +545.0% 50.49 Â 0% turbostat.Pkg%pc6
38.91 Â 1% -48.0% 20.22 Â 0% turbostat.PkgWatt
90 Â 31% -34.7% 58 Â 20% sched_debug.cfs_rq[14]:/.tg_runnable_contrib
20 Â 46% -59.3% 8 Â 33% sched_debug.cfs_rq[14]:/.runnable_load_avg
4196 Â 31% -34.6% 2745 Â 20% sched_debug.cfs_rq[14]:/.avg->runnable_avg_sum
3489 Â 38% -33.2% 2329 Â 11% sched_debug.cfs_rq[15]:/.avg->runnable_avg_sum
2418 Â 43% +54.7% 3741 Â 21% sched_debug.cfs_rq[17]:/.avg->runnable_avg_sum
447 Â 28% +48.5% 663 Â 11% sched_debug.cfs_rq[17]:/.tg_load_contrib
434 Â 31% +45.7% 633 Â 13% sched_debug.cfs_rq[17]:/.blocked_load_avg
51 Â 44% +54.4% 79 Â 21% sched_debug.cfs_rq[17]:/.tg_runnable_contrib
9268 Â 26% -45.4% 5056 Â 46% sched_debug.cfs_rq[1]:/.min_vruntime
3653 Â 42% +96.0% 7162 Â 13% sched_debug.cfs_rq[24]:/.min_vruntime
4355 Â 14% +51.2% 6584 Â 22% sched_debug.cfs_rq[25]:/.min_vruntime
6 Â 36% -55.6% 3 Â 40% sched_debug.cfs_rq[27]:/.runnable_load_avg
589 Â 10% +24.4% 733 Â 12% sched_debug.cfs_rq[31]:/.tg_load_contrib
585 Â 10% +24.1% 727 Â 11% sched_debug.cfs_rq[31]:/.blocked_load_avg
2484 Â 30% +81.1% 4498 Â 20% sched_debug.cfs_rq[6]:/.avg->runnable_avg_sum
52 Â 30% +83.9% 97 Â 20% sched_debug.cfs_rq[6]:/.tg_runnable_contrib
14630 Â 41% -56.5% 6370 Â 40% sched_debug.cfs_rq[7]:/.min_vruntime
58 Â 8% -11.6% 51 Â 9% sched_debug.cfs_rq[9]:/.tg_runnable_contrib
2731 Â 8% -12.1% 2402 Â 8% sched_debug.cfs_rq[9]:/.avg->runnable_avg_sum
18774 Â 43% -60.3% 7460 Â 21% sched_debug.cpu#1.sched_count
16 Â 4% -25.4% 12 Â 18% sched_debug.cpu#10.cpu_load[3]
9 Â 8% -28.2% 7 Â 17% sched_debug.cpu#10.cpu_load[4]
32 Â 21% -33.3% 21 Â 28% sched_debug.cpu#13.cpu_load[1]
28 Â 4% -28.3% 20 Â 25% sched_debug.cpu#13.cpu_load[2]
29 Â 43% -58.0% 12 Â 20% sched_debug.cpu#14.cpu_load[3]
36 Â 26% -42.5% 21 Â 13% sched_debug.cpu#14.cpu_load[1]
215 Â 14% +53.6% 330 Â 9% sched_debug.cpu#14.curr->pid
34 Â 31% -47.8% 18 Â 18% sched_debug.cpu#14.cpu_load[2]
2431 Â 3% +25.3% 3046 Â 20% sched_debug.cpu#16.sched_count
1440 Â 15% +45.3% 2092 Â 34% sched_debug.cpu#16.ttwu_count
2233 Â 5% +12.5% 2512 Â 7% sched_debug.cpu#20.nr_switches
826 Â 16% +65.9% 1370 Â 31% sched_debug.cpu#21.ttwu_count
992 Â 6% +18.0% 1170 Â 4% sched_debug.cpu#23.sched_goidle
308 Â 13% +45.2% 448 Â 19% sched_debug.cpu#23.ttwu_local
2117 Â 5% +14.6% 2426 Â 4% sched_debug.cpu#23.sched_count
2072 Â 6% +14.6% 2373 Â 5% sched_debug.cpu#23.nr_switches
8 Â 44% +61.8% 13 Â 10% sched_debug.cpu#24.nr_uninterruptible
800 Â 17% -26.0% 592 Â 6% sched_debug.cpu#28.sched_goidle
5 Â 37% +195.0% 14 Â 46% sched_debug.cpu#30.cpu_load[1]
9 Â 33% +62.2% 15 Â 12% sched_debug.cpu#31.cpu_load[2]
28 Â 43% +64.9% 47 Â 42% sched_debug.cpu#8.cpu_load[1]
17 Â 8% -21.1% 14 Â 18% sched_debug.cpu#9.cpu_load[3]

lkp-sb03: Sandy Bridge-EP
Memory: 64G




turbostat.CPU%c1

60 ++---------------------------------------------------------------------+
| .*..* |
50 *+*..* + .*.*..*. .*..*.*..*.*..*.*..*.*.*.. |
| *. *.*..* * |
| |
40 ++ |
| |
30 ++ |
| |
20 ++ |
| O O O O O O O O O O O O O O |
O O O O O O O O O O O O O O
10 ++ |
| O O |
0 ++---------------------------------------------------------------------+


turbostat.CPU%c7

100 ++--------------------------------------------------------------------+
| O O |
90 ++ |
O O O O |
| O O O O O O O O O O O O O O O O O O O O O O O O
80 ++ |
| |
70 ++ |
| |
60 ++ |
| |
| *. .*. .*. |
50 *+*..*. + *..*.*. *. *. .*.*..*.*.*..*.*..*.* |
| *..* *. |
40 ++--------------------------------------------------------------------+


turbostat.Pkg%pc6

55 ++---------------------------O-----------------------------------------+
50 O+O O O O O O O O O O O O O O O O O O O O O O O O O O O
| O |
45 ++ |
40 ++ |
| |
35 ++ |
30 ++ |
25 ++ |
| |
20 ++ |
15 ++ |
| .*.. .* |
10 *+ .*.*..* *. .*.*.*. + .*..*.*.. .*..*.*.*..* |
5 ++*--------------*-----------*--*---------*----------------------------+


turbostat.PkgWatt

45 ++-----*---------------------------------------------------------------+
*. .* * .*. |
40 ++*. + .*.. .*. *.. |
| *..*.*..*.* * *.*..*.*..*.*.*..* |
| |
35 ++ |
| |
30 ++ |
| |
25 ++ |
| |
| O |
20 O+O O O O O O O O O O O O O O O O O O O O O O O O O O O O
| |
15 ++---------------------------------------------------------------------+


turbostat.CorWatt

20 ++---------------------------------------------------------------------+
| .*.. |
18 ++ * |
16 *+ .. * *. |
| * + .. *.. |
14 ++ *.. .*..*.*.*..*.* .*..*. .* |
12 ++ * *.*..* *.*. |
| |
10 ++ |
8 ++ |
| |
6 O+O O O O O O O O O O O O O O O O O O O O O |
4 ++ O O O O O O O
| O |
2 ++---------------------------------------------------------------------+


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

To reproduce:

apt-get install ruby
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/setup-local job.yaml # the job file attached in this email
bin/run-local 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

---
testcase: suspend
default-monitors:
wait: pre-test
uptime:
iostat:
vmstat:
numa-numastat:
numa-vmstat:
numa-meminfo:
proc-vmstat:
proc-stat:
meminfo:
slabinfo:
interrupts:
lock_stat:
latency_stats:
softirqs:
bdi_dev_mapping:
diskstats:
nfsstat:
cpuidle:
cpufreq-stats:
turbostat:
pmeter:
sched_debug:
interval: 10
default-watchdogs:
watch-oom:
watchdog:
cpufreq_governor:
commit: 39a8804455fb23f09157341d3ba7db6d7ae6ee76
model: Sandy Bridge-EP
memory: 64G
hdd_partitions: "/dev/disk/by-id/ata-WDC_WD10EARS-00Y5B1_WD-WCAV5F059499-part3"
swap_partitions:
rootfs_partition: "/dev/disk/by-id/ata-WDC_WD10EARS-00Y5B1_WD-WCAV5F059499-part4"
runtime: 300s
iterations: 30x
suspend:
mode: freeze
testbox: lkp-sb03
tbox_group: lkp-sb03
kconfig: x86_64-rhel
enqueue_time: 2015-04-12 18:55:17.918300158 +08:00
head_commit: 39a8804455fb23f09157341d3ba7db6d7ae6ee76
base_commit: 4e8a4830dc9f0883d5302b871adb9e6179338d24
branch: linus/master
kernel: "/kernel/x86_64-rhel/39a8804455fb23f09157341d3ba7db6d7ae6ee76/vmlinuz-4.0.0"
user: lkp
queue: cyclic
rootfs: debian-x86_64-2015-02-07.cgz
result_root: "/result/lkp-sb03/suspend/300s-30x-freeze/debian-x86_64-2015-02-07.cgz/x86_64-rhel/39a8804455fb23f09157341d3ba7db6d7ae6ee76/0"
LKP_SERVER: inn
job_file: "/lkp/scheduled/lkp-sb03/cyclic_suspend-300s-30x-freeze-x86_64-rhel-HEAD-39a8804455fb23f09157341d3ba7db6d7ae6ee76-0-20150412-101013-tt7o2e.yaml"
dequeue_time: 2015-04-13 14:11:42.307612190 +08:00
nr_cpu: "$(nproc)"
initrd: "/osimage/debian/debian-x86_64-2015-02-07.cgz"
bootloader_append:
- root=/dev/ram0
- user=lkp
- job=/lkp/scheduled/lkp-sb03/cyclic_suspend-300s-30x-freeze-x86_64-rhel-HEAD-39a8804455fb23f09157341d3ba7db6d7ae6ee76-0-20150412-101013-tt7o2e.yaml
- ARCH=x86_64
- kconfig=x86_64-rhel
- branch=linus/master
- commit=39a8804455fb23f09157341d3ba7db6d7ae6ee76
- BOOT_IMAGE=/kernel/x86_64-rhel/39a8804455fb23f09157341d3ba7db6d7ae6ee76/vmlinuz-4.0.0
- RESULT_ROOT=/result/lkp-sb03/suspend/300s-30x-freeze/debian-x86_64-2015-02-07.cgz/x86_64-rhel/39a8804455fb23f09157341d3ba7db6d7ae6ee76/0
- LKP_SERVER=inn
- |2-


earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning
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
max_uptime: 1500
lkp_initrd: "/lkp/lkp/lkp-x86_64.cgz"
modules_initrd: "/kernel/x86_64-rhel/39a8804455fb23f09157341d3ba7db6d7ae6ee76/modules.cgz"
bm_initrd: "/osimage/deps/debian-x86_64-2015-02-07.cgz/lkp.cgz,/osimage/deps/debian-x86_64-2015-02-07.cgz/turbostat.cgz,/lkp/benchmarks/turbostat.cgz"
job_state: finished
loadavg: 6.54 4.88 2.13 1/509 2291
start_time: '1428905559'
end_time: '1428906010'
version: "/lkp/lkp/.src-20150413-133732"
_______________________________________________
LKP mailing list
LKP@xxxxxxxxxxxxxxx