Bug#585813: linux-image-2.6.32-trunk-686: gvfs-gdu-volu blocked task in syslog
Package: linux-2.6
Version: 2.6.32-5
Severity: normal
I see this error message in /var/log/syslog:
Jun 14 01:15:59 desk kernel: [186600.460107] INFO: task gvfs-gdu-volume:16915 blocked for more than 120 seconds.
Jun 14 01:15:59 desk kernel: [186600.460112] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jun 14 01:15:59 desk kernel: [186600.460116] gvfs-gdu-volu D b5a386b4 0 16915 1 0x00000004
Jun 14 01:15:59 desk kernel: [186600.460123] f51d4880 00200086 a6f14c40 b5a386b4 00000008 c13f4000 c13f4000 a292e134
Jun 14 01:15:59 desk kernel: [186600.460132] f51d4a3c c2408000 00000000 a049694b 0000a980 a292e148 a7158c80 b5a386b4
Jun 14 01:15:59 desk kernel: [186600.460139] 00000004 f51d4a3c a292e148 a292e150 a71b4de0 b5a386b4 00000004 00000000
Jun 14 01:15:59 desk kernel: [186600.460146] Call Trace:
Jun 14 01:15:59 desk kernel: [186600.460157] [<c125a385>] ? __mutex_lock_common+0xde/0x131
Jun 14 01:15:59 desk kernel: [186600.460162] [<c125a3e7>] ? __mutex_lock_slowpath+0xf/0x11
Jun 14 01:15:59 desk kernel: [186600.460165] [<c125a478>] ? mutex_lock+0x17/0x24
Jun 14 01:15:59 desk kernel: [186600.460169] [<c125a478>] ? mutex_lock+0x17/0x24
Jun 14 01:15:59 desk kernel: [186600.460175] [<c10b84a5>] ? do_lookup+0x62/0x116
Jun 14 01:15:59 desk kernel: [186600.460179] [<c10b8b3a>] ? __link_path_walk+0x299/0x5bf
Jun 14 01:15:59 desk kernel: [186600.460183] [<c10b9016>] ? path_walk+0x4f/0xa3
Jun 14 01:15:59 desk kernel: [186600.460187] [<c10ba174>] ? do_path_lookup+0x1f/0x69
Jun 14 01:15:59 desk kernel: [186600.460191] [<c10ba2e3>] ? do_filp_open+0xdf/0x7e0
Jun 14 01:15:59 desk kernel: [186600.460198] [<c101ff85>] ? kunmap_atomic+0x5d/0x6d
Jun 14 01:15:59 desk kernel: [186600.460203] [<c10c22a1>] ? alloc_fd+0x52/0xb7
Jun 14 01:15:59 desk kernel: [186600.460209] [<c10b0353>] ? do_sys_open+0x4c/0xdf
Jun 14 01:15:59 desk kernel: [186600.460213] [<c10b042a>] ? sys_open+0x1e/0x23
Jun 14 01:15:59 desk kernel: [186600.460218] [<c10030fb>] ? sysenter_do_call+0x12/0x28
un 14 01:15:59 desk kernel: [186600.460227] gvfs-gdu-volu D a292e874 0 16916 1 0x00000004
Jun 14 01:15:59 desk kernel: [186600.460232] f51d7300 00200086 a292e86c a292e874 a7231040 c13f4000 c13f4000 00000000
Jun 14 01:15:59 desk kernel: [186600.460239] f51d74bc c2408000 00000000 a049a386 0000a980 00000000 a292e87c a292e884
Jun 14 01:15:59 desk kernel: [186600.460247] a7286e20 f51d74bc 00000004 00000000 a292e884 a292e88c a718d0a0 b5a386b4
Jun 14 01:15:59 desk kernel: [186600.460254] Call Trace:
Jun 14 01:15:59 desk kernel: [186600.460258] [<c125a385>] ? __mutex_lock_common+0xde/0x131
Jun 14 01:15:59 desk kernel: [186600.460263] [<c125a3e7>] ? __mutex_lock_slowpath+0xf/0x11
Jun 14 01:15:59 desk kernel: [186600.460266] [<c125a478>] ? mutex_lock+0x17/0x24
Jun 14 01:15:59 desk kernel: [186600.460270] [<c125a478>] ? mutex_lock+0x17/0x24
Jun 14 01:15:59 desk kernel: [186600.460274] [<c10b84a5>] ? do_lookup+0x62/0x116
Jun 14 01:15:59 desk kernel: [186600.460278] [<c10b8d5a>] ? __link_path_walk+0x4b9/0x5bf
Jun 14 01:15:59 desk kernel: [186600.460282] [<c10b9016>] ? path_walk+0x4f/0xa3
Jun 14 01:15:59 desk kernel: [186600.460286] [<c10ba174>] ? do_path_lookup+0x1f/0x69
Jun 14 01:15:59 desk kernel: [186600.460290] [<c10ba2e3>] ? do_filp_open+0xdf/0x7e0
Jun 14 01:15:59 desk kernel: [186600.460296] [<c10ad360>] ? __slab_alloc+0x97/0x431
Jun 14 01:15:59 desk kernel: [186600.460300] [<c10ad88c>] ? kmem_cache_alloc+0x6d/0xe5
Jun 14 01:15:59 desk kernel: [186600.460304] [<c10baa3d>] ? getname+0x1d/0xb0
Jun 14 01:15:59 desk kernel: [186600.460308] [<c10c22a1>] ? alloc_fd+0x52/0xb7
Jun 14 01:15:59 desk kernel: [186600.460312] [<c10b0353>] ? do_sys_open+0x4c/0xdf
Jun 14 01:15:59 desk kernel: [186600.460316] [<c10b042a>] ? sys_open+0x1e/0x23
Jun 14 01:15:59 desk kernel: [186600.460320] [<c10030fb>] ? sysenter_do_call+0x12/0x28
Jun 14 01:15:59 desk kernel: [186600.460328] gvfs-gdu-volu D a715d160 0 16917 1 0x00000004
Jun 14 01:15:59 desk kernel: [186600.460333] f50ef300 00200086 a292ef7c a715d160 b5a386b4 c13f4000 c13f4000 a292ef7c
Jun 14 01:15:59 desk kernel: [186600.460340] f50ef4bc c2408000 00000000 a04d4327 0000a980 a292ef84 a292ef8c a72ea5a0
Jun 14 01:15:59 desk kernel: [186600.460347] b5a386b4 f50ef4bc 00000000 a292ef8c a292ef94 a7093580 b5a386b4 00000004
Jun 14 01:15:59 desk kernel: [186600.460354] Call Trace:
Jun 14 01:15:59 desk kernel: [186600.460359] [<c125a385>] ? __mutex_lock_common+0xde/0x131
Jun 14 01:15:59 desk kernel: [186600.460363] [<c125a3e7>] ? __mutex_lock_slowpath+0xf/0x11
Jun 14 01:15:59 desk kernel: [186600.460367] [<c125a478>] ? mutex_lock+0x17/0x24
Jun 14 01:15:59 desk kernel: [186600.460371] [<c125a478>] ? mutex_lock+0x17/0x24
Jun 14 01:15:59 desk kernel: [186600.460375] [<c10b84a5>] ? do_lookup+0x62/0x116
Jun 14 01:15:59 desk kernel: [186600.460379] [<c10b8d5a>] ? __link_path_walk+0x4b9/0x5bf
Jun 14 01:15:59 desk kernel: [186600.460383] [<c10b9016>] ? path_walk+0x4f/0xa3
Jun 14 01:15:59 desk kernel: [186600.460387] [<c10ba174>] ? do_path_lookup+0x1f/0x69
Jun 14 01:15:59 desk kernel: [186600.460391] [<c10bb244>] ? user_path_at+0x37/0x5f
Jun 14 01:15:59 desk kernel: [186600.460395] [<c101ff79>] ? kunmap_atomic+0x51/0x6d
Jun 14 01:15:59 desk kernel: [186600.460399] [<c101ff85>] ? kunmap_atomic+0x5d/0x6d
Jun 14 01:15:59 desk kernel: [186600.460405] [<c109b62c>] ? handle_mm_fault+0x836/0x847
Jun 14 01:15:59 desk kernel: [186600.460410] [<c10b4bc3>] ? vfs_fstatat+0x2a/0x50
Jun 14 01:15:59 desk kernel: [186600.460414] [<c10b4c2a>] ? vfs_lstat+0x13/0x15
Jun 14 01:15:59 desk kernel: [186600.460418] [<c10b4c3b>] ? sys_lstat64+0xf/0x23
Jun 14 01:15:59 desk kernel: [186600.460424] [<c104b564>] ? getnstimeofday+0x4d/0xc9
Jun 14 01:15:59 desk kernel: [186600.460429] [<c101c488>] ? do_page_fault+0x271/0x287
Jun 14 01:15:59 desk kernel: [186600.460433] [<c101c217>] ? do_page_fault+0x0/0x287
Jun 14 01:15:59 desk kernel: [186600.460437] [<c10030fb>] ? sysenter_do_call+0x12/0x28
et cetera. I see about 8 of these.
-- Package-specific info:
** Version:
Linux version 2.6.32-trunk-686 (Debian 2.6.32-5) (ben@decadent.org.uk) (gcc version 4.3.4 (Debian 4.3.4-6) ) #1 SMP Sun Jan 10 06:32:16 UTC 2010
*** Protocol statistics:
Ip:
9384259 total packets received
266594 forwarded
0 incoming packets discarded
9117111 incoming packets delivered
4781257 requests sent out
108 outgoing packets dropped
Icmp:
158 ICMP messages received
3 input ICMP message failed.
ICMP input histogram:
destination unreachable: 156
echo replies: 2
21 ICMP messages sent
0 ICMP messages failed
ICMP output histogram:
destination unreachable: 19
echo request: 2
IcmpMsg:
InType0: 2
InType3: 156
OutType3: 19
OutType8: 2
Tcp:
11889 active connections openings
5087 passive connection openings
273 failed connection attempts
613 connection resets received
1 connections established
9051135 segments received
4424605 segments send out
23627 segments retransmited
0 bad segments received.
1526 resets sent
Udp:
29640 packets received
6 packets to unknown port received.
35585 packet receive errors
66913 packets sent
RcvbufErrors: 35585
UdpLite:
TcpExt:
33 invalid SYN cookies received
13 resets received for embryonic SYN_RECV sockets
8 ICMP packets dropped because they were out-of-window
7946 TCP sockets finished time wait in fast timer
10 time wait sockets recycled by time stamp
14266 delayed acks sent
85 delayed acks further delayed because of locked socket
Quick ack mode was activated 998 times
1333 packets directly queued to recvmsg prequeue.
25584 bytes directly in process context from backlog
1597659 bytes directly received in process context from prequeue
1995846 packet headers predicted
1126 packets header predicted and directly queued to user
181243 acknowledgments not containing data payload received
7347411 predicted acknowledgments
1741 times recovered from packet loss by selective acknowledgements
Detected reordering 15 times using FACK
Detected reordering 301 times using SACK
9 congestion windows fully recovered without slow start
82 congestion windows partially recovered using Hoe heuristic
1076 congestion windows recovered without slow start by DSACK
14 congestion windows recovered without slow start after partial ack
388 TCP data loss events
TCPLostRetransmit: 24
161 timeouts after SACK recovery
31 timeouts in loss state
20754 fast retransmits
642 forward retransmits
1124 retransmits in slow start
428 other TCP timeouts
101 SACK retransmits failed
976 DSACKs sent for old packets
1 DSACKs sent for out of order packets
20089 DSACKs received
35 DSACKs for out of order packets received
229 connections reset due to unexpected data
138 connections reset due to early user close
21 connections aborted due to timeout
TCPDSACKIgnoredOld: 18932
TCPDSACKIgnoredNoUndo: 1049
TCPSpuriousRTOs: 91
TCPSackShifted: 25163
TCPSackMerged: 21209
TCPSackShiftFallback: 4614
IpExt:
InNoRoutes: 3
InMcastPkts: 289
OutMcastPkts: 213
InBcastPkts: 37748
OutBcastPkts: 37300
InOctets: -2042231353
OutOctets: 1852959395
InMcastOctets: 41299
OutMcastOctets: 25720
InBcastOctets: 7461356
OutBcastOctets: 7408485
00:1f.5 Multimedia audio controller [0401]: Intel Corporation 82801EB/ER (ICH5/ICH5R) AC'97 Audio Controller [8086:24d5] (rev 02)
Subsystem: Giga-byte Technology GA-8IPE1000/8KNXP motherboard [1458:a002]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin B routed to IRQ 17
Region 0: I/O ports at d800 [size=256]
Region 1: I/O ports at dc00 [size=64]
Region 2: Memory at fd001000 (32-bit, non-prefetchable) [size=512]
Region 3: Memory at fd002000 (32-bit, non-prefetchable) [size=256]
Capabilities: <access denied>
Kernel driver in use: Intel ICH
01:00.0 VGA compatible controller [0300]: nVidia Corporation NV44A [GeForce 6200] [10de:0221] (rev a1) (prog-if 00 [VGA controller])
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32 (1250ns min, 250ns max)
Interrupt: pin A routed to IRQ 3
Region 0: Memory at f8000000 (32-bit, non-prefetchable) [size=16M]
Region 1: Memory at e0000000 (32-bit, prefetchable) [size=256M]
Region 2: Memory at f9000000 (32-bit, non-prefetchable) [size=16M]
[virtual] Expansion ROM at fa000000 [disabled] [size=128K]
Capabilities: <access denied>
02:01.0 Ethernet controller [0200]: D-Link System Inc RTL8139 Ethernet [1186:1300] (rev 10)
Subsystem: D-Link System Inc DFE-528TX 10/100 Fast Ethernet PCI Adapter [1186:1303]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 64 (8000ns min, 16000ns max)
Interrupt: pin A routed to IRQ 21
Region 0: I/O ports at a000 [size=256]
Region 1: Memory at fc001000 (32-bit, non-prefetchable) [size=256]
Capabilities: <access denied>
Kernel driver in use: 8139too
02:02.0 Ethernet controller [0200]: Realtek Semiconductor Co., Ltd. RTL-8139/8139C/8139C+ [10ec:8139] (rev 10)
Subsystem: Belkin F5D5000 PCI Card/Desktop Network PCI Card [1799:5000]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 64 (8000ns min, 16000ns max)
Interrupt: pin A routed to IRQ 22
Region 0: I/O ports at a400 [size=256]
Region 1: Memory at fc000000 (32-bit, non-prefetchable) [size=256]
[virtual] Expansion ROM at 60000000 [disabled] [size=64K]
Capabilities: <access denied>
Kernel driver in use: 8139too
02:04.0 USB Controller [0c03]: ALi Corporation USB 1.1 Controller [10b9:5237] (rev 03) (prog-if 10 [OHCI])
Subsystem: ALi Corporation ASRock 939Dual-SATA2 Motherboard [10b9:5237]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32 (20000ns max), Cache Line Size: 32 bytes
Interrupt: pin B routed to IRQ 21
Region 0: Memory at fc002000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
Kernel driver in use: ohci_hcd
02:04.1 USB Controller [0c03]: ALi Corporation USB 1.1 Controller [10b9:5237] (rev 03) (prog-if 10 [OHCI])
Subsystem: ALi Corporation ASRock 939Dual-SATA2 Motherboard [10b9:5237]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32 (20000ns max), Cache Line Size: 32 bytes
Interrupt: pin C routed to IRQ 22
Region 0: Memory at fc003000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
Kernel driver in use: ohci_hcd
02:04.2 USB Controller [0c03]: ALi Corporation USB 1.1 Controller [10b9:5237] (rev 03) (prog-if 10 [OHCI])
Subsystem: ALi Corporation ASRock 939Dual-SATA2 Motherboard [10b9:5237]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32 (20000ns max), Cache Line Size: 32 bytes
Interrupt: pin D routed to IRQ 16
Region 0: Memory at fc004000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
Kernel driver in use: ohci_hcd
02:04.3 USB Controller [0c03]: ALi Corporation USB 2.0 Controller [10b9:5239] (rev 01) (prog-if 20 [EHCI])
Subsystem: Device [2020:8888]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32 (20000ns max)
Interrupt: pin A routed to IRQ 18
Region 0: Memory at fc005000 (32-bit, non-prefetchable) [size=256]
Capabilities: <access denied>
Kernel driver in use: ehci_hcd
02:09.0 Ethernet controller [0200]: Realtek Semiconductor Co., Ltd. RTL-8169 Gigabit Ethernet [10ec:8169] (rev 10)
Subsystem: Giga-byte Technology GA-8I915ME-G Mainboard [1458:e000]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 64 (8000ns min, 16000ns max), Cache Line Size: 32 bytes
Interrupt: pin A routed to IRQ 20
Region 0: I/O ports at a800 [size=256]
Region 1: Memory at fc006000 (32-bit, non-prefetchable) [size=256]
[virtual] Expansion ROM at 60010000 [disabled] [size=64K]
Capabilities: <access denied>
Kernel driver in use: r8169
** USB devices:
Bus 009 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 008 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 007 Device 002: ID 04b8:011d Seiko Epson Corp. Perfection 1260 Photo
Bus 007 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 006 Device 002: ID 0458:002e KYE Systems Corp. (Mouse Systems)
Bus 006 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 005 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 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 001 Device 004: ID 04e8:328e Samsung Electronics Co., Ltd CLP-310 Color Laser Printer
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Kernel: Linux 2.6.32-trunk-686 (SMP w/1 CPU core)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Versions of packages linux-image-2.6.32-trunk-686 depends on:
ii debconf [debconf-2.0] 1.5.32 Debian configuration management sy
ii initramfs-tools [linux-initr 0.94.4 tools for generating an initramfs
ii module-init-tools 3.12~pre2-3 tools for managing Linux kernel mo
Versions of packages linux-image-2.6.32-trunk-686 recommends:
ii firmware-linux-free 2.6.32-15 Binary firmware for various driver
ii libc6-i686 2.11.1-3 GNU C Library: Shared libraries [i
Versions of packages linux-image-2.6.32-trunk-686 suggests:
ii grub 0.97-61 GRand Unified Bootloader (dummy pa
pn linux-doc-2.6.32 <none> (no description available)
Versions of packages linux-image-2.6.32-trunk-686 is related to:
pn firmware-bnx2 <none> (no description available)
pn firmware-bnx2x <none> (no description available)
pn firmware-ipw2x00 <none> (no description available)
pn firmware-ivtv <none> (no description available)
pn firmware-iwlwifi <none> (no description available)
pn firmware-linux <none> (no description available)
pn firmware-linux-nonfree <none> (no description available)
pn firmware-qlogic <none> (no description available)
pn firmware-ralink <none> (no description available)
--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: 20100614010022.17643.37041.reportbug@desk.lan">htt p://lists.debian.org/20100614010022.17643.37041.reportbug@desk.lan
06-14-2010, 10:58 AM
Ben Hutchings
Bug#585813: linux-image-2.6.32-trunk-686: gvfs-gdu-volu blocked task in syslog
On Mon, 2010-06-14 at 02:00 +0100, Greg Kochanski wrote:
> Package: linux-2.6
> Version: 2.6.32-5
> Severity: normal
This version is several months old; please test the current version
(package name linux-image-2.6.32-5-686, version 2.6.32-15).
Ben.
--
Ben Hutchings
Once a job is fouled up, anything done to improve it makes it worse.