Re: [patch V3 09/33] genirq/msi: Add range checking to msi_insert_desc()

[Matthew Rosato]

On 12/15/22 9:49 AM, Thomas Gleixner wrote:
> On Wed, Dec 14 2022 at 10:42, Niklas Schnelle wrote:
>> On Tue, 2022-12-13 at 11:04 -0800, Guenter Roeck wrote:
>>> This patch results in various s390 qemu test failures.
>>> There is a warning backtrace
>>>
>>>    12.674858] WARNING: CPU: 0 PID: 1 at kernel/irq/msi.c:167 msi_ctrl_valid+0x2a/0xb0
>>>
>>> followed by
>>>
>>> [   12.684333] virtio_net: probe of virtio0 failed with error -34
>>>
>>> and Ethernet interfaces don't instantiate.
>> As far as I'm aware so far he tracked this down to code calling
>> msi_domain_get_hwsize() which in turn calls msi_get_device_domain()
>> which then returns NULL leading to msi_domain_get_hwsize() returning 0.
>> I think this is related to the fact that we currently don't use IRQ
>> domains.
> 
> Correct and for some stupid reason I thought 0 is a good return value
> here :)
> 
> 
> 
> diff --git a/kernel/irq/msi.c b/kernel/irq/msi.c
> index bd4d4dd626b4..8fb10f216dc0 100644
> --- a/kernel/irq/msi.c
> +++ b/kernel/irq/msi.c
> @@ -609,8 +609,8 @@ static unsigned int msi_domain_get_hwsize(struct device *dev, unsigned int domid
>  		info = domain->host_data;
>  		return info->hwsize;
>  	}
> -	/* No domain, no size... */
> -	return 0;
> +	/* No domain, default to MSI_MAX_INDEX */
> +	return MSI_MAX_INDEX;
>  }
>  
>  static inline void irq_chip_write_msi_msg(struct irq_data *data,

Ah, that makes sense...  So, with that diff applied, that fixes most of the issues I'm seeing incl. the virtio one that Guenter mentioned.  But it looks like NVMe devices are still broken on s390 with a different backtrace -- the bisect for that one points to another patch in part2 of this work and looks like another issue with missing irq domain:

40742716f294 genirq/msi: Make msi_add_simple_msi_descs() device domain aware


[    4.308861] ------------[ cut here ]------------
[    4.308865] WARNING: CPU: 7 PID: 9 at kernel/irq/msi.c:167 msi_domain_free_msi_descs_range+0x3c/0xd0
[    4.308877] Modules linked in: mlx5_core aes_s390 nvme des_s390 libdes sha3_512_s390 sha3_256_s390 sha512_s390 sha256_s390 nvme_core sha1_s390 sha_common pkey zcrypt rng_core autofs4
[    4.308896] CPU: 7 PID: 9 Comm: kworker/u20:0 Not tainted 6.1.0 #179
[    4.308898] Hardware name: IBM 3931 A01 782 (KVM/Linux)
[    4.308900] Workqueue: events_unbound async_run_entry_fn
[    4.308905] Krnl PSW : 0704c00180000000 00000000b6426b78 (msi_domain_free_msi_descs_range+0x40/0xd0)
[    4.308909]            R:0 T:1 IO:1 EX:1 Key:0 M:1 W:0 P:0 AS:3 CC:0 PM:0 RI:0 EA:3
[    4.308911] Krnl GPRS: 0700000080eda0a0 0000000000000000 0000000080eda0a0 0000000000000000
[    4.308913]            0000000000000000 0000000000000000 0000000000000cc0 0000000080eda000
[    4.308914]            00000000b7ddc000 0000000091934aa8 000000000000ffff 0000000000000000
[    4.308915]            0000000080344200 0000000080f2b1c0 0000037fffb5b918 0000037fffb5b8c8
[    4.308924] Krnl Code: 00000000b6426b68: e54cf0ac0000	mvhi	172(%r15),0
[    4.308924]            00000000b6426b6e: ec3c000b017f	clij	%r3,1,12,00000000b6426b84
[    4.308924]           #00000000b6426b74: af000000		mc	0,0
[    4.308924]           >00000000b6426b78: eb9ff0b00004	lmg	%r9,%r15,176(%r15)
[    4.308924]            00000000b6426b7e: 07fe		bcr	15,%r14
[    4.308924]            00000000b6426b80: 47000700		bc	0,1792
[    4.308924]            00000000b6426b84: b90400a5		lgr	%r10,%r5
[    4.308924]            00000000b6426b88: b9040013		lgr	%r1,%r3
[    4.308935] Call Trace:
[    4.308938]  [<00000000b6426b78>] msi_domain_free_msi_descs_range+0x40/0xd0 
[    4.308945]  [<00000000b6bb126e>] pci_free_msi_irqs+0x26/0x48 
[    4.308950]  [<00000000b6baf4d4>] pci_disable_msix+0x6c/0x80 
[    4.308954]  [<00000000b6baf716>] pci_free_irq_vectors+0x26/0x88 
[    4.308956]  [<000003ff7fdfa8f4>] nvme_setup_io_queues+0x18c/0x398 [nvme] 
[    4.308968]  [<000003ff7fdfbf1e>] nvme_probe+0x2e6/0x3b0 [nvme] 
[    4.308972]  [<00000000b6ba44cc>] local_pci_probe+0x44/0x80 
[    4.308974]  [<00000000b6ba46d8>] pci_call_probe+0x50/0x180 
[    4.308976]  [<00000000b6ba5166>] pci_device_probe+0xae/0x110 
[    4.308978]  [<00000000b6c0a19a>] really_probe+0xd2/0x480 
[    4.308982]  [<00000000b6c0a6f8>] driver_probe_device+0x40/0xf0 
[    4.308984]  [<00000000b6c0a80e>] __driver_attach_async_helper+0x66/0xf0 
[    4.308986]  [<00000000b63cfb72>] async_run_entry_fn+0x4a/0x1b0 
[    4.308987]  [<00000000b63c1368>] process_one_work+0x200/0x458 
[    4.308991]  [<00000000b63c1aee>] worker_thread+0x66/0x480 
[    4.308993]  [<00000000b63caa00>] kthread+0x108/0x110 
[    4.308996]  [<00000000b634f2dc>] __ret_from_fork+0x3c/0x58 
[    4.308999]  [<00000000b6f8da2a>] ret_from_fork+0xa/0x40 
[    4.309006] Last Breaking-Event-Address:
[    4.309007]  [<00000000b6426ba8>] msi_domain_free_msi_descs_range+0x70/0xd0
[    4.309009] ---[ end trace 0000000000000000 ]---
[    8.957187] nvme: probe of 0003:00:00.0 failed with error -22
[    8.957216] ------------[ cut here ]------------
[    8.957217] WARNING: CPU: 5 PID: 9 at kernel/irq/msi.c:275 msi_device_data_release+0x76/0xa0
[    8.957229] Modules linked in: mlx5_core aes_s390 nvme des_s390 libdes sha3_512_s390 sha3_256_s390 sha512_s390 sha256_s390 nvme_core sha1_s390 sha_common pkey zcrypt rng_core autofs4
[    8.957248] CPU: 5 PID: 9 Comm: kworker/u20:0 Tainted: G        W          6.1.0 #179
[    8.957252] Hardware name: IBM 3931 A01 782 (KVM/Linux)
[    8.957254] Workqueue: events_unbound async_run_entry_fn
[    8.957259] Krnl PSW : 0704e00180000000 00000000b642729a (msi_device_data_release+0x7a/0xa0)
[    8.957262]            R:0 T:1 IO:1 EX:1 Key:0 M:1 W:0 P:0 AS:3 CC:2 PM:0 RI:0 EA:3
[    8.957265] Krnl GPRS: a813fdc020800000 00000000928b6840 0000000091934ab8 0000000080344200
[    8.957267]            0000000000000000 0000000091934a80 0000000080d79988 0000000000000000
[    8.957268]            0000000080eda0a0 0000037fffb5bc10 0000000080eda0a0 0000000091934aa8
[    8.957270]            0000000080344200 00000000800e0402 00000000b642724e 0000037fffb5bad0
[    8.957279] Krnl Code: 00000000b642728c: f0a0000407fe	srp	4(11,%r0),2046,0
[    8.957279]            00000000b6427292: 47000700		bc	0,1792
[    8.957279]           #00000000b6427296: af000000		mc	0,0
[    8.957279]           >00000000b642729a: a7f4ffdf		brc	15,00000000b6427258
[    8.957279]            00000000b642729e: af000000		mc	0,0
[    8.957279]            00000000b64272a2: 4120b048		la	%r2,72(%r11)
[    8.957279]            00000000b64272a6: c0e5005a0c4d	brasl	%r14,00000000b6f68b40
[    8.957279]            00000000b64272ac: e548a1180000	mvghi	280(%r10),0
[    8.957290] Call Trace:
[    8.957292]  [<00000000b642729a>] msi_device_data_release+0x7a/0xa0 
[    8.957295] ([<00000000b642724e>] msi_device_data_release+0x2e/0xa0)
[    8.957298]  [<00000000b6c0f608>] release_nodes+0x50/0xd8 
[    8.957305]  [<00000000b6c111aa>] devres_release_all+0xaa/0xf0 
[    8.957308]  [<00000000b6c0a2f2>] really_probe+0x22a/0x480 
[    8.957310]  [<00000000b6c0a6f8>] driver_probe_device+0x40/0xf0 
[    8.957312]  [<00000000b6c0a80e>] __driver_attach_async_helper+0x66/0xf0 
[    8.957314]  [<00000000b63cfb72>] async_run_entry_fn+0x4a/0x1b0 
[    8.957315]  [<00000000b63c1368>] process_one_work+0x200/0x458 
[    8.957320]  [<00000000b63c1aee>] worker_thread+0x66/0x480 
[    8.957322]  [<00000000b63caa00>] kthread+0x108/0x110 
[    8.957325]  [<00000000b634f2dc>] __ret_from_fork+0x3c/0x58 
[    8.957328]  [<00000000b6f8da2a>] ret_from_fork+0xa/0x40 
[    8.957336] Last Breaking-Event-Address:
[    8.957337]  [<00000000b6427254>] msi_device_data_release+0x34/0xa0
[    8.957339] ---[ end trace 0000000000000000 ]---

The line number for the first warning points to the WARN_ON check in msi_ctrl_valid -- specifically it's the !dev->msi.data->__domains[ctrl->domid].domain check that is failing.

The second warning is the WARN_ON_ONCE(!xa_empty(&md->__domains[i].store)) check in msi_device_data_release, presumably a victim of backing out after the first error.