Bug 91408 - [irq] ata(4) failure: SETFEATURES SET TRANSFER MODE semaphore timeout !! DANGER Will Robinson !!
Summary: [irq] ata(4) failure: SETFEATURES SET TRANSFER MODE semaphore timeout !! DANG...
Status: Closed FIXED
Alias: None
Product: Base System
Classification: Unclassified
Component: kern (show other bugs)
Version: 6.0-RELEASE
Hardware: Any Any
: Normal Affects Only Me
Assignee: Mark Linimon
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2006-01-06 18:00 UTC by Eugene Grosbein
Modified: 2007-05-24 22:42 UTC (History)
0 users

See Also:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Eugene Grosbein 2006-01-06 18:00:15 UTC
	Under heavy load (linking GENERIC kernel.debug and running
	/usr/libexec/locate.updatedb in parallel)
	a kernel starts to fill console with messages:

ad4: req=0xc25d77d0 SETFEATURES SET TRANSFER MODE semaphore timeout !! DANGER Will Robinson !!
ad4: req=0xc25d77d0 SETFEATURES SET TRANSFER MODE semaphore timeout !! DANGER Will Robinson !!
ad4: req=0xc25d77d0 SETFEATURES SET TRANSFER MODE semaphore timeout !! DANGER Will Robinson !!

	New line is added after every 4 seconds. All disk activity
	is stopped since this moment; correct system reset is impossible,
	power cycle is required to revive system.

	ad4 is connected to Promise Ultra100 TX2 and runs at UDMA100.
	Here is output of "pciconv -lv":

atapci1@pci0:18:0:      class=0x018085 card=0x4d68105a chip=0x4d68105a
rev=0x02
hdr=0x00
    vendor   = 'Promise Technology Inc'
    device   = 'PDC20268 Ultra100 TX2 EIDE Controller'
    class    = mass storage

	This is reproduceable when system is booted with ACPI enabled.
	This is NOT reproduceable when any of next contidions are met:

- ACPI is disabled or
- ad4 is attached to onboard Intel PIIX4 IDE controller:

atapci0@pci0:7:1:       class=0x010180 card=0x00000000 chip=0x71118086
rev=0x01
hdr=0x00
    vendor   = 'Intel Corporation'
    device   = '82371AB/EB/MB PIIX4/4E/4M IDE Controller'
    class    = mass storage
    subclass = ATA

	When ad4 is attached to onboard controller, it runs at UDMA33.

	Note that ad4 hold an installation of 4.11-STABLE and runs
	without a problem at UDMA100 when attached to Promise Ultra100 TX2.
	ad4 also holds installations of Windows 98SE and Windows XP SP2,
	they run without a problem with Promise Ultra at UDMA100.

	5.4-STABLE does have problems with this hardware configuration, see
	http://www.freebsd.org/cgi/query-pr.cgi?pr=kern/80815

	Please note that I'm no longer interested to migrate from
	4.11 to 5.x, I rather migrate to 6.x, so kern/80815 may be closed.

	Here comes dmesg.boot when ad4 is at Promise Ultra and
	ACPI is enabled.

Copyright (c) 1992-2005 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
	The Regents of the University of California. All rights reserved.
FreeBSD 6.0-RELEASE #1: Fri Jan  6 20:35:37 KRAT 2006
    root@grosbein.pp.ru:/mnt/usr/local/obj6/usr/src/sys/DADV
WARNING: debug.mpsafenet forced to 0 as ipsec requires Giant
WARNING: MPSAFE network stack disabled, expect reduced performance.
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Intel Celeron (902.05-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  = 603914240 (575 MB)
avail memory = 581697536 (554 MB)
npx0: [FAST]
npx0: <math processor> on motherboard
npx0: INT 16 interface
acpi0: <AWARD AWRDACPI> on motherboard
acpi0: Power Button (fixed)
pci_link0: <ACPI PCI Link LNKA> irq 11 on acpi0
pci_link1: <ACPI PCI Link LNKB> irq 9 on acpi0
pci_link2: <ACPI PCI Link LNKC> irq 10 on acpi0
pci_link3: <ACPI PCI Link LNKD> irq 9 on acpi0
Timecounter "ACPI-safe" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x4008-0x400b on acpi0
cpu0: <ACPI CPU> on acpi0
acpi_throttle0: <ACPI CPU Throttling> on cpu0
acpi_button0: <Power Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff,0x4000-0x4041,0x5000-0x500f on acpi0
pci0: <ACPI PCI bus> on pcib0
agp0: <Intel 82443BX (440 BX) host to PCI bridge> mem 0xe8000000-0xebffffff at device 0.0 on pci0
pcib1: <PCI-PCI bridge> at device 1.0 on pci0
pci1: <PCI bus> on pcib1
pci1: <display, VGA> at device 0.0 (no driver attached)
pci1: <display> at device 0.1 (no driver attached)
isab0: <PCI-ISA bridge> at device 7.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel PIIX4 UDMA33 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xf000-0xf00f at device 7.1 on pci0
ata0: <ATA channel 0> on atapci0
ata1: <ATA channel 1> on atapci0
uhci0: <Intel 82371AB/EB (PIIX4) USB controller> port 0xa000-0xa01f irq 9 at device 7.2 on pci0
uhci0: [GIANT-LOCKED]
usb0: <Intel 82371AB/EB (PIIX4) USB controller> 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: <bridge> at device 7.3 (no driver attached)
fxp0: <Intel 82557 Pro/100 Ethernet> port 0xa400-0xa41f mem 0xf0104000-0xf0104fff,0xf0000000-0xf00fffff irq 9 at device 16.0 on pci0
miibus0: <MII bus> on fxp0
inphy0: <i82555 10/100 media interface> on miibus0
inphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
fxp0: Ethernet address: 00:a0:c9:89:95:1f
fxp0: [GIANT-LOCKED]
atapci1: <Promise PDC20268 UDMA100 controller> port 0xa800-0xa807,0xac00-0xac03,0xb000-0xb007,0xb400-0xb403,0xb800-0xb80f mem 0xf0100000-0xf0103fff irq 10 at device 18.0 on pci0
ata2: <ATA channel 0> on atapci1
ata3: <ATA channel 1> on atapci1
uhci1: <VIA 83C572 USB controller> port 0xbc00-0xbc1f irq 9 at device 19.0 on pci0
uhci1: [GIANT-LOCKED]
usb1: <VIA 83C572 USB controller> on uhci1
usb1: USB revision 1.0
uhub1: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
uhci2: <VIA 83C572 USB controller> port 0xc000-0xc01f irq 11 at device 19.1 on pci0
uhci2: [GIANT-LOCKED]
usb2: <VIA 83C572 USB controller> on uhci2
usb2: USB revision 1.0
uhub2: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
ehci0: <VIA VT6202 USB 2.0 controller> mem 0xf0105000-0xf01050ff irq 9 at device 19.2 on pci0
ehci0: [GIANT-LOCKED]
usb3: EHCI version 1.0
usb3: companion controllers, 2 ports each: usb1 usb2
usb3: <VIA VT6202 USB 2.0 controller> on ehci0
usb3: USB revision 2.0
uhub3: VIA EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub3: 4 ports with 4 removable, self powered
fwohci0: <VIA Fire II (VT6306)> port 0xc400-0xc47f mem 0xf0106000-0xf01067ff irq 9 at device 19.3 on pci0
fwohci0: [GIANT-LOCKED]
fwohci0: OHCI version 1.10 (ROM=1)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 00:11:06:66:00:00:0d:c9
fwohci0: Phy 1394a available S400, 3 ports.
fwohci0: Link S400, max_rec 2048 bytes.
firewire0: <IEEE1394(FireWire) bus> on fwohci0
sbp0: <SBP-2/SCSI over FireWire> on firewire0
fwe0: <Ethernet over FireWire> on firewire0
if_fwe0: Fake Ethernet address: 02:11:06:00:0d:c9
fwe0: Ethernet address: 02:11:06:00:0d:c9
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)
acpi_tz0: <Thermal Zone> on acpi0
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: [FAST]
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
sio0: <16550A-compatible COM port> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
sio0: type 16550A
sio1: <16550A-compatible COM port> port 0x2f8-0x2ff irq 3 on acpi0
sio1: type 16550A
ppc0: <ECP parallel printer port> port 0x378-0x37f,0x778-0x77b irq 7 drq 3 on acpi0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/16 bytes threshold
ppbus0: <Parallel port bus> on ppc0
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model NetMouse/NetScroll Optical, device ID 0
pmtimer0 on isa0
orm0: <ISA Option ROMs> at iomem 0xc0000-0xccfff,0xd0000-0xd27ff on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
uscanner0: Hewlett-Packard HP ScanJet 2200C, rev 1.10/1.00, addr 2
uhid0: American Power Conversion Back-UPS 500 FW: 6.5.I USB FW: c1, rev 1.10/1.00, addr 3, iclass 3/0
Timecounter "TSC" frequency 902050315 Hz quality 800
Timecounters tick every 1.000 msec
IPsec: Initialized Security Association Processing.
ipfw2 (+ipv6) initialized, divert loadable, rule-based forwarding enabled, default to deny, logging disabled
acd0: DVDR <NEC DVD RW ND-3500AG/2.18> at ata0-master UDMA33
ad4: 76319MB <WDC WD800JB-00FMA0 13.03G13> at ata2-master UDMA100
ad6: 2441MB <WDC AC22500L 32.41N37> at ata3-master UDMA33
Trying to mount root from ufs:/dev/ad6s1a

Fix: 

Unknown.
How-To-Repeat: 
	Take Iwill BD100+ motherboard, attach HDD to 
	Promise Ultra100 TX2 EIDE controller, boot 6.0-RELEASE/GENERIC
	with ACPI enabled and make disk load (f.e., run make buildworld).
