linux-kernel - BUG: soft lockup in 5.17-rc3 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>] [day] [month] [year] [list]
Date:   Tue, 8 Feb 2022 15:59:18 +0800
From:   Guoqing Jiang <guoqing.jiang@...ux.dev>
To:     linux-acpi@...r.kernel.org, LKML <linux-kernel@...r.kernel.org>
Subject: BUG: soft lockup in 5.17-rc3 kernel

Hi,

Not sure if it is new bug or not, I got below message after boot 
5.17-rc3 kernel inside qemu, which might be ACPI relevant.

[    7.713141] ACPI: \_SB_.LNKA: Enabled at IRQ 10
[   12.536785] pci 0000:00:05.0: quirk_usb_early_handoff+0x0/0x850 took 
9536891 usecs
[   17.480456] ACPI: \_SB_.LNKB: Enabled at IRQ 11
[   22.303134] pci 0000:00:05.1: quirk_usb_early_handoff+0x0/0x850 took 
9535422 usecs
[   27.262086] ACPI: \_SB_.LNKC: Enabled at IRQ 11
[   29.241124] watchdog: BUG: soft lockup - CPU#3 stuck for 26s! 
[swapper/0:1]
[   29.241415] Modules linked in:
[   29.241415] irq event stamp: 11164904
[   29.241415] hardirqs last  enabled at (11164903): 
[<ffffffffb6312d5c>] kasan_quarantine_put+0x9c/0x200
[   29.241415] hardirqs last disabled at (11164904): 
[<ffffffffb6f5c90b>] sysvec_apic_timer_interrupt+0xb/0xc0
[   29.241415] softirqs last  enabled at (415092): [<ffffffffb6d5bda8>] 
inet_ctl_sock_create+0xd8/0x110
[   29.241415] softirqs last disabled at (415090): [<ffffffffb6d386ab>] 
raw_unhash_sk+0x3b/0x140
[   29.241415] CPU: 3 PID: 1 Comm: swapper/0 Not tainted 
5.17.0-rc3-57-default #17
[   29.241415] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), 
BIOS rel-1.14.0-0-g155821a-rebuilt.opensuse.org 04/01/2014
[   29.241415] RIP: 0010:rcu_is_watching+0x39/0x50
[   29.241415] Code: c7 c3 90 a2 04 00 89 c5 48 8d 3c ed e0 fa bb b7 e8 
9c 4a 31 00 48 03 1c ed e0 fa bb b7 48 89 df e8 4c 49 31 00 8b
03 83 e0 01 <65> ff 0d 60 8c 03 4a 74 03 5b 5d c3 0f 1f 44 00 00 5b 5d 
c3 0f 1f
[   29.245337] RSP: 0000:ffff88810099f518 EFLAGS: 00000202
[   29.245337] RAX: 0000000000000001 RBX: ffff8881097ca290 RCX: 
dffffc0000000000
[   29.245337] RDX: 0000000000000003 RSI: ffffffffb76a8be0 RDI: 
ffff8881097ca290
[   29.245337] RBP: 0000000000000003 R08: ffffffffb5ffd194 R09: 
0000000000000001
[   29.245337] R10: fffffbfff71976f9 R11: 0000000000000001 R12: 
0000000000000001
[   29.245337] R13: 0000000000000001 R14: ffff888100053680 R15: 
ffff888103add028
[   29.245337] FS:  0000000000000000(0000) GS:ffff888109780000(0000) 
knlGS:0000000000000000
[   29.245337] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   29.245337] CR2: 0000000000000000 CR3: 0000000050816001 CR4: 
0000000000770ee0
[   29.245337] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 
0000000000000000
[   29.245337] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 
0000000000000400
[   29.245337] PKRU: 55555554
[   29.245337] Call Trace:
[   29.245337]  <TASK>
[   29.245337]  rcu_read_lock_sched_held+0x21/0x60
[   29.249327]  kmem_cache_free+0x235/0x560
[   29.249327]  ? acpi_ut_init_stack_ptr_trace+0x8e/0x8e
[   29.249327]  acpi_os_release_object+0xa/0x10
[   29.249327]  acpi_ps_delete_parse_tree+0x19d/0x1f4
[   29.249327]  acpi_ps_complete_this_op+0x2c8/0x397
[   29.249327]  acpi_ps_complete_op+0x68/0x648
[   29.249327]  acpi_ps_parse_loop+0xd6a/0xe12
[   29.249327]  ? acpi_ds_exec_begin_op+0x38c/0x38c
[   29.249327]  ? acpi_ps_get_next_arg+0x9be/0x9be
[   29.249327]  ? kmem_cache_alloc+0x386/0x4e0
[   29.249327]  ? acpi_ut_trace+0x38/0x80
[   29.249327]  acpi_ps_parse_aml+0x258/0x78c
[   29.249327]  acpi_ps_execute_method+0x351/0x442
[   29.249327]  acpi_ns_evaluate+0x57d/0x7f1
[   29.249327]  acpi_ut_evaluate_object+0xd7/0x2ef
[   29.249327]  ? acpi_ut_track_stack_ptr+0x94/0xcf
[   29.249327]  acpi_rs_get_prt_method_data+0x93/0x123
[   29.249327]  ? acpi_rs_set_resource_source+0x97/0x97
[   29.253490]  ? acpi_ut_status_exit+0x98/0xb3
[   29.253490]  ? acpi_rs_validate_parameters+0x126/0x130
[   29.253490]  acpi_get_irq_routing_table+0xbb/0x110
[   29.253490]  ? acpi_rs_validate_parameters+0x130/0x130
[   29.253490]  ? lockdep_hardirqs_on_prepare+0x220/0x220
[   29.253490]  acpi_pci_irq_find_prt_entry+0x10b/0x520
[   29.253490]  ? acpi_penalize_sci_irq+0x30/0x30
[   29.253490]  ? lock_release+0x24e/0x450
[   29.253490]  ? pci_conf1_write+0xa4/0xf0
[   29.253490]  acpi_pci_irq_lookup+0x77/0x300
[   29.253490]  ? acpi_pci_irq_find_prt_entry+0x520/0x520
[   29.253490]  ? lockdep_hardirqs_on_prepare+0x129/0x220
[   29.253490]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[   29.253490]  acpi_pci_irq_disable+0x8d/0x120
[   29.253490]  pci_disable_device+0x156/0x200
[   29.253490]  ? pci_reenable_device+0x90/0x90
[   29.257261]  ? uhci_reset_hc+0x50/0x50
[   29.257261]  ? pci_enable_device_flags+0x19d/0x1b0
[   29.257261]  quirk_usb_early_handoff+0xe9/0x850
[   29.257261]  ? lock_downgrade+0x3c0/0x3c0
[   29.257261]  ? usb_asmedia_modifyflowcontrol+0x90/0x90
[   29.257261]  ? pvclock_clocksource_read+0xd9/0x1a0
[   29.257261]  pci_fixup_device+0x131/0x230
[   29.257261]  ? usb_asmedia_modifyflowcontrol+0x90/0x90
[   29.261412]  pci_apply_final_quirks+0xba/0x1f2
[   29.261412]  ? acpi_pci_init+0x7d/0x7d
[   29.261412]  ? lock_is_held_type+0xe4/0x140
[   29.261412]  ? acpi_pci_init+0x7d/0x7d
[   29.261412]  do_one_initcall+0xb9/0x3e0
[   29.261412]  ? perf_trace_initcall_level+0x270/0x270
[   29.261412]  ? parameq+0x90/0x90
[   29.261412]  ? lock_is_held_type+0xe4/0x140
[   29.261412]  ? rcu_read_lock_sched_held+0x3f/0x60
[   29.261412]  kernel_init_freeable+0x3dd/0x46f
[   29.261412]  ? console_on_rootfs+0x52/0x52
[   29.261412]  ? lockdep_hardirqs_on_prepare+0x129/0x220
[   29.261412]  ? mark_held_locks+0x24/0x90
[   29.261412]  ? rest_init+0x280/0x280
[   29.261412]  kernel_init+0x19/0x140
[   29.261412]  ret_from_fork+0x1f/0x30
[   29.261412]  </TASK>
[   32.125903] pci 0000:00:05.2: quirk_usb_early_handoff+0x0/0x850 took 
9592149 usecs
[   37.063466] ACPI: \_SB_.LNKD: Enabled at IRQ 10
[   41.833830] pci 0000:00:05.7: quirk_usb_early_handoff+0x0/0x850 took 
9479978 usecs
[   41.834293] PCI: CLS 0 bytes, default 64
[   41.834706] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[   41.834980] software IO TLB: mapped [mem 
0x000000009b000000-0x000000009f000000] (64MB)
[   41.835437] ACPI: bus type thunderbolt registered

And config file is attached in case it is needed.

Thanks,
Guoqing

View attachment "config-5.17.0-rc3-57-default" of type "text/plain" (250593 bytes)