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
Test monitoring on custom github repo
by Thomas Garnier
Hi,
I am working on KASLR (PIE for x86_64). I previously used Kees (CCed)
branches for lkp bot testing but someone told be I could ask you to add a
custom github path to monitor all branches on it.
I pushed my changes to: https://github.com/thgarnie/linux (kasrl_pie_v2
right now)
Can you add it? Anything I need to do?
Thanks,
--
Thomas
2 years, 2 months
[lkp-robot] [mm/cma] 2b0f904a5a: fio.read_bw_MBps -16.1% regression
by kernel test robot
Greeting,
FYI, we noticed a -16.1% regression of fio.read_bw_MBps due to commit:
commit: 2b0f904a5a8781498417d67226fd12c5e56053ae ("mm/cma: manage the memory of the CMA area by using the ZONE_MOVABLE")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: fio-basic
on test machine: 56 threads Intel(R) Xeon(R) CPU E5-2695 v3 @ 2.30GHz with 256G memory
with following parameters:
disk: 2pmem
fs: ext4
runtime: 200s
nr_task: 50%
time_based: tb
rw: randread
bs: 2M
ioengine: mmap
test_size: 200G
cpufreq_governor: performance
test-description: 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.
test-url: https://github.com/axboe/fio
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/intel/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/time_based:
2M/gcc-7/performance/2pmem/ext4/mmap/x86_64-rhel-7.2/50%/debian-x86_64-2016-08-31.cgz/200s/randread/lkp-hsw-ep6/200G/fio-basic/tb
commit:
f6572f9cd2 ("mm/page_alloc: don't reserve ZONE_HIGHMEM for ZONE_MOVABLE request")
2b0f904a5a ("mm/cma: manage the memory of the CMA area by using the ZONE_MOVABLE")
f6572f9cd248df2c 2b0f904a5a8781498417d67226
---------------- --------------------------
%stddev %change %stddev
\ | \
11451 -16.1% 9605 fio.read_bw_MBps
0.29 ± 5% +0.1 0.40 ± 3% fio.latency_1000us%
19.35 ± 5% -4.7 14.69 ± 3% fio.latency_10ms%
7.92 ± 3% +12.2 20.15 fio.latency_20ms%
0.05 ± 11% +0.0 0.09 ± 8% fio.latency_2ms%
70.22 -8.9 61.36 fio.latency_4ms%
0.29 ± 13% +0.0 0.33 ± 3% fio.latency_500us%
0.45 ± 29% +1.0 1.45 ± 4% fio.latency_50ms%
1.37 +0.1 1.44 fio.latency_750us%
9792 +31.7% 12896 fio.read_clat_90%_us
10560 +33.0% 14048 fio.read_clat_95%_us
15376 ± 10% +46.9% 22592 fio.read_clat_99%_us
4885 +19.2% 5825 fio.read_clat_mean_us
5725 -16.1% 4802 fio.read_iops
4.598e+09 -16.4% 3.845e+09 fio.time.file_system_inputs
453153 -8.4% 415215 fio.time.involuntary_context_switches
5.748e+08 -16.4% 4.806e+08 fio.time.major_page_faults
1822257 +23.7% 2254706 fio.time.maximum_resident_set_size
5089 +1.6% 5172 fio.time.system_time
514.50 -16.3% 430.48 fio.time.user_time
24569 ± 2% +9.6% 26917 ± 2% fio.time.voluntary_context_switches
54443725 -14.9% 46353339 interrupts.CAL:Function_call_interrupts
0.00 ± 79% -0.0 0.00 ± 17% mpstat.cpu.iowait%
4.45 -0.7 3.71 mpstat.cpu.usr%
1467516 +21.3% 1779543 ± 3% meminfo.Active
1276031 +23.7% 1578443 ± 4% meminfo.Active(file)
25789 ± 3% -76.7% 6013 ± 4% meminfo.CmaFree
1.296e+08 -12.6% 1.133e+08 turbostat.IRQ
41.89 -3.4% 40.47 turbostat.RAMWatt
17444 ± 2% -13.5% 15092 ± 3% turbostat.SMI
10896428 -16.4% 9111830 vmstat.io.bi
6010 -6.2% 5637 vmstat.system.cs
317438 -12.1% 278980 vmstat.system.in
1072892 ± 3% +21.5% 1303487 numa-meminfo.node0.Active
978318 +21.6% 1189809 ± 2% numa-meminfo.node0.Active(file)
222968 -25.2% 166818 numa-meminfo.node0.PageTables
47374 ± 2% +10.6% 52402 ± 7% numa-meminfo.node0.SUnreclaim
165213 +31.9% 217870 numa-meminfo.node1.PageTables
222405 +10.4% 245633 ± 2% numa-meminfo.node1.SReclaimable
102992 ± 46% -80.8% 19812 ± 38% numa-meminfo.node1.Shmem
2.475e+08 ± 2% -24.0% 1.881e+08 numa-numastat.node0.local_node
39371795 ± 14% +167.1% 1.052e+08 ± 2% numa-numastat.node0.numa_foreign
2.475e+08 ± 2% -24.0% 1.881e+08 numa-numastat.node0.numa_hit
31890417 ± 17% +40.2% 44705135 ± 8% numa-numastat.node0.numa_miss
31899482 ± 17% +40.2% 44713255 ± 8% numa-numastat.node0.other_node
2.566e+08 ± 2% -44.2% 1.433e+08 numa-numastat.node1.local_node
31890417 ± 17% +40.2% 44705135 ± 8% numa-numastat.node1.numa_foreign
2.566e+08 ± 2% -44.2% 1.433e+08 numa-numastat.node1.numa_hit
39371795 ± 14% +167.1% 1.052e+08 ± 2% numa-numastat.node1.numa_miss
39373660 ± 14% +167.1% 1.052e+08 ± 2% numa-numastat.node1.other_node
6047 ± 39% -66.5% 2028 ± 63% sched_debug.cfs_rq:/.exec_clock.min
461.37 ± 8% +64.9% 760.74 ± 20% sched_debug.cfs_rq:/.load_avg.avg
1105 ± 13% +1389.3% 16467 ± 56% sched_debug.cfs_rq:/.load_avg.max
408.99 ± 3% +495.0% 2433 ± 49% sched_debug.cfs_rq:/.load_avg.stddev
28746 ± 12% -18.7% 23366 ± 14% sched_debug.cfs_rq:/.min_vruntime.min
752426 ± 3% -12.7% 656636 ± 4% sched_debug.cpu.avg_idle.avg
144956 ± 61% -85.4% 21174 ± 26% sched_debug.cpu.avg_idle.min
245684 ± 11% +44.6% 355257 ± 2% sched_debug.cpu.avg_idle.stddev
236035 ± 15% +51.8% 358264 ± 16% sched_debug.cpu.nr_switches.max
42039 ± 22% +34.7% 56616 ± 8% sched_debug.cpu.nr_switches.stddev
3204 ± 24% -48.1% 1663 ± 30% sched_debug.cpu.sched_count.min
2132 ± 25% +38.7% 2957 ± 11% sched_debug.cpu.sched_count.stddev
90.67 ± 32% -71.8% 25.58 ± 26% sched_debug.cpu.sched_goidle.min
6467 ± 15% +22.3% 7912 ± 15% sched_debug.cpu.ttwu_count.max
1513 ± 27% -55.7% 670.92 ± 22% sched_debug.cpu.ttwu_count.min
1025 ± 20% +68.4% 1727 ± 9% sched_debug.cpu.ttwu_count.stddev
1057 ± 16% -62.9% 391.85 ± 31% sched_debug.cpu.ttwu_local.min
244876 +21.6% 297770 ± 2% numa-vmstat.node0.nr_active_file
88.00 ± 5% +19.3% 105.00 ± 5% numa-vmstat.node0.nr_isolated_file
55778 -25.1% 41765 numa-vmstat.node0.nr_page_table_pages
11843 ± 2% +10.6% 13100 ± 7% numa-vmstat.node0.nr_slab_unreclaimable
159.25 ± 42% -74.9% 40.00 ± 52% numa-vmstat.node0.nr_vmscan_immediate_reclaim
244862 +21.6% 297739 ± 2% numa-vmstat.node0.nr_zone_active_file
19364320 ± 19% +187.2% 55617595 ± 2% numa-vmstat.node0.numa_foreign
268155 ± 3% +49.6% 401089 ± 4% numa-vmstat.node0.workingset_activate
1.229e+08 -19.0% 99590617 numa-vmstat.node0.workingset_refault
6345 ± 3% -76.5% 1489 ± 3% numa-vmstat.node1.nr_free_cma
41335 +32.0% 54552 numa-vmstat.node1.nr_page_table_pages
25770 ± 46% -80.8% 4956 ± 38% numa-vmstat.node1.nr_shmem
55684 +10.4% 61475 ± 2% numa-vmstat.node1.nr_slab_reclaimable
1.618e+08 ± 8% -47.6% 84846798 ± 17% numa-vmstat.node1.numa_hit
1.617e+08 ± 8% -47.6% 84676284 ± 17% numa-vmstat.node1.numa_local
19365342 ± 19% +187.2% 55620100 ± 2% numa-vmstat.node1.numa_miss
19534837 ± 19% +185.6% 55790654 ± 2% numa-vmstat.node1.numa_other
1.296e+08 -21.0% 1.024e+08 numa-vmstat.node1.workingset_refault
1.832e+12 -7.5% 1.694e+12 perf-stat.branch-instructions
0.25 -0.0 0.23 perf-stat.branch-miss-rate%
4.666e+09 -16.0% 3.918e+09 perf-stat.branch-misses
39.88 +1.1 40.98 perf-stat.cache-miss-rate%
2.812e+10 -11.6% 2.485e+10 perf-stat.cache-misses
7.051e+10 -14.0% 6.064e+10 perf-stat.cache-references
1260521 -6.1% 1183071 perf-stat.context-switches
1.87 +9.6% 2.05 perf-stat.cpi
6707 ± 2% -5.2% 6359 perf-stat.cpu-migrations
1.04 ± 11% -0.3 0.77 ± 4% perf-stat.dTLB-load-miss-rate%
2.365e+10 ± 7% -25.9% 1.751e+10 ± 9% perf-stat.dTLB-load-misses
1.05e+12 ± 4% -9.5% 9.497e+11 ± 2% perf-stat.dTLB-stores
28.16 +2.2 30.35 ± 2% perf-stat.iTLB-load-miss-rate%
2.56e+08 -10.4% 2.295e+08 perf-stat.iTLB-loads
8.974e+12 -9.2% 8.151e+12 perf-stat.instructions
89411 -8.8% 81529 perf-stat.instructions-per-iTLB-miss
0.54 -8.8% 0.49 perf-stat.ipc
5.748e+08 -16.4% 4.806e+08 perf-stat.major-faults
52.82 +5.8 58.61 ± 2% perf-stat.node-load-miss-rate%
7.206e+09 ± 2% -18.6% 5.867e+09 ± 3% perf-stat.node-loads
17.96 ± 8% +15.7 33.69 ± 2% perf-stat.node-store-miss-rate%
2.055e+09 ± 8% +65.1% 3.393e+09 ± 4% perf-stat.node-store-misses
9.391e+09 ± 2% -28.9% 6.675e+09 perf-stat.node-stores
5.753e+08 -16.4% 4.811e+08 perf-stat.page-faults
305865 -16.3% 256108 proc-vmstat.allocstall_movable
1923 ± 14% -72.1% 537.00 ± 12% proc-vmstat.allocstall_normal
0.00 +Inf% 1577 ± 67% proc-vmstat.compact_isolated
1005 ± 4% -65.8% 344.00 ± 7% proc-vmstat.kswapd_low_wmark_hit_quickly
320062 +23.2% 394374 ± 4% proc-vmstat.nr_active_file
6411 ± 2% -76.4% 1511 ± 4% proc-vmstat.nr_free_cma
277.00 ± 12% -51.4% 134.75 ± 52% proc-vmstat.nr_vmscan_immediate_reclaim
320049 +23.2% 394353 ± 4% proc-vmstat.nr_zone_active_file
71262212 ± 15% +110.3% 1.499e+08 ± 3% proc-vmstat.numa_foreign
5.042e+08 ± 2% -34.3% 3.314e+08 proc-vmstat.numa_hit
5.041e+08 ± 2% -34.3% 3.314e+08 proc-vmstat.numa_local
71262212 ± 15% +110.3% 1.499e+08 ± 3% proc-vmstat.numa_miss
71273176 ± 15% +110.3% 1.499e+08 ± 3% proc-vmstat.numa_other
1007 ± 4% -65.6% 346.25 ± 7% proc-vmstat.pageoutrun
23070268 -16.0% 19386190 proc-vmstat.pgalloc_dma32
5.525e+08 -16.7% 4.603e+08 proc-vmstat.pgalloc_normal
5.753e+08 -16.4% 4.812e+08 proc-vmstat.pgfault
5.751e+08 -16.3% 4.813e+08 proc-vmstat.pgfree
5.748e+08 -16.4% 4.806e+08 proc-vmstat.pgmajfault
2.299e+09 -16.4% 1.923e+09 proc-vmstat.pgpgin
8.396e+08 -17.8% 6.901e+08 proc-vmstat.pgscan_direct
3.018e+08 ± 2% -13.0% 2.627e+08 proc-vmstat.pgscan_kswapd
4.1e+08 -15.1% 3.48e+08 proc-vmstat.pgsteal_direct
1.542e+08 ± 3% -20.9% 1.22e+08 ± 3% proc-vmstat.pgsteal_kswapd
23514 ± 4% -23.1% 18076 ± 16% proc-vmstat.slabs_scanned
343040 ± 2% +40.3% 481253 ± 2% proc-vmstat.workingset_activate
2.525e+08 -20.1% 2.018e+08 proc-vmstat.workingset_refault
13.64 ± 3% -1.7 11.96 ± 2% perf-profile.calltrace.cycles-pp.ext4_mpage_readpages.filemap_fault.ext4_filemap_fault.__do_fault.__handle_mm_fault
11.67 ± 3% -1.4 10.29 ± 2% perf-profile.calltrace.cycles-pp.submit_bio.ext4_mpage_readpages.filemap_fault.ext4_filemap_fault.__do_fault
11.64 ± 3% -1.4 10.25 ± 2% perf-profile.calltrace.cycles-pp.generic_make_request.submit_bio.ext4_mpage_readpages.filemap_fault.ext4_filemap_fault
11.10 ± 3% -1.3 9.82 ± 2% perf-profile.calltrace.cycles-pp.pmem_make_request.generic_make_request.submit_bio.ext4_mpage_readpages.filemap_fault
9.21 ± 3% -1.2 8.04 ± 3% perf-profile.calltrace.cycles-pp.pmem_do_bvec.pmem_make_request.generic_make_request.submit_bio.ext4_mpage_readpages
27.33 ± 4% -1.0 26.35 ± 5% perf-profile.calltrace.cycles-pp.cpu_startup_entry.start_secondary.secondary_startup_64
27.33 ± 4% -1.0 26.35 ± 5% perf-profile.calltrace.cycles-pp.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64
27.33 ± 4% -1.0 26.35 ± 5% perf-profile.calltrace.cycles-pp.cpuidle_enter_state.do_idle.cpu_startup_entry.start_secondary.secondary_startup_64
27.33 ± 4% -1.0 26.35 ± 5% perf-profile.calltrace.cycles-pp.start_secondary.secondary_startup_64
26.79 ± 4% -0.8 25.98 ± 5% perf-profile.calltrace.cycles-pp.intel_idle.cpuidle_enter_state.do_idle.cpu_startup_entry.start_secondary
27.98 ± 3% -0.8 27.22 ± 4% perf-profile.calltrace.cycles-pp.secondary_startup_64
5.36 ± 12% -0.6 4.76 ± 7% perf-profile.calltrace.cycles-pp.kswapd.kthread.ret_from_fork
5.36 ± 12% -0.6 4.76 ± 7% perf-profile.calltrace.cycles-pp.shrink_node.kswapd.kthread.ret_from_fork
5.30 ± 12% -0.6 4.71 ± 7% perf-profile.calltrace.cycles-pp.shrink_inactive_list.shrink_node_memcg.shrink_node.kswapd.kthread
5.35 ± 12% -0.6 4.76 ± 7% perf-profile.calltrace.cycles-pp.shrink_node_memcg.shrink_node.kswapd.kthread.ret_from_fork
5.43 ± 12% -0.5 4.88 ± 7% perf-profile.calltrace.cycles-pp.ret_from_fork
5.43 ± 12% -0.5 4.88 ± 7% perf-profile.calltrace.cycles-pp.kthread.ret_from_fork
11.04 ± 2% -0.2 10.82 ± 2% perf-profile.calltrace.cycles-pp.shrink_page_list.shrink_inactive_list.shrink_node_memcg.shrink_node.do_try_to_free_pages
62.44 ± 2% +1.9 64.38 perf-profile.calltrace.cycles-pp.page_fault
62.38 ± 2% +2.0 64.33 perf-profile.calltrace.cycles-pp.__do_page_fault.do_page_fault.page_fault
62.38 ± 2% +2.0 64.34 perf-profile.calltrace.cycles-pp.do_page_fault.page_fault
61.52 ± 2% +2.1 63.58 perf-profile.calltrace.cycles-pp.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
61.34 ± 2% +2.1 63.44 perf-profile.calltrace.cycles-pp.__handle_mm_fault.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
30.18 ± 3% +2.3 32.45 ± 2% perf-profile.calltrace.cycles-pp.shrink_inactive_list.shrink_node_memcg.shrink_node.do_try_to_free_pages.try_to_free_pages
7.98 ± 3% +2.3 10.33 ± 2% perf-profile.calltrace.cycles-pp.add_to_page_cache_lru.filemap_fault.ext4_filemap_fault.__do_fault.__handle_mm_fault
30.48 ± 3% +2.4 32.83 ± 2% perf-profile.calltrace.cycles-pp.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.filemap_fault.ext4_filemap_fault
30.46 ± 3% +2.4 32.81 ± 2% perf-profile.calltrace.cycles-pp.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask.filemap_fault
30.46 ± 3% +2.4 32.81 ± 2% perf-profile.calltrace.cycles-pp.shrink_node.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath.__alloc_pages_nodemask
30.37 ± 3% +2.4 32.75 ± 2% perf-profile.calltrace.cycles-pp.shrink_node_memcg.shrink_node.do_try_to_free_pages.try_to_free_pages.__alloc_pages_slowpath
5.58 ± 4% +2.5 8.08 ± 2% perf-profile.calltrace.cycles-pp.__lru_cache_add.add_to_page_cache_lru.filemap_fault.ext4_filemap_fault.__do_fault
32.88 ± 3% +2.5 35.38 ± 2% perf-profile.calltrace.cycles-pp.__alloc_pages_nodemask.filemap_fault.ext4_filemap_fault.__do_fault.__handle_mm_fault
5.51 ± 4% +2.5 8.02 ± 2% perf-profile.calltrace.cycles-pp.pagevec_lru_move_fn.__lru_cache_add.add_to_page_cache_lru.filemap_fault.ext4_filemap_fault
4.24 ± 4% +2.5 6.76 ± 2% perf-profile.calltrace.cycles-pp._raw_spin_lock_irqsave.pagevec_lru_move_fn.__lru_cache_add.add_to_page_cache_lru.filemap_fault
4.18 ± 4% +2.5 6.70 ± 2% perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock_irqsave.pagevec_lru_move_fn.__lru_cache_add.add_to_page_cache_lru
18.64 ± 3% +2.5 21.16 ± 2% perf-profile.calltrace.cycles-pp.native_queued_spin_lock_slowpath._raw_spin_lock_irq.shrink_inactive_list.shrink_node_memcg.shrink_node
31.65 ± 3% +2.7 34.31 ± 2% perf-profile.calltrace.cycles-pp.__alloc_pages_slowpath.__alloc_pages_nodemask.filemap_fault.ext4_filemap_fault.__do_fault
17.21 ± 3% +2.7 19.93 ± 2% perf-profile.calltrace.cycles-pp._raw_spin_lock_irq.shrink_inactive_list.shrink_node_memcg.shrink_node.do_try_to_free_pages
56.37 ± 2% +2.8 59.21 perf-profile.calltrace.cycles-pp.__do_fault.__handle_mm_fault.handle_mm_fault.__do_page_fault.do_page_fault
56.36 ± 2% +2.8 59.20 perf-profile.calltrace.cycles-pp.ext4_filemap_fault.__do_fault.__handle_mm_fault.handle_mm_fault.__do_page_fault
56.10 ± 2% +2.9 58.97 perf-profile.calltrace.cycles-pp.filemap_fault.ext4_filemap_fault.__do_fault.__handle_mm_fault.handle_mm_fault
13.66 ± 3% -1.7 11.98 ± 2% perf-profile.children.cycles-pp.ext4_mpage_readpages
11.69 ± 3% -1.4 10.30 ± 2% perf-profile.children.cycles-pp.submit_bio
11.64 ± 3% -1.4 10.26 ± 2% perf-profile.children.cycles-pp.generic_make_request
11.12 ± 3% -1.3 9.84 ± 2% perf-profile.children.cycles-pp.pmem_make_request
9.27 ± 3% -1.1 8.12 ± 3% perf-profile.children.cycles-pp.pmem_do_bvec
27.33 ± 4% -1.0 26.35 ± 5% perf-profile.children.cycles-pp.start_secondary
27.98 ± 3% -0.8 27.22 ± 4% perf-profile.children.cycles-pp.secondary_startup_64
27.98 ± 3% -0.8 27.22 ± 4% perf-profile.children.cycles-pp.cpu_startup_entry
27.98 ± 3% -0.8 27.22 ± 4% perf-profile.children.cycles-pp.do_idle
27.97 ± 3% -0.8 27.22 ± 4% perf-profile.children.cycles-pp.cpuidle_enter_state
5.36 ± 12% -0.6 4.76 ± 7% perf-profile.children.cycles-pp.kswapd
27.42 ± 4% -0.6 26.84 ± 5% perf-profile.children.cycles-pp.intel_idle
5.43 ± 12% -0.5 4.88 ± 7% perf-profile.children.cycles-pp.kthread
5.43 ± 12% -0.5 4.88 ± 7% perf-profile.children.cycles-pp.ret_from_fork
14.25 -0.4 13.80 ± 2% perf-profile.children.cycles-pp.shrink_page_list
35.60 +1.7 37.31 ± 2% perf-profile.children.cycles-pp.shrink_inactive_list
35.89 +1.8 37.67 ± 2% perf-profile.children.cycles-pp.shrink_node
35.80 +1.8 37.60 ± 2% perf-profile.children.cycles-pp.shrink_node_memcg
62.46 ± 2% +2.0 64.41 perf-profile.children.cycles-pp.page_fault
62.43 ± 2% +2.0 64.39 perf-profile.children.cycles-pp.__do_page_fault
62.41 ± 2% +2.0 64.39 perf-profile.children.cycles-pp.do_page_fault
61.55 ± 2% +2.1 63.63 perf-profile.children.cycles-pp.handle_mm_fault
61.37 ± 2% +2.1 63.49 perf-profile.children.cycles-pp.__handle_mm_fault
8.00 ± 3% +2.3 10.35 ± 2% perf-profile.children.cycles-pp.add_to_page_cache_lru
30.55 ± 3% +2.4 32.92 ± 2% perf-profile.children.cycles-pp.try_to_free_pages
30.53 ± 3% +2.4 32.91 ± 2% perf-profile.children.cycles-pp.do_try_to_free_pages
5.59 ± 4% +2.5 8.09 ± 2% perf-profile.children.cycles-pp.__lru_cache_add
5.61 ± 4% +2.5 8.12 ± 2% perf-profile.children.cycles-pp.pagevec_lru_move_fn
32.97 ± 3% +2.5 35.50 ± 2% perf-profile.children.cycles-pp.__alloc_pages_nodemask
5.26 ± 4% +2.6 7.89 perf-profile.children.cycles-pp._raw_spin_lock_irqsave
19.05 ± 3% +2.7 21.72 ± 3% perf-profile.children.cycles-pp._raw_spin_lock_irq
31.72 ± 3% +2.7 34.41 ± 2% perf-profile.children.cycles-pp.__alloc_pages_slowpath
56.29 ± 2% +2.8 59.07 perf-profile.children.cycles-pp.filemap_fault
56.38 ± 2% +2.8 59.23 perf-profile.children.cycles-pp.__do_fault
56.37 ± 2% +2.8 59.21 perf-profile.children.cycles-pp.ext4_filemap_fault
24.54 +5.3 29.82 ± 2% perf-profile.children.cycles-pp.native_queued_spin_lock_slowpath
9.20 ± 3% -1.2 8.04 ± 3% perf-profile.self.cycles-pp.pmem_do_bvec
27.42 ± 4% -0.6 26.84 ± 5% perf-profile.self.cycles-pp.intel_idle
24.54 +5.3 29.82 ± 2% perf-profile.self.cycles-pp.native_queued_spin_lock_slowpath
fio.read_bw_MBps
11600 +-+-----------------------------------------------------------------+
11400 +-+. .+..+.+.+.+ .+.. .+. .+ + .+. .+.+.+. .+.+.+.+..+.+.+.+.|
| + + .+.+ + + + +. + |
11200 +-+ + |
11000 +-+ |
10800 +-+ |
10600 +-+ |
| |
10400 +-+ |
10200 +-+ |
10000 +-+ |
9800 +-+ |
O O O O O O O O O O |
9600 +-O O O O O O O O O |
9400 +-+-----------------------------------------------------------------+
fio.read_iops
5800 +-+------------------------------------------------------------------+
5700 +-+. .+..+.+.+.+ .+. .+. .+. + .+. .+.+.+.. .+.+.+.+.+..+.+.+.|
| + + .+..+ + + + + + |
5600 +-+ + |
5500 +-+ |
5400 +-+ |
5300 +-+ |
| |
5200 +-+ |
5100 +-+ |
5000 +-+ |
4900 +-+ |
O O O O O O O O O O |
4800 +-O O O O O O O O O |
4700 +-+------------------------------------------------------------------+
fio.read_clat_mean_us
6000 +-+------------------------------------------------------------------+
| |
5800 +-O O O O O O O O O O O O O O O |
O O O O |
| |
5600 +-+ |
| |
5400 +-+ |
| |
5200 +-+ |
| |
| +. |
5000 +-+ + +..+. .+. .+. .+. .+. |
| +.+.+..+.+.+.+ +.+.+ +..+ + +.+.+. +.+.+.+.+..+.+.+.|
4800 +-+------------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
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
2 years, 11 months
[test_parman_move] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1]
by Fengguang Wu
Hello,
FYI this happens in mainline kernel 4.15.0-rc1.
It at least dates back to v4.12 .
It occurs in 2 out of 2 boots.
[ 95.347718] test passed
[ 95.359028] test_printf: all 260 tests passed
[ 96.656859] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 3332
[ 96.770961] test_bitmap: all 460506 tests passed
[ 96.783469] test_uuid: all 18 tests passed
[ 124.230111] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper:1]
[ 124.230111] CPU: 0 PID: 1 Comm: swapper Not tainted 4.15.0-rc1 #360
[ 124.230111] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 124.230111] task: d3430000 task.stack: d342c000
[ 124.230111] EIP: test_parman_move+0xe/0xb0:
test_parman_move at lib/test_parman.c:119
[ 124.230111] EFLAGS: 00200212 CPU: 0
[ 124.230111] EAX: d0c00000 EBX: 00000004 ECX: 00000930 EDX: 0000090c
[ 124.230111] ESI: d0740324 EDI: d359f680 EBP: d342dec4 ESP: d342debc
[ 124.230111] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068
[ 124.230111] CR0: 80050033 CR2: 00000000 CR3: 02b2b000 CR4: 000006b0
[ 124.230111] Call Trace:
[ 124.230111] parman_lsort_item_add+0x9d/0x190:
__list_del_entry at include/linux/list.h:117
(inlined by) list_move_tail at include/linux/list.h:182
(inlined by) parman_prio_shift_down at lib/parman.c:144
(inlined by) parman_lsort_item_add at lib/parman.c:200
[ 124.230111] parman_item_add+0x9/0x10:
parman_item_add at lib/parman.c:356
[ 124.230111] test_parman_init+0x358/0x3b5:
test_parman_run at lib/test_parman.c:284
(inlined by) test_parman_lsort at lib/test_parman.c:369
(inlined by) test_parman_init at lib/test_parman.c:383
[ 124.230111] ? test_uuid_init+0x1a9/0x1a9:
test_parman_init at lib/test_parman.c:382
[ 124.230111] do_one_initcall+0x7c/0x10e:
do_one_initcall at init/main.c:826
[ 124.230111] ? kernel_init_freeable+0xc3/0x15b:
do_initcall_level at init/main.c:891
(inlined by) do_initcalls at init/main.c:900
(inlined by) do_basic_setup at init/main.c:918
(inlined by) kernel_init_freeable at init/main.c:1066
[ 124.230111] kernel_init_freeable+0xe3/0x15b:
do_initcall_level at init/main.c:892
(inlined by) do_initcalls at init/main.c:900
(inlined by) do_basic_setup at init/main.c:918
(inlined by) kernel_init_freeable at init/main.c:1066
[ 124.230111] ? rest_init+0xc0/0xc0:
kernel_init at init/main.c:990
[ 124.230111] kernel_init+0xb/0xf0:
kernel_init at init/main.c:995
[ 124.230111] ? schedule_tail_wrapper+0x9/0xc:
schedule_tail_wrapper at arch/x86/entry/entry_32.S:267
[ 124.230111] ret_from_fork+0x19/0x24:
ret_from_fork at arch/x86/entry/entry_32.S:299
[ 124.230111] Code: e8 48 5f c2 00 5d 85 c0 0f 94 c0 0f b6 c0 c3 90 90 90 90 90 90 90 90 90 90 90 90 90 90 55 89 e5 57 53 8b 5d 08 8b 40 04 c1 e3 02 <8d> 3c 90 89 fa 8d 04 88 89 d9 e8 a3 be c2 00 83 fb 08 89 da 73
[ 124.230111] Kernel panic - not syncing: softlockup: hung tasks
[ 124.230111] CPU: 0 PID: 1 Comm: swapper Tainted: G L 4.15.0-rc1 #360
[ 124.230111] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 124.230111] Call Trace:
[ 124.230111] dump_stack+0x16/0x19:
dump_stack at lib/dump_stack.c:64
[ 124.230111] panic+0x86/0x1bd:
panic at kernel/panic.c:194
[ 124.230111] watchdog_timer_fn+0x1e7/0x1f0:
watchdog_timer_fn at kernel/watchdog.c:443
[ 124.230111] ? watchdog+0x20/0x20:
watchdog_timer_fn at kernel/watchdog.c:340
[ 124.230111] hrtimer_run_queues+0xf3/0x1d0:
__run_hrtimer at kernel/time/hrtimer.c:1211
(inlined by) __hrtimer_run_queues at kernel/time/hrtimer.c:1275
(inlined by) hrtimer_run_queues at kernel/time/hrtimer.c:1414
[ 124.230111] run_local_timers+0x8/0x40:
run_local_timers at kernel/time/timer.c:1700
[ 124.230111] update_process_times+0x22/0x50:
update_process_times at kernel/time/timer.c:1630
[ 124.230111] tick_nohz_handler+0x66/0x100:
tick_nohz_handler at kernel/time/tick-sched.c:1068
[ 124.230111] smp_apic_timer_interrupt+0x40/0x80:
exiting_irq at arch/x86/include/asm/apic.h:540
(inlined by) smp_apic_timer_interrupt at arch/x86/kernel/apic/apic.c:1052
[ 124.230111] ? irq_exit+0x2f/0xb0:
tick_irq_exit at kernel/softirq.c:385
(inlined by) irq_exit at kernel/softirq.c:407
[ 124.230111] apic_timer_interrupt+0x35/0x3c:
apic_timer_interrupt at arch/x86/include/asm/entry_arch.h:36
[ 124.230111] EIP: test_parman_move+0xe/0xb0:
test_parman_move at lib/test_parman.c:119
[ 124.230111] EFLAGS: 00200212 CPU: 0
[ 124.230111] EAX: d0c00000 EBX: 00000004 ECX: 00000930 EDX: 0000090c
[ 124.230111] ESI: d0740324 EDI: d359f680 EBP: d342dec4 ESP: d342debc
[ 124.230111] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068
[ 124.230111] parman_lsort_item_add+0x9d/0x190:
__list_del_entry at include/linux/list.h:117
(inlined by) list_move_tail at include/linux/list.h:182
(inlined by) parman_prio_shift_down at lib/parman.c:144
(inlined by) parman_lsort_item_add at lib/parman.c:200
[ 124.230111] parman_item_add+0x9/0x10:
parman_item_add at lib/parman.c:356
[ 124.230111] test_parman_init+0x358/0x3b5:
test_parman_run at lib/test_parman.c:284
(inlined by) test_parman_lsort at lib/test_parman.c:369
(inlined by) test_parman_init at lib/test_parman.c:383
[ 124.230111] ? test_uuid_init+0x1a9/0x1a9:
test_parman_init at lib/test_parman.c:382
[ 124.230111] do_one_initcall+0x7c/0x10e:
do_one_initcall at init/main.c:826
[ 124.230111] ? kernel_init_freeable+0xc3/0x15b:
do_initcall_level at init/main.c:891
(inlined by) do_initcalls at init/main.c:900
(inlined by) do_basic_setup at init/main.c:918
(inlined by) kernel_init_freeable at init/main.c:1066
[ 124.230111] kernel_init_freeable+0xe3/0x15b:
do_initcall_level at init/main.c:892
(inlined by) do_initcalls at init/main.c:900
(inlined by) do_basic_setup at init/main.c:918
(inlined by) kernel_init_freeable at init/main.c:1066
[ 124.230111] ? rest_init+0xc0/0xc0:
kernel_init at init/main.c:990
[ 124.230111] kernel_init+0xb/0xf0:
kernel_init at init/main.c:995
[ 124.230111] ? schedule_tail_wrapper+0x9/0xc:
schedule_tail_wrapper at arch/x86/entry/entry_32.S:267
[ 124.230111] ret_from_fork+0x19/0x24:
ret_from_fork at arch/x86/entry/entry_32.S:299
[ 124.230111] Kernel Offset: disabled
Attached the full dmesg, kconfig and reproduce scripts.
Thanks,
Fengguang
2 years, 12 months
[lkp-robot] [perf util] f7a7a64385: stderr.builtin-trace.c:#:#:note:in_expansion_of_macro'DECLARE_RESORT_RB_INTLIST'
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: f7a7a64385657f44638b40f09dcae8f7fe02879a ("perf util: Use cached rbtree for rblists")
url: https://github.com/0day-ci/linux/commits/Davidlohr-Bueso/tools-perf-Updat...
in testcase: perf-sanity-tests
with following parameters:
on test machine: qemu-system-x86_64 -enable-kvm -cpu kvm64,+ssse3 -smp 2 -m 8G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
[ 56.941979] DECLARE_RESORT_RB(__name)(&__ilist->rblist.entries, \
[ 56.941982]
[ 56.944797] ^
[ 56.944799]
[ 56.955333] builtin-trace.c:2695:2: note: in expansion of macro 'DECLARE_RESORT_RB_INTLIST'
[ 56.955336]
[ 56.959650] DECLARE_RESORT_RB_INTLIST(syscall_stats, ttrace->syscall_stats);
[ 56.959652]
[ 56.962706] ^~~~~~~~~~~~~~~~~~~~~~~~~
[ 56.962708]
[ 56.973237] builtin-trace.c:2675:18: note: expected 'struct rb_root *' but argument is of type 'struct rb_root_cached *'
[ 56.973240]
[ 56.984371] DEFINE_RESORT_RB(syscall_stats, a->msecs > b->msecs,
[ 56.984374]
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
3 years, 2 months
[lkp-robot] [devinet_ioctl()] fc3e3953b8: stderr.[lkp-bdw-ep6:#]opal_ifinit:ioctl(SIOCGIFADDR)failed_with_errno=
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: fc3e3953b8c0fe209c239cb5e88b86fb66b22cc4 ("devinet_ioctl(): take copyin/copyout to caller")
https://git.kernel.org/cgit/linux/kernel/git/viro/vfs.git work.net-ioctl
in testcase: hpcc
with following parameters:
cpufreq_governor: performance
on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 128G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
[ 39.924528] [lkp-bdw-ep6:01944] opal_ifinit: ioctl(SIOCGIFADDR) failed with errno=99
[ 39.924531]
[ 39.938310] [lkp-bdw-ep6:01944] oob_tcp: problems getting address for index 0 (kernel index -1)
[ 39.938312]
[ 40.126075] [lkp-bdw-ep6:01948] opal_ifinit: ioctl(SIOCGIFADDR) failed with errno=99
[ 40.126079]
[ 40.140852] [lkp-bdw-ep6:01948] oob_tcp: problems getting address for index 0 (kernel index -1)
[ 40.140854]
[ 40.156277] [lkp-bdw-ep6:01949] opal_ifinit: ioctl(SIOCGIFADDR) failed with errno=99
[ 40.156279]
[ 40.170854] [lkp-bdw-ep6:01949] oob_tcp: problems getting address for index 0 (kernel index -1)
To reproduce:
git clone https://github.com/intel/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
3 years, 2 months
[lkp-robot] [x86/spectre] da28512156: BUG:kernel_reboot-without-warning_in_boot_stage
by kernel test robot
FYI, we noticed the following commit (built with gcc-6):
commit: da285121560e769cc31797bba6422eea71d473e0 ("x86/spectre: Add boot time option to select Spectre v2 mitigation")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -smp 2 -m 512M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------------------------+------------+------------+
| | 76b043848f | da28512156 |
+------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 92 | 92 |
| invoked_oom-killer:gfp_mask=0x | 90 | |
| Mem-Info | 90 | |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 90 | |
| BUG:unable_to_handle_kernel | 2 | |
| Oops:#[##] | 2 | |
| RIP:kasan_populate_shadow | 2 | |
| Kernel_panic-not_syncing:Fatal_exception | 2 | |
| BUG:kernel_reboot-without-warning_in_boot_stage | 0 | 91 |
| BUG:kernel_hang_in_boot_stage | 0 | 1 |
+------------------------------------------------------------------+------------+------------+
[ 0.000000] BRK [0x1cf36000, 0x1cf36fff] PGTABLE
[ 0.000000] RAMDISK: [mem 0x1e74e000-0x1ffdffff]
[ 0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x00000000000F6870 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x000000001FFE1628 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
BUG: kernel reboot-without-warning in boot stage
Elapsed time: 10
#!/bin/bash
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
3 years, 2 months
[lkp-robot] [mm/sparse.c] d09cfbbfa0: BUG:KASAN:use-after-free_in_a
by kernel test robot
FYI, we noticed the following commit (built with gcc-6):
commit: d09cfbbfa0f761a97687828b5afb27b56cbf2e19 ("mm/sparse.c: wrong allocation for mem_section")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -m 512M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------------+------------+------------+
| | cdc346b36e | d09cfbbfa0 |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 27 | 16 |
| page_allocation_failure:order:#,mode:#(__GFP_COMP),nodemask=(null) | 4 | |
| Mem-Info | 25 | 14 |
| page_allocation_failure:order:#,mode:#(),nodemask=(null) | 12 | 12 |
| PANIC:double_fault | 17 | 6 |
| RIP:__do_page_fault | 17 | 6 |
| Kernel_panic-not_syncing:Machine_halted | 17 | 6 |
| kernel_BUG_at_arch/x86/mm/physaddr.c | 2 | 2 |
| PANIC:early_exception | 2 | 2 |
| RIP:__phys_addr | 2 | 2 |
| Kernel_panic-not_syncing:kmem_cache_create:Failed_to_create_slab'pid'.Error | 4 | 4 |
| page_allocation_failure:order:#,mode:#(__GFP_RETRY_MAYFAIL),nodemask=(null) | 13 | 6 |
| WARNING:at_kernel/trace/trace.c:#early_trace_init | 13 | 6 |
| RIP:early_trace_init | 13 | 6 |
| kernel_BUG_at_arch/x86/kernel/apic/io_apic.c | 4 | 4 |
| invalid_opcode:#[##] | 4 | 4 |
| RIP:setup_IO_APIC | 4 | 4 |
| Kernel_panic-not_syncing:Fatal_exception | 4 | 4 |
| BUG:KASAN:use-after-free_in_a | 0 | 12 |
+-----------------------------------------------------------------------------+------------+------------+
[ 0.003333] BUG: KASAN: use-after-free in acpi_ut_update_ref_count+0x6c2/0xa96
[ 0.003333] Read of size 1 at addr ffff8800001af432 by task swapper/0/0
[ 0.003333]
[ 0.003333] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.15.0-rc6-00055-gd09cfbb #1
[ 0.003333] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 0.003333] Call Trace:
[ 0.003333] dump_stack+0x10f/0x17a
[ 0.003333] print_address_description+0x95/0x392
[ 0.003333] ? acpi_ut_update_ref_count+0x6c2/0xa96
[ 0.003333] kasan_report+0x325/0x378
[ 0.003333] __asan_report_load1_noabort+0x23/0x2b
[ 0.003333] acpi_ut_update_ref_count+0x6c2/0xa96
[ 0.003333] acpi_ut_update_object_reference+0x421/0x514
[ 0.003333] ? kasan_slab_free+0xbd/0xce
[ 0.003333] ? acpi_ut_update_ref_count+0xa96/0xa96
[ 0.003333] ? kmem_cache_free+0x63/0x234
[ 0.003333] acpi_ut_remove_reference+0x75/0x7e
[ 0.003333] acpi_ns_detach_object+0x272/0x27e
[ 0.003333] acpi_ns_delete_namespace_by_owner+0x10d/0x218
[ 0.003333] acpi_ns_load_table+0x156/0x1ce
[ 0.003333] acpi_tb_load_namespace+0x3a2/0x64a
[ 0.003333] ? acpi_ev_install_region_handlers+0xf5/0x109
[ 0.003333] acpi_load_tables+0x51/0x168
[ 0.003333] acpi_early_init+0x203/0x3f1
[ 0.003333] start_kernel+0x81b/0x987
[ 0.003333] ? mem_encrypt_init+0xd/0xd
[ 0.003333] ? memcpy_orig+0x16/0x110
[ 0.003333] x86_64_start_reservations+0x42/0x4a
[ 0.003333] x86_64_start_kernel+0xd0/0xd9
[ 0.003333] secondary_startup_64+0xa5/0xb0
[ 0.003333]
[ 0.003333] Allocated by task 0:
[ 0.003333] save_stack+0x37/0x117
[ 0.003333] set_track+0x23/0x2f
[ 0.003333] kasan_kmalloc+0xac/0xbe
[ 0.003333] kasan_slab_alloc+0x15/0x1d
[ 0.003333] slab_post_alloc_hook+0x35/0x77
[ 0.003333] kmem_cache_alloc+0x160/0x296
[ 0.003333] acpi_ns_create_node+0x86/0x10a
[ 0.003333] acpi_ns_search_and_enter+0x404/0x4a8
[ 0.003333] acpi_ns_lookup+0x62f/0x9ae
[ 0.003333] acpi_ds_load1_begin_op+0x653/0x8a1
[ 0.003333] acpi_ps_build_named_op+0x244/0x440
[ 0.003333] acpi_ps_create_op+0x5ba/0x9e3
[ 0.003333] acpi_ps_parse_loop+0x45f/0x13e0
[ 0.003333] acpi_ps_parse_aml+0x232/0x865
[ 0.003333] acpi_ns_one_complete_parse+0x2ec/0x33f
[ 0.003333] acpi_ns_parse_table+0x7e/0xb4
[ 0.003333] acpi_ns_load_table+0x5e/0x1ce
[ 0.003333] acpi_tb_load_namespace+0x3a2/0x64a
[ 0.003333] acpi_load_tables+0x51/0x168
[ 0.003333] acpi_early_init+0x203/0x3f1
[ 0.003333] start_kernel+0x81b/0x987
[ 0.003333] x86_64_start_reservations+0x42/0x4a
[ 0.003333] x86_64_start_kernel+0xd0/0xd9
[ 0.003333] secondary_startup_64+0xa5/0xb0
[ 0.003333]
[ 0.003333] Freed by task 0:
[ 0.003333] save_stack+0x37/0x117
[ 0.003333] set_track+0x23/0x2f
[ 0.003333] kasan_slab_free+0xa3/0xce
[ 0.003333] slab_free_freelist_hook+0xc2/0xf7
[ 0.003333] kmem_cache_free+0x63/0x234
[ 0.003333] acpi_os_release_object+0x11/0x1b
[ 0.003333] acpi_ns_delete_node+0x174/0x182
[ 0.003333] acpi_ns_remove_node+0x107/0x117
[ 0.003333] acpi_ns_delete_namespace_by_owner+0xc3/0x218
[ 0.003333] acpi_ns_load_table+0x156/0x1ce
[ 0.003333] acpi_tb_load_namespace+0x3a2/0x64a
[ 0.003333] acpi_load_tables+0x51/0x168
[ 0.003333] acpi_early_init+0x203/0x3f1
[ 0.003333] start_kernel+0x81b/0x987
[ 0.003333] x86_64_start_reservations+0x42/0x4a
[ 0.003333] x86_64_start_kernel+0xd0/0xd9
[ 0.003333] secondary_startup_64+0xa5/0xb0
[ 0.003333]
[ 0.003333] The buggy address belongs to the object at ffff8800001af428
[ 0.003333] which belongs to the cache Acpi-Namespace of size 40
[ 0.003333] The buggy address is located 10 bytes inside of
[ 0.003333] 40-byte region [ffff8800001af428, ffff8800001af450)
[ 0.003333] The buggy address belongs to the page:
[ 0.003333] page:ffffea0000006bc0 count:1 mapcount:0 mapping: (null) index:0x0
[ 0.003333] flags: 0x100(slab)
[ 0.003333] raw: 0000000000000100 0000000000000000 0000000000000000 0000000100490049
[ 0.003333] raw: dead000000000100 dead000000000200 ffff880000174600 0000000000000000
[ 0.003333] page dumped because: kasan: bad access detected
[ 0.003333]
[ 0.003333] Memory state around the buggy address:
[ 0.003333] ffff8800001af300: fc fc fb fb fb fb fb fc fc fb fb fb fb fb fc fc
[ 0.003333] ffff8800001af380: 00 00 00 00 00 fc fc fb fb fb fb fb fc fc fb fb
[ 0.003333] >ffff8800001af400: fb fb fb fc fc fb fb fb fb fb fc fc fb fb fb fb
[ 0.003333] ^
[ 0.003333] ffff8800001af480: fb fc fc 00 00 00 00 00 fc fc 00 00 00 00 00 fc
[ 0.003333] ffff8800001af500: fc 00 00 00 00 00 fc fc fb fb fb fb fb fc fc fb
[ 0.003333] ==================================================================
[ 0.003333] Disabling lock debugging due to kernel taint
[ 0.003333] ACPI Exception: AE_NO_MEMORY, [DSDT] table load failed (20170831/tbxfload-198)
[ 0.003333] ACPI Error: 1 table load failures, 0 successful (20170831/tbxfload-246)
[ 0.003333] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[ 0.003333] hpet clockevent registered
[ 0.003374] APIC: Switch to symmetric I/O mode setup
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
3 years, 2 months
[lkp-robot] [tools/bpf] 8c417dc15f: BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 8c417dc15f9522672795981dcb63d9099ca6bd8c ("tools/bpf: add a testcase for MAP_GET_NEXT_KEY command of LPM_TRIE map")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: kernel_selftests
with following parameters:
test-description: The kernel contains a set of "self tests" under the tools/testing/selftests/ directory. These are intended to be small unit tests to exercise individual code paths in the kernel.
test-url: https://www.kernel.org/doc/Documentation/kselftest.txt
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+---------------------------------------------------------------------+------------+------------+
| | b471f2f1de | 8c417dc15f |
+---------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 8 | 8 |
| invoked_oom-killer:gfp_mask=0x | 4 | 4 |
| Mem-Info | 4 | 4 |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 4 | 4 |
| BUG:sleeping_function_called_from_invalid_context_at_lib/usercopy.c | 4 | |
| BUG:sleeping_function_called_from_invalid_context_at_mm/slab.h | 0 | 4 |
+---------------------------------------------------------------------+------------+------------+
[ 275.143042] BUG: sleeping function called from invalid context at mm/slab.h:419
[ 275.146347] in_atomic(): 1, irqs_disabled(): 0, pid: 7020, name: test_lpm_map
[ 275.148889] CPU: 0 PID: 7020 Comm: test_lpm_map Not tainted 4.15.0-rc8-01647-g8c417dc #1
[ 275.151961] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 275.155015] Call Trace:
[ 275.156181] dump_stack+0x5c/0x7b
[ 275.157563] ___might_sleep+0xf1/0x110
[ 275.159078] __kmalloc+0x178/0x210
[ 275.160459] trie_get_next_key+0x57/0x2a0
[ 275.162019] ? _cond_resched+0x19/0x30
[ 275.163494] ? __kmalloc+0x17d/0x210
[ 275.165010] SyS_bpf+0xdbb/0x14b0
[ 275.166359] ? _cond_resched+0x19/0x30
[ 275.167851] entry_SYSCALL_64_fastpath+0x20/0x83
[ 275.169616] RIP: 0033:0x7fce862efe19
[ 275.171053] RSP: 002b:00007ffccbb62668 EFLAGS: 00000202
[ 275.183028] test_lpm: OK
[ 275.184984]
[ 275.188333] ok 1..5 selftests: test_lpm_map [PASS]
[ 275.188338]
[ 275.191610] selftests: test_progs
[ 275.191615]
[ 275.208854] ========================================
[ 275.208859]
[ 275.731322] libbpf: incorrect bpf_call opcode
[ 275.731329]
[ 275.739681] libbpf: incorrect bpf_call opcode
[ 275.739687]
[ 275.768456] test_pkt_access:PASS:ipv4 165 nsec
[ 275.768463]
[ 275.775864] test_pkt_access:PASS:ipv6 162 nsec
[ 275.775869]
[ 275.783113] test_xdp:PASS:ipv4 4283 nsec
[ 275.783251]
[ 275.798987] test_xdp:PASS:ipv6 1919 nsec
[ 275.798992]
[ 275.811095] test_l4lb:PASS:ipv4 1860 nsec
[ 275.811248]
[ 275.820459] test_l4lb:PASS:ipv6 3139 nsec
[ 275.820464]
[ 275.825614] test_tcp_estats:PASS: 0 nsec
[ 275.825618]
[ 275.831543] test_bpf_obj_id:PASS:get-fd-by-notexist-prog-id 0 nsec
[ 275.831548]
[ 275.838707] test_bpf_obj_id:PASS:get-fd-by-notexist-map-id 0 nsec
[ 275.838712]
[ 275.845485] test_bpf_obj_id:PASS:get-map-info(fd) 0 nsec
[ 275.845490]
[ 275.851833] test_bpf_obj_id:PASS:get-prog-info(fd) 0 nsec
[ 275.851838]
[ 275.858121] test_bpf_obj_id:PASS:get-map-info(fd) 0 nsec
[ 275.858126]
[ 275.862282] test_bpf_obj_id:PASS:get-prog-info(fd) 0 nsec
[ 275.862286]
[ 275.866722] test_bpf_obj_id:PASS:get-prog-fd(next_id) 0 nsec
[ 275.866726]
[ 275.871573] test_bpf_obj_id:PASS:get-prog-fd(next_id) 0 nsec
[ 275.871577]
[ 275.876121] test_bpf_obj_id:PASS:get-prog-fd(next_id) 0 nsec
[ 275.876125]
[ 275.880766] test_bpf_obj_id:PASS:get-prog-fd(next_id) 0 nsec
[ 275.880769]
[ 275.885053] test_bpf_obj_id:PASS:get-prog-fd(next_id) 0 nsec
[ 275.885057]
[ 275.889288] test_bpf_obj_id:PASS:get-prog-fd-bad-nr-map-ids 0 nsec
[ 275.889291]
[ 275.894180] test_bpf_obj_id:PASS:get-prog-info(next_id->fd) 0 nsec
[ 275.894184]
[ 275.900509] test_bpf_obj_id:PASS:get-prog-fd(next_id) 0 nsec
[ 275.900513]
[ 275.905233] test_bpf_obj_id:PASS:get-prog-fd-bad-nr-map-ids 0 nsec
[ 275.905236]
[ 275.910550] test_bpf_obj_id:PASS:get-prog-info(next_id->fd) 0 nsec
[ 275.910554]
[ 275.917881] test_bpf_obj_id:PASS:check total prog id found by get_next_id 0 nsec
[ 275.917885]
[ 275.926199] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.926205]
[ 275.932417] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.932422]
[ 275.937351] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.937356]
[ 275.942885] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.942891]
[ 275.947970] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.947975]
[ 275.953278] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.953283]
[ 275.958715] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.958720]
[ 275.963666] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.963671]
[ 275.968511] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.968516]
[ 275.973634] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.973639]
[ 275.979036] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
[ 275.979041]
[ 275.984546] test_bpf_obj_id:PASS:check get-map-info(next_id->fd) 0 nsec
[ 275.984551]
[ 275.990201] test_bpf_obj_id:PASS:get-map-fd(next_id) 0 nsec
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
3 years, 2 months
[lkp-robot] [x86/kasan] def0e7b54d: Kernel_panic-not_syncing:memblock_virt_alloc_try_nid:Failed_to_allocate#bytes_align=#nid=#from=#max_addr=
by kernel test robot
Hi, Andrey
Is this panic log expected with your commit?
FYI, we noticed the following commit (built with gcc-7):
commit: def0e7b54d63bae120302a4957c272107563ad04 ("x86/kasan: Panic if there is not enough memory to boot")
https://git.kernel.org/cgit/linux/kernel/git/tip/tip.git x86/pti
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 420M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------------------------------------------+------------+------------+
| | 99a9dc98ba | def0e7b54d |
+-----------------------------------------------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 31 | 16 |
| BUG:unable_to_handle_kernel | 18 | |
| Oops:#[##] | 18 | |
| RIP:kasan_populate_shadow | 18 | |
| Kernel_panic-not_syncing:Fatal_exception | 18 | |
| BUG:KASAN:use-after-scope_in_p | 13 | 4 |
| invoked_oom-killer:gfp_mask=0x | 13 | 4 |
| Mem-Info | 13 | 4 |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 13 | 4 |
| Kernel_panic-not_syncing:memblock_virt_alloc_try_nid:Failed_to_allocate#bytes_align=#nid=#from=#max_addr= | 0 | 12 |
+-----------------------------------------------------------------------------------------------------------+------------+------------+
[ 0.000000] DMA zone: 64 pages used for memmap
[ 0.000000] DMA zone: 21 pages reserved
[ 0.000000] DMA zone: 3998 pages, LIFO batch:0
[ 0.000000] DMA32 zone: 1616 pages used for memmap
[ 0.000000] DMA32 zone: 103392 pages, LIFO batch:31
[ 0.000000] Kernel panic - not syncing: memblock_virt_alloc_try_nid: Failed to allocate 4096 bytes align=0x1000 nid=0 from=0x1000000 max_addr=0x0
[ 0.000000]
[ 0.000000] CPU: 0 PID: 0 Comm: swapper Not tainted 4.14.0-00223-gdef0e7b5 #1
[ 0.000000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 0.000000] Call Trace:
[ 0.000000] ? dump_stack+0x134/0x230
[ 0.000000] ? _atomic_dec_and_lock+0x190/0x190
[ 0.000000] ? panic+0x1c1/0x3aa
[ 0.000000] ? __stack_chk_fail+0x24/0x24
[ 0.000000] ? memblock_virt_alloc_try_nid+0xf6/0x12d
[ 0.000000] ? early_alloc+0xcc/0x16c
[ 0.000000] ? kasan_populate_shadow+0xa58/0xc3f
[ 0.000000] ? kasan_init+0x314/0x577
[ 0.000000] ? setup_arch+0x235a/0x25d8
[ 0.000000] ? reserve_standard_io_resources+0x88/0x88
[ 0.000000] ? vprintk_emit+0x43c/0x44e
[ 0.000000] ? vprintk_func+0x9f/0xb3
[ 0.000000] ? printk+0xb7/0xe2
[ 0.000000] ? show_regs_print_info+0x64/0x64
[ 0.000000] ? stop_critical_timings+0xa/0xa
[ 0.000000] ? start_kernel+0xf8/0x101d
[ 0.000000] ? early_idt_handler_common+0x3b/0x52
[ 0.000000] ? mem_encrypt_init+0x37/0x37
[ 0.000000] ? memcpy_orig+0x54/0x110
[ 0.000000] ? secondary_startup_64+0xa5/0xb0
[ 0.000000] ---[ end Kernel panic - not syncing: memblock_virt_alloc_try_nid: Failed to allocate 4096 bytes align=0x1000 nid=0 from=0x1000000 max_addr=0x0
[ 0.000000]
Elapsed time: 10
#!/bin/bash
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
3 years, 2 months
