Skip site navigation (1)Skip section navigation (2)
Date:      Wed, 17 Apr 2013 08:26:00 +0200
From:      Andre Albsmeier <Andre.Albsmeier@siemens.com>
To:        Jeremy Chadwick <jdc@koitsu.org>
Cc:        "freebsd-stable@freebsd.org" <freebsd-stable@freebsd.org>
Subject:   Re: Lost CDROM on 9.1 with ATA_CAM on Promise controller
Message-ID:  <20130417062600.GA15613@bali>
In-Reply-To: <20130416193822.GA83620@icarus.home.lan>
References:  <20130416175520.GA9548@bali> <20130416193822.GA83620@icarus.home.lan>

next in thread | previous in thread | raw e-mail | index | archive | help
On Tue, 16-Apr-2013 at 21:38:22 +0200, Jeremy Chadwick wrote:
> On Tue, Apr 16, 2013 at 07:55:20PM +0200, Andre Albsmeier wrote:
> > I have lost one of my CDROM drives (HL-DT-STDVD-RAM GH22LP20/2.00)
> > after going from 7.4 to 9.1 when using ATA_CAM. It is attached to
> > a Promise PDC20268 UDMA100 controller. A standard harddisk drive
> > attached to this controller works well. Cables, controller and drive
> > where replaced already.
> > 
> > Kernel gives me:
> > 
> > atapci1: <Promise PDC20268 UDMA100 controller> port 0xb000-0xb007,0xa800-0xa803,0xa400-0xa407,0xa000-0xa003,0x9800-0x980f mem 0xdf800000-0xdf803fff irq 11 at device 12.0 on pci0
> > ata2: <ATA channel> at channel 0 on atapci1
> > ata3: <ATA channel> at channel 1 on atapci1
> > ...
> > ada0 at ata2 bus 0 scbus2 target 0 lun 0
> > ada0: <Maxtor 7B300R0 BAH41G10> ATA-7 device
> > ada0: 100.000MB/s transfers (UDMA5, PIO 8192bytes)
> > ada0: 286188MB (586114704 512 byte sectors: 16H 63S/T 16383C)
> > ...
> > (cd2:ata3:0:0:0): got CAM status 0x50
> > (cd2:ata3:0:0:0): fatal error, failed to attach to device
> > (cd2:ata3:0:0:0): lost device, 4 refs
> > (cd2:ata3:0:0:0): removing device entry
> > ...
> > 
> > Attaching the CDROM drive to the controller that is integrated on
> > the mainboard (Intel PIIX4 UDMA33 controller) does not show this
> > problem (but here I don't have UDMA66).
> > 
> > It also works when not using ATA_CAM:
> > 
> > ...
> > acd0: DVDR <HL-DT-STDVD-RAM GH22LP20/2.00> at ata3-master UDMA66 
> > ...
> > 
> > So this semes to be a problem with the Promise controller and ATA_CAM.
> > 
> > Any ideas? Or should I file PR?
> 
> The controller in question is a Promise Ultra100 TX2.

Right. Tried with an Ultra133, same effect.

> 
> The error message comes from sys/cam/scsi/scsi_cd.c, in function
> cddone().  The logic is a little hard for me to follow (I understand
> about 70% of it).  Look at lines 1724 to 1877 for stable/9.
> 
> 1. Can you provide full output from a verbose boot when the CD/DVD drive
> is attached to the Promise controller?

Attached below. I have just filtered out some ahc cruft...

Later I will try to boot a -current kernel -- just to see
how this behaves...

> 
> 2. What firmware version the card is using?  The PDC20268 had many, many
> firmware problems relating to ATAPI devices.

It is the latest BIOS: 2.20.0.15.

> 
> 3. I wouldn't worry about ATA66 vs. ATA33; this drive can only support
> up to about 22MBytes/second so ATA66 isn't going to get you anything,
> so as a workaround, using the PIIX4 for it would not hurt you.

Probably. But I already had cdrecord complain when it
came to the funky DMA speed test it is doing. It went
away when using the UDMA66 port. And on the other hand
I sometimes use the PIIX4 port for other stuff and I
do not want to attach the cdrom to the slave port.

> 
> 4. ONLY if this turns out to be a "controller thing": I'm not sure how
> much effort should be spent trying to make this work, as the PDC20268 is
> legacy/deprecated hardware (made/released 13 years ago).

The whole box is more than 13 years old (good old Asus BX board) ;-)

