From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1752459AbcEVQLI (ORCPT ); Sun, 22 May 2016 12:11:08 -0400 Received: from mail2.linuxsystems.it ([2.119.245.46]:37495 "EHLO mail2.linuxsystems.it" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1751199AbcEVQLE convert rfc822-to-8bit (ORCPT ); Sun, 22 May 2016 12:11:04 -0400 X-Greylist: delayed 485 seconds by postgrey-1.27 at vger.kernel.org; Sun, 22 May 2016 12:11:02 EDT From: =?iso-8859-1?Q?Niccol=F2_Belli?= To: "mailto:" , "mailto:" Subject: Re: (4.3.0) r8152: deadlock related to runtime =?iso-8859-1?Q?suspend=3F?= Date: Sun, 22 May 2016 18:02:42 +0200 MIME-Version: 1.0 Message-ID: <36d82fb8-20a8-4414-8aef-b2933b2059e2@linuxsystems.it> User-Agent: Trojita/v0.6-205-g141f049; Qt/5.6.0; xcb; Linux; Arch Linux Content-Type: text/plain; charset=utf-8; format=flowed Content-Transfer-Encoding: 8BIT Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org http://lkml.iu.edu/hypermail/linux/kernel/1512.0/04117.html http://lkml.iu.edu/hypermail/linux/kernel/1512.0/04831.html Same issue here with kernel 4.4.10 and 4.5.4 and r8153. Mine is an USB 3.0 Hub with Gigabit Ethernet, so the common point seems to be USB 3.0. For me it only happens when playing openmw. [12480.821609] INFO: task kworker/3:1:3221 blocked for more than 120 seconds. [12480.821630] Not tainted 4.4.10-1-ARCH #1 [12480.821633] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12480.821637] kworker/3:1 D ffff880172963c38 0 3221 2 0x00000000 [12480.821677] Workqueue: events rtl_work_func_t [r8152] [12480.821683] ffff880172963c38 0000000000000066 ffff880214b5b700 ffff8801e85c9b80 [12480.821690] ffff880172964000 ffff88003781a140 ffff88003781a200 0000000000000000 [12480.821695] 0000000000000000 ffff880172963c50 ffffffff815b430c ffff88003781a090 [12480.821701] Call Trace: [12480.821727] [] schedule+0x3c/0x90 [12480.821739] [] rpm_resume+0x118/0x6e0 [12480.821753] [] ? wake_atomic_t_function+0x60/0x60 [12480.821758] [] rpm_resume+0x36e/0x6e0 [12480.821762] [] ? dequeue_entity+0x265/0xa70 [12480.821770] [] ? find_next_bit+0x18/0x20 [12480.821777] [] ? next_online_pgdat+0x26/0x50 [12480.821787] [] __pm_runtime_resume+0x3f/0x60 [12480.821796] [] usb_autopm_get_interface+0x22/0x60 [12480.821808] [] rtl_work_func_t+0x70/0x420 [r8152] [12480.821821] [] ? finish_task_switch+0x78/0x1f0 [12480.821834] [] process_one_work+0x1e4/0x470 [12480.821840] [] worker_thread+0x48/0x4a0 [12480.821847] [] ? process_one_work+0x470/0x470 [12480.821852] [] ? process_one_work+0x470/0x470 [12480.821862] [] kthread+0xd8/0xf0 [12480.821867] [] ? kthread_worker_fn+0x170/0x170 [12480.821875] [] ret_from_fork+0x3f/0x70 [12480.821879] [] ? kthread_worker_fn+0x170/0x170 [12600.826910] INFO: task kworker/3:1:3221 blocked for more than 120 seconds. [12600.826934] Not tainted 4.4.10-1-ARCH #1 [12600.826938] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12600.826943] kworker/3:1 D ffff880172963c38 0 3221 2 0x00000000 [12600.826985] Workqueue: events rtl_work_func_t [r8152] [12600.826992] ffff880172963c38 0000000000000066 ffff880214b5b700 ffff8801e85c9b80 [12600.826999] ffff880172964000 ffff88003781a140 ffff88003781a200 0000000000000000 [12600.827004] 0000000000000000 ffff880172963c50 ffffffff815b430c ffff88003781a090 [12600.827009] Call Trace: [12600.827030] [] schedule+0x3c/0x90 [12600.827039] [] rpm_resume+0x118/0x6e0 [12600.827053] [] ? wake_atomic_t_function+0x60/0x60 [12600.827059] [] rpm_resume+0x36e/0x6e0 [12600.827063] [] ? dequeue_entity+0x265/0xa70 [12600.827069] [] ? find_next_bit+0x18/0x20 [12600.827075] [] ? next_online_pgdat+0x26/0x50 [12600.827082] [] __pm_runtime_resume+0x3f/0x60 [12600.827089] [] usb_autopm_get_interface+0x22/0x60 [12600.827098] [] rtl_work_func_t+0x70/0x420 [r8152] [12600.827104] [] ? finish_task_switch+0x78/0x1f0 [12600.827112] [] process_one_work+0x1e4/0x470 [12600.827122] [] worker_thread+0x48/0x4a0 [12600.827127] [] ? process_one_work+0x470/0x470 [12600.827132] [] ? process_one_work+0x470/0x470 [12600.827137] [] kthread+0xd8/0xf0 [12600.827143] [] ? kthread_worker_fn+0x170/0x170 [12600.827152] [] ret_from_fork+0x3f/0x70 [12600.827156] [] ? kthread_worker_fn+0x170/0x170 [12720.832405] INFO: task kworker/3:1:3221 blocked for more than 120 seconds. [12720.832426] Not tainted 4.4.10-1-ARCH #1 [12720.832429] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12720.832432] kworker/3:1 D ffff880172963c38 0 3221 2 0x00000000 [12720.832481] Workqueue: events rtl_work_func_t [r8152] [12720.832490] ffff880172963c38 0000000000000066 ffff880214b5b700 ffff8801e85c9b80 [12720.832497] ffff880172964000 ffff88003781a140 ffff88003781a200 0000000000000000 [12720.832502] 0000000000000000 ffff880172963c50 ffffffff815b430c ffff88003781a090 [12720.832507] Call Trace: [12720.832541] [] schedule+0x3c/0x90 [12720.832559] [] rpm_resume+0x118/0x6e0 [12720.832579] [] ? wake_atomic_t_function+0x60/0x60 [12720.832585] [] rpm_resume+0x36e/0x6e0 [12720.832592] [] ? dequeue_entity+0x265/0xa70 [12720.832599] [] ? find_next_bit+0x18/0x20 [12720.832611] [] ? next_online_pgdat+0x26/0x50 [12720.832621] [] __pm_runtime_resume+0x3f/0x60 [12720.832635] [] usb_autopm_get_interface+0x22/0x60 [12720.832651] [] rtl_work_func_t+0x70/0x420 [r8152] [12720.832659] [] ? finish_task_switch+0x78/0x1f0 [12720.832680] [] process_one_work+0x1e4/0x470 [12720.832690] [] worker_thread+0x48/0x4a0 [12720.832697] [] ? process_one_work+0x470/0x470 [12720.832705] [] ? process_one_work+0x470/0x470 [12720.832712] [] kthread+0xd8/0xf0 [12720.832717] [] ? kthread_worker_fn+0x170/0x170 [12720.832723] [] ret_from_fork+0x3f/0x70 [12720.832727] [] ? kthread_worker_fn+0x170/0x170 [12840.837567] INFO: task avahi-daemon:732 blocked for more than 120 seconds. [12840.837578] Not tainted 4.4.10-1-ARCH #1 [12840.837583] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12840.837586] avahi-daemon D ffff88020ddfbc18 0 732 1 0x00000000 [12840.837600] ffff88020ddfbc18 0000000000000200 ffff880212801b80 ffff88020fe5c4c0 [12840.837606] ffff88020ddfc000 ffffffff818b7b04 ffff88020fe5c4c0 00000000ffffffff [12840.837610] ffffffff818b7b08 ffff88020ddfbc30 ffffffff815b430c ffffffff818b7b00 [12840.837615] Call Trace: [12840.837638] [] schedule+0x3c/0x90 [12840.837644] [] schedule_preempt_disabled+0x15/0x20 [12840.837649] [] __mutex_lock_slowpath+0xce/0x140 [12840.837652] [] mutex_lock+0x17/0x30 [12840.837660] [] rtnl_lock+0x15/0x20 [12840.837666] [] do_ipv6_setsockopt.isra.4+0x167/0x1140 [12840.837679] [] ? __wake_up_sync_key+0x50/0x60 [12840.837685] [] ? sock_def_readable+0x6f/0x80 [12840.837689] [] ? unix_dgram_sendmsg+0x361/0x710 [12840.837694] [] ? sock_sendmsg+0x17/0x30 [12840.837698] [] ? SyS_sendto+0x140/0x190 [12840.837703] [] ipv6_setsockopt+0x53/0xc0 [12840.837711] [] udpv6_setsockopt+0x1b/0x30 [12840.837714] [] sock_common_setsockopt+0x14/0x20 [12840.837717] [] SyS_setsockopt+0x72/0xe0 [12840.837722] [] entry_SYSCALL_64_fastpath+0x12/0x6d [12840.837745] INFO: task Qt bearer threa:1161 blocked for more than 120 seconds. [12840.837748] Not tainted 4.4.10-1-ARCH #1 [12840.837750] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12840.837752] Qt bearer threa D ffff8801ebc5fc78 0 1161 999 0x00000000 [12840.837757] ffff8801ebc5fc78 0000000000000008 ffff8800c0a5a940 ffff8801ebc60000 [12840.837761] ffff8801ebc60000 ffffffff818b7b04 ffff8801ebc60000 00000000ffffffff [12840.837765] ffffffff818b7b08 ffff8801ebc5fc90 ffffffff815b430c ffffffff818b7b00 [12840.837769] Call Trace: [12840.837776] [] schedule+0x3c/0x90 [12840.837781] [] schedule_preempt_disabled+0x15/0x20 [12840.837783] [] __mutex_lock_slowpath+0xce/0x140 [12840.837787] [] mutex_lock+0x17/0x30 [12840.837791] [] rtnetlink_rcv+0x19/0x30 [12840.837797] [] netlink_unicast+0x128/0x1d0 [12840.837801] [] netlink_sendmsg+0x4ed/0x600 [12840.837805] [] ? netlink_insert+0x1dc/0x370 [12840.837809] [] sock_sendmsg+0x17/0x30 [12840.837812] [] SyS_sendto+0x140/0x190 [12840.837816] [] ? SyS_getsockname+0xaa/0xe0 [12840.837823] [] ? sock_alloc_file+0xa5/0x130 [12840.837827] [] ? fd_install+0x25/0x30 [12840.837830] [] ? SyS_socket+0x9f/0xf0 [12840.837835] [] entry_SYSCALL_64_fastpath+0x12/0x6d [12840.837846] INFO: task mission-control:1050 blocked for more than 120 seconds. [12840.837848] Not tainted 4.4.10-1-ARCH #1 [12840.837850] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12840.837852] mission-control D ffff8800c096bc78 0 1050 940 0x00000000 [12840.837859] ffff8800c096bc78 0000000100000000 ffff8800b5aa6e00 ffff8802133d8dc0 [12840.837863] ffff8800c096c000 ffffffff818b7b04 ffff8802133d8dc0 00000000ffffffff [12840.837867] ffffffff818b7b08 ffff8800c096bc90 ffffffff815b430c ffffffff818b7b00 [12840.837871] Call Trace: [12840.837878] [] schedule+0x3c/0x90 [12840.837882] [] schedule_preempt_disabled+0x15/0x20 [12840.837886] [] __mutex_lock_slowpath+0xce/0x140 [12840.837889] [] mutex_lock+0x17/0x30 [12840.837895] [] rtnetlink_rcv+0x19/0x30 [12840.837899] [] netlink_unicast+0x128/0x1d0 [12840.837904] [] netlink_sendmsg+0x4ed/0x600 [12840.837909] [] sock_sendmsg+0x17/0x30 [12840.837913] [] SyS_sendto+0x140/0x190 [12840.837923] [] ? vfs_read+0x86/0x130 [12840.837927] [] ? SyS_read+0xac/0xc0 [12840.837933] [] entry_SYSCALL_64_fastpath+0x12/0x6d [12840.837952] INFO: task Qt bearer threa:1124 blocked for more than 120 seconds. [12840.837955] Not tainted 4.4.10-1-ARCH #1 [12840.837957] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12840.837959] Qt bearer threa D ffff8800bb26fc78 0 1124 999 0x00000000 [12840.837964] ffff8800bb26fc78 0000000000000008 ffff8801ebc60000 ffff88020fd50000 [12840.837968] ffff8800bb270000 ffffffff818b7b04 ffff88020fd50000 00000000ffffffff [12840.837971] ffffffff818b7b08 ffff8800bb26fc90 ffffffff815b430c ffffffff818b7b00 [12840.837975] Call Trace: [12840.837981] [] schedule+0x3c/0x90 [12840.837985] [] schedule_preempt_disabled+0x15/0x20 [12840.837987] [] __mutex_lock_slowpath+0xce/0x140 [12840.837990] [] mutex_lock+0x17/0x30 [12840.837993] [] rtnetlink_rcv+0x19/0x30 [12840.837997] [] netlink_unicast+0x128/0x1d0 [12840.838001] [] netlink_sendmsg+0x4ed/0x600 [12840.838005] [] ? netlink_insert+0x1dc/0x370 [12840.838009] [] sock_sendmsg+0x17/0x30 [12840.838012] [] SyS_sendto+0x140/0x190 [12840.838015] [] ? SyS_getsockname+0xaa/0xe0 [12840.838022] [] ? sock_alloc_file+0xa5/0x130 [12840.838027] [] ? fd_install+0x25/0x30 [12840.838030] [] ? SyS_socket+0x9f/0xf0 [12840.838034] [] entry_SYSCALL_64_fastpath+0x12/0x6d [12840.838075] INFO: task Qt bearer threa:1349 blocked for more than 120 seconds. [12840.838082] Not tainted 4.4.10-1-ARCH #1 [12840.838083] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12840.838087] Qt bearer threa D ffff8801d8c93c78 0 1349 1108 0x00000000 [12840.838091] ffff8801d8c93c78 0000000000000008 ffff880212801b80 ffff8801d8cf3700 [12840.838094] ffff8801d8c94000 ffffffff818b7b04 ffff8801d8cf3700 00000000ffffffff [12840.838099] ffffffff818b7b08 ffff8801d8c93c90 ffffffff815b430c ffffffff818b7b00 [12840.838104] Call Trace: [12840.838109] [] schedule+0x3c/0x90 [12840.838114] [] schedule_preempt_disabled+0x15/0x20 [12840.838118] [] __mutex_lock_slowpath+0xce/0x140 [12840.838120] [] mutex_lock+0x17/0x30 [12840.838123] [] rtnetlink_rcv+0x19/0x30 [12840.838130] [] netlink_unicast+0x128/0x1d0 [12840.838134] [] netlink_sendmsg+0x4ed/0x600 [12840.838139] [] ? netlink_insert+0x1dc/0x370 [12840.838142] [] sock_sendmsg+0x17/0x30 [12840.838146] [] SyS_sendto+0x140/0x190 [12840.838150] [] ? SyS_getsockname+0xaa/0xe0 [12840.838155] [] ? sock_alloc_file+0xa5/0x130 [12840.838161] [] ? fd_install+0x25/0x30 [12840.838164] [] ? SyS_socket+0x9f/0xf0 [12840.838169] [] entry_SYSCALL_64_fastpath+0x12/0x6d [12840.838176] INFO: task Qt bearer threa:1356 blocked for more than 120 seconds. [12840.838179] Not tainted 4.4.10-1-ARCH #1 [12840.838180] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12840.838183] Qt bearer threa D ffff8800a8487c78 0 1356 1108 0x00000000 [12840.838187] ffff8800a8487c78 0000000000000008 ffff8801d8cf6040 ffff8800aabf5280 [12840.838192] ffff8800a8488000 ffffffff818b7b04 ffff8800aabf5280 00000000ffffffff [12840.838197] ffffffff818b7b08 ffff8800a8487c90 ffffffff815b430c ffffffff818b7b00 [12840.838202] Call Trace: [12840.838209] [] schedule+0x3c/0x90 [12840.838213] [] schedule_preempt_disabled+0x15/0x20 [12840.838217] [] __mutex_lock_slowpath+0xce/0x140 [12840.838220] [] mutex_lock+0x17/0x30 [12840.838223] [] rtnetlink_rcv+0x19/0x30 [12840.838228] [] netlink_unicast+0x128/0x1d0 [12840.838231] [] netlink_sendmsg+0x4ed/0x600 [12840.838234] [] ? netlink_insert+0x1dc/0x370 [12840.838238] [] sock_sendmsg+0x17/0x30 [12840.838240] [] SyS_sendto+0x140/0x190 [12840.838244] [] ? SyS_getsockname+0xaa/0xe0 [12840.838248] [] ? sock_alloc_file+0xa5/0x130 [12840.838252] [] ? fd_install+0x25/0x30 [12840.838255] [] ? SyS_socket+0x9f/0xf0 [12840.838260] [] entry_SYSCALL_64_fastpath+0x12/0x6d [12840.838273] INFO: task Qt bearer threa:1361 blocked for more than 120 seconds. [12840.838276] Not tainted 4.4.10-1-ARCH #1 [12840.838278] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [12840.838280] Qt bearer threa D ffff8801d8c6bc78 0 1361 1108 0x00000000 [12840.838284] ffff8801d8c6bc78 0000000000000008 ffff8800aabf44c0 ffff8801d8cf6040 [12840.838287] ffff8801d8c6c000 ffffffff818b7b04 ffff8801d8cf6040 00000000ffffffff [12840.838291] ffffffff818b7b08 ffff8801d8c6bc90 ffffffff815b430c ffffffff818b7b00 [12840.838296] Call Trace: [12840.838301] [] schedule+0x3c/0x90 [12840.838305] [] schedule_preempt_disabled+0x15/0x20 [12840.838308] [] __mutex_lock_slowpath+0xce/0x140 [12840.838312] [] mutex_lock+0x17/0x30 [12840.838315] [] rtnetlink_rcv+0x19/0x30 [12840.838318] [] netlink_unicast+0x128/0x1d0 [12840.838322] [] netlink_sendmsg+0x4ed/0x600 [12840.838325] [] ? netlink_insert+0x1dc/0x370 [12840.838328] [] sock_sendmsg+0x17/0x30 [12840.838332] [] SyS_sendto+0x140/0x190 [12840.838335] [] ? SyS_getsockname+0xaa/0xe0 [12840.838340] [] ? sock_alloc_file+0xa5/0x130 [12840.838347] [] ? fd_install+0x25/0x30 [12840.838350] [] ? SyS_socket+0x9f/0xf0 [12840.838355] [] entry_SYSCALL_64_fastpath+0x12/0x6d Bus 002 Device 003: ID 0bda:8153 Realtek Semiconductor Corp. Couldn't open device, some information will be missing Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 3.00 bDeviceClass 0 bDeviceSubClass 0 bDeviceProtocol 0 bMaxPacketSize0 9 idVendor 0x0bda Realtek Semiconductor Corp. idProduct 0x8153 bcdDevice 30.00 iManufacturer 1 iProduct 2 iSerial 3 bNumConfigurations 2 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 57 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xa0 (Bus Powered) Remote Wakeup MaxPower 36mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 3 bInterfaceClass 255 Vendor Specific Class bInterfaceSubClass 255 Vendor Specific Subclass bInterfaceProtocol 0 iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0400 1x 1024 bytes bInterval 0 bMaxBurst 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x02 EP 2 OUT bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0400 1x 1024 bytes bInterval 0 bMaxBurst 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x83 EP 3 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0002 1x 2 bytes bInterval 8 bMaxBurst 0 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 98 bNumInterfaces 2 bConfigurationValue 2 iConfiguration 0 bmAttributes 0xa0 (Bus Powered) Remote Wakeup MaxPower 36mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 2 Communications bInterfaceSubClass 6 Ethernet Networking bInterfaceProtocol 0 iInterface 5 CDC Header: bcdCDC 1.10 CDC Union: bMasterInterface 0 bSlaveInterface 1 CDC Ethernet: iMacAddress 3 (??) bmEthernetStatistics 0x00000000 wMaxSegmentSize 1514 wNumberMCFilters 0x0000 bNumberPowerFilters 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x83 EP 3 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0010 1x 16 bytes bInterval 8 bMaxBurst 0 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 1 bAlternateSetting 0 bNumEndpoints 0 bInterfaceClass 10 CDC Data bInterfaceSubClass 0 bInterfaceProtocol 0 iInterface 0 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 1 bAlternateSetting 1 bNumEndpoints 2 bInterfaceClass 10 CDC Data bInterfaceSubClass 0 bInterfaceProtocol 0 iInterface 4 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0400 1x 1024 bytes bInterval 0 bMaxBurst 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x02 EP 2 OUT bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0400 1x 1024 bytes bInterval 0 bMaxBurst 0