Unable to connect my Webcam to any AppVM; connects and then fails

Ever since I installed Qubes on Thinkpad T480, I have not been able to use my webcam. I connect the camera to the AppVM. The webcam connects for less than a minute and then fails. The camera indicator light stays on indicating that it’s in use, but it’s no longer available for use by any AppVM software. I disconnect (using the Qubes device manager) then re-connect, and it could work for a few seconds before becoming unavailable (and busy; i.e. the camera light remains on).

I tried with both Debian and Fedora AppVMs. Tried with Chromium and Firefox, with Zoom, Teams, Webex, and finally with Cheese.

I searched various support groups for Qubes and didn’t find anyone facing these problems. I searched for the corresponding drivers and I didn’t find that I needed to install a driver. And this is a Lenovo Thinkpad, so the assumption that it should work.

Finally, I would like to say thank to the developers of Qubes and the people here on Discourse answering questions, and to the overall community. I work in human rights advocacy, and need Qubes.

2 Likes

You should probably look at the logs in the corresponding VMs as well in Dom0. You can find them in the Qube Manager by right click on the corresponding VM. Or using journalctl -f.

Hi @oijawyuh.

I know the folks at the Securedrop Project recommend the Thinkpad T480 for all the journalists that use their software on Qubes. So I think that model in particular is well supported.

Even though it’s a bit off-topic there, it might be worth asking on their chat:

https://gitter.im/freedomofpress/securedrop

And at least to me it means a lot to know that Qubes is able to help Human Rights advocates like yourself :slight_smile: (I’m not a developer, just a community member).

I made a small post a while ago that may be of interest to you: Qubes for at-risk populations.

2 Likes

Thank you @deeplow for your feedback. I had watched your presentation a few weeks ago (I assume it was you?). It was very helpful. This said, I remember thinking that pandemic remote/home working could have made it easier for States to evil-maid secure drop Qubes workstations, and many States could be very tempted to do so.

Back to my problem. @ fsflover how can I share with here the logs of a session where the camera disconnected?

At the end, the logs say: [ 241.527415] usb 1-1: USB disconnect, device number 2

At the beginning of the logs, usb 1-1 is the following:

[  164.990530] usb 1-1: new high-speed USB device number 2 using vhci_hcd
[  165.104561] usb 1-1: SetAddress Request (2) to port 0
[  165.167759] usb 1-1: New USB device found, idVendor=04f2, idProduct=b604, bcdDevice= 0.27
[  165.167846] usb 1-1: New USB device strings: Mfr=3, Product=1, SerialNumber=2
[  165.167911] usb 1-1: Product: Integrated Camera
[  165.167975] usb 1-1: Manufacturer: Chicony Electronics Co.,Ltd.
[  165.168035] usb 1-1: SerialNumber: 0001

These are the final lines of the logs, in case they are helpful

[  240.176210] vhci_hcd: unlink->seqnum 5125
[  240.176227] vhci_hcd: urb->status -104
[  240.304776] vhci_hcd: unlink->seqnum 5127
[  240.304792] vhci_hcd: urb->status -104
[  240.526919] vhci_hcd: unlink->seqnum 5129
[  240.526937] vhci_hcd: urb->status -104
[  240.753017] vhci_hcd: unlink->seqnum 5131
[  240.753038] vhci_hcd: urb->status -104
[  240.773284] vhci_hcd: unlink->seqnum 5133
[  240.773301] vhci_hcd: urb->status -104
[  240.775483] vhci_hcd: unlink->seqnum 5135
[  240.775499] vhci_hcd: urb->status -104
[  240.780163] vhci_hcd: unlink->seqnum 5137
[  240.780181] vhci_hcd: urb->status -104
[  240.990372] vhci_get_frame_number: 1199 callbacks suppressed
[  240.990376] usb usb1: Not yet implemented
[  240.990412] usb usb1: Not yet implemented
[  240.990424] usb usb1: Not yet implemented
[  240.990441] usb usb1: Not yet implemented
[  240.990453] usb usb1: Not yet implemented
[  240.992934] usb usb1: Not yet implemented
[  240.992953] usb usb1: Not yet implemented
[  240.992965] usb usb1: Not yet implemented
[  240.992977] usb usb1: Not yet implemented
[  240.992992] usb usb1: Not yet implemented
[  241.525545] vhci_hcd: connection closed
[  241.527316] vhci_hcd: stop threads
[  241.527346] vhci_hcd: release socket
[  241.527375] vhci_hcd: disconnect device
[  241.527415] usb 1-1: USB disconnect, device number 2
[  252.668728] kauditd_printk_skb: 1 callbacks suppressed
[  252.668730] audit: type=1104 audit(1603350548.911:76): pid=1216 uid=0 auid=0 ses=2 msg='op=PAM:setcred grantors=? acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
[  252.668797] audit: type=1131 audit(1603350548.911:77): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  252.690944] audit: type=1131 audit(1603350548.933:78): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
1 Like

Hi @oijawyuh! :slight_smile:

Not sure I was the one in that presentation, though. Anyhow, I’ve replied as a message to keep things on-topic.

I’ve also added some markdown formatting to your post to make it easier to read the logs. I hope that’s ok.

1 Like

Thank you @deeplow for the markdown.

I have great appreciation for the work of Qubes. I even converted a colleague of mine to Qubes on his Thinkpad T490s. But now both of us are unable to use the webcam for our video conferencing! The camera freezes after a few minutes and becomes inaccessible to the AppVM. The Qubes Devices manager is unable to disconnect/reconnect the camera.

What is surprising me is that there doesn’t seem to be more people facing this problem.

Below are the logs of yet another attempt to use Zoom over Fedora, where the camera disconnected.

Help appreciated!

