Bug 5325 - Processes in D state after printing big jobs
Summary: Processes in D state after printing big jobs
Status: REJECTED INSUFFICIENT_DATA
Alias: None
Product: Drivers
Classification: Unclassified
Component: USB (show other bugs)
Hardware: i386 Linux
: P2 normal
Assignee: Greg Kroah-Hartman
URL:
Keywords:
Depends on: 6374
Blocks: USB
  Show dependency tree
 
Reported: 2005-09-29 03:50 UTC by Alexey Dobriyan
Modified: 2008-09-24 09:54 UTC (History)
6 users (show)

See Also:
Kernel Version: 2.6.13.2
Subsystem:
Regression: ---
Bisected commit-id:


Attachments

Description Alexey Dobriyan 2005-09-29 03:50:07 UTC
From: Olaf Titz <olaf@bigred.inka.de>
http://marc.theaimsgroup.com/?t=112784591300001&r=1&w=2

Sometimes when trying to print big jobs on a HP LJ 1022 it happens that all
processes accessing USB get stuck in D state. I captured the following relevant
stack traces, but apparently this does not include kernel threads (there was
another "khubd" stuck):

usb           D C04760C0     0 10777      1         12440 10778 (NOTLB)
c4057d90 00000086 c66c4100 c04760c0 cee8c3c0 cfbdd180 00000296 c1315cc8
       c4057da8 00000296 c1315cc8 0000071b d901b00a 00000479 c66c4100 c66c4228
       c4056000 c4057e2c c4057dac c4057de4 c02c2dce 00000000 c66c4100 c0117590
Call Trace:
 [wait_for_completion+110/160] wait_for_completion+0x6e/0xa0
 [default_wake_function+0/32] default_wake_function+0x0/0x20
 [default_wake_function+0/32] default_wake_function+0x0/0x20
 [pg0+273911419/1068921856] usb_start_wait_urb+0x9b/0x170 [usbcore]
 [pg0+273911248/1068921856] timeout_kill+0x0/0x10 [usbcore]
 [pg0+273911739/1068921856] usb_internal_control_msg+0x6b/0x80 [usbcore]
 [pg0+273911913/1068921856] usb_control_msg+0x99/0xc0 [usbcore]
 [pg0+273719458/1068921856] usblp_ctrl_msg+0xa2/0xd0 [usblp]
 [pg0+273719834/1068921856] usblp_check_status+0x4a/0xe0 [usblp]
 [pg0+273722129/1068921856] usblp_write+0x251/0x290 [usblp]
 [pipe_readv+678/832] pipe_readv+0x2a6/0x340
 [default_wake_function+0/32] default_wake_function+0x0/0x20
 [default_wake_function+0/32] default_wake_function+0x0/0x20
 [pipe_read+55/64] pipe_read+0x37/0x40
 [vfs_write+428/448] vfs_write+0x1ac/0x1c0
 [sys_write+81/128] sys_write+0x51/0x80
 [syscall_call+7/11] syscall_call+0x7/0xb

epson         D C04760C0     0 12453  12440                     (NOTLB)
ce803ee0 00000082 ccd3fa20 c04760c0 c71a9b7c b7e21140 cee76280 00000000
       c0147d80 cee76280 bfd3c8e8 00002b1c 20ab4836 000007c2 ccd3fa20 ccd3fb48
       cf54e024 ccd3fa20 00000246 cf54e02c c02c2601 00000001 ccd3fa20 c0117590
Call Trace:
 [__handle_mm_fault+368/448] __handle_mm_fault+0x170/0x1c0
 [__down+97/192] __down+0x61/0xc0
 [default_wake_function+0/32] default_wake_function+0x0/0x20
 [__down_failed+7/12] __down_failed+0x7/0xc
 [pg0+273883692/1068921856] .text.lock.usb+0x16/0xba [usbcore]
 [pg0+273949406/1068921856] usb_device_read+0x9e/0x120 [usbcore]
 [vfs_read+428/448] vfs_read+0x1ac/0x1c0
 [sys_read+81/128] sys_read+0x51/0x80
 [syscall_call+7/11] syscall_call+0x7/0xb

This _may_ have something to do with a flaky USB 2.0 hub/multi-card reader
combo (which sometimes disconnects, then immediately re-connects) on the same
USB 2.0 root hub as the printer. Unfortunately I don't see in the log whether
such an event has occurred at the time the print job started.
===============================================================================
From: Alan Cox <alan@lxorguk.ukuu.org.uk>

I've had a couple of almost identical traces here. In my case I also have a hub
which comes and goes as it is part of the monitor and when the monitor goes
into deep sleep it turns off [*].

[*] and yes if you plug your keyboard and mouse into the hub you realise
the designer wasn't thinking.
Comment 1 Alan Stern 2005-09-30 19:26:28 UTC
Unfortunately the khubd process is the important one here.  It's probably hung
somewhere in the printer driver's disconnect routine.  If you can get another
stack trace that includes it, please post it here.