Comment 1 Gleb Smirnoff freebsd_committer freebsd_triage 2006-01-10 13:28:01 UTC
Responsible Changed
From-To: freebsd-bugs->sos

To ATA maintainer.
Comment 2 Søren Schmidt freebsd_committer freebsd_triage 2006-01-23 12:07:40 UTC
State Changed
From-To: open->feedback

Please try an uptodate -current kernel, or at least a releng_6 kernel 
with /sys/sys/ata.h and /sys/dev/ata/* substituted from a fresh current, 
and let me know if that changes anything.
Comment 3 Eugene Grosbein 2006-01-25 18:23:38 UTC
Hi!

I've updated sys/ sources to fresh CURRENT, built my kernel
and booted it with 6.0-RELEASE world. Now the problem has disappeared,
I cannot reproduce it anymore.

Eugene Grosbein
Comment 4 Eugene Grosbein 2006-02-16 16:48:30 UTC
Hi!

Today I've updated sources, built and booted 6.1-PRERELEASE kernel.
The problem has gone, it is not reproduceable now.
Thank you very much!

Eugene Grosbein
Comment 5 John Baldwin freebsd_committer freebsd_triage 2006-02-16 23:09:04 UTC
State Changed
From-To: feedback->closed

Submitter reports that problem is fixed in 6.1-PRERELEASE.  Thanks for testing!
Comment 6 Eugene Grosbein 2006-02-27 19:22:43 UTC
Hi!

I'm terribly sorry but the problem has just repeated :-(

The software and hardware configuration except of
changed hard drives:

# atacontrol info ata0; atacontrol info ata1; atacontrol info ata2; atacontrol info ata3;
Master:  ad0 <WDC AC22500L/32.41N37> ATA/ATAPI revision 3
Slave:       no device present
Master: acd0 <NEC DVD RW ND-3500AG/2.18> ATA/ATAPI revision 0
Slave:       no device present
Master:  ad4 <WDC WD800JB-00FMA0/13.03G13> ATA/ATAPI revision 6
Slave:       no device present
Master:  ad6 <IC35L040AVER07-0/ER4OA45A> ATA/ATAPI revision 5
Slave:       no device present

ad0 is boot device. It is still the same WDC AC22500L holding complete
6.1-PRERELEASE installation, I have not touched it since my last letter
(with the exception of /etc/fstab after drive has moved to ata0).

I've decided to move 6.1 to free slice at ad6, partitioned ad6s2,
newfs'ed filesystems and run "dump|restore" for /, /var and /usr.
The root filesystem and /var were dumped successfully but
while dumping /usr the kernel started to write to console:

ad4: req=0xc6c814b0 SETFEATURES SET TRANSFER MODE semaphore timeout !! DANGER Will Robinson !!
ad4: req=0xc6c814b0 SETFEATURES SET TRANSFER MODE semaphore timeout !! DANGER Will Robinson !!

And so on. dump reads from ad0, restore writes to ad6.
ad4 still holds the installation of 4.11-STABLE,
its filesystems are mounted and /tmp uses space on ad4,
so dump or restore use ad4 in process somehow.

Ctrl-C successfully aborted dump(8) but not restore(8) that is not killable
even with kill -9 now. restore(8) is locked with "wdrain" wchan
and "D+" state.

Now any process trying to access ad4 hangs.

So I'm afraid this PR should be re-opened.

Eugene Grosbein
Comment 7 Eugene Grosbein 2006-02-28 03:46:10 UTC
Hi!

> I'm terribly sorry but the problem has just repeated :-(
> The software and hardware configuration except of
> changed hard drives:

Read: "The software and hardware configuration are the same except of
changed hard drives". There is one more HDD actually,
others are the same too, just changed their location.

I have to note that I performed power-cycle, 6.1-PRE rebooted
and I repeated my attempt to dump|restore the /usr partition.
It completed successfully this time.

Eugene Grosbein
Comment 8 Eugene Grosbein 2006-03-03 13:08:25 UTC
Hi!

6.1-PRERELEASE booting from ad6 just cannot complete boot process
and reach multiuser. This bug hits the system repeatedly at the same
stage while it runs rcorder. The bug does not manifest when ACPI is disabled
or when I add 'set -x' to /etc/rc before rcorder (so it runs slower).

See also http://www.freebsd.org/cgi/query-pr.cgi?pr=kern/93885

This should be show-stopper for release.

Eugene Grosbein
Comment 9 Alexander Logvinov 2006-03-03 13:55:35 UTC
Hello!


Same problem with Iwill DBS100 Rev 2.2 + FastTrak TX2300:

ad6: req=0xc87b9898 SETFEATURES SET TRANSFER MODE semaphore timeout !! DANGER Will Robinson !! 


Environment:

FreeBSD server.lad 6.1-PRERELEASE FreeBSD 6.1-PRERELEASE #2: Mon Feb 20 19:23:52 YAKT 2006     nilard@server.lad:/usr/obj/usr/src/sys/LAD_01  i386

ad0: 114473MB <Seagate ST3120026A 8.54> at ata0-master UDMA33
ad4: 152627MB <Maxtor 6Y160M0 YAR511W0> at ata2-master SATA150
ad6: 152627MB <Maxtor 6Y160M0 YAR511W0> at ata3-master SATA150

ar0: 152627MB <Promise Fasttrak RAID1> status: READY
ar0: disk0 READY (master) using ad4 at ata2-master
ar0: disk1 READY (mirror) using ad6 at ata3-master

# pciconv -lv

atapci1@pci0:18:0:      class=0x010400 card=0x3570105a chip=0x3570105a rev=0x02 hdr=0x00
    vendor   = 'Promise Technology Inc'
    class    = mass storage
    subclass = RAID

# atacontrol list
ATA channel 0:
    Master:  ad0 <ST3120026A/8.54> ATA/ATAPI revision 6
    Slave:       no device present
ATA channel 1:
    Master:      no device present
    Slave:       no device present
ATA channel 2:
    Master:  ad4 <Maxtor 6Y160M0/YAR511W0> Serial ATA v1.0
    Slave:       no device present
ATA channel 3:
    Master:  ad6 <Maxtor 6Y160M0/YAR511W0> Serial ATA v1.0
    Slave:       no device present
ATA channel 4:
    Master:      no device present
    Slave:       no device present


WBR.
Comment 10 Mark Linimon freebsd_committer freebsd_triage 2006-03-03 21:37:36 UTC
State Changed
From-To: closed->open

Submitter notes problem was not fixed after all.
Comment 11 Eugene Grosbein 2006-03-04 04:57:33 UTC
Hi!

As this PR is reopened, I add this to Audit-Trail for completeness.

I've updated my sources to RELENG_6
(and grabbed src/sys/dev/ata/ata-all.c,v 1.252.2.6), rebuilt and booted
new kernel.
      
Now deadlock is gone but the system suffers from temporary delays
while accessing the drive. The kernel is signalling:
      
ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing
request directly
ad4: WARNING - SET_MULTI taskqueue timeout - completing request directly
ad4: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=100861733
ad6: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=62118768
ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing
request directly
ad4: WARNING - SET_MULTI taskqueue timeout - completing request directly
ad4: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=100861989
ad6: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=62184272
ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing
request directly
ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing
request directly
ad4: WARNING - SET_MULTI taskqueue timeout - completing request directly
ad4: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=100862853
ad6: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=62352144

Eugene Grosbein
Comment 12 Søren Schmidt freebsd_committer freebsd_triage 2006-04-03 13:14:35 UTC
Responsible Changed
From-To: sos->bugs

This is an interrupt routing problem, not an ATA issue.
Comment 13 Mark Linimon freebsd_committer freebsd_triage 2006-04-03 17:57:14 UTC
Responsible Changed
From-To: bugs->freebsd-bugs

Fix Synopsis and assignee.
Comment 14 Eugene Grosbein 2006-04-29 16:41:58 UTC
Hi!

With 6.1-RC of today I have the same sympthoms as with 5.4-RELEASE
(http://www.freebsd.org/cgi/query-pr.cgi?pr=kern/80815):

1) machine suffers from ATA timeouts if ACPI is fully enabled;
2) debug.acpi.disable="pci_link" in /boot/loader.conf eliminates the problem.

So, Soren seems to be right: this is interrupt routing problem
and not ATA problem.

The question is: should I consider the workaround mentioned above as solution?
What will I miss if I keep debug.acpi.disable="pci_link" forever?

http://www.freebsd.org/cgi/query-pr.cgi?pr=kern/91408

Eugene Grosbein
Comment 15 John Baldwin freebsd_committer freebsd_triage 2006-04-30 03:54:09 UTC
On Saturday 29 April 2006 11:41 am, Eugene Grosbein wrote:
> Hi!
>
> With 6.1-RC of today I have the same sympthoms as with 5.4-RELEASE
> (http://www.freebsd.org/cgi/query-pr.cgi?pr=3Dkern/80815):
>
> 1) machine suffers from ATA timeouts if ACPI is fully enabled;
> 2) debug.acpi.disable=3D"pci_link" in /boot/loader.conf eliminates the
> problem.
>
> So, Soren seems to be right: this is interrupt routing problem
> and not ATA problem.
>
> The question is: should I consider the workaround mentioned above as
> solution? What will I miss if I keep debug.acpi.disable=3D"pci_link" fore=
ver?

I think it's dangerous and that you should just disable ACPI altogether
if you wish to do that.  Can you provide verbose dmesg's for the
case with pci_link disabled and the case where it is not disabled?

=2D-=20
John Baldwin <jhb@FreeBSD.org> =A0<>< =A0http://www.FreeBSD.org/~jhb/
"Power Users Use the Power to Serve" =A0=3D =A0http://www.FreeBSD.org
Comment 16 Eugene Grosbein 2006-04-30 09:44:32 UTC
On Sat, Apr 29, 2006 at 10:54:09PM -0400, John Baldwin wrote:

> > With 6.1-RC of today I have the same sympthoms as with 5.4-RELEASE
> > (http://www.freebsd.org/cgi/query-pr.cgi?pr=kern/80815):
> >
> > 1) machine suffers from ATA timeouts if ACPI is fully enabled;
> > 2) debug.acpi.disable="pci_link" in /boot/loader.conf eliminates the
> > problem.
> >
> > So, Soren seems to be right: this is interrupt routing problem
> > and not ATA problem.
> >
> > The question is: should I consider the workaround mentioned above as
> > solution? What will I miss if I keep debug.acpi.disable="pci_link" forever?
> 
> I think it's dangerous

What kind of trouble I am "asking for" while using debug.acpi.disable="pci_link"?

> and that you should just disable ACPI altogether if you wish to do that.

I think I do not wish that :-)

This machive has four OS now: FreeBSD 4.11-RELEASE (uses APM to turn power off),
Windows 98SE and Windows XP SP2 (use ACPI, no problems with it).

Would I be allowed to turn power off with 6.1 without ACPI enabled?
Would it be possible not only for 'shutdown -p', but for ACPI power button too?  

>  Can you provide verbose dmesg's for the
> case with pci_link disabled and the case where it is not disabled?

Here comes dmesg.acpi (ACPI is fully enabled):

Copyright (c) 1992-2006 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
	The Regents of the University of California. All rights reserved.
FreeBSD 6.1-RC #13: Sun Apr 30 15:39:40 KRAST 2006
    root@grosbein.pp.ru:/mnt/usr/local/obj6/usr/src/sys/DADV
WARNING: debug.mpsafenet forced to 0 as ipsec requires Giant
WARNING: MPSAFE network stack disabled, expect reduced performance.
MEMGUARD DEBUGGING ALLOCATOR INITIALIZED:
	MEMGUARD map base: 0xc36c9000
	MEMGUARD map limit: 0xc495b000
	MEMGUARD map size: 19472384 (Bytes)
Preloaded elf kernel "/boot/kernel/kernel" at 0xc0925000.
Preloaded elf module "/boot/modules/acpi.ko" at 0xc092516c.
Calibrating clock(s) ... i8254 clock: 1193165 Hz
CLK_USE_I8254_CALIBRATION not specified - using default frequency
Timecounter "i8254" frequency 1193182 Hz quality 0
Calibrating TSC clock ... TSC clock: 902050480 Hz
CPU: Intel Celeron (902.05-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  = 603914240 (575 MB)
Physical memory chunk(s):
0x0000000000001000 - 0x000000000009efff, 647168 bytes (158 pages)
0x0000000000100000 - 0x00000000003fffff, 3145728 bytes (768 pages)
0x0000000000c25000 - 0x0000000023597fff, 580333568 bytes (141683 pages)
avail memory = 581664768 (554 MB)
bios32: Found BIOS32 Service Directory header at 0xc00faef0
bios32: Entry = 0xfb370 (c00fb370)  Rev = 0  Len = 1
pcibios: PCI BIOS entry at 0xf0000+0xb3a0
pnpbios: Found PnP BIOS data at 0xc00fbfc0
pnpbios: Entry = f0000:bfe8  Rev = 1.0
Other BIOS signatures found:
nfslock: pseudo-device
mem: <memory>
Pentium Pro MTRR support enabled
io: <I/O>
null: <null device, zero device>
random: <entropy source, Software, Yarrow>
npx0: [FAST]
npx0: <math processor> on motherboard
npx0: INT 16 interface
acpi0: <AWARD AWRDACPI> 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=71908086)
pcibios: BIOS version 2.10
Found $PIR table, 8 entries at 0xc00fdef0
PCI-Only Interrupts: 9 10 11
Location  Bus Device Pin  Link  IRQs
slot 1      0   15    A   0x60  3 4 5 7 9 10 11 12 14 15
slot 1      0   15    B   0x61  3 4 5 7 9 10 11 12 14 15
slot 1      0   15    C   0x62  3 4 5 7 9 10 11 12 14 15
slot 1      0   15    D   0x63  3 4 5 7 9 10 11 12 14 15
slot 2      0   16    A   0x61  3 4 5 7 9 10 11 12 14 15
slot 2      0   16    B   0x62  3 4 5 7 9 10 11 12 14 15
slot 2      0   16    C   0x63  3 4 5 7 9 10 11 12 14 15
slot 2      0   16    D   0x60  3 4 5 7 9 10 11 12 14 15
slot 3      0   18    A   0x62  3 4 5 7 9 10 11 12 14 15
slot 3      0   18    B   0x63  3 4 5 7 9 10 11 12 14 15
slot 3      0   18    C   0x60  3 4 5 7 9 10 11 12 14 15
slot 3      0   18    D   0x61  3 4 5 7 9 10 11 12 14 15
slot 4      0   19    A   0x63  3 4 5 7 9 10 11 12 14 15
slot 4      0   19    B   0x60  3 4 5 7 9 10 11 12 14 15
slot 4      0   19    C   0x61  3 4 5 7 9 10 11 12 14 15
slot 4      0   19    D   0x62  3 4 5 7 9 10 11 12 14 15
slot 5      0   20    A   0x60  3 4 5 7 9 10 11 12 14 15
slot 5      0   20    B   0x61  3 4 5 7 9 10 11 12 14 15
slot 5      0   20    C   0x62  3 4 5 7 9 10 11 12 14 15
slot 5      0   20    D   0x63  3 4 5 7 9 10 11 12 14 15
slot 6      0   14    A   0x61  3 4 5 7 9 10 11 12 14 15
slot 6      0   14    B   0x62  3 4 5 7 9 10 11 12 14 15
slot 6      0   14    C   0x63  3 4 5 7 9 10 11 12 14 15
slot 6      0   14    D   0x60  3 4 5 7 9 10 11 12 14 15
embedded    0    7    A   0x60  3 4 5 7 9 10 11 12 14 15
embedded    0    7    B   0x61  3 4 5 7 9 10 11 12 14 15
embedded    0    7    C   0x62  3 4 5 7 9 10 11 12 14 15
embedded    0    7    D   0x63  3 4 5 7 9 10 11 12 14 15
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    1    C   0x62  3 4 5 7 9 10 11 12 14 15
embedded    0    1    D   0x63  3 4 5 7 9 10 11 12 14 15
acpi_bus_number: root bus has no _BBN, assuming 0
AcpiOsDerivePciId: bus 0 dev 7 func 0
acpi0: Power Button (fixed)
pci_link0: Links after initial probe:
Index  IRQ  Rtd  Ref  IRQs
    0   11   N     0  3 4 5 6 7 10 11 12 14 15
pci_link0: Links after initial validation:
Index  IRQ  Rtd  Ref  IRQs
    0   11   N     0  3 4 5 6 7 10 11 12 14 15
pci_link0: Links after disable:
Index  IRQ  Rtd  Ref  IRQs
    0  255   N     0  3 4 5 6 7 10 11 12 14 15
pci_link1: Links after initial probe:
Index  IRQ  Rtd  Ref  IRQs
    0    9   N     0  3 4 5 6 7 10 11 12 14 15
pci_link1: Links after initial validation:
Index  IRQ  Rtd  Ref  IRQs
    0    9   N     0  3 4 5 6 7 10 11 12 14 15
pci_link1: Links after disable:
Index  IRQ  Rtd  Ref  IRQs
    0  255   N     0  3 4 5 6 7 10 11 12 14 15
pci_link2: Links after initial probe:
Index  IRQ  Rtd  Ref  IRQs
    0   10   N     0  3 4 5 6 7 10 11 12 14 15
pci_link2: Links after initial validation:
Index  IRQ  Rtd  Ref  IRQs
    0   10   N     0  3 4 5 6 7 10 11 12 14 15
pci_link2: Links after disable:
Index  IRQ  Rtd  Ref  IRQs
    0  255   N     0  3 4 5 6 7 10 11 12 14 15
pci_link3: Links after initial probe:
Index  IRQ  Rtd  Ref  IRQs
    0    9   N     0  3 4 5 6 7 10 11 12 14 15
pci_link3: Links after initial validation:
Index  IRQ  Rtd  Ref  IRQs
    0    9   N     0  3 4 5 6 7 10 11 12 14 15
pci_link3: Links after disable:
Index  IRQ  Rtd  Ref  IRQs
    0  255   N     0  3 4 5 6 7 10 11 12 14 15
ACPI timer: 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 -> 0
Timecounter "ACPI-safe" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x4008-0x400b on acpi0
cpu0: <ACPI CPU> on acpi0
acpi_throttle0: <ACPI CPU Throttling> on cpu0
acpi_throttle0: P_CNT from P_BLK 0x4010
acpi_button0: <Power Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff,0x4000-0x4041,0x5000-0x500f on acpi0
ACPI: Found matching pin for 0.16.INTA at func 0: 9
ACPI: Found matching pin for 0.18.INTA at func 0: 10
ACPI: Found matching pin for 0.19.INTA at func 0: 9
ACPI: Found matching pin for 0.19.INTB at func 1: 11
ACPI: Found matching pin for 0.19.INTC at func 2: 9
ACPI: Found matching pin for 0.7.INTD at func 2: 9
pci0: <ACPI PCI bus> on pcib0
pci0: physical bus=0
found->	vendor=0x8086, dev=0x7190, revid=0x03
	bus=0, slot=0, func=0
	class=06-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0006, statreg=0x2210, cachelnsz=0 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	map[10]: type 3, range 32, base e8000000, size 26, enabled
found->	vendor=0x8086, dev=0x7191, revid=0x03
	bus=0, slot=1, func=0
	class=06-04-00, hdrtype=0x01, mfdev=0
	cmdreg=0x0107, statreg=0x0220, cachelnsz=0 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x88 (34000 ns), maxlat=0x00 (0 ns)
found->	vendor=0x8086, dev=0x7110, revid=0x02
	bus=0, slot=7, 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)
found->	vendor=0x8086, dev=0x7111, revid=0x01
	bus=0, slot=7, func=1
	class=01-01-80, hdrtype=0x00, mfdev=0
	cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	map[20]: type 4, range 32, base 0000f000, size  4, enabled
found->	vendor=0x8086, dev=0x7112, revid=0x01
	bus=0, slot=7, func=2
	class=0c-03-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0005, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=d, irq=9
	map[20]: type 4, range 32, base 0000a000, size  5, enabled
pcib0: matched entry for 0.7.INTD (src \\_SB_.PCI0.ISA_.LNKD:0)
pcib0: slot 7 INTD routed to irq 9 via \\_SB_.PCI0.ISA_.LNKD
found->	vendor=0x8086, dev=0x7113, revid=0x02
	bus=0, slot=7, func=3
	class=06-80-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0003, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	map[90]: type 4, range 32, base 00005000, size  4, enabled
found->	vendor=0x8086, dev=0x1229, revid=0x02
	bus=0, slot=16, func=0
	class=02-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0007, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x08 (2000 ns), maxlat=0x38 (14000 ns)
	intpin=a, irq=9
	map[10]: type 3, range 32, base f0104000, size 12, enabled
	map[14]: type 4, range 32, base 0000a400, size  5, enabled
	map[18]: type 1, range 32, base f0000000, size 20, enabled
pcib0: matched entry for 0.16.INTA (src \\_SB_.PCI0.ISA_.LNKB:0)
pcib0: slot 16 INTA routed to irq 9 via \\_SB_.PCI0.ISA_.LNKB
found->	vendor=0x105a, dev=0x4d68, revid=0x02
	bus=0, slot=18, func=0
	class=01-80-85, hdrtype=0x00, mfdev=0
	cmdreg=0x0007, statreg=0x0430, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x04 (1000 ns), maxlat=0x12 (4500 ns)
	intpin=a, irq=10
	powerspec 1  supports D0 D1 D3  current D0
	map[10]: type 4, range 32, base 0000a800, size  3, enabled
	map[14]: type 4, range 32, base 0000ac00, size  2, enabled
	map[18]: type 4, range 32, base 0000b000, size  3, enabled
	map[1c]: type 4, range 32, base 0000b400, size  2, enabled
	map[20]: type 4, range 32, base 0000b800, size  4, enabled
	map[24]: type 1, range 32, base f0100000, size 14, enabled
pcib0: matched entry for 0.18.INTA (src \\_SB_.PCI0.ISA_.LNKC:0)
pcib0: slot 18 INTA routed to irq 10 via \\_SB_.PCI0.ISA_.LNKC
found->	vendor=0x1106, dev=0x3038, revid=0x61
	bus=0, slot=19, func=0
	class=0c-03-00, hdrtype=0x00, mfdev=1
	cmdreg=0x0007, statreg=0x0210, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=a, irq=9
	powerspec 2  supports D0 D1 D2 D3  current D0
	map[20]: type 4, range 32, base 0000bc00, size  5, enabled
pcib0: matched entry for 0.19.INTA (src \\_SB_.PCI0.ISA_.LNKD:0)
pcib0: slot 19 INTA routed to irq 9 via \\_SB_.PCI0.ISA_.LNKD
found->	vendor=0x1106, dev=0x3038, revid=0x61
	bus=0, slot=19, func=1
	class=0c-03-00, hdrtype=0x00, mfdev=1
	cmdreg=0x0007, statreg=0x0210, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=b, irq=11
	powerspec 2  supports D0 D1 D2 D3  current D0
	map[20]: type 4, range 32, base 0000c000, size  5, enabled
pcib0: matched entry for 0.19.INTB (src \\_SB_.PCI0.ISA_.LNKA:0)
pcib0: slot 19 INTB routed to irq 11 via \\_SB_.PCI0.ISA_.LNKA
found->	vendor=0x1106, dev=0x3104, revid=0x63
	bus=0, slot=19, func=2
	class=0c-03-20, hdrtype=0x00, mfdev=1
	cmdreg=0x0007, statreg=0x0210, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	intpin=c, irq=9
	powerspec 2  supports D0 D1 D2 D3  current D0
	map[10]: type 1, range 32, base f0105000, size  8, enabled
pcib0: matched entry for 0.19.INTC (src \\_SB_.PCI0.ISA_.LNKB:0)
pcib0: slot 19 INTC routed to irq 9 via \\_SB_.PCI0.ISA_.LNKB
found->	vendor=0x1106, dev=0x3044, revid=0x46
	bus=0, slot=19, func=3
	class=0c-00-10, hdrtype=0x00, mfdev=0
	cmdreg=0x0007, statreg=0x0210, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x20 (8000 ns)
	intpin=a, irq=9
	powerspec 2  supports D0 D2 D3  current D0
	map[10]: type 1, range 32, base f0106000, size 11, enabled
	map[14]: type 4, range 32, base 0000c400, size  7, enabled
pcib0: matched entry for 0.19.INTA (src \\_SB_.PCI0.ISA_.LNKD:0)
pcib0: slot 19 INTA routed to irq 9 via \\_SB_.PCI0.ISA_.LNKD
agp0: <Intel 82443BX (440 BX) host to PCI bridge> mem 0xe8000000-0xebffffff at device 0.0 on pci0
agp0: Reserved 0x4000000 bytes for rid 0x10 type 3 at 0xe8000000
agp0: allocating GATT for aperture of size 64M
pcib1: <PCI-PCI bridge> at device 1.0 on pci0
pcib1:   secondary bus     1
pcib1:   subordinate bus   1
pcib1:   I/O decode        0x9000-0x9fff
pcib1:   memory decode     0xec000000-0xedffffff
pcib1:   prefetched decode 0xd8000000-0xe7ffffff
pci1: <PCI bus> on pcib1
pci1: physical bus=1
found->	vendor=0x1002, dev=0x5961, revid=0x01
	bus=1, slot=0, func=0
	class=03-00-00, hdrtype=0x00, mfdev=1
	cmdreg=0x0007, statreg=0x02b0, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x08 (2000 ns), maxlat=0x00 (0 ns)
	intpin=a, irq=11
	powerspec 2  supports D0 D1 D2 D3  current D0
	map[10]: type 3, range 32, base d8000000, size 27, enabled
pcib1: (null) requested memory range 0xd8000000-0xdfffffff: good
	map[14]: type 4, range 32, base 00009000, size  8, enabled
pcib1: (null) requested I/O range 0x9000-0x90ff: in range
	map[18]: type 1, range 32, base ed000000, size 16, enabled
pcib1: (null) requested memory range 0xed000000-0xed00ffff: good
pcib0: matched entry for 0.1.INTA (src \\_SB_.PCI0.ISA_.LNKA:0)
pcib0: slot 1 INTA routed to irq 11 via \\_SB_.PCI0.ISA_.LNKA
pcib1: slot 0 INTA is routed to irq 11
found->	vendor=0x1002, dev=0x5941, revid=0x01
	bus=1, slot=0, func=1
	class=03-80-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0007, statreg=0x02b0, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x08 (2000 ns), maxlat=0x00 (0 ns)
	powerspec 2  supports D0 D1 D2 D3  current D0
	map[10]: type 3, range 32, base e0000000, size 27, enabled
pcib1: (null) requested memory range 0xe0000000-0xe7ffffff: good
	map[14]: type 1, range 32, base ed010000, size 16, enabled
pcib1: (null) requested memory range 0xed010000-0xed01ffff: good
drm0: <ATI Radeon RV280 9200 SE> port 0x9000-0x90ff mem 0xd8000000-0xdfffffff,0xed000000-0xed00ffff irq 11 at device 0.0 on pci1
info: [drm] AGP at 0xe8000000 64MB
info: [drm] Initialized radeon 1.19.0 20050911
pci1: <display> at device 0.1 (no driver attached)
isab0: <PCI-ISA bridge> at device 7.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel PIIX4 UDMA33 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xf000-0xf00f at device 7.1 on pci0
atapci0: Reserved 0x10 bytes for rid 0x20 type 4 at 0xf000
ata0: <ATA 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=00 ostat1=00
ata0: stat0=0x00 err=0x00 lsb=0x00 msb=0x00
ata0: stat1=0x00 err=0x00 lsb=0x00 msb=0x00
ata0: reset tp2 stat0=00 stat1=00 devices=0x0
ata0: [MPSAFE]
ata1: <ATA 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=00
ata1: stat0=0x00 err=0x01 lsb=0x14 msb=0xeb
ata1: stat1=0x00 err=0x04 lsb=0x00 msb=0x02
ata1: reset tp2 stat0=00 stat1=00 devices=0x4<ATAPI_MASTER>
ata1: [MPSAFE]
uhci0: <Intel 82371AB/EB (PIIX4) USB controller> port 0xa000-0xa01f irq 9 at device 7.2 on pci0
uhci0: Reserved 0x20 bytes for rid 0x20 type 4 at 0xa000
uhci0: [GIANT-LOCKED]
usb0: <Intel 82371AB/EB (PIIX4) USB controller> 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: <bridge> at device 7.3 (no driver attached)
fxp0: <Intel 82557 Pro/100 Ethernet> port 0xa400-0xa41f mem 0xf0104000-0xf0104fff,0xf0000000-0xf00fffff irq 9 at device 16.0 on pci0
fxp0: Reserved 0x1000 bytes for rid 0x10 type 3 at 0xf0104000
fxp0: using memory space register mapping
fxp0: PCI IDs: 8086 1229 8086 0001 0002
fxp0: Dynamic Standby mode is disabled
miibus0: <MII bus> on fxp0
inphy0: <i82555 10/100 media interface> on miibus0
inphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
fxp0: bpf attached
fxp0: Ethernet address: 00:a0:c9:89:95:1f
fxp0: [GIANT-LOCKED]
atapci1: <Promise PDC20268 UDMA100 controller> port 0xa800-0xa807,0xac00-0xac03,0xb000-0xb007,0xb400-0xb403,0xb800-0xb80f mem 0xf0100000-0xf0103fff irq 10 at device 18.0 on pci0
atapci1: Reserved 0x10 bytes for rid 0x20 type 4 at 0xb800
atapci1: [MPSAFE]
ata2: <ATA channel 0> on atapci1
atapci1: Reserved 0x8 bytes for rid 0x10 type 4 at 0xa800
atapci1: Reserved 0x4 bytes for rid 0x14 type 4 at 0xac00
ata2: reset tp1 mask=03 ostat0=50 ostat1=00
ata2: stat0=0x50 err=0x01 lsb=0x00 msb=0x00
ata2: stat1=0x00 err=0x01 lsb=0x00 msb=0x00
ata2: reset tp2 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ata2: [MPSAFE]
ata3: <ATA channel 1> on atapci1
atapci1: Reserved 0x8 bytes for rid 0x18 type 4 at 0xb000
atapci1: Reserved 0x4 bytes for rid 0x1c type 4 at 0xb400
ata3: reset tp1 mask=03 ostat0=50 ostat1=00
ata3: stat0=0x50 err=0x01 lsb=0x00 msb=0x00
ata3: stat1=0x00 err=0x01 lsb=0x00 msb=0x00
ata3: reset tp2 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ata3: [MPSAFE]
uhci1: <VIA 83C572 USB controller> port 0xbc00-0xbc1f irq 9 at device 19.0 on pci0
uhci1: Reserved 0x20 bytes for rid 0x20 type 4 at 0xbc00
uhci1: [GIANT-LOCKED]
usb1: <VIA 83C572 USB controller> on uhci1
usb1: USB revision 1.0
uhub1: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
uhci2: <VIA 83C572 USB controller> port 0xc000-0xc01f irq 11 at device 19.1 on pci0
uhci2: Reserved 0x20 bytes for rid 0x20 type 4 at 0xc000
uhci2: [GIANT-LOCKED]
usb2: <VIA 83C572 USB controller> on uhci2
usb2: USB revision 1.0
uhub2: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
ehci0: <VIA VT6202 USB 2.0 controller> mem 0xf0105000-0xf01050ff irq 9 at device 19.2 on pci0
ehci0: Reserved 0x100 bytes for rid 0x10 type 3 at 0xf0105000
ehci0: [GIANT-LOCKED]
ehci0: Dropped interrupts workaround enabled
usb3: EHCI version 1.0
usb3: companion controllers, 2 ports each: usb1 usb2
usb3: <VIA VT6202 USB 2.0 controller> on ehci0
usb3: USB revision 2.0
uhub3: VIA EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub3: 4 ports with 4 removable, self powered
fwohci0: <VIA Fire II (VT6306)> port 0xc400-0xc47f mem 0xf0106000-0xf01067ff irq 9 at device 19.3 on pci0
fwohci0: Reserved 0x800 bytes for rid 0x10 type 3 at 0xf0106000
fwohci0: [GIANT-LOCKED]
fwohci0: OHCI version 1.10 (ROM=1)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 00:11:06:66:00:00:0d:c9
fwohci0: Phy 1394a available S400, 3 ports.
fwohci0: Link S400, max_rec 2048 bytes.
firewire0: <IEEE1394(FireWire) bus> on fwohci0
sbp0: <SBP-2/SCSI over FireWire> on firewire0
fwe0: <Ethernet over FireWire> on firewire0
if_fwe0: Fake Ethernet address: 02:11:06:00:0d:c9
fwe0: bpf attached
fwe0: Ethernet address: 02:11:06:00:0d:c9
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)
acpi_tz0: <Thermal Zone> on acpi0
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: ic_type 90 part_id 80
fdc0: [MPSAFE]
fdc0: [FAST]
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
sio0: irq maps: 0x201 0x211 0x201 0x201
sio0: <16550A-compatible COM port> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
sio0: type 16550A
sio1: irq maps: 0x201 0x209 0x201 0x201
sio1: <16550A-compatible COM port> port 0x2f8-0x2ff irq 3 on acpi0
sio1: type 16550A
ppc0: using extended I/O port range
ppc0: ECP SPP ECP+EPP SPP
ppc0: <ECP parallel printer port> port 0x378-0x37f,0x778-0x77b irq 7 drq 3 on acpi0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/16 bytes threshold
ppbus0: <Parallel port bus> on ppc0
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
psmcpnp0: <PS/2 mouse port> irq 12 on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
atkbd: the current kbd controller command byte 0047
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:0047
psm0: found NetMouse/NetScroll Optical
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model NetMouse/NetScroll Optical, device ID 0-00, 3 buttons
psm0: config:00000000, flags:00000008, packet size:4
psm0: syncmask:08, syncbits:00
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
sc: sc0 already exists; skipping it
sio: sio0 already exists; skipping it
sio: sio1 already exists; skipping it
vga: vga0 already exists; skipping it
pnp_identify: Trying Read_Port at 203
YMH0021: start dependent (0)
YMH0021: adding io range 0x220-0x22f, size=0x10, align=0x10
YMH0021: adding io range 0x530-0x537, size=0x8, align=0x8
YMH0021: adding io range 0x388-0x38f, size=0x8, align=0x8
YMH0021: adding io range 0x330-0x331, size=0x2, align=0x2
YMH0021: adding io range 0x370-0x371, size=0x2, align=0x2
YMH0021: adding irq mask 0x20
YMH0021: adding dma mask 0x1
YMH0021: adding dma mask 0x2
YMH0021: start dependent (1)
YMH0021: adding io range 0x240-0x24f, size=0x10, align=0x10
YMH0021: adding io range 0xe80-0xe87, size=0x8, align=0x8
YMH0021: adding io range 0x388-0x38f, size=0x8, align=0x8
YMH0021: adding io range 0x300-0x301, size=0x2, align=0x2
YMH0021: adding io range 0x100-0xfff, size=0x2, align=0x2
YMH0021: adding irq mask 0xea0
YMH0021: adding dma mask 0xb
YMH0021: adding dma mask 0xb
YMH0021: start dependent (2)
YMH0021: adding io range 0x220-0x28f, size=0x10, align=0x10
YMH0021: adding io range 0x530-0xf4f, size=0x8, align=0x8
YMH0021: adding io range 0x388-0x3ff, size=0x8, align=0x8
YMH0021: adding io range 0x300-0x335, size=0x2, align=0x2
YMH0021: adding io range 0x100-0xfff, size=0x2, align=0x2
YMH0021: adding irq mask 0xea8
YMH0021: adding dma mask 0xb
YMH0021: adding dma mask 0xb
YMH0021: end dependent
YMH0022: start dependent (0)
YMH0022: adding io range 0x201-0x201, size=0x1, align=0x1
YMH0022: start dependent (2)
YMH0022: adding io range 0x201-0x211, size=0x1, align=0x10
YMH0022: end dependent
PNP Identify complete
isa_probe_children: disabling PnP devices
isa_probe_children: probing non-PnP devices
pmtimer0 on isa0
orm0: <ISA Option ROMs> at iomem 0xc0000-0xccfff,0xd0000-0xd27ff on isa0
fb: new array size 4
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sc0: fb0, kbd0, terminal emulator: sc (syscons terminal)
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff 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)
sio2: not probed (disabled)
sio3: not probed (disabled)
sn0: not probed (disabled)
vt0: not probed (disabled)
isa_probe_children: probing PnP devices
pcm0: <Yamaha OPL-SAx> at port 0x220-0x22f,0x530-0x537,0x388-0x38f,0x330-0x331,0x370-0x371 irq 5 drq 0,1 on isa0
Yamaha: ver 0x21 DMA config 0x83
pcm0: [GIANT-LOCKED]
pcm0: sndbuf_setmap ff4000, 1000; 0xdbc11000 -> ff4000
pcm0: sndbuf_setmap ff3000, 1000; 0xdbc12000 -> ff3000
unknown: <OPL3-SA3 Sound Board> failed to probe at port 0x201 on isa0
uscanner0: Hewlett-Packard HP ScanJet 2200C, rev 1.10/1.00, addr 2
ugen0: American Power Conversion Back-UPS 500 FW: 6.5.I USB FW: c1, rev 1.10/1.00, addr 3
Device configuration finished.
linprocfs registered
procfs registered
Timecounter "TSC" frequency 902050480 Hz quality 800
Timecounters tick every 1.000 msec
Linux ELF exec handler installed
IPsec: Initialized Security Association Processing.
DUMMYNET with IPv6 initialized (040826)
ipfw2 (+ipv6) initialized, divert loadable, rule-based forwarding enabled, default to deny, logging disabled
lo0: bpf attached
ata1-master: pio=PIO4 wdma=WDMA2 udma=UDMA33 cable=40 wire
acd0: setting PIO4 on PIIX4 chip
acd0: setting UDMA33 on PIIX4 chip
acd0: <NEC DVD RW ND-3500AG/2.18> DVDR drive at ata1 as master
acd0: read 8268KB/s (8268KB/s) write 8268KB/s (8268KB/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: CD-R 120mm data disc
ata2-master: pio=PIO4 wdma=WDMA2 udma=UDMA100 cable=80 wire
ad4: setting PIO4 on PDC20268 chip
ad4: setting UDMA100 on PDC20268 chip
ad4: 76319MB <WDC WD800JB-00FMA0 13.03G13> at ata2-master UDMA100
ad4: 156301488 sectors [155061C/16H/63S] 16 sectors/interrupt 1 depth queue
ata3-master: pio=PIO4 wdma=WDMA2 udma=UDMA100 cable=80 wire
ad6: setting PIO4 on PDC20268 chip
ad6: setting UDMA100 on PDC20268 chip
ad6: 39266MB <IC35L040AVER07 0 ER4OA45A> at ata3-master UDMA100
ad6: 80418240 sectors [79780C/16H/63S] 16 sectors/interrupt 1 depth queue
GEOM: new disk ad4
GEOM: new disk ad6
(probe0:sbp0:0:0:0): error 22
(probe0:sbp0:0:0:0): Unretryable Error
(probe1:sbp0:0:1:0): error 22
(probe1:sbp0:0:1:0): Unretryable Error
(probe2:sbp0:0:2:0): error 22
(probe2:sbp0:0:2:0): Unretryable Error
(probe3:sbp0:0:3:0): error 22
(probe3:sbp0:0:3:0): Unretryable Error
(probe4:sbp0:0:4:0): error 22
(probe4:sbp0:0:4:0): Unretryable Error
(probe5:sbp0:0:5:0): error 22
(probe5:sbp0:0:5:0): Unretryable Error
(probe6:sbp0:0:6:0): error 22
(probe6:sbp0:0:6:0): Unretryable Error
Trying to mount root from ufs:/dev/ad6s2a
start_init: trying /sbin/init
gif0: bpf attached
tun0: bpf attached
ata2: reiniting channel ..
ata2: reset tp1 mask=03 ostat0=50 ostat1=00
ata2: stat0=0x50 err=0x01 lsb=0x00 msb=0x00
ata2: stat1=0x00 err=0x01 lsb=0x00 msb=0x00
ata2: reset tp2 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
ad4: setting PIO4 on PDC20268 chip
ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
ad4: setting UDMA100 on PDC20268 chip
ad4: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing request directly
ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing request directly
ad4: WARNING - SET_MULTI taskqueue timeout - completing request directly
ata2: reinit done ..
ad4: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=100861733
ata3: reiniting channel ..
ata3: reset tp1 mask=03 ostat0=50 ostat1=00
ata3: stat0=0x50 err=0x01 lsb=0x00 msb=0x00
ata3: stat1=0x00 err=0x01 lsb=0x00 msb=0x00
ata3: reset tp2 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
ad6: setting PIO4 on PDC20268 chip
ad6: setting UDMA100 on PDC20268 chip
ata3: reinit done ..
ad6: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=62118768
fxp0: promiscuous mode enabled
link_elf: symbol cd9660_wchar2char undefined
link_elf: symbol lminor undefined
psm: ENABLE_DEV return code:00fa
psm: SEND_AUX_DEV_STATUS return code:00fa
psm: status 20 02 64
psm: SET_SAMPLING_RATE (100) 00fa
psm: SET_RESOLUTION (3) 00fa
psm: SET_SCALING11 return code:00fa
psm: SEND_AUX_DEV_STATUS return code:00fa
psm: status 20 03 64
fxp0: promiscuous mode disabled

Now there is unified diff with dmesg.pcilink (pci_link is disabled):

--- dmesg.acpi	Sun Apr 30 16:42:09 2006
+++ dmesg.pcilink	Sun Apr 30 16:42:09 2006
@@ -11,10 +11,10 @@
 	MEMGUARD map size: 19472384 (Bytes)
 Preloaded elf kernel "/boot/kernel/kernel" at 0xc0925000.
 Preloaded elf module "/boot/modules/acpi.ko" at 0xc092516c.
-Calibrating clock(s) ... i8254 clock: 1193165 Hz
+Calibrating clock(s) ... i8254 clock: 1193166 Hz
 CLK_USE_I8254_CALIBRATION not specified - using default frequency
 Timecounter "i8254" frequency 1193182 Hz quality 0
-Calibrating TSC clock ... TSC clock: 902050480 Hz
+Calibrating TSC clock ... TSC clock: 902050201 Hz
 CPU: Intel Celeron (902.05-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>
@@ -83,42 +83,6 @@
 acpi_bus_number: root bus has no _BBN, assuming 0
 AcpiOsDerivePciId: bus 0 dev 7 func 0
 acpi0: Power Button (fixed)
-pci_link0: Links after initial probe:
-Index  IRQ  Rtd  Ref  IRQs
-    0   11   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link0: Links after initial validation:
-Index  IRQ  Rtd  Ref  IRQs
-    0   11   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link0: Links after disable:
-Index  IRQ  Rtd  Ref  IRQs
-    0  255   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link1: Links after initial probe:
-Index  IRQ  Rtd  Ref  IRQs
-    0    9   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link1: Links after initial validation:
-Index  IRQ  Rtd  Ref  IRQs
-    0    9   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link1: Links after disable:
-Index  IRQ  Rtd  Ref  IRQs
-    0  255   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link2: Links after initial probe:
-Index  IRQ  Rtd  Ref  IRQs
-    0   10   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link2: Links after initial validation:
-Index  IRQ  Rtd  Ref  IRQs
-    0   10   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link2: Links after disable:
-Index  IRQ  Rtd  Ref  IRQs
-    0  255   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link3: Links after initial probe:
-Index  IRQ  Rtd  Ref  IRQs
-    0    9   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link3: Links after initial validation:
-Index  IRQ  Rtd  Ref  IRQs
-    0    9   N     0  3 4 5 6 7 10 11 12 14 15
-pci_link3: Links after disable:
-Index  IRQ  Rtd  Ref  IRQs
-    0  255   N     0  3 4 5 6 7 10 11 12 14 15
 ACPI timer: 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 -> 0
 Timecounter "ACPI-safe" frequency 3579545 Hz quality 1000
 acpi_timer0: <24-bit timer at 3.579545MHz> port 0x4008-0x400b on acpi0
@@ -127,12 +91,30 @@
 acpi_throttle0: P_CNT from P_BLK 0x4010
 acpi_button0: <Power Button> on acpi0
 pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff,0x4000-0x4041,0x5000-0x500f on acpi0
-ACPI: Found matching pin for 0.16.INTA at func 0: 9
-ACPI: Found matching pin for 0.18.INTA at func 0: 10
-ACPI: Found matching pin for 0.19.INTA at func 0: 9
-ACPI: Found matching pin for 0.19.INTB at func 1: 11
-ACPI: Found matching pin for 0.19.INTC at func 2: 9
-ACPI: Found matching pin for 0.7.INTD at func 2: 9
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKA
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKB
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKC
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKD
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKB
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKC
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKD
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKA
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKC
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKD
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKA
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKB
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKD
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKA
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKB
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKC
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKA
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKB
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKC
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKD
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKA
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKB
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKC
+pcib0: failed to force attach of \\_SB_.PCI0.ISA_.LNKD
 pci0: <ACPI PCI bus> on pcib0
 pci0: physical bus=0
 found->	vendor=0x8086, dev=0x7190, revid=0x03
@@ -165,7 +147,6 @@
 	intpin=d, irq=9
 	map[20]: type 4, range 32, base 0000a000, size  5, enabled
 pcib0: matched entry for 0.7.INTD (src \\_SB_.PCI0.ISA_.LNKD:0)
-pcib0: slot 7 INTD routed to irq 9 via \\_SB_.PCI0.ISA_.LNKD
 found->	vendor=0x8086, dev=0x7113, revid=0x02
 	bus=0, slot=7, func=3
 	class=06-80-00, hdrtype=0x00, mfdev=0
@@ -182,7 +163,6 @@
 	map[14]: type 4, range 32, base 0000a400, size  5, enabled
 	map[18]: type 1, range 32, base f0000000, size 20, enabled
 pcib0: matched entry for 0.16.INTA (src \\_SB_.PCI0.ISA_.LNKB:0)
-pcib0: slot 16 INTA routed to irq 9 via \\_SB_.PCI0.ISA_.LNKB
 found->	vendor=0x105a, dev=0x4d68, revid=0x02
 	bus=0, slot=18, func=0
 	class=01-80-85, hdrtype=0x00, mfdev=0
@@ -197,7 +177,6 @@
 	map[20]: type 4, range 32, base 0000b800, size  4, enabled
 	map[24]: type 1, range 32, base f0100000, size 14, enabled
 pcib0: matched entry for 0.18.INTA (src \\_SB_.PCI0.ISA_.LNKC:0)
-pcib0: slot 18 INTA routed to irq 10 via \\_SB_.PCI0.ISA_.LNKC
 found->	vendor=0x1106, dev=0x3038, revid=0x61
 	bus=0, slot=19, func=0
 	class=0c-03-00, hdrtype=0x00, mfdev=1
@@ -207,7 +186,6 @@
 	powerspec 2  supports D0 D1 D2 D3  current D0
 	map[20]: type 4, range 32, base 0000bc00, size  5, enabled
 pcib0: matched entry for 0.19.INTA (src \\_SB_.PCI0.ISA_.LNKD:0)
-pcib0: slot 19 INTA routed to irq 9 via \\_SB_.PCI0.ISA_.LNKD
 found->	vendor=0x1106, dev=0x3038, revid=0x61
 	bus=0, slot=19, func=1
 	class=0c-03-00, hdrtype=0x00, mfdev=1
@@ -217,7 +195,6 @@
 	powerspec 2  supports D0 D1 D2 D3  current D0
 	map[20]: type 4, range 32, base 0000c000, size  5, enabled
 pcib0: matched entry for 0.19.INTB (src \\_SB_.PCI0.ISA_.LNKA:0)
-pcib0: slot 19 INTB routed to irq 11 via \\_SB_.PCI0.ISA_.LNKA
 found->	vendor=0x1106, dev=0x3104, revid=0x63
 	bus=0, slot=19, func=2
 	class=0c-03-20, hdrtype=0x00, mfdev=1
@@ -227,7 +204,6 @@
 	powerspec 2  supports D0 D1 D2 D3  current D0
 	map[10]: type 1, range 32, base f0105000, size  8, enabled
 pcib0: matched entry for 0.19.INTC (src \\_SB_.PCI0.ISA_.LNKB:0)
-pcib0: slot 19 INTC routed to irq 9 via \\_SB_.PCI0.ISA_.LNKB
 found->	vendor=0x1106, dev=0x3044, revid=0x46
 	bus=0, slot=19, func=3
 	class=0c-00-10, hdrtype=0x00, mfdev=0
@@ -238,7 +214,6 @@
 	map[10]: type 1, range 32, base f0106000, size 11, enabled
 	map[14]: type 4, range 32, base 0000c400, size  7, enabled
 pcib0: matched entry for 0.19.INTA (src \\_SB_.PCI0.ISA_.LNKD:0)
-pcib0: slot 19 INTA routed to irq 9 via \\_SB_.PCI0.ISA_.LNKD
 agp0: <Intel 82443BX (440 BX) host to PCI bridge> mem 0xe8000000-0xebffffff at device 0.0 on pci0
 agp0: Reserved 0x4000000 bytes for rid 0x10 type 3 at 0xe8000000
 agp0: allocating GATT for aperture of size 64M
@@ -264,8 +239,6 @@
 	map[18]: type 1, range 32, base ed000000, size 16, enabled
 pcib1: (null) requested memory range 0xed000000-0xed00ffff: good
 pcib0: matched entry for 0.1.INTA (src \\_SB_.PCI0.ISA_.LNKA:0)
-pcib0: slot 1 INTA routed to irq 11 via \\_SB_.PCI0.ISA_.LNKA
-pcib1: slot 0 INTA is routed to irq 11
 found->	vendor=0x1002, dev=0x5941, revid=0x01
 	bus=1, slot=0, func=1
 	class=03-80-00, hdrtype=0x00, mfdev=0
@@ -494,7 +467,7 @@
 Device configuration finished.
 linprocfs registered
 procfs registered
-Timecounter "TSC" frequency 902050480 Hz quality 800
+Timecounter "TSC" frequency 902050201 Hz quality 800
 Timecounters tick every 1.000 msec
 Linux ELF exec handler installed
 IPsec: Initialized Security Association Processing.
@@ -523,47 +496,24 @@
 ad6: 80418240 sectors [79780C/16H/63S] 16 sectors/interrupt 1 depth queue
 GEOM: new disk ad4
 GEOM: new disk ad6
-(probe0:sbp0:0:0:0): error 22
-(probe0:sbp0:0:0:0): Unretryable Error
 (probe1:sbp0:0:1:0): error 22
 (probe1:sbp0:0:1:0): Unretryable Error
 (probe2:sbp0:0:2:0): error 22
 (probe2:sbp0:0:2:0): Unretryable Error
-(probe3:sbp0:0:3:0): error 22
-(probe3:sbp0:0:3:0): Unretryable Error
-(probe4:sbp0:0:4:0): error 22
-(probe4:sbp0:0:4:0): Unretryable Error
 (probe5:sbp0:0:5:0): error 22
 (probe5:sbp0:0:5:0): Unretryable Error
 (probe6:sbp0:0:6:0): error 22
 (probe6:sbp0:0:6:0): Unretryable Error
+(probe0:sbp0:0:0:0): error 22
+(probe0:sbp0:0:0:0): Unretryable Error
+(probe3:sbp0:0:3:0): error 22
+(probe3:sbp0:0:3:0): Unretryable Error
+(probe4:sbp0:0:4:0): error 22
+(probe4:sbp0:0:4:0): Unretryable Error
 Trying to mount root from ufs:/dev/ad6s2a
 start_init: trying /sbin/init
 gif0: bpf attached
 tun0: bpf attached
-ata2: reiniting channel ..
-ata2: reset tp1 mask=03 ostat0=50 ostat1=00
-ata2: stat0=0x50 err=0x01 lsb=0x00 msb=0x00
-ata2: stat1=0x00 err=0x01 lsb=0x00 msb=0x00
-ata2: reset tp2 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
-ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
-ad4: setting PIO4 on PDC20268 chip
-ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
-ad4: setting UDMA100 on PDC20268 chip
-ad4: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing request directly
-ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing request directly
-ad4: WARNING - SET_MULTI taskqueue timeout - completing request directly
-ata2: reinit done ..
-ad4: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=100861733
-ata3: reiniting channel ..
-ata3: reset tp1 mask=03 ostat0=50 ostat1=00
-ata3: stat0=0x50 err=0x01 lsb=0x00 msb=0x00
-ata3: stat1=0x00 err=0x01 lsb=0x00 msb=0x00
-ata3: reset tp2 stat0=50 stat1=00 devices=0x1<ATA_MASTER>
-ad6: setting PIO4 on PDC20268 chip
-ad6: setting UDMA100 on PDC20268 chip
-ata3: reinit done ..
-ad6: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=62118768
 fxp0: promiscuous mode enabled
 link_elf: symbol cd9660_wchar2char undefined
 link_elf: symbol lminor undefined


Eugene Grosbein
Comment 17 John Baldwin freebsd_committer freebsd_triage 2006-05-01 20:17:06 UTC
On Sunday 30 April 2006 04:44, Eugene Grosbein wrote:
> On Sat, Apr 29, 2006 at 10:54:09PM -0400, John Baldwin wrote:
> 
> > > With 6.1-RC of today I have the same sympthoms as with 5.4-RELEASE
> > > (http://www.freebsd.org/cgi/query-pr.cgi?pr=kern/80815):
> > >
> > > 1) machine suffers from ATA timeouts if ACPI is fully enabled;
> > > 2) debug.acpi.disable="pci_link" in /boot/loader.conf eliminates the
> > > problem.
> > >
> > > So, Soren seems to be right: this is interrupt routing problem
> > > and not ATA problem.
> > >
> > > The question is: should I consider the workaround mentioned above as
> > > solution? What will I miss if I keep debug.acpi.disable="pci_link" forever?
> > 
> > I think it's dangerous
> 
> What kind of trouble I am "asking for" while using debug.acpi.disable="pci_link"?

Because ACPI is rather intertwined, so it is expecting to tell the OS how to
route interrupts in a certain way, and if you enable ACPI the BIOS is expecting
you to use it completely.

> > and that you should just disable ACPI altogether if you wish to do that.
> 
> I think I do not wish that :-)

Do so at your own risk then.

> This machive has four OS now: FreeBSD 4.11-RELEASE (uses APM to turn power off),
> Windows 98SE and Windows XP SP2 (use ACPI, no problems with it).
> 
> Would I be allowed to turn power off with 6.1 without ACPI enabled?
> Would it be possible not only for 'shutdown -p', but for ACPI power button too? 

No, the power button only works with ACPI.  shutdown -p can work using apm
as on 4.x.
  
> >  Can you provide verbose dmesg's for the
> > case with pci_link disabled and the case where it is not disabled?
> 
> Here comes dmesg.acpi (ACPI is fully enabled):

Well, all the IRQs are the same and none of the interrupts were changed to
be edge triggered or anything like that, so it's not a problem with
interrupt routing.  If the interrupt routing were busted, the IRQ numbers
would be different.  All the pci_link devices do is help the OS figure out
which IRQ number a device uses.  If those numbers are all the same, then
interrupt routing is not the issue.

-- 
John Baldwin <jhb@FreeBSD.org>  <><  http://www.FreeBSD.org/~jhb/
"Power Users Use the Power to Serve"  =  http://www.FreeBSD.org
Comment 18 Eugene Grosbein 2006-05-02 04:37:21 UTC
John Baldwin wrote:
> 
> On Sunday 30 April 2006 04:44, Eugene Grosbein wrote:
> > On Sat, Apr 29, 2006 at 10:54:09PM -0400, John Baldwin wrote:
> >
> > > > With 6.1-RC of today I have the same sympthoms as with 5.4-RELEASE
> > > > (http://www.freebsd.org/cgi/query-pr.cgi?pr=kern/80815):
> > > >
> > > > 1) machine suffers from ATA timeouts if ACPI is fully enabled;
> > > > 2) debug.acpi.disable="pci_link" in /boot/loader.conf eliminates the
> > > > problem.
> > > >
> > > > So, Soren seems to be right: this is interrupt routing problem
> > > > and not ATA problem.
> > > >
> > > > The question is: should I consider the workaround mentioned above as
> > > > solution? What will I miss if I keep debug.acpi.disable="pci_link" forever?
> > >
> > > I think it's dangerous
> >
> > What kind of trouble I am "asking for" while using debug.acpi.disable="pci_link"?
> 
> Because ACPI is rather intertwined, so it is expecting to tell the OS how to
> route interrupts in a certain way, and if you enable ACPI the BIOS is expecting
> you to use it completely.
> 
> > > and that you should just disable ACPI altogether if you wish to do that.
> >
> > I think I do not wish that :-)
> 
> Do so at your own risk then.
> 
> > This machive has four OS now: FreeBSD 4.11-RELEASE (uses APM to turn power off),
> > Windows 98SE and Windows XP SP2 (use ACPI, no problems with it).
> >
> > Would I be allowed to turn power off with 6.1 without ACPI enabled?
> > Would it be possible not only for 'shutdown -p', but for ACPI power button too?
> 
> No, the power button only works with ACPI.  shutdown -p can work using apm
> as on 4.x.
> 
> > >  Can you provide verbose dmesg's for the
> > > case with pci_link disabled and the case where it is not disabled?
> >
> > Here comes dmesg.acpi (ACPI is fully enabled):
> 
> Well, all the IRQs are the same and none of the interrupts were changed to
> be edge triggered or anything like that, so it's not a problem with
> interrupt routing.  If the interrupt routing were busted, the IRQ numbers
> would be different.  All the pci_link devices do is help the OS figure out
> which IRQ number a device uses.  If those numbers are all the same, then
> interrupt routing is not the issue.

Does it mean that it is safe for this machine to use pci_link really?

And if it's not interrupt routing problem, what else pci_link affects to?

Eugene Grosbein
Comment 19 John Baldwin freebsd_committer freebsd_triage 2006-05-02 19:59:16 UTC
On Monday 01 May 2006 23:37, Eugene Grosbein wrote:
> John Baldwin wrote:
> > Well, all the IRQs are the same and none of the interrupts were changed to
> > be edge triggered or anything like that, so it's not a problem with
> > interrupt routing.  If the interrupt routing were busted, the IRQ numbers
> > would be different.  All the pci_link devices do is help the OS figure out
> > which IRQ number a device uses.  If those numbers are all the same, then
> > interrupt routing is not the issue.
> 
> And if it's not interrupt routing problem, what else pci_link affects to?

That's all pci_link effects. :(  One thing you can try is to turn
off the code to disable each of the links during boot.  Something like
this:

Index: acpi_pci_link.c
===================================================================
RCS file: /usr/cvs/src/sys/dev/acpica/acpi_pci_link.c,v
retrieving revision 1.53
diff -u -r1.53 acpi_pci_link.c
--- acpi_pci_link.c	6 Jan 2006 16:14:32 -0000	1.53
+++ acpi_pci_link.c	2 May 2006 18:58:47 -0000
@@ -517,6 +517,7 @@
 	for (i = 0; i < sc->pl_num_links; i++)
 		sc->pl_links[i].l_initial_irq = sc->pl_links[i].l_irq;
 
+#if 0
 	/*
 	 * Try to disable this link.  If successful, set the current IRQ to
 	 * zero and flags to indicate this link is not routed.  If we can't
@@ -528,13 +529,16 @@
 		for (i = 0; i < sc->pl_num_links; i++)
 			sc->pl_links[i].l_irq = PCI_INVALID_IRQ;
 	else
+#endif
 		for (i = 0; i < sc->pl_num_links; i++)
 			if (PCI_INTERRUPT_VALID(sc->pl_links[i].l_irq))
 				sc->pl_links[i].l_routed = TRUE;
+#if 0
 	if (bootverbose) {
 		device_printf(dev, "Links after disable:\n");
 		acpi_pci_link_dump(sc);
 	}
+#endif
 	ACPI_SERIAL_END(pci_link);
 	return (0);
 fail:


-- 
John Baldwin <jhb@FreeBSD.org>  <><  http://www.FreeBSD.org/~jhb/
"Power Users Use the Power to Serve"  =  http://www.FreeBSD.org
Comment 20 Eugene Grosbein 2006-05-03 17:28:32 UTC
On Tue, May 02, 2006 at 02:59:16PM -0400, John Baldwin wrote:

> > And if it's not interrupt routing problem, what else pci_link affects to?
> 
> That's all pci_link effects. :(

I'm afraid I did not understand you and an idea of your patch :-(
 
> One thing you can try is to turn
> off the code to disable each of the links during boot.  Something like
> this:
> 
> Index: acpi_pci_link.c

Anyway, I've tried this and found it does not change anything:
there are still the same ATA timeouts and for completeness,
there is a diff between dmesg.acpi I've sent earlier and
dmesg.hack. The later is for a kernel with this patch applied
(plus options USB_DEBUG for some reason), this kernel booted
with ACPI fully enabled:

--- dmesg.acpi	Sun Apr 30 15:58:41 2006
+++ dmesg.hack	Thu May  4 00:07:21 2006
@@ -1,20 +1,20 @@
 Copyright (c) 1992-2006 The FreeBSD Project.
 Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
 	The Regents of the University of California. All rights reserved.
-FreeBSD 6.1-RC #13: Sun Apr 30 15:39:40 KRAST 2006
-    root@grosbein.pp.ru:/mnt/usr/local/obj6/usr/src/sys/DADV
+FreeBSD 6.1-RC #26: Wed May  3 23:53:17 KRAST 2006
+    eu@grosbein.pp.ru:/mnt/usr/local/obj6/usr/src/sys/DADV
 WARNING: debug.mpsafenet forced to 0 as ipsec requires Giant
 WARNING: MPSAFE network stack disabled, expect reduced performance.
 MEMGUARD DEBUGGING ALLOCATOR INITIALIZED:
 	MEMGUARD map base: 0xc36c9000
 	MEMGUARD map limit: 0xc495b000
 	MEMGUARD map size: 19472384 (Bytes)
-Preloaded elf kernel "/boot/kernel/kernel" at 0xc0925000.
-Preloaded elf module "/boot/modules/acpi.ko" at 0xc092516c.
+Preloaded elf kernel "/boot/kernel/kernel" at 0xc0934000.
+Preloaded elf module "/boot/modules/acpi.ko" at 0xc093416c.
 Calibrating clock(s) ... i8254 clock: 1193165 Hz
 CLK_USE_I8254_CALIBRATION not specified - using default frequency
 Timecounter "i8254" frequency 1193182 Hz quality 0
-Calibrating TSC clock ... TSC clock: 902050480 Hz
+Calibrating TSC clock ... TSC clock: 902050309 Hz
 CPU: Intel Celeron (902.05-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>
@@ -119,7 +119,7 @@
 pci_link3: Links after disable:
 Index  IRQ  Rtd  Ref  IRQs
     0  255   N     0  3 4 5 6 7 10 11 12 14 15
-ACPI timer: 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 0/3 -> 0
+ACPI timer: 0/3 0/3 0/3 0/3 0/3 0/16777200 0/3 0/16777185 0/3 0/3 -> 0
 Timecounter "ACPI-safe" frequency 3579545 Hz quality 1000
 acpi_timer0: <24-bit timer at 3.579545MHz> port 0x4008-0x400b on acpi0
 cpu0: <ACPI CPU> on acpi0
@@ -305,6 +305,7 @@
 uhci0: [GIANT-LOCKED]
 usb0: <Intel 82371AB/EB (PIIX4) USB controller> on uhci0
 usb0: USB revision 1.0
+usbd_get_string: getting lang failed, using 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: <bridge> at device 7.3 (no driver attached)
@@ -343,6 +344,7 @@
 uhci1: [GIANT-LOCKED]
 usb1: <VIA 83C572 USB controller> on uhci1
 usb1: USB revision 1.0
+usbd_get_string: getting lang failed, using 0
 uhub1: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
 uhub1: 2 ports with 2 removable, self powered
 uhci2: <VIA 83C572 USB controller> port 0xc000-0xc01f irq 11 at device 19.1 on pci0
@@ -350,6 +352,7 @@
 uhci2: [GIANT-LOCKED]
 usb2: <VIA 83C572 USB controller> on uhci2
 usb2: USB revision 1.0
+usbd_get_string: getting lang failed, using 0
 uhub2: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
 uhub2: 2 ports with 2 removable, self powered
 ehci0: <VIA VT6202 USB 2.0 controller> mem 0xf0105000-0xf01050ff irq 9 at device 19.2 on pci0
@@ -494,7 +497,7 @@
 Device configuration finished.
 linprocfs registered
 procfs registered
-Timecounter "TSC" frequency 902050480 Hz quality 800
+Timecounter "TSC" frequency 902050309 Hz quality 800
 Timecounters tick every 1.000 msec
 Linux ELF exec handler installed
 IPsec: Initialized Security Association Processing.
@@ -523,20 +526,20 @@
 ad6: 80418240 sectors [79780C/16H/63S] 16 sectors/interrupt 1 depth queue
 GEOM: new disk ad4
 GEOM: new disk ad6
-(probe0:sbp0:0:0:0): error 22
-(probe0:sbp0:0:0:0): Unretryable Error
 (probe1:sbp0:0:1:0): error 22
 (probe1:sbp0:0:1:0): Unretryable Error
 (probe2:sbp0:0:2:0): error 22
 (probe2:sbp0:0:2:0): Unretryable Error
-(probe3:sbp0:0:3:0): error 22
-(probe3:sbp0:0:3:0): Unretryable Error
-(probe4:sbp0:0:4:0): error 22
-(probe4:sbp0:0:4:0): Unretryable Error
 (probe5:sbp0:0:5:0): error 22
 (probe5:sbp0:0:5:0): Unretryable Error
 (probe6:sbp0:0:6:0): error 22
 (probe6:sbp0:0:6:0): Unretryable Error
+(probe0:sbp0:0:0:0): error 22
+(probe0:sbp0:0:0:0): Unretryable Error
+(probe3:sbp0:0:3:0): error 22
+(probe3:sbp0:0:3:0): Unretryable Error
+(probe4:sbp0:0:4:0): error 22
+(probe4:sbp0:0:4:0): Unretryable Error
 Trying to mount root from ufs:/dev/ad6s2a
 start_init: trying /sbin/init
 gif0: bpf attached
@@ -554,7 +557,7 @@
 ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing request directly
 ad4: WARNING - SET_MULTI taskqueue timeout - completing request directly
 ata2: reinit done ..
-ad4: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=100861733
+ad4: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=100862789
 ata3: reiniting channel ..
 ata3: reset tp1 mask=03 ostat0=50 ostat1=00
 ata3: stat0=0x50 err=0x01 lsb=0x00 msb=0x00
@@ -563,7 +566,7 @@
 ad6: setting PIO4 on PDC20268 chip
 ad6: setting UDMA100 on PDC20268 chip
 ata3: reinit done ..
-ad6: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=62118768
+ad6: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=63035536
 fxp0: promiscuous mode enabled
 link_elf: symbol cd9660_wchar2char undefined
 link_elf: symbol lminor undefined


Eugene Grosbein
Comment 21 Mark Linimon freebsd_committer freebsd_triage 2007-04-19 07:38:48 UTC
State Changed
From-To: open->feedback

Does this problem still recur on FreeBSD 6.2? 


Comment 22 Mark Linimon freebsd_committer freebsd_triage 2007-04-19 07:38:48 UTC
Responsible Changed
From-To: freebsd-bugs->linimon
Comment 23 Mark Linimon freebsd_committer freebsd_triage 2007-05-24 22:42:39 UTC
State Changed
From-To: feedback->closed

Feedback timeout (3 weeks).