But since it worked in 7.4-STABLE I feel that this is some kind
of regression. I do not want to waste anyone's resources in fixing
it -- just if someone is curious and/or has an idea how to fix
it...

And here is the dmesg:

Copyright (c) 1992-2013 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 is a registered trademark of The FreeBSD Foundation.
FreeBSD 9.1-STABLE #6: Wed Apr 17 07:56:57 CEST 2013
    root@server.ofw.tld:/usr/obj/src/src-9/sys/bratfix i386
gcc version 4.2.1 20070831 patched [FreeBSD]
Preloaded elf kernel "/boot/kernel/kernel" at 0xc097d000.
Calibrating TSC clock ... TSC clock: 1405298309 Hz
CPU: Intel(R) Celeron(TM) CPU                1400MHz (1405.30-MHz 686-class CPU)
  Origin = "GenuineIntel"  Id = 0x6b1  Family = 0x6  Model = 0xb  Stepping = 1
  Features=0x383f9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>

Instruction TLB: 4 KB pages, 4-way set associative, 32 entries
Instruction TLB: 4 MB pages, fully associative, 2 entries
Data TLB: 4 KB pages, 4-way set associative, 64 entries
2nd-level cache: 256 KB, 8-way set associative, 32 byte line size
1st-level instruction cache: 16 KB, 4-way set associative, 32 byte line size
Data TLB: 4 MB Pages, 4-way set associative, 8 entries
1st-level data cache: 16 KB, 4-way set associative, 32 byte line size
real memory  = 268435456 (256 MB)
Physical memory chunk(s):
0x0000000000001000 - 0x000000000009dfff, 643072 bytes (157 pages)
0x0000000000100000 - 0x00000000003fffff, 3145728 bytes (768 pages)
0x0000000000c26000 - 0x000000000fb18fff, 250556416 bytes (61171 pages)
avail memory = 253022208 (241 MB)
bios32: Found BIOS32 Service Directory header at 0xc00f92a0
bios32: Entry = 0xf06c0 (c00f06c0)  Rev = 0  Len = 1
pcibios: PCI BIOS entry at 0xf0000+0x8c0
pnpbios: Found PnP BIOS data at 0xc00fc240
pnpbios: Entry = f0000:c270  Rev = 1.0
pnpbios: OEM ID cd041
Other BIOS signatures found:
ULE: setup cpu 0
random: <entropy source, Software, Yarrow>
cpuctl: access to MSR registers/cpuid info.
nfslock: pseudo-device
io: <I/O>
mem: <memory>
Pentium Pro MTRR support enabled
null: <null device, zero device>
ACPI: RSDP 0xf5a90 00014 (v00 ASUS  )
ACPI: RSDT 0xffec000 0002C (v01 ASUS   CUBX-L   30303031 MSFT 31313031)
ACPI: FACP 0xffec080 00074 (v01 ASUS   CUBX-L   30303031 MSFT 31313031)
ACPI: DSDT 0xffec100 02626 (v01   ASUS CUBX-L   00001000 MSFT 0100000B)
ACPI: FACS 0xffff000 00040
ACPI: BOOT 0xffec040 00028 (v01 ASUS   CUBX-L   30303031 MSFT 31313031)
acpi0: <ASUS CUBX-L> on motherboard
acpi0: Power Button (fixed)
acpi0: wakeup code va 0xc1f76000 pa 0x1000
atpic: Programming IRQ9 as level/low
pci_open(1):	mode 1 addr port (0x0cf8) is 0x8000005c
pci_open(1a):	mode1res=0x80000000 (0x80000000)
pci_cfgcheck:	device 0 [class=060000] [hdr=00] is there (id=71908086)
pcibios: BIOS version 2.10
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, ff00000 (3) failed
cpu0: <ACPI CPU> on acpi0
cpu0: switching to generic Cx mode
attimer0: <AT timer> port 0x40-0x43 irq 0 on acpi0
Timecounter "i8254" frequency 1193182 Hz quality 0
Event timer "i8254" frequency 1193182 Hz quality 100
atrtc0: <AT realtime clock> port 0x70-0x73 irq 8 on acpi0
atrtc0: registered as a time-of-day clock (resolution 1000000us, adjustment 0.500000000s)
Event timer "RTC" frequency 32768 Hz quality 0
ACPI timer: 0/4 0/4 0/4 0/4 0/4 0/4 0/4 0/4 0/4 0/4 -> 0
Timecounter "ACPI-safe" frequency 3579545 Hz quality 850
acpi_timer0: <24-bit timer at 3.579545MHz> port 0xe408-0xe40b on acpi0
pci_link0:        Index  IRQ  Rtd  Ref  IRQs
  Initial Probe       0   11   N     0  3 4 5 6 7 9 10 11 12 14 15
  Validation          0   11   N     0  3 4 5 6 7 9 10 11 12 14 15
  After Disable       0  255   N     0  3 4 5 6 7 9 10 11 12 14 15
