[SDW][]BUG][CML-H]Dmesg shows "sof_sdw sof_sdw: ASoC: can't create pcm SDW1-Playback :-22 " when system boot up successfully.

[sinahuang]

Describe the bug
Aplay -l display "no soundcard" when dmesg shows "sof_sdw sof_sdw: ASoC: can't create pcm SDW1-Playback :-22 ".

To Reproduce
1.Boot up system successfully.
2.Aplay -l and then check dmesg.

Reproduce rate
5/5

Expected result
No error display after booting up successfully.

Actual result
Dmesg shows "sof_sdw sof_sdw: ASoC: can't create pcm SDW1-Playback :-22 ".

[    3.200397] sof_sdw sof_sdw: rt711-aif1 <-> SDW0 Pin2 mapping ok
[    3.200401] sof_sdw sof_sdw: rt711-aif1 <-> SDW0 Pin3 mapping ok
[    3.200432]  SDW1-Playback: DPCM doesn't support Multi CPU yet
[    3.200436] sof_sdw sof_sdw: ASoC: can't create pcm SDW1-Playback :-22
[    3.200931] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x1010f0f successful
[    3.200938] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x1000f0f successful
[    3.200941] sof-audio-pci 0000:00:1f.3: DSP core(s) enabled? 0 : core_mask 1
[    3.200950] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.200957] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.200960] sof-audio-pci 0000:00:1f.3: DSP core(s) enabled? 0 : core_mask 1
[    3.200968] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.200974] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.200977] sof-audio-pci 0000:00:1f.3: DSP core(s) enabled? 0 : core_mask 1
[    3.200985] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.200992] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.200995] sof-audio-pci 0000:00:1f.3: DSP core(s) enabled? 0 : core_mask 1
[    3.201003] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.201009] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.201011] sof-audio-pci 0000:00:1f.3: DSP core(s) enabled? 0 : core_mask 1
[    3.201015] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.201021] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.201023] sof-audio-pci 0000:00:1f.3: DSP core(s) enabled? 0 : core_mask 1
[    3.201028] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.201031] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.201033] sof-audio-pci 0000:00:1f.3: DSP core(s) enabled? 0 : core_mask 1
[    3.201038] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.201042] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0xf0f successful
[    3.201044] sof-audio-pci 0000:00:1f.3: DSP core(s) enabled? 0 : core_mask 1
[    3.201247] sof_sdw sof_sdw: snd_soc_register_card failed -22
[    3.201254] sof_sdw: probe of sof_sdw failed with error -22
[    3.236699] scsi 0:0:0:0: Direct-Access     Generic  MassStorageClass 1536 PQ: 0 ANSI: 6
[    3.236834] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    3.442642] scsi 0:0:0:1: Direct-Access     Generic  MassStorageClass 1536 PQ: 0 ANSI: 6
[    3.442760] sd 0:0:0:1: Attached scsi generic sg1 type 0
[    3.465982] buggy seq_file .next function proc_keys_next did not updated position index
[    3.648735] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    3.859688] sd 0:0:0:1: [sdb] Attached SCSI removable disk
[    4.005009] scsi 1:0:0:0: Direct-Access     Kingston DataTraveler 2.0 PMAP PQ: 0 ANSI: 6
[    4.005275] sd 1:0:0:0: Attached scsi generic sg2 type 0
[    4.005640] sd 1:0:0:0: [sdc] 30240768 512-byte logical blocks: (15.5 GB/14.4 GiB)
[    4.005929] sd 1:0:0:0: [sdc] Write Protect is off
[    4.005931] sd 1:0:0:0: [sdc] Mode Sense: 45 00 00 00
[    4.006185] sd 1:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    4.021266]  sdc: sdc1
[    4.023219] sd 1:0:0:0: [sdc] Attached SCSI removable disk
[    5.538656] FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[    5.687935] rfkill: input handler disabled
[    8.226307] intel-master sdw-master-1: IO transfer timed out, cmd 3 device 1 addr 45 len 1
[    8.226312] intel-master sdw-master-1: trf on Slave 1 failed:-110
[    8.226314] rt1308 sdw:1:25d:1308:0: Clock Stop prepare failed for slave: -110
[    8.226315] rt1308 sdw:1:25d:1308:0: pre-prepare failed:-110
[    8.226316] intel-master sdw-master-1: prepare clock stop failed -110
[    8.226317] intel-master sdw-master-1: cannot enable clock stop on suspend
[    8.226322] intel-master sdw-master-2: IO transfer timed out, cmd 3 device 1 addr 45 len 1
[    8.226323] intel-master sdw-master-2: trf on Slave 1 failed:-110
[    8.226324] rt1308 sdw:2:25d:1308:0: Clock Stop prepare failed for slave: -110
[    8.226326] rt1308 sdw:2:25d:1308:0: pre-prepare failed:-110
[    8.226327] intel-master sdw-master-2: prepare clock stop failed -110
[    8.226328] intel-master sdw-master-2: cannot enable clock stop on suspend
[    8.226332] intel-master sdw-master-3: IO transfer timed out, cmd 3 device 1 addr 45 len 1
[    8.226333] intel-master sdw-master-3: trf on Slave 1 failed:-110
[    8.226335] rt715 sdw:3:25d:715:0: Clock Stop prepare failed for slave: -110
[    8.226336] rt715 sdw:3:25d:715:0: pre-prepare failed:-110

Test recipe

Kernel:https://github.com/thesofproject/linux/commits/topic/sof-dev commit:f17b96ae
FW: https://github.com/thesofproject/sof/commits/master commit: 504ec4
Tplg: Same with FW branch, sof-cml-rt711-rt1308-rt715.tplg
Platform:CML-H Laptop with codec ALC711, 2 x RT1308, RT715 in SDW mode.

dmesg21.log

[sinahuang]

I tried it with kernel on ww16.5( top commit:f9b8747), this issue can't be reproduced. But I found this commit seems replaced by commit:18aaab6 , but the SOF can't load up successfully with it.

[bardliao]

It is due to 9b5db05.

[plbossart]

Are you sure @bardliao ? we already define the min_channels in intel_create_dai():

		if (type == INTEL_PDI_BD || type == INTEL_PDI_OUT) {
			dais[i].playback.channels_min = 1;
			dais[i].playback.channels_max = max_ch;
			dais[i].playback.rates = SNDRV_PCM_RATE_48000;
			dais[i].playback.formats = SNDRV_PCM_FMTBIT_S16_LE;
		}

		if (type == INTEL_PDI_BD || type == INTEL_PDI_IN) {
			dais[i].capture.channels_min = 1;
			dais[i].capture.channels_max = max_ch;
			dais[i].capture.rates = SNDRV_PCM_RATE_48000;
			dais[i].capture.formats = SNDRV_PCM_FMTBIT_S16_LE;
		}

So I am not sure what the addition of the new tests in 9b5db05 would change?

[bardliao]

@plbossart Yes, if (rtd->num_cpus > 1) test is failed.
[ 3.200432] SDW1-Playback: DPCM doesn't support Multi CPU yet
That is a new test in 9b5db05 and I verified with my CML-H laptop

[sinahuang]

Tried it with latest sof-dev after reverting commit:9b5db05, issue can't be reproduced. Issue might be caused by this commit.

[plbossart]

@sinahuang can you try with PR #2058 ?

[sinahuang]

Check this issue with sof-dev (commit:8f1c6ad) +PR #2058, error can't be seen.