FC2 and USB disk problems

Rick Stevens rstevens at vitalstream.com
Wed Jul 21 17:48:29 UTC 2004 

Graeme Nichols wrote:
> On Wed, 2004-07-21 at 02:51, Rick Stevens wrote:
> <snip>
> 
>>Let's have a looksee.
>>
>>
>>>RAMDISK: Compressed image found at block 0
>>>VFS: Mounted root (ext2 filesystem).
>>>kjournald starting.  Commit interval 5 seconds
>>>EXT3-fs: mounted filesystem with ordered data mode.
>>>Freeing unused kernel memory: 144k freed
>>>ohci_hcd: 2004 Feb 02 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
>>>ohci_hcd: block sizes: ed 64 td 64
>>>AMD756: dev 1022:740c, router pirq : 4 get irq : 10
>>>PCI: Found IRQ 10 for device 0000:00:07.4
>>>PCI: Sharing IRQ 10 with 0000:00:08.0
>>>ohci_hcd 0000:00:07.4: OHCI Host Controller
>>>ohci_hcd 0000:00:07.4: irq 10, pci mem 0a836000
>>>ohci_hcd 0000:00:07.4: new USB bus registered, assigned bus number 1
>>>ohci_hcd 0000:00:07.4: AMD756 erratum 4 workaround
>>>hub 1-0:1.0: USB hub found
>>>hub 1-0:1.0: 4 ports detected
>>
>>There's one USB bus and it's USB 1.1.
>>
>>
>>>USB Universal Host Controller Interface driver v2.2
>>>AMD756: dev 1106:3038, router pirq : 4 get irq : 10
>>>PCI: Found IRQ 10 for device 0000:00:08.0
>>>PCI: Sharing IRQ 10 with 0000:00:07.4
>>>uhci_hcd 0000:00:08.0: UHCI Host Controller
>>>uhci_hcd 0000:00:08.0: irq 10, io base 0000d400
>>>uhci_hcd 0000:00:08.0: new USB bus registered, assigned bus number 2
>>>hub 2-0:1.0: USB hub found
>>>hub 2-0:1.0: 2 ports detected
>>
>>There's a second bus and it's USB 1.1.
>>
>>
>>>AMD756: dev 1106:3038, router pirq : 1 get irq :  9
>>>PCI: Found IRQ 9 for device 0000:00:08.1
>>>uhci_hcd 0000:00:08.1: UHCI Host Controller
>>>uhci_hcd 0000:00:08.1: irq 9, io base 0000d800
>>>uhci_hcd 0000:00:08.1: new USB bus registered, assigned bus number 3
>>>hub 3-0:1.0: USB hub found
>>>hub 3-0:1.0: 2 ports detected
>>
>>There's a third bus, USB 1.1.
>>
>>
>>>AMD756: dev 1106:3104, router pirq : 2 get irq : 11
>>>PCI: Found IRQ 11 for device 0000:00:08.2
>>>PCI: Sharing IRQ 11 with 0000:00:0a.0
>>>ehci_hcd 0000:00:08.2: EHCI Host Controller
>>>ehci_hcd 0000:00:08.2: irq 11, pci mem 0a83af00
>>>ehci_hcd 0000:00:08.2: new USB bus registered, assigned bus number 4
>>>ehci_hcd 0000:00:08.2: USB 2.0 enabled, EHCI 0.95, driver 2003-Dec-29
>>>hub 4-0:1.0: USB hub found
>>>hub 4-0:1.0: 4 ports detected
>>
>>And there's a fourth bus, USB 2.0.
>>
>>
>>>hub 3-0:1.0: connect-debounce failed, port 1 disabled
>>
>>The system is having trouble with the third USB bus.  You should check
>>the cables there.
>>
>>
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>EXT3 FS on hdb2, internal journal
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>device-mapper: 4.1.0-ioctl (2003-12-10) initialised: dm at uk.sistina.com
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>hub 4-0:1.0: Cannot enable port 4.  Maybe the USB cable is bad?
>>>usb 3-1: new full speed USB device using address 2
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>SCSI subsystem initialized
>>>Initializing USB Mass Storage driver...
>>>scsi0 : SCSI emulation for USB Mass Storage devices
>>>  Vendor: IOMEGA    Model: ZIP 100           Rev: 90.J
>>>  Type:   Direct-Access                      ANSI SCSI revision: 02
>>>USB Mass Storage device found at 2
>>>usbcore: registered new driver usb-storage
>>>USB Mass Storage support registered.
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>sda: Spinning up disk...<3>ehci_hcd 0000:00:08.2: port 4 reset error
>>>-110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>hub 4-0:1.0: Cannot enable port 4.  Maybe the USB cable is bad?
>>
>>There's a potential issue there on port 4 of the USB 2.0 bus.
>>
>>
>>>.<6>cdrom: open failed.
>>>cdrom: open failed.
>>>.<5>Attached scsi removable disk sda at scsi0, channel 0, id 0, lun 0
>>>Device not ready.  Make sure there is a disc in the drive.
>>>Device not ready.  Make sure there is a disc in the drive.
>>>inserting floppy driver for 2.6.5-1.358
>>>Floppy drive(s): fd0 is 1.44M
>>>FDC 0 is a post-1991 82077
>>>Adding 522104k swap on /dev/hdb3.  Priority:-1 extents:1
>>>Adding 128480k swap on /dev/hda8.  Priority:-2 extents:1
>>>kjournald starting.  Commit interval 5 seconds
>>>EXT3 FS on hdb1, internal journal
>>>EXT3-fs: mounted filesystem with ordered data mode.
>>>parport0: PC-style at 0x378 (0x778) [PCSPP,TRISTATE,EPP]
>>>parport0: irq 7 detected
>>>Device not ready.  Make sure there is a disc in the drive.
>>>8139too Fast Ethernet driver 0.9.27
>>>AMD756: dev 10ec:8139, router pirq : 3 get irq :  5
>>>PCI: Found IRQ 5 for device 0000:00:0b.0
>>>divert: allocating divert_blk for eth0
>>>eth0: RealTek RTL8139 at 0xcc00, 00:05:1c:1d:b7:3b, IRQ 5
>>>eth0:  Identified 8139 chip type 'RTL-8100B/8139D'
>>>divert: freeing divert_blk for eth0
>>>ip_tables: (C) 2000-2002 Netfilter core team
>>>ip_conntrack version 2.1 (1023 buckets, 8184 max) - 296 bytes per
>>>conntrack
>>>8139too Fast Ethernet driver 0.9.27
>>>AMD756: dev 10ec:8139, router pirq : 3 get irq :  5
>>>PCI: Found IRQ 5 for device 0000:00:0b.0
>>>divert: allocating divert_blk for eth0
>>>eth0: RealTek RTL8139 at 0xcc00, 00:05:1c:1d:b7:3b, IRQ 5
>>>eth0:  Identified 8139 chip type 'RTL-8100B/8139D'
>>>eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
>>>parport0: PC-style at 0x378 (0x778) [PCSPP,TRISTATE,EPP]
>>>parport0: irq 7 detected
>>>lp0: using parport0 (polling).
>>>lp0: console ready
>>>NET: Registered protocol family 10
>>>Disabled Privacy Extensions on device 022db720(lo)
>>>IPv6 over IPv4 tunneling driver
>>>divert: not allocating divert_blk for non-ethernet device sit0
>>>eth0: no IPv6 routers present
>>>warning: process `update' used the obsolete bdflush system call
>>>Fix your initscripts?
>>>warning: process `update' used the obsolete bdflush system call
>>>Fix your initscripts?
>>>AMD756: dev 1274:1371, router pirq : 2 get irq : 11
>>>PCI: Found IRQ 11 for device 0000:00:0a.0
>>>PCI: Sharing IRQ 11 with 0000:00:08.2
>>>SCSI device sda: 196608 512-byte hdwr sectors (101 MB)
>>>sda: assuming Write Enabled
>>>sda: assuming drive cache: write through
>>>SCSI device sda: 196608 512-byte hdwr sectors (101 MB)
>>>sda: assuming Write Enabled
>>>sda: assuming drive cache: write through
>>> sda: sda4
>>>ehci_hcd 0000:00:08.2: port 4 reset error -110
>>>hub 4-0:1.0: hub_port_status failed (err = -32)
>>>usb 2-2: new full speed USB device using address 2
>>>
>>>Hoping someone can point me in the right direction.
>>
>>It appears that you have a whole bunch of USB ports.  I counted three
>>buses there, two with 2 ports and two with 4 ports.
>>
>>It looks like your ZIP is in bus 2 (one of the two-port buses that's
>>USB 1.1).  The system is having an issue with bus 3 (the other two-port
>>USB 1.1 bus) and it can't reliably debounce one of the ports.  Check the
>>cable there.
>>
>>Bus 4 (one of the 4-port buses and the only USB 2.0 I see) reports a bad
>>port, and I suspect that's where the external HD is plugged into.
>>
>>There are several possibilities: The bus is USB 2.0.  If your drive
>>is also USB 2.0 compliant and you use a USB 1.1 cable, you'll have
>>issues.  I'd recommend first checking your cables.  Try to always use
>>USB 2.0 cables--even if it's a 1.1 bus.
>>
>>You may have a bad port on that bus.  You might try a different port on
>>that bus.
>>
>>Try the other 4-port bus (USB 1.1, so it'll be slower).
> 
> 
> Hello Rick, What you have just outlined above is sort of what I gathered
> but there are only two usb busses on the system, a two port 1.1 bus on
> the MB, with nothing in it, 

I'll bet you find a second and third set of headers on the mobo.  You
seem to have one USB controller that uses the OHCI driver, two that use
the UHCI driver and one (the 2.0 one) that uses the EHCI driver.

>                             and a four port 2.0 bus on a pci card with 3
> cables plugged in at the moment. The fourth port had a cable in it when
> I connected the usb ADSL modem that was supplied by my ISP when I went
> to ADSL. I removed the usb ADSL modem when I got the ADSL modem/router
> but left the cable plugged into the usb card. Removing it from the card
> made no difference. The three devices all work OK on Win 98, I checked,
> and the Ext. HD has a 2.0 bus and came with its own cable, which I have
> assumed is a 2.0 cable (although I have no idea what the differences
> are).

2.0 cables use different wire with lower capacitance between the
conductors and better shielding.  The speeds of 2.0 can cause severe
crosstalk on 1.1 cables and make the bus very flakey.

>       This problem arose as part of the upgrade to FC2 and was apparent
> immediately which is what makes me think the problem lies with something
> in FC2. Whatever the problem is it has not affected the scanner at all,
> only the HD and Zip drives. At the moment it is picking up the Zip drive
> reliably. Could it be an irq clash?

I doubt it's an IRQ issue, although the below stuff shows two 1.1
controllers on IRQ 10, one 1.1 on IRQ 9 and the 2.0 USB on IRQ 11.

> The Ext. HD mounted OK at boot time today. The output for dmesg follows:
> 
> RAMDISK: Compressed image found at block 0
> VFS: Mounted root (ext2 filesystem).
> kjournald starting.  Commit interval 5 seconds
> EXT3-fs: mounted filesystem with ordered data mode.
> Freeing unused kernel memory: 144k freed
> ohci_hcd: 2004 Feb 02 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
> ohci_hcd: block sizes: ed 64 td 64
> AMD756: dev 1022:740c, router pirq : 4 get irq : 10
> PCI: Found IRQ 10 for device 0000:00:07.4
> PCI: Sharing IRQ 10 with 0000:00:08.0
> ohci_hcd 0000:00:07.4: OHCI Host Controller
> ohci_hcd 0000:00:07.4: irq 10, pci mem 0a836000
> ohci_hcd 0000:00:07.4: new USB bus registered, assigned bus number 1
> ohci_hcd 0000:00:07.4: AMD756 erratum 4 workaround
> hub 1-0:1.0: USB hub found
> hub 1-0:1.0: 4 ports detected
> USB Universal Host Controller Interface driver v2.2
> AMD756: dev 1106:3038, router pirq : 4 get irq : 10
> PCI: Found IRQ 10 for device 0000:00:08.0
> PCI: Sharing IRQ 10 with 0000:00:07.4
> uhci_hcd 0000:00:08.0: UHCI Host Controller
> uhci_hcd 0000:00:08.0: irq 10, io base 0000d400
> uhci_hcd 0000:00:08.0: new USB bus registered, assigned bus number 2
> hub 2-0:1.0: USB hub found
> hub 2-0:1.0: 2 ports detected
> AMD756: dev 1106:3038, router pirq : 1 get irq :  9
> PCI: Found IRQ 9 for device 0000:00:08.1
> uhci_hcd 0000:00:08.1: UHCI Host Controller
> uhci_hcd 0000:00:08.1: irq 9, io base 0000d800
> uhci_hcd 0000:00:08.1: new USB bus registered, assigned bus number 3
> hub 3-0:1.0: USB hub found
> hub 3-0:1.0: 2 ports detected
> AMD756: dev 1106:3104, router pirq : 2 get irq : 11
> PCI: Found IRQ 11 for device 0000:00:08.2
> PCI: Sharing IRQ 11 with 0000:00:0a.0
> ehci_hcd 0000:00:08.2: EHCI Host Controller
> ehci_hcd 0000:00:08.2: irq 11, pci mem 0a83af00
> ehci_hcd 0000:00:08.2: new USB bus registered, assigned bus number 4
> ehci_hcd 0000:00:08.2: USB 2.0 enabled, EHCI 0.95, driver 2003-Dec-29
> hub 4-0:1.0: USB hub found
> hub 4-0:1.0: 4 ports detected
> hub 3-0:1.0: connect-debounce failed, port 1 disabled

That still really bothers me.  Its on the uhci 1.1 bus at IRQ 9.

> ehci_hcd 0000:00:08.2: port 3 reset error -110
> hub 4-0:1.0: hub_port_status failed (err = -32)
> EXT3 FS on hdb2, internal journal
> ehci_hcd 0000:00:08.2: port 4 reset error -110
> hub 4-0:1.0: hub_port_status failed (err = -32)
> device-mapper: 4.1.0-ioctl (2003-12-10) initialised: dm at uk.sistina.com
> ehci_hcd 0000:00:08.2: port 4 reset error -110
> hub 4-0:1.0: hub_port_status failed (err = -32)
> ehci_hcd 0000:00:08.2: port 4 reset error -110
> hub 4-0:1.0: hub_port_status failed (err = -32)
> ehci_hcd 0000:00:08.2: port 4 reset error -110
> hub 4-0:1.0: hub_port_status failed (err = -32)
> ehci_hcd 0000:00:08.2: port 4 reset error -110
> hub 4-0:1.0: hub_port_status failed (err = -32)
> hub 4-0:1.0: Cannot enable port 4.  Maybe the USB cable is bad?

That is also disturbing.  That's a port on the 2.0 bus.

> usb 3-1: new full speed USB device using address 2
> ehci_hcd 0000:00:08.2: port 4 reset error -110
> hub 4-0:1.0: hub_port_status failed (err = -32)
> SCSI subsystem initialized
> Initializing USB Mass Storage driver...
> scsi0 : SCSI emulation for USB Mass Storage devices
>   Vendor: IOMEGA    Model: ZIP 100           Rev: 90.J
>   Type:   Direct-Access                      ANSI SCSI revision: 02
> usb 4-4: new high speed USB device using address 2
> USB Mass Storage device found at 2
> usbcore: registered new driver usb-storage
> USB Mass Storage support registered.
> ++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
> The following section is for the Ext 60G HD
> ++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
> sda: Spinning up disk...<6>scsi1 : SCSI emulation for USB Mass Storage
> devices
>   Vendor: IC35L060  Model: AVVA07-0          Rev: VA3O
>   Type:   Direct-Access                      ANSI SCSI revision: 02
> .<5>Attached scsi removable disk sda at scsi0, channel 0, id 0, lun 0
> SCSI device sdb: 120103200 512-byte hdwr sectors (61493 MB)

Seeing the above makes me suspect that we have a timing issue.  Today
the drive spun up in time.  Last time it didn't.  Hmmmmm.

> sdb: assuming drive cache: write through
>  sdb: sdb1
> Attached scsi disk sdb at scsi1, channel 0, id 0, lun 0
> USB Mass Storage device found at 2
> Device not ready.  Make sure there is a disc in the drive.
> Device not ready.  Make sure there is a disc in the drive.
> Device not ready.  Make sure there is a disc in the drive.

Don't worry about those.  The system is complaining that you don't have
a ZIP disk in its drive.

> inserting floppy driver for 2.6.5-1.358
> Floppy drive(s): fd0 is 1.44M
> FDC 0 is a post-1991 82077
> cdrom: open failed.
> cdrom: open failed.
> Adding 522104k swap on /dev/hdb3.  Priority:-1 extents:1
> Adding 128480k swap on /dev/hda8.  Priority:-2 extents:1

Swaps started up OK.

> kjournald starting.  Commit interval 5 seconds
> EXT3 FS on hdb1, internal journal
> EXT3-fs: mounted filesystem with ordered data mode.

And /dev/hdb1 mounted OK.

[rest deleted]
----------------------------------------------------------------------
- Rick Stevens, Senior Systems Engineer     rstevens at vitalstream.com -
- VitalStream, Inc.                       http://www.vitalstream.com -
-                                                                    -
-            "You think that's tough?  Try herding cats!"            -
----------------------------------------------------------------------

More information about the Redhat-install-list mailing list