It also wouldn't hurt if you turned on USB verbose debugging in the kernel
configuration (CONFIG_USB_DEBUG) and included the dmesg log.
Comment 2 Greg Kroah-Hartman 2005-11-14 21:43:00 UTC
Does this work properly now with 2.6.14?
Comment 3 Olaf Titz 2005-11-19 12:39:10 UTC
Still the same error in 2.6.14.2.
There is a khubd involved, but I can't get SysRQ to show its trace (is there any
other means to do this?)
Comment 4 Alan Stern 2005-11-19 18:04:09 UTC
Probably the stack trace is overflowing your kernel's log buffer.  You can
increase the size of the buffer by changing the Kernal hacking -> Kernel log
buffer size entry in the configuration.
Comment 5 Greg Kroah-Hartman 2006-03-06 10:26:55 UTC
Still an issue in 2.6.16-rc5?
Comment 6 Olaf Titz 2006-06-24 03:13:17 UTC
I can easily reproduce this (or something similar) still in 2.6.17.1.
Simply connect a highspeed storage device to the flaky hub and copy lots of
stuff to it. After some time, the hub disconnects and the whole USB subsystem is
blocked.
(Looks like this is not the printer but transfer of huge amounts of data which
is causing the problem.)

These are the last log entries when this happens:
Jun 24 11:45:45 bigred kernel: hub 4-0:1.0: state 7 ports 6 chg 0000 evt 0028
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: GetStatus port 3 status
00180b POWER sig=j PEC CSC CONNECT
Jun 24 11:45:45 bigred kernel: hub 4-0:1.0: port 3, status 0501, change 0003,
480 Mb/s
Jun 24 11:45:45 bigred kernel: usb 4-3: USB disconnect, address 2
Jun 24 11:45:45 bigred kernel: usb 4-3.3: USB disconnect, address 8
Jun 24 11:45:45 bigred kernel: usb 4-3.3: usb_disable_device nuking all URBs
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c7b7b840 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c7b7b540 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c7b7b1c0 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c0fd3ee0 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c0fd36e0 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c0fd39e0 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c0fd32e0 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c0fd3160 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c0fd3860 pipe
c0010800 ep2out-bulk
Jun 24 11:45:45 bigred kernel: ehci_hcd 0000:00:10.3: shutdown urb c0fd3c60 pipe
c0010800 ep2out-bulk
Jun 24 11:46:14 bigred kernel: usb 4-3.3: usb_sg_cancel, unlink --> -19
Jun 24 11:46:14 bigred last message repeated 9 times


And here are stack traces of all relevant processes, hope I've included all
kernel threads:

00 8de8da00 000f4406 00000001 
       cffc0138 cffc0030 51972b00 000f4425 00000000 00000000 00000000 cffc6000 
       00000000 00000000 cffc6000 cffc6000 cffc1f48 00000000 c0134eaa cffc0030 
Call Trace:
 <c0134eaa> watchdog+0x4a/0x60  <c0134eaa> watchdog+0x4a/0x60
 <c012bf2b> kthread+0xbb/0xf0  <c0134e60> watchdog+0x0/0x60
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10
events/0      R running     0     4      1             5     3 (L-TLB)


khelper       S 00000286     0     5      1             6     4 (L-TLB)
       cffcdf48 00000002 c01292c2 00000286 cffcdf1c 00000086 00000000 00000007 
       cffcb648 cffcb540 9fa77ac0 000f43fd 00000000 00000000 00000000 cffcd000 
       00989680 00000000 cffcd000 c12387e8 c12387f0 cffcdf90 c01292c2 00000011 
Call Trace:
 <c01292c2> worker_thread+0x152/0x190  <c01292c2> worker_thread+0x152/0x190
 <c0116af0> default_wake_function+0x0/0x20  <c012bf2b> kthread+0xbb/0xf0
 <c0129170> worker_thread+0x0/0x190  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


kthread       S 00000286     0     6      1     8     119     5 (L-TLB)
       cff85f48 00000002 c01292c2 00000286 cff85f1c 00000086 00000000 0000000a 
       cffcb158 cffcb050 cd1da7c0 000f4413 00000000 cff85000 00000000 cff85000 
       00000000 00000000 cff85000 c1238828 c1238830 cff85f90 c01292c2 00000011 
Call Trace:
 <c01292c2> worker_thread+0x152/0x190  <c01292c2> worker_thread+0x152/0x190
 <c0116af0> default_wake_function+0x0/0x20  <c012bf2b> kthread+0xbb/0xf0
 <c0129170> worker_thread+0x0/0x190  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


kblockd/0     S 00000292     0     8      6             9       (L-TLB)
       cff99f48 00000002 c01292c2 00000292 cff99f1c 00000086 00000000 0000000a 
       cff8b668 cff8b560 a20e08c0 000f4424 00000000 00000000 00000000 cff99000 
       638a2d80 00000000 cff99000 c1238b68 c1238b70 cff99f90 c01292c2 00000011 
Call Trace:
 <c01292c2> worker_thread+0x152/0x190  <c01292c2> worker_thread+0x152/0x190
 <c0116af0> default_wake_function+0x0/0x20  <c012bf2b> kthread+0xbb/0xf0
 <c0129170> worker_thread+0x0/0x190  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


kacpid        S FFFFFFFF     0     9      6            87     8 (L-TLB)
       cff9bf48 00000002 c01292c2 ffffffff f7ffdfff 57c7c840 000f41fa 00000001 
       cff8b178 cff8b070 57c7c840 000f41fa 00000000 00000000 00000000 cff9b000 
       00000000 00000000 cff9b000 c1238c68 c1238c70 cff9bf90 c01292c2 00000011 
Call Trace:
 <c01292c2> worker_thread+0x152/0x190  <c01292c2> worker_thread+0x152/0x190
 <c0116af0> default_wake_function+0x0/0x20  <c012bf2b> kthread+0xbb/0xf0
 <c0129170> worker_thread+0x0/0x190  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


kseriod       S C036E6C8     0    87      6           117     9 (L-TLB)
       c1253f68 00000002 c02622e5 c036e6c8 00000000 c01cb2ae c036e6f4 0000000a 
       c1254bf8 c1254af0 03cca5c0 000f41fb 00000000 c024ac1e c036e6c8 c1253000 
       00e4e1c0 00000000 c1253000 c1253f98 c1257e00 00000286 c02622e5 c036e6c8 
Call Trace:
 <c02622e5> serio_thread+0xe5/0x29d  <c01cb2ae> kobject_put+0x1e/0x30
 <c024ac1e> bus_add_driver+0x16e/0x180  <c02622e5> serio_thread+0xe5/0x29d
 <c012c2e0> autoremove_wake_function+0x0/0x60  <c012bf2b> kthread+0xbb/0xf0
 <c0262200> serio_thread+0x0/0x29d  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


pdflush       D CFF09F40     0   117      6           118    87 (L-TLB)
       cff09eb0 00000002 c02d247c cff09f40 00000000 c013c53c 00000003 0000000a 
       c1260648 c1260540 54de66c0 000f4425 00000000 cff09000 cff09ed8 cff09000 
       00a7d8c0 00000000 cff09ec4 001fcee7 c0363fe4 cff09ee8 c02d247c cff09ec4 
Call Trace:
 <c02d247c> schedule_timeout+0x4c/0xc0  <c013c53c> do_writepages+0x2c/0x50
 <c02d247c> schedule_timeout+0x4c/0xc0  <c0122890> process_timeout+0x0/0x10
 <c02d1e11> io_schedule_timeout+0x11/0x20  <c01c07d9> blk_congestion_wait+0xa9/0xd0
 <c012c2e0> autoremove_wake_function+0x0/0x60  <c013c89a>
background_writeout+0x3a/0xb0
 <c013d0f5> pdflush+0xb5/0x190  <c013c860> background_writeout+0x0/0xb0
 <c012bf2b> kthread+0xbb/0xf0  <c013d040> pdflush+0x0/0x190
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10


pdflush       D CFF0AF40     0   118      6           120   117 (L-TLB)
       cff0aeb0 00000002 c02d247c cff0af40 00000000 c013c53c 00000003 0000000a 
       c1260158 c1260050 54de66c0 000f4425 00000000 00000000 00000000 cff0a000 
       03ef1480 00000000 cff0aec4 001fcee7 c0363fe4 cff0aee8 c02d247c cff0aec4 
Call Trace:
 <c02d247c> schedule_timeout+0x4c/0xc0  <c013c53c> do_writepages+0x2c/0x50
 <c02d247c> schedule_timeout+0x4c/0xc0  <c0122890> process_timeout+0x0/0x10
 <c02d1e11> io_schedule_timeout+0x11/0x20  <c01c07d9> blk_congestion_wait+0xa9/0xd0
 <c012c2e0> autoremove_wake_function+0x0/0x60  <c013c89a>
background_writeout+0x3a/0xb0
 <c013d0f5> pdflush+0xb5/0x190  <c013c860> background_writeout+0x0/0xb0
 <c012bf2b> kthread+0xbb/0xf0  <c013d040> pdflush+0x0/0x190
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10


kswapd0       D CAFF0A50     0   119      1           267     6 (L-TLB)
       cff0dc24 00000002 c02d1e2e caff0a50 00000001 cef3605c c01c52d6 0000000a 
       cff0cb58 cff0ca50 b3301500 000f4413 00000000 00000001 cd559ca0 cff0d000 
       da54f1c0 00000000 00000000 cef3604c 00000001 cff0dc2c c02d1e2e cef3607c 
Call Trace:
 <c02d1e2e> io_schedule+0xe/0x20  <c01c52d6> as_set_request+0x26/0x80
 <c02d1e2e> io_schedule+0xe/0x20  <c01bfa87> get_request_wait+0x107/0x130
 <c012c2e0> autoremove_wake_function+0x0/0x60  <c01c0b77> __make_request+0x87/0x3d0
 <c02d1a1a> schedule+0x2aa/0x5c0  <c01be867> generic_make_request+0x137/0x1b0
 <c0139904> mempool_alloc+0x44/0xf0  <c01c085a> submit_bio+0x5a/0x100
 <c01589a8> bio_alloc_bioset+0x98/0x180  <c0158ab0> bio_alloc+0x20/0x30
 <c0155372> submit_bh+0xd2/0x130  <c0156d41> __block_write_full_page+0x1a1/0x340
 <d0b75820> fat_get_block+0x0/0x30 [fat]  <d0b7597f> fat_writepage+0x1f/0x30 [fat]
 <d0b75820> fat_get_block+0x0/0x30 [fat]  <c013ee65> pageout+0xb5/0x1e0
 <c013f921> shrink_zone+0x991/0xc20  <c02d2484> schedule_timeout+0x54/0xc0
 <c013fe04> balance_pgdat+0x254/0x420  <c014013c> kswapd+0xfc/0x120
 <c0102f52> work_resched+0x5/0x16  <c0140040> kswapd+0x0/0x120
 <c012c2e0> autoremove_wake_function+0x0/0x60  <c0140040> kswapd+0x0/0x120
 <c0101005> kernel_thread_helper+0x5/0x10 


aio/0         S 00000004     0   120      6           212   118 (L-TLB)
       cff10f48 00000002 c01292c2 00000004 00000001 5affc1c0 000f41fa 00000001 
       cff0c668 cff0c560 5affc1c0 000f41fa 00000000 00000000 00000000 cff10000 
       00000000 00000000 cff10000 cff01aa8 cff01ab0 cff10f90 c01292c2 00000011 
Call Trace:
 <c01292c2> worker_thread+0x152/0x190  <c01292c2> worker_thread+0x152/0x190
 <c0116af0> default_wake_function+0x0/0x20  <c012bf2b> kthread+0xbb/0xf0
 <c0129170> worker_thread+0x0/0x190  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


kpsmoused     S 000F41FA     0   212      6           217   120 (L-TLB)
       cff6ff48 00000002 c01292c2 000f41fa 00000011 00000000 00040001 00000009 
       cff73b38 cff73a30 d9996cc0 000f41fa 00000000 cff6f000 00000000 cff6f000 
       00000000 00000000 cff6f000 cffb5e28 cffb5e30 cff6ff90 c01292c2 00000011 
Call Trace:
 <c01292c2> worker_thread+0x152/0x190  <c01292c2> worker_thread+0x152/0x190
 <c0116af0> default_wake_function+0x0/0x20  <c012bf2b> kthread+0xbb/0xf0
 <c0129170> worker_thread+0x0/0x190  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


kedac         S CFF6D520     0   217      6           220   212 (L-TLB)
       cff38f68 00000002 c02d247c cff6d520 d9996cc0 4291be00 000f4406 0000000a 
       cff6d628 cff6d520 39fcb000 000f4425 00000000 00000000 00000000 cff38000 
       00000000 00000000 cff38f7c 001fd0a8 cffc1f54 00000000 c02d247c cff38f7c 
Call Trace:
 <c02d247c> schedule_timeout+0x4c/0xc0  <c02d247c> schedule_timeout+0x4c/0xc0
 <c0122890> process_timeout+0x0/0x10  <c026f69b> edac_kernel_thread+0xfb/0x140
 <c012bf2b> kthread+0xbb/0xf0  <c026f5a0> edac_kernel_thread+0x0/0x140
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10


kjournald     S C125FDE0     0   220      6          1203   217 (L-TLB)
       cff70f60 00000002 c01a905b c125fde0 00000000 c0116153 cffc1c7c 0000000a 
       cff73648 cff73540 0b2291c0 000f4405 00000000 00000286 c125fe64 cff70000 
       002dc6c0 00000000 00000000 c125fda0 c125fe64 c125fde0 c01a905b 00000000 
Call Trace:
 <c01a905b> kjournald+0x1eb/0x200  <c0116153> __wake_up_common+0x43/0x70
 <c01a905b> kjournald+0x1eb/0x200  <c012c2e0> autoremove_wake_function+0x0/0x60
 <c012bf2b> kthread+0xbb/0xf0  <c01a8e70> kjournald+0x0/0x200
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10


udevd         S 080E4100     0   267      1          2795   119 (NOTLB)
       cf470b40 00000002 c02d24a5 080e4100 00000046 5c5ee100 000f43c5 00000006 
       cfaca708 cfaca600 a663b900 000f43fd 00000000 00895440 00000000 cf470000 
       07459280 00000000 00000006 00000000 00000000 00000000 c02d24a5 c027ae63 
Call Trace:
 <c02d24a5> schedule_timeout+0x75/0xc0  <c02d24a5> schedule_timeout+0x75/0xc0
 <c027ae63> datagram_poll+0x103/0x110  <c0272870> sock_poll+0x20/0x30
 <c0166cf7> do_select+0x3c7/0x4b0  <c01164fa> __activate_task+0x1a/0x20
 <c0167430> __pollwait+0x0/0x100  <c0116af0> default_wake_function+0x0/0x20
 <c0116af0> default_wake_function+0x0/0x20  <c013bb2b> __alloc_pages+0xdb/0x310
 <c01057a5> do_IRQ+0x45/0x60  <c01038b2> common_interrupt+0x1a/0x20
 <c01ce2bf> number+0x22f/0x300  <c0183269> proc_alloc_inode+0x49/0x80
 <c01831d4> proc_read_inode+0x14/0x40  <c01833a5> proc_get_inode+0x105/0x180
 <c01cec6d> vsnprintf+0x33d/0x580  <c0173407> seq_printf+0x37/0x60
 <c0189729> show_stat+0xad9/0xaf0  <c0166fbf> core_sys_select+0x1df/0x320
 <c013ea0f> truncate_inode_pages+0x2f/0x40  <c0157911>
invalidate_inode_buffers+0x11/0x40
 <c018321b> proc_destroy_inode+0x1b/0x20  <c016af48> dentry_iput+0x58/0x90
 <c016f582> mntput_no_expire+0x22/0x70  <c016782b> sys_select+0x4b/0x1f0
 <c0151982> filp_close+0x52/0x80  <c0102ee7> syscall_call+0x7/0xb


khubd         D 00000001     0  1203      6          1653   220 (L-TLB)
       cf4e8e0c 00000002 c02d247c 00000001 c01223fe 5862ab80 000f4425 0000000a 
       c1254218 c1254110 58dcbd80 000f4425 00000000 00000000 00000000 cf4e8000 
       006acfc0 00000000 cf4e8e20 001fcec7 c12e0ec8 c12e0e00 c02d247c cf4e8e20 
Call Trace:
 <c02d247c> schedule_timeout+0x4c/0xc0  <c01223fe> __mod_timer+0x5e/0x70
 <c02d247c> schedule_timeout+0x4c/0xc0  <c0122890> process_timeout+0x0/0x10
 <d09c9250> ehci_endpoint_disable+0x130/0x160 [ehci_hcd]  <d0a38eee>
usb_disable_device+0x6e/0x180 [usbcore]
 <d0a33ccb> usb_disconnect+0xab/0x130 [usbcore]  <d0a33cb2>
usb_disconnect+0x92/0x130 [usbcore]
 <d0a357f9> hub_thread+0x6b9/0x1030 [usbcore]  <c011ce78> do_exit+0x468/0x7f0
 <c012c2e0> autoremove_wake_function+0x0/0x60  <c012bf2b> kthread+0xbb/0xf0
 <d0a35140> hub_thread+0x0/0x1030 [usbcore]  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


kjournald     S 00000046     0  1653      6          1669  1203 (L-TLB)
       cfb10f60 00000002 c01a905b 00000046 cfb10f28 00000000 c01057a5 0000000a 
       cf508bd8 cf508ad0 a1e04200 000f4424 00000000 00000286 cecb6384 cfb10000 
       0d1cef00 00000000 00000000 cecb62c0 cecb6384 cecb6300 c01a905b 00000000 
Call Trace:
 <c01a905b> kjournald+0x1eb/0x200  <c01057a5> do_IRQ+0x45/0x60
 <c01a905b> kjournald+0x1eb/0x200  <c012c2e0> autoremove_wake_function+0x0/0x60
 <c012bf2b> kthread+0xbb/0xf0  <c01a8e70> kjournald+0x0/0x200
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10
kjournald     S CECB6200     0  1669      6          1818  1653 (L-TLB)
       cec8ef60 00000002 c01a905b cecb6200 00000000 c0116153 cff34d10 00000009 
       cf09b178 cf09b070 b16dffc0 000f41fc 00000000 00000286 cecb6284 cec8e000 
       00000000 00000000 00000000 cecb61c0 cecb6284 cecb6200 c01a905b 00000000 
Call Trace:
 <c01a905b> kjournald+0x1eb/0x200  <c0116153> __wake_up_common+0x43/0x70
 <c01a905b> kjournald+0x1eb/0x200  <c012c2e0> autoremove_wake_function+0x0/0x60
 <c012bf2b> kthread+0xbb/0xf0  <c01a8e70> kjournald+0x0/0x200
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10


scsi_eh_0     S CFF29580     0  1818      6          1819  1669 (L-TLB)
       cf543f44 00000002 d0a83a09 cff29580 d3e19440 cf543f10 c0116040 00000009 
       cff29688 cff29580 d42ddf80 000f41fc 00000000 cf34c5a0 cf34c5a0 cf543000 
       00000000 00000000 fffffffc cf543000 cff7cd74 cf6f0000 d0a83a09 cff29580 
Call Trace:
 <d0a83a09> scsi_error_handler+0x49/0x860 [scsi_mod]  <c0116040>
deactivate_task+0x10/0x20
 <d0a83a09> scsi_error_handler+0x49/0x860 [scsi_mod]  <c02d1a1a>
schedule+0x2aa/0x5c0
 <c012bf1b> kthread+0xab/0xf0  <c012bf2b> kthread+0xbb/0xf0
 <d0a839c0> scsi_error_handler+0x0/0x860 [scsi_mod]  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 

usb-storage   S 0000000D     0  1819      6          3684  1818 (L-TLB)
       cff30f24 00000002 c02d2e43 0000000d c0008580 d0a5722f cf2e4000 0000000a 
       cf5081f8 cf5080f0 3f946280 000f43c4 00000000 cf2e4000 0000000d cff30000 
       003d0900 00000000 cf6f0334 00000292 cff30000 cf5080f0 c02d2e43 cf6f033c 
