FS#30117 - [linux] 3.8.5 - 3.9.x USB_SND_AUDIO and Logitech Webcam blocking system

Attached to Project: Arch Linux
Opened by Michael Berg (jk799) - Saturday, 02 June 2012, 19:26 GMT
Last edited by Tobias Powalowski (tpowa) - Tuesday, 06 August 2013, 14:23 GMT
Task Type Bug Report
Category Packages: Core
Status Closed
Assigned To Tobias Powalowski (tpowa)
Thomas Bächler (brain0)
Architecture All
Severity High
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 5
Private No

Details

Description:
Attaching my 046d:0825 Logitech, Inc. Webcam C270 to recent Arch Linux (Linux scott 3.3.7-1-ARCH #1 SMP PREEMPT Tue May 22 00:26:26 CEST 2012 x86_64 GNU/Linux) causes a flood of

Jun 2 20:42:25 localhost kernel: [ 992.525555] 2:3:4: cannot set freq 48000 to ep 0x86
Jun 2 20:42:26 localhost kernel: [ 993.526739] 2:3:4: cannot set freq 48000 to ep 0x86
Jun 2 20:42:27 localhost kernel: [ 994.546810] 2:3:4: cannot set freq 48000 to ep 0x86
Jun 2 20:42:28 localhost kernel: [ 995.548110] 2:3:4: cannot set freq 48000 to ep 0x86
Jun 2 20:42:29 localhost kernel: [ 996.547415] 2:3:4: cannot set freq 48000 to ep 0x86

in the kernel Log.

All Applications that want to play a sound hang then.
Booting is also affected with the webcam attached. SOMETIMES this "cannot set freq"-flood-Problem is not occuring on Boot time, the Systems boots perfectly normal then.

I think there is an upstream Bugreport here https://bugzilla.kernel.org/show_bug.cgi?id=35922 but this is referring to Kernel 2.6.x and its status is resolved.
Maybe there is a regression in recent Kernels?

However, blacklisting the USB_SND_AUDIO Kernel module fixes this, but then no usb audio device is working :-(
This task depends upon

Closed by  Tobias Powalowski (tpowa)
Tuesday, 06 August 2013, 14:23 GMT
Reason for closing:  No response
Comment by Tobias Powalowski (tpowa) - Monday, 11 June 2012, 13:34 GMT
Status on 3.4.x?
Comment by Michael Berg (jk799) - Monday, 11 June 2012, 15:07 GMT
I'm currently installing linux-3.4.2-1-x86_64.pkg.tar.xz from http://www.archlinux.org/packages/testing/x86_64/linux/
(Is there yet a stable 3.4x?)
stand by a sec.
Comment by Michael Berg (jk799) - Monday, 11 June 2012, 15:22 GMT
the webcam is currently not causing the system to hang but still produces this on bootup:


Jun 11 17:18:03 localhost kernel: [ 3.813305] snd_hda_intel 0000:01:00.1: irq 44 for MSI/MSI-X
Jun 11 17:18:03 localhost kernel: [ 3.833130] input: HDA ATI HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:02.0/0000:01:00.1/sound/card2/input14

Jun 11 17:18:03 localhost kernel: [ 4.191125] 4:3:1: cannot set freq 16000 to ep 0x86
Jun 11 17:18:03 localhost kernel: [ 5.188346] 4:3:2: cannot set freq 24000 to ep 0x86
Jun 11 17:18:03 localhost kernel: [ 6.185686] 4:3:3: cannot set freq 32000 to ep 0x86
Jun 11 17:18:03 localhost kernel: [ 7.181532] 4:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:18:03 localhost kernel: [ 7.182319] usbcore: registered new interface driver snd-usb-audio

Jun 11 17:18:03 localhost kernel: [ 7.182330] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:18:03 localhost kernel: [ 7.197741] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input15
Jun 11 17:18:03 localhost kernel: [ 7.197959] usbcore: registered new interface driver uvcvideo
Jun 11 17:18:03 localhost kernel: [ 7.197964] USB Video Class driver (1.1.1)
Jun 11 17:18:03 localhost kernel: [ 13.159855] generic-usb 0003:1E7D:2CB0.0003: usb_submit_urb(ctrl) failed: -1
Jun 11 17:18:03 localhost kernel: [ 13.160034] generic-usb 0003:1E7D:2CB0.0003: timeout initializing reports
Jun 11 17:18:03 localhost kernel: [ 13.160288] input: ROCCAT ROCCAT Kova as /devices/pci0000:00/0000:00:12.1/usb4/4-3/4-3:1.0/input/input16

I'm going to watch this a few hours or days until i can be sure that its fixed :D have you done anything to fix it or is some kind of "luck"? :)
Comment by Tobias Powalowski (tpowa) - Monday, 11 June 2012, 15:25 GMT
could it be that you can enable and disable the camera by a hardware switch?
Try to disable and enable it again.
Comment by Michael Berg (jk799) - Monday, 11 June 2012, 15:47 GMT
no, its an external camera, no hardware switch.
ive pluged it in and out like 10 times now, everything still ok.

/var/log/messages.log
Jun 11 17:28:58 localhost kernel: [ 266.817044] usb 1-4: USB disconnect, device number 4
Jun 11 17:29:00 localhost kernel: [ 268.420290] usb 1-4: new high-speed USB device number 7 using ehci_hcd
Jun 11 17:29:00 localhost kernel: [ 268.771581] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:00 localhost kernel: [ 268.868732] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input20
Jun 11 17:29:01 localhost kernel: [ 268.998244] 7:3:1: cannot get freq at ep 0x86
Jun 11 17:29:01 localhost kernel: [ 269.055696] usb 1-4: USB disconnect, device number 7
Jun 11 17:29:03 localhost kernel: [ 270.966700] usb 1-4: new high-speed USB device number 8 using ehci_hcd
Jun 11 17:29:03 localhost kernel: [ 271.309702] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:03 localhost kernel: [ 271.409651] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input21
Jun 11 17:29:04 localhost kernel: [ 272.412830] 8:3:1: cannot get freq at ep 0x86
Jun 11 17:29:07 localhost pulseaudio[1102]: [pulseaudio] source.c: Default and alternate sample rates are the same.

Jun 11 17:29:25 localhost kernel: [ 293.006880] usb 1-4: USB disconnect, device number 8

Jun 11 17:29:28 localhost kernel: [ 296.336102] usb 1-4: new high-speed USB device number 9 using ehci_hcd
Jun 11 17:29:28 localhost kernel: [ 296.688777] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:28 localhost kernel: [ 296.790776] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input22
Jun 11 17:29:29 localhost kernel: [ 297.793606] 9:3:1: cannot get freq at ep 0x86

Jun 11 17:29:33 localhost kernel: [ 301.153481] usb 1-4: USB disconnect, device number 9

Jun 11 17:29:36 localhost dbus[505]: [system] Failed to activate service 'org.freedesktop.Avahi': timed out
Jun 11 17:29:36 localhost kernel: [ 304.552556] usb 1-4: new high-speed USB device number 10 using ehci_hcd
Jun 11 17:29:37 localhost kernel: [ 304.899777] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:37 localhost kernel: [ 304.998656] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input23
Jun 11 17:29:38 localhost pulseaudio[1102]: [pulseaudio] source.c: Default and alternate sample rates are the same.

Jun 11 17:29:43 localhost kernel: [ 311.112940] usb 1-4: USB disconnect, device number 10
Jun 11 17:29:46 localhost kernel: [ 313.839904] usb 1-4: new high-speed USB device number 11 using ehci_hcd
Jun 11 17:29:46 localhost kernel: [ 314.182790] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:46 localhost kernel: [ 314.284418] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input24
Jun 11 17:29:47 localhost kernel: [ 315.286704] 11:3:1: cannot get freq at ep 0x86


Jun 11 17:29:53 localhost kernel: [ 321.473795] usb 1-4: USB disconnect, device number 11
Jun 11 17:29:57 localhost kernel: [ 324.785127] usb 1-4: new high-speed USB device number 12 using ehci_hcd
Jun 11 17:29:57 localhost kernel: [ 325.136544] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:57 localhost kernel: [ 325.238706] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input25
Jun 11 17:29:58 localhost kernel: [ 326.237965] 12:3:1: cannot get freq at ep 0x86
Jun 11 17:30:02 localhost kernel: [ 330.420438] usb 1-4: USB disconnect, device number 12
Jun 11 17:30:08 localhost dbus[505]: [system] Failed to activate service 'org.freedesktop.Avahi': timed out
Jun 11 17:30:10 localhost kernel: [ 338.009082] usb 2-6: new high-speed USB device number 3 using ehci_hcd
Jun 11 17:30:10 localhost kernel: [ 338.355389] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:30:10 localhost kernel: [ 338.456329] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:13.2/usb2/2-6/2-6:1.0/input/input26
Jun 11 17:30:11 localhost kernel: [ 339.460068] 3:3:1: cannot get freq at ep 0x86
Jun 11 17:30:17 localhost kernel: [ 344.994121] usb 2-6: USB disconnect, device number 3
Jun 11 17:30:21 localhost kernel: [ 349.512408] usb 2-5: new high-speed USB device number 4 using ehci_hcd
Jun 11 17:30:22 localhost kernel: [ 349.862963] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:30:22 localhost kernel: [ 349.961656] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:13.2/usb2/2-5/2-5:1.0/input/input27
Jun 11 17:30:23 localhost kernel: [ 350.963746] 4:3:1: cannot get freq at ep 0x86


____
as i wrote this answer, the system got stuck again, ctrl alt f1 shows scrolling cannot set freq and my desktop wasnt reacting, i had to ctrl alt del. all that after i stopped plugging in and out the cam. wise firefox stored my form data, tank you, firefox :)

here are the fresh error lines:

Jun 11 17:28:51 localhost kernel: [ 259.282034] delay: estimated 178, actual 89
Jun 11 17:28:51 localhost kernel: [ 259.327919] delay: estimated 177, actual 88
Jun 11 17:28:58 localhost kernel: [ 266.817044] usb 1-4: USB disconnect, device number 4
Jun 11 17:29:00 localhost kernel: [ 268.420290] usb 1-4: new high-speed USB device number 7 using ehci_hcd
Jun 11 17:29:00 localhost kernel: [ 268.771581] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:00 localhost kernel: [ 268.868732] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input20
Jun 11 17:29:01 localhost kernel: [ 268.998244] 7:3:1: cannot get freq at ep 0x86
Jun 11 17:29:01 localhost kernel: [ 269.014426] 7:3:2: cannot set freq 24000 to ep 0x86
Jun 11 17:29:01 localhost kernel: [ 269.030627] 7:3:3: cannot set freq 32000 to ep 0x86
Jun 11 17:29:01 localhost kernel: [ 269.046830] 7:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:01 localhost kernel: [ 269.055696] usb 1-4: USB disconnect, device number 7
Jun 11 17:29:03 localhost kernel: [ 270.966700] usb 1-4: new high-speed USB device number 8 using ehci_hcd
Jun 11 17:29:03 localhost kernel: [ 271.309702] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:03 localhost kernel: [ 271.409651] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input21
Jun 11 17:29:04 localhost kernel: [ 272.412830] 8:3:1: cannot get freq at ep 0x86
Jun 11 17:29:05 localhost kernel: [ 273.409297] 8:3:2: cannot set freq 24000 to ep 0x86
Jun 11 17:29:06 localhost kernel: [ 274.407637] 8:3:3: cannot set freq 32000 to ep 0x86
Jun 11 17:29:07 localhost kernel: [ 275.405726] 8:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:25 localhost kernel: [ 293.006880] usb 1-4: USB disconnect, device number 8
Jun 11 17:29:28 localhost kernel: [ 296.336102] usb 1-4: new high-speed USB device number 9 using ehci_hcd
Jun 11 17:29:28 localhost kernel: [ 296.688777] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:28 localhost kernel: [ 296.790776] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input22
Jun 11 17:29:29 localhost kernel: [ 297.793606] 9:3:1: cannot get freq at ep 0x86
Jun 11 17:29:30 localhost kernel: [ 298.790822] 9:3:2: cannot set freq 24000 to ep 0x86
Jun 11 17:29:31 localhost kernel: [ 299.786793] 9:3:3: cannot set freq 32000 to ep 0x86
Jun 11 17:29:32 localhost kernel: [ 300.785009] 9:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:33 localhost kernel: [ 301.153481] usb 1-4: USB disconnect, device number 9
Jun 11 17:29:33 localhost kernel: [ 301.161314] 9:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:33 localhost kernel: [ 301.161378] 9:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:33 localhost kernel: [ 301.161405] 9:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:33 localhost kernel: [ 301.161800] 9:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:33 localhost kernel: [ 301.162059] 9:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:33 localhost kernel: [ 301.162440] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.162454] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.162475] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.162688] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.162970] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.163444] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.163475] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.163557] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.163802] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.164084] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.164670] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.164699] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.164786] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.165039] 9:3:4: usb_set_interface failed
Jun 11 17:29:33 localhost kernel: [ 301.165324] 9:3:4: usb_set_interface failed
Jun 11 17:29:36 localhost kernel: [ 304.552556] usb 1-4: new high-speed USB device number 10 using ehci_hcd
Jun 11 17:29:37 localhost kernel: [ 304.899777] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:37 localhost kernel: [ 304.998656] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input23
Jun 11 17:29:43 localhost kernel: [ 311.112940] usb 1-4: USB disconnect, device number 10
Jun 11 17:29:46 localhost kernel: [ 313.839904] usb 1-4: new high-speed USB device number 11 using ehci_hcd
Jun 11 17:29:46 localhost kernel: [ 314.182790] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:46 localhost kernel: [ 314.284418] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input24
Jun 11 17:29:47 localhost kernel: [ 315.286704] 11:3:1: cannot get freq at ep 0x86
Jun 11 17:29:48 localhost kernel: [ 316.282309] 11:3:2: cannot set freq 24000 to ep 0x86
Jun 11 17:29:49 localhost kernel: [ 317.280140] 11:3:3: cannot set freq 32000 to ep 0x86
Jun 11 17:29:50 localhost kernel: [ 318.277607] 11:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:51 localhost kernel: [ 319.330046] 11:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:52 localhost kernel: [ 320.328761] 11:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:53 localhost kernel: [ 321.325979] 11:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:53 localhost kernel: [ 321.473795] usb 1-4: USB disconnect, device number 11
Jun 11 17:29:53 localhost kernel: [ 321.482037] 11:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:53 localhost kernel: [ 321.482869] 11:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:29:53 localhost kernel: [ 321.483610] 11:3:4: usb_set_interface failed
Jun 11 17:29:53 localhost kernel: [ 321.483647] 11:3:4: usb_set_interface failed
Jun 11 17:29:53 localhost kernel: [ 321.483708] 11:3:4: usb_set_interface failed
Jun 11 17:29:53 localhost kernel: [ 321.484305] 11:3:4: usb_set_interface failed
Jun 11 17:29:53 localhost kernel: [ 321.484954] 11:3:4: usb_set_interface failed
Jun 11 17:29:57 localhost kernel: [ 324.785127] usb 1-4: new high-speed USB device number 12 using ehci_hcd
Jun 11 17:29:57 localhost kernel: [ 325.136544] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:29:57 localhost kernel: [ 325.238706] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-4/1-4:1.0/input/input25
Jun 11 17:29:58 localhost kernel: [ 326.237965] 12:3:1: cannot get freq at ep 0x86
Jun 11 17:29:59 localhost kernel: [ 327.235798] 12:3:2: cannot set freq 24000 to ep 0x86
Jun 11 17:30:00 localhost kernel: [ 328.234762] 12:3:3: cannot set freq 32000 to ep 0x86
Jun 11 17:30:01 localhost kernel: [ 329.229732] 12:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:02 localhost kernel: [ 330.293507] 12:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:02 localhost kernel: [ 330.420438] usb 1-4: USB disconnect, device number 12
Jun 11 17:30:02 localhost kernel: [ 330.428382] 12:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:02 localhost kernel: [ 330.428521] 12:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:02 localhost kernel: [ 330.429204] 12:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:02 localhost kernel: [ 330.429900] 12:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:02 localhost kernel: [ 330.430643] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.430679] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.430740] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.431241] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.431818] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.432790] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.432868] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.433168] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.433858] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.434580] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.436065] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.436144] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.436450] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.437106] 12:3:4: usb_set_interface failed
Jun 11 17:30:02 localhost kernel: [ 330.437855] 12:3:4: usb_set_interface failed
Jun 11 17:30:10 localhost kernel: [ 338.009082] usb 2-6: new high-speed USB device number 3 using ehci_hcd
Jun 11 17:30:10 localhost kernel: [ 338.355389] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Jun 11 17:30:10 localhost kernel: [ 338.456329] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:13.2/usb2/2-6/2-6:1.0/input/input26
Jun 11 17:30:11 localhost kernel: [ 339.460068] 3:3:1: cannot get freq at ep 0x86
Jun 11 17:30:12 localhost kernel: [ 340.457538] 3:3:2: cannot set freq 24000 to ep 0x86
Jun 11 17:30:13 localhost kernel: [ 341.455125] 3:3:3: cannot set freq 32000 to ep 0x86
Jun 11 17:30:14 localhost kernel: [ 342.450347] 3:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:15 localhost kernel: [ 343.527340] 3:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:16 localhost kernel: [ 344.525679] 3:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:17 localhost kernel: [ 344.994121] usb 2-6: USB disconnect, device number 3
Jun 11 17:30:17 localhost kernel: [ 345.001722] 3:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:17 localhost kernel: [ 345.002424] 3:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:17 localhost kernel: [ 345.003034] 3:3:4: cannot set freq 48000 to ep 0x86
Jun 11 17:30:17 localhost kernel: [ 345.003852] 3:3:4: usb_set_interface failed
Jun 11 17:30:17 localhost kernel: [ 345.003891] 3:3:4: usb_set_interface failed
Jun 11 17:30:17 localhost kernel: [ 345.003951] 3:3:4: usb_set_interface failed
Jun 11 17:30:17 localhost kernel: [ 345.004459] 3:3:4: usb_set_interface failed
...