pci_link1:        Index  IRQ  Rtd  Ref  IRQs
  Initial Probe       0   10   N     0  3 4 5 6 7 9 10 11 12 14 15
  Validation          0   10   N     0  3 4 5 6 7 9 10 11 12 14 15
  After Disable       0  255   N     0  3 4 5 6 7 9 10 11 12 14 15
pci_link2:        Index  IRQ  Rtd  Ref  IRQs
  Initial Probe       0   12   N     0  3 4 5 6 7 9 10 11 12 14 15
  Validation          0   12   N     0  3 4 5 6 7 9 10 11 12 14 15
  After Disable       0  255   N     0  3 4 5 6 7 9 10 11 12 14 15
pci_link3:        Index  IRQ  Rtd  Ref  IRQs
  Initial Probe       0   15   N     0  3 4 5 6 7 9 10 11 12 14 15
  Validation          0   15   N     0  3 4 5 6 7 9 10 11 12 14 15
  After Disable       0  255   N     0  3 4 5 6 7 9 10 11 12 14 15
acpi_button0: <Power Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pcib0: decoding 4 range 0-0xcf7
pcib0: decoding 4 range 0xd00-0xffff
pcib0: decoding 3 range 0xa0000-0xbffff
pcib0: decoding 3 range 0xc8000-0xdffff
pcib0: decoding 3 range 0x10000000-0xffffffff
ACPI: Found matching pin for 0.12.INTA at func 0: 11
ACPI: Found matching pin for 0.11.INTA at func 0: 10
ACPI: Found matching pin for 0.10.INTA at func 0: 12
ACPI: Found matching pin for 0.9.INTA at func 0: 15
ACPI: Found matching pin for 0.4.INTD at func 2: 255
pci0: <ACPI PCI bus> on pcib0
pci0: domain=0, physical bus=0
found->	vendor=0x8086, dev=0x7190, revid=0x03
	domain=0, bus=0, slot=0, func=0
	class=06-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0106, statreg=0x2210, cachelnsz=0 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
	map[10]: type Prefetchable Memory, range 32, base 0xe4000000, size 26, enabled
pcib0: allocated type 3 (0xe4000000-0xe7ffffff) for rid 10 of pci0:0:0:0
found->	vendor=0x8086, dev=0x7191, revid=0x03
	domain=0, bus=0, slot=1, func=0
	class=06-04-00, hdrtype=0x01, mfdev=0
	cmdreg=0x0117, statreg=0x0220, cachelnsz=0 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x88 (34000 ns), maxlat=0x00 (0 ns)
found->	vendor=0x8086, dev=0x7110, revid=0x02
	domain=0, bus=0, slot=4, 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
	domain=0, bus=0, slot=4, func=1
	class=01-01-80, hdrtype=0x00, mfdev=0
	cmdreg=0x0000, statreg=0x0280, cachelnsz=0 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
pcib0: allocated type 4 (0x1f0-0x1f7) for rid 10 of pci0:0:4:1
pcib0: allocated type 4 (0x3f6-0x3f6) for rid 14 of pci0:0:4:1
pcib0: allocated type 4 (0x170-0x177) for rid 18 of pci0:0:4:1
pcib0: allocated type 4 (0x376-0x376) for rid 1c of pci0:0:4:1
	map[20]: type I/O Port, range 32, base 0xd800, size  4, port disabled
pcib0: allocated type 4 (0xd800-0xd80f) for rid 20 of pci0:0:4:1
found->	vendor=0x8086, dev=0x7112, revid=0x01
	domain=0, bus=0, slot=4, 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=255
	map[20]: type I/O Port, range 32, base 0xd400, size  5, enabled
pcib0: allocated type 4 (0xd400-0xd41f) for rid 20 of pci0:0:4:2
found->	vendor=0x8086, dev=0x7113, revid=0x02
	domain=0, bus=0, slot=4, 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 I/O Port, range 32, base 0xe800, size  4, enabled
