5.3-BETA6 boot-time hang

From: Bruce A. Mah <bmah_at_freebsd.org>
Date: Sun, 3 Oct 2004 19:58:13 -0700
I just updated a RELENG_5 system from sometime around 5.3-BETA4 (17
September) to very late 5.3-BETA6 (just before the 5.3-BETA7 version
number bump) and it's now hanging after probing its DVD drive.  This
problem sounds vaguely like what was described in the thread "ATTN
Soren: BETA3 still hangs on ATAPI DVD Detection during boot":

http://lists.freebsd.org/pipermail/freebsd-current/2004-September/036543.html

The interesting thing is that my system appeared to be working just
fine with the BETA4 code.

I've appended verbose boot output from BETA4, as well as the lines
that I think were relevant from a BETA6 boot, at least up to the point
where it hung.  The latter output is hand-copied.  The system isn't
totally wedged because I can page back through the console's
scrollback buffer, but the kernel just doesn't seem to finish probing
and attaching devices.

I tried disabling ATAPI DMA as Soren suggested in the thread above by
setting hw.ata.atapi_dma=0 from the loader; this seemed to have no
effect.

I tried backing out the ata driver updates from 30 September to see if
that might help matters, but it didn't seem to help.  Neither did
booting the kernel from the 5.3-BETA6 bootonly iso image; that hangs
in exactly the same way.

At this point I'm kind of flailing around at random.  At first I
had suspected the ata(4) driver but the evidence I've seen so far
doesn't really point in that direction.

Any ideas?  Thanks for any suggestions!

Bruce.

-----

(5.3-BETA4 boot.  This kernel was built from source shortly before 5.3-BETA5.)

FreeBSD 5.3-BETA4 #4: Fri Sep 17 13:00:00 PDT 2004
    root_at_tomcat.kitchenlab.org:/usr/obj/usr/src/sys/NIMITZ5
Preloaded elf kernel "/boot/kernel/kernel" at 0xc0a37000.
Preloaded elf module "/boot/kernel/snd_ich.ko" at 0xc0a37228.
Preloaded elf module "/boot/kernel/sound.ko" at 0xc0a372d4.
Preloaded elf module "/boot/kernel/uvisor.ko" at 0xc0a37380.
Preloaded elf module "/boot/kernel/ucom.ko" at 0xc0a3742c.
Preloaded elf module "/boot/kernel/acpi.ko" at 0xc0a374d8.
Calibrating clock(s) ... i8254 clock: 1193183 Hz
CLK_USE_I8254_CALIBRATION not specified - using default frequency
Timecounter "i8254" frequency 1193182 Hz quality 0
Calibrating TSC clock ... TSC clock: 996749528 Hz
CPU: Intel Pentium III (996.75-MHz 686-class CPU)
  Origin = "GenuineIntel"  Id = 0x68a  Stepping = 10
  Features=0x383f9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>
