[arch-general] kernel 3.3 & khubd problem/freezing failed

Tue Mar 20 20:08:43 EDT 2012

  linux 3.3 from testing - fully updated from test repo.

 I had plugged my cell phone in to usb - a few times in and out. After
unplugging, I closed laptop lid - the sleep light which normally flashes
then goes steady to indicate laptop is sleeping did not. I opened lid -
and found khubd process at 100% cpu.

  In earlier kernels there were some problems with usb if I attempted to
sleep a laptop with a USB device plugged in - but i thought there were
some fixes to this problem.

  Anyway - this is what got recorded in system log:

Where is the preferred place to report this?

 thanks!

gene./

=========== system log for khubd problem ============================

Mar 20 18:08:55 lap3 kernel: [75845.892579] EXT4-fs (dm-0): re-mounted.
Opts: commit=0
Mar 20 18:08:57 lap3 kernel: [75848.292547] PM: Syncing filesystems ...
done.
Mar 20 18:08:57 lap3 kernel: [75848.298398] PM: Preparing system for mem
sleep
Mar 20 18:09:19 lap3 kernel: [75849.834400] Freezing user space
processes ... (elapsed 0.01 seconds) done.
Mar 20 18:09:19 lap3 kernel: [75849.847519] Freezing remaining freezable
tasks ...
Mar 20 18:09:19 lap3 kernel: [75869.819451] Freezing of tasks failed
after 20.01 seconds (1 tasks refusing to freeze, wq_busy=0
):
Mar 20 18:09:19 lap3 kernel: [75869.819457] khubd           R  running
task        0    91      2 0x00000000
Mar 20 18:09:19 lap3 kernel: [75869.819459]  ffff88030a4bda48
ffffffff8146ed06 ffffffff812f5dcc ffffffff816dba22
Mar 20 18:09:19 lap3 kernel: [75869.819462]  ffff88030a420800
ffff88030a4bdfd8 ffff88030a4bdfd8 ffff88030a4bdfd8
Mar 20 18:09:19 lap3 kernel: [75869.819465]  ffff880306188000
ffff88030a420800 ffffffff8189e150 0000000000000030
Mar 20 18:09:19 lap3 kernel: [75869.819467] Call Trace:
Mar 20 18:09:19 lap3 kernel: [75869.819473]  [<ffffffff8146ed06>] ?
__schedule+0x436/0x910
Mar 20 18:09:19 lap3 kernel: [75869.819476]  [<ffffffff812f5dcc>] ?
vt_console_print+0x2cc/0x3b0
Mar 20 18:09:19 lap3 kernel: [75869.819479]  [<ffffffff81050e77>] ?
__call_console_drivers+0x97/0xb0
Mar 20 18:09:19 lap3 kernel: [75869.819481]  [<ffffffff81079482>] ?
up+0x32/0x50
Mar 20 18:09:19 lap3 kernel: [75869.819483]  [<ffffffff81051159>] ?
_call_console_drivers+0x49/0x70
Mar 20 18:09:19 lap3 kernel: [75869.819485]  [<ffffffff8146f602>]
preempt_schedule_irq+0x52/0x80
Mar 20 18:09:19 lap3 kernel: [75869.819488]  [<ffffffff81470866>]
retint_kernel+0x26/0x30
Mar 20 18:09:19 lap3 kernel: [75869.819491]  [<ffffffff81060924>] ?
lock_timer_base.isra.32+0x64/0x70
Mar 20 18:09:19 lap3 kernel: [75869.819493]  [<ffffffff81060b32>]
del_timer+0x42/0x150
Mar 20 18:09:19 lap3 kernel: [75869.819501]  [<ffffffffa0685e25>] ?
bt_printk+0x55/0x60 [bluetooth]
Mar 20 18:09:19 lap3 kernel: [75869.819505]  [<ffffffffa066afc6>]
hci_conn_del+0x26/0x230 [bluetooth]
Mar 20 18:09:19 lap3 kernel: [75869.819508]  [<ffffffffa066b240>]
hci_conn_hash_flush+0x70/0xc0 [bluetooth]
Mar 20 18:09:19 lap3 kernel: [75869.819511]  [<ffffffffa0667121>]
hci_dev_do_close+0xa1/0x2c0 [bluetooth]
Mar 20 18:09:19 lap3 kernel: [75869.819515]  [<ffffffffa066922b>]
hci_unregister_dev+0x5b/0x170 [bluetooth]
Mar 20 18:09:19 lap3 kernel: [75869.819517]  [<ffffffffa03c1c80>]
btusb_disconnect+0x60/0xd0 [btusb]
Mar 20 18:09:19 lap3 kernel: [75869.819529]  [<ffffffffa00111e2>]
usb_unbind_interface+0x52/0x180 [usbcore]
Mar 20 18:09:19 lap3 kernel: [75869.819533]  [<ffffffff81313e2c>]
__device_release_driver+0x7c/0xe0
Mar 20 18:09:19 lap3 kernel: [75869.819535]  [<ffffffff81313ebc>]
device_release_driver+0x2c/0x40
Mar 20 18:09:19 lap3 kernel: [75869.819536]  [<ffffffff81313961>]
bus_remove_device+0xe1/0x120
Mar 20 18:09:19 lap3 kernel: [75869.819539]  [<ffffffff81311510>]
device_del+0x120/0x1a0
Mar 20 18:09:19 lap3 kernel: [75869.819544]  [<ffffffffa000ef6f>]
usb_disable_device+0xaf/0x1d0 [usbcore]
Mar 20 18:09:19 lap3 kernel: [75869.819548]  [<ffffffffa00073f8>]
usb_disconnect+0x98/0x130 [usbcore]
Mar 20 18:09:19 lap3 kernel: [75869.819553]  [<ffffffffa0009296>]
hub_thread+0xa56/0x12e0 [usbcore]
Mar 20 18:09:19 lap3 kernel: [75869.819557]  [<ffffffff81073d00>] ?
abort_exclusive_wait+0xb0/0xb0
Mar 20 18:09:19 lap3 kernel: [75869.819561]  [<ffffffffa0008840>] ?
usb_remote_wakeup+0x40/0x40 [usbcore]
Mar 20 18:09:19 lap3 kernel: [75869.819563]  [<ffffffff810733c3>]
kthread+0x93/0xa0
Mar 20 18:09:19 lap3 kernel: [75869.819566]  [<ffffffff81472464>]
kernel_thread_helper+0x4/0x10
Mar 20 18:09:19 lap3 kernel: [75869.819568]  [<ffffffff81073330>] ?
kthread_freezable_should_stop+0x70/0x70
Mar 20 18:09:19 lap3 kernel: [75869.819570]  [<ffffffff81472460>] ?
gs_change+0x13/0x13
Mar 20 18:09:19 lap3 kernel: [75869.819581]
Mar 20 18:09:19 lap3 kernel: [75869.819581] Restarting kernel threads
... done.
Mar 20 18:09:19 lap3 kernel: [75869.819641] Restarting tasks ... done.
Mar 20 18:09:19 lap3 kernel: [75869.829806] sdhci-pci 0000:0d:00.0:
could not set regulator OCR (-22)
Mar 20 18:09:19 lap3 kernel: [75869.829857] video LNXVIDEO:00: Restoring
backlight state
Mar 20 18:09:19 lap3 kernel: [75869.865629] sdhci-pci 0000:0d:00.0:
could not set regulator OCR (-22)
Mar 20 18:09:19 lap3 kernel: [75869.899151] sdhci-pci 0000:0d:00.0:
could not set regulator OCR (-22)
Mar 20 18:09:19 lap3 kernel: [75869.932325] sdhci-pci 0000:0d:00.0:
could not set regulator OCR (-22)
Mar 20 18:09:20 lap3 kernel: [75870.954441] EXT4-fs (sdb1): re-mounted.
Opts: commit=0
Mar 20 18:09:20 lap3 kernel: [75870.956487] EXT4-fs (sda6): re-mounted.
Opts: commit=0
Mar 20 18:09:20 lap3 kernel: [75870.958144] EXT4-fs (sdb2): re-mounted.
Opts: commit=0
Mar 20 18:09:20 lap3 kernel: [75871.182979] EXT4-fs (dm-0): re-mounted.
Opts: commit=0
Mar 20 18:09:20 lap3 NetworkManager[955]: <info> wake requested
(sleeping: yes  enabled: yes)
Mar 20 18:09:20 lap3 NetworkManager[955]: <info> waking up and
re-enabling...
Mar 20 18:09:20 lap3 NetworkManager[955]: <info> (eth0): now managed
Mar 20 18:09:20 lap3 NetworkManager[955]: <info> (eth0): device state
change: unmanaged -> unavailable (reason 'managed') [10 2
0 2]