* Re: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume [not found] <201204022157.05598.maciej.rutecki@gmail.com> @ 2012-04-03 8:07 ` Johannes Berg 2012-04-05 10:32 ` Stanislaw Gruszka 0 siblings, 1 reply; 8+ messages in thread From: Johannes Berg @ 2012-04-03 8:07 UTC (permalink / raw) To: maciej.rutecki Cc: Linux Kernel Mailing List, Rafael J. Wysocki, linux-wireless, netdev -ilw +netdev I have no idea what this could be, but I'm almost certain it's not caused by our wireless driver. Looks more like rtl8169 is to blame. johannes On Mon, 2012-04-02 at 21:57 +0200, Maciej Rutecki wrote: > 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 ^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume 2012-04-03 8:07 ` [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume Johannes Berg @ 2012-04-05 10:32 ` Stanislaw Gruszka 2012-04-05 18:37 ` Maciej Rutecki 0 siblings, 1 reply; 8+ messages in thread From: Stanislaw Gruszka @ 2012-04-05 10:32 UTC (permalink / raw) To: Johannes Berg, maciej.rutecki Cc: Linux Kernel Mailing List, Rafael J. Wysocki, linux-wireless, netdev On Tue, Apr 03, 2012 at 10:07:49AM +0200, Johannes Berg wrote: > -ilw > +netdev > > I have no idea what this could be, but I'm almost certain it's not > caused by our wireless driver. Looks more like rtl8169 is to blame. I'm not sure. Looks like there is deadlock or infinite loop in process that holds rtnl lock. This can be wireless driver, wired driver or generic network code bug. Maciej, please recompile with CONFIG_LOCKDEP and check if it will not find any deadlock. If not, when problem will happen please do echo w > /proc/sysrq-trigger dmesg -c > dmesg1.txt echo t > /proc/sysrq-trigger dmesg > dmesg2.txt and provide those files. Additionally there are also lot in dmesg: netlink: 140 bytes leftover after parsing attributes. This need to be investigated as well. Perhaps you could modify error message, to show what netlink messages/attributes are processed. Thanks Stanislaw ^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume 2012-04-05 10:32 ` Stanislaw Gruszka @ 2012-04-05 18:37 ` Maciej Rutecki [not found] ` <201204052037.37497.maciej.rutecki-Re5JQEeQqe8AvxtiuMwx3w@public.gmane.org> 0 siblings, 1 reply; 8+ messages in thread From: Maciej Rutecki @ 2012-04-05 18:37 UTC (permalink / raw) To: Stanislaw Gruszka Cc: Johannes Berg, Linux Kernel Mailing List, Rafael J. Wysocki, linux-wireless, netdev On czwartek, 5 kwietnia 2012 o 12:32:54 Stanislaw Gruszka wrote: > On Tue, Apr 03, 2012 at 10:07:49AM +0200, Johannes Berg wrote: > > -ilw > > +netdev > > > > I have no idea what this could be, but I'm almost certain it's not > > caused by our wireless driver. Looks more like rtl8169 is to blame. > > I'm not sure. Looks like there is deadlock or infinite loop in process > that holds rtnl lock. This can be wireless driver, wired driver or > generic network code bug. > > Maciej, please recompile with CONFIG_LOCKDEP and check if it will not > find any deadlock. If not, when problem will happen please do Did you mean "CONFIG_LOCKDEP_SUPPORT"? $ cat /boot/config-3.4.0-rc1 | grep CONFIG_LOCKDEP CONFIG_LOCKDEP_SUPPORT=y > > echo w > /proc/sysrq-trigger > dmesg -c > dmesg1.txt http://mrutecki.pl/download/kernel/3.4-rc1/netdev_resume/dmesg1.txt > echo t > /proc/sysrq-trigger > dmesg > dmesg2.txt http://mrutecki.pl/download/kernel/3.4-rc1/netdev_resume/dmesg2.txt > > and provide those files. > > Additionally there are also lot in dmesg: > > netlink: 140 bytes leftover after parsing attributes. > > This need to be investigated as well. Perhaps you could modify error > message, to show what netlink messages/attributes are processed. > > Thanks > Stanislaw BTW. From tomorrow till Tuesday I'll be on vacation, so until then I could respond to the e-mails. Regards -- Maciej Rutecki http://www.mrutecki.pl ^ permalink raw reply [flat|nested] 8+ messages in thread
[parent not found: <201204052037.37497.maciej.rutecki-Re5JQEeQqe8AvxtiuMwx3w@public.gmane.org>]
* Re: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume [not found] ` <201204052037.37497.maciej.rutecki-Re5JQEeQqe8AvxtiuMwx3w@public.gmane.org> @ 2012-04-05 18:56 ` Johannes Berg 2012-04-05 20:08 ` Maciej Rutecki 0 siblings, 1 reply; 8+ messages in thread From: Johannes Berg @ 2012-04-05 18:56 UTC (permalink / raw) To: maciej.rutecki-Re5JQEeQqe8AvxtiuMwx3w Cc: Stanislaw Gruszka, Linux Kernel Mailing List, Rafael J. Wysocki, linux-wireless-u79uwXL29TY76Z2rM5mHXA, netdev On Thu, 2012-04-05 at 20:37 +0200, Maciej Rutecki wrote: > On czwartek, 5 kwietnia 2012 o 12:32:54 Stanislaw Gruszka wrote: > > On Tue, Apr 03, 2012 at 10:07:49AM +0200, Johannes Berg wrote: > > > -ilw > > > +netdev > > > > > > I have no idea what this could be, but I'm almost certain it's not > > > caused by our wireless driver. Looks more like rtl8169 is to blame. > > > > I'm not sure. Looks like there is deadlock or infinite loop in process > > that holds rtnl lock. This can be wireless driver, wired driver or > > generic network code bug. > > > > Maciej, please recompile with CONFIG_LOCKDEP and check if it will not > > find any deadlock. If not, when problem will happen please do > > Did you mean "CONFIG_LOCKDEP_SUPPORT"? No, _SUPPORT means you could enable CONFIG_LOCKDEP, but we would like to see this with CONFIG_LOCKDEP enabled. johannes -- To unsubscribe from this list: send the line "unsubscribe linux-wireless" in the body of a message to majordomo-u79uwXL29TY76Z2rM5mHXA@public.gmane.org More majordomo info at http://vger.kernel.org/majordomo-info.html ^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume 2012-04-05 18:56 ` Johannes Berg @ 2012-04-05 20:08 ` Maciej Rutecki 2012-04-05 20:23 ` Johannes Berg 0 siblings, 1 reply; 8+ messages in thread From: Maciej Rutecki @ 2012-04-05 20:08 UTC (permalink / raw) To: Johannes Berg Cc: Stanislaw Gruszka, Linux Kernel Mailing List, Rafael J. Wysocki, linux-wireless, netdev On czwartek, 5 kwietnia 2012 o 20:56:23 Johannes Berg wrote: > On Thu, 2012-04-05 at 20:37 +0200, Maciej Rutecki wrote: > > On czwartek, 5 kwietnia 2012 o 12:32:54 Stanislaw Gruszka wrote: > > > On Tue, Apr 03, 2012 at 10:07:49AM +0200, Johannes Berg wrote: > > > > -ilw > > > > +netdev > > > > > > > > I have no idea what this could be, but I'm almost certain it's not > > > > caused by our wireless driver. Looks more like rtl8169 is to blame. > > > > > > I'm not sure. Looks like there is deadlock or infinite loop in process > > > that holds rtnl lock. This can be wireless driver, wired driver or > > > generic network code bug. > > > > > > Maciej, please recompile with CONFIG_LOCKDEP and check if it will not > > > find any deadlock. If not, when problem will happen please do > > > > Did you mean "CONFIG_LOCKDEP_SUPPORT"? > > No, _SUPPORT means you could enable CONFIG_LOCKDEP, but we would like to > see this with CONFIG_LOCKDEP enabled. > > johannes Dmesg when CONFIG_LOCKDEP is set: http://mrutecki.pl/download/kernel/3.4-rc1/netdev_resume/dmesg-lockdep.txt Regards -- Maciej Rutecki http://www.mrutecki.pl ^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume 2012-04-05 20:08 ` Maciej Rutecki @ 2012-04-05 20:23 ` Johannes Berg 2012-04-05 21:01 ` Francois Romieu 0 siblings, 1 reply; 8+ messages in thread From: Johannes Berg @ 2012-04-05 20:23 UTC (permalink / raw) To: maciej.rutecki Cc: Stanislaw Gruszka, Linux Kernel Mailing List, Rafael J. Wysocki, linux-wireless, netdev, Francois Romieu On Thu, 2012-04-05 at 22:08 +0200, Maciej Rutecki wrote: > Dmesg when CONFIG_LOCKDEP is set: > > http://mrutecki.pl/download/kernel/3.4-rc1/netdev_resume/dmesg-lockdep.txt Looks like rtl8169's fault: [ 240.541718] 2 locks held by ifconfig/4964: [ 240.541722] #0: (rtnl_mutex){+.+.+.}, at: [<ffffffff8138c86c>] rtnl_lock+0x12/0x14 [ 240.541737] #1: (&tp->wk.mutex){+.+...}, at: [<ffffffffa00c1556>] rtl_lock_work+0x12/0x14 [r8169] [ 360.350345] INFO: task nmbd:2378 blocked for more than 120 seconds. [ 360.353186] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 360.356124] nmbd D ffffffff81a76ab8 0 2378 1 0x00000000 [ 360.359078] ffff88022f573ab8 0000000000000046 ffff880233aa8040 ffff88022f573fd8 [ 360.362098] ffff88022f573fd8 0000000000013a40 ffff8802351b8040 ffff880233aa8040 [ 360.365170] ffffffff814532aa 000000001e3e7680 ffffffff81a76ab0 ffff880233aa8040 [ 360.368208] Call Trace: [ 360.371129] [<ffffffff814532aa>] ? __mutex_lock_common+0x230/0x36e [ 360.374159] [<ffffffff814543ca>] schedule+0x5f/0x61 [ 360.377195] [<ffffffff8145458e>] schedule_preempt_disabled+0x9/0xb [ 360.380240] [<ffffffff814532b7>] __mutex_lock_common+0x23d/0x36e [ 360.383262] [<ffffffff8138c86c>] ? rtnl_lock+0x12/0x14 [ 360.386260] [<ffffffff8139fe60>] ? netlink_lookup+0x2b/0x7e [ 360.389279] [<ffffffff8138c86c>] ? rtnl_lock+0x12/0x14 [ 360.392352] [<ffffffff814534a7>] mutex_lock_nested+0x2a/0x31 [ 360.395393] [<ffffffff8138c86c>] rtnl_lock+0x12/0x14 [ 360.398376] [<ffffffff8138c87f>] rtnetlink_rcv+0x11/0x28 [ 360.401292] [<ffffffff813a1d9a>] netlink_unicast+0xe7/0x151 [ 360.404192] [<ffffffff813a2002>] netlink_sendmsg+0x1fe/0x236 [ 360.407089] [<ffffffff8136cd79>] sock_sendmsg+0xea/0x10d [ 360.409971] [<ffffffff81080679>] ? lock_acquire+0xef/0xfe [ 360.412825] [<ffffffff810e6bf8>] ? might_fault+0x40/0x90 [ 360.415640] [<ffffffff81080679>] ? lock_acquire+0xef/0xfe [ 360.418447] [<ffffffff810e6bf8>] ? might_fault+0x40/0x90 [ 360.421239] [<ffffffff8108057e>] ? lock_release+0x175/0x181 [ 360.424039] [<ffffffff810e6c41>] ? might_fault+0x89/0x90 [ 360.426896] [<ffffffff8136b5ec>] ? copy_from_user+0x2a/0x2c [ 360.429661] [<ffffffff8136e9e5>] sys_sendto+0x12a/0x16c [ 360.432430] [<ffffffff8107d74f>] ? trace_hardirqs_off_caller +0x33/0x90 [ 360.435219] [<ffffffff81455515>] ? retint_swapgs+0x13/0x1b [ 360.437978] [<ffffffff81080a85>] ? trace_hardirqs_on_caller +0x121/0x158 [ 360.440795] [<ffffffff8121250e>] ? trace_hardirqs_on_thunk+0x3a/0x3f [ 360.443868] [<ffffffff8145acb9>] system_call_fastpath+0x16/0x1b johannes ^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume 2012-04-05 20:23 ` Johannes Berg @ 2012-04-05 21:01 ` Francois Romieu 2012-04-11 19:37 ` Maciej Rutecki 0 siblings, 1 reply; 8+ messages in thread From: Francois Romieu @ 2012-04-05 21:01 UTC (permalink / raw) To: Johannes Berg Cc: maciej.rutecki, Stanislaw Gruszka, Linux Kernel Mailing List, Rafael J. Wysocki, linux-wireless, netdev Johannes Berg <johannes@sipsolutions.net> : > On Thu, 2012-04-05 at 22:08 +0200, Maciej Rutecki wrote: > > > Dmesg when CONFIG_LOCKDEP is set: > > > > http://mrutecki.pl/download/kernel/3.4-rc1/netdev_resume/dmesg-lockdep.txt > > Looks like rtl8169's fault: Fix is on the way to mainline, see http://patchwork.ozlabs.org/patch/150541 -- Ueimor Will code drivers for food. ^ permalink raw reply [flat|nested] 8+ messages in thread
* Re: [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume 2012-04-05 21:01 ` Francois Romieu @ 2012-04-11 19:37 ` Maciej Rutecki 0 siblings, 0 replies; 8+ messages in thread From: Maciej Rutecki @ 2012-04-11 19:37 UTC (permalink / raw) To: Francois Romieu Cc: Johannes Berg, Stanislaw Gruszka, Linux Kernel Mailing List, Rafael J. Wysocki, linux-wireless, netdev On czwartek, 5 kwietnia 2012 o 23:01:22 Francois Romieu wrote: > Johannes Berg <johannes@sipsolutions.net> : > > On Thu, 2012-04-05 at 22:08 +0200, Maciej Rutecki wrote: > > > Dmesg when CONFIG_LOCKDEP is set: > > > > > > http://mrutecki.pl/download/kernel/3.4-rc1/netdev_resume/dmesg-lockdep. > > > txt > > > > Looks like rtl8169's fault: > Fix is on the way to mainline, see http://patchwork.ozlabs.org/patch/150541 Seems be resolved in -rc2. Only in dmesg still I have messages "netlink: 140 bytes leftover after parsing attributes.". It seems be another issue. Thanks -- Maciej Rutecki http://www.mrutecki.pl ^ permalink raw reply [flat|nested] 8+ messages in thread
end of thread, other threads:[~2012-04-11 19:37 UTC | newest]
Thread overview: 8+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
[not found] <201204022157.05598.maciej.rutecki@gmail.com>
2012-04-03 8:07 ` [3.4-rc1][Regression][network wireless] 'task xxxx blocked for more than 120 seconds' after resume Johannes Berg
2012-04-05 10:32 ` Stanislaw Gruszka
2012-04-05 18:37 ` Maciej Rutecki
[not found] ` <201204052037.37497.maciej.rutecki-Re5JQEeQqe8AvxtiuMwx3w@public.gmane.org>
2012-04-05 18:56 ` Johannes Berg
2012-04-05 20:08 ` Maciej Rutecki
2012-04-05 20:23 ` Johannes Berg
2012-04-05 21:01 ` Francois Romieu
2012-04-11 19:37 ` Maciej Rutecki
This is a public inbox, see mirroring instructions for how to clone and mirror all data and code used for this inbox; as well as URLs for NNTP newsgroup(s).