s3156_04.bad.txt 2014.06.16

Test period covers roughly 2014.06.15 16:00 to 2014.06.16 06:45 (local time)

doug@s15:~/temp$ uname -a
Linux s15 3.15.0-doug6 #116 SMP Sun Jun 15 08:36:42 PDT 2014 x86_64 x86_64 x86_64 GNU/Linux

doug@s15:~/temp-k-git-3.10rc4/linux$ git log --oneline
2d100b0 intel_pstate: default sample_rate_ms changed to 20.
942fc04 intel_pstate: fix mistake in target pstate change rounding.
a7e049b intel_pstate: Include C0 for light loads only.
d9218d2 intel_pstate: Remove sample time scaling.
1860e37 Linux 3.15

doug@s15:~/temp$ grep CONFIG_HZ_ /boot/config-3.15.0-doug6
# CONFIG_HZ_PERIODIC is not set
# CONFIG_HZ_100 is not set
CONFIG_HZ_250=y
# CONFIG_HZ_300 is not set
# CONFIG_HZ_1000 is not set

Reference line: (a normal line, without an anomaly):

swapper     0 [000] 28956.304746: power:pstate_sample: core_busy=42 scaled=89 state=16 mperf=2930 aperf=1379 freq=1600125

Anomaly summary from:
doug@s15:~/temp$ sudo /home/doug/bin/perf record -a --event=power:pstate_sample sleep 9000

doug@s15:~/temp$ grep "state=38" perf.s3156_03
swapper     0 [006] 32384.119711: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002656 aperf=17883603 freq=4291859408
swapper     0 [006] 34182.956279: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002626 aperf=17883589 freq=4291859408
swapper     0 [006] 34482.762374: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002646 aperf=17883598 freq=4291859408
swapper     0 [006] 37780.629415: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002641 aperf=17883595 freq=4291859408

Anomaly summary from:
doug@s15:~/temp$ sudo /home/doug/bin/perf record -a --event=power:pstate_sample sleep 23400

doug@s15:~/temp$ grep "state=38" perf.s3156_04
swapper     0 [006] 54569.770715: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002638 aperf=17883594 freq=4291859408
swapper     0 [006] 55469.188999: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38000282 aperf=17882595 freq=4291865915
swapper     0 [006] 56068.801188: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002630 aperf=17883590 freq=4291859408
swapper     0 [006] 56208.710700: power:pstate_sample: core_busy=506 scaled=452 state=38 mperf=18496250 aperf=8704118 freq=438205
swapper     0 [006] 57705.630567: power:pstate_sample: core_busy=150 scaled=134 state=38 mperf=25104675 aperf=12552310 freq=5124835
swapper     0 [007] 58937.381875: power:pstate_sample: core_busy=134 scaled=120 state=38 mperf=25796659 aperf=12139605 freq=4576054
swapper     0 [006] 60709.799534: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002646 aperf=17883598 freq=4291859408
swapper     0 [005] 64793.446385: power:pstate_sample: core_busy=492 scaled=440 state=38 mperf=37097113 aperf=17457482 freq=4294944376
swapper     0 [006] 65062.984028: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002651 aperf=17883600 freq=4291859408
swapper     0 [006] 66562.014500: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002585 aperf=17883569 freq=4291859540
swapper     0 [005] 67372.566290: power:pstate_sample: core_busy=191 scaled=171 state=38 mperf=44505288 aperf=20943666 freq=6502500
swapper     0 [006] 67761.238879: power:pstate_sample: core_busy=427 scaled=382 state=38 mperf=38001241 aperf=19000733 freq=4292717908
swapper     0 [006] 68061.044975: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38001803 aperf=17883252 freq=4291861665
swapper     0 [006] 68360.851069: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002633 aperf=17883592 freq=4291859408
swapper     0 [006] 75556.197341: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38002627 aperf=17883589 freq=4291859408
swapper     0 [006] 75856.003436: power:pstate_sample: core_busy=402 scaled=359 state=38 mperf=38001990 aperf=17883328 freq=4291861134
doug@s15:~/temp$

20 anomalies in 9 hours, or a crude average of 2.2 anomalies per hour.