Bug#475033: linux-image-2.6.18-6-686: DVD burning stops. Kernel stack trace in ide_do_request+0x69e.
Package: linux-image-2.6.18-6-686
Version: 2.6.18.dfsg.1-18etch1
Severity: normal
The system has a single IDE connector on its motherboard. Connected to
this are two DVD burners used for weekly backups. The first week, both
drives successfully complete their burn. The following week, the first
drive completes its burn, but the second drive stops making progress
approx 80% of the way through. So far, this has happened exactly the same
on both backup attempts, but it is only two data points. Reproducing the
problem is also somewhat problematic since it does impact their normal
production, but I can probably squezze in attempts in a delayed fashion.
The first time this happened, the site person simply reset the machine
without informing so I have no further data from that event other than
the problem description.
The second time this happened, I did 'echo t >/proc/sysrq-trigger' Here
is the stack trace from the offending process.
Apr 6 17:23:39 crc-server1 kernel: growisofs D 00000000 0 9231 9525 9233 (NOTLB)
Apr 6 17:23:39 crc-server1 kernel: e7387b98 00200086 0442fef6 00000000 f88f87b2 0000000a eaebd000 df8b2550
Apr 6 17:23:39 crc-server1 kernel: 9b7a52b7 00000a85 0000ec6d 00000000 eaebd110 c2812140 00000000 ffffffff
Apr 6 17:23:39 crc-server1 kernel: 00200086 c0125380 c29dc4e4 c2812184 00200086 c0125824 00200086 e7387c38
Apr 6 17:23:39 crc-server1 kernel: Call Trace:
Apr 6 17:23:39 crc-server1 kernel: [<f88f87b2>] ide_do_request+0x69e/0x711 [ide_core]
Apr 6 17:23:39 crc-server1 kernel: [<c0125380>] lock_timer_base+0x15/0x2f
Apr 6 17:23:39 crc-server1 kernel: [<c0125824>] del_timer+0x41/0x47
Apr 6 17:23:39 crc-server1 kernel: [<c027f31a>] wait_for_completion+0x6a/0x9f
Apr 6 17:23:39 crc-server1 kernel: [<c0117778>] default_wake_function+0x0/0xc
Apr 6 17:23:39 crc-server1 kernel: [<c01ae506>] blk_execute_rq+0x85/0x9f
Apr 6 17:23:39 crc-server1 kernel: [<c01adc9f>] blk_end_sync_rq+0x0/0x1d
Apr 6 17:23:39 crc-server1 kernel: [<c0143f54>] mempool_alloc+0x1c/0xbd
Apr 6 17:23:39 crc-server1 kernel: [<c01b6186>] cfq_set_request+0x0/0x326
Apr 6 17:23:39 crc-server1 kernel: [<c01abad9>] elv_set_request+0x18/0x27
Apr 6 17:23:39 crc-server1 kernel: [<c01adefd>] get_request+0x160/0x28a
Apr 6 17:23:39 crc-server1 kernel: [<c01ae539>] get_request_wait+0x19/0xcd
Apr 6 17:23:39 crc-server1 kernel: [<c01b15b5>] sg_io+0x20c/0x2dd
Apr 6 17:23:39 crc-server1 kernel: [<f886d79c>] ata_qc_issue_prot+0xd9/0x1fd [libata]
Apr 6 17:23:39 crc-server1 kernel: [<c01b1abc>] scsi_cmd_ioctl+0x1a5/0x37d
Apr 6 17:23:39 crc-server1 kernel: [<c011669e>] __wake_up+0x2a/0x3d
Apr 6 17:23:39 crc-server1 kernel: [<f892cc7b>] cdrom_ioctl+0x22/0xb21 [cdrom]
Apr 6 17:23:39 crc-server1 kernel: [<c0142dcf>] generic_file_buffered_write+0x4bc/0x5ea
Apr 6 17:23:39 crc-server1 kernel: [<f894bf3e>] idecd_ioctl+0x12d/0x142 [ide_cd]
Apr 6 17:23:39 crc-server1 kernel: [<f89cd67c>] journal_stop+0x1b0/0x1ba [jbd]
Apr 6 17:23:39 crc-server1 kernel: [<f8a095b3>] __ext3_journal_stop+0x19/0x34 [ext3]
Apr 6 17:23:39 crc-server1 kernel: [<c01afe6f>] blkdev_driver_ioctl+0x4b/0x5b
Apr 6 17:23:39 crc-server1 kernel: [<c01b0485>] blkdev_ioctl+0x606/0x655
Apr 6 17:23:39 crc-server1 kernel: [<c01307ec>] wake_futex+0x3a/0x44
Apr 6 17:23:39 crc-server1 kernel: [<c0143366>] generic_file_aio_write+0x60/0xaa
Apr 6 17:23:39 crc-server1 kernel: [<f8a00e6d>] ext3_file_write+0x19/0x83 [ext3]
Apr 6 17:23:39 crc-server1 kernel: [<c0159cfe>] do_sync_write+0xb6/0xf1
Apr 6 17:23:39 crc-server1 kernel: [<c014b1de>] do_wp_page+0x12b/0x348
Apr 6 17:23:39 crc-server1 kernel: [<c012d92d>] autoremove_wake_function+0x0/0x2d
Apr 6 17:23:39 crc-server1 kernel: [<c01603f9>] block_ioctl+0x13/0x16
Apr 6 17:23:39 crc-server1 kernel: [<c01603e6>] block_ioctl+0x0/0x16
Apr 6 17:23:39 crc-server1 kernel: [<c0169298>] do_ioctl+0x1c/0x5d
Apr 6 17:23:39 crc-server1 kernel: [<c0169523>] vfs_ioctl+0x24a/0x25c
Apr 6 17:23:39 crc-server1 kernel: [<c016957d>] sys_ioctl+0x48/0x5f
Apr 6 17:23:39 crc-server1 kernel: [<c0102c7b>] syscall_call+0x7/0xb
Here is lspci -v output:
crc-server1:~# lspci -v
00:00.0 Host bridge: Intel Corporation E7230 Memory Controller Hub
Subsystem: Dell Unknown device 01e7
Flags: bus master, fast devsel, latency 0
Capabilities: [e0] Vendor Specific Information
00:01.0 PCI bridge: Intel Corporation E7230 PCI Express Root Port (prog-if 00 [Normal decode])
Flags: bus master, fast devsel, latency 0
Bus: primary=00, secondary=01, subordinate=01, sec-latency=0
Capabilities: [88] Subsystem: Dell Unknown device 01e7
Capabilities: [80] Power Management version 2
Capabilities: [90] Message Signalled Interrupts: Mask- 64bit- Queue=0/0 Enable+
Capabilities: [a0] Express Root Port (Slot+) IRQ 0
Capabilities: [100] Virtual Channel
Capabilities: [140] Unknown (5)
00:1c.0 PCI bridge: Intel Corporation 82801G (ICH7 Family) PCI Express Port 1 (rev 01) (prog-if 00 [Normal decode])
Flags: bus master, fast devsel, latency 0
Bus: primary=00, secondary=02, subordinate=03, sec-latency=0
I/O behind bridge: 0000e000-0000efff
Memory behind bridge: fe800000-feafffff
Prefetchable memory behind bridge: 00000000c0000000-00000000c00fffff
Capabilities: [40] Express Root Port (Slot-) IRQ 0
Capabilities: [80] Message Signalled Interrupts: Mask- 64bit- Queue=0/0 Enable+
Capabilities: [90] Subsystem: Dell Unknown device 01e7
Capabilities: [a0] Power Management version 2
Capabilities: [100] Virtual Channel
Capabilities: [180] Unknown (5)
00:1c.4 PCI bridge: Intel Corporation 82801GR/GH/GHM (ICH7 Family) PCI Express Port 5 (rev 01) (prog-if 00 [Normal decode])
Flags: bus master, fast devsel, latency 0
Bus: primary=00, secondary=04, subordinate=04, sec-latency=0
I/O behind bridge: 0000d000-0000dfff
Memory behind bridge: fe600000-fe7fffff
Capabilities: [40] Express Root Port (Slot-) IRQ 0
Capabilities: [80] Message Signalled Interrupts: Mask- 64bit- Queue=0/0 Enable+
Capabilities: [90] Subsystem: Dell Unknown device 01e7
Capabilities: [a0] Power Management version 2
Capabilities: [100] Virtual Channel
Capabilities: [180] Unknown (5)
00:1c.5 PCI bridge: Intel Corporation 82801GR/GH/GHM (ICH7 Family) PCI Express Port 6 (rev 01) (prog-if 00 [Normal decode])
Flags: bus master, fast devsel, latency 0
Bus: primary=00, secondary=05, subordinate=05, sec-latency=0
Capabilities: [40] Express Root Port (Slot+) IRQ 0
Capabilities: [80] Message Signalled Interrupts: Mask- 64bit- Queue=0/0 Enable+
Capabilities: [90] Subsystem: Dell Unknown device 01e7
Capabilities: [a0] Power Management version 2
Capabilities: [100] Virtual Channel
Capabilities: [180] Unknown (5)
00:1d.0 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI #1 (rev 01) (prog-if 00 [UHCI])
Subsystem: Dell Unknown device 01e7
Flags: bus master, medium devsel, latency 0, IRQ 50
I/O ports at bce0 [size=32]
00:1d.1 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI #2 (rev 01) (prog-if 00 [UHCI])
Subsystem: Dell Unknown device 01e7
Flags: bus master, medium devsel, latency 0, IRQ 177
I/O ports at bcc0 [size=32]
00:1d.2 USB Controller: Intel Corporation 82801G (ICH7 Family) USB UHCI #3 (rev 01) (prog-if 00 [UHCI])
Subsystem: Dell Unknown device 01e7
Flags: bus master, medium devsel, latency 0, IRQ 185
I/O ports at bca0 [size=32]
00:1d.7 USB Controller: Intel Corporation 82801G (ICH7 Family) USB2 EHCI Controller (rev 01) (prog-if 20 [EHCI])
Subsystem: Dell Unknown device 01e7
Flags: bus master, medium devsel, latency 0, IRQ 50
Memory at feb00400 (32-bit, non-prefetchable) [size=1K]
Capabilities: [50] Power Management version 2
Capabilities: [58] Debug port
00:1e.0 PCI bridge: Intel Corporation 82801 PCI Bridge (rev e1) (prog-if 01 [Subtractive decode])
Flags: bus master, fast devsel, latency 0
Bus: primary=00, secondary=06, subordinate=06, sec-latency=32
I/O behind bridge: 0000c000-0000cfff
Memory behind bridge: fe400000-fe5fffff
Prefetchable memory behind bridge: 00000000e8000000-00000000efffffff
Capabilities: [50] Subsystem: Dell Unknown device 01e7
00:1f.0 ISA bridge: Intel Corporation 82801GB/GR (ICH7 Family) LPC Interface Bridge (rev 01)
Subsystem: Dell Unknown device 01e7
Flags: bus master, medium devsel, latency 0
Capabilities: [e0] Vendor Specific Information
00:1f.1 IDE interface: Intel Corporation 82801G (ICH7 Family) IDE Controller (rev 01) (prog-if 8a [Master SecP PriP])
Subsystem: Dell Unknown device 01e7
Flags: bus master, medium devsel, latency 0
I/O ports at <unassigned>
I/O ports at <unassigned>
I/O ports at <unassigned>
I/O ports at <unassigned>
I/O ports at fc00 [size=16]
00:1f.2 IDE interface: Intel Corporation 82801GB/GR/GH (ICH7 Family) Serial ATA Storage Controller IDE (rev 01) (prog-if 8f [Master SecP SecO PriP PriO])
Subsystem: Dell Unknown device 01e7
Flags: bus master, 66MHz, medium devsel, latency 0, IRQ 50
I/O ports at bc98 [size=8]
I/O ports at bc90 [size=4]
I/O ports at bc80 [size=8]
I/O ports at bc78 [size=4]
I/O ports at bc60 [size=16]
Memory at feb00000 (32-bit, non-prefetchable) [size=1K]
Capabilities: [70] Power Management version 2
00:1f.3 SMBus: Intel Corporation 82801G (ICH7 Family) SMBus Controller (rev 01)
Subsystem: Dell Unknown device 01e7
Flags: medium devsel
I/O ports at 08c0 [size=32]
02:00.0 PCI bridge: Intel Corporation 6702PXH PCI Express-to-PCI Bridge A (rev 09) (prog-if 00 [Normal decode])
Flags: bus master, fast devsel, latency 0
Bus: primary=02, secondary=03, subordinate=03, sec-latency=32
I/O behind bridge: 0000e000-0000efff
Memory behind bridge: fe900000-feafffff
Prefetchable memory behind bridge: 00000000c0000000-00000000c00fffff
Capabilities: [44] Express PCI/PCI-X Bridge IRQ 0
Capabilities: [5c] Message Signalled Interrupts: Mask- 64bit+ Queue=0/0 Enable-
Capabilities: [6c] Power Management version 2
Capabilities: [d8] PCI-X bridge device
Capabilities: [100] Advanced Error Reporting
Capabilities: [300] Power Budgeting
03:01.0 Mass storage controller: Promise Technology, Inc. PDC40718 (SATA 300 TX4) (rev 02)
Subsystem: Promise Technology, Inc. PDC40718 (SATA 300 TX4)
Flags: bus master, 66MHz, medium devsel, latency 72, IRQ 58
I/O ports at ec80 [size=128]
I/O ports at e800 [size=256]
Memory at fe9ff000 (32-bit, non-prefetchable) [size=4K]
Memory at fe9c0000 (32-bit, non-prefetchable) [size=128K]
Expansion ROM at fea00000 [disabled] [size=32K]
Capabilities: [60] Power Management version 2
03:02.0 Ethernet controller: ADMtek NC100 Network Everywhere Fast Ethernet 10/100 (rev 11)
Subsystem: ADMtek Unknown device 0574
Flags: bus master, medium devsel, latency 32, IRQ 66
I/O ports at e400 [size=256]
Memory at fe9fec00 (32-bit, non-prefetchable) [size=1K]
Expansion ROM at c0000000 [disabled] [size=128K]
Capabilities: [c0] Power Management version 2
04:00.0 Ethernet controller: Broadcom Corporation NetXtreme BCM5721 Gigabit Ethernet PCI Express (rev 11)
Subsystem: Dell Unknown device 01e7
Flags: bus master, fast devsel, latency 0, IRQ 169
Memory at fe6f0000 (64-bit, non-prefetchable) [size=64K]
Capabilities: [48] Power Management version 2
Capabilities: [50] Vital Product Data
Capabilities: [58] Message Signalled Interrupts: Mask- 64bit+ Queue=0/3 Enable-
Capabilities: [d0] Express Endpoint IRQ 0
Capabilities: [100] Advanced Error Reporting
Capabilities: [13c] Virtual Channel
06:00.0 Ethernet controller: D-Link System Inc RTL8139 Ethernet (rev 10)
Subsystem: D-Link System Inc DFE-530TX+ 10/100 Ethernet Adapter
Flags: bus master, medium devsel, latency 32, IRQ 74
I/O ports at cc00 [size=256]
Memory at fe4ffc00 (32-bit, non-prefetchable) [size=256]
Capabilities: [50] Power Management version 2
06:05.0 VGA compatible controller: ATI Technologies Inc ES1000 (rev 02) (prog-if 00 [VGA])
Subsystem: Dell Unknown device 01e7
Flags: bus master, VGA palette snoop, stepping, medium devsel, latency 32, IRQ 11
Memory at e8000000 (32-bit, prefetchable) [size=128M]
I/O ports at c800 [size=256]
Memory at fe4e0000 (32-bit, non-prefetchable) [size=64K]
[virtual] Expansion ROM at fe400000 [disabled] [size=128K]
Capabilities: [50] Power Management version 2
lsmod from the following boot (forgot to grab it on the particular boot).
crc-server1:~# lsmod
Module Size Used by
vmnet 33020 5
vmmon 1799468 5
nfs 202828 0
nfsd 197936 17
exportfs 5600 1 nfsd
button 6672 0
ppdev 8676 0
lockd 54344 3 nfs,nfsd
parport_pc 32132 0
ac 5188 0
lp 11012 0
parport 33256 3 ppdev,parport_pc,lp
nfs_acl 3584 2 nfs,nfsd
battery 9636 0
sunrpc 138812 13 nfs,nfsd,lockd,nfs_acl
ipv6 226272 40
xt_state 2272 2
xt_tcpudp 3136 3
iptable_filter 3104 1
ipt_MASQUERADE 3712 1
iptable_nat 7044 1
ip_nat 16876 2 ipt_MASQUERADE,iptable_nat
ip_conntrack 49088 4
xt_state,ipt_MASQUERADE,iptable_nat,ip_nat
nfnetlink 6680 2 ip_nat,ip_conntrack
ip_tables 13028 2 iptable_filter,iptable_nat
x_tables 13316 5
xt_state,xt_tcpudp,ipt_MASQUERADE,iptable_nat,ip_tables
xfs 475768 8
loop 15048 0
tsdev 7520 0
serio_raw 6660 0
floppy 53156 0
i2c_i801 7468 0
psmouse 35016 0
rtc 12372 0
i2c_core 19680 1 i2c_i801
shpchp 33024 0
pci_hotplug 28704 1 shpchp
pcspkr 3072 0
evdev 9088 1
ext3 119240 5
jbd 52456 1 ext3
mbcache 8356 1 ext3
dm_mirror 19152 0
dm_snapshot 15552 0
dm_mod 50232 27 dm_mirror,dm_snapshot
raid456 115472 1
xor 14216 1 raid456
raid1 21056 1
md_mod 70388 4 raid456,raid1
ide_generic 1408 0 [permanent]
usbhid 37248 1
ide_cd 36064 0
cdrom 32544 1 ide_cd
usb_storage 72000 0
generic 4868 0 [permanent]
sd_mod 19040 16
8139too 25120 0
mii 5344 1 8139too
ehci_hcd 28136 0
tulip 46560 0
tg3 94948 0
ata_piix 13896 8
sata_promise 11620 2
libata 89396 2 ata_piix,sata_promise
scsi_mod 124168 3 usb_storage,sd_mod,libata
piix 9444 0 [permanent]
ide_core 110504 5
ide_generic,ide_cd,usb_storage,generic,piix
uhci_hcd 21164 0
usbcore 112644 6 usbhid,usb_storage,ehci_hcd,uhci_hcd
thermal 13608 0
processor 28840 1 thermal
fan 4804 0
Early part of the boot prior to this hang:
Mar 22 11:17:03 crc-server1 kernel: Linux version 2.6.18-6-686 (Debian 2.6.18.dfsg.1-18etch1) (waldi@debian.org) (gcc version 4.1.2 20061115 (prerelease) (Debian 4.1.1-21)) #1 SMP Sun Feb 10 22:11:31 UTC 2008
...
Mar 22 11:17:03 crc-server1 kernel: Kernel command line: root=/dev/mapper/VG00-ROOT ro
...
Mar 22 11:17:03 crc-server1 kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
Mar 22 11:17:03 crc-server1 kernel: ICH7: IDE controller at PCI slot 0000:00:1f.1
Mar 22 11:17:03 crc-server1 kernel: ACPI: Unable to derive IRQ for device 0000:00:1f.1
Mar 22 11:17:03 crc-server1 kernel: ACPI: PCI Interrupt 0000:00:1f.1[A]: no GSI
Mar 22 11:17:03 crc-server1 kernel: ICH7: chipset revision 1
Mar 22 11:17:03 crc-server1 kernel: ICH7: not 100%% native mode: will probe irqs later
Mar 22 11:17:03 crc-server1 kernel: ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:DMA, hdb:DMA
Mar 22 11:17:03 crc-server1 kernel: Probing IDE interface ide0...
...
Mar 22 11:17:03 crc-server1 kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
...
Mar 22 11:17:03 crc-server1 kernel: hda: ATAPI 48X DVD-ROM DVD-R CD-R/RW drive, 2048kB Cache, UDMA(33)
Mar 22 11:17:03 crc-server1 kernel: hdb: ATAPI 126X DVD-ROM DVD-R-RAM CD-R/RW drive, 2048kB Cache, UDMA(33)
Part of the dmidecode output:
...
BIOS Information
Vendor: Dell Computer Corporation
Version: A05
Release Date: 10/04/2007
...
System Information
Manufacturer: Dell Computer Corporation
Product Name: PowerEdge 840
-- System Information:
Debian Release: 4.0
APT prefers stable
APT policy: (990, 'stable'), (500, 'unstable')
Architecture: i386 (i686)
Shell: /bin/sh linked to /bin/bash
Kernel: Linux 2.6.18-6-686
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Versions of packages linux-image-2.6.18-6-686 depends on:
ii coreutils 5.97-5.3 The GNU core utilities
ii debconf [debconf-2.0] 1.5.11etch1 Debian configuration management sy
ii initramfs-tools [linux-initr 0.85h tools for generating an initramfs
ii module-init-tools 3.3-pre4-2 tools for managing Linux kernel mo
Versions of packages linux-image-2.6.18-6-686 recommends:
pn libc6-i686 <none> (no description available)
-- debconf information:
shared/kernel-image/really-run-bootloader: true
linux-image-2.6.18-6-686/preinst/elilo-initrd-2.6.18-6-686: true
linux-image-2.6.18-6-686/preinst/already-running-this-2.6.18-6-686:
linux-image-2.6.18-6-686/postinst/depmod-error-2.6.18-6-686: false
linux-image-2.6.18-6-686/preinst/initrd-2.6.18-6-686:
linux-image-2.6.18-6-686/postinst/old-initrd-link-2.6.18-6-686: true
linux-image-2.6.18-6-686/preinst/bootloader-initrd-2.6.18-6-686: true
linux-image-2.6.18-6-686/preinst/abort-install-2.6.18-6-686:
linux-image-2.6.18-6-686/preinst/lilo-has-ramdisk:
linux-image-2.6.18-6-686/preinst/overwriting-modules-2.6.18-6-686: true
linux-image-2.6.18-6-686/postinst/bootloader-error-2.6.18-6-686:
linux-image-2.6.18-6-686/prerm/would-invalidate-boot-loader-2.6.18-6-686: true
linux-image-2.6.18-6-686/postinst/bootloader-test-error-2.6.18-6-686:
linux-image-2.6.18-6-686/postinst/create-kimage-link-2.6.18-6-686: true
linux-image-2.6.18-6-686/postinst/depmod-error-initrd-2.6.18-6-686: false
linux-image-2.6.18-6-686/preinst/lilo-initrd-2.6.18-6-686: true
linux-image-2.6.18-6-686/postinst/old-dir-initrd-link-2.6.18-6-686: true
linux-image-2.6.18-6-686/preinst/failed-to-move-modules-2.6.18-6-686:
linux-image-2.6.18-6-686/preinst/abort-overwrite-2.6.18-6-686:
linux-image-2.6.18-6-686/prerm/removing-running-kernel-2.6.18-6-686: true
linux-image-2.6.18-6-686/postinst/old-system-map-link-2.6.18-6-686: true
linux-image-2.6.18-6-686/postinst/kimage-is-a-directory:
Reply to: