media / serial_ir: BUG: unable to handle kernel NULL pointer dereference at 0000039c
by Linus Torvalds
Sorry, resending with a less broken subject line that doesn't talk
about anti-virus scanning failures. I almost ignored the original
email due to the crazy subject line.
So if you get this twice, don't worry. It's all the same thing, just
quoted and subject line cleaned up.
Linus
On Fri, Feb 24, 2017 at 11:15 AM, Linus Torvalds
<torvalds(a)linux-foundation.org> wrote:
> Added more relevant people. I've debugged the immediate problem below,
> but I think there's another problem that actually triggered this.
>
> On Fri, Feb 24, 2017 at 10:28 AM, kernel test robot
> <fengguang.wu(a)intel.com> wrote:
>>
>> 0day kernel testing robot got the below dmesg and the first bad commit is
>>
>> https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master
>>
>> commit ff58d005cd10fcd372787cceac547e11cf706ff6
>> Merge: 5ab3566 9eeb0ed
>>
>> Merge tag 'media/v4.11-1' of git://git.kernel.org/pub/scm/linux/kernel/git/mchehab/linux-media
> [...]
>> [ 4.664940] rc rc0: lirc_dev: driver ir-lirc-codec (rc-loopback) registered at minor = 0
>> [ 4.666322] BUG: unable to handle kernel NULL pointer dereference at 0000039c
>> [ 4.666675] IP: serial_ir_irq_handler+0x189/0x410
>
> This merge being fingered ends up being a subtle interaction with other changes.
>
> Those "other changes" are (again) the interrupt retrigger code that
> was reverted for 4.10, and then we tried to merge them again this
> merge window.
>
> Because the immediate cause is:
>
>> [ 4.666675] EIP: serial_ir_irq_handler+0x189/0x410
>> [ 4.666675] Call Trace:
>> [ 4.666675] <IRQ>
>> [ 4.666675] __handle_irq_event_percpu+0x57/0x100
>> [ 4.666675] handle_irq_event_percpu+0x1d/0x50
>> [ 4.666675] handle_irq_event+0x32/0x60
>> [ 4.666675] handle_edge_irq+0xa5/0x120
>> [ 4.666675] handle_irq+0x9d/0xd0
>> [ 4.666675] </IRQ>
>> [ 4.666675] do_IRQ+0x5f/0x130
>> [ 4.666675] common_interrupt+0x33/0x38
>> [ 4.666675] EIP: hardware_init_port+0x3f/0x190
>> [ 4.666675] EFLAGS: 00200246 CPU: 0
>> [ 4.666675] EAX: c718990f EBX: 00000000 ECX: 00000000 EDX: 000003f9
>> [ 4.666675] ESI: 000003f9 EDI: 000003f8 EBP: c0065d98 ESP: c0065d84
>> [ 4.666675] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
>> [ 4.666675] serial_ir_probe+0xbb/0x300
>> [ 4.666675] platform_drv_probe+0x48/0xb0
> ...
>
> ie an interrupt came in immediately after the request_irq(), before
> all the data was properly set up, which then causes the interrupt
> handler to take a fault because it tries to access some field that
> hasn't even been set up yet.
>
> The code line is helpful, the faulting instruction is
>
> mov 0x39c(%rax),%eax <--- fault
> call ..
> mov someglobalvar,%edx
>
> which together with the supplied config file makes me able to match it
> up with the assembly generation around it:
>
> inb %dx, %al # tmp254, value
> andb $1, %al #, tmp255
> testb %al, %al # tmp255
> je .L233 #,
> .L215:
> movl serial_ir+8, %eax # serial_ir.rcdev, serial_ir.rcdev
> xorl %edx, %edx # _66->timeout
> movl 924(%eax), %eax # _66->timeout, _66->timeout
> call nsecs_to_jiffies #
> movl jiffies, %edx # jiffies, jiffies.33_70
> addl %eax, %edx # _69, tmp259
> movl $serial_ir+16, %eax #,
> call mod_timer #
> movl serial_ir+8, %eax # serial_ir.rcdev,
> call ir_raw_event_handle #
> movl $1, %eax #, <retval>
>
> so it's that "serial_ir.rcdev->timeout" access that faults. So this is
> the faulting source code:
>
> drivers/media/rc/serial_ir.c: 402
>
> mod_timer(&serial_ir.timeout_timer,
> jiffies + nsecs_to_jiffies(serial_ir.rcdev->timeout));
>
> ir_raw_event_handle(serial_ir.rcdev);
>
> return IRQ_HANDLED;
>
> and serial_ir.rcdev is NULL when ti tries to look up the timeout.
>
> That mod_timer() is new as of commit 2940c7e49775 ("[media] serial_ir:
> generate timeout") and *that* is the actual new bug.
>
> Looking at the code, that serial_ir.rcdev thing is initialized fairly
> late in serial_ir_init_module(), while the interrupt is allocated
> early in serial_ir_probe(), which is done _early_ in
> serial_ir_init_module():
>
> serial_ir_init_module -> serial_ir_init -> platform_driver_register ->
> serial_ir_probe -> devm_request_irq
>
> Mauro, Sean, please fix.
>
> Anyway, this is clearly a bug in the serial_ir code, but it is *also*
> once again clearly now being *triggered* due to the irq handling
> changes.
>
> I'm pretty sure that the thing that triggered this is once more commit
> a9b4f08770b4 ("x86/ioapic: Restore IO-APIC irq_chip retrigger
> callback") which seems to retrigger stale irqs that simply should not
> be retriggered.
>
> They aren't actually active any more, if they ever were.
>
> So that commit seems to act like a random CONFIG_DEBUG_SHIRQ. It's
> good for testing, but not good for actual users.
>
> I the local APIC retrigger just unconditionally resends that irq. But
> it's the core interrupt code that decides to retrigger it incorrectly
> for some reason.
>
> Why is IRQS_PENDING set for that thing? Something must have almost
> certainly set it, despite the irq not actually having ever been
> pending. Thomas?
>
> Linus
4 years
- 1
- 0
ecc369bebb: BUG: unable to handle kernel NULL pointer dereference at 0000039c
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://internal_merge_and_test_tree devel-hourly-2017022318
commit ecc369bebb06fe232b09fcbe1d40839db0c6467d
Merge: 1a11428 0e954b1
Author: 0day robot <fengguang.wu(a)intel.com>
AuthorDate: Thu Feb 23 18:58:19 2017 +0800
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Thu Feb 23 18:58:19 2017 +0800
Merge 'nfsd/nfsd-next' into devel-hourly-2017022318
1a11428c16 Merge 'linux-review/Mariusz-Bialonczyk/w1-add-DS2438-support-documentation-and-small-fixes/20170223-144441' into devel-hourly-2017022318
0e954b1e9d nfsd: special case truncates some more
ecc369bebb Merge 'nfsd/nfsd-next' into devel-hourly-2017022318
+-------------------------------------------------------+------------+------------+------------+
| | 1a11428c16 | 0e954b1e9d | ecc369bebb |
+-------------------------------------------------------+------------+------------+------------+
| boot_successes | 138 | 155 | 0 |
| boot_failures | 0 | 2 | 49 |
| IP-Config:Auto-configuration_of_network_failed | 0 | 2 | 2 |
| BUG:unable_to_handle_kernel | 0 | 0 | 47 |
| Oops | 0 | 0 | 47 |
| EIP:serial_ir_irq_handler | 0 | 0 | 47 |
| EIP:hardware_init_port | 0 | 0 | 47 |
| Kernel_panic-not_syncing:Fatal_exception_in_interrupt | 0 | 0 | 47 |
+-------------------------------------------------------+------------+------------+------------+
[ 4.221694] input: rc-core loopback device as /devices/virtual/rc/rc0/input5
[ 4.222328] evbug: Connected device: input5 (rc-core loopback device at rc-core/virtual)
[ 4.223100] input: MCE IR Keyboard/Mouse (rc-loopback) as /devices/virtual/input/input6
[ 4.223936] evbug: Connected device: input6 (MCE IR Keyboard/Mouse (rc-loopback) at /input0)
[ 4.224647] rc rc0: lirc_dev: driver ir-lirc-codec (rc-loopback) registered at minor = 0
[ 4.225484] BUG: unable to handle kernel NULL pointer dereference at 0000039c
[ 4.225921] IP: serial_ir_irq_handler+0x189/0x410
[ 4.226204] *pde = 00000000
[ 4.226204]
[ 4.226472] Oops: 0000 [#1] PREEMPT SMP DEBUG_PAGEALLOC
[ 4.226793] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.10.0-06046-gecc369b #2
[ 4.226910] task: c0068040 task.stack: c0066000
[ 4.226910] EIP: serial_ir_irq_handler+0x189/0x410
[ 4.226910] EFLAGS: 00210046 CPU: 0
[ 4.226910] EAX: 00000000 EBX: 00000000 ECX: 000000b0 EDX: 00000000
[ 4.226910] ESI: 000003f8 EDI: 00000101 EBP: c008df88 ESP: c008df44
[ 4.226910] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 4.226910] CR0: 80050033 CR2: 0000039c CR3: 120e9000 CR4: 000006d0
[ 4.226910] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
[ 4.226910] DR6: fffe0ff0 DR7: 00000400
[ 4.226910] Call Trace:
[ 4.226910] <IRQ>
[ 4.226910] __handle_irq_event_percpu+0x57/0x100
[ 4.226910] ? handle_edge_irq+0x12/0x120
[ 4.226910] handle_irq_event_percpu+0x1d/0x50
[ 4.226910] handle_irq_event+0x32/0x60
[ 4.226910] handle_edge_irq+0xa5/0x120
[ 4.226910] ? handle_fasteoi_irq+0x150/0x150
[ 4.226910] handle_irq+0x9d/0xd0
[ 4.226910] </IRQ>
[ 4.226910] do_IRQ+0x5f/0x130
[ 4.226910] ? __this_cpu_preempt_check+0xf/0x20
[ 4.226910] common_interrupt+0x33/0x38
[ 4.226910] EIP: hardware_init_port+0x3f/0x190
[ 4.226910] EFLAGS: 00200246 CPU: 0
[ 4.226910] EAX: d158ba0f EBX: 00000000 ECX: 00000000 EDX: 000003f9
[ 4.226910] ESI: 000003f9 EDI: 000003f8 EBP: c0067d98 ESP: c0067d84
[ 4.226910] DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[ 4.226910] ? serial_ir_open+0x3f/0xa0
[ 4.226910] ? show_parconfig_smsc37c669+0x14b/0x170
[ 4.226910] serial_ir_probe+0xbb/0x300
[ 4.226910] platform_drv_probe+0x48/0xb0
[ 4.226910] driver_probe_device+0x161/0x500
[ 4.226910] ? acpi_driver_match_device+0x36/0x50
[ 4.226910] __device_attach_driver+0x77/0x110
[ 4.226910] ? klist_next+0x82/0xf0
[ 4.226910] ? __driver_attach+0xf0/0xf0
[ 4.226910] bus_for_each_drv+0x47/0x80
[ 4.226910] __device_attach+0xb5/0x130
[ 4.226910] ? __driver_attach+0xf0/0xf0
[ 4.226910] device_initial_probe+0xd/0x10
[ 4.226910] bus_probe_device+0x77/0x80
[ 4.226910] device_add+0x37e/0x5f0
[ 4.226910] ? kobject_set_name_vargs+0x6b/0x90
[ 4.226910] platform_device_add+0xfd/0x290
[ 4.226910] serial_ir_init_module+0xa6/0x269
[ 4.226910] ? gpio_ir_recv_driver_init+0x11/0x11
[ 4.226910] do_one_initcall+0x7e/0x121
[ 4.226910] ? kernel_init_freeable+0xec/0x188
[ 4.226910] kernel_init_freeable+0x10f/0x188
[ 4.226910] ? rest_init+0x110/0x110
[ 4.226910] kernel_init+0xb/0x100
[ 4.226910] ? schedule_tail+0x20/0x80
[ 4.226910] ? rest_init+0x110/0x110
[ 4.226910] ret_from_fork+0x21/0x2c
[ 4.226910] Code: 89 15 64 8e c9 d2 84 db ba 02 00 00 00 74 08 8b 0d ac 8e c9 d2 d3 e2 01 f2 ec 24 01 84 c0 0f 84 ce fe ff ff a1 68 8e c9 d2 31 d2 <8b> 80 9c 03 00 00 e8 4c 62 73 ff 8b 15 c0 28 ef d1 01 c2 b8 70
[ 4.226910] EIP: serial_ir_irq_handler+0x189/0x410 SS:ESP: 0068:c008df44
[ 4.226910] CR2: 000000000000039c
[ 4.226910] ---[ end trace c50ea67af86e6288 ]---
[ 4.226910] Kernel panic - not syncing: Fatal exception in interrupt
git bisect start 74ada5fb1d5116434de3060c8f83f3b6da9d7a8f c470abd4fde40ea6a0846a2beab642a578c0b8cd --
git bisect bad d03364dc0006815bc63c84319679f5577fd40fd6 # 09:57 0- 26 Merge 'linux-review/Shanker-Donthineni/arm64-Fix-the-kernel-panic-on-QDF2400-platform/20170223-112801' into devel-hourly-2017022318
git bisect bad 6f4335e18547b9db274f5761d8a28adbc8a8a785 # 09:57 0- 26 Merge 'linux-review/Elaine-Zhang/clk-rockchip-add-pll_wait_lock-for-pll_enable/20170222-120523' into devel-hourly-2017022318
git bisect bad 00bb32e425b982b706df9c11740b2aee7cbe3420 # 09:57 0- 26 Merge 'linux-review/Finn-Thain/scsi-mac_scsi-Fix-MAC_SCSI-m-option-when-SCSI-m/20170223-061255' into devel-hourly-2017022318
git bisect good 1a11428c16c7e1f8ac3426af34d710bb7d2bcceb # 10:08 22+ 0 Merge 'linux-review/Mariusz-Bialonczyk/w1-add-DS2438-support-documentation-and-small-fixes/20170223-144441' into devel-hourly-2017022318
git bisect bad be4a6b5b1b5fcf59884261ad4dc7bccd72068af1 # 10:08 0- 27 Merge 'slave-dma/next' into devel-hourly-2017022318
git bisect bad ecc369bebb06fe232b09fcbe1d40839db0c6467d # 11:37 0- 49 Merge 'nfsd/nfsd-next' into devel-hourly-2017022318
git bisect good c2ccf64a6c4bf3bcbf4e6e6b94a51500991022a1 # 19:24 22+ 3 svcrdma: Clean up backchannel send header encoding
git bisect good f7d1ddbe7648af7460d23688c8c131342eb43b3a # 19:44 22+ 2 nfsd/callback: Cleanup callback cred on shutdown
git bisect good 7323f0d2881bbd426ce6bc0a956ef9e739ffe767 # 19:58 21+ 5 NFSD: Reserve adequate space for LOCKT operation
git bisect good 758e99fefe1d9230111296956335cd35995c0eaf # 20:04 20+ 2 nfsd: minor nfsd_setattr cleanup
git bisect good 0e954b1e9d2cc4bc0f79e06764b7347f323aaff2 # 23:22 22+ 2 nfsd: special case truncates some more
# first bad commit: [ecc369bebb06fe232b09fcbe1d40839db0c6467d] Merge 'nfsd/nfsd-next' into devel-hourly-2017022318
git bisect good 1a11428c16c7e1f8ac3426af34d710bb7d2bcceb # 23:34 65+ 0 Merge 'linux-review/Mariusz-Bialonczyk/w1-add-DS2438-support-documentation-and-small-fixes/20170223-144441' into devel-hourly-2017022318
git bisect good 0e954b1e9d2cc4bc0f79e06764b7347f323aaff2 # 23:43 60+ 2 nfsd: special case truncates some more
# extra tests with CONFIG_DEBUG_INFO_REDUCED
# extra tests on HEAD of linux-devel/devel-hourly-2017022318
git bisect bad e34ceceb62355da2f071256a491700830f1c1350 # 23:51 0- 13 0day head guard for 'devel-hourly-2017022318'
# extra tests on tree/branch linux-devel/devel-hourly-2017022318
git bisect bad e34ceceb62355da2f071256a491700830f1c1350 # 00:04 0- 13 0day head guard for 'devel-hourly-2017022318'
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years
- 1
- 0
[lkp-robot] [mm] 5d2ba5b293: [No primary change] +5% vm-scalability.perf-stat.branch-misses
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: 5d2ba5b29383235474e96d935f42c0f274e9ed2d ("mm: convert try_to_unmap_one() to use page_vma_mapped_walk()")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: vm-scalability
on test machine: 4 threads Intel(R) Core(TM) i3-3220 CPU @ 3.30GHz with 8G memory
with following parameters:
runtime: 300s
test: mmap-pread-seq
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-mmap-pread-seq-performance/lkp-ivb-d02
f24c18f12cc1a1f9 5d2ba5b29383235474e96d935f
---------------- --------------------------
%stddev change %stddev
\ | \
18513661 18062721 vm-scalability.time.minor_page_faults
6559 ± 75% 1e+04 21091 ± 62% latency_stats.sum.pipe_read.__vfs_read.vfs_read.SyS_read.entry_SYSCALL_64_fastpath
0.04 ± 3% 6% 0.04 perf-stat.branch-miss-rate%
5.663e+08 ± 3% 5% 5.957e+08 perf-stat.branch-misses
3.146e+11 3.204e+11 perf-stat.dTLB-stores
65.99 64.66 perf-stat.cache-miss-rate%
9.019e+09 8.831e+09 perf-stat.cache-references
18795518 18346133 perf-stat.minor-faults
18796083 18346680 perf-stat.page-faults
5.952e+09 -4% 5.71e+09 perf-stat.cache-misses
14369137 ± 6% -9% 13024713 ± 7% 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,
Xiaolong
4 years
- 1
- 0
[lkp-robot] [llist] bc1a405b00: Kernel_panic-not_syncing:No_working_init_found
by kernel test robot
FYI, we noticed the following commit:
commit: bc1a405b009b69c8f86a6741614a503400a01f27 ("llist: Don't reinvent the wheel but use existing llist API")
https://github.com/0day-ci/linux Byungchul-Park/llist-Don-t-reinvent-the-wheel-but-use-existing-llist-API/20170213-124427
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -m 420M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+------------------------------------------------+---------------+------------+
| | next-20170210 | bc1a405b00 |
+------------------------------------------------+---------------+------------+
| boot_successes | 2 | 0 |
| boot_failures | 0 | 16 |
| Kernel_panic-not_syncing:No_working_init_found | 0 | 16 |
+------------------------------------------------+---------------+------------+
[ 6.339828] Write protecting the kernel read-only data: 20480k
[ 6.341283] Freeing unused kernel memory: 1040K
[ 6.342591] Freeing unused kernel memory: 336K
[ 6.343088] Failed to execute /init (error -26)
[ 6.343598] Starting init: /bin/sh exists but couldn't execute it (error -26)
[ 6.344335] Kernel panic - not syncing: No working init found. Try passing init= option to kernel. See Linux Documentation/admin-guide/init.rst for guidance.
[ 6.345783] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.10.0-rc7-next-20170210-00001-gbc1a405 #17
[ 6.346645] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 6.347642] Call Trace:
[ 6.347887] dump_stack+0x61/0x7e
[ 6.348236] ? rest_init+0x27/0xb9
[ 6.348315] panic+0xd3/0x20a
[ 6.348315] ? rest_init+0xb9/0xb9
[ 6.348315] kernel_init+0xe2/0xe6
[ 6.348315] ret_from_fork+0x2c/0x40
[ 6.348315] Kernel Offset: disabled
Elapsed time: 10
initrds=(
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
4 years
- 1
- 0
[drm] 2ab8c5f8c0 WARNING: CPU: 0 PID: 1 at drivers/gpu/drm/drm_mode_config.c:424 drm_mode_config_cleanup
by Fengguang Wu
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 2ab8c5f8c0358010a19e82656fa4826a6a51d1e0
Author: Daniel Vetter <daniel.vetter(a)ffwll.ch>
AuthorDate: Wed Dec 14 00:08:08 2016 +0100
Commit: Daniel Vetter <daniel.vetter(a)ffwll.ch>
CommitDate: Sun Dec 18 14:33:51 2016 +0100
drm: Clean up connectors by unreferencing them
Only static connectors should be left at this point, and we should be
able to clean them out by simply dropping that last reference still
around from drm_connector_init.
If that leaves anything behind then we have a driver bug.
Doing the final cleanup this way also allows us to use
drm_connector_iter, removing the very last place where we walk
connector_list explicitly in drm core&helpers.
Reviewed-by: Harry Wentland <harry.wentland(a)amd.com>
Reviewed-by: Sean Paul <seanpaul(a)chromium.org>
Signed-off-by: Daniel Vetter <daniel.vetter(a)intel.com>
Link: http://patchwork.freedesktop.org/patch/msgid/20161213230814.19598-8-danie...
c36a3254f7 drm: Convert all helpers to drm_connector_list_iter
2ab8c5f8c0 drm: Clean up connectors by unreferencing them
+-----------------------------------------------------------------------+------------+------------+
| | c36a3254f7 | 2ab8c5f8c0 |
+-----------------------------------------------------------------------+------------+------------+
| boot_successes | 92 | 0 |
| boot_failures | 0 | 48 |
| WARNING:at_drivers/gpu/drm/drm_mode_config.c:#drm_mode_config_cleanup | 0 | 48 |
| calltrace:bochs_init | 0 | 48 |
+-----------------------------------------------------------------------+------------+------------+
[ 21.723856] Console: switching to colour frame buffer device 128x48
[ 21.729355] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
[ 21.755257] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[ 21.761762] Console: switching to colour VGA+ 80x25
[ 21.795640] ------------[ cut here ]------------
[ 21.797398] WARNING: CPU: 0 PID: 1 at drivers/gpu/drm/drm_mode_config.c:424 drm_mode_config_cleanup+0x99/0x20c
[ 21.801086] CPU: 0 PID: 1 Comm: swapper Not tainted 4.9.0-rc8-01683-g2ab8c5f #1
[ 21.803731] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 21.807009] ffff88001e4ebbf8 ffffffff813762d0 ffff88001e4ebc38 ffffffff810a2a88
[ 21.810375] 000001a81e4ebbf8 ffff88001fb18008 ffff88001fb189e8 ffff88001fb189f0
[ 21.813719] 0000000000000001 0000000000000000 ffff88001e4ebc48 ffffffff810a2b50
[ 21.817119] Call Trace:
[ 21.818349] [<ffffffff813762d0>] dump_stack+0x19/0x1b
[ 21.820153] [<ffffffff810a2a88>] __warn+0xb1/0xcc
[ 21.821905] [<ffffffff810a2b50>] warn_slowpath_null+0x18/0x1a
[ 21.823954] [<ffffffff81566950>] drm_mode_config_cleanup+0x99/0x20c
[ 21.826109] [<ffffffff817bbd45>] bochs_kms_fini+0x1a/0x24
[ 21.828035] [<ffffffff817bae26>] bochs_unload+0x1e/0x46
[ 21.829948] [<ffffffff81551f28>] drm_dev_unregister+0x38/0xb0
[ 21.831959] [<ffffffff81551fe4>] drm_put_dev+0x44/0x4f
[ 21.833853] [<ffffffff817bad2c>] bochs_pci_remove+0x10/0x12
[ 21.835878] [<ffffffff813e0344>] pci_device_remove+0x46/0x8f
[ 21.837866] [<ffffffff817d937a>] driver_probe_device+0x202/0x439
[ 21.839999] [<ffffffff817d966c>] __driver_attach+0xbb/0xde
[ 21.841813] [<ffffffff817d95b1>] ? driver_probe_device+0x439/0x439
[ 21.843808] [<ffffffff817d774a>] bus_for_each_dev+0x5a/0x82
[ 21.845669] [<ffffffff817d98cc>] driver_attach+0x19/0x1b
[ 21.847412] [<ffffffff817d7fee>] bus_add_driver+0x134/0x218
[ 21.849191] [<ffffffff817d9f0f>] driver_register+0x83/0xba
[ 21.850859] [<ffffffff813e0219>] __pci_register_driver+0x5b/0x5e
[ 21.852653] [<ffffffff82805845>] ? nouveau_drm_init+0x1e2/0x1e2
[ 21.854457] [<ffffffff81553840>] drm_pci_init+0x47/0xc8
[ 21.856087] [<ffffffff82805845>] ? nouveau_drm_init+0x1e2/0x1e2
[ 21.857817] [<ffffffff827cb73a>] ? do_early_param+0x8f/0x8f
[ 21.859494] [<ffffffff8280585c>] bochs_init+0x17/0x19
[ 21.861231] [<ffffffff827cc0dc>] do_one_initcall+0x94/0x152
[ 21.864299] [<ffffffff810b9700>] ? parse_args+0x1e4/0x373
[ 21.866212] [<ffffffff827cb73a>] ? do_early_param+0x8f/0x8f
[ 21.867927] [<ffffffff827cc298>] kernel_init_freeable+0xfe/0x197
[ 21.869732] [<ffffffff81b999a1>] ? rest_init+0xb8/0xb8
[ 21.871334] [<ffffffff81b999aa>] kernel_init+0x9/0xeb
[ 21.872948] [<ffffffff81ba5535>] ret_from_fork+0x25/0x30
[ 21.874584] ---[ end trace c0673e7b7c7b335f ]---
[ 21.877475] [TTM] Finalizing pool allocator
git bisect start 546a214099cfdce7851266b0a288e45c11ebfc52 v4.9 --
git bisect good fb1d8e0e2c50f374cfc244564decfc3f0a336cb4 # 00:40 22+ 0 Merge tag 'upstream-4.10-rc5' of git://git.infradead.org/linux-ubifs
git bisect good 77c43e3363fe4f05f4ee32b0ad89e69a46e8f410 # 00:54 22+ 0 Merge remote-tracking branch 'asm-generic/master'
git bisect bad cd73d1675614a817ff3ea3fbf32e9d8251d7828b # 01:07 0- 12 Merge remote-tracking branch 'kspp/for-next/kspp'
git bisect good 956c6cd9555cc6bc3d841a558c75ff779da07ca7 # 01:18 22+ 0 Merge remote-tracking branch 'f2fs/dev'
git bisect good 6c3c3b71c0e14fe1ef08f9d5a56b9b17cbffe9ab # 01:43 22+ 0 Merge remote-tracking branch 'pm/linux-next'
git bisect good 142c3794a59d179437534628bf5ae2b8bc43d857 # 02:03 22+ 0 Merge remote-tracking branch 'bluetooth/master'
git bisect bad def0451540b29e2cac571d23f21339c1a3914625 # 02:16 0- 9 Merge remote-tracking branch 'drm/drm-next'
git bisect good 8eb5a9b6f81c9c96475123a5eeebf2627326700e # 02:28 22+ 0 Merge remote-tracking branch 'rdma/for-next'
git bisect good d7e5960db95570fdb5d4c7053e8c6c4a8a09f285 # 05:09 22+ 0 Merge remote-tracking branch 'mtd/master'
git bisect good ad7b78d2e5105e46c577ab24aa7d565bfdaaa611 # 05:20 22+ 0 Merge remote-tracking branch 'crypto/master'
git bisect bad 8bab1193c193fa1a695aa9bb881bb4cb0ea2ba85 # 08:08 0- 48 drm/i915: Convert i915_ggtt_view to use an anonymous union
git bisect good a402eae64d0ad12b1c4a411f250d6c161e67f623 # 08:35 22+ 0 Merge tag 'v4.10-rc2' into drm-intel-next-queued
git bisect bad b5c3714fe8789745521d8351d75049b9c6a0d26b # 11:23 0- 27 drm/mm: Convert to drm_printer
git bisect good 2407d1dc04789bfb2f39d248644981d2233feb0e # 14:15 20+ 0 drm: Fix compilation warning caused by static inline forward declaration
git bisect bad 06df8ac682e6a00e6e32d105208953ca5323cc82 # 14:26 0- 31 drm: kselftest for drm_mm_debug()
git bisect bad 4e98c378a137def586b3ffc8ac5a5852da2e0538 # 17:13 0- 48 drm/ttm: use designated initializers
git bisect bad 188f78825de7b43593ae26cb86fecdf9ce5a4ac8 # 20:34 0- 48 drm: Fix spelling of clock in drm_connector.h
git bisect good 15092c7a3cddb495649363a403f242edd2aaa6a5 # 23:16 22+ 0 drm: Drop locking cargo-cult from drm_mode_config_init
git bisect bad 2ab8c5f8c0358010a19e82656fa4826a6a51d1e0 # 01:58 0- 37 drm: Clean up connectors by unreferencing them
git bisect good c36a3254f7857f1ad9badbe3578ccc92be541a8e # 02:40 21+ 0 drm: Convert all helpers to drm_connector_list_iter
# first bad commit: [2ab8c5f8c0358010a19e82656fa4826a6a51d1e0] drm: Clean up connectors by unreferencing them
git bisect good c36a3254f7857f1ad9badbe3578ccc92be541a8e # 02:44 66+ 0 drm: Convert all helpers to drm_connector_list_iter
# extra tests with CONFIG_DEBUG_INFO_REDUCED
# extra tests on HEAD of linux-devel/devel-catchup-201702200357
git bisect bad 546a214099cfdce7851266b0a288e45c11ebfc52 # 02:49 0- 35 0day head guard for 'devel-catchup-201702200357'
# extra tests on tree/branch linux-next/master
git bisect bad b349d1bbdc9ea7df856ab3af395c03b858e29d48 # 05:39 0- 150 Add linux-next specific files for 20170220
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years
- 2
- 1
0693cc0dcc: WARNING:at_kernel/time/hrtimer.c:#hrtimer_init
by kernel test robot
FYI, we noticed the following commit:
commit: 0693cc0dcc1288e8985d0b714f6fa50022cf3aad ("origin")
git://git.cmpxchg.org/linux-mmotm.git master
in testcase: boot
on test machine: qemu-system-x86_64 -enable-kvm -smp 2 -m 512M
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------+-------+------------+
| | v4.10 | 0693cc0dcc |
+----------------------------------------------------+-------+------------+
| boot_successes | 1926 | 0 |
| boot_failures | 150 | 8 |
| BUG:kernel_hang_in_test_stage | 122 | |
| invoked_oom-killer:gfp_mask=0x | 9 | |
| Mem-Info | 9 | |
| BUG:kernel_hang_in_boot_stage | 8 | |
| BUG:kernel_reboot-without-warning_in_test_stage | 7 | |
| INFO:creating/lkp/benchmarks/ltp/output_directory | 3 | |
| INFO:creating/lkp/benchmarks/ltp/results_directory | 3 | |
| INFO:ltp-pan_reported_some_tests_FAIL | 2 | |
| INFO:ltp-pan_reported_all_tests_PASS | 1 | |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 1 | |
| WARNING:at_kernel/time/hrtimer.c:#hrtimer_init | 0 | 8 |
+----------------------------------------------------+-------+------------+
[ 8.264432] WARNING: CPU: 0 PID: 1 at kernel/time/hrtimer.c:1090 hrtimer_init+0x11f/0x199
[ 8.267301] Invalid clockid 4. Using MONOTONIC
[ 8.268707] Modules linked in:
[ 8.269767] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.10.0-00001-g0693cc0 #24
[ 8.272201] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 8.272696] Call Trace:
[ 8.272696] dump_stack+0x82/0xb8
[ 8.272696] __warn+0xc2/0xdd
[ 8.272696] warn_slowpath_fmt+0x4b/0x53
[ 8.272696] ? lock_is_held_type+0x4b/0x54
[ 8.272696] hrtimer_init+0x11f/0x199
[ 8.272696] ? mac80211_hwsim_get_tsf+0x1d/0x1d
[ 8.272696] tasklet_hrtimer_init+0x1b/0x4f
[ 8.272696] mac80211_hwsim_new_radio+0x7fe/0x916
[ 8.272696] ? set_debug_rodata+0x12/0x12
[ 8.272696] init_mac80211_hwsim+0x138/0x29f
[ 8.272696] ? rndis_wlan_driver_init+0x1b/0x1b
[ 8.272696] do_one_initcall+0x90/0x142
[ 8.272696] ? set_debug_rodata+0x12/0x12
[ 8.272696] kernel_init_freeable+0x1cb/0x258
[ 8.272696] ? rest_init+0x13b/0x13b
[ 8.272696] kernel_init+0xe/0xf5
[ 8.272696] ret_from_fork+0x31/0x40
[ 8.300087] ---[ end trace d6a3917592f85890 ]---
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,
Kernel Test Robot
4 years
- 1
- 0
[lkp-robot] [mm, vmscan] 5e56dfbd83: fsmark.files_per_sec -11.1% regression
by kernel test robot
Greeting,
FYI, we noticed a -11.1% regression of fsmark.files_per_sec due to commit:
commit: 5e56dfbd837421b7fa3c6c06018c6701e2704917 ("mm, vmscan: consider eligible zones in get_scan_count")
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
in testcase: fsmark
on test machine: 48 threads Intel(R) Xeon(R) CPU E5-2697 v2 @ 2.70GHz with 64G memory
with following parameters:
iterations: 1
nr_threads: 64
disk: 3HDD
md: RAID5
fs: btrfs
filesize: 4M
test_size: 130G
sync_method: NoSync
cpufreq_governor: performance
test-description: The fsmark is a file system benchmark to test synchronous write workloads, for example, mail servers workload.
test-url: https://sourceforge.net/projects/fsmark/
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: fsmark/1-64-3HDD-RAID5-btrfs-4M-130G-NoSync-performance/ivb44
69ec9c5d23a4b83a 5e56dfbd837421b7fa3c6c0601
---------------- --------------------------
%stddev change %stddev
\ | \
57.60 -11% 51.20 fsmark.files_per_sec
606 9% 659 fsmark.time.elapsed_time
606 9% 659 fsmark.time.elapsed_time.max
139 4% 145 fsmark.time.system_time
610856 626380 fsmark.time.voluntary_context_switches
311298 15% 358279 ± 8% interrupts.CAL:Function_call_interrupts
208429 -8% 191541 vmstat.io.bo
93 -5% 89 turbostat.Avg_MHz
3.17 -6% 3.00 turbostat.%Busy
576085 ± 62% -5e+05 49030 ±173% latency_stats.avg.wait_current_trans.[btrfs].start_transaction.[btrfs].btrfs_join_transaction.[btrfs].btrfs_alloc_data_chunk_ondemand.[btrfs].btrfs_check_data_free_space.[btrfs].__btrfs_buffered_write.[btrfs].btrfs_file_write_iter.[btrfs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
597933 ± 61% -5e+05 54999 ±173% latency_stats.max.wait_current_trans.[btrfs].start_transaction.[btrfs].btrfs_join_transaction.[btrfs].btrfs_alloc_data_chunk_ondemand.[btrfs].btrfs_check_data_free_space.[btrfs].__btrfs_buffered_write.[btrfs].btrfs_file_write_iter.[btrfs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
16948 ±149% -7e+03 9562 ± 56% latency_stats.sum.btrfs_insert_delayed_dir_index.[btrfs].btrfs_insert_dir_item.[btrfs].btrfs_add_link.[btrfs].btrfs_create.[btrfs].path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
915770 ±170% -9e+05 12746 ± 8% latency_stats.sum.btrfs_tree_read_lock.[btrfs].btrfs_read_lock_root_node.[btrfs].btrfs_search_slot.[btrfs].btrfs_lookup_dir_item.[btrfs].btrfs_lookup_dentry.[btrfs].btrfs_lookup.[btrfs].path_openat.do_filp_open.do_sys_open.SyS_open.entry_SYSCALL_64_fastpath
34543093 ± 63% -3e+07 1519937 ±173% latency_stats.sum.wait_current_trans.[btrfs].start_transaction.[btrfs].btrfs_join_transaction.[btrfs].btrfs_alloc_data_chunk_ondemand.[btrfs].btrfs_check_data_free_space.[btrfs].__btrfs_buffered_write.[btrfs].btrfs_file_write_iter.[btrfs].__vfs_write.vfs_write.SyS_write.entry_SYSCALL_64_fastpath
1.812e+08 ± 5% 16% 2.107e+08 ± 5% perf-stat.iTLB-loads
1152482 9% 1252381 perf-stat.minor-faults
1152506 9% 1252385 perf-stat.page-faults
4041609 9% 4389968 perf-stat.context-switches
2.927e+11 ± 5% 9% 3.177e+11 perf-stat.dTLB-loads
2.207e+08 7% 2.361e+08 perf-stat.iTLB-load-misses
47548 -4% 45696 perf-stat.cpu-migrations
5106 -6% 4812 ± 6% perf-stat.instructions-per-iTLB-miss
49.90 -12% 44.12 perf-stat.cache-miss-rate%
9.607e+09 ± 3% -13% 8.317e+09 ± 4% perf-stat.cache-misses
4.745e+09 ± 3% -20% 3.792e+09 ± 5% perf-stat.node-stores
208303 -8% 191422 iostat.md0.wkB/s
11.74 ± 3% 47% 17.26 ± 4% iostat.sda.rrqm/s
104126 -8% 95733 iostat.sda.wkB/s
25814 -8% 23674 iostat.sda.wrqm/s
931 -14% 801 iostat.sda.avgrq-sz
118.10 -34% 78.02 ± 10% iostat.sda.r_await
26.34 -48% 13.59 ± 12% iostat.sda.avgqu-sz
118.11 -50% 59.59 ± 12% iostat.sda.await
117.87 -50% 59.32 ± 12% iostat.sda.w_await
15.27 ± 4% 35% 20.61 ± 6% iostat.sdb.rrqm/s
104103 -8% 95711 iostat.sdb.wkB/s
25808 -8% 23668 iostat.sdb.wrqm/s
930 -14% 800 iostat.sdb.avgrq-sz
122.79 -33% 82.36 ± 7% iostat.sdb.r_await
27.23 -48% 14.15 ± 12% iostat.sdb.avgqu-sz
121.89 -49% 62.10 ± 13% iostat.sdb.await
121.67 -49% 61.83 ± 13% iostat.sdb.w_await
12.00 ± 5% 39% 16.63 ± 7% iostat.sdc.rrqm/s
229 17% 267 iostat.sdc.w/s
104125 -8% 95734 iostat.sdc.wkB/s
25812 -8% 23665 iostat.sdc.wrqm/s
904 -19% 733 iostat.sdc.avgrq-sz
115.80 -36% 74.64 ± 8% iostat.sdc.r_await
26.99 -47% 14.41 ± 10% iostat.sdc.avgqu-sz
118.17 -49% 59.81 ± 11% iostat.sdc.await
117.98 -49% 59.60 ± 12% iostat.sdc.w_await
perf-stat.page-faults
1.3e+06 ++---------------------------------------------------------------+
| O |
1.28e+06 O+O O O O O |
1.26e+06 ++ O OO O O O |
| O O |
1.24e+06 ++ O |
| O |
1.22e+06 ++ |
| |
1.2e+06 ++ |
1.18e+06 ++ |
| |
1.16e+06 *+ .* .*. *. .* *.* *. * |
| **.* *.** * *.**. *.* *.*.* *.*.* *.**.**. + *.*.**.**.*
1.14e+06 ++---------------------*----------------------------*------------+
perf-stat.minor-faults
1.3e+06 ++---------------------------------------------------------------+
| O |
1.28e+06 O+O O O O O |
1.26e+06 ++ O OO O O O |
| O O |
1.24e+06 ++ O |
| O |
1.22e+06 ++ |
| |
1.2e+06 ++ |
1.18e+06 ++ |
| |
1.16e+06 *+ .* .*. *. .* *.* *. * |
| **.* *.** * *.**. *.* *.*.* *.*.* *.**.**. + *.*.**.**.*
1.14e+06 ++---------------------*----------------------------*------------+
perf-stat.cache-miss-rate_
54 ++---------------------------------------------------------------------+
| |
52 ++ * * |
50 ++*. .* .*.*. *.* ** *. *. + + :: *. *.*. .*
|+ **.*.* *.* * + + + + *.**. + **.* * : + *.* *.**.* |
48 *+ * * * * |
| |
46 ++ |
| O O O |
44 ++ O O O O O O O O |
42 ++ O O O |
| O |
40 ++ O |
O |
38 ++---------------------------------------------------------------------+
fsmark.time.elapsed_time
680 ++--------------------------------------------------------------------+
O O O O O |
670 ++O O O O O O O O |
660 ++ O O |
| O |
650 ++ |
| O |
640 ++ |
| |
630 ++ |
620 ++ |
| |
610 *+ .*. .**. .*. .* |
| *.**.*.*.** * *.*.**.* **.*.* *.*.*.**.*.*.**.*.*.**.*.*.**.*
600 ++--------------------------------------------------------------------+
fsmark.time.elapsed_time.max
680 ++--------------------------------------------------------------------+
O O O O O |
670 ++O O O O O O O O |
660 ++ O O |
| O |
650 ++ |
| O |
640 ++ |
| |
630 ++ |
620 ++ |
| |
610 *+ .*. .**. .*. .* |
| *.**.*.*.** * *.*.**.* **.*.* *.*.*.**.*.*.**.*.*.**.*.*.**.*
600 ++--------------------------------------------------------------------+
fsmark.files_per_sec
58 ++---------------------------------------------------------------------+
|.*.**.*.*.**.*.*.*.**.*.*.**. .*.*.**. .*.**.*.*.**.*.*.*.**.*.*.**.*.*
57 *+ * * |
| |
56 ++ |
| |
55 ++ |
| |
54 ++ |
| |
53 ++ |
| |
52 ++ |
| |
51 O+O-OO-O-O-OO-O-O-O-OO-O-O-OO------------------------------------------+
vmstat.io.bo
210000 ++*--------------------*------*--------*---------------------------+
|+ *.*.**.*.* .*.**.*.* *.* + *.**. + *.*.**.*.**.*.**.*.**.*.**.*
* * * * |
205000 ++ |
| |
| |
200000 ++ |
| |
195000 ++ O |
| |
| O |
190000 ++ O O O |
O O O O O O O O |
| O O O O |
185000 ++-----------------------------------------------------------------+
iostat.sdb.wrqm_s
26000 ++*---------------------*------*-------*--------*-------------------+
|+ *.*.*.**.*. *.*.**.* **. + *.*.* + *.**.* *.*.**.*.*.**.*.**.*
25500 *+ * * * |
| |
| |
25000 ++ |
| |
24500 ++ |
| |
24000 ++ O |
| O |
| |
23500 ++ O O O O |
O OO O O O OO O O O |
23000 ++------------------------------------------------------------------+
iostat.sdb.wkB_s
106000 ++-----------------------------------------------------------------+
| *. |
104000 ++**.*.**.*.* .*.**.*.* *.* .*.*.**. .**.*.**.*.**.*.**.*.**.*.**.*
* * * * |
102000 ++ |
| |
100000 ++ |
| |
98000 ++ O |
| |
96000 ++ O |
| O O O |
94000 O+OO O O O OO O O O O |
| |
92000 ++-----------------------------------------------------------------+
iostat.sdb.avgrq-sz
940 ++--------------------------------------------------------------------+
*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*
920 ++ |
900 ++ |
| |
880 ++ |
860 ++ |
| |
840 ++ |
820 ++ O |
| O |
800 ++ O |
780 ++ O O O O O |
O O O OO O O O O |
760 ++--------------------------------------------------------------------+
iostat.sdb.avgqu-sz
28 ++*--------*--------*--*--------*--------*------------------*----------+
* **.*.* *.*.*.* * *.**.* *.**.* **.*.*.**.*.*.*.* *.*.**.*.*
26 ++ |
24 ++ |
| |
22 ++ |
20 ++ |
| |
18 ++ |
16 ++ O |
| O |
14 ++ O |
12 ++O OO O O O O O OO |
O O O O |
10 ++---------------------------------------------------------------------+
iostat.sdb.await
130 ++--------------------------------------------------------------------+
*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*
120 ++ |
110 ++ |
| |
100 ++ |
90 ++ |
| |
80 ++ |
70 ++ O |
| O |
60 ++ O O |
50 O+O OO O OO O O O O O O |
| |
40 ++--------------------------------------------------------------------+
iostat.sdb.w_await
130 ++--------------------------------------------------------------------+
*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*
120 ++ |
110 ++ |
| |
100 ++ |
90 ++ |
| |
80 ++ |
70 ++ O |
| O |
60 ++ O O |
50 ++O OO O OO O O O O O O |
O |
40 ++--------------------------------------------------------------------+
iostat.sda.wrqm_s
26000 ++*---------------------*------*-------*--------*-------------------+
|+ *.*.*.**.*. *.*.**.* **. + *.*.* + *.**.* *.*.**.*.*.**.*.**.*
25500 *+ * * * |
| |
| |
25000 ++ |
| |
24500 ++ |
| |
24000 ++ O |
| O |
| |
23500 ++ O O O O |
O OO O O O OO O O O |
23000 ++------------------------------------------------------------------+
iostat.sda.wkB_s
106000 ++-----------------------------------------------------------------+
| * *. *. * |
104000 ++ *.*.**.*.* .*.**.*.* *.* + *.**. + *.*.**.*.**.*.**.*.**.*.**.*
* * * * |
102000 ++ |
| |
100000 ++ |
| |
98000 ++ O |
| |
96000 ++ O |
| O O O |
94000 O+OO O O O OO O O O O |
| |
92000 ++-----------------------------------------------------------------+
iostat.sda.avgrq-sz
940 ++--------------------------------------------------------------------+
*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*
920 ++ |
900 ++ |
| |
880 ++ |
860 ++ |
| |
840 ++ |
820 ++ O |
| O |
800 ++ O |
780 ++ OO O O O O |
O O OO O O O O |
760 ++--------------------------------------------------------------------+
iostat.sda.avgqu-sz
28 ++---------------------------------------------------------------------+
|.*. *.*.*.* .*. .**.*.*.**. .*. .**. .*. *.*.*.**.*.*.*.**.*.*.**.*.*
26 *+ * *.* * * * * * |
24 ++ |
| |
22 ++ |
20 ++ |
| |
18 ++ |
16 ++ O |
| |
14 ++ O |
12 ++ O O O O O |
O O O OO O O O O O |
10 ++---------------------------------------------------------------------+
iostat.sda.await
130 ++--------------------------------------------------------------------+
| .* |
120 *+*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.* *.*.*.**.*
110 ++ |
| |
100 ++ |
90 ++ |
| |
80 ++ |
70 ++ O |
| |
60 ++ O O |
50 ++O OO O O OO O O O O |
O O O |
40 ++--------------------------------------------------------------------+
iostat.sda.w_await
130 ++--------------------------------------------------------------------+
| .* |
120 *+*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.* *.*.*.**.*
110 ++ |
| |
100 ++ |
90 ++ |
| |
80 ++ |
70 ++ O |
| |
60 ++ O O |
50 ++O OO O O OO O O O O |
O O O |
40 ++--------------------------------------------------------------------+
iostat.sdc.wrqm_s
26000 ++*---------------------*------*-------*--------*-------------------+
|+ *.*.*.**.*. *.*.**.* **. + *.*.* + *.**.* *.*.**.*.*.**.*.**.*
25500 *+ * * * |
| |
| |
25000 ++ |
| |
24500 ++ |
| |
24000 ++ O |
| O |
| |
23500 ++ O O O O |
O OO O O O OO O O O |
23000 ++------------------------------------------------------------------+
iostat.sdc.w_s
280 O+--------------------------------------------------------------------+
| O O O O O O O |
270 ++ OO O O O O |
| |
| O O O |
260 ++ |
| |
250 ++ |
| |
240 ++ |
| |
| |
230 *+*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*
| |
220 ++--------------------------------------------------------------------+
iostat.sdc.wkB_s
106000 ++-----------------------------------------------------------------+
| *. |
104000 ++**.*.**.*.* .*.**.*.* *.* .*.*.**. .**.*.**.*.**.*.**.*.**.*.**.*
* * * * |
102000 ++ |
| |
100000 ++ |
| |
98000 ++ O |
| |
96000 ++ O |
| O O O |
94000 O+OO O O O OO O O O O |
| |
92000 ++-----------------------------------------------------------------+
iostat.sdc.avgrq-sz
950 ++--------------------------------------------------------------------+
| |
900 *+*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*
| |
| |
850 ++ |
| |
800 ++ |
| |
750 ++ O |
| O O |
| OO O O O O O O O |
700 O+O O O O |
| |
650 ++--------------------------------------------------------------------+
iostat.sdc.avgqu-sz
28 ++----------------------------------------------------------*----------+
*.*.**.*.*.**.*.*.*.**.*.*.**.*.*.*.**.*.*.**.*.*.**.*.*.*.* *.*.**.*.*
26 ++ |
24 ++ |
| |
22 ++ |
20 ++ |
| |
18 ++ |
16 ++ O |
| O |
14 ++ O O O |
12 ++O OO O OO O O O O O |
O |
10 ++---------------------------------------------------------------------+
iostat.sdc.await
130 ++--------------------------------------------------------------------+
| .* |
120 *+*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.* *.*.*.**.*
110 ++ |
| |
100 ++ |
90 ++ |
| |
80 ++ |
70 ++ O |
| O |
60 ++ O |
50 ++O OO O O OO O O O O |
O O O |
40 ++--------------------------------------------------------------------+
iostat.sdc.r_await
130 ++--------------------------------------------------------------------+
| * |
120 *+*.* + * * *.**. .**.*. *. .*. *.*. *
| *.*.* + .*.**.*. + *. + *.* *.* *.*.** *. : *.* +|
110 ++ * * * * * |
| |
100 ++ |
| |
90 ++ |
| O |
80 ++ |
| O |
70 ++ O O O O O O O O O |
O O O O O O |
60 ++--------------------------------------------------------------------+
iostat.sdc.w_await
130 ++--------------------------------------------------------------------+
| .* |
120 *+*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.*.**.*.* *.*.*.**.*
110 ++ |
| |
100 ++ |
90 ++ |
| |
80 ++ |
70 ++ O |
| O |
60 ++ O |
50 ++O OO O O OO O O O O |
O O O |
40 ++--------------------------------------------------------------------+
iostat.md0.wkB_s
210000 ++---------------------*-------------------------------------------+
|.**.*.**.*.* .*.**.*.* *.* .*.*.**. .**.*.**.*.**.*.**.*.**.*.**.*
* * * * |
205000 ++ |
| |
| |
200000 ++ |
| |
195000 ++ O |
| |
| O |
190000 ++ O O O |
O O O O O O O O |
| O O O O |
185000 ++-----------------------------------------------------------------+
[*] 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
4 years
- 4
- 10
[lkp-robot] [Btrfs] 260b9778a3: fio.write_clat_99%_us -41.2% improvement
by kernel test robot
Greeting,
FYI, we noticed a -41.2% improvement of fio.write_clat_99%_us due to commit:
commit: 260b9778a3f475fd4b9401666dcbb6ef70446dee ("Btrfs: kill trans in run_delalloc_nocow and btrfs_cross_ref_exist")
https://github.com/kdave/btrfs-devel.git dev/less-likely
in testcase: fio-basic
on test machine: 56 threads Intel(R) Xeon(R) CPU E5-2695 v3 @ 2.30GHz with 256G memory
with following parameters:
disk: 2pmem
fs: btrfs
runtime: 200s
nr_task: 50%
time_based: tb
rw: randwrite
bs: 4k
ioengine: sync
test_size: 200G
cpufreq_governor: performance
test-description: Fio is a tool that will spawn a number of threads or processes doing a particular type of I/O action as specified by the user.
test-url: https://github.com/axboe/fio
Details are as below:
-------------------------------------------------------------------------------------------------->
To reproduce:
git clone 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: fio-basic/2pmem-btrfs-200s-50%-tb-randwrite-4k-sync-200G-performance/lkp-hsw-ep6
64e62d433eabd688 260b9778a3f475fd4b9401666d
---------------- --------------------------
%stddev %change %stddev
\ | \
1.49 ± 3% -83.7% 0.24 ± 3% fio.latency_10ms%
0.19 ± 6% -10.8% 0.17 ± 2% fio.latency_10us%
0.08 ± 0% -62.5% 0.03 ± 0% fio.latency_20ms%
0.79 ± 0% -10.8% 0.71 ± 7% fio.latency_20us%
5.44 ± 0% +2.6% 5.58 ± 0% fio.latency_250us%
1.29 ± 2% +37.0% 1.77 ± 1% fio.latency_4ms%
31484768 ± 1% +4.4% 32856328 ± 1% fio.time.file_system_outputs
3834246 ± 1% +4.4% 4002940 ± 1% fio.time.voluntary_context_switches
76.87 ± 1% +4.3% 80.19 ± 1% fio.write_bw_MBps
1528 ± 1% +3.4% 1580 ± 0% fio.write_clat_95%_us
5173 ± 0% -41.2% 3040 ± 1% fio.write_clat_99%_us
1416 ± 1% -4.2% 1357 ± 1% fio.write_clat_mean_us
5956 ± 1% -2.7% 5796 ± 1% fio.write_clat_stddev
19677 ± 1% +4.3% 20528 ± 1% fio.write_iops
2499 ± 2% +31.2% 3279 ± 5% meminfo.Writeback
419623 ± 1% +14.6% 480772 ± 0% softirqs.SCHED
2977 ± 4% -12.3% 2612 ± 5% time.involuntary_context_switches
632.67 ± 3% +28.5% 813.00 ± 5% proc-vmstat.nr_writeback
654980 ± 1% -10.1% 589101 ± 1% proc-vmstat.pgfree
487100 ± 1% +5.7% 514957 ± 1% vmstat.io.bo
255300 ± 1% +11.9% 285769 ± 1% vmstat.system.cs
15.28 ± 0% +10.6% 16.90 ± 0% turbostat.%Busy
184.67 ± 0% +10.7% 204.50 ± 0% turbostat.Avg_MHz
61.15 ± 0% +2.0% 62.35 ± 0% turbostat.PkgWatt
6.334e+08 ± 2% +12.1% 7.098e+08 ± 1% cpuidle.C1-HSW.time
19509883 ± 2% +13.2% 22093431 ± 1% cpuidle.C1-HSW.usage
1.539e+08 ± 1% +12.6% 1.733e+08 ± 0% cpuidle.C1E-HSW.time
2489717 ± 1% +19.2% 2967279 ± 1% cpuidle.C1E-HSW.usage
124554 ± 3% +9.9% 136926 ± 2% cpuidle.POLL.usage
88232 ± 36% -48.8% 45174 ± 46% numa-meminfo.node0.Active(anon)
41771 ± 22% -44.2% 23304 ± 70% numa-meminfo.node0.AnonHugePages
1382 ± 12% +16.2% 1606 ± 8% numa-meminfo.node0.Writeback
17769 ± 51% +113.0% 37851 ± 40% numa-meminfo.node1.AnonHugePages
4275350 ± 7% +10.7% 4734943 ± 3% numa-meminfo.node1.Inactive(file)
5183 ± 2% +13.7% 5893 ± 5% numa-meminfo.node1.KernelStack
1174 ± 7% +40.9% 1654 ± 10% numa-meminfo.node1.Writeback
22058 ± 36% -48.8% 11293 ± 46% numa-vmstat.node0.nr_active_anon
fio.write_clat_99__us
6000 ++-------------------------------------------------------------------+
| .*. .*. .*.. .*.. .*.. .*..*. |
5000 *+*..*.*..*.*. *..*.*..*.*..*.*. * * * * *..*.* *
| : |
| : |
4000 ++ : |
| O O O O : :|
3000 O+O O O O O O O O O O O O O O O O O O : :|
| : :|
2000 ++ : :|
| : :|
| : :|
1000 ++ : |
| : |
0 ++-----------------------------------------------------------------*-+
fio.latency_4ms_
2.5 ++--------------------------------------------------------------------+
| O O |
| O O |
2 ++ O |
O O O O O O O O O O O O O O |
| O O O O |
1.5 *+.*. .*.. .*. |
| *..*.*..*.*..*.*..* *.*..*.*..*.*..*.*. *..*.*..*.*..*.* *
1 ++ : |
| : :|
| : :|
0.5 ++ : :|
| : :|
| : |
0 ++------------------------------------------------------------------*-+
fio.latency_10ms_
1.8 ++--------------------------------------------------------------------+
| .*.. *.. |
1.6 *+.*.*..*.*..*.*..*.*..*.*..* + *.*..*.*..*.*..*.*..*.*.. .* *
1.4 ++ * * : |
| : |
1.2 ++ : |
1 ++ : :|
| : :|
0.8 ++ : :|
0.6 ++ : :|
| : :|
0.4 ++ : :|
0.2 O+ O O O O O O O O O O O O O O O O O O O O O O : |
| : |
0 ++------------------------------------------------------------------*-+
fio.latency_20ms_
0.12 ++-------------------------------------------------------------------+
| * * * * *.. |
0.1 ++ + : : : :: :+ * : * |
| + : : : : : : + + + : : |
*.*.. .*.* : .*. .*. : :: : : * + : : |
0.08 ++ .*. *. *..*.*. * * * * *..*.* *
| * : |
0.06 ++ : |
| : :|
0.04 ++ O O O O : :|
| : :|
O O O O O O O O O O O O O O O O O O : :|
0.02 ++ O :: |
| : |
0 ++-----------------------------------------------------------------*-+
turbostat.Avg_MHz
250 ++--------------------------------------------------------------------+
| |
O O O O O O O O O |
200 ++ O O O O O .*. O O O O O O O O O |
*..*.*..*.*..*.*..*.*..*.*. *..*.*..*.*..*.*..*.*..*.*..*.*..*.* *
| : |
150 ++ : |
| : :|
100 ++ : :|
| : :|
| : :|
50 ++ : :|
| :: |
| : |
0 ++------------------------------------------------------------------*-+
turbostat._Busy
18 ++---------------------------------------------------------------------+
O O O O O O O O O O O O O O O O O O O O O O O |
16 *+.*.*..*.*..*.*..*.*..*.*..*.*..*..*.*..*.*..*.*..*.*..*.*..*.*..* *
14 ++ : |
| : |
12 ++ : :|
10 ++ : :|
| : :|
8 ++ : :|
6 ++ : : |
| : : |
4 ++ :: |
2 ++ :: |
| : |
0 ++------------------------------------------------------------------*--+
[*] 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
4 years
- 1
- 0
[lkp-robot] [drm/i915] 5a4c6f1b1b: piglit.igt.drv_hangman.error-state-basic.fail
by kernel test robot
FYI, we noticed the following commit:
commit: 5a4c6f1b1b2d91c5252cedf1c7b68a894c3f117e ("drm/i915: The return of i915_gpu_info to debugfs")
git://anongit.freedesktop.org/drm-intel drm-intel-next-queued
in testcase: piglit
with following parameters:
group: igt-basic
test-description: Piglit is a collection of automated tests for OpenGL implementations.
test-url: https://people.freedesktop.org/~nh/piglit/
on test machine: 8 threads Intel(R) Core(TM) i7-6700 CPU @ 3.40GHz with 8G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
user :notice: [ 28.580274] 2017-02-18 20:11:49 piglit run igt -t igt/drv_hangman/error-state-basic /tmp/lkp/piglit-results -x igt/drv_hangman/error-state-basic.
user :info : [ 29.887103] [IGT] drv_hangman: executing
user :info : [ 29.891468] [IGT] drv_hangman: starting subtest error-state-basic
user :info : [ 29.898419] [IGT] drv_hangman: exiting, ret=99
user :notice: [ 29.908582] [1/1] fail: 1 |
user :notice: [ 29.979716] [1/1] fail: 1
user :notice: [ 29.986934]
user :notice: [ 29.992095] Thank you for running Piglit!
user :notice: [ 29.998897] Results have been written to /tmp/lkp/piglit-results
user :notice: [ 30.007823] igt/drv_hangman/error-state-basic.seconds: 1.515051095
user :notice: [ 30.017154] 2017-02-18 20:11:51 piglit summary console /tmp/lkp/piglit-results/results.json.bz2
user :notice: [ 30.094068] igt/drv_hangman/error-state-basic: fail
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Xiaolong
4 years
- 1
- 0
[lkp-robot] [mm, vmscan] 74e6b057d2: fsmark.files_per_sec -11.1% regression
by kernel test robot
Hi, Michal
Just FYI, this is another bisect result captured by 0day. This is actually the
same as pervious "[mm, vmscan] 5e56dfbd83: fsmark.files_per_sec -11.1% regression",
just commit sha change due to linux-next rebase.
commit: 74e6b057d2406d596f60fbdd279d1a4e6b830aac ("mm, vmscan: consider eligible zones in get_scan_count")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master
in testcase: fsmark
on test machine: 48 threads Intel(R) Xeon(R) CPU E5-2697 v2 @ 2.70GHz with 64G memory
with following parameters:
iterations: 1
nr_threads: 64
disk: 3HDD
md: RAID5
fs: btrfs
filesize: 4M
test_size: 130G
sync_method: NoSync
cpufreq_governor: performance
test-description: The fsmark is a file system benchmark to test synchronous write workloads, for example, mail servers workload.
test-url: https://sourceforge.net/projects/fsmark/
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: fsmark/1-64-3HDD-RAID5-btrfs-4M-130G-NoSync-performance/ivb44
588efaf242c620c2 74e6b057d2406d596f60fbdd27
---------------- --------------------------
%stddev change %stddev
\ | \
57.60 -11% 51.20 fsmark.files_per_sec
608 10% 668 fsmark.time.elapsed_time
608 10% 668 fsmark.time.elapsed_time.max
139 6% 148 fsmark.time.system_time
617366 631564 fsmark.time.voluntary_context_switches
14194 ± 14% -22% 11017 ± 5% fsmark.time.involuntary_context_switches
325773 ± 4% 10% 357414 ± 7% interrupts.CAL:Function_call_interrupts
207718 -9% 188656 vmstat.io.bo
93 -5% 89 turbostat.Avg_MHz
5.17 -6% 4.84 turbostat.RAMWatt
3.22 ± 4% -7% 3.00 turbostat.%Busy
1154955 10% 1267383 perf-stat.page-faults
1154939 10% 1267363 perf-stat.minor-faults
4098974 8% 4428264 perf-stat.context-switches
50.11 -16% 41.96 perf-stat.cache-miss-rate%
0.52 ± 9% -16% 0.44 ± 11% perf-stat.dTLB-load-miss-rate%
1.521e+09 ± 11% -16% 1.274e+09 ± 16% perf-stat.dTLB-load-misses
9.988e+09 -17% 8.291e+09 ± 6% perf-stat.cache-misses
4.616e+09 -18% 3.776e+09 ± 10% perf-stat.node-stores
207602 -9% 188553 iostat.md0.wkB/s
12.05 ± 5% 47% 17.75 ± 10% iostat.sda.rrqm/s
103778 -9% 94296 iostat.sda.wkB/s
25724 -9% 23312 iostat.sda.wrqm/s
931 -15% 789 iostat.sda.avgrq-sz
118.65 -37% 74.29 ± 7% iostat.sda.r_await
26.37 -53% 12.39 ± 6% iostat.sda.avgqu-sz
118.68 -54% 54.60 ± 6% iostat.sda.await
118.43 -54% 54.33 ± 6% iostat.sda.w_await
15.16 ± 6% 36% 20.58 ± 6% iostat.sdb.rrqm/s
103754 -9% 94275 iostat.sdb.wkB/s
25719 -9% 23308 iostat.sdb.wrqm/s
931 -15% 788 iostat.sdb.avgrq-sz
122.91 -38% 76.07 ± 4% iostat.sdb.r_await
27.43 -52% 13.08 ± 6% iostat.sdb.avgqu-sz
123.40 -53% 57.69 ± 7% iostat.sdb.await
123.18 -53% 57.43 ± 7% iostat.sdb.w_await
11.91 ± 4% 45% 17.24 ± 11% iostat.sdc.rrqm/s
228 18% 270 iostat.sdc.w/s
103780 -9% 94299 iostat.sdc.wkB/s
25723 -9% 23303 iostat.sdc.wrqm/s
905 -21% 716 iostat.sdc.avgrq-sz
115.29 -40% 69.74 ± 4% iostat.sdc.r_await
26.95 -51% 13.22 ± 6% iostat.sdc.avgqu-sz
118.48 -54% 54.83 ± 6% iostat.sdc.await
118.28 -54% 54.62 ± 6% iostat.sdc.w_await
perf-stat.page-faults
1.28e+06 ++-O-------------------------------------------------------------+
O O O |
1.26e+06 ++ |
| O |
1.24e+06 ++ |
| |
1.22e+06 ++ |
| |
1.2e+06 ++ |
| |
1.18e+06 ++ |
| .*.. .*.. .*. .*.*.. *.. |
1.16e+06 ++.*. .* *. .*..* .*.. .*. *..*.*. + .*.. .*
*. *. *..* * * * * * |
1.14e+06 ++---------------------------------------------------------------+
perf-stat.minor-faults
1.28e+06 ++-O-------------------------------------------------------------+
O O O |
1.26e+06 ++ |
| O |
1.24e+06 ++ |
| |
1.22e+06 ++ |
| |
1.2e+06 ++ |
| |
1.18e+06 ++ |
| .*.. .*.. .*. .*.*.. *.. |
1.16e+06 ++.*. .* *. .*..* .*.. .*. *..*.*. + .*.. .*
*. *. *..* * * * * * |
1.14e+06 ++---------------------------------------------------------------+
perf-stat.cache-miss-rate_
52 ++---------------------------------------------------------------------+
| .*.. * *.*..|
50 ++ .*. + + *.*.. *.. *.. *.. + |
*..* *.. + + .. .. + .*..*.. + *.. + *
| *.* * * *. + * .* * |
48 ++ * *. |
| |
46 ++ |
| |
44 ++ |
| O |
O |
42 ++ O O |
| O |
40 ++---------------------------------------------------------------------+
fsmark.time.elapsed_time
680 ++--------------------------------------------------------------------+
O |
670 ++ O O O |
660 ++ O |
| |
650 ++ |
| |
640 ++ |
| |
630 ++ |
620 ++ |
| .*.. |
610 *+. .*.*.. .*..*.*..*.. .*.*..*..*.*. *.. .*.. .*.*..|
| *.*..*. *. *.*..*. * *. *
600 ++--------------------------------------------------------------------+
fsmark.time.elapsed_time.max
680 ++--------------------------------------------------------------------+
O |
670 ++ O O O |
660 ++ O |
| |
650 ++ |
| |
640 ++ |
| |
630 ++ |
620 ++ |
| .*.. |
610 *+. .*.*.. .*..*.*..*.. .*.*..*..*.*. *.. .*.. .*.*..|
| *.*..*. *. *.*..*. * *. *
600 ++--------------------------------------------------------------------+
fsmark.files_per_sec
58 ++---------------------------------------------------------------------+
*..*.*..*..*..*.*..*..*..*. .*..*..*.*..*..*.*..* *.*..*..*..*.*..*
57 ++ *. + + |
| + + |
56 ++ * |
| |
55 ++ |
| |
54 ++ |
| |
53 ++ |
| |
52 ++ |
| |
51 O+-O-O--O--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
4 years
- 1
- 0