Re: [alsa-devel] Intel HDA audio on EEE PC 1101HGo

10 Jan 2017


      Sorry - forgot to mention that the errors I reported trigger on different events, and notably:
- console beep
- volume up+down via alsamixer for example
... they may also trigger in other conditions, but I had to disable console dmesg logging, otherwise the system could become difficult to use in some cases, due to the amount of output.
On Tue, 10 Jan 2017, Enrico Mioso wrote:
...
Date: Tue, 10 Jan 2017 14:32:05
From: Enrico Mioso mrkiko.rs@gmail.com
To: Takashi Iwai tiwai@suse.de
Cc: hui.wang@canonical.com, alsa-devel@alsa-project.org, kailang@realtek.com
Subject: Re: [alsa-devel] Intel HDA audio on EEE PC 1101HGo
First of all - Thank you very very much.
I am apreciating a lot your help and kind replies.
Running the module like:
options snd_hda_intel power_save_controller=0 power_save=0 single_cmd=1
prevents the system from crashing, still I can see lots of errors like the 
ones you find below.
(I already reported them, doing it again just for reference / clarity after 
trying to change my top-posting habits.)
I'll test your patch ASAP: starting now kernel recompiling.
Enrico
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a02d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a02e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902d
snd_hdac_bus_update_rirb: 42 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a02c
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902c
snd_hdac_bus_update_rirb: 2 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hdac_bus_update_rirb: 2 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902d
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a02e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902e
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902f
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02f
snd_hdac_bus_update_rirb: 46 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a031
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x239031
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a031
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339031
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a030
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x239030
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a030
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339030
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a02f
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902f
snd_hdac_bus_update_rirb: 30 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x239029
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339029
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339029
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339029
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902a
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902a
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902a
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902b
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02b
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902b
snd_hdac_bus_update_rirb: 114 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x239038
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339038
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339038
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x239037
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a037
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339037
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339037
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x239036
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a036
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x339036
snd_hdac_bus_update_rirb: 58 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hdac_bus_update_rirb: 1 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a02b
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902b
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02b
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902b
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a02a
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23902a
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33a02a
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x33902a
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x23a029
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x239029
snd_hdac_bus_update_rirb: 2 callbacks suppressed
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a10
snd_hda_intel 0000:00:1b.0: spurious response 0x0:0x0, last cmd=0x170a00
On Tue, 10 Jan 2017, Takashi Iwai wrote:
...
Date: Tue, 10 Jan 2017 11:52:22
 From: Takashi Iwai tiwai@suse.de
 To: Enrico Mioso mrkiko.rs@gmail.com
 Cc: hui.wang@canonical.com, alsa-devel@alsa-project.org,
 kailang@realtek.com
 Subject: Re: [alsa-devel] Intel HDA audio on EEE PC 1101HGo
On Tue, 03 Jan 2017 14:49:40 +0100,
 Enrico Mioso wrote:
...
hello Tahaski,
 hello everyone reading this message.
Thank you for the patience you had with me. Now I finally came to set up 
 this system for crash analysis, compiling the kernel with debug 
 informations and frame pointers and so on. I set up kdump also. 
 Unfortunately, I can get no useful output.
 The last things the system wrote before dying have been reconstructed. I 
 noted down decimal values with the help of my mum. She wrote them down 
 on a piece of paper.
 I am hitting the following messages:
 azx_get_response timeout, switching to polling mode: last cmd=0x014ba000
 azx_get_response timeout, switching to single_cmd mode: last 
 cmd=0x014ba000
It's reading the output amp on NID 0x14, the speaker pin.  It's weird
 that such a verb triggers the problem.  Usually the unexpected
 behavior is triggered at the widget power up/down.
If the switching to single cmd mode causes an issue, you can disable
 by the patch like below.  This may lead to the continuous errors,
 though.
Takashi

diff --git a/sound/pci/hda/hda_controller.c
 b/sound/pci/hda/hda_controller.c
 index 500878556578..43bf151d8712 100644
 --- a/sound/pci/hda/hda_controller.c
 +++ b/sound/pci/hda/hda_controller.c
 @@ -861,6 +861,7 @@ static int azx_rirb_get_response(struct hdac_bus *bus,
 unsigned int addr,
   return -EIO;
  }
+#if 0
  /* a fatal communication error; need either to reset or to fallback

to the single_cmd mode

*/
 @@ -875,6 +876,7 @@ static int azx_rirb_get_response(struct hdac_bus *bus,
 unsigned int addr,
  chip->single_cmd = 1;
  hbus->response_reset = 0;
  snd_hdac_bus_stop_cmd_io(bus);
 +#endif
   return -EIO;
 }