lkml.org 
[lkml]   [2009]   [May]   [23]   [last100]   RSS Feed
Views: [wrap][no wrap]   [headers]  [forward] 
 
Messages in this thread
    /
    Date
    From
    SubjectRe: 2.6.29 regression: ATA bus errors on resume (output with debug patch)
    On 05/23/2009 11:17 AM, Niel Lambrechts wrote:
    > On 04/09/2009 08:18 PM, Tejun Heo wrote:
    >> Hmm... none of the debugging messages got triggered, so there should
    >> be no noticeable timing change. It seems like you're being lucky for
    >> the time being. Can you please keep testing and report back?
    >
    > Hi Tejun,
    >
    > Just to keep you up informed - this issue is still happening, I've
    > been testing the latest 2.6.30-git kernels the last couple of weeks
    > and just checked my logs after having an unexpected fsck upon system
    > boot - the freeze seems to trigger very infrequently though, since I
    > use s2disk at least twice a day and the error only seems to have
    > happened twice since May 2.
    >
    > I had a look at your 2.6.29 debug patch, it does "Hunk #6 succeeded at
    > 3368" so it should still be good (I hope), I'll try and run with it
    > for a while.

    Hi Tejun

    Bug triggered with your patch! I played audio while suspending to try
    and increase activity (I also removed a CD on boot), and the filesystem
    came up dirty! This was on attempt nr. 3 or 4.

    Here is the /var/log/messages output - i hope this is sufficient to
    trace down the cause:
    May 23 12:15:08 linux-7vph kernel: PM: Shrinking memory... done (98443
    pages freed)
    May 23 12:15:07 linux-7vph pulseaudio[3781]: alsa-util.c: period_time
    : 185759
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: tstamp_mode
    : ENABLE
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: period_step : 1
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: avail_min
    : 15503
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: period_event : 0
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c:
    start_threshold : -1
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c:
    stop_threshold : 1073741824
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c:
    silence_threshold: 0
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: silence_size : 0
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: boundary
    : 1073741824
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: Slave:
    Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: Its setup is:
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: stream
    : PLAYBACK
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: access
    : MMAP_INTERLEAVED
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: format
    : S16_LE
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: subformat
    : STD
    May 23 12:15:08 linux-7vph kernel: PM: Freed 393772 kbytes in 3.23
    seconds (121.91 MB/s)
    May 23 12:15:09 linux-7vph kernel: Suspending console(s) (use
    no_console_suspend to debug)
    May 23 12:15:09 linux-7vph kernel: sd 0:0:0:0: [sda] Synchronizing SCSI
    cache
    May 23 12:15:09 linux-7vph kernel: ACPI handle has no context!
    May 23 12:15:09 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0
    SErr 0x0 action 0x0
    May 23 12:15:09 linux-7vph kernel: ata2: exception Emask 0x0 SAct 0x0
    SErr 0x0 action 0x0
    May 23 12:15:09 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D disabled
    May 23 12:15:09 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
    May 23 12:15:09 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C disabled
    May 23 12:15:09 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B disabled
    May 23 12:15:09 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A disabled
    May 23 12:15:09 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B
    disabled
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D disabled
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C disabled
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B disabled
    May 23 12:15:08 linux-7vph pulseaudio[3781]: alsa-util.c: channels : 2
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: rate
    : 44100
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: exact rate
    : 44100 (44100/1)
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A disabled
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: msbits
    : 16
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A disabled
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: buffer_size
    : 16384
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: period_size
    : 8192
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: period_time
    : 185759
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: tstamp_mode
    : ENABLE
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: period_step : 1
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: avail_min
    : 15503
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: period_event : 0
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PME# enabled
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c:
    start_threshold : -1
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: wake-up
    capability enabled by ACPI
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c:
    stop_threshold : 1073741824
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c:
    silence_threshold: 0
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: silence_size : 0
    May 23 12:15:10 linux-7vph kernel: ACPI: Preparing to enter system sleep
    state S4
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-util.c: boundary
    : 1073741824
    May 23 12:15:10 linux-7vph kernel: PM: Saving platform NVS memory
    May 23 12:15:10 linux-7vph kernel: Disabling non-boot CPUs ...
    May 23 12:15:10 linux-7vph kernel: CPU 1 is now offline
    May 23 12:15:10 linux-7vph kernel: SMP alternatives: switching to UP code
    May 23 12:15:10 linux-7vph pulseaudio[3781]: alsa-sink.c: Increasing
    wakeup watermark to 40.00 ms
    May 23 12:15:10 linux-7vph kernel: CPU0 attaching NULL sched-domain.
    May 23 12:15:10 linux-7vph kernel: CPU1 attaching NULL sched-domain.
    May 23 12:15:10 linux-7vph kernel: CPU0 attaching NULL sched-domain.
    May 23 12:15:10 linux-7vph kernel: CPU1 is down
    May 23 12:15:10 linux-7vph kernel: Extended CMOS year: 2000
    May 23 12:15:10 linux-7vph kernel: PM: Creating hibernation image:
    May 23 12:15:10 linux-7vph kernel: PM: Need to copy 124750 pages
    May 23 12:15:10 linux-7vph kernel: x86 PAT enabled: cpu 0, old
    0x7040600070406, new 0x7010600070106
    May 23 12:15:10 linux-7vph kernel: Intel machine check architecture
    supported.
    May 23 12:15:10 linux-7vph kernel: Intel machine check reporting enabled
    on CPU#0.
    May 23 12:15:10 linux-7vph kernel: PM: Restoring platform NVS memory
    May 23 12:15:10 linux-7vph kernel: Extended CMOS year: 2000
    May 23 12:15:10 linux-7vph kernel: Enabling non-boot CPUs ...
    May 23 12:15:10 linux-7vph kernel: SMP alternatives: switching to SMP code
    May 23 12:15:10 linux-7vph kernel: Booting processor 1 APIC 0x1 ip 0x6000
    May 23 12:15:10 linux-7vph kernel: Initializing CPU#1
    May 23 12:15:10 linux-7vph kernel: Calibrating delay using timer
    specific routine.. 5054.05 BogoMIPS (lpj=10108117)
    May 23 12:15:10 linux-7vph kernel: CPU: L1 I cache: 32K, L1 D cache: 32K
    May 23 12:15:10 linux-7vph kernel: CPU: L2 cache: 6144K
    May 23 12:15:10 linux-7vph kernel: CPU: Physical Processor ID: 0
    May 23 12:15:10 linux-7vph kernel: CPU: Processor Core ID: 1
    May 23 12:15:10 linux-7vph kernel: Intel machine check architecture
    supported.
    May 23 12:15:10 linux-7vph kernel: Intel machine check reporting enabled
    on CPU#1.
    May 23 12:15:10 linux-7vph kernel: x86 PAT enabled: cpu 1, old
    0x7040600070406, new 0x7010600070106
    May 23 12:15:10 linux-7vph kernel: CPU1: Intel(R) Core(TM)2 Duo CPU
    T9400 @ 2.53GHz stepping 06
    May 23 12:15:10 linux-7vph kernel: CPU0 attaching NULL sched-domain.
    May 23 12:15:10 linux-7vph kernel: Switched to high resolution mode on CPU 1
    May 23 12:15:10 linux-7vph kernel: CPU0 attaching sched-domain:
    May 23 12:15:10 linux-7vph kernel: domain 0: span 0-1 level MC
    May 23 12:15:10 linux-7vph kernel: groups: 0 1
    May 23 12:15:10 linux-7vph kernel: domain 1: span 0-1 level CPU
    May 23 12:15:10 linux-7vph kernel: groups: 0-1 (__cpu_power = 2048)
    May 23 12:15:10 linux-7vph kernel: CPU1 attaching sched-domain:
    May 23 12:15:10 linux-7vph kernel: domain 0: span 0-1 level MC
    May 23 12:15:10 linux-7vph kernel: groups: 1 0
    May 23 12:15:10 linux-7vph kernel: domain 1: span 0-1 level CPU
    May 23 12:15:10 linux-7vph kernel: groups: 0-1 (__cpu_power = 2048)
    May 23 12:15:10 linux-7vph kernel: microcode: failed to init CPU1
    May 23 12:15:10 linux-7vph kernel: CPU1 is up
    May 23 12:15:10 linux-7vph kernel: ACPI: Waking up from system sleep
    state S4
    May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: restoring config
    space at offset 0x1 (was 0x900007, writing 0x900403)
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.0: power state
    changed by ACPI to D0
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.2: power state
    changed by ACPI to D0
    May 23 12:15:10 linux-7vph kernel: HDA Intel 0000:00:1b.0: restoring
    config space at offset 0x1 (was 0x100106, writing 0x100102)
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.0: power state
    changed by ACPI to D0
    May 23 12:15:10 linux-7vph kernel: ahci 0000:00:1f.2: restoring config
    space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
    May 23 12:15:10 linux-7vph kernel: iwlagn 0000:03:00.0: restoring config
    space at offset 0x1 (was 0x100106, writing 0x100506)
    May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: PME# disabled
    May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: setting latency
    timer to 64
    May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.1: PME# disabled
    May 23 12:15:10 linux-7vph kernel: pci 0000:00:03.0: PME# disabled
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PCI INT A -> GSI
    20 (level, low) -> IRQ 20
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0:
    pci_enable_pcie_error_reporting failed 0xfffffffb
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: setting latency
    timer to 64
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: wake-up
    capability disabled by ACPI
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: wake-up
    capability disabled by ACPI
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: PME# disabled
    May 23 12:15:10 linux-7vph kernel: e1000e 0000:00:19.0: irq 29 for MSI/MSI-X
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.0: PCI INT A ->
    GSI 20 (level, low) -> IRQ 20
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.0: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.1: PCI INT B ->
    GSI 21 (level, low) -> IRQ 21
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.1: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.2: PCI INT C ->
    GSI 22 (level, low) -> IRQ 22
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1a.2: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PCI INT D ->
    GSI 23 (level, low) -> IRQ 23
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1a.7: PME# disabled
    May 23 12:15:10 linux-7vph kernel: HDA Intel 0000:00:1b.0: PCI INT B ->
    GSI 17 (level, low) -> IRQ 17
    May 23 12:15:10 linux-7vph kernel: HDA Intel 0000:00:1b.0: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.0: PCI INT A ->
    GSI 16 (level, low) -> IRQ 16
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.0: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.1: PCI INT B ->
    GSI 17 (level, low) -> IRQ 17
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.1: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.2: PCI INT C ->
    GSI 18 (level, low) -> IRQ 18
    May 23 12:15:10 linux-7vph kernel: uhci_hcd 0000:00:1d.2: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PCI INT D ->
    GSI 19 (level, low) -> IRQ 19
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: setting
    latency timer to 64
    May 23 12:15:10 linux-7vph kernel: ehci_hcd 0000:00:1d.7: PME# disabled
    May 23 12:15:10 linux-7vph kernel: pci 0000:00:1e.0: setting latency
    timer to 64
    May 23 12:15:10 linux-7vph kernel: ahci 0000:00:1f.2: setting latency
    timer to 64
    May 23 12:15:10 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0
    SErr 0x0 action 0x16 frozen
    May 23 12:15:10 linux-7vph kernel: ata2: exception Emask 0x0 SAct 0x0
    SErr 0x0 action 0x16 frozen
    May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.0: PCI INT A -> GSI 16
    (level, low) -> IRQ 16
    May 23 12:15:10 linux-7vph kernel: ohci1394: fw-host0: OHCI-1394 1.1
    (PCI): IRQ=[17] MMIO=[f4801000-f48017ff] Max Packet=[2048] IR/IT
    contexts=[4/4]
    May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.2: PME# disabled
    May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.3: PME# disabled
    May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.4: PME# disabled
    May 23 12:15:10 linux-7vph kernel: pci 0000:15:00.5: PME# disabled
    May 23 12:15:10 linux-7vph kernel: sd 0:0:0:0: [sda] Starting disk
    May 23 12:15:10 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus
    113 SControl 300)
    May 23 12:15:10 linux-7vph kernel: ata2: SATA link up 1.5 Gbps (SStatus
    113 SControl 300)
    May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd
    ef/02:00:00:00:00:a0 succeeded
    May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd
    f5/00:00:00:00:00:a0 filtered out
    May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd
    ef/5f:00:00:00:00:a0 succeeded
    May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd
    ef/10:03:00:00:00:a0 filtered out
    May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd
    ef/02:00:00:00:00:a0 succeeded
    May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd
    f5/00:00:00:00:00:a0 filtered out
    May 23 12:15:10 linux-7vph kernel: ata2.00: ACPI cmd
    e3/00:1f:00:00:00:a0 succeeded
    May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd
    ef/5f:00:00:00:00:a0 succeeded
    May 23 12:15:10 linux-7vph kernel: ata1.00: ACPI cmd
    ef/10:03:00:00:00:a0 filtered out
    May 23 12:15:10 linux-7vph kernel: ata2.00: ACPI cmd
    e3/00:02:00:00:00:a0 succeeded
    May 23 12:15:10 linux-7vph kernel: ata1.00: configured for UDMA/133
    May 23 12:15:10 linux-7vph kernel: ata1: exception Emask 0x10 SAct 0x0
    SErr 0x0 action 0x9 t4
    May 23 12:15:10 linux-7vph kernel: ata1: irq_stat 0x00400040, connection
    status changed
    May 23 12:15:10 linux-7vph kernel: ata1.00: configured for UDMA/133
    May 23 12:15:10 linux-7vph kernel: ata1: EH complete
    May 23 12:15:10 linux-7vph kernel: ata2.00: ACPI cmd
    e3/00:1f:00:00:00:a0 succeeded
    May 23 12:15:10 linux-7vph kernel: ata2.00: ACPI cmd
    e3/00:02:00:00:00:a0 succeeded
    May 23 12:15:10 linux-7vph kernel: ata2.00: configured for UDMA/133
    May 23 12:15:10 linux-7vph kernel: ata2: exception Emask 0x10 SAct 0x0
    SErr 0x0 action 0x9 t4
    May 23 12:15:10 linux-7vph kernel: ata2: irq_stat 0x40000001
    May 23 12:15:10 linux-7vph kernel: ata2.00: configured for UDMA/133
    May 23 12:15:10 linux-7vph kernel: ata2: EH complete
    May 23 12:15:10 linux-7vph kernel: Registered led device: iwl-phy0::radio
    May 23 12:15:10 linux-7vph kernel: Registered led device: iwl-phy0::assoc
    May 23 12:15:10 linux-7vph kernel: Registered led device: iwl-phy0::RX
    May 23 12:15:10 linux-7vph kernel: Registered led device: iwl-phy0::TX
    May 23 12:15:10 linux-7vph kernel: mac80211-phy0: failed to set key (0,
    00:1d:92:1d:1e:8e) to hardware (-22)
    May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: restoring config
    space at offset 0x1 (was 0x900407, writing 0x900403)
    May 23 12:15:10 linux-7vph kernel: pci 0000:00:02.0: setting latency
    timer to 64
    May 23 12:15:10 linux-7vph kernel: Restarting tasks ... <3>ata1.00:
    exception Emask 0x10 SAct 0x1f SErr 0x50000 action 0xe frozen
    May 23 12:15:10 linux-7vph kernel: ata1.00: irq_stat 0x00400008, PHY RDY
    changed
    May 23 12:15:10 linux-7vph kernel: ata1: SError: { PHYRdyChg CommWake }
    May 23 12:15:10 linux-7vph kernel: ata1.00: cmd
    60/08:00:5f:89:67/01:00:0e:00:00/40 tag 0 ncq 135168 in
    May 23 12:15:10 linux-7vph kernel: res
    50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
    May 23 12:15:10 linux-7vph kernel: ata1.00: status: { DRDY }
    May 23 12:15:10 linux-7vph kernel: ata1.00: cmd
    60/08:08:6f:88:6f/01:00:0e:00:00/40 tag 1 ncq 135168 in
    May 23 12:15:10 linux-7vph kernel: res
    50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
    May 23 12:15:10 linux-7vph kernel: ata1.00: status: { DRDY }
    May 23 12:15:10 linux-7vph kernel: ata1.00: cmd
    60/08:10:5f:08:c8/00:00:0c:00:00/40 tag 2 ncq 4096 in
    May 23 12:15:10 linux-7vph kernel: res
    50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
    May 23 12:15:10 linux-7vph kernel: ata1.00: status: { DRDY }
    May 23 12:15:10 linux-7vph kernel: ata1.00: cmd
    60/08:18:9f:98:6d/00:00:0e:00:00/40 tag 3 ncq 4096 in
    May 23 12:15:10 linux-7vph kernel: res
    50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
    May 23 12:15:11 linux-7vph kernel: ata1.00: status: { DRDY }
    May 23 12:15:11 linux-7vph kernel: ata1.00: cmd
    60/e8:20:5f:8e:2b/00:00:0b:00:00/40 tag 4 ncq 118784 in
    May 23 12:15:11 linux-7vph kernel: res
    50/00:08:5f:08:c8/00:00:0c:00:00/40 Emask 0x10 (ATA bus error)
    May 23 12:15:11 linux-7vph kernel: ata1.00: status: { DRDY }
    May 23 12:15:11 linux-7vph kernel: ata1: hard resetting link
    May 23 12:15:11 linux-7vph kernel: done.
    May 23 12:15:11 linux-7vph kernel: ata1: SATA link up 1.5 Gbps (SStatus
    113 SControl 300)
    May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd
    ef/02:00:00:00:00:a0 succeeded
    May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd
    f5/00:00:00:00:00:a0 filtered out
    May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd
    ef/5f:00:00:00:00:a0 succeeded
    May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd
    ef/10:03:00:00:00:a0 filtered out
    May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd
    ef/02:00:00:00:00:a0 succeeded
    May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd
    f5/00:00:00:00:00:a0 filtered out
    May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd
    ef/5f:00:00:00:00:a0 succeeded
    May 23 12:15:11 linux-7vph kernel: ata1.00: ACPI cmd
    ef/10:03:00:00:00:a0 filtered out
    May 23 12:15:11 linux-7vph kernel: ata1.00: configured for UDMA/133
    May 23 12:15:11 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0
    SErr 0x0 action 0x0 t4
    May 23 12:15:11 linux-7vph kernel: ata1.00: configured for UDMA/133
    May 23 12:15:11 linux-7vph kernel: XXX scsi_eh_flush_done_q: online=1(2)
    noretry=2 retries=0 allowed=5
    May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush finish cmd: f6838ec0
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Result:
    hostbyte=DID_OK driverbyte=DRIVER_SENSE
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted
    Command [current] [descriptor]
    May 23 12:15:11 linux-7vph kernel: Descriptor sense data with sense
    descriptors (in hex):
    May 23 12:15:11 linux-7vph kernel: 72 0b 00 00 00 00 00 0c 00 0a
    80 00 00 00 00 00
    May 23 12:15:11 linux-7vph kernel: 0c c8 08 5f
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No
    additional sense information
    May 23 12:15:11 linux-7vph kernel: end_request: I/O error, dev sda,
    sector 241666399
    May 23 12:15:11 linux-7vph kernel: XXX scsi_eh_flush_done_q: online=1(2)
    noretry=2 retries=0 allowed=5
    May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush finish cmd: f6838680
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Result:
    hostbyte=DID_OK driverbyte=DRIVER_SENSE
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted
    Command <2>EXT4-fs error (device sda6): __ext4_get_inode_loc: unable to
    read inode block - inode=2330983, block=9338933
    May 23 12:15:11 linux-7vph kernel: [current] [descriptor]
    May 23 12:15:11 linux-7vph kernel: Descriptor sense data with sense
    descriptors (in hex):
    May 23 12:15:11 linux-7vph kernel: 72 0b 00 00 00 00 00 0c 00 0a
    80 00 00 00 00 00
    May 23 12:15:11 linux-7vph kernel: 0c c8 08 5f
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No
    additional sense information
    May 23 12:15:11 linux-7vph kernel: end_request: I/O error, dev sda,
    sector 242190447
    May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6):
    __ext4_get_inode_loc: XXX scsi_eh_flush_done_q: online=1(2) noretry=0
    retries=0 allowed=5
    May 23 12:15:11 linux-7vph kernel: unable to read inode block -
    inode=2346524, block=9404418
    May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush retry cmd: f6838800
    May 23 12:15:11 linux-7vph kernel: XXX scsi_eh_flush_done_q: online=1(2)
    noretry=0 retries=0 allowed=5
    May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush retry cmd: f403de00
    May 23 12:15:11 linux-7vph kernel: XXX scsi_eh_flush_done_q: online=1(2)
    noretry=2 retries=0 allowed=5
    May 23 12:15:11 linux-7vph kernel: scsi_eh_0: flush finish cmd: f403d200
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Result:
    hostbyte=DID_OK driverbyte=DRIVER_SENSE
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Sense Key : Aborted
    Command [current] [descriptor]
    May 23 12:15:11 linux-7vph kernel: Descriptor sense data with sense
    descriptors (in hex):
    May 23 12:15:11 linux-7vph kernel: 72 0b 00 00 00 00 00 0c 00 0a
    80 00 00 00 00 00
    May 23 12:15:11 linux-7vph kernel: 0c c8 08 5f
    May 23 12:15:11 linux-7vph kernel: sd 0:0:0:0: [sda] Add. Sense: No
    additional sense information
    May 23 12:15:11 linux-7vph kernel: end_request: I/O error, dev sda,
    sector 187403871
    May 23 12:15:11 linux-7vph kernel: ata1: EH complete
    May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6):
    __ext4_get_inode_loc: unable to read inode block - inode=641187,
    block=2556122
    May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6) in
    ext4_reserve_inode_write: IO failure
    May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6) in
    ext4_reserve_inode_write: IO failure
    May 23 12:15:11 linux-7vph kernel: EXT4-fs error (device sda6) in
    ext4_reserve_inode_write: IO failure
    May 23 12:16:50 linux-7vph kernel: ata1: exception Emask 0x0 SAct 0x0
    SErr 0x0 action 0x10
    May 23 12:16:50 linux-7vph kernel: CPU0 attaching NULL sched-domain.
    May 23 12:16:50 linux-7vph kernel: CPU1 attaching NULL sched-domain.
    May 23 12:16:50 linux-7vph kernel: CPU0 attaching sched-domain:
    May 23 12:16:50 linux-7vph kernel: domain 0: span 0-1 level MC
    May 23 12:16:50 linux-7vph kernel: groups: 0 1
    May 23 12:16:50 linux-7vph kernel: domain 1: span 0-1 level CPU
    May 23 12:16:50 linux-7vph kernel: groups: 0-1 (__cpu_power = 2048)
    May 23 12:16:50 linux-7vph kernel: CPU1 attaching sched-domain:
    May 23 12:16:50 linux-7vph kernel: domain 0: span 0-1 level MC
    May 23 12:16:50 linux-7vph kernel: groups: 1 0
    May 23 12:16:50 linux-7vph kernel: domain 1: span 0-1 level CPU
    May 23 12:16:50 linux-7vph kernel: groups: 0-1 (__cpu_power = 2048)


    Regards,
    Niel


    \
     
     \ /
      Last update: 2009-05-23 12:31    [W:0.083 / U:59.228 seconds]
    ©2003-2016 Jasper Spaans. hosted at Digital OceanAdvertise on this site