lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [<thread-prev] [day] [month] [year] [list]
Date:   Fri, 23 Jul 2021 10:57:19 +0800
From:   kernel test robot <oliver.sang@...el.com>
To:     Xiongwei Song <sxwjean@...com>
Cc:     0day robot <lkp@...el.com>, LKML <linux-kernel@...r.kernel.org>,
        lkp@...ts.01.org, peterz@...radead.org, mingo@...hat.com,
        will@...nel.org, longman@...hat.com, boqun.feng@...il.com,
        Xiongwei Song <sxwjean@...il.com>
Subject: [locking/lockdep]  e0a77a7a5a: WARNING:bad_unlock_balance_detected



Greeting,

FYI, we noticed the following commit (built with gcc-9):

commit: e0a77a7a5a75e5e5163669d7625c765504cc2f94 ("[RFC PATCH v1 2/3] locking/lockdep: Unify the return values of check_wait_context()")
url: https://github.com/0day-ci/linux/commits/Xiongwei-Song/locking-lockdep-Fix-false-warning-of-check_wait_context/20210711-221747
base: https://git.kernel.org/cgit/linux/kernel/git/tip/tip.git d1bbfd0c7c9f985e57795a7e0cefc209ebf689c0

in testcase: boot

on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):


+----------------------------------------+------------+------------+
|                                        | 428eeba5e1 | e0a77a7a5a |
+----------------------------------------+------------+------------+
| boot_successes                         | 26         | 0          |
| boot_failures                          | 0          | 6          |
| WARNING:bad_unlock_balance_detected    | 0          | 6          |
| is_trying_to_release_lock(pool_lock)at | 0          | 6          |
+----------------------------------------+------------+------------+


If you fix the issue, kindly add following tag
Reported-by: kernel test robot <oliver.sang@...el.com>