real memory  = 535691264 (510 MB)
Physical memory chunk(s):
0x0000000000001000 - 0x000000000009efff, 647168 bytes (158 pages)
0x0000000000100000 - 0x00000000003fffff, 3145728 bytes (768 pages)
0x0000000000c29000 - 0x000000001f5b4fff, 513327104 bytes (125324 pages)
avail memory = 514519040 (490 MB)
bios32: Found BIOS32 Service Directory header at 0xc00fdad0
bios32: Entry = 0xfdae0 (c00fdae0)  Rev = 0  Len = 1
pcibios: PCI BIOS entry at 0xf0000+0xdb01
pnpbios: Found PnP BIOS data at 0xc00f6830
pnpbios: Entry = f0000:5834  Rev = 1.0
Other BIOS signatures found:
wlan: <802.11 Link Layer>
random: <entropy source, Software, Yarrow>
io: <I/O>
mem: <memory>
Pentium Pro MTRR support enabled
null: <null device, zero device>
npx0: [FAST]
npx0: <math processor> on motherboard
npx0: INT 16 interface
acpi0: <AMIINT AMIINT10> on motherboard
acpi0: [MPSAFE]
pci_open(1):	mode 1 addr port (0x0cf8) is 0x80000058
pci_open(1a):	mode1res=0x80000000 (0x80000000)
pci_cfgcheck:	device 0 [class=060000] [hdr=00] is there (id=11308086)
pcibios: BIOS version 2.10
Found $PIR table, 8 entries at 0xc00f6e20
PCI-Only Interrupts: none
Location  Bus Device Pin  Link  IRQs
embedded    0    1    A   0x60  3 4 5 7 9 10 11 12 14 15
embedded    0    1    B   0x61  3 4 5 7 9 10 11 12 14 15
embedded    0    2    A   0x60  3 4 5 7 9 10 11 12 14 15
embedded    0   31    A   0xfe  14
embedded    0   31    B   0x61  3 4 5 7 9 10 11 12 14 15
embedded    0   31    C   0x6b  3 4 5 7 9 10 11 12 14 15
embedded    0   31    D   0x63  3 4 5 7 9 10 11 12 14 15
slot 1      1    3    A   0x61  4 5 6 7 9 10 11 12 14
slot 1      1    3    B   0x62  4 5 6 7 9 10 11 12 14
slot 1      1    3    C   0x63  4 5 6 7 9 10 11 12 14
slot 1      1    3    D   0x60  4 5 6 7 9 10 11 12 14
slot 2      1    4    A   0x61  4 5 6 7 9 10 11 12 14
slot 2      1    4    B   0x62  4 5 6 7 9 10 11 12 14
slot 2      1    4    C   0x63  4 5 6 7 9 10 11 12 14
slot 2      1    4    D   0x60  4 5 6 7 9 10 11 12 14
slot 3      1    5    A   0x61  4 5 6 7 9 10 11 12 14
slot 3      1    5    B   0x62  4 5 6 7 9 10 11 12 14
slot 3      1    5    C   0x63  4 5 6 7 9 10 11 12 14
slot 3      1    5    D   0x60  4 5 6 7 9 10 11 12 14
embedded    1    2    A   0x60  4 5 6 7 9 10 11 12 14
embedded    1    2    B   0x61  4 5 6 7 9 10 11 12 14
embedded    1    8    A   0x68  4 5 6 7 9 10 11 12 14
AcpiOsDerivePciId: bus 1 dev 2 func 0
AcpiOsDerivePciId: bus 1 dev 2 func 0
AcpiOsDerivePciId: bus 0 dev 31 func 0
AcpiOsDerivePciId: bus 0 dev 31 func 0
AcpiOsDerivePciId: bus 0 dev 31 func 0
acpi0: Power Button (fixed)
AcpiOsDerivePciId: bus 0 dev 0 func 0
AcpiOsDerivePciId: bus 0 dev 0 func 0
AcpiOsDerivePciId: bus 0 dev 0 func 0
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
ACPI timer looks GOOD min = 2, max = 3, width = 1
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
unknown: not probed (disabled)
unknown: not probed (disabled)
cpu0: <ACPI CPU (3 Cx states)> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
ACPI PCI link initial configuration:
\\_SB_.LNKB irq  0: [ 4  5  6  7  9 10 11 12 14] 11+ low,level,sharable 0.31.1
\\_SB_.LNKH irq  0: [ 4  5  6  7  9 10 11 12 14] 10+ low,level,sharable 0.31.2
\\_SB_.LNKD irq  0: [ 4  5  6  7  9 10 11 12 14]  5+ low,level,sharable 0.31.3
\\_SB_.LNKA irq  0: [ 4  5  6  7  9 10 11 12 14]  9+ low,level,sharable 0.1.0
\\_SB_.LNKB irq  0: [ 4  5  6  7  9 10 11 12 14] 11+ low,level,sharable 0.1.1
\\_SB_.LNKC irq  0: [ 4  5  6  7  9 10 11 12 14]  0+ low,level,sharable 0.1.2
\\_SB_.LNKD irq  0: [ 4  5  6  7  9 10 11 12 14]  5+ low,level,sharable 0.1.3
\\_SB_.LNKA irq  0: [ 4  5  6  7  9 10 11 12 14]  9+ low,level,sharable 0.2.0
\\_SB_.LNKB irq  0: [ 4  5  6  7  9 10 11 12 14] 11+ low,level,sharable 0.2.1
\\_SB_.LNKC irq  0: [ 4  5  6  7  9 10 11 12 14]  0+ low,level,sharable 0.2.2
\\_SB_.LNKD irq  0: [ 4  5  6  7  9 10 11 12 14]  5+ low,level,sharable 0.2.3
pci0: <ACPI PCI bus> on pcib0
pci0: physical bus=0
found->	vendor=0x8086, dev=0x1130, revid=0x02
	bus=0, slot=0, func=0
	class=06-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0106, statreg=0x2090, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	map[10]: type 3, range 32, base e8000000, size 26, enabled
	map[14]: type 1, range 32, base eff80000, size 19, enabled
pcib0: matched entry for 0.2.INTA (src \\_SB_.LNKA)
pcib0: possible interrupts:  4  5  6  7  9 10 11 12 14
ACPI PCI link arbitrated settings:
\\_SB_.LNKB (references 3, priority 23716):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   110   110   160   220  5110  5110  5110  5110 50110
\\_SB_.LNKD (references 3, priority 23716):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   110   110   160   220  5110  5110  5110  5110 50110
\\_SB_.LNKA (references 2, priority 15811):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   110   110   160   220  5110  5110  5110  5110 50110
\\_SB_.LNKC (references 2, priority 15811):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   110   110   160   220  5110  5110  5110  5110 50110
\\_SB_.LNKH (references 1, priority 7905):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   110   110   160   220  5110  5110  5110  5110 50110
pcib0: slot 2 INTA routed to irq 9 via \\_SB_.LNKA
found->	vendor=0x8086, dev=0x1132, revid=0x02
	bus=0, slot=2, func=0
	class=03-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0007, statreg=0x02b0, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=a, irq=9
	powerspec 2  supports D0 D3  current D0
