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, 7 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
1 year, 12 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, 8 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, 9 months
[lkp-robot] [iversion] c0cef30e4f: aim7.jobs-per-min -18.0% regression
by kernel test robot
Greeting,
FYI, we noticed a -18.0% regression of aim7.jobs-per-min due to commit:
commit: c0cef30e4ff0dc025f4a1660b8f0ba43ed58426e ("iversion: make inode_cmp_iversion{+raw} return bool instead of s64")
https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git master
in testcase: aim7
on test machine: 40 threads Intel(R) Xeon(R) CPU E5-2690 v2 @ 3.00GHz with 384G memory
with following parameters:
disk: 4BRD_12G
md: RAID0
fs: xfs
test: disk_src
load: 3000
cpufreq_governor: performance
test-description: AIM7 is a traditional UNIX system level benchmark suite which is used to test and measure the performance of multiuser system.
test-url: https://sourceforge.net/projects/aimbench/files/aim-suite7/
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
=========================================================================================
compiler/cpufreq_governor/disk/fs/kconfig/load/md/rootfs/tbox_group/test/testcase:
gcc-7/performance/4BRD_12G/xfs/x86_64-rhel-7.2/3000/RAID0/debian-x86_64-2016-08-31.cgz/lkp-ivb-ep01/disk_src/aim7
commit:
3da90b159b (" f2fs-for-4.16-rc1")
c0cef30e4f ("iversion: make inode_cmp_iversion{+raw} return bool instead of s64")
3da90b159b146672 c0cef30e4ff0dc025f4a1660b8
---------------- --------------------------
%stddev %change %stddev
\ | \
40183 -18.0% 32964 aim7.jobs-per-min
448.60 +21.9% 546.68 aim7.time.elapsed_time
448.60 +21.9% 546.68 aim7.time.elapsed_time.max
5615 ± 5% +33.4% 7489 ± 4% aim7.time.involuntary_context_switches
3086 +14.0% 3518 aim7.time.system_time
19439782 -5.6% 18359474 aim7.time.voluntary_context_switches
199333 +14.3% 227794 ± 2% interrupts.CAL:Function_call_interrupts
0.59 -0.1 0.50 mpstat.cpu.usr%
2839401 +16.0% 3293688 softirqs.SCHED
7600068 +15.1% 8747820 softirqs.TIMER
118.00 ± 43% +98.7% 234.50 ± 15% vmstat.io.bo
87840 -22.4% 68154 vmstat.system.cs
552798 ± 6% +15.8% 640107 ± 4% numa-numastat.node0.local_node
557345 ± 6% +15.7% 644666 ± 4% numa-numastat.node0.numa_hit
528341 ± 7% +21.7% 642933 ± 4% numa-numastat.node1.local_node
531604 ± 7% +21.6% 646209 ± 4% numa-numastat.node1.numa_hit
2.147e+09 -12.4% 1.88e+09 cpuidle.C1.time
13702041 -14.7% 11683737 cpuidle.C1.usage
2.082e+08 ± 4% +28.1% 2.667e+08 ± 5% cpuidle.C1E.time
4.719e+08 ± 2% +23.1% 5.807e+08 ± 4% cpuidle.C3.time
1.141e+10 +31.0% 1.496e+10 cpuidle.C6.time
15672622 +27.8% 20031028 cpuidle.C6.usage
13520572 ± 3% +29.5% 17514398 ± 9% cpuidle.POLL.time
278.25 ± 5% -46.0% 150.25 ± 73% numa-vmstat.node0.nr_dirtied
3200 ± 14% -20.6% 2542 ± 19% numa-vmstat.node0.nr_mapped
277.75 ± 5% -46.2% 149.50 ± 73% numa-vmstat.node0.nr_written
28.50 ± 52% +448.2% 156.25 ± 70% numa-vmstat.node1.nr_dirtied
2577 ± 19% +26.3% 3255 ± 15% numa-vmstat.node1.nr_mapped
634338 ± 4% +7.8% 683959 ± 4% numa-vmstat.node1.numa_hit
457411 ± 6% +10.8% 506800 ± 5% numa-vmstat.node1.numa_local
3734 ± 8% -11.5% 3306 ± 6% proc-vmstat.numa_hint_faults_local
1114538 +18.3% 1318978 proc-vmstat.numa_hit
1106722 +18.5% 1311136 proc-vmstat.numa_local
22100 +7.5% 23753 ± 4% proc-vmstat.numa_pages_migrated
1174556 +18.0% 1386359 proc-vmstat.pgalloc_normal
1241445 +18.1% 1466086 proc-vmstat.pgfault
1138310 +19.3% 1358132 proc-vmstat.pgfree
22100 +7.5% 23753 ± 4% proc-vmstat.pgmigrate_success
53332 ± 43% +143.0% 129617 ± 14% proc-vmstat.pgpgout
1.42 ± 2% +1.7 3.07 perf-stat.branch-miss-rate%
1.064e+10 +123.3% 2.375e+10 perf-stat.branch-misses
10.79 +0.6 11.43 perf-stat.cache-miss-rate%
5.583e+09 +5.9% 5.915e+09 perf-stat.cache-misses
39652092 -5.0% 37662545 perf-stat.context-switches
1.29 +11.7% 1.44 perf-stat.cpi
4.637e+12 +12.8% 5.23e+12 perf-stat.cpu-cycles
8.653e+11 +9.8% 9.498e+11 ± 2% perf-stat.dTLB-loads
3.654e+11 +12.4% 4.109e+11 perf-stat.dTLB-stores
0.78 -10.5% 0.70 perf-stat.ipc
1214932 +17.9% 1432266 perf-stat.minor-faults
1.334e+09 -1.8% 1.31e+09 perf-stat.node-store-misses
1.651e+09 -1.8% 1.62e+09 perf-stat.node-stores
1214954 +17.9% 1432313 perf-stat.page-faults
256.75 -100.0% 0.00 turbostat.Avg_MHz
21.39 -21.4 0.00 turbostat.Busy%
1200 -100.0% 0.00 turbostat.Bzy_MHz
13695007 -100.0% 0.00 turbostat.C1
11.92 -11.9 0.00 turbostat.C1%
2116683 ± 2% -100.0% 0.00 turbostat.C1E
1.16 ± 4% -1.2 0.00 turbostat.C1E%
3112269 -100.0% 0.00 turbostat.C3
2.62 ± 2% -2.6 0.00 turbostat.C3%
15671277 -100.0% 0.00 turbostat.C6
63.38 -63.4 0.00 turbostat.C6%
49.46 -100.0% 0.00 turbostat.CPU%c1
1.42 ± 2% -100.0% 0.00 turbostat.CPU%c3
27.73 -100.0% 0.00 turbostat.CPU%c6
31.41 -100.0% 0.00 turbostat.CorWatt
63.25 -100.0% 0.00 turbostat.CoreTmp
18919351 -100.0% 0.00 turbostat.IRQ
1.21 ± 18% -100.0% 0.00 turbostat.Pkg%pc2
0.67 ± 31% -100.0% 0.00 turbostat.Pkg%pc6
63.25 -100.0% 0.00 turbostat.PkgTmp
57.63 -100.0% 0.00 turbostat.PkgWatt
30.73 -100.0% 0.00 turbostat.RAMWatt
36030 -100.0% 0.00 turbostat.SMI
3000 -100.0% 0.00 turbostat.TSC_MHz
aim7.jobs-per-min
41000 +-+-----------------------------------------------------------------+
| ..+....+.... ..+....+....+.... ..+....+....+...+.... |
40000 +-+ +. +.. + |
39000 +-+ |
| |
38000 +-+ |
37000 +-+ |
| |
36000 +-+ |
35000 +-+ |
| |
34000 +-+ |
33000 +-+ O O |
O O O O O O O O O O O O O
32000 +-+-----------------------------------------------------------------+
[*] 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, 10 months
[lkp-robot] [printk] c162d5b433: BUG:KASAN:use-after-scope_in_c
by kernel test robot
TO: Petr Mladek <pmladek(a)suse.com>
CC: Cong Wang <xiyou.wangcong(a)gmail.com>, Dave Hansen <dave.hansen(a)intel.com>, Johannes Weiner <hannes(a)cmpxchg.org>, Mel Gorman <mgorman(a)suse.de>, Michal Hocko <mhocko(a)kernel.org>, Vlastimil Babka <vbabka(a)suse.cz>, Peter Zijlstra <peterz(a)infradead.org>, Linus Torvalds <torvalds(a)linux-foundation.org>, Jan Kara <jack(a)suse.cz>, Mathieu Desnoyers <mathieu.desnoyers(a)efficios.com>, Tetsuo Handa <penguin-kernel(a)I-love.SAKURA.ne.jp>, Byungchul Park <byungchul.park(a)lge.com>, Tejun Heo <tj(a)kernel.org>, Pavel Machek <pavel(a)ucw.cz>, Steven Rostedt (VMware) <rostedt(a)goodmis.org>, Sergey Senozhatsky <sergey.senozhatsky(a)gmail.com>, LKML <linux-kernel(a)vger.kernel.org>, linux-kernel(a)vger.kernel.org, lkp(a)01.org
FYI, we noticed the following commit (built with gcc-7):
commit: c162d5b4338d72deed61aa65ed0f2f4ba2bbc8ab ("printk: Hide console waiter logic into helpers")
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 host -smp 2 -m 1G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+--------------------------------+------------+------------+
| | dbdda842fe | c162d5b433 |
+--------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 18 | 16 |
| BUG:KASAN:use-after-scope_in_p | 18 | |
| BUG:KASAN:use-after-scope_in_c | 0 | 16 |
+--------------------------------+------------+------------+
[ 0.003333] BUG: KASAN: use-after-scope in console_unlock+0x185/0x960
[ 0.003333] BUG: KASAN: use-after-scope in console_unlock+0x185/0x960
[ 0.003333] Write of size 1 at addr ffffffff828079b8 by task swapper/0
[ 0.003333] Write of size 1 at addr ffffffff828079b8 by task swapper/0
[ 0.003333]
[ 0.003333]
[ 0.003333] CPU: 0 PID: 0 Comm: swapper Not tainted 4.14.0-12953-gc162d5b #1
[ 0.003333] CPU: 0 PID: 0 Comm: swapper Not tainted 4.14.0-12953-gc162d5b #1
[ 0.003333] Call Trace:
[ 0.003333] Call Trace:
[ 0.003333] ? dump_stack+0x11d/0x1c5
[ 0.003333] ? dump_stack+0x11d/0x1c5
[ 0.003333] ? printk+0xb5/0xd1
[ 0.003333] ? printk+0xb5/0xd1
[ 0.003333] ? arch_local_irq_restore+0x17/0x17
[ 0.003333] ? arch_local_irq_restore+0x17/0x17
[ 0.003333] ? do_raw_spin_unlock+0x137/0x169
[ 0.003333] ? do_raw_spin_unlock+0x137/0x169
[ 0.003333] ? irq_trace+0x2e/0x32
[ 0.003333] ? irq_trace+0x2e/0x32
[ 0.003333] ? console_unlock+0x185/0x960
[ 0.003333] ? console_unlock+0x185/0x960
[ 0.003333] ? print_address_description+0x6e/0x23b
[ 0.003333] ? print_address_description+0x6e/0x23b
[ 0.003333] ? console_unlock+0x185/0x960
[ 0.003333] ? console_unlock+0x185/0x960
[ 0.003333] ? kasan_report+0x223/0x249
[ 0.003333] ? kasan_report+0x223/0x249
[ 0.003333] ? console_unlock+0x185/0x960
[ 0.003333] ? console_unlock+0x185/0x960
[ 0.003333] ? wake_up_klogd+0xdf/0xdf
[ 0.003333] ? wake_up_klogd+0xdf/0xdf
[ 0.003333] ? do_raw_spin_unlock+0x145/0x169
[ 0.003333] ? do_raw_spin_unlock+0x145/0x169
[ 0.003333] ? do_raw_spin_trylock+0xed/0xed
[ 0.003333] ? do_raw_spin_trylock+0xed/0xed
[ 0.003333] ? irq_trace+0x2e/0x32
[ 0.003333] ? irq_trace+0x2e/0x32
[ 0.003333] ? _raw_spin_unlock_irqrestore+0x3b/0x54
[ 0.003333] ? _raw_spin_unlock_irqrestore+0x3b/0x54
[ 0.003333] ? time_hardirqs_off+0x12/0x2d
[ 0.003333] ? time_hardirqs_off+0x12/0x2d
[ 0.003333] ? arch_local_save_flags+0x7/0x8
[ 0.003333] ? arch_local_save_flags+0x7/0x8
[ 0.003333] ? trace_hardirqs_off_caller+0x127/0x139
[ 0.003333] ? trace_hardirqs_off_caller+0x127/0x139
[ 0.003333] ? irq_trace+0x2e/0x32
[ 0.003333] ? irq_trace+0x2e/0x32
[ 0.003333] ? vprintk_emit+0x579/0x823
[ 0.003333] ? vprintk_emit+0x579/0x823
[ 0.003333] ? __down_trylock_console_sem+0x90/0xa4
[ 0.003333] ? __down_trylock_console_sem+0x90/0xa4
[ 0.003333] ? __down_trylock_console_sem+0x9d/0xa4
[ 0.003333] ? __down_trylock_console_sem+0x9d/0xa4
[ 0.003333] ? vprintk_emit+0x7ec/0x823
[ 0.003333] ? vprintk_emit+0x7ec/0x823
[ 0.003333] ? console_unlock+0x960/0x960
[ 0.003333] ? console_unlock+0x960/0x960
[ 0.003333] ? memblock_merge_regions+0x2d/0x154
[ 0.003333] ? memblock_merge_regions+0x2d/0x154
[ 0.003333] ? memblock_add_range+0x322/0x333
[ 0.003333] ? memblock_add_range+0x322/0x333
[ 0.003333] ? memblock_reserve+0xbb/0xe1
[ 0.003333] ? memblock_reserve+0xbb/0xe1
[ 0.003333] ? memblock_add+0xe1/0xe1
[ 0.003333] ? memblock_add+0xe1/0xe1
[ 0.003333] ? set_pte+0x24/0x27
[ 0.003333] ? set_pte+0x24/0x27
[ 0.003333] ? vprintk_func+0x94/0xa5
[ 0.003333] ? vprintk_func+0x94/0xa5
[ 0.003333] ? printk+0xb5/0xd1
[ 0.003333] ? printk+0xb5/0xd1
[ 0.003333] ? show_regs_print_info+0x41/0x41
[ 0.003333] ? show_regs_print_info+0x41/0x41
[ 0.003333] ? kasan_populate_zero_shadow+0x37b/0x3f6
[ 0.003333] ? kasan_populate_zero_shadow+0x37b/0x3f6
[ 0.003333] ? native_flush_tlb_global+0x74/0x80
[ 0.003333] ? native_flush_tlb_global+0x74/0x80
[ 0.003333] ? kasan_init+0x211/0x22d
[ 0.003333] ? kasan_init+0x211/0x22d
[ 0.003333] ? setup_arch+0xdfa/0xf3c
[ 0.003333] ? setup_arch+0xdfa/0xf3c
[ 0.003333] ? css_set_populated+0x79/0x79
[ 0.003333] ? css_set_populated+0x79/0x79
[ 0.003333] ? reserve_standard_io_resources+0x39/0x39
[ 0.003333] ? reserve_standard_io_resources+0x39/0x39
[ 0.003333] ? vprintk_func+0x9d/0xa5
[ 0.003333] ? vprintk_func+0x9d/0xa5
[ 0.003333] ? printk+0xb5/0xd1
[ 0.003333] ? printk+0xb5/0xd1
[ 0.003333] ? show_regs_print_info+0x41/0x41
[ 0.003333] ? show_regs_print_info+0x41/0x41
[ 0.003333] ? start_kernel+0xa2/0x515
[ 0.003333] ? start_kernel+0xa2/0x515
[ 0.003333] ? mem_encrypt_init+0xa/0xa
[ 0.003333] ? mem_encrypt_init+0xa/0xa
[ 0.003333] ? x86_family+0x2e/0x33
[ 0.003333] ? x86_family+0x2e/0x33
[ 0.003333] ? load_ucode_bsp+0x58/0xec
[ 0.003333] ? load_ucode_bsp+0x58/0xec
[ 0.003333] ? secondary_startup_64+0xa5/0xb0
[ 0.003333] ? secondary_startup_64+0xa5/0xb0
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,
Shun
2 years, 10 months
[mm] cb9cc5caaf: BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c
by kernel test robot
FYI, we noticed the following commit (built with gcc-4.9):
commit: cb9cc5caafb2b2ad1db9742432754913d36f9cec ("mm: initialize pages on demand during boot")
git://git.cmpxchg.org/linux-mmotm.git master
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 1G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------------+------------+------------+
| | ee4fbdc56f | cb9cc5caaf |
+-----------------------------------------------------------------------------+------------+------------+
| boot_successes | 8 | 0 |
| boot_failures | 0 | 8 |
| BUG:sleeping_function_called_from_invalid_context_at_kernel/locking/mutex.c | 0 | 8 |
+-----------------------------------------------------------------------------+------------+------------+
[ 0.046841] BUG: sleeping function called from invalid context at kernel/locking/mutex.c:747
[ 0.048069] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: swapper/0
[ 0.048731] 1 lock held by swapper/0/1:
[ 0.049191] #0: (deferred_zone_grow_lock){....}, at: [<00000000a84c7c0f>] page_alloc_init_late+0x19/0xf4
[ 0.050019] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.16.0-rc2-mm1-00042-gcb9cc5c #1
[ 0.051072] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 0.052174] Call Trace:
[ 0.052511] dump_stack+0x5c/0x7d
[ 0.052961] ___might_sleep+0xfe/0x120
[ 0.053333] __mutex_lock+0x54/0x920
[ 0.053333] ? ret_from_fork+0x35/0x40
[ 0.053333] ? find_held_lock+0x38/0xa0
[ 0.053333] ? static_key_disable_cpuslocked+0x2c/0x70
[ 0.053333] static_key_disable_cpuslocked+0x2c/0x70
[ 0.053333] page_alloc_init_late+0x25/0xf4
[ 0.053333] kernel_init_freeable+0x129/0x23d
[ 0.053333] ? rest_init+0x100/0x100
[ 0.053333] kernel_init+0x5/0x100
[ 0.053333] ret_from_fork+0x35/0x40
[ 0.139999] node 0 initialised, 155246 pages in 86ms
[ 0.146928] devtmpfs: initialized
[ 0.149084] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
[ 0.150034] futex hash table entries: 16 (order: -1, 2048 bytes)
[ 0.150942] xor: automatically using best checksumming function avx
[ 0.151852] pinctrl core: initialized pinctrl subsystem
[ 0.153183] NET: Registered protocol family 16
[ 0.154520] audit: initializing netlink subsys (disabled)
[ 0.155375] audit: type=2000 audit(1519791676.541:1): state=initialized audit_enabled=0 res=1
[ 0.155375] cpuidle: using governor menu
[ 0.157750] ACPI: bus type PCI registered
[ 0.158341] dca service started, version 1.12.1
[ 0.158987] PCI: Using configuration type 1 for base access
[ 0.187303] cryptd: max_cpu_qlen set to 1000
[ 0.243360] raid6: sse2x1 gen() 5114 MB/s
[ 0.300041] raid6: sse2x1 xor() 4184 MB/s
[ 0.356687] raid6: sse2x2 gen() 7241 MB/s
[ 0.413353] raid6: sse2x2 xor() 4964 MB/s
[ 0.470017] raid6: sse2x4 gen() 8703 MB/s
[ 0.526685] raid6: sse2x4 xor() 5517 MB/s
[ 0.583352] raid6: avx2x1 gen() 8967 MB/s
[ 0.640017] raid6: avx2x1 xor() 7215 MB/s
[ 0.696698] raid6: avx2x2 gen() 12965 MB/s
[ 0.753355] raid6: avx2x2 xor() 11309 MB/s
[ 0.810018] raid6: avx2x4 gen() 13942 MB/s
[ 0.866682] raid6: avx2x4 xor() 8612 MB/s
[ 0.867191] raid6: using algorithm avx2x4 gen() 13942 MB/s
[ 0.867818] raid6: .... xor() 8612 MB/s, rmw enabled
[ 0.868382] raid6: using avx2x2 recovery algorithm
[ 0.869538] ACPI: Added _OSI(Module Device)
[ 0.870030] ACPI: Added _OSI(Processor Device)
[ 0.870546] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.871278] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.877562] ACPI: Interpreter enabled
[ 0.878027] ACPI: (supports S0 S5)
[ 0.878452] ACPI: Using IOAPIC for interrupt routing
[ 0.879536] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.880489] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 0.890221] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.890940] acpi PNP0A03:00: _OSC: OS supports [Segments MSI]
[ 0.891683] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 0.892660] PCI host bridge to bus 0000:00
[ 0.893135] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.893351] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.894106] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.894929] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[ 0.895755] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.896530] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[ 0.897591] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[ 0.899201] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[ 0.903831] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f]
[ 0.906339] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.906688] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.907456] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.908291] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.910201] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[ 0.911325] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 0.912135] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 0.913545] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[ 0.920036] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[ 0.925641] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[ 0.934899] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[ 0.936236] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[ 0.938070] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[ 0.940025] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f]
[ 0.950845] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[ 0.952156] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[ 0.953804] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f]
[ 0.961181] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 0.961852] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 0.962480] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 0.963086] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 0.963445] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 0.964754] SCSI subsystem initialized
[ 0.964754] libata version 3.00 loaded.
[ 0.964754] pps_core: LinuxPPS API ver. 1 registered
[ 0.966686] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti(a)linux.it>
[ 0.967764] PTP clock support registered
[ 0.969504] EDAC MC: Ver: 3.0.0
[ 0.970351] FPGA manager framework
[ 0.970570] PCI: Using ACPI for IRQ routing
[ 0.970570] PCI: pci_cache_line_size set to 64 bytes
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,
lkp
2 years, 10 months
[lkp-robot] [bisect done] 6dfb696fc4 [ 17.249909] WARNING: CPU: 0 PID: 493 at include/net/dst.h:242 ip6_rt_copy_init
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/0day-ci/linux/commits/David-Ahern/net-ipv6-Separate-da...
commit 6dfb696fc42745139b639a1fde83d0bf55fd80bb
Author: David Ahern <dsahern(a)gmail.com>
AuthorDate: Sun Feb 25 11:47:22 2018 -0800
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Mon Feb 26 22:24:23 2018 +0800
net/ipv6: Add rt6_info create function for ip6_pol_route_lookup
ip6_pol_route_lookup is the lookup function for ip6_route_lookup and
rt6_lookup. At the moment it returns either a reference to a FIB entry
or a cached exception. To move FIB entries to a separate struct, this
lookup function need to convert FIB entries to an rt6_info that is
returned to the caller.
Signed-off-by: David Ahern <dsahern(a)gmail.com>
c06ccebddf net/ipv6: Add fib6_null_entry
6dfb696fc4 net/ipv6: Add rt6_info create function for ip6_pol_route_lookup
c0a9458e6f net/ipv6: Remove unused code and variables for rt6_info
+-------------------------------------------------------+------------+------------+------------+
| | c06ccebddf | 6dfb696fc4 | c0a9458e6f |
+-------------------------------------------------------+------------+------------+------------+
| boot_successes | 40 | 2 | 14 |
| boot_failures | 4 | 16 | 3 |
| BUG:unable_to_handle_kernel | 4 | 4 | 1 |
| Oops:#[##] | 4 | 4 | 3 |
| RIP:ndisc_router_discovery | 4 | 4 | 3 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 4 | 4 | 3 |
| WARNING:at_include/net/dst.h:#ip6_rt_copy_init | 0 | 12 | |
| RIP:ip6_rt_copy_init | 0 | 12 | |
+-------------------------------------------------------+------------+------------+------------+
[ 3.593470] gfs2: path_lookup on rootfs returned error -2
Configuring network interfaces...
Kernel tests: Boot OK!
[ 17.240418] trinity-main uses obsolete (PF_INET,SOCK_PACKET)
[ 17.247594] sock: process `trinity-main' is using obsolete setsockopt SO_BSDCOMPAT
[ 17.249909] WARNING: CPU: 0 PID: 493 at include/net/dst.h:242 ip6_rt_copy_init+0x2f5/0x300
[ 17.251479] Modules linked in:
[ 17.251975] CPU: 0 PID: 493 Comm: trinity-main Not tainted 4.16.0-rc2-00682-g6dfb696 #1
[ 17.253268] RIP: 0010:ip6_rt_copy_init+0x2f5/0x300
[ 17.254097] RSP: 0018:ffffa170007d3ae8 EFLAGS: 00010293
[ 17.254984] RAX: ffff93641bb684c0 RBX: ffff93641c5c6a00 RCX: ffffffffab94cce5
[ 17.256138] RDX: 0000000000000000 RSI: ffff93641c5c6a00 RDI: ffff93641bf77040
[ 17.257248] RBP: ffffa170007d3b10 R08: 00000000ffffffff R09: 0000000000000000
[ 17.258397] R10: 0000000000000001 R11: 0000000000000000 R12: ffff93641bf77040
[ 17.259588] R13: 0000000000000000 R14: ffff93641c5c6a00 R15: ffff93641c5c6a00
[ 17.260715] FS: 000000000288a880(0000) GS:ffff93641f800000(0000) knlGS:0000000000000000
[ 17.261999] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 17.262965] CR2: 0000000002a9aa38 CR3: 000000001c6fe000 CR4: 00000000000006f0
[ 17.264127] Call Trace:
[ 17.264536] ip6_pol_route_lookup+0x3a6/0x3d0
[ 17.265242] ? rt6_multipath_select+0xe0/0xe0
[ 17.265962] fib6_rule_lookup+0x2c/0xa0
[ 17.266619] rt6_lookup+0xa1/0xe0
[ 17.267203] ipv6_sock_ac_join+0x18f/0x2b0
[ 17.267916] do_ipv6_setsockopt+0x1b4c/0x1db0
[ 17.268748] ? sched_clock+0x5/0x10
[ 17.269328] ? sched_clock_cpu+0xc/0xb0
[ 17.269940] ? __lock_acquire+0x16b/0x870
[ 17.270701] ? kvm_sched_clock_read+0x21/0x30
[ 17.271407] ? sched_clock+0x5/0x10
[ 17.271962] ? sched_clock_cpu+0xc/0xb0
[ 17.272587] ? __lock_acquire+0x16b/0x870
[ 17.273372] ? get_empty_filp+0x61/0x200
[ 17.274052] ? __lockdep_init_map+0x57/0x1d0
[ 17.274767] ? kvm_sched_clock_read+0x21/0x30
[ 17.275472] ? sched_clock+0x5/0x10
[ 17.276035] ? sched_clock_cpu+0xc/0xb0
[ 17.276672] ? ipv6_setsockopt+0x7f/0xa0
[ 17.277323] ipv6_setsockopt+0x7f/0xa0
[ 17.277977] udpv6_setsockopt+0x40/0x90
[ 17.278635] SyS_setsockopt+0x8a/0x100
[ 17.279253] do_syscall_64+0x80/0x270
[ 17.279848] entry_SYSCALL_64_after_hwframe+0x3d/0xa2
[ 17.280657] RIP: 0033:0x45878a
[ 17.281168] RSP: 002b:00007fff0bfd18a8 EFLAGS: 00000202 ORIG_RAX: 0000000000000036
[ 17.282439] RAX: ffffffffffffffda RBX: 0000000000000053 RCX: 000000000045878a
[ 17.283600] RDX: 000000000000001b RSI: 0000000000000029 RDI: 000000000000014f
[ 17.284721] RBP: 000000000000014f R08: 0000000000000014 R09: 0000000001045560
[ 17.285895] R10: 0000000002a99f70 R11: 0000000000000202 R12: 0000000002a99f30
[ 17.287065] R13: 00007fff0bfd18c0 R14: 0000000002a99f40 R15: 000000000000000a
[ 17.288183] Code: ed 74 23 e8 1e 11 7f ff 41 8d 55 01 44 89 e8 3e 41 0f b1 16 41 89 c5 0f 84 bb fe ff ff eb da e8 02 11 7f ff 0f 0b e8 fb 10 7f ff <0f> 0b e9 a6 fe ff ff 0f 1f 40 00 55 48 89 e5 41 57 41 56 41 55
[ 17.291428] ---[ end trace ff90c7223a228047 ]---
[ 17.300081] dst_release: dst:00000000298f3ab0 refcnt:-1
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start b3894fc23ea070638f47964257623cd126101ca1 4a3928c6f8a53fa1aed28ccba227742486e8ddcb --
git bisect bad aff2ba25f7bfce0b17a08ba2e1d74773b1020d7f # 21:43 B 1 4 0 0 Merge 'hverkuil-media/cec-error-inj' into devel-spot-201802270024
git bisect bad 57c1a2fef06644c43a4fded2fb5cd860d644afea # 22:01 B 0 1 17 2 Merge 'cdall/vhe-optimize-v5' into devel-spot-201802270024
git bisect good 8872dd7f852a99b1896ca2b7e2d4056cedcf1743 # 22:26 G 13 0 1 1 Merge 'metag/for-next' into devel-spot-201802270024
git bisect bad 8ba81c857a81baec03e7f46af28b4cf9e7c7c7ac # 22:43 B 2 3 0 0 Merge 'vireshk-pm/cpufreq/table-validation-updates' into devel-spot-201802270024
git bisect bad afc956c304ca2037165e8e8865157b9050a08373 # 22:59 B 0 1 15 0 Merge 'linux-review/David-Ahern/net-ipv6-Separate-data-structures-for-FIB-and-data-path/20180226-222416' into devel-spot-201802270024
git bisect good ac61af3575553f7fecb2732d9343e19a367aa12a # 23:14 G 14 0 1 1 Merge 'usb/usb-next' into devel-spot-201802270024
git bisect good 389a3244e0c401310065b3ecf710d9e6348f76d3 # 23:33 G 14 0 0 0 Merge 'staging/staging-next' into devel-spot-201802270024
git bisect good 15c9251fd2c06777e65c74b2df6aaac5575070cb # 23:54 G 13 0 0 4 net/ipv6: move metrics from dst to rt6_info
git bisect bad 57f9aaf6e30236c9f276c888c7a4a16f4ee1e307 # 00:11 B 0 4 20 2 net/ipv6: Move dst flags to booleans in fib entries
git bisect good c06ccebddfa0348e65b12ab08826b071818cd199 # 00:27 G 13 0 1 3 net/ipv6: Add fib6_null_entry
git bisect bad 6dfb696fc42745139b639a1fde83d0bf55fd80bb # 00:45 B 0 4 20 2 net/ipv6: Add rt6_info create function for ip6_pol_route_lookup
# first bad commit: [6dfb696fc42745139b639a1fde83d0bf55fd80bb] net/ipv6: Add rt6_info create function for ip6_pol_route_lookup
git bisect good c06ccebddfa0348e65b12ab08826b071818cd199 # 00:56 G 41 0 1 6 net/ipv6: Add fib6_null_entry
# extra tests with debug options
git bisect bad 6dfb696fc42745139b639a1fde83d0bf55fd80bb # 01:45 B 0 2 16 0 net/ipv6: Add rt6_info create function for ip6_pol_route_lookup
# extra tests on HEAD of linux-devel/devel-spot-201802270024
git bisect bad b3894fc23ea070638f47964257623cd126101ca1 # 01:45 B 107 233 0 27 0day head guard for 'devel-spot-201802270024'
# extra tests on tree/branch linux-review/David-Ahern/net-ipv6-Separate-data-structures-for-FIB-and-data-path/20180226-222416
git bisect good c0a9458e6f718e4eece4d1a0392d4aab1b879ba9 # 02:06 G 14 0 0 3 net/ipv6: Remove unused code and variables for rt6_info
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 10 months
[lkp-robot] [mm] df2af627c7: BUG:sleeping_function_called_from_invalid_context_at_include/linux/percpu-rwsem.h
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: df2af627c79ad4273b4b4c49711252c084490fd2 ("mm: initialize pages on demand during boot")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
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):
+-----------------------------------------------------------------------------------+------------+------------+
| | 47fc40a7ee | df2af627c7 |
+-----------------------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 12 | 19 |
| BUG:KASAN:use-after-scope_in_c | 12 | 19 |
| invoked_oom-killer:gfp_mask=0x | 10 | 15 |
| Mem-Info | 9 | 15 |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 9 | 15 |
| BUG:sleeping_function_called_from_invalid_context_at_include/linux/percpu-rwsem.h | 0 | 19 |
+-----------------------------------------------------------------------------------+------------+------------+
[ 0.054881] BUG: sleeping function called from invalid context at include/linux/percpu-rwsem.h:34
[ 0.055015] in_atomic(): 1, irqs_disabled(): 0, pid: 1, name: swapper/0
[ 0.055749] INFO: lockdep is turned off.
[ 0.056015] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G B 4.16.0-rc2-00103-gdf2af62 #1
[ 0.057000] Call Trace:
[ 0.057000] dump_stack+0x14b/0x22d
[ 0.057000] ? arch_local_irq_restore+0x16/0x16
[ 0.057000] ___might_sleep+0x335/0x382
[ 0.057000] ? in_sched_functions+0x54/0x54
[ 0.057000] ? page_alloc_init_late+0x8f/0x3b6
[ 0.057000] ? lock_contended+0x578/0x578
[ 0.057000] __might_sleep+0x190/0x1a3
[ 0.057000] cpus_read_lock+0x28/0xc0
[ 0.057000] static_key_disable+0x11/0x36
[ 0.057000] page_alloc_init_late+0xa2/0x3b6
[ 0.057000] ? setup_nr_node_ids+0x57/0x57
[ 0.057000] ? __bitmap_weight+0xab/0xb8
[ 0.057000] kernel_init_freeable+0x28b/0x69d
[ 0.057000] ? __clear_rsb+0x15/0x3d
[ 0.057000] ? rest_init+0x156/0x156
[ 0.057000] ? rest_init+0x156/0x156
[ 0.057000] kernel_init+0xe/0x1b3
[ 0.057000] ? rest_init+0x156/0x156
[ 0.057000] ret_from_fork+0x35/0x40
[ 0.058382] node 0 initialised, 0 pages in 0ms
[ 0.059079] pgdatinit0 (12) used greatest stack depth: 30272 bytes left
[ 0.061285] devtmpfs: initialized
[ 0.066273] gcov: version magic: 0x4137332a
[ 0.094475] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[ 0.095155] futex hash table entries: 256 (order: 3, 32768 bytes)
[ 0.104040] NET: Registered protocol family 16
[ 0.109641] audit: initializing netlink subsys (disabled)
[ 0.114096] cpuidle: using governor menu
[ 0.117157] ACPI: bus type PCI registered
[ 0.118569] PCI: Using configuration type 1 for base access
[ 0.120449] audit: type=2000 audit(1519774283.546:1): state=initialized audit_enabled=0 res=1
[ 0.234918] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.237759] cryptd: max_cpu_qlen set to 1000
[ 0.240431] ACPI: Added _OSI(Module Device)
[ 0.240918] ACPI: Added _OSI(Processor Device)
[ 0.241012] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.241547] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.287077] ACPI: Interpreter enabled
[ 0.287864] ACPI: (supports S0 S3 S5)
[ 0.288013] ACPI: Using IOAPIC for interrupt routing
[ 0.289315] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.296964] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 0.513386] swapper/0 invoked oom-killer: gfp_mask=0x14002c0(GFP_KERNEL|__GFP_NOWARN), nodemask=(null), order=2, oom_score_adj=0
[ 0.514014] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G B W 4.16.0-rc2-00103-gdf2af62 #1
[ 0.514979] Call Trace:
[ 0.515000] dump_stack+0x14b/0x22d
[ 0.515000] ? arch_local_irq_restore+0x16/0x16
[ 0.515000] dump_header+0x1bf/0x7f4
[ 0.515000] ? global_node_page_state+0xfd/0xfd
[ 0.515000] ? lock_release+0x769/0x7b1
[ 0.515000] ? rcu_read_unlock+0x43/0x43
[ 0.515000] ? rcu_read_lock+0x51/0x51
[ 0.515000] ? reacquire_held_locks+0x1a5/0x1a5
[ 0.515000] ? lock_acquire+0xf1/0x15b
[ 0.515000] ? mark_oom_victim+0x15e/0x15e
[ 0.515000] ? lock_acquire+0x148/0x15b
[ 0.515000] out_of_memory+0xdf2/0xeee
[ 0.515000] ? cpumask_next+0x53/0x5d
[ 0.515000] ? unregister_oom_notifier+0x1e/0x1e
[ 0.515000] ? lock_acquire+0xf1/0x15b
[ 0.515000] ? lock_acquire+0x148/0x15b
[ 0.515000] __alloc_pages_slowpath+0x130e/0x184d
[ 0.515000] ? __alloc_pages_cpuset_fallback+0x4f/0x4f
[ 0.515000] ? stack_access_ok+0x16/0x76
[ 0.515000] ? deref_stack_reg+0xc2/0x158
[ 0.515000] ? stack_access_ok+0x16/0x76
[ 0.515000] ? __free_pages+0x43/0x43
[ 0.515000] ? deref_stack_reg+0xc2/0x158
[ 0.515000] ? stack_access_ok+0x16/0x76
[ 0.515000] ? stack_access_ok+0x16/0x76
[ 0.515000] ? deref_stack_reg+0xc2/0x158
[ 0.515000] ? in_sched_functions+0x54/0x54
[ 0.515000] ? fs_reclaim_release+0x10/0x37
[ 0.515000] ? __might_sleep+0x190/0x1a3
[ 0.515000] __alloc_pages_nodemask+0x50b/0x586
[ 0.515000] ? __alloc_pages_slowpath+0x184d/0x184d
[ 0.515000] ? ret_from_fork+0x34/0x40
[ 0.515000] ? ret_from_fork+0x34/0x40
[ 0.515000] ? unwind_next_frame+0x374/0x14f3
[ 0.515000] __alloc_pages+0xe/0x16
[ 0.515000] alloc_page_interleave+0x12/0x12d
[ 0.515000] alloc_pages_current+0x9d/0xf6
[ 0.515000] depot_save_stack+0x1c8/0x484
[ 0.515000] save_stack+0x100/0x110
[ 0.515000] ? save_stack+0x37/0x110
[ 0.515000] ? set_track+0x23/0x2f
[ 0.515000] ? kasan_kmalloc+0xa9/0xbb
[ 0.515000] ? __kmalloc+0x18e/0x1bc
[ 0.515000] ? acpi_os_allocate_zeroed+0x4c/0x5b
[ 0.515000] ? acpi_ut_execute_CID+0x237/0x4c8
[ 0.515000] ? acpi_get_object_info+0x3f8/0xc8c
[ 0.515000] ? acpi_init_device_object+0x7c2/0x1678
[ 0.515000] ? acpi_add_single_object+0x24c/0x156c
[ 0.515000] ? acpi_bus_check_add+0x549/0x864
[ 0.515000] ? acpi_ns_walk_namespace+0x1a4/0x35b
[ 0.515000] ? acpi_walk_namespace+0xd5/0x112
[ 0.515000] ? acpi_bus_scan+0xc5/0x14a
[ 0.515000] ? acpi_scan_init+0x49e/0xc57
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,
Shun
2 years, 10 months
[x86/fsgsbase/64] 46fe4a0751: PANIC:double_fault
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 46fe4a075163b448897b7d49e171ac61800855cb ("x86/fsgsbase/64: Enable FSGSBASE by default and add a chicken bit")
https://github.com/changbae/FSGSBASE fsgs_tip_4.16-rc3_v10
in testcase: trinity
with following parameters:
runtime: 300s
test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/
on test machine: qemu-system-x86_64 -enable-kvm -cpu host -smp 2 -m 1G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------+------------+------------+
| | 0404fe4f16 | 46fe4a0751 |
+-----------------------------------------+------------+------------+
| boot_successes | 6 | 0 |
| boot_failures | 0 | 4 |
| PANIC:double_fault | 0 | 4 |
| RIP:async_page_fault | 0 | 4 |
| Kernel_panic-not_syncing:Machine_halted | 0 | 4 |
+-----------------------------------------+------------+------------+
[ 0.100062] smpboot: Max logical packages: 2
[ 0.100960] ----------------
[ 0.101552] | NMI testsuite:
[ 0.102117] --------------------
[ 0.102743] remote IPI:
[ 0.100000] PANIC: double fault, error_code: 0x0
[ 0.100000] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.16.0-rc3-00014-g46fe4a0 #90
[ 0.100000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 0.100000] RIP: 0010:async_page_fault+0x17/0x80
[ 0.100000] RSP: 0000:fffffe0000033000 EFLAGS: 00010046
[ 0.100000] RAX: 0000000086200a07 RBX: 0000000000000001 RCX: ffffffff86200a07
[ 0.100000] RDX: ffffa06450e00000 RSI: ffffffff86201d5e RDI: ffffffff84002d28
[ 0.100000] RBP: ffffffff86201d5e R08: 0000000000000000 R09: 0000000000000000
[ 0.100000] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
[ 0.100000] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 0.100000] FS: 0000000000000000(0000) GS:ffffa06450e00000(0000) knlGS:0000000000000000
[ 0.100000] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 0.100000] CR2: fffffe0000032ff8 CR3: 0000000054684001 CR4: 00000000001706e0
[ 0.100000] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 0.100000] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 0.100000] Call Trace:
[ 0.100000] Code: 78 48 c7 44 24 78 ff ff ff ff e8 14 c0 ea fd e9 d0 02 00 00 0f 1f 00 57 56 52 51 50 41 50 4d 31 c0 41 51 4d 31 c9 41 52 4d 31 d2 <41> 53 4d 31 db 53 31 db 55 31 ed 41 54 4d 31 e4 41 55 4d 31 ed
[ 0.100000] Kernel panic - not syncing: Machine halted.
[ 0.100000] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.16.0-rc3-00014-g46fe4a0 #90
[ 0.100000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 0.100000] Call Trace:
[ 0.100000] <#DF>
[ 0.100000] dump_stack+0x81/0xb3
[ 0.100000] panic+0xec/0x251
[ 0.100000] ? console_unlock+0x58e/0x5fa
[ 0.100000] df_debug+0x29/0x29
[ 0.100000] do_double_fault+0x106/0x116
[ 0.100000] double_fault+0x56/0x60
[ 0.100000] WARNING: stack going in the wrong direction? ip=double_fault+0x56/0x60
[ 0.100000] ? error_entry+0x3e/0xc0
[ 0.100000] ? native_iret+0x7/0x7
[ 0.100000] ? error_entry+0x3e/0xc0
[ 0.100000] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 0.100000] ? async_page_fault+0x17/0x80
[ 0.100000] </#DF>
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,
lkp
2 years, 10 months