ps. the kernel log is now flooded by other lines too, but i think thats another problem.

i added the kernel.log beginning at the first bootup with the testing kernel
   kernel.log (1.45 MiB)
Comment by Marti (intgr) - Thursday, 28 June 2012, 19:43 GMT
I seem to have a similar problem on my desktop computer, running 3.4.4-2-ARCH. I have a Logitech webcam, including a microphone, and a TI PCM2902 USB sound card. I don't get the "cannot set freq" errors, but I also get these errors, in batches, at seemingly arbitrary intervals:
[ 238.109437] delay: estimated 90, actual 1
[ 238.136337] delay: estimated 353, actual 1
[ 238.141279] delay: estimated 134, actual 1
[ 238.170137] delay: estimated 353, actual 0

And this error exactly every 5 seconds:
[ 1320.180743] 4:1:1: usb_set_interface failed
[ 1325.167944] 4:1:1: usb_set_interface failed
[ 1330.156038] 4:1:1: usb_set_interface failed
[ 1335.144181] 4:1:1: usb_set_interface failed

Worse, my keyboard worked at boot, but after unplugging and re-plugging it, it's no longer working -- not listed in "lsusb" output either.

Another side-effect is seemingly slow boot and shutdown -- which hangs for half a minute or so.
(application/x-gzip)    dmesg.log.gz (27.2 KiB)
Comment by Marti (intgr) - Thursday, 28 June 2012, 19:48 GMT
Oh, I also get these messages in dmesg which don't look right, but are not present in the OP's dmesg:
[ 78.731413] retire_capture_urb: 96 callbacks suppressed
[ 83.899955] retire_capture_urb: 387 callbacks suppressed
...
[ 1554.613245] retire_capture_urb: 696 callbacks suppressed
Comment by Marti (intgr) - Thursday, 28 June 2012, 20:08 GMT
Sorry about spamming the bug tracker... But after unplugging all USB devices, I got this oops in my dmesg:

[ 1796.493263] INFO: task khubd:214 blocked for more than 120 seconds.
[ 1796.493271] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1796.493278] khubd D ffff8802237b7ad8 0 214 2 0x00000000
[ 1796.493289] ffff880222843c10 0000000000000046 ffff8802237b7710 ffff880222843fd8
[ 1796.493301] ffff880222843fd8 ffff880222843fd8 ffff880224120000 ffff8802237b7710
[ 1796.493311] ffff880222843b50 ffffffffa03ad1dd ffff88021c649000 0000000000000286
[ 1796.493320] Call Trace:
[ 1796.493349] [<ffffffffa03ad1dd>] ? start_ed_unlink+0x1d/0x70 [ohci_hcd]
[ 1796.493363] [<ffffffffa03ae620>] ? ohci_urb_dequeue+0x80/0xd0 [ohci_hcd]
[ 1796.493384] [<ffffffffa0342144>] ? unlink1+0x34/0x160 [usbcore]
[ 1796.493394] [<ffffffff8130b6c7>] ? put_device+0x17/0x20
[ 1796.493404] [<ffffffff81468b29>] schedule+0x29/0x70
[ 1796.493422] [<ffffffffa0344945>] usb_kill_urb+0x75/0xb0 [usbcore]
[ 1796.493431] [<ffffffff81073b60>] ? abort_exclusive_wait+0xb0/0xb0
[ 1796.493451] [<ffffffffa03455f8>] usb_start_wait_urb+0xd8/0xf0 [usbcore]
[ 1796.493469] [<ffffffffa034587e>] usb_control_msg+0xde/0x140 [usbcore]
[ 1796.493486] [<ffffffffa0345ebe>] usb_get_descriptor+0x8e/0xd0 [usbcore]
[ 1796.493503] [<ffffffffa0340a0b>] hub_thread+0xf6b/0x12e0 [usbcore]
[ 1796.493511] [<ffffffff81073b60>] ? abort_exclusive_wait+0xb0/0xb0
[ 1796.493527] [<ffffffffa033faa0>] ? usb_remote_wakeup+0x40/0x40 [usbcore]
[ 1796.493534] [<ffffffff810731d3>] kthread+0x93/0xa0
[ 1796.493543] [<ffffffff8146bbe4>] kernel_thread_helper+0x4/0x10
[ 1796.493551] [<ffffffff81073140>] ? kthread_freezable_should_stop+0x70/0x70
[ 1796.493559] [<ffffffff8146bbe0>] ? gs_change+0x13/0x13