found->	vendor=0x8086, dev=0x244e, revid=0x11
	bus=0, slot=30, func=0
	class=06-04-00, hdrtype=0x01, mfdev=0
	cmdreg=0x0107, statreg=0x0080, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x06 (1500 ns), maxlat=0x00 (0 ns)
found->	vendor=0x8086, dev=0x2440, revid=0x11
	bus=0, slot=31, func=0
	class=06-01-00, hdrtype=0x00, mfdev=1
	cmdreg=0x000f, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	map[20]: type 4, range 32, base 0000ffa0, size  4, enabled
found->	vendor=0x8086, dev=0x244b, revid=0x11
	bus=0, slot=31, func=1
	class=01-01-80, hdrtype=0x00, mfdev=0
	cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	map[20]: type 4, range 32, base 0000cc00, size  5, enabled
pcib0: matched entry for 0.31.INTD (src \\_SB_.LNKD)
pcib0: possible interrupts:  4  5  6  7  9 10 11 12 14
ACPI PCI link arbitrated settings:
\\_SB_.LNKB (references 3, priority 24090):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   220   240   270   440  5220  5220  5220  5220 50220
\\_SB_.LNKD (references 3, priority 24090):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   220   240   270   440  5220  5220  5220  5220 50220
\\_SB_.LNKC (references 2, priority 16060):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   220   240   270   440  5220  5220  5220  5220 50220
\\_SB_.LNKH (references 1, priority 8030):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   220   240   270   440  5220  5220  5220  5220 50220
pcib0: slot 31 INTD routed to irq 5 via \\_SB_.LNKD
found->	vendor=0x8086, dev=0x2442, revid=0x11
	bus=0, slot=31, func=2
	class=0c-03-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=d, irq=5
	map[20]: type 4, range 32, base 00000540, size  4, enabled
pcib0: matched entry for 0.31.INTB (src \\_SB_.LNKB)
pcib0: possible interrupts:  4  5  6  7  9 10 11 12 14
ACPI PCI link arbitrated settings:
\\_SB_.LNKB (references 3, priority 24466):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   330   350   410   660  5330  5330  5330  5330 50330
\\_SB_.LNKC (references 2, priority 16311):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   330   350   410   660  5330  5330  5330  5330 50330
\\_SB_.LNKH (references 1, priority 8155):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   330   350   410   660  5330  5330  5330  5330 50330
pcib0: slot 31 INTB routed to irq 11 via \\_SB_.LNKB
found->	vendor=0x8086, dev=0x2443, revid=0x11
	bus=0, slot=31, func=3
	class=0c-05-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0001, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=b, irq=11
	map[20]: type 4, range 32, base 0000d400, size  5, enabled
pcib0: matched entry for 0.31.INTC (src \\_SB_.LNKH)
pcib0: possible interrupts:  4  5  6  7  9 10 11 12 14
ACPI PCI link arbitrated settings:
\\_SB_.LNKC (references 2, priority 16562):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   440   460   520   910  5440  5440  5440  5440 50440
\\_SB_.LNKH (references 1, priority 8281):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   440   460   520   910  5440  5440  5440  5440 50440
pcib0: slot 31 INTC routed to irq 10 via \\_SB_.LNKH
found->	vendor=0x8086, dev=0x2444, revid=0x11
	bus=0, slot=31, func=4
	class=0c-03-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=c, irq=10
	map[10]: type 4, range 32, base 0000dc00, size  8, enabled
	map[14]: type 4, range 32, base 0000d800, size  6, enabled
pcib0: matched entry for 0.31.INTB (src \\_SB_.LNKB)
pcib0: slot 31 INTB is already routed to irq 11
found->	vendor=0x8086, dev=0x2445, revid=0x11
	bus=0, slot=31, func=5
	class=04-01-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=b, irq=11