Call Trace:
 <c02d2e43> __down_interruptible+0xa3/0x120  <d0a5722f>
usb_stor_bulk_transfer_buf+0x4f/0x90 [usb_storage]
 <c02d2e43> __down_interruptible+0xa3/0x120  <c0116af0>
default_wake_function+0x0/0x20
 <c02d174b> __down_failed_interruptible+0x7/0xc  <d0a58bbf>
.text.lock.usb+0x1b/0x2c [usb_storage]
 <c012bf2b> kthread+0xbb/0xf0  <d0a58850> usb_stor_control_thread+0x0/0x1a0
[usb_storage]
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10



scsi_eh_3     D CFC64A68     0  5872      6          5873  3684 (L-TLB)
       caf72ef4 00000002 c02d1f6e cfc64a68 00000011 caf72ecc 00000046 0000000a 
       c6d2f6c8 c6d2f5c0 b4fd56c0 000f441a 00000000 00000011 c6d2f5c0 caf72000 
       00000000 00000000 caf72000 c95f5f44 caf72fa4 caf72f38 c02d1f6e 00000000 
Call Trace:
 <c02d1f6e> wait_for_completion+0x9e/0xd0  <c02d1f6e> wait_for_completion+0x9e/0xd0
 <c0116af0> default_wake_function+0x0/0x20  <d0a56615> command_abort+0x45/0x70
[usb_storage]
 <d0a83d97> scsi_error_handler+0x3d7/0x860 [scsi_mod]  <c02d1a1a>
schedule+0x2aa/0x5c0
 <c012bf1b> kthread+0xab/0xf0  <c012bf2b> kthread+0xbb/0xf0
 <d0a839c0> scsi_error_handler+0x0/0x860 [scsi_mod]  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


usb-storage   D C02D1A1A     0  5873      6          6534  5872 (L-TLB)
       cd45ee4c 00000002 c02d1f6e c02d1a1a cd45ee5c 00000002 c02d1f6e 0000000a 
       c6d2fbb8 c6d2fab0 b8d9aac0 000f4413 00000000 c0359760 b8d9aac0 cd45e000 
       017d7840 00000000 cd45e000 c95f5f18 00000038 cd45ee90 c02d1f6e 00000000 
Call Trace:
 <c02d1f6e> wait_for_completion+0x9e/0xd0  <c02d1a1a> schedule+0x2aa/0x5c0
 <c02d1f6e> wait_for_completion+0x9e/0xd0  <c02d1f6e> wait_for_completion+0x9e/0xd0
 <c0116af0> default_wake_function+0x0/0x20  <d0a39454> usb_sg_wait+0x144/0x160
[usbcore]
 <d0a5740b> usb_stor_bulk_transfer_sg+0xbb/0x120 [usb_storage]  <d0a5722f>
usb_stor_bulk_transfer_buf+0x4f/0x90 [usb_storage]
 <d0a57bf7> usb_stor_Bulk_transport+0x147/0x2e0 [usb_storage]  <d0a57811>
usb_stor_invoke_transport+0x31/0x2d0 [usb_storage]
 <c0116af0> default_wake_function+0x0/0x20  <d0a5896e>
usb_stor_control_thread+0x11e/0x1a0 [usb_storage]
 <c012bf2b> kthread+0xbb/0xf0  <d0a58850> usb_stor_control_thread+0x0/0x1a0
[usb_storage]
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10


cupsd         S 00510005     0  6016      1                4240 (NOTLB)
       c9260b40 00000002 c02d247c 00510005 00001000 c9260b88 00286bcd 00000009 
       c90b7b38 c90b7a30 685b86c0 000f4424 00000000 00000246 cf0133c0 c9260000 
       0d4ab5c0 00000000 c9260b54 0020304b 00000000 00000000 c02d247c c9260b54 
Call Trace:
 <c02d247c> schedule_timeout+0x4c/0xc0  <c02d247c> schedule_timeout+0x4c/0xc0
 <c0122890> process_timeout+0x0/0x10  <c0166cf7> do_select+0x3c7/0x4b0
 <c0167430> __pollwait+0x0/0x100  <c0116af0> default_wake_function+0x0/0x20
 <c0116af0> default_wake_function+0x0/0x20  <c0116af0>
default_wake_function+0x0/0x20
 <c0191bb2> ext3_mark_iloc_dirty+0x1b2/0x3e0  <c01921ee>
ext3_reserve_inode_write+0x7e/0xc0
 <c019ae34> __ext3_journal_stop+0x24/0x50  <c019ae34> __ext3_journal_stop+0x24/0x50
 <c013ddb0> __pagevec_lru_add+0x80/0xa0  <c013804f>
generic_file_buffered_write+0x49f/0x6c0
 <c010397b> error_code+0x4f/0x54  <c011eb4e> current_fs_time+0x4e/0x60
 <c016d64b> file_update_time+0x4b/0xc0  <c0138559>
