Re: sata + vinum + Asus p4p800 = :(

From: Alexander Leidinger <Alexander_at_Leidinger.net>
Date: Mon, 20 Oct 2003 19:02:22 +0200
Greg 'groggy' Lehey schrieb:

> Provide a dump?  Analyse the problem yourself?  This *is* -CURRENT,
> after all.  

I can reproduce this here (same MB). I don't think it's a vinum problem, 
but vinum seems to be a good way to reproduce the bug. I set up a stripe 
over 2 SATA disks, newfs it, run "iozone -a" and BOOM.

Using just one disk without vinum doesn't result in a panic, but with a 
verbose boot I see a lot of spurious interrupt messages while running 
iozone on one disk.

The panic is:
panic: ata_dmasetup: transfer active on this disk.

Backtrace:
ata_dmastart
ata_pci_dmastart
ata_transaction
ata_start
g_disk_start
g_io_schedule_down
g_down_procbody
fork_exit
fork_trampoline

Trying to write a dump results in a hard hang of the system.

The backtrace is with todays kernel source:
	ata-lowlevel 1.20
	ata-pci.c 1.69
	ata-queue.c 1.11
	atapi-cd.c 1.149

Verbose dmesg attached (booted with an oct 17 kernel, if it's not 
enough, I rebuild the kernel with a larger message buffer).

Greg, I don't know if the following is ATA related:
I'm able to fdisk and disklabel the disks without any prolems, and the 
label survives a reboot, but rebooting after setting up the stripe 
results in a lost configuration, only the names of the drives show up 
with "vinum l", everything else is "clean" (0 volumes, 0 plexes, 0 
subdisks). The config is:
---snip---
drive SATA1 device /dev/ad1s1a
drive SATA2 device /dev/ad2s1a
volume space setupstate
	plex org striped 279k
		sd length 0 drive SATA1
		sd length 0 drive SATA2
---snip---

S/oren, one additional datapoint: With the oct 17 kernel I've seen the 
following output with a verbose boot before it hunged hard (I had 
DDB_UNATTENDED in the oct 17 kernel):
- spurious interrupt messages for ata 0 and 2
- WARNING FLUSHCASHE for ad0
- WARNING WRITE_DMA recovered from missing int for ad1
- TIMEOUT WRITE_DMA retrying for ad2
- ata3 reset tp1 mask=03 ostat0=d0 ostat1=00
---snip---

Feel free to use me as an testing-ape, I also can provide a root login 
to this machine if needed (just tell me and I grab the ssh key from 
freefall).

Bye,
Alexander.

ngnt=0x00 (0 ns), maxlat=0x00 (0 ns)
found->	vendor=0x8086, dev=0x2571, revid=0x02
	bus=0, slot=1, func=0
	class=06-04-00, hdrtype=0x01, mfdev=0
	cmdreg=0x0107, statreg=0x00a0, cachelnsz=0 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x08 (2000 ns), maxlat=0x00 (0 ns)
	map[20]: type 4, range 32, base 0000ef00, size  5, enabled
pcib0: matched entry for 0.29.INTA (source \\_SB_.LNKA)
pcib0: slot 29 INTA is routed to irq 10
found->	vendor=0x8086, dev=0x24d2, revid=0x02
	bus=0, slot=29, func=0
	class=0c-03-00, hdrtype=0x00, mfdev=1
	cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=a, irq=10
	map[20]: type 4, range 32, base 0000ef20, size  5, enabled
pcib0: matched entry for 0.29.INTB (source \\_SB_.LNKD)
pcib0: slot 29 INTB is routed to irq 5
found->	vendor=0x8086, dev=0x24d4, revid=0x02
	bus=0, slot=29, func=1
	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=b, irq=5
	map[20]: type 4, range 32, base 0000ef40, size  5, enabled
pcib0: matched entry for 0.29.INTC (source \\_SB_.LNKC)
pcib0: slot 29 INTC is routed to irq 5
found->	vendor=0x8086, dev=0x24d7, revid=0x02
	bus=0, slot=29, 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=c, irq=5
	map[20]: type 4, range 32, base 0000ef80, size  5, enabled
pcib0: matched entry for 0.29.INTA (source \\_SB_.LNKA)
pcib0: slot 29 INTA is routed to irq 10
found->	vendor=0x8086, dev=0x24de, revid=0x02
	bus=0, slot=29, func=3
	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=a, irq=10
	map[10]: type 1, range 32, base febff800, size 10, enabled
pcib0: matched entry for 0.29.INTD (source \\_SB_.LNKH)
pcib0: slot 29 INTD is routed to irq 11
found->	vendor=0x8086, dev=0x24dd, revid=0x02
	bus=0, slot=29, func=7
	class=0c-03-20, hdrtype=0x00, mfdev=0
	cmdreg=0x0106, statreg=0x0290, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=d, irq=11
	powerspec 2  supports D0 D3  current D0
found->	vendor=0x8086, dev=0x244e, revid=0xc2
	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=0x02 (500 ns), maxlat=0x00 (0 ns)
found->	vendor=0x8086, dev=0x24d0, revid=0x02
	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[10]: type 4, range 32, base 00000000, size  3, enabled
	map[14]: type 4, range 32, base 00000000, size  2, enabled
	map[18]: type 4, range 32, base 00000000, size  3, enabled
	map[1c]: type 4, range 32, base 00000000, size  2, enabled
	map[20]: type 4, range 32, base 0000fc00, size  4, enabled
found->	vendor=0x8086, dev=0x24db, revid=0x02
	bus=0, slot=31, func=1
	class=01-01-8a, hdrtype=0x00, mfdev=0
	cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=a, irq=255
	map[10]: type 4, range 32, base 0000efe0, size  3, enabled
	map[14]: type 4, range 32, base 0000efac, size  2, enabled
	map[18]: type 4, range 32, base 0000efa0, size  3, enabled
	map[1c]: type 4, range 32, base 0000efa8, size  2, enabled
	map[20]: type 4, range 32, base 0000ef60, size  4, enabled
pcib0: matched entry for 0.31.INTA (source \\_SB_.LNKC)
pcib0: slot 31 INTA is routed to irq 5
found->	vendor=0x8086, dev=0x24d1, revid=0x02
	bus=0, slot=31, func=2
	class=01-01-8f, hdrtype=0x00, mfdev=0
	cmdreg=0x0005, statreg=0x02a0, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=a, irq=5
	map[20]: type 4, range 32, base 00000400, size  5, enabled
pcib0: matched entry for 0.31.INTB (source \\_SB_.LNKB)
pcib0: slot 31 INTB is routed to irq 11
found->	vendor=0x8086, dev=0x24d3, revid=0x02
	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[10]: type 4, range 32, base 0000e800, size  8, enabled
	map[14]: type 4, range 32, base 0000ee80, size  6, enabled
	map[18]: type 1, range 32, base febff400, size  9, enabled
	map[1c]: type 1, range 32, base febff000, size  8, enabled
pcib0: matched entry for 0.31.INTB (source \\_SB_.LNKB)
pcib0: slot 31 INTB is routed to irq 11
found->	vendor=0x8086, dev=0x24d5, revid=0x02
	bus=0, slot=31, func=5
	class=04-01-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0007, statreg=0x0290, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=b, irq=11
	powerspec 2  supports D0 D3  current D0
agp0: <Intel 82865 host to AGP bridge> mem 0xf8000000-0xfbffffff at device 0.0 on pci0
agp0: allocating GATT for aperture of size 64M
pcib1: <ACPI PCI-PCI bridge> at device 1.0 on pci0
pcib1:   secondary bus     1
pcib1:   subordinate bus   1
pcib1:   I/O decode        0xc000-0xcfff
pcib1:   memory decode     0xfe900000-0xfe9fffff
pcib1:   prefetched decode 0xd7f00000-0xf7efffff
pcib1: could not get PCI interrupt routing table for \\_SB_.PCI0.P0P1 - AE_NOT_FOUND
pci1: <ACPI PCI bus> on pcib1
pci1: physical bus=1
	map[10]: type 3, range 32, base e0000000, size 28, enabled
	map[14]: type 4, range 32, base 0000c000, size  8, enabled
	map[18]: type 1, range 32, base fe9f0000, size 16, enabled
found->	vendor=0x1002, dev=0x5964, revid=0x01
	bus=1, slot=0, func=0
	class=03-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0107, statreg=0x02b0, cachelnsz=4 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x08 (2000 ns), maxlat=0x00 (0 ns)
	intpin=a, irq=10
	powerspec 2  supports D0 D1 D2 D3  current D0
pci1: <display, VGA> at device 0.0 (no driver attached)
uhci0: <Intel 82801EB (ICH5) USB controller USB-A> port 0xef00-0xef1f irq 10 at device 29.0 on pci0
usb0: <Intel 82801EB (ICH5) 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
uhci1: <Intel 82801EB (ICH5) USB controller USB-B> port 0xef20-0xef3f irq 5 at device 29.1 on pci0
usb1: <Intel 82801EB (ICH5) 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
uhci2: <Intel 82801EB (ICH5) USB controller USB-C> port 0xef40-0xef5f irq 5 at device 29.2 on pci0
usb2: <Intel 82801EB (ICH5) USB controller USB-C> on uhci2
usb2: USB revision 1.0
uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
uhci3: <Intel 82801EB (ICH5) USB controller USB-D> port 0xef80-0xef9f irq 10 at device 29.3 on pci0
usb3: <Intel 82801EB (ICH5) USB controller USB-D> on uhci3
usb3: USB revision 1.0
uhub3: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub3: 2 ports with 2 removable, self powered
ehci0: <EHCI (generic) USB 2.0 controller> mem 0xfebff800-0xfebffbff irq 11 at device 29.7 on pci0
ehci0: (New EHCI DeviceId=0x24dd8086)
ehci_pci_attach: companion usb0
ehci_pci_attach: companion usb1
ehci_pci_attach: companion usb2
ehci_pci_attach: companion usb3
usb4: EHCI version 1.0
usb4: companion controllers, 2 ports each: usb0 usb1 usb2 usb3
usb4: <EHCI (generic) USB 2.0 controller> on ehci0
usb4: USB revision 2.0
uhub4: (0x8086) EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub4: 8 ports with 8 removable, self powered
pcib2: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pcib2:   secondary bus     2
pcib2:   subordinate bus   2
pcib2:   I/O decode        0xd000-0xdfff
pcib2:   memory decode     0xfea00000-0xfeafffff
pcib2:   prefetched decode 0xfff00000-0xfffff
---- initial configuration ------------------------
\\_SB_.LNKE irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.8.0
\\_SB_.LNKF irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.9.0
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.9.1
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.9.2
\\_SB_.LNKE irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.9.3
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.10.0
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.10.1
\\_SB_.LNKE irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.10.2
\\_SB_.LNKF irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.10.3
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.11.0
\\_SB_.LNKE irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.11.1
\\_SB_.LNKF irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.11.2
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.11.3
\\_SB_.LNKE irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.12.0
\\_SB_.LNKF irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.12.1
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.12.2
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.12.3
\\_SB_.LNKF irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.13.0
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.13.1
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.13.2
\\_SB_.LNKE irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.13.3
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.5.0
\\_SB_.LNKE irq   0: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.3.0
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.4.0
---- before setting priority for links ------------
\\_SB_.LNKE:
	interrupts:	     3     4     5     6     7    10    11    12    14    15
	penalty:	  2320  2320  1820  2320  2320  1620  2920  2320 11320 11320
	references:	7
	priority:	0
\\_SB_.LNKF:
	interrupts:	     3     4     5     6     7    10    11    12    14    15
	penalty:	  2320  2320  1820  2320  2320  1620  2920  2320 11320 11320
	references:	5
	priority:	0
---- before fixup boot-disabled links -------------
\\_SB_.LNKE:
	interrupts:	     3     4     5     6     7    10    11    12    14    15
	penalty:	  2320  2320  1820  2320  2320  1620  2920  2320 11320 11320
	references:	7
	priority:	28420
\\_SB_.LNKF:
	interrupts:	     3     4     5     6     7    10    11    12    14    15
	penalty:	  2320  2320  1820  2320  2320  1620  2920  2320 11320 11320
	references:	5
	priority:	20300
---- after fixup boot-disabled links --------------
---- arbitrated configuration ---------------------
\\_SB_.LNKE irq  10: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.8.0
\\_SB_.LNKF irq   5: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.9.0
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.9.1
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.9.2
\\_SB_.LNKE irq  10: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.9.3
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.10.0
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.10.1
\\_SB_.LNKE irq  10: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.10.2
\\_SB_.LNKF irq   5: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.10.3
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.11.0
\\_SB_.LNKE irq  10: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.11.1
\\_SB_.LNKF irq   5: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.11.2
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.11.3
\\_SB_.LNKE irq  10: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.12.0
\\_SB_.LNKF irq   5: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.12.1
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.12.2
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.12.3
\\_SB_.LNKF irq   5: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.13.0
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.13.1
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.13.2
\\_SB_.LNKE irq  10: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.13.3
\\_SB_.LNKG irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.5.0
\\_SB_.LNKE irq  10: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.3.0
\\_SB_.LNKH irq  11: [  3  4  5  6  7 10 11 12 14 15] low,level,sharable 2.4.0
pci2: <ACPI PCI bus> on pcib2
pci2: physical bus=2
	map[10]: type 1, range 32, base feafc000, size 14, enabled
	map[14]: type 4, range 32, base 0000d800, size  8, enabled
pcib2: matched entry for 2.5.INTA (source \\_SB_.LNKG)
pcib2: slot 5 INTA is routed to irq 11
found->	vendor=0x10b7, dev=0x1700, revid=0x12
	bus=2, slot=5, func=0
	class=02-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0117, statreg=0x02b0, cachelnsz=4 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x17 (5750 ns), maxlat=0x1f (7750 ns)
	intpin=a, irq=11
	powerspec 2  supports D0 D1 D2 D3  current D0
skc0: <3Com 3C940 Gigabit Ethernet> port 0xd800-0xd8ff mem 0xfeafc000-0xfeafffff irq 11 at device 5.0 on pci2
pcib2: device skc0 requested decoded memory range 0xfeafc000-0xfeafffff
skc0: 3Com Gigabit LOM (3C940)
sk0: <Marvell Semiconductor, Inc. Yukon> on skc0
sk0: Ethernet address: 00:0c:6e:a0:f2:62
sk0: bpf attached
miibus0: <MII bus> on sk0
e1000phy0: <Marvell 88E1000 Gigabit PHY> on miibus0
e1000phy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseTX-FDX, auto
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH5 UDMA100 controller> port 0xfc00-0xfc0f,0-0x3,0-0x7,0-0x3,0-0x7 at device 31.1 on pci0
ata0: reset tp1 mask=03 ostat0=50 ostat1=00
ata0-master: stat=0x50 err=0x01 lsb=0x00 msb=0x00
ata0-slave:  stat=0x00 err=0x01 lsb=0x00 msb=0x00
ata0: reset tp2 mask=03 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ata0: at 0x1f0 irq 14 on atapci0
ata0: [MPSAFE]
ata1: reset tp1 mask=03 ostat0=3e ostat1=50
ata1-master: stat=0x7f err=0x3d lsb=0x7f msb=0x7f
ata1-slave:  stat=0x90 err=0x01 lsb=0x14 msb=0xeb
ata1-slave:  stat=0x90 err=0x01 lsb=0x14 msb=0xeb
ata1-slave:  stat=0x90 err=0x01 lsb=0x14 msb=0xeb
ata1-slave:  stat=0x00 err=0x01 lsb=0x14 msb=0xeb
ata1: reset tp2 mask=03 stat0=7f stat1=00 devices=0x8<ATAPI_SLAVE>
ata1: at 0x170 irq 15 on atapci0
ata1: [MPSAFE]
atapci1: <Intel ICH5 SATA150 controller> port 0xef60-0xef6f,0xefa8-0xefab,0xefa0-0xefa7,0xefac-0xefaf,0xefe0-0xefe7 irq 5 at device 31.2 on pci0
atapci1: [MPSAFE]
ata2: reset tp1 mask=03 ostat0=50 ostat1=00
ata2-master: stat=0x50 err=0x01 lsb=0x00 msb=0x00
ata2-slave:  stat=0x00 err=0x01 lsb=0x00 msb=0x00
ata2: reset tp2 mask=03 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ata2: at 0xefe0 on atapci1
ata2: [MPSAFE]
ata3: reset tp1 mask=03 ostat0=50 ostat1=00
ata3-master: stat=0x50 err=0x01 lsb=0x00 msb=0x00
ata3-slave:  stat=0x00 err=0x01 lsb=0x00 msb=0x00
ata3: reset tp2 mask=03 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ata3: at 0xefa0 on atapci1
ata3: [MPSAFE]
ichsmb0: <Intel 82801EB (ICH5) SMBus controller> port 0x400-0x41f irq 11 at device 31.3 on pci0
smbus0: <System Management Bus> on ichsmb0
smb0: <SMBus generic I/O> on smbus0
pci0: <multimedia, audio> at device 31.5 (no driver attached)
acpi_button0: <Power Button> on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0
atkbd0: <AT Keyboard> flags 0x1 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:0x1, flags:0x3d0000
psm0: unable to allocate IRQ
unknown: not probed (disabled)
sio0: irq maps: 0x1 0x11 0x1 0x1
sio0 port 0x3f8-0x3ff irq 4 on acpi0
sio0: type 16550A
sio1: irq maps: 0x1 0x9 0x1 0x1
sio1 port 0x2f8-0x2ff irq 3 on acpi0
sio1: type 16550A
fdc0: <Enhanced floppy controller (i82077, NE72065 or clone)> port 0x3f7,0x3f0-0x3f5 irq 6 drq 2 on acpi0
fdc0: FIFO enabled, 8 bytes threshold
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
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
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)
unknown: not probed (disabled)
unknown: not probed (disabled)
unknown: not probed (disabled)
unknown: not probed (disabled)
fdc: fdc0 already exists; skipping it
ata: ata0 already exists; skipping it
ata: ata1 already exists; skipping it
atkbdc: atkbdc0 already exists; skipping it
sio: sio0 already exists; skipping it
sio: sio1 already exists; skipping it
ppc: ppc0 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
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: <Option ROM> at iomem 0xc0000-0xccfff on isa0
pmtimer0 on isa0
adv0: not probed (disabled)
bt0: not probed (disabled)
aha0: not probed (disabled)
aic0: 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 
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sc0: fb0, kbd0, terminal emulator: sc (syscons terminal)
vt0: not probed (disabled)
pcic0 failed to probe at port 0x3e0 iomem 0xd0000 on isa0
pcic1: not probed (disabled)
sio2: not probed (disabled)
sio3: not probed (disabled)
ed0: not probed (disabled)
cs0: not probed (disabled)
sn0: not probed (disabled)
ie0: not probed (disabled)
fe0: not probed (disabled)
le0: not probed (disabled)
lnc0: not probed (disabled)
isa_probe_children: probing PnP devices
Device configuration finished.
Timecounter "TSC" frequency 2398860468 Hz quality 800
Timecounters tick every 10.000 msec
lo0: bpf attached
Linux ELF exec handler installed
ata1: spurious interrupt - status=0x00 error=0x01
acpi_cpu: throttling enabled, 8 steps (100% to 12.5%), currently 100.0%
ata0-master: pio=0x0c wdma=0x22 udma=0x46 cable=80pin
ad0: setting UDMA100 on Intel ICH5 chip
GEOM: create disk ad0 dp=0xc66b1870
ad0: <Maxtor 6Y080L0/YAR41BW0> ATA-7 disk at ata0-master
ad0: 78167MB (160086528 sectors), 158816 C, 16 H, 63 S, 512 B
ad0: 16 secs/int, 1 depth queue, UDMA100
GEOM: new disk ad0
ar: FreeBSD check1 failed
ata1-slave: pio=0x0c wdma=0x22 udma=0x42 cable=40pin
acd0: setting PIO4 on Intel ICH5 chip
acd0: <JLMS XJ-HD165H/CH11> DVDROM drive at ata1 as slave
acd0: read 8268KB/s (8268KB/s), 512KB buffer, PIO4
acd0: Reads: CDR, CDRW, CDDA stream, DVDROM, DVDR, DVDRAM, packet
acd0: Writes:
acd0: Audio: play, 256 volume levels
acd0: Mechanism: ejectable tray, unlocked
acd0: Medium: no/blank disc
ata3: spurious interrupt - status=0x00 error=0x01
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x00 error=0x01
ata2-master: pio=0x0c wdma=0x22 udma=0x46 cable=40pin
ata3: spurious interrupt - status=0x00 error=0x01
ata3: spurious interrupt - status=0x00 error=0x01
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x00 error=0x01
ata3: spurious interrupt - status=0x00 error=0x01
ata3: spurious interrupt - status=0x00 error=0x01
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x00 error=0x01
ata3: spurious interrupt - status=0x00 error=0x01
ata3: spurious interrupt - status=0x00 error=0x01
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x00 error=0x01
ata3: spurious interrupt - status=0x00 error=0x01
ata3: spurious interrupt - status=0x00 error=0x01
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x00 error=0x01
ad1: setting UDMA133 on Intel ICH5 chip
GEOM: create disk ad1 dp=0xc66b1470
ad1: <Maxtor 6Y080M0/YAR51BW0> ATA-7 disk at ata2-master
ad1: 78167MB (160086528 sectors), 158816 C, 16 H, 63 S, 512 B
ad1: 16 secs/int, 1 depth queue, UDMA133
ata3: spurious interrupt - status=0x00 error=0x01
[0] f:80 typ:165 s(CHS):0/1/1 e(CHS):1023/3/63 s:63 l:160071597
[1] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0ata3: spurious interrupt - status=0x00 error=0x01

