Bug 222723 – sys-kernel/gentoo-sources-2.6.25-r3: soft lockup - CPU#0 stuck for 61s! (appears to be in USB core code)

• Product: Gentoo Linux
• Status: RESOLVED NEEDINFO

[Description Robert Clark 2008-05-18 20:51:44 UTC]

I have an intermittent lock up while using Wine to play World of 
Warcraft. I have not been able to determine a reliable way to 
reproduce this, but have noticed it since approximately the 2.6.23
kernel.

When the system locks up, it becomes completely unresponsive. Specifically:

   - UI frozen
   - no mouse control
   - no keyboard control (not event capslock LED)
   - no network access (will not respond to pigs from remote systems)

I have been able to get remote syslog to record the following before the 
system has to be be hard reset:

May 17 23:27:39 osirus BUG: soft lockup - CPU#0 stuck for 61s! [WoW.exe:11531]
May 17 23:27:39 osirus CPU 0:
May 17 23:27:39 osirus Modules linked in: snd_seq snd_pcm_oss snd_mixer_oss snd_usb_audio snd_usb_lib snd_rawmidi snd_seq_device snd_hwdep snd_hda_intel snd_pcm snd_timer snd snd_page_alloc nvidia(P) it87 hwmon_vid i2c_core soundcore k8temp [last unloaded: snd_page_alloc]
May 17 23:27:39 osirus Pid: 11531, comm: WoW.exe Tainted: P         2.6.25-gentoo-r3 #1
May 17 23:27:39 osirus RIP: 0010:[<ffffffff8050a304>]  [<ffffffff8050a304>] _spin_lock+0x12/0x15
May 17 23:27:39 osirus RSP: 0000:ffffffff8073eba0  EFLAGS: 00000297
May 17 23:27:39 osirus RAX: 000000000000a2a1 RBX: ffff81011e558558 RCX: ffff8100df808220
May 17 23:27:39 osirus RDX: 00000000ffffff8d RSI: ffff81011e591680 RDI: ffffffff807c293c
May 17 23:27:39 osirus RBP: ffffffff8073eb20 R08: 00000000ffffff8d R09: 0000000000000001
May 17 23:27:39 osirus R10: ffff8100df80e000 R11: ffff8100de849d00 R12: ffffffff8020ba96
May 17 23:27:39 osirus R13: ffffffff8073eb20 R14: ffff81011e591680 R15: ffffffff8073eb20
May 17 23:27:39 osirus FS:  000000007ffd8000(0063) GS:ffffffff806c2000(006b) knlGS:00000000f7d1c6c0
May 17 23:27:39 osirus CS:  0010 DS: 002b ES: 002b CR0: 0000000080050033
May 17 23:27:39 osirus CR2: 000000001a4ea008 CR3: 00000000df90c000 CR4: 00000000000006e0
May 17 23:27:39 osirus DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
May 17 23:27:39 osirus DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
May 17 23:27:39 osirus
May 17 23:27:39 osirus Call Trace:
May 17 23:27:39 osirus <IRQ>  [<ffffffff80448c60>] ? usb_hcd_unlink_urb_from_ep+0x10/0x33
May 17 23:27:39 osirus [<ffffffff80457c2a>] ? ehci_urb_done+0x67/0x88
May 17 23:27:39 osirus [<ffffffff80458728>] ? qh_completions+0x2de/0x361
May 17 23:27:39 osirus [<ffffffff80458a21>] ? ehci_work+0x276/0x700
May 17 23:27:39 osirus [<ffffffff8045b771>] ? ehci_irq+0x14f/0x176
May 17 23:27:39 osirus [<ffffffff8044924a>] ? usb_hcd_irq+0x23/0x51
May 17 23:27:39 osirus [<ffffffff8024f130>] ? handle_IRQ_event+0x25/0x53
May 17 23:27:39 osirus [<ffffffff80250793>] ? handle_level_irq+0x93/0xe0
May 17 23:27:39 osirus [<ffffffff8020d8ee>] ? do_IRQ+0xf1/0x15f
May 17 23:27:39 osirus [<ffffffff8020b371>] ? ret_from_intr+0x0/0xa
May 17 23:27:39 osirus [<ffffffff8050a2fc>] ? _spin_lock+0xa/0x15
May 17 23:27:39 osirus [<ffffffff80448c60>] ? usb_hcd_unlink_urb_from_ep+0x10/0x33
May 17 23:27:39 osirus [<ffffffff8045c7c7>] ? finish_urb+0x58/0xa1
May 17 23:27:39 osirus [<ffffffff8045c852>] ? takeback_td+0x42/0xb1
May 17 23:27:39 osirus [<ffffffff802238c9>] ? enqueue_task+0x13/0x1e
May 17 23:27:39 osirus [<ffffffff8045c9ec>] ? dl_done_list+0x12b/0x13b
May 17 23:27:39 osirus [<ffffffff8045ed24>] ? ohci_irq+0x164/0x27c
May 17 23:27:39 osirus [<ffffffff8044924a>] ? usb_hcd_irq+0x23/0x51
May 17 23:27:39 osirus [<ffffffff8024f130>] ? handle_IRQ_event+0x25/0x53
May 17 23:27:39 osirus [<ffffffff80250793>] ? handle_level_irq+0x93/0xe0
May 17 23:27:39 osirus [<ffffffff8020d8ee>] ? do_IRQ+0xf1/0x15f
May 17 23:27:39 osirus [<ffffffff8020b371>] ? ret_from_intr+0x0/0xa
May 17 23:27:39 osirus <EOI>

