* [sata_sil] kernel 2.6.17(-mm2) test - timeout issue
@ 2006-06-24 20:50 Martin Ammermüller
2006-06-25 3:06 ` Tejun Heo
0 siblings, 1 reply; 7+ messages in thread
From: Martin Ammermüller @ 2006-06-24 20:50 UTC (permalink / raw)
To: linux-ide; +Cc: jgarzik, Tejun Heo
[-- Attachment #1.1: Type: text/plain, Size: 623 bytes --]
Hello list!
I stress-tested the SATA-hdd of my notebook[0] again with current kernel
versions.
With the 2.6.17 kernel i got a freeze after a timeout message (printed
on the screen repeatedly). Running a 2.6.17-mm2 kernel, libata/sata_sil
recovered from the timeout, resetting after a 30 second limit.
See the attached error-messages and dmesg outputs for details, please.
Regards,
Martin Ammermüller
P.S.: Does the reset/error handling procedure which happened when
testing the 2.6.17-mm2 kernel include loss of data or is this a "clean"
reset?
[0]http://www.spinics.net/lists/linux-ide/msg01480.html
[-- Attachment #1.2: dmesg_2.6.17 --]
[-- Type: text/plain, Size: 23350 bytes --]
[4294667.296000] Linux version 2.6.17 (root@tenco) (gcc-Version 4.0.3 (Ubuntu 4.0.3-1ubuntu5)) #1 PREEMPT Sun Jun 18 22:24:27 CEST 2006
[4294667.296000] BIOS-provided physical RAM map:
[4294667.296000] BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
[4294667.296000] BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
[4294667.296000] BIOS-e820: 00000000000dc000 - 0000000000100000 (reserved)
[4294667.296000] BIOS-e820: 0000000000100000 - 0000000017ea0000 (usable)
[4294667.296000] BIOS-e820: 0000000017ea0000 - 0000000017eb0000 (ACPI data)
[4294667.296000] BIOS-e820: 0000000017eb0000 - 0000000017f00000 (ACPI NVS)
[4294667.296000] BIOS-e820: 0000000017f00000 - 0000000018000000 (reserved)
[4294667.296000] BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
[4294667.296000] BIOS-e820: 00000000fec00000 - 00000000fec10000 (reserved)
[4294667.296000] BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[4294667.296000] BIOS-e820: 00000000fff80000 - 0000000100000000 (reserved)
[4294667.296000] 0MB HIGHMEM available.
[4294667.296000] 382MB LOWMEM available.
[4294667.296000] found SMP MP-table at 000f7710
[4294667.296000] On node 0 totalpages: 97952
[4294667.296000] DMA zone: 4096 pages, LIFO batch:0
[4294667.296000] Normal zone: 93856 pages, LIFO batch:31
[4294667.296000] DMI present.
[4294667.296000] ACPI: RSDP (v000 TOSINV ) @ 0x000f76c0
[4294667.296000] ACPI: RSDT (v001 TOSINV RSDT 0x06040000 LTP 0x00000000) @ 0x17eabf9b
[4294667.296000] ACPI: FADT (v001 TOSINV Goldfish 0x06040000 ATI 0x000f4240) @ 0x17eafef6
[4294667.296000] ACPI: MADT (v001 TOSINV APIC 0x06040000 LTP 0x00000000) @ 0x17eaff6a
[4294667.296000] ACPI: MCFG (v001 TOSINV MCFG 0x06040000 LTP 0x00000000) @ 0x17eaffc4
[4294667.296000] ACPI: SSDT (v001 TOSINV Cpu0Cst 0x00003001 INTL 0x20030224) @ 0x17eac1ce
[4294667.296000] ACPI: SSDT (v001 TOSINV CpuPm 0x00003000 INTL 0x20030224) @ 0x17eabfd3
[4294667.296000] ACPI: DSDT (v001 TOSINV SB450 0x06040000 MSFT 0x0100000e) @ 0x00000000
[4294667.296000] ATI board detected. Disabling timer routing over 8254.
[4294667.296000] ACPI: PM-Timer IO Port: 0x8008
[4294667.296000] ACPI: Local APIC address 0xfee00000
[4294667.296000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[4294667.296000] Processor #0 6:13 APIC version 20
[4294667.296000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[4294667.296000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
[4294667.296000] IOAPIC[0]: apic_id 1, version 33, address 0xfec00000, GSI 0-23
[4294667.296000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 high edge)
[4294667.296000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 21 low level)
[4294667.296000] ACPI: IRQ0 used by override.
[4294667.296000] ACPI: IRQ2 used by override.
[4294667.296000] Enabling APIC mode: Flat. Using 1 I/O APICs
[4294667.296000] Using ACPI (MADT) for SMP configuration information
[4294667.296000] Allocating PCI resources starting at 20000000 (gap: 18000000:c8000000)
[4294667.296000] Built 1 zonelists
[4294667.296000] Kernel command line: root=/dev/sda1 ro
[4294667.296000] mapped APIC to ffffd000 (fee00000)
[4294667.296000] mapped IOAPIC to ffffc000 (fec00000)
[4294667.296000] Enabling fast FPU save and restore... done.
[4294667.296000] Enabling unmasked SIMD FPU exception support... done.
[4294667.296000] Initializing CPU#0
[4294667.296000] PID hash table entries: 2048 (order: 11, 8192 bytes)
[4294667.296000] Detected 1393.540 MHz processor.
[4294667.296000] Using pmtmr for high-res timesource
[4294667.296000] Console: colour VGA+ 80x25
[4294669.805000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[4294669.805000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[4294669.818000] Memory: 378396k/391808k available (1893k kernel code, 12740k reserved, 589k data, 288k init, 0k highmem)
[4294669.818000] Checking if this processor honours the WP bit even in supervisor mode... Ok.
[4294669.878000] Calibrating delay using timer specific routine.. 2790.09 BogoMIPS (lpj=1395045)
[4294669.878000] Security Framework v1.0.0 initialized
[4294669.878000] SELinux: Disabled at boot.
[4294669.878000] Mount-cache hash table entries: 512
[4294669.878000] CPU: After generic identify, caps: afe9fbff 00100000 00000000 00000000 00000000 00000000 00000000
[4294669.878000] CPU: After vendor identify, caps: afe9fbff 00100000 00000000 00000000 00000000 00000000 00000000
[4294669.878000] CPU: L1 I cache: 32K, L1 D cache: 32K
[4294669.878000] CPU: L2 cache: 1024K
[4294669.878000] CPU: After all inits, caps: afe9fbff 00100000 00000000 00000040 00000000 00000000 00000000
[4294669.878000] CPU: Intel(R) Celeron(R) M processor 1.40GHz stepping 08
[4294669.878000] Checking 'hlt' instruction... OK.
[4294669.882000] SMP alternatives: switching to UP code
[4294669.882000] Freeing SMP alternatives: 0k freed
[4294669.900000] ENABLING IO-APIC IRQs
[4294669.900000] ..TIMER: vector=0x31 apic1=0 pin1=2 apic2=-1 pin2=-1
[4294669.900000] ..MP-BIOS bug: 8254 timer not connected to IO-APIC
[4294669.900000] ...trying to set up timer (IRQ0) through the 8259A ... failed.
[4294669.900000] ...trying to set up timer as Virtual Wire IRQ... works.
[4294670.022000] checking if image is initramfs... it is
[4294670.745000] Freeing initrd memory: 6087k freed
[4294670.745000] NET: Registered protocol family 16
[4294670.746000] EISA bus registered
[4294670.746000] ACPI: bus type pci registered
[4294670.746000] PCI: Using MMCONFIG
[4294670.747000] Setting up standard PCI resources
[4294670.750000] ACPI: Subsystem revision 20060127
[4294670.763000] ACPI: Interpreter enabled
[4294670.763000] ACPI: Using IOAPIC for interrupt routing
[4294670.764000] ACPI: PCI Root Bridge [PCI0] (0000:00)
[4294670.764000] PCI: Probing PCI hardware (bus 00)
[4294670.766000] PCI: Ignoring BAR0-3 of IDE controller 0000:00:14.1
[4294670.766000] Boot video device is 0000:01:05.0
[4294670.767000] PCI: Transparent bridge - 0000:00:14.4
[4294670.767000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[4294670.770000] ACPI: PCI Interrupt Link [LNKA] (IRQs 10 11) *0, disabled.
[4294670.771000] ACPI: PCI Interrupt Link [LNKB] (IRQs 10 11) *0, disabled.
[4294670.771000] ACPI: PCI Interrupt Link [LNKC] (IRQs 10 11) *0, disabled.
[4294670.772000] ACPI: PCI Interrupt Link [LNKD] (IRQs 10 11) *0, disabled.
[4294670.773000] ACPI: PCI Interrupt Link [LNKE] (IRQs 10 11) *0, disabled.
[4294670.773000] ACPI: PCI Interrupt Link [LNKF] (IRQs 10 11) *0, disabled.
[4294670.774000] ACPI: PCI Interrupt Link [LNKG] (IRQs 10 11) *0, disabled.
[4294670.774000] ACPI: PCI Interrupt Link [LNKH] (IRQs 10 11) *0, disabled.
[4294670.775000] ACPI: Embedded Controller [EC0] (gpe 7) interrupt mode.
[4294670.775000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P2P_._PRT]
[4294670.776000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.AGP_._PRT]
[4294670.776000] ACPI: Power Resource [PFA1] (off)
[4294670.776000] Linux Plug and Play Support v0.97 (c) Adam Belay
[4294670.776000] pnp: PnP ACPI init
[4294670.790000] pnp: PnP ACPI: found 10 devices
[4294670.790000] PnPBIOS: Disabled by ACPI PNP
[4294670.790000] PCI: Using ACPI for IRQ routing
[4294670.790000] PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
[4294670.790000] pnp: 00:08: ioport range 0x1080-0x1080 has been reserved
[4294670.790000] pnp: 00:08: ioport range 0x220-0x22f has been reserved
[4294670.790000] pnp: 00:08: ioport range 0x400-0x401 has been reserved
[4294670.790000] pnp: 00:08: ioport range 0x40b-0x40b has been reserved
[4294670.791000] PCI: Bridge: 0000:00:01.0
[4294670.791000] IO window: 9000-9fff
[4294670.791000] MEM window: c0100000-c01fffff
[4294670.791000] PREFETCH window: d0000000-dfffffff
[4294670.791000] PCI: Bus 3, cardbus bridge: 0000:02:06.0
[4294670.791000] IO window: 0000a400-0000a4ff
[4294670.791000] IO window: 0000a800-0000a8ff
[4294670.791000] PREFETCH window: 20000000-21ffffff
[4294670.791000] MEM window: 24000000-25ffffff
[4294670.791000] PCI: Bridge: 0000:00:14.4
[4294670.791000] IO window: a000-afff
[4294670.791000] MEM window: c0200000-c02fffff
[4294670.791000] PREFETCH window: 20000000-21ffffff
[4294670.791000] PCI: Enabling device 0000:02:06.0 (0004 -> 0007)
[4294670.791000] ACPI: PCI Interrupt 0000:02:06.0[A] -> GSI 23 (level, low) -> IRQ 177
[4294670.791000] NET: Registered protocol family 2
[4294670.801000] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[4294670.801000] TCP established hash table entries: 16384 (order: 4, 65536 bytes)
[4294670.801000] TCP bind hash table entries: 8192 (order: 3, 32768 bytes)
[4294670.801000] TCP: Hash tables configured (established 16384 bind 8192)
[4294670.801000] TCP reno registered
[4294670.802000] audit: initializing netlink socket (disabled)
[4294670.802000] audit(1150667085.801:1): initialized
[4294670.802000] VFS: Disk quotas dquot_6.5.1
[4294670.802000] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[4294670.802000] Initializing Cryptographic API
[4294670.802000] io scheduler noop registered
[4294670.802000] io scheduler anticipatory registered (default)
[4294670.802000] io scheduler deadline registered
[4294670.802000] io scheduler cfq registered
[4294671.474000] isapnp: Scanning for PnP cards...
[4294671.832000] isapnp: No Plug & Play device found
[4294671.855000] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
[4294671.856000] RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize
[4294671.857000] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[4294671.857000] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[4294671.857000] PNP: PS/2 Controller [PNP0303:KBC0,PNP0f13:MSE0] at 0x60,0x64 irq 1,12
[4294671.859000] serio: i8042 AUX port at 0x60,0x64 irq 12
[4294671.859000] serio: i8042 KBD port at 0x60,0x64 irq 1
[4294671.859000] mice: PS/2 mouse device common for all mice
[4294671.860000] EISA: Probing bus 0 at eisa.0
[4294671.860000] Cannot allocate resource for EISA slot 1
[4294671.860000] Cannot allocate resource for EISA slot 8
[4294671.860000] EISA: Detected 0 cards.
[4294671.860000] TCP bic registered
[4294671.860000] NET: Registered protocol family 1
[4294671.860000] NET: Registered protocol family 8
[4294671.860000] NET: Registered protocol family 20
[4294671.860000] Using IPI Shortcut mode
[4294671.860000] ACPI wakeup devices:
[4294671.860000] LID OHC1 OHC2 EHCI P2P LANC AUDO MODM
[4294671.861000] ACPI: (supports S0 S3 S4 S5)
[4294671.862000] Freeing unused kernel memory: 288k freed
[4294671.897000] input: AT Translated Set 2 keyboard as /class/input/input0
[4294671.934000] ACPI: Fan [FAN1] (off)
[4294671.937000] ACPI: CPU0 (power states: C1[C1] C2[C2] C3[C3])
[4294671.937000] ACPI: Processor [CPU0] (supports 8 throttling states)
[4294672.044000] ACPI: Thermal Zone [TZCR] (51 C)
[4294672.364000] SCSI subsystem initialized
[4294672.367000] libata version 1.20 loaded.
[4294672.368000] sata_sil 0000:00:12.0: version 0.9
[4294672.368000] PCI: Enabling device 0000:00:12.0 (0005 -> 0007)
[4294672.368000] ACPI: PCI Interrupt 0000:00:12.0[A] -> GSI 22 (level, low) -> IRQ 185
[4294672.369000] ata1: SATA max UDMA/100 cmd 0xD882A080 ctl 0xD882A08A bmdma 0xD882A000 irq 185
[4294672.369000] ata2: SATA max UDMA/100 cmd 0xD882A0C0 ctl 0xD882A0CA bmdma 0xD882A008 irq 185
[4294672.721000] ata1: SATA link up 1.5 Gbps (SStatus 113)
[4294672.725000] ata1: dev 0 cfg 49:2f00 82:746b 83:7d09 84:6023 85:7469 86:3d09 87:6023 88:203f
[4294672.725000] ata1: dev 0 ATA-6, max UDMA/100, 156301488 sectors: LBA48
[4294672.729000] ata1: dev 0 configured for UDMA/100
[4294672.730000] scsi0 : sata_sil
[4294672.931000] ata2: SATA link down (SStatus 0)
[4294672.943000] scsi1 : sata_sil
[4294672.943000] Vendor: ATA Model: TOSHIBA MK8032GS Rev: AS11
[4294672.945000] Type: Direct-Access ANSI SCSI revision: 05
[4294672.952000] SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
[4294672.952000] sda: Write Protect is off
[4294672.952000] sda: Mode Sense: 00 3a 00 00
[4294672.952000] SCSI device sda: drive cache: write back
[4294672.953000] SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
[4294672.953000] sda: Write Protect is off
[4294672.953000] sda: Mode Sense: 00 3a 00 00
[4294672.953000] SCSI device sda: drive cache: write back
[4294672.953000] sda: sda1 sda2 sda3
[4294673.007000] sd 0:0:0:0: Attached scsi disk sda
[4294673.305000] ATIIXP: IDE controller at PCI slot 0000:00:14.1
[4294673.306000] ACPI: PCI Interrupt 0000:00:14.1[A] -> GSI 16 (level, low) -> IRQ 193
[4294673.306000] ATIIXP: chipset revision 128
[4294673.306000] ATIIXP: not 100% native mode: will probe irqs later
[4294673.306000] ide0: BM-DMA at 0x8460-0x8467, BIOS settings: hda:pio, hdb:pio
[4294673.306000] ide1: BM-DMA at 0x8468-0x846f, BIOS settings: hdc:DMA, hdd:pio
[4294673.306000] Probing IDE interface ide0...
[4294673.825000] Probing IDE interface ide1...
[4294674.498000] hdc: HL-DT-ST DVDRAM GMA-4082N, ATAPI CD/DVD-ROM drive
[4294674.804000] ide1 at 0x170-0x177,0x376 on irq 15
[4294674.814000] hdc: ATAPI 24X DVD-ROM DVD-R-RAM CD-R/RW drive, 2048kB Cache, UDMA(33)
[4294674.814000] Uniform CD-ROM driver Revision: 3.20
[4294674.933000] ieee1394: Initialized config rom entry `ip1394'
[4294674.935000] ACPI: PCI Interrupt 0000:02:0a.0[A] -> GSI 22 (level, low) -> IRQ 185
[4294674.974000] usbcore: registered new driver usbfs
[4294674.974000] usbcore: registered new driver hub
[4294674.975000] ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
[4294674.975000] ACPI: PCI Interrupt 0000:00:13.0[A] -> GSI 19 (level, low) -> IRQ 201
[4294674.975000] ohci_hcd 0000:00:13.0: OHCI Host Controller
[4294674.976000] ohci_hcd 0000:00:13.0: new USB bus registered, assigned bus number 1
[4294674.976000] ohci_hcd 0000:00:13.0: irq 201, io mem 0xc0005000
[4294675.027000] usb usb1: configuration #1 chosen from 1 choice
[4294675.027000] hub 1-0:1.0: USB hub found
[4294675.027000] hub 1-0:1.0: 4 ports detected
[4294675.031000] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[185] MMIO=[c0215800-c0215fff] Max Packet=[2048] IR/IT contexts=[4/8]
[4294675.128000] ACPI: PCI Interrupt 0000:00:13.1[A] -> GSI 19 (level, low) -> IRQ 201
[4294675.129000] ohci_hcd 0000:00:13.1: OHCI Host Controller
[4294675.129000] ohci_hcd 0000:00:13.1: new USB bus registered, assigned bus number 2
[4294675.129000] ohci_hcd 0000:00:13.1: irq 201, io mem 0xc0006000
[4294675.180000] usb usb2: configuration #1 chosen from 1 choice
[4294675.180000] hub 2-0:1.0: USB hub found
[4294675.180000] hub 2-0:1.0: 4 ports detected
[4294675.307000] ACPI: PCI Interrupt 0000:00:13.2[A] -> GSI 19 (level, low) -> IRQ 201
[4294675.307000] ehci_hcd 0000:00:13.2: EHCI Host Controller
[4294675.307000] ehci_hcd 0000:00:13.2: new USB bus registered, assigned bus number 3
[4294675.307000] ehci_hcd 0000:00:13.2: irq 201, io mem 0xc0007000
[4294675.307000] ehci_hcd 0000:00:13.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[4294675.307000] usb usb3: configuration #1 chosen from 1 choice
[4294675.307000] hub 3-0:1.0: USB hub found
[4294675.307000] hub 3-0:1.0: 8 ports detected
[4294675.345000] usb 1-1: new low speed USB device using ohci_hcd and address 2
[4294675.427000] Probing IDE interface ide0...
[4294675.921000] ohci_hcd 0000:00:13.0: wakeup
[4294675.974000] Attempting manual resume
[4294675.981000] ohci_hcd 0000:00:13.1: wakeup
[4294675.982000] JFS: nTxBlock = 3008, nTxLock = 24066
[4294676.258000] usb 1-1: new low speed USB device using ohci_hcd and address 3
[4294676.288000] ieee1394: Host added: ID:BUS[0-00:1023] GUID[00080da0d1343923]
[4294676.401000] usb 1-1: configuration #1 chosen from 1 choice
[4294676.625000] usb 2-1: new full speed USB device using ohci_hcd and address 2
[4294676.788000] usb 2-1: configuration #1 chosen from 1 choice
[4294682.267000] sd 0:0:0:0: Attached scsi generic sg0 type 0
[4294683.204000] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[4294683.242000] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[4294683.435000] ACPI: PCI Interrupt 0000:02:06.0[A] -> GSI 23 (level, low) -> IRQ 177
[4294683.435000] Yenta: CardBus bridge found at 0000:02:06.0 [1179:ff10]
[4294683.435000] Yenta: Enabling burst memory read transactions
[4294683.435000] Yenta: Using CSCINT to route CSC interrupts to PCI
[4294683.435000] Yenta: Routing CardBus interrupts to PCI
[4294683.435000] Yenta TI: socket 0000:02:06.0, mfunc 0x01111122, devctl 0x44
[4294683.696000] Yenta: ISA IRQ mask 0x0ef8, PCI irq 177
[4294683.696000] Socket status: 30000006
[4294683.696000] pcmcia: parent PCI bridge I/O window: 0xa000 - 0xafff
[4294683.696000] cs: IO port probe 0xa000-0xafff: clean.
[4294683.696000] pcmcia: parent PCI bridge Memory window: 0xc0200000 - 0xc02fffff
[4294683.696000] pcmcia: parent PCI bridge Memory window: 0x20000000 - 0x21ffffff
[4294683.725000] 8139too Fast Ethernet driver 0.9.27
[4294683.725000] ACPI: PCI Interrupt 0000:02:07.0[A] -> GSI 20 (level, low) -> IRQ 209
[4294683.726000] eth0: RealTek RTL8139 at 0xd8ba2000, 00:a0:d1:34:39:23, IRQ 209
[4294683.726000] eth0: Identified 8139 chip type 'RTL-8100B/8139D'
[4294683.852000] Linux agpgart interface v0.101 (c) Dave Jones
[4294683.936000] 8139cp: 10/100 PCI Ethernet driver v1.2 (Mar 22, 2004)
[4294684.136000] Real Time Clock Driver v1.12ac
[4294684.311000] drivers/usb/class/usblp.c: Disabling reads from problem bidirectional printer on usblp0
[4294684.328000] drivers/usb/class/usblp.c: usblp0: USB Unidirectional printer dev 2 if 0 alt 1 proto 2 vid 0x03F0 pid 0x0604
[4294684.328000] usbcore: registered new driver usblp
[4294684.328000] drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
[4294684.334000] input: PC Speaker as /class/input/input1
[4294684.382000] usbcore: registered new driver hiddev
[4294684.388000] input: Microsoft Microsoft 3-Button Mouse with IntelliEye? as /class/input/input2
[4294684.388000] input: USB HID v1.00 Mouse [Microsoft Microsoft 3-Button Mouse with IntelliEye?] on usb-0000:00:13.0-1
[4294684.388000] usbcore: registered new driver usbhid
[4294684.388000] drivers/usb/input/hid-core.c: v2.6:USB HID core driver
[4294684.499000] ACPI Error (evxfevnt-0388): Could not disable RealTimeClock events [20060127]
[4294684.849000] cs: IO port probe 0x100-0x3af: excluding 0x1f0-0x1f7
[4294684.852000] cs: IO port probe 0x3e0-0x4ff: excluding 0x3f0-0x3f7 0x4d0-0x4d7
[4294684.853000] cs: IO port probe 0x820-0x8ff: excluding 0x878-0x87f
[4294684.855000] cs: IO port probe 0xc00-0xcf7: excluding 0xc00-0xc07 0xc10-0xc17 0xc50-0xc57 0xc68-0xc6f 0xcd0-0xcdf
[4294684.856000] cs: IO port probe 0xa00-0xaff: clean.
[4294684.891000] ts: Compaq touchscreen protocol output
[4294684.916000] Synaptics Touchpad, model: 1, fw: 6.2, id: 0x25a0b1, caps: 0xa04713/0x0
[4294684.916000] synaptics: Toshiba Satellite A100 detected, limiting rate to 40pps.
[4294684.953000] input: SynPS/2 Synaptics TouchPad as /class/input/input3
[4294684.998000] ACPI: PCI Interrupt 0000:00:14.2[A] -> GSI 16 (level, low) -> IRQ 193
[4294685.174000] hda_codec: Unknown model for ALC861, trying auto-probe from BIOS...
[4294685.882000] lp: driver loaded but no devices found
[4294685.955000] ieee1394: sbp2: Driver forced to serialize I/O (serialize_io=1)
[4294685.955000] ieee1394: sbp2: Try serialize_io=0 for better performance
[4294686.164000] Adding 971924k swap on /dev/sda2. Priority:-1 extents:1 across:971924k
[4294688.867000] md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
[4294688.867000] md: bitmap version 4.39
[4294691.151000] device-mapper: 4.6.0-ioctl (2006-02-17) initialised: dm-devel@redhat.com
[4294693.978000] device-mapper: dm-linear: Device lookup failed
[4294693.979000] device-mapper: error adding target to table
[4294693.979000] device-mapper: dm-linear: Device lookup failed
[4294693.979000] device-mapper: error adding target to table
[4294693.979000] device-mapper: dm-linear: Device lookup failed
[4294693.980000] device-mapper: error adding target to table
[4294693.980000] device-mapper: dm-linear: Device lookup failed
[4294693.980000] device-mapper: error adding target to table
[4294693.980000] device-mapper: dm-linear: Device lookup failed
[4294693.980000] device-mapper: error adding target to table
[4294693.981000] device-mapper: dm-linear: Device lookup failed
[4294693.981000] device-mapper: error adding target to table
[4294693.981000] device-mapper: dm-linear: Device lookup failed
[4294693.981000] device-mapper: error adding target to table
[4294693.982000] device-mapper: dm-linear: Device lookup failed
[4294693.982000] device-mapper: error adding target to table
[4294693.982000] device-mapper: dm-linear: Device lookup failed
[4294693.982000] device-mapper: error adding target to table
[4294693.983000] device-mapper: dm-linear: Device lookup failed
[4294693.983000] device-mapper: error adding target to table
[4294693.983000] device-mapper: dm-linear: Device lookup failed
[4294693.983000] device-mapper: error adding target to table
[4294693.984000] device-mapper: dm-linear: Device lookup failed
[4294693.984000] device-mapper: error adding target to table
[4294694.348000] NTFS driver 2.1.27 [Flags: R/O MODULE].
[4294694.478000] NTFS volume version 3.1.
[4294696.487000] ACPI: AC Adapter [ADP0] (on-line)
[4294696.540000] ACPI: Battery Slot [BAT0] (battery absent)
[4294696.558000] ACPI Exception (utmutex-0257): AE_TIME, Thread 0 could not acquire Mutex [8] [20060127]
[4294696.558000] ACPI Exception (utmutex-0257): AE_TIME, Thread 0 could not acquire Mutex [8] [20060127]
[4294696.559000] ACPI: Power Button (FF) [PWRF]
[4294696.559000] ACPI: Power Button (CM) [PWRB]
[4294696.559000] ACPI: Lid Switch [LID]
[4294696.610000] Using specific hotkey driver
[4294696.633000] ibm_acpi: ec object not found
[4294696.660000] toshiba_acpi: Unknown parameter `hotkeys_over_acpi'
[4294697.500000] ACPI Error (evxfevnt-0388): Could not disable RealTimeClock events [20060127]
[4294702.910000] ppdev: user-space parallel port driver
[4294704.034000] NET: Registered protocol family 10
[4294704.034000] lo: Disabled Privacy Extensions
[4294704.035000] IPv6 over IPv4 tunneling driver
[4294705.108000] apm: BIOS not found.
[4294710.259000] Capability LSM initialized
[4294710.536000] eth0: link down
[4294710.537000] ADDRCONF(NETDEV_UP): eth0: link is not ready
[4294715.201000] hda-intel: Invalid position buffer, using LPIB read method instead.
[4294719.610000] Bluetooth: Core ver 2.8
[4294719.610000] NET: Registered protocol family 31
[4294719.610000] Bluetooth: HCI device and connection manager initialized
[4294719.610000] Bluetooth: HCI socket layer initialized
[4294719.720000] Bluetooth: L2CAP ver 2.8
[4294719.720000] Bluetooth: L2CAP socket layer initialized
[4294719.791000] Bluetooth: RFCOMM socket layer initialized
[4294719.791000] Bluetooth: RFCOMM TTY layer initialized
[4294719.791000] Bluetooth: RFCOMM ver 1.7
[-- Attachment #1.3: error_2.6.17 --]
[-- Type: text/plain, Size: 353 bytes --]
ata1: command 0xc8 timeout, stat 0xd8 host_stat 0x21
ata1: translated ATA stat/err 0xd8/00 to SCSI SK/ASC/ASCQ 0xb/47/00
ata1: status=0xd8 { Busy }
sd 0:0:0:0: SCSI error: return code = 0x8000002
sda: Current: sense key: Aborted Command
Additional sense: Scsi parity error
Info fld=0x7050337
end_request: I/O error, dev sda, sector 117769015
[-- Attachment #1.4: dmesg_2.6.17-mm2 --]
[-- Type: text/plain, Size: 14843 bytes --]
Linux version 2.6.17-mm2 (root@tenco) (gcc-Version 4.0.3 (Ubuntu 4.0.3-1ubuntu5)) #1 PREEMPT Sat Jun 24 20:36:16 CEST 2006
BIOS-provided physical RAM map:
BIOS-e820: 0000000000000000 - 000000000009f800 (usable)
BIOS-e820: 000000000009f800 - 00000000000a0000 (reserved)
BIOS-e820: 00000000000dc000 - 0000000000100000 (reserved)
BIOS-e820: 0000000000100000 - 000000001dea0000 (usable)
BIOS-e820: 000000001dea0000 - 000000001deb0000 (ACPI data)
BIOS-e820: 000000001deb0000 - 000000001df00000 (ACPI NVS)
BIOS-e820: 000000001df00000 - 000000001e000000 (reserved)
BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
BIOS-e820: 00000000fec00000 - 00000000fec10000 (reserved)
BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
BIOS-e820: 00000000fff80000 - 0000000100000000 (reserved)
478MB LOWMEM available.
found SMP MP-table at 000f7710
On node 0 totalpages: 122528
DMA zone: 4096 pages, LIFO batch:0
Normal zone: 118432 pages, LIFO batch:31
DMI present.
ACPI: RSDP (v000 TOSINV ) @ 0x000f76c0
ACPI: RSDT (v001 TOSINV RSDT 0x06040000 LTP 0x00000000) @ 0x1deabf9b
ACPI: FADT (v001 TOSINV Goldfish 0x06040000 ATI 0x000f4240) @ 0x1deafef6
ACPI: MADT (v001 TOSINV APIC 0x06040000 LTP 0x00000000) @ 0x1deaff6a
ACPI: MCFG (v001 TOSINV MCFG 0x06040000 LTP 0x00000000) @ 0x1deaffc4
ACPI: SSDT (v001 TOSINV Cpu0Cst 0x00003001 INTL 0x20030224) @ 0x1deac1ce
ACPI: SSDT (v001 TOSINV CpuPm 0x00003000 INTL 0x20030224) @ 0x1deabfd3
ACPI: DSDT (v001 TOSINV SB450 0x06040000 MSFT 0x0100000e) @ 0x00000000
ATI board detected. Disabling timer routing over 8254.
ACPI: PM-Timer IO Port: 0x8008
ACPI: Local APIC address 0xfee00000
ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
Processor #0 6:13 APIC version 20
ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
IOAPIC[0]: apic_id 1, version 33, address 0xfec00000, GSI 0-23
ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 high edge)
ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 21 low level)
ACPI: IRQ0 used by override.
ACPI: IRQ2 used by override.
Enabling APIC mode: Flat. Using 1 I/O APICs
Using ACPI (MADT) for SMP configuration information
Allocating PCI resources starting at 20000000 (gap: 1e000000:c2000000)
Detected 1393.523 MHz processor.
Built 1 zonelists. Total pages: 122528
Kernel command line: root=/dev/sda1 ro single
mapped APIC to ffffd000 (fee00000)
mapped IOAPIC to ffffc000 (fec00000)
Enabling fast FPU save and restore... done.
Enabling unmasked SIMD FPU exception support... done.
Initializing CPU#0
PID hash table entries: 2048 (order: 11, 8192 bytes)
Console: colour VGA+ 80x25
Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Memory: 478012k/490112k available (1596k kernel code, 11436k reserved, 530k data, 276k init, 0k highmem)
Checking if this processor honours the WP bit even in supervisor mode... Ok.
Calibrating delay using timer specific routine.. 2788.61 BogoMIPS (lpj=1394309)
Mount-cache hash table entries: 512
CPU: After generic identify, caps: afe9fbff 00100000 00000000 00000000 00000000 00000000 00000000
CPU: After vendor identify, caps: afe9fbff 00100000 00000000 00000000 00000000 00000000 00000000
CPU: L1 I cache: 32K, L1 D cache: 32K
CPU: L2 cache: 1024K
CPU: After all inits, caps: afe9fbff 00100000 00000000 00000040 00000000 00000000 00000000
CPU: Intel(R) Celeron(R) M processor 1.40GHz stepping 08
Checking 'hlt' instruction... OK.
SMP alternatives: switching to UP code
Freeing SMP alternatives: 0k freed
ACPI: Core revision 20060608
ENABLING IO-APIC IRQs
..TIMER: vector=0x31 apic1=0 pin1=2 apic2=-1 pin2=-1
..MP-BIOS bug: 8254 timer not connected to IO-APIC
...trying to set up timer (IRQ0) through the 8259A ... failed.
...trying to set up timer as Virtual Wire IRQ... works.
checking if image is initramfs... it is
Freeing initrd memory: 4556k freed
NET: Registered protocol family 16
EISA bus registered
ACPI: bus type pci registered
PCI: Using MMCONFIG
Setting up standard PCI resources
ACPI: Interpreter enabled
ACPI: Using IOAPIC for interrupt routing
ACPI: PCI Root Bridge [PCI0] (0000:00)
PCI: Probing PCI hardware (bus 00)
PCI: Ignoring BAR0-3 of IDE controller 0000:00:14.1
Boot video device is 0000:01:05.0
PCI: Transparent bridge - 0000:00:14.4
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
ACPI: PCI Interrupt Link [LNKA] (IRQs 10 11) *0, disabled.
ACPI: PCI Interrupt Link [LNKB] (IRQs 10 11) *0, disabled.
ACPI: PCI Interrupt Link [LNKC] (IRQs 10 11) *0, disabled.
ACPI: PCI Interrupt Link [LNKD] (IRQs 10 11) *0, disabled.
ACPI: PCI Interrupt Link [LNKE] (IRQs 10 11) *0, disabled.
ACPI: PCI Interrupt Link [LNKF] (IRQs 10 11) *0, disabled.
ACPI: PCI Interrupt Link [LNKG] (IRQs 10 11) *0, disabled.
ACPI: PCI Interrupt Link [LNKH] (IRQs 10 11) *0, disabled.
ACPI: Embedded Controller [EC0] (gpe 7) interrupt mode.
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P2P_._PRT]
ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.AGP_._PRT]
ACPI: Power Resource [PFA1] (off)
Linux Plug and Play Support v0.97 (c) Adam Belay
pnp: PnP ACPI init
pnp: PnP ACPI: found 10 devices
PnPBIOS: Disabled by ACPI PNP
PCI: Using ACPI for IRQ routing
PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
pnp: 00:08: ioport range 0x1080-0x1080 has been reserved
pnp: 00:08: ioport range 0x220-0x22f has been reserved
pnp: 00:08: ioport range 0x400-0x401 has been reserved
pnp: 00:08: ioport range 0x40b-0x40b has been reserved
PCI: Bridge: 0000:00:01.0
IO window: 9000-9fff
MEM window: c0100000-c01fffff
PREFETCH window: d0000000-dfffffff
PCI: Bus 3, cardbus bridge: 0000:02:06.0
IO window: 0000a000-0000a0ff
IO window: 0000a400-0000a4ff
PREFETCH window: 20000000-21ffffff
MEM window: 24000000-25ffffff
PCI: Bridge: 0000:00:14.4
IO window: a000-afff
MEM window: c0200000-c02fffff
PREFETCH window: 20000000-21ffffff
PCI: Enabling device 0000:02:06.0 (0004 -> 0007)
ACPI: PCI Interrupt 0000:02:06.0[A] -> GSI 23 (level, low) -> IRQ 177
NET: Registered protocol family 2
IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
TCP established hash table entries: 16384 (order: 4, 65536 bytes)
TCP bind hash table entries: 8192 (order: 3, 32768 bytes)
TCP: Hash tables configured (established 16384 bind 8192)
TCP reno registered
audit: initializing netlink socket (disabled)
audit(1151176565.930:1): initialized
VFS: Disk quotas dquot_6.5.1
Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Initializing Cryptographic API
io scheduler noop registered
io scheduler anticipatory registered (default)
io scheduler deadline registered
io scheduler cfq registered
isapnp: Scanning for PnP cards...
isapnp: No Plug & Play device found
Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
PNP: PS/2 Controller [PNP0303:KBC0,PNP0f13:MSE0] at 0x60,0x64 irq 1,12
serio: i8042 AUX port at 0x60,0x64 irq 12
serio: i8042 KBD port at 0x60,0x64 irq 1
mice: PS/2 mouse device common for all mice
EISA: Probing bus 0 at eisa.0
Cannot allocate resource for EISA slot 1
Cannot allocate resource for EISA slot 8
EISA: Detected 0 cards.
TCP bic registered
NET: Registered protocol family 1
Using IPI Shortcut mode
ACPI: (supports S0 S3 S4 S5)
Time: tsc clocksource has been installed.
Freeing unused kernel memory: 276k freed
input: AT Translated Set 2 keyboard as /class/input/input0
ACPI: Fan [FAN1] (off)
ACPI: CPU0 (power states: C1[C1] C2[C2] C3[C3])
ACPI: Processor [CPU0] (supports 8 throttling states)
Time: acpi_pm clocksource has been installed.
ACPI: Thermal Zone [TZCR] (53 C)
SCSI subsystem initialized
libata version 1.30 loaded.
sata_sil 0000:00:12.0: version 1.0
PCI: Enabling device 0000:00:12.0 (0005 -> 0007)
ACPI: PCI Interrupt 0000:00:12.0[A] -> GSI 22 (level, low) -> IRQ 185
ata1: SATA max UDMA/100 cmd 0xDE826080 ctl 0xDE82608A bmdma 0xDE826000 irq 185
ata2: SATA max UDMA/100 cmd 0xDE8260C0 ctl 0xDE8260CA bmdma 0xDE826008 irq 185
scsi0 : sata_sil
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: configured for UDMA/100
scsi1 : sata_sil
ata2: SATA link down (SStatus 0 SControl 300)
Vendor: ATA Model: TOSHIBA MK8032GS Rev: AS11
Type: Direct-Access ANSI SCSI revision: 05
SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
sda: Write Protect is off
sda: Mode Sense: 00 3a 00 00
SCSI device sda: drive cache: write back
SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
sda: Write Protect is off
sda: Mode Sense: 00 3a 00 00
SCSI device sda: drive cache: write back
sda: sda1 sda2 sda3
sd 0:0:0:0: Attached scsi disk sda
ATIIXP: IDE controller at PCI slot 0000:00:14.1
ACPI: PCI Interrupt 0000:00:14.1[A] -> GSI 16 (level, low) -> IRQ 193
ATIIXP: chipset revision 128
ATIIXP: not 100% native mode: will probe irqs later
ide0: BM-DMA at 0x8460-0x8467, BIOS settings: hda:pio, hdb:pio
ide1: BM-DMA at 0x8468-0x846f, BIOS settings: hdc:DMA, hdd:pio
Probing IDE interface ide0...
Probing IDE interface ide1...
hdc: HL-DT-ST DVDRAM GMA-4082N, ATAPI CD/DVD-ROM drive
ide1 at 0x170-0x177,0x376 on irq 15
hdc: ATAPI 24X DVD-ROM DVD-R-RAM CD-R/RW drive, 2048kB Cache, UDMA(33)
Uniform CD-ROM driver Revision: 3.20
usbcore: registered new driver usbfs
usbcore: registered new driver hub
ohci_hcd: 2006 May 24 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
ACPI: PCI Interrupt 0000:00:13.0[A] -> GSI 19 (level, low) -> IRQ 201
ohci_hcd 0000:00:13.0: OHCI Host Controller
ohci_hcd 0000:00:13.0: new USB bus registered, assigned bus number 1
ohci_hcd 0000:00:13.0: irq 201, io mem 0xc0005000
usb usb1: new device found, idVendor=0000, idProduct=0000
usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
usb usb1: Product: OHCI Host Controller
usb usb1: Manufacturer: Linux 2.6.17-mm2 ohci_hcd
usb usb1: SerialNumber: 0000:00:13.0
usb usb1: configuration #1 chosen from 1 choice
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 4 ports detected
ACPI: PCI Interrupt 0000:00:13.1[A] -> GSI 19 (level, low) -> IRQ 201
ohci_hcd 0000:00:13.1: OHCI Host Controller
ohci_hcd 0000:00:13.1: new USB bus registered, assigned bus number 2
ohci_hcd 0000:00:13.1: irq 201, io mem 0xc0006000
usb usb2: new device found, idVendor=0000, idProduct=0000
usb usb2: new device strings: Mfr=3, Product=2, SerialNumber=1
usb usb2: Product: OHCI Host Controller
usb usb2: Manufacturer: Linux 2.6.17-mm2 ohci_hcd
usb usb2: SerialNumber: 0000:00:13.1
usb usb2: configuration #1 chosen from 1 choice
hub 2-0:1.0: USB hub found
hub 2-0:1.0: 4 ports detected
ACPI: PCI Interrupt 0000:00:13.2[A] -> GSI 19 (level, low) -> IRQ 201
ehci_hcd 0000:00:13.2: EHCI Host Controller
ehci_hcd 0000:00:13.2: new USB bus registered, assigned bus number 3
ehci_hcd 0000:00:13.2: irq 201, io mem 0xc0007000
ehci_hcd 0000:00:13.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
usb usb3: new device found, idVendor=0000, idProduct=0000
usb usb3: new device strings: Mfr=3, Product=2, SerialNumber=1
usb usb3: Product: EHCI Host Controller
usb usb3: Manufacturer: Linux 2.6.17-mm2 ehci_hcd
usb usb3: SerialNumber: 0000:00:13.2
usb usb3: configuration #1 chosen from 1 choice
hub 3-0:1.0: USB hub found
hub 3-0:1.0: 8 ports detected
Probing IDE interface ide0...
ohci_hcd 0000:00:13.0: wakeup
ohci_hcd 0000:00:13.1: wakeup
Attempting manual resume
JFS: nTxBlock = 3774, nTxLock = 30195
usb 1-1: new low speed USB device using ohci_hcd and address 3
usb 1-1: new device found, idVendor=045e, idProduct=007d
usb 1-1: new device strings: Mfr=1, Product=3, SerialNumber=0
usb 1-1: Product: Microsoft 3-Button Mouse with IntelliEye?
usb 1-1: Manufacturer: Microsoft
usb 1-1: configuration #1 chosen from 1 choice
usb 2-1: new full speed USB device using ohci_hcd and address 2
usb 2-1: new device found, idVendor=03f0, idProduct=0604
usb 2-1: new device strings: Mfr=1, Product=2, SerialNumber=3
usb 2-1: Product: DeskJet 840C
usb 2-1: Manufacturer: Hewlett-Packard
usb 2-1: SerialNumber: HU0441S0D6KV
usb 2-1: configuration #1 chosen from 1 choice
sd 0:0:0:0: Attached scsi generic sg0 type 0
Adding 971924k swap on /dev/sda2. Priority:-1 extents:1 across:971924k
Synaptics Touchpad, model: 1, fw: 6.2, id: 0x25a0b1, caps: 0xa04713/0x0
synaptics: Toshiba Satellite A100 detected, limiting rate to 40pps.
input: SynPS/2 Synaptics TouchPad as /class/input/input1
input: PC Speaker as /class/input/input2
Real Time Clock Driver v1.12ac
Linux agpgart interface v0.101 (c) Dave Jones
piix4_smbus 0000:00:14.0: Found 0000:00:14.0 device
Yenta: CardBus bridge found at 0000:02:06.0 [1179:ff10]
Yenta: Enabling burst memory read transactions
Yenta: Using CSCINT to route CSC interrupts to PCI
Yenta: Routing CardBus interrupts to PCI
Yenta TI: socket 0000:02:06.0, mfunc 0x01111122, devctl 0x44
ACPI Error (evxfevnt-0386): Could not disable RealTimeClock events [20060608]
drivers/usb/class/usblp.c: Disabling reads from problem bidirectional printer on usblp0
ts: Compaq touchscreen protocol output
drivers/usb/class/usblp.c: usblp0: USB Unidirectional printer dev 2 if 0 alt 1 proto 2 vid 0x03F0 pid 0x0604
usbcore: registered new driver usblp
drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
usbcore: registered new driver hiddev
input: Microsoft Microsoft 3-Button Mouse with IntelliEye? as /class/input/input3
input: USB HID v1.00 Mouse [Microsoft Microsoft 3-Button Mouse with IntelliEye?] on usb-0000:00:13.0-1
usbcore: registered new driver usbhid
drivers/usb/input/hid-core.c: v2.6:USB HID core driver
Yenta: ISA IRQ mask 0x0ef8, PCI irq 177
Socket status: 30000006
pcmcia: parent PCI bridge I/O window: 0xa000 - 0xafff
cs: IO port probe 0xa000-0xafff: clean.
pcmcia: parent PCI bridge Memory window: 0xc0200000 - 0xc02fffff
pcmcia: parent PCI bridge Memory window: 0x20000000 - 0x21ffffff
ACPI: PCI Interrupt 0000:00:14.2[A] -> GSI 16 (level, low) -> IRQ 193
hda_codec: Unknown model for ALC861, trying auto-probe from BIOS...
cs: IO port probe 0x100-0x3af: excluding 0x1f0-0x1f7
cs: IO port probe 0x3e0-0x4ff: excluding 0x3f0-0x3f7 0x4d0-0x4d7
cs: IO port probe 0x820-0x8ff: excluding 0x878-0x87f
cs: IO port probe 0xc00-0xcf7: excluding 0xc00-0xc07 0xc10-0xc17 0xc50-0xc57 0xc68-0xc6f 0xcd0-0xcdf
cs: IO port probe 0xa00-0xaff: clean.
NTFS driver 2.1.27 [Flags: R/O MODULE].
NTFS volume version 3.1.
[-- Attachment #1.5: error_2.6.17-mm2 --]
[-- Type: text/plain, Size: 543 bytes --]
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: (BMDMA stat 0x21)
ata1.00: tag 0 cmd 0xc8 Emask 0x4 stat 0x40 err 0x0 (timeout)
ata1: port is slow to respond, please be patient
ata1: port failed to respond (30 secs)
ata1: soft resetting port
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: configured for UDMA/100
ata1: EH complete
SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
sda: Write Protect is off
sda: Mode Sense: 00 3a 00 00
SCSI device sda: drive cache: write back
[-- Attachment #2: Dies ist ein digital signierter Nachrichtenteil --]
[-- Type: application/pgp-signature, Size: 191 bytes --]
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: [sata_sil] kernel 2.6.17(-mm2) test - timeout issue
2006-06-24 20:50 [sata_sil] kernel 2.6.17(-mm2) test - timeout issue Martin Ammermüller
@ 2006-06-25 3:06 ` Tejun Heo
2006-07-24 8:21 ` Martin Ammermüller
0 siblings, 1 reply; 7+ messages in thread
From: Tejun Heo @ 2006-06-25 3:06 UTC (permalink / raw)
To: Martin Ammermüller; +Cc: linux-ide, jgarzik
Martin Ammermüller wrote:
> Hello list!
>
> I stress-tested the SATA-hdd of my notebook[0] again with current kernel
> versions.
>
> With the 2.6.17 kernel i got a freeze after a timeout message (printed
> on the screen repeatedly). Running a 2.6.17-mm2 kernel, libata/sata_sil
> recovered from the timeout, resetting after a 30 second limit.
>
> See the attached error-messages and dmesg outputs for details, please.
>
> Regards,
> Martin Ammermüller
> P.S.: Does the reset/error handling procedure which happened when
> testing the 2.6.17-mm2 kernel include loss of data or is this a "clean"
> reset?
Failed commands are retried several times, so no data was lost in your
case. If data gets lost, you'll see more error messages from higher
layers (SCSI, FS).
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: (BMDMA stat 0x21)
> ata1.00: tag 0 cmd 0xc8 Emask 0x4 stat 0x40 err 0x0 (timeout)
> ata1: port is slow to respond, please be patient
> ata1: port failed to respond (30 secs)
> ata1: soft resetting port
> ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> ata1.00: configured for UDMA/100
> ata1: EH complete
> SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
> sda: Write Protect is off
> sda: Mode Sense: 00 3a 00 00
> SCSI device sda: drive cache: write back
It took more than a minute to recover from it. I think we can use some
improvement here. Anyways, if your harddisk is doing this regularly,
your hardware is faulty. Maybe the connection between the controller
and the disk is the problem or the disk itself.
--
tejun
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: [sata_sil] kernel 2.6.17(-mm2) test - timeout issue
2006-06-25 3:06 ` Tejun Heo
@ 2006-07-24 8:21 ` Martin Ammermüller
2006-07-30 20:22 ` Tejun Heo
0 siblings, 1 reply; 7+ messages in thread
From: Martin Ammermüller @ 2006-07-24 8:21 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide, jgarzik
[-- Attachment #1: Type: text/plain, Size: 2133 bytes --]
Am Sonntag, den 25.06.2006, 12:06 +0900 schrieb Tejun Heo:
> Failed commands are retried several times, so no data was lost in your
> case. If data gets lost, you'll see more error messages from higher
> layers (SCSI, FS).
With high disk I/O and a 2.6.18-rc1 kernel i get these errors (depending
upon the work i do, up to several times a day):
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x400000 action 0x2 frozen
ata1.00: (BMDMA stat 0x20)
ata1.00: tag 0 cmd 0xc8 Emask 0x2 stat 0x58 err 0x0 (HSM violation)
ata1: soft resetting port
ata1: port is slow to respond, please be patient
ata1: port failed to respond (30 secs)
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ATA: abnormal status 0xD8 on port 0xDCA18087
ATA: abnormal status 0xD8 on port 0xDCA18087
ATA: abnormal status 0xD8 on port 0xDCA18087
ATA: abnormal status 0xD8 on port 0xDCA18087
ATA: abnormal status 0xD8 on port 0xDCA18087
ata1.00: qc timeout (cmd 0xec)
ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata1.00: revalidation failed (errno=-5)
ata1: failed to recover some devices, retrying in 5 secs
ata1: hard resetting port
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: configured for UDMA/100
ata1: EH complete
SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
sda: Write Protect is off
sda: Mode Sense: 00 3a 00 00
SCSI device sda: drive cache: write back
> Anyways, if your harddisk is doing this regularly,
> your hardware is faulty. Maybe the connection between the controller
> and the disk is the problem or the disk itself.
I did not get those errors with Windows XP and i am not the only one who
has problems running this particular laptop model with a linux kernel.
Ok, to be honest, there's actually only one person i know of which
bothered enough about exactly the same errors to send me an e-mail (he
discovered at least one of my messages to this list). But in my
experience there are almost always others getting the same error, but
which remain silent.
Anyway, i will try to probe the device with smartmontools.
Regards,
Martin Ammermüller
[-- Attachment #2: Dies ist ein digital signierter Nachrichtenteil --]
[-- Type: application/pgp-signature, Size: 191 bytes --]
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: [sata_sil] kernel 2.6.17(-mm2) test - timeout issue
2006-07-24 8:21 ` Martin Ammermüller
@ 2006-07-30 20:22 ` Tejun Heo
2006-08-05 13:36 ` Martin Ammermüller
0 siblings, 1 reply; 7+ messages in thread
From: Tejun Heo @ 2006-07-30 20:22 UTC (permalink / raw)
To: Martin Ammermüller; +Cc: linux-ide, jgarzik
[-- Attachment #1: Type: text/plain, Size: 2201 bytes --]
Martin Ammermüller wrote:
> With high disk I/O and a 2.6.18-rc1 kernel i get these errors (depending
> upon the work i do, up to several times a day):
>
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x400000 action 0x2 frozen
> ata1.00: (BMDMA stat 0x20)
> ata1.00: tag 0 cmd 0xc8 Emask 0x2 stat 0x58 err 0x0 (HSM violation)
Hmm... Interesting. It gets HSM violation first.
> ata1: soft resetting port
> ata1: port is slow to respond, please be patient
> ata1: port failed to respond (30 secs)
> ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> ATA: abnormal status 0xD8 on port 0xDCA18087
> ATA: abnormal status 0xD8 on port 0xDCA18087
> ATA: abnormal status 0xD8 on port 0xDCA18087
> ATA: abnormal status 0xD8 on port 0xDCA18087
> ATA: abnormal status 0xD8 on port 0xDCA18087
> ata1.00: qc timeout (cmd 0xec)
> ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
> ata1.00: revalidation failed (errno=-5)
> ata1: failed to recover some devices, retrying in 5 secs
> ata1: hard resetting port
> ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> ata1.00: configured for UDMA/100
> ata1: EH complete
Then two timeouts while recovering.
> SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
> sda: Write Protect is off
> sda: Mode Sense: 00 3a 00 00
> SCSI device sda: drive cache: write back
>
>> Anyways, if your harddisk is doing this regularly,
>> your hardware is faulty. Maybe the connection between the controller
>> and the disk is the problem or the disk itself.
>
> I did not get those errors with Windows XP and i am not the only one who
> has problems running this particular laptop model with a linux kernel.
> Ok, to be honest, there's actually only one person i know of which
> bothered enough about exactly the same errors to send me an e-mail (he
> discovered at least one of my messages to this list). But in my
> experience there are almost always others getting the same error, but
> which remain silent.
It might be that the drive is quirky and raises interrupts prematurely
sometimes. Depending on how the driver performs recovery, the effect
can be hidden from user. Can you try the attached patch and see how the
kernel acts?
--
tejun
[-- Attachment #2: patch --]
[-- Type: text/plain, Size: 1314 bytes --]
diff --git a/drivers/scsi/sata_sil.c b/drivers/scsi/sata_sil.c
index d0a8507..6633738 100644
--- a/drivers/scsi/sata_sil.c
+++ b/drivers/scsi/sata_sil.c
@@ -380,11 +380,14 @@ static void sil_host_intr(struct ata_por
ap->eh_info.serror |= serror;
}
+ ata_port_printk(ap, KERN_ERR, "XXX: SATA_IRQ serror=%x\n", serror);
goto freeze;
}
- if (unlikely(!qc || qc->tf.ctl & ATA_NIEN))
+ if (unlikely(!qc || qc->tf.ctl & ATA_NIEN)) {
+ ata_port_printk(ap, KERN_ERR, "XXX: qc=%p ctl=%x\n", qc, qc ? qc->tf.ctl : 0);
goto freeze;
+ }
/* Check whether we are expecting interrupt in this state */
switch (ap->hsm_task_state) {
@@ -415,13 +418,17 @@ static void sil_host_intr(struct ata_por
case HSM_ST:
break;
default:
+ ata_port_printk(ap, KERN_ERR, "XXX: HSM=%d\n", ap->hsm_task_state);
goto err_hsm;
}
/* check main status, clearing INTRQ */
status = ata_chk_status(ap);
- if (unlikely(status & ATA_BUSY))
+ if (unlikely(status & ATA_BUSY)) {
+ ata_port_printk(ap, KERN_ERR, "XXX: BUSY status=%x\n",
+ status);
goto err_hsm;
+ }
/* ack bmdma irq events */
ata_bmdma_irq_clear(ap);
@@ -434,6 +441,7 @@ static void sil_host_intr(struct ata_por
err_hsm:
qc->err_mask |= AC_ERR_HSM;
freeze:
+ ap->eh_info.action |= ATA_EH_HARDRESET;
ata_port_freeze(ap);
}
^ permalink raw reply related [flat|nested] 7+ messages in thread
* Re: [sata_sil] kernel 2.6.17(-mm2) test - timeout issue
2006-07-30 20:22 ` Tejun Heo
@ 2006-08-05 13:36 ` Martin Ammermüller
2006-08-06 15:51 ` Tejun Heo
0 siblings, 1 reply; 7+ messages in thread
From: Martin Ammermüller @ 2006-08-05 13:36 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide, jgarzik
[-- Attachment #1.1: Type: text/plain, Size: 4338 bytes --]
Am Montag, den 31.07.2006, 05:22 +0900 schrieb Tejun Heo:
> Martin Ammermüller wrote:
> > With high disk I/O and a 2.6.18-rc1 kernel i get these errors (depending
> > upon the work i do, up to several times a day):
> >
> > ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x400000 action 0x2 frozen
> > ata1.00: (BMDMA stat 0x20)
> > ata1.00: tag 0 cmd 0xc8 Emask 0x2 stat 0x58 err 0x0 (HSM violation)
>
> Hmm... Interesting. It gets HSM violation first.
>
> > ata1: soft resetting port
> > ata1: port is slow to respond, please be patient
> > ata1: port failed to respond (30 secs)
> > ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> > ATA: abnormal status 0xD8 on port 0xDCA18087
> > ATA: abnormal status 0xD8 on port 0xDCA18087
> > ATA: abnormal status 0xD8 on port 0xDCA18087
> > ATA: abnormal status 0xD8 on port 0xDCA18087
> > ATA: abnormal status 0xD8 on port 0xDCA18087
> > ata1.00: qc timeout (cmd 0xec)
> > ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
> > ata1.00: revalidation failed (errno=-5)
> > ata1: failed to recover some devices, retrying in 5 secs
> > ata1: hard resetting port
> > ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> > ata1.00: configured for UDMA/100
> > ata1: EH complete
>
> Then two timeouts while recovering.
>
> > SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
> > sda: Write Protect is off
> > sda: Mode Sense: 00 3a 00 00
> > SCSI device sda: drive cache: write back
> >
> >> Anyways, if your harddisk is doing this regularly,
> >> your hardware is faulty. Maybe the connection between the controller
> >> and the disk is the problem or the disk itself.
> >
> > I did not get those errors with Windows XP and i am not the only one who
> > has problems running this particular laptop model with a linux kernel.
> > Ok, to be honest, there's actually only one person i know of which
> > bothered enough about exactly the same errors to send me an e-mail (he
> > discovered at least one of my messages to this list). But in my
> > experience there are almost always others getting the same error, but
> > which remain silent.
>
> It might be that the drive is quirky and raises interrupts prematurely
> sometimes. Depending on how the driver performs recovery, the effect
> can be hidden from user. Can you try the attached patch and see how the
> kernel acts?
I tried the patch, but i couldn't see any changes in kerneloutput. I
also noticed, that there are actually two slightly different
error-messages.
#1 (shorter one, without HSM violation):
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
ata1.00: (BMDMA stat 0x21)
ata1.00: tag 0 cmd 0xc8 Emask 0x4 stat 0x40 err 0x0 (timeout)
ata1: port is slow to respond, please be patient
ata1: port failed to respond (30 secs)
ata1: soft resetting port
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: configured for UDMA/100
ata1: EH complete
SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
sda: Write Protect is off
sda: Mode Sense: 00 3a 00 00
SCSI device sda: drive cache: write back
#2 (longer, with HSM violation):
ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x400000 action 0x2 frozen
ata1.00: (BMDMA stat 0x20)
ata1.00: tag 0 cmd 0xc8 Emask 0x2 stat 0x58 err 0x0 (HSM violation)
ata1: soft resetting port
ata1: port is slow to respond, please be patient
ata1: port failed to respond (30 secs)
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ATA: abnormal status 0xD8 on port 0xDCA44087
ATA: abnormal status 0xD8 on port 0xDCA44087
ATA: abnormal status 0xD8 on port 0xDCA44087
ATA: abnormal status 0xD8 on port 0xDCA44087
ATA: abnormal status 0xD8 on port 0xDCA44087
ata1.00: qc timeout (cmd 0xec)
ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
ata1.00: revalidation failed (errno=-5)
ata1: failed to recover some devices, retrying in 5 secs
ata1: hard resetting port
ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata1.00: configured for UDMA/100
ata1: EH complete
SCSI device sda: 156301488 512-byte hdwr sectors (80026 MB)
sda: Write Protect is off
sda: Mode Sense: 00 3a 00 00
SCSI device sda: drive cache: write back
Additionally, i attached the output of "smartctl --all -d ata /dev/sda"
Regards,
Martin Ammermüller
[-- Attachment #1.2: smart_all --]
[-- Type: text/plain, Size: 5055 bytes --]
smartctl version 5.34 [i686-pc-linux-gnu] Copyright (C) 2002-5 Bruce Allen
Home page is http://smartmontools.sourceforge.net/
=== START OF INFORMATION SECTION ===
Device Model: TOSHIBA MK8032GSX
Serial Number: 26GI5560S
Firmware Version: AS111G
User Capacity: 80.026.361.856 bytes
Device is: Not in smartctl database [for details use: -P showall]
ATA Version is: 6
ATA Standard is: Exact ATA specification draft version not indicated
Local Time is: Sat Aug 5 15:33:19 2006 CEST
SMART support is: Available - device has SMART capability.
SMART support is: Enabled
=== START OF READ SMART DATA SECTION ===
SMART overall-health self-assessment test result: PASSED
General SMART Values:
Offline data collection status: (0x00) Offline data collection activity
was never started.
Auto Offline Data Collection: Disabled.
Self-test execution status: ( 0) The previous self-test routine completed
without error or no self-test has ever
been run.
Total time to complete Offline
data collection: ( 331) seconds.
Offline data collection
capabilities: (0x5b) SMART execute Offline immediate.
Auto Offline data collection on/off support.
Suspend Offline collection upon new
command.
Offline surface scan supported.
Self-test supported.
No Conveyance Self-test supported.
Selective Self-test supported.
SMART capabilities: (0x0003) Saves SMART data before entering
power-saving mode.
Supports SMART auto save timer.
Error logging capability: (0x01) Error logging supported.
General Purpose Logging supported.
Short self-test routine
recommended polling time: ( 2) minutes.
Extended self-test routine
recommended polling time: ( 65) minutes.
SMART Attributes Data Structure revision number: 16
Vendor Specific SMART Attributes with Thresholds:
ID# ATTRIBUTE_NAME FLAG VALUE WORST THRESH TYPE UPDATED WHEN_FAILED RAW_VALUE
1 Raw_Read_Error_Rate 0x000b 100 100 050 Pre-fail Always - 0
2 Throughput_Performance 0x0005 100 100 050 Pre-fail Offline - 0
3 Spin_Up_Time 0x0027 100 100 001 Pre-fail Always - 2104
4 Start_Stop_Count 0x0032 100 100 000 Old_age Always - 482
5 Reallocated_Sector_Ct 0x0033 100 100 050 Pre-fail Always - 0
7 Seek_Error_Rate 0x000b 100 100 050 Pre-fail Always - 0
8 Seek_Time_Performance 0x0005 100 100 050 Pre-fail Offline - 0
9 Power_On_Hours 0x0032 096 096 000 Old_age Always - 1729
10 Spin_Retry_Count 0x0033 109 100 030 Pre-fail Always - 0
12 Power_Cycle_Count 0x0032 100 100 000 Old_age Always - 394
192 Power-Off_Retract_Count 0x0032 100 100 000 Old_age Always - 97
193 Load_Cycle_Count 0x0032 089 089 000 Old_age Always - 111507
194 Temperature_Celsius 0x0022 100 100 000 Old_age Always - 37 (Lifetime Min/Max 15/50)
196 Reallocated_Event_Count 0x0032 100 100 000 Old_age Always - 0
197 Current_Pending_Sector 0x0032 100 100 000 Old_age Always - 0
198 Offline_Uncorrectable 0x0030 100 100 000 Old_age Offline - 0
199 UDMA_CRC_Error_Count 0x0032 200 200 000 Old_age Always - 0
220 Disk_Shift 0x0002 100 100 000 Old_age Always - 122
222 Loaded_Hours 0x0032 098 098 000 Old_age Always - 1168
223 Load_Retry_Count 0x0032 100 100 000 Old_age Always - 0
224 Load_Friction 0x0022 100 100 000 Old_age Always - 0
226 Load-in_Time 0x0026 100 100 000 Old_age Always - 324
240 Head_Flying_Hours 0x0001 100 100 001 Pre-fail Offline - 0
SMART Error Log Version: 1
No Errors Logged
SMART Self-test log structure revision number 1
Num Test_Description Status Remaining LifeTime(hours) LBA_of_first_error
# 1 Extended offline Completed without error 00% 1729 -
# 2 Short offline Completed without error 00% 1728 -
# 3 Short offline Completed without error 00% 1128 -
SMART Selective self-test log data structure revision number 1
SPAN MIN_LBA MAX_LBA CURRENT_TEST_STATUS
1 0 0 Not_testing
2 0 0 Not_testing
3 0 0 Not_testing
4 0 0 Not_testing
5 0 0 Not_testing
Selective self-test flags (0x0):
After scanning selected spans, do NOT read-scan remainder of disk.
If Selective self-test is pending on power-up, resume after 0 minute delay.
[-- Attachment #2: Dies ist ein digital signierter Nachrichtenteil --]
[-- Type: application/pgp-signature, Size: 191 bytes --]
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: [sata_sil] kernel 2.6.17(-mm2) test - timeout issue
2006-08-05 13:36 ` Martin Ammermüller
@ 2006-08-06 15:51 ` Tejun Heo
2006-08-14 10:12 ` Martin Ammermüller
0 siblings, 1 reply; 7+ messages in thread
From: Tejun Heo @ 2006-08-06 15:51 UTC (permalink / raw)
To: Martin Ammermüller; +Cc: linux-ide, jgarzik
Hello,
Martin Ammermüller wrote:
> I tried the patch, but i couldn't see any changes in kerneloutput. I
> also noticed, that there are actually two slightly different
> error-messages.
>
> #1 (shorter one, without HSM violation):
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x2 frozen
> ata1.00: (BMDMA stat 0x21)
> ata1.00: tag 0 cmd 0xc8 Emask 0x4 stat 0x40 err 0x0 (timeout)
DRDY (device ready), DMA engine active but no DRQ (data request), while
READ DMA - seems like a packet loss/corruption during data transfer to
me, but set DRDY is a bit weird.
> ata1: port is slow to respond, please be patient
> ata1: port failed to respond (30 secs)
Again, weird. libata times out waiting for DRDY, which is weird because
DRDY was set when the timeout occurred (as reported above) but when EH
reset code is executed (which should have followed immediately), the
code sees !DRDY and times out waiting for it.
> ata1: soft resetting port
> ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> ata1.00: configured for UDMA/100
> ata1: EH complete
SRST successfully recovers the device in this case.
> #2 (longer, with HSM violation):
> ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x400000 action 0x2 frozen
SErr is reporting handshake error (R_ERR seen) diagnostic bit but not
reporting any error bit.
> ata1.00: (BMDMA stat 0x20)
> ata1.00: tag 0 cmd 0xc8 Emask 0x2 stat 0x58 err 0x0 (HSM violation)
DMA engine off and DRDY && DRQ. Again, looks like data transmission
error but considering data transmission direction is from device to host
(READ_DMA), the error status is confusing.
> ata1: soft resetting port
> ata1: port is slow to respond, please be patient
> ata1: port failed to respond (30 secs)
prereset saw set DRDY this time but after SRST, BSY is stuck at 1.
> ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> ATA: abnormal status 0xD8 on port 0xDCA44087
> ATA: abnormal status 0xD8 on port 0xDCA44087
> ATA: abnormal status 0xD8 on port 0xDCA44087
> ATA: abnormal status 0xD8 on port 0xDCA44087
> ATA: abnormal status 0xD8 on port 0xDCA44087
> ata1.00: qc timeout (cmd 0xec)
We should really fail softreset if ata_busy_sleep() fails in
ata_bus_post_reset(). In this case, softreset reports success after
timeout causing the following revalidation to timeout too.
> ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
> ata1.00: revalidation failed (errno=-5)
> ata1: failed to recover some devices, retrying in 5 secs
> ata1: hard resetting port
> ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> ata1.00: configured for UDMA/100
> ata1: EH complete
hardreset successfully recovers the device.
Hmmm.. with the patch, sata_sil should have tried hardreset at the first
time in the second case. There's our third weirdity. I think the
problem can be worked around by...
1. having shorter timeout value on READ/WRITE commands. 30s is *way*
too long.
2. making reset procedure more intelligent. There's no reason to wait
full 30s before and after softreset if it's not for hotplug. It should
switch to hardreset if the device doesn't respond in several secs.
Being responsive && giving device enough time eventually shouldn't be
too difficult.
#1 shouldn't be difficult but we need to be careful. #2 might take some
time to implement.
I'm not sure why the previous patch didn't kick in. The condition
should have been caught and EH_HARDRESET requested. Can you please
double check the patched kernel is running? You can put a little
printk() after the freeze: label in sil_interrupt() to be sure. That's
the only place where sata_sil freezes the port except for timing out.
Thanks.
--
tejun
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: [sata_sil] kernel 2.6.17(-mm2) test - timeout issue
2006-08-06 15:51 ` Tejun Heo
@ 2006-08-14 10:12 ` Martin Ammermüller
0 siblings, 0 replies; 7+ messages in thread
From: Martin Ammermüller @ 2006-08-14 10:12 UTC (permalink / raw)
To: Tejun Heo; +Cc: linux-ide, jgarzik
[-- Attachment #1: Type: text/plain, Size: 1464 bytes --]
Am Montag, den 07.08.2006, 00:51 +0900 schrieb Tejun Heo:
> I'm not sure why the previous patch didn't kick in. The condition
> should have been caught and EH_HARDRESET requested. Can you please
> double check the patched kernel is running? You can put a little
> printk() after the freeze: label in sil_interrupt() to be sure. That's
> the only place where sata_sil freezes the port except for timing out.
Sorry, i couldn't find a "freeze" label in sil_interrupt(). There's one
in sil_host_intr(), but appending 'printk("THIS VERSION RUNS MODIFIED
sata_sil!");' there, doesn't have any effect (it doesn't get printed
out). That's the only freeze label i found in sata_sil.c. My original
drivers/scsi/sata_sil.c was version 2.6.18-rc1.
'strings /lib/modules/2.6.18-rc1/kernel/drivers/scsi/sata_sil.ko'
doesn't give any unexpected results:
----------------------
[...]
<7>%s %s: version 2.0
<3>ata%u: XXX: qc=%p ctl=%x
<3>ata%u: XXX: HSM=%d
<6>ata%u.%02u: applying Seagate errata fix (mod15write workaround)
<6>ata%u.%02u: applying Maxtor errata fix %s
<4>%s %s: cache line size not set. Driver may not function
<6>%s %s: Applying R_ERR on DMA activate FIS errata fix
<3>ata%u: XXX: SATA_IRQ serror=%x
<3>ata%u: XXX: BUSY status=%x
THIS VERSION RUNS MODIFIED sata_sil!
[...]
------------------------
The kernel was built with Debian's make-kpkg (make-kpkg --initrd
kernel_image). Output is still the same.
- Martin
[-- Attachment #2: Dies ist ein digital signierter Nachrichtenteil --]
[-- Type: application/pgp-signature, Size: 191 bytes --]
^ permalink raw reply [flat|nested] 7+ messages in thread
end of thread, other threads:[~2006-08-14 10:11 UTC | newest]
Thread overview: 7+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2006-06-24 20:50 [sata_sil] kernel 2.6.17(-mm2) test - timeout issue Martin Ammermüller
2006-06-25 3:06 ` Tejun Heo
2006-07-24 8:21 ` Martin Ammermüller
2006-07-30 20:22 ` Tejun Heo
2006-08-05 13:36 ` Martin Ammermüller
2006-08-06 15:51 ` Tejun Heo
2006-08-14 10:12 ` Martin Ammermüller
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).