libata errors

libata errors

[Matt Grice]

Dear Mr Garzik,

I am currently having trouble with the new libata kernel subsystem (My
system broke after 2.6.21 I believe). After much fruitless googling
and trawling through the libata code (I am *no* kernel programmer!) I
took the serious, final step of bugging you.

Ten or so minutes after boot (long enough for my two IDE Sony DW-G120A
DVD writers to spin down) I start getting these error messages in my
kernel logs:

[ 6317.817041] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 6317.817057] ata4.01: cmd a0/00:00:00:08:00/00:00:00:00:00/b0 tag 0
pio 16392 in
[ 6317.817058]          cdb 4a 01 00 00 10 00 00 00  08 00 00 00 00 00 00 00
[ 6317.817059]          res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask
0x4 (timeout)
[ 6317.817064] ata4.01: status: { DRDY }
[ 6317.817087] ata4: soft resetting link
[ 6318.221356] ata4.00: configured for PIO0
[ 6318.253533] ata4.01: configured for PIO0
[ 6318.253567] ata4: EH complete
[ 6335.817039] ata4.00: qc timeout (cmd 0xa0)
[ 6335.817059] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 6335.817069] ata4.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0
[ 6335.817070]          cdb 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00
[ 6335.817071]          res 51/20:03:00:00:00/00:00:00:00:00/a0 Emask
0x5 (timeout)

ad nauseam.

(I am running kernel 2.6.27-11, amd64. I have also tried 2.6.29, and
the messages are the same)

My DVD writers then cease to function.


My initial thoughts are that my spun-down devices are taking too long
to respond to requests from the kernel. I braced myself and then dived
in to the icy waters of the kernel sources to see if there was a
timeout I could increase. However, there are several structs with
timeouts in libata-eh.c, and the last thing my poor kernel needs is a
moron like myself twiddling random constants.

Could you please let me know if you think I am on the right track? Or
maybe suggest a timeout to twiddle?


Many thanks, and many apologies for interrupting you.


Kind Regards,


Matt Grice

Re: libata errors

[Jeff Garzik]

Matt Grice wrote:
> Dear Mr Garzik,
> 
> I am currently having trouble with the new libata kernel subsystem (My
> system broke after 2.6.21 I believe). After much fruitless googling
> and trawling through the libata code (I am *no* kernel programmer!) I
> took the serious, final step of bugging you.
> 
> Ten or so minutes after boot (long enough for my two IDE Sony DW-G120A
> DVD writers to spin down) I start getting these error messages in my
> kernel logs:
> 
> [ 6317.817041] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
> [ 6317.817057] ata4.01: cmd a0/00:00:00:08:00/00:00:00:00:00/b0 tag 0
> pio 16392 in
> [ 6317.817058]          cdb 4a 01 00 00 10 00 00 00  08 00 00 00 00 00 00 00
> [ 6317.817059]          res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask
> 0x4 (timeout)
> [ 6317.817064] ata4.01: status: { DRDY }
> [ 6317.817087] ata4: soft resetting link
> [ 6318.221356] ata4.00: configured for PIO0
> [ 6318.253533] ata4.01: configured for PIO0
> [ 6318.253567] ata4: EH complete
> [ 6335.817039] ata4.00: qc timeout (cmd 0xa0)
> [ 6335.817059] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
> [ 6335.817069] ata4.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0
> [ 6335.817070]          cdb 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00
> [ 6335.817071]          res 51/20:03:00:00:00/00:00:00:00:00/a0 Emask
> 0x5 (timeout)
> 
> ad nauseam.
> 
> (I am running kernel 2.6.27-11, amd64. I have also tried 2.6.29, and
> the messages are the same)

What type of system hardware?  What libata driver?

If its ahci, try using ata_piix.  I am beginning to think that ATAPI on 
AHCI is broken.

Otherwise, "timeout" generally means your hardware stopped sending 
interrupts, which could indicate a non-libata problem.  Timeouts are 
often symptoms of broken PCI MSI interrupts, for examples.

	Jeff

Re: libata errors

[Robert Hancock]

Matt Grice wrote:
> Dear Mr Garzik,
> 
> I am currently having trouble with the new libata kernel subsystem (My
> system broke after 2.6.21 I believe). After much fruitless googling
> and trawling through the libata code (I am *no* kernel programmer!) I
> took the serious, final step of bugging you.
> 
> Ten or so minutes after boot (long enough for my two IDE Sony DW-G120A
> DVD writers to spin down) I start getting these error messages in my
> kernel logs:
> 
> [ 6317.817041] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
> [ 6317.817057] ata4.01: cmd a0/00:00:00:08:00/00:00:00:00:00/b0 tag 0
> pio 16392 in
> [ 6317.817058]          cdb 4a 01 00 00 10 00 00 00  08 00 00 00 00 00 00 00
> [ 6317.817059]          res 40/00:03:00:00:00/00:00:00:00:00/a0 Emask
> 0x4 (timeout)
> [ 6317.817064] ata4.01: status: { DRDY }
> [ 6317.817087] ata4: soft resetting link
> [ 6318.221356] ata4.00: configured for PIO0
> [ 6318.253533] ata4.01: configured for PIO0
> [ 6318.253567] ata4: EH complete
> [ 6335.817039] ata4.00: qc timeout (cmd 0xa0)
> [ 6335.817059] ata4.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
> [ 6335.817069] ata4.00: cmd a0/00:00:00:00:00/00:00:00:00:00/a0 tag 0
> [ 6335.817070]          cdb 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00
> [ 6335.817071]          res 51/20:03:00:00:00/00:00:00:00:00/a0 Emask
> 0x5 (timeout)
> 
> ad nauseam.
> 
> (I am running kernel 2.6.27-11, amd64. I have also tried 2.6.29, and
> the messages are the same)
> 
> My DVD writers then cease to function.
> 
> 
> My initial thoughts are that my spun-down devices are taking too long
> to respond to requests from the kernel. I braced myself and then dived
> in to the icy waters of the kernel sources to see if there was a
> timeout I could increase. However, there are several structs with
> timeouts in libata-eh.c, and the last thing my poor kernel needs is a
> moron like myself twiddling random constants.
> 
> Could you please let me know if you think I am on the right track? Or
> maybe suggest a timeout to twiddle?

