On Wed, 4 Jan 2023 at 02:21, Takashi Iwai tiwai@suse.de wrote:
Hrm... Try to reload snd_usb_audio module with the dyndbg=+p option, e.g.
# modprobe -r snd-usb-audio # modprobe snd_usb_audio dyndbg=+p
Or you can try to put your own debug printk(); we need to make sure whether it's really the right code you're testing at first.
Ok, it looks like it was ignoring the kernel command line for some reason. modprobing it with the option brought up debug messages again.
I'm still running kernel 6.1.2 vanilla with the revert and the patches.
aplay hung again when I ran it, the kernel was stuck on: Jan 04 02:25:59 leatherback kernel: usb 1-4: 1:1 Start Playback PCM
and didn't output another line until I ctrl+c aplay.
Jan 04 02:25:27 leatherback kernel: mc: Linux media interface: v0.10 Jan 04 02:25:27 leatherback kernel: usb 1-3: Found last interface = 1 Jan 04 02:25:27 leatherback kernel: usb 1-4: Set quirk_flags 0x20010 for device 1397:0509 Jan 04 02:25:27 leatherback kernel: usb 1-4: Found last interface = 4 Jan 04 02:25:27 leatherback kernel: usb 1-4: 1:1: added playback implicit_fb sync_ep 88, iface 2:1 Jan 04 02:25:27 leatherback kernel: usb 1-4: 1:1: add audio endpoint 0x8 Jan 04 02:25:27 leatherback kernel: usb 1-4: Creating new data endpoint #8 Jan 04 02:25:27 leatherback kernel: usb 1-4: Creating new data endpoint #88 Jan 04 02:25:27 leatherback kernel: usb 1-4: 2:1: add audio endpoint 0x88 Jan 04 02:25:27 leatherback kernel: usb 1-4: [10] FU [PCM Playback Switch] ch = 4, val = 0/1/1 Jan 04 02:25:27 leatherback kernel: usb 1-4: [10] FU [PCM Playback Switch] ch = 1, val = 0/1/1 Jan 04 02:25:27 leatherback kernel: usb 1-4: [10] FU [PCM Playback Volume] ch = 4, val = -32512/0/256 Jan 04 02:25:27 leatherback kernel: usb 1-4: [10] FU [PCM Playback Volume] ch = 1, val = -32512/0/256 Jan 04 02:25:27 leatherback kernel: usb 1-4: [11] FU [Mic Capture Switch] ch = 4, val = 0/1/1 Jan 04 02:25:27 leatherback kernel: usb 1-4: [11] FU [Mic Capture Switch] ch = 1, val = 0/1/1 Jan 04 02:25:27 leatherback kernel: usb 1-4: [11] FU [Mic Capture Volume] ch = 4, val = -32512/0/256 Jan 04 02:25:27 leatherback kernel: usb 1-4: [11] FU [Mic Capture Volume] ch = 1, val = -32512/0/256 Jan 04 02:25:27 leatherback kernel: usbcore: registered new interface driver snd-usb-audio Jan 04 02:25:59 leatherback kernel: usb 1-4: Open EP 0x8, iface=1:1, idx=0 Jan 04 02:25:59 leatherback kernel: usb 1-4: channels=4, rate=48000, format=S32_LE, period_bytes=96000, periods=4, implicit_fb=1 Jan 04 02:25:59 leatherback kernel: usb 1-4: Open EP 0x88, iface=2:1, idx=0 Jan 04 02:25:59 leatherback kernel: usb 1-4: channels=4, rate=48000, format=S32_LE, period_bytes=96000, periods=4, implicit_fb=0 Jan 04 02:25:59 leatherback kernel: usb 1-4: Setting params for data EP 0x88, pipe 0x40580 Jan 04 02:25:59 leatherback kernel: usb 1-4: Set up 12 URBS, ret=0 Jan 04 02:25:59 leatherback kernel: usb 1-4: Setting params for data EP 0x8, pipe 0x40500 Jan 04 02:25:59 leatherback kernel: usb 1-4: Set up 12 URBS, ret=0 Jan 04 02:25:59 leatherback kernel: usb 1-4: Setting usb interface 2:0 for EP 0x88 Jan 04 02:25:59 leatherback kernel: usb 1-4: 2:1 Set sample rate 48000, clock 40 Jan 04 02:25:59 leatherback kernel: usb 1-4: Setting usb interface 2:1 for EP 0x88 Jan 04 02:25:59 leatherback kernel: usb 1-4: Setting usb interface 1:0 for EP 0x8 Jan 04 02:25:59 leatherback kernel: usb 1-4: Setting usb interface 1:1 for EP 0x8 Jan 04 02:25:59 leatherback kernel: usb 1-4: Starting data EP 0x8 (running 0) Jan 04 02:25:59 leatherback kernel: usb 1-4: 12 URBs submitted for EP 0x8 Jan 04 02:25:59 leatherback kernel: usb 1-4: Starting data EP 0x88 (running 0) Jan 04 02:25:59 leatherback kernel: usb 1-4: 12 URBs submitted for EP 0x88 Jan 04 02:25:59 leatherback kernel: usb 1-4: 1:1 Start Playback PCM Jan 04 02:26:20 leatherback kernel: usb 1-4: Stopping data EP 0x88 (running 1) Jan 04 02:26:20 leatherback kernel: usb 1-4: Stopping data EP 0x8 (running 1) Jan 04 02:26:20 leatherback kernel: usb 1-4: 1:1 Stop Playback PCM Jan 04 02:26:20 leatherback kernel: usb 1-4: Closing EP 0x8 (count 1) Jan 04 02:26:20 leatherback kernel: usb 1-4: Setting usb interface 1:0 for EP 0x8 Jan 04 02:26:20 leatherback kernel: usb 1-4: EP 0x8 closed Jan 04 02:26:20 leatherback kernel: usb 1-4: Closing EP 0x88 (count 1) Jan 04 02:26:20 leatherback kernel: usb 1-4: Setting usb interface 2:0 for EP 0x88 Jan 04 02:26:20 leatherback kernel: usb 1-4: EP 0x88 closed