2014-10-06 12:55:08

by Oliver Neukum

[permalink] [raw]
Subject: Re: btusb_intr_complete returns -EPIPE

On Mon, 2014-10-06 at 18:18 +0530, Naveen Kumar Parna wrote:
> Hi,
>
> I just collected the usbmon log(1.mon.out) and attached it. It stalls
> for INT in transfers.
>
> Corresponding kernel log is here:
> Oct 6 18:00:48 naveen-OptiPlex-745 kernel: [ 7528.718473] hci3 urb
> ffff88012954dd80 status -32 count 0
> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.688122] hci3 urb
> ffff88012954dd80 status -32 count 0
> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.693086] hci3 urb
> ffff88012954dd80 status -32 count 0
> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.695058] hci3 urb
> ffff88012954dd80 status -32 count 0
> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.703073] hci3 urb
> ffff88012954dd80 status -32 count 0
> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.717038] hci5 urb
> ffff88012954de40 status -32 count 0
> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.717496] hci3 urb
> ffff88012954dd80 status -32 count 0
>
> Corresponding Usbmon trace:
> ffff88012954dd80 2936526502 C Ii:1:009:1 -32:1 0
> ffff88012954dd80 3223215374 C Ii:1:009:1 -32:1 0
> ffff88012954dd80 3223220352 C Ii:1:009:1 -32:1 0
> ffff88012954dd80 3223222332 C Ii:1:009:1 -32:1 0
> ffff88012954dd80 3223230362 C Ii:1:009:1 -32:1 0
> ffff88012954de40 3223244362 C Ii:1:019:1 -32:1 0
> ffff88012954dd80 3223244830 C Ii:1:009:1 -32:1 0
>
> Does it gives any clue?

Not really. I'll make a patch to clear the condition.
Let's see what happens then.

Regards
Oliver




2014-10-06 14:50:50

by Oliver Neukum

[permalink] [raw]
Subject: Re: btusb_intr_complete returns -EPIPE

On Mon, 2014-10-06 at 20:08 +0530, Naveen Kumar Parna wrote:
> Thanks for the patch.
>
> I tried and It crashed after the first occurrence of EPIPE.
>
> Crash log is attached.

Could you post a full "lsusb -v"?

Regards
Oliver



2014-10-06 14:38:02

by Naveen Kumar Parna

[permalink] [raw]
Subject: Re: btusb_intr_complete returns -EPIPE

Thanks for the patch.

I tried and It crashed after the first occurrence of EPIPE.

Crash log is attached.

Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.188379] hci4 urb
ffff880127ad9240 status -32 count 0
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.188556] BUG: unable
to handle kernel paging request at 00000000000102a0
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.189281] IP:
[<ffffffff812e53c9>] atomic_inc+0x9/0xe
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.189634] PGD
127884067 PUD 131fc0067 PMD 0
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.189989] Oops: 0002 [#1] SMP
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.190337] CPU 6
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.190344] Modules
linked in: rfcomm bnep btusb bluetooth rfkill babel nfs fscache
auth_rpcgss nfs_acl lockd iTCO_wdt iTCO_vendor_support i2c_i801
i2c_core tg3 joydev sunrpc uinput microcode hpsa usb_storage uas [last
unloaded: scsi_wait_scan]
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.192109]
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.192442] Pid: 53,
comm: kworker/6:1 Not tainted 3.1.0-7.fc16.x86_64 #1 HP ProLiant DL120
G6/ProLiant DL120 G6
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.193151] RIP:
0010:[<ffffffff812e53c9>] [<ffffffff812e53c9>] atomic_inc+0x9/0xe
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.193839] RSP:
0018:ffff88013868ddb0 EFLAGS: 00010202
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.194273] RAX:
ffff880128191c78 RBX: 0000000000010130 RCX: ffff880128191c70
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.194625] RDX:
ffff880128191c70 RSI: 0000000000000004 RDI: 00000000000102a0
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.194979] RBP:
ffff88013868ddb0 R08: ffff880128191c78 R09: 0000000000608007
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.195329] R10:
0000000000608007 R11: ffff88013fd92f80 R12: 0000000000010100
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.195680] R13:
0000000000010130 R14: 0000000000000004 R15: ffff88013fd96805
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.196032] FS:
0000000000000000(0000) GS:ffff88013fd80000(0000)
knlGS:0000000000000000
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.196716] CS: 0010
DS: 0000 ES: 0000 CR0: 000000008005003b
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.197063] CR2:
00000000000102a0 CR3: 000000012a613000 CR4: 00000000000006e0
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.197422] DR0:
0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.197780] DR3:
0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.198138] Process
kworker/6:1 (pid: 53, threadinfo ffff88013868c000, task
ffff880138690000)
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.198811] Stack:

Message from syslogd@naveen-OptiPlex-745 at Oct 6 19:49:24 ...
kernel:[ 979.189989] Oops: 0002 [#1] SMP

Message from syslogd@naveen-OptiPlex-745 at Oct 6 19:49:24 ...
kernel:[ 979.198811] Stack:
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.199146]
ffff88013868ddf0 ffffffff812e645c ffffffff81605920 ffff88013fd92f80
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.199831]
ffff880128191c70 0000000000010100 0000000000010130 ffffffffa0091b49
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.200525]
ffff88013868de20 ffffffff8133a672 ffff880128191c70 ffff880128191c70
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.201211] Call Trace:
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.201550]
[<ffffffff812e645c>] __pm_runtime_resume+0x2c/0x65
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.201907]
[<ffffffffa0091b49>] ? btusb_submit_intr_urb+0x173/0x173 [btusb]
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.202268]
[<ffffffff8133a672>] usb_autopm_get_interface+0x23/0x52
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.202626]
[<ffffffffa0091b60>] clear_halt_intr_in+0x17/0xac [btusb]
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.202978]
[<ffffffff8106edbc>] process_one_work+0x176/0x2a9
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.203331]
[<ffffffff8106f8ca>] worker_thread+0xda/0x15d
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.203680]
[<ffffffff8106f7f0>] ? manage_workers+0x176/0x176
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.204022]
[<ffffffff81072d17>] kthread+0x84/0x8c
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.204365]
[<ffffffff814be5f4>] kernel_thread_helper+0x4/0x10
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.204711]
[<ffffffff81072c93>] ? kthread_worker_fn+0x148/0x148
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.205058]
[<ffffffff814be5f0>] ? gs_change+0x13/0x13
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.205402] Code: d9 81
e8 07 f6 ff ff 80 3d 24 26 ab 00 00 75 05 e8 da f7 ff ff 8a 05 17 26
ab 00 41 5b 5b 5d c3 90 90 55 48 89 e5 66 66 66 66 90 <f0> ff 07 5d c3
55 48 89 e5 66 66 66 66 90 f0 ff 0f 0f 94 c0 84
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.206652] RIP
[<ffffffff812e53c9>] atomic_inc+0x9/0xe
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.207007] RSP
<ffff88013868ddb0>
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.207354] CR2: 00000000000102a0
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.208287] ---[ end
trace 0089da2b8191af16 ]---
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.208739] BUG: unable
to handle kernel paging request at fffffffffffffff8
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.208742] IP:
[<ffffffff81072f65>] kthread_data+0x11/0x16
Oct 6 19:49:24 naveen-OptiPlex-745 kernel: [ 979.208747] PGD 1a07067
PUD 1a08067 PMD 0

Thanks,
Naveen

On Mon, Oct 6, 2014 at 6:59 PM, Oliver Neukum <[email protected]> wrote:
> On Mon, 2014-10-06 at 18:33 +0530, Naveen Kumar Parna wrote:
>> Thank you very much. I will try that patch.
>
> Then please try.
>
> Regards
> Oliver
>


Attachments:
0001-btusb-clear-halt-if-intr-in-stalls.patch.crash.log (12.30 kB)

2014-10-06 13:29:29

by Oliver Neukum

[permalink] [raw]
Subject: Re: btusb_intr_complete returns -EPIPE

On Mon, 2014-10-06 at 18:33 +0530, Naveen Kumar Parna wrote:
> Thank you very much. I will try that patch.

Then please try.

Regards
Oliver


Attachments:
0001-btusb-clear-halt-if-intr-in-stalls.patch (2.59 kB)

2014-10-06 13:03:29

by Naveen Kumar Parna

[permalink] [raw]
Subject: Re: btusb_intr_complete returns -EPIPE

Thank you very much. I will try that patch.



Thanks,
Naveen

On Mon, Oct 6, 2014 at 6:25 PM, Oliver Neukum <[email protected]> wrote:
> On Mon, 2014-10-06 at 18:18 +0530, Naveen Kumar Parna wrote:
>> Hi,
>>
>> I just collected the usbmon log(1.mon.out) and attached it. It stalls
>> for INT in transfers.
>>
>> Corresponding kernel log is here:
>> Oct 6 18:00:48 naveen-OptiPlex-745 kernel: [ 7528.718473] hci3 urb
>> ffff88012954dd80 status -32 count 0
>> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.688122] hci3 urb
>> ffff88012954dd80 status -32 count 0
>> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.693086] hci3 urb
>> ffff88012954dd80 status -32 count 0
>> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.695058] hci3 urb
>> ffff88012954dd80 status -32 count 0
>> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.703073] hci3 urb
>> ffff88012954dd80 status -32 count 0
>> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.717038] hci5 urb
>> ffff88012954de40 status -32 count 0
>> Oct 6 18:05:35 naveen-OptiPlex-745 kernel: [ 7814.717496] hci3 urb
>> ffff88012954dd80 status -32 count 0
>>
>> Corresponding Usbmon trace:
>> ffff88012954dd80 2936526502 C Ii:1:009:1 -32:1 0
>> ffff88012954dd80 3223215374 C Ii:1:009:1 -32:1 0
>> ffff88012954dd80 3223220352 C Ii:1:009:1 -32:1 0
>> ffff88012954dd80 3223222332 C Ii:1:009:1 -32:1 0
>> ffff88012954dd80 3223230362 C Ii:1:009:1 -32:1 0
>> ffff88012954de40 3223244362 C Ii:1:019:1 -32:1 0
>> ffff88012954dd80 3223244830 C Ii:1:009:1 -32:1 0
>>
>> Does it gives any clue?
>
> Not really. I'll make a patch to clear the condition.
> Let's see what happens then.
>
> Regards
> Oliver
>
>