linux-kernel - [locking/lockdep] 62f18467c4: WARNING:at_kernel/locking/lockdep.c:#register_lock

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>] [day] [month] [year] [list]
Date:   Fri, 30 Nov 2018 05:24:46 +0800
From:   kernel test robot <lkp@...el.com>
To:     Bart Van Assche <bvanassche@....org>
Cc:     LKML <linux-kernel@...r.kernel.org>,
        Bart Van Assche <bart.vanassche@...disk.com>, lkp@...org
Subject: [locking/lockdep]  62f18467c4:
 WARNING:at_kernel/locking/lockdep.c:#register_lock_class


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

commit: 62f18467c40065cae25a8e52c41de0d9771cfd24 ("locking/lockdep: Complain if a lock object has no name")
https://github.com/bvanassche/linux for-next

in testcase: trinity
with following parameters:

	runtime: 300s

test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/


on test machine: qemu-system-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 512M

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


+----------------------------------------------------------+------------+------------+
|                                                          | 85c300bf33 | 62f18467c4 |
+----------------------------------------------------------+------------+------------+
| boot_successes                                           | 22         | 0          |
| boot_failures                                            | 0          | 10         |
| WARNING:at_kernel/locking/lockdep.c:#register_lock_class | 0          | 10         |
| EIP:register_lock_class                                  | 0          | 10         |
| EIP:_raw_spin_unlock_irqrestore                          | 0          | 10         |
+----------------------------------------------------------+------------+------------+



[    0.308396] WARNING: CPU: 0 PID: 0 at kernel/locking/lockdep.c:746 register_lock_class+0x290/0x540
[    0.309861] Modules linked in:
[    0.310284] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.20.0-rc4-00049-g62f1846 #1
[    0.311315] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[    0.312423] EIP: register_lock_class+0x290/0x540
[    0.313066] Code: 68 34 18 9c c1 e8 bb 2c 01 00 68 5c 18 9c c1 e8 b1 2c 01 00 e8 2b cc 66 00 8b 06 83 c4 0c e9 28 fe ff ff 8d b4 26 00 00 00 00 <0f> 0b e9 8f fd ff ff e8 d4 82 22 00 85 c0 74 20 8b 0d dc 80 b8 c1
[    0.315508] EAX: 00000000 EBX: c1a8ac80 ECX: 00000000 EDX: 00000000
[    0.316338] ESI: dbaf4dfc EDI: 00000000 EBP: db439e3c ESP: db439e0c
[    0.317171] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00210046
[    0.318067] CR0: 80050033 CR2: ffffffff CR3: 01da6000 CR4: 00040690
[    0.318901] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000
[    0.319697] DR6: fffe0ff0 DR7: 00000400
[    0.320211] Call Trace:
[    0.320527]  <IRQ>
[    0.320823]  __lock_acquire+0x5a/0x1580
[    0.321312]  ? mark_lock+0x44b/0x5e0
[    0.321804]  ? __lock_acquire+0xd6b/0x1580
[    0.322327]  ? pvclock_clocksource_read+0xae/0x1c0
[    0.322949]  lock_acquire+0x82/0x170
[    0.323430]  ? scheduler_tick+0x46/0x130
[    0.323968]  update_rq_clock+0xbe/0x230
[    0.324475]  ? scheduler_tick+0x46/0x130
[    0.325043]  ? prandom_u32+0x1b/0x30
[    0.325518]  scheduler_tick+0x46/0x130
[    0.326038]  update_process_times+0x36/0x40
[    0.326585]  tick_periodic+0x35/0xc0
[    0.327092]  tick_handle_periodic+0x1d/0x70
[    0.327635]  timer_interrupt+0xd/0x20
[    0.328154]  __handle_irq_event_percpu+0x75/0x410
[    0.328786]  handle_irq_event_percpu+0x26/0x60
[    0.329367]  handle_irq_event+0x29/0x50
[    0.329910]  ? irq_set_chained_handler_and_data+0x70/0x70
[    0.330643]  handle_level_irq+0x7a/0x110
[    0.331219]  handle_irq+0x121/0x160
[    0.331674]  </IRQ>
[    0.332005]  do_IRQ+0x48/0x110
[    0.332425]  common_interrupt+0xec/0x100
[    0.332970] EIP: _raw_spin_unlock_irqrestore+0x2c/0x50
[    0.333626] Code: e5 56 53 89 c6 89 d3 8d 40 10 8b 4d 04 ba 01 00 00 00 e8 27 cc 96 ff 89 f0 e8 a0 1d 97 ff f6 c7 02 74 1b e8 76 15 9e ff 53 9d <64> ff 0d d0 57 c5 c1 5b 5e 5d c3 89 f6 8d bc 27 00 00 00 00 53 9d
[    0.336275] EAX: c1a8ac80 EBX: 00200246 ECX: 0001056c EDX: 00000001
[    0.337164] ESI: db420264 EDI: c1a9b440 EBP: c1a83f18 ESP: c1a83f10
[    0.337986] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00200246
[    0.338901]  ? tracing_release+0x11b/0x190
[    0.339439]  ? kmsg_dump_get_buffer+0x48/0x3a0
[    0.340007]  __setup_irq+0x42b/0x6f0
[    0.340479]  setup_irq+0x70/0xd0
[    0.340930]  hpet_time_init+0x1d/0x2e
[    0.341429]  x86_late_time_init+0x9/0x16
[    0.341972]  start_kernel+0x414/0x491
[    0.342454]  i386_start_kernel+0x8f/0x93
[    0.343003]  startup_32_smp+0x15f/0x170
[    0.343507] irq event stamp: 3500
[    0.343974] hardirqs last  enabled at (3497): [<c1767dfa>] _raw_spin_unlock_irqrestore+0x2a/0x50
[    0.345124] hardirqs last disabled at (3498): [<c100167d>] trace_hardirqs_off_thunk+0xc/0x1f
[    0.346225] softirqs last  enabled at (3500): [<c108001c>] _local_bh_enable+0x4c/0x70
[    0.347295] softirqs last disabled at (3499): [<c1080d00>] irq_enter+0x50/0x70
[    0.348297] ---[ end trace 04c6066979bd8a05 ]---


To reproduce:

        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



Thanks,
lkp

View attachment "config-4.20.0-rc4-00049-g62f1846" of type "text/plain" (112875 bytes)

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

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