ATA command timeouts are usually pretty long (like 30 seconds) so 
usually if they time out something is wrong, or the drive has really 
gone into the weeds.

It looks like your drives are running at PIO mode 0 after the fault 
happens. What are they running at before? Full dmesg output from bootup 
might be useful..

> 
> 
> Many thanks, and many apologies for interrupting you.
> 
> 
> Kind Regards,
> 
> 
> Matt Grice
> --
> To unsubscribe from this list: send the line "unsubscribe linux-ide" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html
> 

Re: libata errors

[Mikael Pettersson]

Jeff Garzik writes:
 > If its ahci, try using ata_piix.  I am beginning to think that ATAPI on 
 > AHCI is broken.

I use a SATAPI DVD-writer on AHCI (Intel 965) on a semi-regular
basis, and it's never had any issues with recent 2.6.2x kernels.

Re: libata errors

[Jeff Garzik]

Mikael Pettersson wrote:
> Jeff Garzik writes:
>  > If its ahci, try using ata_piix.  I am beginning to think that ATAPI on 
>  > AHCI is broken.
> 
> I use a SATAPI DVD-writer on AHCI (Intel 965) on a semi-regular
> basis, and it's never had any issues with recent 2.6.2x kernels.

Are you able to rip audio CDs?

	Jeff

Re: libata errors

[Matt Grice]

[-- Attachment #1: Type: text/plain, Size: 903 bytes --]

Hi! Many thanks for all of your responses, I'll try and deal with all
of the issues in one email. ;)

Hardware is an ECS KV2-Lite motherboard with an AMD Athlon64 X2 Dual
Core Processor 4200+. DVD-ROM drives are Sony DW-G120A.

My version of libata (from libata.h and dmesg) is 3.00.

I am using the VIA driver:

matt@Dexter64:~$ lsmod | grep ata
pata_via               18180  2
sata_via               17668  0
ata_generic            14212  0
pata_acpi              13568  0
libata                201312  4 pata_via,sata_via,ata_generic,pata_acpi
scsi_mod              183160  4 sr_mod,sd_mod,sg,libata
dock                   18464  1 libata
matt@Dexter64:~$


Please find attached my dmesg.

Jeff - when the errors start, I am unable to play / rip audio CDs.

Many thanks in advance for your help,

Matt Grice

PS - How do you find out if a drive has been forced from DMA to PIO
mode during operation?

