Re: Lockdep warnings on kexec (virtio_blk, hrtimers)

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



On Thu, 2024-12-12 at 14:34 +0100, Thomas Gleixner wrote:
> 
> David, can you retest with the debug patch below? That should pin-point
> the real culprit.

B[    1.545489] ------------[ cut here ]------------
[    1.546338] DEBUG_LOCKS_WARN_ON(suspend_syscore_active)
[    1.546375] WARNING: CPU: 0 PID: 18 at kernel/locking/lockdep.c:4471 lockdep_hardirqs_on+0x13a/0x140
[    1.548658] Modules linked in:
[    1.549164] CPU: 0 UID: 0 PID: 18 Comm: rcu_preempt Not tainted 6.13.0-rc1+ #2034
[    1.550421] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[    1.552173] RIP: 0010:lockdep_hardirqs_on+0x13a/0x140
[    1.552918] Code: 85 c9 74 13 8b 15 c6 b8 de 02 85 d2 0f 84 4d ff ff ff e9 58 ff ff ff 48 c7 c6 a3 47 e5 bb 48 c7 c7 4c 8f e4 bb e8 96 52 e6 fe <0f> 0b eb d6 66 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90
[    1.555728] RSP: 0018:ffffbde64009bcc8 EFLAGS: 00010082
[    1.556513] RAX: 0000000000000000 RBX: ffff9d23c18db340 RCX: ffffffffbc782a08
[    1.557579] RDX: 0000000000000000 RSI: 0000000000000003 RDI: 0000000000000001
[    1.558629] RBP: ffffffffba20fe54 R08: 0000000000000000 R09: 0000000000000000
[    1.559683] R10: 0000000000037e0c R11: 0000000000000000 R12: ffff9d23c3a18000
[    1.560721] R13: 0000000000000000 R14: ffff9d23c18db340 R15: 0000000000000000
[    1.561758] FS:  0000000000000000(0000) GS:ffff9d243d600000(0000) knlGS:0000000000000000
[    1.562925] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    1.563765] CR2: 00007fa03567f070 CR3: 00000000039b0001 CR4: 0000000000170ef0
[    1.564825] Call Trace:
[    1.565178]  <TASK>
[    1.565483]  ? __warn.cold+0xb7/0x151
[    1.566009]  ? lockdep_hardirqs_on+0x13a/0x140
[    1.566663]  ? report_bug+0xff/0x140
[    1.567176]  ? console_unlock+0x9d/0x150
[    1.567745]  ? handle_bug+0x58/0x90
[    1.568239]  ? exc_invalid_op+0x17/0x70
[    1.568796]  ? asm_exc_invalid_op+0x1a/0x20
[    1.569411]  ? finish_task_switch.isra.0+0xc4/0x2d0
[    1.570125]  ? lockdep_hardirqs_on+0x13a/0x140
[    1.570759]  ? lockdep_hardirqs_on+0x13a/0x140
[    1.571399]  finish_task_switch.isra.0+0xc4/0x2d0
[    1.572062]  __schedule+0x50a/0x1a10
[    1.572586]  ? find_held_lock+0x2b/0x80
[    1.573125]  ? schedule+0xea/0x140
[    1.573616]  ? __pfx_rcu_gp_kthread+0x10/0x10
[    1.574232]  schedule+0x3a/0x140
[    1.574708]  schedule_timeout+0x91/0x110
[    1.575261]  ? __pfx_process_timeout+0x10/0x10
[    1.575896]  rcu_gp_fqs_loop+0x10b/0x5b0
[    1.576455]  ? _raw_spin_unlock_irq+0x28/0x50
[    1.577072]  rcu_gp_kthread+0xf8/0x1b0
[    1.577621]  kthread+0xd5/0x100
[    1.578066]  ? __pfx_kthread+0x10/0x10
[    1.578606]  ret_from_fork+0x34/0x50
[    1.579110]  ? __pfx_kthread+0x10/0x10
[    1.579661]  ret_from_fork_asm+0x1a/0x30
[    1.580210]  </TASK>
[    1.580533] irq event stamp: 4182
[    1.581000] hardirqs last  enabled at (4181): [<ffffffffbb36b74a>] _raw_spin_unlock_irqrestore+0x5a/0x70
[    1.582346] hardirqs last disabled at (4182): [<ffffffffbb35fa37>] __schedule+0xf67/0x1a10
[    1.583504] softirqs last  enabled at (0): [<ffffffffba1b92d7>] copy_process+0xac7/0x2ba0
[    1.584651] softirqs last disabled at (0): [<0000000000000000>] 0x0
[    1.585531] ---[ end trace 0000000000000000 ]---
[    1.586205] ------------[ cut here ]------------
[    1.586863] WARNING: CPU: 0 PID: 18 at kernel/time/timekeeping.c:807 ktime_get+0xd6/0x100
[    1.588024] Modules linked in:
[    1.588464] CPU: 0 UID: 0 PID: 18 Comm: rcu_preempt Tainted: G        W          6.13.0-rc1+ #2034
[    1.589748] Tainted: [W]=WARN
[    1.590162] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[    1.591771] RIP: 0010:ktime_get+0xd6/0x100
[    1.592362] Code: cc cc cc cc 48 d1 e8 48 f7 d0 48 85 c8 74 22 8b 0d 23 16 eb 03 83 f9 3f 0f 87 f9 13 d4 ff 48 8b 05 17 16 eb 03 48 d3 e8 eb be <0f> 0b e9 40 ff ff ff 48 8b 15 04 16 eb 03 8b 35 fa 15 eb 03 8b 3d
[    1.594979] RSP: 0018:ffffbde64009bb90 EFLAGS: 00010002
[    1.595724] RAX: 0000000000000001 RBX: ffff9d243d639b28 RCX: 0000000000000003
[    1.596727] RDX: 00000000ffffff01 RSI: 0000000000000000 RDI: ffff9d243d639b28
[    1.597732] RBP: ffff9d243d6390c0 R08: 0000000000000000 R09: ffff9d243d739bc1
[    1.598735] R10: ffff9d243d627580 R11: 0000000000000000 R12: 000000001cb51477
[    1.599736] R13: 00000000972b390b R14: 000000000335a525 R15: 0000000000000001
[    1.600750] FS:  0000000000000000(0000) GS:ffff9d243d600000(0000) knlGS:0000000000000000
[    1.601871] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    1.602670] CR2: 00007fa03567f070 CR3: 00000000039b0001 CR4: 0000000000170ef0
[    1.603669] Call Trace:
[    1.604007]  <TASK>
[    1.604304]  ? __warn.cold+0xb7/0x151
[    1.604811]  ? ktime_get+0xd6/0x100
[    1.605300]  ? report_bug+0xff/0x140
[    1.605794]  ? handle_bug+0x58/0x90
[    1.606276]  ? exc_invalid_op+0x17/0x70
[    1.606813]  ? asm_exc_invalid_op+0x1a/0x20
[    1.607413]  ? ktime_get+0xd6/0x100
[    1.607907]  start_dl_timer+0x7c/0x200
[    1.608433]  update_curr_dl_se+0x1bf/0x1f0
[    1.609002]  update_curr+0x1a6/0x1d0
[    1.609523]  dequeue_entity+0x2b/0x630
[    1.610047]  ? asm_sysvec_kvm_asyncpf_interrupt+0x1a/0x20
[    1.610797]  dequeue_entities+0x113/0x5d0
[    1.611364]  ? __pfx_rcu_gp_kthread+0x10/0x10
[    1.611964]  dequeue_task_fair+0x139/0x2a0
[    1.612539]  ? __pfx_rcu_gp_kthread+0x10/0x10
[    1.613140]  __schedule+0x859/0x1a10
[    1.613636]  ? lock_timer_base+0x2b/0xf0
[    1.614176]  ? lock_acquire+0x2a2/0x310
[    1.614718]  ? lock_release+0x218/0x2c0
[    1.615248]  ? __pfx_rcu_gp_kthread+0x10/0x10
[    1.615861]  schedule+0x3a/0x140
[    1.616317]  schedule_timeout+0x91/0x110
[    1.616859]  ? __pfx_process_timeout+0x10/0x10
[    1.617477]  rcu_gp_fqs_loop+0x10b/0x5b0
[    1.618018]  ? _raw_spin_unlock_irq+0x28/0x50
[    1.618624]  rcu_gp_kthread+0xf8/0x1b0
[    1.619145]  kthread+0xd5/0x100
[    1.619617]  ? __pfx_kthread+0x10/0x10
[    1.620135]  ret_from_fork+0x34/0x50
[    1.620678]  ? __pfx_kthread+0x10/0x10
[    1.621202]  ret_from_fork_asm+0x1a/0x30
[    1.621756]  </TASK>
[    1.622057] irq event stamp: 4183
[    1.622519] hardirqs last  enabled at (4183): [<ffffffffba20fe54>] finish_task_switch.isra.0+0xc4/0x2d0
[    1.623816] hardirqs last disabled at (4182): [<ffffffffbb35fa37>] __schedule+0xf67/0x1a10
[    1.624952] softirqs last  enabled at (0): [<ffffffffba1b92d7>] copy_process+0xac7/0x2ba0
[    1.626085] softirqs last disabled at (0): [<0000000000000000>] 0x0
[    1.626958] ---[ end trace 0000000000000000 ]---
[    1.627802] ------------[ cut here ]------------
[    1.628457] Interrupts enabled after irqrouter_resume+0x0/0x50
[    1.629279] WARNING: CPU: 0 PID: 214 at drivers/base/syscore.c:103 syscore_resume+0x18a/0x220
[    1.630485] Modules linked in:
[    1.630901] CPU: 0 UID: 0 PID: 214 Comm: loadret Tainted: G        W          6.13.0-rc1+ #2034
[    1.632088] Tainted: [W]=WARN
[    1.632500] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[    1.634079] RIP: 0010:syscore_resume+0x18a/0x220
[    1.634717] Code: 00 e9 21 ff ff ff 80 3d 22 d0 d3 01 00 0f 85 03 ff ff ff 48 8b 73 18 48 c7 c7 8d f6 e9 bb c6 05 0a d0 d3 01 01 e8 d6 55 46 ff <0f> 0b e9 e5 fe ff ff e8 aa e9 54 ff 84 c0 0f 85 fb fe ff ff 80 3d
[    1.637298] RSP: 0018:ffffbde6402c7b38 EFLAGS: 00010286
[    1.638015] RAX: 0000000000000000 RBX: ffffffffbc9918c0 RCX: ffffffffbc782a08
[    1.638995] RDX: 0000000000000000 RSI: 0000000000000003 RDI: 0000000000000001
[    1.639982] RBP: 0000000000037e0c R08: 0000000000000000 R09: 0000000000000000
[    1.640961] R10: 0000000000000000 R11: 7075727265746e49 R12: ffffbde6402c7b68
[    1.641956] R13: ffffffffbc68ebe0 R14: 00000000fee1dead R15: 0000000000000000
[    1.642933] FS:  00007fed47db1680(0000) GS:ffff9d243d600000(0000) knlGS:0000000000000000
[    1.644036] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[    1.644825] CR2: 00007fa03567f070 CR3: 00000000039b0001 CR4: 0000000000170ef0
[    1.645811] Call Trace:
[    1.646145]  <TASK>
[    1.646437]  ? __warn.cold+0xb7/0x151
[    1.646935]  ? syscore_resume+0x18a/0x220
[    1.647485]  ? report_bug+0xff/0x140
[    1.647973]  ? console_unlock+0x9d/0x150
[    1.648513]  ? handle_bug+0x58/0x90
[    1.648990]  ? exc_invalid_op+0x17/0x70
[    1.649528]  ? asm_exc_invalid_op+0x1a/0x20
[    1.650106]  ? syscore_resume+0x18a/0x220
[    1.650661]  ? syscore_resume+0x18a/0x220
[    1.651210]  kernel_kexec+0xff/0x190
[    1.651712]  __do_sys_reboot+0x206/0x250
[    1.652267]  do_syscall_64+0x95/0x180
[    1.652774]  ? lockdep_hardirqs_on_prepare+0xdb/0x190
[    1.653473]  ? smp_call_function_many_cond+0x11c/0x790
[    1.654172]  ? lock_acquire+0xd0/0x310
[    1.654690]  ? free_unref_page+0x22b/0x6a0
[    1.655249]  ? __slab_free+0xdf/0x330
[    1.655760]  ? do_kexec_load+0x11d/0x340
[    1.656304]  ? kfree+0x2bf/0x3a0
[    1.656749]  ? __x64_sys_kexec_load+0xa9/0xe0
[    1.657356]  ? kfree+0xdb/0x3a0
[    1.657797]  ? do_kexec_load+0x11d/0x340
[    1.658342]  ? lockdep_hardirqs_on_prepare+0xdb/0x190
[    1.659042]  ? syscall_exit_to_user_mode+0x97/0x290
[    1.659720]  ? do_syscall_64+0xa1/0x180
[    1.660245]  entry_SYSCALL_64_after_hwframe+0x76/0x7e
[    1.660941] RIP: 0033:0x7fed47ce160d
[    1.661434] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d f3 47 0c 00 f7 d8 64 89 01 48
[    1.663995] RSP: 002b:00007ffc75b2de78 EFLAGS: 00000246 ORIG_RAX: 00000000000000a9
[    1.665024] RAX: ffffffffffffffda RBX: 00007ffc75b2dfe8 RCX: 00007fed47ce160d
[    1.666000] RDX: 0000000045584543 RSI: 0000000028121969 RDI: 00000000fee1dead
[    1.666974] RBP: 00007ffc75b2dec0 R08: 0000000000000000 R09: 0000000000000000
[    1.667947] R10: 00007fed47dbce90 R11: 0000000000000246 R12: 0000000000000001
[    1.668918] R13: 0000000000000000 R14: 00007fed47dec000 R15: 0000000000403e00
[    1.669899]  </TASK>
[    1.670195] irq event stamp: 12392
[    1.670661] hardirqs last  enabled at (12391): [<ffffffffba2ff03f>] smp_call_function_many_cond+0x66f/0x790
[    1.671990] hardirqs last disabled at (12392): [<ffffffffba305b2c>] kernel_kexec+0x13c/0x190
[    1.673135] softirqs last  enabled at (12376): [<ffffffffba1c6c12>] __irq_exit_rcu+0xe2/0x100
[    1.674307] softirqs last disabled at (12363): [<ffffffffba1c6c12>] __irq_exit_rcu+0xe2/0x100
[    1.675472] ---[ end trace 0000000000000000 ]---

Attachment: smime.p7s
Description: S/MIME cryptographic signature


[Index of Archives]     [Reiser Filesystem Development]     [Ceph FS]     [Kernel Newbies]     [Security]     [Netfilter]     [Bugtraq]     [Linux FS]     [Yosemite National Park]     [MIPS Linux]     [ARM Linux]     [Linux Security]     [Linux RAID]     [Samba]     [Device Mapper]     [Linux Media]

  Powered by Linux