Hi,

On Tue, Dec 05, 2006 at 04:39:34PM -0500, Alan Stern wrote:
> > On Tue, Dec 05, 2006 at 07:19:01AM -0500, Dominik Brodowski wrote:
> > > Hi,
> > > 
> > > git bisect proved that the patch
> > > 
> > > commit 40f122f343797d02390c5a157372cac0c5b50bb7
> > > Author: Alan Stern <[EMAIL PROTECTED]>
> > > Date:   Thu Nov 9 14:44:33 2006 -0500
> > > 
> > >     USB: Add autosuspend support to the hub driver
> > > 
> > >     This patch (as742b) adds autosuspend/autoresume support to the USB hub
> > >     driver.  The largest aspect of the change is that we no longer need a
> > >     special flag for root hubs that want to be resumed.  Now every hub is
> > >     autoresumed whenever khubd needs to access it.
> > > 
> > > is the cause for IRQ #10 being disabled on my notebook:
> > 
> > 
> > Now I tested this a bit further... and strangely, if I modprobe the USB
> > modules somewhen later, it is no problem at all. Running many many possible
> > combinations of modprobe'ing yenta_socket, ehci_hcd, uhci_hcd and other
> > modules loaded at the same time in the init scripts I use, or even using
> > /etc/init.d/modules, does not reproduce the error... Very strange.
> 
> I don't entirely trust git-bisect.  Can you try reverting just this one 
> patch by hand, to verify that the problem really does go away?

Verified by hand, well, by "git reset HEAD^ && git checkout -f", and yes,
it's caused by autosuspend... BTW, why don't you entirely trust git-bisect?

> Also, can you post a system log showing the problem with CONFIG_USB_DEBUG 
> turned on?

It's attached.

> If you prevent ehci-hcd.ko from being loaded in the usual way (say by 
> renaming it), does the problem still occur?

No, it doesn't occur.

>  What about uhci-hcd.ko?

If I rename ehci-hcd, uhci-hcd seems to be bound to that hub, and all works
well there. Also, ehci-hcd works fine
a) if my Matrox USB HD isn't connected to that port [haven't tested other 
   USB devices yet] or
b) if the modules uhci-hcd, ehci-hcd and yenta_socket[*] are modprobed in
   any order after bootup. I can only reproduce it at the stage where udev
   events are created through uevent at boot time -- it's right then when
   udev is processing events that this error occurs.

Any ideas?

        Dominik


