On 14.09.2021 01:46, Bjorn Helgaas wrote: > On Mon, Sep 13, 2021 at 10:18:18AM -0400, Dave Jones wrote: >> On Sun, Sep 12, 2021 at 04:58:27PM -0700, Linus Torvalds wrote: >> > So 5.15 isn't shaping up to be a particularly large release, at least >> > in number of commits. At only just over 10k non-merge commits, this is >> > in fact the smallest rc1 we have had in the 5.x series. We're usually >> > hovering in the 12-14k commit range. >> >> This release takes over two minutes longer to boot on one my >> machines than 5.14. The time just seems to be unaccounted for, even >> with initcall_debug > >> ... >> [ 2.194093] pci 0000:01:00.0: calling quirk_f0_vpd_link+0x0/0x60 @ 1 >> [ 2.194097] pci 0000:01:00.0: quirk_f0_vpd_link+0x0/0x60 took 0 usecs >> [ 2.194100] pci 0000:01:00.0: [8086:10fb] type 00 class 0x020000 >> [ 2.194109] pci 0000:01:00.0: reg 0x10: [mem 0xd0080000-0xd00fffff 64bit pref] >> [ 2.194113] pci 0000:01:00.0: reg 0x18: [io 0xe020-0xe03f] >> [ 2.194121] pci 0000:01:00.0: reg 0x20: [mem 0xd0104000-0xd0107fff 64bit pref] >> [ 2.194126] pci 0000:01:00.0: reg 0x30: [mem 0xdfd80000-0xdfdfffff pref] >> [ 2.194136] pci 0000:01:00.0: calling quirk_igfx_skip_te_disable+0x0/0x50 @ 1 >> [ 2.194139] pci 0000:01:00.0: quirk_igfx_skip_te_disable+0x0/0x50 took 0 usecs >> [ 2.194164] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold >> >> * stall here for 86 seconds * >> >> [ 88.675114] pci 0000:01:00.0: reg 0x184: [mem 0x00000000-0x00003fff 64bit pref] >> ... > > >> 7bac54497c3e3b2ca37b7043f1fa78586540f10e is the first bad commit >> commit 7bac54497c3e3b2ca37b7043f1fa78586540f10e >> Author: Heiner Kallweit <hkallweit1@xxxxxxxxx> >> Date: Sun Aug 8 19:22:52 2021 +0200 >> >> PCI/VPD: Determine VPD size in pci_vpd_init() >> >> Determine VPD size in pci_vpd_init(). >> >> Quirks set dev->vpd.len to a non-zero value, so they cause us to skip the >> dynamic size calculation. Prerequisite is that we move the quirks from >> FINAL to HEADER so they are run before pci_vpd_init(). >> >> Link: https://lore.kernel.org/r/cc4a6538-557a-294d-4f94-e6d1d3c91589@xxxxxxxxx >> Signed-off-by: Heiner Kallweit <hkallweit1@xxxxxxxxx> >> Signed-off-by: Bjorn Helgaas <bhelgaas@xxxxxxxxxx> >> >> >> Which unfortunately doesn't revert cleanly I can't test it reverted in >> isolation. >> >> My guess is there's something quirky about the PCI bus on this machine >> that causes stalls until we hit timeout, but I'm not sure where to begin >> debugging this. > > Sorry for the inconvenience of this, and thank you very much for doing > the bisection to track it down. > > We *could* revert 7bac54497c3e, but it'd be messy because a bunch of > follow-up stuff depends on it. > > I propose something like the patch below. Would you mind trying it > out? > > > commit 4ede9949b93c ("PCI/VPD: Defer VPD sizing until first access") > Author: Bjorn Helgaas <bhelgaas@xxxxxxxxxx> > Date: Mon Sep 13 16:13:26 2021 -0500 > > PCI/VPD: Defer VPD sizing until first access > > 7bac54497c3e ("PCI/VPD: Determine VPD size in pci_vpd_init()") reads VPD at > enumeration-time to find the size. But this is quite slow, and we don't > need the size until we actually need data from VPD. Dave reported a boot > slowdown of more than two minutes [1]. > > Defer the VPD sizing until a driver or the user requests information from > VPD. If devices are quirked because VPD is known not to work, clear the > vpd.cap pointer so we don't access it at all. > > [1] https://lore.kernel.org/r/20210913141818.GA27911@xxxxxxxxxxxxxxxxx/ > Fixes: 7bac54497c3e ("PCI/VPD: Determine VPD size in pci_vpd_init()") > Signed-off-by: Bjorn Helgaas <bhelgaas@xxxxxxxxxx> > > diff --git a/drivers/pci/vpd.c b/drivers/pci/vpd.c > index 25557b272a4f..ca823ceee10c 100644 > --- a/drivers/pci/vpd.c > +++ b/drivers/pci/vpd.c > @@ -46,13 +46,12 @@ static struct pci_dev *pci_get_func0_dev(struct pci_dev *dev) > } > > #define PCI_VPD_MAX_SIZE (PCI_VPD_ADDR_MASK + 1) > -#define PCI_VPD_SZ_INVALID UINT_MAX > > /** > * pci_vpd_size - determine actual size of Vital Product Data > * @dev: pci device struct > */ > -static size_t pci_vpd_size(struct pci_dev *dev) > +static void pci_vpd_size(struct pci_dev *dev) > { > size_t off = 0, size; > unsigned char tag, header[1+2]; /* 1 byte tag, 2 bytes length */ > @@ -71,7 +70,7 @@ static size_t pci_vpd_size(struct pci_dev *dev) > if (pci_read_vpd(dev, off + 1, 2, &header[1]) != 2) { > pci_warn(dev, "failed VPD read at offset %zu\n", > off + 1); > - return off ?: PCI_VPD_SZ_INVALID; > + goto finish; > } > size = pci_vpd_lrdt_size(header); > if (off + size > PCI_VPD_MAX_SIZE) > @@ -87,16 +86,19 @@ static size_t pci_vpd_size(struct pci_dev *dev) > > off += PCI_VPD_SRDT_TAG_SIZE + size; > if (tag == PCI_VPD_STIN_END) /* End tag descriptor */ > - return off; > + goto finish; > } > } > - return off; > + goto finish; > > error: > pci_info(dev, "invalid VPD tag %#04x (size %zu) at offset %zu%s\n", > header[0], size, off, off == 0 ? > "; assume missing optional EEPROM" : ""); > - return off ?: PCI_VPD_SZ_INVALID; > +finish: > + dev->vpd.len = off; > + if (off == 0) > + dev->vpd.cap = 0; /* No VPD at all */ > } > > /* > @@ -145,9 +147,6 @@ static ssize_t pci_vpd_read(struct pci_dev *dev, loff_t pos, size_t count, > loff_t end = pos + count; > u8 *buf = arg; > > - if (!vpd->cap) > - return -ENODEV; > - > if (pos < 0) > return -EINVAL; > > @@ -206,9 +205,6 @@ static ssize_t pci_vpd_write(struct pci_dev *dev, loff_t pos, size_t count, > loff_t end = pos + count; > int ret = 0; > > - if (!vpd->cap) > - return -ENODEV; > - > if (pos < 0 || (pos & 3) || (count & 3)) > return -EINVAL; > > @@ -244,12 +240,6 @@ void pci_vpd_init(struct pci_dev *dev) > { > dev->vpd.cap = pci_find_capability(dev, PCI_CAP_ID_VPD); > mutex_init(&dev->vpd.lock); > - > - if (!dev->vpd.len) > - dev->vpd.len = pci_vpd_size(dev); > - > - if (dev->vpd.len == PCI_VPD_SZ_INVALID) > - dev->vpd.cap = 0; > } > > static ssize_t vpd_read(struct file *filp, struct kobject *kobj, > @@ -294,25 +284,29 @@ const struct attribute_group pci_dev_vpd_attr_group = { > > void *pci_vpd_alloc(struct pci_dev *dev, unsigned int *size) > { > - unsigned int len = dev->vpd.len; > + struct pci_vpd *vpd = &dev->vpd; > + unsigned int len; > void *buf; > int cnt; > > - if (!dev->vpd.cap) > + if (!vpd->cap) > return ERR_PTR(-ENODEV); > > buf = kmalloc(len, GFP_KERNEL); > if (!buf) > return ERR_PTR(-ENOMEM); > > - cnt = pci_read_vpd(dev, 0, len, buf); > + if (vpd->len == 0) > + pci_vpd_size(dev); > + > + cnt = pci_read_vpd(dev, 0, vpd->len, buf); > if (cnt != len) { > kfree(buf); > return ERR_PTR(-EIO); > } > > if (size) > - *size = len; > + *size = vpd->len; > > return buf; > } > @@ -374,6 +368,7 @@ static int pci_vpd_find_info_keyword(const u8 *buf, unsigned int off, > */ > ssize_t pci_read_vpd(struct pci_dev *dev, loff_t pos, size_t count, void *buf) > { > + struct pci_vpd *vpd; > ssize_t ret; > > if (dev->dev_flags & PCI_DEV_FLAGS_VPD_REF_F0) { > @@ -381,11 +376,27 @@ ssize_t pci_read_vpd(struct pci_dev *dev, loff_t pos, size_t count, void *buf) > if (!dev) > return -ENODEV; > > + vpd = &dev->vpd; > + if (!vpd->cap) { > + pci_dev_put(dev); > + return -ENODEV; > + } > + > + if (vpd->len == 0) > + pci_vpd_size(dev); > + > ret = pci_vpd_read(dev, pos, count, buf); > pci_dev_put(dev); > return ret; > } > > + vpd = &dev->vpd; > + if (!vpd->cap) > + return -ENODEV; > + > + if (vpd->len == 0) > + pci_vpd_size(dev); > + > return pci_vpd_read(dev, pos, count, buf); > } > EXPORT_SYMBOL(pci_read_vpd); > @@ -399,6 +410,7 @@ EXPORT_SYMBOL(pci_read_vpd); > */ > ssize_t pci_write_vpd(struct pci_dev *dev, loff_t pos, size_t count, const void *buf) > { > + struct pci_vpd *vpd; > ssize_t ret; > > if (dev->dev_flags & PCI_DEV_FLAGS_VPD_REF_F0) { > @@ -406,11 +418,26 @@ ssize_t pci_write_vpd(struct pci_dev *dev, loff_t pos, size_t count, const void > if (!dev) > return -ENODEV; > > + vpd = &dev->vpd; > + if (!vpd->cap) { > + pci_dev_put(dev); > + return -ENODEV; > + } > + > + if (vpd->len == 0) > + pci_vpd_size(dev); > + > ret = pci_vpd_write(dev, pos, count, buf); > pci_dev_put(dev); > return ret; > } > > + if (!vpd->cap) > + return -ENODEV; > + > + if (vpd->len == 0) > + pci_vpd_size(dev); > + > return pci_vpd_write(dev, pos, count, buf); > } > EXPORT_SYMBOL(pci_write_vpd); > @@ -500,27 +527,27 @@ DECLARE_PCI_FIXUP_CLASS_EARLY(PCI_VENDOR_ID_INTEL, PCI_ANY_ID, > */ > static void quirk_blacklist_vpd(struct pci_dev *dev) > { > - dev->vpd.len = PCI_VPD_SZ_INVALID; > + dev->vpd.cap = 0; > pci_warn(dev, FW_BUG "disabling VPD access (can't determine size of non-standard VPD format)\n"); > } > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x0060, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x007c, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x0413, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x0078, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x0079, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x0073, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x0071, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x005b, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x002f, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x005d, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_LSI_LOGIC, 0x005f, quirk_blacklist_vpd); > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_ATTANSIC, PCI_ANY_ID, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x0060, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x007c, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x0413, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x0078, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x0079, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x0073, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x0071, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x005b, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x002f, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x005d, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_LSI_LOGIC, 0x005f, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_ATTANSIC, PCI_ANY_ID, quirk_blacklist_vpd); > /* Leaving the quirks in FIXUP_HEADER stage would have the advantage that for blacklisted devices the vpd sysfs attribute isn't visibale. The needed changes to the patch are minimal. > * The Amazon Annapurna Labs 0x0031 device id is reused for other non Root Port > * device types, so the quirk is registered for the PCI_CLASS_BRIDGE_PCI class. > */ > -DECLARE_PCI_FIXUP_CLASS_HEADER(PCI_VENDOR_ID_AMAZON_ANNAPURNA_LABS, 0x0031, > - PCI_CLASS_BRIDGE_PCI, 8, quirk_blacklist_vpd); > +DECLARE_PCI_FIXUP_CLASS_FINAL(PCI_VENDOR_ID_AMAZON_ANNAPURNA_LABS, 0x0031, > + PCI_CLASS_BRIDGE_PCI, 8, quirk_blacklist_vpd); > > static void quirk_chelsio_extend_vpd(struct pci_dev *dev) > { > @@ -545,7 +572,7 @@ static void quirk_chelsio_extend_vpd(struct pci_dev *dev) > dev->vpd.len = 2048; > } > > -DECLARE_PCI_FIXUP_HEADER(PCI_VENDOR_ID_CHELSIO, PCI_ANY_ID, > - quirk_chelsio_extend_vpd); > +DECLARE_PCI_FIXUP_FINAL(PCI_VENDOR_ID_CHELSIO, PCI_ANY_ID, > + quirk_chelsio_extend_vpd); > > #endif >