2.6.34-rc: sound hungs (ice1724)

2.6.34-rc: sound hungs (ice1724)

[Alexander Beregalov]

Hi Takashi

Could you please help me how to debug an issue.
Sometimes soung hungs, it happens few days (up to a week) after boot.
I have no idea how to reproduce it or how to debug it.
That's why it is very hard to bisect, I have not tried, the better way
should exist.
The problem appeared somewhere after 2.6.33.

Card is M-Audio Revo 5.1 (1412:1724).

After it hungs ALSA continues to play last sound frame forever.
When I try to play something else playing frame changes.
Only reboot heals it.

rmmod hungs at
INFO: task rmmod:30356 blocked for more than 120 seconds.
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
rmmod         D db281e7c     0 30356   2345 0x00000004
 db281e8c 00000086 00000002 db281e7c cc2d7b90 00000000 c14e8b80 09c3638a
 c10252a0 00000001 c14e2000 c14e2000 c14e8b80 c14e8b80 cc2d7a00 db281e8c
 00000246 db281e8c c1043d23 00000001 f6923000 f69231b4 db281e9c db281ebc
Call Trace:
 [<c10252a0>] ? __wake_up+0x40/0x50
 [<c1043d23>] ? prepare_to_wait+0x43/0x70
 [<f8ce328d>] snd_card_free+0x5d/0x90 [snd]
 [<c1043af0>] ? autoremove_wake_function+0x0/0x40
 [<f9ebcaa6>] snd_vt1724_remove+0x13/0x25 [snd_ice1724]
 [<c11cddb9>] pci_device_remove+0x19/0x40
 [<c1239f3e>] __device_release_driver+0x3e/0x90
 [<c123a017>] driver_detach+0x87/0x90
 [<c1239323>] bus_remove_driver+0x63/0xa0
 [<c123a526>] driver_unregister+0x46/0x80
 [<c10d616d>] ? sysfs_remove_file+0xd/0x10
 [<c11cdfed>] pci_unregister_driver+0x2d/0x80
 [<f9ebca91>] alsa_card_ice1724_exit+0xd/0xf [snd_ice1724]
 [<c1057bee>] sys_delete_module+0x15e/0x210
 [<c107f91f>] ? remove_vma+0x3f/0x60
 [<c1080902>] ? do_munmap+0x202/0x2d0
 [<c1002b50>] sysenter_do_call+0x12/0x26

CONFIG_SOUND=y
CONFIG_SND=m
CONFIG_SND_TIMER=m
CONFIG_SND_PCM=m
CONFIG_SND_RAWMIDI=m
CONFIG_SND_VMASTER=y
CONFIG_SND_DMA_SGBUF=y
CONFIG_SND_AC97_CODEC=m
CONFIG_SND_PCI=y
CONFIG_SND_ICE1724=m

Re: 2.6.34-rc: sound hungs (ice1724)

[Takashi Iwai]

At Wed, 14 Apr 2010 00:23:37 +0400,
Alexander Beregalov wrote:
> 
> Hi Takashi
> 
> Could you please help me how to debug an issue.
> Sometimes soung hungs, it happens few days (up to a week) after boot.
> I have no idea how to reproduce it or how to debug it.
> That's why it is very hard to bisect, I have not tried, the better way
> should exist.
> The problem appeared somewhere after 2.6.33.
> 
> Card is M-Audio Revo 5.1 (1412:1724).
> 
> After it hungs ALSA continues to play last sound frame forever.
> When I try to play something else playing frame changes.
> Only reboot heals it.
> 
> rmmod hungs at
> INFO: task rmmod:30356 blocked for more than 120 seconds.
> "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
> rmmod         D db281e7c     0 30356   2345 0x00000004
>  db281e8c 00000086 00000002 db281e7c cc2d7b90 00000000 c14e8b80 09c3638a
>  c10252a0 00000001 c14e2000 c14e2000 c14e8b80 c14e8b80 cc2d7a00 db281e8c
>  00000246 db281e8c c1043d23 00000001 f6923000 f69231b4 db281e9c db281ebc
> Call Trace:
>  [<c10252a0>] ? __wake_up+0x40/0x50
>  [<c1043d23>] ? prepare_to_wait+0x43/0x70
>  [<f8ce328d>] snd_card_free+0x5d/0x90 [snd]
>  [<c1043af0>] ? autoremove_wake_function+0x0/0x40
>  [<f9ebcaa6>] snd_vt1724_remove+0x13/0x25 [snd_ice1724]
>  [<c11cddb9>] pci_device_remove+0x19/0x40
>  [<c1239f3e>] __device_release_driver+0x3e/0x90
>  [<c123a017>] driver_detach+0x87/0x90
>  [<c1239323>] bus_remove_driver+0x63/0xa0
>  [<c123a526>] driver_unregister+0x46/0x80
>  [<c10d616d>] ? sysfs_remove_file+0xd/0x10
>  [<c11cdfed>] pci_unregister_driver+0x2d/0x80
>  [<f9ebca91>] alsa_card_ice1724_exit+0xd/0xf [snd_ice1724]
>  [<c1057bee>] sys_delete_module+0x15e/0x210
>  [<c107f91f>] ? remove_vma+0x3f/0x60
>  [<c1080902>] ? do_munmap+0x202/0x2d0
>  [<c1002b50>] sysenter_do_call+0x12/0x26

