[lkp-developer] [sched] db4ea8c107: general_protection_fault:#[##]SMP_KASAN
by kernel test robot
FYI, we noticed the following commit:
commit: db4ea8c10708f3879f80c9822775bc72f3391b41 ("sched: force update of blocked load of idle cpus")
git://internal_merge_and_test_tree devel-catchup-201612230208
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -smp 2 -m 512M
caused below changes:
+-------------------------------------------------------+------------+------------+
| | 6b94780e45 | db4ea8c107 |
+-------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 6 | 4 |
| BUG:key_not_in.data | 6 | |
| WARNING:at_kernel/locking/lockdep.c:#lockdep_init_map | 6 | |
| calltrace:vhci_hcd_init | 6 | |
| general_protection_fault:#[##]SMP_KASAN | 0 | 4 |
| RIP:run_rebalance_domains | 0 | 4 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 4 |
+-------------------------------------------------------+------------+------------+
[ 7.489496] clocksource: Switched to clocksource kvm-clock
[ 8.319602] kasan: CONFIG_KASAN_INLINE enabled
[ 8.326177] kasan: GPF could be caused by NULL-ptr deref or user memory access
[ 8.346329] general protection fault: 0000 [#1] SMP KASAN
[ 8.353824] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.9.0-rc8-00180-gdb4ea8c #1
[ 8.374304] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 8.395966] task: ffff880000389480 task.stack: ffff880000398000
[ 8.405112] RIP: 0010:[<ffffffff81184c95>] [<ffffffff81184c95>] run_rebalance_domains+0x18d/0x5d6
[ 8.420303] RSP: 0000:ffff880018d07ec0 EFLAGS: 00010206
[ 8.428279] RAX: dffffc0000000000 RBX: ffffffff83c09000 RCX: 0000000000000005
[ 8.440013] RDX: dffffc0000000000 RSI: 000000004f45a5d9 RDI: 0000000000000028
[ 8.451672] RBP: ffff880018d07f38 R08: 0000000000000000 R09: 0000000000000000
[ 8.463146] R10: ffff8800183978ef R11: ffffed0003072f1d R12: 0000000000000000
[ 8.474625] R13: ffffffff83b853e8 R14: 0000000000000001 R15: ffff880018d1fac0
[ 8.486385] FS: 0000000000000000(0000) GS:ffff880018d00000(0000) knlGS:0000000000000000
[ 8.499287] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 8.509007] CR2: 00000000ffffffff CR3: 0000000003c0e000 CR4: 00000000000006e0
[ 8.520279] Stack:
[ 8.523515] ffffffff81071467 dffffc0000000000 fffffbfff09e1e45 0000000084f0f230
[ 8.536878] ffffffff811eecfd ffffffff84f0f228 ffffffffffffffff 00000000fffea5c8
[ 8.550090] 0000000118d07f60 000000000001fac0 dffffc0000000000 0000000000000080
[ 8.562414] Call Trace:
[ 8.567270] <IRQ>
[ 8.570645] [<ffffffff81071467>] ? kvm_clock_read+0x2e/0x2e
[ 8.580396] [<ffffffff811eecfd>] ? clocksource_delta+0x34/0x3e
[ 8.589403] [<ffffffff83040731>] __do_softirq+0x231/0x4e8
[ 8.599257] [<ffffffff8111490b>] irq_exit+0x7a/0x1d4
[ 8.607318] [<ffffffff8105dc11>] smp_reschedule_interrupt+0x24/0x26
[ 8.618021] [<ffffffff8303fd24>] reschedule_interrupt+0x84/0x90
[ 8.628230] <EOI>
[ 8.631601] [<ffffffff8303d3b9>] ? native_safe_halt+0x6/0x8
[ 8.641093] [<ffffffff8303d0a7>] default_idle+0x1f/0x32
[ 8.649198] [<ffffffff810397b5>] arch_cpu_idle+0xf/0x11
[ 8.658688] [<ffffffff8303d46b>] default_idle_call+0x1e/0x20
[ 8.667630] [<ffffffff81195db3>] cpu_startup_entry+0x1b2/0x2b4
[ 8.677513] [<ffffffff81195c01>] ? cpu_in_idle+0x25/0x25
[ 8.686723] [<ffffffff811fd4b8>] ? clockevents_config_and_register+0x88/0x91
[ 8.698485] [<ffffffff8105fd88>] start_secondary+0x28e/0x2b6
[ 8.707487] [<ffffffff8105fafa>] ? set_cpu_sibling_map+0xdf8/0xdf8
[ 8.717766] Code: c1 ea 03 80 3c 02 00 74 08 4c 89 e7 e8 cd 8e 20 00 4d 8b 24 24 48 ba 00 00 00 00 00 fc ff df 49 8d 7c 24 28 48 89 f9 48 c1 e9 03 <8a> 0c 11 48 89 fa 83 e2 07 83 c2 03 38 ca 7c 09 84 c9 74 05 e8
[ 8.764755] RIP [<ffffffff81184c95>] run_rebalance_domains+0x18d/0x5d6
[ 8.775188] RSP <ffff880018d07ec0>
[ 8.782191] ---[ end trace b7890bb32f0d091d ]---
[ 8.788958] VFS: Disk quotas dquot_6.6.0
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
5 years, 7 months
[idr] d13fbbcb97 WARNING: CPU: 0 PID: 1 at lib/idr.c:327 ida_remove
by Fengguang Wu
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://git.infradead.org/users/willy/linux-dax.git idr-2016-12-16
commit d13fbbcb97c36334c73c8c67b96c895381563d01
Author: Matthew Wilcox <mawilcox(a)microsoft.com>
AuthorDate: Sat Dec 17 17:32:00 2016 -0500
Commit: Matthew Wilcox <mawilcox(a)microsoft.com>
CommitDate: Sat Dec 17 17:32:00 2016 -0500
idr: Handle exceptional entries anywhere
My original idea was to only use an exceptional entry at the root of the
tree, and to expand the entry into a bitmap whenever any bit anywhere
beyond BITS_PER_LONG - 2 was set. But that's more complex than just
supporting exceptional entries anywhere in the tree, as the new test
case demonstrates. Also, the new code never fails to allocate an IDA
where the previous one could require more memory to be allocated. I'll
merge this with the previous patch before submission.
Signed-off-by: Matthew Wilcox <mawilcox(a)microsoft.com>
+----------------------------------+------------+------------+------------+
| | 3d145dc3ef | d13fbbcb97 | d13fbbcb97 |
+----------------------------------+------------+------------+------------+
| boot_successes | 63 | 0 | 0 |
| boot_failures | 0 | 13 | 13 |
| WARNING:at_lib/idr.c:#ida_remove | 0 | 13 | 13 |
+----------------------------------+------------+------------+------------+
[ 0.937484] ACPI: bus type PCI registered
[ 0.938160] PCI: Using configuration type 1 for base access
[ 0.961748] ------------[ cut here ]------------
[ 0.962357] WARNING: CPU: 0 PID: 1 at lib/idr.c:327 ida_remove+0xd2/0x120
[ 0.963362] ida_remove called for id=1026 which is not allocated.
[ 0.964173] Modules linked in:
[ 0.964617] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.9.0-rc8-mm1-00334-gd13fbbc #169
[ 0.965705] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 0.985103] Call Trace:
[ 0.985471] dump_stack+0x58/0x79
[ 0.985961] __warn+0xf5/0x110
[ 0.986430] ? ida_remove+0xd2/0x120
git bisect start d13fbbcb97c36334c73c8c67b96c895381563d01 026142ac445639730830629002e24a7083a2763c --
git bisect good 4cfaeffbcf091d1ef08bb7f1eb6bb4d89d1c60aa # 20:13 22+ 0 ida: Move ida_bitmap to a percpu variable
git bisect good bbf727264b396b28c3c9143d56be93bfe334c609 # 20:22 21+ 0 radix tree test suite: Reduce kernel.h
git bisect good 57b4ccae27adaed954f03e3323bc20e0493c0f00 # 20:32 22+ 0 radix tree test suite: Remove bitops.h
git bisect good 217a1be9f02affd94610f749ea2e988ac9242493 # 20:44 22+ 0 radix tree test suite: More decluttering kernel.h
git bisect good 3d145dc3ef3317f0fd7270a95d76aebbcf7e1eac # 20:54 22+ 0 ida: Use exceptional entries for small IDAs
# first bad commit: [d13fbbcb97c36334c73c8c67b96c895381563d01] idr: Handle exceptional entries anywhere
git bisect good 3d145dc3ef3317f0fd7270a95d76aebbcf7e1eac # 20:55 60+ 0 ida: Use exceptional entries for small IDAs
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad d13fbbcb97c36334c73c8c67b96c895381563d01 # 21:04 0- 44 idr: Handle exceptional entries anywhere
# extra tests on HEAD of dax/idr-2016-12-16
git bisect bad d13fbbcb97c36334c73c8c67b96c895381563d01 # 21:04 0- 13 idr: Handle exceptional entries anywhere
# extra tests on tree/branch dax/idr-2016-12-16
git bisect bad d13fbbcb97c36334c73c8c67b96c895381563d01 # 21:04 0- 13 idr: Handle exceptional entries anywhere
# extra tests with first bad commit reverted
git bisect good db0520019ffe348e50259bc311f526e40f7a9db3 # 21:16 65+ 0 Revert "idr: Handle exceptional entries anywhere"
# extra tests on tree/branch linus/master
git bisect good 52f40e9d657cc126b766304a5dd58ad73b02ff46 # 21:19 61+ 9 Merge git://git.kernel.org/pub/scm/linux/kernel/git/davem/net
# extra tests on tree/branch linux-next/master
git bisect good bf579a3afa46c74b7e89930974ba119d4c76bab2 # 21:27 66+ 10 Add linux-next specific files for 20161216
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
5 years, 7 months
[lkp-developer] [mm, memcg] d18e2b2aca: WARNING:at_mm/memcontrol.c:#mem_cgroup_update_lru_size
by kernel test robot
FYI, we noticed the following commit:
commit: d18e2b2aca0396849f588241e134787a829c707d ("mm, memcg: fix (Re: OOM: Better, but still there on)")
url: https://github.com/0day-ci/linux/commits/Michal-Hocko/mm-memcg-fix-Re-OOM...
base: git://git.cmpxchg.org/linux-mmotm.git master
in testcase: boot
on test machine: qemu-system-i386 -enable-kvm -m 360M
caused below changes:
+--------------------------------------------------------+------------+------------+
| | c7d85b880b | d18e2b2aca |
+--------------------------------------------------------+------------+------------+
| boot_successes | 8 | 0 |
| boot_failures | 0 | 2 |
| WARNING:at_mm/memcontrol.c:#mem_cgroup_update_lru_size | 0 | 2 |
| kernel_BUG_at_mm/memcontrol.c | 0 | 2 |
| invalid_opcode:#[##]DEBUG_PAGEALLOC | 0 | 2 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 2 |
+--------------------------------------------------------+------------+------------+
[ 95.226364] init: tty6 main process (990) killed by TERM signal
[ 95.314020] init: plymouth-upstart-bridge main process (1039) terminated with status 1
[ 97.588568] ------------[ cut here ]------------
[ 97.594364] WARNING: CPU: 0 PID: 1055 at mm/memcontrol.c:1032 mem_cgroup_update_lru_size+0xdd/0x12b
[ 97.606654] mem_cgroup_update_lru_size(40297f00, 0, -1): lru_size 1 but empty
[ 97.615140] Modules linked in:
[ 97.618834] CPU: 0 PID: 1055 Comm: killall5 Not tainted 4.9.0-mm1-00095-gd18e2b2 #82
[ 97.628008] Call Trace:
[ 97.631025] dump_stack+0x16/0x18
[ 97.635107] __warn+0xaf/0xc6
[ 97.638729] ? mem_cgroup_update_lru_size+0xdd/0x12b
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
5 years, 7 months
[lkp-developer] [mm] 66a6197c11: vm-scalability.throughput 90.3% improvement
by kernel test robot
Greeting,
FYI, we noticed a 90.3% improvement of vm-scalability.throughput due to commit:
commit: 66a6197c118540d454913eef24d68d7491ab5d5f ("mm: provide helper for finishing mkwrite faults")
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: vm-scalability
on test machine: 88 threads Intel(R) Xeon(R) CPU E5-2699 v4 @ 2.20GHz with 128G memory
with following parameters:
runtime: 300s
size: 1T
test: msync-mt
cpufreq_governor: performance
test-description: The motivation behind this suite is to exercise functions and regions of the mm/ of the Linux kernel which are of interest to us.
test-url: https://git.kernel.org/cgit/linux/kernel/git/wfg/vm-scalability.git/
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
testcase/path_params/tbox_group/run: vm-scalability/300s-1T-msync-mt-performance/lkp-bdw-ep2
997dd98dd68beb2a 66a6197c118540d454913eef24
---------------- --------------------------
%stddev change %stddev
\ | \
0.00 ± 53% 282% 0.01 ± 94% vm-scalability.stddev
6727484 90% 12800442 vm-scalability.throughput
4.852e+08 29% 6.237e+08 interrupts.CAL:Function_call_interrupts
48016 144% 116970 vmstat.io.bo
612808 24% 758937 vmstat.system.in
556800 20% 666097 vmstat.system.cs
613 53% 941 turbostat.Avg_MHz
21.98 53% 33.70 turbostat.%Busy
166 ± 4% 11% 184 turbostat.PkgWatt
67.29 7% 71.73 turbostat.RAMWatt
42508120 ± 5% 9e+07 1.351e+08 ± 3% latency_stats.sum.wait_on_page_bit.__migration_entry_wait.migration_entry_wait.do_swap_page.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
4971400 ± 11% 9e+07 94614270 ± 7% latency_stats.sum.call_rwsem_down_write_failed.xfs_ilock.xfs_vn_update_time.file_update_time.xfs_filemap_page_mkwrite.do_page_mkwrite.do_wp_page.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
2932983 ± 11% 5e+07 56035780 ± 7% latency_stats.sum.call_rwsem_down_write_failed.xfs_ilock.xfs_file_iomap_begin_delay.xfs_file_iomap_begin.iomap_apply.iomap_page_mkwrite.xfs_filemap_page_mkwrite.do_page_mkwrite.do_wp_page.handle_mm_fault.__do_page_fault.do_page_fault
429 ± 55% 6e+04 59266 ± 16% latency_stats.sum.call_rwsem_down_write_failed.xfs_ilock.xfs_vn_update_time.file_update_time.xfs_filemap_page_mkwrite.do_page_mkwrite.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
112 ± 88% 3e+04 32879 ± 11% latency_stats.sum.call_rwsem_down_write_failed.xfs_ilock.xfs_file_iomap_begin_delay.xfs_file_iomap_begin.iomap_apply.iomap_page_mkwrite.xfs_filemap_page_mkwrite.do_page_mkwrite.handle_mm_fault.__do_page_fault.do_page_fault.page_fault
0 1e+04 11193 ± 58% latency_stats.sum.submit_bio_wait.blkdev_issue_flush.xfs_blkdev_issue_flush.xfs_file_fsync.vfs_fsync_range.SyS_msync.entry_SYSCALL_64_fastpath
1.857e+10 94% 3.607e+10 perf-stat.node-store-misses
2.475e+11 88% 4.654e+11 perf-stat.cache-references
6.691e+10 74% 1.164e+11 perf-stat.cache-misses
6.154e+12 71% 1.055e+13 perf-stat.branch-instructions
2.46e+13 67% 4.12e+13 perf-stat.instructions
5.878e+12 62% 9.493e+12 perf-stat.dTLB-loads
44015 57% 68958 ± 8% perf-stat.instructions-per-iTLB-miss
4.973e+13 55% 7.717e+13 perf-stat.cpu-cycles
1.721e+12 54% 2.644e+12 perf-stat.dTLB-stores
2.403e+09 52% 3.657e+09 perf-stat.dTLB-store-misses
3.92e+08 ± 4% 46% 5.729e+08 ± 13% perf-stat.node-loads
10963820 39% 15208401 ± 4% perf-stat.cpu-migrations
1.336e+09 25% 1.673e+09 ± 6% perf-stat.dTLB-load-misses
6.689e+09 25% 8.346e+09 perf-stat.iTLB-loads
5.175e+08 20% 6.233e+08 perf-stat.context-switches
3.556e+09 19% 4.223e+09 perf-stat.node-stores
1.307e+10 15% 1.508e+10 perf-stat.branch-misses
1.449e+10 10% 1.591e+10 perf-stat.node-load-misses
0.49 8% 0.53 perf-stat.ipc
5.589e+08 8% 6.022e+08 ± 9% perf-stat.iTLB-load-misses
83.93 7% 89.52 perf-stat.node-store-miss-rate%
27.04 -8% 25.00 perf-stat.cache-miss-rate%
7.71 -13% 6.73 ± 8% perf-stat.iTLB-load-miss-rate%
4.593e+08 -16% 3.878e+08 perf-stat.minor-faults
4.593e+08 -16% 3.878e+08 perf-stat.page-faults
0.02 -22% 0.02 ± 5% perf-stat.dTLB-load-miss-rate%
0.21 -33% 0.14 perf-stat.branch-miss-rate%
perf-stat.instructions
4.4e+13 ++----------------------------------------------------------------+
4.2e+13 ++O O O O O O O O |
O O O O O O O O O O O O O O O O O O O |
4e+13 ++ |
3.8e+13 ++ |
| |
3.6e+13 ++ |
3.4e+13 ++ |
3.2e+13 ++ |
| |
3e+13 ++ |
2.8e+13 ++ |
*.*.*.*.* *.*.*.*.*.*.*.*.*.* |
2.6e+13 ++ + + + .*. .*. |
2.4e+13 ++--------*-*-*--*-*-*-*-*---------------------*--*---*-*-*---*-*-*
perf-stat.cache-references
5.5e+11 ++----------------------------------------------------------------+
| |
5e+11 ++O O O O O |
O O O O O O O O O O O O O |
4.5e+11 ++ O O O O O O O O O |
| |
4e+11 ++ |
| |
3.5e+11 ++ |
| |
3e+11 *+*.*.*.* *.*.*.*.*.*.*.*.*.* |
| + + + |
2.5e+11 ++ *.*.*..*.*. .*.* *..*.*.*.*.*.*.*.*.*
| * |
2e+11 ++----------------------------------------------------------------+
perf-stat.cache-misses
1.4e+11 ++----------------------------------------------------------------+
| |
1.2e+11 O+O O O O O O O O O O O O O O O O O O O O O |
| O O O O O |
1e+11 ++ |
| |
8e+10 ++ |
| .*. .*.*. .* .*.*.*.*.*.*.*.*
6e+10 ++ * *. *.* : *..* |
| : : : |
4e+10 ++ : : : |
| : : : |
2e+10 ++ : : : |
*.*.*.*.* *.*.*.*.*.*.*.*.*.* |
0 ++----------------------------------------------------------------+
perf-stat.branch-instructions
1.2e+13 ++----------------------------------------------------------------+
| |
1.1e+13 ++O O O |
O O O O O O O O O O O O O O O O O O O |
| O O O O O |
1e+13 ++ |
| |
9e+12 ++ |
| |
8e+12 ++ |
| |
*.*.*.*. *.*.*.*. .*.*.*.*. |
7e+12 ++ * : * * |
| + : + |
6e+12 ++--------*-*-*--*-*-*-*-*---------------------*--*-*-*-*-*-*-*-*-*
perf-stat.dTLB-loads
1e+13 ++----------------------------------------------------------------+
O O O O O O O O O O O O O O O O O O O |
9.5e+12 ++ O O O O O O O O |
9e+12 ++ |
| |
8.5e+12 ++ |
8e+12 ++ |
| |
7.5e+12 ++ |
7e+12 ++ |
| |
6.5e+12 *+*.*.*. *.*.*.*. .*.*.*.*. |
6e+12 ++ *. + * *. |
| *.*.*..*.*.*.*.* *..*.*.*.*.*.*.*.*.*
5.5e+12 ++----------------------------------------------------------------+
perf-stat.dTLB-stores
2.8e+12 ++------O-O-------------------------------------------------------+
| O O O O O O O O O |
2.6e+12 O+ O O O O O O O O O O O O O O O |
| |
| |
2.4e+12 ++ |
| |
2.2e+12 ++ |
| |
2e+12 ++ |
| |
*.*.*.*. *.*.*.*. .*.*.*.*. |
1.8e+12 ++ *. + * *. .*.*
| *.*.*..*.*.*.*.* *..*.*.*.*.*.*.* |
1.6e+12 ++----------------------------------------------------------------+
perf-stat.dTLB-store-misses
4.2e+09 ++----------------------------------------------------------------+
4e+09 ++ O O |
| O O O O |
3.8e+09 O+ O O O O O O O O |
3.6e+09 ++ O O O O O O O O |
| O O O O |
3.4e+09 ++ |
3.2e+09 ++ |
3e+09 ++ |
| |
2.8e+09 ++ |
2.6e+09 ++ |
| .*. .*
2.4e+09 ++ .*.*.*..*.*.*.*.*. .*..*.*.*.* *.*.* |
2.2e+09 *+*-*-*-*------------------*-*-*-*-*-*-*-*-*-*--------------------+
perf-stat.node-store-misses
4e+10 ++----------------------------------------------------------------+
O O O O O O O O O O O O O O O O O O O O |
3.5e+10 ++ O O O O O O O |
3e+10 ++ |
| |
2.5e+10 ++ |
| |
2e+10 ++ .*. .*.*
| *.*.*..*.*.*.*.* *..*.*.*.*.* * |
1.5e+10 ++ : : : |
1e+10 ++ : : : |
| : : : |
5e+09 ++ : : : |
| : : : |
0 *+*-*-*-*------------------*-*-*-*-*-*-*-*-*-*--------------------+
perf-stat.page-faults
4.7e+08 ++----*--------------------------*---------*----------------------+
4.6e+08 *+*.* *. .*.*.*.*.*.*.* *.*.*.* *. .*. .*.*
| *.*.*..* *..*. .*.* *.* |
4.5e+08 ++ * |
4.4e+08 ++ |
| |
4.3e+08 ++ |
4.2e+08 ++ |
4.1e+08 ++ |
| |
4e+08 ++ O O O O |
3.9e+08 O+O O O O O O O |
| O O O O O O O O |
3.8e+08 ++ O O O O O O O |
3.7e+08 ++----------------------------------------------------------------+
perf-stat.context-switches
6.4e+08 ++----------------------------------------------------------------+
| O O O O O |
6.2e+08 ++ O O O O O O O O O O |
6e+08 O+ O O O O O O O O O |
| O O |
5.8e+08 ++ |
| |
5.6e+08 ++ |
| |
5.4e+08 ++ |
5.2e+08 ++ .*. .*.*
| *.*.*..*.*.*.*.* *.. .*.* *.* |
5e+08 ++ : : : *.* |
|.*.*.*. : : .*.*.*. .*.*.*. : |
4.8e+08 *+------*------------------*-------*-*-------*--------------------+
perf-stat.minor-faults
4.7e+08 ++----*--------------------------*---------*----------------------+
4.6e+08 *+*.* *. .*.*.*.*.*.*.* *.*.*.* *. .*. .*.*
| *.*.*..* *..*. .*.* *.* |
4.5e+08 ++ * |
4.4e+08 ++ |
| |
4.3e+08 ++ |
4.2e+08 ++ |
4.1e+08 ++ |
| |
4e+08 ++ O O O O |
3.9e+08 O+O O O O O O O |
| O O O O O O O O |
3.8e+08 ++ O O O O O O O |
3.7e+08 ++----------------------------------------------------------------+
perf-stat.branch-miss-rate_
0.23 ++-------------------------------------------------------------------+
0.22 ++ .*. .*.* |
| .* *.*.*.*..* + .*. .*..*.*. .*.*.*.. .*.*.*
0.21 *+*.*.*. *.*.*..* *.*.*.* * * |
0.2 ++ |
0.19 ++ |
0.18 ++ |
| |
0.17 ++ |
0.16 ++ |
0.15 ++ |
0.14 ++ O O O O O O O O O O O O O O |
O O O O O O O O O O O |
0.13 ++O O |
0.12 ++-------------------------------------------------------------------+
perf-stat.node-store-miss-rate_
95 ++---------------------------------------------------------------------+
90 O+O O O O O O O O O O O O O O O O O O O O O O O O O O |
| |
85 ++ *.*..*.*.*.*.*..* *.*.*..*.*.*.*..*.*.*
80 ++ : : : |
75 ++ : : : |
70 ++ : : : |
| : : : |
65 ++ : : : |
60 ++ : : : |
55 ++ : : : |
50 ++ * : : * * : |
*. + +: *. + + .*. + +: |
45 ++*. + * *. + * *. + * |
40 ++--*----------------------------*----------*--------------------------+
turbostat.RAMWatt
74 ++O------O-------------------------------------------------------------+
72 O+ O O O O O O O O O O O O O |
| O O O O O O O O O O O |
70 ++ |
68 ++ .*. .*
| *.*..*.*.*.*.*..* *.*.*..* *.*..*.* |
66 ++ : : : |
64 ++ : : : |
62 ++ : : : |
| : : : |
60 ++ : : : |
58 ++*. : : *. *. : |
|+ *..*.* :+ *..*.*. + *..*.* |
56 *+ * * |
54 ++---------------------------------------------------------------------+
140000 ++---------------O-------------------------------------------------+
| |
120000 ++ |
| |
100000 ++ |
| |
80000 ++ O |
O O O |
60000 ++O O O O |
| O O O O O O O O |
40000 ++ O O O O O O O O O O |
| |
20000 ++ |
| |
0 *+*-*-*-*-*--*-*-*-*-*-*-*-*-*-*--*-*-*-*-*-*-*-*-*-*-*--*-*-*-*-*-*
vm-scalability.throughput
1.5e+07 ++----------------------------------------------------------------+
| O O |
1.4e+07 ++O O O O O |
1.3e+07 O+ O O O O O O O O O O O O O |
| O O O O O O |
1.2e+07 ++ |
1.1e+07 ++ |
| |
1e+07 ++ |
9e+06 ++ |
| |
8e+06 ++*. .*. .*. .*. .*. .*. |
7e+06 *+ * *. .* * *.* * *. |
| *.*.*..*.*.*.*.* *..*.*.*.*.*.*.*.*.*
6e+06 ++----------------------------------------------------------------+
interrupts.CAL:Function_call_interrupts
7e+08 ++----------------------------------------------------------------+
| |
6.5e+08 O+ O O O O O O O O O O O O O O |
6e+08 ++O O O O O O O O O O O |
| O |
5.5e+08 ++ |
5e+08 ++ |
| *.*.*..*.*.*.*.* *..*.*.*.*.*.*.*.*.*
4.5e+08 ++ : : : |
4e+08 ++ : : : |
| : : : |
3.5e+08 ++ : : : |
3e+08 *+*. .*.* *.*. .*.*.*.*. .*.* |
| * * * |
2.5e+08 ++----------------------------------------------------------------+
vmstat.io.bo
140000 ++-----------------------------------------------------------------+
130000 ++O O O |
| O O O O O O |
120000 O+ O O O O O O O O O O O |
110000 ++ O O O O O O |
| |
100000 ++ |
90000 ++ |
80000 ++ |
| |
70000 ++ |
60000 *+*.*.*.* *.*.*..*.*.*.*.*.*.* |
| + + + |
50000 ++ *..*.*.*.*.*.*.* *.*.*.*..*.*.*.*.*.*
40000 ++-----------------------------------------------------------------+
[*] 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
5 years, 7 months
[lkp-developer] [sched/core] f519a3f1c6: [No primary change] -5% ebizzy.throughput.per_thread.max
by kernel test robot
Greeting,
There is no primary kpi change in this test, below is the data collected through multiple monitors running background just for your information.
commit: f519a3f1c6b7a990e5aed37a8f853c6ecfdee945 ("sched/core: Fix find_idlest_group() for fork")
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: ebizzy
on test machine: 160 threads Intel(R) Xeon(R) CPU E7-8890 v4 @ 2.20GHz with 512G memory
with following parameters:
nr_threads: 200%
iterations: 100x
duration: 10s
cpufreq_governor: performance
test-description: ebizzy is designed to generate a workload resembling common web application server workloads.
test-url: http://ebizzy.sourceforge.net/
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
testcase/path_params/tbox_group/run: ebizzy/200%-100x-10s-performance/lkp-bdw-ex2
6643aab30f88e292 f519a3f1c6b7a990e5aed37a8f
---------------- --------------------------
2704 ± 4% 15% 3120 ± 3% ebizzy.throughput.per_thread.min
7900 -5% 7539 ebizzy.throughput.per_thread.max
114170 ±173% -4e+04 69344 ±100% latency_stats.avg.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_getattr.[nfsv4].nfs4_proc_getattr.[nfsv4].__nfs_revalidate_inode.nfs_do_access.nfs_permission.__inode_permission.inode_permission
232398 ±106% -2e+05 228 ± 11% latency_stats.avg.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_range.filemap_write_and_wait_range.nfs_file_fsync.vfs_fsync_range.vfs_fsync.nfs4_file_flush.[nfsv4].filp_close.do_dup2.SyS_dup2.entry_SYSCALL_64_fastpath
744886 ±139% -3e+05 447909 ± 68% latency_stats.avg.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_getattr.[nfsv4].nfs4_proc_getattr.[nfsv4].__nfs_revalidate_inode.nfs_getattr.vfs_getattr_nosec.vfs_getattr.vfs_fstatat
953762 ± 98% -3e+05 615947 ± 27% latency_stats.avg.max
232398 ±106% -2e+05 228 ± 11% latency_stats.max.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_range.filemap_write_and_wait_range.nfs_file_fsync.vfs_fsync_range.vfs_fsync.nfs4_file_flush.[nfsv4].filp_close.do_dup2.SyS_dup2.entry_SYSCALL_64_fastpath
744886 ±139% -3e+05 447909 ± 68% latency_stats.max.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_getattr.[nfsv4].nfs4_proc_getattr.[nfsv4].__nfs_revalidate_inode.nfs_getattr.vfs_getattr_nosec.vfs_getattr.vfs_fstatat
232398 ±106% -2e+05 228 ± 11% latency_stats.sum.wait_on_page_bit.__filemap_fdatawait_range.filemap_fdatawait_range.filemap_write_and_wait_range.nfs_file_fsync.vfs_fsync_range.vfs_fsync.nfs4_file_flush.[nfsv4].filp_close.do_dup2.SyS_dup2.entry_SYSCALL_64_fastpath
744886 ±139% -3e+05 447909 ± 68% latency_stats.sum.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_getattr.[nfsv4].nfs4_proc_getattr.[nfsv4].__nfs_revalidate_inode.nfs_getattr.vfs_getattr_nosec.vfs_getattr.vfs_fstatat
1027542 ±173% -4e+05 624100 ±100% latency_stats.sum.rpc_wait_bit_killable.__rpc_execute.rpc_execute.rpc_run_task.nfs4_call_sync_sequence.[nfsv4]._nfs4_proc_getattr.[nfsv4].nfs4_proc_getattr.[nfsv4].__nfs_revalidate_inode.nfs_do_access.nfs_permission.__inode_permission.inode_permission
14.80 ± 8% 46% 21.67 ± 5% perf-stat.node-store-miss-rate%
84.97 6% 90.44 perf-stat.node-load-miss-rate%
0.01 0.01 perf-stat.dTLB-store-miss-rate%
1.611e+09 1.583e+09 perf-stat.dTLB-store-misses
0.01 0.01 perf-stat.ipc
3.209e+12 3.124e+12 perf-stat.instructions
7.003e+11 -4% 6.72e+11 perf-stat.branch-instructions
3.396e+08 ± 4% -5% 3.217e+08 perf-stat.node-store-misses
9215 ± 6% -17% 7676 ± 12% perf-stat.instructions-per-iTLB-miss
4.153e+09 ± 10% -27% 3.048e+09 ± 5% perf-stat.cache-misses
0.02 ± 10% -27% 0.01 ± 6% perf-stat.cache-miss-rate%
1.615e+08 ± 12% -40% 96874183 ± 22% perf-stat.node-loads
1.979e+09 ± 14% -41% 1.169e+09 ± 9% perf-stat.node-stores
:4 25% 1:4 kmsg.DHCP/BOOTP:Reply_not_for_us_on_eth#,op[#]xid[#]
ebizzy.throughput.per_thread.min
3500 ++-------------------------------------------------------------------+
| O O O O O O O O O O
3000 O+ O O O O O O O .O...O |
| .* * .*..*...*..*... .*...*. *..|
2500 *+ : : *...*..*...*..*...*. *. *
| : : : : |
2000 ++ : : : : |
| : : : : |
1500 ++ : : : : |
| : : : : |
1000 ++ : : : : |
| : : : : |
500 ++ : : : : |
| : : |
0 ++-----*------*--O---------------------------------------------------+
[*] 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
5 years, 7 months
[sched] 4aa9e831f4 WARNING: CPU: 0 PID: 0 at kernel/sched/sched.h:804 attach_entity_cfs_rq
by Fengguang Wu
Hi Matt,
FYI, this may be your expected results from the debug patch.
https://git.kernel.org/pub/scm/linux/kernel/git/peterz/queue.git sched/debug
commit 4aa9e831f4b4662b26f4bebc1f3d3be30179d12a
Author: Matt Fleming <matt(a)codeblueprint.co.uk>
AuthorDate: Wed Sep 21 14:38:13 2016 +0100
Commit: Peter Zijlstra <peterz(a)infradead.org>
CommitDate: Fri Dec 16 21:24:39 2016 +0100
sched/core: Add debug code to catch missing update_rq_clock()
There's no diagnostic checks for figuring out when we've accidentally
missed update_rq_clock() calls. Let's add some by piggybacking on the
rq_*pin_lock() wrappers.
The idea behind the diagnostic checks is that upon pining rq lock the
rq clock should be updated, via update_rq_clock(), before anybody
reads the clock with rq_clock() or rq_clock_task().
The exception to this rule is when updates have explicitly been
disabled with the rq_clock_skip_update() optimisation.
There are some functions that only unpin the rq lock in order to grab
some other lock and avoid deadlock. In that case we don't need to
update the clock again and the previous diagnostic state can be
carried over in rq_repin_lock() by saving the state in the rq_flags
context.
Since this patch adds a new clock update flag and some already exist
in rq::clock_skip_update, that field has now been renamed. An attempt
has been made to keep the flag manipulation code small and fast since
it's used in the heart of the __schedule() fast path.
For the !CONFIG_SCHED_DEBUG case the only object code change (other
than addresses) is the following change to reset RQCF_ACT_SKIP inside
of __schedule(),
- c7 83 38 09 00 00 00 movl $0x0,0x938(%rbx)
- 00 00 00
+ 83 a3 38 09 00 00 fc andl $0xfffffffc,0x938(%rbx)
Cc: Yuyang Du <yuyang.du(a)intel.com>
Cc: Ingo Molnar <mingo(a)kernel.org>
Cc: Luca Abeni <luca.abeni(a)unitn.it>
Cc: Wanpeng Li <wanpeng.li(a)hotmail.com>
Cc: Sergey Senozhatsky <sergey.senozhatsky.work(a)gmail.com>
Cc: Byungchul Park <byungchul.park(a)lge.com>
Cc: Frederic Weisbecker <fweisbec(a)gmail.com>
Cc: Thomas Gleixner <tglx(a)linutronix.de>
Cc: Jan Kara <jack(a)suse.cz>
Cc: Rik van Riel <riel(a)redhat.com>
Cc: Petr Mladek <pmladek(a)suse.com>
Cc: Mel Gorman <mgorman(a)techsingularity.net>
Cc: Mike Galbraith <umgwanakikbuti(a)gmail.com>
Suggested-by: Peter Zijlstra <peterz(a)infradead.org>
Signed-off-by: Matt Fleming <matt(a)codeblueprint.co.uk>
Signed-off-by: Peter Zijlstra (Intel) <peterz(a)infradead.org>
Link: http://lkml.kernel.org/r/20160921133813.31976-8-matt@codeblueprint.co.uk
+-------------------------------------------------------+------------+------------+------------+
| | 527bb8647b | 4aa9e831f4 | abdf6489a5 |
+-------------------------------------------------------+------------+------------+------------+
| boot_successes | 66 | 0 | 0 |
| boot_failures | 0 | 22 | 13 |
| WARNING:at_kernel/sched/sched.h:#attach_entity_cfs_rq | 0 | 22 | 13 |
+-------------------------------------------------------+------------+------------+------------+
[ 0.658154] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.660410] Freeing SMP alternatives memory: 16K
[ 0.663773] ------------[ cut here ]------------
[ 0.664276] WARNING: CPU: 0 PID: 0 at kernel/sched/sched.h:804 attach_entity_cfs_rq+0xd31/0x11b0
[ 0.665396] rq->clock_update_flags < RQCF_ACT_SKIP
[ 0.665854] Modules linked in:
[ 0.666201] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.9.0-02686-g4aa9e83 #1
[ 0.666897] Call Trace:
[ 0.667179] dump_stack+0x83/0xb3
[ 0.667568] ? attach_entity_cfs_rq+0xd31/0x11b0
[ 0.668034] __warn+0x13d/0x160
git bisect start abdf6489a5f073e70c6fa6d6bf420eb579e08381 69973b830859bc6529a7a0468ba0d80ee5117826 --
git bisect bad 0afefecb9930e9e849a9d98b83a82eb248f0cfe7 # 17:50 0- 5 Merge 'jpirko-mlxsw/combined_queue' into devel-spot-201612171441
git bisect good ca7d27b9a6bbafade95bcf24fdf4741e3c30e50b # 18:03 22+ 0 Merge 'linux-review/Sudip-Mukherjee/usb-atm-cxacru-remove-impossible-condition/20161217-081754' into devel-spot-201612171441
git bisect good 64f3ae7e6a84f5c315b786e2ecffccc82a3e6a38 # 18:12 22+ 0 Merge 'miklos-vfs/next' into devel-spot-201612171441
git bisect bad ade1719dd918143d65cb2b22ebd9610d1a2f7a72 # 18:20 0- 1 Merge 'peterz-queue/sched/debug' into devel-spot-201612171441
git bisect good 597fa42b6a80f7179dfdb13c7a9231efbbc25baf # 18:29 22+ 0 Merge 'linux-review/Radim-Kr-m/KVM-x86-minor-irqchip-improvements-API-change/20161217-043342' into devel-spot-201612171441
git bisect good beb3aa7a2adc1714ecead2aff44d28dc2accdca8 # 18:39 21+ 0 Merge 'tile/master' into devel-spot-201612171441
git bisect good e9d28f9d9f7c8fcf58e0d6a631689d53fe003abb # 18:51 22+ 0 Merge 'peterz-queue/master' into devel-spot-201612171441
git bisect good f90df7c568ab27b934952ea6b840fbec6580e024 # 19:03 22+ 0 sched/core: Reset RQCF_ACT_SKIP before unpinning rq->lock
git bisect bad 4aa9e831f4b4662b26f4bebc1f3d3be30179d12a # 19:11 0- 7 sched/core: Add debug code to catch missing update_rq_clock()
git bisect good 527bb8647b2c15275495474cb8b5db4533ca3bff # 19:21 20+ 0 sched/fair: Push rq lock pin/unpin into idle_balance()
# first bad commit: [4aa9e831f4b4662b26f4bebc1f3d3be30179d12a] sched/core: Add debug code to catch missing update_rq_clock()
git bisect good 527bb8647b2c15275495474cb8b5db4533ca3bff # 19:24 66+ 0 sched/fair: Push rq lock pin/unpin into idle_balance()
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad 4aa9e831f4b4662b26f4bebc1f3d3be30179d12a # 19:35 0- 8 sched/core: Add debug code to catch missing update_rq_clock()
# extra tests on HEAD of linux-devel/devel-spot-201612171441
git bisect bad abdf6489a5f073e70c6fa6d6bf420eb579e08381 # 19:36 0- 13 0day head guard for 'devel-spot-201612171441'
# extra tests on tree/branch peterz-queue/sched/debug
git bisect good 597f6fc362a11b362050939a734b954fcb285795 # 19:47 66+ 0 sched: Avoid double update_rq_clock()
# extra tests on tree/branch linus/master
git bisect good 59331c215daf600a650e281b6e8ef3e1ed1174c2 # 19:57 66+ 0 Merge tag 'ceph-for-4.10-rc1' of git://github.com/ceph/ceph-client
# extra tests on tree/branch linux-next/master
git bisect good bf579a3afa46c74b7e89930974ba119d4c76bab2 # 20:07 66+ 0 Add linux-next specific files for 20161216
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
5 years, 7 months
[lkp-developer] [mac80211] 76f43b4c0a: hwsim.mesh_missing_mic.fail
by kernel test robot
FYI, we noticed the following commit:
commit: 76f44b4c0a9337af22827d78de4f2b8fd5328489 ("mac80211: Remove invalid flag operations in mesh TSF synchronization")
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
in testcase: hwsim
with following parameters:
group: hwsim-06
on test machine: 8 threads Intel(R) Core(TM) i7-2600K CPU @ 3.40GHz with 8G memory
caused below changes:
2016-12-18 23:20:41 ./run-tests.py mesh_missing_mic
DEV: wlan0: 02:00:00:00:00:00
DEV: wlan1: 02:00:00:00:01:00
DEV: wlan2: 02:00:00:00:02:00
APDEV: wlan3
APDEV: wlan4
START mesh_missing_mic 1/1
Test: Secure mesh network and missing MIC
MGMT-RX timeout
Traceback (most recent call last):
File "./run-tests.py", line 449, in main
t(dev, apdev)
File "/lkp/benchmarks/hwsim/tests/hwsim/test_wpas_mesh.py", line 1582, in test_mesh_missing_mic
raise Exception("MGMT-RX timeout")
Exception: MGMT-RX timeout
FAIL mesh_missing_mic 5.18796 2016-12-18 23:20:46.752155
passed 0 test case(s)
skipped 0 test case(s)
failed tests: mesh_missing_mic
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Xiaolong
5 years, 7 months
[lkp-developer] [alarmtimer] 4a057549d6: [No primary change] -29% perf-stat.instructions-per-iTLB-miss
by kernel test robot
Greeting,
There is no primary kpi change in this test, below is the data collected through multiple monitors running background just for your information.
commit: 4a057549d6044c2dea47e80f8369a76225ec9d90 ("alarmtimer: Add tracepoints for alarm timers")
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: netperf
on test machine: 8 threads Intel(R) Atom(TM) CPU C2750 @ 2.40GHz with 16G memory
with following parameters:
ip: ipv4
runtime: 300s
nr_threads: 200%
cluster: cs-localhost
test: TCP_SENDFILE
cpufreq_governor: performance
test-description: Netperf is a benchmark that can be use to measure various aspect of networking performance.
test-url: http://www.netperf.org/netperf/
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
testcase/path_params/tbox_group/run: netperf/ipv4-300s-200%-cs-localhost-TCP_SENDFILE-performance/lkp-avoton3
2924ecd441f3c12d 4a057549d6044c2dea47e80f83
---------------- --------------------------
%stddev change %stddev
\ | \
0.44 40% 0.62 perf-stat.iTLB-load-miss-rate%
1.169e+10 39% 1.63e+10 perf-stat.iTLB-load-misses
2.36 3% 2.43 perf-stat.branch-miss-rate%
1.11e+10 1.137e+10 perf-stat.branch-misses
3.00 3.06 perf-stat.cache-miss-rate%
2.292e+11 2.263e+11 perf-stat.cache-references
224 -29% 159 perf-stat.instructions-per-iTLB-miss
perf-stat.instructions-per-iTLB-miss
250 ++--------------------------------------------------------------------+
240 *******.*************.************ |
| : |
230 ++ : |
220 ++ : ********.*************.*******
| : * : |
210 ++ *.*** * |
200 ++ OOOOOOOOOO OO |
190 ++ OO OO |
| O OOOOOOO OO |
180 ++ OO |
170 ++ |
OOOOO |
160 ++ OOOOOOOOOO |
150 ++--------------------------------------------------------------------+
[*] 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
5 years, 7 months
[lkp-developer] [xfs] acdda3aae1: WARNING:at_fs/iomap.c:#iomap_dio_rw
by kernel test robot
FYI, we noticed the following commit:
commit: acdda3aae146d9b69d30e9d8a32a8d8937055523 ("xfs: use iomap_dio_rw")
https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: xfstests
with following parameters:
disk: 4HDD
fs: xfs
test: generic-mid1
test-description: xfstests is a regression test suite for xfs and other files ystems.
test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git
on test machine: 24 threads Westmere-EP with 16G memory
caused below changes:
[ 700.460773] XFS (sdf1): Mounting V5 Filesystem
[ 700.619048] XFS (sdf1): Ending clean mount
[ 701.352337] ------------[ cut here ]------------
[ 701.357125] WARNING: CPU: 0 PID: 16179 at fs/iomap.c:950 iomap_dio_rw+0x3b7/0x3d0
[ 701.366771] Modules linked in:
[ 701.369680] ipmi_devintf dm_mod ses enclosure rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver btrfs xor raid6_pq sr_mod sd_mod cdrom ata_generic sg pata_acpi intel_powerclamp coretemp kvm_intel kvm irqbypass crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel snd_pcm mptsas pata_jmicron snd_timer aesni_intel mptscsih ahci snd mptbase soundcore libahci lrw ppdev gf128mul glue_helper ablk_helper serio_raw pcspkr i7core_edac scsi_transport_sas cryptd libata ipmi_si edac_core parport_pc shpchp parport ipmi_msghandler acpi_cpufreq ip_tables
[ 701.420836] CPU: 12 PID: 16179 Comm: fio Not tainted 4.9.0-rc1-00077-gacdda3a #1
[ 701.421787] Hardware name: Supermicro X8DTN/X8DTN, BIOS 4.6.3 01/06/2010
[ 701.421787] ffffc9000ebabcc8 ffffffff81466609 0000000000000000 0000000000000000
[ 701.421787] ffffc9000ebabd08 ffffffff810802fb 000003b60ebabce8 ffffffff81279cef
[ 701.421787] ffff8802215a3840 ffffc9000ebabe70 ffff88021dd00000 ffffffff812788a0
[ 701.421787] Call Trace:
[ 701.421787] [<ffffffff81466609>] dump_stack+0x63/0x8a
[ 701.421787] [<ffffffff810802fb>] __warn+0xcb/0xf0
[ 701.421787] [<ffffffff81279cef>] ? iomap_dio_rw+0x25f/0x3d0
[ 701.421787] [<ffffffff812788a0>] ? iomap_dio_zero+0x120/0x120
[ 701.421787] [<ffffffff810803ed>] warn_slowpath_null+0x1d/0x20
[ 701.421787] [<ffffffff81279e47>] iomap_dio_rw+0x3b7/0x3d0
[ 701.421787] [<ffffffff812788a0>] ? iomap_dio_zero+0x120/0x120
[ 701.421787] [<ffffffff8122ee34>] ? file_update_time+0xa4/0xf0
[ 701.421787] [<ffffffff8139320a>] xfs_file_dio_aio_write+0xda/0x1f0
[ 701.421787] [<ffffffff813935e8>] xfs_file_write_iter+0x98/0x150
[ 701.421787] [<ffffffff81211b47>] __vfs_write+0xc7/0x140
[ 701.421787] [<ffffffff812121b5>] vfs_write+0xb5/0x1a0
[ 701.421787] [<ffffffff81213656>] SyS_pwrite64+0x76/0x90
[ 701.421787] [<ffffffff81955fb7>] entry_SYSCALL_64_fastpath+0x1a/0xa9
[ 701.541969] ---[ end trace dd0e384b2bd64dda ]---
[ 702.038468] ------------[ cut here ]------------
[ 702.043251] WARNING: CPU: 0 PID: 16195 at fs/iomap.c:892 iomap_dio_rw+0x192/0x3d0
[ 702.052874] Modules linked in:
[ 702.055770] ipmi_devintf dm_mod ses enclosure rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver btrfs xor raid6_pq sr_mod sd_mod cdrom ata_generic sg pata_acpi intel_powerclamp coretemp kvm_intel kvm irqbypass crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel snd_pcm mptsas pata_jmicron snd_timer aesni_intel mptscsih ahci snd mptbase soundcore libahci lrw ppdev gf128mul glue_helper ablk_helper serio_raw pcspkr i7core_edac scsi_transport_sas cryptd libata ipmi_si edac_core parport_pc shpchp parport ipmi_msghandler acpi_cpufreq ip_tables
[ 702.107631] CPU: 12 PID: 16195 Comm: fio Tainted: G W 4.9.0-rc1-00077-gacdda3a #1
[ 702.108625] Hardware name: Supermicro X8DTN/X8DTN, BIOS 4.6.3 01/06/2010
[ 702.108625] ffffc90001c3fcc8 ffffffff81466609 0000000000000000 0000000000000000
[ 702.108625] ffffc90001c3fd08 ffffffff810802fb 0000037c377aafd0 0000000000030800
[ 702.108625] ffff88043b9dc0c0 ffffc90001c3fe70 ffff8804377aae58 0000000000000000
[ 702.108625] Call Trace:
[ 702.108625] [<ffffffff81466609>] dump_stack+0x63/0x8a
[ 702.108625] [<ffffffff810802fb>] __warn+0xcb/0xf0
[ 702.108625] [<ffffffff810803ed>] warn_slowpath_null+0x1d/0x20
[ 702.108625] [<ffffffff81279c22>] iomap_dio_rw+0x192/0x3d0
[ 702.108625] [<ffffffff8122ee34>] ? file_update_time+0xa4/0xf0
[ 702.108625] [<ffffffff81392f9e>] ? xfs_file_aio_write_checks+0x13e/0x1a0
[ 702.108625] [<ffffffff8139320a>] xfs_file_dio_aio_write+0xda/0x1f0
[ 702.108625] [<ffffffff813935e8>] xfs_file_write_iter+0x98/0x150
[ 702.108625] [<ffffffff81211b47>] __vfs_write+0xc7/0x140
[ 702.108625] [<ffffffff812121b5>] vfs_write+0xb5/0x1a0
[ 702.108625] [<ffffffff81213656>] SyS_pwrite64+0x76/0x90
[ 702.108625] [<ffffffff81955fb7>] entry_SYSCALL_64_fastpath+0x1a/0xa9
[ 702.219173] ---[ end trace dd0e384b2bd64ddb ]---
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Xiaolong
5 years, 7 months
[sched] d984e601d4 WARNING: CPU: 1 PID: 15 at kernel/sched/sched.h:804 can_migrate_task
by Fengguang Wu
Hi Peter,
FYI, this commit shifts the warning from switched_from_fair() to
can_migrate_task(). Attached are the dmesg files after/before patch.
https://git.kernel.org/pub/scm/linux/kernel/git/peterz/queue.git sched/debug
commit d984e601d4db9189f55d79fb8946fc38a334d522
Author: Peter Zijlstra <peterz(a)infradead.org>
AuthorDate: Mon Oct 3 16:28:37 2016 +0200
Commit: Peter Zijlstra <peterz(a)infradead.org>
CommitDate: Fri Dec 16 21:24:39 2016 +0100
sched: Add missing update_rq_clock() in detach_task_cfs_rq()
Instead of adding the update_rq_clock() all the way at the bottom of
the callstack, add one at the top, this to aid later effort to
minimize update_rq_lock() calls.
4WARNING: CPU: 0 PID: 1 at ../kernel/sched/sched.h:797 detach_task_cfs_rq+0x6fe/0x930
rq->clock_update_flags < RQCF_ACT_SKIPdModules linked in:
dCPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.8.0-00637-g67223e2-dirty #553
dHardware name: Intel Corporation S2600GZ/S2600GZ, BIOS SE5C600.86B.02.02.0002.122320131210 12/23/2013
ffffc900000cbc00 ffffffff816152c5 ffffc900000cbc50 0000000000000000
ffffc900000cbc40 ffffffff810d5bab 0000031d00017b00 ffff88042f817b68
ffff88042dbb0000 ffff88042f817b00 ffff88042dbb0000 ffffffff81c18900
Call Trace:
[<ffffffff816152c5>] dump_stack+0x67/0x92
[<ffffffff810d5bab>] __warn+0xcb/0xf0
[<ffffffff810d5c1f>] warn_slowpath_fmt+0x4f/0x60
[<ffffffff8110efae>] detach_task_cfs_rq+0x6fe/0x930
[<ffffffff8110f1f1>] switched_from_fair+0x11/0x20
[<ffffffff810fde77>] __sched_setscheduler+0x2a7/0xb40
[<ffffffff810fe779>] _sched_setscheduler+0x69/0x70
[<ffffffff810ff243>] sched_set_stop_task+0x53/0x90
[<ffffffff81173703>] cpu_stop_create+0x23/0x30
[<ffffffff810f90c0>] __smpboot_create_thread.part.2+0xb0/0x100
[<ffffffff810f91ef>] smpboot_register_percpu_thread_cpumask+0xdf/0x140
[<ffffffff823c24e7>] ? pid_namespaces_init+0x40/0x40
[<ffffffff823c254b>] cpu_stop_init+0x64/0x9b
[<ffffffff8100040d>] do_one_initcall+0x3d/0x150
[<ffffffff8107763d>] ? print_cpu_info+0x7d/0xe0
[<ffffffff823a0001>] kernel_init_freeable+0xcc/0x207
[<ffffffff81a7d8d0>] ? rest_init+0x90/0x90
[<ffffffff81a7d8de>] kernel_init+0xe/0x100
[<ffffffff81a89bc7>] ret_from_fork+0x27/0x40
4---[ end trace 90bea7c93d2289cb ]---
Signed-off-by: Peter Zijlstra (Intel) <peterz(a)infradead.org>
+-----------------------------------------------------+------------+------------+------------+
| | 663ffc9df6 | d984e601d4 | 9058057c9a |
+-----------------------------------------------------+------------+------------+------------+
| boot_successes | 0 | 0 | 3 |
| boot_failures | 96 | 24 | 10 |
| WARNING:at_kernel/sched/sched.h:#switched_from_fair | 96 | | |
| WARNING:at_kernel/sched/sched.h:#can_migrate_task | 0 | 22 | 9 |
| BUG:kernel_reboot-without-warning_in_test_stage | 0 | 2 | 1 |
+-----------------------------------------------------+------------+------------+------------+
[ 0.060195] x86/mm: Memory block size: 128MB
[ 0.065726] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[ 0.066020] ------------[ cut here ]------------
[ 0.066648] WARNING: CPU: 1 PID: 15 at kernel/sched/sched.h:804 can_migrate_task+0x18c/0x1b4
[ 0.067000] rq->clock_update_flags < RQCF_ACT_SKIP
[ 0.067000] CPU: 1 PID: 15 Comm: kworker/1:0 Not tainted 4.9.0-02688-gd984e60 #1
[ 0.067000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 0.067000] Call Trace:
[ 0.067000] dump_stack+0x85/0xae
[ 0.067000] __warn+0xb3/0xce
[ 0.067000] warn_slowpath_fmt+0x46/0x4e
git bisect start 9058057c9a73c96c4e3eadabb2c3aea119a05d67 69973b830859bc6529a7a0468ba0d80ee5117826 --
git bisect bad 1ad680c707bbefd8bcefb8e187fe9b8e604c95a5 # 15:28 0- 11 Merge 'hwmon/watchdog-next' into devel-spot-201612171041
git bisect good 4d07c2ce6a719b4ed994fd6d540f91ab6be270cb # 15:39 23+ 1 Merge 'l1k/thunderbolt_runpm_v3' into devel-spot-201612171041
git bisect bad c11382c07e74bada7736f9d2dff67b87b358be7d # 15:49 4- 14 Merge 'peterz-queue/sched/debug' into devel-spot-201612171041
git bisect good 93cf7d15a234924b861dcb736d79ee627b6c7776 # 15:59 24+ 23 Merge 'vfs/for-linus' into devel-spot-201612171041
git bisect good 5463133b894f9787320fe0b2dfebe597a867e625 # 16:10 24+ 0 Merge 'linux-review/Ozgur-Karatas/tools-net-bpf_dbg-c-fixed-keyboard-typo/20161217-043841' into devel-spot-201612171041
git bisect good 792886cb3dc81adae8a562ff25a5c27e73a3f62f # 16:22 24+ 12 Merge 'linux-review/Radim-Kr-m/KVM-x86-minor-irqchip-improvements-API-change/20161217-043342' into devel-spot-201612171041
git bisect good bfefa32cc9609ef7b041e60e86d887650ba5ee3c # 16:29 24+ 7 Merge 'tile/master' into devel-spot-201612171041
git bisect good 527bb8647b2c15275495474cb8b5db4533ca3bff # 16:40 24+ 18 sched/fair: Push rq lock pin/unpin into idle_balance()
git bisect good 663ffc9df655dc76cfe23541943a3b1222cfd83e # 16:55 24+ 24 sched: Add missing update_rq_clock() in post_init_entity_util_avg().
git bisect bad d984e601d4db9189f55d79fb8946fc38a334d522 # 17:05 0- 13 sched: Add missing update_rq_clock() in detach_task_cfs_rq()
# first bad commit: [d984e601d4db9189f55d79fb8946fc38a334d522] sched: Add missing update_rq_clock() in detach_task_cfs_rq()
git bisect good 663ffc9df655dc76cfe23541943a3b1222cfd83e # 17:08 72+ 96 sched: Add missing update_rq_clock() in post_init_entity_util_avg().
# extra tests with CONFIG_DEBUG_INFO_REDUCED
git bisect bad d984e601d4db9189f55d79fb8946fc38a334d522 # 17:24 0- 12 sched: Add missing update_rq_clock() in detach_task_cfs_rq()
# extra tests on HEAD of linux-devel/devel-spot-201612171041
git bisect bad 9058057c9a73c96c4e3eadabb2c3aea119a05d67 # 17:24 0- 10 0day head guard for 'devel-spot-201612171041'
# extra tests on tree/branch peterz-queue/sched/debug
git bisect good 597f6fc362a11b362050939a734b954fcb285795 # 17:37 68+ 15 sched: Avoid double update_rq_clock()
# extra tests with first bad commit reverted
git bisect good 61c8ceb96e6f3487fe5547a82002aaba358bae84 # 17:55 69+ 69 Revert "sched: Add missing update_rq_clock() in detach_task_cfs_rq()"
# extra tests on tree/branch linus/master
git bisect good 59331c215daf600a650e281b6e8ef3e1ed1174c2 # 18:06 69+ 13 Merge tag 'ceph-for-4.10-rc1' of git://github.com/ceph/ceph-client
# extra tests on tree/branch linux-next/master
git bisect good bf579a3afa46c74b7e89930974ba119d4c76bab2 # 18:17 69+ 27 Add linux-next specific files for 20161216
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
5 years, 7 months