[    0.000000] Linux version 2.6.19 ([EMAIL PROTECTED]) (gcc-Version 4.1.1 
(Gentoo 4.1.1-r1)) #16 PREEMPT Wed Dec 6 21:25:29 EST 2006
...
[   10.282625] ACPI: bus type pci registered
[   10.286080] PCI: PCI BIOS revision 2.10 entry at 0xfd9b2, last bus=2
[   10.286137] PCI: Using configuration type 1
[   10.286190] Setting up standard PCI resources
...
[   10.328881] PCI: Probing PCI hardware (bus 00)
[   10.336758] Boot video device is 0000:00:02.0
[   10.337473] PCI quirk: region 1000-107f claimed by ICH4 ACPI/GPIO/TCO
[   10.337535] PCI quirk: region 1180-11bf claimed by ICH4 GPIO
[   10.337675] PCI: Ignoring BAR0-3 of IDE controller 0000:00:1f.1
[   10.338637] PCI: Firmware left 0000:02:08.0 e100 interrupts enabled, 
disabling
[   10.338813] PCI: Transparent bridge - 0000:00:1e.0
[   10.339136] PCI: Bus #03 (-#06) is hidden behind transparent bridge #02 
(-#02) (try 'pci=assign-busses')
[   10.339213] Please report the result to linux-kernel to fix this permanently
[   10.339527] PCI: Bus #07 (-#0a) is hidden behind transparent bridge #02 
(-#02) (try 'pci=assign-busses')
[   10.339605] Please report the result to linux-kernel to fix this permanently
[   10.339694] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[   10.369585] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PCIB._PRT]
[   10.374175] ACPI: PCI Interrupt Link [LNKA] (IRQs *10)
[   10.375301] ACPI: PCI Interrupt Link [LNKB] (IRQs *10)
[   10.376400] ACPI: PCI Interrupt Link [LNKC] (IRQs *11)
[   10.377546] ACPI: PCI Interrupt Link [LNKD] (IRQs *11)
[   10.378651] ACPI: PCI Interrupt Link [LNKE] (IRQs *5)
[   10.379740] ACPI: PCI Interrupt Link [LNKF] (IRQs 5) *0, disabled.
[   10.380933] ACPI: PCI Interrupt Link [LNKG] (IRQs 10) *0, disabled.
[   10.382172] ACPI: PCI Interrupt Link [LNKH] (IRQs *10)
[   10.392529] ACPI: Power Resource [PFAN] (on)
[   10.394992] SCSI subsystem initialized
[   10.395223] libata version 2.00 loaded.
[   10.395617] usbcore: registered new interface driver usbfs
[   10.395940] usbcore: registered new interface driver hub
[   10.396326] usbcore: registered new device driver usb
[   10.397025] PCI: Using ACPI for IRQ routing
[   10.397079] PCI: If a device doesn't work, try "pci=routeirq".  If it helps, 
post a report
...
[   10.406006] PCI: Ignore bogus resource 6 [0:0] of 0000:00:02.0
[   10.406103] PCI: Bus 3, cardbus bridge: 0000:02:03.0
[   10.406158]   IO window: 00003400-000034ff
[   10.406214]   IO window: 00003800-000038ff
[   10.406269]   PREFETCH window: 40000000-41ffffff
[   10.406326]   MEM window: 46000000-47ffffff
[   10.406402] PCI: Bus 7, cardbus bridge: 0000:02:03.1
[   10.406456]   IO window: 00003c00-00003cff
[   10.406512]   IO window: 00001400-000014ff
[   10.406567]   PREFETCH window: 42000000-43ffffff
[   10.406623]   MEM window: 48000000-49ffffff
[   10.406678] PCI: Bridge: 0000:00:1e.0
[   10.406731]   IO window: 3000-3fff
[   10.406787]   MEM window: e0200000-e02fffff
[   10.406843]   PREFETCH window: 40000000-43ffffff
[   10.406913] PCI: Setting latency timer of device 0000:00:1e.0 to 64
[   10.407627] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[   10.407686] PCI: setting IRQ 10 as level-triggered
[   10.407690] ACPI: PCI Interrupt 0000:02:03.0[A] -> Link [LNKA] -> GSI 10 
(level, low) -> IRQ 10
[   10.407829] PCI: Setting latency timer of device 0000:02:03.0 to 64
[   10.407848] PCI: Enabling device 0000:02:03.1 (0000 -> 0003)
[   10.408521] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
[   10.408578] ACPI: PCI Interrupt 0000:02:03.1[B] -> Link [LNKB] -> GSI 10 
(level, low) -> IRQ 10
[   10.408717] PCI: Setting latency timer of device 0000:02:03.1 to 64
...
[   10.445445] pci 0000:00:1d.0: uhci_check_and_reset_hc: legsup = 0x2f00
[   10.445448] pci 0000:00:1d.0: Performing full reset
[   10.445468] pci 0000:00:1d.1: uhci_check_and_reset_hc: legsup = 0x2000
[   10.445471] pci 0000:00:1d.1: Performing full reset
[   10.445490] pci 0000:00:1d.2: uhci_check_and_reset_hc: legsup = 0x2000
[   10.445493] pci 0000:00:1d.2: Performing full reset
...
[   12.876533] Bluetooth: HCI USB driver ver 2.9
[   12.876801] usbcore: registered new interface driver hci_usb
[   12.877200] Advanced Linux Sound Architecture Driver Version 1.0.13 (Tue Nov 
28 14:07:24 2006 UTC).
[   12.879855] ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link [LNKB] -> GSI 10 
(level, low) -> IRQ 10
[   12.880028] PCI: Setting latency timer of device 0000:00:1f.5 to 64
[   12.899128] input: AT Translated Set 2 keyboard as /class/input/input1
[   13.535251] input: ImPS/2 Synaptics TouchPad as /class/input/input2
[   13.694284] intel8x0_measure_ac97_clock: measured 50992 usecs
[   13.694342] intel8x0: clocking to 48000
[   13.699676] ACPI: PCI Interrupt 0000:00:1f.6[B] -> Link [LNKB] -> GSI 10 
(level, low) -> IRQ 10
[   13.699830] PCI: Setting latency timer of device 0000:00:1f.6 to 64
[   13.801179] MC'97 1 converters and GPIO not ready (0xf000)
[   13.803867] usbcore: registered new interface driver snd-usb-audio
[   13.803926] ALSA device list:
[   13.803976]   #0: Intel 82801DB-ICH4 with STAC9752,53 at 0xe0100c00, irq 10
[   13.804034]   #1: Intel 82801DB-ICH4 Modem at 0x2400, irq 10
...
[   13.929847] VFS: Mounted root (ext3 filesystem) readonly.
[   13.930140] Freeing unused kernel memory: 168k freed
[   13.930283] Write protecting the kernel read-only data: 556k
[   22.229058] USB Universal Host Controller Interface driver v3.0
[   22.236270] ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link [LNKA] -> GSI 10 
(level, low) -> IRQ 10
[   22.236293] PCI: Setting latency timer of device 0000:00:1d.0 to 64
[   22.236300] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[   22.244925] drivers/usb/core/inode.c: creating file 'devices'
[   22.245065] drivers/usb/core/inode.c: creating file '001'
[   22.245260] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus 
number 1
[   22.245286] uhci_hcd 0000:00:1d.0: detected 2 ports
[   22.245296] uhci_hcd 0000:00:1d.0: uhci_check_and_reset_hc: cmd = 0x0000
[   22.245299] uhci_hcd 0000:00:1d.0: Performing full reset
[   22.245324] uhci_hcd 0000:00:1d.0: irq 10, io base 0x00001820
[   22.246077] usb usb1: default language 0x0409
[   22.246124] usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
[   22.246128] usb usb1: Product: UHCI Host Controller
[   22.246131] usb usb1: Manufacturer: Linux 2.6.19 uhci_hcd
[   22.246134] usb usb1: SerialNumber: 0000:00:1d.0
[   22.246473] usb usb1: uevent
[   22.252780] usb usb1: usb_probe_device
[   22.259984] usb usb1: configuration #1 chosen from 1 choice
[   22.260448] usb usb1: adding 1-0:1.0 (config #1, interface 0)
[   22.260645] usb 1-0:1.0: uevent
[   22.266993] hub 1-0:1.0: usb_probe_interface
[   22.266996] hub 1-0:1.0: usb_probe_interface - got id
[   22.267002] hub 1-0:1.0: USB hub found
[   22.267434] hub 1-0:1.0: 2 ports detected
[   22.267437] hub 1-0:1.0: standalone hub
[   22.267439] hub 1-0:1.0: no power switching (usb 1.0)
[   22.267443] hub 1-0:1.0: individual port over-current protection
[   22.267447] hub 1-0:1.0: power on to power good time: 2ms
[   22.267470] hub 1-0:1.0: local power source is good
[   22.267476] hub 1-0:1.0: trying to enable port power on non-switchable hub
[   22.308082] ehci_hcd: block sizes: qh 128 qtd 96 itd 192 sitd 96
[   22.368260] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0000
[   22.382636] drivers/usb/core/inode.c: creating file '001'
[   22.382752] uhci_hcd 0000:00:1d.0: port 1 portsc 009b,00
[   22.382779] hub 1-0:1.0: port 1, status 0101, change 0003, 12 Mb/s
[   22.383857] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[   22.383863] ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link [LNKD] -> GSI 11 
(level, low) -> IRQ 11
[   22.383885] PCI: Setting latency timer of device 0000:00:1d.1 to 64
[   22.383892] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[   22.390270] drivers/usb/core/inode.c: creating file '002'
[   22.390323] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus 
number 2
[   22.390344] uhci_hcd 0000:00:1d.1: detected 2 ports
[   22.390352] uhci_hcd 0000:00:1d.1: uhci_check_and_reset_hc: cmd = 0x0000
[   22.390356] uhci_hcd 0000:00:1d.1: Performing full reset
[   22.390383] uhci_hcd 0000:00:1d.1: irq 11, io base 0x00001840
[   22.390557] usb usb2: default language 0x0409
[   22.390601] usb usb2: new device strings: Mfr=3, Product=2, SerialNumber=1
[   22.390605] usb usb2: Product: UHCI Host Controller
[   22.390608] usb usb2: Manufacturer: Linux 2.6.19 uhci_hcd
[   22.390611] usb usb2: SerialNumber: 0000:00:1d.1
[   22.390785] usb usb2: uevent
[   22.397046] usb usb2: usb_probe_device
[   22.403649] usb usb2: configuration #1 chosen from 1 choice
[   22.403682] usb usb2: adding 2-0:1.0 (config #1, interface 0)
[   22.403828] usb 2-0:1.0: uevent
[   22.409994] hub 2-0:1.0: usb_probe_interface
[   22.409998] hub 2-0:1.0: usb_probe_interface - got id
[   22.410003] hub 2-0:1.0: USB hub found
[   22.410027] hub 2-0:1.0: 2 ports detected
[   22.410030] hub 2-0:1.0: standalone hub
[   22.410033] hub 2-0:1.0: no power switching (usb 1.0)
[   22.410036] hub 2-0:1.0: individual port over-current protection
[   22.410041] hub 2-0:1.0: power on to power good time: 2ms
[   22.410062] hub 2-0:1.0: local power source is good
[   22.410068] hub 2-0:1.0: trying to enable port power on non-switchable hub
[   22.486350] hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 
0x101
[   22.555864] drivers/usb/core/inode.c: creating file '001'
[   22.556028] ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link [LNKC] -> GSI 11 
(level, low) -> IRQ 11
[   22.556052] PCI: Setting latency timer of device 0000:00:1d.2 to 64
[   22.556059] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[   22.562311] drivers/usb/core/inode.c: creating file '003'
[   22.562365] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus 
number 3
[   22.562390] uhci_hcd 0000:00:1d.2: detected 2 ports
[   22.562398] uhci_hcd 0000:00:1d.2: uhci_check_and_reset_hc: cmd = 0x0000
[   22.562402] uhci_hcd 0000:00:1d.2: Performing full reset
[   22.562426] uhci_hcd 0000:00:1d.2: irq 11, io base 0x00001860
[   22.562592] usb usb3: default language 0x0409
[   22.562636] usb usb3: new device strings: Mfr=3, Product=2, SerialNumber=1
[   22.562639] usb usb3: Product: UHCI Host Controller
[   22.562643] usb usb3: Manufacturer: Linux 2.6.19 uhci_hcd
[   22.562646] usb usb3: SerialNumber: 0000:00:1d.2
[   22.562840] usb usb3: uevent
[   22.568878] usb usb3: usb_probe_device
[   22.575121] usb usb3: configuration #1 chosen from 1 choice
[   22.575155] usb usb3: adding 3-0:1.0 (config #1, interface 0)
[   22.575317] usb 3-0:1.0: uevent
[   22.581383] hub 3-0:1.0: usb_probe_interface
[   22.581387] hub 3-0:1.0: usb_probe_interface - got id
[   22.581393] hub 3-0:1.0: USB hub found
[   22.581418] hub 3-0:1.0: 2 ports detected
[   22.581421] hub 3-0:1.0: standalone hub
[   22.581424] hub 3-0:1.0: no power switching (usb 1.0)
[   22.581427] hub 3-0:1.0: individual port over-current protection
[   22.581432] hub 3-0:1.0: power on to power good time: 2ms
[   22.581453] hub 3-0:1.0: local power source is good
[   22.581459] hub 3-0:1.0: trying to enable port power on non-switchable hub
[   22.588360] usb 1-1: new full speed USB device using uhci_hcd and address 2
[   22.689367] drivers/usb/core/inode.c: creating file '001'
[   22.703207] ACPI: PCI Interrupt Link [LNKH] enabled at IRQ 10
[   22.703214] ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link [LNKH] -> GSI 10 
(level, low) -> IRQ 10
[   22.703242] PCI: Setting latency timer of device 0000:00:1d.7 to 64
[   22.703249] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[   22.703439] drivers/usb/core/inode.c: creating file '004'
[   22.703491] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus 
number 4
[   22.703514] ehci_hcd 0000:00:1d.7: reset hcs_params 0x103206 dbg=1 cc=3 
pcc=2 ordered !ppc ports=6
[   22.703520] ehci_hcd 0000:00:1d.7: reset hcc_params 6871 thresh 7 uframes 
1024 64 bit addr
[   22.703596] ehci_hcd 0000:00:1d.7: debug port 1
[   22.703606] PCI: cache line size of 32 is not supported by device 
0000:00:1d.7
[   22.703610] ehci_hcd 0000:00:1d.7: supports USB remote wakeup
[   22.703622] ehci_hcd 0000:00:1d.7: irq 10, io mem 0xe0100000
[   22.703629] ehci_hcd 0000:00:1d.7: reset command 080002 (park)=0 ithresh=8 
period=1024 Reset HALT
[   22.707519] ehci_hcd 0000:00:1d.7: init command 010001 (park)=0 ithresh=1 
period=1024 RUN
[   22.707526] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 
2004
[   22.707601] usb usb4: default language 0x0409
[   22.707645] usb usb4: new device strings: Mfr=3, Product=2, SerialNumber=1
[   22.707649] usb usb4: Product: EHCI Host Controller
[   22.707652] usb usb4: Manufacturer: Linux 2.6.19 ehci_hcd
[   22.707656] usb usb4: SerialNumber: 0000:00:1d.7
[   22.707822] usb usb4: uevent
[   22.707887] usb usb4: usb_probe_device
[   22.708257] usb usb4: configuration #1 chosen from 1 choice
[   22.708287] usb usb4: adding 4-0:1.0 (config #1, interface 0)
[   22.708433] usb 4-0:1.0: uevent
[   22.708500] hub 4-0:1.0: usb_probe_interface
[   22.708503] hub 4-0:1.0: usb_probe_interface - got id
[   22.708508] hub 4-0:1.0: USB hub found
[   22.708835] hub 4-0:1.0: 6 ports detected
[   22.708838] hub 4-0:1.0: standalone hub
[   22.708841] hub 4-0:1.0: no power switching (usb 1.0)
[   22.708844] hub 4-0:1.0: individual port over-current protection
[   22.708848] hub 4-0:1.0: Single TT
[   22.708851] hub 4-0:1.0: TT requires at most 8 FS bit times (666 ns)
[   22.708855] hub 4-0:1.0: power on to power good time: 20ms
[   22.708878] hub 4-0:1.0: local power source is good
[   22.708884] hub 4-0:1.0: trying to enable port power on non-switchable hub
[   22.715984] usb 1-1: uhci_result_common: failed with status 440000
[   22.716056] usb 1-1: device descriptor read/all, error -71
[   22.769085] uhci_hcd 0000:00:1d.0: port 2 portsc 008a,00
[   22.769111] hub 1-0:1.0: port 2, status 0100, change 0003, 12 Mb/s
[   22.815938] drivers/usb/core/inode.c: creating file '001'
[   22.828262] ACPI: PCI Interrupt 0000:00:1f.3[B] -> Link [LNKB] -> GSI 10 
(level, low) -> IRQ 10
[   22.872871] hub 1-0:1.0: debounce: port 2: total 100ms stable 100ms status 
0x100
[   22.872881] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0002
[   22.872971] hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0000
[   22.873000] hub 3-0:1.0: state 7 ports 2 chg 0000 evt 0000
[   22.873026] hub 4-0:1.0: state 7 ports 6 chg 0000 evt 0000
[   22.873043] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001803 POWER 
sig=j CSC CONNECT
[   22.873061] hub 4-0:1.0: port 1, status 0501, change 0001, 480 Mb/s
[   22.890056] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing 
disabled
[   22.976743] hub 4-0:1.0: debounce: port 1: total 100ms stable 100ms status 
0x501
[   23.027893] ehci_hcd 0000:00:1d.7: port 1 high speed
[   23.027902] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   23.078627] usb 4-1: new high speed USB device using ehci_hcd and address 2
[   23.131760] ehci_hcd 0000:00:1d.7: port 1 high speed
[   23.131767] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   23.198592] usb 4-1: default language 0x0409
[   23.217949] usb 4-1: new device strings: Mfr=1, Product=2, SerialNumber=3
[   23.217955] usb 4-1: Product: ATAPI-6 Bridge Controller
[   23.217958] usb 4-1: Manufacturer: Prolific Technology Inc.
[   23.217962] usb 4-1: SerialNumber: 12C3032B
[   23.218129] usb 4-1: uevent
[   23.218186] usb 4-1: usb_probe_device
[   23.218524] usb 4-1: configuration #1 chosen from 1 choice
[   23.218942] usb 4-1: adding 4-1:1.0 (config #1, interface 0)
[   23.219079] usb 4-1:1.0: uevent
[   23.219951] drivers/usb/core/inode.c: creating file '002'
[   23.220030] ehci_hcd 0000:00:1d.7: GetStatus port 2 status 001803 POWER 
sig=j CSC CONNECT
[   23.220050] hub 4-0:1.0: port 2, status 0501, change 0001, 480 Mb/s
[   23.323310] hub 4-0:1.0: debounce: port 2: total 100ms stable 100ms status 
0x501
[   23.374456] ehci_hcd 0000:00:1d.7: port 2 full speed --> companion
[   23.374465] ehci_hcd 0000:00:1d.7: GetStatus port 2 status 003801 POWER 
OWNER sig=j CONNECT
[   23.374555] hub 4-0:1.0: state 7 ports 6 chg 0000 evt 0000
[   23.381230] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0004
[   23.381246] uhci_hcd 0000:00:1d.0: port 2 portsc 0093,00
[   23.381262] hub 1-0:1.0: port 2, status 0101, change 0001, 12 Mb/s
[   23.485113] hub 1-0:1.0: debounce: port 2: total 100ms stable 100ms status 
0x101
[   23.554005] usb usb2: suspend_rh (auto-stop)
[   23.586992] usb 1-2: new full speed USB device using uhci_hcd and address 4
[   23.627991] ieee1394: Initialized config rom entry `ip1394'
[   23.687838] usb usb3: suspend_rh (auto-stop)
[   23.707805] usb 1-2: ep0 maxpacket = 8
[   23.729738] usb 1-2: default language 0x0409
[   23.744707] usb 1-2: new device strings: Mfr=1, Product=2, SerialNumber=0
[   23.744714] usb 1-2: Product: Generic USB Hub
[   23.744717] usb 1-2: Manufacturer: ALCOR
[   23.744931] usb 1-2: uevent
[   23.744992] usb 1-2: usb_probe_device
[   23.745264] usb 1-2: configuration #1 chosen from 1 choice
[   23.747703] usb 1-2: adding 1-2:1.0 (config #1, interface 0)
[   23.747861] usb 1-2:1.0: uevent
[   23.747914] hub 1-2:1.0: usb_probe_interface
[   23.747918] hub 1-2:1.0: usb_probe_interface - got id
[   23.747923] hub 1-2:1.0: USB hub found
[   23.750806] hub 1-2:1.0: 4 ports detected
[   23.750809] hub 1-2:1.0: standalone hub
[   23.750812] hub 1-2:1.0: individual port power switching
[   23.750816] hub 1-2:1.0: individual port over-current protection
[   23.750820] hub 1-2:1.0: power on to power good time: 44ms
[   23.752700] hub 1-2:1.0: local power source is good
[   23.752706] hub 1-2:1.0: enabling power on all ports
[   23.798317] ACPI: PCI Interrupt 0000:02:03.2[C] -> Link [LNKC] -> GSI 11 
(level, low) -> IRQ 11
[   23.853438] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[11]  
MMIO=[e0202000-e02027ff]  Max Packet=[2048]  IR/IT contexts=[4/4]
[   23.857984] drivers/usb/core/inode.c: creating file '004'
[   23.858061] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0004
[   23.858089] hub 1-2:1.0: state 7 ports 4 chg 0000 evt 0000
[   23.861859] hub 1-2:1.0: port 2, status 0101, change 0001, 12 Mb/s
[   23.901592] Yenta: CardBus bridge found at 0000:02:03.0 [144d:c009]
[   23.971448] hub 1-2:1.0: debounce: port 2: total 100ms stable 100ms status 
0x101
[   24.023966] Yenta: ISA IRQ mask 0x0098, PCI irq 10
[   24.023972] Socket status: 30000006
[   24.023976] Yenta: Raising subordinate bus# of parent bus (#02) from #02 to 
#06
[   24.023994] pcmcia: parent PCI bridge I/O window: 0x3000 - 0x3fff
[   24.024225] cs: IO port probe 0x3000-0x3fff: clean.
[   24.026040] pcmcia: parent PCI bridge Memory window: 0xe0200000 - 0xe02fffff
[   24.026046] pcmcia: parent PCI bridge Memory window: 0x40000000 - 0x43ffffff
[   24.026855] Yenta: CardBus bridge found at 0000:02:03.1 [144d:c009]
[   24.036361] usb 1-2.2: new full speed USB device using uhci_hcd and address 5
[   24.136252] usb 1-2.2: skipped 16 descriptors after interface
[   24.136276] usb 1-2.2: skipped 2 descriptors after interface
[   24.136281] usb 1-2.2: skipped 1 descriptor after endpoint
[   24.136286] usb 1-2.2: skipped 2 descriptors after interface
[   24.136291] usb 1-2.2: skipped 1 descriptor after endpoint
[   24.141221] usb 1-2.2: default language 0x0409
[   24.146225] usb 1-2.2: new device strings: Mfr=0, Product=1, SerialNumber=0
[   24.146231] usb 1-2.2: Product: USB Audio
[   24.146457] usb 1-2.2: uevent
[   24.146511] usb 1-2.2: usb_probe_device
[   24.146804] usb 1-2.2: configuration #1 chosen from 1 choice
[   24.148822] Yenta: ISA IRQ mask 0x0098, PCI irq 10
[   24.148826] Socket status: 30000006
[   24.148831] Yenta: Raising subordinate bus# of parent bus (#02) from #06 to 
#0a
[   24.148848] pcmcia: parent PCI bridge I/O window: 0x3000 - 0x3fff
[   24.148854] cs: IO port probe 0x3000-0x3fff:<7>usb 1-2.2: adding 1-2.2:1.0 
(config #1, interface 0)
[   24.149480] usb 1-2.2:1.0: uevent
[   24.149541] snd-usb-audio 1-2.2:1.0: usb_probe_interface
[   24.149546] snd-usb-audio 1-2.2:1.0: usb_probe_interface - got id
[   24.149992] 5:1:1: add audio endpoint 0x1
[   24.152261]  clean.
[   24.152265] pcmcia: parent PCI bridge Memory window: 0xe0200000 - 0xe02fffff
[   24.152271] pcmcia: parent PCI bridge Memory window: 0x40000000 - 0x43ffffff
[   24.153327] 5:2:1: add audio endpoint 0x82
[   24.265776] ipw2100: Intel(R) PRO/Wireless 2100 Network Driver, git-1.2.2
[   24.265782] ipw2100: Copyright(c) 2003-2006 Intel Corporation
[   24.266741] ACPI: PCI Interrupt 0000:02:07.0[A] -> Link [LNKE] -> GSI 5 
(level, low) -> IRQ 5
[   24.274343] ipw2100: Detected Intel PRO/Wireless 2100 Network Connection
[   24.614658] [13] FU [Mic Playback Switch] ch = 1, val = 0/1/1
[   24.627606] cannot set ctl value: req = 0x4, wValue = 0x200, wIndex = 0xd00, 
type = 4, data = 0x18/0x0
[   24.631605] [13] FU [Mic Playback Volume] ch = 1, val = 0/12240/48
[   24.631626] [14] FU [Line Playback Switch] ch = 1, val = 0/1/1
[   24.644582] cannot set ctl value: req = 0x4, wValue = 0x201, wIndex = 0xe00, 
type = 4, data = 0x18/0x0
[   24.649580] [14] FU [Line Playback Volume] ch = 2, val = 0/12240/48
[   24.649599] [9] FU [Master Playback Switch] ch = 1, val = 0/1/1
[   24.662564] cannot set ctl value: req = 0x4, wValue = 0x201, wIndex = 0x900, 
type = 4, data = 0x18/0x0
[   24.667575] [9] FU [Master Playback Volume] ch = 2, val = -12256/-16/48
[   24.667623] [10] FU [Mic Capture Switch] ch = 1, val = 0/1/1
[   24.680543] cannot set ctl value: req = 0x4, wValue = 0x200, wIndex = 0xa00, 
type = 4, data = 0x18/0x0
[   24.685537] [10] FU [Mic Capture Volume] ch = 1, val = -48/6096/48
[   24.685554] [10] FU [Mic Boost] ch = 1, val = 0/1/1
[   24.685567] [11] FU [Line Capture Switch] ch = 1, val = 0/1/1
[   24.698518] cannot set ctl value: req = 0x4, wValue = 0x201, wIndex = 0xb00, 
type = 4, data = 0x18/0x0
[   24.703522] [11] FU [Line Capture Volume] ch = 2, val = -48/6096/48
[   24.703543] [12] FU [Digital In Playback Switch] ch = 1, val = 0/1/1
[   24.703597] [8] SU [Capture Input Source] items = 3
[   24.705541] usb 1-2.2: adding 1-2.2:1.1 (config #1, interface 1)
[   24.705720] usb 1-2.2:1.1: uevent
[   24.705846] usb 1-2.2: adding 1-2.2:1.2 (config #1, interface 2)
[   24.705990] usb 1-2.2:1.2: uevent
[   24.706279] drivers/usb/core/inode.c: creating file '005'
[   24.708502] hub 1-2:1.0: state 7 ports 4 chg 0000 evt 0004
[   24.870406] hub 2-0:1.0: hub_suspend
[   24.870493] usb usb2: suspend_rh
[   24.870884] usb usb2: usb auto-suspend
[   24.870950] hub 3-0:1.0: hub_suspend
[   24.870954] usb usb3: suspend_rh
[   24.870965] usb usb3: usb auto-suspend
[   25.123613] ieee1394: Host added: ID:BUS[0-00:1023]  GUID[0000f0414001c880]
[   25.217333] usb 4-1: usb auto-suspend
[   25.371786] hub 4-0:1.0: hub_suspend
[   25.371979] usb usb4: usb auto-suspend
[   26.125010] cs: IO port probe 0xc00-0xcff: clean.
[   26.125931] cs: IO port probe 0x800-0x8ff:<3>irq 10: nobody cared (try 
booting with the "irqpoll" option)
[   28.956096]  [<c01034ee>] dump_trace+0x64/0x1d0
[   28.956109]  [<c0103674>] show_trace_log_lvl+0x1a/0x2f
[   28.956117]  [<c0103d29>] show_trace+0x12/0x14
[   28.956123]  [<c0103dcc>] dump_stack+0x19/0x1b
[   28.956130]  [<c0141525>] __report_bad_irq+0x39/0x81
[   28.956139]  [<c0141706>] note_interrupt+0x199/0x1d3
[   28.956146]  [<c0141f80>] handle_level_irq+0xa3/0xdf
[   28.956154]  [<c0104ef8>] do_IRQ+0xcb/0xf7
[   28.956161]  [<c01030b9>] common_interrupt+0x25/0x2c
[   28.956172]  [<c011bf29>] __do_softirq+0x4c/0xb0
[   28.956181]  [<c0104dbf>] do_softirq+0x5b/0xc9
[   28.956188]  [<c011bed1>] irq_exit+0x3f/0x4b
[   28.956194]  [<c0104f0c>] do_IRQ+0xdf/0xf7
[   28.956201]  [<c01030b9>] common_interrupt+0x25/0x2c
[   28.956210]  [<f00783dd>] adjust_io+0xfd/0x2a6 [rsrc_nonstatic]
[   28.956227]  [<f0079193>] store_io_db+0x93/0xb3 [rsrc_nonstatic]
[   28.956240]  [<c0274243>] class_device_attr_store+0x1d/0x22
[   28.956250]  [<c018e4bc>] sysfs_write_file+0xab/0xd2
[   28.956260]  [<c015d5c9>] vfs_write+0x90/0x149
[   28.956270]  [<c015db4f>] sys_write+0x3d/0x61
[   28.956277]  [<c0102e4d>] sysenter_past_esp+0x56/0x8d
[   28.956285]  [<b7f29410>] 0xb7f29410
[   28.956291]  =======================
[   28.956293] handlers:
[   28.956295] [<c03048c6>] (snd_intel8x0_interrupt+0x0/0x1d5)
[   28.956306] [<c03067bd>] (snd_intel8x0_interrupt+0x0/0x1a4)
[   28.956312] [<c02bb774>] (usb_hcd_irq+0x0/0x51)
[   28.956319] [<c02bb774>] (usb_hcd_irq+0x0/0x51)
[   28.956324] [<f015d4fe>] (yenta_interrupt+0x0/0xb7 [yenta_socket])
[   28.956336] [<f015d4fe>] (yenta_interrupt+0x0/0xb7 [yenta_socket])
[   28.956345] Disabling IRQ #10
[   29.084240]  excluding 0x800-0x80f
[   29.085046] cs: IO port probe 0x100-0x4ff: excluding 0x4d0-0x4d7
[   29.088610] cs: IO port probe 0xa00-0xaff: clean.
[   29.091813] cs: IO port probe 0xc00-0xcff: clean.
[   29.092741] cs: IO port probe 0x800-0x8ff: excluding 0x800-0x80f
[   29.097742] cs: IO port probe 0x100-0x4ff: excluding 0x4d0-0x4d7
[   29.102263] cs: IO port probe 0xa00-0xaff: clean.
[   30.977674] Initializing USB Mass Storage driver...
[   30.978884] usb-storage 4-1:1.0: usb_probe_interface
[   30.978891] usb-storage 4-1:1.0: usb_probe_interface - got id
[   30.978907] usb usb4: usb auto-resume
[   30.978910] usb usb4: finish resume
[   30.979185] hub 4-0:1.0: hub_resume
[   30.979194] ehci_hcd 0000:00:1d.7: resume root hub
[   31.009825] hub 4-0:1.0: state 7 ports 6 chg 0000 evt 0000
[   31.009855] usb 4-1: usb auto-resume
[   31.035720] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   31.046657] usb 4-1: finish resume
[   36.040436] usb 4-1: modprobe timed out on ep0in len=0/2
[   36.050614] usb 4-1: gone after usb resume? status -110
[   36.050620] hub 4-0:1.0: logical disconnect on port 1
[   36.050648] usb 4-1: can't resume, status -110
[   36.050675] hub 4-0:1.0: port 1, status 0501, change 0000, 480 Mb/s
[   36.050680] usb 4-1: USB disconnect, address 2
[   36.050687] usb-storage: probe of 4-1:1.0 failed with error -110
[   36.050723] usb 4-1: unregistering device
[   36.050727] usb 4-1: usb_disable_device nuking all URBs
[   36.050743] usb 4-1: unregistering interface 4-1:1.0
[   36.051102]  usbdev4.2_ep01: ep_device_release called for usbdev4.2_ep01
[   36.051372]  usbdev4.2_ep82: ep_device_release called for usbdev4.2_ep82
[   36.051638] usb 4-1:1.0: uevent
[   36.053138]  usbdev4.2_ep00: ep_device_release called for usbdev4.2_ep00
[   36.053437] usb 4-1: uevent
[   36.058222] usbcore: registered new interface driver usb-storage
[   36.058229] USB Mass Storage support registered.
[   36.104577] ehci_hcd 0000:00:1d.7: port 1 high speed
[   36.104586] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   36.155320] usb 4-1: new high speed USB device using ehci_hcd and address 4
[   37.154046] usb 4-1: khubd timed out on ep0in len=0/64
[   37.215176] ehci_hcd 0000:00:1d.7: port 1 high speed
[   37.215183] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   38.007191] EXT3 FS on hda5, internal journal
[   38.444438] ACPI: CPU0 (power states: C1[C1] C2[C2] C3[C3])
[   38.444450] ACPI: Processor [CPU0] (supports 8 throttling states)
[   28.389000] Time: acpi_pm clocksource has been installed.
[   28.480000] acpi_processor-0740 [00] processor_preregister_: Error while 
parsing _PSD domain information. Assuming no coordination
[   29.672000] kjournald starting.  Commit interval 5 seconds
[   29.690000] EXT3 FS on hda9, internal journal
[   29.690000] EXT3-fs: mounted filesystem with ordered data mode.
[   29.826000] Adding 1004020k swap on /dev/hda8.  Priority:-1 extents:1 
across:1004020k
[   32.198000] usb 4-1: khubd timed out on ep0out len=0/0
[   37.409000] usb 4-1: khubd timed out on ep0out len=0/0
[   37.620000] usb 4-1: device not accepting address 4, error -110
[   37.671000] ehci_hcd 0000:00:1d.7: port 1 high speed
[   37.671000] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   37.722000] usb 4-1: new high speed USB device using ehci_hcd and address 5
[   37.829000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   37.997000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   38.247000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   38.497000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   38.722000] usb 4-1: khubd timed out on ep0in len=0/64
[   38.747000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   38.783000] ehci_hcd 0000:00:1d.7: port 1 high speed
[   38.783000] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   38.997000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   39.247000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   39.497000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   39.747000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   39.997000] usb 1-2.2: alsactl timed out on ep0in len=-8/1
[   40.147000] cannot get ctl value: req = 0x81, wValue = 0x100, wIndex = 
0xd00, type = 1
[   43.834000] usb 4-1: khubd timed out on ep0out len=0/0
[   49.045000] usb 4-1: khubd timed out on ep0out len=0/0
[   49.256000] usb 4-1: device not accepting address 5, error -110
[   49.307000] ehci_hcd 0000:00:1d.7: port 1 high speed
[   49.307000] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   49.358000] usb 4-1: new high speed USB device using ehci_hcd and address 6
[   54.358000] usb 4-1: khubd timed out on ep0out len=0/0
[   59.569000] usb 4-1: khubd timed out on ep0out len=0/0
[   59.780000] usb 4-1: device not accepting address 6, error -110
[   59.831000] ehci_hcd 0000:00:1d.7: port 1 high speed
[   59.831000] ehci_hcd 0000:00:1d.7: GetStatus port 1 status 001005 POWER 
sig=se0 PE CONNECT
[   59.882000] usb 4-1: new high speed USB device using ehci_hcd and address 7
[   64.882000] usb 4-1: khubd timed out on ep0out len=0/0
[   70.093000] usb 4-1: khubd timed out on ep0out len=0/0
[   70.304000] usb 4-1: device not accepting address 7, error -110
[   70.304000] hub 4-0:1.0: state 7 ports 6 chg 0000 evt 0000
[   72.304000] hub 4-0:1.0: hub_suspend
[   72.304000] usb usb4: usb auto-suspend
[   77.936000] setting usb interface 1:1
[   78.420000]  usbdev1.5_ep01: ep_device_release called for usbdev1.5_ep01

-------------------------------------------------------------------------
Take Surveys. Earn Cash. Influence the Future of IT
Join SourceForge.net's Techsay panel and you'll get the chance to share your
opinions on IT & business topics through brief surveys - and earn cash
http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
_______________________________________________
linux-usb-devel@lists.sourceforge.net
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel

Reply via email to