[-- Attachment #2: dmesg.txt --]
[-- Type: text/plain, Size: 32250 bytes --]

[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 2.6.27-11-generic (buildd@crested) (gcc version 4.3.2 (Ubuntu 4.3.2-1ubuntu11) ) #1 SMP Thu Jan 29 19:28:32 UTC 2009 (Ubuntu 2.6.27-11.27-generic)
[    0.000000] Command line: root=UUID=566f5921-d45d-47b0-bda2-6538fa9927b7 ro quiet 
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009f000 (usable)
[    0.000000]  BIOS-e820: 000000000009f000 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000007fee0000 (usable)
[    0.000000]  BIOS-e820: 000000007fee0000 - 000000007fee3000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000007fee3000 - 000000007fef0000 (ACPI data)
[    0.000000]  BIOS-e820: 000000007fef0000 - 000000007ff00000 (reserved)
[    0.000000]  BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
[    0.000000] DMI 2.3 present.
[    0.000000] Phoenix BIOS detected: BIOS may corrupt low RAM, working it around.
[    0.000000] last_pfn = 0x7fee0 max_arch_pfn = 0x3ffffffff
[    0.000000] init_memory_mapping
[    0.000000]  0000000000 - 007fe00000 page 2M
[    0.000000]  007fe00000 - 007fee0000 page 4k
[    0.000000] kernel direct mapping tables up to 7fee0000 @ 10000-14000
[    0.000000] last_map_addr: 7fee0000 end: 7fee0000
[    0.000000] RAMDISK: 377ac000 - 37feffff
[    0.000000] ACPI: RSDP 000F7680, 0014 (r0 VIAK8T)
[    0.000000] ACPI: RSDT 7FEE3040, 0030 (r1 VIAK8T AWRDACPI 42302E31 AWRD        0)
[    0.000000] ACPI: FACP 7FEE30C0, 0074 (r1 VIAK8T AWRDACPI 42302E31 AWRD        0)
[    0.000000] ACPI: DSDT 7FEE3180, 4D6E (r1 VIAK8T AWRDACPI     1000 MSFT  100000E)
[    0.000000] ACPI: FACS 7FEE0000, 0040
[    0.000000] ACPI: SSDT 7FEE8000, 01CA (r1 PTLTD  POWERNOW        1  LTP        1)
[    0.000000] ACPI: APIC 7FEE7F40, 0068 (r1 VIAK8T AWRDACPI 42302E31 AWRD        0)
[    0.000000] Scanning NUMA topology in Northbridge 24
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at 0000000000000000-000000007fee0000
[    0.000000] Bootmem setup node 0 0000000000000000-000000007fee0000
[    0.000000]   NODE_DATA [0000000000012000 - 0000000000016fff]
[    0.000000]   bootmap [0000000000017000 -  0000000000026fdf] pages 10
[    0.000000] (6 early reservations) ==> bootmem [0000000000 - 007fee0000]
[    0.000000]   #0 [0000000000 - 0000001000]   BIOS data page ==> [0000000000 - 0000001000]
[    0.000000]   #1 [0000006000 - 0000008000]       TRAMPOLINE ==> [0000006000 - 0000008000]
[    0.000000]   #2 [0000200000 - 00008b9f5c]    TEXT DATA BSS ==> [0000200000 - 00008b9f5c]
[    0.000000]   #3 [00377ac000 - 0037feffff]          RAMDISK ==> [00377ac000 - 0037feffff]
[    0.000000]   #4 [000009f000 - 0000100000]    BIOS reserved ==> [000009f000 - 0000100000]
[    0.000000]   #5 [0000010000 - 0000012000]          PGTABLE ==> [0000010000 - 0000012000]
[    0.000000] found SMP MP-table at [ffff8800000f3800] 000f3800
[    0.000000]  [ffffe20000000000-ffffe20001ffffff] PMD -> [ffff880001200000-ffff8800031fffff] on node 0
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000010 -> 0x00001000
[    0.000000]   DMA32    0x00001000 -> 0x00100000
[    0.000000]   Normal   0x00100000 -> 0x00100000
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[2] active PFN ranges
[    0.000000]     0: 0x00000010 -> 0x0000009f
[    0.000000]     0: 0x00000100 -> 0x0007fee0
[    0.000000] On node 0 totalpages: 523887
[    0.000000]   DMA zone: 2094 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 511780 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0x4008
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 2, version 0, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Setting APIC routing to flat
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] SMP: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000
[    0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000
[    0.000000] Allocating PCI resources starting at 80000000 (gap: 7ff00000:7ed00000)
[    0.000000] PERCPU: Allocating 64928 bytes of per cpu data
[    0.000000] NR_CPUS: 64, nr_cpu_ids: 2, nr_node_ids 1
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 513874
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: root=UUID=566f5921-d45d-47b0-bda2-6538fa9927b7 ro quiet 
[    0.000000] Initializing CPU#0
[    0.000000] PID hash table entries: 4096 (order: 12, 32768 bytes)
[    0.000000] TSC: PIT calibration confirmed by PMTIMER.
[    0.000000] TSC: using PIT calibration value
[    0.000000] Detected 2199.699 MHz processor.
[    0.004000] Console: colour VGA+ 80x25
[    0.004000] console [tty0] enabled
[    0.004000] Checking aperture...
[    0.004000] AGP bridge at 00:00:00
[    0.004000] Aperture from AGP @ f0000000 old size 32 MB
[    0.004000] Aperture from AGP @ f0000000 size 128 MB (APSIZE f20)
[    0.004000] Node 0: aperture @ f0000000 size 128 MB
[    0.004000] Memory: 2047140k/2096000k available (3116k kernel code, 48408k reserved, 1575k data, 540k init)
[    0.004000] CPA: page pool initialized 1 of 1 pages preallocated
[    0.004000] SLUB: Genslabs=13, HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.004016] Calibrating delay loop (skipped), value calculated using timer frequency.. 4399.39 BogoMIPS (lpj=8798796)
[    0.004053] Security Framework initialized
[    0.004065] SELinux:  Disabled at boot.
[    0.004089] AppArmor: AppArmor initialized
[    0.004321] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.007090] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.008488] Mount-cache hash table entries: 256
[    0.008772] Initializing cgroup subsys ns
[    0.008776] Initializing cgroup subsys cpuacct
[    0.008779] Initializing cgroup subsys memory
[    0.008799] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[    0.008801] CPU: L2 Cache: 512K (64 bytes/line)
[    0.008804] CPU 0/0 -> Node 0
[    0.008806] tseg: 007ff00000
[    0.008807] CPU: Physical Processor ID: 0
[    0.008809] CPU: Processor Core ID: 0
[    0.010267] ACPI: Core revision 20080609
[    0.012261] ACPI: Checking initramfs for custom DSDT
[    0.337017] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0
[    0.376999] CPU0: AMD Athlon(tm) 64 X2 Dual Core Processor 4200+ stepping 02
[    0.377003] Using local APIC timer interrupts.
[    0.380025] APIC timer calibration result 12498293
[    0.380026] Detected 12.498 MHz APIC timer.
[    0.380215] Booting processor 1/1 ip 6000
[    0.004000] Initializing CPU#1
[    0.004000] Calibrating delay using timer specific routine.. 4399.47 BogoMIPS (lpj=8798956)
[    0.004000] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[    0.004000] CPU: L2 Cache: 512K (64 bytes/line)
[    0.004000] CPU 1/1 -> Node 0
[    0.004000] CPU: Physical Processor ID: 0
[    0.004000] CPU: Processor Core ID: 1
[    0.468254] CPU1: AMD Athlon(tm) 64 X2 Dual Core Processor 4200+ stepping 02
[    0.468270] Brought up 2 CPUs
[    0.468273] Total of 2 processors activated (8798.87 BogoMIPS).
[    0.468478] CPU0 attaching sched-domain:
[    0.468480]  domain 0: span 0-1 level CPU
[    0.468482]   groups: 0 1
[    0.468486]   domain 1: span 0-1 level NODE
[    0.468487]    groups: 0-1
[    0.468493] CPU1 attaching sched-domain:
[    0.468494]  domain 0: span 0-1 level CPU
[    0.468496]   groups: 1 0
[    0.468498]   domain 1: span 0-1 level NODE
[    0.468500]    groups: 0-1
[    0.468596] net_namespace: 1552 bytes
[    0.468596] Booting paravirtualized kernel on bare hardware
[    0.468596] Time: 15:50:04  Date: 04/08/09
[    0.468596] NET: Registered protocol family 16
[    0.468596] node 0 link 0: io port [e000, ffff]
[    0.468596] TOM: 0000000080000000 aka 2048M
[    0.468596] node 0 link 0: mmio [a0000, bffff]
[    0.468596] node 0 link 0: mmio [80000000, ffffffff]
[    0.468596] bus: [00,01] on node 0 link 0
[    0.468596] bus: 00 index 0 io port: [0, ffff]
[    0.468596] bus: 00 index 1 mmio: [a0000, bffff]
[    0.468596] bus: 00 index 2 mmio: [80000000, fcffffffff]
[    0.468596] ACPI: bus type pci registered
[    0.468596] PCI: Using configuration type 1 for base access
[    0.468596] ACPI: EC: Look up EC in DSDT
[    0.479091] ACPI: Interpreter enabled
[    0.479098] ACPI: (supports S0 S3 S4 S5)
[    0.479117] ACPI: Using IOAPIC for interrupt routing
[    0.484603] ACPI: PCI Root Bridge [PCI0] (0000:00)
[    0.484603] PCI: 0000:00:00.0 reg 10 32bit mmio: [f0000000, f7ffffff]
[    0.484603] pci 0000:00:01.0: supports D1
[    0.484603] PCI: 0000:00:0b.0 reg 10 32bit mmio: [fdffc000, fdffdfff]
[    0.484603] PCI: 0000:00:0f.0 reg 10 io port: [ff00, ff07]
[    0.484603] PCI: 0000:00:0f.0 reg 14 io port: [fe00, fe03]
[    0.484603] PCI: 0000:00:0f.0 reg 18 io port: [fd00, fd07]
[    0.484603] PCI: 0000:00:0f.0 reg 1c io port: [fc00, fc03]
[    0.484603] PCI: 0000:00:0f.0 reg 20 io port: [fb00, fb0f]
[    0.484603] PCI: 0000:00:0f.0 reg 24 io port: [f400, f4ff]
[    0.484603] PCI: 0000:00:0f.1 reg 20 io port: [fa00, fa0f]
[    0.484603] PCI: 0000:00:10.0 reg 20 io port: [f900, f91f]
[    0.484603] pci 0000:00:10.0: supports D1
[    0.484603] pci 0000:00:10.0: supports D2
[    0.484603] pci 0000:00:10.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.484603] pci 0000:00:10.0: PME# disabled
[    0.484603] PCI: 0000:00:10.1 reg 20 io port: [f800, f81f]
[    0.484611] pci 0000:00:10.1: supports D1
[    0.484612] pci 0000:00:10.1: supports D2
[    0.484614] pci 0000:00:10.1: PME# supported from D0 D1 D2 D3hot D3cold
[    0.484618] pci 0000:00:10.1: PME# disabled
[    0.484653] PCI: 0000:00:10.2 reg 20 io port: [f700, f71f]
[    0.484671] pci 0000:00:10.2: supports D1
[    0.484672] pci 0000:00:10.2: supports D2
[    0.484674] pci 0000:00:10.2: PME# supported from D0 D1 D2 D3hot D3cold
[    0.484677] pci 0000:00:10.2: PME# disabled
[    0.484713] PCI: 0000:00:10.3 reg 20 io port: [f600, f61f]
[    0.484732] pci 0000:00:10.3: supports D1
[    0.484733] pci 0000:00:10.3: supports D2
[    0.484734] pci 0000:00:10.3: PME# supported from D0 D1 D2 D3hot D3cold
[    0.484738] pci 0000:00:10.3: PME# disabled
[    0.484761] PCI: 0000:00:10.4 reg 10 32bit mmio: [fdfff000, fdfff0ff]
[    0.484793] pci 0000:00:10.4: supports D1
[    0.484794] pci 0000:00:10.4: supports D2
[    0.484796] pci 0000:00:10.4: PME# supported from D0 D1 D2 D3hot D3cold
[    0.484799] pci 0000:00:10.4: PME# disabled
[    0.484853] HPET not enabled in BIOS. You might try hpet=force boot option
[    0.484887] PCI: 0000:00:11.5 reg 10 io port: [f200, f2ff]
[    0.484921] pci 0000:00:11.5: supports D1
[    0.484922] pci 0000:00:11.5: supports D2
[    0.484948] PCI: 0000:00:12.0 reg 10 io port: [f000, f0ff]
[    0.484953] PCI: 0000:00:12.0 reg 14 32bit mmio: [fdffe000, fdffe0ff]
[    0.484983] pci 0000:00:12.0: supports D1
[    0.484984] pci 0000:00:12.0: supports D2
[    0.484986] pci 0000:00:12.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.484989] pci 0000:00:12.0: PME# disabled
[    0.485082] PCI: 0000:01:00.0 reg 10 32bit mmio: [fa000000, faffffff]
[    0.485087] PCI: 0000:01:00.0 reg 14 32bit mmio: [e0000000, efffffff]
[    0.485092] PCI: 0000:01:00.0 reg 18 32bit mmio: [fb000000, fbffffff]
[    0.485106] PCI: 0000:01:00.0 reg 30 32bit mmio: [0, 1ffff]
[    0.485143] PCI: bridge 0000:00:01.0 io port: [e000, efff]
[    0.485147] PCI: bridge 0000:00:01.0 32bit mmio: [fa000000, fcffffff]
[    0.485150] PCI: bridge 0000:00:01.0 32bit mmio pref: [e0000000, efffffff]
[    0.485163] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[    0.542489] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 6 7 *10 11 12)
[    0.542489] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 6 7 10 *11 12)
[    0.542489] ACPI: PCI Interrupt Link [LNKC] (IRQs *3 4 6 7 10 11 12)
[    0.542489] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 6 7 10 11 12) *0, disabled.
[    0.544210] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 6 7 10 11 12) *5
[    0.544365] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 6 7 10 11 12) *0, disabled.
[    0.544520] ACPI: PCI Interrupt Link [LNK0] (IRQs 3 4 6 7 10 11 12) *0, disabled.
[    0.544676] ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 6 7 10 11 12) *0, disabled.
[    0.544882] ACPI: PCI Interrupt Link [ALKA] (IRQs *20)
[    0.545073] ACPI: PCI Interrupt Link [ALKB] (IRQs *21)
[    0.545265] ACPI: PCI Interrupt Link [ALKC] (IRQs *22)
[    0.545494] ACPI: PCI Interrupt Link [ALKD] (IRQs *23)
[    0.545539] Linux Plug and Play Support v0.97 (c) Adam Belay
[    0.545539] pnp: PnP ACPI init
[    0.545539] ACPI: bus type pnp registered
[    0.548795] pnp: PnP ACPI: found 13 devices
[    0.548795] ACPI: ACPI bus type pnp unregistered
[    0.548795] PCI: Using ACPI for IRQ routing
[    0.548795] pci 0000:00:00.0: BAR 0: can't allocate resource
[    0.564043] NET: Registered protocol family 8
[    0.564044] NET: Registered protocol family 20
[    0.564080] NetLabel: Initializing
[    0.564081] NetLabel:  domain hash size = 128
[    0.564083] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.564101] NetLabel:  unlabeled traffic allowed by default
[    0.564146] agpgart-amd64 0000:00:00.0: AGP bridge [1106/0282]
[    0.570161] agpgart-amd64 0000:00:00.0: AGP aperture is 128M @ 0xf0000000
[    0.570161] tracer: 1286 pages allocated for 65536 entries of 80 bytes
[    0.570161]    actual entries 65586
[    0.570161] AppArmor: AppArmor Filesystem Enabled
[    0.570161] ACPI: RTC can wake from S4
[    0.596056] system 00:00: iomem range 0xcf000-0xcffff has been reserved
[    0.596059] system 00:00: iomem range 0xf0000-0xf7fff could not be reserved
[    0.596061] system 00:00: iomem range 0xf8000-0xfbfff could not be reserved
[    0.596064] system 00:00: iomem range 0xfc000-0xfffff could not be reserved
[    0.596067] system 00:00: iomem range 0x7ff00000-0x7fffffff has been reserved
[    0.596069] system 00:00: iomem range 0x7fee0000-0x7fefffff could not be reserved
[    0.596072] system 00:00: iomem range 0xffff0000-0xffffffff could not be reserved
[    0.596075] system 00:00: iomem range 0x0-0x9ffff could not be reserved
[    0.596077] system 00:00: iomem range 0x100000-0x7fedffff could not be reserved
[    0.596080] system 00:00: iomem range 0xfec00000-0xfec00fff could not be reserved
[    0.596082] system 00:00: iomem range 0xfee00000-0xfee00fff could not be reserved
[    0.596085] system 00:00: iomem range 0xfff80000-0xfffeffff could not be reserved
[    0.596094] system 00:02: ioport range 0x4000-0x407f has been reserved
[    0.596101] system 00:02: ioport range 0x5000-0x500f has been reserved
[    0.596108] system 00:03: ioport range 0x4d0-0x4d1 has been reserved
[    0.596110] system 00:03: ioport range 0x800-0x805 has been reserved
[    0.596113] system 00:03: ioport range 0x290-0x297 has been reserved
[    0.601416] pci 0000:00:01.0: PCI bridge, secondary bus 0000:01
[    0.601420] pci 0000:00:01.0:   IO window: 0xe000-0xefff
[    0.601425] pci 0000:00:01.0:   MEM window: 0xfa000000-0xfcffffff
[    0.601429] pci 0000:00:01.0:   PREFETCH window: 0x000000e0000000-0x000000efffffff
[    0.601445] pci 0000:00:01.0: setting latency timer to 64
[    0.601448] bus: 00 index 0 io port: [0, ffff]
[    0.601450] bus: 00 index 1 mmio: [0, ffffffffffffffff]
[    0.601452] bus: 01 index 0 io port: [e000, efff]
[    0.601454] bus: 01 index 1 mmio: [fa000000, fcffffff]
[    0.601456] bus: 01 index 2 mmio: [e0000000, efffffff]
[    0.601458] bus: 01 index 3 mmio: [0, 0]
[    0.601476] NET: Registered protocol family 2
[    0.604048] Switched to high resolution mode on CPU 0
[    0.604210] Switched to high resolution mode on CPU 1
[    0.640129] IP route cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.641297] TCP established hash table entries: 262144 (order: 10, 4194304 bytes)
[    0.646680] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    0.647993] TCP: Hash tables configured (established 262144 bind 65536)
[    0.648020] TCP reno registered
[    0.660162] NET: Registered protocol family 1
[    0.660318] checking if image is initramfs... it is
[    1.322456] Freeing initrd memory: 8463k freed
[    1.334129] audit: initializing netlink socket (disabled)
[    1.334156] type=2000 audit(1239205804.332:1): initialized
[    1.339657] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    1.342644] VFS: Disk quotas dquot_6.5.1
[    1.342752] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.342885] msgmni has been set to 4014
[    1.343042] io scheduler noop registered
[    1.343044] io scheduler anticipatory registered
[    1.343046] io scheduler deadline registered
[    1.343197] io scheduler cfq registered (default)
[    1.343216] PCI: VIA PCI bridge detected.Disabling DAC.
[    1.343322] pci 0000:01:00.0: Boot video device
[    1.383962] Linux agpgart interface v0.103
[    1.383971] Serial: 8250/16550 driver4 ports, IRQ sharing enabled
[    1.384527] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    1.385320] 00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    1.387583] brd: module loaded
[    1.387675] input: Macintosh mouse button emulation as /devices/virtual/input/input0
[    1.387840] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[    1.389231] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.389242] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.409148] mice: PS/2 mouse device common for all mice
[    1.409318] rtc_cmos 00:05: rtc core: registered rtc_cmos as rtc0
[    1.409367] rtc0: alarms up to one year, y3k
[    1.409420] cpuidle: using governor ladder
[    1.409423] cpuidle: using governor menu
[    1.409822] TCP cubic registered
[    1.410093] registered taskstats version 1
[    1.410251]   Magic number: 5:964:840
[    1.410339] tty ptyu6: hash matches
[    1.410437] rtc_cmos 00:05: setting system clock to 2009-04-08 15:50:05 UTC (1239205805)
[    1.410441] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    1.410442] EDD information not available.
[    1.410485] Freeing unused kernel memory: 540k freed
[    1.410912] Write protecting the kernel read-only data: 4352k
[    1.436104] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    1.550699] fuse init (API version 7.9)
[    1.574762] processor ACPI0007:00: registered as cooling_device0
[    1.574838] processor ACPI0007:01: registered as cooling_device1
[    1.577206] thermal LNXTHERM:01: registered as thermal_zone0
[    1.577499] ACPI: Thermal Zone [THRM] (40 C)
[    1.862586] No dock devices found.
[    1.875024] SCSI subsystem initialized
[    1.908062] libata version 3.00 loaded.
[    1.910094] usbcore: registered new interface driver usbfs
[    1.910122] usbcore: registered new interface driver hub
[    1.910190] usbcore: registered new device driver usb
[    1.910614] ACPI: PCI Interrupt Link [ALKA] enabled at IRQ 20
[    1.910624] pata_acpi 0000:00:0f.0: PCI INT B -> Link[ALKA] -> GSI 20 (level, low) -> IRQ 20
[    1.910686] pata_acpi 0000:00:0f.0: PCI INT B disabled
[    1.910705] pata_acpi 0000:00:0f.1: PCI INT A -> Link[ALKA] -> GSI 20 (level, low) -> IRQ 20
[    1.910724] pata_acpi 0000:00:0f.1: PCI INT A disabled
[    1.915337] via-rhine.c:v1.10-LK1.4.3 2007-03-06 Written by Donald Becker
[    1.915344] via-rhine: Broken BIOS detected, avoid_D3 enabled.
[    1.915772] ACPI: PCI Interrupt Link [ALKD] enabled at IRQ 23
[    1.915785] via-rhine 0000:00:12.0: PCI INT A -> Link[ALKD] -> GSI 23 (level, low) -> IRQ 23
[    1.920168] eth0: VIA Rhine II at 0xfdffe000, 00:14:2a:12:4e:77, IRQ 23.
[    1.920878] eth0: MII PHY found at address 1, status 0x7849 advertising 05e1 Link 0000.
[    1.930349] sata_via 0000:00:0f.0: version 2.3
[    1.930382] sata_via 0000:00:0f.0: PCI INT B -> Link[ALKA] -> GSI 20 (level, low) -> IRQ 20
[    1.930447] sata_via 0000:00:0f.0: routed to hard irq line 11
[    1.932710] scsi0 : sata_via
[    1.932893] scsi1 : sata_via
[    1.932938] ata1: SATA max UDMA/133 cmd 0xff00 ctl 0xfe00 bmdma 0xfb00 irq 20
[    1.932941] ata2: SATA max UDMA/133 cmd 0xfd00 ctl 0xfc00 bmdma 0xfb08 irq 20
[    1.933124] USB Universal Host Controller Interface driver v3.0
[    1.935405] Warning! ehci_hcd should always be loaded before uhci_hcd and ohci_hcd, not after
[    2.136077] ata1: SATA link down 1.5 Gbps (SStatus 0 SControl 300)
[    2.340037] ata2: SATA link down 1.5 Gbps (SStatus 0 SControl 300)
[    2.340175] pata_via 0000:00:0f.1: version 0.3.3
[    2.340214] pata_via 0000:00:0f.1: PCI INT A -> Link[ALKA] -> GSI 20 (level, low) -> IRQ 20
[    2.340757] scsi2 : pata_via
[    2.340872] scsi3 : pata_via
[    2.343442] ata3: PATA max UDMA/133 cmd 0x1f0 ctl 0x3f6 bmdma 0xfa00 irq 14
[    2.343445] ata4: PATA max UDMA/133 cmd 0x170 ctl 0x376 bmdma 0xfa08 irq 15
[    2.505955] ata3.00: ATA-7: Maxtor 6L250R0, BAJ41G20, max UDMA/133
[    2.505959] ata3.00: 490234752 sectors, multi 16: LBA48 
[    2.521783] ata3.00: configured for UDMA/133
[    2.892349] ata4.00: ATAPI: SONY    DVD RW DW-G120A, MYS1, max UDMA/66
[    2.892368] ata4.01: ATAPI: SONY    DVD RW DW-G120A, MYS2, max UDMA/66
[    2.892379] ata4.00: limited to UDMA/33 due to 40-wire cable
[    2.892381] ata4.01: limited to UDMA/33 due to 40-wire cable
[    2.924224] ata4.00: configured for UDMA/33
[    2.956217] ata4.01: configured for UDMA/33
[    2.956269] isa bounce pool size: 16 pages
[    2.956360] scsi 2:0:0:0: Direct-Access     ATA      Maxtor 6L250R0   BAJ4 PQ: 0 ANSI: 5
[    2.958342] scsi 3:0:0:0: CD-ROM            SONY     DVD RW DW-G120A  MYS1 PQ: 0 ANSI: 5
[    2.960357] scsi 3:0:1:0: CD-ROM            SONY     DVD RW DW-G120A  MYS2 PQ: 0 ANSI: 5
[    2.960770] ACPI: PCI Interrupt Link [ALKB] enabled at IRQ 21
[    2.960781] uhci_hcd 0000:00:10.0: PCI INT A -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[    2.960793] uhci_hcd 0000:00:10.0: UHCI Host Controller
[    2.960865] uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 1
[    2.960916] uhci_hcd 0000:00:10.0: irq 21, io base 0x0000f900
[    2.961118] usb usb1: configuration #1 chosen from 1 choice
[    2.961146] hub 1-0:1.0: USB hub found
[    2.961155] hub 1-0:1.0: 2 ports detected
[    3.168314] uhci_hcd 0000:00:10.1: PCI INT A -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[    3.168327] uhci_hcd 0000:00:10.1: UHCI Host Controller
[    3.168358] uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 2
[    3.168382] uhci_hcd 0000:00:10.1: irq 21, io base 0x0000f800
[    3.168483] usb usb2: configuration #1 chosen from 1 choice
[    3.168512] hub 2-0:1.0: USB hub found
[    3.168520] hub 2-0:1.0: 2 ports detected
[    3.376221] uhci_hcd 0000:00:10.2: PCI INT B -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[    3.376233] uhci_hcd 0000:00:10.2: UHCI Host Controller
[    3.376271] uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 3
[    3.376296] uhci_hcd 0000:00:10.2: irq 21, io base 0x0000f700
[    3.376395] usb usb3: configuration #1 chosen from 1 choice
[    3.376424] hub 3-0:1.0: USB hub found
[    3.376432] hub 3-0:1.0: 2 ports detected
[    3.488013] usb 2-1: new low speed USB device using uhci_hcd and address 2
[    3.584372] uhci_hcd 0000:00:10.3: PCI INT B -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[    3.584386] uhci_hcd 0000:00:10.3: UHCI Host Controller
[    3.584419] uhci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 4
[    3.584445] uhci_hcd 0000:00:10.3: irq 21, io base 0x0000f600
[    3.584570] usb usb4: configuration #1 chosen from 1 choice
[    3.584601] hub 4-0:1.0: USB hub found
[    3.584610] hub 4-0:1.0: 2 ports detected
[    3.592311] scsi 2:0:0:0: Attached scsi generic sg0 type 0
[    3.592350] scsi 3:0:0:0: Attached scsi generic sg1 type 5
[    3.592386] scsi 3:0:1:0: Attached scsi generic sg2 type 5
[    3.663539] usb 2-1: configuration #1 chosen from 1 choice
[    3.776017] usb 2-2: new low speed USB device using uhci_hcd and address 3
[    3.792335] ehci_hcd 0000:00:10.4: PCI INT C -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[    3.792356] ehci_hcd 0000:00:10.4: EHCI Host Controller
[    3.792387] ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus number 5
[    3.792446] ehci_hcd 0000:00:10.4: irq 21, io mem 0xfdfff000
[    3.904015] ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[    3.904166] usb usb5: configuration #1 chosen from 1 choice
[    3.904200] hub 5-0:1.0: USB hub found
[    3.904211] hub 5-0:1.0: 8 ports detected
[    4.161222] Driver 'sd' needs updating - please use bus_type methods
[    4.161416] sd 2:0:0:0: [sda] 490234752 512-byte hardware sectors (251000 MB)
[    4.161433] sd 2:0:0:0: [sda] Write Protect is off
[    4.161436] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    4.161466] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.161548] sd 2:0:0:0: [sda] 490234752 512-byte hardware sectors (251000 MB)
[    4.161938] sd 2:0:0:0: [sda] Write Protect is off
[    4.161941] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    4.161974] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.161979]  sda:<4>Driver 'sr' needs updating - please use bus_type methods
[    4.189438]  sda1 sda2 < sda5 >
[    4.213706] sd 2:0:0:0: [sda] Attached SCSI disk
[    4.228475] sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray
[    4.228484] Uniform CD-ROM driver Revision: 3.20
[    4.228683] sr 3:0:0:0: Attached scsi CD-ROM sr0
[    4.232887] sr1: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray
[    4.233067] sr 3:0:1:0: Attached scsi CD-ROM sr1
[    4.301022] usb 2-2: device not accepting address 3, error -71
[    4.357055] hub 2-0:1.0: unable to enumerate USB device on port 2
[    4.468081] usb 2-1: USB disconnect, address 2
[    4.468258] usbcore: registered new interface driver hiddev
[    4.708016] usb 2-1: new low speed USB device using uhci_hcd and address 5
[    4.882306] usb 2-1: configuration #1 chosen from 1 choice
[    5.124016] usb 2-2: new low speed USB device using uhci_hcd and address 6
[    5.299147] usb 2-2: configuration #1 chosen from 1 choice
[    5.461129] input: 3Dconnexion SpaceNavigator as /devices/pci0000:00/0000:00:10.1/usb2/2-2/2-2:1.0/input/input2
[    5.461267] input,hidraw0: USB HID v1.10 Multi-Axis Controller [3Dconnexion SpaceNavigator] on usb-0000:00:10.1-2
[    5.461292] usbcore: registered new interface driver usbhid
[    5.461296] usbhid: v2.6:USB HID core driver
[    5.567508] PM: Starting manual resume from disk
[    5.567512] PM: Resume from partition 8:5
[    5.567513] PM: Checking hibernation image.
[    5.567681] PM: Resume from disk failed.
[    5.614951] kjournald starting.  Commit interval 5 seconds
[    5.614965] EXT3-fs: mounted filesystem with ordered data mode.
[   11.311197] udevd version 124 started
[   11.767498] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[   11.806552] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[   11.965045] input: Power Button (FF) as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
[   11.989091] ACPI: Power Button (FF) [PWRF]
[   11.989203] input: Power Button (CM) as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input4
[   12.021060] ACPI: Power Button (CM) [PWRB]
[   12.058565] parport_pc 00:0a: reported by Plug and Play ACPI
[   12.058655] parport0: PC-style at 0x378 (0x778), irq 7, dma 3 [PCSPP,TRISTATE,COMPAT,ECP,DMA]
[   12.583299] nvidia: module license 'NVIDIA' taints kernel.
[   12.872706] nvidia 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[   12.873184] NVRM: loading NVIDIA UNIX x86_64 Kernel Module  180.11  Wed Nov 26 10:53:43 PST 2008
[   13.021548] rt2500pci 0000:00:0b.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
[   13.037506] phy0: Selected rate control algorithm 'pid'
[   13.093226] input: PC Speaker as /devices/platform/pcspkr/input/input5
[   13.093596] input: Wacom Bamboo1 as /devices/pci0000:00/0000:00:10.1/usb2/2-1/2-1:1.0/input/input6
[   13.184721] Registered led device: rt2500pci-phy0:radio
[   13.264873] usbcore: registered new interface driver wacom
[   13.266389] wacom: v1.49:USB Wacom Graphire and Wacom Intuos tablet driver
[   13.326791] logips2pp: Detected unknown logitech mouse model 90
[   13.425929] ACPI: PCI Interrupt Link [ALKC] enabled at IRQ 22
[   13.425941] VIA 82xx Audio 0000:00:11.5: PCI INT C -> Link[ALKC] -> GSI 22 (level, low) -> IRQ 22
[   13.426118] VIA 82xx Audio 0000:00:11.5: setting latency timer to 64
[   13.803613] input: ImExPS/2 Logitech Explorer Mouse as /devices/platform/i8042/serio1/input/input7
[   15.282863] lp0: using parport0 (interrupt-driven).
[   15.481799] Adding 6016300k swap on /dev/sda5.  Priority:-1 extents:1 across:6016300k
[   15.981365] EXT3 FS on sda1, internal journal
[   16.964649] type=1505 audit(1239205820.817:2): operation="profile_load" name="/usr/share/gdm/guest-session/Xsession" name2="default" pid=4155
[   17.167409] type=1505 audit(1239205821.021:3): operation="profile_load" name="/usr/lib/cups/backend/cups-pdf" name2="default" pid=4160
[   17.167749] type=1505 audit(1239205821.021:4): operation="profile_load" name="/usr/sbin/cupsd" name2="default" pid=4160
[   17.269796] type=1505 audit(1239205821.125:5): operation="profile_load" name="/usr/sbin/mysqld" name2="default" pid=4164
[   17.401380] ip_tables: (C) 2000-2006 Netfilter Core Team
[   18.260244] wlan0: authenticate with AP 00:18:4d:64:09:b6
[   18.261618] wlan0: authenticated
[   18.261624] wlan0: associate with AP 00:18:4d:64:09:b6
[   18.263684] wlan0: RX AssocResp from 00:18:4d:64:09:b6 (capab=0x431 status=0 aid=2)
[   18.263687] wlan0: associated
[   18.762265] NET: Registered protocol family 17
[   22.879426] NET: Registered protocol family 10
[   22.880185] lo: Disabled Privacy Extensions
[   23.710293] ACPI: WMI: Mapper loaded
[   24.714413] warning: `avahi-daemon' uses 32-bit capabilities (legacy support in use)
[   27.012929] ppdev: user-space parallel port driver
[   32.915743] Bluetooth: Core ver 2.13
[   32.916494] NET: Registered protocol family 31
[   32.916497] Bluetooth: HCI device and connection manager initialized
[   32.916504] Bluetooth: HCI socket layer initialized
[   32.934493] Bluetooth: L2CAP ver 2.11
[   32.934500] Bluetooth: L2CAP socket layer initialized
[   32.977922] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   32.977929] Bluetooth: BNEP filters: protocol multicast
[   33.165770] Bluetooth: RFCOMM socket layer initialized
[   33.165809] Bluetooth: RFCOMM TTY layer initialized
[   33.165812] Bluetooth: RFCOMM ver 1.10
[   33.179950] Bridge firewalling registered
[   33.196291] Bluetooth: SCO (Voice Link) ver 0.6
[   33.196298] Bluetooth: SCO socket layer initialized
[   33.457009] wlan0: no IPv6 routers present
[   35.934408] agpgart-amd64 0000:00:00.0: AGP 3.0 bridge
[   35.934424] agpgart: Xorg tried to set rate=x0. Setting to AGP3 x4 mode.
[   35.934430] agpgart-amd64 0000:00:00.0: putting AGP V3 device into 4x mode
[   35.934505] nvidia 0000:01:00.0: putting AGP V3 device into 4x mode
[   37.328831] eth0: link down
[   37.329720] ADDRCONF(NETDEV_UP): eth0: link is not ready

Re: libata errors

[Robert Hancock]

Mikael Pettersson wrote:
> Jeff Garzik writes:
>  > If its ahci, try using ata_piix.  I am beginning to think that ATAPI on 
>  > AHCI is broken.
> 
> I use a SATAPI DVD-writer on AHCI (Intel 965) on a semi-regular
> basis, and it's never had any issues with recent 2.6.2x kernels.

I tend to doubt that the driver is doing the wrong thing. I suspect it's 
more that ATAPI devices on SATA are about as standards-compliant in 
general as ATAPI devices on PATA (which is to say not very good) and 
some of them break if the controller does something slightly differently 
or with different timing, which is quite possible in AHCI mode.

Tejun was looking at some SATA analyzer traces of a few drives that were 
apparently failing consistently on AHCI. Tejun, did you get anywhere 
with this? Even if the trace doesn't reveal any invalid behavior, if it 
includes high-precision timing data it would be interesting to compare 
that to the results on a working controller..

Re: libata errors

[Tejun Heo]

Hello,

Robert Hancock wrote:
> I tend to doubt that the driver is doing the wrong thing.

I've tested everything including recording and cd ripping and most of
my devices seem happy but you never know.

> I suspect it's more that ATAPI devices on SATA are about as
> standards-compliant in general as ATAPI devices on PATA (which is to
> say not very good) and some of them break if the controller does
> something slightly differently or with different timing, which is
> quite possible in AHCI mode.
> 
> Tejun was looking at some SATA analyzer traces of a few drives that were
> apparently failing consistently on AHCI. Tejun, did you get anywhere
> with this?

I need to take a deeper look at that but in general I couldn't find
anything standing out.  After a series of ATAPI changes (IIRC, the DMA
padding change was the last thing), most ATAPI bug reports got
resolved and I'm not getting many ATAPI failure bug reports other than
recent ahci ones anymore.  I'm not sure whethre it indicates that we
finally got it right or something else tho.

Jeff, what kind of failure are you seeing?

Thanks.

-- 
tejun

Re: libata errors

[Jeff Garzik]

Tejun Heo wrote:
> Hello,
> 
> Robert Hancock wrote:
>> I tend to doubt that the driver is doing the wrong thing.
> 
> I've tested everything including recording and cd ripping and most of
> my devices seem happy but you never know.
> 
>> I suspect it's more that ATAPI devices on SATA are about as
>> standards-compliant in general as ATAPI devices on PATA (which is to
>> say not very good) and some of them break if the controller does
>> something slightly differently or with different timing, which is
>> quite possible in AHCI mode.
>>
>> Tejun was looking at some SATA analyzer traces of a few drives that were
>> apparently failing consistently on AHCI. Tejun, did you get anywhere
>> with this?
> 
> I need to take a deeper look at that but in general I couldn't find
> anything standing out.  After a series of ATAPI changes (IIRC, the DMA
> padding change was the last thing), most ATAPI bug reports got
> resolved and I'm not getting many ATAPI failure bug reports other than
> recent ahci ones anymore.  I'm not sure whethre it indicates that we
> finally got it right or something else tho.
> 
> Jeff, what kind of failure are you seeing?

Hardware:  SATA DVD-ROM/CD-RW devices, AHCI, ICH$foo

Reading and writing data CDs and DVDs works fine.  Ripping audio CDs 
using gRip application leads to timeouts almost immediately.

	Jeff

Re: libata errors

[Robert Hancock]

Jeff Garzik wrote:
> Tejun Heo wrote:
>> Hello,
>>
>> Robert Hancock wrote:
>>> I tend to doubt that the driver is doing the wrong thing.
>>
>> I've tested everything including recording and cd ripping and most of
>> my devices seem happy but you never know.
>>
>>> I suspect it's more that ATAPI devices on SATA are about as
>>> standards-compliant in general as ATAPI devices on PATA (which is to
>>> say not very good) and some of them break if the controller does
>>> something slightly differently or with different timing, which is
>>> quite possible in AHCI mode.
>>>
>>> Tejun was looking at some SATA analyzer traces of a few drives that were
>>> apparently failing consistently on AHCI. Tejun, did you get anywhere
>>> with this?
>>
>> I need to take a deeper look at that but in general I couldn't find
>> anything standing out.  After a series of ATAPI changes (IIRC, the DMA
>> padding change was the last thing), most ATAPI bug reports got
>> resolved and I'm not getting many ATAPI failure bug reports other than
>> recent ahci ones anymore.  I'm not sure whethre it indicates that we
>> finally got it right or something else tho.
>>
>> Jeff, what kind of failure are you seeing?
> 
> Hardware:  SATA DVD-ROM/CD-RW devices, AHCI, ICH$foo
> 
> Reading and writing data CDs and DVDs works fine.  Ripping audio CDs 
> using gRip application leads to timeouts almost immediately.

Well, it's a potentially unusual transfer length compared to other 
commands, which might be part of it. Do you have some error output? It 
would be useful to look at the reported failed CDB.