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?
9 months
[lkp-robot] [KEYS] bdf7c0f8bf: ltp.add_key02.fail
by kernel test robot
FYI, we noticed the following commit:
commit: bdf7c0f8bf282ba44827ce3c7fd7936c8e90a18a ("KEYS: fix dereferencing NULL payload with nonzero length")
url: https://github.com/0day-ci/linux/commits/Eric-Biggers/KEYS-fix-dereferenc...
base: https://git.kernel.org/cgit/linux/kernel/git/jmorris/linux-security.git next
in testcase: ltp
with following parameters:
test: syscalls_part1
test-description: The LTP testsuite contains a collection of tools for testing the Linux kernel and related features.
test-url: http://linux-test-project.github.io/
on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 48G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
user :notice: [ 45.447047] <<<test_start>>>
user :notice: [ 45.447365] tag=add_key02 stime=1492169102
user :notice: [ 45.447567] cmdline="add_key02"
user :notice: [ 45.447685] contacts=""
user :notice: [ 45.447826] analysis=exit
user :notice: [ 45.448011] <<<test_output>>>
user :notice: [ 45.448568] tst_test.c:760: INFO: Timeout per run is 0h 05m 00s
user :notice: [ 45.449439] add_key02.c:65: FAIL: add_key() failed unexpectedly, expected EINVAL: EFAULT
To reproduce:
git clone https://github.com/01org/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
2 years, 9 months
[btrfs] "fio: pid=2214, got signal=7" error showed in fio test for btrfs
by kernel test robot
Hi,
We detected below error messages in fio pmem test for btrfs.
machine: Intel(R) Xeon(R) CPU E5-2695 v3 @ 2.30GHz with 256G memory
kernel: v4.10
test parameters:
[global]
bs=2M
ioengine=mmap
iodepth=32
size=7669584457
direct=0
runtime=200
invalidate=1
fallocate=posix
group_reporting
time_based
[task_0]
rw=write
directory=/fs/pmem0
numjobs=14
[task_1]
rw=write
directory=/fs/pmem1
numjobs=14
==> /tmp/stderr <==
fio: pid=2214, got signal=7
fio: pid=2215, got signal=7
fio: pid=2217, got signal=7
fio: pid=2220, got signal=7
fio: pid=2222, got signal=7
fio: pid=2224, got signal=7
fio: pid=2225, got signal=7
fio: pid=2227, got signal=7
fio: pid=2216, got signal=7
fio: pid=2218, got signal=7
fio: pid=2219, got signal=7
fio: pid=2221, got signal=7
fio: pid=2223, got signal=7
fio: pid=2226, got signal=7
fio: pid=2200, got signal=7
fio: pid=2201, got signal=7
fio: pid=2203, got signal=7
fio: pid=2204, got signal=7
fio: pid=2205, got signal=7
fio: pid=2208, got signal=7
fio: pid=2209, got signal=7
fio: pid=2210, got signal=7
fio: pid=2211, got signal=7
fio: pid=2212, got signal=7
fio: pid=2213, got signal=7
fio: pid=2202, got signal=7
fio: pid=2206, got signal=7
fio: pid=2207, got signal=7
fio: file hash not empty on exit
while fio test is ok for ext4 with the same test parameters.
Attached reproduce.sh may help reproduce the problem.
Thanks,
Xiaolong
2 years, 9 months
[printk] fbc14616f4: BUG:kernel_reboot-without-warning_in_test_stage
by kernel test robot
FYI, we noticed the following commit:
commit: fbc14616f483788afabe77d05bfb99883dc66c73 ("printk: enable printk offloading")
url: https://github.com/0day-ci/linux/commits/Sergey-Senozhatsky/printk-introd...
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 Westmere -m 512M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-------------------------------------------------+------------+------------+
| | fd8b6b120c | fbc14616f4 |
+-------------------------------------------------+------------+------------+
| boot_successes | 8 | 8 |
| boot_failures | 0 | 6 |
| BUG:kernel_reboot-without-warning_in_test_stage | 0 | 4 |
| BUG:kernel_hang_in_test_stage | 0 | 2 |
+-------------------------------------------------+------------+------------+
[ 21.009531] VFS: Warning: trinity-c2 using old stat() call. Recompile your binary.
[ 21.148898] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary.
[ 22.298208] warning: process `trinity-c2' used the deprecated sysctl system call with
Elapsed time: 310
BUG: kernel reboot-without-warning in test stage
initrds=(
/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
/lkp/scheduled/vm-kbuild-yocto-ia32-9/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-fbc14616f483788afabe77d05bfb99883dc66c73-20170331-102676-cxuzks-0.cgz
/lkp/lkp/lkp-i386.cgz
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Kernel Test Robot
2 years, 9 months
[lkp-robot] [EDAC] e3c4ff6d8c: kmsg.EDAC_sbridge:Failed_to_register_device_with_error
by kernel test robot
FYI, we noticed the following commit:
commit: e3c4ff6d8c949fa9a9ea1bd005bf1967efe09d5d ("EDAC: Remove EDAC_MM_EDAC")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: unixbench
with following parameters:
runtime: 300s
nr_task: 100%
test: pipe
cpufreq_governor: performance
test-description: UnixBench is the original BYTE UNIX benchmark suite aims to test performance of Unix-like system.
test-url: https://github.com/kdlucas/byte-unixbench
on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 48G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
kern :err : [ 31.707838] EDAC sbridge: Couldn't find mci handler
kern :err : [ 31.707839] EDAC sbridge: Couldn't find mci handler
kern :err : [ 31.707841] EDAC sbridge: Failed to register device with error -22.
To reproduce:
git clone https://github.com/01org/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
2 years, 9 months
[lkp-robot] [sched/fair] 4c77b18cf8: hackbench.throughput -14.4% regression
by kernel test robot
Greeting,
FYI, we noticed a -14.4% regression of hackbench.throughput due to commit:
commit: 4c77b18cf8b7ab37c7d5737b4609010d2ceec5f0 ("sched/fair: Make select_idle_cpu() more aggressive")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: hackbench
on test machine: 48 threads 2 sockets Intel(R) Xeon(R) CPU E5-2697 v2 @ 2.70GHz with 64G memory
with following parameters:
nr_threads: 50%
mode: process
ipc: pipe
cpufreq_governor: performance
test-description: Hackbench is both a benchmark and a stress test for the Linux kernel scheduler.
test-url: https://github.com/linux-test-project/ltp/blob/master/testcases/kernel/sc...
In addition to that, the commit also has significant impact on the following tests:
+------------------+-----------------------------------------------------------------------+
| testcase: change | netperf: netperf.Throughput_tps -33.8% regression |
| test machine | 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 128G memory |
| test parameters | cluster=cs-localhost |
| | cpufreq_governor=performance |
| | ip=ipv4 |
| | nr_threads=200% |
| | runtime=300s |
| | test=SCTP_RR |
+------------------+-----------------------------------------------------------------------+
| testcase: change | netperf: netperf.Throughput_tps -50.8% regression |
| test machine | 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 128G memory |
| test parameters | cluster=cs-localhost |
| | cpufreq_governor=performance |
| | ip=ipv4 |
| | nr_threads=200% |
| | runtime=300s |
| | test=TCP_RR |
+------------------+-----------------------------------------------------------------------+
| testcase: change | netperf: netperf.Throughput_Mbps -8.7% regression |
| test machine | 16 threads Intel(R) Xeon(R) CPU D-1541 @ 2.10GHz with 8G memory |
| test parameters | cluster=cs-localhost |
| | cpufreq_governor=performance |
| | ip=ipv4 |
| | nr_threads=200% |
| | runtime=300s |
| | send_size=10K |
| | test=SCTP_STREAM_MANY |
+------------------+-----------------------------------------------------------------------+
| testcase: change | hackbench: hackbench.throughput 12.1% improvement |
| test machine | 8 threads Ivy Bridge with 16G memory |
| test parameters | cpufreq_governor=performance |
| | ipc=pipe |
| | mode=process |
| | nr_threads=50% |
+------------------+-----------------------------------------------------------------------+
| testcase: change | netperf: netperf.Throughput_Mbps -2.5% regression |
| test machine | 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 128G memory |
| test parameters | cluster=cs-localhost |
| | cpufreq_governor=performance |
| | ip=ipv4 |
| | nr_threads=200% |
| | runtime=300s |
| | send_size=10K |
| | test=SCTP_STREAM_MANY |
+------------------+-----------------------------------------------------------------------+
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
testcase/path_params/tbox_group/run: hackbench/50%-process-pipe-performance/ivb42
4977ab6e92e267af 4c77b18cf8b7ab37c7d5737b46
---------------- --------------------------
179106 -14% 153395 hackbench.throughput
5.036e+08 21% 6.113e+08 hackbench.time.involuntary_context_switches
4523 3% 4675 hackbench.time.percent_of_cpu_this_job_got
27089 3% 27956 hackbench.time.system_time
1394 -10% 1252 hackbench.time.user_time
2.501e+09 -11% 2.223e+09 hackbench.time.voluntary_context_switches
779669 -14% 667478 hackbench.time.minor_page_faults
319399 3% 329894 interrupts.CAL:Function_call_interrupts
884938 -22% 692644 vmstat.system.in
5224554 -9% 4736985 vmstat.system.cs
2880 2955 turbostat.Avg_MHz
96.25 98.77 turbostat.%Busy
6.59 -14% 5.63 turbostat.RAMWatt
2.009e+08 98% 3.986e+08 perf-stat.cpu-migrations
0.67 8% 0.73 perf-stat.branch-miss-rate%
5.046e+11 13% 5.722e+11 perf-stat.cache-references
5.897e+10 5% 6.22e+10 perf-stat.branch-misses
3851 16% 4471 perf-stat.instructions-per-iTLB-miss
38.80 -11% 34.53 perf-stat.node-store-miss-rate%
8.697e+13 8.833e+13 perf-stat.cpu-cycles
1928944 -8% 1777815 perf-stat.page-faults
1928944 -8% 1777789 perf-stat.minor-faults
1.332e+10 ± 3% -18% 1.098e+10 ± 16% perf-stat.dTLB-store-misses
1.87 ± 4% -20% 1.50 ± 19% perf-stat.dTLB-load-miss-rate%
2.654e+11 ± 4% -25% 1.988e+11 ± 20% perf-stat.dTLB-load-misses
0.53 -6% 0.50 perf-stat.ipc
8.738e+12 8.565e+12 perf-stat.branch-instructions
3.299e+09 -10% 2.968e+09 perf-stat.context-switches
4.586e+13 -4% 4.398e+13 perf-stat.instructions
64.05 31% 84.13 perf-stat.iTLB-load-miss-rate%
1.392e+13 -6% 1.306e+13 perf-stat.dTLB-loads
8.613e+12 -10% 7.773e+12 perf-stat.dTLB-stores
1.135e+10 ± 4% -45% 6.254e+09 ± 4% perf-stat.node-loads
1.878e+10 ± 3% -46% 1.016e+10 ± 4% perf-stat.cache-misses
1.1e+10 ± 4% -46% 5.949e+09 ± 4% perf-stat.node-load-misses
1.191e+10 -17% 9.836e+09 perf-stat.iTLB-load-misses
7.431e+09 ± 4% -48% 3.875e+09 ± 4% perf-stat.node-stores
3.72 ± 4% -52% 1.78 ± 4% perf-stat.cache-miss-rate%
4.711e+09 ± 4% -57% 2.044e+09 ± 3% perf-stat.node-store-misses
6.682e+09 -72% 1.856e+09 ± 3% perf-stat.iTLB-loads
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Thanks,
Ying Huang
2 years, 10 months
[lkp-robot] [perf] 924726b2b5: double_fault:#[##]
by kernel test robot
FYI, we noticed the following commit:
commit: 924726b2b5e5000dfb8eb6032651baed1b1bdc6c ("perf: Cure hotplug lock ordering issues")
https://git.kernel.org/cgit/linux/kernel/git/tip/tip.git smp/hotplug
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):
+-------------------------------------------------------+------------+------------+
| | fc932a0477 | 924726b2b5 |
+-------------------------------------------------------+------------+------------+
| boot_successes | 537 | 2 |
| boot_failures | 2 | 175 |
| BUG:kernel_hang_in_test_stage | 2 | |
| double_fault:#[##] | 0 | 170 |
| BUG:unable_to_handle_kernel | 0 | 49 |
| Oops:#[##] | 0 | 49 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 166 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 5 |
| BUG:kernel_reboot-without-warning_in_test_stage | 0 | 3 |
| WARNING:at_kernel/workqueue.c:#worker_thread | 0 | 1 |
| WARNING:at_kernel/workqueue.c:#process_one_work | 0 | 1 |
| general_protection_fault:#[##] | 0 | 1 |
+-------------------------------------------------------+------------+------------+
[ 203.023547] mmap: trinity-c0 (2509) uses deprecated remap_file_pages() syscall. See Documentation/vm/remap_file_pages.txt.
[child0:2509] uid changed! Was: 0, now 252
[child1:2511] child exiting.
Bailing main loop. Exit reason: UID changed.
[watchdog] [2502] Watchdog exiting
[ 204.078036] double fault: 0000 [#1] SMP DEBUG_PAGEALLOC
[ 204.078036] double fault: 0000 [#1] SMP DEBUG_PAGEALLOC
[ 204.079690] Modules linked in:
[ 204.079690] Modules linked in:
[ 204.082920] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 204.082920] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 204.087953] task: ffff88001ca78000 task.stack: ffff88001ca78020
[ 204.087953] task: ffff88001ca78000 task.stack: ffff88001ca78020
[ 204.089605] RIP: 0010:static_key_disable_cpuslocked+0x4/0x10
[ 204.089605] RIP: 0010:static_key_disable_cpuslocked+0x4/0x10
[ 204.091488] RSP: 0018:ffff88001ca24000 EFLAGS: 00010246
[ 204.091488] RSP: 0018:ffff88001ca24000 EFLAGS: 00010246
[ 204.093081] RAX: 0000000000000000 RBX: ffffffff81b98620 RCX: 0000000000001e06
[ 204.093081] RAX: 0000000000000000 RBX: ffffffff81b98620 RCX: 0000000000001e06
[ 204.095056] RDX: ffff88001da0da60 RSI: 0000000000000000 RDI: 0000000000000246
[ 204.095056] RDX: ffff88001da0da60 RSI: 0000000000000000 RDI: 0000000000000246
[ 204.097029] RBP: ffff88001ca24000 R08: 0000000000000000 R09: 0000000000000001
[ 204.097029] RBP: ffff88001ca24000 R08: 0000000000000000 R09: 0000000000000001
[ 204.099004] R10: ffff88001ca7fe00 R11: 0000000000000000 R12: ffff88001ca5b378
[ 204.099004] R10: ffff88001ca7fe00 R11: 0000000000000000 R12: ffff88001ca5b378
[ 204.101107] R13: ffff88001dbdc200 R14: 0000000000000000 R15: ffff88001dbd8340
[ 204.101107] R13: ffff88001dbdc200 R14: 0000000000000000 R15: ffff88001dbd8340
[ 204.103091] FS: 0000000000000000(0000) GS:ffff88001da00000(0000) knlGS:0000000000000000
[ 204.103091] FS: 0000000000000000(0000) GS:ffff88001da00000(0000) knlGS:0000000000000000
[ 204.105346] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 204.105346] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 204.106927] CR2: ffff88001ca23ff8 CR3: 0000000001a11000 CR4: 00000000000006b0
[ 204.106927] CR2: ffff88001ca23ff8 CR3: 0000000001a11000 CR4: 00000000000006b0
[ 204.109034] Call Trace:
[ 204.109034] Call Trace:
[ 204.109734] Code: 1f 84 00 00 00 00 00 55 31 c0 48 89 e5 5d c3 0f 1f 84 00 00 00 00 00 55 48 89 e5 5d c3 66 2e 0f 1f 84 00 00 00 00 00 55 48 89 e5 <e8> f7 ff ff ff 5d c3 0f 1f 44 00 00 55 48 89 e5 e8 b7 bf f5 ff
[ 204.109734] Code: 1f 84 00 00 00 00 00 55 31 c0 48 89 e5 5d c3 0f 1f 84 00 00 00 00 00 55 48 89 e5 5d c3 66 2e 0f 1f 84 00 00 00 00 00 55 48 89 e5 <e8> f7 ff ff ff 5d c3 0f 1f 44 00 00 55 48 89 e5 e8 b7 bf f5 ff
[ 204.115026] RIP: static_key_disable_cpuslocked+0x4/0x10 RSP: ffff88001ca24000
[ 204.115026] RIP: static_key_disable_cpuslocked+0x4/0x10 RSP: ffff88001ca24000
[ 204.117360] ---[ end trace 76cb39a4929e5712 ]---
[ 204.117360] ---[ end trace 76cb39a4929e5712 ]---
[ 204.117362] BUG: unable to handle kernel paging request at ffffffff8113e9f9
[ 204.117362] BUG: unable to handle kernel paging request at ffffffff8113e9f9
[ 204.117370] IP: update_curr+0x10b/0x130
[ 204.117370] IP: update_curr+0x10b/0x130
[ 204.117371] PGD 1a14067
[ 204.117371] PGD 1a14067
[ 204.117372] PUD 1a15063
[ 204.117372] PUD 1a15063
[ 204.117373] PMD 10001e1
[ 204.117373] PMD 10001e1
[ 204.117374]
[ 204.117374]
[ 204.117377] Oops: 0003 [#2] SMP DEBUG_PAGEALLOC
[ 204.117377] Oops: 0003 [#2] SMP DEBUG_PAGEALLOC
[ 204.117379] Modules linked in:
[ 204.117379] Modules linked in:
[ 204.117383] CPU: 1 PID: 15 Comm: migration/1 Tainted: G D 4.11.0-rc6-00244-g924726b #1
[ 204.117383] CPU: 1 PID: 15 Comm: migration/1 Tainted: G D 4.11.0-rc6-00244-g924726b #1
[ 204.117384] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 204.117384] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 204.117386] task: ffff88001c998000 task.stack: ffff88001c99c000
[ 204.117386] task: ffff88001c998000 task.stack: ffff88001c99c000
[ 204.117388] RIP: 0010:update_curr+0x10b/0x130
[ 204.117388] RIP: 0010:update_curr+0x10b/0x130
[ 204.117389] RSP: 0018:ffff88001c99fd18 EFLAGS: 00010083
[ 204.117389] RSP: 0018:ffff88001c99fd18 EFLAGS: 00010083
[ 204.117391] RAX: ffffffff8113e8b9 RBX: ffff88001ca78080 RCX: ffffffffffa4b511
[ 204.117391] RAX: ffffffff8113e8b9 RBX: ffff88001ca78080 RCX: ffffffffffa4b511
[ 204.117392] RDX: 00000000000000f3 RSI: 0000000000000000 RDI: ffff88001dbd8e70
[ 204.117392] RDX: 00000000000000f3 RSI: 0000000000000000 RDI: ffff88001dbd8e70
[ 204.117394] RBP: ffff88001c99fd28 R08: 0000000000000400 R09: ffff88001ca78080
[ 204.117394] RBP: ffff88001c99fd28 R08: 0000000000000400 R09: ffff88001ca78080
[ 204.117395] R10: ffff88001c99fd18 R11: 0000782e03e0348e R12: ffff88001dbd8e70
[ 204.117395] R10: ffff88001c99fd18 R11: 0000782e03e0348e R12: ffff88001dbd8e70
[ 204.117396] R13: ffff88001cb78080 R14: ffff88001ca78080 R15: ffff88001cb78000
[ 204.117396] R13: ffff88001cb78080 R14: ffff88001ca78080 R15: ffff88001cb78000
[ 204.117398] FS: 0000000000000000(0000) GS:ffff88001dc00000(0000) knlGS:0000000000000000
[ 204.117398] FS: 0000000000000000(0000) GS:ffff88001dc00000(0000) knlGS:0000000000000000
[ 204.117399] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 204.117399] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 204.117400] CR2: ffffffff8113e9f9 CR3: 00000000124ad000 CR4: 00000000000006a0
[ 204.117400] CR2: ffffffff8113e9f9 CR3: 00000000124ad000 CR4: 00000000000006a0
[ 204.117404] Call Trace:
[ 204.117404] Call Trace:
[ 204.117408] enqueue_task_fair+0x139/0xe20
[ 204.117408] enqueue_task_fair+0x139/0xe20
[ 204.117413] ? kvm_sched_clock_read+0x25/0x40
[ 204.117413] ? kvm_sched_clock_read+0x25/0x40
[ 204.117417] move_queued_task+0x224/0x250
[ 204.117417] move_queued_task+0x224/0x250
[ 204.117420] __migrate_task+0xbe/0xd0
[ 204.117420] __migrate_task+0xbe/0xd0
[ 204.117423] migration_cpu_stop+0xa3/0x100
[ 204.117423] migration_cpu_stop+0xa3/0x100
[ 204.117425] ? sched_ttwu_pending+0xd0/0xd0
[ 204.117425] ? sched_ttwu_pending+0xd0/0xd0
[ 204.117430] cpu_stopper_thread+0x8c/0x100
[ 204.117430] cpu_stopper_thread+0x8c/0x100
[ 204.117433] ? smpboot_thread_fn+0x2f/0x210
[ 204.117433] ? smpboot_thread_fn+0x2f/0x210
[ 204.117434] ? smpboot_thread_fn+0x157/0x210
[ 204.117434] ? smpboot_thread_fn+0x157/0x210
[ 204.117436] smpboot_thread_fn+0x1ea/0x210
[ 204.117436] smpboot_thread_fn+0x1ea/0x210
[ 204.117440] kthread+0x12b/0x140
[ 204.117440] kthread+0x12b/0x140
[ 204.117441] ? sort_range+0x20/0x20
[ 204.117441] ? sort_range+0x20/0x20
[ 204.117443] ? kthread_stop+0x70/0x70
[ 204.117443] ? kthread_stop+0x70/0x70
[ 204.117447] ret_from_fork+0x2c/0x40
[ 204.117447] ret_from_fork+0x2c/0x40
[ 204.117450] Code: 89 e7 e8 19 f5 ff ff 48 8b 83 e8 09 00 00 0f b6 90 48 01 00 00 84 d2 74 32 48 83 bb f0 09 00 00 00 74 28 48 3d d0 fe ff ff 74 20 <f0> 4c 01 98 40 01 00 00 eb 16 be 4e 03 00 00 48 c7 c7 b8 1b 82
[ 204.117450] Code: 89 e7 e8 19 f5 ff ff 48 8b 83 e8 09 00 00 0f b6 90 48 01 00 00 84 d2 74 32 48 83 bb f0 09 00 00 00 74 28 48 3d d0 fe ff ff 74 20 <f0> 4c 01 98 40 01 00 00 eb 16 be 4e 03 00 00 48 c7 c7 b8 1b 82
[ 204.117486] RIP: update_curr+0x10b/0x130 RSP: ffff88001c99fd18
[ 204.117486] RIP: update_curr+0x10b/0x130 RSP: ffff88001c99fd18
[ 204.117487] CR2: ffffffff8113e9f9
[ 204.117487] CR2: ffffffff8113e9f9
[ 204.117489] ---[ end trace 76cb39a4929e5713 ]---
[ 204.117489] ---[ end trace 76cb39a4929e5713 ]---
[ 204.117490] Kernel panic - not syncing: Fatal exception
[ 204.117490] Kernel panic - not syncing: Fatal exception
[ 205.173161] Shutting down cpus with NMI
[ 205.173161] Shutting down cpus with NMI
[ 205.174528] Kernel Offset: disabled
[ 205.174528] Kernel Offset: disabled
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
2 years, 10 months
[lkp-robot] [sched] 014a8b28d7: unixbench.score -3.6% regression
by kernel test robot
Greeting,
FYI, we noticed a -3.6% regression of unixbench.score due to commit:
commit: 014a8b28d7cce0508d6a18e3ba2a86a08c1323fb ("sched: get ride of smt_gain")
git://internal_merge_and_test_tree devel-catchup-201704202119
in testcase: unixbench
on test machine: 8 threads Ivy Bridge with 16G memory
with following parameters:
runtime: 300s
nr_task: 100%
test: shell1
cpufreq_governor: performance
test-description: UnixBench is the original BYTE UNIX benchmark suite aims to test performance of Unix-like system.
test-url: https://github.com/kdlucas/byte-unixbench
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
testcase/path_params/tbox_group/run: unixbench/300s-100%-shell1-performance/lkp-ivb-d01
73810a069120aa83 014a8b28d7cce0508d6a18e3ba
---------------- --------------------------
13046 -4% 12580 unixbench.score
4.642e+08 -4% 4.436e+08 unixbench.time.minor_page_faults
2521 -5% 2397 unixbench.time.user_time
562 -5% 533 unixbench.time.percent_of_cpu_this_job_got
15266663 -6% 14366909 unixbench.time.voluntary_context_switches
1029 -6% 964 unixbench.time.system_time
9770474 -8% 8949985 unixbench.time.involuntary_context_switches
530792 -52% 254212 interrupts.CAL:Function_call_interrupts
16739 -4% 16043 vmstat.system.in
79549 -9% 72741 vmstat.system.cs
47.04 45.94 turbostat.PkgWatt
40.79 39.70 turbostat.CorWatt
2677 -6% 2525 turbostat.Avg_MHz
72.54 -6% 68.36 turbostat.%Busy
702 ± 50% 1e+04 10831 ±130% latency_stats.avg.io_schedule.nfs_wait_on_request.nfs_writepage_setup.nfs_updatepage.nfs_write_end.generic_perform_write.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
702 ± 50% 1e+04 10831 ±130% latency_stats.max.io_schedule.nfs_wait_on_request.nfs_writepage_setup.nfs_updatepage.nfs_write_end.generic_perform_write.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
702 ± 50% 1e+04 10831 ±130% latency_stats.sum.io_schedule.nfs_wait_on_request.nfs_writepage_setup.nfs_updatepage.nfs_write_end.generic_perform_write.nfs_file_write.__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
2491 2539 perf-stat.instructions-per-iTLB-miss
0.84 0.85 perf-stat.ipc
1.598e+11 -4% 1.533e+11 perf-stat.cache-references
2.217e+12 -4% 2.122e+12 perf-stat.branch-instructions
1.13e+13 -4% 1.081e+13 perf-stat.instructions
4.541e+08 -4% 4.34e+08 perf-stat.minor-faults
4.541e+08 -4% 4.34e+08 perf-stat.page-faults
2.89e+12 -5% 2.759e+12 perf-stat.dTLB-loads
1.917e+12 -5% 1.829e+12 perf-stat.dTLB-stores
5.567e+10 -5% 5.295e+10 perf-stat.branch-misses
1.344e+13 -5% 1.27e+13 perf-stat.cpu-cycles
4.533e+09 -6% 4.257e+09 perf-stat.iTLB-load-misses
50349088 -9% 46038907 perf-stat.context-switches
1.081e+09 -10% 9.754e+08 perf-stat.iTLB-loads
9524246 -14% 8161301 perf-stat.cpu-migrations
perf-stat.dTLB-loads
2.94e+12 ++---------------------------------------------------------------+
2.92e+12 ++ * |
| * * ** ** * :: |
2.9e+12 ++ ** * :****. * * * : :** *. * ***|
2.88e+12 ++ **.*** * : : * * : : * * *
2.86e+12 **** * *****.***** *****.***** |
2.84e+12 ++ |
| |
2.82e+12 ++ |
2.8e+12 ++ O O |
2.78e+12 O+ O O O |
2.76e+12 ++O O O OOO O OO OO |
|O O O O O O O O OO O |
2.74e+12 ++ O |
2.72e+12 ++---------------------------------------------------------------+
perf-stat.iTLB-loads
1.15e+09 ++---------------------------------------------------------------+
| * |
1.1e+09 +* :**. ** * |
* * ** : : ** ** *** ***.* * : ** ** * ** ******
| *** ** *.** * * : * : ** *.** ** * **.* |
1.05e+09 ++ ** * |
| |
1e+09 O+ |
| OOO OOOOO OOOOOOOOOOO |
9.5e+08 +O O OO |
| OO O |
| |
9e+08 ++ O O |
| |
8.5e+08 ++---------------------------------------------------------------+
perf-stat.page-faults
4.6e+08 ++---------------------------------------------------------------+
| * |
4.55e+08 ++ ** ****. :*** ** ****. *****|
| *.******* : * ** * : * * *
4.5e+08 *** * : : : : |
| * *****.***** *****.***** |
4.45e+08 ++ |
| |
4.4e+08 ++ |
| |
4.35e+08 ++ OO OO |
| OO O O OOOO OO O |
4.3e+08 ++ OOOOO OO |
OO OO O |
4.25e+08 ++OO-------------------------------------------------------------+
perf-stat.context-switches
5.3e+07 ++----------------------------------------------------------------+
| |
5.2e+07 ++ **.******** ********.** |
5.1e+07 ++ : : : : |
| : * : : * .* |
5e+07 *****.******** *.**********.** ***** ** *****
| |
4.9e+07 ++ |
| |
4.8e+07 ++ |
4.7e+07 ++ |
| |
4.6e+07 ++ OOO OOOOOOOO OOOO |
OOOOO OO OOOOO OO |
4.5e+07 ++----------------------------------------------------------------+
perf-stat.cpu-migrations
9.6e+06 ++----------*------------**--**---------------------**---*---*-*--+
| ****** * : * *** : *** ** * ***
9.4e+06 ++ + : : ****.** : |
9.2e+06 *+* * : : : : |
|* * **.******** ********.** |
9e+06 ++ |
8.8e+06 ++ |
| |
8.6e+06 ++ |
8.4e+06 ++ |
| |
8.2e+06 ++ OOOO OOOO |
8e+06 ++ |
OOOOO OOOOOOOOOO OOOOOO |
7.8e+06 ++----------------------------------------------------------------+
[*] 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
[rhashtable] 6d684e5469: Kernel panic - not syncing: rtnetlink_init: cannot initialize rtnetlink
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://git.kernel.org/pub/scm/linux/kernel/git/davem/net-next.git master
commit 6d684e54690caef45cf14051ddeb7c71beeb681b
Author: Herbert Xu <herbert(a)gondor.apana.org.au>
AuthorDate: Thu Apr 27 13:44:51 2017 +0800
Commit: David S. Miller <davem(a)davemloft.net>
CommitDate: Thu Apr 27 11:48:24 2017 -0400
rhashtable: Cap total number of entries to 2^31
When max_size is not set or if it set to a sufficiently large
value, the nelems counter can overflow. This would cause havoc
with the automatic shrinking as it would then attempt to fit a
huge number of entries into a tiny hash table.
This patch fixes this by adding max_elems to struct rhashtable
to cap the number of elements. This is set to 2^31 as nelems is
not a precise count. This is sufficiently smaller than UINT_MAX
that it should be safe.
When max_size is set max_elems will be lowered to at most twice
max_size as is the status quo.
Signed-off-by: Herbert Xu <herbert(a)gondor.apana.org.au>
Signed-off-by: David S. Miller <davem(a)davemloft.net>
4b726e81da tcp: tcp_rack_reo_timeout() must update tp->tcp_mstamp
6d684e5469 rhashtable: Cap total number of entries to 2^31
e221c1f0fe Merge tag 'wireless-drivers-next-for-davem-2017-04-27' of git://git.kernel.org/pub/scm/linux/kernel/git/kvalo/wireless-drivers-next
+---------------------------------------------------------------------+------------+------------+------------+
| | 4b726e81da | 6d684e5469 | e221c1f0fe |
+---------------------------------------------------------------------+------------+------------+------------+
| boot_successes | 35 | 0 | 0 |
| boot_failures | 0 | 13 | 13 |
| Kernel_panic-not_syncing:rtnetlink_init:cannot_initialize_rtnetlink | 0 | 13 | 13 |
| BUG:kernel_in_stage | 0 | 1 | 1 |
+---------------------------------------------------------------------+------------+------------+------------+
[ 0.106834] futex hash table entries: 256 (order: 3, 32768 bytes)
[ 0.148019] xor: automatically using best checksumming function avx
[ 0.148019] xor: automatically using best checksumming function avx
[ 0.152501] NET: Registered protocol family 16
[ 0.152501] NET: Registered protocol family 16
[ 0.157635] Kernel panic - not syncing: rtnetlink_init: cannot initialize rtnetlink
[ 0.157635]
[ 0.157635] Kernel panic - not syncing: rtnetlink_init: cannot initialize rtnetlink
[ 0.157635]
[ 0.162789] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.11.0-rc8-02028-g6d684e5 #1
[ 0.162789] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.11.0-rc8-02028-g6d684e5 #1
[ 0.166143] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 0.166143] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 0.169615] Call Trace:
[ 0.169615] Call Trace:
[ 0.170458] show_stack+0x72/0x76
[ 0.170458] show_stack+0x72/0x76
[ 0.171559] dump_stack+0x10c/0x181
[ 0.171559] dump_stack+0x10c/0x181
[ 0.172753] ? __mutex_unlock_slowpath+0x171/0x34a
[ 0.172753] ? __mutex_unlock_slowpath+0x171/0x34a
[ 0.174332] panic+0x121/0x329
[ 0.174332] panic+0x121/0x329
[ 0.175385] rtnetlink_init+0x39/0x1b7
[ 0.175385] rtnetlink_init+0x39/0x1b7
[ 0.176784] netlink_proto_init+0x1bc/0x1da
[ 0.176784] netlink_proto_init+0x1bc/0x1da
[ 0.178399] ? net_ns_init+0x19c/0x19c
[ 0.178399] ? net_ns_init+0x19c/0x19c
[ 0.180171] ? eth_offload_init+0x14/0x14
[ 0.180171] ? eth_offload_init+0x14/0x14
[ 0.182045] do_one_initcall+0xa2/0x1cc
[ 0.182045] do_one_initcall+0xa2/0x1cc
[ 0.183789] do_basic_setup+0x98/0xb8
[ 0.183789] do_basic_setup+0x98/0xb8
[ 0.185369] kernel_init_freeable+0x92/0x147
[ 0.185369] kernel_init_freeable+0x92/0x147
[ 0.187076] ? rest_init+0x170/0x170
[ 0.187076] ? rest_init+0x170/0x170
[ 0.188694] kernel_init+0xe/0x171
[ 0.188694] kernel_init+0xe/0x171
[ 0.190235] ret_from_fork+0x31/0x40
[ 0.190235] ret_from_fork+0x31/0x40
# HH:MM RESULT GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD
git bisect start d1c34825a799a7ba4693bf46fbc79238aed374e6 5a7ad1146caa895ad718a534399e38bd2ba721b7 --
git bisect good c6616d396b8b3e3c55cc66b24652a63108ea98dc # 06:18 G 11 0 0 0 Merge 'linux-review/Jerry-Snitselaar/tpm-print-tpm-error-code-in-hex-for-TPM2-0-devices/20170428-043331' into devel-catchup-201704280512
git bisect good b25b2abcfd0401937550e1d1806d83d796f1a043 # 06:50 G 10 0 2 2 Merge 'iio/fixes-togreg' into devel-catchup-201704280512
git bisect bad d03caeb5c291c2fa03c57c5835677986c040913a # 07:03 B 0 2 12 0 Merge 'net-next/master' into devel-catchup-201704280512
git bisect good a195fe56e7785f560ddf35293946bb4f9b024bf0 # 07:32 G 11 0 0 0 Merge 'linux-review/Kirill-Tkhai/pid_ns-Introduce-ioctl-to-set-vector-of-ns_last_pid-s-on-ns-hierarhy/20170428-035028' into devel-catchup-201704280512
git bisect good aa59004bdc2e6569a7101fe196a56c6bd58b12d8 # 07:56 G 11 0 0 0 s390/qeth: use QDIO_*_QFMT defines
git bisect good fada43ccc84e225fe8d3804878a4028def7f85a5 # 08:09 G 11 0 0 0 bindings: net: stmmac: add missing note about LPI interrupt
git bisect good a0597834dc0e1af68f48f92b31617b360a14dfc8 # 08:28 G 11 0 0 0 rt2800: fix mt7620 E2 channel registers
git bisect good 9b5381637e2baecf14433452d349ca44db72e9e5 # 08:45 G 11 0 0 0 Merge branch '1GbE' of git://git.kernel.org/pub/scm/linux/kernel/git/jkirsher/next-queue
git bisect good 0e43d1009d64afcc57bfb5155f1a36c395fac178 # 09:01 G 11 0 0 0 Merge branch 'bpf-misc-cleanups'
git bisect good 36784277c59bdfce8b90233c85e957ae4ee702fc # 09:17 G 11 0 0 0 Merge branch 'l2tpeth-info'
git bisect good 128eda86bebeacefb0fcc64cab0155aa76857c92 # 09:31 G 11 0 0 0 tcp: do not pass timestamp to tcp_rack_mark_lost()
git bisect good 4c5e7a2c0501bd531aad1d0378c589a92cb3cc31 # 09:43 G 11 0 0 0 dt-bindings: mdio: Clarify binding document
git bisect good 78a57b482aa53b040fbc2b2ef0208b54f004161b # 09:57 G 11 0 0 2 virtio-net: on tx, only call napi_disable if tx napi is on
git bisect good 4b726e81dab2cefefd2c1cb00e3074ac0eec4b15 # 10:10 G 11 0 0 0 tcp: tcp_rack_reo_timeout() must update tp->tcp_mstamp
git bisect bad 3993f2cb983b2100409851f7b2abb21d685ea19c # 10:22 B 0 1 11 0 samples/bpf: Add support for SKB_MODE to xdp1 and xdp_tx_iptunnel
git bisect bad 6d684e54690caef45cf14051ddeb7c71beeb681b # 10:37 B 0 1 11 0 rhashtable: Cap total number of entries to 2^31
# first bad commit: [6d684e54690caef45cf14051ddeb7c71beeb681b] rhashtable: Cap total number of entries to 2^31
git bisect good 4b726e81dab2cefefd2c1cb00e3074ac0eec4b15 # 10:48 G 31 0 0 0 tcp: tcp_rack_reo_timeout() must update tp->tcp_mstamp
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad 6d684e54690caef45cf14051ddeb7c71beeb681b # 11:03 B 0 5 15 0 rhashtable: Cap total number of entries to 2^31
# extra tests on HEAD of linux-devel/devel-catchup-201704280512
git bisect bad d1c34825a799a7ba4693bf46fbc79238aed374e6 # 11:08 B 0 17 30 0 0day head guard for 'devel-catchup-201704280512'
# extra tests on tree/branch net-next/master
git bisect bad e221c1f0fe2590042b4f8c3a8ccccd8e7bed2c31 # 11:30 B 0 6 16 0 Merge tag 'wireless-drivers-next-for-davem-2017-04-27' of git://git.kernel.org/pub/scm/linux/kernel/git/kvalo/wireless-drivers-next
# extra tests with first bad commit reverted
git bisect good 4c6425d9630c7a235c8a6144aeac0dd5449cc903 # 11:48 G 11 0 0 0 Revert "rhashtable: Cap total number of entries to 2^31"
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
2 years, 10 months
[lkp-robot] [x86, tsc] 3d2d1b9d2d: INFO:rcu_preempt_detected_stalls_on_CPUs/tasks
by kernel test robot
FYI, we noticed the following commit:
commit: 3d2d1b9d2d2fcd875a0834cbd44b208ac3695c91 ("x86,tsc: Feed refined TSC calibration into sched_clock")
https://git.kernel.org/cgit/linux/kernel/git/peterz/queue.git sched/core
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-i386 -enable-kvm -smp 2 -m 320M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+-----------------------------------------------------------------------------------+------------+------------+
| | f1844e5954 | 3d2d1b9d2d |
+-----------------------------------------------------------------------------------+------------+------------+
| boot_successes | 30 | 4 |
| boot_failures | 2 | 30 |
| invoked_oom-killer:gfp_mask=0x | 2 | 4 |
| Mem-Info | 2 | 4 |
| Kernel_panic-not_syncing:Out_of_memory_and_no_killable_processes | 2 | 4 |
| INFO:rcu_preempt_detected_stalls_on_CPUs/tasks | 0 | 9 |
| EIP:set_cyc2ns_scale | 0 | 7 |
| INFO:rcu_sched_detected_expedited_stalls_on_CPUs/tasks:{#-...}#jiffies_s:#root:#/ | 0 | 24 |
| BUG:kernel_hang_in_boot_stage | 0 | 26 |
| EIP:native_safe_halt | 0 | 2 |
+-----------------------------------------------------------------------------------+------------+------------+
[ 102.303164] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 102.303164] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 102.306476] (detected by 1, t=100002 jiffies, g=-100, c=-101, q=23)
[ 102.306476] (detected by 1, t=100002 jiffies, g=-100, c=-101, q=23)
[ 102.308165] All QSes seen, last rcu_preempt kthread activity 100002 (-197791--297793), jiffies_till_next_fqs=3, root ->qsmask 0x0
[ 102.308165] All QSes seen, last rcu_preempt kthread activity 100002 (-197791--297793), jiffies_till_next_fqs=3, root ->qsmask 0x0
[ 102.312118] swapper/1 R running task 0 0 1 0x00000000
[ 102.312118] swapper/1 R running task 0 0 1 0x00000000
[ 102.315817] Call Trace:
[ 102.315817] Call Trace:
[ 102.317684] sched_show_task+0xc5/0xd6
[ 102.317684] sched_show_task+0xc5/0xd6
[ 102.318621] rcu_check_callbacks+0x598/0x717
[ 102.318621] rcu_check_callbacks+0x598/0x717
[ 102.319904] update_process_times+0x28/0x4a
[ 102.319904] update_process_times+0x28/0x4a
[ 102.325452] tick_nohz_handler+0x95/0xd6
[ 102.325452] tick_nohz_handler+0x95/0xd6
[ 102.326426] local_apic_timer_interrupt+0x40/0x45
[ 102.326426] local_apic_timer_interrupt+0x40/0x45
[ 102.327668] smp_apic_timer_interrupt+0x33/0x42
[ 102.327668] smp_apic_timer_interrupt+0x33/0x42
[ 102.328776] apic_timer_interrupt+0x37/0x3c
[ 102.328776] apic_timer_interrupt+0x37/0x3c
[ 102.337498] EIP: native_safe_halt+0x5/0x7
[ 102.337498] EIP: native_safe_halt+0x5/0x7
[ 102.338509] EFLAGS: 00200246 CPU: 1
[ 102.338509] EFLAGS: 00200246 CPU: 1
[ 102.341545] EAX: 00000001 EBX: c668c840 ECX: 01000000 EDX: c17ad179
[ 102.341545] EAX: 00000001 EBX: c668c840 ECX: 01000000 EDX: c17ad179
[ 102.343252] ESI: 00000001 EDI: c66c1fb4 EBP: c66c1f4c ESP: c66c1f4c
[ 102.343252] ESI: 00000001 EDI: c66c1fb4 EBP: c66c1f4c ESP: c66c1f4c
[ 102.346251] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 102.346251] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 102.352681] default_idle+0x5a/0xd9
[ 102.352681] default_idle+0x5a/0xd9
[ 102.353511] arch_cpu_idle+0xe/0x10
[ 102.353511] arch_cpu_idle+0xe/0x10
[ 102.355846] default_idle_call+0x30/0x37
[ 102.355846] default_idle_call+0x30/0x37
[ 102.356951] do_idle+0x8f/0xfb
[ 102.356951] do_idle+0x8f/0xfb
[ 102.357664] cpu_startup_entry+0x1e/0x20
[ 102.357664] cpu_startup_entry+0x1e/0x20
[ 102.360040] start_secondary+0x11f/0x143
[ 102.360040] start_secondary+0x11f/0x143
[ 102.362676] startup_32_smp+0x16b/0x16d
[ 102.362676] startup_32_smp+0x16b/0x16d
[ 102.363583] ? startup_32_smp+0x16b/0x16d
[ 102.363583] ? startup_32_smp+0x16b/0x16d
[ 102.366855] rcu_preempt kthread starved for 100002 jiffies! g4294967196 c4294967195 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 102.366855] rcu_preempt kthread starved for 100002 jiffies! g4294967196 c4294967195 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 102.371855] rcu_preempt W 0 8 2 0x00000000
[ 102.371855] rcu_preempt W 0 8 2 0x00000000
[ 102.375186] Call Trace:
[ 102.375186] Call Trace:
[ 102.377102] __schedule+0x29a/0x512
[ 102.377102] __schedule+0x29a/0x512
[ 102.377935] schedule+0x78/0x8b
[ 102.377935] schedule+0x78/0x8b
[ 102.380544] schedule_timeout+0x225/0x26d
[ 102.380544] schedule_timeout+0x225/0x26d
[ 102.381738] ? del_timer_sync+0x41/0x41
[ 102.381738] ? del_timer_sync+0x41/0x41
[ 102.384186] rcu_gp_kthread+0x46a/0x7b8
[ 102.384186] rcu_gp_kthread+0x46a/0x7b8
[ 102.385095] kthread+0xf8/0xfd
[ 102.385095] kthread+0xf8/0xfd
[ 102.388478] ? force_qs_rnp+0x169/0x169
[ 102.388478] ? force_qs_rnp+0x169/0x169
[ 102.391622] ? kthread_create_on_node+0x1f/0x1f
[ 102.391622] ? kthread_create_on_node+0x1f/0x1f
[ 102.394154] ret_from_fork+0x21/0x2c
[ 102.394154] ret_from_fork+0x21/0x2c
[ 123.966184] INFO: rcu_sched detected expedited stalls on CPUs/tasks: { 0-... } 102399 jiffies s: 5 root: 0x1/.
[ 123.966184] INFO: rcu_sched detected expedited stalls on CPUs/tasks: { 0-... } 102399 jiffies s: 5 root: 0x1/.
[ 123.969178] blocking rcu_node structures:
[ 123.969178] blocking rcu_node structures:
[ 123.970732] Task dump for CPU 0:
[ 123.970732] Task dump for CPU 0:
[ 123.971986] kworker/0:1 R running task 0 20 2 0x00000000
[ 123.971986] kworker/0:1 R running task 0 20 2 0x00000000
[ 123.978939] Workqueue: events tsc_refine_calibration_work
[ 123.978939] Workqueue: events tsc_refine_calibration_work
[ 123.982792] Call Trace:
[ 123.982792] Call Trace:
[ 123.988528] ? process_one_work+0x18a/0x31a
[ 123.988528] ? process_one_work+0x18a/0x31a
[ 123.991864] ? worker_thread+0x236/0x30e
[ 123.991864] ? worker_thread+0x236/0x30e
[ 123.998041] ? kthread+0xf8/0xfd
[ 123.998041] ? kthread+0xf8/0xfd
[ 123.999264] ? rescuer_thread+0x26d/0x26d
[ 123.999264] ? rescuer_thread+0x26d/0x26d
[ 124.004894] ? kthread_create_on_node+0x1f/0x1f
[ 124.004894] ? kthread_create_on_node+0x1f/0x1f
[ 124.006744] ? ret_from_fork+0x21/0x2c
[ 124.006744] ? ret_from_fork+0x21/0x2c
[ 402.308172] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 402.308172] INFO: rcu_preempt detected stalls on CPUs/tasks:
[ 402.310307] (detected by 1, t=400007 jiffies, g=-100, c=-101, q=23)
[ 402.310307] (detected by 1, t=400007 jiffies, g=-100, c=-101, q=23)
[ 402.311803] All QSes seen, last rcu_preempt kthread activity 400007 (102214--297793), jiffies_till_next_fqs=3, root ->qsmask 0x0
[ 402.311803] All QSes seen, last rcu_preempt kthread activity 400007 (102214--297793), jiffies_till_next_fqs=3, root ->qsmask 0x0
[ 402.314481] swapper/1 R running task 0 0 1 0x00000000
[ 402.314481] swapper/1 R running task 0 0 1 0x00000000
[ 402.316303] Call Trace:
[ 402.316303] Call Trace:
[ 402.316886] sched_show_task+0xc5/0xd6
[ 402.316886] sched_show_task+0xc5/0xd6
[ 402.317775] rcu_check_callbacks+0x598/0x717
[ 402.317775] rcu_check_callbacks+0x598/0x717
[ 402.318778] update_process_times+0x28/0x4a
[ 402.318778] update_process_times+0x28/0x4a
[ 402.319763] tick_nohz_handler+0x95/0xd6
[ 402.319763] tick_nohz_handler+0x95/0xd6
[ 402.320696] local_apic_timer_interrupt+0x40/0x45
[ 402.320696] local_apic_timer_interrupt+0x40/0x45
[ 402.322024] smp_apic_timer_interrupt+0x33/0x42
[ 402.322024] smp_apic_timer_interrupt+0x33/0x42
[ 402.323287] apic_timer_interrupt+0x37/0x3c
[ 402.323287] apic_timer_interrupt+0x37/0x3c
[ 402.324459] EIP: native_safe_halt+0x5/0x7
[ 402.324459] EIP: native_safe_halt+0x5/0x7
[ 402.325574] EFLAGS: 00200246 CPU: 1
[ 402.325574] EFLAGS: 00200246 CPU: 1
[ 402.326527] EAX: 00000001 EBX: c668c840 ECX: 01000000 EDX: c17ad179
[ 402.326527] EAX: 00000001 EBX: c668c840 ECX: 01000000 EDX: c17ad179
[ 402.328262] ESI: 00000001 EDI: c66c1fb4 EBP: c66c1f4c ESP: c66c1f4c
[ 402.328262] ESI: 00000001 EDI: c66c1fb4 EBP: c66c1f4c ESP: c66c1f4c
[ 402.329973] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 402.329973] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 402.331483] default_idle+0x5a/0xd9
[ 402.331483] default_idle+0x5a/0xd9
[ 402.332462] arch_cpu_idle+0xe/0x10
[ 402.332462] arch_cpu_idle+0xe/0x10
[ 402.333441] default_idle_call+0x30/0x37
[ 402.333441] default_idle_call+0x30/0x37
[ 402.335061] do_idle+0x8f/0xfb
[ 402.335061] do_idle+0x8f/0xfb
[ 402.335893] cpu_startup_entry+0x1e/0x20
[ 402.335893] cpu_startup_entry+0x1e/0x20
[ 402.337026] start_secondary+0x11f/0x143
[ 402.337026] start_secondary+0x11f/0x143
[ 402.339020] startup_32_smp+0x16b/0x16d
[ 402.339020] startup_32_smp+0x16b/0x16d
[ 402.340127] ? startup_32_smp+0x16b/0x16d
[ 402.340127] ? startup_32_smp+0x16b/0x16d
[ 402.341246] rcu_preempt kthread starved for 400007 jiffies! g4294967196 c4294967195 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 402.341246] rcu_preempt kthread starved for 400007 jiffies! g4294967196 c4294967195 f0x2 RCU_GP_WAIT_FQS(3) ->state=0x100
[ 402.344284] rcu_preempt W 0 8 2 0x00000000
[ 402.344284] rcu_preempt W 0 8 2 0x00000000
[ 402.345775] Call Trace:
[ 402.345775] Call Trace:
[ 402.346482] __schedule+0x29a/0x512
[ 402.346482] __schedule+0x29a/0x512
[ 402.347467] schedule+0x78/0x8b
[ 402.347467] schedule+0x78/0x8b
[ 402.348338] schedule_timeout+0x225/0x26d
[ 402.348338] schedule_timeout+0x225/0x26d
[ 402.349434] ? del_timer_sync+0x41/0x41
[ 402.349434] ? del_timer_sync+0x41/0x41
[ 402.350510] rcu_gp_kthread+0x46a/0x7b8
[ 402.350510] rcu_gp_kthread+0x46a/0x7b8
[ 402.351576] kthread+0xf8/0xfd
[ 402.351576] kthread+0xf8/0xfd
[ 402.352406] ? force_qs_rnp+0x169/0x169
[ 402.352406] ? force_qs_rnp+0x169/0x169
[ 402.353493] ? kthread_create_on_node+0x1f/0x1f
[ 402.353493] ? kthread_create_on_node+0x1f/0x1f
[ 402.354716] ret_from_fork+0x21/0x2c
[ 402.354716] ret_from_fork+0x21/0x2c
[ 431.166819] INFO: rcu_sched detected expedited stalls on CPUs/tasks: { 0-... } 409599 jiffies s: 5 root: 0x1/.
[ 431.166819] INFO: rcu_sched detected expedited stalls on CPUs/tasks: { 0-... } 409599 jiffies s: 5 root: 0x1/.
[ 431.173597] blocking rcu_node structures:
[ 431.173597] blocking rcu_node structures:
[ 431.174757] Task dump for CPU 0:
[ 431.174757] Task dump for CPU 0:
[ 431.179513] kworker/0:1 R running task 0 20 2 0x00000000
[ 431.179513] kworker/0:1 R running task 0 20 2 0x00000000
[ 431.182360] Workqueue: events tsc_refine_calibration_work
[ 431.182360] Workqueue: events tsc_refine_calibration_work
[ 431.183888] Call Trace:
[ 431.183888] Call Trace:
[ 431.184594] ? process_one_work+0x18a/0x31a
[ 431.184594] ? process_one_work+0x18a/0x31a
[ 431.187524] ? worker_thread+0x236/0x30e
[ 431.187524] ? worker_thread+0x236/0x30e
[ 431.190252] ? kthread+0xf8/0xfd
[ 431.190252] ? kthread+0xf8/0xfd
[ 431.191145] ? rescuer_thread+0x26d/0x26d
[ 431.191145] ? rescuer_thread+0x26d/0x26d
[ 431.192968] ? kthread_create_on_node+0x1f/0x1f
[ 431.192968] ? kthread_create_on_node+0x1f/0x1f
[ 431.194159] ? ret_from_fork+0x21/0x2c
[ 431.194159] ? ret_from_fork+0x21/0x2c
Elapsed time: 470
BUG: kernel hang in boot stage
initrds=(
/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
/lkp/scheduled/vm-ivb41-yocto-i386-8/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-3d2d1b9d2d2fcd875a0834cbd44b208ac3695c91-20170427-25062-1vyakhz-29.cgz
/lkp/lkp/lkp-i386.cgz
/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig.i386_2016-09-03.cgz
/osimage/pkg/static/trinity-i386.cgz
/pkg/linux/i386-randconfig-b0-04270810/gcc-5/3d2d1b9d2d2fcd875a0834cbd44b208ac3695c91/modules.cgz
)
cat "${initrds[@]}" > /fs/sda5/initrd-vm-ivb41-yocto-i386-8
kvm=(
qemu-system-i386
-enable-kvm
-kernel /pkg/linux/i386-randconfig-b0-04270810/gcc-5/3d2d1b9d2d2fcd875a0834cbd44b208ac3695c91/vmlinuz-4.11.0-rc6-00038-g3d2d1b9
-initrd /fs/sda5/initrd-vm-ivb41-yocto-i386-8
-m 320
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-watchdog-action debug
-rtc base=localtime
-drive file=/fs/sda5/disk0-vm-ivb41-yocto-i386-8,media=disk,if=virtio
-pidfile /dev/shm/kboot/pid-vm-ivb41-yocto-i386-8
-serial file:/dev/shm/kboot/vm-ivb41-yocto-i386-8/serial
-serial file:/dev/shm/kboot/vm-ivb41-yocto-i386-8/kmsg
-daemonize
-display none
-monitor null
)
append=(
ip=::::vm-ivb41-yocto-i386-8::dhcp
root=/dev/ram0
user=lkp
job=/lkp/scheduled/vm-ivb41-yocto-i386-8/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-3d2d1b9d2d2fcd875a0834cbd44b208ac3695c91-20170427-25062-1vyakhz-29.yaml
ARCH=i386
kconfig=i386-randconfig-b0-04270810
branch=linux-devel/devel-spot-201704270425
commit=3d2d1b9d2d2fcd875a0834cbd44b208ac3695c91
BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-04270810/gcc-5/3d2d1b9d2d2fcd875a0834cbd44b208ac3695c91/vmlinuz-4.11.0-rc6-00038-g3d2d1b9
max_uptime=1500
RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-b0-04270810/gcc-5/3d2d1b9d2d2fcd875a0834cbd44b208ac3695c91/29
LKP_SERVER=inn
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
net.ifnames=0
printk.devkmsg=on
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
drbd.minor_count=8
systemd.log_level=err
ignore_loglevel
earlyprintk=ttyS0,115200
console=ttyS0,115200
console=tty0
vga=normal
rw
drbd.minor_count=8
)
"${kvm[@]}" -append "${append[*]}"
To reproduce:
git clone https://github.com/01org/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
2 years, 10 months