[    3.560336] systemd[1]: No hostname configured.
[    3.560356] systemd[1]: Set hostname to <localhost>.
[    3.910112] systemd[1]: /usr/lib/systemd/system/qubes-meminfo-writer.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/meminfo-writer.pid → /run/meminfo-writer.pid; please update the unit file accordingly.
[    3.953528] systemd[1]: Created slice system-getty.slice.
[.[0;32m  OK  .[0m] Created slice .[0;1;39msystem-getty.slice.[0m.
[    3.954417] systemd[1]: Created slice system-modprobe.slice.
[.[0;32m  OK  .[0m] Created slice .[0;1;39msystem-modprobe.slice.[0m.
[    3.955141] systemd[1]: Created slice system-serial\x2dgetty.slice.
[.[0;32m  OK  .[0m] Created slice .[0;1;39msystem-serial\x2dgetty.slice.[0m.
[    3.956832] systemd[1]: Created slice User and Session Slice.
[.[0;32m  OK  .[0m] Created slice .[0;1;39mUser and Session Slice.[0m.
[    3.957008] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[.[0;32m  OK  .[0m] Started .[0;1;39mDispatch Password …ts to Console Directory Watch.[0m.
[    3.957165] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[.[0;32m  OK  .[0m] Started .[0;1;39mForward Password R…uests to Wall Directory Watch.[0m.
[    3.957736] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[.[0;32m  OK  .[0m] Set up automount .[0;1;39mArbitrary…s File System Automount Point.[0m.
[    3.958041] systemd[1]: Reached target Local Encrypted Volumes.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mLocal Encrypted Volumes.[0m.
[    3.958264] systemd[1]: Reached target Remote File Systems.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mRemote File Systems.[0m.
[    3.958447] systemd[1]: Reached target Slices.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mSlices.[0m.
[    3.960379] systemd[1]: Listening on Process Core Dump Socket.
[.[0;32m  OK  .[0m] Listening on .[0;1;39mProcess Core Dump Socket.[0m.
[    3.960736] systemd[1]: Listening on initctl Compatibility Named Pipe.
[.[0;32m  OK  .[0m] Listening on .[0;1;39minitctl Compatibility Named Pipe.[0m.
[    3.961604] systemd[1]: Listening on Journal Audit Socket.
[.[0;32m  OK  .[0m] Listening on .[0;1;39mJournal Audit Socket.[0m.
[    3.961847] systemd[1]: Listening on Journal Socket (/dev/log).
[.[0;32m  OK  .[0m] Listening on .[0;1;39mJournal Socket (/dev/log).[0m.
[    3.962083] systemd[1]: Listening on Journal Socket.
[.[0;32m  OK  .[0m] Listening on .[0;1;39mJournal Socket.[0m.
[    3.962251] systemd[1]: Listening on udev Control Socket.
[.[0;32m  OK  .[0m] Listening on .[0;1;39mudev Control Socket.[0m.
[    3.962365] systemd[1]: Listening on udev Kernel Socket.
[.[0;32m  OK  .[0m] Listening on .[0;1;39mudev Kernel Socket.[0m.
[    3.962574] systemd[1]: Listening on User Database Manager Socket.
[.[0;32m  OK  .[0m] Listening on .[0;1;39mUser Database Manager Socket.[0m.
[    3.965734] systemd[1]: Mounting Huge Pages File System...
         Mounting .[0;1;39mHuge Pages File System.[0m...
[    3.969627] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting .[0;1;39mPOSIX Message Queue File System.[0m...
[    3.972762] systemd[1]: Mounting /proc/xen...
         Mounting .[0;1;39m/proc/xen.[0m...
[    4.113108] systemd[1]: Mounting Kernel Debug File System...
         Mounting .[0;1;39mKernel Debug File System.[0m...
[    4.117062] systemd[1]: Mounting Kernel Trace File System...
         Mounting .[0;1;39mKernel Trace File System.[0m...
[    4.118780] systemd[1]: Starting Create list of static device nodes for the current kernel...
         Starting .[0;1;39mCreate list of st…odes for the current kernel.[0m...
[    4.120408] systemd[1]: Starting Load Kernel Module drm...
         Starting .[0;1;39mLoad Kernel Module drm.[0m...
[    4.121913] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    4.125088] systemd[1]: Starting File System Check on Root Device...
         Starting .[0;1;39mFile System Check on Root Device.[0m...
[    4.128602] systemd[1]: Starting Load Kernel Modules...
         Starting .[0;1;39mLoad Kernel Modules.[0m...
[    4.131311] systemd[1]: Starting Repartition Root Disk...
         Starting .[0;1;39mRepartition Root Disk.[0m...
[    4.133762] systemd[1]: Starting udev Coldplug all Devices...
         Starting .[0;1;39mudev Coldplug all Devices.[0m...
[    4.136622] systemd[1]: Mounted Huge Pages File System.
[.[0;32m  OK  .[0m] Mounted .[0;1;39mHuge Pages File System.[0m.
[    4.139642] systemd[1]: Mounted POSIX Message Queue File System.
[.[0;32m  OK  .[0m] Mounted .[0;1;39mPOSIX Message Queue File System.[0m.
[    4.143998] systemd[1]: Mounted /proc/xen.
[.[0;32m  OK  .[0m] Mounted .[0;1;39m/proc/xen.[0m.
[    4.144154] systemd[1]: Mounted Kernel Debug File System.
[.[0;32m  OK  .[0m] Mounted .[0;1;39mKernel Debug File System.[0m.
[    4.144301] systemd[1]: Mounted Kernel Trace File System.
[.[0;32m  OK  .[0m] Mounted .[0;1;39mKernel Trace File System.[0m.
[    4.144649] systemd[1]: Finished Create list of static device nodes for the current kernel.
[.[0;32m  OK  .[0m] Finished .[0;1;39mCreate list of st… nodes for the current kernel.[0m.
[    4.146175] systemd[1]: modprobe@drm.service: Succeeded.
[    4.146452] systemd[1]: Finished Load Kernel Module drm.
[.[0;32m  OK  .[0m] Finished .[0;1;39mLoad Kernel Module drm.[0m.
[    4.152710] xen:xen_evtchn: Event-channel device installed
[    4.164635] systemd[1]: Finished Repartition Root Disk.
[.[0;32m  OK  .[0m] Finished .[0;1;39mRepartition Root Disk.[0m.
[    4.174606] u2mfn: loading out-of-tree module taints kernel.
[    4.175921] systemd[1]: Finished File System Check on Root Device.
[.[0;32m  OK  .[0m] Finished .[0;1;39mFile System Check on Root Device.[0m.
[    4.177667] systemd[1]: Starting Remount Root and Kernel File Systems...
         Starting .[0;1;39mRemount Root and Kernel File Systems.[0m...
[    4.178138] systemd[1]: Finished Load Kernel Modules.
[.[0;32m  OK  .[0m] Finished .[0;1;39mLoad Kernel Modules.[0m.
[    4.178478] systemd[1]: Condition check resulted in FUSE Control File System being skipped.
[    4.181730] systemd[1]: Mounting Kernel Configuration File System...
         Mounting .[0;1;39mKernel Configuration File System.[0m...
[    4.182922] systemd[1]: Starting Qubes DB agent...
         Starting .[0;1;39mQubes DB agent.[0m...
[    4.184766] systemd[1]: Starting Apply Kernel Variables...
         Starting .[0;1;39mApply Kernel Variables.[0m...
[    4.185769] systemd[1]: Mounted Kernel Configuration File System.
[.[0;32m  OK  .[0m] Mounted .[0;1;39mKernel Configuration File System.[0m.
[    4.194498] EXT4-fs (xvda3): re-mounted. Opts: discard
[    4.202369] systemd[1]: Finished Remount Root and Kernel File Systems.
[.[0;32m  OK  .[0m] Finished .[0;1;39mRemount Root and Kernel File Systems.[0m.
[    4.202613] systemd[1]: Condition check resulted in OSTree Remount OS/ Bind Mounts being skipped.
[    4.202669] systemd[1]: Condition check resulted in First Boot Wizard being skipped.
[    4.205224] systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
[    4.205281] systemd[1]: Condition check resulted in Create System Users being skipped.
[    4.208655] systemd[1]: Starting Create Static Device Nodes in /dev...
         Starting .[0;1;39mCreate Static Device Nodes in /dev.[0m...
[    4.312590] systemd[1]: Finished udev Coldplug all Devices.
[.[0;32m  OK  .[0m] Finished .[0;1;39mudev Coldplug all Devices.[0m.
[    4.313509] systemd[1]: Finished Apply Kernel Variables.
[.[0;32m  OK  .[0m] Finished .[0;1;39mApply Kernel Variables.[0m.
[    4.313939] systemd[1]: Finished Create Static Device Nodes in /dev.
[.[0;32m  OK  .[0m] Finished .[0;1;39mCreate Static Device Nodes in /dev.[0m.
[    4.314196] systemd[1]: Reached target Local File Systems (Pre).
[.[0;32m  OK  .[0m] Reached target .[0;1;39mLocal File Systems (Pre).[0m.
[    4.321429] systemd[1]: Started Entropy Daemon based on the HAVEGE algorithm.
[.[0;32m  OK  .[0m] Started .[0;1;39mEntropy Daemon based on the HAVEGE algorithm.[0m.
[    4.323672] systemd[1]: Starting Journal Service...
         Starting .[0;1;39mJournal Service.[0m...
[    4.327113] systemd[1]: Starting udev Kernel Device Manager...
         Starting .[0;1;39mudev Kernel Device Manager.[0m...
[    4.332964] systemd[1]: Started Qubes DB agent.
[.[0;32m  OK  .[0m] Started .[0;1;39mQubes DB agent.[0m.
[    4.335036] systemd[1]: Starting Init Qubes Services settings...
         Starting .[0;1;39mInit Qubes Services settings.[0m...
[    4.338587] systemd[1]: Starting Load/Save Random Seed...
         Starting .[0;1;39mLoad/Save Random Seed.[0m...
[    4.393553] systemd[1]: Finished Load/Save Random Seed.
[.[0;32m  OK  .[0m] Finished .[0;1;39mLoad/Save Random Seed.[0m.
[    4.394151] audit: type=1130 audit(1603541839.529:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    4.919212] input: PC Speaker as /devices/platform/pcspkr/input/input0
[    5.126118] RAPL PMU: API unit is 2^-32 Joules, 5 fixed counters, 655360 ms ovfl timer
[    5.126169] RAPL PMU: hw unit of domain pp0-core 2^-14 Joules
[    5.126198] RAPL PMU: hw unit of domain package 2^-14 Joules
[    5.126225] RAPL PMU: hw unit of domain dram 2^-14 Joules
[    5.126244] RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules
[    5.126268] RAPL PMU: hw unit of domain psys 2^-14 Joules
[    5.836287] xen_netfront: Initialising Xen virtual ethernet driver
[    6.421820] systemd[1]: Reexecuting.
[    6.464948] systemd[1]: systemd v245.8-2.fc32 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    6.465371] systemd[1]: Detected virtualization xen.
[    6.465402] systemd[1]: Detected architecture x86-64.
[    6.591528] systemd[1]: /usr/lib/systemd/system/qubes-meminfo-writer.service:8: PIDFile= references a path below legacy directory /var/run/, updating /var/run/meminfo-writer.pid → /run/meminfo-writer.pid; please update the unit file accordingly.
[    6.620938] systemd[1]: Found device /dev/xvdc1.
[.[0;32m  OK  .[0m] Found device .[0;1;39m/dev/xvdc1.[0m.
[    6.621479] systemd[1]: Found device /dev/hvc0.
[.[0;32m  OK  .[0m] Found device .[0;1;39m/dev/hvc0.[0m.
[    6.637164] systemd[1]: Started Journal Service.
[.[0;32m  OK  .[0m] Started .[0;1;39mJournal Service.[0m.
[    6.637411] audit: type=1130 audit(1603541841.773:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[.[0;32m  OK  .[0m] Started .[0;1;39mudev Kernel Device Manager.[0m.
[    6.638954] audit: type=1130 audit(1603541841.774:4): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
         Activating swap .[0;1;39m/dev/xvdc1.[0m...
         Starting .[0;1;39mFlush Journal to Persistent Storage.[0m...
[    6.653107] Adding 1048572k swap on /dev/xvdc1.  Priority:-2 extents:1 across:1048572k SSFS
[    6.654337] systemd-journald[250]: Received client request to flush runtime journal.
[.[0;32m  OK  .[0m] Activated swap .[0;1;39m/dev/xvdc1.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mSwap.[0m.
         Mounting .[0;1;39mTemporary Directory (/tmp).[0m...
[.[0;32m  OK  .[0m] Mounted .[0;1;39mTemporary Directory (/tmp).[0m.
[.[0;32m  OK  .[0m] Finished .[0;1;39mInit Qubes Services settings.[0m.
[    6.676787] audit: type=1130 audit(1603541841.812:5): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=qubes-sysinit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
         Starting .[0;1;39mInitialize and mount /rw and /home.[0m...
         Starting .[0;1;39mAdjust root filesystem size.[0m...
[    6.774292] EXT4-fs (xvdb): mounted filesystem with ordered data mode. Opts: discard
[.[0;32m  OK  .[0m] Finished .[0;1;39mAdjust root filesystem size.[0m.
[    6.811143] audit: type=1130 audit(1603541841.947:6): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=qubes-rootfs-resize comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[.[0;32m  OK  .[0m] Finished .[0;1;39mInitialize and mount /rw and /home.[0m.
[    6.894238] audit: type=1130 audit(1603541842.030:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=qubes-mount-dirs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[.[0;32m  OK  .[0m] Reached target .[0;1;39mLocal File Systems.[0m.
         Starting .[0;1;39mRestore /run/initramfs on shutdown.[0m...
         Starting .[0;1;39mEarly Qubes VM settings.[0m...
[.[0;32m  OK  .[0m] Finished .[0;1;39mRestore /run/initramfs on shutdown.[0m.
[.[0;32m  OK  .[0m] Finished .[0;1;39mFlush Journal to Persistent Storage.[0m.
[    6.901419] audit: type=1130 audit(1603541842.036:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dracut-shutdown comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[    6.901473] audit: type=1130 audit(1603541842.037:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
         Starting .[0;1;39mCreate Volatile Files and Directories.[0m...
[.[0;32m  OK  .[0m] Finished .[0;1;39mEarly Qubes VM settings.[0m.
[    6.929116] audit: type=1130 audit(1603541842.064:10): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=qubes-early-vm-config comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[.[0;32m  OK  .[0m] Finished .[0;1;39mCreate Volatile Files and Directories.[0m.
[    6.934836] audit: type=1130 audit(1603541842.070:11): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[.[0;32m  OK  .[0m] Reached target .[0;1;39mSystem Time Set.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mSystem Time Synchronized.[0m.
         Starting .[0;1;39mUpdate UTMP about System Boot/Shutdown.[0m...
[    6.944916] audit: type=1127 audit(1603541842.080:12): pid=496 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
[.[0;32m  OK  .[0m] Finished .[0;1;39mUpdate UTMP about System Boot/Shutdown.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mSystem Initialization.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mCUPS Scheduler.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mDiscard unused blocks once a week.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mDaily rotation of log files.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mUpdate system time each 6h.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mPeriodically check for updates.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mDaily Cleanup of Temporary Directories.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mdaily update of the root trust anchor for DNSSEC.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mPaths.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mTimers.[0m.
[.[0;32m  OK  .[0m] Listening on .[0;1;39mCUPS Scheduler.[0m.
[.[0;32m  OK  .[0m] Listening on .[0;1;39mD-Bus System Message Bus Socket.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mSockets.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mBasic System.[0m.
         Starting .[0;1;39mQubes base firewall settings.[0m...
         Starting .[0;1;39mQubes memory information reporter.[0m...
         Starting .[0;1;39mHome Area Manager.[0m...
         Starting .[0;1;39mLogin Service.[0m...
         Starting .[0;1;39mXen driver domain device daemon.[0m...
[.[0;32m  OK  .[0m] Started .[0;1;39mQubes memory information reporter.[0m.
         Starting .[0;1;39mQubes GUI Agent.[0m...
         Starting .[0;1;39mupdate of the roo…NSSEC validation in unbound.[0m...
         Starting .[0;1;39mRotate log files.[0m...
[.[0;32m  OK  .[0m] Started .[0;1;39mXen driver domain device daemon.[0m.
         Starting .[0;1;39mQubes remote exec agent.[0m...
[.[0;32m  OK  .[0m] Started .[0;1;39mQubes GUI Agent.[0m.
         Starting .[0;1;39mD-Bus System Message Bus.[0m...
[.[0;32m  OK  .[0m] Started .[0;1;39mQubes remote exec agent.[0m.
[.[0;32m  OK  .[0m] Finished .[0;1;39mupdate of the roo… DNSSEC validation in unbound.[0m.
         Starting .[0;1;39mUser Database Manager.[0m...
[.[0;32m  OK  .[0m] Finished .[0;1;39mRotate log files.[0m.
[.[0;32m  OK  .[0m] Finished .[0;1;39mQubes base firewall settings.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mNetwork.[0m.
         Starting .[0;1;39mCUPS Scheduler.[0m...
         Starting .[0;1;39mQubes misc post-boot actions.[0m...
         Starting .[0;1;39mPermit User Sessions.[0m...
[.[0;32m  OK  .[0m] Finished .[0;1;39mPermit User Sessions.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mGetty on tty1.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mSerial Getty on hvc0.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mLogin Prompts.[0m.
[.[0;32m  OK  .[0m] Finished .[0;1;39mQubes misc post-boot actions.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mD-Bus System Message Bus.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mLogin Service.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mHome Area Manager.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mCUPS Scheduler.[0m.
[.[0;32m  OK  .[0m] Reached target .[0;1;39mMulti-User System.[0m.
         Starting .[0;1;39mUpdate UTMP about System Runlevel Changes.[0m...
[.[0;32m  OK  .[0m] Finished .[0;1;39mUpdate UTMP about System Runlevel Changes.[0m.
[.[0;32m  OK  .[0m] Started .[0;1;39mUser Database Manager.[0m.
[    8.892139] fbcon: Taking over console
[    8.940327] intel_rapl: Found RAPL domain package
[    8.940347] intel_rapl: Found RAPL domain core
[    8.940361] intel_rapl: Found RAPL domain uncore
[    8.940376] intel_rapl: Found RAPL domain dram
[    9.561391] fuse init (API version 7.27)


Fedora 32 (Thirty Two)
Kernel 4.19.147-1.pvops.qubes.x86_64 on an x86_64 (hvc0)

disp4287 login: [   24.372657] kauditd_printk_skb: 61 callbacks suppressed
[   24.372658] audit: type=1100 audit(1603541859.508:64): pid=1442 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_rootok acct="root" exe="/usr/lib/qubes/qrexec-agent" hostname=? addr=? terminal=? res=success'
[   24.372844] audit: type=1103 audit(1603541859.508:65): pid=1442 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="root" exe="/usr/lib/qubes/qrexec-agent" hostname=? addr=? terminal=? res=success'
[   24.383410] audit: type=1130 audit(1603541859.519:66): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   24.387367] audit: type=1101 audit(1603541859.523:67): pid=1444 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_unix acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   24.387412] audit: type=1103 audit(1603541859.523:68): pid=1444 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=? acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
[   24.387449] audit: type=1006 audit(1603541859.523:69): pid=1444 uid=0 old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=2 res=1
[   24.389010] audit: type=1105 audit(1603541859.524:70): pid=1444 uid=0 auid=0 ses=2 msg='op=PAM:session_open grantors=pam_selinux,pam_selinux,pam_loginuid,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   24.458450] audit: type=1130 audit(1603541859.593:71): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   24.462034] audit: type=1105 audit(1603541859.597:72): pid=1442 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_systemd,pam_unix,pam_umask,pam_lastlog acct="root" exe="/usr/lib/qubes/qrexec-agent" hostname=? addr=? terminal=? res=success'
[   24.619183] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[   24.619231] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
[   24.619253] vhci_hcd: created sysfs vhci_hcd.0
[   24.619287] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[   24.619303] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   24.619319] usb usb1: Product: USB/IP Virtual Host Controller
[   24.619333] usb usb1: Manufacturer: Linux 4.19.147-1.pvops.qubes.x86_64 vhci_hcd
[   24.619349] usb usb1: SerialNumber: vhci_hcd.0
[   24.619413] hub 1-0:1.0: USB hub found
[   24.619428] hub 1-0:1.0: 8 ports detected
[   24.619520] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[   24.619554] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
[   24.619579] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[   24.619609] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[   24.619625] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   24.619641] usb usb2: Product: USB/IP Virtual Host Controller
[   24.619654] usb usb2: Manufacturer: Linux 4.19.147-1.pvops.qubes.x86_64 vhci_hcd
[   24.619670] usb usb2: SerialNumber: vhci_hcd.0
[   24.619728] hub 2-0:1.0: USB hub found
[   24.619745] hub 2-0:1.0: 8 ports detected
[   25.151027] vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(0)
[   25.151048] vhci_hcd vhci_hcd.0: devid(131074) speed(3) speed_str(high-speed)
[   25.368080] usb 1-1: new high-speed USB device number 2 using vhci_hcd
[   25.482084] usb 1-1: SetAddress Request (2) to port 0
[   25.545357] usb 1-1: New USB device found, idVendor=04f2, idProduct=b604, bcdDevice= 0.27
[   25.545378] usb 1-1: New USB device strings: Mfr=3, Product=1, SerialNumber=2
[   25.545395] usb 1-1: Product: Integrated Camera
[   25.545406] usb 1-1: Manufacturer: Chicony Electronics Co.,Ltd.
[   25.545419] usb 1-1: SerialNumber: 0001
[   25.577391] media: Linux media interface: v0.10
[   25.581613] videodev: Linux video capture interface: v2.00
[   25.590470] uvcvideo: Found UVC 1.00 device Integrated Camera (04f2:b604)
[   25.603214] uvcvideo 1-1:1.0: Entity type for entity Realtek Extended Controls Unit was not initialized!
[   25.603238] uvcvideo 1-1:1.0: Entity type for entity Extension 4 was not initialized!
[   25.603257] uvcvideo 1-1:1.0: Entity type for entity Processing 2 was not initialized!
[   25.603274] uvcvideo 1-1:1.0: Entity type for entity Camera 1 was not initialized!
[   25.603332] input: Integrated Camera: Integrated C as /devices/platform/vhci_hcd.0/usb1/1-1/1-1:1.0/input/input1
[   25.603398] usbcore: registered new interface driver uvcvideo
[   25.603413] USB Video Class driver (1.1.1)
[   25.615684] vhci_hcd: unlink->seqnum 34
[   25.615698] vhci_hcd: urb->status -104
[   25.643713] audit: type=1106 audit(1603541860.779:73): pid=1442 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_systemd,pam_unix,pam_umask,pam_lastlog acct="root" exe="/usr/lib/qubes/qrexec-agent" hostname=? addr=? terminal=? res=success'
[  120.300995] vhci_hcd: unlink->seqnum 37
[  120.301137] vhci_hcd: urb->status -104
[  120.334904] vhci_hcd: unlink->seqnum 69
[  120.334917] vhci_hcd: urb->status -104
[  156.004936] usb usb1: Not yet implemented
[  156.004950] usb usb1: Not yet implemented
[  156.004960] usb usb1: Not yet implemented
[  156.004969] usb usb1: Not yet implemented
[  156.004980] usb usb1: Not yet implemented
[  156.005001] usb usb1: Not yet implemented
[  156.005010] usb usb1: Not yet implemented
[  156.005020] usb usb1: Not yet implemented
[  156.005029] usb usb1: Not yet implemented
[  156.007253] usb usb1: Not yet implemented
[  160.096038] vhci_hcd: connection reset by peer
[  160.101122] vhci_hcd: stop threads
[  160.101142] vhci_hcd: release socket
[  160.101157] vhci_hcd: disconnect device
[  160.101199] usb 1-1: USB disconnect, device number 2
[  171.093768] kauditd_printk_skb: 1 callbacks suppressed
[  171.093770] audit: type=1104 audit(1603542006.229:75): pid=1445 uid=0 auid=0 ses=2 msg='op=PAM:setcred grantors=? acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
[  171.095214] audit: type=1131 audit(1603542006.230:76): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  171.105548] audit: type=1131 audit(1603542006.241:77): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'

Hi again @oijawyuh.

:partying_face: Wow. Let’s now figure our how this can be solved so Qubes doesn’t become a burden because of this issue.

Also, the fact that the issue also exists in another thinkpad (t490s) means it’s probably an isolated case.

I’ve tried to reproduce this on an x1 carbon (gen5) thinkpad (same webcam manufacturer) with a disposable fedora-32 virtual machine.

Log - Startup (relevant portion)
[...]
Fedora 32 (Thirty Two)
Kernel 4.19.147-1.pvops.qubes.x86_64 on an x86_64 (hvc0)

disp1490 login: [   26.494139] audit: type=1130 audit(1604153524.578:63): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=unbound-anchor comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   26.494181] audit: type=1131 audit(1604153524.578:64): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=unbound-anchor comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   80.303492] audit: type=1100 audit(1604153578.388:65): pid=865 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_rootok acct="root" exe="/usr/lib/qubes/qrexec-agent" hostname=? addr=? terminal=? res=success'
[   80.306062] audit: type=1103 audit(1604153578.390:66): pid=865 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_rootok acct="root" exe="/usr/lib/qubes/qrexec-agent" hostname=? addr=? terminal=? res=success'
[   80.370539] audit: type=1130 audit(1604153578.455:67): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   80.386330] audit: type=1101 audit(1604153578.470:68): pid=867 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_unix acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   80.386375] audit: type=1103 audit(1604153578.470:69): pid=867 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=? acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
[   80.386413] audit: type=1006 audit(1604153578.470:70): pid=867 uid=0 old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=2 res=1
[   80.389589] audit: type=1105 audit(1604153578.474:71): pid=867 uid=0 auid=0 ses=2 msg='op=PAM:session_open grantors=pam_selinux,pam_selinux,pam_loginuid,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   80.525290] audit: type=1130 audit(1604153578.609:72): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   80.531147] audit: type=1105 audit(1604153578.615:73): pid=865 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_systemd,pam_unix,pam_umask,pam_lastlog acct="root" exe="/usr/lib/qubes/qrexec-agent" hostname=? addr=? terminal=? res=success'
[   80.793826] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[   80.793882] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1
[   80.793905] vhci_hcd: created sysfs vhci_hcd.0
[   80.793941] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[   80.793958] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   80.793974] usb usb1: Product: USB/IP Virtual Host Controller
[   80.793988] usb usb1: Manufacturer: Linux 4.19.147-1.pvops.qubes.x86_64 vhci_hcd
[   80.794004] usb usb1: SerialNumber: vhci_hcd.0
[   80.794072] hub 1-0:1.0: USB hub found
[   80.794085] hub 1-0:1.0: 8 ports detected
[   80.794185] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller
[   80.794217] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2
[   80.794243] usb usb2: We dont know the algorithms for LPM for this host, disabling LPM.
[   80.794274] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[   80.794291] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   80.794307] usb usb2: Product: USB/IP Virtual Host Controller
[   80.794320] usb usb2: Manufacturer: Linux 4.19.147-1.pvops.qubes.x86_64 vhci_hcd
[   80.794336] usb usb2: SerialNumber: vhci_hcd.0
[   80.794396] hub 2-0:1.0: USB hub found
[   80.794409] hub 2-0:1.0: 8 ports detected
[   81.206528] vhci_hcd vhci_hcd.0: pdev(0) rhport(0) sockfd(0)
[   81.206547] vhci_hcd vhci_hcd.0: devid(131075) speed(3) speed_str(high-speed)
[   81.423468] usb 1-1: new high-speed USB device number 2 using vhci_hcd
[   81.538498] usb 1-1: SetAddress Request (2) to port 0
[   81.563484] usb 1-1: New USB device found, idVendor=04f2, idProduct=b5ce, bcdDevice= 0.06
[   81.563505] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   81.563522] usb 1-1: Product: Integrated Camera
[   81.563556] usb 1-1: Manufacturer: Chicony Electronics Co.,Ltd.
[   81.615752] media: Linux media interface: v0.10
[   81.622659] videodev: Linux video capture interface: v2.00
[   81.638314] uvcvideo: Found UVC 1.00 device Integrated Camera (04f2:b5ce)
[   81.674900] uvcvideo 1-1:1.0: Entity type for entity Extension 4 was not initialized!
[   81.674922] uvcvideo 1-1:1.0: Entity type for entity Extension 3 was not initialized!
[   81.674939] uvcvideo 1-1:1.0: Entity type for entity Processing 2 was not initialized!
[   81.674959] uvcvideo 1-1:1.0: Entity type for entity Camera 1 was not initialized!
[   81.675167] input: Integrated Camera: Integrated C as /devices/platform/vhci_hcd.0/usb1/1-1/1-1:1.0/input/input1
[   81.675423] usbcore: registered new interface driver uvcvideo
[   81.675458] USB Video Class driver (1.1.1)
[   81.695729] vhci_hcd: unlink->seqnum 29
[   81.695747] vhci_hcd: urb->status -104
[   81.727477] audit: type=1106 audit(1604153579.811:74): pid=865 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_systemd,pam_unix,pam_umask,pam_lastlog acct="root" exe="/usr/lib/qubes/qrexec-agent" hostname=? addr=? terminal=? res=success'
Log - Attaching USB webcam

At this point I attach the webcam to the Fedora qube. I also started the “cheese” application to make use of the webcam and confirm it was working.

[  270.173040] kauditd_printk_skb: 1 callbacks suppressed
[  270.173041] audit: type=1101 audit(1604153768.257:76): pid=1174 uid=1000 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_unix acct="user" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  270.174300] audit: type=1123 audit(1604153768.257:77): pid=1174 uid=1000 auid=4294967295 ses=4294967295 msg='cwd="/home/user" cmd=646E6620696E7374616C6C20636865657365 exe="/usr/bin/sudo" terminal=pts/0 res=success'
[  270.174342] audit: type=1110 audit(1604153768.258:78): pid=1174 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  270.175922] audit: type=1105 audit(1604153768.260:79): pid=1174 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  272.172155] audit: type=1106 audit(1604153770.256:80): pid=1174 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  272.172642] audit: type=1104 audit(1604153770.257:81): pid=1174 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  277.214463] xen_netfront: Initialising Xen virtual ethernet driver
[  278.396793] audit: type=1101 audit(1604153776.481:82): pid=1282 uid=1000 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_unix acct="user" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  278.397129] audit: type=1123 audit(1604153776.481:83): pid=1282 uid=1000 auid=4294967295 ses=4294967295 msg='cwd="/home/user" cmd=646E6620696E7374616C6C20636865657365 exe="/usr/bin/sudo" terminal=pts/0 res=success'
[  278.397712] audit: type=1110 audit(1604153776.482:84): pid=1282 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  278.400652] audit: type=1105 audit(1604153776.485:85): pid=1282 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  310.948802] audit: type=1106 audit(1604153809.033:86): pid=1282 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  310.960915] audit: type=1104 audit(1604153809.045:87): pid=1282 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  315.962699] vhci_hcd: unlink->seqnum 32
[  315.962716] vhci_hcd: urb->status -104
[  316.072701] vhci_hcd: unlink->seqnum 34
[  316.072715] vhci_hcd: urb->status -104
[  316.075362] vhci_hcd: unlink->seqnum 36
[  316.075379] vhci_hcd: urb->status -104
[  316.698463] vhci_hcd: unlink->seqnum 80
[  316.698476] vhci_hcd: urb->status -104
[  316.842769] vhci_hcd: unlink->seqnum 87
[  316.842783] vhci_hcd: urb->status -104
[  316.993824] usb usb1: Not yet implemented
[  316.993842] usb usb1: Not yet implemented
[  316.993851] usb usb1: Not yet implemented
[  316.993860] usb usb1: Not yet implemented
[  316.993869] usb usb1: Not yet implemented
[  316.997715] usb usb1: Not yet implemented
[  316.997730] usb usb1: Not yet implemented
[  316.997739] usb usb1: Not yet implemented
[  316.997751] usb usb1: Not yet implemented
[  317.002045] usb usb1: Not yet implemented
[  321.998552] vhci_get_frame_number: 4945 callbacks suppressed
[  321.998555] usb usb1: Not yet implemented
[  321.998583] usb usb1: Not yet implemented
[  321.998593] usb usb1: Not yet implemented
[  321.998604] usb usb1: Not yet implemented
[  322.002708] usb usb1: Not yet implemented
[  322.002724] usb usb1: Not yet implemented
[  322.002735] usb usb1: Not yet implemented
[  322.002745] usb usb1: Not yet implemented
[  322.006760] usb usb1: Not yet implemented
[  322.006776] usb usb1: Not yet implemented
[  326.555096] audit: type=1130 audit(1604153824.639:88): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=qubes-update-check comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  326.555147] audit: type=1131 audit(1604153824.639:89): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=qubes-update-check comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  326.999973] vhci_get_frame_number: 4919 callbacks suppressed
[  326.999976] usb usb1: Not yet implemented
[  327.000005] usb usb1: Not yet implemented
[  327.000016] usb usb1: Not yet implemented
[  327.000026] usb usb1: Not yet implemented
[  327.003990] usb usb1: Not yet implemented
[  327.004006] usb usb1: Not yet implemented
[  327.004016] usb usb1: Not yet implemented
[  327.004029] usb usb1: Not yet implemented
[  327.008068] usb usb1: Not yet implemented
[  327.008085] usb usb1: Not yet implemented
[  332.003436] vhci_get_frame_number: 4994 callbacks suppressed
[  332.003439] usb usb1: Not yet implemented
[  332.003467] usb usb1: Not yet implemented
[  332.003489] usb usb1: Not yet implemented
[  332.003498] usb usb1: Not yet implemented
[  332.007498] usb usb1: Not yet implemented
[  332.007515] usb usb1: Not yet implemented
[  332.007524] usb usb1: Not yet implemented
[  332.007536] usb usb1: Not yet implemented
[  332.013515] usb usb1: Not yet implemented
[  332.013528] usb usb1: Not yet implemented
[  337.007054] vhci_get_frame_number: 4994 callbacks suppressed
[  337.007058] usb usb1: Not yet implemented
[  337.007087] usb usb1: Not yet implemented
[  337.007098] usb usb1: Not yet implemented
[  337.007109] usb usb1: Not yet implemented
[  337.011209] usb usb1: Not yet implemented
[  337.011225] usb usb1: Not yet implemented
[  337.011236] usb usb1: Not yet implemented
[  337.011246] usb usb1: Not yet implemented
[  337.015581] usb usb1: Not yet implemented
[  337.015598] usb usb1: Not yet implemented
[  342.008479] vhci_get_frame_number: 4990 callbacks suppressed
[  342.008484] usb usb1: Not yet implemented
[  342.008513] usb usb1: Not yet implemented
[  342.008522] usb usb1: Not yet implemented
[  342.008530] usb usb1: Not yet implemented
[  342.010496] usb usb1: Not yet implemented
[  342.010509] usb usb1: Not yet implemented
[  342.010519] usb usb1: Not yet implemented
[  342.010528] usb usb1: Not yet implemented
[  342.014605] usb usb1: Not yet implemented
[  342.014619] usb usb1: Not yet implemented
[  347.009849] vhci_get_frame_number: 4990 callbacks suppressed
[  347.009853] usb usb1: Not yet implemented
[  347.009880] usb usb1: Not yet implemented
[  347.009889] usb usb1: Not yet implemented
[  347.009898] usb usb1: Not yet implemented
[  347.011440] usb usb1: Not yet implemented
[  347.011452] usb usb1: Not yet implemented
[  347.011461] usb usb1: Not yet implemented
[  347.011470] usb usb1: Not yet implemented
[  347.014204] usb usb1: Not yet implemented
[  347.014217] usb usb1: Not yet implemented
[  352.013954] vhci_get_frame_number: 4998 callbacks suppressed
[  352.013958] usb usb1: Not yet implemented
[  352.013989] usb usb1: Not yet implemented
[  352.014000] usb usb1: Not yet implemented
[  352.014013] usb usb1: Not yet implemented
[  352.017878] usb usb1: Not yet implemented
[  352.017894] usb usb1: Not yet implemented
[  352.017904] usb usb1: Not yet implemented
[  352.017915] usb usb1: Not yet implemented
[  352.022015] usb usb1: Not yet implemented
[  352.022030] usb usb1: Not yet implemented
[  357.017477] vhci_get_frame_number: 4994 callbacks suppressed
[  357.017481] usb usb1: Not yet implemented
[  357.017508] usb usb1: Not yet implemented
[  357.017519] usb usb1: Not yet implemented
[  357.017528] usb usb1: Not yet implemented
[  357.021484] usb usb1: Not yet implemented
[  357.021498] usb usb1: Not yet implemented
[  357.021509] usb usb1: Not yet implemented
[  357.021518] usb usb1: Not yet implemented
[  357.025280] usb usb1: Not yet implemented
[  357.025293] usb usb1: Not yet implemented
[  362.020978] vhci_get_frame_number: 4994 callbacks suppressed
[  362.020981] usb usb1: Not yet implemented
[  362.021011] usb usb1: Not yet implemented
[  362.021021] usb usb1: Not yet implemented
[  362.021032] usb usb1: Not yet implemented
[  362.024938] usb usb1: Not yet implemented
[  362.024956] usb usb1: Not yet implemented
[  362.024966] usb usb1: Not yet implemented
[  362.024977] usb usb1: Not yet implemented
[  362.029396] usb usb1: Not yet implemented
[  362.029411] usb usb1: Not yet implemented
[  367.024399] vhci_get_frame_number: 4994 callbacks suppressed
[  367.024403] usb usb1: Not yet implemented
[  367.024430] usb usb1: Not yet implemented
[  367.024442] usb usb1: Not yet implemented
[  367.024451] usb usb1: Not yet implemented
[  367.028413] usb usb1: Not yet implemented
[  367.028427] usb usb1: Not yet implemented
[  367.028438] usb usb1: Not yet implemented
[  367.028447] usb usb1: Not yet implemented
[  367.032337] usb usb1: Not yet implemented
[  367.032350] usb usb1: Not yet implemented
[  372.027943] vhci_get_frame_number: 4994 callbacks suppressed
[  372.027946] usb usb1: Not yet implemented
[  372.027976] usb usb1: Not yet implemented
[  372.027985] usb usb1: Not yet implemented
[  372.027994] usb usb1: Not yet implemented
[  372.031993] usb usb1: Not yet implemented
[  372.032008] usb usb1: Not yet implemented
[  372.032016] usb usb1: Not yet implemented
[  372.032026] usb usb1: Not yet implemented
[  372.036178] usb usb1: Not yet implemented
[  372.036195] usb usb1: Not yet implemented
[  377.031699] vhci_get_frame_number: 4994 callbacks suppressed
[  377.031702] usb usb1: Not yet implemented
[  377.031731] usb usb1: Not yet implemented
[  377.031742] usb usb1: Not yet implemented
[  377.031752] usb usb1: Not yet implemented
[  377.035709] usb usb1: Not yet implemented
[  377.035729] usb usb1: Not yet implemented
[  377.035739] usb usb1: Not yet implemented
[  377.035750] usb usb1: Not yet implemented
[  377.039712] usb usb1: Not yet implemented
[  377.039728] usb usb1: Not yet implemented
[  382.035304] vhci_get_frame_number: 4994 callbacks suppressed
[  382.035307] usb usb1: Not yet implemented
[  382.035334] usb usb1: Not yet implemented
[  382.035343] usb usb1: Not yet implemented
[  382.035352] usb usb1: Not yet implemented
[  382.039232] usb usb1: Not yet implemented
[  382.039245] usb usb1: Not yet implemented
[  382.039254] usb usb1: Not yet implemented
[  382.039263] usb usb1: Not yet implemented
[  382.043111] usb usb1: Not yet implemented
[  382.043127] usb usb1: Not yet implemented
[  387.038772] vhci_get_frame_number: 4994 callbacks suppressed
[  387.038775] usb usb1: Not yet implemented
[  387.038803] usb usb1: Not yet implemented
[  387.038814] usb usb1: Not yet implemented
[  387.038824] usb usb1: Not yet implemented
[  387.042827] usb usb1: Not yet implemented
[  387.042843] usb usb1: Not yet implemented
[  387.042855] usb usb1: Not yet implemented
[  387.042866] usb usb1: Not yet implemented
[  387.047233] usb usb1: Not yet implemented
[  387.047248] usb usb1: Not yet implemented
[  392.042306] vhci_get_frame_number: 4994 callbacks suppressed
[  392.042309] usb usb1: Not yet implemented
[  392.042337] usb usb1: Not yet implemented
[  392.042346] usb usb1: Not yet implemented
[  392.042355] usb usb1: Not yet implemented
[  392.046234] usb usb1: Not yet implemented
[  392.046248] usb usb1: Not yet implemented
[  392.046257] usb usb1: Not yet implemented
[  392.046266] usb usb1: Not yet implemented
[  392.050248] usb usb1: Not yet implemented
[  392.050262] usb usb1: Not yet implemented
[  397.045910] vhci_get_frame_number: 4994 callbacks suppressed
[  397.045913] usb usb1: Not yet implemented
[  397.045941] usb usb1: Not yet implemented
[  397.045950] usb usb1: Not yet implemented
[  397.045959] usb usb1: Not yet implemented
[  397.049980] usb usb1: Not yet implemented
[  397.049995] usb usb1: Not yet implemented
[  397.050005] usb usb1: Not yet implemented
[  397.050016] usb usb1: Not yet implemented
[  397.054920] usb usb1: Not yet implemented
[  397.054937] usb usb1: Not yet implemented
[  402.047113] vhci_get_frame_number: 4982 callbacks suppressed
[  402.047116] usb usb1: Not yet implemented
[  402.047148] usb usb1: Not yet implemented
[  402.047158] usb usb1: Not yet implemented
[  402.047169] usb usb1: Not yet implemented
[  402.051156] usb usb1: Not yet implemented
[  402.051172] usb usb1: Not yet implemented
[  402.051183] usb usb1: Not yet implemented
[  402.051193] usb usb1: Not yet implemented
[  402.055377] usb usb1: Not yet implemented
[  402.055394] usb usb1: Not yet implemented
[  407.050387] vhci_get_frame_number: 4994 callbacks suppressed
[  407.050390] usb usb1: Not yet implemented
[  407.050421] usb usb1: Not yet implemented
[  407.050430] usb usb1: Not yet implemented
[  407.050438] usb usb1: Not yet implemented
[  407.054971] usb usb1: Not yet implemented
[  407.054987] usb usb1: Not yet implemented
[  407.054996] usb usb1: Not yet implemented
[  407.055005] usb usb1: Not yet implemented
[  407.058390] usb usb1: Not yet implemented
[  407.058403] usb usb1: Not yet implemented
[  412.054113] vhci_get_frame_number: 4994 callbacks suppressed
[  412.054117] usb usb1: Not yet implemented
[  412.054147] usb usb1: Not yet implemented
[  412.054158] usb usb1: Not yet implemented
[  412.054169] usb usb1: Not yet implemented
[  412.058494] usb usb1: Not yet implemented
[  412.058510] usb usb1: Not yet implemented
[  412.058522] usb usb1: Not yet implemented
[  412.058532] usb usb1: Not yet implemented
[  412.062100] usb usb1: Not yet implemented
[  412.062117] usb usb1: Not yet implemented
[  417.057689] vhci_get_frame_number: 4994 callbacks suppressed
[  417.057693] usb usb1: Not yet implemented
[  417.057720] usb usb1: Not yet implemented
[  417.057729] usb usb1: Not yet implemented
[  417.057738] usb usb1: Not yet implemented
[  417.061499] usb usb1: Not yet implemented
[  417.061512] usb usb1: Not yet implemented
[  417.061522] usb usb1: Not yet implemented
[  417.061531] usb usb1: Not yet implemented
[  417.065577] usb usb1: Not yet implemented
[  417.065592] usb usb1: Not yet implemented
[  422.061065] vhci_get_frame_number: 4994 callbacks suppressed
[  422.061068] usb usb1: Not yet implemented
[  422.061097] usb usb1: Not yet implemented
[  422.061106] usb usb1: Not yet implemented
[  422.061115] usb usb1: Not yet implemented
[  422.065085] usb usb1: Not yet implemented
[  422.065102] usb usb1: Not yet implemented
[  422.065111] usb usb1: Not yet implemented
[  422.065120] usb usb1: Not yet implemented
[  422.069175] usb usb1: Not yet implemented
[  422.069193] usb usb1: Not yet implemented
[  427.064797] vhci_get_frame_number: 4987 callbacks suppressed
[  427.064800] usb usb1: Not yet implemented
[  427.064830] usb usb1: Not yet implemented
[  427.064841] usb usb1: Not yet implemented
[  427.064851] usb usb1: Not yet implemented
[  427.069770] usb usb1: Not yet implemented
[  427.069789] usb usb1: Not yet implemented
[  427.069801] usb usb1: Not yet implemented
[  427.069812] usb usb1: Not yet implemented
[  427.072886] usb usb1: Not yet implemented
[  427.072902] usb usb1: Not yet implemented
[  432.065537] vhci_get_frame_number: 4971 callbacks suppressed
[  432.065540] usb usb1: Not yet implemented
[  432.065568] usb usb1: Not yet implemented
[  432.065577] usb usb1: Not yet implemented
[  432.065586] usb usb1: Not yet implemented
[  432.069318] usb usb1: Not yet implemented
[  432.069332] usb usb1: Not yet implemented
[  432.069341] usb usb1: Not yet implemented
[  432.069350] usb usb1: Not yet implemented
[  432.073328] usb usb1: Not yet implemented
[  432.073342] usb usb1: Not yet implemented
[  437.068816] vhci_get_frame_number: 4991 callbacks suppressed
[  437.068819] usb usb1: Not yet implemented
[  437.068850] usb usb1: Not yet implemented
[  437.068861] usb usb1: Not yet implemented
[  437.068872] usb usb1: Not yet implemented
[  437.072838] usb usb1: Not yet implemented
[  437.072856] usb usb1: Not yet implemented
[  437.072867] usb usb1: Not yet implemented
[  437.072878] usb usb1: Not yet implemented
[  437.076854] usb usb1: Not yet implemented
[  437.076872] usb usb1: Not yet implemented
[  442.070825] vhci_get_frame_number: 4990 callbacks suppressed
[  442.070828] usb usb1: Not yet implemented
[  442.070869] usb usb1: Not yet implemented
[  442.070878] usb usb1: Not yet implemented
[  442.070890] usb usb1: Not yet implemented
[  442.072673] usb usb1: Not yet implemented
[  442.072687] usb usb1: Not yet implemented
[  442.072696] usb usb1: Not yet implemented
[  442.072705] usb usb1: Not yet implemented
[  442.076316] usb usb1: Not yet implemented
[  442.076345] usb usb1: Not yet implemented
[  447.072089] vhci_get_frame_number: 4994 callbacks suppressed
[  447.072093] usb usb1: Not yet implemented
[  447.072125] usb usb1: Not yet implemented
[  447.072134] usb usb1: Not yet implemented
[  447.072143] usb usb1: Not yet implemented
[  447.075867] usb usb1: Not yet implemented
[  447.075882] usb usb1: Not yet implemented
[  447.075891] usb usb1: Not yet implemented
[  447.075900] usb usb1: Not yet implemented
[  447.080226] usb usb1: Not yet implemented
[  447.080240] usb usb1: Not yet implemented
[  452.075521] vhci_get_frame_number: 4994 callbacks suppressed
[  452.075524] usb usb1: Not yet implemented
[  452.075554] usb usb1: Not yet implemented
[  452.075566] usb usb1: Not yet implemented
[  452.075577] usb usb1: Not yet implemented
[  452.079634] usb usb1: Not yet implemented
[  452.079650] usb usb1: Not yet implemented
[  452.079661] usb usb1: Not yet implemented
[  452.079672] usb usb1: Not yet implemented
[  452.083583] usb usb1: Not yet implemented
[  452.083598] usb usb1: Not yet implemented
Log - remove webcam via Device Manager

At this point I forcefully remove the webcame via Qubes Device Manager. (To compare it to your result)

[  455.348443] vhci_hcd: connection closed
[  455.348692] vhci_hcd: stop threads
[  455.348714] vhci_hcd: release socket
[  455.348725] vhci_hcd: disconnect device
[  455.348783] usb 1-1: USB disconnect, device number 2

The only key difference seems to be on the following line (of your log). Or at least I couldn’t find any noticeable differences (but another pair of eve balls may help spot something else):

[  160.096038] vhci_hcd: connection reset by peer

Whereas in my log, when I detach via the Device Manager it says instead

[  455.348443] vhci_hcd: connection closed

So we have to investigate who this “peer” is. But this is about the extent of my digging. I don’t know where to go from here.

Maybe someone more experienced can help. Or we may start a formal bug report on Qubes Issues.

Temporary Solution

As a temporary solution, the only think I can think of is for you to get a USB webcam and attach it tho the VM.

1 Like

I tried today the temporary solution of a USB webcam attached to the VM, instead of the built-in webcam. And I faced the same problem (running Jitsi Meet in Firefox in Fedora). The USB webcam worked for a few minutes before it stopped working and my video signal froze for the others.

I’ve experience similar issues when experimenting with an external webcam. It detected the webcam and it showed the image, but after a few seconds it disconnected. After that I was not even able to get the video from the webcam as it got disconnected right after I opened an application that used the webcam.

Looking at the logs in /var/log/xen/console/ I was only able to get 1 time the vhci_hcd: connection reset by peer and all the other times the vhci_hcd: connection closed. So I’m not exactly sure if it’s the same problem or not

1 Like

Since nobody else stepped in, I guess our last resort is to report this as an issue on the bug tracker:

The only similar issue was this one but I was unable to assert if that is the same one or not.

@oijawyuh would you like to report the issue or shall I go ahead?

See if this resolves it:

  1. Shutdown sys-usb
  2. Go to the settings of sys-usb, is at 300MB of memory? Increase it to 1024MB
  3. Boot sys-usb
  4. Attach camera to AppVM qube

Does it work?

This might be:

If it still has disconnection issues, check dmesg within sys-usb as well.

5 Likes

I will try this and see if it works. Thank you @icequbes1 and @deeplow for your responses.

2 Likes

So, I upgraded sys-usb from 300MB to 1024MB and I used video conferencing software (Zoom, Jitsi) in the past 3 weeks and I encountered NO PROBLEM to-date with the camera. It seems that my problem here has been solved. Thank you!

Maybe sys-usb should be 1024MB by default?

5 Likes

Interesting. Glad it’s solved!

Thanks for your help @icequbes1

There is also this suggestion by Andrew to perhaps increase it only when a webcam is detected.

2 Likes

As an update, a friend was trying to use an HDMI video to USB converter, and he was experiencing a similar situation as I described above with my webcam - the plugged in USB converter connects for a few seconds and then fails. I increased his sys-usb RAM from 300 MB to 1024 MB and the problem was solved.

I hope that as of Qubes 4.04 or 4.1, RAM in sys-usb is by default 1024 MB. The problem affects peripherals rather than the webcam only.

2 Likes

Thanks! Shared your comment on the related issue so the developers can see it as well.

btw, thanks to a contribution by @icequbes1 3 days ago, this solution is now suggested on the documentation. Thank you both!

2 Likes

Documenting is sometimes not enough