Webcam crashes guest and manager

[stevobailey]

I have a clean install of Windows in a VM which hangs every time I attempt to use my USB webcam. I can connect the camera and install drivers, but when any application tries to use the camera the whole guest system hangs and I have to kill it. VB manager also crashes and I have to restart my host machine to get VB working again (a VirtualBox.exe process can't be killed by Task Manager or the command line). I've seen this in a few other tickets but they mostly were using other host systems (and none were resolved) so I thought it best to create a new ticket.

[stevobailey]

Log file

[volker1]

Webcam crashes guest: do confirm the described problem:

Host: ubuntu 12.04 on dell e6500 and dell D505 Guests: Bodhi 2.2 under ubuntu 12.04 / Lubuntu 13.04 Webcamera: VX-3000

use of any webcam application, such as videoplayer, mplayer, xawtv, cheese leads to freeze of the VM. This is independent on the hardware and the OS of the VM.

No such problem occurs when I use instead the build-in Webcam (Dell e6500 laptop).

[Nicolas]

For me this bug applies as well with the exception of VirtualBox Manager which remains open and shows status "aborted" (translated from german "Abgebrochen") for the crashed VM.

(Maybe) another difference:

The FIRST USE of the usb attached webcam does work without a problem! Regardless on how long the cam is being used.

But after disconnect of corresponding application (Microsoft Lync, Cisco WebEx, ..) and re-use (new video call) the virtual machine crashes.

I _reliably_ works without crash when disconnecting and reconnecting the usb webcam from the virtual machine!!

Then, another "first use" is possible.

I am unsure if the problem arises with video only as I can make a video call via Microsoft Lync when having checked the video device (including test video frame) in the settings dialog prior. So maybe the integrated audio in the usb webcam or the AV combination provokes the problem? This is a guess only .. may be wrong. :)

Host:

OS: Ubuntu 12.04.2 LTS 64 Bit
Kernel: 3.2.0-52-generic
GNOME 3.4.2 (running in classic mode)
Graphics: Gallium 0.4 on AMD CEDAR
Compiz enabled

Guest:

OS: Windows 7 Enterprise 64 Bit
Running in windowed mode

[Nicolas]

Log of virtual machine with manual usb webcam disconnect/reconnects and crash at the end (not visible in log!)

[Nicolas]

I am unsure to post this update here as it may be a slightly different issue: just had a crash of the host system a few seconds after disconnecting a video call from within the guest. This is what syslog tells me:

