USB sequence errors in snd_usb_usx2y

Bug #1177394 reported by Jon-o Addleman
8
This bug affects 1 person
Affects Status Importance Assigned to Milestone
linux (Ubuntu)
Triaged
Medium
Unassigned

Bug Description

Using kernel 3.8.0.19.35, when I plug in my Tascam us-122 usb audio device, the log fills up with many of the following messages as soon as the firmware is loaded:

May 5 18:40:31 scaph kernel: [64138.774214] Sequence Error!(hcd_frame=391894 ep=10out;wait=391892,frame=391890).
May 5 18:40:31 scaph kernel: [64138.774214] Most probably some urb of usb-frame 391892 is still missing.
May 5 18:40:31 scaph kernel: [64138.774214] Cause could be too long delays in usb-hcd interrupt handling.

The device seems to initialize properly, but any attempt to actually use it (starting jackd for example) results in a few more of those messages in the log, and the program fails to connect to the device.

This is very similar to a bug that cropped up several years ago - here's a thread I made on the alsa-user list back in 2006 (!) about that one: http://<email address hidden>/msg18003.html That bug was fixed in 2.6.18 so I don't know if it might be actually related or not.

In any case, I've temporarily solved the issue by downgrading to quantal's kernel, 3.5.0-28, which doesn't have the bug.

ProblemType: Bug
DistroRelease: Ubuntu 13.04
Package: linux-image-generic 3.8.0.19.35
ProcVersionSignature: Ubuntu 3.5.0-28.48-generic 3.5.7.9
Uname: Linux 3.5.0-28-generic i686
ApportVersion: 2.9.2-0ubuntu8
Architecture: i386
AudioDevicesInUse:
 USER PID ACCESS COMMAND
 /dev/snd/controlC0: jono 1741 F.... pulseaudio
Date: Tue May 7 09:31:02 2013
HibernationDevice: RESUME=UUID=21e8f32e-d542-4900-ac9c-2ade76ed3084
Lsusb:
 Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
 Bus 002 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
 Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
 Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
 Bus 005 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
MachineType: LENOVO 170237U
MarkForUpload: True
PccardctlIdent:
 Socket 0:
   no product info available
PccardctlStatus:
 Socket 0:
   no card
ProcFB: 0 inteldrmfb
ProcKernelCmdLine: BOOT_IMAGE=/boot/vmlinuz-3.5.0-28-generic root=UUID=860958bf-acf0-48eb-abb6-acd3022e5a9c ro quiet splash
RelatedPackageVersions:
 linux-restricted-modules-3.5.0-28-generic N/A
 linux-backports-modules-3.5.0-28-generic N/A
 linux-firmware 1.106
RfKill:
 0: phy0: Wireless LAN
  Soft blocked: no
  Hard blocked: no
SourcePackage: linux
UpgradeStatus: Upgraded to raring on 2013-04-13 (23 days ago)
WifiSyslog:

WpaSupplicantLog:

dmi.bios.date: 03/31/2011
dmi.bios.vendor: LENOVO
dmi.bios.version: 7BETD8WW (2.19 )
dmi.board.name: 170237U
dmi.board.vendor: LENOVO
dmi.board.version: Not Available
dmi.chassis.asset.tag: No Asset Information
dmi.chassis.type: 10
dmi.chassis.vendor: LENOVO
dmi.chassis.version: Not Available
dmi.modalias: dmi:bvnLENOVO:bvr7BETD8WW(2.19):bd03/31/2011:svnLENOVO:pn170237U:pvrThinkPadX60s:rvnLENOVO:rn170237U:rvrNotAvailable:cvnLENOVO:ct10:cvrNotAvailable:
dmi.product.name: 170237U
dmi.product.version: ThinkPad X60s
dmi.sys.vendor: LENOVO

Revision history for this message
Jon-o Addleman (jaddle) wrote :
Revision history for this message
Brad Figg (brad-figg) wrote : Status changed to Confirmed

This change was made by a bot.

Changed in linux (Ubuntu):
status: New → Confirmed
Revision history for this message
Joseph Salisbury (jsalisbury) wrote :

Would it be possible for you to test the latest upstream kernel? Refer to https://wiki.ubuntu.com/KernelMainlineBuilds . Please test the latest v3.9 kernel[0].

If this bug is fixed in the mainline kernel, please add the following tag 'kernel-fixed-upstream'.

If the mainline kernel does not fix this bug, please add the tag: 'kernel-bug-exists-upstream'.

If you are unable to test the mainline kernel, for example it will not boot, please add the tag: 'kernel-unable-to-test-upstream'.
Once testing of the upstream kernel is complete, please mark this bug as "Confirmed".

Thanks in advance.

[0] http://kernel.ubuntu.com/~kernel-ppa/mainline/v3.9-saucy/

Changed in linux (Ubuntu):
importance: Undecided → Medium
status: Confirmed → Incomplete
Revision history for this message
Jon-o Addleman (jaddle) wrote :

The linux-image-3.9.0-030900-generic_3.9.0-030900.201304291257_i386.deb kernel did not fix the problem. Exactly the same thing occurs.