__generic_file_aio_write_nolock+0x2e9/0x520
 <c0166fbf> core_sys_select+0x1df/0x320  <c0138805> generic_file_aio_write+0x75/0xf0
 <c01959af> ext3_dirty_inode+0x6f/0x90  <c0190584> ext3_file_write+0x44/0xd0
 <c0153568> do_sync_write+0xd8/0x120  <c012c2e0> autoremove_wake_function+0x0/0x60
 <c018e4dd> rsv_window_remove+0x2d/0x40  <c016f582> mntput_no_expire+0x22/0x70
 <c01678df> sys_select+0xff/0x1f0  <c01064e6> do_gettimeofday+0x16/0xc0
 <c0102ee7> syscall_call+0x7/0xb 


cp            D C88CCDB8     0  6532   6112                     (NOTLB)
       c7cd49d4 00000002 c02d1e2e c88ccdb8 00000001 cef3605c c01c52d6 00000008 
       c76f4648 c76f4540 a50fbfc0 000f4413 00000000 00000001 c66ace60 c7cd4000 
       28f2c9c0 00000000 00000000 cef3604c 00000001 c7cd49dc c02d1e2e cef3607c 
Call Trace:
 <c02d1e2e> io_schedule+0xe/0x20  <c01c52d6> as_set_request+0x26/0x80
 <c02d1e2e> io_schedule+0xe/0x20  <c01bfa87> get_request_wait+0x107/0x130
 <c012c2e0> autoremove_wake_function+0x0/0x60  <c01c0b77> __make_request+0x87/0x3d0
 <c0155abd> __find_get_block+0x10d/0x150  <c01be867>
generic_make_request+0x137/0x1b0
 <c0139904> mempool_alloc+0x44/0xf0  <c0139904> mempool_alloc+0x44/0xf0
 <c0139904> mempool_alloc+0x44/0xf0  <c01c085a> submit_bio+0x5a/0x100
 <c01589a8> bio_alloc_bioset+0x98/0x180  <c0158ab0> bio_alloc+0x20/0x30
 <c0155372> submit_bh+0xd2/0x130  <c0156d41> __block_write_full_page+0x1a1/0x340
 <d0b75820> fat_get_block+0x0/0x30 [fat]  <d0b7597f> fat_writepage+0x1f/0x30 [fat]
 <d0b75820> fat_get_block+0x0/0x30 [fat]  <c013ee65> pageout+0xb5/0x1e0
 <c013f921> shrink_zone+0x991/0xc20  <c014036a> try_to_free_pages+0x16a/0x280
 <c013bbdb> __alloc_pages+0x18b/0x310  <c0137d2f>
generic_file_buffered_write+0x17f/0x6c0
 <c01038d4> apic_timer_interrupt+0x1c/0x24  <c011eb4e> current_fs_time+0x4e/0x60
 <c016d64b> file_update_time+0x4b/0xc0  <c0138559>
__generic_file_aio_write_nolock+0x2e9/0x520
 <c0137a1c> __generic_file_aio_read+0xec/0x220  <c01361a0> file_read_actor+0x0/0x130
 <c01387fc> generic_file_aio_write+0x6c/0xf0  <c0137b9e>
generic_file_aio_read+0x4e/0x60
 <c0153568> do_sync_write+0xd8/0x120  <c012c2e0> autoremove_wake_function+0x0/0x60
 <c01057a5> do_IRQ+0x45/0x60  <c0154035> vfs_write+0xb5/0x190
 <c01546fb> sys_write+0x4b/0x80  <c0102ee7> syscall_call+0x7/0xb


pdflush       D CE6AFF40     0  6534      6          6535  5873 (L-TLB)
       ce6afeb0 00000002 c02d247c ce6aff40 00000000 c013c53c 00000003 0000000a 
       c04fa138 c04fa030 54de66c0 000f4425 00000000 00000002 c02d1d67 ce6af000 
       003d0900 00000000 ce6afec4 001fcee7 c0363fe4 ce6afee8 c02d247c ce6afec4 
Call Trace:
 <c02d247c> schedule_timeout+0x4c/0xc0  <c013c53c> do_writepages+0x2c/0x50
 <c02d1d67> cond_resched+0x37/0x50  <c02d247c> schedule_timeout+0x4c/0xc0
 <c0122890> process_timeout+0x0/0x10  <c02d1e11> io_schedule_timeout+0x11/0x20
 <c01c07d9> blk_congestion_wait+0xa9/0xd0  <c012c2e0>
autoremove_wake_function+0x0/0x60
 <c013c89a> background_writeout+0x3a/0xb0  <c013d0f5> pdflush+0xb5/0x190
 <c013c860> background_writeout+0x0/0xb0  <c012bf2b> kthread+0xbb/0xf0
 <c013d040> pdflush+0x0/0x190  <c012be70> kthread+0x0/0xf0
 <c0101005> kernel_thread_helper+0x5/0x10 


pdflush       D C6815F40     0  6535      6          6547  6534 (L-TLB)
       c6815eb0 00000002 c02d247c c6815f40 00000000 c013c53c 00000003 0000000a 
       c90b7648 c90b7540 54de66c0 000f4425 00000000 00000001 0000007b c6815000 
       000f4240 00000000 c6815ec4 001fcee7 c0363fe4 c6815ee8 c02d247c c6815ec4 