[    0.000000][    T0] WARNING: bad unlock balance detected!
[    0.000000][    T0] 5.13.0-rc1-00135-ge0a77a7a5a75 #1 Not tainted
[    0.000000][    T0] -------------------------------------
[    0.000000][    T0] swapper/0 is trying to release lock (pool_lock) at:
[ 0.000000][ T0] __debug_object_init (kbuild/src/consumer/lib/debugobjects.c:273 kbuild/src/consumer/lib/debugobjects.c:568) 
[    0.000000][    T0] but there are no more locks to release!
[    0.000000][    T0]
[    0.000000][    T0] other info that might help us debug this:
[    0.000000][    T0] no locks held by swapper/0.
[    0.000000][    T0]
[    0.000000][    T0] stack backtrace:
[    0.000000][    T0] CPU: 0 PID: 0 Comm: swapper Not tainted 5.13.0-rc1-00135-ge0a77a7a5a75 #1
[    0.000000][    T0] Call Trace:
[ 0.000000][ T0] ? lock_release (kbuild/src/consumer/kernel/locking/lockdep.c:5303 kbuild/src/consumer/kernel/locking/lockdep.c:5643) 
[ 0.000000][ T0] ? _raw_spin_unlock (kbuild/src/consumer/include/linux/spinlock_api_smp.h:151 kbuild/src/consumer/kernel/locking/spinlock.c:183) 
[ 0.000000][ T0] ? __debug_object_init (kbuild/src/consumer/lib/debugobjects.c:273 kbuild/src/consumer/lib/debugobjects.c:568) 
[ 0.000000][ T0] ? init_cgroup_housekeeping (kbuild/src/consumer/include/linux/lockdep.h:195 kbuild/src/consumer/include/linux/lockdep.h:202 kbuild/src/consumer/include/linux/lockdep.h:208 kbuild/src/consumer/kernel/cgroup/cgroup.c:1909) 
[ 0.000000][ T0] ? init_cgroup_root (kbuild/src/consumer/kernel/cgroup/cgroup.c:1922) 
[ 0.000000][ T0] ? cgroup_init_early (kbuild/src/consumer/kernel/cgroup/cgroup.c:5614) 
[ 0.000000][ T0] ? start_kernel (kbuild/src/consumer/arch/x86/include/asm/irqflags.h:40 kbuild/src/consumer/arch/x86/include/asm/irqflags.h:75 kbuild/src/consumer/init/main.c:886) 
[ 0.000000][ T0] ? copy_bootdata (kbuild/src/consumer/arch/x86/kernel/head64.c:433) 
[ 0.000000][ T0] ? secondary_startup_64_no_verify (kbuild/src/consumer/arch/x86/kernel/head_64.S:283) 
[    0.000000][    T0] Linux version 5.13.0-rc1-00135-ge0a77a7a5a75 (kbuild@...bfb9d9f4c) (gcc-9 (Debian 9.3.0-22) 9.3.0, GNU ld (GNU Binutils for Debian) 2.35.2) #1 Thu Jul 22 11:35:57 CST 2021
[    0.000000][    T0] Command line: ip=::::vm-snb-147::dhcp root=/dev/ram0 user=lkp job=/lkp/jobs/scheduled/vm-snb-147/boot-1-yocto-x86_64-minimal-20190520.cgz-e0a77a7a5a75e5e5163669d7625c765504cc2f94-20210723-25037-1fky0q3-4.yaml ARCH=x86_64 kconfig=x86_64-randconfig-r012-20210713 branch=linux-review/Xiongwei-Song/locking-lockdep-Fix-false-warning-of-check_wait_context/20210711-221747 commit=e0a77a7a5a75e5e5163669d7625c765504cc2f94 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r012-20210713/gcc-9/e0a77a7a5a75e5e5163669d7625c765504cc2f94/vmlinuz-5.13.0-rc1-00135-ge0a77a7a5a75 vmalloc=512M initramfs_async=0 page_owner=on max_uptime=600 RESULT_ROOT=/result/boot/1/vm-snb/yocto-x86_64-minimal-20190520.cgz/x86_64-randconfig-r012-20210713/gcc-9/e0a77a7a5a75e5e5163669d7625c765504cc2f94/3 LKP_SERVER=internal-lkp-server selinux=0 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_
[    0.000000][    T0] KERNEL supported cpus:
[    0.000000][    T0]   Intel GenuineIntel
[    0.000000][    T0]   Centaur CentaurHauls
[    0.000000][    T0] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000][    T0] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000][    T0] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000][    T0] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000][    T0] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
[    0.000000][    T0] BIOS-provided physical RAM map:
[    0.000000][    T0] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000][    T0] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000][    T0] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000][    T0] BIOS-e820: [mem 0x0000000000100000-0x00000000bffdffff] usable
[    0.000000][    T0] BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved
[    0.000000][    T0] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000][    T0] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000][    T0] BIOS-e820: [mem 0x0000000100000000-0x000000043fffffff] usable
[    0.000000][    T0] printk: debug: ignoring loglevel setting.
[    0.000000][    T0] printk: bootconsole [earlyser0] enabled
[    0.000000][    T0] NX (Execute Disable) protection: active
[    0.000000][    T0] SMBIOS 2.8 present.
[    0.000000][    T0] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014
[    0.000000][    T0] Hypervisor detected: KVM
[    0.000000][    T0] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000][    T0] kvm-clock: cpu 0, msr 5a477001, primary cpu clock
[    0.000006][    T0] kvm-clock: using sched offset of 1327495314 cycles
[    0.001114][    T0] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.004079][    T0] tsc: Detected 2493.988 MHz processor
[    0.006744][    T0] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.008135][    T0] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.009276][    T0] last_pfn = 0x440000 max_arch_pfn = 0x400000000
[    0.010470][    T0] x86/PAT: PAT support disabled because CONFIG_X86_PAT is disabled in the kernel.
[    0.012198][    T0] x86/PAT: Configuration [0-7]: WB  WT  UC- UC  WB  WT  UC- UC
Memory KASLR using RDTSC...
[    0.014038][    T0] last_pfn = 0xbffe0 max_arch_pfn = 0x400000000
[    0.015131][    T0] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.016143][    T0] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.017017][    T0] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.025962][    T0] found SMP MP-table at [mem 0x000f5a80-0x000f5a8f]
[    0.027214][    T0]   mpc: f5a90-f5b74
[    0.030043][    T0] RAMDISK: [mem 0x7f797000-0x7fffffff]
[    0.031073][    T0] ACPI: Early table checksum verification disabled
[    0.032219][    T0] ACPI: RSDP 0x00000000000F5850 000014 (v00 BOCHS )
[    0.033450][    T0] ACPI: RSDT 0x00000000BFFE15C9 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.035153][    T0] ACPI: FACP 0x00000000BFFE149D 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.036541][    T0] ACPI: DSDT 0x00000000BFFE0040 00145D (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.037869][    T0] ACPI: FACS 0x00000000BFFE0000 000040
[    0.038613][    T0] ACPI: APIC 0x00000000BFFE1511 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.040326][    T0] ACPI: HPET 0x00000000BFFE1591 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.042095][    T0] ACPI: Reserving FACP table memory at [mem 0xbffe149d-0xbffe1510]
[    0.043560][    T0] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe149c]
[    0.045025][    T0] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f]
[    0.046492][    T0] ACPI: Reserving APIC table memory at [mem 0xbffe1511-0xbffe1590]
[    0.047863][    T0] ACPI: Reserving HPET table memory at [mem 0xbffe1591-0xbffe15c8]
[    0.049237][    T0] ACPI: Local APIC address 0xfee00000
[    0.050155][    T0] mapped APIC to ffffffffff5fd000 (        fee00000)
[    0.051437][    T0] cma: dma_contiguous_reserve(limit 440000000)
[    0.156468][    T0] Zone ranges:
[    0.157142][    T0]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.158408][    T0]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.159598][    T0]   Normal   [mem 0x0000000100000000-0x000000043fffffff]
[    0.160801][    T0] Movable zone start for each node
[    0.161703][    T0] Early memory node ranges
[    0.162412][    T0]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.163664][    T0]   node   0: [mem 0x0000000000100000-0x00000000bffdffff]
[    0.164905][    T0]   node   0: [mem 0x0000000100000000-0x000000043fffffff]
[    0.166193][    T0] Initmem setup node 0 [mem 0x0000000000001000-0x000000043fffffff]
[    0.167603][    T0] On node 0 totalpages: 4194174
[    0.168453][    T0]   DMA zone: 56 pages used for memmap
[    0.169328][    T0]   DMA zone: 21 pages reserved
[    0.170204][    T0]   DMA zone: 3998 pages, LIFO batch:0
[    0.171680][    T0]   DMA zone: 28770 pages in unavailable ranges
[    0.172690][    T0]   DMA32 zone: 10696 pages used for memmap
[    0.173664][    T0]   DMA32 zone: 782304 pages, LIFO batch:63
[    0.185335][    T0]   DMA32 zone: 32 pages in unavailable ranges
[    0.186534][    T0]   Normal zone: 46592 pages used for memmap
[    0.187533][    T0]   Normal zone: 3407872 pages, LIFO batch:63


To reproduce:

        # build kernel
	cd linux
	cp config-5.13.0-rc1-00135-ge0a77a7a5a75 .config
	make HOSTCC=gcc-9 CC=gcc-9 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage

        git clone https://github.com/intel/lkp-tests.git
        cd lkp-tests
        bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email



---
0DAY/LKP+ Test Infrastructure                   Open Source Technology Center
https://lists.01.org/hyperkitty/list/lkp@lists.01.org       Intel Corporation

Thanks,
Oliver Sang


View attachment "config-5.13.0-rc1-00135-ge0a77a7a5a75" of type "text/plain" (135828 bytes)

View attachment "job-script" of type "text/plain" (4693 bytes)

Download attachment "dmesg.xz" of type "application/x-xz" (13892 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