This is odd.  Don't you see any other messages before that?

There are almost no change in ice172x.  Thus, if any, the regression
must be in ALSA core.  There have been many changes of PCM hw-updater,
at least.


thanks,

Takashi

Re: 2.6.34-rc: sound hungs (ice1724)

[Alexander Beregalov]

On 14 April 2010 10:26, Takashi Iwai <tiwai@suse.de> wrote:
> At Wed, 14 Apr 2010 00:23:37 +0400,
> Alexander Beregalov wrote:
>>
>> Hi Takashi
>>
>> Could you please help me how to debug an issue.
>> Sometimes soung hungs, it happens few days (up to a week) after boot.
>> I have no idea how to reproduce it or how to debug it.
>> That's why it is very hard to bisect, I have not tried, the better way
>> should exist.
>> The problem appeared somewhere after 2.6.33.
>>
>> Card is M-Audio Revo 5.1 (1412:1724).
>>
>> After it hungs ALSA continues to play last sound frame forever.
>> When I try to play something else playing frame changes.
>> Only reboot heals it.
>>
>> rmmod hungs at
>> INFO: task rmmod:30356 blocked for more than 120 seconds.
>> "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
>> rmmod         D db281e7c     0 30356   2345 0x00000004
>>  db281e8c 00000086 00000002 db281e7c cc2d7b90 00000000 c14e8b80 09c3638a
>>  c10252a0 00000001 c14e2000 c14e2000 c14e8b80 c14e8b80 cc2d7a00 db281e8c
>>  00000246 db281e8c c1043d23 00000001 f6923000 f69231b4 db281e9c db281ebc
>> Call Trace:
>>  [<c10252a0>] ? __wake_up+0x40/0x50
>>  [<c1043d23>] ? prepare_to_wait+0x43/0x70
>>  [<f8ce328d>] snd_card_free+0x5d/0x90 [snd]
>>  [<c1043af0>] ? autoremove_wake_function+0x0/0x40
>>  [<f9ebcaa6>] snd_vt1724_remove+0x13/0x25 [snd_ice1724]
>>  [<c11cddb9>] pci_device_remove+0x19/0x40
>>  [<c1239f3e>] __device_release_driver+0x3e/0x90
>>  [<c123a017>] driver_detach+0x87/0x90
>>  [<c1239323>] bus_remove_driver+0x63/0xa0
>>  [<c123a526>] driver_unregister+0x46/0x80
>>  [<c10d616d>] ? sysfs_remove_file+0xd/0x10
>>  [<c11cdfed>] pci_unregister_driver+0x2d/0x80
>>  [<f9ebca91>] alsa_card_ice1724_exit+0xd/0xf [snd_ice1724]
>>  [<c1057bee>] sys_delete_module+0x15e/0x210
>>  [<c107f91f>] ? remove_vma+0x3f/0x60
>>  [<c1080902>] ? do_munmap+0x202/0x2d0
>>  [<c1002b50>] sysenter_do_call+0x12/0x26
>
> This is odd.  Don't you see any other messages before that?
No messages. I can try to boot with ignore_loglevel parameter.
>
> There are almost no change in ice172x.  Thus, if any, the regression
> must be in ALSA core.  There have been many changes of PCM hw-updater,
> at least.
It seems the bug is in the core, but in this case I should not be the
only reporter.
_______________________________________________
Alsa-devel mailing list
Alsa-devel@alsa-project.org
http://mailman.alsa-project.org/mailman/listinfo/alsa-devel

Re: 2.6.34-rc: sound hungs (ice1724)

[Alexander Beregalov]

On 14 April 2010 12:46, Alexander Beregalov <a.beregalov@gmail.com> wrote:
>> This is odd.  Don't you see any other messages before that?
> No messages. I can try to boot with ignore_loglevel parameter.
>>
>> There are almost no change in ice172x.  Thus, if any, the regression
>> must be in ALSA core.  There have been many changes of PCM hw-updater,
>> at least.
> It seems the bug is in the core, but in this case I should not be the
> only reporter.

Hi Takashi

I did some bisection.
The oldest bad kernel is 6123637fa, with kernels from v2.6.33 to
6123637fa I was not able to reproduce the bug, it's understandable -
the bug is very hard to reproduce, I cannot be sure if the kernel is
really good after running even for a week.
Perhaps could you look at "git log v2.6.33..6123637fa sound/core/" to
see something suspicious?
_______________________________________________
Alsa-devel mailing list
Alsa-devel@alsa-project.org
http://mailman.alsa-project.org/mailman/listinfo/alsa-devel