[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 81956657664 end 81956689919
ar: FreeBSD check1 failed
GEOM: new disk ad1
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3-master: pio=0x0c wdma=0x22 udma=0x46 cable=40pin
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ad2: setting UDMA133 on Intel ICH5 chip
GEOM: create disk ad2 dp=0xc66b1070
ad2: <Maxtor 6Y080M0/YAR51BW0> ATA-7 disk at ata3-master
ad2: 78167MB (160086528 sectors), 158816 C, 16 H, 63 S, 512 B
ad2: 16 secs/int, 1 depth queue, UDMA133
ata2: spurious interrupt - status=0x50 error=0x00
GEOM: Configure ad0s1a, start 0 length 2147483648 end 2147483647
GEOM: Configure ad0s1b, start 79456894976 length 2499762688 end 81956657663
GEOM: Configure ad0s1c, start 0 length 81956657664 end 81956657663
GEOM: Configure ad0s1d, start 2147483648 length 77309411328 end 79456894975
ar: FreeBSD check1 failed
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
[0] f:80 typ:165 s(CHS):0/1/1 e(CHS):1023/254/63 s:63 l:160071597
[1] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0
[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 ad1s1, start 32256 length 81956657664 end 81956689919
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
GEOM: new disk ad2
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
GEOM: Configure ad1s1a, start 0 length 81956657664 end 81956657663
GEOM: Configure ad1s1c, start 0 length 81956657664 end 81956657663
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
[0] f:80 typ:165 s(CHS):0/1/1 e(CHS):1023/254/63 s:63 l:160071597
[1] f:00 typ:0 s(CHS):0/0/0 e(CHS):0/0/0 s:0 l:0
[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 ad2s1, start 32256 length 81956657664 end 81956689919
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
GEOM: Configure ad2s1a, start 0 length 81956657664 end 81956657663
GEOM: Configure ad2s1c, start 0 length 81956657664 end 81956657663
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
Mounting root from ufs:/dev/ad0s1a
start_init: trying /sbin/init
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata2: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
splash: image decoder found: green_saver
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
GEOM: Configure ad1s1a, start 0 length 81956657664 end 81956657663
GEOM: Configure ad1s1c, start 0 length 81956657664 end 81956657663
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
ata3: spurious interrupt - status=0x50 error=0x00
Received on Mon Oct 20 2003 - 08:03:17 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:37:26 UTC