* [2.6.23-rc3] Regression: "spurious completions during NCQ"
@ 2007-08-13 18:05 Frans Pop
2007-08-14 10:11 ` Tejun Heo
0 siblings, 1 reply; 8+ messages in thread
From: Frans Pop @ 2007-08-13 18:05 UTC (permalink / raw)
To: linux-ide
[-- Attachment #1: Type: text/plain, Size: 1131 bytes --]
Hi,
I've been running 2.6.23-rc2 without problems. Today I compiled 2.6.23-rc3
and shortly after boot got the following messages in my logs:
kernel: ata1.00: spurious completions during NCQ issue=0x0 SAct=0x7ffc7 FIS=005040a1:00000020
kernel: ata1.00: cmd 61/30:00:73:96:db/00:00:04:00:00/40 tag 0 cdb 0x0 data 24576 out
kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
[...]
kernel: ata1: soft resetting port
kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
kernel: ata1.00: configured for UDMA/133
kernel: ata1: EH complete
kernel: sd 0:0:0:0: [sda] 321672960 512-byte hardware sectors (164697 MB)
kernel: sd 0:0:0:0: [sda] Write Protect is off
kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
A grep in the logs shows that the first error has never occurred before.
I was doing nothing special at the time (SVN checkout, not even running X).
Full kern.log for that boot and lsmod attached.
My system is Debian unstable with 2.6.23 built from Linus' git tree.
Cheers,
Frans Pop
[-- Attachment #2: kern.log --]
[-- Type: text/x-log, Size: 45185 bytes --]
Aug 13 19:32:42 faramir kernel: klogd 1.5.0#1, log source = /proc/kmsg started.
Aug 13 19:32:42 faramir kernel: Linux version 2.6.23-rc3 (root@faramir) (gcc version 4.1.3 20070718 (prerelease) (Debian 4.1.2-14)) #1 SMP Mon Aug 13 17:24:51 CEST 2007
Aug 13 19:32:42 faramir kernel: Command line: root=/dev/mapper/main-root ro quiet vga=791
Aug 13 19:32:42 faramir kernel: BIOS-provided physical RAM map:
Aug 13 19:32:42 faramir kernel: BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
Aug 13 19:32:42 faramir kernel: BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
Aug 13 19:32:42 faramir kernel: BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
Aug 13 19:32:42 faramir kernel: BIOS-e820: 0000000000100000 - 000000007fe74000 (usable)
Aug 13 19:32:42 faramir kernel: BIOS-e820: 000000007fe74000 - 000000007fee9000 (ACPI NVS)
Aug 13 19:32:42 faramir kernel: BIOS-e820: 000000007fee9000 - 000000007feec000 (usable)
Aug 13 19:32:42 faramir kernel: BIOS-e820: 000000007feec000 - 000000007feff000 (ACPI data)
Aug 13 19:32:42 faramir kernel: BIOS-e820: 000000007feff000 - 000000007ff00000 (usable)
Aug 13 19:32:42 faramir kernel: Entering add_active_range(0, 0, 159) 0 entries of 3200 used
Aug 13 19:32:42 faramir kernel: Entering add_active_range(0, 256, 523892) 1 entries of 3200 used
Aug 13 19:32:42 faramir kernel: Entering add_active_range(0, 524009, 524012) 2 entries of 3200 used
Aug 13 19:32:42 faramir kernel: Entering add_active_range(0, 524031, 524032) 3 entries of 3200 used
Aug 13 19:32:42 faramir kernel: end_pfn_map = 524032
Aug 13 19:32:42 faramir kernel: DMI 2.3 present.
Aug 13 19:32:42 faramir kernel: ACPI: RSDP 000FE020, 0014 (r0 INTEL )
Aug 13 19:32:42 faramir kernel: ACPI: RSDT 7FEFDE48, 0058 (r1 INTEL D945GCZ FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: FACP 7FEFCF10, 0074 (r1 INTEL D945GCZ FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: DSDT 7FEF7010, 4348 (r1 INTEL D945GCZ FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: FACS 7FEE2C40, 0040
Aug 13 19:32:42 faramir kernel: ACPI: APIC 7FEFCE10, 0078 (r1 INTEL D945GCZ FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: WDDT 7FEF6F90, 0040 (r1 INTEL D945GCZ FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: MCFG 7FEF6F10, 003C (r1 INTEL D945GCZ FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: ASF! 7FEFCD10, 00A6 (r32 INTEL D945GCZ FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: HPET 7FEF6E90, 0038 (r1 INTEL D945GCZ FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: SSDT 7FEFDC10, 01BC (r1 INTEL CpuPm FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: SSDT 7FEFDA10, 0175 (r1 INTEL Cpu0Ist FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: SSDT 7FEFD810, 0175 (r1 INTEL Cpu1Ist FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: SSDT 7FEFD610, 0175 (r1 INTEL Cpu2Ist FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: SSDT 7FEFD410, 0175 (r1 INTEL Cpu3Ist FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: SSDT 7FEF6E10, 002F (r1 INTEL SetupVar FF9 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: ACPI: TCPA 7FEE4D90, 0032 (r1 INTEL TIANO 2 MSFT 1000013)
Aug 13 19:32:42 faramir kernel: No NUMA configuration found
Aug 13 19:32:42 faramir kernel: Faking a node at 0000000000000000-000000007ff00000
Aug 13 19:32:42 faramir kernel: Entering add_active_range(0, 0, 159) 0 entries of 3200 used
Aug 13 19:32:42 faramir kernel: Entering add_active_range(0, 256, 523892) 1 entries of 3200 used
Aug 13 19:32:42 faramir kernel: Entering add_active_range(0, 524009, 524012) 2 entries of 3200 used
Aug 13 19:32:42 faramir kernel: Entering add_active_range(0, 524031, 524032) 3 entries of 3200 used
Aug 13 19:32:42 faramir kernel: Bootmem setup node 0 0000000000000000-000000007ff00000
Aug 13 19:32:42 faramir kernel: Zone PFN ranges:
Aug 13 19:32:42 faramir kernel: DMA 0 -> 4096
Aug 13 19:32:42 faramir kernel: DMA32 4096 -> 1048576
Aug 13 19:32:42 faramir kernel: Normal 1048576 -> 1048576
Aug 13 19:32:42 faramir kernel: Movable zone start PFN for each node
Aug 13 19:32:42 faramir kernel: early_node_map[4] active PFN ranges
Aug 13 19:32:42 faramir kernel: 0: 0 -> 159
Aug 13 19:32:42 faramir kernel: 0: 256 -> 523892
Aug 13 19:32:42 faramir kernel: 0: 524009 -> 524012
Aug 13 19:32:42 faramir kernel: 0: 524031 -> 524032
Aug 13 19:32:42 faramir kernel: On node 0 totalpages: 523799
Aug 13 19:32:42 faramir kernel: DMA zone: 56 pages used for memmap
Aug 13 19:32:42 faramir kernel: DMA zone: 1015 pages reserved
Aug 13 19:32:42 faramir kernel: DMA zone: 2928 pages, LIFO batch:0
Aug 13 19:32:42 faramir kernel: DMA32 zone: 7108 pages used for memmap
Aug 13 19:32:42 faramir kernel: DMA32 zone: 512692 pages, LIFO batch:31
Aug 13 19:32:42 faramir kernel: Normal zone: 0 pages used for memmap
Aug 13 19:32:42 faramir kernel: Movable zone: 0 pages used for memmap
Aug 13 19:32:42 faramir kernel: ACPI: PM-Timer IO Port: 0x408
Aug 13 19:32:42 faramir kernel: ACPI: Local APIC address 0xfee00000
Aug 13 19:32:42 faramir kernel: ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
Aug 13 19:32:42 faramir kernel: Processor #0 (Bootup-CPU)
Aug 13 19:32:42 faramir kernel: ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
Aug 13 19:32:42 faramir kernel: Processor #1
Aug 13 19:32:42 faramir kernel: ACPI: LAPIC (acpi_id[0x03] lapic_id[0x82] disabled)
Aug 13 19:32:42 faramir kernel: ACPI: LAPIC (acpi_id[0x04] lapic_id[0x83] disabled)
Aug 13 19:32:42 faramir kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
Aug 13 19:32:42 faramir kernel: ACPI: LAPIC_NMI (acpi_id[0x02] dfl dfl lint[0x1])
Aug 13 19:32:42 faramir kernel: ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
Aug 13 19:32:42 faramir kernel: IOAPIC[0]: apic_id 2, address 0xfec00000, GSI 0-23
Aug 13 19:32:42 faramir kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Aug 13 19:32:42 faramir kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Aug 13 19:32:42 faramir kernel: ACPI: IRQ0 used by override.
Aug 13 19:32:42 faramir kernel: ACPI: IRQ2 used by override.
Aug 13 19:32:42 faramir kernel: ACPI: IRQ9 used by override.
Aug 13 19:32:42 faramir kernel: Setting APIC routing to flat
Aug 13 19:32:42 faramir kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000
Aug 13 19:32:42 faramir kernel: Using ACPI (MADT) for SMP configuration information
Aug 13 19:32:42 faramir kernel: swsusp: Registered nosave memory region: 000000000009f000 - 00000000000a0000
Aug 13 19:32:42 faramir kernel: swsusp: Registered nosave memory region: 00000000000a0000 - 00000000000e0000
Aug 13 19:32:42 faramir kernel: swsusp: Registered nosave memory region: 00000000000e0000 - 0000000000100000
Aug 13 19:32:42 faramir kernel: swsusp: Registered nosave memory region: 000000007fe74000 - 000000007fee9000
Aug 13 19:32:42 faramir kernel: swsusp: Registered nosave memory region: 000000007feec000 - 000000007feff000
Aug 13 19:32:42 faramir kernel: Allocating PCI resources starting at 80000000 (gap: 7ff00000:80100000)
Aug 13 19:32:42 faramir kernel: SMP: Allowing 4 CPUs, 2 hotplug CPUs
Aug 13 19:32:42 faramir kernel: PERCPU: Allocating 35040 bytes of per cpu data
Aug 13 19:32:42 faramir kernel: Built 1 zonelists in Node order. Total pages: 515620
Aug 13 19:32:42 faramir kernel: Policy zone: DMA32
Aug 13 19:32:42 faramir kernel: Kernel command line: root=/dev/mapper/main-root ro quiet vga=791
Aug 13 19:32:42 faramir kernel: Initializing CPU#0
Aug 13 19:32:42 faramir kernel: PID hash table entries: 4096 (order: 12, 32768 bytes)
Aug 13 19:32:42 faramir kernel: time.c: Detected 3199.884 MHz processor.
Aug 13 19:32:42 faramir kernel: Console: colour dummy device 80x25
Aug 13 19:32:42 faramir kernel: console [tty0] enabled
Aug 13 19:32:42 faramir kernel: Checking aperture...
Aug 13 19:32:42 faramir kernel: Calgary: detecting Calgary via BIOS EBDA area
Aug 13 19:32:42 faramir kernel: Calgary: Unable to locate Rio Grande table in EBDA - bailing!
Aug 13 19:32:42 faramir kernel: Memory: 2058208k/2096128k available (2016k kernel code, 36988k reserved, 949k data, 304k init)
Aug 13 19:32:42 faramir kernel: Calibrating delay using timer specific routine.. 6405.18 BogoMIPS (lpj=12810360)
Aug 13 19:32:42 faramir kernel: Security Framework v1.0.0 initialized
Aug 13 19:32:42 faramir kernel: SELinux: Disabled at boot.
Aug 13 19:32:42 faramir kernel: Capability LSM initialized
Aug 13 19:32:42 faramir kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
Aug 13 19:32:42 faramir kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
Aug 13 19:32:42 faramir kernel: Mount-cache hash table entries: 256
Aug 13 19:32:42 faramir kernel: CPU: Trace cache: 12K uops, L1 D cache: 16K
Aug 13 19:32:42 faramir kernel: CPU: L2 cache: 1024K
Aug 13 19:32:42 faramir kernel: CPU 0/0 -> Node 0
Aug 13 19:32:42 faramir kernel: using mwait in idle threads.
Aug 13 19:32:42 faramir kernel: CPU: Physical Processor ID: 0
Aug 13 19:32:42 faramir kernel: CPU: Processor Core ID: 0
Aug 13 19:32:42 faramir kernel: CPU0: Thermal monitoring enabled (TM1)
Aug 13 19:32:42 faramir kernel: SMP alternatives: switching to UP code
Aug 13 19:32:42 faramir kernel: ACPI: Core revision 20070126
Aug 13 19:32:42 faramir kernel: Using local APIC timer interrupts.
Aug 13 19:32:42 faramir kernel: result 12499507
Aug 13 19:32:42 faramir kernel: Detected 12.499 MHz APIC timer.
Aug 13 19:32:42 faramir kernel: SMP alternatives: switching to SMP code
Aug 13 19:32:42 faramir kernel: Booting processor 1/2 APIC 0x1
Aug 13 19:32:42 faramir kernel: Initializing CPU#1
Aug 13 19:32:42 faramir kernel: Calibrating delay using timer specific routine.. 6400.07 BogoMIPS (lpj=12800147)
Aug 13 19:32:42 faramir kernel: CPU: Trace cache: 12K uops, L1 D cache: 16K
Aug 13 19:32:42 faramir kernel: CPU: L2 cache: 1024K
Aug 13 19:32:42 faramir kernel: CPU 1/1 -> Node 0
Aug 13 19:32:42 faramir kernel: CPU: Physical Processor ID: 0
Aug 13 19:32:42 faramir kernel: CPU: Processor Core ID: 1
Aug 13 19:32:42 faramir kernel: CPU1: Thermal monitoring enabled (TM1)
Aug 13 19:32:42 faramir kernel: Intel(R) Pentium(R) D CPU 3.20GHz stepping 07
Aug 13 19:32:42 faramir kernel: checking TSC synchronization [CPU#0 -> CPU#1]: passed.
Aug 13 19:32:42 faramir kernel: Brought up 2 CPUs
Aug 13 19:32:42 faramir kernel: NET: Registered protocol family 16
Aug 13 19:32:42 faramir kernel: ACPI: bus type pci registered
Aug 13 19:32:42 faramir kernel: PCI: Found Intel Corporation 945G/GZ/P/PL Express Memory Controller Hub without MMCONFIG support.
Aug 13 19:32:42 faramir kernel: PCI: Using configuration type 1
Aug 13 19:32:42 faramir kernel: ACPI: EC: Look up EC in DSDT
Aug 13 19:32:42 faramir kernel: ACPI: Interpreter enabled
Aug 13 19:32:42 faramir kernel: ACPI: (supports S0 S1 S3)
Aug 13 19:32:42 faramir kernel: ACPI: Using IOAPIC for interrupt routing
Aug 13 19:32:42 faramir kernel: ACPI: PCI Root Bridge [PCI0] (0000:00)
Aug 13 19:32:42 faramir kernel: PCI quirk: region 0400-047f claimed by ICH6 ACPI/GPIO/TCO
Aug 13 19:32:42 faramir kernel: PCI quirk: region 0500-053f claimed by ICH6 GPIO
Aug 13 19:32:42 faramir kernel: PCI: Transparent bridge - 0000:00:1e.0
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P32_._PRT]
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX0._PRT]
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX2._PRT]
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX3._PRT]
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX4._PRT]
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX5._PRT]
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 7 9 10 *11 12)
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 7 9 *10 11 12)
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 7 9 *10 11 12)
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 7 9 10 *11 12)
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 7 9 10 11 12) *0, disabled.
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 7 9 10 11 12) *0, disabled.
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 7 *9 10 11 12)
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 7 *9 10 11 12)
Aug 13 19:32:42 faramir kernel: Linux Plug and Play Support v0.97 (c) Adam Belay
Aug 13 19:32:42 faramir kernel: pnp: PnP ACPI init
Aug 13 19:32:42 faramir kernel: ACPI: bus type pnp registered
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0A03
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0C02
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0200
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0B00
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0C04
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0800
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0C02
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0C31
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0C02
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0700
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0401
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0501
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0003
Aug 13 19:32:42 faramir kernel: pnp: ACPI device : hid PNP0103
Aug 13 19:32:42 faramir kernel: pnp: PnP ACPI: found 14 devices
Aug 13 19:32:42 faramir kernel: ACPI: ACPI bus type pnp unregistered
Aug 13 19:32:42 faramir kernel: usbcore: registered new interface driver usbfs
Aug 13 19:32:42 faramir kernel: usbcore: registered new interface driver hub
Aug 13 19:32:42 faramir kernel: usbcore: registered new device driver usb
Aug 13 19:32:42 faramir kernel: PCI: Using ACPI for IRQ routing
Aug 13 19:32:42 faramir kernel: PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
Aug 13 19:32:42 faramir kernel: PCI-GART: No AMD northbridge found.
Aug 13 19:32:42 faramir kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
Aug 13 19:32:42 faramir kernel: hpet0: 3 64-bit timers, 14318180 Hz
Aug 13 19:32:42 faramir kernel: ACPI: RTC can wake from S4
Aug 13 19:32:42 faramir kernel: Time: tsc clocksource has been installed.
Aug 13 19:32:42 faramir kernel: pnp: the driver 'system' has been registered
Aug 13 19:32:42 faramir kernel: pnp: match found with the PnP device '00:01' and the driver 'system'
Aug 13 19:32:42 faramir kernel: pnp: 00:01: iomem range 0xf0000000-0xf3ffffff has been reserved
Aug 13 19:32:42 faramir kernel: pnp: 00:01: iomem range 0xfed13000-0xfed13fff has been reserved
Aug 13 19:32:42 faramir kernel: pnp: 00:01: iomem range 0xfed14000-0xfed17fff has been reserved
Aug 13 19:32:42 faramir kernel: pnp: 00:01: iomem range 0xfed18000-0xfed18fff has been reserved
Aug 13 19:32:42 faramir kernel: pnp: match found with the PnP device '00:06' and the driver 'system'
Aug 13 19:32:42 faramir kernel: pnp: 00:06: ioport range 0x500-0x53f has been reserved
Aug 13 19:32:42 faramir kernel: pnp: 00:06: ioport range 0x400-0x47f has been reserved
Aug 13 19:32:42 faramir kernel: pnp: 00:06: ioport range 0x680-0x6ff has been reserved
Aug 13 19:32:42 faramir kernel: pnp: match found with the PnP device '00:07' and the driver 'system'
Aug 13 19:32:42 faramir kernel: pnp: 00:07: iomem range 0xfed40000-0xfed44fff has been reserved
Aug 13 19:32:42 faramir kernel: pnp: match found with the PnP device '00:08' and the driver 'system'
Aug 13 19:32:42 faramir kernel: PCI: Bridge: 0000:00:01.0
Aug 13 19:32:42 faramir kernel: IO window: 2000-2fff
Aug 13 19:32:42 faramir kernel: MEM window: 90300000-903fffff
Aug 13 19:32:42 faramir kernel: PREFETCH window: 80000000-8fffffff
Aug 13 19:32:42 faramir kernel: PCI: Bridge: 0000:00:1c.0
Aug 13 19:32:42 faramir kernel: IO window: 1000-1fff
Aug 13 19:32:42 faramir kernel: MEM window: 90100000-902fffff
Aug 13 19:32:42 faramir kernel: PREFETCH window: disabled.
Aug 13 19:32:42 faramir kernel: PCI: Bridge: 0000:00:1c.2
Aug 13 19:32:42 faramir kernel: IO window: disabled.
Aug 13 19:32:42 faramir kernel: MEM window: 90500000-905fffff
Aug 13 19:32:42 faramir kernel: PREFETCH window: disabled.
Aug 13 19:32:42 faramir kernel: PCI: Bridge: 0000:00:1c.3
Aug 13 19:32:42 faramir kernel: IO window: disabled.
Aug 13 19:32:42 faramir kernel: MEM window: 90600000-906fffff
Aug 13 19:32:42 faramir kernel: PREFETCH window: disabled.
Aug 13 19:32:42 faramir kernel: PCI: Bridge: 0000:00:1c.4
Aug 13 19:32:42 faramir kernel: IO window: disabled.
Aug 13 19:32:42 faramir kernel: MEM window: 90700000-907fffff
Aug 13 19:32:42 faramir kernel: PREFETCH window: disabled.
Aug 13 19:32:42 faramir kernel: PCI: Bridge: 0000:00:1c.5
Aug 13 19:32:42 faramir kernel: IO window: disabled.
Aug 13 19:32:42 faramir kernel: MEM window: 90800000-908fffff
Aug 13 19:32:42 faramir kernel: PREFETCH window: disabled.
Aug 13 19:32:42 faramir kernel: PCI: Bridge: 0000:00:1e.0
Aug 13 19:32:42 faramir kernel: IO window: disabled.
Aug 13 19:32:42 faramir kernel: MEM window: 90000000-900fffff
Aug 13 19:32:42 faramir kernel: PREFETCH window: disabled.
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:01.0[A] -> GSI 16 (level, low) -> IRQ 16
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:01.0 to 64
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1c.0[A] -> GSI 17 (level, low) -> IRQ 17
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.0 to 64
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1c.2[C] -> GSI 18 (level, low) -> IRQ 18
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.2 to 64
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1c.3[D] -> GSI 19 (level, low) -> IRQ 19
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.3 to 64
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1c.4[A] -> GSI 17 (level, low) -> IRQ 17
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.4 to 64
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1c.5[B] -> GSI 16 (level, low) -> IRQ 16
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.5 to 64
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1e.0 to 64
Aug 13 19:32:42 faramir kernel: NET: Registered protocol family 2
Aug 13 19:32:42 faramir kernel: IP route cache hash table entries: 65536 (order: 7, 524288 bytes)
Aug 13 19:32:42 faramir kernel: TCP established hash table entries: 262144 (order: 10, 6291456 bytes)
Aug 13 19:32:42 faramir kernel: TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
Aug 13 19:32:42 faramir kernel: TCP: Hash tables configured (established 262144 bind 65536)
Aug 13 19:32:42 faramir kernel: TCP reno registered
Aug 13 19:32:42 faramir kernel: checking if image is initramfs... it is
Aug 13 19:32:42 faramir kernel: Freeing initrd memory: 4014k freed
Aug 13 19:32:42 faramir kernel: audit: initializing netlink socket (disabled)
Aug 13 19:32:42 faramir kernel: audit(1187026335.748:1): initialized
Aug 13 19:32:42 faramir kernel: VFS: Disk quotas dquot_6.5.1
Aug 13 19:32:42 faramir kernel: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Aug 13 19:32:42 faramir kernel: io scheduler noop registered
Aug 13 19:32:42 faramir kernel: io scheduler anticipatory registered
Aug 13 19:32:42 faramir kernel: io scheduler deadline registered
Aug 13 19:32:42 faramir kernel: io scheduler cfq registered (default)
Aug 13 19:32:42 faramir kernel: Boot video device is 0000:01:00.0
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:01.0 to 64
Aug 13 19:32:42 faramir kernel: assign_interrupt_mode Found MSI capability
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:01.0:pcie00]
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.0 to 64
Aug 13 19:32:42 faramir kernel: assign_interrupt_mode Found MSI capability
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.0:pcie00]
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.0:pcie02]
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.2 to 64
Aug 13 19:32:42 faramir kernel: assign_interrupt_mode Found MSI capability
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.2:pcie00]
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.2:pcie02]
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.3 to 64
Aug 13 19:32:42 faramir kernel: assign_interrupt_mode Found MSI capability
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.3:pcie00]
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.3:pcie02]
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.4 to 64
Aug 13 19:32:42 faramir kernel: assign_interrupt_mode Found MSI capability
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.4:pcie00]
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.4:pcie02]
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1c.5 to 64
Aug 13 19:32:42 faramir kernel: assign_interrupt_mode Found MSI capability
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.5:pcie00]
Aug 13 19:32:42 faramir kernel: Allocate Port Service[0000:00:1c.5:pcie02]
Aug 13 19:32:42 faramir kernel: vesafb: framebuffer at 0x80000000, mapped to 0xffffc20000b00000, using 3072k, total 16384k
Aug 13 19:32:42 faramir kernel: vesafb: mode is 1024x768x16, linelength=2048, pages=9
Aug 13 19:32:42 faramir kernel: vesafb: scrolling: redraw
Aug 13 19:32:42 faramir kernel: vesafb: Truecolor: size=0:5:6:5, shift=0:11:5:0
Aug 13 19:32:42 faramir kernel: Console: switching to colour frame buffer device 128x48
Aug 13 19:32:42 faramir kernel: fb0: VESA VGA frame buffer device
Aug 13 19:32:42 faramir kernel: fb1: Virtual frame buffer device, using 1024K of video memory
Aug 13 19:32:42 faramir kernel: Real Time Clock Driver v1.12ac
Aug 13 19:32:42 faramir kernel: hpet_resources: 0xfed00000 is busy
Aug 13 19:32:42 faramir kernel: Linux agpgart interface v0.102
Aug 13 19:32:42 faramir kernel: Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
Aug 13 19:32:42 faramir kernel: serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Aug 13 19:32:42 faramir kernel: pnp: the driver 'serial' has been registered
Aug 13 19:32:42 faramir kernel: pnp: match found with the PnP device '00:0b' and the driver 'serial'
Aug 13 19:32:42 faramir kernel: 00:0b: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:02:00.3[C] -> GSI 18 (level, low) -> IRQ 18
Aug 13 19:32:42 faramir kernel: 0000:02:00.3: ttyS1 at I/O 0x1020 (irq = 18) is a 16550A
Aug 13 19:32:42 faramir kernel: RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize
Aug 13 19:32:42 faramir kernel: pnp: the driver 'i8042 kbd' has been registered
Aug 13 19:32:42 faramir kernel: pnp: the driver 'i8042 aux' has been registered
Aug 13 19:32:42 faramir kernel: pnp: the driver 'i8042 kbd' has been unregistered
Aug 13 19:32:42 faramir kernel: pnp: the driver 'i8042 aux' has been unregistered
Aug 13 19:32:42 faramir kernel: PNP: No PS/2 controller found. Probing ports directly.
Aug 13 19:32:42 faramir kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Aug 13 19:32:42 faramir kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Aug 13 19:32:42 faramir kernel: mice: PS/2 mouse device common for all mice
Aug 13 19:32:42 faramir kernel: TCP bic registered
Aug 13 19:32:42 faramir kernel: NET: Registered protocol family 1
Aug 13 19:32:42 faramir kernel: NET: Registered protocol family 17
Aug 13 19:32:42 faramir kernel: Freeing unused kernel memory: 304k freed
Aug 13 19:32:42 faramir kernel: ACPI Exception (processor_core-0790): AE_NOT_FOUND, Processor Device is not present [20070126]
Aug 13 19:32:42 faramir kernel: ACPI Exception (processor_core-0790): AE_NOT_FOUND, Processor Device is not present [20070126]
Aug 13 19:32:42 faramir kernel: Intel(R) PRO/1000 Network Driver - version 7.3.20-k2-NAPI
Aug 13 19:32:42 faramir kernel: Copyright (c) 1999-2006 Intel Corporation.
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:02:00.0[A] -> GSI 16 (level, low) -> IRQ 16
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:02:00.0 to 64
Aug 13 19:32:42 faramir kernel: USB Universal Host Controller Interface driver v3.0
Aug 13 19:32:42 faramir kernel: e1000: 0000:02:00.0: e1000_probe: (PCI Express:2.5Gb/s:Width x1) 00:16:76:04:ff:09
Aug 13 19:32:42 faramir kernel: SCSI subsystem initialized
Aug 13 19:32:42 faramir kernel: Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
Aug 13 19:32:42 faramir kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
Aug 13 19:32:42 faramir kernel: pnp: the driver 'ide' has been registered
Aug 13 19:32:42 faramir kernel: Floppy drive(s): fd0 is 1.44M
Aug 13 19:32:42 faramir kernel: libata version 2.21 loaded.
Aug 13 19:32:42 faramir kernel: FDC 0 is a post-1991 82077
Aug 13 19:32:42 faramir kernel: e1000: eth0: e1000_probe: Intel(R) PRO/1000 Network Connection
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1d.0[A] -> GSI 23 (level, low) -> IRQ 23
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1d.0 to 64
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.0: UHCI Host Controller
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.0: irq 23, io base 0x00003080
Aug 13 19:32:42 faramir kernel: usb usb1: configuration #1 chosen from 1 choice
Aug 13 19:32:42 faramir kernel: hub 1-0:1.0: USB hub found
Aug 13 19:32:42 faramir kernel: hub 1-0:1.0: 2 ports detected
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1d.1[B] -> GSI 19 (level, low) -> IRQ 19
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1d.1 to 64
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.1: UHCI Host Controller
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 2
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.1: irq 19, io base 0x00003060
Aug 13 19:32:42 faramir kernel: usb usb2: configuration #1 chosen from 1 choice
Aug 13 19:32:42 faramir kernel: hub 2-0:1.0: USB hub found
Aug 13 19:32:42 faramir kernel: hub 2-0:1.0: 2 ports detected
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1d.2[C] -> GSI 18 (level, low) -> IRQ 18
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1d.2 to 64
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.2: UHCI Host Controller
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 3
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.2: irq 18, io base 0x00003040
Aug 13 19:32:42 faramir kernel: usb usb3: configuration #1 chosen from 1 choice
Aug 13 19:32:42 faramir kernel: hub 3-0:1.0: USB hub found
Aug 13 19:32:42 faramir kernel: hub 3-0:1.0: 2 ports detected
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1d.3[D] -> GSI 16 (level, low) -> IRQ 16
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1d.3 to 64
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.3: UHCI Host Controller
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 4
Aug 13 19:32:42 faramir kernel: uhci_hcd 0000:00:1d.3: irq 16, io base 0x00003020
Aug 13 19:32:42 faramir kernel: usb usb4: configuration #1 chosen from 1 choice
Aug 13 19:32:42 faramir kernel: hub 4-0:1.0: USB hub found
Aug 13 19:32:42 faramir kernel: hub 4-0:1.0: 2 ports detected
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1d.7[A] -> GSI 23 (level, low) -> IRQ 23
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1d.7 to 64
Aug 13 19:32:42 faramir kernel: ehci_hcd 0000:00:1d.7: EHCI Host Controller
Aug 13 19:32:42 faramir kernel: ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 5
Aug 13 19:32:42 faramir kernel: ehci_hcd 0000:00:1d.7: debug port 1
Aug 13 19:32:42 faramir kernel: PCI: cache line size of 128 is not supported by device 0000:00:1d.7
Aug 13 19:32:42 faramir kernel: ehci_hcd 0000:00:1d.7: irq 23, io mem 0x90404400
Aug 13 19:32:42 faramir kernel: ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
Aug 13 19:32:42 faramir kernel: usb usb5: configuration #1 chosen from 1 choice
Aug 13 19:32:42 faramir kernel: hub 5-0:1.0: USB hub found
Aug 13 19:32:42 faramir kernel: hub 5-0:1.0: 8 ports detected
Aug 13 19:32:42 faramir kernel: ahci 0000:00:1f.2: version 2.3
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1f.2[B] -> GSI 19 (level, low) -> IRQ 19
Aug 13 19:32:42 faramir kernel: usb 3-1: new low speed USB device using uhci_hcd and address 2
Aug 13 19:32:42 faramir kernel: usb 3-1: configuration #1 chosen from 1 choice
Aug 13 19:32:42 faramir kernel: ahci 0000:00:1f.2: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf impl SATA mode
Aug 13 19:32:42 faramir kernel: ahci 0000:00:1f.2: flags: 64bit ncq led clo pio slum part
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1f.2 to 64
Aug 13 19:32:42 faramir kernel: scsi0 : ahci
Aug 13 19:32:42 faramir kernel: scsi1 : ahci
Aug 13 19:32:42 faramir kernel: scsi2 : ahci
Aug 13 19:32:42 faramir kernel: scsi3 : ahci
Aug 13 19:32:42 faramir kernel: ata1: SATA max UDMA/133 cmd 0xffffc20000ab4100 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1273
Aug 13 19:32:42 faramir kernel: ata2: SATA max UDMA/133 cmd 0xffffc20000ab4180 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1273
Aug 13 19:32:42 faramir kernel: ata3: SATA max UDMA/133 cmd 0xffffc20000ab4200 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1273
Aug 13 19:32:42 faramir kernel: ata4: SATA max UDMA/133 cmd 0xffffc20000ab4280 ctl 0x0000000000000000 bmdma 0x0000000000000000 irq 1273
Aug 13 19:32:42 faramir kernel: usb 4-1: new low speed USB device using uhci_hcd and address 2
Aug 13 19:32:42 faramir kernel: usb 4-1: configuration #1 chosen from 1 choice
Aug 13 19:32:42 faramir kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Aug 13 19:32:42 faramir kernel: ata1.00: ATA-7: HDT722516DLA380, V43OA96A, max UDMA/133
Aug 13 19:32:42 faramir kernel: ata1.00: 321672960 sectors, multi 16: LBA48 NCQ (depth 31/32)
Aug 13 19:32:42 faramir kernel: ata1.00: configured for UDMA/133
Aug 13 19:32:42 faramir kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Aug 13 19:32:42 faramir kernel: ata2.00: ATA-7: HDT722516DLA380, V43OA91A, max UDMA/133
Aug 13 19:32:42 faramir kernel: ata2.00: 321672960 sectors, multi 16: LBA48 NCQ (depth 31/32)
Aug 13 19:32:42 faramir kernel: ata2.00: configured for UDMA/133
Aug 13 19:32:42 faramir kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Aug 13 19:32:42 faramir kernel: ata3.00: ATAPI: Optiarc DVD RW AD-7170S, 1.00, max UDMA/66
Aug 13 19:32:42 faramir kernel: ata3.00: configured for UDMA/66
Aug 13 19:32:42 faramir kernel: ata4: SATA link down (SStatus 0 SControl 300)
Aug 13 19:32:42 faramir kernel: scsi 0:0:0:0: Direct-Access ATA HDT722516DLA380 V43O PQ: 0 ANSI: 5
Aug 13 19:32:42 faramir kernel: scsi 1:0:0:0: Direct-Access ATA HDT722516DLA380 V43O PQ: 0 ANSI: 5
Aug 13 19:32:42 faramir kernel: scsi 2:0:0:0: CD-ROM Optiarc DVD RW AD-7170S 1.00 PQ: 0 ANSI: 5
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:07:05.0[A] -> GSI 17 (level, low) -> IRQ 17
Aug 13 19:32:42 faramir kernel: firewire_ohci: Added fw-ohci device 0000:07:05.0, OHCI version 1.10
Aug 13 19:32:42 faramir kernel: ICH7: IDE controller at PCI slot 0000:00:1f.1
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 18 (level, low) -> IRQ 18
Aug 13 19:32:42 faramir kernel: ICH7: chipset revision 1
Aug 13 19:32:42 faramir kernel: ICH7: not 100% native mode: will probe irqs later
Aug 13 19:32:42 faramir kernel: ide0: BM-DMA at 0x30b0-0x30b7, BIOS settings: hda:DMA, hdb:pio
Aug 13 19:32:42 faramir kernel: Probing IDE interface ide0...
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] 321672960 512-byte hardware sectors (164697 MB)
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] 321672960 512-byte hardware sectors (164697 MB)
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 13 19:32:42 faramir kernel: sda: sda1 sda2 < sda5 sda6 > sda3 sda4
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] 321672960 512-byte hardware sectors (164697 MB)
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] Write Protect is off
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] 321672960 512-byte hardware sectors (164697 MB)
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] Write Protect is off
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 13 19:32:42 faramir kernel: sdb: sdb1 sdb2 < sdb5<6>usbcore: registered new interface driver hiddev
Aug 13 19:32:42 faramir kernel: sdb6 >
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: [sdb] Attached SCSI disk
Aug 13 19:32:42 faramir kernel: sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray
Aug 13 19:32:42 faramir kernel: Uniform CD-ROM driver Revision: 3.20
Aug 13 19:32:42 faramir kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0
Aug 13 19:32:42 faramir kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0
Aug 13 19:32:42 faramir kernel: sd 1:0:0:0: Attached scsi generic sg1 type 0
Aug 13 19:32:42 faramir kernel: sr 2:0:0:0: Attached scsi generic sg2 type 5
Aug 13 19:32:42 faramir kernel: input: USB Compliant Keyboard as /class/input/input0
Aug 13 19:32:42 faramir kernel: input: USB HID v1.10 Keyboard [USB Compliant Keyboard] on usb-0000:00:1d.2-1
Aug 13 19:32:42 faramir kernel: input: USB Compliant Keyboard as /class/input/input1
Aug 13 19:32:42 faramir kernel: input: USB HID v1.10 Device [USB Compliant Keyboard] on usb-0000:00:1d.2-1
Aug 13 19:32:42 faramir kernel: input: Logitech USB Receiver as /class/input/input2
Aug 13 19:32:42 faramir kernel: input: USB HID v1.10 Mouse [Logitech USB Receiver] on usb-0000:00:1d.3-1
Aug 13 19:32:42 faramir kernel: usbcore: registered new interface driver usbhid
Aug 13 19:32:42 faramir kernel: drivers/hid/usbhid/hid-core.c: v2.6:USB HID core driver
Aug 13 19:32:42 faramir kernel: firewire_core: created new fw device fw0 (0 config rom retries, S400)
Aug 13 19:32:42 faramir kernel: hda: LITE-ON DVD SOHD-16P9SV, ATAPI CD/DVD-ROM drive
Aug 13 19:32:42 faramir kernel: hda: selected mode 0x42
Aug 13 19:32:42 faramir kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Aug 13 19:32:42 faramir kernel: hda: ATAPI 48X DVD-ROM drive, 254kB Cache, UDMA(33)
Aug 13 19:32:42 faramir kernel: device-mapper: ioctl: 4.11.0-ioctl (2006-10-12) initialised: dm-devel@redhat.com
Aug 13 19:32:42 faramir kernel: kjournald starting. Commit interval 5 seconds
Aug 13 19:32:42 faramir kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 13 19:32:42 faramir kernel: input: PC Speaker as /class/input/input3
Aug 13 19:32:42 faramir kernel: pnp: the driver 'parport_pc' has been registered
Aug 13 19:32:42 faramir kernel: pnp: match found with the PnP device '00:0a' and the driver 'parport_pc'
Aug 13 19:32:42 faramir kernel: parport_pc 00:0a: reported by Plug and Play ACPI
Aug 13 19:32:42 faramir kernel: parport0: PC-style at 0x378 (0x778), irq 7, using FIFO [PCSPP,TRISTATE,COMPAT,ECP]
Aug 13 19:32:42 faramir kernel: parport0: Printer, HEWLETT-PACKARD PHOTOSMART P1100
Aug 13 19:32:42 faramir kernel: iTCO_wdt: Intel TCO WatchDog Timer Driver v1.02 (26-Jul-2007)
Aug 13 19:32:42 faramir kernel: iTCO_wdt: Found a ICH7 or ICH7R TCO device (Version=2, TCOBASE=0x0460)
Aug 13 19:32:42 faramir kernel: iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
Aug 13 19:32:42 faramir kernel: input: Power Button (FF) as /class/input/input4
Aug 13 19:32:42 faramir kernel: ACPI: Power Button (FF) [PWRF]
Aug 13 19:32:42 faramir kernel: input: Sleep Button (CM) as /class/input/input5
Aug 13 19:32:42 faramir kernel: ACPI: Sleep Button (CM) [SLPB]
Aug 13 19:32:42 faramir kernel: intel_rng: Firmware space is locked read-only. If you can't or
Aug 13 19:32:42 faramir kernel: intel_rng: don't want to disable this in firmware setup, and if
Aug 13 19:32:42 faramir kernel: intel_rng: you are certain that your system has a functional
Aug 13 19:32:42 faramir kernel: intel_rng: RNG, try using the 'no_fwh_detect' option.
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1f.3[B] -> GSI 19 (level, low) -> IRQ 19
Aug 13 19:32:42 faramir kernel: ACPI: PCI Interrupt 0000:00:1b.0[A] -> GSI 22 (level, low) -> IRQ 22
Aug 13 19:32:42 faramir kernel: PCI: Setting latency timer of device 0000:00:1b.0 to 64
Aug 13 19:32:42 faramir kernel: Adding 979924k swap on /dev/sda6. Priority:-1 extents:1 across:979924k
Aug 13 19:32:42 faramir kernel: EXT3 FS on dm-0, internal journal
Aug 13 19:32:42 faramir kernel: NET: Registered protocol family 10
Aug 13 19:32:42 faramir kernel: lo: Disabled Privacy Extensions
Aug 13 19:32:42 faramir kernel: i2c-adapter i2c-0: lm85: Detected SMSC chip
Aug 13 19:32:42 faramir kernel: i2c-adapter i2c-0: lm85: Unrecognized version/stepping 0x68 Defaulting to Generic LM85.
Aug 13 19:32:42 faramir kernel: fuse init (API version 7.8)
Aug 13 19:32:42 faramir kernel: kjournald starting. Commit interval 5 seconds
Aug 13 19:32:42 faramir kernel: EXT3 FS on sda1, internal journal
Aug 13 19:32:42 faramir kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 13 19:32:42 faramir kernel: kjournald starting. Commit interval 5 seconds
Aug 13 19:32:42 faramir kernel: EXT3 FS on dm-2, internal journal
Aug 13 19:32:42 faramir kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 13 19:32:42 faramir kernel: kjournald starting. Commit interval 5 seconds
Aug 13 19:32:42 faramir kernel: EXT3 FS on dm-4, internal journal
Aug 13 19:32:42 faramir kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 13 19:32:42 faramir kernel: kjournald starting. Commit interval 5 seconds
Aug 13 19:32:42 faramir kernel: EXT3 FS on dm-1, internal journal
Aug 13 19:32:42 faramir kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 13 19:32:42 faramir kernel: kjournald starting. Commit interval 5 seconds
Aug 13 19:32:42 faramir kernel: EXT3 FS on dm-3, internal journal
Aug 13 19:32:42 faramir kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 13 19:32:42 faramir kernel: kjournald starting. Commit interval 5 seconds
Aug 13 19:32:42 faramir kernel: EXT3 FS on dm-5, internal journal
Aug 13 19:32:42 faramir kernel: EXT3-fs: mounted filesystem with ordered data mode.
Aug 13 19:32:42 faramir kernel: ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug 13 19:32:42 faramir kernel: e1000: eth0: e1000_watchdog: NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
Aug 13 19:32:42 faramir kernel: e1000: eth0: e1000_watchdog: 10/100 speed: disabling TSO
Aug 13 19:32:42 faramir kernel: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Aug 13 19:32:45 faramir kernel: lp0: using parport0 (interrupt-driven).
Aug 13 19:32:45 faramir kernel: ppdev: user-space parallel port driver
Aug 13 19:32:50 faramir kernel: eth0: no IPv6 routers present
Aug 13 19:40:50 faramir kernel: ata1.00: exception Emask 0x2 SAct 0x7ffc7 SErr 0x0 action 0x2 frozen
Aug 13 19:40:50 faramir kernel: ata1.00: spurious completions during NCQ issue=0x0 SAct=0x7ffc7 FIS=005040a1:00000020
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/30:00:73:96:db/00:00:04:00:00/40 tag 0 cdb 0x0 data 24576 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/30:08:1b:97:db/00:00:04:00:00/40 tag 1 cdb 0x0 data 24576 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/10:10:53:97:db/00:00:04:00:00/40 tag 2 cdb 0x0 data 8192 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/08:30:1b:80:db/00:00:04:00:00/40 tag 6 cdb 0x0 data 4096 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/08:38:2b:80:db/00:00:04:00:00/40 tag 7 cdb 0x0 data 4096 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/60:40:3b:80:db/00:00:04:00:00/40 tag 8 cdb 0x0 data 49152 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/d0:48:a3:80:db/01:00:04:00:00/40 tag 9 cdb 0x0 data 237568 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/10:50:7b:82:db/00:00:04:00:00/40 tag 10 cdb 0x0 data 8192 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/08:58:93:82:db/00:00:04:00:00/40 tag 11 cdb 0x0 data 4096 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/10:60:a3:82:db/00:00:04:00:00/40 tag 12 cdb 0x0 data 8192 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/38:68:ab:96:db/00:00:04:00:00/40 tag 13 cdb 0x0 data 28672 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/10:70:eb:96:db/00:00:04:00:00/40 tag 14 cdb 0x0 data 8192 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/38:78:bb:82:db/00:00:04:00:00/40 tag 15 cdb 0x0 data 28672 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/10:80:03:97:db/00:00:04:00:00/40 tag 16 cdb 0x0 data 8192 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/08:88:6b:97:db/00:00:04:00:00/40 tag 17 cdb 0x0 data 4096 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1.00: cmd 61/08:90:7b:97:db/00:00:04:00:00/40 tag 18 cdb 0x0 data 4096 out
Aug 13 19:40:50 faramir kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
Aug 13 19:40:50 faramir kernel: ata1: soft resetting port
Aug 13 19:40:50 faramir kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Aug 13 19:40:50 faramir kernel: ata1.00: configured for UDMA/133
Aug 13 19:40:50 faramir kernel: ata1: EH complete
Aug 13 19:40:50 faramir kernel: sd 0:0:0:0: [sda] 321672960 512-byte hardware sectors (164697 MB)
Aug 13 19:40:50 faramir kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 13 19:40:50 faramir kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Aug 13 19:40:50 faramir kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 13 19:45:28 faramir kernel: fuse exit
Aug 13 19:45:29 faramir kernel: Kernel logging (proc) stopped.
Aug 13 19:45:29 faramir kernel: Kernel log daemon terminating.
[-- Attachment #3: lsmod.txt --]
[-- Type: text/plain, Size: 3215 bytes --]
Module Size Used by
i915 31488 2
drm 100392 3 i915
ppdev 13832 0
lp 17480 0
ac 10632 0
battery 17936 0
nfs 264440 1
lockd 73392 2 nfs
nfs_acl 8192 1 nfs
sunrpc 189192 4 nfs,lockd,nfs_acl
fuse 50480 1
lm85 39332 0
hwmon_vid 7296 1 lm85
cpufreq_ondemand 13456 2
acpi_cpufreq 13832 0
freq_table 9728 2 cpufreq_ondemand,acpi_cpufreq
firewire_sbp2 18820 0
ipv6 294536 16
snd_hda_intel 339364 1
snd_pcm_oss 48288 0
snd_mixer_oss 21376 1 snd_pcm_oss
snd_pcm 89864 2 snd_hda_intel,snd_pcm_oss
snd_seq_dummy 8452 0
snd_seq_oss 36736 0
snd_seq_midi 13376 0
snd_rawmidi 30624 1 snd_seq_midi
snd_seq_midi_event 12416 2 snd_seq_oss,snd_seq_midi
snd_seq 58752 6 snd_seq_dummy,snd_seq_oss,snd_seq_midi,snd_seq_midi_event
snd_timer 28552 2 snd_pcm,snd_seq
snd_seq_device 12948 5 snd_seq_dummy,snd_seq_oss,snd_seq_midi,snd_rawmidi,snd_seq
snd 65640 11 snd_hda_intel,snd_pcm_oss,snd_mixer_oss,snd_pcm,snd_seq_oss,snd_rawmidi,snd_seq,snd_timer,snd_seq_device
soundcore 13216 1 snd
i2c_i801 13980 0
psmouse 45724 0
parport_pc 42664 1
parport 44556 3 ppdev,lp,parport_pc
snd_page_alloc 14608 2 snd_hda_intel,snd_pcm
iTCO_wdt 17232 0
intel_agp 32032 1
serio_raw 11908 0
i2c_core 30976 2 lm85,i2c_i801
button 13856 0
evdev 15360 0
tsdev 13440 0
joydev 15744 0
pcspkr 7808 0
ext3 137232 7
jbd 68080 1 ext3
mbcache 13952 1 ext3
dm_mirror 27008 0
dm_snapshot 21960 0
dm_mod 64112 15 dm_mirror,dm_snapshot
ide_cd 44704 0
ata_piix 23300 0
ata_generic 13188 0
sg 40872 0
sr_mod 22436 0
sd_mod 33408 4
cdrom 39592 2 ide_cd,sr_mod
usbhid 34144 0
hid 33152 1 usbhid
piix 15236 0 [permanent]
ahci 29700 3
floppy 67112 0
generic 10372 0 [permanent]
firewire_ohci 22784 0
firewire_core 49344 2 firewire_sbp2,firewire_ohci
crc_itu_t 6656 1 firewire_core
libata 129584 3 ata_piix,ata_generic,ahci
scsi_mod 164152 5 firewire_sbp2,sg,sr_mod,sd_mod,libata
ehci_hcd 39308 0
ide_core 144168 3 ide_cd,piix,generic
uhci_hcd 29984 0
e1000 127296 0
thermal 19728 1
processor 42472 2 acpi_cpufreq,thermal
fan 10248 1
^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [2.6.23-rc3] Regression: "spurious completions during NCQ"
2007-08-13 18:05 Frans Pop
@ 2007-08-14 10:11 ` Tejun Heo
2007-08-15 7:21 ` Frans Pop
0 siblings, 1 reply; 8+ messages in thread
From: Tejun Heo @ 2007-08-14 10:11 UTC (permalink / raw)
To: Frans Pop; +Cc: linux-ide
Frans Pop wrote:
> Hi,
>
> I've been running 2.6.23-rc2 without problems. Today I compiled 2.6.23-rc3
> and shortly after boot got the following messages in my logs:
>
> kernel: ata1.00: spurious completions during NCQ issue=0x0 SAct=0x7ffc7 FIS=005040a1:00000020
> kernel: ata1.00: cmd 61/30:00:73:96:db/00:00:04:00:00/40 tag 0 cdb 0x0 data 24576 out
> kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM violation)
> [...]
> kernel: ata1: soft resetting port
> kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> kernel: ata1.00: configured for UDMA/133
> kernel: ata1: EH complete
> kernel: sd 0:0:0:0: [sda] 321672960 512-byte hardware sectors (164697 MB)
> kernel: sd 0:0:0:0: [sda] Write Protect is off
> kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
> kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
>
> A grep in the logs shows that the first error has never occurred before.
> I was doing nothing special at the time (SVN checkout, not even running X).
>
> Full kern.log for that boot and lsmod attached.
> My system is Debian unstable with 2.6.23 built from Linus' git tree.
How reproducible is the problem?
--
tejun
^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [2.6.23-rc3] Regression: "spurious completions during NCQ"
2007-08-14 10:11 ` Tejun Heo
@ 2007-08-15 7:21 ` Frans Pop
2007-08-15 7:32 ` Tejun Heo
0 siblings, 1 reply; 8+ messages in thread
From: Frans Pop @ 2007-08-15 7:21 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide
On Tuesday 14 August 2007, Tejun Heo wrote:
> Frans Pop wrote:
> > I've been running 2.6.23-rc2 without problems. Today I compiled
> > 2.6.23-rc3 and shortly after boot got the following messages in my
> > logs:
> >
> > kernel: ata1.00: spurious completions during NCQ issue=0x0 SAct=0x7ffc7 FIS=005040a1:00000020
> > kernel: ata1.00: cmd 61/30:00:73:96:db/00:00:04:00:00/40 tag 0 cdb 0x0 data 24576 out
> > kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM
> > violation)
> > [...]
> > kernel: ata1: soft resetting port
>
> How reproducible is the problem?
Not reproducible at all, at least it has not happened again. Guess it may
have been unrelated to rc3.
I had rebooted with a change in the BIOS config, but that was only to
change the active video controller. Don't know how that could affect this.
OTOH, I pulled that video card out of the system later and that solved a
longstanding issue I've had with powering off this system [1]...
I did find one other soft reset in my kernel logs (which go back to Okt 2006,
2.6.18); that was on Aug 1 with 2.6.22, but after a different error:
kernel: ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action0x2 frozen
kernel: ata3.00: cmd a0/00:00:00:00:20/00:00:00:00:00/a0 tag 0 cdb 0x1b data 0
kernel: res 40/00:03:00:00:20/00:00:00:00:00/a0 Emask 0x4 (timeout)
kernel: ata3: soft resetting port
kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
kernel: ata3.00: configured for UDMA/66
kernel: ata3: EH complete
As ata3 is a DVD-RW, I'm a bit less concerned about that one.
Thanks for the reply,
FJP
[1] http://bugzilla.kernel.org/show_bug.cgi?id=6879
^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [2.6.23-rc3] Regression: "spurious completions during NCQ"
2007-08-15 7:21 ` Frans Pop
@ 2007-08-15 7:32 ` Tejun Heo
0 siblings, 0 replies; 8+ messages in thread
From: Tejun Heo @ 2007-08-15 7:32 UTC (permalink / raw)
To: Frans Pop; +Cc: linux-ide
Frans Pop wrote:
> On Tuesday 14 August 2007, Tejun Heo wrote:
>> Frans Pop wrote:
>>> I've been running 2.6.23-rc2 without problems. Today I compiled
>>> 2.6.23-rc3 and shortly after boot got the following messages in my
>>> logs:
>>>
>>> kernel: ata1.00: spurious completions during NCQ issue=0x0 SAct=0x7ffc7 FIS=005040a1:00000020
>>> kernel: ata1.00: cmd 61/30:00:73:96:db/00:00:04:00:00/40 tag 0 cdb 0x0 data 24576 out
>>> kernel: res 50/00:08:7b:97:db/00:00:04:00:00/40 Emask 0x2 (HSM
>>> violation)
>>> [...]
>>> kernel: ata1: soft resetting port
>> How reproducible is the problem?
>
> Not reproducible at all, at least it has not happened again. Guess it may
> have been unrelated to rc3.
>
> I had rebooted with a change in the BIOS config, but that was only to
> change the active video controller. Don't know how that could affect this.
> OTOH, I pulled that video card out of the system later and that solved a
> longstanding issue I've had with powering off this system [1]...
>
> I did find one other soft reset in my kernel logs (which go back to Okt 2006,
> 2.6.18); that was on Aug 1 with 2.6.22, but after a different error:
> kernel: ata3.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action0x2 frozen
> kernel: ata3.00: cmd a0/00:00:00:00:20/00:00:00:00:00/a0 tag 0 cdb 0x1b data 0
> kernel: res 40/00:03:00:00:20/00:00:00:00:00/a0 Emask 0x4 (timeout)
> kernel: ata3: soft resetting port
> kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> kernel: ata3.00: configured for UDMA/66
> kernel: ata3: EH complete
>
> As ata3 is a DVD-RW, I'm a bit less concerned about that one.
You don't need to worry too much as long as errors are properly
recovered. All commands are retried and you won't lose any data.
Please report if the spurious NCQ problem happens again. Thanks.
--
tejun
^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [2.6.23-rc3] Regression: "spurious completions during NCQ"
@ 2007-09-22 13:11 Carsten Otto
2007-10-04 16:16 ` Carsten Otto
2007-10-24 10:18 ` Tejun Heo
0 siblings, 2 replies; 8+ messages in thread
From: Carsten Otto @ 2007-09-22 13:11 UTC (permalink / raw)
To: linux-ide
[-- Attachment #1: Type: text/plain, Size: 2474 bytes --]
Hi!
(I am referring to the thread at
http://marc.info/?l=linux-ide&m=118703097726277&w=2)
I also experience a lot of NCQ errors which look like these:
Sep 22 10:11:53 server kernel: ata1.00: exception Emask 0x2 SAct 0x7 SErr 0x0 action 0x2 frozen
Sep 22 10:11:53 server kernel: ata1.00: (spurious completions during NCQ issue=0x0 SAct=0x7 FIS=004040a1:00000010)
Sep 22 10:11:53 server kernel: ata1.00: cmd 60/38:00:bd:ee:01/00:00:13:00:00/40 tag 0 cdb 0x0 data 28672 in
Sep 22 10:11:53 server kernel: res 40/00:20:5d:1d:eb/00:00:13:00:00/40 Emask 0x2 (HSM violation)
Sep 22 10:11:53 server kernel: ata1.00: cmd 60/40:08:f5:ee:01/00:00:13:00:00/40 tag 1 cdb 0x0 data 32768 in
Sep 22 10:11:53 server kernel: res 40/00:20:5d:1d:eb/00:00:13:00:00/40 Emask 0x2 (HSM violation)
Sep 22 10:11:53 server kernel: ata1.00: cmd 60/08:10:35:ef:01/00:00:13:00:00/40 tag 2 cdb 0x0 data 4096 in
Sep 22 10:11:53 server kernel: res 40/00:20:5d:1d:eb/00:00:13:00:00/40 Emask 0x2 (HSM violation)
Sep 22 10:11:53 server kernel: ata1: soft resetting port
Sep 22 10:11:53 server kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 22 10:11:53 server kernel: ata1.00: configured for UDMA/133
Sep 22 10:11:53 server kernel: ata1: EH complete
Sep 22 10:11:53 server kernel: sd 0:0:0:0: [sda] 586114704 512-byte hardware sectors (300091 MB)
Sep 22 10:11:53 server kernel: sd 0:0:0:0: [sda] Write Protect is off
Sep 22 10:11:53 server kernel: sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
Sep 22 10:11:53 server kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
In total I have 355 such entries regarding my SATA disks starting on
September 15 when I installed kernel 2.6.22.6 (before I had
2.6.18-rc4-mm1 or so).
In addition I also see messages like this one:
kern.log:Sep 20 00:42:23 server kernel: ata2.00: NCQ disabled due to excessive errors
Please tell me how I can help resolving the issue (if it is a bug) or,
if possible, tell me how to fix it myself.
Information about my system:
- 4x Maxtor 7V300F0 (MaxLine III, 300GB, 7200rpm, 16MB cache, SATA2),
firmware VA111900
- 1x Samsung 2SP2014N (SpinPoint P, 200GB, 7200rpm, 8MB cache, PATA),
firmware VC100-33
- 4 software RAIDs on the SATA disks, 1x RAID1, 3x RAID5
- heavy usage of the RAIDs
- ICH7R
- 2.6.22.6
- Pentium D 805, 2GB RAM
Thanks a lot,
--
Carsten Otto
c-otto@gmx.de
www.c-otto.de
[-- Attachment #2: Digital signature --]
[-- Type: application/pgp-signature, Size: 189 bytes --]
^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [2.6.23-rc3] Regression: "spurious completions during NCQ"
2007-09-22 13:11 [2.6.23-rc3] Regression: "spurious completions during NCQ" Carsten Otto
@ 2007-10-04 16:16 ` Carsten Otto
2007-10-24 10:18 ` Tejun Heo
1 sibling, 0 replies; 8+ messages in thread
From: Carsten Otto @ 2007-10-04 16:16 UTC (permalink / raw)
To: linux-ide
[-- Attachment #1: Type: text/plain, Size: 188 bytes --]
Hi again!
Any reply to my problem? I think blacklisting the disks would be a good
idea (Maxtor 7V300F0, firmware VA111900).
Thanks
--
Carsten Otto
c-otto@gmx.de
www.c-otto.de
[-- Attachment #2: Digital signature --]
[-- Type: application/pgp-signature, Size: 189 bytes --]
^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [2.6.23-rc3] Regression: "spurious completions during NCQ"
2007-09-22 13:11 [2.6.23-rc3] Regression: "spurious completions during NCQ" Carsten Otto
2007-10-04 16:16 ` Carsten Otto
@ 2007-10-24 10:18 ` Tejun Heo
2007-10-28 20:53 ` Carsten Otto
1 sibling, 1 reply; 8+ messages in thread
From: Tejun Heo @ 2007-10-24 10:18 UTC (permalink / raw)
To: Carsten Otto; +Cc: linux-ide
Carsten Otto wrote:
> kern.log:Sep 20 00:42:23 server kernel: ata2.00: NCQ disabled due to excessive errors
>
> Please tell me how I can help resolving the issue (if it is a bug) or,
> if possible, tell me how to fix it myself.
>
> Information about my system:
> - 4x Maxtor 7V300F0 (MaxLine III, 300GB, 7200rpm, 16MB cache, SATA2),
> firmware VA111900
> - 1x Samsung 2SP2014N (SpinPoint P, 200GB, 7200rpm, 8MB cache, PATA),
> firmware VC100-33
> - 4 software RAIDs on the SATA disks, 1x RAID1, 3x RAID5
> - heavy usage of the RAIDs
> - ICH7R
> - 2.6.22.6
> - Pentium D 805, 2GB RAM
Please post dmesg including full boot log and error messages and the
result of 'hdparm -I /dev/sdX' where sdX are drives which cause such errors.
Thanks.
--
tejun
^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [2.6.23-rc3] Regression: "spurious completions during NCQ"
2007-10-24 10:18 ` Tejun Heo
@ 2007-10-28 20:53 ` Carsten Otto
0 siblings, 0 replies; 8+ messages in thread
From: Carsten Otto @ 2007-10-28 20:53 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide
[-- Attachment #1.1: Type: text/plain, Size: 400 bytes --]
On Wed, Oct 24, 2007 at 06:18:20AM -0400, Tejun Heo wrote:
> Please post dmesg including full boot log and error messages and the result
> of 'hdparm -I /dev/sdX' where sdX are drives which cause such errors.
Attached. The other disks (sda, sdb, sdd) have the same information, but
different serial numbers of course.
Thanks for your time,
--
Carsten Otto
c-otto@gmx.de
www.c-otto.de
[-- Attachment #1.2: dmesg.txt --]
[-- Type: text/plain, Size: 28260 bytes --]
Linux version 2.6.23.1 (root@server.c-otto.de) (gcc version 4.2.1 (Debian 4.2.1-3)) #1 SMP Sun Oct 28 14:21:11 CET 2007
BIOS-provided physical RAM map:
BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
BIOS-e820: 00000000000e4000 - 0000000000100000 (reserved)
BIOS-e820: 0000000000100000 - 00000000e7f80000 (usable)
BIOS-e820: 00000000e7f80000 - 00000000e7f8e000 (ACPI data)
BIOS-e820: 00000000e7f8e000 - 00000000e7fe0000 (ACPI NVS)
BIOS-e820: 00000000e7fe0000 - 00000000e8000000 (reserved)
BIOS-e820: 00000000ffb00000 - 0000000100000000 (reserved)
2815MB HIGHMEM available.
896MB LOWMEM available.
found SMP MP-table at 000ff780
NX (Execute Disable) protection: active
Entering add_active_range(0, 0, 950144) 0 entries of 256 used
Zone PFN ranges:
DMA 0 -> 4096
Normal 4096 -> 229376
HighMem 229376 -> 950144
Movable zone start PFN for each node
early_node_map[1] active PFN ranges
0: 0 -> 950144
On node 0 totalpages: 950144
DMA zone: 32 pages used for memmap
DMA zone: 0 pages reserved
DMA zone: 4064 pages, LIFO batch:0
Normal zone: 1760 pages used for memmap
Normal zone: 223520 pages, LIFO batch:31
HighMem zone: 5631 pages used for memmap
HighMem zone: 715137 pages, LIFO batch:31
Movable zone: 0 pages used for memmap
DMI 2.3 present.
Using APIC driver default
ACPI: RSDP 000FA960, 0024 (r2 ACPIAM)
ACPI: XSDT E7F80100, 0044 (r1 A M I OEMXSDT 3000620 MSFT 97)
ACPI: FACP E7F80290, 00F4 (r3 A M I OEMFACP 3000620 MSFT 97)
ACPI: DSDT E7F80400, 81D2 (r1 A0228 A0228000 0 INTL 2002026)
ACPI: FACS E7F8E000, 0040
ACPI: APIC E7F80390, 0070 (r1 A M I OEMAPIC 3000620 MSFT 97)
ACPI: OEMB E7F8E040, 0066 (r1 A M I AMI_OEM 3000620 MSFT 97)
ACPI: MCFG E7F885E0, 003C (r1 A M I OEMMCFG 3000620 MSFT 97)
ACPI: PM-Timer IO Port: 0x808
ACPI: Local APIC address 0xfee00000
ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
Processor #0 15:4 APIC version 20
ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
Processor #1 15:4 APIC version 20
ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23
ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
ACPI: IRQ0 used by override.
ACPI: IRQ2 used by override.
ACPI: IRQ9 used by override.
Enabling APIC mode: Flat. Using 1 I/O APICs
Using ACPI (MADT) for SMP configuration information
Allocating PCI resources starting at ea000000 (gap: e8000000:17b00000)
Built 1 zonelists in Zone order. Total pages: 942721
Kernel command line: root=/dev/md2
mapped APIC to ffffb000 (fee00000)
mapped IOAPIC to ffffa000 (fec00000)
Enabling fast FPU save and restore... done.
Enabling unmasked SIMD FPU exception support... done.
Initializing CPU#0
PID hash table entries: 4096 (order: 12, 16384 bytes)
Detected 2676.295 MHz processor.
Console: colour VGA+ 80x25
console [tty0] enabled
Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Memory: 3762432k/3800576k available (3695k kernel code, 36968k reserved, 1723k data, 240k init, 2883072k highmem)
virtual kernel memory layout:
fixmap : 0xfff9a000 - 0xfffff000 ( 404 kB)
pkmap : 0xffc00000 - 0xffe00000 (2048 kB)
vmalloc : 0xf8800000 - 0xffbfe000 ( 115 MB)
lowmem : 0xc0000000 - 0xf8000000 ( 896 MB)
.init : 0xc0654000 - 0xc0690000 ( 240 kB)
.data : 0xc049bd04 - 0xc064aa54 (1723 kB)
.text : 0xc0100000 - 0xc049bd04 (3695 kB)
Checking if this processor honours the WP bit even in supervisor mode... Ok.
Calibrating delay using timer specific routine.. 5357.76 BogoMIPS (lpj=10715521)
Mount-cache hash table entries: 512
CPU: After generic identify, caps: bfebfbff 20100000 00000000 00000000 0000651d 00000000 00000001 00000000
monitor/mwait feature present.
using mwait in idle threads.
CPU: Trace cache: 12K uops, L1 D cache: 16K
CPU: L2 cache: 1024K
CPU: Physical Processor ID: 0
CPU: Processor Core ID: 0
CPU: After all inits, caps: bfebfbff 20100000 00000000 0000b180 0000651d 00000000 00000001 00000000
Intel machine check architecture supported.
Intel machine check reporting enabled on CPU#0.
CPU0: Intel P4/Xeon Extended MCE MSRs (24) available
CPU0: Thermal monitoring enabled
Compat vDSO mapped to ffffe000.
Checking 'hlt' instruction... OK.
SMP alternatives: switching to UP code
ACPI: Core revision 20070126
Parsing all Control Methods:
Table [DSDT](id 0001) - 794 Objects with 55 Devices 212 Methods 26 Regions
tbxface-0598 [00] tb_load_namespace : ACPI Tables successfully acquired
evxfevnt-0091 [00] enable : Transition to ACPI mode successful
CPU0: Intel(R) Pentium(R) D CPU 2.66GHz stepping 07
SMP alternatives: switching to SMP code
Booting processor 1/1 eip 3000
Initializing CPU#1
Calibrating delay using timer specific routine.. 5352.78 BogoMIPS (lpj=10705569)
CPU: After generic identify, caps: bfebfbff 20100000 00000000 00000000 0000651d 00000000 00000001 00000000
monitor/mwait feature present.
CPU: Trace cache: 12K uops, L1 D cache: 16K
CPU: L2 cache: 1024K
CPU: Physical Processor ID: 0
CPU: Processor Core ID: 1
CPU: After all inits, caps: bfebfbff 20100000 00000000 0000b180 0000651d 00000000 00000001 00000000
Intel machine check architecture supported.
Intel machine check reporting enabled on CPU#1.
CPU1: Intel P4/Xeon Extended MCE MSRs (24) available
CPU1: Thermal monitoring enabled
CPU1: Intel(R) Pentium(R) D CPU 2.66GHz stepping 07
Total of 2 processors activated (10710.54 BogoMIPS).
ENABLING IO-APIC IRQs
..TIMER: vector=0x31 apic1=0 pin1=2 apic2=-1 pin2=-1
checking TSC synchronization [CPU#0 -> CPU#1]: passed.
Brought up 2 CPUs
xor: automatically using best checksumming function: pIII_sse
pIII_sse : 4285.000 MB/sec
xor: using function: pIII_sse (4285.000 MB/sec)
NET: Registered protocol family 16
ACPI: bus type pci registered
PCI: Found Intel Corporation 945G/GZ/P/PL Express Memory Controller Hub without MMCONFIG support.
PCI: PCI BIOS revision 2.10 entry at 0xf0031, last bus=2
PCI: Using configuration type 1
Setting up standard PCI resources
evgpeblk-0956 [00] ev_create_gpe_block : GPE 00 to 1F [_GPE] 4 regs on int 0x9
evgpeblk-1052 [00] ev_initialize_gpe_bloc: Found 11 Wake, Enabled 0 Runtime GPEs in this block
ACPI: EC: Look up EC in DSDT
Completing Region/Field/Buffer/Package initialization:................................................................................................................................................................................
Initialized 20/26 Regions 23/23 Fields 40/40 Buffers 93/95 Packages (803 nodes)
Initializing Device/Processor/Thermal objects by executing _INI methods:.
Executed 1 _INI methods requiring 0 _STA executions (examined 59 objects)
ACPI: Interpreter enabled
ACPI: (supports S0 S1 S3 S5)
ACPI: Using IOAPIC for interrupt routing
ACPI: PCI Root Bridge [PCI0] (0000:00)
PCI quirk: region 0800-087f claimed by ICH6 ACPI/GPIO/TCO
PCI quirk: region 0480-04bf claimed by ICH6 GPIO
PCI: Transparent bridge - 0000:00:1e.0
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P3._PRT]
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P4._PRT]
ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 *10 11 12 14 15)
ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 10 *11 12 14 15)
ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 *5 6 7 10 11 12 14 15)
ACPI Warning (tbutils-0217): Incorrect checksum in table [OEMB] - 08, should be 07 [20070126]
Linux Plug and Play Support v0.97 (c) Adam Belay
pnp: PnP ACPI init
ACPI: bus type pnp registered
pnp: PnP ACPI: found 13 devices
ACPI: ACPI bus type pnp unregistered
SCSI subsystem initialized
libata version 2.21 loaded.
usbcore: registered new interface driver usbfs
usbcore: registered new interface driver hub
usbcore: registered new device driver usb
PCI: Using ACPI for IRQ routing
PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
Time: tsc clocksource has been installed.
pnp: 00:01: iomem range 0xfed13000-0xfed19fff has been reserved
pnp: 00:06: ioport range 0x290-0x297 has been reserved
pnp: 00:07: iomem range 0xfed1c000-0xfed1ffff has been reserved
pnp: 00:07: iomem range 0xfed20000-0xfed8ffff has been reserved
pnp: 00:07: iomem range 0xffb00000-0xffbfffff could not be reserved
pnp: 00:07: iomem range 0xfff00000-0xffffffff could not be reserved
pnp: 00:0a: iomem range 0xfec00000-0xfec00fff has been reserved
pnp: 00:0a: iomem range 0xfee00000-0xfee00fff has been reserved
pnp: 00:0b: iomem range 0xf0000000-0xf3ffffff has been reserved
pnp: 00:0c: iomem range 0x0-0x9ffff could not be reserved
pnp: 00:0c: iomem range 0xc0000-0xdffff could not be reserved
pnp: 00:0c: iomem range 0xe0000-0xfffff could not be reserved
pnp: 00:0c: iomem range 0x100000-0xe7ffffff could not be reserved
PCI: Bridge: 0000:00:1c.0
IO window: e000-efff
MEM window: eff00000-efffffff
PREFETCH window: disabled.
PCI: Bridge: 0000:00:1e.0
IO window: d000-dfff
MEM window: ef000000-efefffff
PREFETCH window: ea000000-ea0fffff
ACPI: PCI Interrupt 0000:00:1c.0[A] -> GSI 16 (level, low) -> IRQ 16
PCI: Setting latency timer of device 0000:00:1c.0 to 64
PCI: Setting latency timer of device 0000:00:1e.0 to 64
NET: Registered protocol family 2
IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
TCP established hash table entries: 131072 (order: 8, 1572864 bytes)
TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
TCP: Hash tables configured (established 131072 bind 65536)
TCP reno registered
Machine check exception polling timer started.
IA-32 Microcode Update Driver: v1.14a <tigran@aivazian.fsnet.co.uk>
highmem bounce pool size: 64 pages
Total HugeTLB memory allocated, 0
Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
SGI XFS with large block numbers, no debug enabled
async_tx: api initialized (sync-only)
io scheduler noop registered
io scheduler anticipatory registered (default)
io scheduler deadline registered
io scheduler cfq registered
Boot video device is 0000:01:00.0
input: Power Button (FF) as /devices/virtual/input/input0
ACPI: Power Button (FF) [PWRF]
input: Power Button (CM) as /devices/virtual/input/input1
ACPI: Power Button (CM) [PWRB]
ACPI Warning (tbutils-0217): Incorrect checksum in table [ ùŠ] - 00, should be 55 [20070126]
ACPI Error (tbinstal-0134): Table has invalid signature [ ùŠ], must be SSDT, PSDT or OEMx [20070126]
ACPI Error (psparse-0537): Method parse/execution failed [\_PR_.CPU1._PDC] (Node c2e312d4), AE_BAD_SIGNATURE
ACPI: Processor [CPU1] (supports 8 throttling states)
ACPI Warning (tbutils-0217): Incorrect checksum in table [ ùŠ] - 00, should be 55 [20070126]
ACPI Error (tbinstal-0134): Table has invalid signature [ ùŠ], must be SSDT, PSDT or OEMx [20070126]
ACPI Error (psparse-0537): Method parse/execution failed [\_PR_.CPU2._PDC] (Node c2e3184c), AE_BAD_SIGNATURE
Real Time Clock Driver v1.12ac
intel_rng: FWH not detected
Linux agpgart interface v0.102
Hangcheck: starting hangcheck timer 0.9.0 (tick is 180 seconds, margin is 60 seconds).
Hangcheck: Using get_cycles().
Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing disabled
Switched to high resolution mode on CPU 1
Switched to high resolution mode on CPU 0
floppy0: no floppy controllers found
RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
loop: module loaded
Intel(R) PRO/1000 Network Driver - version 7.3.20-k2-NAPI
Copyright (c) 1999-2006 Intel Corporation.
ACPI: PCI Interrupt 0000:02:00.0[A] -> GSI 16 (level, low) -> IRQ 16
PCI: Setting latency timer of device 0000:02:00.0 to 64
e1000: 0000:02:00.0: e1000_probe: (PCI Express:2.5Gb/s:Width x1) 00:15:17:0b:8b:2d
e1000: eth0: e1000_probe: Intel(R) PRO/1000 Network Connection
e100: Intel(R) PRO/100 Network Driver, 3.5.23-k4-NAPI
e100: Copyright(c) 1999-2006 Intel Corporation
forcedeth.c: Reverse Engineered nForce ethernet driver. Version 0.60.
tun: Universal TUN/TAP device driver, 1.6
tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
netconsole: not configured, aborting
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
ICH7: IDE controller at PCI slot 0000:00:1f.1
ACPI: PCI Interrupt 0000:00:1f.1[A] -> GSI 22 (level, low) -> IRQ 17
ICH7: chipset revision 1
ICH7: not 100% native mode: will probe irqs later
ide0: BM-DMA at 0xffa0-0xffa7, BIOS settings: hda:DMA, hdb:pio
ide1: BM-DMA at 0xffa8-0xffaf, BIOS settings: hdc:pio, hdd:pio
Probing IDE interface ide0...
hda: SAMSUNG SP2014N, ATA DISK drive
hda: selected mode 0x45
ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Probing IDE interface ide1...
Probing IDE interface ide1...
hda: max request size: 512KiB
hda: 390721968 sectors (200049 MB) w/8192KiB Cache, CHS=24321/255/63, UDMA(100)
hda: cache flushes supported
hda: hda1
ahci 0000:00:1f.2: version 2.3
ACPI: PCI Interrupt 0000:00:1f.2[B] -> GSI 23 (level, low) -> IRQ 18
ahci 0000:00:1f.2: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf impl SATA mode
ahci 0000:00:1f.2: flags: 64bit ncq led clo pio slum part
PCI: Setting latency timer of device 0000:00:1f.2 to 64
scsi0 : ahci
scsi1 : ahci
scsi2 : ahci
scsi3 : ahci
ata1: SATA max UDMA/133 cmd 0xf881ad00 ctl 0x00000000 bmdma 0x00000000 irq 223
ata2: SATA max UDMA/133 cmd 0xf881ad80 ctl 0x00000000 bmdma 0x00000000 irq 223
ata3: SATA max UDMA/133 cmd 0xf881ae00 ctl 0x00000000 bmdma 0x00000000 irq 223
ata4: SATA max UDMA/133 cmd 0xf881ae80 ctl 0x00000000 bmdma 0x00000000 irq 223
ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata1.00: ATA-7: Maxtor 7V300F0, VA111900, max UDMA/133
ata1.00: 586114704 sectors, multi 16: LBA48 NCQ (depth 31/32)
ata1.00: configured for UDMA/133
ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata2.00: ATA-7: Maxtor 7V300F0, VA111900, max UDMA/133
ata2.00: 586114704 sectors, multi 16: LBA48 NCQ (depth 31/32)
ata2.00: configured for UDMA/133
ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata3.00: ATA-7: Maxtor 7V300F0, VA111900, max UDMA/133
ata3.00: 586114704 sectors, multi 16: LBA48 NCQ (depth 31/32)
ata3.00: configured for UDMA/133
ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata4.00: ATA-7: Maxtor 7V300F0, VA111900, max UDMA/133
ata4.00: 586114704 sectors, multi 16: LBA48 NCQ (depth 31/32)
ata4.00: configured for UDMA/133
scsi 0:0:0:0: Direct-Access ATA Maxtor 7V300F0 VA11 PQ: 0 ANSI: 5
sd 0:0:0:0: [sda] 586114704 512-byte hardware sectors (300091 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
sd 0:0:0:0: [sda] 586114704 512-byte hardware sectors (300091 MB)
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
sda: sda1 sda2 sda3 < sda5 sda6 sda7 >
sd 0:0:0:0: [sda] Attached SCSI disk
scsi 1:0:0:0: Direct-Access ATA Maxtor 7V300F0 VA11 PQ: 0 ANSI: 5
sd 1:0:0:0: [sdb] 586114704 512-byte hardware sectors (300091 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sd 1:0:0:0: [sdb] 586114704 512-byte hardware sectors (300091 MB)
sd 1:0:0:0: [sdb] Write Protect is off
sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sdb: sdb1 sdb2 sdb3 < sdb5 sdb6 sdb7 >
sd 1:0:0:0: [sdb] Attached SCSI disk
scsi 2:0:0:0: Direct-Access ATA Maxtor 7V300F0 VA11 PQ: 0 ANSI: 5
sd 2:0:0:0: [sdc] 586114704 512-byte hardware sectors (300091 MB)
sd 2:0:0:0: [sdc] Write Protect is off
sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sd 2:0:0:0: [sdc] 586114704 512-byte hardware sectors (300091 MB)
sd 2:0:0:0: [sdc] Write Protect is off
sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sdc: sdc1 sdc2 sdc3 < sdc5 sdc6 sdc7 >
sd 2:0:0:0: [sdc] Attached SCSI disk
scsi 3:0:0:0: Direct-Access ATA Maxtor 7V300F0 VA11 PQ: 0 ANSI: 5
sd 3:0:0:0: [sdd] 586114704 512-byte hardware sectors (300091 MB)
sd 3:0:0:0: [sdd] Write Protect is off
sd 3:0:0:0: [sdd] Mode Sense: 00 3a 00 00
sd 3:0:0:0: [sdd] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sd 3:0:0:0: [sdd] 586114704 512-byte hardware sectors (300091 MB)
sd 3:0:0:0: [sdd] Write Protect is off
sd 3:0:0:0: [sdd] Mode Sense: 00 3a 00 00
sd 3:0:0:0: [sdd] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
sdd: sdd1 sdd2 sdd3 < sdd5 sdd6 sdd7 >
sd 3:0:0:0: [sdd] Attached SCSI disk
Fusion MPT base driver 3.04.05
Copyright (c) 1999-2007 LSI Logic Corporation
Fusion MPT SPI Host driver 3.04.05
ieee1394: raw1394: /dev/raw1394 device initialized
usbmon: debugfs is not available
ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver
USB Universal Host Controller Interface driver v3.0
usbcore: registered new interface driver usblp
Initializing USB Mass Storage driver...
usbcore: registered new interface driver usb-storage
USB Mass Storage support registered.
PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
serio: i8042 KBD port at 0x60,0x64 irq 1
serio: i8042 AUX port at 0x60,0x64 irq 12
mice: PS/2 mouse device common for all mice
input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
ACPI: PCI Interrupt 0000:00:1f.3[B] -> GSI 23 (level, low) -> IRQ 18
input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
md: linear personality registered for level -1
md: raid0 personality registered for level 0
md: raid1 personality registered for level 1
md: raid10 personality registered for level 10
raid6: int32x1 643 MB/s
raid6: int32x2 625 MB/s
raid6: int32x4 653 MB/s
raid6: int32x8 537 MB/s
raid6: mmxx1 1353 MB/s
raid6: mmxx2 1854 MB/s
raid6: sse1x1 959 MB/s
raid6: sse1x2 1574 MB/s
raid6: sse2x1 1928 MB/s
raid6: sse2x2 2853 MB/s
raid6: using algorithm sse2x2 (2853 MB/s)
md: raid6 personality registered for level 6
md: raid5 personality registered for level 5
md: raid4 personality registered for level 4
usbcore: registered new interface driver usbhid
drivers/hid/usbhid/hid-core.c: v2.6:USB HID core driver
oprofile: using NMI interrupt.
Netfilter messages via NETLINK v0.30.
nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
ctnetlink v0.93: registering with nfnetlink.
IPv4 over IPv4 tunneling driver
GRE over IPv4 tunneling driver
ip_tables: (C) 2000-2006 Netfilter Core Team
ClusterIP Version 0.8 loaded successfully
arp_tables: (C) 2002 David S. Miller
TCP cubic registered
NET: Registered protocol family 1
NET: Registered protocol family 10
IPv6 over IPv4 tunneling driver
NET: Registered protocol family 17
Bridge firewalling registered
802.1Q VLAN Support v1.8 Ben Greear <greearb@candelatech.com>
All bugs added by David S. Miller <davem@redhat.com>
p4-clockmod: P4/Xeon(TM) CPU On-Demand Clock Modulation available
Using IPI No-Shortcut mode
md: Autodetecting RAID arrays.
md: autorun ...
md: considering sdd7 ...
md: adding sdd7 ...
md: sdd6 has different UUID to sdd7
md: sdd5 has different UUID to sdd7
md: sdd2 has different UUID to sdd7
md: sdd1 has different UUID to sdd7
md: adding sdc7 ...
md: sdc6 has different UUID to sdd7
md: sdc5 has different UUID to sdd7
md: sdc2 has different UUID to sdd7
md: sdc1 has different UUID to sdd7
md: adding sdb7 ...
md: sdb6 has different UUID to sdd7
md: sdb5 has different UUID to sdd7
md: sdb2 has different UUID to sdd7
md: sdb1 has different UUID to sdd7
md: adding sda7 ...
md: sda6 has different UUID to sdd7
md: sda5 has different UUID to sdd7
md: sda2 has different UUID to sdd7
md: sda1 has different UUID to sdd7
md: created md4
md: bind<sda7>
md: bind<sdb7>
md: bind<sdc7>
md: bind<sdd7>
md: running: <sdd7><sdc7><sdb7><sda7>
raid5: device sdd7 operational as raid disk 1
raid5: device sdc7 operational as raid disk 2
raid5: device sdb7 operational as raid disk 3
raid5: device sda7 operational as raid disk 0
raid5: allocated 4211kB for md4
raid5: raid level 5 set md4 active with 4 out of 4 devices, algorithm 2
RAID5 conf printout:
--- rd:4 wd:4
disk 0, o:1, dev:sda7
disk 1, o:1, dev:sdd7
disk 2, o:1, dev:sdc7
disk 3, o:1, dev:sdb7
md: considering sdd6 ...
md: adding sdd6 ...
md: sdd5 has different UUID to sdd6
md: sdd2 has different UUID to sdd6
md: sdd1 has different UUID to sdd6
md: adding sdc6 ...
md: sdc5 has different UUID to sdd6
md: sdc2 has different UUID to sdd6
md: sdc1 has different UUID to sdd6
md: adding sdb6 ...
md: sdb5 has different UUID to sdd6
md: sdb2 has different UUID to sdd6
md: sdb1 has different UUID to sdd6
md: adding sda6 ...
md: sda5 has different UUID to sdd6
md: sda2 has different UUID to sdd6
md: sda1 has different UUID to sdd6
md: created md3
md: bind<sda6>
md: bind<sdb6>
md: bind<sdc6>
md: bind<sdd6>
md: running: <sdd6><sdc6><sdb6><sda6>
raid1: raid set md3 active with 4 out of 4 mirrors
md: considering sdd5 ...
md: adding sdd5 ...
md: sdd2 has different UUID to sdd5
md: sdd1 has different UUID to sdd5
md: adding sdc5 ...
md: sdc2 has different UUID to sdd5
md: sdc1 has different UUID to sdd5
md: adding sdb5 ...
md: sdb2 has different UUID to sdd5
md: sdb1 has different UUID to sdd5
md: adding sda5 ...
md: sda2 has different UUID to sdd5
md: sda1 has different UUID to sdd5
md: created md2
md: bind<sda5>
md: bind<sdb5>
md: bind<sdc5>
md: bind<sdd5>
md: running: <sdd5><sdc5><sdb5><sda5>
raid5: device sdd5 operational as raid disk 1
raid5: device sdc5 operational as raid disk 2
raid5: device sdb5 operational as raid disk 3
raid5: device sda5 operational as raid disk 0
raid5: allocated 4211kB for md2
raid5: raid level 5 set md2 active with 4 out of 4 devices, algorithm 2
RAID5 conf printout:
--- rd:4 wd:4
disk 0, o:1, dev:sda5
disk 1, o:1, dev:sdd5
disk 2, o:1, dev:sdc5
disk 3, o:1, dev:sdb5
md: considering sdd2 ...
md: adding sdd2 ...
md: sdd1 has different UUID to sdd2
md: adding sdc2 ...
md: sdc1 has different UUID to sdd2
md: adding sdb2 ...
md: sdb1 has different UUID to sdd2
md: adding sda2 ...
md: sda1 has different UUID to sdd2
md: created md1
md: bind<sda2>
md: bind<sdb2>
md: bind<sdc2>
md: bind<sdd2>
md: running: <sdd2><sdc2><sdb2><sda2>
raid5: device sdd2 operational as raid disk 1
raid5: device sdc2 operational as raid disk 0
raid5: device sdb2 operational as raid disk 3
raid5: device sda2 operational as raid disk 2
raid5: allocated 4211kB for md1
raid5: raid level 5 set md1 active with 4 out of 4 devices, algorithm 2
RAID5 conf printout:
--- rd:4 wd:4
disk 0, o:1, dev:sdc2
disk 1, o:1, dev:sdd2
disk 2, o:1, dev:sda2
disk 3, o:1, dev:sdb2
md: considering sdd1 ...
md: adding sdd1 ...
md: adding sdc1 ...
md: adding sdb1 ...
md: adding sda1 ...
md: created md0
md: bind<sda1>
md: bind<sdb1>
md: bind<sdc1>
md: bind<sdd1>
md: running: <sdd1><sdc1><sdb1><sda1>
raid1: raid set md0 active with 4 out of 4 mirrors
md: ... autorun DONE.
kjournald starting. Commit interval 5 seconds
EXT3-fs: mounted filesystem with ordered data mode.
VFS: Mounted root (ext3 filesystem) readonly.
Freeing unused kernel memory: 240k freed
Adding 5855416k swap on /dev/md1. Priority:-1 extents:1 across:5855416k
EXT3-fs warning: maximal mount count reached, running e2fsck is recommended
EXT3 FS on md2, internal journal
Filesystem "md3": Disabling barriers, not supported by the underlying device
XFS mounting filesystem md3
Ending clean XFS mount for filesystem: md3
Filesystem "md4": Disabling barriers, not supported by the underlying device
XFS mounting filesystem md4
Ending clean XFS mount for filesystem: md4
XFS mounting filesystem hda1
Ending clean XFS mount for filesystem: hda1
ADDRCONF(NETDEV_UP): eth0: link is not ready
e1000: eth0: e1000_watchdog: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
CIFS VFS: Send error in SETFSUnixInfo = -5
vlan_check_real_dev: ALREADY had VLAN registered
eth0: no IPv6 routers present
eth0.2: no IPv6 routers present
tap0: no IPv6 routers present
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
ata3.00: exception Emask 0x2 SAct 0x1ff80 SErr 0x0 action 0x2 frozen
ata3.00: spurious completions during NCQ issue=0x0 SAct=0x1ff80 FIS=004040a1:00000040
ata3.00: cmd 61/08:38:16:c6:3f/00:00:00:00:00/40 tag 7 cdb 0x0 data 4096 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/10:40:1e:c6:3f/00:00:00:00:00/40 tag 8 cdb 0x0 data 8192 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/08:48:2e:c6:3f/00:00:00:00:00/40 tag 9 cdb 0x0 data 4096 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/08:50:36:c6:3f/00:00:00:00:00/40 tag 10 cdb 0x0 data 4096 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/10:58:3e:c6:3f/00:00:00:00:00/40 tag 11 cdb 0x0 data 8192 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/08:60:4e:c6:3f/00:00:00:00:00/40 tag 12 cdb 0x0 data 4096 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/08:68:56:c6:3f/00:00:00:00:00/40 tag 13 cdb 0x0 data 4096 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/10:70:5e:c6:3f/00:00:00:00:00/40 tag 14 cdb 0x0 data 8192 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/08:78:6e:c6:3f/00:00:00:00:00/40 tag 15 cdb 0x0 data 4096 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3.00: cmd 61/08:80:76:c6:3f/00:00:00:00:00/40 tag 16 cdb 0x0 data 4096 out
res 40/00:80:76:c6:3f/00:00:00:00:00/40 Emask 0x2 (HSM violation)
ata3: soft resetting port
ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata3.00: configured for UDMA/133
ata3: EH complete
sd 2:0:0:0: [sdc] 586114704 512-byte hardware sectors (300091 MB)
sd 2:0:0:0: [sdc] Write Protect is off
sd 2:0:0:0: [sdc] Mode Sense: 00 3a 00 00
sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
CIFS VFS: Send error in SETFSUnixInfo = -5
[-- Attachment #1.3: sdc.txt --]
[-- Type: text/plain, Size: 2575 bytes --]
/dev/sdc:
ATA device, with non-removable media
Model Number: Maxtor 7V300F0
Serial Number: V605PR7G
Firmware Revision: VA111900
Standards:
Used: ATA/ATAPI-7 T13 1532D revision 0
Supported: 7 6 5 4
Configuration:
Logical max current
cylinders 16383 16383
heads 16 16
sectors/track 63 63
--
CHS current addressable sectors: 16514064
LBA user addressable sectors: 268435455
LBA48 user addressable sectors: 586114704
device size with M = 1024*1024: 286188 MBytes
device size with M = 1000*1000: 300090 MBytes (300 GB)
Capabilities:
LBA, IORDY(can be disabled)
Queue depth: 32
Standby timer values: spec'd by Standard, no device specific minimum
R/W multiple sector transfer: Max = 16 Current = 16
Advanced power management level: unknown setting (0x0000)
Recommended acoustic management value: 192, current value: 0
DMA: mdma0 mdma1 mdma2 udma0 udma1 udma2 udma3 udma4 udma5 *udma6
Cycle time: min=120ns recommended=120ns
PIO: pio0 pio1 pio2 pio3 pio4
Cycle time: no flow control=120ns IORDY flow control=120ns
Commands/features:
Enabled Supported:
* SMART feature set
Security Mode feature set
* Power Management feature set
* Write cache
* Look-ahead
* Host Protected Area feature set
* WRITE_VERIFY command
* WRITE_BUFFER command
* READ_BUFFER command
* NOP cmd
* DOWNLOAD_MICROCODE
Advanced Power Management feature set
Power-Up In Standby feature set
SET_FEATURES required to spinup after power up
SET_MAX security extension
Automatic Acoustic Management feature set
* 48-bit Address feature set
* Device Configuration Overlay feature set
* Mandatory FLUSH_CACHE
* FLUSH_CACHE_EXT
* SMART error logging
* SMART self-test
Media Card Pass-Through
* General Purpose Logging feature set
* WRITE_{DMA|MULTIPLE}_FUA_EXT
* 64-bit World wide name
* URG for READ_STREAM[_DMA]_EXT
* URG for WRITE_STREAM[_DMA]_EXT
* SATA-I signaling speed (1.5Gb/s)
* SATA-II signaling speed (3.0Gb/s)
* Native Command Queueing (NCQ)
* Host-initiated interface power management
* Software settings preservation
* SMART Command Transport (SCT) feature set
* SCT Data Tables (AC5)
Security:
Master password revision code = 65534
supported
not enabled
not locked
not frozen
not expired: security count
not supported: enhanced erase
Checksum: correct
[-- Attachment #2: Digital signature --]
[-- Type: application/pgp-signature, Size: 189 bytes --]
^ permalink raw reply [flat|nested] 8+ messages in thread
end of thread, other threads:[~2007-10-28 20:53 UTC | newest]
Thread overview: 8+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2007-09-22 13:11 [2.6.23-rc3] Regression: "spurious completions during NCQ" Carsten Otto
2007-10-04 16:16 ` Carsten Otto
2007-10-24 10:18 ` Tejun Heo
2007-10-28 20:53 ` Carsten Otto
-- strict thread matches above, loose matches on Subject: below --
2007-08-13 18:05 Frans Pop
2007-08-14 10:11 ` Tejun Heo
2007-08-15 7:21 ` Frans Pop
2007-08-15 7:32 ` Tejun Heo
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).