Changed in linux (Ubuntu):
status: Incomplete → Confirmed
penalvch (penalvch)
tags: added: bios-outdated-3.03 needs-upstream-testing regression-release
tags: added: kernel-bug-exists-upstream-v3.9
Changed in linux (Ubuntu):
status: Confirmed → Incomplete
Revision history for this message
Jon-o Addleman (jaddle) wrote :

I think that the 3.03 package is actually a utility for setting up the bios of multiple laptops at once. It doesn't appear to have an actual bios in it.

From the readme:
WHAT THIS PACKAGE DOES

  ThinkPad stores various BIOS settings in its non-volatile memory.
  These settings are configured by using the ThinkPad setup menu. In general,
  this is done manually by the user. However, from a deployment point of view,
  the BIOS settings are expected to be common to all machines and to be
  controlled remotely. This program is the Windows based of "ThinkPad Setup
  Settings Capture/Playback Utility" (SRCMOS.EXE or SRSETUP.EXE).

  Features:
  - Copy ThinkPad setup data settings
  - Copy ThinkPad secure data settings
  - Install and update for power-on password
  - Install for hard disk password

I think BIOS 2.19 (which I have) is the most recent version. (this is the version of the 'BIOS Update Utility' and the 'BIOS Update Bootable CD')

Revision history for this message
penalvch (penalvch) wrote :

Jon-o Addleman, you are correct in that 2.19 is the newest. Despite this, could you please test for this problem in the newest mainline kernel via http://kernel.ubuntu.com/~kernel-ppa/mainline/v3.11-rc2-saucy/ ?

tags: added: latest-bios-2.19
removed: bios-outdated-3.03
Revision history for this message
Launchpad Janitor (janitor) wrote :

[Expired for linux (Ubuntu) because there has been no activity for 60 days.]

Changed in linux (Ubuntu):
status: Incomplete → Expired
Revision history for this message
Jon-o Addleman (jaddle) wrote :

Finally had a chance to test this with a more recent kernel... With the latest in Saucy (3.11.0-12-generic) I get the same error. I'll try with v3.12-rc6-saucy too, though I don't see any reason for it to change there.

Revision history for this message
Jon-o Addleman (jaddle) wrote :

Spoke too soon! It does seem to work with 3.12-rc6! I'm not getting the usb sequence error message anyway, and it seems to play back audio properly.

Revision history for this message
penalvch (penalvch) wrote :

Jon-o Addleman, the next step would be to identify the earliest mainline kernel that fixed the problem, so that the commit may be isolated and backported. Could you please do this following https://wiki.ubuntu.com/Kernel/KernelBisection#How_do_I_reverse_bisect_the_upstream_kernel.3F ?

tags: added: saucy
tags: added: kernel-fixed-upstream-v3.12-rc6
tags: removed: needs-upstream-testing
Changed in linux (Ubuntu):
status: Expired → Incomplete
tags: added: needs-reverse-bisect
Revision history for this message
Launchpad Janitor (janitor) wrote :

[Expired for linux (Ubuntu) because there has been no activity for 60 days.]

Changed in linux (Ubuntu):
status: Incomplete → Expired
Revision history for this message
Jon-o Addleman (jaddle) wrote :

I'm reopening this old bug report, because it's still not really fixed, even in the most recent kernels, and I've finally had a chance to do some more debugging. Here's what I found, after doing some git bisects and compiling a whole lot of kernels:

This is the first commit causing the problem: kernels after this point exhibit the "sequence errors" messages in the log.

commit c44b225077bb1fb25ed5cd5c4f226897b91bedd4
Author: Alan Stern <email address hidden>
Date: Mon Oct 1 10:32:09 2012 -0400

      UHCI: implement new semantics for URB_ISO_ASAP

Later on, more usb changes occured around commit bef073b067a7b1874a6b381e0035bb0516d71a77, right before v3.12-rc3 - after this point, the messages no longer appear in the log, but instead, there's a kernel panic when I use the Tascam - sometimes it works for a while, and sometimes it crashes right away. This is true for kernels well into this year (though I haven't tested the most recent ones, it doesn't look like this USB code has been touched since 2013).

I hope this is useful for further debugging - as things stand, I have to boot up a 3-year-old kernel to use the device!

Changed in linux (Ubuntu):
status: Expired → Confirmed
Revision history for this message
penalvch (penalvch) wrote :

Jon-o Addleman, any change on this with the latest mainline kernel (4.2-rc8)?

tags: added: bisect-done
Changed in linux (Ubuntu):
status: Confirmed → Incomplete
Revision history for this message
Jon-o Addleman (jaddle) wrote :
Download full text (11.4 KiB)

Hello,

Sorry I'm not able to test this more quickly - my free time has all but dried up these days!

But I've just tried the latest kernel, 4.3 rc4, and saw no change. Here's the trace from the kernel that shows up in the syslog when I plug the device in:

Oct 7 15:16:43 scaph kernel: [ 93.329270] usb 3-1.2: new full-speed USB device number 9 using ehci-pci
Oct 7 15:16:43 scaph kernel: [ 93.422645] usb 3-1.2: New USB device found, idVendor=1604, idProduct=8007
Oct 7 15:16:43 scaph kernel: [ 93.422652] usb 3-1.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Oct 7 15:16:43 scaph mtp-probe: checking bus 3, device 9: "/sys/devices/pci0000:00/0000:00:1a.0/usb3/3-1/3-1.2"
Oct 7 15:16:43 scaph mtp-probe: bus: 3, device: 9 was not an MTP device
Oct 7 15:16:43 scaph /lib/udev/tascam_fpga: calling /usr/bin/usx2yloader for
Oct 7 15:16:45 scaph /lib/udev/tascam_fpga: leaving
Oct 7 15:16:46 scaph systemd-udevd[2016]: Process '/usr/sbin/alsactl -E HOME=/var/run/alsa restore 1' failed with exit code 99.
Oct 7 15:16:46 scaph kernel: [ 96.656497] ------------[ cut here ]------------
Oct 7 15:16:46 scaph kernel: [ 96.656507] WARNING: CPU: 0 PID: 0 at include/linux/kref.h:47 usb_get_urb+0x40/0x50 [usbcore]()
Oct 7 15:16:46 scaph kernel: [ 96.656508] Modules linked in: snd_seq_midi snd_seq_midi_event snd_usb_usx2y snd_usbmidi_lib snd_rawmidi sha256_generic hmac drbg ansi_cprng ctr ccm binfmt_misc
 snd_hrtimer snd_seq snd_seq_device bnep joydev nfsd auth_rpcgss oid_registry nfs_acl nfs lockd grace fscache sunrpc snd_hda_codec_hdmi iTCO_wdt snd_hda_codec_realtek iTCO_vendor_support snd_hd
a_codec_generic intel_rapl iosf_mbi x86_pkg_temp_thermal intel_powerclamp coretemp kvm arc4 crc32_pclmul aesni_intel aes_i586 xts lrw gf128mul ablk_helper cryptd iwldvm evdev psmouse serio_raw
mac80211 i2c_i801 uvcvideo videobuf2_vmalloc videobuf2_memops iwlwifi videobuf2_core btusb v4l2_common btrtl btbcm btintel bluetooth videodev snd_hda_intel cfg80211 snd_hda_codec snd_hda_core m
edia snd_hwdep snd_pcm lpc_ich sg mfd_core snd_timer shpchp i915 thinkpad_acpi wmi nvram snd soundcore tpm_tis rfkill battery drm_kms_helper ac tpm drm i2c_algo_bit video button processor loop
parport_pc ppdev lp parport autofs4 ext4 crc16 mbcache jbd2 sd_mod crc32c_intel ahci libahci libata scsi_mod sdhci_pci sdhci mmc_core ehci_pci ehci_hcd xhci_pci e1000e xhci_hcd ptp pps_core usb
core usb_common thermal
Oct 7 15:16:46 scaph kernel: [ 96.656572] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.3.0-rc4+ #3
Oct 7 15:16:46 scaph kernel: [ 96.656573] Hardware name: LENOVO 23252UF/23252UF, BIOS G2ET85WW (2.05 ) 10/22/2012
Oct 7 15:16:46 scaph kernel: [ 96.656575] f82dbc1a f742fe24 c12949c5 00000000 c106367d c15e79a4 00000000 00000000
Oct 7 15:16:46 scaph kernel: [ 96.656578] f82dbc1a 0000002f f82c7300 00000009 f82c7300 f68b4300 f0de4400 00000020
Oct 7 15:16:46 scaph kernel: [ 96.656581] f742fe34 c1063752 00000009 00000000 f742fe40 f82c7300 f68b4300 f742ff00
Oct 7 15:16:46 scaph kernel: [ 96.656584] Call Trace:
Oct 7 15:16:46 scaph kernel: [ 96.656590] [<c12949c5>] ? dump_stack+0x3e/0x59
Oct 7 15:16:46 scaph kernel: [ 96.656593] [<c106367d...

Revision history for this message
Jon-o Addleman (jaddle) wrote :

And it appears I've spoken too soon again - I had the exact same crash happen (the 2nd stack track in the log above) using the 3.16 kernel that used to work well! That kernel didn't have the first trace though, so it does work somewhat better than newer ones.

penalvch (penalvch)
tags: added: kernel-bug-exists-upstream kernel-bug-exists-upstream-4.3-rc4
removed: kernel-bug-exists-upstream-v3.9 kernel-fixed-upstream-v3.12-rc6 needs-reverse-bisect
Revision history for this message
penalvch (penalvch) wrote :

Jon-o Addleman, the issue you are reporting is an upstream one. Could you please report this problem following the instructions verbatim at https://wiki.ubuntu.com/Bugs/Upstream/kernel to the appropriate venue (linux-usb CC Alan Stern)?

Please provide a direct URL to your newly made report when it becomes available so that it may be tracked.

Thank you for your understanding.

Changed in linux (Ubuntu):
status: Incomplete → Triaged
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.