You are not logged in.

#1 2019-09-24 07:17:00

Batou
Member
Registered: 2017-01-03
Posts: 259

USB Audio Interface error: clock source 18 is not valid, cannot use

Hi all,

I have experienced this bug a few times over the years but with 5.2.x and 5.3.x kernel it's become a lot more common. With 5.3.1 I now experience it at least once a day or more. Basically, when you play audio through an USB interface, all of a sudden, it freezes. Also, every application that uses audio freezes too. You have to kill programs to quit them. At that point everything that uses audio is frozen and if you start a new application that uses audio, it too will freeze.

Interestingly, if you suspend and then wake the computer up, everything unfreezes and you can play the audio again.

dmesg log below shows the errors and how they go away after you wake up the machine:

[Sep24 02:43] usb 2-3.4.1: 1:1: cannot set freq 44100 (v2/v3): err -32
[  +0.000140] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000001] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +0.010096] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -32
[  +5.027187] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120031] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[Sep24 02:44] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000002] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.120069] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.119954] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120018] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +5.120022] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000004] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.119985] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.119999] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120007] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +5.119955] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000003] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.120080] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.119935] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120046] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[Sep24 02:45] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000003] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.120037] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.119947] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.119977] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +5.120026] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000002] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.119994] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120021] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.119991] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +5.120045] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000004] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.120042] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.119925] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120007] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[Sep24 02:46] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000002] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.119979] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120007] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120038] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +5.119988] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000005] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.119939] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120136] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120002] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +5.119882] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.000002] usb 2-3.4.1: clock source 18 is not valid, cannot use
[  +5.120015] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +2.217711] PM: suspend entry (deep)
[  +0.003059] Filesystems sync: 0.003 seconds
[  +0.777723] Freezing user space processes ... 
[  +2.121526] usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
[  +5.120045] usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
[  +0.006788] (elapsed 7.248 seconds) done.
[  +0.000006] OOM killer disabled.
[  +0.000002] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[  +0.001692] printk: Suspending console(s) (use no_console_suspend to debug)
[  +0.004387] e1000e: EEE TX LPI TIMER: 00000011
[  +0.011156] sd 2:0:0:0: [sdb] Synchronizing SCSI cache
[  +0.000215] sd 2:0:0:0: [sdb] Stopping disk
[  +0.002437] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[  +0.000039] sd 4:0:0:0: [sdc] Synchronizing SCSI cache
[  +0.001311] sd 4:0:0:0: [sdc] Stopping disk
[  +0.000114] sd 0:0:0:0: [sda] Stopping disk
[Sep24 02:47] ACPI: Preparing to enter system sleep state S3
[  +0.049783] PM: Saving platform NVS memory
[  +0.000066] Disabling non-boot CPUs ...
[  +0.001594] smpboot: CPU 1 is now offline
[  +0.003532] smpboot: CPU 2 is now offline
[  +0.004382] smpboot: CPU 3 is now offline
[  +0.003460] smpboot: CPU 4 is now offline
[  +0.002305] smpboot: CPU 5 is now offline
[  +0.002207] smpboot: CPU 6 is now offline
[  +0.002430] smpboot: CPU 7 is now offline
[  +0.002678] ACPI: Low-level resume complete
[  +0.000040] PM: Restoring platform NVS memory
[  +0.002451] Enabling non-boot CPUs ...
[  +0.000050] x86: Booting SMP configuration:
[  +0.000001] smpboot: Booting Node 0 Processor 1 APIC 0x2
[  +0.001957] CPU1 is up
[  +0.000040] smpboot: Booting Node 0 Processor 2 APIC 0x4
[  +0.001967] CPU2 is up
[  +0.000026] smpboot: Booting Node 0 Processor 3 APIC 0x6
[  +0.001973] CPU3 is up
[  +0.000026] smpboot: Booting Node 0 Processor 4 APIC 0x1
[  +0.000733] CPU4 is up
[  +0.000024] smpboot: Booting Node 0 Processor 5 APIC 0x3
[  +0.000626] CPU5 is up
[  +0.000019] smpboot: Booting Node 0 Processor 6 APIC 0x5
[  +0.000642] CPU6 is up
[  +0.000021] smpboot: Booting Node 0 Processor 7 APIC 0x7
[  +0.001131] CPU7 is up
[  +0.005772] ACPI: Waking up from system sleep state S3
[  +0.025079] sd 0:0:0:0: [sda] Starting disk
[  +0.000031] sd 2:0:0:0: [sdb] Starting disk
[  +0.000024] sd 4:0:0:0: [sdc] Starting disk
[  +0.106494] snd_hda_intel 0000:05:00.0: Direct firmware load for ctefx-desktop.bin failed with error -2
[  +0.172270] usb 2-5: reset full-speed USB device number 5 using xhci_hcd
[  +0.026320] ata7: SATA link down (SStatus 0 SControl 300)
[  +0.000054] ata8: SATA link down (SStatus 0 SControl 300)
[  +0.049601] ata4: SATA link down (SStatus 0 SControl 300)
[  +0.000261] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[  +0.000049] ata2: SATA link down (SStatus 0 SControl 300)
[  +0.000183] ata6: SATA link down (SStatus 0 SControl 300)
[  +0.000048] ata1.00: supports DRM functions and may not be fully accessible
[  +0.000843] ata1.00: disabling queued TRIM support
[  +0.001688] ata1.00: supports DRM functions and may not be fully accessible
[  +0.000523] ata1.00: disabling queued TRIM support
[  +0.001323] ata1.00: configured for UDMA/133
[  +0.149101] usb 2-3.4: reset high-speed USB device number 6 using xhci_hcd
[  +0.290271] snd_hda_codec_ca0132 hdaudioC2D1: ca0132 DSP downloaded and running
[  +0.126533] usb 2-3.4.1: reset high-speed USB device number 12 using xhci_hcd
[  +0.100467] nct6775: Enabling hardware monitor logical device mappings.
[  +4.369262] ata5: link is slow to respond, please be patient (ready=0)
[  +0.003336] ata3: link is slow to respond, please be patient (ready=0)
[  +0.056896] usb 2-10: reset low-speed USB device number 9 using xhci_hcd
[  +0.884813] e1000e: eno1 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
[  +0.871637] ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[  +0.000895] ata3.00: supports DRM functions and may not be fully accessible
[  +0.000403] ata3.00: ATA Identify Device Log not supported
[  +0.000004] ata3.00: Security Log not supported
[  +0.000896] ata3.00: supports DRM functions and may not be fully accessible
[  +0.000349] ata3.00: ATA Identify Device Log not supported
[  +0.000002] ata3.00: Security Log not supported
[  +0.000008] ata3.00: configured for UDMA/133
[  +2.827435] ata5: COMRESET failed (errno=-16)
[  +0.596677] ata5: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[  +0.000335] ACPI BIOS Error (bug): Could not resolve symbol [\_SB.PCI0.SAT0.SPT4._GTF.DSSP], AE_NOT_FOUND (20190703/psargs-330)
[  +0.000021] ACPI Error: Aborting method \_SB.PCI0.SAT0.SPT4._GTF due to previous error (AE_NOT_FOUND) (20190703/psparse-529)
[  +0.081532] ACPI BIOS Error (bug): Could not resolve symbol [\_SB.PCI0.SAT0.SPT4._GTF.DSSP], AE_NOT_FOUND (20190703/psargs-330)
[  +0.000021] ACPI Error: Aborting method \_SB.PCI0.SAT0.SPT4._GTF due to previous error (AE_NOT_FOUND) (20190703/psparse-529)
[  +0.003211] ata5.00: configured for UDMA/133
[  +0.228383] PM: dpm_run_callback(): usb_dev_resume+0x0/0x10 returns -5
[  +0.000014] PM: Device 2-10 failed to resume async: error -5
[  +0.000913] OOM killer enabled.
[  +0.000004] Restarting tasks ... 
[  +0.012226] usb 2-10: USB disconnect, device number 9
[  +0.000474] done.
[  +0.006943] PM: suspend exit
[  +0.017859] audit: type=1130 audit(1569307635.026:67): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  +0.000004] audit: type=1131 audit(1569307635.026:68): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  +0.083123] e1000e: eno1 NIC Link is Down
[  +0.009042] audit: type=1130 audit(1569307635.123:69): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  +0.005842] usb 2-10: new low-speed USB device number 14 using xhci_hcd
[  +0.145903] usb 2-10: New USB device found, idVendor=0764, idProduct=0501, bcdDevice= 0.01
[  +0.000003] usb 2-10: New USB device strings: Mfr=3, Product=1, SerialNumber=0
[  +0.000001] usb 2-10: Product: SX650G
[  +0.000001] usb 2-10: Manufacturer: CPS
[  +0.017308] hid-generic 0003:0764:0501.000B: hiddev0,hidraw2: USB HID v1.10 Device [CPS SX650G] on usb-0000:00:14.0-10/input0
[  +5.507344] e1000e: eno1 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
[  +4.621183] audit: type=1131 audit(1569307645.419:70): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ +10.691575] mei_me 0000:00:16.0: timer: init clients timeout hbm_state = 1.
[  +0.000012] mei_me 0000:00:16.0: unexpected reset: dev_state = INIT_CLIENTS fw status = 1E000245 60002306 00000200 00000000 00000000 00000000

