From: Lucas <jaffa225man@gmail.com>
To: Takashi Iwai <tiwai@suse.de>
Cc: alsa-devel@alsa-project.org,
"Keith A . Milner" <maillist@superlative.org>,
Mike Oliphant <oliphant@nostatic.org>
Subject: Re: [PATCH 2/2] ALSA: usb-audio: Generic application of implicit fb to Roland/BOSS devices
Date: Fri, 23 Apr 2021 13:04:38 -0500 [thread overview]
Message-ID: <CAOsVg8rPfh-scSmmJSVASPTw7o3vw_zZ05YzVZ_JYFJHbcOcyg@mail.gmail.com> (raw)
In-Reply-To: <s5hlf99788o.wl-tiwai@suse.de>
On Fri, Apr 23, 2021 at 2:47 AM Takashi Iwai <tiwai@suse.de> wrote:
> Is this really with snd-usb-audio driver? Please check the entry in
> /proc/asound/modules (or traverse via sysfs).
>
No, I see it's not. It's using the correct snd_ua101 module
This rather sounds like a generic problem of the implicit feedback.
> Please give the kernel log with snd_usb_audio.dyndbg=+p boot option
> with some notion which timestamp corresponding to which action
> (e.g. starting playback, stopping recording, etc).
>
Okay, that's good news! Thanks, and here's that:
I turned the INTEGRA-7 on at 11:59:00
It started fully at 11:59:27, which is probably when the kernel could see
it:
[ 1632.073938] usb 1-1.6: new high-speed USB device number 5 using ehci-pci
[ 1634.034266] usb 1-1.6: New USB device found, idVendor=0582,
idProduct=015b, bcdDevice= 1.00
[ 1634.034274] usb 1-1.6: New USB device strings: Mfr=1, Product=2,
SerialNumber=0
[ 1634.034277] usb 1-1.6: Product: INTEGRA-7
[ 1634.034279] usb 1-1.6: Manufacturer: Roland
[ 1634.036522] usb 1-1.6: 1:1: added playback implicit_fb sync_ep 8e, iface
2:1
[ 1634.036527] usb 1-1.6: 1:1: add audio endpoint 0xd
[ 1634.036539] usb 1-1.6: Creating new data endpoint #d
[ 1634.036541] usb 1-1.6: Creating new data endpoint #8e
[ 1634.036638] usb 1-1.6: 1:1 Set sample rate 96000, clock 0
[ 1634.036891] usb 1-1.6: 2:1: added capture implicit_fb sync_ep d, iface
1:1
[ 1634.036894] usb 1-1.6: 2:1: add audio endpoint 0x8e
[ 1634.036997] usb 1-1.6: 2:1 Set sample rate 96000, clock 0
[ 1634.082834] usb 1-1.6: Open EP 0x8e, iface=2:1, idx=0
[ 1634.082840] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=19184, periods=4, implicit_fb=1
[ 1634.082843] usb 1-1.6: Open EP 0xd, iface=1:1, idx=0
[ 1634.082844] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=19184, periods=4, implicit_fb=1
[ 1634.082847] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.083011] usb 1-1.6: Setting usb interface 2:1 for EP 0x8e
[ 1634.083143] usb 1-1.6: 2:1 Set sample rate 96000, clock 0
[ 1634.083146] usb 1-1.6: Setting params for data EP 0x8e, pipe 0x70580
[ 1634.083160] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.083162] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.083246] usb 1-1.6: Setting usb interface 1:1 for EP 0xd
[ 1634.083416] usb 1-1.6: 1:1 Set sample rate 96000, clock 0
[ 1634.083421] usb 1-1.6: Setting params for data EP 0xd, pipe 0x68500
[ 1634.083444] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.085019] usb 1-1.6: Closing EP 0x8e (count 1)
[ 1634.085022] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.085144] usb 1-1.6: EP 0x8e closed
[ 1634.085146] usb 1-1.6: Closing EP 0xd (count 1)
[ 1634.085148] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.085273] usb 1-1.6: EP 0xd closed
[ 1634.085652] usb 1-1.6: Open EP 0x8e, iface=2:1, idx=0
[ 1634.085655] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=19184, periods=4, implicit_fb=1
[ 1634.085658] usb 1-1.6: Open EP 0xd, iface=1:1, idx=0
[ 1634.085659] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=19184, periods=4, implicit_fb=1
[ 1634.085661] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.085826] usb 1-1.6: Setting usb interface 2:1 for EP 0x8e
[ 1634.085873] usb 1-1.6: 2:1 Set sample rate 96000, clock 0
[ 1634.085879] usb 1-1.6: Setting params for data EP 0x8e, pipe 0x70580
[ 1634.085884] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.085886] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.086018] usb 1-1.6: Setting usb interface 1:1 for EP 0xd
[ 1634.086139] usb 1-1.6: 1:1 Set sample rate 96000, clock 0
[ 1634.086153] usb 1-1.6: Setting params for data EP 0xd, pipe 0x68500
[ 1634.086158] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.086292] usb 1-1.6: Closing EP 0x8e (count 1)
[ 1634.086294] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.086400] usb 1-1.6: EP 0x8e closed
[ 1634.086403] usb 1-1.6: Closing EP 0xd (count 1)
[ 1634.086404] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.086524] usb 1-1.6: EP 0xd closed
[ 1634.086817] usb 1-1.6: Open EP 0xd, iface=1:1, idx=0
[ 1634.086820] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=19184, periods=4, implicit_fb=1
[ 1634.086822] usb 1-1.6: Open EP 0x8e, iface=2:1, idx=0
[ 1634.086824] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=19184, periods=4, implicit_fb=1
[ 1634.086826] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.086858] usb 1-1.6: Setting usb interface 1:1 for EP 0xd
[ 1634.086993] usb 1-1.6: 1:1 Set sample rate 96000, clock 0
[ 1634.086996] usb 1-1.6: Setting params for data EP 0xd, pipe 0x68500
[ 1634.087000] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.087001] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.087106] usb 1-1.6: Setting usb interface 2:1 for EP 0x8e
[ 1634.087272] usb 1-1.6: 2:1 Set sample rate 96000, clock 0
[ 1634.087279] usb 1-1.6: Setting params for data EP 0x8e, pipe 0x70580
[ 1634.087284] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.087295] usb 1-1.6: Starting data EP 0xd (running 0)
[ 1634.087320] usb 1-1.6: 12 URBs submitted for EP 0xd
[ 1634.087322] usb 1-1.6: Starting data EP 0x8e (running 0)
[ 1634.087338] usb 1-1.6: 12 URBs submitted for EP 0x8e
[ 1634.088270] usb 1-1.6: Reopened EP 0x8e (count 1)
[ 1634.088273] usb 1-1.6: Reopened EP 0xd (count 1)
[ 1634.088286] usb 1-1.6: Closing EP 0x8e (count 2)
[ 1634.088288] usb 1-1.6: Closing EP 0xd (count 2)
[ 1634.088599] usb 1-1.6: Reopened EP 0x8e (count 1)
[ 1634.088601] usb 1-1.6: Reopened EP 0xd (count 1)
[ 1634.088612] usb 1-1.6: Stopping data EP 0x8e (running 1)
[ 1634.088616] usb 1-1.6: Stopping data EP 0xd (running 1)
[ 1634.101964] usb 1-1.6: Closing EP 0xd (count 2)
[ 1634.101972] usb 1-1.6: Closing EP 0x8e (count 2)
[ 1634.102043] usb 1-1.6: Closing EP 0x8e (count 1)
[ 1634.102045] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.102129] usb 1-1.6: EP 0x8e closed
[ 1634.102132] usb 1-1.6: Closing EP 0xd (count 1)
[ 1634.102134] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.102255] usb 1-1.6: EP 0xd closed
[ 1634.107863] usb 1-1.6: Open EP 0xd, iface=1:1, idx=0
[ 1634.107867] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=19184, periods=4, implicit_fb=1
[ 1634.107869] usb 1-1.6: Open EP 0x8e, iface=2:1, idx=0
[ 1634.107871] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=19184, periods=4, implicit_fb=1
[ 1634.107873] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.107992] usb 1-1.6: Setting usb interface 1:1 for EP 0xd
[ 1634.108137] usb 1-1.6: 1:1 Set sample rate 96000, clock 0
[ 1634.108147] usb 1-1.6: Setting params for data EP 0xd, pipe 0x68500
[ 1634.108161] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.108164] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.108236] usb 1-1.6: Setting usb interface 2:1 for EP 0x8e
[ 1634.108376] usb 1-1.6: 2:1 Set sample rate 96000, clock 0
[ 1634.108384] usb 1-1.6: Setting params for data EP 0x8e, pipe 0x70580
[ 1634.108393] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.108413] usb 1-1.6: Starting data EP 0xd (running 0)
[ 1634.108452] usb 1-1.6: 12 URBs submitted for EP 0xd
[ 1634.108454] usb 1-1.6: Starting data EP 0x8e (running 0)
[ 1634.108470] usb 1-1.6: 12 URBs submitted for EP 0x8e
[ 1634.108782] usb 1-1.6: Reopened EP 0x8e (count 1)
[ 1634.108785] usb 1-1.6: Reopened EP 0xd (count 1)
[ 1634.108796] usb 1-1.6: Closing EP 0x8e (count 2)
[ 1634.108797] usb 1-1.6: Closing EP 0xd (count 2)
[ 1634.109076] usb 1-1.6: Reopened EP 0x8e (count 1)
[ 1634.109078] usb 1-1.6: Reopened EP 0xd (count 1)
[ 1634.109089] usb 1-1.6: Stopping data EP 0x8e (running 1)
[ 1634.109093] usb 1-1.6: Stopping data EP 0xd (running 1)
[ 1634.121982] usb 1-1.6: Closing EP 0xd (count 2)
[ 1634.121987] usb 1-1.6: Closing EP 0x8e (count 2)
[ 1634.122023] usb 1-1.6: Closing EP 0x8e (count 1)
[ 1634.122024] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.122136] usb 1-1.6: EP 0x8e closed
[ 1634.122139] usb 1-1.6: Closing EP 0xd (count 1)
[ 1634.122141] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.122250] usb 1-1.6: EP 0xd closed
[ 1634.136890] usb 1-1.6: Open EP 0xd, iface=1:1, idx=0
[ 1634.136895] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=524288, periods=2, implicit_fb=1
[ 1634.136898] usb 1-1.6: Open EP 0x8e, iface=2:1, idx=0
[ 1634.136899] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=524288, periods=2, implicit_fb=1
[ 1634.136901] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1634.137059] usb 1-1.6: Setting usb interface 1:1 for EP 0xd
[ 1634.137214] usb 1-1.6: 1:1 Set sample rate 96000, clock 0
[ 1634.137225] usb 1-1.6: Setting params for data EP 0xd, pipe 0x68500
[ 1634.137235] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.137238] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1634.137362] usb 1-1.6: Setting usb interface 2:1 for EP 0x8e
[ 1634.137500] usb 1-1.6: 2:1 Set sample rate 96000, clock 0
[ 1634.137508] usb 1-1.6: Setting params for data EP 0x8e, pipe 0x70580
[ 1634.137517] usb 1-1.6: Set up 12 URBS, ret=0
[ 1634.137608] usb 1-1.6: Starting data EP 0xd (running 0)
[ 1634.137630] usb 1-1.6: 12 URBs submitted for EP 0xd
[ 1634.137632] usb 1-1.6: Starting data EP 0x8e (running 0)
[ 1634.137648] usb 1-1.6: 12 URBs submitted for EP 0x8e
[ 1634.138532] usb 1-1.6: 1:1 Start Playback PCM
[ 1634.139021] usb 1-1.6: Reopened EP 0x8e (count 1)
[ 1634.139024] usb 1-1.6: Reopened EP 0xd (count 1)
[ 1634.139549] usb 1-1.6: Starting data EP 0x8e (running 1)
[ 1634.139553] usb 1-1.6: Starting data EP 0xd (running 1)
[ 1634.139554] usb 1-1.6: 2:1 Start Capture PCM
[ 1639.143013] usb 1-1.6: Stopping data EP 0xd (running 2)
[ 1639.143022] usb 1-1.6: Stopping data EP 0x8e (running 2)
[ 1639.143026] usb 1-1.6: 2:1 Stop Capture PCM
[ 1639.143083] usb 1-1.6: Closing EP 0x8e (count 2)
[ 1639.143086] usb 1-1.6: Closing EP 0xd (count 2)
[ 1639.144139] usb 1-1.6: Stopping data EP 0x8e (running 1)
[ 1639.144147] usb 1-1.6: Stopping data EP 0xd (running 1)
[ 1639.144150] usb 1-1.6: 1:1 Stop Playback PCM
[ 1639.157925] usb 1-1.6: Closing EP 0xd (count 1)
[ 1639.157933] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1639.158186] usb 1-1.6: EP 0xd closed
[ 1639.158190] usb 1-1.6: Closing EP 0x8e (count 1)
[ 1639.158192] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1639.158431] usb 1-1.6: EP 0x8e closed
I began playing the long file.wav at 12:00:00
aplay -D hw:INTEGRA7 -f S32_LE -r 96000 -c 2 ./file.wav
Playing WAVE './file.wav' : Signed 32 bit Little Endian, Rate 96000 Hz,
Stereo
[ 1682.241471] usb 1-1.6: Open EP 0xd, iface=1:1, idx=0
[ 1682.241478] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=96000, periods=4, implicit_fb=1
[ 1682.241482] usb 1-1.6: Open EP 0x8e, iface=2:1, idx=0
[ 1682.241484] usb 1-1.6: channels=2, rate=96000, format=S32_LE,
period_bytes=96000, periods=4, implicit_fb=1
[ 1682.241487] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1682.241608] usb 1-1.6: Setting usb interface 1:1 for EP 0xd
[ 1682.241746] usb 1-1.6: 1:1 Set sample rate 96000, clock 0
[ 1682.241754] usb 1-1.6: Setting params for data EP 0xd, pipe 0x68500
[ 1682.241761] usb 1-1.6: Set up 12 URBS, ret=0
[ 1682.241764] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1682.241988] usb 1-1.6: Setting usb interface 2:1 for EP 0x8e
[ 1682.242126] usb 1-1.6: 2:1 Set sample rate 96000, clock 0
[ 1682.242136] usb 1-1.6: Setting params for data EP 0x8e, pipe 0x70580
[ 1682.242144] usb 1-1.6: Set up 12 URBS, ret=0
[ 1682.242176] usb 1-1.6: Starting data EP 0xd (running 0)
[ 1682.242202] usb 1-1.6: 12 URBs submitted for EP 0xd
[ 1682.242204] usb 1-1.6: Starting data EP 0x8e (running 0)
[ 1682.242223] usb 1-1.6: 12 URBs submitted for EP 0x8e
[ 1682.242373] usb 1-1.6: 1:1 Start Playback PCM
I began recording soon after:
arecord -D hw:INTEGRA7 -f S32_LE -r 96000 -c 2 ./duplex.wav
Recording WAVE './duplex.wav' : Signed 32 bit Little Endian, Rate 96000 Hz,
Stereo
[ 1742.225424] usb 1-1.6: Reopened EP 0x8e (count 1)
[ 1742.225431] usb 1-1.6: Reopened EP 0xd (count 1)
[ 1742.225555] usb 1-1.6: Starting data EP 0x8e (running 1)
[ 1742.225559] usb 1-1.6: Starting data EP 0xd (running 1)
[ 1742.225561] usb 1-1.6: 2:1 Start Capture PCM
I ended arecord at 12:02:00 with ctrl-c:
^CAborted by signal Interrupt...
And playback became abruptly inaudible at 12:02:00 too.
[ 1798.440257] usb 1-1.6: Stopping data EP 0xd (running 2)
[ 1798.440266] usb 1-1.6: Stopping data EP 0x8e (running 2)
[ 1798.440270] usb 1-1.6: 2:1 Stop Capture PCM
[ 1798.440277] usb 1-1.6: Closing EP 0x8e (count 2)
[ 1798.440280] usb 1-1.6: Closing EP 0xd (count 2)
By 12:03:00 (appears to be 12:02:30), aplay had timed out with:
aplay: pcm_write:2061: write error: Input/output error
[ 1828.881035] usb 1-1.6: Stopping data EP 0x8e (running 1)
[ 1828.881049] usb 1-1.6: Stopping data EP 0xd (running 1)
[ 1828.881053] usb 1-1.6: 1:1 Stop Playback PCM
[ 1828.896990] usb 1-1.6: Closing EP 0xd (count 1)
[ 1828.896998] usb 1-1.6: Setting usb interface 1:0 for EP 0xd
[ 1828.897139] usb 1-1.6: EP 0xd closed
[ 1828.897146] usb 1-1.6: Closing EP 0x8e (count 1)
[ 1828.897149] usb 1-1.6: Setting usb interface 2:0 for EP 0x8e
[ 1828.897406] usb 1-1.6: EP 0x8e closed
I can confirm everything else is perfect, as my previous test of this patch
already proved.
Thanks for putting up with my pedantic musings,
Lucas
next prev parent reply other threads:[~2021-04-23 18:05 UTC|newest]
Thread overview: 15+ messages / expand[flat|nested] mbox.gz Atom feed top
2021-04-22 12:04 [PATCH 1/2] Revert "ALSA: usb-audio: Add support for many Roland devices..." Takashi Iwai
2021-04-22 12:04 ` [PATCH 2/2] ALSA: usb-audio: Generic application of implicit fb to Roland/BOSS devices Takashi Iwai
2021-04-22 19:20 ` Mike Oliphant
2021-04-23 6:49 ` Lucas
2021-04-23 7:47 ` Takashi Iwai
2021-04-23 18:04 ` Lucas [this message]
2021-04-25 9:16 ` Takashi Iwai
2021-04-25 19:23 ` Lucas
2021-04-26 6:34 ` Takashi Iwai
2021-04-28 21:43 ` Lucas
2021-04-29 9:15 ` Takashi Iwai
2021-04-29 17:29 ` Lucas
2021-04-23 9:29 ` Takashi Iwai
2021-04-24 11:09 ` Keith Milner
2021-04-24 21:43 ` Lucas
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=CAOsVg8rPfh-scSmmJSVASPTw7o3vw_zZ05YzVZ_JYFJHbcOcyg@mail.gmail.com \
--to=jaffa225man@gmail.com \
--cc=alsa-devel@alsa-project.org \
--cc=maillist@superlative.org \
--cc=oliphant@nostatic.org \
--cc=tiwai@suse.de \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).