FAQ Search Today's Posts Mark Forums Read
» Video Reviews

» Linux Archive

Linux-archive is a website aiming to archive linux email lists and to make them easily accessible for linux users/developers.


» Sponsor

» Partners

» Sponsor

Go Back   Linux Archive > Debian > Debian Kernel

 
 
LinkBack Thread Tools
 
Old 06-23-2008, 06:41 PM
Valentin QUEQUET
 
Default Bug#487725: linux-image-2.6.25-2-amd64: Got much trouble and message 'ehci_hcd: HC died' when scanning image from 'Canon PIXMA MP150'.

Package: linux-image-2.6.25-2-amd64
Version: 2.6.25-5
Severity: normal


Hello, all.

While scanning (with SANE) a document from my 'Canon PIXMA MP150' scanner/printer combo, I stumbled upon a grave problem.

I discovered that my configuration - out-of-the-box up-to-date lenny - was using module 'ehci_hcd' (possibly amongst others) to dialog with my scanner.

And I discovered that there was some activity on IRQ_7, which was 'listened-to' by module 'ehci_hcd', when I queried scanner list typing:
scanimage -L
and when I put my scanner ON or OFF.

I was able to query this list 3 times without a problem ; this step did no hurt.

But a grave problem suddenly arose when I typed:
scanimage -T
which is a scanner/communication test which consist in scanning a full line - equivalent to effectively scanning a small part of the document.

Not only the test failed, but 3 more very bad things happened:
- I got alarming messages on console (and in 'dmesg' below) : "ehci_hdc: ... HC died", and kernel said it would forget about IRQ_7 (which 'ehci_hcd' was 'listening-to' earlier).
- I became unable to query the list of scanners anymore : the scanner ceased to respond.
- The kernel no longer notified (eg on console) when I put my scanner OFF, ON, and OFF again.

Fortunately, I discovered that in this situation, I had just to unload module 'ehci_hcd' to get my scanner+SANE functional (including scanner power ON/OFF notifications).

But a few points draw my attention:

I retried this scenario, implying scanner OFF, PC reboot, delay, scanner ON.

A few times the scenario showed to be exactly like I described above.

While the other times, symptoms of the bug (alarming messages + unability to communicate with the scanner) happened merely whenever I chose to put my scanner OFF (alarm) and ON (no comm.) instead of doing the scanner test (via scanimage -T).

Again, unloading module 'ehci_hcd' got my scanner+SANE functional.

I found very strange that IRQ_7 would just be used for some handchecking and not for the transfer phase.
But it's obvious: 'ehci_hcd', which was the sole user of IRQ_7, succeeded at handchecking and broke on scanning/transfer attempts.

So, when I unloaded 'ehci_hcd', the transfer phase likely falled back to some non-IRQ (polling) method.

I was sorry that my new Debian pre-packaged linux-image-2.6.25-2-amd64 (ver 2.6.25-5) made my scanning experience so tricky.

And I asked myself what it would have been if module 'echi_hcd' didn't broke. Would 'ehci_hcd' be usefull after all ?
Would IRQ_7 be used ? With many interruptions in scanning/transfer phase ? And would transfers get faster ?

So, I decided to repeat the whole thing with Debian pre-packaged linux-image-2.6.24-1-amd64 (ver 2.6.24-7).
You can't believe it ! Scanning (scanner+SANE) functioned right out of the box, without having root to unload module 'ehci_hcd' (it was effectively loaded).

And I was witnessing an intensive use of IRQ_7 by module 'ehci_hcd' ; transfers were similar than with 2.6.25 : certainly no far from perfect.

And whenever I put my scanner ON or OFF, I got the matching notifications on console and in dmesg.

And whenever I wanted to scan some paper, all was good and perfect.

To help you understand what happened, and in the case the piece of dmesg below would not be enough, I give you a pointer to my full dmesg log:
http://pagesperso-orange.fr/mandolosse/logs/2008-06-23__dmesg__ehci_hcd__died.txt

I also captured 'scanimage', 'lsmod' output and snapshots of '/proc/interrupts' at different times. I plan to scrutinize all this data, to comment the relevant parts and to post them later.

In hope my report will prove useful.

Sincerely,
Valentin QUEQUET