agp0: <Intel 82815 (i815 GMCH) SVGA controller> mem 0xeff80000-0xefffffff,0xe8000000-0xebffffff irq 9 at device 2.0 on pci0
agp0: Reserved 0x4000000 bytes for rid 0x10 type 3 at 0xe8000000
agp0: Reserved 0x80000 bytes for rid 0x14 type 3 at 0xeff80000
pcib1: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pcib1:   secondary bus     1
pcib1:   subordinate bus   1
pcib1:   I/O decode        0xa000-0xafff
pcib1:   memory decode     0xefd00000-0xefdfffff
pcib1:   prefetched decode 0xe7b00000-0xe7bfffff
pcib1:   Subtractively decoded bridge.
ACPI PCI link initial configuration:
\\_SB_.LNKB irq*11: [ 4  5  6  7  9 10 11 12 14] 11+ low,level,sharable 1.3.0
\\_SB_.LNKC irq  0: [ 4  5  6  7  9 10 11 12 14]  0+ low,level,sharable 1.3.1
\\_SB_.LNKD irq* 5: [ 4  5  6  7  9 10 11 12 14]  5+ low,level,sharable 1.3.2
\\_SB_.LNKA irq* 9: [ 4  5  6  7  9 10 11 12 14]  9+ low,level,sharable 1.3.3
\\_SB_.LNKC irq  0: [ 4  5  6  7  9 10 11 12 14]  0+ low,level,sharable 1.4.0
\\_SB_.LNKD irq* 5: [ 4  5  6  7  9 10 11 12 14]  5+ low,level,sharable 1.4.1
\\_SB_.LNKA irq* 9: [ 4  5  6  7  9 10 11 12 14]  9+ low,level,sharable 1.4.2
\\_SB_.LNKB irq*11: [ 4  5  6  7  9 10 11 12 14] 11+ low,level,sharable 1.4.3
\\_SB_.LNKD irq* 5: [ 4  5  6  7  9 10 11 12 14]  5+ low,level,sharable 1.5.0
\\_SB_.LNKA irq* 9: [ 4  5  6  7  9 10 11 12 14]  9+ low,level,sharable 1.5.1
\\_SB_.LNKB irq*11: [ 4  5  6  7  9 10 11 12 14] 11+ low,level,sharable 1.5.2
\\_SB_.LNKC irq  0: [ 4  5  6  7  9 10 11 12 14]  0+ low,level,sharable 1.5.3
\\_SB_.LNKA irq* 9: [ 4  5  6  7  9 10 11 12 14]  9+ low,level,sharable 1.2.0
\\_SB_.LNKB irq*11: [ 4  5  6  7  9 10 11 12 14] 11+ low,level,sharable 1.2.1
\\_SB_.LNKE irq  0: [ 4  5  6  7  9 10 11 12 14] 11+ low,level,sharable 1.8.0
pci1: <ACPI PCI bus> on pcib1
pci1: physical bus=1
	map[10]: type 1, range 32, base 00000000, size 12, enabled
found->	vendor=0x104c, dev=0xac41, revid=0x02
	bus=1, slot=2, func=0
	class=06-07-00, hdrtype=0x02, mfdev=1
	cmdreg=0x0007, statreg=0x0210, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0xc0 (48000 ns), maxlat=0x04 (1000 ns)
	intpin=a, irq=255
	powerspec 1  supports D0 D1 D2 D3  current D0
	map[10]: type 1, range 32, base efdff800, size 11, enabled
pcib1: device (null) requested decoded memory range 0xefdff800-0xefdfffff
	map[14]: type 1, range 32, base efdf8000, size 14, enabled
pcib1: device (null) requested decoded memory range 0xefdf8000-0xefdfbfff
pcib1: matched entry for 1.2.INTB (src \\_SB_.LNKB)
pcib1: slot 2 INTB is already routed to irq 11
found->	vendor=0x104c, dev=0x8017, revid=0x02
	bus=1, slot=2, func=1
	class=0c-00-10, hdrtype=0x00, mfdev=1
	cmdreg=0x0116, statreg=0x0210, cachelnsz=8 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x02 (500 ns), maxlat=0x04 (1000 ns)
	intpin=b, irq=11
	powerspec 1  supports D0 D2 D3  current D0
	map[10]: type 1, range 32, base efdfe000, size 12, enabled
pcib1: device (null) requested decoded memory range 0xefdfe000-0xefdfefff
	map[14]: type 4, range 32, base 0000ac00, size  6, enabled
pcib1: device (null) requested decoded I/O range 0xac00-0xac3f
pcib1: matched entry for 1.8.INTA (src \\_SB_.LNKE)
pcib1: possible interrupts:  4  5  6  7  9 10 11 12 14
ACPI PCI link arbitrated settings:
\\_SB_.LNKC (references 5, priority 42911):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   750   760   820  1210  5740  5740  5740  5740 50740
\\_SB_.LNKE (references 1, priority 8582):
	interrupts:	    10     9     5    11    12     7     6     4    14
	penalty:	   750   760   820  1210  5740  5740  5740  5740 50740
pcib1: slot 8 INTA routed to irq 11 via \\_SB_.LNKE
found->	vendor=0x8086, dev=0x2449, revid=0x03
	bus=1, slot=8, func=0
	class=02-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0117, statreg=0x0290, cachelnsz=8 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x08 (2000 ns), maxlat=0x38 (14000 ns)
	intpin=a, irq=11
	powerspec 2  supports D0 D1 D2 D3  current D0
cbb0: <TI4410 PCI-CardBus Bridge> at device 2.0 on pci1
pcib1: device cbb0 requested decoded memory range 0xefd00000-0xefdfffff
cbb0: Lazy allocation of 0x1000 bytes rid 0x10 type 3 at 0xefd00000
cardbus0: <CardBus bus> on cbb0
pccard0: <16-bit PCCard bus> on cbb0
pcib1: matched entry for 1.2.INTA (src \\_SB_.LNKA)
pcib1: slot 2 INTA is already routed to irq 9
cbb0: [MPSAFE]
cbb0: PCI Configuration space:
  0x00: 0xac41104c 0x02100007 0x06070002 0x00822000 
  0x10: 0xefd00000 0x020000a0 0x20030201 0xfffff000 
  0x20: 0x00000000 0xfffff000 0x00000000 0xfffffffc 
  0x30: 0x00000000 0xfffffffc 0x00000000 0x04400109 
  0x40: 0x89a71509 0x00000001 0x00000000 0x00000000 
  0x50: 0x00000000 0x00000000 0x00000000 0x00000000 
  0x60: 0x00000000 0x00000000 0x00000000 0x00000000 
  0x70: 0x00000000 0x00000000 0x00000000 0x00000000 
  0x80: 0x08249060 0x00000400 0x00000000 0x00001000 
  0x90: 0x216400c0 0x00000000 0x00000000 0x00000000 
  0xa0: 0xfe110001 0x00c00100 0x0000000a 0x0000001f 
  0xb0: 0x00000000 0x00000000 0x00000000 0x00000000 
  0xc0: 0x00000000 0x00000000 0x00000000 0x00000000 
  0xd0: 0x00000000 0x00000000 0x00000000 0x00000000 
  0xe0: 0x00000000 0x00000000 0x00000000 0x00000000 
  0xf0: 0x00000000 0x00000000 0x00000000 0x00000000 