pcib0: allocated type 4 (0xe800-0xe80f) for rid 90 of pci0:0:4:3
found->	vendor=0x9004, dev=0x8178, revid=0x00
	domain=0, bus=0, slot=9, func=0
	class=01-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0017, statreg=0x0280, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x08 (2000 ns), maxlat=0x08 (2000 ns)
	intpin=a, irq=15
	map[10]: type I/O Port, range 32, base 0xd000, size  8, enabled
pcib0: allocated type 4 (0xd000-0xd0ff) for rid 10 of pci0:0:9:0
	map[14]: type Memory, range 32, base 0xe1000000, size 12, enabled
pcib0: allocated type 3 (0xe1000000-0xe1000fff) for rid 14 of pci0:0:9:0
pcib0: matched entry for 0.9.INTA (src \134_SB_.LNKD:0)
pcib0: slot 9 INTA routed to irq 15 via \134_SB_.LNKD
found->	vendor=0x8086, dev=0x107c, revid=0x05
	domain=0, bus=0, slot=10, func=0
	class=02-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0017, statreg=0x0230, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0xff (63750 ns), maxlat=0x00 (0 ns)
	intpin=a, irq=12
	powerspec 2  supports D0 D3  current D0
	map[10]: type Memory, range 32, base 0xe0800000, size 17, enabled
pcib0: allocated type 3 (0xe0800000-0xe081ffff) for rid 10 of pci0:0:10:0
	map[18]: type I/O Port, range 32, base 0xb800, size  6, enabled
pcib0: allocated type 4 (0xb800-0xb83f) for rid 18 of pci0:0:10:0
pcib0: matched entry for 0.10.INTA (src \134_SB_.LNKC:0)
pcib0: slot 10 INTA routed to irq 12 via \134_SB_.LNKC
found->	vendor=0x9005, dev=0x0080, revid=0x02
	domain=0, bus=0, slot=11, func=0
	class=01-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0017, statreg=0x02b0, cachelnsz=8 (dwords)
	lattimer=0x20 (960 ns), mingnt=0x28 (10000 ns), maxlat=0x19 (6250 ns)
	intpin=a, irq=10
	powerspec 2  supports D0 D3  current D0
	map[10]: type I/O Port, range 32, base 0xb400, size  8, enabled
pcib0: allocated type 4 (0xb400-0xb4ff) for rid 10 of pci0:0:11:0
	map[14]: type Memory, range 64, base 0xe0000000, size 12, enabled
pcib0: allocated type 3 (0xe0000000-0xe0000fff) for rid 14 of pci0:0:11:0
pcib0: matched entry for 0.11.INTA (src \134_SB_.LNKB:0)
pcib0: slot 11 INTA routed to irq 10 via \134_SB_.LNKB
found->	vendor=0x105a, dev=0x4d68, revid=0x02
	domain=0, bus=0, slot=12, 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=11
	powerspec 1  supports D0 D1 D3  current D0
	map[10]: type I/O Port, range 32, base 0xb000, size  3, enabled
pcib0: allocated type 4 (0xb000-0xb007) for rid 10 of pci0:0:12:0
	map[14]: type I/O Port, range 32, base 0xa800, size  2, enabled
pcib0: allocated type 4 (0xa800-0xa803) for rid 14 of pci0:0:12:0
	map[18]: type I/O Port, range 32, base 0xa400, size  3, enabled
pcib0: allocated type 4 (0xa400-0xa407) for rid 18 of pci0:0:12:0
	map[1c]: type I/O Port, range 32, base 0xa000, size  2, enabled
pcib0: allocated type 4 (0xa000-0xa003) for rid 1c of pci0:0:12:0
	map[20]: type I/O Port, range 32, base 0x9800, size  4, enabled
pcib0: allocated type 4 (0x9800-0x980f) for rid 20 of pci0:0:12:0
	map[24]: type Memory, range 32, base 0xdf800000, size 14, enabled
pcib0: allocated type 3 (0xdf800000-0xdf803fff) for rid 24 of pci0:0:12:0
pcib0: matched entry for 0.12.INTA (src \134_SB_.LNKA:0)
pcib0: slot 12 INTA routed to irq 11 via \134_SB_.LNKA
eccmon0: RAM ECC Monitor v0.13 on i440BX/ZX (8086:7190), reporting tested
eccmon0: Capabilities: ECC with hardware scrubber
eccmon0: Current mode: ECC with hardware scrubber
eccmon0: Bank  Size  Type  ILV  ECC
eccmon0:   0   128M   SDR   N    Y
eccmon0:   2   128M   SDR   N    Y
eccmon0: Total RAM detected: 256M
eccmon0: <RAM ECC Monitor v0.13> on hostb0
eccmon0: attached
pcib1: <PCI-PCI bridge> at device 1.0 on pci0
pcib0: allocated type 3 (0xe1800000-0xe2dfffff) for rid 20 of pcib1
pcib0: allocated type 3 (0xe2f00000-0xe3ffffff) for rid 24 of pcib1
pcib1:   domain            0
pcib1:   secondary bus     1
pcib1:   subordinate bus   1
pcib1:   memory decode     0xe1800000-0xe2dfffff
pcib1:   prefetched decode 0xe2f00000-0xe3ffffff
pci1: <PCI bus> on pcib1
pci1: domain=0, physical bus=1
found->	vendor=0x102b, dev=0x0521, revid=0x03
	domain=0, bus=1, slot=0, func=0
	class=03-00-00, hdrtype=0x00, mfdev=0
	cmdreg=0x0007, statreg=0x0290, cachelnsz=8 (dwords)
	lattimer=0x40 (1920 ns), mingnt=0x10 (4000 ns), maxlat=0x20 (8000 ns)
	intpin=a, irq=11
	powerspec 1  supports D0 D3  current D0
	map[10]: type Prefetchable Memory, range 32, base 0xe3000000, size 24, enabled
pcib1: allocated prefetch range (0xe3000000-0xe3ffffff) for rid 10 of pci0:1:0:0
	map[14]: type Memory, range 32, base 0xe2000000, size 14, enabled
pcib1: allocated memory range (0xe2000000-0xe2003fff) for rid 14 of pci0:1:0:0
	map[18]: type Memory, range 32, base 0xe1800000, size 23, enabled