-- Package-specific info:
** Version:
Linux version 2.6.25-2-amd64 (Debian 2.6.25-5) (waldi@debian.org) (gcc version 4.1.3 20080420 (prerelease) (Debian 4.1.2-22)) #1 SMP Thu Jun 12 15:38:32 UTC 2008

** Command line:
BOOT_IMAGE=252k8c ro root=1641 acpi=off bootkbd=fr

** Not tainted

** Kernel log:
[ 26.186050] sd 4:0:0:0: [sdb] Attached SCSI removable disk
[ 26.226053] sd 4:0:0:1: [sdc] Attached SCSI removable disk
[ 26.235133] sd 4:0:0:2: [sdd] Attached SCSI removable disk
[ 26.257114] sd 4:0:0:3: [sde] Attached SCSI removable disk
[ 28.474762] loop: AES key scrubbing enabled
[ 28.474762] loop: loaded (max 8 devices)
[ 84.509321] fuse init (API version 7.9)
[ 84.649334] ReiserFS: hdd2: found reiserfs format "3.6" with standard journal
[ 84.649334] ReiserFS: hdd2: using ordered data mode
[ 84.649334] ReiserFS: hdd2: journal params: device hdd2, size 8125, journal first block 66, max trans len 256, max batch 225, max commit age 30, max trans age 30
[ 84.650872] ReiserFS: hdd2: checking transaction log (hdd2)
[ 84.709413] ReiserFS: hdd2: Using r5 hash to sort names
[ 84.927097] NTFS driver 2.1.29 [Flags: R/W MODULE].
[ 84.996773] NTFS volume version 3.1.
[ 84.996819] NTFS-fs warning (device sda1): load_system_files(): Unsupported volume flags 0x4000 encountered.
[ 84.996878] NTFS-fs warning (device sda1): load_system_files(): Volume has unsupported flags set. Will not be able to remount read-write. Run chkdsk and mount in Windows.
[ 87.293651] NET: Registered protocol family 10
[ 87.294374] lo: Disabled Privacy Extensions
[ 93.486573] pnp: the driver 'parport_pc' has been registered
[ 93.489315] lp: driver loaded but no devices found
[ 93.530623] ppdev: user-space parallel port driver
[ 97.754310] eth0: no IPv6 routers present
[ 98.738434] device eth0 entered promiscuous mode
[ 108.534468] warning: `avahi-daemon' uses 32-bit capabilities (legacy support in use)
[ 110.505302] Bluetooth: Core ver 2.11
[ 110.507060] NET: Registered protocol family 31
[ 110.507109] Bluetooth: HCI device and connection manager initialized
[ 110.507155] Bluetooth: HCI socket layer initialized
[ 110.549312] Bluetooth: L2CAP ver 2.9
[ 110.549312] Bluetooth: L2CAP socket layer initialized
[ 110.583071] Bluetooth: RFCOMM socket layer initialized
[ 110.583132] Bluetooth: RFCOMM TTY layer initialized
[ 110.583174] Bluetooth: RFCOMM ver 1.8
[ 361.692993] gdm[3674]: segfault at 800c23f48 ip 7f9a35f4a9cf sp 7fff4178d8a0 error 6 in libglib-2.0.so.0.1600.3[7f9a35ef4000+bb000]
[ 509.595914] usb 2-5: new high speed USB device using ehci_hcd and address 4
[ 509.731927] usb 2-5: configuration #1 chosen from 1 choice
[ 509.731927] usb 2-5: New USB device found, idVendor=04a9, idProduct=1709
[ 509.731927] usb 2-5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 509.731927] usb 2-5: Product: MP150
[ 509.731927] usb 2-5: Manufacturer: Canon
[ 509.731927] usb 2-5: SerialNumber: 62E00F
[ 509.821451] usblp0: USB Bidirectional printer dev 4 if 1 alt 0 proto 2 vid 0x04A9 pid 0x1709
[ 509.821532] usbcore: registered new interface driver usblp
[ 568.106335] usb 2-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 576.820736] usb 2-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 599.432848] usb 2-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 613.438078] usb 2-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 613.439381] usb 2-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 613.618140] ehci_hcd 0000:00:0b.1: HC died; cleaning up
[ 613.622140] usb 2-5: USB disconnect, address 4
[ 613.622140] usblp0: removed
[ 753.425626] irq 7: nobody cared (try booting with the "irqpoll" option)
[ 753.425685] Pid: 0, comm: swapper Not tainted 2.6.25-2-amd64 #1
[ 753.425729]
[ 753.425730] Call Trace:
[ 753.425805] <IRQ> [<ffffffff8026ca2f>] __report_bad_irq+0x30/0x72
[ 753.425896] [<ffffffff8026cc6e>] note_interrupt+0x1fd/0x23f
[ 753.425944] [<ffffffff8026d4fb>] handle_fasteoi_irq+0xa5/0xc8
[ 753.425991] [<ffffffff8020f41c>] do_IRQ+0x6d/0xd9
[ 753.426035] [<ffffffff8020c34d>] ret_from_intr+0x0/0x19
[ 753.426077] <EOI> [<ffffffff802206e4>] native_safe_halt+0x2/0x3
[ 753.426156] [<ffffffff8020ae96>] default_idle+0x3b/0x6e
[ 753.426199] [<ffffffff8020ae5b>] default_idle+0x0/0x6e
[ 753.426242] [<ffffffff8020af52>] cpu_idle+0x89/0xb3
[ 753.426295]
[ 753.426332] handlers:
[ 753.426370] [<ffffffff80388a58>] (usb_hcd_irq+0x0/0x51)
[ 753.426465] Disabling IRQ #7
[ 909.869608] ehci_hcd 0000:00:0b.1: remove, state 0
[ 909.869666] usb usb2: USB disconnect, address 1
[ 909.870026] ehci_hcd 0000:00:0b.1: USB bus 2 deregistered
[ 985.440223] usb 1-5: new full speed USB device using ohci_hcd and address 4
[ 985.652254] usb 1-5: configuration #1 chosen from 1 choice
[ 985.660917] usblp0: USB Bidirectional printer dev 4 if 1 alt 0 proto 2 vid 0x04A9 pid 0x1709
[ 985.661021] usb 1-5: New USB device found, idVendor=04a9, idProduct=1709
[ 985.661066] usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 985.661111] usb 1-5: Product: MP150
[ 985.661151] usb 1-5: Manufacturer: Canon
[ 985.661191] usb 1-5: SerialNumber: 62E00F
[ 1066.493582] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1121.621664] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1122.989919] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1123.002483] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1121.745183] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1145.424243] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1145.436247] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1192.279838] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1217.393738] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1217.406051] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1288.529388] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1289.938854] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1289.952693] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1370.843262] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1399.100915] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1399.117534] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1589.445166] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1621.725262] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1621.736912] usb 1-5: usbfs: interface 1 claimed by usblp while 'scanimage' sets config #1
[ 1840.468886] usb 1-5: USB disconnect, address 4
[ 1840.468886] usblp0: removed

** Loaded modules:
Module Size Used by
usblp 19840 0
binfmt_misc 17164 1
rfcomm 46112 2
l2cap 29696 9 rfcomm
bluetooth 65444 4 rfcomm,l2cap
ppdev 13832 0
parport_pc 34344 0
lp 17540 0
parport 44848 3 ppdev,parport_pc,lp
ipv6 294120 18
nls_cp437 11392 1
vfat 17920 1
fat 57016 1 vfat
nls_utf8 6528 2
ntfs 197760 1
nls_base 13444 5 nls_cp437,vfat,fat,nls_utf8,ntfs
fuse 53440 1
dm_crypt 20488 0
crypto_blkcipher 24324 1 dm_crypt
firewire_sbp2 22808 0
loop 69524 0
usb_storage 94400 0
usbhid 49632 0
hid 44736 1 usbhid
ff_memless 10248 1 usbhid
snd_hda_intel 414296 1
snd_pcm_oss 46752 0
snd_mixer_oss 21376 2 snd_pcm_oss
snd_pcm 88072 2 snd_hda_intel,snd_pcm_oss
snd_timer 29328 1 snd_pcm
snd 66888 5 snd_hda_intel,snd_pcm_oss,snd_mixer_oss,snd_pcm,sn d_timer
firewire_ohci 27908 0
firewire_core 45920 2 firewire_sbp2,firewire_ohci
psmouse 45724 0
soundcore 13088 2 snd
serio_raw 11652 0
crc_itu_t 6656 1 firewire_core
pcspkr 7808 0
snd_page_alloc 15120 2 snd_hda_intel,snd_pcm
k8temp 10624 0
forcedeth 55692 0
ohci_hcd 28292 0
i2c_nforce2 11904 0
i2c_core 30752 1 i2c_nforce2
evdev 17408 1
reiserfs 224384 2
dm_mirror 31748 0
dm_snapshot 22472 0
dm_mod 68536 3 dm_crypt,dm_mirror,dm_snapshot
raid10 27392 0
raid456 129824 0
async_xor 7424 1 raid456
async_memcpy 6400 1 raid456
async_tx 7168 1 raid456
xor 10128 2 raid456,async_xor
raid1 28032 0
raid0 12032 0
multipath 13440 0
linear 10112 0
md_mod 87204 6 raid10,raid456,raid1,raid0,multipath,linear
sd_mod 33728 3
sata_nv 32520 2
ide_cd_mod 41504 0
cdrom 39464 1 ide_cd_mod
ide_pci_generic 9476 0 [permanent]
amd74xx 13576 0 [permanent]
ata_generic 13572 0
libata 166064 2 sata_nv,ata_generic
scsi_mod 170360 4 firewire_sbp2,usb_storage,sd_mod,libata
dock 16288 1 libata
ide_disk 20096 3
ide_core 138160 4 ide_cd_mod,ide_pci_generic,amd74xx,ide_disk

** PCI devices:
00:00.0 RAM memory [0500]: nVidia Corporation C51 Host Bridge [10de:02f0] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Capabilities: <access denied>

00:00.1 RAM memory [0500]: nVidia Corporation C51 Memory Controller 0 [10de:02fa] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap- 66MHz+ UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:00.2 RAM memory [0500]: nVidia Corporation C51 Memory Controller 1 [10de:02fe] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz+ UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:00.3 RAM memory [0500]: nVidia Corporation C51 Memory Controller 5 [10de:02f8] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap- 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:00.4 RAM memory [0500]: nVidia Corporation C51 Memory Controller 4 [10de:02f9] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap- 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0

00:00.5 RAM memory [0500]: nVidia Corporation C51 Host Bridge [10de:02ff] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Capabilities: <access denied>

00:00.6 RAM memory [0500]: nVidia Corporation C51 Memory Controller 3 [10de:027f] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap- 66MHz+ UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:00.7 RAM memory [0500]: nVidia Corporation C51 Memory Controller 2 [10de:027e] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz+ UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:02.0 PCI bridge [0604]: nVidia Corporation C51 PCI Express Bridge [10de:02fc] (rev a1)
Control: I/O- Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Bus: primary=00, secondary=01, subordinate=01, sec-latency=0
Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR-
BridgeCtl: Parity- SERR+ NoISA- VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>
Kernel driver in use: pcieport-driver
Kernel modules: shpchp

00:03.0 PCI bridge [0604]: nVidia Corporation C51 PCI Express Bridge [10de:02fd] (rev a1)
Control: I/O- Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Bus: primary=00, secondary=02, subordinate=02, sec-latency=0
Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR-
BridgeCtl: Parity- SERR+ NoISA- VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>
Kernel driver in use: pcieport-driver
Kernel modules: shpchp

00:04.0 PCI bridge [0604]: nVidia Corporation C51 PCI Express Bridge [10de:02fb] (rev a1)
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Bus: primary=00, secondary=03, subordinate=03, sec-latency=0
Memory behind bridge: fc000000-feafffff
Prefetchable memory behind bridge: 00000000d0000000-00000000dfffffff
Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort+ <SERR- <PERR-
BridgeCtl: Parity- SERR+ NoISA- VGA+ MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>
Kernel driver in use: pcieport-driver
Kernel modules: shpchp

00:09.0 RAM memory [0500]: nVidia Corporation MCP51 Host Bridge [10de:0270] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Capabilities: <access denied>

00:0a.0 ISA bridge [0601]: nVidia Corporation MCP51 LPC Bridge [10de:0260] (rev a3)
Subsystem: nVidia Corporation Device [10de:cb84]
Control: I/O+ Mem+ BusMaster+ SpecCycle+ MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0

00:0a.1 SMBus [0c05]: nVidia Corporation MCP51 SMBus [10de:0264] (rev a3)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O+ Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Interrupt: pin A routed to IRQ 5
Region 4: I/O ports at 5000 [size=64]
Region 5: I/O ports at 6000 [size=64]
Capabilities: <access denied>
Kernel driver in use: nForce2_smbus
Kernel modules: i2c-nforce2

00:0b.0 USB Controller [0c03]: nVidia Corporation MCP51 USB Controller [10de:026d] (rev a3) (prog-if 10)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0 (750ns min, 250ns max)
Interrupt: pin A routed to IRQ 10
Region 0: Memory at fbfbe000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
Kernel driver in use: ohci_hcd
Kernel modules: ohci-hcd

00:0b.1 USB Controller [0c03]: nVidia Corporation MCP51 USB Controller [10de:026e] (rev a3) (prog-if 20)
Subsystem: nVidia Corporation Device [10de:cb84]
Control: I/O- Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Interrupt: pin B routed to IRQ 7
Region 0: Memory at fbfbfc00 (32-bit, non-prefetchable) [size=256]
Capabilities: <access denied>
Kernel modules: ehci-hcd

00:0d.0 IDE interface [0101]: nVidia Corporation MCP51 IDE [10de:0265] (rev a1) (prog-if 8a [Master SecP PriP])
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0 (750ns min, 250ns max)
Region 0: [virtual] Memory at 000001f0 (32-bit, non-prefetchable) [disabled] [size=8]
Region 1: [virtual] Memory at 000003f0 (type 3, non-prefetchable) [disabled] [size=1]
Region 2: [virtual] Memory at 00000170 (32-bit, non-prefetchable) [disabled] [size=8]
Region 3: [virtual] Memory at 00000370 (type 3, non-prefetchable) [disabled] [size=1]
Region 4: I/O ports at ffa0 [size=16]
Capabilities: <access denied>
Kernel driver in use: AMD_IDE
Kernel modules: ata_generic, amd74xx, ide-pci-generic

00:0e.0 IDE interface [0101]: nVidia Corporation MCP51 Serial ATA Controller [10de:0266] (rev a1) (prog-if 85 [Master SecO PriO])
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0 (750ns min, 250ns max)
Interrupt: pin A routed to IRQ 11
Region 0: I/O ports at d800 [size=8]
Region 1: I/O ports at d480 [size=4]
Region 2: I/O ports at d400 [size=8]
Region 3: I/O ports at d080 [size=4]
Region 4: I/O ports at d000 [size=16]
Region 5: Memory at fbfbd000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
Kernel driver in use: sata_nv
Kernel modules: sata_nv, ata_generic, ide-pci-generic

00:0f.0 IDE interface [0101]: nVidia Corporation MCP51 Serial ATA Controller [10de:0267] (rev a1) (prog-if 85 [Master SecO PriO])
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0 (750ns min, 250ns max)
Interrupt: pin A routed to IRQ 5
Region 0: I/O ports at cc00 [size=8]
Region 1: I/O ports at c880 [size=4]
Region 2: I/O ports at c800 [size=8]
Region 3: I/O ports at c480 [size=4]
Region 4: I/O ports at c400 [size=16]
Region 5: Memory at fbfbc000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
Kernel driver in use: sata_nv
Kernel modules: sata_nv, ata_generic, ide-pci-generic

00:10.0 PCI bridge [0604]: nVidia Corporation MCP51 PCI Bridge [10de:026f] (rev a2) (prog-if 01)
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Bus: primary=00, secondary=04, subordinate=04, sec-latency=64
I/O behind bridge: 0000e000-0000efff
Memory behind bridge: feb00000-febfffff
Secondary status: 66MHz- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- <SERR- <PERR+
BridgeCtl: Parity- SERR+ NoISA- VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr+ DiscTmrStat- DiscTmrSERREn-
Capabilities: <access denied>

00:10.1 Audio device [0403]: nVidia Corporation MCP51 High Definition Audio [10de:026c] (rev a2)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0 (500ns min, 1250ns max)
Interrupt: pin B routed to IRQ 11
Region 0: Memory at fbfb8000 (32-bit, non-prefetchable) [size=16K]
Capabilities: <access denied>
Kernel driver in use: HDA Intel
Kernel modules: snd-hda-intel

00:14.0 Bridge [0680]: nVidia Corporation MCP51 Ethernet Controller [10de:0269] (rev a3)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0 (250ns min, 5000ns max)
Interrupt: pin A routed to IRQ 10
Region 0: Memory at fbfb7000 (32-bit, non-prefetchable) [size=4K]
Region 1: I/O ports at c080 [size=8]
Capabilities: <access denied>
Kernel driver in use: forcedeth
Kernel modules: forcedeth

00:18.0 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] HyperTransport Technology Configuration [1022:1100]
Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Capabilities: <access denied>

00:18.1 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] Address Map [1022:1101]
Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:18.2 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] DRAM Controller [1022:1102]
Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:18.3 Host bridge [0600]: Advanced Micro Devices [AMD] K8 [Athlon64/Opteron] Miscellaneous Control [1022:1103]
Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Capabilities: <access denied>
Kernel driver in use: k8temp
Kernel modules: k8temp

03:00.0 VGA compatible controller [0300]: nVidia Corporation G72 [GeForce 7500 LE] [10de:01dd] (rev a1)
Subsystem: ASUSTeK Computer Inc. Device [1043:034b]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0, Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 10
Region 0: Memory at fd000000 (32-bit, non-prefetchable) [size=16M]
Region 1: Memory at d0000000 (64-bit, prefetchable) [size=256M]
Region 3: Memory at fc000000 (64-bit, non-prefetchable) [size=16M]
Expansion ROM at feae0000 [disabled] [size=128K]
Capabilities: <access denied>
Kernel modules: nvidiafb, nvidia

04:04.0 FireWire (IEEE 1394) [0c00]: VIA Technologies, Inc. IEEE 1394 Host Controller [1106:3044] (rev c0) (prog-if 10)
Subsystem: Hewlett-Packard Company Device [103c:2a33]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 64 (8000ns max), Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 11
Region 0: Memory at febff800 (32-bit, non-prefetchable) [size=2K]
Region 1: I/O ports at ec00 [size=128]
Capabilities: <access denied>
Kernel driver in use: firewire_ohci
Kernel modules: firewire-ohci



-- System Information:
Debian Release: lenny/sid
APT prefers testing
APT policy: (500, 'testing')
Architecture: amd64 (x86_64)

Kernel: Linux 2.6.25-2-amd64 (SMP w/2 CPU cores)
Locale: LANG=fr_FR.UTF-8, LC_CTYPE=fr_FR.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/bash

Versions of packages linux-image-2.6.25-2-amd64 depends on:
hi debconf [debconf-2.0] 1.5.22 Debian configuration management sy
hi initramfs-tools [linux-initra 0.92b tools for generating an initramfs
hi module-init-tools 3.4-1 tools for managing Linux kernel mo

linux-image-2.6.25-2-amd64 recommends no packages.

-- debconf information:
linux-image-2.6.25-2-amd64/preinst/abort-overwrite-2.6.25-2-amd64:
linux-image-2.6.25-2-amd64/postinst/create-kimage-link-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/preinst/failed-to-move-modules-2.6.25-2-amd64:
linux-image-2.6.25-2-amd64/postinst/depmod-error-2.6.25-2-amd64: false
linux-image-2.6.25-2-amd64/postinst/bootloader-test-error-2.6.25-2-amd64:
linux-image-2.6.25-2-amd64/prerm/would-invalidate-boot-loader-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/postinst/old-dir-initrd-link-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/preinst/lilo-has-ramdisk:
linux-image-2.6.25-2-amd64/preinst/bootloader-initrd-2.6.25-2-amd64: true
shared/kernel-image/really-run-bootloader: true
linux-image-2.6.25-2-amd64/prerm/removing-running-kernel-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/postinst/old-initrd-link-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/preinst/abort-install-2.6.25-2-amd64:
linux-image-2.6.25-2-amd64/postinst/kimage-is-a-directory:
linux-image-2.6.25-2-amd64/postinst/old-system-map-link-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/preinst/overwriting-modules-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/postinst/depmod-error-initrd-2.6.25-2-amd64: false
linux-image-2.6.25-2-amd64/preinst/elilo-initrd-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/postinst/bootloader-error-2.6.25-2-amd64:
linux-image-2.6.25-2-amd64/preinst/lilo-initrd-2.6.25-2-amd64: true
linux-image-2.6.25-2-amd64/preinst/initrd-2.6.25-2-amd64:




--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
 
Old 06-25-2008, 01:39 AM
maximilian attems
 
Default Bug#487725: linux-image-2.6.25-2-amd64: Got much trouble and message 'ehci_hcd: HC died' when scanning image from 'Canon PIXMA MP150'.

On Mon, 23 Jun 2008, Valentin QUEQUET wrote:

> Package: linux-image-2.6.25-2-amd64
> Version: 2.6.25-5
> Severity: normal
>
>
> Hello, all.
>
> While scanning (with SANE) a document from my 'Canon PIXMA MP150' scanner/printer combo, I stumbled upon a grave problem.
>
> I discovered that my configuration - out-of-the-box up-to-date lenny - was using module 'ehci_hcd' (possibly amongst others) to dialog with my scanner.
>
> And I discovered that there was some activity on IRQ_7, which was 'listened-to' by module 'ehci_hcd', when I queried scanner list typing:
> scanimage -L
> and when I put my scanner ON or OFF.
>
> I was able to query this list 3 times without a problem ; this step did no hurt.
>
> But a grave problem suddenly arose when I typed:
> scanimage -T
> which is a scanner/communication test which consist in scanning a full line - equivalent to effectively scanning a small part of the document.
>
> Not only the test failed, but 3 more very bad things happened:
> - I got alarming messages on console (and in 'dmesg' below) : "ehci_hdc: ... HC died", and kernel said it would forget about IRQ_7 (which 'ehci_hcd' was 'listening-to' earlier).
> - I became unable to query the list of scanners anymore : the scanner ceased to respond.
> - The kernel no longer notified (eg on console) when I put my scanner OFF, ON, and OFF again.
>
> Fortunately, I discovered that in this situation, I had just to unload module 'ehci_hcd' to get my scanner+SANE functional (including scanner power ON/OFF notifications).
>
> But a few points draw my attention:
>
> I retried this scenario, implying scanner OFF, PC reboot, delay, scanner ON.
>
> A few times the scenario showed to be exactly like I described above.
>
> While the other times, symptoms of the bug (alarming messages + unability to communicate with the scanner) happened merely whenever I chose to put my scanner OFF (alarm) and ON (no comm.) instead of doing the scanner test (via scanimage -T).
>
> Again, unloading module 'ehci_hcd' got my scanner+SANE functional.
>
> I found very strange that IRQ_7 would just be used for some handchecking and not for the transfer phase.
> But it's obvious: 'ehci_hcd', which was the sole user of IRQ_7, succeeded at handchecking and broke on scanning/transfer attempts.
>
> So, when I unloaded 'ehci_hcd', the transfer phase likely falled back to some non-IRQ (polling) method.
>
> I was sorry that my new Debian pre-packaged linux-image-2.6.25-2-amd64 (ver 2.6.25-5) made my scanning experience so tricky.
>
> And I asked myself what it would have been if module 'echi_hcd' didn't broke. Would 'ehci_hcd' be usefull after all ?
> Would IRQ_7 be used ? With many interruptions in scanning/transfer phase ? And would transfers get faster ?
>
> So, I decided to repeat the whole thing with Debian pre-packaged linux-image-2.6.24-1-amd64 (ver 2.6.24-7).
> You can't believe it ! Scanning (scanner+SANE) functioned right out of the box, without having root to unload module 'ehci_hcd' (it was effectively loaded).
>
> And I was witnessing an intensive use of IRQ_7 by module 'ehci_hcd' ; transfers were similar than with 2.6.25 : certainly no far from perfect.
>
> And whenever I put my scanner ON or OFF, I got the matching notifications on console and in dmesg.
>
> And whenever I wanted to scan some paper, all was good and perfect.
>
> To help you understand what happened, and in the case the piece of dmesg below would not be enough, I give you a pointer to my full dmesg log:
> http://pagesperso-orange.fr/mandolosse/logs/2008-06-23__dmesg__ehci_hcd__died.txt
>
> I also captured 'scanimage', 'lsmod' output and snapshots of '/proc/interrupts' at different times. I plan to scrutinize all this data, to comment the relevant parts and to post them later.
>
> In hope my report will prove useful.
>
> Sincerely,
> Valentin QUEQUET

urrgs indeed.

but please can you test out latest upstream linux images 2.6.26-rcX
they install just fine in testing/unstable. see trunk apt lines
-> http://wiki.debian.org/DebianKernel

kind regards

--
maks



--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
 
Old 06-25-2008, 08:37 PM
Valentin QUEQUET
 
Default Bug#487725: linux-image-2.6.25-2-amd64: Got much trouble and message 'ehci_hcd: HC died' when scanning image from 'Canon PIXMA MP150'.

urrgs indeed.

but please can you test out latest upstream linux images 2.6.26-rcX
they install just fine in testing/unstable. see trunk apt lines
-> http://wiki.debian.org/DebianKernel

kind regards



Hi, Maks and other Linux' enthusiasts.

I would like to thank you for the suggestion to use the latest Release
Candidate of (Debian-Packaged-)Linux 2.6.26
(2.6.26~rc7-1~experimental.1~snapshot.11693 by the time of my writing) ;
but I'm afraid I still have bad news for you :-(


My report against this bug (#487725) remains unchanged. (Kernel NOT Tainted)

I'm so sorry.

Least but not last: I still experience bug #427421 (The FAMOUS
Linux/LILO/initRD case) with kernel
2.6.26~rc7-1~experimental.1~snapshot.11693 :-(


Help this report will prove useful.

Yours, sincerely.
Valentin QUEQUET




--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
 
Old 06-26-2008, 12:59 AM
maximilian attems
 
Default Bug#487725: linux-image-2.6.25-2-amd64: Got much trouble and message 'ehci_hcd: HC died' when scanning image from 'Canon PIXMA MP150'.

On Wed, Jun 25, 2008 at 10:37:32PM +0200, Valentin QUEQUET wrote:
>
> Hi, Maks and other Linux' enthusiasts.
>
> I would like to thank you for the suggestion to use the latest Release
> Candidate of (Debian-Packaged-)Linux 2.6.26
> (2.6.26~rc7-1~experimental.1~snapshot.11693 by the time of my writing) ;
> but I'm afraid I still have bad news for you :-(

ok so time to notify upstream, please file the corresponding info
in bugzilla.kernel.org saying that it is not a regression
but with the oops output.
lspci might help too.

> My report against this bug (#487725) remains unchanged. (Kernel NOT Tainted)
>
> I'm so sorry.

please tell us the upstream bug number so we can mark it appropriately
then.

> Least but not last: I still experience bug #427421 (The FAMOUS
> Linux/LILO/initRD case) with kernel
> 2.6.26~rc7-1~experimental.1~snapshot.11693 :-(

right that is expexted, sorry but my lilo debug foo is very limited
i hope a fix will come up soon.

sunny greetings

--
maks



--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
 
Old 06-28-2008, 12:07 AM
Valentin QUEQUET
 
Default Bug#487725: linux-image-2.6.25-2-amd64: Got much trouble and message 'ehci_hcd: HC died' when scanning image from 'Canon PIXMA MP150'.

ok so time to notify upstream, please file the corresponding info
in bugzilla.kernel.org saying that it is not a regression
but with the oops output.
lspci might help too.


My report against this bug (#487725) remains unchanged. (Kernel NOT Tainted)

I'm so sorry.


please tell us the upstream bug number so we can mark it appropriately
then.


Hello, Maks and other Linux' enthusiasts.

I tested with Pristine Linux 2.6.26-rc8 with debugging/logging
facilities ; got the same as with Debian-packaged -rc8


I reported the bug upstream as Bug #10997 which can be shown at:
http://bugzilla.kernel.org/show_bug.cgi?id=10997

I also updated things on my WEB site (more log files, .config, ...):
http://pagesperso-orange.fr/mandolosse/

Have a nice W.E.

regards
Valentin QUEQUET




--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
 

Thread Tools




All times are GMT. The time now is 11:43 AM.

VBulletin, Copyright ©2000 - 2014, Jelsoft Enterprises Ltd.
Content Relevant URLs by vBSEO ©2007, Crawlability, Inc.
Copyright 2007 - 2008, www.linux-archive.org