Re: [LKP] [rcu] kernel BUG at include/linux/pagemap.h:149!
by Frederic Weisbecker
On Fri, Sep 11, 2015 at 10:19:47AM +0800, Boqun Feng wrote:
> Subject: [PATCH 01/27] rcu: Don't disable preemption for Tiny and Tree RCU
> readers
>
> Because preempt_disable() maps to barrier() for non-debug builds,
> it forces the compiler to spill and reload registers. Because Tree
> RCU and Tiny RCU now only appear in CONFIG_PREEMPT=n builds, these
> barrier() instances generate needless extra code for each instance of
> rcu_read_lock() and rcu_read_unlock(). This extra code slows down Tree
> RCU and bloats Tiny RCU.
>
> This commit therefore removes the preempt_disable() and preempt_enable()
> from the non-preemptible implementations of __rcu_read_lock() and
> __rcu_read_unlock(), respectively.
>
> For debug purposes, preempt_disable() and preempt_enable() are still
> kept if CONFIG_PREEMPT_COUNT=y, which makes the detection of sleeping
> inside atomic sections still work in non-preemptible kernels.
>
> Signed-off-by: Boqun Feng <boqun.feng(a)gmail.com>
> Signed-off-by: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
> ---
> include/linux/rcupdate.h | 6 ++++--
> include/linux/rcutiny.h | 1 +
> kernel/rcu/tree.c | 9 +++++++++
> 3 files changed, 14 insertions(+), 2 deletions(-)
>
> diff --git a/include/linux/rcupdate.h b/include/linux/rcupdate.h
> index d63bb77..6c3cece 100644
> --- a/include/linux/rcupdate.h
> +++ b/include/linux/rcupdate.h
> @@ -297,12 +297,14 @@ void synchronize_rcu(void);
>
> static inline void __rcu_read_lock(void)
> {
> - preempt_disable();
> + if (IS_ENABLED(CONFIG_PREEMPT_COUNT))
> + preempt_disable();
preempt_disable() is a no-op when !CONFIG_PREEMPT_COUNT, right?
Or rather it's a barrier(), which is anyway implied by rcu_read_lock().
So perhaps we can get rid of the IS_ENABLED() check?
1 year, 10 months
Re: [LKP] [lkp] [f2fs] ec795418c4: fsmark.files_per_sec -36.3% regression
by Jaegeuk Kim
On Wed, Aug 24, 2016 at 09:51:11AM -0700, huang ying wrote:
> Hi, Jaegeuk,
>
> On Thu, Aug 11, 2016 at 6:22 PM, Jaegeuk Kim <jaegeuk(a)kernel.org> wrote:
> > On Thu, Aug 11, 2016 at 03:49:41PM -0700, Huang, Ying wrote:
> >> Hi, Kim,
> >>
> >> "Huang, Ying" <ying.huang(a)intel.com> writes:
> >> >>
> >> >> [lkp] [f2fs] 3bdad3c7ee: aim7.jobs-per-min -25.3% regression
> >> >> [lkp] [f2fs] b93f771286: aim7.jobs-per-min -81.2% regression
> >> >>
> >> >> In terms of the above regression, I could check that _reproduce_ procedure
> >> >> includes mounting filesystem only. Is that correct?
> >> >
> >> > Sorry, our test system failed to generate reproduce steps for aim7. We
> >> > will implement it.
> >> >
> >> > - [lkp] [f2fs] 3bdad3c7ee: aim7.jobs-per-min -25.3% regression
> >> >
> >> > The disk is one 48G ram disk. The steps for aim7 is,
> >> >
> >> > cat > workfile <<EOF
> >> > FILESIZE: 1M
> >> > POOLSIZE: 10M
> >> > 10 disk_cp
> >> > EOF
> >> >
> >> > (
> >> > echo $HOSTNAME
> >> > echo disk_cp
> >> >
> >> > echo 1
> >> > echo 3000
> >> > echo 2
> >> > echo 3000
> >> > echo 1
> >> > ) | ./multitask -t &
> >> >
> >> >
> >> > - [lkp] [f2fs] b93f771286: aim7.jobs-per-min -81.2% regression
> >> >
> >> > The disk is 4 12G ram disk, and setup RAID0 on them via mdadm. The
> >> > steps for aim7 is,
> >> >
> >> > cat > workfile <<EOF
> >> > FILESIZE: 1M
> >> > POOLSIZE: 10M
> >> > 10 sync_disk_rw
> >> > EOF
> >> >
> >> > (
> >> > echo $HOSTNAME
> >> > echo sync_disk_rw
> >> >
> >> > echo 1
> >> > echo 600
> >> > echo 2
> >> > echo 600
> >> > echo 1
> >> > ) | ./multitask -t &
> >>
> >> Any update on these 2 regressions? Is the information is enough for you
> >> to reproduce?
> >
> > Sorry, I've had no time to dig this due to business travel now.
> > I'll check that when back to US.
>
> Any update?
Sorry, how can I get multitask binary?
Thanks,
>
> Best Regards,
> Huang, Ying
4 years, 5 months
[list] 8052ce2d97: WARNING: CPU: 0 PID: 1 at lib/list_debug.c:60 __list_del_entry+0x4d/0x71
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/paulmck/linux-rcu.git dev.2016.08.19a
commit 8052ce2d9771ab5a818307f3abbaf33bba82a631 ("list: Split list_add() debug checking into separate function")
in testcase: boot
on test machine: qemu-system-i386 -enable-kvm -m 360M
caused below changes:
+------------------------------------------------------------+------------+------------+
| | 0a33d912a3 | 8052ce2d97 |
+------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 211 | 18 |
| WARNING:at_lib/list_debug.c:#__list_del_entry | 211 | 18 |
| calltrace:init | 209 | 18 |
| WARNING:at_lib/list_debug.c:#__list_add | 209 | |
| calltrace:i8042_init | 209 | 17 |
| calltrace:SyS_reboot | 192 | 4 |
| EIP_is_at__mutex_unlock_slowpath | 29 | |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 138 | |
| BUG:workqueue_lockup-pool_cpus=#flags=#nice=#stuck_for#s | 56 | |
| EIP_is_at_lock_release | 7 | |
| EIP_is_at_lock_acquire | 28 | |
| EIP_is_at_raw_spin_unlock_irq | 30 | |
| EIP_is_at_mutex_lock_nested | 21 | |
| EIP_is_at_lock_is_held | 10 | |
| EIP_is_at_device_shutdown | 3 | |
| EIP_is_at___might_sleep | 5 | |
| EIP_is_at__list_del_entry | 4 | 1 |
| EIP_is_at_get_device | 1 | |
| BUG:unable_to_handle_kernel | 7 | 5 |
| Oops | 7 | 5 |
| calltrace:SyS_ipc | 4 | 4 |
| Kernel_panic-not_syncing:Fatal_exception | 7 | 5 |
| EIP_is_at_pm_runtime_barrier | 1 | |
| EIP_is_at_lock_acquired | 1 | |
| EIP_is_at__might_sleep | 1 | |
| BUG:workqueue_lockup-pool_cpus=#-#flags=#nice=#stuck_for#s | 1 | |
| WARNING:at_arch/x86/mm/dump_pagetables.c:#note_page | 26 | |
| calltrace:mark_rodata_ro | 26 | |
| WARNING:at_lib/list_debug.c:#__list_add_valid | 0 | 17 |
| calltrace:serio_handle_event | 0 | 17 |
| calltrace:userio_misc_init | 0 | 17 |
| calltrace:mousedev_init | 0 | 17 |
| calltrace:evdev_init | 0 | 17 |
| calltrace:cmos_init | 0 | 17 |
| calltrace:test_init | 0 | 17 |
| calltrace:pca_isa_driver_init | 0 | 17 |
| calltrace:vim2m_init | 0 | 17 |
| calltrace:fm_drv_init | 0 | 17 |
| calltrace:test_power_init | 0 | 17 |
| calltrace:raid#_init | 0 | 17 |
| calltrace:dm_init | 0 | 17 |
| calltrace:vhci_init | 0 | 17 |
| calltrace:dcdrbu_init | 0 | 17 |
| calltrace:dcdbas_init | 0 | 17 |
| calltrace:uhid_init | 0 | 17 |
| calltrace:vhost_net_init | 0 | 17 |
| calltrace:dummy_stm_init | 0 | 17 |
| calltrace:stm_heartbeat_init | 0 | 17 |
| calltrace:binder_init | 0 | 17 |
| calltrace:teql_init | 0 | 17 |
| calltrace:ipip_init | 0 | 17 |
| calltrace:ipgre_init | 0 | 17 |
| calltrace:sit_init | 0 | 17 |
| calltrace:ip6_tunnel_init | 0 | 17 |
| calltrace:ip6gre_init | 0 | 17 |
| calltrace:irlan_init | 0 | 17 |
| calltrace:pm_qos_power_init | 0 | 17 |
| calltrace:ubi_init | 0 | 17 |
| calltrace:SyS_setsockopt | 0 | 2 |
| calltrace:SyS_socketcall | 0 | 2 |
| WARNING:at_include/linux/kref.h:#kobject_get | 0 | 4 |
| WARNING:at_kernel/locking/lockdep.c:#__lock_acquire | 0 | 4 |
| BUG:kernel_hang_in_test_stage | 0 | 4 |
| calltrace:fw_cfg_sysfs_init | 0 | 7 |
+------------------------------------------------------------+------------+------------+
[ 152.680291] g_acm_ms usbip-vudc.0: failed to start g_acm_ms: -22
[ 152.689209] usbip-vudc: probe of usbip-vudc.0 failed with error -22
[ 152.698509] ------------[ cut here ]------------
[ 152.709786] WARNING: CPU: 0 PID: 1 at lib/list_debug.c:60 __list_del_entry+0x4d/0x71
[ 152.722785] list_del corruption. next->prev should be cc0230d4, but was 6b6b6b6b
[ 152.732828] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.8.0-rc1-00033-g8052ce2 #1
[ 152.742576] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 152.754081] 00000000 00200246 c0033e78 c13c456b c0033ea4 c13e72ac c0033e90 c103fd02
[ 152.765720] 0000003c 6b6b6b6b cc0230d4 cc02300c c0033eac c103fd41 00000009 00000000
[ 152.776864] c0033ea4 c1f7bc14 c0033ec0 c0033ecc c13e72ac c1f7bace 0000003c c1f7bc14
[ 152.788472] Call Trace:
[ 152.791964] [<c13c456b>] dump_stack+0x75/0xa9
[ 152.797835] [<c13e72ac>] ? __list_del_entry+0x4d/0x71
[ 152.804221] [<c103fd02>] __warn+0xad/0xc4
[ 152.809694] [<c103fd41>] warn_slowpath_fmt+0x28/0x2d
[ 152.816501] [<c13e72ac>] __list_del_entry+0x4d/0x71
[ 152.822946] [<c15027a8>] device_pm_remove+0x2b/0x5a
[ 152.829560] [<c14f6317>] device_del+0x134/0x19a
[ 152.835558] [<c14f5c38>] ? dev_set_name+0x14/0x16
[ 152.842135] [<c14f9d7c>] platform_device_del+0x16/0x5d
[ 152.848909] [<c2207515>] init+0xe5/0x112
[ 152.854149] [<c2207430>] ? usbip_host_init+0xe5/0xe5
[ 152.860796] [<c21cec35>] do_one_initcall+0x7e/0x101
[ 152.867316] [<c10564b7>] ? parse_args+0x190/0x265
[ 152.873497] [<c21ced88>] ? kernel_init_freeable+0xd0/0x174
[ 152.880923] [<c21ceda4>] kernel_init_freeable+0xec/0x174
[ 152.887499] [<c1be421b>] kernel_init+0x8/0xd0
[ 152.893658] [<c1bec3ae>] ret_from_kernel_thread+0xe/0x30
[ 152.900759] [<c1be4213>] ? rest_init+0x111/0x111
[ 152.907334] ---[ end trace 2003739b08d25430 ]---
[ 152.920040] ------------[ cut here ]------------
Thanks,
Kernel Test Robot
4 years, 5 months
[lkp] [x86] 784d5699ed: kmsg.ip_tables:no_symbol_version_for_copy_from_user
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 784d5699eddc55878627da20d3fe0c8542e2f1a2 ("x86: move exports to actual definitions")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes:
[ 4.386609] ip_tables: no symbol version for _copy_from_user
[ 4.387902] ip_tables: Unknown symbol _copy_from_user (err -22)
[ 4.389219] ip_tables: no symbol version for __put_user_2
[ 4.390473] ip_tables: Unknown symbol __put_user_2 (err -22)
[ 4.391760] ip_tables: no symbol version for __sw_hweight32
[ 4.393031] ip_tables: Unknown symbol __sw_hweight32 (err -22)
[ 4.394332] ip_tables: no symbol version for memcpy
[ 4.395508] ip_tables: Unknown symbol memcpy (err -22)
[ 4.396729] ip_tables: no symbol version for __fentry__
[ 4.397937] ip_tables: Unknown symbol __fentry__ (err -22)
[ 4.399234] ip_tables: no symbol version for _copy_to_user
[ 4.400488] ip_tables: Unknown symbol _copy_to_user (err -22)
[ 4.405835] systemd[1]: Failed to insert module 'ip_tables': Invalid argument
Thanks,
Xiaolong
4 years, 6 months
[lkp] [sctp] a6c2f79287: netperf.Throughput_Mbps -37.2% regression
by kernel test robot
FYI, we noticed a -37.2% regression of netperf.Throughput_Mbps due to commit:
commit a6c2f792873aff332a4689717c3cd6104f46684c ("sctp: implement prsctp TTL policy")
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
in testcase: netperf
on test machine: 4 threads Ivy Bridge with 8G memory
with following parameters:
ip: ipv4
runtime: 300s
nr_threads: 200%
cluster: cs-localhost
send_size: 10K
test: SCTP_STREAM_MANY
cpufreq_governor: performance
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.
Details are as below:
-------------------------------------------------------------------------------------------------->
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
=========================================================================================
cluster/compiler/cpufreq_governor/ip/kconfig/nr_threads/rootfs/runtime/send_size/tbox_group/test/testcase:
cs-localhost/gcc-6/performance/ipv4/x86_64-rhel-7.2/200%/debian-x86_64-2015-02-07.cgz/300s/10K/lkp-ivb-d02/SCTP_STREAM_MANY/netperf
commit:
826d253d57 ("sctp: add SCTP_PR_ASSOC_STATUS on sctp sockopt")
a6c2f79287 ("sctp: implement prsctp TTL policy")
826d253d57b11f69 a6c2f792873aff332a4689717c
---------------- --------------------------
%stddev %change %stddev
\ | \
3923 ± 0% -37.2% 2462 ± 0% netperf.Throughput_Mbps
11701 ± 14% -21.9% 9139 ± 11% meminfo.AnonHugePages
642.75 ± 7% +15.8% 744.00 ± 6% slabinfo.kmalloc-512.active_objs
4993 ± 2% +14.6% 5724 ± 2% slabinfo.kmalloc-64.active_objs
4993 ± 2% +14.6% 5724 ± 2% slabinfo.kmalloc-64.num_objs
9.00 ± 0% -77.8% 2.00 ± 0% vmstat.procs.r
112616 ± 1% +19.0% 133959 ± 0% vmstat.system.cs
4053 ± 0% +6.3% 4309 ± 0% vmstat.system.in
16466114 ± 0% -37.4% 10307354 ± 0% softirqs.NET_RX
72067 ± 10% -63.5% 26326 ± 4% softirqs.RCU
8598 ± 4% +954.6% 90677 ± 1% softirqs.SCHED
605899 ± 0% -45.7% 329111 ± 0% softirqs.TIMER
2529 ± 4% -15.1% 2147 ± 1% proc-vmstat.nr_alloc_batch
1.48e+08 ± 0% -37.1% 93059843 ± 0% proc-vmstat.numa_hit
1.48e+08 ± 0% -37.1% 93059842 ± 0% proc-vmstat.numa_local
3.742e+08 ± 1% -36.9% 2.362e+08 ± 0% proc-vmstat.pgalloc_dma32
4.733e+08 ± 0% -36.6% 3e+08 ± 0% proc-vmstat.pgalloc_normal
8.476e+08 ± 0% -36.7% 5.362e+08 ± 0% proc-vmstat.pgfree
99.31 ± 0% -44.6% 55.03 ± 0% turbostat.%Busy
3269 ± 0% -44.8% 1804 ± 1% turbostat.Avg_MHz
0.05 ± 17% +51942.1% 24.72 ± 1% turbostat.CPU%c1
0.64 ± 0% +3067.3% 20.11 ± 3% turbostat.CPU%c6
20.20 ± 0% -24.7% 15.21 ± 0% turbostat.CorWatt
0.12 ± 39% +1906.4% 2.36 ± 3% turbostat.Pkg%pc2
0.46 ± 10% +1696.7% 8.27 ± 6% turbostat.Pkg%pc6
37.54 ± 0% -14.5% 32.11 ± 0% turbostat.PkgWatt
76510 ± 46% +2.6e+05% 1.954e+08 ± 1% cpuidle.C1-IVB.time
6742 ± 76% +2.9e+05% 19860856 ± 1% cpuidle.C1-IVB.usage
19769 ± 17% +5414.4% 1090172 ± 3% cpuidle.C1E-IVB.time
151.00 ± 11% +4024.8% 6228 ± 3% cpuidle.C1E-IVB.usage
33074 ± 14% +5086.6% 1715442 ± 2% cpuidle.C3-IVB.time
114.50 ± 14% +6075.1% 7070 ± 3% cpuidle.C3-IVB.usage
8006184 ± 0% +4066.3% 3.336e+08 ± 2% cpuidle.C6-IVB.time
8874 ± 0% +4197.0% 381350 ± 2% cpuidle.C6-IVB.usage
476.75 ± 92% +77822.9% 371497 ± 12% cpuidle.POLL.time
46.25 ±144% +30824.3% 14302 ± 3% cpuidle.POLL.usage
1.758e+11 ± 2% -38.4% 1.083e+11 ± 1% perf-stat.L1-dcache-load-misses
6.405e+11 ± 0% -29.7% 4.5e+11 ± 0% perf-stat.L1-dcache-loads
6.28e+09 ± 3% -30.0% 4.398e+09 ± 0% perf-stat.L1-dcache-prefetch-misses
8.142e+10 ± 1% -39.3% 4.941e+10 ± 0% perf-stat.L1-dcache-store-misses
5.32e+11 ± 0% -33.6% 3.533e+11 ± 1% perf-stat.L1-dcache-stores
5.59e+10 ± 1% -19.3% 4.512e+10 ± 1% perf-stat.L1-icache-load-misses
5.199e+10 ± 0% -42.1% 3.01e+10 ± 1% perf-stat.LLC-loads
3.066e+10 ± 1% -42.4% 1.765e+10 ± 1% perf-stat.LLC-prefetches
5.282e+10 ± 0% -40.0% 3.168e+10 ± 1% perf-stat.LLC-stores
2.776e+11 ± 0% -27.9% 2.002e+11 ± 0% perf-stat.branch-instructions
3.861e+09 ± 1% -50.8% 1.9e+09 ± 0% perf-stat.branch-load-misses
2.772e+11 ± 0% -27.8% 2e+11 ± 0% perf-stat.branch-loads
3.864e+09 ± 1% -50.8% 1.9e+09 ± 0% perf-stat.branch-misses
1.179e+11 ± 0% -43.9% 6.61e+10 ± 0% perf-stat.bus-cycles
7.126e+09 ± 16% -96.8% 2.272e+08 ± 4% perf-stat.cache-misses
1.173e+11 ± 0% -38.0% 7.278e+10 ± 0% perf-stat.cache-references
34232822 ± 1% +19.1% 40787311 ± 0% perf-stat.context-switches
3.897e+12 ± 0% -44.2% 2.174e+12 ± 1% perf-stat.cpu-cycles
12019 ± 35% +306.6% 48867 ± 1% perf-stat.cpu-migrations
4.069e+09 ± 20% -58.4% 1.694e+09 ± 46% perf-stat.dTLB-load-misses
6.421e+11 ± 0% -30.3% 4.476e+11 ± 1% perf-stat.dTLB-loads
5.285e+08 ± 22% -71.0% 1.531e+08 ± 17% perf-stat.dTLB-store-misses
5.32e+11 ± 0% -33.4% 3.544e+11 ± 1% perf-stat.dTLB-stores
3.735e+08 ± 5% -48.5% 1.923e+08 ± 3% perf-stat.iTLB-load-misses
1.803e+08 ± 52% +662.4% 1.374e+09 ± 2% perf-stat.iTLB-loads
1.505e+12 ± 0% -29.3% 1.064e+12 ± 0% perf-stat.instructions
339045 ± 0% +4.4% 354068 ± 0% perf-stat.minor-faults
339041 ± 0% +4.4% 354062 ± 0% perf-stat.page-faults
3.892e+12 ± 0% -43.9% 2.182e+12 ± 0% perf-stat.ref-cycles
3.052e+12 ± 0% -25.9% 2.261e+12 ± 0% perf-stat.stalled-cycles-frontend
34082 ± 16% -65.5% 11746 ± 83% sched_debug.cfs_rq:/.MIN_vruntime.avg
75047 ± 3% -58.2% 31366 ± 63% sched_debug.cfs_rq:/.MIN_vruntime.max
34069 ± 3% -59.9% 13660 ± 61% sched_debug.cfs_rq:/.MIN_vruntime.stddev
1403286 ± 4% -34.9% 913870 ± 14% sched_debug.cfs_rq:/.load.avg
1874082 ± 7% -27.9% 1352098 ± 19% sched_debug.cfs_rq:/.load.max
1041057 ± 0% -66.8% 345620 ± 35% sched_debug.cfs_rq:/.load.min
1063 ± 0% -54.6% 482.93 ± 15% sched_debug.cfs_rq:/.load_avg.avg
1204 ± 3% -20.6% 956.50 ± 4% sched_debug.cfs_rq:/.load_avg.max
939.38 ± 0% -86.4% 127.33 ± 31% sched_debug.cfs_rq:/.load_avg.min
104.33 ± 19% +238.6% 353.26 ± 6% sched_debug.cfs_rq:/.load_avg.stddev
34082 ± 16% -65.5% 11746 ± 83% sched_debug.cfs_rq:/.max_vruntime.avg
75047 ± 3% -58.2% 31366 ± 63% sched_debug.cfs_rq:/.max_vruntime.max
34069 ± 3% -59.9% 13660 ± 61% sched_debug.cfs_rq:/.max_vruntime.stddev
74820 ± 0% -10.4% 67031 ± 1% sched_debug.cfs_rq:/.min_vruntime.min
1091 ± 7% +378.3% 5221 ± 13% sched_debug.cfs_rq:/.min_vruntime.stddev
1.35 ± 3% -35.4% 0.88 ± 14% sched_debug.cfs_rq:/.nr_running.avg
1.83 ± 6% -29.5% 1.29 ± 19% sched_debug.cfs_rq:/.nr_running.max
1.00 ± 0% -66.7% 0.33 ± 35% sched_debug.cfs_rq:/.nr_running.min
943.28 ± 0% -61.0% 367.80 ± 20% sched_debug.cfs_rq:/.runnable_load_avg.avg
991.42 ± 1% -21.2% 781.00 ± 14% sched_debug.cfs_rq:/.runnable_load_avg.max
894.88 ± 1% -90.9% 81.46 ± 20% sched_debug.cfs_rq:/.runnable_load_avg.min
39.07 ± 31% +667.7% 299.97 ± 16% sched_debug.cfs_rq:/.runnable_load_avg.stddev
-794.96 ±-98% +505.2% -4810 ±-18% sched_debug.cfs_rq:/.spread0.avg
-1935 ±-42% +442.8% -10508 ±-13% sched_debug.cfs_rq:/.spread0.min
1092 ± 7% +378.0% 5221 ± 13% sched_debug.cfs_rq:/.spread0.stddev
949.60 ± 0% -41.6% 554.85 ± 12% sched_debug.cfs_rq:/.util_avg.avg
976.08 ± 1% -9.9% 879.71 ± 1% sched_debug.cfs_rq:/.util_avg.max
925.83 ± 0% -67.0% 305.25 ± 20% sched_debug.cfs_rq:/.util_avg.min
18.71 ± 30% +1151.4% 234.18 ± 6% sched_debug.cfs_rq:/.util_avg.stddev
2.60 ± 9% -66.3% 0.88 ± 62% sched_debug.cpu.clock.stddev
2.60 ± 9% -66.3% 0.88 ± 62% sched_debug.cpu.clock_task.stddev
941.57 ± 1% -65.8% 322.38 ± 25% sched_debug.cpu.cpu_load[0].avg
992.46 ± 1% -29.5% 700.08 ± 25% sched_debug.cpu.cpu_load[0].max
880.67 ± 4% -93.3% 58.58 ± 64% sched_debug.cpu.cpu_load[0].min
44.14 ± 46% +526.2% 276.40 ± 28% sched_debug.cpu.cpu_load[0].stddev
944.76 ± 0% -59.6% 381.23 ± 17% sched_debug.cpu.cpu_load[1].avg
980.25 ± 1% -17.5% 808.25 ± 5% sched_debug.cpu.cpu_load[1].max
900.79 ± 1% -89.8% 91.67 ± 28% sched_debug.cpu.cpu_load[1].min
30.83 ± 30% +904.7% 309.72 ± 7% sched_debug.cpu.cpu_load[1].stddev
941.95 ± 0% -61.6% 361.83 ± 18% sched_debug.cpu.cpu_load[2].avg
974.08 ± 1% -18.0% 798.29 ± 5% sched_debug.cpu.cpu_load[2].max
899.00 ± 1% -90.8% 82.42 ± 24% sched_debug.cpu.cpu_load[2].min
29.59 ± 30% +946.9% 309.73 ± 6% sched_debug.cpu.cpu_load[2].stddev
935.02 ± 0% -63.1% 344.78 ± 19% sched_debug.cpu.cpu_load[3].avg
965.83 ± 1% -18.7% 785.50 ± 5% sched_debug.cpu.cpu_load[3].max
893.04 ± 1% -91.8% 73.21 ± 26% sched_debug.cpu.cpu_load[3].min
28.77 ± 29% +976.4% 309.70 ± 6% sched_debug.cpu.cpu_load[3].stddev
922.55 ± 0% -64.2% 330.33 ± 19% sched_debug.cpu.cpu_load[4].avg
951.75 ± 1% -20.0% 761.71 ± 6% sched_debug.cpu.cpu_load[4].max
880.96 ± 0% -92.8% 63.08 ± 31% sched_debug.cpu.cpu_load[4].min
27.92 ± 26% +988.8% 304.04 ± 6% sched_debug.cpu.cpu_load[4].stddev
1302 ± 1% -15.7% 1097 ± 7% sched_debug.cpu.curr->pid.avg
798.04 ± 5% -70.5% 235.67 ± 39% sched_debug.cpu.curr->pid.min
812.92 ± 1% +22.9% 999.29 ± 4% sched_debug.cpu.curr->pid.stddev
1359793 ± 6% -34.4% 892053 ± 17% sched_debug.cpu.load.avg
1830834 ± 4% -28.5% 1308664 ± 19% sched_debug.cpu.load.max
1041241 ± 0% -62.6% 389138 ± 37% sched_debug.cpu.load.min
0.00 ± 21% +77.1% 0.00 ± 7% sched_debug.cpu.next_balance.stddev
150630 ± 0% -24.2% 114118 ± 0% sched_debug.cpu.nr_load_updates.avg
151833 ± 0% -21.6% 119037 ± 0% sched_debug.cpu.nr_load_updates.max
149812 ± 0% -27.3% 108953 ± 1% sched_debug.cpu.nr_load_updates.min
745.51 ± 1% +502.7% 4492 ± 14% sched_debug.cpu.nr_load_updates.stddev
2.60 ± 1% -52.8% 1.23 ± 17% sched_debug.cpu.nr_running.avg
3.79 ± 3% -39.6% 2.29 ± 39% sched_debug.cpu.nr_running.max
1.62 ± 8% -74.4% 0.42 ± 34% sched_debug.cpu.nr_running.min
4242583 ± 1% +20.1% 5094187 ± 0% sched_debug.cpu.nr_switches.avg
3880089 ± 1% +24.6% 4835277 ± 1% sched_debug.cpu.nr_switches.min
487080 ± 18% -61.6% 186796 ± 37% sched_debug.cpu.nr_switches.stddev
netperf.Throughput_Mbps
4000 ++-----------------------------------*--*-----*--*--*--*--*-*--*-----+
| .*.. .*..*..* *. |
3500 *+.*..* *.*..*..*. *. |
3000 ++ : : |
| : O |
2500 O+ O O: O : O O O O O O O O O O O O O O
| : : O O O O O |
2000 ++ : : |
| : : |
1500 ++ : : |
1000 ++ : : |
| : : |
500 ++ :: |
| : |
0 ++-------*------------------------------------O----------------------+
[*] bisect-good sample
[O] bisect-bad sample
Thanks,
Xiaolong
4 years, 6 months
[lkp] [sched/core] 4fa5cd5245: BUG: sleeping function called from invalid context at kernel/locking/mutex.c:97
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 4fa5cd5245b627db88c9ca08ae442373b02596b4 ("sched/core: Do not use smp_processor_id() with preempt enabled in smpboot_thread_fn()")
in testcase: will-it-scale
with following parameters:
test: open2
cpufreq_governor: performance
Will It Scale takes a testcase and runs it from 1 through to n parallel copies to see if the testcase will scale. It builds both a process and threads based test in order to see any differences between the two.
on test machine: 48 threads 2 sockets Intel(R) Xeon(R) CPU E5-2697 v2 @ 2.70GHz with 64G memory
caused below changes:
+-----------------------------------------------------------------------------+------------+------------+
| | d59dc7bcfa | 4fa5cd5245 |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 15 | 2 |
| boot_failures | 8 | 12 |
| WARNING:at_kernel/events/core.c:#perf_event_read | 7 | 2 |
| calltrace:SyS_read | 7 | 2 |
| BUG:kernel_reboot-without-warning_in_test_stage | 1 | 2 |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 0 | 10 |
| calltrace:smpboot_thread_fn | 0 | 10 |
+-----------------------------------------------------------------------------+------------+------------+
user :notice: [ 37.079160] IPMI Device Information
kern :info : [ 37.086367] ipmi device interface
kern :err : [ 37.180350] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:97
kern :err : [ 37.192550] in_atomic(): 1, irqs_disabled(): 0, pid: 14, name: watchdog/0
kern :warn : [ 37.200866] CPU: 0 PID: 14 Comm: watchdog/0 Not tainted 4.8.0-rc5-00037-g4fa5cd5 #1
kern :warn : [ 37.210142] Hardware name: Intel Corporation S2600WP/S2600WP, BIOS SE5C600.86B.02.02.0002.122320131210 12/23/2013
kern :warn : [ 37.222331] 0000000000000000 ffff88081ab67d90 ffffffff8144f659 ffff88081ab3cb80
kern :warn : [ 37.231371] 0000000000000061 ffff88081ab67da8 ffffffff810a4ca4 ffffffff81ca2258
kern :warn : [ 37.240415] ffff88081ab67dd0 ffffffff810a4d39 ffff88081e01d1a8 ffff88081dc37800
kern :warn : [ 37.249439] Call Trace:
kern :warn : [ 37.252907] [<ffffffff8144f659>] dump_stack+0x63/0x8a
kern :warn : [ 37.259387] [<ffffffff810a4ca4>] ___might_sleep+0xd4/0x120
kern :warn : [ 37.266354] [<ffffffff810a4d39>] __might_sleep+0x49/0x80
kern :warn : [ 37.273104] [<ffffffff81930e20>] mutex_lock+0x20/0x50
kern :warn : [ 37.279565] [<ffffffff81169b72>] perf_event_ctx_lock_nested+0x52/0xa0
kern :warn : [ 37.288360] [<ffffffff8116ccaf>] perf_event_disable+0xf/0x30
kern :warn : [ 37.295509] [<ffffffff8112e906>] watchdog_nmi_disable+0x36/0x70
kern :warn : [ 37.302948] [<ffffffff810a15b4>] ? smpboot_thread_fn+0x34/0x1e0
kern :warn : [ 37.310388] [<ffffffff8112e98d>] watchdog_disable+0x4d/0x60
kern :warn : [ 37.317430] [<ffffffff810a16de>] smpboot_thread_fn+0x15e/0x1e0
kern :warn : [ 37.324750] [<ffffffff810a1580>] ? sort_range+0x30/0x30
kern :warn : [ 37.331394] [<ffffffff8109e194>] kthread+0xd4/0xf0
kern :warn : [ 37.337548] [<ffffffff81933c3f>] ret_from_fork+0x1f/0x40
kern :warn : [ 37.344284] [<ffffffff8109e0c0>] ? kthread_create_on_node+0x180/0x180
user :notice: [ 37.466233] BMC ARP Control : ARP Responses Enabled, Gratuitous ARP Disabled
user :notice: [ 38.078292] 2016-09-29 21:16:05 ./runtest.py open2 25 both 1 12 24 36 48
kern :notice: [ 112.518727] random: crng init done
kern :warn : [ 124.655523] ------------[ cut here ]------------
kern :warn : [ 124.662221] WARNING: CPU: 2 PID: 883 at kernel/events/core.c:3554 perf_event_read+0x17d/0x190
kern :warn : [ 124.675342] Modules linked in: ipmi_devintf rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sd_mod sg intel_rapl sb_edac edac_core x86_pkg_temp_thermal mgag200 intel_powerclamp ttm coretemp drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops kvm_intel isci drm snd_pcm kvm snd_timer irqbypass libsas crct10dif_pclmul crc32_pclmul ahci snd scsi_transport_sas crc32c_intel soundcore ghash_clmulni_intel libahci aesni_intel lrw gf128mul glue_helper ablk_helper cryptd pcspkr libata shpchp wmi ipmi_si ipmi_msghandler ip_tables
kern :warn : [ 124.739874] CPU: 2 PID: 883 Comm: perf Tainted: G W 4.8.0-rc5-00037-g4fa5cd5 #1
kern :warn : [ 124.750790] Hardware name: Intel Corporation S2600WP/S2600WP, BIOS SE5C600.86B.02.02.0002.122320131210 12/23/2013
kern :warn : [ 124.763775] 0000000000000000 ffff880806c9fcf0 ffffffff8144f659 0000000000000000
kern :warn : [ 124.773539] 0000000000000000 ffff880806c9fd30 ffffffff8107e70b 00000de20a73d540
kern :warn : [ 124.783256] ffff8808032a8800 0000000000000000 0000000000000003 ffff8808032a8800
kern :warn : [ 124.793019] Call Trace:
kern :warn : [ 124.797047] [<ffffffff8144f659>] dump_stack+0x63/0x8a
kern :warn : [ 124.804100] [<ffffffff8107e70b>] __warn+0xcb/0xf0
kern :warn : [ 124.810741] [<ffffffff8107e7fd>] warn_slowpath_null+0x1d/0x20
kern :warn : [ 124.818591] [<ffffffff8116961d>] perf_event_read+0x17d/0x190
kern :warn : [ 124.826322] [<ffffffff81169675>] perf_event_read_value+0x45/0x130
kern :warn : [ 124.834551] [<ffffffff8116dceb>] perf_read+0x17b/0x2d0
kern :warn : [ 124.841694] [<ffffffff8120d108>] __vfs_read+0x28/0x130
kern :warn : [ 124.848827] [<ffffffff813dbc90>] ? security_file_permission+0xa0/0xc0
kern :warn : [ 124.857433] [<ffffffff8120d4ce>] ? rw_verify_area+0x4e/0xb0
kern :warn : [ 124.865067] [<ffffffff8120d5c6>] vfs_read+0x96/0x130
kern :warn : [ 124.871984] [<ffffffff8120e9b6>] SyS_read+0x46/0xa0
kern :warn : [ 124.878800] [<ffffffff81933a32>] entry_SYSCALL_64_fastpath+0x1a/0xa4
kern :warn : [ 124.887299] ---[ end trace 7fd930587be847d3 ]---
kern :warn : [ 124.887299] ---[ end trace 7fd930587be847d3 ]---
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
Thanks,
Xiaolong
4 years, 6 months
[lkp] [staging] d4f56b47a8: divide error: 0000 [#1] PREEMPT SMP KASAN
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit d4f56b47a8fac90b15adfae80a42a2735d6b3213 ("staging: greybus: Add drivers/staging/greybus to the build")
in testcase: trinity
with following parameters:
runtime: 300s
Trinity is a linux system call fuzz tester.
on test machine: qemu-system-x86_64 -enable-kvm -m 512M
caused below changes:
+------------------------------------------------+------------+------------+
| | 526dec0642 | d4f56b47a8 |
+------------------------------------------------+------------+------------+
| boot_successes | 5 | 0 |
| boot_failures | 8 | 12 |
| calltrace:SyS_open | 8 | |
| invoked_oom-killer:gfp_mask=0x | 1 | |
| Mem-Info | 1 | |
| IP-Config:Auto-configuration_of_network_failed | 2 | |
| BUG:kernel_hang_in_test_stage | 6 | |
| divide_error:#[##]PREEMPT_SMP_KASAN | 0 | 12 |
| RIP:gb_timesync_init | 0 | 12 |
| calltrace:gb_init | 0 | 12 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 12 |
+------------------------------------------------+------------+------------+
[ 16.795543] FPGA image file name: xlinx_fpga_firmware.bit
[ 16.796615] GPIO INIT FAIL!!
[ 16.799462] Unable to find a compatible ARMv7 timer
[ 16.799948] divide error: 0000 [#1] PREEMPT SMP KASAN
[ 16.800459] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.8.0-rc6-02364-gd4f56b4 #29
[ 16.801197] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 16.802055] task: ffff88001a124000 task.stack: ffff88001a140000
[ 16.802645] RIP: 0010:[<ffffffff82f67e97>] [<ffffffff82f67e97>] gb_timesync_init+0x35/0x78
[ 16.803534] RSP: 0000:ffff88001a147e58 EFLAGS: 00010246
[ 16.804040] RAX: 00038d7ea4c68000 RBX: 0000000000000000 RCX: ffffffff8114ea41
[ 16.804716] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff88001a124c2c
[ 16.805393] RBP: ffff88001a147e60 R08: 0000000000000001 R09: 0000000000000000
[ 16.806066] R10: ffff88001a147d70 R11: ffffffff83cddb35 R12: ffffffff82f67cc6
[ 16.806744] R13: 0000000000000000 R14: ffffffff82fbe8b0 R15: ffffffff82fbe8f8
[ 16.807421] FS: 0000000000000000(0000) GS:ffff88001a400000(0000) knlGS:0000000000000000
[ 16.808185] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 16.808728] CR2: 0000000000000000 CR3: 0000000002c0a000 CR4: 00000000000006b0
[ 16.809405] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 16.810078] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 16.810752] Stack:
[ 16.811058] 0000000000000000 ffff88001a147e78 ffffffff82f67d45 00000000ffffffff
[ 16.811819] ffff88001a147ee8 ffffffff82efe339 ffffffff82b89800 0000000000000012
[ 16.812576] ffff88001fa80fe5 0000000000000000 ffffffff82b0495f 0000000000000006
[ 16.813332] Call Trace:
[ 16.813577] [<ffffffff82f67d45>] gb_init+0x7f/0xb3
[ 16.814045] [<ffffffff82efe339>] do_one_initcall+0x9a/0x12c
[ 16.814588] [<ffffffff82efe57b>] kernel_init_freeable+0x1b0/0x246
[ 16.815180] [<ffffffff82404a51>] kernel_init+0xc/0x108
[ 16.815679] [<ffffffff8240e40f>] ret_from_fork+0x1f/0x40
[ 16.816197] [<ffffffff82404a45>] ? rest_init+0x13c/0x13c
[ 16.816724] Code: 85 c0 89 c3 74 12 48 c7 c7 64 ae b4 82 31 c0 e8 40 b5 27 fe 89 d8 eb 53 e8 cb 55 23 ff 31 d2 89 c6 48 b8 00 80 c6 a4 7e 8d 03 00 <48> f7 f6 31 d2 48 c7 c7 84 ae b4 82 48 89 35 de 65 64 01 48 89
[ 16.819509] RIP [<ffffffff82f67e97>] gb_timesync_init+0x35/0x78
[ 16.820094] RSP <ffff88001a147e58>
[ 16.820548] ---[ end trace c73ba0f929e81492 ]---
[ 16.821001] Kernel panic - not syncing: Fatal exception
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
Thanks,
Xiaolong
4 years, 6 months
[mm] 3f40a9185a: kernel BUG at kernel/cred.c:768!
by kernel test robot
FYI, we noticed the following commit:
https://github.com/0day-ci/linux Jann-Horn/fs-exec-don-t-force-writing-memory-access/20160929-222244
commit 3f40a9185af5f5335b8117178c706b74537b960b ("mm: add LSM hook for writes to readonly memory")
in testcase: boot
on test machine: qemu-system-i386 -enable-kvm -cpu Haswell,+smep,+smap -m 360M
caused below changes:
+------------------------------------------+------------+------------+
| | dc00268ef0 | 3f40a9185a |
+------------------------------------------+------------+------------+
| boot_successes | 24 | 2 |
| boot_failures | 0 | 18 |
| kernel_BUG_at_kernel/cred.c | 0 | 12 |
| invalid_opcode:#[##]SMP | 0 | 12 |
| EIP_is_at__invalid_creds | 0 | 12 |
| calltrace:SyS_exit_group | 0 | 18 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 18 |
| BUG:unable_to_handle_kernel | 0 | 10 |
| Oops | 0 | 10 |
| EIP_is_at_mem_release | 0 | 10 |
+------------------------------------------+------------+------------+
[ 23.725743] trinity-c0 (12124) used greatest stack depth: 6144 bytes left
[ 23.729863] CRED: ->security {83184389, d88918c4}
[ 23.730466] ------------[ cut here ]------------
[ 23.731054] kernel BUG at kernel/cred.c:768!
[ 23.731770] invalid opcode: 0000 [#1] SMP
[ 23.732270] Modules linked in:
[ 23.732674] CPU: 0 PID: 10617 Comm: trinity-main Not tainted 4.8.0-rc8-00015-g3f40a91 #78
[ 23.733678] task: 8c79a6c0 task.stack: 8c48c000
[ 23.734248] EIP: 0060:[<8104cad8>] EFLAGS: 00010292 CPU: 0
[ 23.734962] EIP is at __invalid_creds+0x35/0x37
[ 23.735523] EAX: 00000025 EBX: 8d11a458 ECX: 8106ce3c EDX: 00000001
[ 23.736304] ESI: 813d667c EDI: 0000010f EBP: 8c48ded4 ESP: 8c48deb8
[ 23.737080] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
[ 23.737750] CR0: 80050033 CR2: 7fc1ed30 CR3: 01647000 CR4: 00040690
[ 23.738529] DR0: c0100220 DR1: 00000000 DR2: 00000000 DR3: 00000000
[ 23.739308] DR6: ffff0ff0 DR7: 00050602
[ 23.739793] Stack:
[ 23.740054] 813d6660 813d667c 0000010f 813d6643 8d11a458 8d03ab80 8847b8e4 8c48dee4
[ 23.741161] 811153aa 8ca8f6c0 00000010 8c48df08 810de5a9 8ca8f6c8 88460a18 8847b8e4
[ 23.742265] 93c83c50 8ca8f6c0 8c79a6c0 8ca8e700 8c48df10 810de65c 8c48df28 8104a7a7
[ 23.743369] Call Trace:
[ 23.743700] [<811153aa>] mem_release+0x35/0x4e
[ 23.744284] [<810de5a9>] __fput+0xd8/0x162
[ 23.744815] [<810de65c>] ____fput+0x8/0xa
[ 23.745333] [<8104a7a7>] task_work_run+0x54/0x78
[ 23.745935] [<8103a20a>] do_exit+0x33c/0x7ec
[ 23.746478] [<810dd644>] ? vfs_write+0x9a/0xa4
[ 23.747051] [<8103a711>] do_group_exit+0x30/0x86
[ 23.747634] [<8103a778>] SyS_exit_group+0x11/0x11
[ 23.748236] [<81000e0b>] do_int80_syscall_32+0x43/0x55
[ 23.748909] [<812b8911>] entry_INT80_32+0x31/0x31
[ 23.749503] Code: 89 cf 68 43 66 3d 81 e8 1e 9a 05 00 57 56 68 60 66 3d 81 e8 12 9a 05 00 64 8b 0d dc 8a 4f 81 ba 72 66 3d 81 89 d8 e8 ac fe ff ff <0f> 0b 81 78 0c 64 65 73 43 74 08 55 89 e5 e8 b8 ff ff ff c3 55
[ 23.753032] EIP: [<8104cad8>] __invalid_creds+0x35/0x37 SS:ESP 0068:8c48deb8
[ 23.753971] ---[ end trace e46a82be55c05913 ]---
[ 23.754894] BUG: unable to handle kernel NULL pointer dereference at (null)
Thanks,
Kernel Test Robot
4 years, 6 months
[lkp] 62f37bbb53: fio.latency_20us% -7.1% improvement
by kernel test robot
FYI, we noticed a -7.1% improvement of fio.latency_20us% due to commit:
commit 62f37bbb539729c6b3182ba5345dccfc582ee6f2 ("remove mapping from balance_dirty_pages*()")
https://github.com/kdave/btrfs-devel.git foreign/josef/no-btree-inode
in testcase: fio-basic
on test machine: 16 threads Intel(R) Xeon(R) CPU D-1541 @ 2.10GHz with 8G memory
with following parameters:
runtime: 300s
nr_task: 8
disk: 1SSD
fs: btrfs
rw: write
bs: 4k
ioengine: sync
test_size: 400g
cpufreq_governor: performance
Fio is a tool that will spawn a number of threads or processes doing a particular type of I/O action as specified by the user.
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.
Details are as below:
-------------------------------------------------------------------------------------------------->
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
=========================================================================================
bs/compiler/cpufreq_governor/disk/fs/ioengine/kconfig/nr_task/rootfs/runtime/rw/tbox_group/test_size/testcase:
4k/gcc-6/performance/1SSD/btrfs/sync/x86_64-rhel-7.2/8/debian-x86_64-2016-08-31.cgz/300s/write/lkp-bdw-de1/400g/fio-basic
commit:
v4.8-rc5
62f37bbb53 ("remove mapping from balance_dirty_pages*()")
v4.8-rc5 62f37bbb539729c6b3182ba534
---------------- --------------------------
fail:runs %reproduction fail:runs
| | |
%stddev %change %stddev
\ | \
0.64 ± 0% -7.1% 0.59 ± 1% fio.latency_20us%
1584 ± 0% +2.5% 1624 ± 0% fio.write_clat_stddev
24776 ± 64% +114.1% 53052 ± 0% interrupts.CAL:Function_call_interrupts
150248 ± 14% +31.0% 196781 ± 4% cpuidle.C1-BDW.usage
1.138e+09 ± 12% -18.5% 9.274e+08 ± 8% cpuidle.C3-BDW.time
1365995 ± 10% -16.5% 1140547 ± 7% cpuidle.C3-BDW.usage
29.69 ± 0% +380.1% 142.52 ± 0% iostat.sda.avgqu-sz
66.80 ± 0% +377.3% 318.81 ± 0% iostat.sda.await
66.89 ± 0% +376.8% 318.94 ± 0% iostat.sda.w_await
3.16 ± 7% +52.5% 4.82 ± 2% iostat.sda.wrqm/s
8.036e+09 ± 1% +1.9% 8.188e+09 ± 0% perf-stat.cache-misses
8.036e+09 ± 1% +1.9% 8.188e+09 ± 0% perf-stat.cache-references
0.08 ± 1% -3.5% 0.08 ± 2% perf-stat.dTLB-load-miss-rate%
1.189e+11 ± 0% +2.1% 1.214e+11 ± 1% perf-stat.dTLB-stores
47.23 ± 4% +41.9% 67.00 ± 1% perf-stat.iTLB-load-miss-rate%
46522400 ± 2% +159.4% 1.207e+08 ± 2% perf-stat.iTLB-load-misses
14723 ± 2% -61.3% 5692 ± 2% perf-stat.instructions-per-iTLB-miss
36245 ± 0% +1605.2% 618076 ± 33% latency_stats.avg.max
0.00 ± -1% +Inf% 618076 ± 33% latency_stats.avg.wait_current_trans.[btrfs].start_transaction.[btrfs].btrfs_join_transaction.[btrfs].btrfs_alloc_data_chunk_ondemand.[btrfs].btrfs_check_data_free_space.[btrfs].__btrfs_buffered_write.[btrfs].btrfs_file_write_iter.[btrfs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
55510 ± 0% +232.1% 184333 ± 9% latency_stats.max.balance_dirty_pages.balance_dirty_pages_ratelimited.__btrfs_buffered_write.[btrfs].btrfs_file_write_iter.[btrfs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
55510 ± 0% +1042.5% 634181 ± 33% latency_stats.max.max
0.00 ± -1% +Inf% 634181 ± 33% latency_stats.max.wait_current_trans.[btrfs].start_transaction.[btrfs].btrfs_join_transaction.[btrfs].btrfs_alloc_data_chunk_ondemand.[btrfs].btrfs_check_data_free_space.[btrfs].__btrfs_buffered_write.[btrfs].btrfs_file_write_iter.[btrfs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
1070112 ± 57% -100.0% 0.00 ± -1% latency_stats.sum.msleep_interruptible.uart_wait_until_sent.tty_wait_until_sent.tty_port_close_start.tty_port_close_start.uart_close.tty_release.__fput.____fput.task_work_run.exit_to_usermode_loop.syscall_return_slowpath
3406813 ± 57% -100.0% 0.00 ± -1% latency_stats.sum.tty_wait_until_sent.tty_port_close_start.tty_port_close_start.uart_close.tty_release.__fput.____fput.task_work_run.exit_to_usermode_loop.syscall_return_slowpath.entry_SYSCALL_64_fastpath
0.00 ± -1% +Inf% 4944613 ± 33% latency_stats.sum.wait_current_trans.[btrfs].start_transaction.[btrfs].btrfs_join_transaction.[btrfs].btrfs_alloc_data_chunk_ondemand.[btrfs].btrfs_check_data_free_space.[btrfs].__btrfs_buffered_write.[btrfs].btrfs_file_write_iter.[btrfs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
48883 ± 51% +91.6% 93647 ± 0% meminfo.Active(anon)
23808 ± 98% +179.3% 66508 ± 1% meminfo.AnonHugePages
46431 ± 54% +96.4% 91173 ± 0% meminfo.AnonPages
1059 ± 0% -30.7% 733.40 ± 49% meminfo.Buffers
289278 ±100% +174.2% 793156 ± 0% meminfo.Committed_AS
108335 ±172% +325.4% 460910 ± 3% meminfo.Dirty
2023600 ±140% +244.0% 6961094 ± 0% meminfo.Inactive
45858 ±105% +183.1% 129843 ± 0% meminfo.Inactive(anon)
1977741 ±141% +245.4% 6831250 ± 0% meminfo.Inactive(file)
7500 ± 13% -22.5% 5814 ± 0% meminfo.KernelStack
51079 ± 66% +117.5% 111089 ± 0% meminfo.SReclaimable
37424 ± 13% +28.9% 48226 ± 0% meminfo.SUnreclaim
88503 ± 43% +80.0% 159316 ± 0% meminfo.Slab
12429 ± 50% +88.4% 23415 ± 0% proc-vmstat.nr_active_anon
11844 ± 52% +92.5% 22794 ± 0% proc-vmstat.nr_anon_pages
8569526 ±173% +300.5% 34317951 ± 0% proc-vmstat.nr_dirtied
27087 ±172% +325.2% 115182 ± 3% proc-vmstat.nr_dirty
11464 ±105% +183.1% 32459 ± 0% proc-vmstat.nr_inactive_anon
494358 ±141% +245.3% 1707266 ± 0% proc-vmstat.nr_inactive_file
7650 ± 13% -23.3% 5866 ± 0% proc-vmstat.nr_kernel_stack
12793 ± 65% +117.1% 27772 ± 0% proc-vmstat.nr_slab_reclaimable
9378 ± 13% +28.5% 12056 ± 0% proc-vmstat.nr_slab_unreclaimable
98.25 ±173% +582.3% 670.40 ± 4% proc-vmstat.nr_vmscan_immediate_reclaim
8488016 ±173% +300.5% 33992756 ± 0% proc-vmstat.nr_written
12429 ± 50% +88.4% 23415 ± 0% proc-vmstat.nr_zone_active_anon
11464 ±105% +183.1% 32459 ± 0% proc-vmstat.nr_zone_inactive_anon
494355 ±141% +245.3% 1707256 ± 0% proc-vmstat.nr_zone_inactive_file
8853611 ±168% +291.5% 34664544 ± 0% proc-vmstat.numa_hit
8853608 ±168% +291.5% 34664544 ± 0% proc-vmstat.numa_local
6742739 ±165% +287.5% 26131244 ± 0% proc-vmstat.pgalloc_normal
9938521 ±133% +232.2% 33013181 ± 0% proc-vmstat.pgfree
33960263 ±173% +300.8% 1.361e+08 ± 0% proc-vmstat.pgpgout
8138579 ±173% +300.6% 32604499 ± 0% proc-vmstat.pgscan_kswapd
8116173 ±173% +300.6% 32517422 ± 0% proc-vmstat.pgsteal_kswapd
102208 ±173% +303.2% 412083 ± 0% proc-vmstat.slabs_scanned
34553 ±173% +300.7% 138453 ± 0% proc-vmstat.workingset_nodereclaim
1401 ±173% +300.3% 5611 ± 0% sched_debug.cfs_rq:/.exec_clock.avg
63.00 ±173% +554.0% 412.03 ± 21% sched_debug.cfs_rq:/.exec_clock.min
36695 ± 89% +155.3% 93674 ± 15% sched_debug.cfs_rq:/.load.avg
175323 ±135% +360.9% 807984 ± 15% sched_debug.cfs_rq:/.load.max
56018 ±135% +302.8% 225649 ± 12% sched_debug.cfs_rq:/.load.stddev
1284 ± 41% -77.3% 291.42 ± 5% sched_debug.cfs_rq:/.load_avg.avg
1684 ± 29% -65.0% 589.23 ± 29% sched_debug.cfs_rq:/.load_avg.max
1176 ± 45% -79.7% 239.03 ± 4% sched_debug.cfs_rq:/.load_avg.min
0.62 ± 39% -63.0% 0.23 ± 5% sched_debug.cfs_rq:/.nr_running.avg
659.98 ± 38% -73.9% 172.16 ± 6% sched_debug.cfs_rq:/.util_avg.avg
818.29 ± 10% -53.1% 383.57 ± 30% sched_debug.cfs_rq:/.util_avg.max
530.92 ± 45% -80.0% 106.40 ± 5% sched_debug.cfs_rq:/.util_avg.min
506937 ± 33% +65.4% 838646 ± 1% sched_debug.cpu.avg_idle.avg
188707 ± 79% +154.6% 480359 ± 8% sched_debug.cpu.avg_idle.min
213657 ± 16% -32.4% 144386 ± 6% sched_debug.cpu.avg_idle.stddev
26.41 ±165% +528.9% 166.10 ± 25% sched_debug.cpu.clock.stddev
26.41 ±165% +528.9% 166.10 ± 25% sched_debug.cpu.clock_task.stddev
4.17 ± 54% -83.2% 0.70 ± 34% sched_debug.cpu.cpu_load[3].min
512.26 ± 33% -41.3% 300.60 ± 4% sched_debug.cpu.curr->pid.avg
175366 ±135% +346.1% 782313 ± 12% sched_debug.cpu.load.max
56184 ±135% +294.5% 221659 ± 11% sched_debug.cpu.load.stddev
0.00 ± 69% +246.9% 0.00 ± 19% sched_debug.cpu.next_balance.stddev
1529 ± 58% +116.2% 3306 ± 17% sched_debug.cpu.nr_load_updates.min
11523 ±173% +521.2% 71584 ± 4% sched_debug.cpu.sched_count.avg
32424 ±173% +527.3% 203408 ± 8% sched_debug.cpu.sched_count.max
9878 ±173% +552.0% 64407 ± 4% sched_debug.cpu.sched_count.stddev
78.71 ±173% +601.4% 552.03 ± 52% sched_debug.cpu.ttwu_local.min
1168 ± 0% -100.0% 0.00 ± -1% slabinfo.Acpi-Parse.active_objs
1168 ± 0% -100.0% 0.00 ± -1% slabinfo.Acpi-Parse.num_objs
34437 ± 2% +11.9% 38537 ± 1% slabinfo.Acpi-State.num_objs
379.00 ±127% +234.2% 1266 ± 2% slabinfo.bio-1.active_objs
392.25 ±129% +238.4% 1327 ± 2% slabinfo.bio-1.num_objs
800.50 ± 97% -100.0% 0.00 ± -1% slabinfo.blkdev_ioc.active_objs
803.50 ± 97% -100.0% 0.00 ± -1% slabinfo.blkdev_ioc.num_objs
1225 ±156% +274.6% 4589 ± 0% slabinfo.btrfs_extent_buffer.active_objs
43.00 ±157% +276.7% 162.00 ± 1% slabinfo.btrfs_extent_buffer.active_slabs
1225 ±156% +274.6% 4589 ± 0% slabinfo.btrfs_extent_buffer.num_objs
43.00 ±157% +276.7% 162.00 ± 1% slabinfo.btrfs_extent_buffer.num_slabs
2668 ± 36% -100.0% 0.00 ± -1% slabinfo.btrfs_extent_map.active_objs
2672 ± 37% -100.0% 0.00 ± -1% slabinfo.btrfs_extent_map.num_objs
442.75 ±132% +245.9% 1531 ± 15% slabinfo.btrfs_ordered_extent.active_objs
442.75 ±132% +245.9% 1531 ± 15% slabinfo.btrfs_ordered_extent.num_objs
118.25 ± 31% +91.0% 225.80 ± 8% slabinfo.btrfs_transaction.active_objs
118.25 ± 31% +91.0% 225.80 ± 8% slabinfo.btrfs_transaction.num_objs
9333 ± 4% -56.2% 4089 ± 12% slabinfo.cred_jar.active_objs
223.75 ± 5% -56.9% 96.40 ± 12% slabinfo.cred_jar.active_slabs
9398 ± 5% -56.5% 4089 ± 12% slabinfo.cred_jar.num_objs
223.75 ± 5% -56.9% 96.40 ± 12% slabinfo.cred_jar.num_slabs
881.50 ± 2% +12.9% 995.00 ± 1% slabinfo.dentry.active_slabs
37030 ± 2% +12.9% 41809 ± 1% slabinfo.dentry.num_objs
881.50 ± 2% +12.9% 995.00 ± 1% slabinfo.dentry.num_slabs
2118 ± 7% +57.8% 3343 ± 8% slabinfo.file_lock_cache.active_objs
2122 ± 7% +58.0% 3354 ± 8% slabinfo.file_lock_cache.num_objs
3463 ± 1% +22.7% 4250 ± 1% slabinfo.ftrace_event_field.active_objs
3463 ± 1% +22.7% 4250 ± 1% slabinfo.ftrace_event_field.num_objs
29613 ± 0% +14.8% 33999 ± 0% slabinfo.kernfs_node_cache.active_objs
870.75 ± 0% +14.7% 999.00 ± 0% slabinfo.kernfs_node_cache.active_slabs
29613 ± 0% +14.8% 33999 ± 0% slabinfo.kernfs_node_cache.num_objs
870.75 ± 0% +14.7% 999.00 ± 0% slabinfo.kernfs_node_cache.num_slabs
527.00 ± 74% +133.3% 1229 ± 1% slabinfo.kmalloc-4096.active_objs
67.25 ± 76% +139.1% 160.80 ± 0% slabinfo.kmalloc-4096.active_slabs
539.50 ± 76% +139.6% 1292 ± 0% slabinfo.kmalloc-4096.num_objs
67.25 ± 76% +139.1% 160.80 ± 0% slabinfo.kmalloc-4096.num_slabs
2708 ± 14% +21.8% 3299 ± 4% slabinfo.kmalloc-512.active_objs
514.50 ± 6% +41.0% 725.40 ± 7% slabinfo.mnt_cache.active_objs
514.50 ± 6% +41.0% 725.40 ± 7% slabinfo.mnt_cache.num_objs
1748 ± 1% +31.6% 2300 ± 2% slabinfo.trace_event_file.active_objs
1748 ± 1% +31.6% 2300 ± 2% slabinfo.trace_event_file.num_objs
1.90 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.__ata_qc_complete.ata_qc_complete.ata_qc_complete_multiple.ahci_handle_port_interrupt.ahci_handle_port_intr
1.87 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.__blk_mq_complete_request.blk_mq_complete_request.scsi_mq_done.ata_scsi_qc_complete.__ata_qc_complete
1.95 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.__handle_irq_event_percpu.handle_irq_event_percpu.handle_irq_event.handle_edge_irq.handle_irq
0.00 ± -1% +Inf% 2.28 ± 14% perf-profile.calltrace.cycles-pp.__softirqentry_text_start.irq_exit.do_IRQ.ret_from_intr.cpuidle_enter
1.92 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.ahci_handle_port_interrupt.ahci_handle_port_intr.ahci_single_level_irq_intr.__handle_irq_event_percpu.handle_irq_event_percpu
1.93 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.ahci_handle_port_intr.ahci_single_level_irq_intr.__handle_irq_event_percpu.handle_irq_event_percpu.handle_irq_event
1.94 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.ahci_single_level_irq_intr.__handle_irq_event_percpu.handle_irq_event_percpu.handle_irq_event.handle_edge_irq
1.90 ± 2% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.ata_qc_complete.ata_qc_complete_multiple.ahci_handle_port_interrupt.ahci_handle_port_intr.ahci_single_level_irq_intr
1.90 ± 2% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.ata_qc_complete_multiple.ahci_handle_port_interrupt.ahci_handle_port_intr.ahci_single_level_irq_intr.__handle_irq_event_percpu
1.88 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.ata_scsi_qc_complete.__ata_qc_complete.ata_qc_complete.ata_qc_complete_multiple.ahci_handle_port_interrupt
0.00 ± -1% +Inf% 2.27 ± 14% perf-profile.calltrace.cycles-pp.blk_done_softirq.__softirqentry_text_start.irq_exit.do_IRQ.ret_from_intr
1.87 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.blk_mq_complete_request.scsi_mq_done.ata_scsi_qc_complete.__ata_qc_complete.ata_qc_complete
1.97 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.handle_edge_irq.handle_irq.do_IRQ.ret_from_intr.cpuidle_enter
1.97 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.handle_irq.do_IRQ.ret_from_intr.cpuidle_enter.call_cpuidle
1.97 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.handle_irq_event.handle_edge_irq.handle_irq.do_IRQ.ret_from_intr
1.97 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.handle_irq_event_percpu.handle_irq_event.handle_edge_irq.handle_irq.do_IRQ
0.00 ± -1% +Inf% 2.29 ± 14% perf-profile.calltrace.cycles-pp.irq_exit.do_IRQ.ret_from_intr.cpuidle_enter.call_cpuidle
1.85 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.scsi_end_request.scsi_io_completion.scsi_finish_command.scsi_softirq_done.__blk_mq_complete_request
0.00 ± -1% +Inf% 2.26 ± 14% perf-profile.calltrace.cycles-pp.scsi_end_request.scsi_io_completion.scsi_finish_command.scsi_softirq_done.blk_done_softirq
1.86 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.scsi_finish_command.scsi_softirq_done.__blk_mq_complete_request.blk_mq_complete_request.scsi_mq_done
0.00 ± -1% +Inf% 2.27 ± 14% perf-profile.calltrace.cycles-pp.scsi_finish_command.scsi_softirq_done.blk_done_softirq.__softirqentry_text_start.irq_exit
1.85 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.scsi_io_completion.scsi_finish_command.scsi_softirq_done.__blk_mq_complete_request.blk_mq_complete_request
0.00 ± -1% +Inf% 2.26 ± 14% perf-profile.calltrace.cycles-pp.scsi_io_completion.scsi_finish_command.scsi_softirq_done.blk_done_softirq.__softirqentry_text_start
1.87 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.scsi_mq_done.ata_scsi_qc_complete.__ata_qc_complete.ata_qc_complete.ata_qc_complete_multiple
1.87 ± 1% -100.0% 0.00 ± -1% perf-profile.calltrace.cycles-pp.scsi_softirq_done.__blk_mq_complete_request.blk_mq_complete_request.scsi_mq_done.ata_scsi_qc_complete
0.00 ± -1% +Inf% 2.27 ± 14% perf-profile.calltrace.cycles-pp.scsi_softirq_done.blk_done_softirq.__softirqentry_text_start.irq_exit.do_IRQ
2.13 ± 1% -99.3% 0.01 ±200% perf-profile.children.cycles-pp.__ata_qc_complete
2.10 ± 1% -100.0% 0.00 ± -1% perf-profile.children.cycles-pp.__blk_mq_complete_request
2.20 ± 1% -95.3% 0.10 ± 63% perf-profile.children.cycles-pp.__handle_irq_event_percpu
0.33 ± 50% +798.9% 2.94 ± 14% perf-profile.children.cycles-pp.__softirqentry_text_start
2.15 ± 1% -97.3% 0.06 ± 58% perf-profile.children.cycles-pp.ahci_handle_port_interrupt
2.16 ± 1% -96.3% 0.08 ± 61% perf-profile.children.cycles-pp.ahci_handle_port_intr
2.19 ± 1% -95.2% 0.10 ± 63% perf-profile.children.cycles-pp.ahci_single_level_irq_intr
2.13 ± 2% -99.3% 0.01 ±200% perf-profile.children.cycles-pp.ata_qc_complete
2.13 ± 2% -99.3% 0.01 ±200% perf-profile.children.cycles-pp.ata_qc_complete_multiple
2.12 ± 2% -99.5% 0.01 ±200% perf-profile.children.cycles-pp.ata_scsi_qc_complete
0.00 ± -1% +Inf% 2.62 ± 15% perf-profile.children.cycles-pp.blk_done_softirq
2.10 ± 1% -100.0% 0.00 ± -1% perf-profile.children.cycles-pp.blk_mq_complete_request
2.22 ± 1% -94.6% 0.12 ± 65% perf-profile.children.cycles-pp.handle_edge_irq
2.22 ± 1% -94.4% 0.12 ± 62% perf-profile.children.cycles-pp.handle_irq
2.21 ± 1% -94.9% 0.11 ± 61% perf-profile.children.cycles-pp.handle_irq_event
2.21 ± 1% -95.0% 0.11 ± 63% perf-profile.children.cycles-pp.handle_irq_event_percpu
1.38 ± 19% +177.4% 3.83 ± 12% perf-profile.children.cycles-pp.irq_exit
2.10 ± 1% -100.0% 0.00 ± -1% perf-profile.children.cycles-pp.scsi_mq_done
perf-stat.iTLB-load-miss-rate_
90 O+---------------------------------------------------------------------+
| O O O O |
80 ++ |
70 ++ O |
| O O O
60 ++ |
50 ++ *........*........ |
*. . * |
40 ++.. . |
30 ++ . . |
| . .. |
20 ++ .. . |
10 ++ . . |
| . |
0 ++-------*-------------------------------------------------------------+
perf-stat.instructions-per-iTLB-miss
16000 ++------------------------*-----------------------------------------+
* *.... * |
14000 ++ : |
12000 ++ : |
| : : |
10000 ++ : : |
| : : |
8000 ++ : : |
| : : |
6000 ++ : : O O O O
4000 ++ : : |
| : : |
2000 ++ :O: O O O |
O : : |
0 ++-------*----------------------------------------------------------+
latency_stats.avg.max
800000 ++-----------------------------------------------------------------+
| O O
700000 O+ O |
| |
600000 ++ |
500000 ++ |
| O O O |
400000 ++ O |
| |
300000 ++ |
200000 ++ |
| O |
100000 ++ |
*....... ....*.......*........* |
0 ++------*----------------------------------------------------------+
latency_stats.max.max
800000 ++-----------------------------------------------------------------+
O O O O
700000 ++ |
| |
600000 ++ |
500000 ++ O O |
| O O |
400000 ++ |
| |
300000 ++ |
200000 ++ O |
| |
100000 ++ |
*....... ....*.......*........* |
0 ++------*----------------------------------------------------------+
iostat.sda.wrqm_s
5 ++---------------------------------O-----------------O-------O--------+
4.5 ++ O O O O O
O |
4 ++ |
3.5 ++ |
| *.......*........* |
3 *+ + |
2.5 ++. + |
2 ++ . + |
| .. + |
1.5 ++ . + |
1 ++ . + |
| . + |
0.5 ++ + |
0 ++-------*------------------------------------------------------------+
iostat.sda.avgqu-sz
160 ++--------------------------------------------------------------------+
| O |
140 O+ O O O O O O O
120 ++ |
| |
100 ++ |
| |
80 ++ |
| |
60 ++ |
40 ++ |
*.... .*.......*........* |
20 ++ ... ... |
| . ... |
0 ++-------*------------------------------------------------------------+
iostat.sda.await
350 ++--------------------------------------------------------------------+
O O O O O O O O O
300 ++ |
| |
250 ++ |
| |
200 ++ |
| |
150 ++ |
| |
100 ++ |
*.... .*.......*........* |
50 ++ ... ... |
| . ... |
0 ++-------*------------------------------------------------------------+
iostat.sda.w_await
350 ++--------------------------------------------------------------------+
O O O O O O O O O
300 ++ |
| |
250 ++ |
| |
200 ++ |
| |
150 ++ |
| |
100 ++ |
*.... .*.......*........* |
50 ++ ... ... |
| . ... |
0 ++-------*------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
Thanks,
Xiaolong
4 years, 6 months
[lkp] [e1000] b97f7a155a: WARNING: CPU: 0 PID: 0 at net/sched/sch_generic.c:316 dev_watchdog+0x17d/0x1d8
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/jkirsher/next-queue.git dev-queue
commit b97f7a155a5e3f32690caf233064e664ef951910 ("e1000: track BQL bytes regardless of skb or not")
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -smp 2 -m 1G
caused below changes:
+--------------------------------------------------+------------+------------+
| | 5ce828df95 | b97f7a155a |
+--------------------------------------------------+------------+------------+
| boot_successes | 3 | 0 |
| boot_failures | 4 | 8 |
| BUG:kernel_reboot-without-warning_in_test_stage | 4 | |
| WARNING:at_net/sched/sch_generic.c:#dev_watchdog | 0 | 8 |
+--------------------------------------------------+------------+------------+
[ 14.564635] <address>Apache/2.4.12 (Debian) Server at inn Port 80</address>
[ 14.566504] </body></html>
[ 21.431808] ------------[ cut here ]------------
[ 21.432999] WARNING: CPU: 0 PID: 0 at net/sched/sch_generic.c:316 dev_watchdog+0x17d/0x1d8
[ 21.435160] NETDEV WATCHDOG: eth0 (e1000): transmit queue 0 timed out
[ 21.436589] Modules linked in: sr_mod cdrom sg ata_generic
[ 21.439232] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.8.0-rc7-01711-gb97f7a1 #1
[ 21.442413] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 21.446876] 0000000000000000 ffff880030a03d70 ffffffff8137f48f ffff880030a03dc0
[ 21.450508] ffffffff81cd4381 ffff880030a03db0 ffffffff81046753 0000013c30a03e28
[ 21.454129] ffff880024ce2000 ffff88003f8ea000 0000000000000001 0000000000000000
[ 21.471925] Call Trace:
[ 21.473367] <IRQ> [<ffffffff8137f48f>] dump_stack+0x61/0x7e
[ 21.475602] [<ffffffff81046753>] __warn+0xb8/0xd3
[ 21.477519] [<ffffffff810467b4>] warn_slowpath_fmt+0x46/0x4e
[ 21.479617] [<ffffffff8185d0e7>] dev_watchdog+0x17d/0x1d8
[ 21.481699] [<ffffffff8185cf6a>] ? qdisc_rcu_free+0x39/0x39
[ 21.495672] [<ffffffff81091b02>] call_timer_fn+0x17/0x6b
[ 21.497814] [<ffffffff81091bdb>] expire_timers+0x85/0x96
[ 21.499878] [<ffffffff81091c55>] run_timer_softirq+0x69/0x10c
[ 21.501945] [<ffffffff81096419>] ? ktime_get+0x4c/0xa1
[ 21.503929] [<ffffffff8102dc0a>] ? lapic_next_deadline+0x21/0x27
[ 21.505975] [<ffffffff8109c30f>] ? clockevents_program_event+0xd2/0xea
[ 21.511522] [<ffffffff8104a60c>] __do_softirq+0xc9/0x1c8
[ 21.513505] [<ffffffff8104a8b4>] irq_exit+0x3d/0x82
[ 21.515419] [<ffffffff8102e5eb>] smp_trace_apic_timer_interrupt+0x7b/0x88
[ 21.517699] [<ffffffff8102e601>] smp_apic_timer_interrupt+0x9/0xb
[ 21.520024] [<ffffffff8193f602>] apic_timer_interrupt+0x82/0x90
[ 21.522159] <EOI> [<ffffffff81036639>] ? native_safe_halt+0x6/0x8
[ 21.524606] [<ffffffff8101b46b>] default_idle+0x9/0xd
[ 21.526562] [<ffffffff8101b934>] arch_cpu_idle+0xa/0xc
[ 21.528954] [<ffffffff81075f97>] default_idle_call+0x2e/0x30
[ 21.531027] [<ffffffff810760c5>] cpu_startup_entry+0x12c/0x1f4
[ 21.533136] [<ffffffff81933d09>] rest_init+0x80/0x82
[ 21.535043] [<ffffffff81ed4e6e>] start_kernel+0x3d4/0x3e1
[ 21.537262] [<ffffffff81ed4120>] ? early_idt_handler_array+0x120/0x120
[ 21.539673] [<ffffffff81ed4299>] x86_64_start_reservations+0x2a/0x2c
[ 21.541877] [<ffffffff81ed4386>] x86_64_start_kernel+0xeb/0xf8
[ 21.544001] ---[ end trace 30358ab6fd3dd6c8 ]---
[ 21.555454] e1000 0000:00:03.0 eth0: Reset adapter
Thanks,
Xiaolong
4 years, 6 months