journal shows the following:

Sep 24 02:43:50 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:43:56 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:44:01 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:44:01 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:44:06 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:44:11 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:44:16 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:44:21 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:44:21 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:44:26 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:44:31 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:44:37 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:44:42 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:44:42 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:44:47 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:44:52 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:44:57 batou pulseaudio[1049]: E: [alsa-sink-USB Audio] alsa-sink.c: Failed to set hardware parameters: No such device or address
Sep 24 02:44:57 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:45:02 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:45:02 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:45:07 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:45:12 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:45:18 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:45:23 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:45:23 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:45:28 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:45:33 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:45:38 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:45:43 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:45:43 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:45:48 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:45:53 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:45:58 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:46:04 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:46:04 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:46:09 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:46:14 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:46:19 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:46:24 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:46:24 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:46:29 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:46:34 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:46:39 batou pulseaudio[1049]: E: [alsa-sink-USB Audio] alsa-sink.c: Failed to set hardware parameters: No such device or address
Sep 24 02:46:39 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:46:45 batou kernel: usb 2-3.4.1: uac_clock_source_is_valid(): cannot get clock validity for id 18
Sep 24 02:46:45 batou kernel: usb 2-3.4.1: clock source 18 is not valid, cannot use
Sep 24 02:46:50 batou kernel: usb 2-3.4.1: 1:1: cannot get freq (v2/v3): err -110
Sep 24 02:46:52 batou NetworkManager[769]: <info>  [1569307612.0700] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Sep 24 02:46:52 batou NetworkManager[769]: <info>  [1569307612.0701] manager: NetworkManager state is now ASLEEP
Sep 24 02:46:52 batou systemd[1]: Reached target Sleep.

