[LAU] retire_capture_urb: 19662 callbacks suppressed [ADD.INFO]

[rob]

On 13/10/14 22:48, rob wrote:
> On 13/10/14 21:31, rob wrote:
>> On 13/10/14 12:54, rob wrote:
>>> On 13/10/14 11:48, Clemens Ladisch wrote:
>>>> The message in the subject means that the driver logged some error
>>>> message repeatedly, and that the duplicates were suppressed.
>>>>
>>>> The actual error message (before that) would be useful.
>>>>
>>>>
>>>> Regards,
>>>> Clemens
>>>>
>>>>
>>> There isn't preceding message
>>> from syslog :
>>> Oct 13 11:47:46 debian dnsmasq[3465]: reading /etc/resolv.conf
>>> Oct 13 11:47:46 debian dnsmasq[3465]: using nameserver 192.168.178.1#53
>>> Oct 13 12:08:46 debian acpid: client connected from 8202[1001:1001]
>>> Oct 13 12:08:46 debian acpid: 1 client rule loaded
>>> Oct 13 12:17:01 debian /USR/SBIN/CRON[11997]: (root) CMD (   cd / &&
>>> run-parts --report /etc/cron.hourly)
>>> Oct 13 12:36:00 debian kernel: [371346.915918] retire_capture_urb: 13960
>>> callbacks suppressed
>>> Oct 13 12:36:05 debian kernel: [371351.975159] retire_capture_urb: 31944
>>> callbacks suppressed
>>> Oct 13 12:36:10 debian kernel: [371357.022838] retire_capture_urb: 32053
>>> callbacks suppressed
>>> Oct 13 12:36:15 debian kernel: [371362.089701] retire_capture_urb: 31962
>>> callbacks suppressed
>>> Oct 13 12:36:20 debian kernel: [371367.129415] retire_capture_urb: 32068
>>> callbacks suppressed
>>> Oct 13 12:36:25 debian kernel: [371372.203356] retire_capture_urb: 31613
>>> callbacks suppressed
>>> Oct 13 12:36:30 debian kernel: [371377.236139] retire_capture_urb: 31959
>>> callbacks suppressed
>>> Oct 13 12:36:35 debian kernel: [371382.319187] retire_capture_urb: 31742
>>> callbacks suppressed
>>> Oct 13 12:36:40 debian kernel: [371387.342876] retire_capture_urb: 32271
>>> callbacks suppressed
>>> Oct 13 12:36:46 debian kernel: [371392.432545] retire_capture_urb: 31773
>>> callbacks suppressed
>>>
>>>
>>>
>>> I forgot to mention kernel is started with threadirqs
>>>
>>>
>>> _______________________________________________
>>
>>
>>
>>
>> I was using jackd2. I've now tried jackd1 which works without the
>> problem. Maybe something to do with dbus?
>>
>> Thanks
>>
>> rob
>>
>>
>
> I spoke too soon. Same error with jackd1, just takes sometime to start.
> The qjackctl messages are different multiple
> delay of 29465.000 usecs exceeds estimated spare time of 5224.000;
> restart ...
>
>
> Going to try kxstudio livedvd to see if it happens there.
>
>
> rob


KXstudio worked which should rule out hardware.

Interestingly going back to Debian, after a couple of KX sessions, the 
problem disappeared for 2 days.

Today it started again. This time some further info appeared in syslog 
which may be relevant:

] CPU: 1 PID: 135 Comm: irq/17-ehci_hcd Not tainted 3.14.4preempt #1
[194597.086617] Hardware name: Gigabyte Technology Co., Ltd. To be 
filled by O.E.M./990FXA-UD5, BIOS FB 01/23/2013
[194597.086619]  ffff880321b70ebc ffffffff814d0ea9 ffff880321b70e00 
ffffffff810a2148
[194597.086622]  ffff880321b70e00 0000000000000011 ffff880321b70e00 
ffffffff810a25d5
[194597.086624]  ffffffff810594cf ffff88031f4df270 ffff88031ed2a280 
ffff880321b70e00
[194597.086626] Call Trace:
[194597.086632]  [<ffffffff814d0ea9>] ? dump_stack+0x4a/0x75
[194597.086635]  [<ffffffff810a2148>] ? __report_bad_irq+0x28/0xc0
[194597.086637]  [<ffffffff810a25d5>] ? note_interrupt+0x1b5/0x210
[194597.086640]  [<ffffffff810594cf>] ? __local_bh_enable_ip+0x3f/0xa0
[194597.086642]  [<ffffffff810a08f0>] ? irq_thread_fn+0x40/0x40
[194597.086644]  [<ffffffff810a0e15>] ? irq_thread+0x135/0x140
[194597.086646]  [<ffffffff810a0970>] ? wake_threads_waitq+0x30/0x30
[194597.086647]  [<ffffffff810a0ce0>] ? irq_thread_check_affinity+0xb0/0xb0
[194597.086650]  [<ffffffff81075321>] ? kthread+0xc1/0xe0
[194597.086652]  [<ffffffff81075260>] ? kthread_create_on_node+0x180/0x180
[194597.086654]  [<ffffffff814de00c>] ? ret_from_fork+0x7c/0xb0
[194597.086656]  [<ffffffff81075260>] ? kthread_create_on_node+0x180/0x180
[194597.086658] handlers:
[194597.086660] [<ffffffff810a0250>] irq_default_primary_handler 
threaded [<ffffffffa000e520>] usb_hcd_irq [usbcore]
[194597.086677] [<ffffffff810a0250>] irq_default_primary_handler 
threaded [<ffffffffa000e520>] usb_hcd_irq [usbcore]
[194597.086683] [<ffffffff810a0250>] irq_default_primary_handler 
threaded [<ffffffffa000e520>] usb_hcd_irq [usbcore]
[194597.086687] Disabling IRQ #17
[194602.166921] retire_capture_urb: 31152 callbacks suppressed
[194607.193310] retire_capture_urb: 32400 callbacks suppressed
[194612.270351] retire_capture_urb: 31820 callbacks suppressed etc..

kernel is started with threadirqs

Thanks

rob