----

Then I remembered that I'd had USB problems with this machine before. A reboot or a poweroff didn't fix it. But physically removing the power cord and replugging after a while, did! I guess the USB controller itself gets into some weird state and it's not completely powered off even after shutdown.

I have an ASUS M3A76-CM motherboard using AMD 760G chipset. BIOS updated as of 09/15/2010.
Comment by Greg (dolby) - Monday, 15 October 2012, 03:45 GMT
Status with 3.6.2?
Comment by Michael Berg (jk799) - Monday, 15 October 2012, 18:14 GMT
Will check when at home, thanks that you care :-)
Comment by Christophe (tolgam) - Monday, 15 October 2012, 20:13 GMT
Seems to work fine!
Comment by st.binder (nium) - Thursday, 15 November 2012, 00:01 GMT
  • Field changed: Percent Complete (100% → 0%)
I have the same problem with Logitech webcam c310 and linux kernel 3.6.3 x64:
[ 2336.291254] usb 4-2: new high-speed USB device number 5 using ehci_hcd
[ 2336.630087] uvcvideo: Found UVC 1.00 device <unnamed> (046d:081b)
[ 2336.726552] input: UVC Camera (046d:081b) as /devices/pci0000:00/0000:00:13.2/usb4/4-2/4-2:1.0/input/input14
[ 2337.808678] 5:3:4: cannot set freq 48000 to ep 0x86
[ 2338.806853] 5:3:4: cannot set freq 48000 to ep 0x86
[ 2339.805152] 5:3:4: cannot set freq 48000 to ep 0x86
[ 2340.803318] 5:3:4: cannot set freq 48000 to ep 0x86
[ 2341.801619] 5:3:4: cannot set freq 48000 to ep 0x86
system hangs on boot and is unusable. A workaround: disable camera audio in gnome settings, plug-in, enable audio again -> camera+audio works
Comment by st.binder (nium) - Thursday, 15 November 2012, 19:14 GMT
seems to work now (no problems since yesterday)
Comment by Michael Berg (jk799) - Tuesday, 09 April 2013, 08:21 GMT
Long time since I plugged the camera in again.
And it still does:

