Error message from ‘msc313-fcie’

[ltrsunny]

Build from branch ‘mstar_v5_15_rebase’, working with a low CPU & memory consumption but very slow.
Got multiple times of such messages:

[   38.888642] msc313-fcie 1f282000.sdio: timeout waiting for interrupt, int: 0000, status: 0000, ctrl: 0306
[   38.888668] msc313-fcie 1f282000.sdio: timedout and no status flags were set
[   38.895767] msc313-fcie 1f282000.sdio: stop command timeout; cmd: 0x0c arg: 0x00000000
[   70.248644] msc313-fcie 1f282000.sdio: timeout waiting for interrupt, int: 0000, status: 0000, ctrl: 0306
[   70.248673] msc313-fcie 1f282000.sdio: timedout and no status flags were set
[   70.255773] msc313-fcie 1f282000.sdio: stop command timeout; cmd: 0x0c arg: 0x00000000

[fifteenhex]

The driver might need some tweaks. Does the SD card keep working when you see those messages?

[fifteenhex]

Please try with mstar_v5_16_rebase now (you'll probably need to force pull). Most of the issues should be gone now. It needs some fixes to make it faster at some point.

[ltrsunny]

Cool! Let me try later.

[ltrsunny]

Have been testing a whole day. Much better than before but still sometimes frozen. I am not sure whether it is about MMC now.

[fifteenhex]

Does it lock up entirely forever or does it eventually come back again?

[ltrsunny]

Does it lock up entirely forever or does it eventually come back again?

It will recover and there is nothing new in dmesg now
There is still something wrong in initialization

[    1.528477] gpio gpiochip1: (gpio-msc313-pm): failed to allocate parent hwirq 73 for hwirq 1
[    1.537633] msc313-fcie 1f282000.sdio: err during job; status: 0008, ctrl: 0206, blksz: 0200, blkcnt: 0001, cmdrspsz: 0505
[    1.548830] msc313-fcie 1f282000.sdio: err during job; cmd 1 (1), data 0 (0), busy 0 (0)
[    1.556988] msc313-fcie 1f282000.sdio: no response from card, removed?
[    1.563592] msc313-fcie 1f282000.sdio: failed to send command; cmd: 52 arg: 0x00000c00
[    1.572159] msc313-fcie 1f282000.sdio: err during job; status: 0008, ctrl: 0206, blksz: 0200, blkcnt: 0001, cmdrspsz: 0505
[    1.583364] msc313-fcie 1f282000.sdio: err during job; cmd 1 (1), data 0 (0), busy 0 (0)
[    1.589184] msc313-rsa 1f224480.rsa: in: fffefdfcfbfaf9f8f7f6f5f4f3f2f1f0efeeedecebeae9e8e7e6e5e4e3e2e1e0dfdedddcdbdad9d8d7d6d5d4d3d2d1d0cfcecdcccbcac9c8c7c6c5c4c3c2c1c0
[    1.591534] msc313-fcie 1f282000.sdio: no response from card, removed?
[    1.606802] msc313-rsa 1f224480.rsa: out: 00000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[    1.613317] msc313-fcie 1f282000.sdio: failed to send command; cmd: 52 arg: 0x80000c08
[    1.629028] cpu cpu0: Looking up cpu-supply from device tree
[    1.636815] cpu cpu0: Looking up cpu-supply from device tree
[    1.637564] clk: Not disabling unused clocks
[    1.641950] ALSA device list:
[    1.644943]   No soundcards found.
[    1.648808] Waiting for root device /dev/mmcblk0p1...
[    1.656109] msc313-fcie 1f282000.sdio: no response from card, removed?
[    1.662730] msc313-fcie 1f282000.sdio: failed to send command; cmd: 5 arg: 0x00000000
[    1.671124] msc313-fcie 1f282000.sdio: no response from card, removed?
[    1.677693] msc313-fcie 1f282000.sdio: failed to send command; cmd: 5 arg: 0x00000000
[    1.686110] msc313-fcie 1f282000.sdio: no response from card, removed?
[    1.692722] msc313-fcie 1f282000.sdio: failed to send command; cmd: 5 arg: 0x00000000
[    1.701120] msc313-fcie 1f282000.sdio: no response from card, removed?
[    1.707688] msc313-fcie 1f282000.sdio: failed to send command; cmd: 5 arg: 0x00000000
[    1.743301] mmc0: host does not support reading read-only switch, assuming write-enable
[    1.753826] mmc0: new high speed SDHC card at address 1234
[    1.760028] mmcblk0: mmc0:1234 SA16G 14.5 GiB
[    1.766032]  mmcblk0: p1

[fifteenhex]

[ 2983.533705] mmc0: starting CMD25 arg 000f4ac0 flags 000000b5
[ 2983.533720] mmc0:     blksz 512 blocks 8 flags 00000100 tsac 3000 ms nsac 0
[ 2983.533736] mmc0:     CMD12 arg 00000000 flags 0000049d
[ 2983.535481] mmc0: req done (CMD25): 0: 00000900 00000000 00000000 00000000
[ 2983.535504] mmc0:     4096 bytes transferred: 0
[ 2983.535515] mmc0:     (CMD12): 0: 00000c00 00000000 00000000 00000000
[ 2983.535548] mmc0: starting CMD13 arg 59b40000 flags 00000195
[ 2983.535608] mmc0: req done (CMD13): 0: 00000900 00000000 00000000 00000000
[ 2983.535637] mmc0: starting CMD25 arg 000f4ac8 flags 000000b5
[ 2983.535653] mmc0:     blksz 512 blocks 8 flags 00000100 tsac 3000 ms nsac 0
[ 2983.535669] mmc0:     CMD12 arg 00000000 flags 0000049d
[ 2983.537441] mmc0: req done (CMD25): 0: 00000900 00000000 00000000 00000000
[ 2983.537468] mmc0:     4096 bytes transferred: 0
[ 2[ 3014.166621] msc313-fcie 1f282000.sdio: timeout waiting for interrupt, int: 0000, status: 0000, ctrl: 0306
983.537478] mmc0[ 3014.176547] msc313-fcie 1f282000.sdio: timedout and no status flags were set
:     (CMD12): 0[ 3014.185006] msc313-fcie 1f282000.sdio: stop command timeout; cmd: 0x0c arg: 0x00000000
: 00000c00 00000000 00000000 00000000

[fifteenhex]

Looks like sometimes the response for CMD12 (stop) after a CMD25 (multiblock transfer) for dirty page writeout isn't coming or isn't triggering an interrupt. The data is getting written correctly though.

[ltrsunny]

[ 2983.533705] mmc0: starting CMD25 arg 000f4ac0 flags 000000b5
[ 2983.533720] mmc0:     blksz 512 blocks 8 flags 00000100 tsac 3000 ms nsac 0
[ 2983.533736] mmc0:     CMD12 arg 00000000 flags 0000049d
[ 2983.535481] mmc0: req done (CMD25): 0: 00000900 00000000 00000000 00000000
[ 2983.535504] mmc0:     4096 bytes transferred: 0
[ 2983.535515] mmc0:     (CMD12): 0: 00000c00 00000000 00000000 00000000
[ 2983.535548] mmc0: starting CMD13 arg 59b40000 flags 00000195
[ 2983.535608] mmc0: req done (CMD13): 0: 00000900 00000000 00000000 00000000
[ 2983.535637] mmc0: starting CMD25 arg 000f4ac8 flags 000000b5
[ 2983.535653] mmc0:     blksz 512 blocks 8 flags 00000100 tsac 3000 ms nsac 0
[ 2983.535669] mmc0:     CMD12 arg 00000000 flags 0000049d
[ 2983.537441] mmc0: req done (CMD25): 0: 00000900 00000000 00000000 00000000
[ 2983.537468] mmc0:     4096 bytes transferred: 0
[ 2[ 3014.166621] msc313-fcie 1f282000.sdio: timeout waiting for interrupt, int: 0000, status: 0000, ctrl: 0306
983.537478] mmc0[ 3014.176547] msc313-fcie 1f282000.sdio: timedout and no status flags were set
:     (CMD12): 0[ 3014.185006] msc313-fcie 1f282000.sdio: stop command timeout; cmd: 0x0c arg: 0x00000000
: 00000c00 00000000 00000000 00000000

How do you get these outputs?

[fifteenhex]

I used dynamic debug to enable the debug messages..

[ltrsunny]

Any ideas about the bug？