Re: [LKP] [rcu] kernel BUG at include/linux/pagemap.h:149!
by Frederic Weisbecker
On Fri, Sep 11, 2015 at 10:19:47AM +0800, Boqun Feng wrote:
> Subject: [PATCH 01/27] rcu: Don't disable preemption for Tiny and Tree RCU
> readers
>
> Because preempt_disable() maps to barrier() for non-debug builds,
> it forces the compiler to spill and reload registers. Because Tree
> RCU and Tiny RCU now only appear in CONFIG_PREEMPT=n builds, these
> barrier() instances generate needless extra code for each instance of
> rcu_read_lock() and rcu_read_unlock(). This extra code slows down Tree
> RCU and bloats Tiny RCU.
>
> This commit therefore removes the preempt_disable() and preempt_enable()
> from the non-preemptible implementations of __rcu_read_lock() and
> __rcu_read_unlock(), respectively.
>
> For debug purposes, preempt_disable() and preempt_enable() are still
> kept if CONFIG_PREEMPT_COUNT=y, which makes the detection of sleeping
> inside atomic sections still work in non-preemptible kernels.
>
> Signed-off-by: Boqun Feng <boqun.feng(a)gmail.com>
> Signed-off-by: Paul E. McKenney <paulmck(a)linux.vnet.ibm.com>
> ---
> include/linux/rcupdate.h | 6 ++++--
> include/linux/rcutiny.h | 1 +
> kernel/rcu/tree.c | 9 +++++++++
> 3 files changed, 14 insertions(+), 2 deletions(-)
>
> diff --git a/include/linux/rcupdate.h b/include/linux/rcupdate.h
> index d63bb77..6c3cece 100644
> --- a/include/linux/rcupdate.h
> +++ b/include/linux/rcupdate.h
> @@ -297,12 +297,14 @@ void synchronize_rcu(void);
>
> static inline void __rcu_read_lock(void)
> {
> - preempt_disable();
> + if (IS_ENABLED(CONFIG_PREEMPT_COUNT))
> + preempt_disable();
preempt_disable() is a no-op when !CONFIG_PREEMPT_COUNT, right?
Or rather it's a barrier(), which is anyway implied by rcu_read_lock().
So perhaps we can get rid of the IS_ENABLED() check?
2 years, 7 months
[x86/KASLR] ed9f007ee6: -- System halted
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/tip/tip.git x86/boot
commit ed9f007ee68478f6a50ec9971ade25a0129a5c0e ("x86/KASLR: Extend kernel image physical address randomization to addresses larger than 4G")
on test machine: 1 threads qemu-system-x86_64 -enable-kvm -cpu SandyBridge with 320M memory
caused below changes:
+-------------------------+------------+------------+
| | 8391c73c96 | ed9f007ee6 |
+-------------------------+------------+------------+
| boot_successes | 12 | 1 |
| boot_failures | 0 | 18 |
| System_halted | 0 | 16 |
| BUG:kernel_boot_hang | 0 | 16 |
| BUG:kernel_boot_crashed | 0 | 2 |
+-------------------------+------------+------------+
XZ-compressed data is corrupt
-- System halted
Elapsed time: 440
BUG: kernel boot hang
Linux version 4.7.0-rc3-00015-ged9f007 #1
Command line: root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-x86_64-24/rand_boot-1-yocto-minimal-x86_64.cgz-x86_64-randconfig-s0-06271005-ed9f007ee68478f6a50ec9971ade25a0129a5c0e-20160627-104046-1ydmxyc-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s0-06271005 branch=linux-devel/devel-spot-201606270954 commit=ed9f007ee68478f6a50ec9971ade25a0129a5c0e BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s0-06271005/gcc-6/ed9f007ee68478f6a50ec9971ade25a0129a5c0e/vmlinuz-4.7.0-rc3-00015-ged9f007 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-x86_64/yocto-minimal-x86_64.cgz/x86_64-randconfig-s0-06271005/gcc-6/ed9f007ee68478f6a50ec9971ade25a0129a5c0e/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-x86_64-24::dhcp drbd.minor_count=8
qemu-system-x86_64 -enable-kvm -cpu SandyBridge -kernel /pkg/linux/x86_64-randconfig-s0-06271005/gcc-6/ed9f007ee68478f6a50ec9971ade25a0129a5c0e/vmlinuz-4.7.0-rc3-00015-ged9f007 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-x86_64-24/rand_boot-1-yocto-minimal-x86_64.cgz-x86_64-randconfig-s0-06271005-ed9f007ee68478f6a50ec9971ade25a0129a5c0e-20160627-104046-1ydmxyc-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s0-06271005 branch=linux-devel/devel-spot-201606270954 commit=ed9f007ee68478f6a50ec9971ade25a0129a5c0e BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s0-06271005/gcc-6/ed9f007ee68478f6a50ec9971ade25a0129a5c0e/vmlinuz-4.7.0-rc3-00015-ged9f007 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-x86_64/yocto-minimal-x86_64.cgz/x86_64-randconfig-s0-06271005/gcc-6/ed9f007ee68478f6a50ec9971ade25a0129a5c0e/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-x86_64-24::dhcp drbd.minor_count=8' -initrd /fs/sdg1/initrd-vm-kbuild-yocto-x86_64-24 -m 320 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdg1/disk0-vm-kbuild-yocto-x86_64-24,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-x86_64-24 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-x86_64-24 -daemonize -display none -monitor null
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu SandyBridge -kernel /pkg/linux/x86_64-randconfig-s0-06271005/gcc-6/ed9f007ee68478f6a50ec9971ade25a0129a5c0e/vmlinuz-4.7.0-rc3-00015-ged9f007 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-x86_64-24/rand_boot-1-yocto-minimal-x86_64.cgz-x86_64-randconfig-s0-06271005-ed9f007ee68478f6a50ec9971ade25a0129a5c0e-20160627-104046-1ydmxyc-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s0-06271005 branch=linux-devel/devel-spot-201606270954 commit=ed9f007ee68478f6a50ec9971ade25a0129a5c0e BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s0-06271005/gcc-6/ed9f007ee68478f6a50ec9971ade25a0129a5c0e/vmlinuz-4.7.0-rc3-00015-ged9f007 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-x86_64/yocto-minimal-x86_64.cgz/x86_64-randconfig-s0-06271005/gcc-6/ed9f007ee68478f6a50ec9971ade25a0129a5c0e/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-x86_64-24::dhcp drbd.minor_count=8' -initrd /fs/sdg1/initrd-vm-kbuild-yocto-x86_64-24 -m 320 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdg1/disk0-vm-kbuild-yocto-x86_64-24,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-x86_64-24 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-x86_64-24 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
5 years, 7 months
[lkp] [ASoC] 91c1832579: BUG: unable to handle kernel NULL pointer dereference
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 91c1832579700891747820862633f9a8d0d81fa4 ("ASoC: Intel: Skylake: Increase loglevel of debug messages.")
in testcase: boot
on test machine: 1 threads qemu-system-i386 -enable-kvm with 360M memory
caused below changes:
+------------------------------------------------------------+------------+------------+
| | ef06b6f391 | 91c1832579 |
+------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 14 | 14 |
| genirq:Flags_mismatch_irq##(serial)vs.#(goldfish_pdev_bus) | 14 | |
| invoked_oom-killer:gfp_mask=0x | 1 | |
| Mem-Info | 1 | |
| Out_of_memory:Kill_process | 1 | |
| backtrace:do_writev | 1 | |
| backtrace:SyS_writev | 1 | |
| backtrace:vfs_write | 1 | |
| backtrace:SyS_write | 1 | |
| BUG:unable_to_handle_kernel | 0 | 14 |
| Oops:#[##] | 0 | 14 |
| EIP_is_at_strlen | 0 | 14 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 14 |
| backtrace:dynamic_debug_init | 0 | 14 |
| backtrace:kernel_init_freeable | 0 | 14 |
+------------------------------------------------------------+------------+------------+
[ 0.291679] Performance Events:
[ 0.291679] Performance Events: Broken PMU hardware detected, using software events only.
Broken PMU hardware detected, using software events only.
[ 0.309122] BUG: unable to handle kernel
[ 0.309122] BUG: unable to handle kernel NULL pointer dereferenceNULL pointer dereference at (null)
at (null)
[ 0.310586] IP:
[ 0.310586] IP: [<c131a701>] strlen+0xb/0x17
[<c131a701>] strlen+0xb/0x17
[ 0.311438] *pdpt = 0000000000000000
[ 0.311438] *pdpt = 0000000000000000 *pde = f000ff53f000ff53 *pde = f000ff53f000ff53
[ 0.312549] Oops: 0000 [#1]
[ 0.312549] Oops: 0000 [#1]
[ 0.313064] CPU: 0 PID: 1 Comm: swapper Not tainted 4.7.0-rc1-00046-g91c1832 #1
[ 0.313064] CPU: 0 PID: 1 Comm: swapper Not tainted 4.7.0-rc1-00046-g91c1832 #1
[ 0.314469] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 0.314469] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 0.316115] task: c0058040 ti: c004e000 task.ti: c004e000
[ 0.316115] task: c0058040 ti: c004e000 task.ti: c004e000
[ 0.317110] EIP: 0060:[<c131a701>] EFLAGS: 00210246 CPU: 0
[ 0.317110] EIP: 0060:[<c131a701>] EFLAGS: 00210246 CPU: 0
[ 0.318160] EIP is at strlen+0xb/0x17
[ 0.318160] EIP is at strlen+0xb/0x17
[ 0.318866] EAX: 00000000 EBX: c21facc0 ECX: ffffffff EDX: c20bc808
[ 0.318866] EAX: 00000000 EBX: c21facc0 ECX: ffffffff EDX: c20bc808
[ 0.320029] ESI: 00000004 EDI: 00000000 EBP: c004ff00 ESP: c004fefc
[ 0.320029] ESI: 00000004 EDI: 00000000 EBP: c004ff00 ESP: c004fefc
[ 0.321264] DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068
[ 0.321264] DS: 007b ES: 007b FS: 0000 GS: 00e0 SS: 0068
[ 0.322264] CR0: 80050033 CR2: 00000000 CR3: 022a1000 CR4: 000006b0
[ 0.322264] CR0: 80050033 CR2: 00000000 CR3: 022a1000 CR4: 000006b0
[ 0.323471] Stack:
[ 0.323471] Stack:
[ 0.323861] 0000003a
[ 0.323861] 0000003a c004ff2c c004ff2c c2225b46 c2225b46 c20bc808 c20bc808 00000024 00000024 00001aa5 00001aa5 000adc33 000adc33 00000256 00000256
[ 0.325492] c21fac60
[ 0.325492] c21fac60 c2225abd c2225abd ffffffff ffffffff 00000000 00000000 c004ff8c c004ff8c c2201c4f c2201c4f 7fffffff 7fffffff c2250884 c2250884
[ 0.327033] c0058000
[ 0.327033] c0058000 7fffffff 7fffffff c004ff4c c004ff4c c1ad6a4b c1ad6a4b c004ff80 c004ff80 c1ad4825 c1ad4825 00000000 00000000 c1ad61f2 c1ad61f2
[ 0.328680] Call Trace:
[ 0.328680] Call Trace:
[ 0.329134] [<c2225b46>] dynamic_debug_init+0x89/0x1ee
[ 0.329134] [<c2225b46>] dynamic_debug_init+0x89/0x1ee
[ 0.330100] [<c2225abd>] ? dynamic_debug_init_debugfs+0x5f/0x5f
[ 0.330100] [<c2225abd>] ? dynamic_debug_init_debugfs+0x5f/0x5f
[ 0.331237] [<c2201c4f>] do_one_initcall+0x89/0x112
[ 0.331237] [<c2201c4f>] do_one_initcall+0x89/0x112
[ 0.332161] [<c1ad6a4b>] ? _raw_spin_unlock_irq+0xd/0x15
[ 0.332161] [<c1ad6a4b>] ? _raw_spin_unlock_irq+0xd/0x15
[ 0.333158] [<c1ad4825>] ? __wait_for_common+0xed/0xf7
[ 0.333158] [<c1ad4825>] ? __wait_for_common+0xed/0xf7
[ 0.334158] [<c1ad61f2>] ? console_conditional_schedule+0x24/0x24
[ 0.334158] [<c1ad61f2>] ? console_conditional_schedule+0x24/0x24
[ 0.335303] [<c2201d1e>] kernel_init_freeable+0x46/0x165
[ 0.335303] [<c2201d1e>] kernel_init_freeable+0x46/0x165
[ 0.336383] [<c1ad2e5d>] kernel_init+0x8/0xcb
[ 0.336383] [<c1ad2e5d>] kernel_init+0x8/0xcb
[ 0.337209] [<c1ad6c62>] ret_from_kernel_thread+0xe/0x24
[ 0.337209] [<c1ad6c62>] ret_from_kernel_thread+0xe/0x24
[ 0.338236] [<c1ad2e55>] ? rest_init+0x65/0x65
[ 0.338236] [<c1ad2e55>] ? rest_init+0x65/0x65
[ 0.339151] Code:
[ 0.339151] Code: 5d 5d c3 c3 55 55 89 89 e5 e5 56 56 89 89 c6 c6 89 89 d0 d0 88 88 c4 c4 ac ac 38 38 e0 e0 74 74 09 09 84 84 c0 c0 75 75 f7 f7 be be 01 01 00 00 00 00 00 00 89 89 f0 f0 48 48 5e 5e 5d 5d c3 c3 55 55 83 83 c9 c9 ff ff 89 89 e5 e5 57 57 89 89 c7 c7 31 31 c0 c0 <f2> <f2> ae ae 5f 5f b8 b8 fe fe ff ff ff ff ff ff 29 29 c8 c8 5d 5d c3 c3 85 85 c9 c9 74 74 16 16 55 55 89 89 e5 e5 57 57 89 89
[ 0.343525] EIP: [<c131a701>]
[ 0.343525] EIP: [<c131a701>] strlen+0xb/0x17strlen+0xb/0x17 SS:ESP 0068:c004fefc
SS:ESP 0068:c004fefc
[ 0.344739] CR2: 0000000000000000
[ 0.344739] CR2: 0000000000000000
[ 0.345342] ---[ end trace 04f806a67a86eccc ]---
[ 0.345342] ---[ end trace 04f806a67a86eccc ]---
FYI, raw QEMU command line is:
qemu-system-i386 -enable-kvm -kernel /pkg/linux/i386-randconfig-c0-06280858/gcc-6/91c1832579700891747820862633f9a8d0d81fa4/vmlinuz-4.7.0-rc1-00046-g91c1832 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-quantal-i386-3/rand_boot-1-quantal-core-i386.cgz-i386-randconfig-c0-06280858-91c1832579700891747820862633f9a8d0d81fa4-20160628-92876-1qu8nei-0.yaml ARCH=i386 kconfig=i386-randconfig-c0-06280858 branch=linux-devel/devel-hourly-2016062804 commit=91c1832579700891747820862633f9a8d0d81fa4 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-06280858/gcc-6/91c1832579700891747820862633f9a8d0d81fa4/vmlinuz-4.7.0-rc1-00046-g91c1832 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-quantal-i386/quantal-core-i386.cgz/i386-randconfig-c0-06280858/gcc-6/91c1832579700891747820862633f9a8d0d81fa4/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-lkp-wsx03-quantal-i386-3::dhcp drbd.minor_count=8' -initrd /fs/sdc1/initrd-vm-lkp-wsx03-quantal-i386-3 -m 360 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-quantal-i386-3 -serial file:/dev/shm/kboot/serial-vm-lkp-wsx03-quantal-i386-3 -daemonize -display none -monitor null
Thanks,
Xiaolong
5 years, 7 months
[lkp] [sched] 325e2efd8c: BUG: unable to handle kernel paging request at ffff88000ea0befc
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/vmap_stack
commit 325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79 ("sched: Free the stack early if CONFIG_THREAD_INFO_IN_TASK")
in testcase: boot
on test machine: 1 threads qemu-system-x86_64 -enable-kvm -cpu Westmere with 320M memory
caused below changes:
+--------------------------------------------------------------+------------+------------+
| | 7117d60112 | 325e2efd8c |
+--------------------------------------------------------------+------------+------------+
| boot_successes | 91 | 1 |
| boot_failures | 9 | 30 |
| Out_of_memory:Kill_process | 4 | |
| BUG:kernel_test_oversize | 4 | |
| WARNING:at_arch/x86/include/asm/fpu/internal.h:#fpu__restore | 1 | |
| BUG:unable_to_handle_kernel | 0 | 30 |
| Oops | 0 | 30 |
| RIP:do_raw_spin_lock | 0 | 30 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 30 |
| backtrace:kthread_stop | 0 | 30 |
| backtrace:test_rht_init | 0 | 30 |
| backtrace:kernel_init_freeable | 0 | 30 |
+--------------------------------------------------------------+------------+------------+
[ 1.603591] Test failed: thread 6 returned: -12
[ 1.603595] Test failed: thread 7 returned: -12
[ 1.631305] Test failed: thread 8 returned: -12
[ 1.632027] BUG: unable to handle kernel paging request at ffff88000ea0befc
[ 1.633135] IP: [<ffffffff8111599a>] do_raw_spin_lock+0x2a/0x120
[ 1.634101] PGD 55e1067 PUD 55e2067 PMD 1398b067 PTE 800000000ea0b060
[ 1.635199] Oops: 0000 [#1] PREEMPT DEBUG_PAGEALLOC
[ 1.635935] CPU: 0 PID: 1 Comm: swapper Not tainted 4.7.0-rc4-00256-g325e2ef #2
[ 1.637047] task: ffff880013552600 task.stack: ffff880013558000
[ 1.637979] RIP: 0010:[<ffffffff8111599a>] [<ffffffff8111599a>] do_raw_spin_lock+0x2a/0x120
[ 1.639304] RSP: 0000:ffff88001355bd18 EFLAGS: 00010082
[ 1.640118] RAX: 0000000000000000 RBX: ffff88000ea0bef8 RCX: 0000000000000000
[ 1.641206] RDX: 0000000000000000 RSI: 00000000000005a8 RDI: ffff88000ea0bef8
[ 1.642294] RBP: ffff88001355bd30 R08: 0000000000001000 R09: 0000000000000002
[ 1.643376] R10: 0000000000000067 R11: 00003ffffffff000 R12: ffff88000ea0bef8
[ 1.644453] R13: ffff88000ea0bef0 R14: ffff880013552600 R15: ffff880013552600
[ 1.645539] FS: 0000000000000000(0000) GS:ffffffff8304c000(0000) knlGS:0000000000000000
[ 1.646755] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1.647647] CR2: ffff88000ea0befc CR3: 000000000300c000 CR4: 00000000000006b0
[ 1.648718] Stack:
[ 1.649055] ffff88000ea0bef8 ffff88000ea0bef8 ffff88000ea0bef0 ffff88001355bd48
[ 1.650269] ffffffff82793ca1 7fffffffffffffff ffff88001355bdb0 ffffffff8278ecf3
[ 1.651467] ffff88001355bd78 0000000000000001 ffff880013552600 ffffffff811007b0
[ 1.652679] Call Trace:
[ 1.653048] [<ffffffff82793ca1>] _raw_spin_lock_irq+0x21/0x30
[ 1.653944] [<ffffffff8278ecf3>] wait_for_completion+0x133/0x1c0
[ 1.654887] [<ffffffff811007b0>] ? sched_set_stop_task+0x60/0x60
[ 1.655807] [<ffffffff810f801b>] kthread_stop+0x5b/0xa0
[ 1.656623] [<ffffffff8449d644>] test_rht_init+0xa87/0xb40
[ 1.657476] [<ffffffff8449cbbd>] ? test_kstrtox_init+0xb9b/0xb9b
[ 1.658454] [<ffffffff8446b454>] do_one_initcall+0xd1/0x164
[ 1.659328] [<ffffffff8446b71b>] kernel_init_freeable+0x234/0x2ff
[ 1.660288] [<ffffffff827843da>] kernel_init+0xa/0x110
[ 1.661047] [<ffffffff8279449d>] ret_from_fork+0x1d/0x40
[ 1.661869] [<ffffffff827843d0>] ? rest_init+0x80/0x80
[ 1.662667] Code: 00 55 48 89 e5 48 83 ec 18 48 85 ff 48 89 5d e8 4c 89 65 f0 48 89 fb 4c 89 6d f8 75 0e 31 f6 48 c7 c7 c0 8b 0d 83 e8 a6 dd 69 00 <81> 7b 04 ad 4e ad de 74 18 e8 a8 37 63 00 85 c0 74 0f 48 c7 c6
[ 1.666891] RIP [<ffffffff8111599a>] do_raw_spin_lock+0x2a/0x120
[ 1.667830] RSP <ffff88001355bd18>
[ 1.668391] CR2: ffff88000ea0befc
[ 1.668904] ---[ end trace fdce1905cee5a7a0 ]---
[ 1.669633] Kernel panic - not syncing: Fatal exception
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Westmere -kernel /pkg/linux/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/vmlinuz-4.7.0-rc4-00256-g325e2ef -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-ia32-28/bisect_boot-1-yocto-minimal-i386.cgz-x86_64-randconfig-s4-06300257-325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79-20160630-98844-15a2z12-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s4-06300257 branch=linux-devel/devel-spot-201606300239 commit=325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/vmlinuz-4.7.0-rc4-00256-g325e2ef max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-ia32/yocto-minimal-i386.cgz/x86_64-randconfig-s4-06300257/gcc-6/325e2efd8c4ae6b4bf328e0ede2acfe8b3b48e79/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-ia32-28::dhcp drbd.minor_count=8' -initrd /fs/sdg1/initrd-vm-kbuild-yocto-ia32-28 -m 320 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdg1/disk0-vm-kbuild-yocto-ia32-28,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-ia32-28 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-ia32-28 -daemonize -display none -monitor null
Thanks,
Xiaolong
5 years, 7 months
[lkp] [mm, kasan] 5bced26420: BUG radix_tree_node (Not tainted): Object padding overwritten
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
commit 5bced26420be17f44647cbc17d0217ba1a564cd2 ("mm, kasan: switch SLUB to stackdepot, enable memory quarantine for SLUB")
on test machine: 1 threads qemu-system-x86_64 -enable-kvm -cpu Westmere with 320M memory
caused below changes:
+---------------------------------------------------------------+------------+------------+
| | c0df48f854 | 5bced26420 |
+---------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 84 | 87 |
| BUG:KASAN:use-after-free_in_kobj_kset_leave_at_addr | 84 | |
| BUG_kmalloc-#(Not_tainted):kasan:bad_access_detected | 84 | |
| INFO:Allocated_in#age=#cpu=#pid= | 84 | |
| INFO:Freed_in#age=#cpu=#pid= | 84 | |
| INFO:Slab#objects=#used=#fp=#flags= | 84 | 49 |
| INFO:Object#@offset=#fp= | 84 | 87 |
| BUG:KASAN:use-after-free_in_devices_kset_move_last_at_addr | 84 | |
| BUG_kmalloc-#(Tainted:G_B):kasan:bad_access_detected | 84 | |
| BUG_kmalloc-#(Tainted:G_B):Poison_overwritten | 84 | |
| INFO:#-#.First_byte#instead_of | 84 | 87 |
| INFO:Allocated_in_usb_add_gadget_udc_release_age=#cpu=#pid= | 84 | |
| INFO:Freed_in_usb_add_gadget_udc_release_age=#cpu=#pid= | 84 | |
| INFO:Slab#objects=#used=#fp=0x(null)flags= | 84 | 87 |
| genirq:Flags_mismatch_irq##(serial)vs.#(goldfish_pdev_bus) | 82 | |
| backtrace:init | 84 | |
| backtrace:kernel_init_freeable | 84 | 87 |
| backtrace:pnp_register_driver | 84 | |
| backtrace:i8042_init | 84 | |
| backtrace:__platform_create_bundle | 84 | |
| BUG:KASAN:use-after-free_in_worker_thread_at_addr | 2 | |
| BUG:KASAN:wild-memory-access_on_address | 2 | |
| general_protection_fault:#[##]SMP_DEBUG_PAGEALLOC_KASAN | 2 | |
| RIP:worker_thread | 2 | |
| Kernel_panic-not_syncing:Fatal_exception | 2 | |
| BUG_radix_tree_node(Not_tainted):Object_padding_overwritten | 0 | 87 |
| BUG_inode_cache(Tainted:G_B):Object_padding_overwritten | 0 | 87 |
| BUG_bdev_cache(Tainted:G_B):Object_padding_overwritten | 0 | 87 |
| BUG_sighand_cache(Tainted:G_B):Object_padding_overwritten | 0 | 87 |
| BUG_proc_inode_cache(Tainted:G_B):Object_padding_overwritten | 0 | 87 |
| BUG_radix_tree_node(Tainted:G_B):Object_padding_overwritten | 0 | 87 |
| INFO:Object#@offset=#fp=0x(null) | 0 | 78 |
| BUG_shmem_inode_cache(Tainted:G_B):Object_padding_overwritten | 0 | 76 |
| BUG_sock_inode_cache(Tainted:G_B):Object_padding_overwritten | 0 | 55 |
| BUG_kmalloc-#(Tainted:G_B):Object_padding_overwritten | 0 | 51 |
| INFO:Allocated_in_pcpu_mem_zalloc_age=#cpu=#pid= | 0 | 51 |
| INFO:Allocated_in_do_set_mempolicy_age=#cpu=#pid= | 0 | 51 |
| INFO:Allocated_in_alloc_cpumask_var_node_age=#cpu=#pid= | 0 | 51 |
| INFO:Allocated_in_kzalloc_age=#cpu=#pid= | 0 | 51 |
| BUG_idr_layer_cache(Tainted:G_B):Object_padding_overwritten | 0 | 50 |
| INFO:Allocated_in_ida_pre_get_age=#cpu=#pid= | 0 | 50 |
| backtrace:__radix_tree_insert | 0 | 87 |
| backtrace:early_irq_init | 0 | 87 |
| backtrace:vfs_kern_mount | 0 | 87 |
| backtrace:mnt_init | 0 | 87 |
| backtrace:vfs_caches_init | 0 | 87 |
| backtrace:kern_mount_data | 0 | 87 |
| backtrace:bdev_cache_init | 0 | 87 |
| backtrace:nsfs_init | 0 | 87 |
| backtrace:_do_fork | 0 | 87 |
| backtrace:native_smp_prepare_cpus | 0 | 87 |
| backtrace:fork_idle | 0 | 14 |
| backtrace:idle_threads_init | 0 | 14 |
| backtrace:smp_init | 0 | 14 |
| backtrace:shmem_init | 0 | 76 |
| backtrace:do_mount | 0 | 67 |
| backtrace:SyS_mount | 0 | 67 |
| backtrace:devtmpfsd | 0 | 67 |
| backtrace:debugfs_create_dir | 0 | 66 |
| backtrace:regulator_init | 0 | 66 |
| backtrace:debugfs_create_file | 0 | 63 |
| backtrace:rdev_init_debugfs | 0 | 59 |
| backtrace:__platform_driver_register | 0 | 59 |
| backtrace:regulator_dummy_init | 0 | 59 |
| backtrace:debugfs_create_u32 | 0 | 57 |
| backtrace:sock_init | 0 | 54 |
| backtrace:__netlink_kernel_create | 0 | 52 |
| backtrace:rtnetlink_net_init | 0 | 52 |
| backtrace:ops_init | 0 | 52 |
| backtrace:register_pernet_subsys | 0 | 52 |
| backtrace:rtnetlink_init | 0 | 52 |
| backtrace:netlink_proto_init | 0 | 52 |
| backtrace:bdi_class_init | 0 | 52 |
| backtrace:uevent_net_init | 0 | 52 |
| backtrace:kobject_uevent_init | 0 | 52 |
| backtrace:regmap_initcall | 0 | 52 |
| backtrace:arch_kdebugfs_init | 0 | 52 |
| backtrace:debugfs_create_x16 | 0 | 51 |
| backtrace:debugfs_create_blob | 0 | 51 |
| backtrace:register_one_node | 0 | 5 |
| backtrace:topology_init | 0 | 5 |
| backtrace:pci_direct_probe | 0 | 46 |
| backtrace:pci_arch_init | 0 | 46 |
| INFO:Allocated_in__register_sysctl_paths_age=#cpu=#pid= | 0 | 12 |
| INFO:Allocated_in_allocate_cgrp_cset_links_age=#cpu=#pid= | 0 | 2 |
+---------------------------------------------------------------+------------+------------+
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[ 0.000000] NR_IRQS:4352 nr_irqs:48 16
[ 0.000000] =============================================================================
[ 0.000000] BUG radix_tree_node (Not tainted): Object padding overwritten
[ 0.000000] -----------------------------------------------------------------------------
[ 0.000000]
[ 0.000000] Disabling lock debugging due to kernel taint
[ 0.000000] INFO: 0xffff880009c00390-0xffff880009c00390. First byte 0x58 instead of 0x5a
[ 0.000000] INFO: Slab 0xffffea0000270000 objects=17 used=17 fp=0x (null) flags=0x1fffff80004080
[ 0.000000] INFO: Object 0xffff880009c00008 @offset=8 fp=0xffff880009c003b8
[ 0.000000]
[ 0.000000] Redzone ffff880009c00000: bb bb bb bb bb bb bb bb ........
[ 0.000000] Object ffff880009c00008: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00018: 00 00 00 00 00 00 00 00 20 00 c0 09 00 88 ff ff ........ .......
[ 0.000000] Object ffff880009c00028: 20 00 c0 09 00 88 ff ff 00 00 00 00 00 00 00 00 ...............
[ 0.000000] Object ffff880009c00038: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00048: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00058: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00068: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00078: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00088: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00098: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c000a8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c000b8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c000c8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c000d8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c000e8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c000f8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00108: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00118: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00128: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00138: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00148: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00158: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00168: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00178: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00188: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00198: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c001a8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c001b8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c001c8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c001d8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c001e8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c001f8: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00208: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00218: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00228: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Object ffff880009c00238: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 0.000000] Redzone ffff880009c00248: bb bb bb bb bb bb bb bb ........
[ 0.000000] Padding ffff880009c00388: 5a 5a 5a 5a 5a 5a 5a 5a 58 5a 5a 5a 5a 5a 5a 5a ZZZZZZZZXZZZZZZZ
[ 0.000000] Padding ffff880009c00398: 5a 5a 5a 5a 5a 5a 5a 5a 5a 5a 5a 5a 5a 5a 5a 5a ZZZZZZZZZZZZZZZZ
[ 0.000000] Padding ffff880009c003a8: 5a 5a 5a 5a 5a 5a 5a 5a ZZZZZZZZ
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Westmere -kernel /pkg/linux/x86_64-randconfig-s0-06160151/gcc-6/5bced26420be17f44647cbc17d0217ba1a564cd2/vmlinuz-4.7.0-rc4-00215-g5bced26 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-ia32-23/validate_boot-1-yocto-minimal-i386.cgz-x86_64-randconfig-s0-06160151-5bced26420be17f44647cbc17d0217ba1a564cd2-20160629-38918-1pxnsf3-31.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s0-06160151 branch=linux-devel/devel-hourly-2016061522 commit=5bced26420be17f44647cbc17d0217ba1a564cd2 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s0-06160151/gcc-6/5bced26420be17f44647cbc17d0217ba1a564cd2/vmlinuz-4.7.0-rc4-00215-g5bced26 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-ia32/yocto-minimal-i386.cgz/x86_64-randconfig-s0-06160151/gcc-6/5bced26420be17f44647cbc17d0217ba1a564cd2/31 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-kbuild-yocto-ia32-23::dhcp drbd.minor_count=8' -initrd /fs/sda1/initrd-vm-kbuild-yocto-ia32-23 -m 320 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sda1/disk0-vm-kbuild-yocto-ia32-23,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-ia32-23 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-ia32-23 -daemonize -display none -monitor null
Thanks,
Xiaolong
5 years, 7 months
kthread_stop insanity (Re: [[DEBUG] force] 2642458962: BUG: unable to handle kernel paging request at ffffc90000997f18)
by Andy Lutomirski
My v4 series was doing pretty well until this explosion:
On Sun, Jun 26, 2016 at 9:41 PM, kernel test robot
<xiaolong.ye(a)intel.com> wrote:
>
>
> FYI, we noticed the following commit:
>
> https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/vmap_stack
> commit 26424589626d7f82d09d4e7c0569f9487b2e810a ("[DEBUG] force-enable CONFIG_VMAP_STACK")
>
...
> [ 4.425052] BUG: unable to handle kernel paging request at ffffc90000997f18
> [ 4.426645] IP: [<ffffffff81a9ace0>] _raw_spin_lock_irq+0x2c/0x3d
> [ 4.427869] PGD 1249e067 PUD 1249f067 PMD 11e4e067 PTE 0
> [ 4.429245] Oops: 0002 [#1] SMP
> [ 4.430086] Modules linked in:
> [ 4.430992] CPU: 0 PID: 1741 Comm: mount Not tainted 4.7.0-rc4-00258-g26424589 #1
> [ 4.432727] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
> [ 4.434646] task: ffff88000d950c80 ti: ffff88000d950c80 task.ti: ffff88000d950c80
Yeah, this line is meaningless with the thread_info cleanups, and I
have it fixed for v5.
> [ 4.436406] RIP: 0010:[<ffffffff81a9ace0>] [<ffffffff81a9ace0>] _raw_spin_lock_irq+0x2c/0x3d
> [ 4.438341] RSP: 0018:ffffc90000957c80 EFLAGS: 00010046
> [ 4.439438] RAX: 0000000000000000 RBX: 7fffffffffffffff RCX: 0000000000000a66
> [ 4.440735] RDX: 0000000000000001 RSI: ffff880013619bc0 RDI: ffffc90000997f18
> [ 4.442035] RBP: ffffc90000957c88 R08: 0000000000019bc0 R09: ffffffff81200748
> [ 4.443323] R10: ffffea0000474900 R11: 000000000001a2a0 R12: ffffc90000997f10
> [ 4.444614] R13: 0000000000000002 R14: ffffc90000997f18 R15: 00000000ffffffea
> [ 4.445896] FS: 00007f9ca6a32700(0000) GS:ffff880013600000(0000) knlGS:0000000000000000
> [ 4.447690] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 4.448819] CR2: ffffc90000997f18 CR3: 000000000d87c000 CR4: 00000000000006f0
> [ 4.450102] Stack:
> [ 4.450810] ffffc90000997f18 ffffc90000957d00 ffffffff81a982eb 0000000000000246
> [ 4.452827] 0000000000000000 ffffc90000957d00 ffffffff8112584b 0000000000000000
> [ 4.454838] 0000000000000246 ffff88000e27f6bc 0000000000000000 ffff88000e27f080
> [ 4.456845] Call Trace:
> [ 4.457616] [<ffffffff81a982eb>] wait_for_common+0x44/0x197
> [ 4.458719] [<ffffffff8112584b>] ? try_to_wake_up+0x2dd/0x2ef
> [ 4.459877] [<ffffffff81a9845b>] wait_for_completion+0x1d/0x1f
> [ 4.461027] [<ffffffff8111db10>] kthread_stop+0x82/0x10a
> [ 4.462125] [<ffffffff81117f08>] destroy_workqueue+0x10d/0x1cd
> [ 4.463347] [<ffffffff81445236>] xfs_destroy_mount_workqueues+0x49/0x64
> [ 4.464620] [<ffffffff81445c03>] xfs_fs_fill_super+0x2c0/0x49c
> [ 4.465807] [<ffffffff8123547a>] mount_bdev+0x143/0x195
> [ 4.466937] [<ffffffff81445943>] ? xfs_test_remount_options+0x5b/0x5b
> [ 4.468727] [<ffffffff81444568>] xfs_fs_mount+0x15/0x17
> [ 4.469838] [<ffffffff8123614a>] mount_fs+0x15/0x8c
> [ 4.470882] [<ffffffff8124cfc4>] vfs_kern_mount+0x6a/0xfe
> [ 4.472005] [<ffffffff8124fc2f>] do_mount+0x985/0xa9a
> [ 4.473078] [<ffffffff811e0846>] ? strndup_user+0x3a/0x6a
> [ 4.474193] [<ffffffff8124ff6a>] SyS_mount+0x77/0x9f
> [ 4.475255] [<ffffffff81a9b081>] entry_SYSCALL_64_fastpath+0x1f/0xbd
> [ 4.476463] Code: 66 66 66 90 55 48 89 e5 50 48 89 7d f8 fa 66 66 90 66 66 90 e8 2d 0a 70 ff 65 ff 05 73 18 57 7e 31 c0 ba 01 00 00 00 48 8b 7d f8 <f0> 0f b1 17 85 c0 74 07 89 c6 e8 3e 20 6a ff c9 c3 66 66 66 66
> [ 4.484413] RIP [<ffffffff81a9ace0>] _raw_spin_lock_irq+0x2c/0x3d
> [ 4.485639] RSP <ffffc90000957c80>
> [ 4.486509] CR2: ffffc90000997f18
> [ 4.487366] ---[ end trace 79763b41869f2580 ]---
> [ 4.488367] Kernel panic - not syncing: Fatal exception
>
kthread_stop is *sick*.
struct kthread self;
...
current->vfork_done = &self.exited;
...
do_exit(ret);
And then some other thread goes and waits for the completion, which is
*on the stack*, which, in any sane world (e.g. with my series
applied), is long gone by then.
But this is broken even without any changes: since when is gcc
guaranteed to preserve the stack contents when a function ends with a
sibling call, let alone with a __noreturn call?
Is there seriously no way to directly wait for a struct task_struct to
exit? Could we, say, kmalloc the completion (or maybe even the whole
struct kthread) and (ick!) hang it off ->vfork_done?
Linus, maybe it's time for you to carve another wax figurine.
--Andy
5 years, 7 months
0e80a5db05: WARNING: CPU: 1 PID: 0 at kernel/smp.c:416 smp_call_function_many+0xa7/0x2bb
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/luto/linux.git x86/vmap_stack
commit 0e80a5db0584e9505a8dfaa94d4d72fbc8b0315f ("[DEBUG] forcibly free stacks immediately")
in testcase: boot
on test machine: 2 threads qemu-system-x86_64 -enable-kvm with 360M memory
caused below changes:
+---------------------------------------------------+------------+------------+
| | e5aec78386 | 0e80a5db05 |
+---------------------------------------------------+------------+------------+
| boot_successes | 9 | 0 |
| boot_failures | 3 | 20 |
| WARNING:at_kernel/fork.c:#free_task | 3 | |
| backtrace:_do_fork | 3 | |
| backtrace:SyS_clone | 3 | |
| WARNING:at_kernel/smp.c:#smp_call_function_many | 0 | 20 |
| WARNING:at_kernel/smp.c:#smp_call_function_single | 0 | 14 |
| BUG:spinlock_lockup_suspected_on_CPU | 0 | 14 |
| RIP:__default_send_IPI_dest_field | 0 | 14 |
| RIP:smp_call_function_single | 0 | 14 |
| backtrace:sysfs_remove_group | 0 | 1 |
| backtrace:param_sysfs_init | 0 | 1 |
| backtrace:kernel_init_freeable | 0 | 5 |
| BUG:unable_to_handle_kernel | 0 | 6 |
| Oops | 0 | 6 |
| RIP:__lock_acquire | 0 | 6 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 6 |
| backtrace:__clocksource_register_scale | 0 | 3 |
| backtrace:init_jiffies_clocksource | 0 | 3 |
| backtrace:do_mount | 0 | 6 |
| backtrace:compat_SyS_mount | 0 | 4 |
| backtrace:schedule_timeout | 0 | 3 |
| backtrace:schedule | 0 | 3 |
| backtrace:SyS_mount | 0 | 2 |
| backtrace:__class_create | 0 | 1 |
| backtrace:vtconsole_class_init | 0 | 1 |
+---------------------------------------------------+------------+------------+
[ 1.185876] xor: measuring software checksum speed
[ 1.188092] kworker/u4:0 (19) used greatest stack depth: 13440 bytes left
[ 1.189789] ------------[ cut here ]------------
[ 1.191115] WARNING: CPU: 1 PID: 0 at kernel/smp.c:416 smp_call_function_many+0xa7/0x2bb
[ 1.193741] Modules linked in:
[ 1.194924] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.7.0-rc4-00261-g0e80a5db #266
[ 1.197245] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.199734] 0000000000000000 ffffc9000011fc38
[ 1.209171] prefetch64-sse: 17332.000 MB/sec
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-nfsroot/gcc-6/0e80a5db0584e9505a8dfaa94d4d72fbc8b0315f/vmlinuz-4.7.0-rc4-00261-g0e80a5db -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-3/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-nfsroot-0e80a5db0584e9505a8dfaa94d4d72fbc8b0315f-20160630-12888-1uqtsqt-0.yaml ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-devel/devel-catchup-201606300112 commit=0e80a5db0584e9505a8dfaa94d4d72fbc8b0315f BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-6/0e80a5db0584e9505a8dfaa94d4d72fbc8b0315f/vmlinuz-4.7.0-rc4-00261-g0e80a5db max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-nfsroot/gcc-6/0e80a5db0584e9505a8dfaa94d4d72fbc8b0315f/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw ip=::::vm-vp-quantal-x86_64-3::dhcp drbd.minor_count=8' -initrd /fs/sdb1/initrd-vm-vp-quantal-x86_64-3 -m 360 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-vp-quantal-x86_64-3 -serial file:/dev/shm/kboot/serial-vm-vp-quantal-x86_64-3 -daemonize -display none -monitor null
Thanks,
Kernel Test Robot
5 years, 7 months
[lkp] [signal] 98ea270c46: ltp.float_exp_log.fail
by kernel test robot
FYI, we noticed the following commit:
https://github.com/yyu168/linux.git WIP.timers
commit 98ea270c46b4bde85c9685667bb6179f0e65f203 ("signal: Use hrtimer for sigtimedwait")
in testcase: ltp
with following parameters: test=math
on test machine: 8 threads Nehalem with 4G memory
caused below changes:
2016-06-26 05:30:56 ./runltp -f math
INFO: creating /lkp/benchmarks/ltp/output directory
INFO: creating /lkp/benchmarks/ltp/results directory
Checking for required user/group ids
'nobody' user id and group found.
'bin' user id and group found.
'daemon' user id and group found.
Users group found.
Sys group found.
Required users/groups exist.
If some fields are empty or look unusual you may have an old version.
Compare to the current minimal requirements in Documentation/Changes.
PRETTY_NAME="Debian GNU/Linux 8 (jessie)"
NAME="Debian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
ID=debian
HOME_URL="http://www.debian.org/"
SUPPORT_URL="http://www.debian.org/support/"
BUG_REPORT_URL="https://bugs.debian.org/"
Linux nhm-white2 4.7.0-rc3-00122-g98ea270 #1 SMP Sun Jun 26 05:27:43 CST 2016 x86_64 GNU/Linux
Gnu C
util-linux linux 2.25.2
mount linux 2.25.2 (libmount 2.25.0: selinux, assert, debug)
modutils 18
e2fsprogs 1.42.12
Linux C Library > libc.2.19
Dynamic linker (ldd) 2.19
Procps 3.3.10
Net-tools 1.60
iproute2 iproute2-ss140804
Kbd 69:
Sh-utils 8.23
Modules Loaded rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver netconsole sg sr_mod cdrom sd_mod dcdbas coretemp kvm_intel usb_storage kvm irqbypass crc32c_intel serio_raw snd_hda_codec_realtek ata_generic pata_acpi pcspkr snd_hda_codec_generic i7core_edac edac_core snd_hda_codec_hdmi firewire_ohci firewire_core crc_itu_t ata_piix snd_hda_intel snd_hda_codec libata snd_hda_core snd_hwdep snd_pcm snd_timer snd soundcore shpchp acpi_cpufreq broadcom bcm_phy_lib
free reports:
total used free shared buff/cache available
Mem: 4034828 47928 3041700 8852 945200 3734372
Swap: 0 0 0
/proc/cpuinfo
processor : 0
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
microcode : 0x3
cpu MHz : 2925.787
cache size : 8192 KB
physical id : 0
siblings : 8
core id : 0
cpu cores : 4
apicid : 0
initial apicid : 0
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf eagerfpu pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm
bugs :
bogomips : 5851.57
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:
processor : 1
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
microcode : 0x3
cpu MHz : 2925.787
cache size : 8192 KB
physical id : 0
siblings : 8
core id : 1
cpu cores : 4
apicid : 2
initial apicid : 2
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf eagerfpu pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm
bugs :
bogomips : 5850.97
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:
processor : 2
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
microcode : 0x3
cpu MHz : 2925.787
cache size : 8192 KB
physical id : 0
siblings : 8
core id : 2
cpu cores : 4
apicid : 4
initial apicid : 4
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf eagerfpu pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm
bugs :
bogomips : 5850.99
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:
processor : 3
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
microcode : 0x3
cpu MHz : 2925.787
cache size : 8192 KB
physical id : 0
siblings : 8
core id : 3
cpu cores : 4
apicid : 6
initial apicid : 6
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf eagerfpu pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm
bugs :
bogomips : 5850.98
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:
processor : 4
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
microcode : 0x3
cpu MHz : 2925.787
cache size : 8192 KB
physical id : 0
siblings : 8
core id : 0
cpu cores : 4
apicid : 1
initial apicid : 1
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf eagerfpu pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm
bugs :
bogomips : 5850.97
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:
processor : 5
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
microcode : 0x3
cpu MHz : 2925.787
cache size : 8192 KB
physical id : 0
siblings : 8
core id : 1
cpu cores : 4
apicid : 3
initial apicid : 3
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf eagerfpu pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm
bugs :
bogomips : 5850.98
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:
processor : 6
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
microcode : 0x3
cpu MHz : 2925.787
cache size : 8192 KB
physical id : 0
siblings : 8
core id : 2
cpu cores : 4
apicid : 5
initial apicid : 5
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf eagerfpu pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm
bugs :
bogomips : 5850.98
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:
processor : 7
vendor_id : GenuineIntel
cpu family : 6
model : 30
model name : Intel(R) Core(TM) i7 CPU 870 @ 2.93GHz
stepping : 5
microcode : 0x3
cpu MHz : 2925.787
cache size : 8192 KB
physical id : 0
siblings : 8
core id : 3
cpu cores : 4
apicid : 7
initial apicid : 7
fpu : yes
fpu_exception : yes
cpuid level : 11
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe syscall nx rdtscp lm constant_tsc arch_perfmon pebs bts rep_good nopl xtopology nonstop_tsc aperfmperf eagerfpu pni dtes64 monitor ds_cpl vmx smx est tm2 ssse3 cx16 xtpr pdcm sse4_1 sse4_2 popcnt lahf_lm tpr_shadow vnmi flexpriority ept vpid dtherm
bugs :
bogomips : 5850.97
clflush size : 64
cache_alignment : 64
address sizes : 36 bits physical, 48 bits virtual
power management:
no big block device was specified on commandline.
Tests which require a big block device are disabled.
You can specify it with option -z
COMMAND: /lkp/benchmarks/ltp/bin/ltp-pan -e -S -a 588 -n 588 -p -f /tmp/ltp-alEuEZbSN7/alltests -l /lkp/benchmarks/ltp/results/LTP_RUN_ON-2016_06_26-05h_30m_56s.log -C /lkp/benchmarks/ltp/output/LTP_RUN_ON-2016_06_26-05h_30m_56s.failed -T /lkp/benchmarks/ltp/output/LTP_RUN_ON-2016_06_26-05h_30m_56s.tconf
LOG File: /lkp/benchmarks/ltp/results/LTP_RUN_ON-2016_06_26-05h_30m_56s.log
FAILED COMMAND File: /lkp/benchmarks/ltp/output/LTP_RUN_ON-2016_06_26-05h_30m_56s.failed
TCONF COMMAND File: /lkp/benchmarks/ltp/output/LTP_RUN_ON-2016_06_26-05h_30m_56s.tconf
Running tests.......
<<<test_start>>>
tag=abs01 stime=1466890256
cmdline="abs01"
contacts=""
analysis=exit
<<<test_output>>>
abs01 1 TPASS : Test passed
abs01 2 TPASS : Test passed
abs01 3 TPASS : Test passed
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=0 corefile=no
cutime=0 cstime=0
<<<test_end>>>
<<<test_start>>>
tag=atof01 stime=1466890256
cmdline="atof01"
contacts=""
analysis=exit
<<<test_output>>>
atof01 1 TPASS : Test passed
atof01 2 TPASS : Test passed
atof01 3 TPASS : Test passed
atof01 4 TPASS : Test passed
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=0 corefile=no
cutime=0 cstime=0
<<<test_end>>>
<<<test_start>>>
tag=float_bessel stime=1466890256
cmdline="cd $LTPROOT/testcases/bin; float_bessel -v"
contacts=""
analysis=exit
<<<test_output>>>
float_bessel 1 TPASS : Test passed
float_bessel 0 TINFO : float_bessel: will run for 500 loops; using . as a data directory
float_bessel 0 TINFO : float_bessel: will run 5 functions, 20 threads per function
float_bessel 0 TINFO : signal handler 140339933017856 started
float_bessel 0 TINFO : Signal handler starts waiting...
float_bessel 0 TINFO : Signal handler caught signal 0
float_bessel 1 TFAIL : main.c:41: Test failed
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=1 corefile=no
cutime=2 cstime=8
<<<test_end>>>
<<<test_start>>>
tag=float_exp_log stime=1466890256
cmdline="cd $LTPROOT/testcases/bin; float_exp_log -v"
contacts=""
analysis=exit
<<<test_output>>>
float_exp_log 1 TPASS : Test passed
float_exp_log 0 TINFO : float_exp_log: will run for 500 loops; using . as a data directory
float_exp_log 0 TINFO : float_exp_log: will run 7 functions, 20 threads per function
float_exp_log 0 TINFO : signal handler 140129771460352 started
float_exp_log 0 TINFO : Signal handler starts waiting...
float_exp_log 0 TINFO : Signal handler caught signal 0
float_exp_log 1 TFAIL : main.c:41: Test failed
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=1 corefile=no
cutime=1 cstime=13
<<<test_end>>>
<<<test_start>>>
tag=float_iperb stime=1466890256
cmdline="cd $LTPROOT/testcases/bin; float_iperb -v"
contacts=""
analysis=exit
<<<test_output>>>
float_iperb 1 TPASS : Test passed
float_iperb 0 TINFO : float_iperb: will run for 500 loops; using . as a data directory
float_iperb 0 TINFO : float_iperb: will run 3 functions, 20 threads per function
float_iperb 0 TINFO : signal handler 140698113312512 started
float_iperb 0 TINFO : Signal handler starts waiting...
float_iperb 0 TINFO : Signal handler caught signal 32758
float_iperb 1 TFAIL : main.c:41: Test failed
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=1 corefile=no
cutime=1 cstime=5
<<<test_end>>>
<<<test_start>>>
tag=float_power stime=1466890256
cmdline="cd $LTPROOT/testcases/bin; float_power -v"
contacts=""
analysis=exit
<<<test_output>>>
float_power 1 TPASS : Test passed
float_power 0 TINFO : float_power: will run for 500 loops; using . as a data directory
float_power 0 TINFO : float_power: will run 6 functions, 20 threads per function
float_power 0 TINFO : signal handler 140048649369344 started
float_power 0 TINFO : Signal handler starts waiting...
float_power 0 TINFO : Signal handler caught signal 32607
float_power 1 TFAIL : main.c:41: Test failed
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=1 corefile=no
cutime=2 cstime=13
<<<test_end>>>
<<<test_start>>>
tag=float_trigo stime=1466890256
cmdline="cd $LTPROOT/testcases/bin; float_trigo -v"
contacts=""
analysis=exit
<<<test_output>>>
float_trigo 1 TPASS : Test passed
float_trigo 0 TINFO : float_trigo: will run for 500 loops; using . as a data directory
float_trigo 0 TINFO : float_trigo: will run 7 functions, 20 threads per function
float_trigo 0 TINFO : signal handler 139747098916608 started
float_trigo 0 TINFO : Signal handler starts waiting...
float_trigo 0 TINFO : Signal handler caught signal 32537
float_trigo 1 TFAIL : main.c:41: Test failed
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=1 corefile=no
cutime=1 cstime=10
<<<test_end>>>
<<<test_start>>>
tag=fptest01 stime=1466890256
cmdline="fptest01"
contacts=""
analysis=exit
<<<test_output>>>
fptest01 1 TPASS : PASS
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=0 corefile=no
cutime=0 cstime=0
<<<test_end>>>
<<<test_start>>>
tag=fptest02 stime=1466890256
cmdline="fptest02"
contacts=""
analysis=exit
<<<test_output>>>
fptest02 1 TPASS : PASS
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=0 corefile=no
cutime=1 cstime=0
<<<test_end>>>
<<<test_start>>>
tag=nextafter01 stime=1466890256
cmdline="nextafter01"
contacts=""
analysis=exit
<<<test_output>>>
nextafter01 1 TPASS : Test passed
nextafter01 2 TPASS : Test passed
nextafter01 3 TPASS : Test passed
incrementing stop
<<<execution_status>>>
initiation_status="ok"
duration=0 termination_type=exited termination_id=0 corefile=no
cutime=0 cstime=0
<<<test_end>>>
INFO: ltp-pan reported some tests FAIL
LTP Version: 20160510-18-g790d70e
###############################################################
Done executing testcases.
LTP Version: 20160510-18-g790d70e
###############################################################
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] [f2fs] b93f771286: aim7.jobs-per-min -81.2% regression
by kernel test robot
FYI, we noticed a -81.2% regression of aim7.jobs-per-min due to commit:
commit b93f7712868648c0529eed6b568cea1493d3d9f9 ("f2fs: remove writepages lock")
https://git.kernel.org/pub/scm/linux/kernel/git/next/linux-next.git master
in testcase: aim7
on test machine: 48 threads Ivytown Ivy Bridge-EP with 64G memory
with following parameters: disk=4BRD_12G/fs=f2fs/load=600/md=RAID0/test=sync_disk_rw
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.
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
=========================================================================================
compiler/disk/fs/kconfig/load/md/rootfs/tbox_group/test/testcase:
gcc-4.9/4BRD_12G/f2fs/x86_64-rhel/600/RAID0/debian-x86_64-2015-02-07.cgz/ivb44/sync_disk_rw/aim7
commit:
69e9e42744 ("f2fs: set flush_merge by default")
b93f771286 ("f2fs: remove writepages lock")
69e9e4274450c778 b93f7712868648c0529eed6b56
---------------- --------------------------
fail:runs %reproduction fail:runs
| | |
:4 50% 2:4 kmsg.Spurious_LAPIC_timer_interrupt_on_cpu
%stddev %change %stddev
\ | \
17539 ± 1% -81.2% 3303 ± 0% aim7.jobs-per-min
205.35 ± 1% +430.8% 1089 ± 0% aim7.time.elapsed_time
205.35 ± 1% +430.8% 1089 ± 0% aim7.time.elapsed_time.max
19225140 ± 1% +13.7% 21864211 ± 0% aim7.time.involuntary_context_switches
76889 ± 3% +73.4% 133360 ± 6% aim7.time.minor_page_faults
1169 ± 0% +484.6% 6835 ± 0% aim7.time.system_time
7.04 ± 1% +421.8% 36.75 ± 1% aim7.time.user_time
43519877 ± 0% +633.9% 3.194e+08 ± 0% aim7.time.voluntary_context_switches
aim7.jobs-per-min
20000 ++------------------------------------------------------------------+
| .*.. |
18000 *+*..*.*..* *.*.*..*. .*. .*.*.*.. .*..*.*..*.*.*..*. .*.*..*.*
16000 ++ *. *. * *. |
| |
14000 ++ |
12000 ++ |
| |
10000 ++ |
8000 ++ |
O O O O O O O O O O O O O |
6000 ++ |
4000 ++ |
| O O O O O O O O O O |
2000 ++------------------------------------------------------------------+
aim7.time.user_time
45 ++---------------------------------------------------------------------+
| O O |
40 ++ O O O O |
35 O+ O O O O O O O O O O O O O O O O |
| |
30 ++ |
| |
25 ++ |
| |
20 ++ |
15 ++ |
| |
10 ++ |
*..*.*..*.*.. .*..*.*..*.*..*.*..*.*..*.*..*
5 ++-----------*-*--*-*--*-*--*-*--*--*-*--*-----------------------------+
aim7.time.system_time
7000 ++------------------------------O--O-O-O--O-O--O----O-O--------------+
| O |
6000 ++ |
| |
| |
5000 ++ |
| |
4000 ++ |
| |
3000 O+O O O O O O O O O O O O |
| |
| |
2000 ++ |
*.*..*.*..*. .*.. .*..*.*..*.*..*. .*..*.*..*.*..*.*..*.*.. |
1000 ++----------*--*----*----------------*------------------------*-*--*-*
aim7.time.elapsed_time
1200 ++-------------------------------------------------------------------+
1100 ++ O O O O O O O O O |
| O |
1000 ++ |
900 ++ |
800 ++ |
700 ++ |
| |
600 ++ |
500 O+O O O O O O O O O O O O |
400 ++ |
300 ++ |
| .*.. |
200 *+*..*.*..*.*..*.*..*.*..*.*..*.*..*.*.*..*.*..*.*..*.*..* *.*..*.*
100 ++-------------------------------------------------------------------+
aim7.time.elapsed_time.max
1200 ++-------------------------------------------------------------------+
1100 ++ O O O O O O O O O |
| O |
1000 ++ |
900 ++ |
800 ++ |
700 ++ |
| |
600 ++ |
500 O+O O O O O O O O O O O O |
400 ++ |
300 ++ |
| .*.. |
200 *+*..*.*..*.*..*.*..*.*..*.*..*.*..*.*.*..*.*..*.*..*.*..* *.*..*.*
100 ++-------------------------------------------------------------------+
aim7.time.voluntary_context_switches
3.5e+08 ++----------------------------------------------------------------+
O O O O O O O O O O O O O O O O O O O O O O O |
3e+08 ++ |
| |
2.5e+08 ++ |
| |
2e+08 ++ |
| |
1.5e+08 ++ |
| |
1e+08 ++ |
| |
5e+07 *+*..*.*.*..*.*..*.*.*..*.*.*..*.*.*..*.*.*..*.*..*.*.*..*.*.*..*.*
| |
0 ++----------------------------------------------------------------+
[*] bisect-good sample
[O] bisect-bad sample
Thanks,
Xiaolong
5 years, 7 months
[x86/dumpstack] 58a997a709: BUG: kernel early-boot hang Booting the kernel.
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://github.com/jpoimboe/linux encode-2-2016-06-28-2
commit 58a997a7099cf09984251e5e63e329273e90f951
Author: Josh Poimboeuf <jpoimboe(a)redhat.com>
AuthorDate: Mon Jun 27 12:33:40 2016 -0500
Commit: Josh Poimboeuf <jpoimboe(a)redhat.com>
CommitDate: Tue Jun 28 00:16:38 2016 -0500
x86/dumpstack: simplify stack bounds checking
Always pass the stack end pointer to the 'ops->walk_stack()' callback,
which removes the need for the valid_stack_ptr() helper.
Signed-off-by: Josh Poimboeuf <jpoimboe(a)redhat.com>
+-----------------------------------------------+------------+------------+------------+
| | 3db0c18ce4 | 58a997a709 | 8492c2210b |
+-----------------------------------------------+------------+------------+------------+
| boot_successes | 67 | 0 | 0 |
| boot_failures | 0 | 26 | 21 |
| BUG:kernel_early-boot_hang_Booting_the_kernel | 0 | 26 | 21 |
+-----------------------------------------------+------------+------------+------------+
Booting the kernel.
Elapsed time: 310
BUG: kernel early-boot hang Booting the kernel.
Linux version 4.7.0-rc5-00398-g58a997a #2
Command line: hung_task_panic=1 earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/i386-randconfig-h0-06282312/linux-devel:devel-spot-201606282203:58a997a7099cf09984251e5e63e329273e90f951:bisect-linux-2/.vmlinuz-58a997a7099cf09984251e5e63e329273e90f951-20160629071400-19-ivb41 branch=linux-devel/devel-spot-201606282203 BOOT_IMAGE=/pkg/linux/i386-randconfig-h0-06282312/gcc-6/58a997a7099cf09984251e5e63e329273e90f951/vmlinuz-4.7.0-rc5-00398-g58a997a drbd.minor_count=8
qemu-system-x86_64 -enable-kvm -cpu kvm64 -kernel /pkg/linux/i386-randconfig-h0-06282312/gcc-6/58a997a7099cf09984251e5e63e329273e90f951/vmlinuz-4.7.0-rc5-00398-g58a997a -append 'hung_task_panic=1 earlyprintk=ttyS0,115200 systemd.log_level=err debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/i386-randconfig-h0-06282312/linux-devel:devel-spot-201606282203:58a997a7099cf09984251e5e63e329273e90f951:bisect-linux-2/.vmlinuz-58a997a7099cf09984251e5e63e329273e90f951-20160629071400-19-ivb41 branch=linux-devel/devel-spot-201606282203 BOOT_IMAGE=/pkg/linux/i386-randconfig-h0-06282312/gcc-6/58a997a7099cf09984251e5e63e329273e90f951/vmlinuz-4.7.0-rc5-00398-g58a997a drbd.minor_count=8' -initrd /osimage/quantal/quantal-core-i386.cgz -m 300 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sda5/disk0-quantal-ivb41-115,media=disk,if=virtio -drive file=/fs/sda5/disk1-quantal-ivb41-115,media=disk,if=virtio -drive file=/fs/sda5/disk2-quantal-ivb41-115,media=disk,if=virtio -drive file=/fs/sda5/disk3-quantal-ivb41-115,media=disk,if=virtio -drive file=/fs/sda5/disk4-quantal-ivb41-115,media=disk,if=virtio -drive file=/fs/sda5/disk5-quantal-ivb41-115,media=disk,if=virtio -drive file=/fs/sda5/disk6-quantal-ivb41-115,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-quantal-ivb41-115 -serial file:/dev/shm/kboot/serial-quantal-ivb41-115 -daemonize -display none -monitor null
git bisect start 8492c2210b3a310e40fe492a2742863b4afab7fd 4c2e07c6a29e0129e975727b9f57eede813eea85 --
git bisect bad 0400a4c504d528df9a0bc849758a477562706201 # 03:32 0- 15 Merge 'shawnguo/for-next' into devel-spot-201606282203
git bisect good 5df0ee1ab7869dfb9b237d3cb06e678acbcd5e74 # 03:57 22+ 2 Merge 'linux-review/Imre-Deak/drm-i915-Avoid-early-timeout-due-to-wait_for_atomic/20160628-184111' into devel-spot-201606282203
git bisect good b205b1389801af9e85a06b8f2f44926d2ed0c965 # 04:07 22+ 0 Merge 'linux-review/Tan-Jui-Nee/x86-platform-p2sb-New-Primary-to-Sideband-bridge-support-driver-for-Intel-SOC-s/20160628-160704' into devel-spot-201606282203
git bisect good 3600bcf06ad756cb952fd22aab4fb107aedc1876 # 04:41 22+ 0 Merge 'arc/for-next' into devel-spot-201606282203
git bisect bad b661a192c6ecdba888c1a02f00ad2e2902f2106d # 04:55 0- 1 Merge 'linux-review/James-Ban/regulator-da9211-add-descriptions-for-da9212-da9214/20160628-130806' into devel-spot-201606282203
git bisect bad 816937cf767e4df15476e0e0f3ffe4db8b74a30a # 05:07 0- 22 Merge 'jpoimboe/encode-2-2016-06-28' into devel-spot-201606282203
git bisect good 06b1a204a21928e5e60f209907a9fad985369281 # 05:23 22+ 0 Merge 'linux-review/Namhyung-Kim/ftrace-Reduce-size-of-function-graph-entries/20160628-133516' into devel-spot-201606282203
git bisect good bec1e33b7fd7a356bf87dbe7b40568481005a033 # 05:40 20+ 0 Merge branch 'locking/arch-atomic'
git bisect good f5c0e72b0a72c98303b1a84390f024736bade6f9 # 05:55 22+ 0 Merge branch 'x86/fpu'
git bisect good b17a9fa8d436ab40f03caea900b4a7cdb2e911f1 # 06:26 22+ 0 Merge branch 'WIP.futex'
git bisect good b1153787403a4f4acf9ac7fd18af148869a23e70 # 06:33 22+ 0 timer: Remove slack leftovers
git bisect good 49dbbca012a045c8be99067949d1d8cee08697e0 # 06:50 21+ 0 timer: Optimization for same expiry time in mod_timer()
git bisect good d4ff9d34091e96a39d7a32045eedb3c9f4e41e59 # 07:05 22+ 0 x86/dumpstack: don't assume ftrace graph return address is reliable
git bisect bad 58a997a7099cf09984251e5e63e329273e90f951 # 07:19 0- 1 x86/dumpstack: simplify stack bounds checking
git bisect good 3db0c18ce48107252d8c83ca0e726b9257a52b3f # 07:53 21+ 0 x86/dumpstack: propagate errors from print_ftrace_graph_addr()
# first bad commit: [58a997a7099cf09984251e5e63e329273e90f951] x86/dumpstack: simplify stack bounds checking
git bisect good 3db0c18ce48107252d8c83ca0e726b9257a52b3f # 07:57 61+ 0 x86/dumpstack: propagate errors from print_ftrace_graph_addr()
# extra tests on HEAD of linux-devel/devel-spot-201606282203
git bisect bad 8492c2210b3a310e40fe492a2742863b4afab7fd # 07:57 0- 21 0day head guard for 'devel-spot-201606282203'
# extra tests on tree/branch jpoimboe/encode-2-2016-06-28-2
git bisect bad 03e2aef3cfbeff41e8a25a86e87fa4c9ce2df299 # 08:20 0- 56 fixme
# extra tests on tree/branch linus/master
git bisect good 02184c60eba8491ea574cd17b8ba766c86d468f2 # 09:05 63+ 2 Merge tag 'for-v4.7-rc' of git://git.kernel.org/pub/scm/linux/kernel/git/sre/linux-power-supply
# extra tests on tree/branch linux-next/master
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu kvm64
-kernel $kernel
-m 300
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
console=ttyS0,115200
console=tty0
vga=normal
root=/dev/ram0
rw
drbd.minor_count=8
)
"${kvm[@]}" --append "${append[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
5 years, 7 months