pcib1: allocated memory range (0xe1800000-0xe1ffffff) for rid 18 of pci0:1:0:0
pcib0: matched entry for 0.1.INTA (src \134_SB_.LNKA:0)
pcib0: slot 1 INTA routed to irq 11 via \134_SB_.LNKA
pcib1: slot 0 INTA is routed to irq 11
vgapci0: <VGA-compatible display> mem 0xe3000000-0xe3ffffff,0xe2000000-0xe2003fff,0xe1800000-0xe1ffffff irq 11 at device 0.0 on pci1
isab0: <PCI-ISA bridge> at device 4.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel PIIX4 UDMA33 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xd800-0xd80f at device 4.1 on pci0
ata0: <ATA channel> at channel 0 on atapci0
ata1: <ATA channel> at channel 1 on atapci0
pci0: <serial bus, USB> at device 4.2 (no driver attached)
intsmb0: <Intel PIIX4 SMBUS Interface> port 0xe800-0xe80f at device 4.3 on pci0
intsmb0: intr IRQ 9 enabled revision 0
smbus0: <System Management Bus> on intsmb0
smb0: <SMBus generic I/O> on smbus0
ahc0: <Adaptec 2940 Ultra SCSI adapter> port 0xd000-0xd0ff mem 0xe1000000-0xe1000fff irq 15 at device 9.0 on pci0
ahc0: Bugs (0x0025): TMODE_WIDEODD CACHETHEN PCI_MWI
ahc0: Defaulting to MEMIO on
ahc0: Reading SEEPROM...done.
ahc0: Low byte termination Enabled
ahc0: Downloading Sequencer Program... 442 instructions downloaded
ahc0: Features 0x10001, Bugs 0x25, Flags 0x20485540
aic7880: Ultra Single Channel A, SCSI Id=7, 16/253 SCBs
em0: <Intel(R) PRO/1000 Legacy Network Connection 1.0.5> port 0xb800-0xb83f mem 0xe0800000-0xe081ffff irq 12 at device 10.0 on pci0
em0: bpf attached
em0: Ethernet address: 00:1b:21:0a:8d:db
ahc1: <Adaptec 29160 Ultra160 SCSI adapter> port 0xb400-0xb4ff mem 0xe0000000-0xe0000fff irq 10 at device 11.0 on pci0
ahc1: Bugs (0x0040): SCBCHAN_UPLOAD
ahc1: Defaulting to MEMIO on
ahc1: Reading SEEPROM...done.
ahc1: Manual SE Termination
ahc1: Manual LVD Termination
ahc1: BIOS eeprom is present
ahc1: Secondary High byte termination Enabled
ahc1: Secondary Low byte termination Enabled
ahc1: Primary Low Byte termination Enabled
ahc1: Primary High Byte termination Enabled
ahc1: Downloading Sequencer Program... 423 instructions downloaded
ahc1: Features 0x1def6, Bugs 0x40, Flags 0x20485560
aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs
atapci1: <Promise PDC20268 UDMA100 controller> port 0xb000-0xb007,0xa800-0xa803,0xa400-0xa407,0xa000-0xa003,0x9800-0x980f mem 0xdf800000-0xdf803fff irq 11 at device 12.0 on pci0
ata2: <ATA channel> at channel 0 on atapci1
ata3: <ATA channel> at channel 1 on atapci1
fdc0: <floppy drive controller> port 0x3f2-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: ic_type 90 part_id 80
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: fast interrupt
uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
uart1: fast interrupt
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 0067
atkbd: keyboard ID 0x41ab (2)
kbd0: atkbd0, AT 101/102 (2), config:0x0, flags:0x3d0000
atkbd0: [GIANT-LOCKED]
pnp_identify: Trying Read_Port at 203
...
pnp_identify: Trying Read_Port at 3c3
PNP Identify complete
ahc_isa_probe 0: ioport 0xc00 alloc failed
...
pcib0: allocated type 3 (0xdf800-0xdffff) for rid 2 of orm0
isa_probe_children: disabling PnP devices
ata: ata0 already exists; skipping it
ata: ata1 already exists; skipping it
atkbdc: atkbdc0 already exists; skipping it
atrtc: atrtc0 already exists; skipping it
attimer: attimer0 already exists; skipping it
fdc: fdc0 already exists; skipping it
sc: sc0 already exists; skipping it
uart: uart0 already exists; skipping it
uart: uart1 already exists; skipping it
isa_probe_children: probing non-PnP devices
orm0: <ISA Option ROMs> at iomem 0xc0000-0xc7fff,0xd8000-0xda7ff pnpid ORM0000 on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <9 virtual consoles, flags=0x300>
sc0: fb0, kbd0, terminal emulator: scteken (teken terminal)
vga0: <Generic ISA VGA> at port 0x3b0-0x3df iomem 0xa0000-0xbffff on isa0
pcib0: allocated type 4 (0x3b0-0x3df) for rid 0 of vga0
pcib0: allocated type 3 (0xa0000-0xbffff) for rid 0 of vga0
ppc0 failed to probe at irq 7 on isa0
wbwd0 failed to probe on isa0
isa_probe_children: probing PnP devices
acpi_throttle0: <ACPI CPU Throttling> on cpu0
acpi_throttle0: P_CNT from P_BLK 0xe410
Device configuration finished.
procfs registered
Timecounters tick every 10.000 msec
lo0: bpf attached
ata0: reset tp1 mask=00 ostat0=ff ostat1=ff
ata1: reset tp1 mask=00 ostat0=ff ostat1=ff
(noperiph:ahc0:0:-1:-1): SCSI bus reset delivered. 0 SCBs aborted.
(noperiph:ahc1:0:-1:-1): SCSI bus reset delivered. 0 SCBs aborted.
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
ata3: reset tp1 mask=03 ostat0=51 ostat1=00
ata3: stat0=0x00 err=0x01 lsb=0x14 msb=0xeb
ata3: stat1=0x00 err=0x00 lsb=0x00 msb=0x00
ata3: reset tp2 stat0=00 stat1=00 devices=0x10000
ahc0: Selection Timeout on A:2. 0 SCBs aborted
ahc0: Selection Timeout on A:3. 0 SCBs aborted
ahc0: Selection Timeout on A:4. 0 SCBs aborted
(probe5:ahc0:0:5:0): Down reving Protocol Version from 4 to 2?
...
(ahc0:A:1:0): Received SDTR period c, offset f
	Filtered to period c, offset f
ahc0: target 1 synchronous at 20.0MHz, offset = 0xf
ahc1: Selection Timeout on A:1. 0 SCBs aborted
ahc1: Selection Timeout on A:2. 0 SCBs aborted
ahc1: Selection Timeout on A:3. 0 SCBs aborted
(ahc1:A:4:0): Received WDTR 1 filtered to 0
(ahc1:A:4:0): Target Initiated WDTR
(ahc1:A:4:0): Sending WDTR 0
ahc1: target 4 using 8bit transfers
(ahc1:A:4:0): Received SDTR period a, offset 79
	Filtered to period 0, offset 0