Aug 26 13:41:39 pspc01 kernel: [13507.199704] uvcvideo: Found UVC 1.00 device HD Webcam C525 (046d:0826)
Aug 26 13:41:39 pspc01 kernel: [13507.446926] input: HD Webcam C525 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.5/2-1.5:1.2/input/input17
Aug 26 13:41:39 pspc01 kernel: [13507.479723] usb_audio: Warning! Unlikely big volume range (=6144), cval->res is probably wrong.
Aug 26 13:41:40 pspc01 rtkit-daemon[2453]: Successfully made thread 5165 of process 3166 (n/a) owned by '1000' RT at priority 5.
Aug 26 13:41:40 pspc01 rtkit-daemon[2453]: Supervising 5 threads of 1 processes of 1 users.
Aug 26 13:41:43 pspc01 pulseaudio[3166]: [alsa-source] alsa-util.c: Got POLLNVAL from ALSA
Aug 26 13:41:43 pspc01 pulseaudio[3166]: [alsa-source] alsa-util.c: Could not recover from POLLERR|POLLNVAL|POLLHUP with snd_pcm_prepare(): Kein passendes Gerät gefunden
Aug 26 13:41:52 pspc01 kernel: [13507.479730] usb_audio: [5] FU [Mic Capture Volume] ch = 1, val = 1536/7680/1
Aug 26 13:41:52 pspc01 kernel: [13519.857443] usb 2-1.5: usbfs: usb_submit_urb returned -27
Aug 26 13:41:52 pspc01 kernel: [13519.857506] usb 2-1.5: usbfs: usb_submit_urb returned -27
Aug 26 13:41:52 pspc01 kernel: [13519.857517] usb 2-1.5: usbfs: usb_submit_urb returned -27
Aug 26 13:42:52 pspc01 kernel: [13579.886803] usb 2-1.5: reset high-speed USB device number 4 using ehci_hcd
Aug 26 13:42:52 pspc01 kernel: [13580.240541] uvcvideo: Found UVC 1.00 device HD Webcam C525 (046d:0826)
Aug 26 13:42:53 pspc01 kernel: [13580.487556] input: HD Webcam C525 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.5/2-1.5:1.2/input/input18
Aug 26 13:42:53 pspc01 kernel: [13580.521949] usb_audio: Warning! Unlikely big volume range (=6144), cval->res is probably wrong.
Aug 26 13:42:53 pspc01 rtkit-daemon[2453]: Successfully made thread 5182 of process 3166 (n/a) owned by '1000' RT at priority 5.
Aug 26 13:42:53 pspc01 rtkit-daemon[2453]: Supervising 5 threads of 1 processes of 1 users.
Aug 26 13:42:56 pspc01 pulseaudio[3166]: [alsa-source] alsa-util.c: Got POLLNVAL from ALSA
Aug 26 13:42:56 pspc01 pulseaudio[3166]: [alsa-source] alsa-util.c: Could not recover from POLLERR|POLLNVAL|POLLHUP with snd_pcm_prepare(): Kein passendes Gerät gefunden
Aug 26 13:43:04 pspc01 kernel: [13580.521955] usb_audio: [5] FU [Mic Capture Volume] ch = 1, val = 1536/7680/1
Aug 26 13:43:04 pspc01 kernel: [13591.538570] usb 2-1.5: usbfs: usb_submit_urb returned -27
Aug 26 13:43:04 pspc01 kernel: [13591.538576] usb 2-1.5: usbfs: usb_submit_urb returned -27
Aug 26 13:43:04 pspc01 kernel: [13591.538581] usb 2-1.5: usbfs: usb_submit_urb returned -27
Aug 26 13:43:47 pspc01 kernel: [13634.991822] usb 2-1.5: reset high-speed USB device number 4 using ehci_hcd
Aug 26 13:43:48 pspc01 kernel: [13635.346065] uvcvideo: Found UVC 1.00 device HD Webcam C525 (046d:0826)
Aug 26 13:43:48 pspc01 kernel: [13635.592938] input: HD Webcam C525 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.5/2-1.5:1.2/input/input19
Aug 26 13:43:48 pspc01 kernel: [13635.626093] usb_audio: Warning! Unlikely big volume range (=6144), cval->res is probably wrong.
Aug 26 13:43:48 pspc01 rtkit-daemon[2453]: Successfully made thread 5197 of process 3166 (n/a) owned by '1000' RT at priority 5.
Aug 26 13:43:48 pspc01 rtkit-daemon[2453]: Supervising 5 threads of 1 processes of 1 users.
Aug 26 13:43:50 pspc01 pulseaudio[3166]: [alsa-source] alsa-util.c: Got POLLNVAL from ALSA
Aug 26 13:43:50 pspc01 pulseaudio[3166]: [alsa-source] alsa-util.c: Could not recover from POLLERR|POLLNVAL|POLLHUP with snd_pcm_prepare(): Kein passendes Gerät gefunden
Aug 26 13:44:11 pspc01 kernel: [13635.626100] usb_audio: [5] FU [Mic Capture Volume] ch = 1, val = 1536/7680/1
Aug 26 13:44:11 pspc01 kernel: [13659.089867] usb 2-1.5: usbfs: usb_submit_urb returned -27
Aug 26 13:44:11 pspc01 kernel: [13659.089875] usb 2-1.5: usbfs: usb_submit_urb returned -27
Aug 26 13:44:11 pspc01 kernel: [13659.089881] usb 2-1.5: usbfs: usb_submit_urb returned -27

Maybe this helps?

[Nicolas]

For comparision, this occurs in syslog when normally disconnecting the webcam:

Aug 26 14:37:25 pspc01 kernel: [  833.844959] usb_audio: [5] FU [Mic Capture Volume] ch = 1, val = 1536/7680/1
Aug 26 14:37:25 pspc01 kernel: [ 2954.981240] usb 2-1.5: reset high-speed USB device number 4 using ehci_hcd
Aug 26 14:37:26 pspc01 kernel: [ 2955.335317] uvcvideo: Found UVC 1.00 device HD Webcam C525 (046d:0826)
Aug 26 14:37:26 pspc01 kernel: [ 2955.582402] input: HD Webcam C525 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.5/2-1.5:1.2/input/input17
Aug 26 14:37:26 pspc01 kernel: [ 2955.614804] usb_audio: Warning! Unlikely big volume range (=6144), cval->res is probably wrong.

Those "unlikely big volumen ranges" seems normal whereas this "usb 2-1.5: usbfs: usb_submit_urb returned -27" seems special and maybe more error descriptive?

Sorry, have to correct the guess above: "usb 2-1.5: usbfs: usb_submit_urb returned -27" appears when connecting the video call.

[Nicolas]

