ata loses interrupt, system freezes

[tz]

Binary package hint: kernel-package

(I reported this more briefly earlier, but here is an apport version)

On lucid Beta-1, the hard drive keeps freezing for about 10 seconds, then restarts. It is intermittent and I can't cause it. The cpu goes to 100% iowait on both cored, then works.

It does not seem to be power-management related as it happened out of bootup as the attached dmesg shows.

This is on an HP 1151NR netbook.

[ 41.021350] Adding 2618552k swap on /dev/sda5. Priority:-1 extents:1 across:2618552k
[ 41.093067] udev: starting version 151
[ 41.344213] lib80211: common routines for IEEE802.11 drivers
[ 41.344223] lib80211_crypt: registered algorithm 'NULL'
[ 41.369912] lp: driver loaded but no devices found
[ 41.445155] intel_rng: FWH not detected
[ 41.635866] EXT3 FS on sda7, internal journal
[ 41.699993] Bluetooth: Core ver 2.15
[ 41.704419] NET: Registered protocol family 31
[ 41.704427] Bluetooth: HCI device and connection manager initialized
[ 41.704436] Bluetooth: HCI socket layer initialized
[ 41.732633] Bluetooth: Generic Bluetooth USB driver ver 0.6
[ 41.733020] usbcore: registered new interface driver btusb
[ 41.826981] wl: module license 'MIXED/Proprietary' taints kernel.
[ 41.826993] Disabling lock debugging due to kernel taint
[ 41.833966] Linux video capture interface: v2.00
[ 41.899287] wl 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 41.899310] wl 0000:01:00.0: setting latency timer to 64
[ 41.930194] uvcvideo: Found UVC 1.00 device Webcam-101 (05c8:0202)
[ 41.953565] input: Webcam-101 as /devices/pci0000:00/0000:00:1d.7/usb1/1-4/1-4:1.0/input/input7
[ 41.953999] usbcore: registered new interface driver uvcvideo
[ 41.954013] USB Video Class driver (v0.1.0)
[ 41.989733] lib80211_crypt: registered algorithm 'TKIP'
[ 41.990091] eth1: Broadcom BCM4315 802.11 Hybrid Wireless Controller 5.60.48.36
[ 41.993642] type=1505 audit(1270479009.579:2): operation="profile_load" pid=604 name="/sbin/dhclient3"
[ 41.994430] type=1505 audit(1270479009.579:3): operation="profile_load" pid=604 name="/usr/lib/NetworkManager/nm-dhcp-client.action"
[ 41.994893] type=1505 audit(1270479009.579:4): operation="profile_load" pid=604 name="/usr/lib/connman/scripts/dhclient-script"
[ 42.476190] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 42.476270] HDA Intel 0000:00:1b.0: setting latency timer to 64
[ 42.501953] type=1505 audit(1270479010.088:5): operation="profile_load" pid=626 name="/usr/sbin/ntpd"
[ 42.540831] Synaptics Touchpad, model: 1, fw: 7.2, id: 0x1c0b1, caps: 0xd04711/0xa00000
[ 42.569967] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1b.0/input/input8
[ 42.577498] input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio4/input/input9
[ 42.596664] input: HDA Intel Line In at Ext Rear Jack as /devices/pci0000:00/0000:00:1b.0/sound/card0/input10
[ 42.600691] input: HDA Intel Mic at Sep Rear Jack as /devices/pci0000:00/0000:00:1b.0/sound/card0/input11
[ 42.602675] input: HDA Intel Mic at Ext Rear Jack as /devices/pci0000:00/0000:00:1b.0/sound/card0/input12
[ 42.603601] input: HDA Intel HP Out at Ext Rear Jack as /devices/pci0000:00/0000:00:1b.0/sound/card0/input13
[ 45.192678] kjournald starting. Commit interval 5 seconds
[ 45.193144] EXT3 FS on sda8, internal journal
[ 45.193158] EXT3-fs: mounted filesystem with ordered data mode.
[ 51.384927] type=1505 audit(1270479018.971:6): operation="profile_load" pid=842 name="/usr/sbin/avahi-daemon"
[ 51.389693] type=1505 audit(1270479018.975:7): operation="profile_load" pid=843 name="/bin/ping"
[ 51.394571] type=1505 audit(1270479018.979:8): operation="profile_load" pid=846 name="/usr/share/gdm/guest-session/Xsession"
[ 51.405352] type=1505 audit(1270479018.991:9): operation="profile_replace" pid=849 name="/sbin/dhclient3"
[ 51.406191] type=1505 audit(1270479018.991:10): operation="profile_replace" pid=849 name="/usr/lib/NetworkManager/nm-dhcp-client.action"
[ 51.406680] type=1505 audit(1270479018.991:11): operation="profile_replace" pid=849 name="/usr/lib/connman/scripts/dhclient-script"
[ 51.423109] type=1505 audit(1270479019.007:12): operation="profile_load" pid=851 name="/sbin/klogd"
[ 51.442189] type=1505 audit(1270479019.027:13): operation="profile_load" pid=852 name="/sbin/syslog-ng"
[ 51.453851] type=1505 audit(1270479019.039:14): operation="profile_load" pid=855 name="/sbin/syslogd"
[ 51.474613] type=1505 audit(1270479019.059:15): operation="profile_load" pid=856 name="/usr/bin/evince"
[ 51.754771] sky2 eth0: enabling interface
[ 51.759942] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 54.124685] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
[ 54.124694] apm: disabled - APM is not SMP safe.
[ 58.281047] ppdev: user-space parallel port driver
[ 61.853023] eth1: no IPv6 routers present
[ 128.816265] ata1: lost interrupt (Status 0x58)
[ 128.820169] ata1: drained 32768 bytes to clear DRQ.
[ 128.915617] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 128.915636] ata1.00: BMDMA stat 0x24
[ 128.915654] ata1.00: failed command: READ DMA
[ 128.915688] ata1.00: cmd c8/00:08:1a:c8:63/00:00:00:00:00/e8 tag 0 dma 4096 in
[ 128.915696] res 58/00:08:1a:c8:63/00:00:00:00:00/e8 Emask 0x2 (HSM violation)
[ 128.915715] ata1.00: status: { DRDY DRQ }
[ 128.915785] ata1: soft resetting link
[ 129.140605] ata1.00: configured for UDMA/100
[ 129.140643] ata1: EH complete
[ 143.636018] ata1: drained 32768 bytes to clear DRQ.
[ 143.728736] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 143.728746] ata1.00: BMDMA stat 0x24
[ 143.728757] ata1.00: failed command: READ DMA
[ 143.728776] ata1.00: cmd c8/00:08:b2:7a:33/00:00:00:00:00/e6 tag 0 dma 4096 in
[ 143.728780] res 58/00:08:b2:7a:33/00:00:00:00:00/e6 Emask 0x2 (HSM violation)
[ 143.728789] ata1.00: status: { DRDY DRQ }
[ 143.728837] ata1: soft resetting link
[ 143.910978] ata1.00: configured for UDMA/100
[ 143.911015] ata1: EH complete
[ 216.816149] ata1: lost interrupt (Status 0x58)
[ 216.907397] ata1: drained 32768 bytes to clear DRQ.
[ 216.915802] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 216.915822] ata1.00: BMDMA stat 0x24
[ 216.915840] ata1.00: failed command: READ DMA
[ 216.915875] ata1.00: cmd c8/00:10:62:05:1e/00:00:00:00:00/e9 tag 0 dma 8192 in
[ 216.915882] res 58/00:10:62:05:1e/00:00:00:00:00/e9 Emask 0x2 (HSM violation)
[ 216.915901] ata1.00: status: { DRDY DRQ }
[ 216.915971] ata1: soft resetting link
[ 217.096461] ata1.00: configured for UDMA/100
[ 217.096505] ata1: EH complete
[ 247.816122] ata1: lost interrupt (Status 0x58)
[ 247.820021] ata1: drained 32768 bytes to clear DRQ.
[ 247.915435] ata1.00: limiting speed to UDMA/66:PIO4
[ 247.915457] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 247.915480] ata1.00: failed command: READ DMA
[ 247.915515] ata1.00: cmd c8/00:10:62:05:1e/00:00:00:00:00/e9 tag 0 dma 8192 in
[ 247.915523] res 40/00:10:62:05:1e/00:00:00:00:00/e9 Emask 0x4 (timeout)
[ 247.915540] ata1.00: status: { DRDY }
[ 247.915609] ata1: soft resetting link
[ 248.092596] ata1.00: configured for UDMA/66
[ 248.092641] ata1: EH complete
[ 261.067847] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 261.067968] ata1.00: BMDMA stat 0x25
[ 261.068082] ata1.00: failed command: WRITE DMA
[ 261.068167] ata1.00: cmd ca/00:08:9a:c9:a7/00:00:00:00:00/e8 tag 0 dma 4096 out
[ 261.068171] res 58/00:07:9b:c9:a7/00:00:00:00:00/e8 Emask 0x2 (HSM violation)
[ 261.068388] ata1.00: status: { DRDY DRQ }
[ 261.068496] ata1: soft resetting link
[ 261.524436] ata1.00: configured for UDMA/66
[ 261.524471] ata1: EH complete
[ 263.389647] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 263.389767] ata1.00: BMDMA stat 0x25
[ 263.389831] ata1.00: failed command: WRITE DMA EXT
[ 263.389919] ata1.00: cmd 35/00:70:7a:13:50/00:02:08:00:00/e0 tag 0 dma 319488 out
[ 263.389924] res 58/00:6f:7b:13:50/00:02:08:00:00/e0 Emask 0x2 (HSM violation)
[ 263.390140] ata1.00: status: { DRDY DRQ }
[ 263.390247] ata1: soft resetting link
[ 263.568404] ata1.00: configured for UDMA/66
[ 263.568438] ata1: EH complete
[ 305.842822] ata1: drained 32768 bytes to clear DRQ.
[ 305.938198] ata1.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen
[ 305.938219] ata1.00: BMDMA stat 0x24
[ 305.938237] ata1.00: failed command: READ DMA
[ 305.938274] ata1.00: cmd c8/00:08:92:71:65/00:00:00:00:00/e7 tag 0 dma 4096 in
[ 305.938283] res 58/00:08:92:71:65/00:02:08:00:00/e7 Emask 0x2 (HSM violation)
[ 305.938302] ata1.00: status: { DRDY DRQ }
[ 305.938377] ata1: soft resetting link
[ 306.116635] ata1.00: configured for UDMA/66
[ 306.116679] ata1: EH complete

ProblemType: Bug
DistroRelease: Ubuntu 10.04
Package: kernel-package 12.032
ProcVersionSignature: Ubuntu 2.6.32-18.27-generic 2.6.32.10+drm33.1
Uname: Linux 2.6.32-18-generic i686
NonfreeKernelModules: wl
Architecture: i386
Date: Mon Apr 5 07:56:31 2010
PackageArchitecture: all
ProcEnviron:
 PATH=(custom, user)
 LANG=en_US.UTF-8
 SHELL=/bin/bash
SourcePackage: kernel-package

[tz (thomas-mich)]

Notes:

1. it seemed to happen when I inserted a thumb drive - it wouldn't recognize the thumb drive and initiated this ATA lockup.

2. SpinRite detected no bad sectors or even any correctable areas for the given partition on the disk. Jaunty run from the thumb drive doesn't seem to show the freezes.

3, It may be something very intermittent so it is possible that these are false negatives, however I've changed it to invalid since it might be a hardware problem - the disk might be loose.