Call Trace:
 <c02d247c> schedule_timeout+0x4c/0xc0  <c013c53c> do_writepages+0x2c/0x50
 <c02d247c> schedule_timeout+0x4c/0xc0  <c0122890> process_timeout+0x0/0x10
 <c02d1e11> io_schedule_timeout+0x11/0x20  <c01c07d9> blk_congestion_wait+0xa9/0xd0
 <c012c2e0> autoremove_wake_function+0x0/0x60  <c013c89a>
background_writeout+0x3a/0xb0
 <c013d0f5> pdflush+0xb5/0x190  <c013c860> background_writeout+0x0/0xb0
 <c012bf2b> kthread+0xbb/0xf0  <c013d040> pdflush+0x0/0x190
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10


pdflush       D FFFFFF01     0  6547      6                6535 (L-TLB)
       cc664e88 00000002 c02d247c ffffff01 ffffffff cd31e780 000f441d 0000000a 
       c76f4158 c76f4050 54de66c0 000f4425 00000000 00000000 c0363fe4 cc664000 
       000f4240 00000000 cc664e9c 001fcee7 c0363fe4 cc664ec0 c02d247c cc664e9c 
Call Trace:
 <c02d247c> schedule_timeout+0x4c/0xc0  <c02d247c> schedule_timeout+0x4c/0xc0
 <c0122890> process_timeout+0x0/0x10  <c02d1e11> io_schedule_timeout+0x11/0x20
 <c01c07d9> blk_congestion_wait+0xa9/0xd0  <c012c2e0>
autoremove_wake_function+0x0/0x60
 <c013c6f8> wb_kupdate+0xb8/0x110  <c013d0c6> pdflush+0x86/0x190
 <c013d0f5> pdflush+0xb5/0x190  <c013c640> wb_kupdate+0x0/0x110
 <c012bf2b> kthread+0xbb/0xf0  <c013d040> pdflush+0x0/0x190
 <c012be70> kthread+0x0/0xf0  <c0101005> kernel_thread_helper+0x5/0x10


lsusb         D 00000000     0  6570   6562                     (NOTLB)
       c97d3ea8 00000002 c02d2f61 00000000 00000059 c6e2d2fc 00000004 00000007 
       cf542bf8 cf542af0 62be34c0 000f441e 00000000 00000059 c97d3f2c c97d3000 
       00895440 00000000 cf0c8124 00000282 cf542af0 cf0c812c c02d2f61 00000000 
Call Trace:
 <c02d2f61> __down+0xa1/0xf7  <c02d2f61> __down+0xa1/0xf7
 <d0a31867> usb_get_dev+0x17/0x20 [usbcore]  <c0116af0>
default_wake_function+0x0/0x20
 <c02d173f> __down_failed+0x7/0xc  <d0a40c54> .text.lock.devio+0x1b8/0x1f4 [usbcore]
 <c0151dbc> do_filp_open+0x5c/0x70  <c0165b86> do_ioctl+0x76/0x80
 <c01541c5> vfs_read+0xb5/0x190  <c015467b> sys_read+0x4b/0x80
 <c0102ee7> syscall_call+0x7/0xb 
Comment 7 Alan Stern 2006-06-26 08:14:34 UTC
This looks like a duplicate of bug #6374.  The original problem as reported by 
 crazy@wi.rr.com, that is, not the comments added later by other people
describing completely different problems.  (Or maybe, since the number is
higher, that bug is a duplicate of this one.)

Something seems to goes wrong with certain EHCI controllers, causing
ehci_endpoint_disable() to hang.  A experimental patch was attached to the other
report, but it didn't seem to help.  Maybe you should try it just to see what
happens.
Comment 8 Olaf Titz 2006-10-31 03:47:28 UTC
Okay, some more data:
- I have replaced the broken USB hub, it still happens. 
- Like in bug 6374, my mobo is a VIA chipset. This is the EHCI controller:
0000:00:10.3 USB Controller: VIA Technologies, Inc. USB 2.0 (rev 82) (prog-if 20
[EHCI])
        Subsystem: Asustek Computer, Inc. A7V8X motherboard
        Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr-
Stepping- SERR- FastB2B-
        Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort-
<TAbort- <MAbort- >SERR- <PERR-
        Latency: 32, Cache Line Size: 0x10 (64 bytes)
        Interrupt: pin D routed to IRQ 18
        Region 0: Memory at e9800000 (32-bit, non-prefetchable) [size=256]
        Capabilities: [80] Power Management version 2
                Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=375mA
PME(D0+,D1+,D2+,D3hot+,D3cold+)
                Status: D0 PME-Enable- DSel=0 DScale=0 PME-

Now trying the patch from bug 6374.
Comment 9 Olaf Titz 2006-11-11 10:29:19 UTC
Still happens with the patch from 6374, but so far it has not happened without
any external hub connected.
Comment 10 Natalie Protasevich 2007-08-11 16:16:57 UTC
Any updates on this problem since?
Thanks.

Note You need to log in before you can comment on or make changes to this bug.