linux-kernel - [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume

lists.openwall.net		lists / announce owl-users owl-dev john-users john-dev passwdqc-users yescrypt popa3d-users / oss-security kernel-hardening musl sabotage tlsify passwords / crypt-dev xvendor / Bugtraq Full-Disclosure linux-kernel linux-netdev linux-ext4 linux-hardening linux-cve-announce PHC
Open Source and information security mailing list archives
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [thread-next>] [day] [month] [year] [list]
Message-Id: <201204022157.05598.maciej.rutecki@gmail.com>
Date:	Mon, 2 Apr 2012 21:57:05 +0200
From:	Maciej Rutecki <maciej.rutecki@...il.com>
To:	Linux Kernel Mailing List <linux-kernel@...r.kernel.org>
Cc:	"Rafael J. Wysocki" <rjw@...k.pl>, linux-wireless@...r.kernel.org,
	ilw@...ux.intel.com
Subject: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume

Last known good kernel: 3.3

Bad kernel: 3.4-rc1 + patch: http://cgit.freedesktop.org/~danvet/drm-
intel/commit/?h=drm-intel-fixes&id=55a254ac63a3ac1867d1501030e7fba69c7d4aeb

Subsystem: network (wireless)

After resume from suspend to disk I do not have network connection (wireless) 
and I got it in dmesg:

[ 2398.278375] INFO: task nmbd:2442 blocked for more than 120 seconds.
[ 2398.278384] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables 
this message.
[ 2398.278390] nmbd            D ffff88023fb933c0     0  2442      1 0x00000000
[ 2398.278401]  ffff880232d6f5d0 0000000000000082 0000000000000206 
ffff880235186ee0
[ 2398.278413]  00000000000133c0 ffff8802320fdfd8 ffff8802320fdfd8 ffff880232d6f5d0
[ 2398.278423]  ffff8802320fde5c ffffffff81868020 ffff880232d6f5d0 0000000000000002
[ 2398.278433] Call Trace:
[ 2398.278451]  [<ffffffff813fba8c>] ? __mutex_lock_common.isra.5+0xff/0x164
[ 2398.278462]  [<ffffffff813fb97a>] ? mutex_lock+0x1a/0x2d
[ 2398.278471]  [<ffffffff8135bb44>] ? netlink_trim+0x13/0x79
[ 2398.278480]  [<ffffffff81349f0c>] ? rtnetlink_rcv+0xe/0x28
[ 2398.278488]  [<ffffffff8135cffc>] ? netlink_unicast+0xe6/0x14e
[ 2398.278497]  [<ffffffff8135d265>] ? netlink_sendmsg+0x201/0x23c
[ 2398.278505]  [<ffffffff811091b1>] ? __pollwait+0xce/0xce
[ 2398.278515]  [<ffffffff8132e2a2>] ? sock_sendmsg+0xca/0xe7
[ 2398.278521]  [<ffffffff811091b1>] ? __pollwait+0xce/0xce
[ 2398.278530]  [<ffffffff8103d1da>] ? sys_time+0xc/0x39
[ 2398.278539]  [<ffffffff81011e5d>] ? emulate_vsyscall+0x191/0x270
[ 2398.278548]  [<ffffffff81056a33>] ? should_resched+0x5/0x23
[ 2398.278554]  [<ffffffff813fc545>] ? _cond_resched+0x6/0x1b
[ 2398.278562]  [<ffffffff8132cd08>] ? copy_from_user+0x18/0x30
[ 2398.278571]  [<ffffffff8132fb20>] ? sys_sendto+0xf7/0x137
[ 2398.278581]  [<ffffffff810fa2a0>] ? fd_install+0x25/0x4a
[ 2398.278591]  [<ffffffff81401ff9>] ? system_call_fastpath+0x16/0x1b
[ 2398.278659] INFO: task ifconfig:6734 blocked for more than 120 seconds.
[ 2398.278664] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables 
this message.
[ 2398.278669] ifconfig        D ffff88023fb933c0     0  6734   2598 0x00000000
[ 2398.278678]  ffff8801faae30a0 0000000000000086 0000000000000001 
ffff880235186ee0
[ 2398.278688]  00000000000133c0 ffff880232163fd8 ffff880232163fd8 ffff8801faae30a0
[ 2398.278697]  0000000000000001 ffff880230f2b4d8 ffff8801faae30a0 
0000000000000002
[ 2398.278707] Call Trace:
[ 2398.278716]  [<ffffffff813fba8c>] ? __mutex_lock_common.isra.5+0xff/0x164
[ 2398.278723]  [<ffffffff81056a33>] ? should_resched+0x5/0x23
[ 2398.278732]  [<ffffffff813fb97a>] ? mutex_lock+0x1a/0x2d
[ 2398.278759]  [<ffffffffa0116d33>] ? rtl8169_close+0x49/0x14f [r8169]
[ 2398.278768]  [<ffffffff8133cb37>] ? __dev_close_many+0x85/0xb2
[ 2398.278775]  [<ffffffff8133cb94>] ? __dev_close+0x30/0x47
[ 2398.278783]  [<ffffffff813405c0>] ? dev_set_rx_mode+0x1d/0x29
[ 2398.278792]  [<ffffffff81340844>] ? __dev_change_flags+0x9d/0x118
[ 2398.278799]  [<ffffffff81340922>] ? dev_change_flags+0x12/0x42
[ 2398.278810]  [<ffffffff8138a769>] ? devinet_ioctl+0x285/0x554
[ 2398.278818]  [<ffffffff8132cdb3>] ? sock_do_ioctl+0x1b/0x36
[ 2398.278826]  [<ffffffff8132d1be>] ? sock_ioctl+0x205/0x212
[ 2398.278836]  [<ffffffff8110887d>] ? do_vfs_ioctl+0x459/0x49a
[ 2398.278844]  [<ffffffff810ee42b>] ? kmem_cache_alloc+0x86/0xea
[ 2398.278853]  [<ffffffff81108909>] ? sys_ioctl+0x4b/0x72
[ 2398.278862]  [<ffffffff81401ff9>] ? system_call_fastpath+0x16/0x1b
[ 2518.146666] INFO: task nmbd:2442 blocked for more than 120 seconds.
[ 2518.146675] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables 
this message.
[ 2518.146682] nmbd            D ffff88023fb933c0     0  2442      1 0x00000000
[ 2518.146693]  ffff880232d6f5d0 0000000000000082 0000000000000206 
ffff880235186ee0
[ 2518.146704]  00000000000133c0 ffff8802320fdfd8 ffff8802320fdfd8 ffff880232d6f5d0
[ 2518.146714]  ffff8802320fde5c ffffffff81868020 ffff880232d6f5d0 0000000000000002
[ 2518.146725] Call Trace:
[ 2518.146743]  [<ffffffff813fba8c>] ? __mutex_lock_common.isra.5+0xff/0x164
[ 2518.146753]  [<ffffffff813fb97a>] ? mutex_lock+0x1a/0x2d
[ 2518.146763]  [<ffffffff8135bb44>] ? netlink_trim+0x13/0x79
[ 2518.146772]  [<ffffffff81349f0c>] ? rtnetlink_rcv+0xe/0x28
[ 2518.146780]  [<ffffffff8135cffc>] ? netlink_unicast+0xe6/0x14e
[ 2518.146788]  [<ffffffff8135d265>] ? netlink_sendmsg+0x201/0x23c
[ 2518.146796]  [<ffffffff811091b1>] ? __pollwait+0xce/0xce
[ 2518.146807]  [<ffffffff8132e2a2>] ? sock_sendmsg+0xca/0xe7
[ 2518.146813]  [<ffffffff811091b1>] ? __pollwait+0xce/0xce
[ 2518.146822]  [<ffffffff8103d1da>] ? sys_time+0xc/0x39
[ 2518.146831]  [<ffffffff81011e5d>] ? emulate_vsyscall+0x191/0x270
[ 2518.146839]  [<ffffffff81056a33>] ? should_resched+0x5/0x23
[ 2518.146846]  [<ffffffff813fc545>] ? _cond_resched+0x6/0x1b
[ 2518.146854]  [<ffffffff8132cd08>] ? copy_from_user+0x18/0x30
[ 2518.146862]  [<ffffffff8132fb20>] ? sys_sendto+0xf7/0x137
[ 2518.146872]  [<ffffffff810fa2a0>] ? fd_install+0x25/0x4a
[ 2518.146883]  [<ffffffff81401ff9>] ? system_call_fastpath+0x16/0x1b
[ 2518.146915] INFO: task ksysguardd:3501 blocked for more than 120 seconds.
[ 2518.146919] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables 
this message.
[ 2518.146924] ksysguardd      D ffff88023fa133c0     0  3501   3436 0x00000000
[ 2518.146934]  ffff8802322ed8d0 0000000000000082 ffff880228522980 ffffffff81813020
[ 2518.146943]  00000000000133c0 ffff880225c7bfd8 ffff880225c7bfd8 ffff8802322ed8d0
[ 2518.146953]  00000008a01445fe ffffffff81868020 ffff8802322ed8d0 0000000000000002
[ 2518.146962] Call Trace:
[ 2518.146972]  [<ffffffff813fba8c>] ? __mutex_lock_common.isra.5+0xff/0x164
[ 2518.146981]  [<ffffffff813fb97a>] ? mutex_lock+0x1a/0x2d
[ 2518.146993]  [<ffffffff813daa7b>] ? wireless_dev_seq_start+0x12/0x5e
[ 2518.147002]  [<ffffffff811136bd>] ? seq_read+0x148/0x354
[ 2518.147012]  [<ffffffff81141a3e>] ? proc_reg_read+0x78/0x91
[ 2518.147021]  [<ffffffff810fb7e5>] ? vfs_read+0x9f/0xe6
[ 2518.147028]  [<ffffffff810fb871>] ? sys_read+0x45/0x6b
[ 2518.147037]  [<ffffffff81401ff9>] ? system_call_fastpath+0x16/0x1b
[ 2518.147063] INFO: task ifconfig:6734 blocked for more than 120 seconds.
[ 2518.147068] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables 
this message.
[ 2518.147072] ifconfig        D ffff88023fb933c0     0  6734   2598 0x00000000
[ 2518.147081]  ffff8801faae30a0 0000000000000086 0000000000000001 
ffff880235186ee0
[ 2518.147090]  00000000000133c0 ffff880232163fd8 ffff880232163fd8 ffff8801faae30a0
[ 2518.147099]  0000000000000001 ffff880230f2b4d8 ffff8801faae30a0 
0000000000000002
[ 2518.147109] Call Trace:
[ 2518.147118]  [<ffffffff813fba8c>] ? __mutex_lock_common.isra.5+0xff/0x164
[ 2518.147125]  [<ffffffff81056a33>] ? should_resched+0x5/0x23
[ 2518.147133]  [<ffffffff813fb97a>] ? mutex_lock+0x1a/0x2d
[ 2518.147160]  [<ffffffffa0116d33>] ? rtl8169_close+0x49/0x14f [r8169]
[ 2518.147169]  [<ffffffff8133cb37>] ? __dev_close_many+0x85/0xb2
[ 2518.147176]  [<ffffffff8133cb94>] ? __dev_close+0x30/0x47
[ 2518.147184]  [<ffffffff813405c0>] ? dev_set_rx_mode+0x1d/0x29
[ 2518.147192]  [<ffffffff81340844>] ? __dev_change_flags+0x9d/0x118
[ 2518.147200]  [<ffffffff81340922>] ? dev_change_flags+0x12/0x42
[ 2518.147210]  [<ffffffff8138a769>] ? devinet_ioctl+0x285/0x554
[ 2518.147219]  [<ffffffff8132cdb3>] ? sock_do_ioctl+0x1b/0x36
[ 2518.147227]  [<ffffffff8132d1be>] ? sock_ioctl+0x205/0x212
[ 2518.147237]  [<ffffffff8110887d>] ? do_vfs_ioctl+0x459/0x49a
[ 2518.147245]  [<ffffffff810ee42b>] ? kmem_cache_alloc+0x86/0xea
[ 2518.147254]  [<ffffffff81108909>] ? sys_ioctl+0x4b/0x72
[ 2518.147263]  [<ffffffff81401ff9>] ? system_call_fastpath+0x16/0x1b
[ 2638.014954] INFO: task nmbd:2442 blocked for more than 120 seconds.
[ 2638.014963] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables 
this message.
[ 2638.014969] nmbd            D ffff88023fb933c0     0  2442      1 0x00000000
[ 2638.014980]  ffff880232d6f5d0 0000000000000082 0000000000000206 
ffff880235186ee0
[ 2638.014992]  00000000000133c0 ffff8802320fdfd8 ffff8802320fdfd8 ffff880232d6f5d0
[ 2638.015002]  ffff8802320fde5c ffffffff81868020 ffff880232d6f5d0 0000000000000002
[ 2638.015012] Call Trace:
[ 2638.015030]  [<ffffffff813fba8c>] ? __mutex_lock_common.isra.5+0xff/0x164
[ 2638.015041]  [<ffffffff813fb97a>] ? mutex_lock+0x1a/0x2d
[ 2638.015050]  [<ffffffff8135bb44>] ? netlink_trim+0x13/0x79
[ 2638.015059]  [<ffffffff81349f0c>] ? rtnetlink_rcv+0xe/0x28
[ 2638.015067]  [<ffffffff8135cffc>] ? netlink_unicast+0xe6/0x14e
[ 2638.015075]  [<ffffffff8135d265>] ? netlink_sendmsg+0x201/0x23c
[ 2638.015083]  [<ffffffff811091b1>] ? __pollwait+0xce/0xce
[ 2638.015093]  [<ffffffff8132e2a2>] ? sock_sendmsg+0xca/0xe7
[ 2638.015099]  [<ffffffff811091b1>] ? __pollwait+0xce/0xce
[ 2638.015108]  [<ffffffff8103d1da>] ? sys_time+0xc/0x39
[ 2638.015116]  [<ffffffff81011e5d>] ? emulate_vsyscall+0x191/0x270
[ 2638.015124]  [<ffffffff81056a33>] ? should_resched+0x5/0x23
[ 2638.015131]  [<ffffffff813fc545>] ? _cond_resched+0x6/0x1b
[ 2638.015138]  [<ffffffff8132cd08>] ? copy_from_user+0x18/0x30
[ 2638.015147]  [<ffffffff8132fb20>] ? sys_sendto+0xf7/0x137
[ 2638.015156]  [<ffffffff810fa2a0>] ? fd_install+0x25/0x4a
[ 2638.015166]  [<ffffffff81401ff9>] ? system_call_fastpath+0x16/0x1b
[ 2638.015196] INFO: task ksysguardd:3501 blocked for more than 120 seconds.
[ 2638.015201] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables 
this message.
[ 2638.015205] ksysguardd      D ffff88023fa133c0     0  3501   3436 0x00000000
[ 2638.015214]  ffff8802322ed8d0 0000000000000082 ffff880228522980 ffffffff81813020
[ 2638.015224]  00000000000133c0 ffff880225c7bfd8 ffff880225c7bfd8 ffff8802322ed8d0
[ 2638.015233]  00000008a01445fe ffffffff81868020 ffff8802322ed8d0 0000000000000002
[ 2638.015243] Call Trace:
[ 2638.015252]  [<ffffffff813fba8c>] ? __mutex_lock_common.isra.5+0xff/0x164
[ 2638.015262]  [<ffffffff813fb97a>] ? mutex_lock+0x1a/0x2d
[ 2638.015273]  [<ffffffff813daa7b>] ? wireless_dev_seq_start+0x12/0x5e
[ 2638.015282]  [<ffffffff811136bd>] ? seq_read+0x148/0x354
[ 2638.015291]  [<ffffffff81141a3e>] ? proc_reg_read+0x78/0x91
[ 2638.015299]  [<ffffffff810fb7e5>] ? vfs_read+0x9f/0xe6
[ 2638.015307]  [<ffffffff810fb871>] ? sys_read+0x45/0x6b
[ 2638.015315]  [<ffffffff81401ff9>] ? system_call_fastpath+0x16/0x1b
[ 2638.015339] INFO: task ifconfig:6734 blocked for more than 120 seconds.
[ 2638.015344] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables 
this message.
[ 2638.015348] ifconfig        D ffff88023fb933c0     0  6734   2598 0x00000000
[ 2638.015357]  ffff8801faae30a0 0000000000000086 0000000000000001 
ffff880235186ee0
[ 2638.015366]  00000000000133c0 ffff880232163fd8 ffff880232163fd8 ffff8801faae30a0
[ 2638.015375]  0000000000000001 ffff880230f2b4d8 ffff8801faae30a0 
0000000000000002
[ 2638.015384] Call Trace:
[ 2638.015393]  [<ffffffff813fba8c>] ? __mutex_lock_common.isra.5+0xff/0x164
[ 2638.015400]  [<ffffffff81056a33>] ? should_resched+0x5/0x23
[ 2638.015409]  [<ffffffff813fb97a>] ? mutex_lock+0x1a/0x2d
[ 2638.015434]  [<ffffffffa0116d33>] ? rtl8169_close+0x49/0x14f [r8169]
[ 2638.015443]  [<ffffffff8133cb37>] ? __dev_close_many+0x85/0xb2
[ 2638.015450]  [<ffffffff8133cb94>] ? __dev_close+0x30/0x47
[ 2638.015458]  [<ffffffff813405c0>] ? dev_set_rx_mode+0x1d/0x29
[ 2638.015466]  [<ffffffff81340844>] ? __dev_change_flags+0x9d/0x118
[ 2638.015474]  [<ffffffff81340922>] ? dev_change_flags+0x12/0x42
[ 2638.015484]  [<ffffffff8138a769>] ? devinet_ioctl+0x285/0x554
[ 2638.015492]  [<ffffffff8132cdb3>] ? sock_do_ioctl+0x1b/0x36
[ 2638.015500]  [<ffffffff8132d1be>] ? sock_ioctl+0x205/0x212
[ 2638.015510]  [<ffffffff8110887d>] ? do_vfs_ioctl+0x459/0x49a
[ 2638.015518]  [<ffffffff810ee42b>] ? kmem_cache_alloc+0x86/0xea
[ 2638.015527]  [<ffffffff81108909>] ? sys_ioctl+0x4b/0x72
[ 2638.015535]  [<ffffffff81401ff9>] ? system_call_fastpath+0x16/0x1b


Config:
http://mrutecki.pl/download/kernel/3.4-rc1/netdev_resume/config-3.4.0-rc1

full dmesg:
http://mrutecki.pl/download/kernel/3.4-rc1/netdev_resume/dmesg_netdev-3.4-
rc1.txt

lspci:
00:00.0 Host bridge: Intel Corporation 2nd Generation Core Processor Family 
DRAM Controller (rev 09)
00:02.0 VGA compatible controller: Intel Corporation 2nd Generation Core 
Processor Family Integrated Graphics Controller (rev 09)
00:16.0 Communication controller: Intel Corporation 6 Series/C200 Series 
Chipset Family MEI Controller #1 (rev 04)
00:1a.0 USB controller: Intel Corporation 6 Series/C200 Series Chipset Family 
USB Enhanced Host Controller #2 (rev 04)
00:1b.0 Audio device: Intel Corporation 6 Series/C200 Series Chipset Family 
High Definition Audio Controller (rev 04)
00:1c.0 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI 
Express Root Port 1 (rev b4)
00:1c.1 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI 
Express Root Port 2 (rev b4)
00:1c.2 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI 
Express Root Port 3 (rev b4)
00:1c.3 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI 
Express Root Port 4 (rev b4)
00:1c.7 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI 
Express Root Port 8 (rev b4)
00:1d.0 USB controller: Intel Corporation 6 Series/C200 Series Chipset Family 
USB Enhanced Host Controller #1 (rev 04)
00:1f.0 ISA bridge: Intel Corporation HM65 Express Chipset Family LPC 
Controller (rev 04)
00:1f.2 SATA controller: Intel Corporation 6 Series/C200 Series Chipset Family 
6 port SATA AHCI Controller (rev 04)
00:1f.3 SMBus: Intel Corporation 6 Series/C200 Series Chipset Family SMBus 
Controller (rev 04)
02:00.0 Ethernet controller: Realtek Semiconductor Co., Ltd. RTL8111/8168B PCI 
Express Gigabit Ethernet controller (rev 06)
03:00.0 System peripheral: Ricoh Co Ltd MMC/SD Host Controller (rev 07)
08:00.0 Network controller: Intel Corporation Centrino Wireless-N 1000

Regards
-- 
Maciej Rutecki
http://www.mrutecki.pl
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@...r.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/