fwohci0: <Texas Instruments PCI4410A> mem 0xefdf8000-0xefdfbfff,0xefdff800-0xefdfffff irq 11 at device 2.1 on pci1
fwohci0: Reserved 0x800 bytes for rid 0x10 type 3 at 0xefdff800
fwohci0: [MPSAFE]
fwohci0: OHCI version 1.0 (ROM=1)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 00:40:ca:07:02:00:53:e4
fwohci0: Phy 1394a available S400, 2 ports.
fwohci0: Link S400, max_rec 2048 bytes.
firewire0: <IEEE1394(FireWire) bus> on fwohci0
fwe0: <Ethernet over FireWire> on firewire0
if_fwe0: Fake Ethernet address: 02:40:ca:00:53:e4
fwe0: bpf attached
fwe0: Ethernet address: 02:40:ca:00:53:e4
fwe0: if_start running deferred for Giant
sbp0: <SBP-2/SCSI over FireWire> on firewire0
fwohci0: Initiate bus reset
fwohci0: node_id=0xc800ffc0, gen=1, CYCLEMASTER mode
firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
firewire0: bus manager 0 (me)
fxp0: <Intel 82801BA/CAM (ICH2/3) Pro/100 Ethernet> port 0xac00-0xac3f mem 0xefdfe000-0xefdfefff irq 11 at device 8.0 on pci1
fxp0: Reserved 0x1000 bytes for rid 0x10 type 3 at 0xefdfe000
fxp0: using memory space register mapping
fxp0: PCI IDs: 8086 2449 8086 3013 0003
fxp0: Dynamic Standby mode is disabled
miibus0: <MII bus> on fxp0
inphy0: <i82562ET 10/100 media interface> on miibus0
inphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
fxp0: bpf attached
fxp0: Ethernet address: 00:40:ca:28:84:c9
fxp0: [MPSAFE]
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH2 UDMA100 controller> port 0xffa0-0xffaf,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 at device 31.1 on pci0
atapci0: Reserved 0x10 bytes for rid 0x20 type 4 at 0xffa0
ata0: channel #0 on atapci0
atapci0: Reserved 0x8 bytes for rid 0x10 type 4 at 0x1f0
atapci0: Reserved 0x1 bytes for rid 0x14 type 4 at 0x3f6
ata0: reset tp1 mask=03 ostat0=50 ostat1=00
ata0-master: stat=0x90 err=0x90 lsb=0x90 msb=0x90
ata0-master: stat=0x50 err=0x01 lsb=0x00 msb=0x00
ata0-slave:  stat=0x00 err=0x01 lsb=0x00 msb=0x00
ata0: reset tp2 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ata0: [MPSAFE]
ata1: channel #1 on atapci0
atapci0: Reserved 0x8 bytes for rid 0x18 type 4 at 0x170
atapci0: Reserved 0x1 bytes for rid 0x1c type 4 at 0x376
ata1: reset tp1 mask=03 ostat0=50 ostat1=01
ata1-master: stat=0x00 err=0x01 lsb=0x14 msb=0xeb
ata1-slave:  stat=0x00 err=0x00 lsb=0x00 msb=0x00
ata1: reset tp2 stat0=00 stat1=00 devices=0x4<ATAPI_MASTER>
ata1: [MPSAFE]
uhci0: <Intel 82801BA/BAM (ICH2) USB controller USB-A> port 0xcc00-0xcc1f irq 5 at device 31.2 on pci0
uhci0: Reserved 0x20 bytes for rid 0x20 type 4 at 0xcc00
uhci0: [GIANT-LOCKED]
usb0: <Intel 82801BA/BAM (ICH2) USB controller USB-A> on uhci0
usb0: USB revision 1.0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
uhci1: <Intel 82801BA/BAM (ICH2) USB controller USB-B> port 0xd400-0xd41f irq 10 at device 31.4 on pci0
uhci1: Reserved 0x20 bytes for rid 0x20 type 4 at 0xd400
uhci1: [GIANT-LOCKED]
usb1: <Intel 82801BA/BAM (ICH2) USB controller USB-B> on uhci1
usb1: USB revision 1.0
uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
pcm0: <Intel ICH2 (82801BA)> port 0xd800-0xd83f,0xdc00-0xdcff irq 11 at device 31.5 on pci0
pcm0: Reserved 0x100 bytes for rid 0x10 type 4 at 0xdc00
pcm0: Reserved 0x40 bytes for rid 0x14 type 4 at 0xd800
pcm0: [GIANT-LOCKED]
pcm0: <Cirrus Logic CS4299 AC97 Codec (id = 0x43525934)>
pcm0: Codec features headphone, 20 bit DAC, 18 bit ADC, 6 bit master volume, Crystal Semi 3D Stereo Enhancement
pcm0: Primary codec extended features variable rate PCM, AMAP
pcm0: sndbuf_setmap 1f07e000, 4000; 0xd96c1000 -> 1f07e000
pcm0: sndbuf_setmap 1f074000, 4000; 0xd96c5000 -> 1f074000
acpi_button0: <Power Button> on acpi0
unknown: not probed (disabled)
psmcpnp0 irq 12 on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
atkbd: the current kbd controller command byte 0065
atkbd: keyboard ID 0x41ab (2)
kbd0 at atkbd0
kbd0: atkbd0, AT 101/102 (2), config:0x0, flags:0x3d0000
atkbd0: [GIANT-LOCKED]
psm0: current command byte:0065
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model Generic PS/2 mouse, device ID 0-00, 2 buttons
psm0: config:00000000, flags:00000008, packet size:3
psm0: syncmask:c0, syncbits:00
fdc0: <floppy drive controller> port 0x3f7,0x3f4-0x3f5,0x3f0-0x3f3 irq 6 drq 2 on acpi0
fdc0: output ready timeout
fdc0: cmd 3 failed at out byte 1 of 3
device_attach: fdc0 attach returned 6
sio0: irq maps: 0x801 0x811 0x801 0x801
sio0 port 0x3f8-0x3ff irq 4 on acpi0
sio0: type 16550A
unknown: not probed (disabled)
ppc0: using extended I/O port range
ppc0: ECP SPP ECP+EPP SPP
ppc0 port 0x778-0x77b,0x378-0x37f irq 7 drq 3 on acpi0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/9 bytes threshold
ppbus0: <Parallel port bus> on ppc0
plip0: <PLIP network interface> on ppbus0
plip0: bpf attached
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
unknown: not probed (disabled)
unknown: not probed (disabled)
unknown: not probed (disabled)
fdc0: <floppy drive controller> port 0x3f7,0x3f4-0x3f5,0x3f0-0x3f3 irq 6 drq 2 on acpi0
fdc0: output ready timeout
fdc0: cmd 3 failed at out byte 1 of 3
device_attach: fdc0 attach returned 6
unknown: not probed (disabled)
ata: ata0 already exists; skipping it
ata: ata1 already exists; skipping it
atkbdc: atkbdc0 already exists; skipping it
fdc: fdc0 already exists; skipping it
ppc: ppc0 already exists; skipping it
sio: sio0 already exists; skipping it
Trying Read_Port at 203
Trying Read_Port at 243
Trying Read_Port at 283
Trying Read_Port at 2c3
Trying Read_Port at 303
Trying Read_Port at 343
Trying Read_Port at 383
Trying Read_Port at 3c3
ex_isa_identify()
unknown: status reg test failed ff
unknown: status reg test failed ff
unknown: status reg test failed ff
unknown: status reg test failed ff
unknown: status reg test failed ff
unknown: status reg test failed ff
ahc_isa_probe 10: ioport 0xac00 alloc failed
ahc_isa_probe 12: ioport 0xcc00 alloc failed
ahc_isa_probe 13: ioport 0xdc00 alloc failed
sc: sc0 already exists; skipping it
vga: vga0 already exists; skipping it
isa_probe_children: disabling PnP devices
isa_probe_children: probing non-PnP devices
orm0: <ISA Option ROMs> at iomem 0xe0000-0xe0fff,0xc0000-0xcbfff on isa0
pmtimer0 on isa0
adv0: not probed (disabled)
aha0: not probed (disabled)
aic0: not probed (disabled)
bt0: not probed (disabled)
cs0: not probed (disabled)
ed0: not probed (disabled)
fe0: not probed (disabled)
ie0: not probed (disabled)
lnc0: not probed (disabled)
pcic0 failed to probe at port 0x3e0 iomem 0xd0000 on isa0
pcic1: not probed (disabled)
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sc0: fb0, kbd0, terminal emulator: sc (syscons terminal)
sio1: configured irq 3 not in bitmap of probed irqs 0
sio1: port may not be enabled
sio1: irq maps: 0x801 0x801 0x801 0x801
sio1: probe failed test(s): 0 1 2 4 6 7 9
sio1 failed to probe at port 0x2f8-0x2ff irq 3 on isa0
sio2: not probed (disabled)
sio3: not probed (disabled)
sn0: not probed (disabled)
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
fb0: vga0, vga, type:VGA (5), flags:0x7007f
fb0: port:0x3c0-0x3df, crtc:0x3d4, mem:0xa0000 0x20000
fb0: init mode:24, bios mode:3, current mode:24
fb0: window:0xc00b8000 size:32k gran:32k, buf:0 size:32k
VGA parameters upon power-up
50 18 10 00 00 00 03 00 02 67 5f 4f 50 82 55 81 
bf 1f 00 4f 0d 0e 00 00 07 80 9c 8e 8f 28 1f 96 
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff 
VGA parameters in BIOS for mode 24
50 18 10 00 10 00 03 00 02 67 5f 4f 50 82 55 81 
bf 1f 00 4f 0d 0e 00 00 00 00 9c 8e 8f 28 1f 96 
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff 
EGA/VGA parameters to be used for mode 24
50 18 10 00 10 00 03 00 02 67 5f 4f 50 82 55 81 
bf 1f 00 4f 0d 0e 00 00 00 00 9c 8e 8f 28 1f 96 
b9 a3 ff 00 01 02 03 04 05 14 07 38 39 3a 3b 3c 
3d 3e 3f 0c 00 0f 08 00 00 00 00 00 10 0e 00 ff 
vt0: not probed (disabled)
isa_probe_children: probing PnP devices
Device configuration finished.
procfs registered
Timecounter "TSC" frequency 996749528 Hz quality 800
Timecounters tick every 10.000 msec
lo0: bpf attached
cpu0: set speed to 100.0%
acpi_cpu: throttling enabled, 8 steps (100% to 12.5%), currently 100.0%
ata0-master: pio=0x0c wdma=0x22 udma=0x45 cable=80pin
ata0-master: setting PIO4 on Intel ICH2 chip
ata0-master: setting UDMA100 on Intel ICH2 chip
ad0: <ST3120026A/3.06> ATA-6 disk at ata0-master
ad0: 114473MB (234441648 sectors), 232581 C, 16 H, 63 S, 512 B
ad0: 16 secs/int, 1 depth queue, UDMA100
GEOM: new disk ad0
ar: FreeBSD check1 failed
ata1-master: pio=0x0c wdma=0x22 udma=0x42 cable=40pin
ATAPI_RESET time = 670us
ata1-master: setting PIO4 on Intel ICH2 chip
ata1-master: setting UDMA33 on Intel ICH2 chip
acd0: <SONY DVD RW DRU-530A/1.0d> DVDR drive at ata1 as master
acd0: read 6890KB/s (6890KB/s) write 4133KB/s (4133KB/s), 2048KB buffer, UDMA33
acd0: Reads: CDR, CDRW, CDDA stream, DVDROM, DVDR, packet
acd0: Writes: CDR, CDRW, DVDR, test write, burnproof
acd0: Audio: play, 256 volume levels
acd0: Mechanism: ejectable tray, unlocked
acd0: Medium: no/blank disc
pcm0: measured ac97 link rate at 47998 Hz, will use 48000 Hz
[0] f:80 typ:165 s(CHS):0/1/1 e(CHS):1023/254/63 s:63 l:167766732
[1] f:00 typ:165 s(CHS):1023/255/63 e(CHS):1023/254/63 s:167766795 l:66669750
[2] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0
[3] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0
GEOM: Configure ad0s1, start 32256 length 85896566784 end 85896599039
GEOM: Configure ad0s2, start 85896599040 length 34134912000 end 120031511039
GEOM: Configure ad0s1a, start 0 length 536870912 end 536870911
GEOM: Configure ad0s1b, start 536870912 length 1073741824 end 1610612735
GEOM: Configure ad0s1c, start 0 length 85896566784 end 85896566783
GEOM: Configure ad0s1d, start 1610612736 length 1073741824 end 2684354559
GEOM: Configure ad0s1e, start 2684354560 length 21474836480 end 24159191039
GEOM: Configure ad0s1f, start 24159191040 length 21474836480 end 45634027519
GEOM: Configure ad0s1g, start 45634027520 length 40262539264 end 85896566783
GEOM: Configure ad0s2a, start 0 length 536870912 end 536870911
GEOM: Configure ad0s2c, start 0 length 34134912000 end 34134911999
GEOM: Configure ad0s2e, start 536870912 length 1073741824 end 1610612735
GEOM: Configure ad0s2f, start 1610612736 length 1073741824 end 2684354559
GEOM: Configure ad0s2g, start 2684354560 length 21474836480 end 24159191039
GEOM: Configure ad0s2h, start 24159191040 length 9975720960 end 34134911999
(probe0:ata0:0:0:0): error 22
(probe0:ata0:0:0:0): Unretryable Error
(probe1:ata0:0:1:0): error 22
(probe1:ata0:0:1:0): Unretryable Error
(probe3:ata1:0:1:0): error 22
(probe3:ata1:0:1:0): Unretryable Error
(probe0:ata0:0:0:0): error 22
(probe0:ata0:0:0:0): Unretryable Error
(probe1:ata0:0:1:0): error 22
(probe1:ata0:0:1:0): Unretryable Error
(probe3:ata1:0:1:0): error 22
(probe3:ata1:0:1:0): Unretryable Error
(probe2:ata1:0:0:0): error 6
(probe2:ata1:0:0:0): Unretryable Error
(probe6:sbp0:0:2:0): error 22
(probe6:sbp0:0:2:0): Unretryable Error
(probe7:sbp0:0:3:0): error 22
(probe7:sbp0:0:3:0): Unretryable Error
(probe8:sbp0:0:4:0): error 22
(probe8:sbp0:0:4:0): Unretryable Error
(probe9:sbp0:0:5:0): error 22
(probe9:sbp0:0:5:0): Unretryable Error
(probe10:sbp0:0:6:0): error 22
(probe10:sbp0:0:6:0): Unretryable Error
(probe4:sbp0:0:0:0): error 22
(probe4:sbp0:0:0:0): Unretryable Error
(probe5:sbp0:0:1:0): error 22
(probe5:sbp0:0:1:0): Unretryable Error
pass0 at ata1 bus 0 target 0 lun 0
pass0: <SONY DVD RW DRU-530A 1.0d> Removable CD-ROM SCSI-0 device 
pass0: Serial Number 3
pass0: 33.000MB/s transfers
GEOM: new disk cd0
(cd0:ata1:0:0:0): error 6
(cd0:ata1:0:0:0): Unretryable Error
cd0 at ata1 bus 0 target 0 lun 0
cd0: <SONY DVD RW DRU-530A 1.0d> Removable CD-ROM SCSI-0 device 
cd0: Serial Number 3
cd0: 33.000MB/s transfers
cd0: Attempt to query device size failed: NOT READY, Medium not present - tray closed
(cd0:ata1:0:0:0): error 6
(cd0:ata1:0:0:0): Unretryable Error
(cd0:ata1:0:0:0): error 6
(cd0:ata1:0:0:0): Unretryable Error
(cd0:ata1:0:0:0): error 6
(cd0:ata1:0:0:0): Unretryable Error
Mounting root from ufs:/dev/ad0s1a
start_init: trying /sbin/init
Linux ELF exec handler installed
linprocfs registered
splash: image decoder found: logo_saver