I have experienced this with several different audio interfaces. This is not something that's vendor specific. I have used this audio interface with macOS at one of the work machines and there are no issues with it whatsoever.

USB DAC is Schiit Modi:

[  +0.049942] usb 2-3.4.1: new full-speed USB device number 22 using xhci_hcd
[  +0.149982] usb 2-3.4.1: new high-speed USB device number 23 using xhci_hcd
[  +0.098497] usb 2-3.4.1: New USB device found, idVendor=0d8c, idProduct=0319, bcdDevice= 1.05
[  +0.000002] usb 2-3.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[  +0.000001] usb 2-3.4.1: Product: USB Modi Device
[  +0.000000] usb 2-3.4.1: Manufacturer: Schiit Audio

Kernel is latest. Everything is updated as of the time this post is written.

Linux batou 5.3.1-arch1-1-ARCH #1 SMP PREEMPT Sat Sep 21 11:33:49 UTC 2019 x86_64 GNU/Linux

Does anyone have any ideas how to fix this? I'm suspecting this is a kernel issue...

Thanks!

Last edited by Batou (2019-09-24 20:53:53)


Please vote for all the AUR packages you're using. You can mass-vote for all of them by doing: "pacman -Qqm | xargs aurvote -v" (make sure to run "aurvote --configure"  first)

Offline

#2 2019-09-24 11:53:17

V1del
Forum Moderator
Registered: 2012-10-16
Posts: 21,410

Re: USB Audio Interface error: clock source 18 is not valid, cannot use

Any power management daemons active that enable USB autosuspend? FWIW since it seems to have issues with the UAC clock try loading in modprobe.d or so

options snd_usb_audio autoclock=0

Offline

#3 2019-09-24 20:50:08

Batou
Member
Registered: 2017-01-03
Posts: 259

Re: USB Audio Interface error: clock source 18 is not valid, cannot use

V1del wrote:

Any power management daemons active that enable USB autosuspend? FWIW since it seems to have issues with the UAC clock try loading in modprobe.d or so

options snd_usb_audio autoclock=0

No USB autosuspend.. I have this kernel options set though:

usbcore.autosuspend=-1

IIRC, this was a fix for my mouse sometimes not working. I'll give those options a try when I reboot later on! Thank you.

After some searching, I came across this post: http://guillaumeplayground.net/teac-ud- … dio-patch/
It's about a different USB interface from mine but it's the same issue...


Please vote for all the AUR packages you're using. You can mass-vote for all of them by doing: "pacman -Qqm | xargs aurvote -v" (make sure to run "aurvote --configure"  first)

Offline

Board footer

Powered by FluxBB