ahc1: target 4 using asynchronous transfers
(ahc1:A:4:0): Target Initiated SDTR
(ahc1:A:4:0): Sending SDTR period 45, offset 0
ahc1: Selection Timeout on A:5. 0 SCBs aborted
...
ahc1: Selection Timeout on A:14. 0 SCBs aborted
ahc1: Selection Timeout on A:15. 0 SCBs aborted
(ahc1:A:4:0): Sending PPR bus_width 1, period 9, offset 7f, ppr_options 2
(ahc1:A:4:0): Received PPR width 1, period 9, offset 78,options 2
	Filtered to width 1, period 9, offset 78, options 2
ahc1: target 4 using 16bit transfers
ahc1: target 4 synchronous at 80.0MHz DT, offset = 0x78
(probe7:ahc1:0:0:0): Down reving Protocol Version from 4 to 2?
(probe7:ahc1:0:0:0): Down reving Transport Version from 3 to 2?
(ahc1:A:4:0): Sending PPR bus_width 1, period 9, offset 78, ppr_options 2
(ahc1:A:4:0): Received PPR width 1, period 9, offset 78,options 2
	Filtered to width 1, period 9, offset 78, options 2
(ahc1:A:0:0): Sending WDTR 1
(ahc1:A:0:0): Received WDTR 1 filtered to 1
ahc1: target 0 using 16bit transfers
(ahc1:A:0:0): Sending SDTR period c, offset 7f
(ahc1:A:0:0): Received SDTR period c, offset f
	Filtered to period c, offset f
ahc1: target 0 synchronous at 20.0MHz, offset = 0xf
pass0 at ahc0 bus 0 scbus0 target 0 lun 0
pass0: <IBM DCAS-32160 S65A> Fixed Direct Access SCSI-2 device 
pass0: Serial Number F25V7824        
pass0: 20.000MB/s transfers (20.000MHz, offset 15)
pass1 at ahc0 bus 0 scbus0 target 1 lun 0
pass1: <IBM DCAS-32160 S61A> Fixed Direct Access SCSI-2 device 
pass1: Serial Number F2587270        
pass1: 20.000MB/s transfers (20.000MHz, offset 15)
pass2 at ahc0 bus 0 scbus0 target 5 lun 0
pass2: <PLEXTOR CD-R   PX-W4012S 1.05> Removable CD-ROM SCSI-2 device 
pass2: 20.000MB/s transfers (20.000MHz, offset 15)
pass3 at ahc0 bus 0 scbus0 target 6 lun 0
pass3: <PLEXTOR CD-ROM PX-40TS 1.14> Removable CD-ROM SCSI-2 device 
pass3: 20.000MB/s transfers (20.000MHz, offset 15)
pass4 at ahc1 bus 0 scbus1 target 0 lun 0
pass4: <SEAGATE ST39175LW 0001> Fixed Direct Access SCSI-2 device 
pass4: Serial Number 3AL0RR7H0000701423PB
pass4: 40.000MB/s transfers (20.000MHz, offset 15, 16bit)
pass4: Command Queueing enabled
pass5 at ahc1 bus 0 scbus1 target 4 lun 0
pass5: <QUANTUM SDLT600 3333> Removable Sequential Access SCSI-4 device 
pass5: Serial Number QD0719AMC00028  
pass5: 160.000MB/s transfers (80.000MHz DT, offset 120, 16bit)
pass6 at ata2 bus 0 scbus2 target 0 lun 0
pass6: <Maxtor 7B300R0 BAH41G10> ATA-7 device
pass6: Serial Number B61T05CH
pass6: 100.000MB/s transfers (UDMA5, PIO 8192bytes)
pass7 at ata3 bus 0 scbus3 target 0 lun 0
pass7: <HL-DT-ST DVD-RAM GH22LP20 2.00> Removable CD-ROM SCSI-0 device 
pass7: 66.700MB/s transfers (UDMA4, ATAPI 12bytes, PIO 65534bytes)
sa0 at ahc1 bus 0 scbus1 target 4 lun 0
sa0: <QUANTUM SDLT600 3333> Removable Sequential Access SCSI-4 device 
sa0: Serial Number QD0719AMC00028  
sa0: 160.000MB/s transfers (80.000MHz DT, offset 120, 16bit)
ada0 at ata2 bus 0 scbus2 target 0 lun 0
ada0: <Maxtor 7B300R0 BAH41G10> ATA-7 device
ada0: Serial Number B61T05CH
ada0: 100.000MB/s transfers (UDMA5, PIO 8192bytes)
ada0: 286188MB (586114704 512 byte sectors: 16H 63S/T 16383C)
da10 at ahc1 bus 0 scbus1 target 0 lun 0
da10: <SEAGATE ST39175LW 0001> Fixed Direct Access SCSI-2 device 
da10: Serial Number 3AL0RR7H0000701423PB
da10: 40.000MB/s transfers (20.000MHz, offset 15, 16bit)
da10: Command Queueing enabled
da10: 8683MB (17783240 512 byte sectors: 255H 63S/T 1106C)
(ahc0:A:5:0): Sending SDTR period c, offset f
Timecounter "TSC" frequency 1405298309 Hz quality 800
GEOM: new disk da0
GEOM: new disk da1
GEOM: new disk da10
GEOM: new disk cd1
GEOM: new disk cd0
GEOM: new disk cd2
GEOM: new disk ada0
(ahc0:A:5:0): Received SDTR period c, offset f
	Filtered to period c, offset f
