On Wed, Apr 02 2014, Alan Stern wrote:
> Below is 0aa2832dd0d9d860 back-ported to 3.9. Please try testing a 3.9
> kernel with this patch installed (and also the first diagnostic patch,
> if it applies with no errors), with CONFIG_USB_SUSPEND _enabled_ and
> the mouse plugged into the rear port.
>
> If this combination works, then maybe you can do yet another
> git-bisect. Search between 3.9 and 0aa2832dd0d9d860, and at each step
> merge this patch into the kernel you are testing before you build it.
No, it does not work. Please find attached the log.
--
Peter
[ 207.379865] PM: Syncing filesystems ... done.
[ 207.446504] PM: Preparing system for mem sleep
[ 207.453621] ohci_hcd 0000:00:12.1: Dequeue: ffff880122086cc0 count 1
[ 207.454080] ohci_hcd 0000:00:12.1: IRQ: count 1 intr-en 8000005e intr-stat 24 frame 25e6
[ 207.454119] ohci_hcd 0000:00:12.1: Giveback: ffff880122086cc0 count 1
[ 207.467685] Freezing user space processes ... (elapsed 0.01 seconds) done.
[ 207.479603] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[ 207.490595] PM: Entering mem sleep
[ 207.491304] usb 4-3: usb suspend, wakeup 0
[ 207.491460] ohci_hcd 0000:00:13.1: Dequeue: ffff880122086300 count 1
[ 207.491474] sd 2:0:0:0: [sdb] Synchronizing SCSI cache
[ 207.491546] usb 2-3: usb suspend, wakeup 0
[ 207.491594] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[ 207.491799] usb usb7: usb auto-resume
[ 207.491829] ohci_hcd 0000:00:14.5: resume root hub
[ 207.491879] usb usb5: usb auto-resume
[ 207.491902] ohci_hcd 0000:00:13.0: resume root hub
[ 207.492043] ohci_hcd 0000:00:13.1: IRQ: count 1 intr-en 8000005e intr-stat 24 frame 259e
[ 207.492207] ohci_hcd 0000:00:13.1: Giveback: ffff880122086300 count 1
[ 207.492439] hub 4-0:1.0: hub_suspend
[ 207.492482] usb usb4: bus suspend, wakeup 0
[ 207.492507] ohci_hcd 0000:00:12.1: suspend root hub
[ 207.492603] usb usb3: usb auto-resume
[ 207.492665] ohci_hcd 0000:00:12.0: resume root hub
[ 207.492754] hub 2-0:1.0: hub_suspend
[ 207.492788] usb usb2: bus suspend, wakeup 0
[ 207.492810] ehci-pci 0000:00:13.2: suspend root hub
[ 207.492875] usb usb1: usb auto-resume
[ 207.492967] ehci-pci 0000:00:12.2: resume root hub
[ 207.493059] usb 6-3: usb suspend, wakeup 1
[ 210.437072] r8169 0000:02:00.0 eth0: link up
[ 212.542512] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 212.566103] ata3.00: configured for UDMA/133
[ 212.570950] sd 2:0:0:0: [sdb] Starting disk
[ 213.739414] ohci_hcd 0000:00:12.1: Dequeue: ffff8800cc7ed180 count 1
[ 213.794366] ohci_hcd 0000:00:13.1: Dequeue: ffff8800cfafb840 count 1
[ 213.796714] ohci_hcd 0000:00:13.1: IRQ: count 1 intr-en 8000005a intr-stat 24 frame 25f0
[ 214.270943] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 214.288910] ata1.00: configured for UDMA/133
[ 214.293936] sd 0:0:0:0: [sda] Starting disk
[ 223.747318] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 223.811297] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 233.754254] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 233.818228] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 243.761180] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 243.825161] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 253.768112] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 253.832091] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 263.775042] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 263.839021] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 273.781972] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 273.845952] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 283.788901] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 283.852879] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 293.795830] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 293.859810] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 303.802759] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 303.866746] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 313.809685] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 313.873665] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 323.816613] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 323.880592] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 333.823531] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 333.887519] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 343.830475] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 343.894445] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 353.837392] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 353.901372] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 360.883114] INFO: task s2ram:2883 blocked for more than 120 seconds.
[ 360.887265] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 360.891570] s2ram D ffff88012fc53500 0 2883 2705 0x00000000
[ 360.895971] ffff880127fd3c70 0000000000000082 0000000000013500 ffff880127fd3fd8
[ 360.900464] ffff880127fd3fd8 0000000000013500 ffff8800cfab2380 7fffffffffffffff
[ 360.904950] ffff8801226ab4f0 ffff8801226ab4e8 ffff8800cfab2380 0000000000000000
[ 360.906540] Call Trace:
[ 360.907803] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 360.909056] [<ffffffff815b8179>] schedule_timeout+0x219/0x320
[ 360.910314] [<ffffffff8143e4fc>] ? input_pass_values.part.4+0x5c/0x170
[ 360.911578] [<ffffffff8143feb5>] ? input_dev_release_keys+0xa5/0xb0
[ 360.912863] [<ffffffff815b9244>] wait_for_completion+0x94/0x100
[ 360.914134] [<ffffffff810763f0>] ? wake_up_state+0x10/0x10
[ 360.915414] [<ffffffff813b635d>] dpm_wait+0x2d/0x30
[ 360.916691] [<ffffffff813b63d8>] device_resume+0x78/0x1f0
[ 360.917890] [<ffffffff813b7a49>] dpm_resume+0x109/0x230
[ 360.919041] [<ffffffff813b7d7c>] dpm_resume_end+0xc/0x20
[ 360.920180] [<ffffffff8108a6ed>] suspend_devices_and_enter+0x16d/0x4a0
[ 360.921340] [<ffffffff8108ab90>] pm_suspend+0x170/0x250
[ 360.922506] [<ffffffff81089da7>] state_store+0x97/0xa0
[ 360.923689] [<ffffffff812c682f>] kobj_attr_store+0xf/0x20
[ 360.924870] [<ffffffff811deda1>] sysfs_write_file+0xc1/0x140
[ 360.926058] [<ffffffff8116ee5a>] vfs_write+0x9a/0x160
[ 360.927241] [<ffffffff8116f134>] sys_write+0x44/0x90
[ 360.928432] [<ffffffff815c26d6>] system_call_fastpath+0x1a/0x1f
[ 360.929663] INFO: task kworker/u:96:2978 blocked for more than 120 seconds.
[ 360.933912] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 360.938209] kworker/u:96 D ffff88012fc53500 0 2978 2 0x00000000
[ 360.939418] ffff880124bffb48 0000000000000046 0000000000013500 ffff880124bfffd8
[ 360.940648] ffff880124bfffd8 0000000000013500 ffff880124bfc300 ffff8800cc7ed180
[ 360.941861] 0000000000000000 ffff880124bffbb8 ffff880124bffc2c 0000000000001388
[ 360.943070] Call Trace:
[ 360.944278] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 360.945485] [<ffffffff814123f5>] usb_kill_urb+0x75/0xb0
[ 360.946701] [<ffffffff81066300>] ? wake_up_bit+0x30/0x30
[ 360.947914] [<ffffffff81412ab8>] usb_start_wait_urb+0xc8/0x150
[ 360.949116] [<ffffffff81412bfc>] usb_control_msg+0xbc/0x100
[ 360.950315] [<ffffffff81412ea5>] usb_get_status+0x75/0xb0
[ 360.951505] [<ffffffff8140bb44>] usb_port_resume+0x2a4/0x600
[ 360.952688] [<ffffffff815b8179>] ? schedule_timeout+0x219/0x320
[ 360.953875] [<ffffffff814064a0>] ? usb_dev_thaw+0x10/0x10
[ 360.955051] [<ffffffff8141e8f0>] generic_resume+0x10/0x30
[ 360.956230] [<ffffffff81415f6f>] usb_resume_both+0xff/0x140
[ 360.957402] [<ffffffff81416aaa>] usb_resume+0x1a/0xd0
[ 360.958550] [<ffffffff814064a0>] ? usb_dev_thaw+0x10/0x10
[ 360.959688] [<ffffffff814064ae>] usb_dev_resume+0xe/0x10
[ 360.960828] [<ffffffff813b62ef>] dpm_run_callback+0x3f/0x80
[ 360.961950] [<ffffffff813b6426>] device_resume+0xc6/0x1f0
[ 360.963080] [<ffffffff813b6568>] async_resume+0x18/0x40
[ 360.964217] [<ffffffff8106bd52>] async_run_entry_fn+0x32/0x130
[ 360.965366] [<ffffffff8105f4d7>] process_one_work+0x167/0x460
[ 360.966470] [<ffffffff8105fb30>] worker_thread+0x110/0x3c0
[ 360.967581] [<ffffffff8105fa20>] ? rescuer_thread+0x250/0x250
[ 360.968703] [<ffffffff8106551b>] kthread+0xbb/0xc0
[ 360.969830] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 360.970977] [<ffffffff815c262c>] ret_from_fork+0x7c/0xb0
[ 360.972130] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 360.973344] INFO: task kworker/u:97:2979 blocked for more than 120 seconds.
[ 360.977690] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 360.982186] kworker/u:97 D ffff88012fc93500 0 2979 2 0x00000000
[ 360.986773] ffff880124a83ca0 0000000000000046 0000000000013500 ffff880124a83fd8
[ 360.991465] ffff880124a83fd8 0000000000013500 ffff880124a80340 7fffffffffffffff
[ 360.996190] ffff880122084148 ffff880122084140 ffff880124a80340 0000000000000000
[ 360.997640] Call Trace:
[ 360.998925] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 361.000229] [<ffffffff815b8179>] schedule_timeout+0x219/0x320
[ 361.001525] [<ffffffff8107cf29>] ? check_preempt_wakeup+0x179/0x270
[ 361.002851] [<ffffffff815b9244>] wait_for_completion+0x94/0x100
[ 361.004191] [<ffffffff810763f0>] ? wake_up_state+0x10/0x10
[ 361.005539] [<ffffffff813b635d>] dpm_wait+0x2d/0x30
[ 361.006887] [<ffffffff813b63d8>] device_resume+0x78/0x1f0
[ 361.008259] [<ffffffff813b6568>] async_resume+0x18/0x40
[ 361.009621] [<ffffffff8106bd52>] async_run_entry_fn+0x32/0x130
[ 361.011010] [<ffffffff8105f4d7>] process_one_work+0x167/0x460
[ 361.012393] [<ffffffff8105fb30>] worker_thread+0x110/0x3c0
[ 361.013757] [<ffffffff8105fa20>] ? rescuer_thread+0x250/0x250
[ 361.015107] [<ffffffff8106551b>] kthread+0xbb/0xc0
[ 361.016439] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.017768] [<ffffffff815c262c>] ret_from_fork+0x7c/0xb0
[ 361.019096] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.020453] INFO: task kworker/u:98:2980 blocked for more than 120 seconds.
[ 361.021848] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.026863] kworker/u:98 D ffff88012fc93500 0 2980 2 0x00000000
[ 361.028274] ffff880124a8bca0 0000000000000046 0000000000013500 ffff880124a8bfd8
[ 361.029696] ffff880124a8bfd8 0000000000013500 ffff880124a88380 7fffffffffffffff
[ 361.031117] ffff8801226ab4f0 ffff8801226ab4e8 ffff880124a88380 0000000000000000
[ 361.032539] Call Trace:
[ 361.033945] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 361.035361] [<ffffffff815b8179>] schedule_timeout+0x219/0x320
[ 361.036783] [<ffffffff8107cf29>] ? check_preempt_wakeup+0x179/0x270
[ 361.038209] [<ffffffff815b9244>] wait_for_completion+0x94/0x100
[ 361.039609] [<ffffffff810763f0>] ? wake_up_state+0x10/0x10
[ 361.041002] [<ffffffff813b635d>] dpm_wait+0x2d/0x30
[ 361.042390] [<ffffffff813b63d8>] device_resume+0x78/0x1f0
[ 361.043782] [<ffffffff813b6568>] async_resume+0x18/0x40
[ 361.045172] [<ffffffff8106bd52>] async_run_entry_fn+0x32/0x130
[ 361.046562] [<ffffffff8105f4d7>] process_one_work+0x167/0x460
[ 361.047950] [<ffffffff8105fb30>] worker_thread+0x110/0x3c0
[ 361.049344] [<ffffffff8105fa20>] ? rescuer_thread+0x250/0x250
[ 361.050738] [<ffffffff8106551b>] kthread+0xbb/0xc0
[ 361.052118] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.053494] [<ffffffff815c262c>] ret_from_fork+0x7c/0xb0
[ 361.054853] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.056250] INFO: task kworker/u:99:2981 blocked for more than 120 seconds.
[ 361.061317] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.066419] kworker/u:99 D ffff88012fc93500 0 2981 2 0x00000000
[ 361.071556] ffff880124a8fca0 0000000000000046 0000000000013500 ffff880124a8ffd8
[ 361.072993] ffff880124a8ffd8 0000000000013500 ffff880124a8c3c0 7fffffffffffffff
[ 361.074428] ffff880122084148 ffff880122084140 ffff880124a8c3c0 0000000000000000
[ 361.075872] Call Trace:
[ 361.077299] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 361.078736] [<ffffffff815b8179>] schedule_timeout+0x219/0x320
[ 361.080176] [<ffffffff8107cf29>] ? check_preempt_wakeup+0x179/0x270
[ 361.081618] [<ffffffff815b9244>] wait_for_completion+0x94/0x100
[ 361.083064] [<ffffffff810763f0>] ? wake_up_state+0x10/0x10
[ 361.084509] [<ffffffff813b635d>] dpm_wait+0x2d/0x30
[ 361.085954] [<ffffffff813b63d8>] device_resume+0x78/0x1f0
[ 361.087416] [<ffffffff813b6568>] async_resume+0x18/0x40
[ 361.088881] [<ffffffff8106bd52>] async_run_entry_fn+0x32/0x130
[ 361.090351] [<ffffffff8105f4d7>] process_one_work+0x167/0x460
[ 361.091816] [<ffffffff8105fb30>] worker_thread+0x110/0x3c0
[ 361.093284] [<ffffffff8105fa20>] ? rescuer_thread+0x250/0x250
[ 361.094757] [<ffffffff8106551b>] kthread+0xbb/0xc0
[ 361.096226] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.097709] [<ffffffff815c262c>] ret_from_fork+0x7c/0xb0
[ 361.099188] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.100703] INFO: task kworker/u:100:2982 blocked for more than 120 seconds.
[ 361.102208] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.107707] kworker/u:100 D ffff88012fcd3500 0 2982 2 0x00000000
[ 361.109247] ffff880124a93b48 0000000000000046 0000000000013500 ffff880124a93fd8
[ 361.110789] ffff880124a93fd8 0000000000013500 ffff880124a90400 ffff8800cfafb840
[ 361.112329] 0000000000000000 ffff880124a93bb8 ffff880124a93c2c 0000000000001388
[ 361.113862] Call Trace:
[ 361.115370] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 361.116875] [<ffffffff814123f5>] usb_kill_urb+0x75/0xb0
[ 361.118367] [<ffffffff81066300>] ? wake_up_bit+0x30/0x30
[ 361.119856] [<ffffffff81412ab8>] usb_start_wait_urb+0xc8/0x150
[ 361.121345] [<ffffffff81412bfc>] usb_control_msg+0xbc/0x100
[ 361.122821] [<ffffffff81412ea5>] usb_get_status+0x75/0xb0
[ 361.124292] [<ffffffff8140bb44>] usb_port_resume+0x2a4/0x600
[ 361.125763] [<ffffffff815b8179>] ? schedule_timeout+0x219/0x320
[ 361.127234] [<ffffffff814064a0>] ? usb_dev_thaw+0x10/0x10
[ 361.128704] [<ffffffff8141e8f0>] generic_resume+0x10/0x30
[ 361.130148] [<ffffffff81415f6f>] usb_resume_both+0xff/0x140
[ 361.131592] [<ffffffff81416aaa>] usb_resume+0x1a/0xd0
[ 361.133034] [<ffffffff814064a0>] ? usb_dev_thaw+0x10/0x10
[ 361.134477] [<ffffffff814064ae>] usb_dev_resume+0xe/0x10
[ 361.135919] [<ffffffff813b62ef>] dpm_run_callback+0x3f/0x80
[ 361.137358] [<ffffffff813b6426>] device_resume+0xc6/0x1f0
[ 361.138798] [<ffffffff813b6568>] async_resume+0x18/0x40
[ 361.140235] [<ffffffff8106bd52>] async_run_entry_fn+0x32/0x130
[ 361.141674] [<ffffffff8105f4d7>] process_one_work+0x167/0x460
[ 361.143110] [<ffffffff8105fb30>] worker_thread+0x110/0x3c0
[ 361.144541] [<ffffffff8105fa20>] ? rescuer_thread+0x250/0x250
[ 361.145970] [<ffffffff8106551b>] kthread+0xbb/0xc0
[ 361.147349] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.148724] [<ffffffff815c262c>] ret_from_fork+0x7c/0xb0
[ 361.150085] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.151469] INFO: task kworker/u:101:2983 blocked for more than 120 seconds.
[ 361.152902] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.158089] kworker/u:101 D ffff88012fc93500 0 2983 2 0x00000000
[ 361.159525] ffff880124a99ca0 0000000000000046 0000000000013500 ffff880124a99fd8
[ 361.160980] ffff880124a99fd8 0000000000013500 ffff880124a96440 7fffffffffffffff
[ 361.162438] ffff88012260c948 ffff88012260c940 ffff880124a96440 0000000000000000
[ 361.163894] Call Trace:
[ 361.165335] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 361.166785] [<ffffffff815b8179>] schedule_timeout+0x219/0x320
[ 361.168235] [<ffffffff8107cf29>] ? check_preempt_wakeup+0x179/0x270
[ 361.169693] [<ffffffff815b9244>] wait_for_completion+0x94/0x100
[ 361.171152] [<ffffffff810763f0>] ? wake_up_state+0x10/0x10
[ 361.172616] [<ffffffff813b635d>] dpm_wait+0x2d/0x30
[ 361.174075] [<ffffffff813b63d8>] device_resume+0x78/0x1f0
[ 361.175536] [<ffffffff813b6568>] async_resume+0x18/0x40
[ 361.176993] [<ffffffff8106bd52>] async_run_entry_fn+0x32/0x130
[ 361.178449] [<ffffffff8105f4d7>] process_one_work+0x167/0x460
[ 361.179904] [<ffffffff8105fb30>] worker_thread+0x110/0x3c0
[ 361.181353] [<ffffffff8105fa20>] ? rescuer_thread+0x250/0x250
[ 361.182802] [<ffffffff8106551b>] kthread+0xbb/0xc0
[ 361.184235] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.185644] [<ffffffff815c262c>] ret_from_fork+0x7c/0xb0
[ 361.187059] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.188498] INFO: task kworker/u:102:2984 blocked for more than 120 seconds.
[ 361.189933] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.195050] kworker/u:102 D ffff88012fc93500 0 2984 2 0x00000000
[ 361.200198] ffff880124a9dca0 0000000000000046 0000000000013500 ffff880124a9dfd8
[ 361.201636] ffff880124a9dfd8 0000000000013500 ffff880124a9a480 7fffffffffffffff
[ 361.203080] ffff8801226ad4f0 ffff8801226ad4e8 ffff880124a9a480 0000000000000000
[ 361.204494] Call Trace:
[ 361.205888] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 361.207289] [<ffffffff815b8179>] schedule_timeout+0x219/0x320
[ 361.208727] [<ffffffff8107cf29>] ? check_preempt_wakeup+0x179/0x270
[ 361.210170] [<ffffffff815b9244>] wait_for_completion+0x94/0x100
[ 361.211614] [<ffffffff810763f0>] ? wake_up_state+0x10/0x10
[ 361.213066] [<ffffffff813b635d>] dpm_wait+0x2d/0x30
[ 361.214510] [<ffffffff813b63d8>] device_resume+0x78/0x1f0
[ 361.215954] [<ffffffff813b6568>] async_resume+0x18/0x40
[ 361.217396] [<ffffffff8106bd52>] async_run_entry_fn+0x32/0x130
[ 361.218854] [<ffffffff8105f4d7>] process_one_work+0x167/0x460
[ 361.220312] [<ffffffff8105fb30>] worker_thread+0x110/0x3c0
[ 361.221765] [<ffffffff8105fa20>] ? rescuer_thread+0x250/0x250
[ 361.223217] [<ffffffff8106551b>] kthread+0xbb/0xc0
[ 361.224653] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.226087] [<ffffffff815c262c>] ret_from_fork+0x7c/0xb0
[ 361.227504] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.228943] INFO: task kworker/u:103:2985 blocked for more than 120 seconds.
[ 361.230384] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 361.235566] kworker/u:103 D ffff88012fc93500 0 2985 2 0x00000000
[ 361.240784] ffff880124aa1ca0 0000000000000046 0000000000013500 ffff880124aa1fd8
[ 361.242240] ffff880124aa1fd8 0000000000013500 ffff880124a9e4c0 7fffffffffffffff
[ 361.243646] ffff88012260c948 ffff88012260c940 ffff880124a9e4c0 0000000000000000
[ 361.245060] Call Trace:
[ 361.246455] [<ffffffff815ba4b4>] schedule+0x24/0x70
[ 361.247879] [<ffffffff815b8179>] schedule_timeout+0x219/0x320
[ 361.249323] [<ffffffff8107cf29>] ? check_preempt_wakeup+0x179/0x270
[ 361.250771] [<ffffffff815b9244>] wait_for_completion+0x94/0x100
[ 361.252222] [<ffffffff810763f0>] ? wake_up_state+0x10/0x10
[ 361.253671] [<ffffffff813b635d>] dpm_wait+0x2d/0x30
[ 361.255122] [<ffffffff813b63d8>] device_resume+0x78/0x1f0
[ 361.256571] [<ffffffff813b6568>] async_resume+0x18/0x40
[ 361.258024] [<ffffffff8106bd52>] async_run_entry_fn+0x32/0x130
[ 361.259479] [<ffffffff8105f4d7>] process_one_work+0x167/0x460
[ 361.260936] [<ffffffff8105fb30>] worker_thread+0x110/0x3c0
[ 361.262392] [<ffffffff8105fa20>] ? rescuer_thread+0x250/0x250
[ 361.263851] [<ffffffff8106551b>] kthread+0xbb/0xc0
[ 361.265309] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 361.266778] [<ffffffff815c262c>] ret_from_fork+0x7c/0xb0
[ 361.268243] [<ffffffff81065460>] ? kthread_create_on_node+0x120/0x120
[ 363.844318] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 363.908297] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 373.851244] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 373.915222] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 383.858168] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 383.922148] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 393.865093] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 393.929031] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 403.872017] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 403.935996] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 413.878941] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 413.942920] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 423.885865] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 423.949844] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 433.892788] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 433.956766] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 443.899711] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 443.963689] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 453.906633] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 453.970613] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 463.913556] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 463.977536] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
[ 473.920478] ohci_hcd 0000:00:12.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 2627
[ 473.984457] ohci_hcd 0000:00:13.1: Timer: count 1 intr-en 8000005e intr-stat 20 frame 25f0