Reproducible: Sometimes

Steps to Reproduce:
I have not found a reliable method to reproduce this yet.



Portage 2.1.4.4 (default-linux/amd64/2007.0, gcc-4.1.2, glibc-2.6.1-r0, 2.6.25-gentoo-r3 x86_64)
=================================================================
System uname: 2.6.25-gentoo-r3 x86_64 AMD Athlon(tm) 64 X2 Dual Core Processor 6000+
Timestamp of tree: Sat, 17 May 2008 11:45:01 +0000
app-shells/bash:     3.2_p33
dev-java/java-config: 1.3.7, 2.1.6
dev-lang/python:     2.4.4-r6, 2.5.2-r3
dev-python/pycrypto: 2.0.1-r6
sys-apps/baselayout: 1.12.11.1
sys-apps/sandbox:    1.2.18.1-r2
sys-devel/autoconf:  2.13, 2.61-r1
sys-devel/automake:  1.6.3, 1.7.9-r1, 1.8.5-r3, 1.9.6-r2, 1.10.1
sys-devel/binutils:  2.18-r1
sys-devel/gcc-config: 1.4.0-r4
sys-devel/libtool:   1.5.26
virtual/os-headers:  2.6.23-r3
ACCEPT_KEYWORDS="amd64"
CBUILD="x86_64-pc-linux-gnu"
CFLAGS="-march=k8 -O2 -pipe"
CHOST="x86_64-pc-linux-gnu"
CONFIG_PROTECT="/etc /usr/kde/3.5/env /usr/kde/3.5/share/config /usr/kde/3.5/shutdown /usr/kde/4.0/env /usr/kde/4.0/share/config /usr/kde/4.0/shutdown /usr/share/config"
CONFIG_PROTECT_MASK="/etc/env.d /etc/env.d/java/ /etc/fonts/fonts.conf /etc/gconf /etc/revdep-rebuild /etc/terminfo /etc/udev/rules.d"
CXXFLAGS="-march=k8 -O2 -pipe"
DISTDIR="/usr/portage/distfiles"
FEATURES="collision-protect distlocks metadata-transfer parallel-fetch sandbox sfperms strict unmerge-orphans userfetch"
GENTOO_MIRRORS="ftp://gentoo.arcticnetwork.ca/pub/gentoo/ http://gentoo.arcticnetwork.ca/ ftp://mirrors.tera-byte.com/pub/gentoo http://gentoo.mirrors.tera-byte.com/ "
LANG="en_US.UTF-8"
LC_ALL="en_US.UTF-8"
LDFLAGS="-Wl,-O1 -Wl,--enable-new-dtags -Wl,--sort-common -s"
MAKEOPTS="-j3"
PKGDIR="/usr/portage/packages"
PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --compress --force --whole-file --delete --stats --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages"
PORTAGE_TMPDIR="/var/tmp"
PORTDIR="/usr/portage"
PORTDIR_OVERLAY="/usr/local/portage"
SYNC="rsync://rsync.namerica.gentoo.org/gentoo-portage"
USE="3dnow 3dnowext X a52 aac acl acpi alsa amd64 arts bacula-clientonly bash-completion berkdb bzip2 cdda cddb cdparanoia cdr cdrom cli connectionstatus cracklib crypt css cups curl dbus dga dnotify dri dvd dvdr dvdread ecc encode exif fam fame fortran gdbm gif gimp gimpprint glut gmail gpgme gpm gs hal highlight history iconv imap ipod ipv6 irc isdnlog jpeg jpeg2k kde kdepim lm_sensors logrotate mad md5sum midi mjpeg mmx mod mp3 mp4 mpeg mplayer mudflap ncurses nfs nis nls nowlistening nptl nptlonly ntfs nvidia offensive ogg opengl openmp openssh openssl pam pcre pdf perl png posix pppd python qt3support qt4 quicktime readline reflection rtc samba sasl sdl session smime spell spl sse sse2 ssl subtitles svg tcpd threads tiff transcode truetype type1 unicode usb vcd vim vlm vorbis xanim xcomposite xinerama xorg xosd xpm xrandr xscreensaver xvid xvmc zlib"ALSA_CARDS="hda-intel" ALSA_PCM_PLUGINS="adpcm alaw asym copy dmix dshare dsnoop empty extplug file hooks iec958 ioplug ladspa lfloat linear meter mulaw multi null plug rate route share shm softvol" APACHE2_MODULES="actions alias auth_basic authn_alias authn_anon authn_dbm authn_default authn_file authz_dbm authz_default authz_groupfile authz_host authz_owner authz_user autoindex cache dav dav_fs dav_lock deflate dir disk_cache env expires ext_filter file_cache filter headers include info log_config logio mem_cache mime mime_magic negotiation rewrite setenvif speling status unique_id userdir usertrack vhost_alias" ELIBC="glibc" INPUT_DEVICES="keyboard mouse evdev" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" USERLAND="GNU" VIDEO_CARDS="nv nvidia vesa"
Unset:  CPPFLAGS, CTARGET, EMERGE_DEFAULT_OPTS, INSTALL_MASK, LINGUAS, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, PORTAGE_RSYNC_EXTRA_OPTS