Apr 8 16:44:16 localhost kernel: [ 1992.235197] usb 1-6: new high-speed USB device number 3 using ehci-pci
Apr 8 16:44:16 localhost kernel: [ 1992.714006] media: Linux media interface: v0.10
Apr 8 16:44:16 localhost kernel: [ 1992.724131] Linux video capture interface: v2.00
Apr 8 16:44:16 localhost kernel: [ 1992.727813] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
Apr 8 16:44:16 localhost kernel: [ 1992.746167] input: UVC Camera (046d:0825) as /devices/pci0000:00/0000:00:12.2/usb1/1-6/1-6:1.0/input/input26
Apr 8 16:44:16 localhost kernel: [ 1992.746371] usbcore: registered new interface driver uvcvideo
Apr 8 16:44:16 localhost kernel: [ 1992.746376] USB Video Class driver (1.1.1)
Apr 8 16:44:16 localhost colord: Device added: sysfs-046d-0825
Apr 8 16:44:17 localhost kernel: [ 1993.781064] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:18 localhost kernel: [ 1994.778406] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:19 localhost kernel: [ 1995.775623] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:20 localhost kernel: [ 1996.772962] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:21 localhost kernel: [ 1997.770172] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:22 localhost kernel: [ 1998.767409] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:23 localhost kernel: [ 1999.764759] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:24 localhost kernel: [ 2000.762078] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:25 localhost kernel: [ 2001.759294] 3:3:4: cannot set freq 48000 to ep 0x86
Apr 8 16:44:26 localhost kernel: [ 2002.756636] 3:3:4: cannot set freq 48000 to ep 0x86
Comment by Tobias Powalowski (tpowa) - Tuesday, 09 April 2013, 08:23 GMT
Which kernel is now affected?
Comment by Michael Berg (jk799) - Tuesday, 09 April 2013, 21:50 GMT
I'm running linux 3.8.5-1 x86_64 (stock).

System hung today on bootup with the typical messages "3:3:4: cannot set freq 48000 to ep 0x86". Plugging off the camera unfroze the system immediately.

Device: Bus 001 Device 004: ID 046d:0825 Logitech, Inc. Webcam C270
Here the output of lsusb -v (only the webcam): http://pastebin.com/5ZFCHwrk
Comment by Tobias Powalowski (tpowa) - Thursday, 23 May 2013, 19:41 GMT
Status on 3.9?
Comment by Michael Berg (jk799) - Monday, 27 May 2013, 08:35 GMT
Still the same. :-(
DO you need additional log files?
Comment by Tobias Powalowski (tpowa) - Tuesday, 30 July 2013, 10:33 GMT
Status on 3.10.x?

Loading...