Re: updating from r231158 to 234465: mounting from ufs:/dev/ad4s1a failed with error 19

On Friday, May 04, 2012 4:07:24 pm Anton Shterenlikht wrote:
> On Fri, May 04, 2012 at 11:07:59AM -0400, John Baldwin wrote:
> > On Friday, May 04, 2012 7:51:33 am Anton Shterenlikht wrote:
> > > On Thu, May 03, 2012 at 02:46:18PM -0400, John Baldwin wrote:
> > > > On Thursday, May 03, 2012 11:35:19 am Anton Shterenlikht wrote:
> > > > > On Tue, May 01, 2012 at 12:35:26PM +0100, Anton Shterenlikht wrote:
> > > > > > On Mon, Apr 30, 2012 at 08:43:14AM -0400, John Baldwin wrote:
> > > > > > > > 
> > > > > > > > I also see:
> > > > > > > > 
> > > > > > > > ata0: stat0=0x00 err=0x01 lsb=0x14 msb=0exb
> > > > > > > > ata0: stat1=0x00 err=0x00 lsb=0x00 msb=0x00
> > > > > > > > ata0: reset tp2 stat0=00 stat1=00 devices=0x10000
> > > > > > > 
> > > > > > > Hmmm, I don't know how to grok these lines, but does your disk work 
> > at 
> > > > all now 
> > > > > > > with any kernel?  It may be that your disk has died (or a cable, 
> > etc.) 
> > > > and it
> > > > > > > just happened to coincide with your upgrade?
> > > > > > 
> > > > > > I reverted back to r231158, built world and generic
> > > > > > kernel (minus all modules, i.e. "option MODULES_OVERRIDE=").
> > > > > > This works, see the verbose boot dmesg at the end.
> > > > > > 
> > > > > > I think I'll just do a binary search.
> > > > > 
> > > > > I traced it to r233677.
> > > > > The only change from 233676 to 233677 is
> > > > > in /sys/dev/pci/pci.c
> > > > > 
> > > > > My kernel is GENERIC with no modules
> > > > > and with various bits removed, e.g. all raid devices
> > > > > and PCI network devices, which I definitely
> > > > > haven't got on this laptop.
> > > > > 
> > > > > Below is the verbose boot with r233676.
> > > > > Apparently at the beginning there's also
> > > > > the previous unsuccessful boot with r233677.
> > > > > Is this a new feature? I didn't know the
> > > > > previous dmesg is preserved after a reboot.
> > > > > Anyway, you can see clearly the error with r233677.
> > > > > 
> > > > > I guess this is something to do with
> > > > > ata -> ada change?
> > > > 
> > > > I don't think so.
> > > > 
> > > > Please try just this change:
> > > > 
> > > > Index: pci.c
> > > > ===================================================================
> > > > --- pci.c	(revision 234928)
> > > > +++ pci.c	(working copy)
> > > > _at__at_ -2822,10 +2822,14 _at__at_ pci_add_map(device_t bus, device_t dev, int reg, s
> > > >  		 * from the parent.
> > > >  		 */
> > > >  		resource_list_delete(rl, type, reg);
> > > > -	} else {
> > > > +		start = 0;
> > > > +		device_printf(bus,
> > > > +		    "pci%d:%d:%d:%d bar %#x failed to allocate",
> > > > +		    pci_get_domain(dev), pci_get_bus(dev), pci_get_slot(dev),
> > > > +		    pci_get_function(dev), reg);
> > > > +	} else
> > > >  		start = rman_get_start(res);
> > > > -		pci_write_bar(dev, pm, start);
> > > > -	}
> > > > +	pci_write_bar(dev, pm, start);
> > > >  	return (barlen);
> > > >  }
> > > >  
> > > 
> > > That helped, thank you.
> > 
> > Bizarre, can you get a regular dmesg with that change applied?
> 

Hmm, I missed a newline at the end. :)  Looks like this happened twice.
I've added the relevant verbose boot messages from your earlier kernel
below each one:

> pci0: <ACPI PCI bus> on pcib0
> pci0: pci0:0:20:2 bar 0x10 failed to allocate
> pcib1: <ACPI PCI-PCI bridge> at device 1.0 on pci0

found-> vendor=0x1002, dev=0x4383, revid=0x00
        domain=0, bus=0, slot=20, func=2
        class=04-03-00, hdrtype=0x00, mfdev=0
        cmdreg=0x0006, statreg=0x0410, cachelnsz=16 (dwords)
        lattimer=0x40 (1920 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
        intpin=a, irq=10
        powerspec 2  supports D0 D3  current D0
        map[10]: type Memory, range 64, base 0xcc408000, size 14, enabled
pcib0: matched entry for 0.20.INTA
pcib0: slot 20 INTA hardwired to IRQ 16

> pcib4: <ACPI PCI-PCI bridge> at device 20.4 on pci0
> pcib4: failed to allocate initial memory window: 0xcc100000-0xcc1fffff
> pci2: <ACPI PCI bus> on pcib4
> pci2: pci0:2:4:0 bar 0x10 failed to allocate
> cbb0: <RF5C476 PCI-CardBus Bridge> irq 20 at device 4.0 on pci2

found-> vendor=0x1180, dev=0x0476, revid=0xb6
        domain=0, bus=2, slot=4, func=0
        class=06-07-00, hdrtype=0x02, mfdev=1
        cmdreg=0x0007, statreg=0x0210, cachelnsz=0 (dwords)
        lattimer=0x40 (1920 ns), mingnt=0x80 (32000 ns), maxlat=0x07 (1750 ns)
        intpin=a, irq=10
        powerspec 2  supports D0 D1 D2 D3  current D0
        map[10]: type Memory, range 32, base 0xcc100000, size 12, enabled
pcib4: failed to allocate initial memory window (0xcc100000-0xcc1fffff,0x100000)
pcib4: matched entry for 2.4.INTA
pcib4: slot 4 INTA hardwired to IRQ 20
cbb0: <RF5C476 PCI-CardBus Bridge> irq 20 at device 4.0 on pci2
pcib0: allocated type 3 (0xcc500000-0xcc5fffff) for rid 20 of pcib4
pcib4: allocated initial memory window of 0xcc500000-0xcc5fffff
pcib4: allocated memory range (0xcc500000-0xcc500fff) for rid 10 of cbb0
cbb0: Lazy allocation of 0x1000 bytes rid 0x10 type 3 at 0xcc500000

So the second case actually recovers and allocates a different range.

Can you try booting with 'debug.acpi.disabled=sysres' set in the loader?

Also, can you get the output of 'devinfo -rv' from a working kernel?

-- 
John Baldwin