[Comment 1 Robert Clark 2008-05-18 20:52:39 UTC]

Created attachment 153613 [details]
Output from lspci -vvv

[Comment 2 Robert Clark 2008-05-18 20:53:45 UTC]

Created attachment 153615 [details]
Contents of /proc/cpuinfo

[Comment 3 Robert Clark 2008-05-18 20:54:47 UTC]

Created attachment 153617 [details]
Output of /usr/scr/linux/scripts/ver_linux

[Comment 4 Mike Pagano 2008-05-30 14:11:08 UTC]

Is this reproducible in any way without a tainted kernel (nvidia)?

I see you're playng WoW via wine so I'm not sure if this is possible as I don't play games.

[Comment 5 Robert Clark 2008-06-01 23:50:27 UTC]

(In reply to comment #4)
> Is this reproducible in any way without a tainted kernel (nvidia)?

Unfortunately no, I need to have nvidia in there to get X up and running. I believe (am guesing) the problem is related to the USB HID devices I'm using (mouse, headset) and I have only ever been able to reproduce this with WoW running.

[Comment 6 Mike Pagano 2008-07-21 17:35:39 UTC]

Have you tried to reproduce without the USB mouse or headset plugged in?

[Comment 7 Robert Clark 2008-07-21 19:28:43 UTC]

Since I've had real problem reproducing this reliably, I wanted to wait for a fair bit whenever I made a change trying to track this done.

I'm fairly confident that this is only reproducible with USB HID devices plugged into a USB HUB. When I plug the mouse/keyboard/headset/USB coffee warmer/whatever directly into the computer, the problem does not occur.

Given that there is a reasonable workaround for this, I'd suggest that the PR be given a lower priority than other kernel bugs. 

[Comment 8 Mike Pagano 2008-08-04 14:32:57 UTC]

Any updates on this bug?

[Comment 9 Mike Pagano 2008-08-25 12:44:09 UTC]

Feel free to reopen if still an issue.