I don't know if this is related, but when setting a video call on hold (which turns the video stream black) and retrieving it afterwards (which revitalizes the video stream) I get this stack trace in syslog:

Aug 26 14:46:17 pspc01 kernel: [ 3485.864293] ------------[ cut here ]------------
Aug 26 14:46:17 pspc01 kernel: [ 3485.864305] WARNING: at /build/buildd/linux-3.2.0/drivers/usb/host/ehci-hcd.c:1186 ehci_endpoint_reset+0x111/0x120()
Aug 26 14:46:17 pspc01 kernel: [ 3485.864310] Hardware name: System Product Name
Aug 26 14:46:17 pspc01 kernel: [ 3485.864312] clear_halt for a busy endpoint
Aug 26 14:46:17 pspc01 kernel: [ 3485.864314] Modules linked in: pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) ip6table_filter ip6_tables ebtable_nat ebtables vboxdrv(O) ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp dm_crypt uvcvideo snd_usb_audio videodev snd_usbmidi_lib v4l2_compat_ioctl32 snd_hda_codec_hdmi rfcomm bnep snd_hda_codec_via bluetooth parport_pc ppdev nfsd serio_raw nfs lockd binfmt_misc fscache auth_rpcgss nfs_acl i7core_edac edac_core sunrpc snd_hda_intel snd_hda_codec snd_hwdep snd_pcm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq asus_atk0110 snd_timer snd_seq_device snd soundcore snd_page_alloc mac_hid lp parport vesafb usbhid 3c59x hid r8169 pata_jmicron radeon ttm drm_kms_helper drm i2c_algo_bit
Aug 26 14:46:17 pspc01 kernel: [ 3485.864376] Pid: 3423, comm: EMT-1 Tainted: G           O 3.2.0-52-generic #78-Ubuntu
Aug 26 14:46:17 pspc01 kernel: [ 3485.864377] Call Trace:
Aug 26 14:46:17 pspc01 kernel: [ 3485.864381]  [<ffffffff810681af>] warn_slowpath_common+0x7f/0xc0
Aug 26 14:46:17 pspc01 kernel: [ 3485.864383]  [<ffffffff810682a6>] warn_slowpath_fmt+0x46/0x50
Aug 26 14:46:17 pspc01 kernel: [ 3485.864386]  [<ffffffff8103ec59>] ? default_spin_lock_flags+0x9/0x10
Aug 26 14:46:17 pspc01 kernel: [ 3485.864392]  [<ffffffff81499b97>] ? usb_control_msg+0xf7/0x120
Aug 26 14:46:17 pspc01 kernel: [ 3485.864394]  [<ffffffff814af4a1>] ehci_endpoint_reset+0x111/0x120
Aug 26 14:46:17 pspc01 kernel: [ 3485.864396]  [<ffffffff81497ff5>] usb_hcd_reset_endpoint+0x25/0x70
Aug 26 14:46:17 pspc01 kernel: [ 3485.864398]  [<ffffffff814991c8>] usb_reset_endpoint+0x28/0x40
Aug 26 14:46:17 pspc01 kernel: [ 3485.864400]  [<ffffffff81499c2e>] usb_clear_halt+0x6e/0x80
Aug 26 14:46:17 pspc01 kernel: [ 3485.864403]  [<ffffffff814a2088>] proc_clearhalt+0x78/0xa0
Aug 26 14:46:17 pspc01 kernel: [ 3485.864405]  [<ffffffff814a4212>] usbdev_do_ioctl+0x5e2/0xc90
Aug 26 14:46:17 pspc01 kernel: [ 3485.864407]  [<ffffffff814a48ee>] usbdev_ioctl+0xe/0x20
Aug 26 14:46:17 pspc01 kernel: [ 3485.864410]  [<ffffffff8118c4ea>] do_vfs_ioctl+0x8a/0x340
Aug 26 14:46:17 pspc01 kernel: [ 3485.864413]  [<ffffffff810a20e7>] ? sys_futex+0x147/0x1a0
Aug 26 14:46:17 pspc01 kernel: [ 3485.864415]  [<ffffffff8117a370>] ? vfs_write+0x110/0x180
Aug 26 14:46:17 pspc01 kernel: [ 3485.864417]  [<ffffffff8118c831>] sys_ioctl+0x91/0xa0
Aug 26 14:46:17 pspc01 kernel: [ 3485.864421]  [<ffffffff81668d02>] system_call_fastpath+0x16/0x1b
Aug 26 14:46:17 pspc01 kernel: [ 3485.864422] ---[ end trace 73769134ffdf1e83 ]---

But both video windows (sending and receiving) still work! Dunno what crashed there...