-----

(Selected lines from 5.3-BETA6 kernel, built just prior to 5.3-BETA7
version bump.  Copied by hand.)

atapci0: <Intel ICH2 UDMA100 controller> port 0xffa0-0xffaf,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 at device 31.1 on pci0
atapci0: Reserved 0x10 bytes for rid0x20 type 4 at 0xffa0
ata0: channel #0 on atapci0
atapci0: Reserved 0x8 bytes for rid 0x10 type 4 at 0x1f0
atapci0: Reserved 0x1 bytes for rid 0x14 type 4 at 0x3f6
ata0: reset tp1 mask=03 ostat0=50 ostat1=00
ata0-master: stat 0x90 err=0x90 lsb=0x90 msb=0x90
ata0-master: stat=0x50 err=0x01 lsb=0x00 msb=0x00
ata0-slave:  stat=0x00 err=0x01 lsb=0x00 msb=0x00
ata0: reset tp2 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ata0: [MPSAFE]
ata1: channel #1 on atapci0
atapi0: Reserved 0x08 bytes for rid 0x18 type 4 at 0x170
atapci0: Reserved 0x1 bytes for rid 0x1c type 4 at 0x376
ata1: reset tp1 mask=03 ostat0=50 ostat1=01
ata1-master: stat=0x00 err=0x01 lsb=0x14 msb=0xeb
ata1-slave:  stat=0x00 err=0x00 lsb=0x00 msb=0x00
ata1: reset tp2 stat0=00 stat1=00 devices=0x4<ATAPI_MASTER>
ata1: [MPSAFE]

