linux-kernel - [BUG v6.0-rc2] lockdep splat on ct_kernel

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-next>] [day] [month] [year] [list]

Message-ID: <20220822164404.57952727@gandalf.local.home>
Date:   Mon, 22 Aug 2022 16:44:04 -0400
From:   Steven Rostedt <rostedt@...dmis.org>
To:     LKML <linux-kernel@...r.kernel.org>
Cc:     Linus Torvalds <torvalds@...ux-foundation.org>,
        Frederic Weisbecker <fweisbec@...il.com>,
        "Paul E. McKenney" <paulmck@...nel.org>,
        Peter Zijlstra <peterz@...radead.org>
Subject: [BUG v6.0-rc2] lockdep splat on ct_kernel_enter()

My tests are failing because of this splat:

[   16.073659] ------------[ cut here ]------------
[   16.074407] bus: 'platform': add driver acpi-ged
[   16.074424] DEBUG_LOCKS_WARN_ON(lockdep_hardirqs_enabled())
[   16.074424] WARNING: CPU: 0 PID: 0 at kernel/locking/lockdep.c:5506 check_flags+0x114/0x1d0
[   16.083020] bus: 'acpi': add driver ac
[   16.074424] Modules linked in:
[   16.074424] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 6.0.0-rc2-test+ #18
[   16.096856] bus: 'acpi': add driver button
[   16.074424] Hardware name: MSI MS-7823/CSM-H87M-G43 (MS-7823), BIOS V1.6 02/22/2014
[   16.074424] RIP: 0010:check_flags+0x114/0x1d0
[   16.103642] bus: 'acpi': __driver_probe_device: matched device PNP0C0C:00 with driver button
[   16.074424] Code: ff ff e8 ef 0a aa ff 85 c0 74 21 44 8b 0d bc 56 c6 00 45 85 c9 75 15 48 c7 c6 c7 e8 37 b9 48 c7 c7 a0 96 36 b9 e8 54 c9 f9 ff <0f> 0b 48 c7 c7 b0 fc 37 b9 e8 2e 20 fa ff eb 99 85 c0 0f 85 38 ff
[   16.074424] RSP: 0000:ffffffffb9603d28 EFLAGS: 00010082
[   16.114488] bus: 'acpi': really_probe: probing driver button with device PNP0C0C:00
[   16.074424] 
[   16.074424] RAX: 0000000000000000 RBX: ffffffffb96d6c20 RCX: 0000000000000000
[   16.074424] RDX: 0000000000000000 RSI: ffffffffb81171da RDI: 0000000000000001
[   16.126499] device: 'input0': device_add
[   16.074424] RBP: ffffffffb9603d28 R08: 0000000000000000 R09: 0000000000000001
[   16.074424] R10: 0000000000000000 R11: 0000000000000030 R12: ffffffffb961aa80
[   16.074424] R13: ffffffffb961b3f0 R14: 0000000000000000 R15: ffffffffb9758878
[   16.153763] PM: Adding info for No Bus:input0
[   16.074424] FS:  0000000000000000(0000) GS:ffff961b55c00000(0000) knlGS:0000000000000000
[   16.074424] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   16.166543] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[   16.074424] CR2: ffff961b5edff000 CR3: 00000000b1612001 CR4: 00000000001706f0
[   16.074424] Call Trace:
[   16.074424]  <TASK>
[   16.175153] device: 'wakeup15': device_add
[   16.074424]  lock_is_held_type+0x6f/0x130
[   16.186284]  rcu_read_lock_sched_held+0x4a/0x90
[   16.186284]  trace_rcu_dyntick+0x3a/0xe0
[   16.186284]  ct_kernel_enter.constprop.0+0x66/0xa0
[   16.186284]  ct_idle_exit+0xd/0x30
[   16.186284]  cpuidle_enter_state+0x28a/0x310
[   16.186284]  cpuidle_enter+0x2e/0x50
[   16.186284]  do_idle+0x1ec/0x280
[   16.186284]  cpu_startup_entry+0x20/0x30
[   16.186284]  rest_init+0xf3/0x170
[   16.186284]  arch_call_rest_init+0xe/0x1d
[   16.186284]  start_kernel+0x69f/0x6c9
[   16.186284]  x86_64_start_reservations+0x24/0x2a
[   16.186284]  x86_64_start_kernel+0x8d/0x97
[   16.186284]  secondary_startup_64_no_verify+0xe0/0xeb
[   16.186284]  </TASK>
[   16.186284] irq event stamp: 129196
[   16.186284] hardirqs last  enabled at (129196): [<ffffffffb8ba6bf2>] poll_idle+0x32/0xbc
[   16.186284] hardirqs last disabled at (129195): [<ffffffffb80ec080>] do_idle+0xb0/0x280
[   16.186284] softirqs last  enabled at (129097): [<ffffffffb8e00273>] __do_softirq+0x273/0x441
[   16.186284] softirqs last disabled at (129086): [<ffffffffb809c4be>] __irq_exit_rcu+0x9e/0xd0
[   16.186284] ---[ end trace 0000000000000000 ]---


Looks like lockdep and the trace_rcu_dyntick() code is getting out of sync
with respect to whether or not interrupts are enabled.

Config attached.

-- Steve

Download attachment "config" of type "application/octet-stream" (133289 bytes)