Bug 7590
| Summary: | libata: /dev/sdb slow and unstable - Timing buffered disk reads: 1.79 MB/sec | ||
|---|---|---|---|
| Product: | IO/Storage | Reporter: | Marco Bega (astrodanzante) |
| Component: | Serial ATA | Assignee: | Tejun Heo (htejun) |
| Status: | RESOLVED CODE_FIX | ||
| Severity: | high | CC: | akpm, htejun |
| Priority: | P2 | ||
| Hardware: | i386 | ||
| OS: | Linux | ||
| Kernel Version: | 2.6.17.2 and higher | Subsystem: | |
| Regression: | --- | Bisected commit-id: | |
| Attachments: |
Dmesg 2.6.17.2 ATA_DEBUG
2.6.17.2 ATA_DEBUG dmesg -s 2147483639 not truncated dmesg 2.6.17.2 ATA_DEBUG debug patch Dmesg debug patch sata_uli-clear-simplex dmesg-2.6.19.1-patch-dbg vanish sata dmesg without "ata_pci_clear_simplex(pdev)" sata_uli ignore simplex patch dmesg with patch The last dmesg |
||
|
Description
Marco Bega
2006-11-28 16:50:29 UTC
/proc/scsi/scsi ^^^^^^^^^^^^^^^ Attached devices: Host: scsi0 Channel: 00 Id: 00 Lun: 00 Vendor: ATA Model: Maxtor 6V250F0 Rev: VA11 Type: Direct-Access ANSI SCSI revision: 05 Host: scsi1 Channel: 00 Id: 00 Lun: 00 Vendor: ATA Model: Maxtor 6L200M0 Rev: BANC Type: Direct-Access ANSI SCSI revision: 05 Can you please attach the output of `dmesg -s 1000000' for both 2.6.17.1 and for 2.6.17.2? (ie: one good kernel and one bad one) Thanks. 2.6.17.1 (Working) sudo dmesg -s 1000000 [17179569.184000] Linux version 2.6.17.1-k-mb (marco@king-test) (gcc version 4.1.2 20060928 (prerelease) (Ubuntu 4.1.1-13ubuntu5)) #1 SMP Tue Nov 28 22:28:02 CET 2006 [17179569.184000] BIOS-provided physical RAM map: [17179569.184000] BIOS-e820: 0000000000000000 - 000000000009f400 (usable) [17179569.184000] BIOS-e820: 000000000009f400 - 00000000000a0000 (reserved) [17179569.184000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) [17179569.184000] BIOS-e820: 0000000000100000 - 000000003fff0000 (usable) [17179569.184000] BIOS-e820: 000000003fff0000 - 000000003fff3000 (ACPI NVS) [17179569.184000] BIOS-e820: 000000003fff3000 - 0000000040000000 (ACPI data) [17179569.184000] BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved) [17179569.184000] BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved) [17179569.184000] BIOS-e820: 00000000ffff0000 - 0000000100000000 (reserved) [17179569.184000] 127MB HIGHMEM available. [17179569.184000] 896MB LOWMEM available. [17179569.184000] On node 0 totalpages: 262128 [17179569.184000] DMA zone: 4096 pages, LIFO batch:0 [17179569.184000] Normal zone: 225280 pages, LIFO batch:31 [17179569.184000] HighMem zone: 32752 pages, LIFO batch:7 [17179569.184000] DMI 2.2 present. [17179569.184000] ACPI: RSDP (v000 Nvidia ) @ 0x000f75c0 [17179569.184000] ACPI: RSDT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff3000 [17179569.184000] ACPI: FADT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff3040 [17179569.184000] ACPI: MADT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff74c0 [17179569.184000] ACPI: DSDT (v001 NVIDIA AWRDACPI 0x00001000 MSFT 0x0100000e) @ 0x00000000 [17179569.184000] ACPI: PM-Timer IO Port: 0x4008 [17179569.184000] ACPI: Local APIC address 0xfee00000 [17179569.184000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [17179569.184000] Processor #0 6:10 APIC version 16 [17179569.184000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1]) [17179569.184000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0]) [17179569.184000] IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23 [17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [17179569.184000] ACPI: BIOS IRQ0 pin2 override ignored. [17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 14 global_irq 14 high edge) [17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 15 global_irq 15 high edge) [17179569.184000] ACPI: IRQ9 used by override. [17179569.184000] ACPI: IRQ14 used by override. [17179569.184000] ACPI: IRQ15 used by override. [17179569.184000] Enabling APIC mode: Flat. Using 1 I/O APICs [17179569.184000] Using ACPI (MADT) for SMP configuration information [17179569.184000] Allocating PCI resources starting at 50000000 (gap: 40000000:bec00000) [17179569.184000] Built 1 zonelists [17179569.184000] Kernel command line: root=/dev/hda7 ro quiet splash locale=it_IT [17179569.184000] mapped APIC to ffffd000 (fee00000) [17179569.184000] mapped IOAPIC to ffffc000 (fec00000) [17179569.184000] Enabling fast FPU save and restore... done. [17179569.184000] Enabling unmasked SIMD FPU exception support... done. [17179569.184000] Initializing CPU#0 [17179569.184000] PID hash table entries: 4096 (order: 12, 16384 bytes) [17179569.184000] Detected 2205.252 MHz processor. [17179569.184000] Using pmtmr for high-res timesource [17179569.184000] Console: colour VGA+ 80x25 [17179572.556000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [17179572.556000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [17179572.580000] Memory: 1029948k/1048512k available (1869k kernel code, 17860k reserved, 1050k data, 308k init, 131008k highmem) [17179572.580000] Checking if this processor honours the WP bit even in supervisor mode... Ok. [17179572.660000] Calibrating delay using timer specific routine.. 4414.99 BogoMIPS (lpj=8829999) [17179572.660000] Security Framework v1.0.0 initialized [17179572.660000] SELinux: Disabled at boot. [17179572.660000] Mount-cache hash table entries: 512 [17179572.660000] CPU: After generic identify, caps: 0383fbff c1c3fbff 00000000 00000000 00000000 00000000 00000000 [17179572.660000] CPU: After vendor identify, caps: 0383fbff c1c3fbff 00000000 00000000 00000000 00000000 00000000 [17179572.660000] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line) [17179572.660000] CPU: L2 Cache: 512K (64 bytes/line) [17179572.660000] CPU: After all inits, caps: 0383fbff c1c3fbff 00000000 00000420 00000000 00000000 00000000 [17179572.660000] Checking 'hlt' instruction... OK. [17179572.676000] SMP alternatives: switching to UP code [17179572.676000] Freeing SMP alternatives: 16k freed [17179572.684000] CPU0: AMD Athlon(tm) XP 3200+ stepping 00 [17179572.684000] Total of 1 processors activated (4414.99 BogoMIPS). [17179572.684000] ENABLING IO-APIC IRQs [17179572.684000] ..TIMER: vector=0x31 apic1=0 pin1=0 apic2=-1 pin2=-1 [17179572.828000] Brought up 1 CPUs [17179572.828000] migration_cost=0 [17179572.828000] checking if image is initramfs... it is [17179573.220000] Freeing initrd memory: 5144k freed [17179573.220000] NET: Registered protocol family 16 [17179573.224000] EISA bus registered [17179573.224000] ACPI: bus type pci registered [17179573.236000] PCI: PCI BIOS revision 2.10 entry at 0xfb4a0, last bus=2 [17179573.236000] Setting up standard PCI resources [17179573.244000] ACPI: Subsystem revision 20060127 [17179573.252000] ACPI: Interpreter enabled [17179573.252000] ACPI: Using IOAPIC for interrupt routing [17179573.252000] ACPI: PCI Root Bridge [PCI0] (0000:00) [17179573.252000] PCI: Probing PCI hardware (bus 00) [17179573.256000] PCI: nForce2 C1 Halt Disconnect fixup [17179573.260000] Boot video device is 0000:02:00.0 [17179573.260000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT] [17179573.308000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.HUB0._PRT] [17179573.308000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.AGPB._PRT] [17179573.308000] ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 *5 6 7 10 11 12 14 15) [17179573.308000] ACPI: PCI Interrupt Link [LNK2] (IRQs 3 4 5 6 7 *10 11 12 14 15) [17179573.308000] ACPI: PCI Interrupt Link [LNK3] (IRQs 3 4 *5 6 7 10 11 12 14 15) [17179573.308000] ACPI: PCI Interrupt Link [LNK4] (IRQs 3 4 5 6 7 10 *11 12 14 15) [17179573.308000] ACPI: PCI Interrupt Link [LNK5] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179573.308000] ACPI: PCI Interrupt Link [LUBA] (IRQs 3 4 *5 6 7 10 11 12 14 15) [17179573.308000] ACPI: PCI Interrupt Link [LUBB] (IRQs 3 4 5 6 7 *10 11 12 14 15) [17179573.308000] ACPI: PCI Interrupt Link [LMAC] (IRQs 3 4 5 6 7 *10 11 12 14 15) [17179573.308000] ACPI: PCI Interrupt Link [LAPU] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179573.312000] ACPI: PCI Interrupt Link [LACI] (IRQs 3 4 5 6 7 10 *11 12 14 15) [17179573.312000] ACPI: PCI Interrupt Link [LMCI] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179573.312000] ACPI: PCI Interrupt Link [LSMB] (IRQs 3 4 5 6 7 10 *11 12 14 15) [17179573.312000] ACPI: PCI Interrupt Link [LUB2] (IRQs 3 4 5 6 7 10 *11 12 14 15) [17179573.312000] ACPI: PCI Interrupt Link [LFIR] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179573.312000] ACPI: PCI Interrupt Link [L3CM] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179573.312000] ACPI: PCI Interrupt Link [LIDE] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179573.312000] ACPI: PCI Interrupt Link [APC1] (IRQs *16), disabled. [17179573.312000] ACPI: PCI Interrupt Link [APC2] (IRQs *17), disabled. [17179573.312000] ACPI: PCI Interrupt Link [APC3] (IRQs *18), disabled. [17179573.312000] ACPI: PCI Interrupt Link [APC4] (IRQs *19), disabled. [17179573.312000] ACPI: PCI Interrupt Link [APC5] (IRQs *16), disabled. [17179573.312000] ACPI: PCI Interrupt Link [APCF] (IRQs 20 21 22) *0, disabled. [17179573.312000] ACPI: PCI Interrupt Link [APCG] (IRQs 20 21 22) *0, disabled. [17179573.312000] ACPI: PCI Interrupt Link [APCH] (IRQs 20 21 22) *0, disabled. [17179573.312000] ACPI: PCI Interrupt Link [APCI] (IRQs 20 21 22) *0, disabled. [17179573.316000] ACPI: PCI Interrupt Link [APCJ] (IRQs 20 21 22) *0, disabled. [17179573.316000] ACPI: PCI Interrupt Link [APCK] (IRQs 20 21 22) *0, disabled. [17179573.316000] ACPI: PCI Interrupt Link [APCS] (IRQs *23), disabled. [17179573.316000] ACPI: PCI Interrupt Link [APCL] (IRQs 20 21 22) *0, disabled. [17179573.316000] ACPI: PCI Interrupt Link [APCM] (IRQs 20 21 22) *0, disabled. [17179573.316000] ACPI: PCI Interrupt Link [AP3C] (IRQs 20 21 22) *0, disabled. [17179573.316000] ACPI: PCI Interrupt Link [APCZ] (IRQs 20 21 22) *0, disabled. [17179573.320000] Linux Plug and Play Support v0.97 (c) Adam Belay [17179573.320000] pnp: PnP ACPI init [17179573.324000] pnp: PnP ACPI: found 13 devices [17179573.324000] PnPBIOS: Disabled by ACPI PNP [17179573.324000] PCI: Using ACPI for IRQ routing [17179573.324000] PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report [17179573.368000] pnp: 00:00: ioport range 0x4000-0x407f could not be reserved [17179573.368000] pnp: 00:00: ioport range 0x4080-0x40ff has been reserved [17179573.368000] pnp: 00:00: ioport range 0x4400-0x447f has been reserved [17179573.368000] pnp: 00:00: ioport range 0x4480-0x44ff could not be reserved [17179573.368000] pnp: 00:00: ioport range 0x4200-0x427f has been reserved [17179573.368000] pnp: 00:00: ioport range 0x4280-0x42ff has been reserved [17179573.368000] pnp: 00:01: ioport range 0x5000-0x503f has been reserved [17179573.368000] pnp: 00:01: ioport range 0x5500-0x553f has been reserved [17179573.368000] PCI: Bridge: 0000:00:08.0 [17179573.368000] IO window: 9000-bfff [17179573.368000] MEM window: ea000000-ebffffff [17179573.368000] PREFETCH window: 50000000-500fffff [17179573.368000] PCI: Bridge: 0000:00:1e.0 [17179573.368000] IO window: c000-cfff [17179573.368000] MEM window: e8000000-e9ffffff [17179573.368000] PREFETCH window: d8000000-e7ffffff [17179573.368000] PCI: Setting latency timer of device 0000:00:08.0 to 64 [17179573.368000] NET: Registered protocol family 2 [17179573.408000] IP route cache hash table entries: 32768 (order: 5, 131072 bytes) [17179573.408000] TCP established hash table entries: 131072 (order: 8, 1048576 bytes) [17179573.408000] TCP bind hash table entries: 65536 (order: 7, 524288 bytes) [17179573.408000] TCP: Hash tables configured (established 131072 bind 65536) [17179573.408000] TCP reno registered [17179573.408000] audit: initializing netlink socket (disabled) [17179573.408000] audit(1164788702.408:1): initialized [17179573.408000] highmem bounce pool size: 64 pages [17179573.408000] VFS: Disk quotas dquot_6.5.1 [17179573.408000] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [17179573.408000] Initializing Cryptographic API [17179573.408000] io scheduler noop registered [17179573.408000] io scheduler anticipatory registered [17179573.408000] io scheduler deadline registered [17179573.408000] io scheduler cfq registered (default) [17179573.472000] isapnp: Scanning for PnP cards... [17179573.824000] isapnp: No Plug & Play device found [17179573.844000] Real Time Clock Driver v1.12ac [17179573.844000] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled [17179573.844000] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [17179573.844000] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [17179573.844000] 00:0a: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [17179573.844000] 00:0b: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [17179573.848000] RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize [17179573.848000] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2 [17179573.848000] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx [17179573.848000] PNP: No PS/2 controller found. Probing ports directly. [17179573.848000] serio: i8042 AUX port at 0x60,0x64 irq 12 [17179573.848000] serio: i8042 KBD port at 0x60,0x64 irq 1 [17179573.848000] mice: PS/2 mouse device common for all mice [17179573.848000] EISA: Probing bus 0 at eisa.0 [17179573.848000] Cannot allocate resource for EISA slot 4 [17179573.848000] Cannot allocate resource for EISA slot 5 [17179573.848000] EISA: Detected 0 cards. [17179573.848000] TCP bic registered [17179573.848000] NET: Registered protocol family 1 [17179573.848000] NET: Registered protocol family 8 [17179573.848000] NET: Registered protocol family 20 [17179573.848000] Using IPI No-Shortcut mode [17179573.848000] ACPI wakeup devices: [17179573.848000] HUB0 HUB1 USB0 USB1 USB2 F139 MMAC MMCI UAR1 [17179573.848000] ACPI: (supports S0 S1 S3 S4 S5) [17179573.852000] Freeing unused kernel memory: 308k freed [17179575.224000] SCSI subsystem initialized [17179575.224000] libata version 1.20 loaded. [17179575.224000] NFORCE2: IDE controller at PCI slot 0000:00:09.0 [17179575.224000] NFORCE2: chipset revision 162 [17179575.224000] NFORCE2: not 100% native mode: will probe irqs later [17179575.224000] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [17179575.224000] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [17179575.224000] NFORCE2: 0000:00:09.0 (rev a2) UDMA133 controller [17179575.224000] ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA [17179575.228000] ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA [17179575.228000] Probing IDE interface ide0... [17179575.520000] hda: SAMSUNG SP1604N, ATA DISK drive [17179575.804000] hdb: ST3160022ACE, ATA DISK drive [17179575.864000] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 [17179575.864000] Probing IDE interface ide1... [17179576.604000] hdc: Optiarc DVD RW AD-7173A, ATAPI CD/DVD-ROM drive [17179577.280000] ide1 at 0x170-0x177,0x376 on irq 15 [17179577.284000] hda: max request size: 512KiB [17179577.292000] hda: 312581808 sectors (160041 MB) w/2048KiB Cache, CHS=19457/255/63, UDMA(133) [17179577.296000] hda: cache flushes supported [17179577.296000] hda: hda1 hda2 < hda5 hda6 hda7 > [17179577.376000] hdb: max request size: 512KiB [17179577.376000] hdb: 312581808 sectors (160041 MB) w/2048KiB Cache, CHS=19457/255/63, UDMA(100) [17179577.376000] hdb: cache flushes supported [17179577.376000] hdb: hdb1 < hdb5 hdb6 hdb7 hdb8 > [17179577.448000] hdc: ATAPI 48X DVD-ROM DVD-R-RAM CD-R/RW drive, 2048kB Cache, UDMA(66) [17179577.448000] Uniform CD-ROM driver Revision: 3.20 [17179577.988000] sata_uli 0000:01:07.0: version 0.5 [17179577.992000] ACPI: PCI Interrupt Link [APC4] enabled at IRQ 19 [17179577.992000] ACPI: PCI Interrupt 0000:01:07.0[A] -> Link [APC4] -> GSI 19 (level, high) -> IRQ 177 [17179577.992000] ata1: SATA max UDMA/133 cmd 0x9000 ctl 0x9402 bmdma 0xA000 irq 177 [17179577.992000] ata2: SATA max UDMA/133 cmd 0x9800 ctl 0x9C02 bmdma 0xA008 irq 177 [17179578.196000] ata1: SATA link up 1.5 Gbps (SStatus 113) [17179578.212000] ata1: dev 0 cfg 49:2f00 82:7c6b 83:7f69 84:4773 85:7c69 86:3e01 87:4763 88:407f [17179578.212000] ata1: dev 0 ATA-7, max UDMA/133, 490234752 sectors: LBA48 [17179578.224000] ata1: dev 0 configured for UDMA/133 [17179578.224000] scsi0 : sata_uli [17179578.428000] ata2: SATA link up 1.5 Gbps (SStatus 113) [17179578.444000] ata2: dev 0 cfg 49:2f00 82:7c6b 83:7f09 84:4673 85:7c69 86:3e01 87:4663 88:007f [17179578.444000] ata2: dev 0 ATA-7, max UDMA/133, 398297088 sectors: LBA48 [17179578.456000] ata2: dev 0 configured for PIO4 [17179578.456000] scsi1 : sata_uli [17179578.456000] Vendor: ATA Model: Maxtor 6V250F0 Rev: VA11 [17179578.456000] Type: Direct-Access ANSI SCSI revision: 05 [17179578.456000] Vendor: ATA Model: Maxtor 6L200M0 Rev: BANC [17179578.456000] Type: Direct-Access ANSI SCSI revision: 05 [17179578.464000] SCSI device sda: 490234752 512-byte hdwr sectors (251000 MB) [17179578.464000] sda: Write Protect is off [17179578.464000] sda: Mode Sense: 00 3a 00 00 [17179578.464000] SCSI device sda: drive cache: write back [17179578.464000] SCSI device sda: 490234752 512-byte hdwr sectors (251000 MB) [17179578.464000] sda: Write Protect is off [17179578.464000] sda: Mode Sense: 00 3a 00 00 [17179578.464000] SCSI device sda: drive cache: write back [17179578.464000] sda: sda2 < sda5 sda6 > [17179578.528000] sd 0:0:0:0: Attached scsi disk sda [17179578.532000] SCSI device sdb: 398297088 512-byte hdwr sectors (203928 MB) [17179578.532000] sdb: Write Protect is off [17179578.532000] sdb: Mode Sense: 00 3a 00 00 [17179578.540000] SCSI device sdb: drive cache: write back [17179578.540000] SCSI device sdb: 398297088 512-byte hdwr sectors (203928 MB) [17179578.540000] sdb: Write Protect is off [17179578.540000] sdb: Mode Sense: 00 3a 00 00 [17179578.540000] SCSI device sdb: drive cache: write back [17179578.540000] sdb: sdb1 < sdb5 sdb6 > [17179578.628000] sd 1:0:0:0: Attached scsi disk sdb [17179578.884000] ALI15X3: IDE controller at PCI slot 0000:01:07.1 [17179578.884000] ACPI: PCI Interrupt 0000:01:07.1[A] -> Link [APC4] -> GSI 19 (level, high) -> IRQ 177 [17179578.884000] ALI15X3: chipset revision 198 [17179578.884000] ALI15X3: 100% native mode on irq 177 [17179578.884000] ide2: BM-DMA at 0xb400-0xb407, BIOS settings: hde:DMA, hdf:DMA [17179578.884000] ide3: BM-DMA at 0xb408-0xb40f, BIOS settings: hdg:pio, hdh:pio [17179578.884000] Probing IDE interface ide2... [17179579.448000] Probing IDE interface ide3... [17179580.060000] Probing IDE interface ide2... [17179580.088000] usbcore: registered new driver usbfs [17179580.088000] usbcore: registered new driver hub [17179580.088000] ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI) [17179580.088000] ACPI: PCI Interrupt Link [APCF] enabled at IRQ 22 [17179580.092000] ACPI: PCI Interrupt 0000:00:02.0[A] -> Link [APCF] -> GSI 22 (level, high) -> IRQ 185 [17179580.092000] PCI: Setting latency timer of device 0000:00:02.0 to 64 [17179580.092000] ohci_hcd 0000:00:02.0: OHCI Host Controller [17179580.092000] ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 1 [17179580.092000] ohci_hcd 0000:00:02.0: irq 185, io mem 0xec003000 [17179580.152000] usb usb1: configuration #1 chosen from 1 choice [17179580.152000] hub 1-0:1.0: USB hub found [17179580.152000] hub 1-0:1.0: 3 ports detected [17179580.260000] ACPI: PCI Interrupt Link [APCG] enabled at IRQ 21 [17179580.260000] ACPI: PCI Interrupt 0000:00:02.1[B] -> Link [APCG] -> GSI 21 (level, high) -> IRQ 193 [17179580.260000] PCI: Setting latency timer of device 0000:00:02.1 to 64 [17179580.260000] ohci_hcd 0000:00:02.1: OHCI Host Controller [17179580.260000] ohci_hcd 0000:00:02.1: new USB bus registered, assigned bus number 2 [17179580.260000] ohci_hcd 0000:00:02.1: irq 193, io mem 0xec004000 [17179580.320000] usb usb2: configuration #1 chosen from 1 choice [17179580.320000] hub 2-0:1.0: USB hub found [17179580.320000] hub 2-0:1.0: 3 ports detected [17179580.428000] ACPI: PCI Interrupt Link [APCL] enabled at IRQ 20 [17179580.428000] ACPI: PCI Interrupt 0000:00:02.2[C] -> Link [APCL] -> GSI 20 (level, high) -> IRQ 201 [17179580.428000] PCI: Setting latency timer of device 0000:00:02.2 to 64 [17179580.428000] ehci_hcd 0000:00:02.2: EHCI Host Controller [17179580.428000] ehci_hcd 0000:00:02.2: new USB bus registered, assigned bus number 3 [17179580.428000] ehci_hcd 0000:00:02.2: debug port 1 [17179580.428000] PCI: cache line size of 64 is not supported by device 0000:00:02.2 [17179580.428000] ehci_hcd 0000:00:02.2: irq 201, io mem 0xec005000 [17179580.428000] ehci_hcd 0000:00:02.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 [17179580.428000] usb usb3: configuration #1 chosen from 1 choice [17179580.428000] hub 3-0:1.0: USB hub found [17179580.428000] hub 3-0:1.0: 6 ports detected [17179580.624000] Probing IDE interface ide3... [17179580.880000] usb 3-2: new high speed USB device using ehci_hcd and address 2 [17179581.028000] usb 3-2: configuration #1 chosen from 1 choice [17179581.028000] hub 3-2:1.0: USB hub found [17179581.028000] hub 3-2:1.0: 4 ports detected [17179581.208000] Attempting manual resume [17179581.220000] kjournald starting. Commit interval 5 seconds [17179581.220000] EXT3-fs: mounted filesystem with ordered data mode. [17179581.360000] usb 3-2.2: new low speed USB device using ehci_hcd and address 3 [17179581.468000] usb 3-2.2: configuration #1 chosen from 1 choice [17179581.700000] usb 3-2.4: new low speed USB device using ehci_hcd and address 4 [17179581.804000] usb 3-2.4: config 1 has an invalid interface number: 1 but max is 0 [17179581.804000] usb 3-2.4: config 1 has no interface number 0 [17179581.808000] usb 3-2.4: configuration #1 chosen from 1 choice [17179590.044000] Linux agpgart interface v0.101 (c) Dave Jones [17179590.188000] agpgart: Detected NVIDIA nForce2 chipset [17179590.196000] agpgart: AGP aperture is 128M @ 0xd0000000 [17179590.260000] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [17179590.616000] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [17179590.808000] Floppy drive(s): fd0 is 1.44M [17179590.828000] FDC 0 is a post-1991 82077 [17179591.016000] parport: PnPBIOS parport detected. [17179591.016000] parport0: PC-style at 0x378 (0x778), irq 7, dma 3 [PCSPP,TRISTATE,COMPAT,ECP,DMA] [17179591.456000] forcedeth.c: Reverse Engineered nForce ethernet driver. Version 0.54. [17179591.456000] ACPI: PCI Interrupt Link [APCH] enabled at IRQ 22 [17179591.456000] ACPI: PCI Interrupt 0000:00:04.0[A] -> Link [APCH] -> GSI 22 (level, high) -> IRQ 185 [17179591.456000] PCI: Setting latency timer of device 0000:00:04.0 to 64 [17179591.572000] input: PC Speaker as /class/input/input0 [17179591.676000] usbcore: registered new driver hiddev [17179591.692000] input: HID Keyboard Device as /class/input/input1 [17179591.692000] input: USB HID v1.10 Keyboard [HID Keyboard Device] on usb-0000:00:02.2-2.2 [17179591.696000] input: HID Keyboard Device as /class/input/input2 [17179591.696000] input: USB HID v1.10 Device [HID Keyboard Device] on usb-0000:00:02.2-2.2 [17179591.700000] input: PS/2+USB Mouse as /class/input/input3 [17179591.700000] input: USB HID v1.00 Mouse [PS/2+USB Mouse] on usb-0000:00:02.2-2.4 [17179591.700000] usbcore: registered new driver usbhid [17179591.700000] drivers/usb/input/hid-core.c: v2.6:USB HID core driver [17179591.812000] sd 0:0:0:0: Attached scsi generic sg0 type 0 [17179591.816000] sd 1:0:0:0: Attached scsi generic sg1 type 0 [17179591.976000] eth0: forcedeth.c: subsystem: 01043:80a7 bound to 0000:00:04.0 [17179591.980000] ACPI: PCI Interrupt Link [APCJ] enabled at IRQ 21 [17179591.980000] ACPI: PCI Interrupt 0000:00:06.0[A] -> Link [APCJ] -> GSI 21 (level, high) -> IRQ 193 [17179591.980000] PCI: Setting latency timer of device 0000:00:06.0 to 64 [17179592.004000] ts: Compaq touchscreen protocol output [17179592.304000] intel8x0_measure_ac97_clock: measured 56795 usecs [17179592.304000] intel8x0: clocking to 48000 [17179592.308000] ACPI: PCI Interrupt Link [APC2] enabled at IRQ 17 [17179592.308000] ACPI: PCI Interrupt 0000:01:09.0[A] -> Link [APC2] -> GSI 17 (level, high) -> IRQ 209 [17179592.308000] i2c_adapter i2c-0: nForce2 SMBus adapter at 0x5000 [17179592.308000] i2c_adapter i2c-1: nForce2 SMBus adapter at 0x5500 [17179592.420000] eth0: no link during initialization. [17179592.472000] lp0: using parport0 (interrupt-driven). [17179592.496000] Adding 2096440k swap on /dev/disk/by-uuid/a67b572b-a166-4dc7-9b19-17ae0dd320e2. Priority:-1 extents:1 across:2096440k [17179592.556000] EXT3 FS on hda7, internal journal [17179593.056000] eth0: link up. [17179593.476000] NET: Registered protocol family 17 [17179596.684000] NET: Registered protocol family 10 [17179596.684000] lo: Disabled Privacy Extensions [17179596.684000] IPv6 over IPv4 tunneling driver [17179598.192000] ACPI: Power Button (FF) [PWRF] [17179598.192000] ACPI: Power Button (CM) [PWRB] [17179598.208000] Using specific hotkey driver [17179598.240000] ibm_acpi: ec object not found [17179598.268000] toshiba_acpi: Unknown parameter `hotkeys_over_acpi' [17179599.740000] [drm] Initialized drm 1.0.1 20051102 [17179599.756000] ACPI: PCI Interrupt 0000:02:00.0[A] -> Link [APC4] -> GSI 19 (level, high) -> IRQ 177 [17179599.756000] [drm] Initialized radeon 1.24.0 20060225 on minor 0 [17179600.436000] apm: BIOS version 1.2 Flags 0x07 (Driver version 1.16ac) [17179600.436000] apm: overridden by ACPI. [17179601.048000] agpgart: Found an AGP 3.0 compliant device at 0000:00:00.0. [17179601.048000] agpgart: Putting AGP V3 device at 0000:00:00.0 into 4x mode [17179601.048000] agpgart: Putting AGP V3 device at 0000:02:00.0 into 4x mode [17179601.388000] [drm] Setting GART location based on new memory map [17179601.388000] [drm] Loading R200 Microcode [17179601.388000] [drm] writeback test succeeded in 1 usecs [17179607.164000] eth0: no IPv6 routers present [17179608.476000] Bluetooth: Core ver 2.8 [17179608.476000] NET: Registered protocol family 31 [17179608.476000] Bluetooth: HCI device and connection manager initialized [17179608.476000] Bluetooth: HCI socket layer initialized [17179608.608000] Bluetooth: L2CAP ver 2.8 [17179608.608000] Bluetooth: L2CAP socket layer initialized [17179608.728000] Bluetooth: RFCOMM socket layer initialized [17179608.728000] Bluetooth: RFCOMM TTY layer initialized [17179608.728000] Bluetooth: RFCOMM ver 1.7 2.6.17.2 (not working) dmesg -s 1000000 [17179569.184000] Linux version 2.6.17.2mb (marco@king-test) (gcc version 4.1.2 20060928 (prerelease) (Ubuntu 4.1.1-13ubuntu5)) #1 SMP Tue Nov 28 21:18:11 CET 2006 [17179569.184000] BIOS-provided physical RAM map: [17179569.184000] BIOS-e820: 0000000000000000 - 000000000009f400 (usable) [17179569.184000] BIOS-e820: 000000000009f400 - 00000000000a0000 (reserved) [17179569.184000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) [17179569.184000] BIOS-e820: 0000000000100000 - 000000003fff0000 (usable) [17179569.184000] BIOS-e820: 000000003fff0000 - 000000003fff3000 (ACPI NVS) [17179569.184000] BIOS-e820: 000000003fff3000 - 0000000040000000 (ACPI data) [17179569.184000] BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved) [17179569.184000] BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved) [17179569.184000] BIOS-e820: 00000000ffff0000 - 0000000100000000 (reserved) [17179569.184000] 127MB HIGHMEM available. [17179569.184000] 896MB LOWMEM available. [17179569.184000] On node 0 totalpages: 262128 [17179569.184000] DMA zone: 4096 pages, LIFO batch:0 [17179569.184000] Normal zone: 225280 pages, LIFO batch:31 [17179569.184000] HighMem zone: 32752 pages, LIFO batch:7 [17179569.184000] DMI 2.2 present. [17179569.184000] ACPI: RSDP (v000 Nvidia ) @ 0x000f75c0 [17179569.184000] ACPI: RSDT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff3000 [17179569.184000] ACPI: FADT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff3040 [17179569.184000] ACPI: MADT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff74c0 [17179569.184000] ACPI: DSDT (v001 NVIDIA AWRDACPI 0x00001000 MSFT 0x0100000e) @ 0x00000000 [17179569.184000] ACPI: PM-Timer IO Port: 0x4008 [17179569.184000] ACPI: Local APIC address 0xfee00000 [17179569.184000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [17179569.184000] Processor #0 6:10 APIC version 16 [17179569.184000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1]) [17179569.184000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0]) [17179569.184000] IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23 [17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [17179569.184000] ACPI: BIOS IRQ0 pin2 override ignored. [17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 14 global_irq 14 high edge) [17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 15 global_irq 15 high edge) [17179569.184000] ACPI: IRQ9 used by override. [17179569.184000] ACPI: IRQ14 used by override. [17179569.184000] ACPI: IRQ15 used by override. [17179569.184000] Enabling APIC mode: Flat. Using 1 I/O APICs [17179569.184000] Using ACPI (MADT) for SMP configuration information [17179569.184000] Allocating PCI resources starting at 50000000 (gap: 40000000:bec00000) [17179569.184000] Built 1 zonelists [17179569.184000] Kernel command line: root=/dev/hda7 ro quiet splash locale=it_IT [17179569.184000] mapped APIC to ffffd000 (fee00000) [17179569.184000] mapped IOAPIC to ffffc000 (fec00000) [17179569.184000] Enabling fast FPU save and restore... done. [17179569.184000] Enabling unmasked SIMD FPU exception support... done. [17179569.184000] Initializing CPU#0 [17179569.184000] PID hash table entries: 4096 (order: 12, 16384 bytes) [17179569.184000] Detected 2205.232 MHz processor. [17179569.184000] Using pmtmr for high-res timesource [17179569.184000] Console: colour VGA+ 80x25 [17179572.072000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [17179572.072000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [17179572.096000] Memory: 1029948k/1048512k available (1873k kernel code, 17852k reserved, 1050k data, 308k init, 131008k highmem) [17179572.096000] Checking if this processor honours the WP bit even in supervisor mode... Ok. [17179572.176000] Calibrating delay using timer specific routine.. 4415.04 BogoMIPS (lpj=8830096) [17179572.176000] Security Framework v1.0.0 initialized [17179572.176000] SELinux: Disabled at boot. [17179572.176000] Mount-cache hash table entries: 512 [17179572.176000] CPU: After generic identify, caps: 0383fbff c1c3fbff 00000000 00000000 00000000 00000000 00000000 [17179572.176000] CPU: After vendor identify, caps: 0383fbff c1c3fbff 00000000 00000000 00000000 00000000 00000000 [17179572.176000] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line) [17179572.176000] CPU: L2 Cache: 512K (64 bytes/line) [17179572.176000] CPU: After all inits, caps: 0383fbff c1c3fbff 00000000 00000420 00000000 00000000 00000000 [17179572.176000] Checking 'hlt' instruction... OK. [17179572.192000] SMP alternatives: switching to UP code [17179572.192000] Freeing SMP alternatives: 16k freed [17179572.200000] CPU0: AMD Athlon(tm) XP 3200+ stepping 00 [17179572.200000] Total of 1 processors activated (4415.04 BogoMIPS). [17179572.200000] ENABLING IO-APIC IRQs [17179572.200000] ..TIMER: vector=0x31 apic1=0 pin1=0 apic2=-1 pin2=-1 [17179572.344000] Brought up 1 CPUs [17179572.344000] migration_cost=0 [17179572.344000] checking if image is initramfs... it is [17179572.732000] Freeing initrd memory: 5136k freed [17179572.732000] NET: Registered protocol family 16 [17179572.732000] EISA bus registered [17179572.732000] ACPI: bus type pci registered [17179572.748000] PCI: PCI BIOS revision 2.10 entry at 0xfb4a0, last bus=2 [17179572.748000] Setting up standard PCI resources [17179572.752000] ACPI: Subsystem revision 20060127 [17179572.760000] ACPI: Interpreter enabled [17179572.760000] ACPI: Using IOAPIC for interrupt routing [17179572.764000] ACPI: PCI Root Bridge [PCI0] (0000:00) [17179572.764000] PCI: Probing PCI hardware (bus 00) [17179572.764000] PCI: nForce2 C1 Halt Disconnect fixup [17179572.768000] Boot video device is 0000:02:00.0 [17179572.768000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT] [17179572.816000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.HUB0._PRT] [17179572.816000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.AGPB._PRT] [17179572.820000] ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 *5 6 7 10 11 12 14 15) [17179572.820000] ACPI: PCI Interrupt Link [LNK2] (IRQs 3 4 5 6 7 *10 11 12 14 15) [17179572.820000] ACPI: PCI Interrupt Link [LNK3] (IRQs 3 4 *5 6 7 10 11 12 14 15) [17179572.820000] ACPI: PCI Interrupt Link [LNK4] (IRQs 3 4 5 6 7 10 *11 12 14 15) [17179572.820000] ACPI: PCI Interrupt Link [LNK5] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179572.820000] ACPI: PCI Interrupt Link [LUBA] (IRQs 3 4 *5 6 7 10 11 12 14 15) [17179572.820000] ACPI: PCI Interrupt Link [LUBB] (IRQs 3 4 5 6 7 *10 11 12 14 15) [17179572.820000] ACPI: PCI Interrupt Link [LMAC] (IRQs 3 4 5 6 7 *10 11 12 14 15) [17179572.820000] ACPI: PCI Interrupt Link [LAPU] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179572.820000] ACPI: PCI Interrupt Link [LACI] (IRQs 3 4 5 6 7 10 *11 12 14 15) [17179572.820000] ACPI: PCI Interrupt Link [LMCI] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179572.820000] ACPI: PCI Interrupt Link [LSMB] (IRQs 3 4 5 6 7 10 *11 12 14 15) [17179572.824000] ACPI: PCI Interrupt Link [LUB2] (IRQs 3 4 5 6 7 10 *11 12 14 15) [17179572.824000] ACPI: PCI Interrupt Link [LFIR] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [L3CM] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [LIDE] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [APC1] (IRQs *16), disabled. [17179572.824000] ACPI: PCI Interrupt Link [APC2] (IRQs *17), disabled. [17179572.824000] ACPI: PCI Interrupt Link [APC3] (IRQs *18), disabled. [17179572.824000] ACPI: PCI Interrupt Link [APC4] (IRQs *19), disabled. [17179572.824000] ACPI: PCI Interrupt Link [APC5] (IRQs *16), disabled. [17179572.824000] ACPI: PCI Interrupt Link [APCF] (IRQs 20 21 22) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [APCG] (IRQs 20 21 22) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [APCH] (IRQs 20 21 22) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [APCI] (IRQs 20 21 22) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [APCJ] (IRQs 20 21 22) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [APCK] (IRQs 20 21 22) *0, disabled. [17179572.824000] ACPI: PCI Interrupt Link [APCS] (IRQs *23), disabled. [17179572.828000] ACPI: PCI Interrupt Link [APCL] (IRQs 20 21 22) *0, disabled. [17179572.828000] ACPI: PCI Interrupt Link [APCM] (IRQs 20 21 22) *0, disabled. [17179572.828000] ACPI: PCI Interrupt Link [AP3C] (IRQs 20 21 22) *0, disabled. [17179572.828000] ACPI: PCI Interrupt Link [APCZ] (IRQs 20 21 22) *0, disabled. [17179572.828000] Linux Plug and Play Support v0.97 (c) Adam Belay [17179572.828000] pnp: PnP ACPI init [17179572.836000] pnp: PnP ACPI: found 13 devices [17179572.836000] PnPBIOS: Disabled by ACPI PNP [17179572.836000] PCI: Using ACPI for IRQ routing [17179572.836000] PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report [17179572.880000] pnp: 00:00: ioport range 0x4000-0x407f could not be reserved [17179572.880000] pnp: 00:00: ioport range 0x4080-0x40ff has been reserved [17179572.880000] pnp: 00:00: ioport range 0x4400-0x447f has been reserved [17179572.880000] pnp: 00:00: ioport range 0x4480-0x44ff could not be reserved [17179572.880000] pnp: 00:00: ioport range 0x4200-0x427f has been reserved [17179572.880000] pnp: 00:00: ioport range 0x4280-0x42ff has been reserved [17179572.880000] pnp: 00:01: ioport range 0x5000-0x503f has been reserved [17179572.880000] pnp: 00:01: ioport range 0x5500-0x553f has been reserved [17179572.880000] PCI: Bridge: 0000:00:08.0 [17179572.880000] IO window: 9000-bfff [17179572.880000] MEM window: ea000000-ebffffff [17179572.880000] PREFETCH window: 50000000-500fffff [17179572.880000] PCI: Bridge: 0000:00:1e.0 [17179572.880000] IO window: c000-cfff [17179572.880000] MEM window: e8000000-e9ffffff [17179572.880000] PREFETCH window: d8000000-e7ffffff [17179572.880000] PCI: Setting latency timer of device 0000:00:08.0 to 64 [17179572.880000] NET: Registered protocol family 2 [17179572.916000] IP route cache hash table entries: 32768 (order: 5, 131072 bytes) [17179572.916000] TCP established hash table entries: 131072 (order: 8, 1048576 bytes) [17179572.916000] TCP bind hash table entries: 65536 (order: 7, 524288 bytes) [17179572.916000] TCP: Hash tables configured (established 131072 bind 65536) [17179572.916000] TCP reno registered [17179572.916000] audit: initializing netlink socket (disabled) [17179572.916000] audit(1164789362.916:1): initialized [17179572.916000] highmem bounce pool size: 64 pages [17179572.916000] VFS: Disk quotas dquot_6.5.1 [17179572.916000] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [17179572.916000] Initializing Cryptographic API [17179572.916000] io scheduler noop registered [17179572.916000] io scheduler anticipatory registered [17179572.916000] io scheduler deadline registered [17179572.916000] io scheduler cfq registered (default) [17179572.980000] isapnp: Scanning for PnP cards... [17179573.332000] isapnp: No Plug & Play device found [17179573.352000] Real Time Clock Driver v1.12ac [17179573.352000] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled [17179573.352000] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [17179573.352000] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [17179573.352000] 00:0a: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [17179573.352000] 00:0b: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [17179573.352000] RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize [17179573.352000] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2 [17179573.352000] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx [17179573.356000] PNP: No PS/2 controller found. Probing ports directly. [17179573.356000] serio: i8042 AUX port at 0x60,0x64 irq 12 [17179573.356000] serio: i8042 KBD port at 0x60,0x64 irq 1 [17179573.356000] mice: PS/2 mouse device common for all mice [17179573.356000] EISA: Probing bus 0 at eisa.0 [17179573.356000] Cannot allocate resource for EISA slot 4 [17179573.356000] Cannot allocate resource for EISA slot 5 [17179573.356000] EISA: Detected 0 cards. [17179573.356000] TCP bic registered [17179573.356000] NET: Registered protocol family 1 [17179573.356000] NET: Registered protocol family 8 [17179573.356000] NET: Registered protocol family 20 [17179573.356000] Using IPI No-Shortcut mode [17179573.356000] ACPI wakeup devices: [17179573.356000] HUB0 HUB1 USB0 USB1 USB2 F139 MMAC MMCI UAR1 [17179573.356000] ACPI: (supports S0 S1 S3 S4 S5) [17179573.356000] Freeing unused kernel memory: 308k freed [17179574.716000] SCSI subsystem initialized [17179574.716000] libata version 1.20 loaded. [17179574.720000] NFORCE2: IDE controller at PCI slot 0000:00:09.0 [17179574.720000] NFORCE2: chipset revision 162 [17179574.720000] NFORCE2: not 100% native mode: will probe irqs later [17179574.720000] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [17179574.720000] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround. [17179574.720000] NFORCE2: 0000:00:09.0 (rev a2) UDMA133 controller [17179574.720000] ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA [17179574.720000] ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA [17179574.720000] Probing IDE interface ide0... [17179575.012000] hda: SAMSUNG SP1604N, ATA DISK drive [17179575.296000] hdb: ST3160022ACE, ATA DISK drive [17179575.356000] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 [17179575.356000] Probing IDE interface ide1... [17179576.096000] hdc: Optiarc DVD RW AD-7173A, ATAPI CD/DVD-ROM drive [17179576.772000] ide1 at 0x170-0x177,0x376 on irq 15 [17179576.776000] hda: max request size: 512KiB [17179576.788000] hda: 312581808 sectors (160041 MB) w/2048KiB Cache, CHS=19457/255/63, UDMA(133) [17179576.788000] hda: cache flushes supported [17179576.788000] hda: hda1 hda2 < hda5 hda6 hda7 > [17179576.884000] hdb: max request size: 512KiB [17179576.884000] hdb: 312581808 sectors (160041 MB) w/2048KiB Cache, CHS=19457/255/63, UDMA(100) [17179576.884000] hdb: cache flushes supported [17179576.884000] hdb: hdb1 < hdb5 hdb6 hdb7 hdb8 > [17179576.956000] hdc: ATAPI 48X DVD-ROM DVD-R-RAM CD-R/RW drive, 2048kB Cache, UDMA(66) [17179576.956000] Uniform CD-ROM driver Revision: 3.20 [17179577.596000] sata_uli 0000:01:07.0: version 0.5 [17179577.596000] ACPI: PCI Interrupt Link [APC4] enabled at IRQ 19 [17179577.596000] ACPI: PCI Interrupt 0000:01:07.0[A] -> Link [APC4] -> GSI 19 (level, high) -> IRQ 177 [17179577.596000] ata1: SATA max UDMA/133 cmd 0x9000 ctl 0x9402 bmdma 0xA000 irq 177 [17179577.596000] ata2: SATA max UDMA/133 cmd 0x9800 ctl 0x9C02 bmdma 0xA008 irq 177 [17179577.800000] ata1: SATA link up 1.5 Gbps (SStatus 113) [17179577.816000] ata1: dev 0 cfg 49:2f00 82:7c6b 83:7f69 84:4773 85:7c69 86:3e01 87:4763 88:407f [17179577.816000] ata1: dev 0 ATA-7, max UDMA/133, 490234752 sectors: LBA48 [17179577.828000] ata1: dev 0 configured for UDMA/133 [17179577.828000] scsi0 : sata_uli [17179578.032000] ata2: SATA link up 1.5 Gbps (SStatus 113) [17179578.048000] ata2: dev 0 cfg 49:2f00 82:7c6b 83:7f09 84:4673 85:7c69 86:3e01 87:4663 88:007f [17179578.048000] ata2: dev 0 ATA-7, max UDMA/133, 398297088 sectors: LBA48 [17179578.060000] ata2: dev 0 configured for PIO4 [17179578.060000] scsi1 : sata_uli [17179578.060000] Vendor: ATA Model: Maxtor 6V250F0 Rev: VA11 [17179578.060000] Type: Direct-Access ANSI SCSI revision: 05 [17179578.060000] Vendor: ATA Model: Maxtor 6L200M0 Rev: BANC [17179578.060000] Type: Direct-Access ANSI SCSI revision: 05 [17179578.068000] SCSI device sda: 490234752 512-byte hdwr sectors (251000 MB) [17179578.068000] sda: Write Protect is off [17179578.068000] sda: Mode Sense: 00 3a 00 00 [17179578.068000] SCSI device sda: drive cache: write back [17179578.068000] SCSI device sda: 490234752 512-byte hdwr sectors (251000 MB) [17179578.068000] sda: Write Protect is off [17179578.068000] sda: Mode Sense: 00 3a 00 00 [17179578.068000] SCSI device sda: drive cache: write back [17179578.068000] sda: sda2 < sda5 sda6 > [17179578.108000] sd 0:0:0:0: Attached scsi disk sda [17179578.112000] SCSI device sdb: 398297088 512-byte hdwr sectors (203928 MB) [17179578.112000] sdb: Write Protect is off [17179578.112000] sdb: Mode Sense: 00 3a 00 00 [17179578.112000] SCSI device sdb: drive cache: write back [17179578.116000] SCSI device sdb: 398297088 512-byte hdwr sectors (203928 MB) [17179578.120000] sdb: Write Protect is off [17179578.120000] sdb: Mode Sense: 00 3a 00 00 [17179578.120000] SCSI device sdb: drive cache: write back [17179578.120000] sdb: sdb1 < sdb5 sdb6 > [17179578.220000] sd 1:0:0:0: Attached scsi disk sdb [17179578.620000] ALI15X3: IDE controller at PCI slot 0000:01:07.1 [17179578.620000] ACPI: PCI Interrupt 0000:01:07.1[A] -> Link [APC4] -> GSI 19 (level, high) -> IRQ 177 [17179578.620000] ALI15X3: chipset revision 198 [17179578.620000] ALI15X3: 100% native mode on irq 177 [17179578.620000] ide2: BM-DMA at 0xb400-0xb407, BIOS settings: hde:DMA, hdf:DMA [17179578.624000] ide3: BM-DMA at 0xb408-0xb40f, BIOS settings: hdg:pio, hdh:pio [17179578.624000] Probing IDE interface ide2... [17179579.184000] Probing IDE interface ide3... [17179579.796000] Probing IDE interface ide2... [17179579.824000] usbcore: registered new driver usbfs [17179579.824000] usbcore: registered new driver hub [17179579.828000] ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI) [17179579.828000] ACPI: PCI Interrupt Link [APCF] enabled at IRQ 22 [17179579.828000] ACPI: PCI Interrupt 0000:00:02.0[A] -> Link [APCF] -> GSI 22 (level, high) -> IRQ 185 [17179579.828000] PCI: Setting latency timer of device 0000:00:02.0 to 64 [17179579.828000] ohci_hcd 0000:00:02.0: OHCI Host Controller [17179579.828000] ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 1 [17179579.828000] ohci_hcd 0000:00:02.0: irq 185, io mem 0xec003000 [17179579.888000] usb usb1: configuration #1 chosen from 1 choice [17179579.888000] hub 1-0:1.0: USB hub found [17179579.888000] hub 1-0:1.0: 3 ports detected [17179579.996000] ACPI: PCI Interrupt Link [APCG] enabled at IRQ 21 [17179579.996000] ACPI: PCI Interrupt 0000:00:02.1[B] -> Link [APCG] -> GSI 21 (level, high) -> IRQ 193 [17179579.996000] PCI: Setting latency timer of device 0000:00:02.1 to 64 [17179579.996000] ohci_hcd 0000:00:02.1: OHCI Host Controller [17179579.996000] ohci_hcd 0000:00:02.1: new USB bus registered, assigned bus number 2 [17179579.996000] ohci_hcd 0000:00:02.1: irq 193, io mem 0xec004000 [17179580.056000] usb usb2: configuration #1 chosen from 1 choice [17179580.056000] hub 2-0:1.0: USB hub found [17179580.056000] hub 2-0:1.0: 3 ports detected [17179580.164000] ACPI: PCI Interrupt Link [APCL] enabled at IRQ 20 [17179580.164000] ACPI: PCI Interrupt 0000:00:02.2[C] -> Link [APCL] -> GSI 20 (level, high) -> IRQ 201 [17179580.164000] PCI: Setting latency timer of device 0000:00:02.2 to 64 [17179580.164000] ehci_hcd 0000:00:02.2: EHCI Host Controller [17179580.164000] ehci_hcd 0000:00:02.2: new USB bus registered, assigned bus number 3 [17179580.164000] ehci_hcd 0000:00:02.2: debug port 1 [17179580.164000] PCI: cache line size of 64 is not supported by device 0000:00:02.2 [17179580.164000] ehci_hcd 0000:00:02.2: irq 201, io mem 0xec005000 [17179580.164000] ehci_hcd 0000:00:02.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 [17179580.164000] usb usb3: configuration #1 chosen from 1 choice [17179580.164000] hub 3-0:1.0: USB hub found [17179580.164000] hub 3-0:1.0: 6 ports detected [17179580.360000] Probing IDE interface ide3... [17179580.616000] usb 3-2: new high speed USB device using ehci_hcd and address 2 [17179580.764000] usb 3-2: configuration #1 chosen from 1 choice [17179580.764000] hub 3-2:1.0: USB hub found [17179580.764000] hub 3-2:1.0: 4 ports detected [17179580.940000] Attempting manual resume [17179580.960000] kjournald starting. Commit interval 5 seconds [17179580.960000] EXT3-fs: mounted filesystem with ordered data mode. [17179581.096000] usb 3-2.2: new low speed USB device using ehci_hcd and address 3 [17179581.204000] usb 3-2.2: configuration #1 chosen from 1 choice [17179581.440000] usb 3-2.4: new low speed USB device using ehci_hcd and address 4 [17179581.544000] usb 3-2.4: config 1 has an invalid interface number: 1 but max is 0 [17179581.544000] usb 3-2.4: config 1 has no interface number 0 [17179581.548000] usb 3-2.4: configuration #1 chosen from 1 choice [17179590.004000] Floppy drive(s): fd0 is 1.44M [17179590.028000] FDC 0 is a post-1991 82077 [17179590.144000] input: PC Speaker as /class/input/input0 [17179590.192000] forcedeth.c: Reverse Engineered nForce ethernet driver. Version 0.54. [17179590.192000] ACPI: PCI Interrupt Link [APCH] enabled at IRQ 22 [17179590.192000] ACPI: PCI Interrupt 0000:00:04.0[A] -> Link [APCH] -> GSI 22 (level, high) -> IRQ 185 [17179590.192000] PCI: Setting latency timer of device 0000:00:04.0 to 64 [17179590.712000] eth0: forcedeth.c: subsystem: 01043:80a7 bound to 0000:00:04.0 [17179590.780000] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [17179590.788000] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [17179590.828000] Linux agpgart interface v0.101 (c) Dave Jones [17179590.944000] parport: PnPBIOS parport detected. [17179590.944000] parport0: PC-style at 0x378 (0x778), irq 7, dma 3 [PCSPP,TRISTATE,COMPAT,ECP,DMA] [17179591.052000] i2c_adapter i2c-0: nForce2 SMBus adapter at 0x5000 [17179591.052000] i2c_adapter i2c-1: nForce2 SMBus adapter at 0x5500 [17179591.076000] agpgart: Detected NVIDIA nForce2 chipset [17179591.084000] agpgart: AGP aperture is 128M @ 0xd0000000 [17179591.212000] ACPI: PCI Interrupt Link [APCJ] enabled at IRQ 21 [17179591.212000] ACPI: PCI Interrupt 0000:00:06.0[A] -> Link [APCJ] -> GSI 21 (level, high) -> IRQ 193 [17179591.212000] PCI: Setting latency timer of device 0000:00:06.0 to 64 [17179591.404000] sd 0:0:0:0: Attached scsi generic sg0 type 0 [17179591.404000] sd 1:0:0:0: Attached scsi generic sg1 type 0 [17179591.424000] usbcore: registered new driver hiddev [17179591.440000] input: HID Keyboard Device as /class/input/input1 [17179591.440000] input: USB HID v1.10 Keyboard [HID Keyboard Device] on usb-0000:00:02.2-2.2 [17179591.448000] input: HID Keyboard Device as /class/input/input2 [17179591.448000] input: USB HID v1.10 Device [HID Keyboard Device] on usb-0000:00:02.2-2.2 [17179591.448000] drivers/usb/input/hid-core.c: input irq status -32 received [17179591.452000] input: PS/2+USB Mouse as /class/input/input3 [17179591.452000] input: USB HID v1.00 Mouse [PS/2+USB Mouse] on usb-0000:00:02.2-2.4 [17179591.452000] usbcore: registered new driver usbhid [17179591.452000] drivers/usb/input/hid-core.c: v2.6:USB HID core driver [17179591.500000] eth0: no link during initialization. [17179591.536000] intel8x0_measure_ac97_clock: measured 56798 usecs [17179591.536000] intel8x0: clocking to 48000 [17179591.540000] ACPI: PCI Interrupt Link [APC2] enabled at IRQ 17 [17179591.540000] ACPI: PCI Interrupt 0000:01:09.0[A] -> Link [APC2] -> GSI 17 (level, high) -> IRQ 209 [17179591.624000] ts: Compaq touchscreen protocol output [17179591.816000] eth0: link up. [17179592.024000] lp0: using parport0 (interrupt-driven). [17179592.048000] Adding 2096440k swap on /dev/disk/by-uuid/a67b572b-a166-4dc7-9b19-17ae0dd320e2. Priority:-1 extents:1 across:2096440k [17179592.112000] EXT3 FS on hda7, internal journal [17179592.560000] NET: Registered protocol family 17 [17179593.000000] NET: Registered protocol family 10 [17179593.000000] lo: Disabled Privacy Extensions [17179593.000000] IPv6 over IPv4 tunneling driver [17179597.712000] ACPI: Power Button (FF) [PWRF] [17179597.712000] ACPI: Power Button (CM) [PWRB] [17179597.724000] Using specific hotkey driver [17179597.752000] ibm_acpi: ec object not found [17179597.772000] toshiba_acpi: Unknown parameter `hotkeys_over_acpi' [17179599.232000] [drm] Initialized drm 1.0.1 20051102 [17179599.244000] ACPI: PCI Interrupt 0000:02:00.0[A] -> Link [APC4] -> GSI 19 (level, high) -> IRQ 177 [17179599.244000] [drm] Initialized radeon 1.24.0 20060225 on minor 0 [17179599.868000] apm: BIOS version 1.2 Flags 0x07 (Driver version 1.16ac) [17179599.868000] apm: overridden by ACPI. [17179600.512000] agpgart: Found an AGP 3.0 compliant device at 0000:00:00.0. [17179600.512000] agpgart: Putting AGP V3 device at 0000:00:00.0 into 4x mode [17179600.512000] agpgart: Putting AGP V3 device at 0000:02:00.0 into 4x mode [17179600.836000] [drm] Setting GART location based on new memory map [17179600.836000] [drm] Loading R200 Microcode [17179600.836000] [drm] writeback test succeeded in 1 usecs [17179603.680000] eth0: no IPv6 routers present [17179608.348000] Bluetooth: Core ver 2.8 [17179608.348000] NET: Registered protocol family 31 [17179608.348000] Bluetooth: HCI device and connection manager initialized [17179608.348000] Bluetooth: HCI socket layer initialized [17179608.500000] Bluetooth: L2CAP ver 2.8 [17179608.500000] Bluetooth: L2CAP socket layer initialized [17179608.624000] Bluetooth: RFCOMM socket layer initialized [17179608.624000] Bluetooth: RFCOMM TTY layer initialized [17179608.624000] Bluetooth: RFCOMM ver 1.7 Between, 2.6.17.1 and 2.6.17.2, ATA_DFLAG_PIO has changed its position. Please make sure you haven't mixed modules for the two versions. If that's not the case, please change #undef ATA_DEBUG in include/linux/libata.h to #define ATA_DEBUG, recompile the kernel, and report the boot dmesg. Created attachment 9664 [details]
Dmesg 2.6.17.2 ATA_DEBUG
No mixed modules, I'm sure. It's occur even with Debian Etch precompiled kernel 2.6.17.2, or even in the Live-CD of distribution that use kernel >= 2.6.17.2 as Ubuntu 6.10 and Mandriva 2007. In attachment there's dmesg with ATA_DEBUG define. If you need, I have dmesg with ATA_VERBOSE_DEBUG define too. I'm looking for something in libata-core.c, but my C knowlodge is too poor to do it... Well here's the dmesg-diff. I don't see anything in here which would explain a sata-super-slowdown. --- 1 2006-11-29 19:17:28.000000000 -0800 +++ 2 2006-11-29 19:17:36.000000000 -0800 @@ -1,22 +1,22 @@ Return-Path: <www@osdl.org> Received: from localhost (bix ) - by localhost.localdomain (8.12.10/8.12.10) with ESMTP id kAT8Y3e9026093 - for <akpm@localhost>; Wed, 29 Nov 2006 00:34:05 -0800 + by localhost.localdomain (8.12.10/8.12.10) with ESMTP id kAT8dNe8026143 + for <akpm@localhost>; Wed, 29 Nov 2006 00:39:24 -0800 Received: from bix by localhost with POP3 (fetchmail-6.2.0) - for akpm@localhost (single-drop); Wed, 29 Nov 2006 00:34:05 -0800 (PST) + for akpm@localhost (single-drop); Wed, 29 Nov 2006 00:39:24 -0800 (PST) Received: from smtp.osdl.org (smtp.osdl.org ) - by shell0.pdx.osdl.net (8.13.1/8.11.6) with ESMTP id kAT7YSOB012771 - for <akpm@mail.gateway.osdl.net>; Tue, 28 Nov 2006 23:34:28 -0800 + by shell0.pdx.osdl.net (8.13.1/8.11.6) with ESMTP id kAT7dqsa012888 + for <akpm@mail.gateway.osdl.net>; Tue, 28 Nov 2006 23:39:52 -0800 Received: from fire-2.osdl.org (localhost ) - by smtp.osdl.org (8.12.8/8.12.8) with ESMTP id kAT7YKix028534 + by smtp.osdl.org (8.12.8/8.12.8) with ESMTP id kAT7diix028971 (version=TLSv1/SSLv3 cipher=EDH-RSA-DES-CBC3-SHA bits=168 verify=NO) - for <akpm@osdl.org>; Tue, 28 Nov 2006 23:34:20 -0800 + for <akpm@osdl.org>; Tue, 28 Nov 2006 23:39:45 -0800 Received: (from www@localhost) - by fire-2.osdl.org (8.12.8/8.12.5/Submit) id kAT7YKDZ028532; - Tue, 28 Nov 2006 23:34:20 -0800 -Date: Tue, 28 Nov 2006 23:34:20 -0800 -Message-Id: <200611290734.kAT7YKDZ028532@fire-2.osdl.org> + by fire-2.osdl.org (8.12.8/8.12.5/Submit) id kAT7dilb028969; + Tue, 28 Nov 2006 23:39:44 -0800 +Date: Tue, 28 Nov 2006 23:39:44 -0800 +Message-Id: <200611290739.kAT7dilb028969@fire-2.osdl.org> From: bugme-daemon@bugzilla.kernel.org To: akpm@osdl.org Subject: [Bug 7590] libata: /dev/sdb slow and unstable - Timing buffered disk reads: 1.79 MB/sec @@ -36,14 +36,15 @@ -------- Additional Comments From astrodanzante@gmail.com 2006-11-28 23:31 ------- -2.6.17.1 (Working) +------- Additional Comments From astrodanzante@gmail.com 2006-11-28 23:37 ------- +2.6.17.2 (not working) -sudo dmesg -s 1000000 - Linux version 2.6.17.1-k-mb (marco@king-test) (gcc version +dmesg -s 1000000 + + Linux version 2.6.17.2mb (marco@king-test) (gcc version 4.1.2 20060928 (prerelease) (Ubuntu 4.1.1-13ubuntu5)) #1 SMP Tue Nov 28 -22:28:02 CET 2006 +21:18:11 CET 2006 BIOS-provided physical RAM map: BIOS-e820: 0000000000000000 - 000000000009f400 (usable) BIOS-e820: 000000000009f400 - 00000000000a0000 (reserved) @@ -100,19 +101,19 @@ Enabling unmasked SIMD FPU exception support... done. Initializing CPU#0 PID hash table entries: 4096 (order: 12, 16384 bytes) - Detected 2205.252 MHz processor. + Detected 2205.232 MHz processor. Using pmtmr for high-res timesource Console: colour VGA+ 80x25 Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) - Memory: 1029948k/1048512k available (1869k kernel code, -17860k reserved, 1050k data, 308k init, 131008k highmem) + Memory: 1029948k/1048512k available (1873k kernel code, +17852k reserved, 1050k data, 308k init, 131008k highmem) Checking if this processor honours the WP bit even in supervisor mode... Ok. - Calibrating delay using timer specific routine.. 4414.99 -BogoMIPS (lpj=8829999) + Calibrating delay using timer specific routine.. 4415.04 +BogoMIPS (lpj=8830096) Security Framework v1.0.0 initialized SELinux: Disabled at boot. Mount-cache hash table entries: 512 @@ -129,13 +130,13 @@ SMP alternatives: switching to UP code Freeing SMP alternatives: 16k freed CPU0: AMD Athlon(tm) XP 3200+ stepping 00 - Total of 1 processors activated (4414.99 BogoMIPS). + Total of 1 processors activated (4415.04 BogoMIPS). ENABLING IO-APIC IRQs ..TIMER: vector=0x31 apic1=0 pin1=0 apic2=-1 pin2=-1 Brought up 1 CPUs migration_cost=0 checking if image is initramfs... it is - Freeing initrd memory: 5144k freed + Freeing initrd memory: 5136k freed NET: Registered protocol family 16 EISA bus registered ACPI: bus type pci registered @@ -242,7 +243,7 @@ TCP: Hash tables configured (established 131072 bind 65536) TCP reno registered audit: initializing netlink socket (disabled) - audit(1164788702.408:1): initialized + audit(1164789362.916:1): initialized highmem bounce pool size: 64 pages VFS: Disk quotas dquot_6.5.1 Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) @@ -434,23 +435,33 @@ max is 0 usb 3-2.4: config 1 has no interface number 0 usb 3-2.4: configuration #1 chosen from 1 choice - Linux agpgart interface v0.101 (c) Dave Jones - agpgart: Detected NVIDIA nForce2 chipset - agpgart: AGP aperture is 128M @ 0xd0000000 - pci_hotplug: PCI Hot Plug PCI Core version: 0.5 - shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 Floppy drive(s): fd0 is 1.44M FDC 0 is a post-1991 82077 - parport: PnPBIOS parport detected. - parport0: PC-style at 0x378 (0x778), irq 7, dma 3 -[PCSPP,TRISTATE,COMPAT,ECP,DMA] + input: PC Speaker as /class/input/input0 forcedeth.c: Reverse Engineered nForce ethernet driver. Version 0.54. ACPI: PCI Interrupt Link [APCH] enabled at IRQ 22 ACPI: PCI Interrupt 0000:00:04.0[A] -> Link [APCH] -> GSI 22 (level, high) -> IRQ 185 PCI: Setting latency timer of device 0000:00:04.0 to 64 - input: PC Speaker as /class/input/input0 + eth0: forcedeth.c: subsystem: 01043:80a7 bound to +0000:00:04.0 + pci_hotplug: PCI Hot Plug PCI Core version: 0.5 + shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 + Linux agpgart interface v0.101 (c) Dave Jones + parport: PnPBIOS parport detected. + parport0: PC-style at 0x378 (0x778), irq 7, dma 3 +[PCSPP,TRISTATE,COMPAT,ECP,DMA] + i2c_adapter i2c-0: nForce2 SMBus adapter at 0x5000 + i2c_adapter i2c-1: nForce2 SMBus adapter at 0x5500 + agpgart: Detected NVIDIA nForce2 chipset + agpgart: AGP aperture is 128M @ 0xd0000000 + ACPI: PCI Interrupt Link [APCJ] enabled at IRQ 21 + ACPI: PCI Interrupt 0000:00:06.0[A] -> Link [APCJ] -> GSI 21 +(level, high) -> IRQ 193 + PCI: Setting latency timer of device 0000:00:06.0 to 64 + sd 0:0:0:0: Attached scsi generic sg0 type 0 + sd 1:0:0:0: Attached scsi generic sg1 type 0 usbcore: registered new driver hiddev input: HID Keyboard Device as /class/input/input1 input: USB HID v1.10 Keyboard [HID Keyboard Device] on @@ -458,34 +469,25 @@ input: HID Keyboard Device as /class/input/input2 input: USB HID v1.10 Device [HID Keyboard Device] on usb-0000:00:02.2-2.2 + drivers/usb/input/hid-core.c: input irq status -32 received input: PS/2+USB Mouse as /class/input/input3 input: USB HID v1.00 Mouse [PS/2+USB Mouse] on usb-0000:00:02.2-2.4 usbcore: registered new driver usbhid drivers/usb/input/hid-core.c: v2.6:USB HID core driver - sd 0:0:0:0: Attached scsi generic sg0 type 0 - sd 1:0:0:0: Attached scsi generic sg1 type 0 - eth0: forcedeth.c: subsystem: 01043:80a7 bound to -0000:00:04.0 - ACPI: PCI Interrupt Link [APCJ] enabled at IRQ 21 - ACPI: PCI Interrupt 0000:00:06.0[A] -> Link [APCJ] -> GSI 21 -(level, high) -> IRQ 193 - PCI: Setting latency timer of device 0000:00:06.0 to 64 - ts: Compaq touchscreen protocol output - intel8x0_measure_ac97_clock: measured 56795 usecs + eth0: no link during initialization. + intel8x0_measure_ac97_clock: measured 56798 usecs intel8x0: clocking to 48000 ACPI: PCI Interrupt Link [APC2] enabled at IRQ 17 ACPI: PCI Interrupt 0000:01:09.0[A] -> Link [APC2] -> GSI 17 (level, high) -> IRQ 209 - i2c_adapter i2c-0: nForce2 SMBus adapter at 0x5000 - i2c_adapter i2c-1: nForce2 SMBus adapter at 0x5500 - eth0: no link during initialization. + ts: Compaq touchscreen protocol output + eth0: link up. lp0: using parport0 (interrupt-driven). Adding 2096440k swap on /dev/disk/by-uuid/a67b572b-a166-4dc7-9b19-17ae0dd320e2. Priority:-1 extents:1 across:2096440k EXT3 FS on hda7, internal journal - eth0: link up. NET: Registered protocol family 17 NET: Registered protocol family 10 lo: Disabled Privacy Extensions On Wed, 29 Nov 2006 00:35:57 -0800 bugme-daemon@bugzilla.kernel.org wrote: > http://bugzilla.kernel.org/show_bug.cgi?id=7590 > > htejun@gmail.com changed: > > What |Removed |Added > ---------------------------------------------------------------------------- > Owner|jgarzik@pobox.com |htejun@gmail.com > Status|NEW |ASSIGNED > > > > ------- Additional Comments From htejun@gmail.com 2006-11-29 00:33 ------- > Between, 2.6.17.1 and 2.6.17.2, ATA_DFLAG_PIO has changed its position. Please > make sure you haven't mixed modules for the two versions. If that's not the > case, please change #undef ATA_DEBUG in include/linux/libata.h to #define > ATA_DEBUG, recompile the kernel, and report the boot dmesg. > > ------- You are receiving this mail because: ------- > You are on the CC list for the bug, or are watching someone who is. What Tejun said. Here's the only sata diff between 2.6.17.1 and 2.6.17.2. You could see if reverting this fixes it. Problem: - With 2.6.17 libata, some PIO-only devices are given DMA commands. Changes: - Do not clear the ATA_DFLAG_PIO flag in ata_dev_configure(). Signed-off-by: Tejun Heo <htejun@gmail.com>. Signed-off-by: Albert Lee <albertcc@tw.ibm.com> --- The patch is originally done by Tejun and already in the libata-dev upstream. Patch reviewed and acked by Jeff. diff -Nrup linux-2.6.17-0/drivers/scsi/libata-core.c linux-2.6.17-1/drivers/scsi/libata-core.c --- linux-2.6.17-0/drivers/scsi/libata-core.c 2006-06-18 09:49:35.000000000 +0800 +++ linux-2.6.17-1/drivers/scsi/libata-core.c 2006-06-20 16:56:14.000000000 +0800 @@ -1229,7 +1229,7 @@ static int ata_dev_configure(struct ata_ id[84], id[85], id[86], id[87], id[88]); /* initialize to-be-configured parameters */ - dev->flags = 0; + dev->flags &= ~ATA_DFLAG_CFG_MASK; dev->max_sectors = 0; dev->cdb_len = 0; dev->n_sectors = 0; diff -Nrup linux-2.6.17-0/include/linux/libata.h linux-2.6.17-1/include/linux/libata.h --- linux-2.6.17-0/include/linux/libata.h 2006-06-18 09:49:35.000000000 +0800 +++ linux-2.6.17-1/include/linux/libata.h 2006-06-20 16:55:41.000000000 +0800 @@ -120,9 +120,12 @@ enum { ATA_SHT_USE_CLUSTERING = 1, /* struct ata_device stuff */ - ATA_DFLAG_LBA48 = (1 << 0), /* device supports LBA48 */ - ATA_DFLAG_PIO = (1 << 1), /* device currently in PIO mode */ - ATA_DFLAG_LBA = (1 << 2), /* device supports LBA */ + ATA_DFLAG_LBA = (1 << 0), /* device supports LBA */ + ATA_DFLAG_LBA48 = (1 << 1), /* device supports LBA48 */ + + ATA_DFLAG_CFG_MASK = (1 << 8) - 1, + + ATA_DFLAG_PIO = (1 << 8), /* device currently in PIO mode */ ATA_DEV_UNKNOWN = 0, /* unknown device */ ATA_DEV_ATA = 1, /* ATA device */ _______________________________________________ stable mailing list stable@linux.kernel.org http://linux.kernel.org/mailman/listinfo/stable Yes, reverting 2.6.17.2 libata.h and libata-core.c fixes it. I have just tried it again. (I already thought it). Isn't possible to correct the 2.6.17.2 patch without downgrading it? Okay, I get it. It's ironic. Your second drive is configured for PIO4. Before the fix, libata issued DMA commands to devices which are configured for PIO mode, so DMA commands were issued to your drive anyway thus showing normal UDMA performance. 2.6.17.2 fixes the bug and libata issues PIO commands to devices which are configured for PIO mode resulting in massive slow down. So, the fix is correct but it hurts you nevertheless. Let's track down why libata is configuring your drive to PIO4. Messages with ATA_DEBUG contains those info but your last report was truncated. Please increase the size of kernel log buffersize under kernel hacking and report the result of dmesg -s 1000000. dmesg -s 2147483639 >> dmesg-dbg More of this dmesg can't do. But always truncated. I'm going to unplug some ata device, maybe it can help us... Created attachment 9684 [details]
2.6.17.2 ATA_DEBUG dmesg -s 2147483639
I unplugged an HDD p-ata and a CD-ROM, but dmesg is always truncated. I don't know if it can help you: when I use an kernel >= 2.6.17.2 the system freeze on shutdown, often but not always. Using 2.6.17.1 or less never do. Yeah, ATA_DEBUG turns on a LOT of messages. I'll prepare some selective debug message printing patch tomorrow. Gotta sleep now. Created attachment 9687 [details]
not truncated dmesg 2.6.17.2 ATA_DEBUG
/var/log/dmesg.1.gz
I think this last one should gone. Goodnight :-) (Here it's 16.36) I tried in 2.6.17.13 to do the same thing that i do in 17.2: include/linux/libata.h - ATA_DFLAG_LBA = (1 << 0), /* device supports LBA */ - ATA_DFLAG_LBA48 = (1 << 1), /* device supports LBA48 */ - ATA_DFLAG_HPA = (1 << 2), /* device has an HPA */ - ATA_DFLAG_CFG_MASK = (1 << 8) - 1, - ATA_DFLAG_PIO = (1 << 8), /* device currently in PIO mode */ + ATA_DFLAG_LBA48 = (1 << 0), /* device supports LBA48 */ + ATA_DFLAG_PIO = (1 << 1), /* device currently in PIO mode */ + ATA_DFLAG_LBA = (1 << 2), /* device supports LBA */ drivers/scsi/libata-core.c - dev->flags &= ~ATA_DFLAG_CFG_MASK; + dev->flags = 0; and it does not works. Created attachment 9700 [details]
debug patch
Please apply this patch and report what the kernel says. ATA_DEBUG isn't
necessary. Thanks.
Created attachment 9704 [details]
Dmesg debug patch
This should be the interesting...
[17179576.564000] ata1: SATA max UDMA/133 cmd 0x9000 ctl 0x9402 bmdma 0xA000
irq 177
[17179576.564000] ata2: SATA max UDMA/133 cmd 0x9800 ctl 0x9C02 bmdma 0xA008
irq 177
[17179576.768000] ata1: SATA link up 1.5 Gbps (SStatus 113)
[17179576.776000] ata1: dev 0 cfg 49:2f00 82:7c6b 83:7f09 84:4673 85:7c69
86:3c01 87:4663 88:007f
[17179576.776000] ata1: dev 0 ATA-7, max UDMA/133, 398297088 sectors: LBA48
[17179576.776000] XXX xfer_mask0=0x7f1f
[17179576.776000] XXX xfer_mask1:0=0x7f1f
[17179576.776000] XXX xfer_mask2:0=0x7f1f
[17179576.776000] XXX xfer_mask3:0=0x7f1f
[17179576.776000] XXX xfer_mask4:0=0x7f1f
[17179576.776000] XXX xfer_mask5=0x7f1f
[17179576.776000] XXX xfer_mask6=0x7f1f
[17179576.784000] ata1: dev 0 configured for UDMA/133
[17179576.784000] scsi0 : sata_uli
[17179576.988000] ata2: SATA link up 1.5 Gbps (SStatus 113)
[17179576.996000] ata2: dev 0 cfg 49:2f00 82:7c6b 83:7f69 84:4773 85:7c69
86:3c01 87:4763 88:407f
[17179576.996000] ata2: dev 0 ATA-7, max UDMA/133, 490234752 sectors: LBA48
[17179576.996000] XXX xfer_mask0=0x7f1f
[17179576.996000] XXX xfer_mask1:0=0x7f1f
[17179576.996000] XXX xfer_mask2:0=0x7f1f
[17179576.996000] XXX xfer_mask3:0=0x7f1f
[17179576.996000] XXX xfer_mask4:0=0x7f1f
[17179576.996000] XXX xfer_mask5=0x1f
[17179576.996000] XXX xfer_mask6=0x1f
[17179577.004000] ata2: dev 0 configured for PIO4
Your controller is reporting simplex DMA. That is, two ports on the controller share DMA channel and thus only one device can use DMA at a time. On 2.6.17.1 where both devices are functioning at high spped, can you run some data integrity test while performing heavy IO to both disks? I'm a bit surprised that a modern SATA controller is reporting simplex. I bought two of this cheap controller about 2 years ago, and they run on two box, this one that I'm using now and another one, an old PII that run Windows XP. I never I had loose data, except when an HDD was fail but it was an head crash. I realized that running linux it see my two sata hdd as udma6 and not as sata, but I was not worry about it. Running Windows that is not happen, and the two sata hdd is faster than my p-ata udma 133 hdd. The chip on the controller is the same that you can find on a lot on motherboard. It's all that i can tell you. If you can advice my an data integrity test I'll be happy to run it and I report you the output. Okay, uli seems to have bogus SIMPLEX bit. This is being fixed in upstream. http://thread.gmane.org/gmane.linux.kernel/475453/focus=475453 Created attachment 9891 [details]
sata_uli-clear-simplex
Please test the attached patch from Alan Cox.
I just tried to patch drivers/scsi/sata_uli.c on 2.6.17.14 and it doesn't work.
^^^^
This " ppi[0] = ppi[1] = &uli_port_info; " doesn't work (?) so I leaved "ppi =
&uli_port_info;"
Of course, I added "ata_pci_clear_simplex(pdev);".
Did I done something bad?
Ergh.. it's against 2.6.19. You just need to add ata_pci_clear_simplex(pdev) somewhere between pci_enable_device() and ata_device_add(). This is what I did, and does't work. Always PIO4...
rc = pci_set_consistent_dma_mask(pdev, ATA_DMA_MASK);
if (rc)
goto err_out_regions;
/*ADDED*/
ata_pci_clear_simplex(pdev);
/*END*/
ppi = &uli_port_info;
probe_ent = ata_pci_init_native_mode(pdev, &ppi, ATA_PORT_PRIMARY | ATA_
PORT_SECONDARY);
if (!probe_ent) {
Can you try the following?
rc = ata_pci_clear_simplex(pdev);
printk("XXX: clear_simplex=%d\n", rc);
Created attachment 9901 [details]
dmesg-2.6.19.1-patch-dbg vanish sata
so, I have trouble...
I got the 2.6.19.1 tree and I added on drivers/ata/sata_uli.c this code:
rc = pci_set_consistent_dma_mask(pdev, ATA_DMA_MASK);
if (rc)
goto err_out_regions;
/* ADD */
ata_pci_clear_simplex(pdev);
rc = ata_pci_clear_simplex(pdev);
printk("XXX: clear_simplex=%d\n", rc);
/*END*/
ppi[0] = ppi[1] = &uli_port_info;
probe_ent = ata_pci_init_native_mode(pdev, ppi, ATA_PORT_PRIMARY |
ATA_P
ORT_SECONDARY);
and simply my SATA HDD was vanish.
I can see the controller with lcpci:
01:07.0 Mass storage controller: ALi Corporation ALi M5281 Serial ATA / RAID
Host Controller
(rev a1)
01:07.1 Mass storage controller: ALi Corporation M5228 ALi ATA/RAID Controller
(rev c6)
I think that the trouble is in the double ata_pci_clear_simplex(pdev), isn't
it?
No, I don't think so. Can you post full dmesg? Created attachment 9908 [details]
dmesg without "ata_pci_clear_simplex(pdev)"
With all 3 lines you can read dmesg in the last attachment. No debug print.
I have a dmesg from a kernel compiled without "ata_pci_clear_simplex(pdev)" but
with only:
rc = ata_pci_clear_simplex(pdev);
printk("XXX: clear_simplex=%d\n", rc);
and you can read it in this attachment.
Created attachment 9998 [details]
sata_uli ignore simplex patch
Please test this patch. The patch will apply to 2.6.19[.1] and all 20.rcX
kernels. Happy new year.
Ah... I forgot to mention. Please post boot dmesg too. Created attachment 10002 [details]
dmesg with patch
Happy new year.
Here is (in the attachment) the dmesg.
There's something that i don't understand.
In the dmseg output I can see:
ALI15X3: 100% native mode on irq 19
ide0: BM-DMA at 0xb400-0xb407, BIOS settings: hda:DMA, hdb:DMA
ide1: BM-DMA at 0xb408-0xb40f, BIOS settings: hdc:pio, hdd:pio
Probing IDE interface ide0...
but I can't find this hda and hdb device. Also if I try to do "mknod /dev/hda b
3 0" it doesn't work. (Normally I use udev)
(In this kernel sata hard disk is hdx and no more sdx, it isn't?)
You need to load sata_uli and the disks will appear as /dev/sdX. Created attachment 10003 [details]
The last dmesg
ehm, yes, I had just forget to compile it (the next time I won't compile kernel
at 3 am).
You can see dmesg in attachment. SATA information are in the end because I
loaded modules just after that I compiled them.
Now it likes to work.
The kernel says that it goes at 133 MB/s and hdparm returns
Timing buffered disk reads: 204 MB in 3.01 seconds = 67.71 MB/sec
Now I'm going to test some large read/write action.
Is 133 MB/s for PCI limitation or is there samething wrong in the UDMA mode?
The P-ATA controller on the PCI have a strange way to work now... if I plug a
disk in the only IDE port, the kernel see it as hdc or hdd if it is slave. The
hda and hdb simply don't exist and can't exist.
Thank you very much for the hard and good work.
I'm forwarding the patch upstream and closing the bug. Thanks. |