(ahc0:A:6:0): Sending SDTR period c, offset f
cd1 at ahc0 bus 0 scbus0 target 5 lun 0
cd1: <PLEXTOR CD-R   PX-W4012S 1.05> Removable CD-ROM SCSI-2 device 
cd1: 20.000MB/s transfers (20.000MHz, offset 15)
cd1: Attempt to query device size failed: NOT READY, Medium not present - tray closed
(ahc0:A:6:0): Received SDTR period c, offset f
	Filtered to period c, offset f
da1 at ahc0 bus 0 scbus0 target 1 lun 0
da1: <IBM DCAS-32160 S61A> Fixed Direct Access SCSI-2 device 
da1: Serial Number F2587270        
da1: 20.000MB/s transfers (20.000MHz, offset 15)
da1: 2063MB (4226725 512 byte sectors: 255H 63S/T 263C)
da0 at ahc0 bus 0 scbus0 target 0 lun 0
da0: <IBM DCAS-32160 S65A> Fixed Direct Access SCSI-2 device 
da0: Serial Number F25V7824        
da0: 20.000MB/s transfers (20.000MHz, offset 15)
da0: 2063MB (4226725 512 byte sectors: 255H 63S/T 263C)
(ahc0:A:5:0): Sending SDTR period c, offset f
(ahc0:A:5:0): Received SDTR period c, offset f
	Filtered to period c, offset f
...
(ahc0:A:6:0): Sending SDTR period c, offset f
(ahc0:A:6:0): Received SDTR period c, offset f
	Filtered to period c, offset f
cd0 at ahc0 bus 0 scbus0 target 6 lun 0
cd0: <PLEXTOR CD-ROM PX-40TS 1.14> Removable CD-ROM SCSI-2 device 
cd0: 20.000MB/s transfers (20.000MHz, offset 15)
cd0: Attempt to query device size failed: NOT READY, Medium not present - tray closed
(ahc0:A:6:0): Sending SDTR period c, offset f
(ahc0:A:6:0): Received SDTR period c, offset f
	Filtered to period c, offset f
...
(ahc0:A:6:0): Sending SDTR period c, offset f
(ahc0:A:6:0): Received SDTR period c, offset f
	Filtered to period c, offset f
ata3: reset tp1 mask=03 ostat0=50 ostat1=00
ata3: stat0=0x00 err=0x01 lsb=0x14 msb=0xeb
ata3: stat1=0x00 err=0x00 lsb=0x00 msb=0x00
ata3: reset tp2 stat0=00 stat1=00 devices=0x10000
(cd2:ata3:0:0:0): got CAM status 0x50
(cd2:ata3:0:0:0): fatal error, failed to attach to device
(cd2:ata3:0:0:0): lost device, 4 refs
Opened disk cd2 -> 6
(cd2:ata3:0:0:0): removing device entry
(ahc0:A:5:0): Sending SDTR period c, offset f
(ahc0:A:5:0): Received SDTR period c, offset f
	Filtered to period c, offset f
...



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20130417062600.GA15613>