ata0-master: pio=0x0c wdma=0x22 udma=0x45 cable=80pin
ata0-master: setting PIO4 on Intel ICH2 chip
ata0-master: setting UDMA100 on Intel ICH2 chip
ad0: <ST3120026A/3.06> ATA-6 disk at ata0-master
ad0: 114473MB (234441648 sectors), 232581 C, 16 H, 63 S, 512B
ad0: 16 secs/int, 1 depth queue, UDMA100
ar: FreeBSD check1 failed
ata1-master: pio=0x0c wdma=0x22 udma=0x42 cable=40pin
ata1-master: setting PIO4 on Intel ICH2 chip
ata1-master: setting UDMA33 on Intel ICH2 chip
acd0: <SONY DVD RW DRU-530A/1.0d> DVDR drive at ata1 as master
acd0: read 6890KB/s (6890KB/s) write 4133KB/s (4133KB/s), 2048KB buffer, UDMA33
acd0: Reads: CDR, CDRW, CDDA stream, DVDROM, DVDR, packet
acd0: Writes: CDR, CDRW, DVDR, test write, burnproof
acd0: Audio: play, 256 volume levels
acd0: Mechanism: ejectable tray, unlocked
acd0: Medium: no/blank disk


Received on Mon Oct 04 2004 - 00:58:14 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:38:15 UTC