MIPI CSI VC problem

cloundliu · September 24, 2021, 2:48am

Hi all,

The platform is Xavier / Linux BSP 32.4.3.

Hardware configuration is as follows:
sensor–> TI DS90UB33 (serializer)--------TI DS90UB960 (deserializer)–> AGX Xavier

for MIPI input:

continuous_clk, 2x2 lane, 2 VC(0 and 1)
sensor data always stream on
1280x960@30Hz yuv 8bit

The problem is:

Xavier receive image data correctly most of time and no error for trace logs.
If I use follow script to test all 2 cameras which shared one MIPI-CSI phy at the same time, sometimes it both failed, sometimes one failed, can’t recovery and hang out.
For both failed case, I use Ctrl+C to kill one of the two camera test this moment, and another test sometimes recovered from hang out.

#!/bin/bash
count=0

while [ 1 ]
do
    v4l2-ctl -d /dev/video$1 --set-fmt-video=width=1280,height=960,pixelformat=UYVY --set-ctrl bypass_mode=0 --stream-mmap --stream-count=30
    let count=$count+1
    echo "###############$count#####################"
    sleep 1
done

kernel log:

[  593.504762] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.537981] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.570490] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 246, err_data 6291552
[  593.571301] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 544
[  593.604752] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.638100] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.671451] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.704785] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.737244] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 246, err_data 6291552
[  593.738082] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 544
[  593.771578] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.804884] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.838193] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.871572] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.904057] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 246, err_data 6291552
[  593.904964] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 544
[  593.938237] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.971601] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.004926] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.038412] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.070837] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 246, err_data 6291552
[  594.071745] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 544
[  594.105127] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.138458] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.171814] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544

Or

[ 1060.340601] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 160
[ 1060.668377] tegra194-vi5 15c10000.vi: no reply from camera processor
[ 1060.668562] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[ 1060.668703] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[ 1060.671334] tegra194-vi5 15c10000.vi: err_rec: successfully reset the capture channel

trace log:

     kworker/5:2-15013 [005] ....   718.926237: rtcpu_nvcsi_intr: tstamp:22784863935 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000100
     kworker/5:2-15013 [005] ....   718.926238: rtcpu_nvcsi_intr: tstamp:22784863935 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:0 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926238: rtcpu_nvcsi_intr: tstamp:22784863935 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:0 st:0 vc:0 status:0x00000100
     kworker/5:2-15013 [005] ....   718.926238: rtcpu_nvcsi_intr: tstamp:22784864624 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:0 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926238: rtcpu_nvcsi_intr: tstamp:22784864624 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000110
     kworker/5:2-15013 [005] ....   718.926238: rtcpu_nvcsi_intr: tstamp:22784864624 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000002
     kworker/5:2-15013 [005] ....   718.926239: rtcpu_nvcsi_intr: tstamp:22784864624 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:0 st:0 vc:0 status:0x00000010
     kworker/5:2-15013 [005] ....   718.926239: rtcpu_nvcsi_intr: tstamp:22784865512 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926239: rtcpu_nvcsi_intr: tstamp:22784865512 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000010
     kworker/5:2-15013 [005] ....   718.926239: rtcpu_nvcsi_intr: tstamp:22784865512 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
     kworker/5:2-15013 [005] ....   718.926239: rtcpu_nvcsi_intr: tstamp:22784865512 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:0 st:0 vc:0 status:0x00000100
     kworker/5:2-15013 [005] ....   718.926239: rtcpu_nvcsi_intr: tstamp:22784866400 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:0 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926240: rtcpu_nvcsi_intr: tstamp:22784866400 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000100
     kworker/5:2-15013 [005] ....   718.926240: rtcpu_nvcsi_intr: tstamp:22784866400 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:0 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926240: rtcpu_nvcsi_intr: tstamp:22784866400 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:0 st:0 vc:0 status:0x00000110
     kworker/5:2-15013 [005] ....   718.926240: rtcpu_nvcsi_intr: tstamp:22784867290 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926240: rtcpu_nvcsi_intr: tstamp:22784867290 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000010
     kworker/5:2-15013 [005] ....   718.926241: rtcpu_nvcsi_intr: tstamp:22784867290 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926241: rtcpu_nvcsi_intr: tstamp:22784867290 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:0 st:0 vc:0 status:0x00000010
     kworker/5:2-15013 [005] ....   718.926241: rtcpu_nvcsi_intr: tstamp:22784868173 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:0 status:0x00000002
     kworker/5:2-15013 [005] ....   718.926241: rtcpu_nvcsi_intr: tstamp:22784868173 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000100
     kworker/5:2-15013 [005] ....   718.926241: rtcpu_nvcsi_intr: tstamp:22784868173 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:0 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926242: rtcpu_nvcsi_intr: tstamp:22784868173 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:0 st:0 vc:0 status:0x00000100
     kworker/5:2-15013 [005] ....   718.926242: rtcpu_nvcsi_intr: tstamp:22784869069 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:0 status:0x00000004
     kworker/5:2-15013 [005] ....   718.926242: rtcpu_nvcsi_intr: tstamp:22784869069 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000010
     kworker/5:2-15013 [005] ....   718.926242: rtcpu_nvcsi_intr: tstamp:22784869069 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926242: rtcpu_nvcsi_intr: tstamp:22784869069 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:0 st:0 vc:0 status:0x00000010
     kworker/5:2-15013 [005] ....   718.926242: rtcpu_nvcsi_intr: tstamp:22784869962 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926243: rtcpu_nvcsi_intr: tstamp:22784869962 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000110
     kworker/5:2-15013 [005] ....   718.926243: rtcpu_nvcsi_intr: tstamp:22784869962 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:0 status:0x00000006
     kworker/5:2-15013 [005] ....   718.926243: rtcpu_nvcsi_intr: tstamp:22784869962 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:0 st:0 vc:0 status:0x00000100

How to fix the aforementioned problem?
Is there anything special that should be done with Xavier MIPI CS2 timing?
Xavier MIPI CSI/VI driver bug and so on?

thanks

ShaneCCC · September 27, 2021, 8:57am

Try boost the NVCSI/VI clocks to try.

sudo su
echo 1 > /sys/kernel/debug/bpmp/debug/clk/vi/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/isp/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/nvcsi/mrq_rate_locked
cat /sys/kernel/debug/bpmp/debug/clk/vi/max_rate |tee /sys/kernel/debug/bpmp/debug/clk/vi/rate
cat /sys/kernel/debug/bpmp/debug/clk/isp/max_rate | tee  /sys/kernel/debug/bpmp/debug/clk/isp/rate
cat /sys/kernel/debug/bpmp/debug/clk/nvcsi/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/nvcsi/rate

cloundliu · September 27, 2021, 9:15am

Thans for your reply.

I have bootsted the NVCSI/VI clocks. If I don’t do that, Xavier will reboot due to video frame capturing error as follows:

[  593.904964] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 544
[  593.938237] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  593.971601] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.004926] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.038412] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.070837] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 246, err_data 6291552
[  594.071745] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 544
[  594.105127] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544
[  594.138458] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 38, err_data 544

and kernel dmesg:

CPU5: SError detected, daif=1c0, spsr=0x40c000c5, mpidr=80000201, esr=be000000
CPU3: SError detected, daif=1c0, spsr=0x40c000c5, mpidr=80000101, esr=be000000
CPU1: SError detected, daif=1c0, spsr=0x40c000c5, mpidr=80000001, esr=be000000
CPU4: SError detected, daif=1c0, spsr=0x40c000c5, mpidr=80000200, esr=be000000
CPU7: SError detected, daif=1c0, spsr=0x40c000c5, mpidr=80000301, esr=be000000
**************************************
* For more Internal Decode Help
* http://nv/cbberr
* NVIDIA userID is required to access
**************************************
CPU:3, Error:RCE-NOC
Error Logger : 1
ErrLog0 : 0x80030600
Transaction Type : RD - Read, Incrementing
Error Code : TMO
Error Source : Target NIU
Error Description : Target time-out error
Packet header Lock : 0
Packet header Len1 : 3
NOC protocol version : version >= 2.7
ErrLog1 : 0x157600
ErrLog2 : 0x0
...

After bootsted the NVCSI/VI clocks, the error sometimes still happens, but xavier not reboot.

ShaneCCC · September 27, 2021, 10:03am

Which trace log from boost the clocks?

cloundliu · September 27, 2021, 11:41pm

kernel log：

[  543.611245] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.644603] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.677963] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.692400] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.711961] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 240, err_data 6291553
[  543.744660] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.778028] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.811393] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.844740] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.854826] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.878746] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 240, err_data 6291553
[  543.911458] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.944807] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  543.978176] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  544.011549] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  544.019656] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  544.045576] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 240, err_data 6291553
[  544.078248] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  544.111614] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  544.144976] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  544.178367] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545
[  544.211671] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 32, err_data 545

trace log:

     kworker/2:4-5998  [002] ....   610.811058: rtcpu_nvcsi_intr: tstamp:19413275202 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000002
     kworker/2:4-5998  [002] ....   610.811059: rtcpu_nvcsi_intr: tstamp:19413275202 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000100
     kworker/2:4-5998  [002] ....   610.811059: rtcpu_nvcsi_intr: tstamp:19413275202 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811059: rtcpu_nvcsi_intr: tstamp:19413275202 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:1 st:0 vc:0 status:0x00000100
     kworker/2:4-5998  [002] ....   610.811059: rtcpu_nvcsi_intr: tstamp:19413276094 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000004
     kworker/2:4-5998  [002] ....   610.811059: rtcpu_nvcsi_intr: tstamp:19413276094 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000010
     kworker/2:4-5998  [002] ....   610.811059: rtcpu_nvcsi_intr: tstamp:19413276094 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:0 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811059: rtcpu_nvcsi_intr: tstamp:19413276094 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:1 st:0 vc:0 status:0x00000010
     kworker/2:4-5998  [002] ....   610.811059: rtcpu_nvcsi_intr: tstamp:19413276982 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:0 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811060: rtcpu_nvcsi_intr: tstamp:19413276982 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000110
     kworker/2:4-5998  [002] ....   610.811060: rtcpu_nvcsi_intr: tstamp:19413276982 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000002
     kworker/2:4-5998  [002] ....   610.811060: rtcpu_nvcsi_intr: tstamp:19413276982 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:1 st:0 vc:0 status:0x00000100
     kworker/2:4-5998  [002] ....   610.811060: rtcpu_nvcsi_intr: tstamp:19413277870 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811060: rtcpu_nvcsi_intr: tstamp:19413277870 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000110
     kworker/2:4-5998  [002] ....   610.811060: rtcpu_nvcsi_intr: tstamp:19413277870 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000004
     kworker/2:4-5998  [002] ....   610.811060: rtcpu_nvcsi_intr: tstamp:19413277870 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:1 st:0 vc:0 status:0x00000010
     kworker/2:4-5998  [002] ....   610.811061: rtcpu_nvcsi_intr: tstamp:19413278761 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:0 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811061: rtcpu_nvcsi_intr: tstamp:19413278761 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000110
     kworker/2:4-5998  [002] ....   610.811061: rtcpu_nvcsi_intr: tstamp:19413278761 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:0 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811061: rtcpu_nvcsi_intr: tstamp:19413278761 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:1 st:0 vc:0 status:0x00000100
     kworker/2:4-5998  [002] ....   610.811061: rtcpu_nvcsi_intr: tstamp:19413279651 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811061: rtcpu_nvcsi_intr: tstamp:19413279651 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000100
     kworker/2:4-5998  [002] ....   610.811061: rtcpu_nvcsi_intr: tstamp:19413279651 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811062: rtcpu_nvcsi_intr: tstamp:19413279651 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:1 st:0 vc:0 status:0x00000110
     kworker/2:4-5998  [002] ....   610.811062: rtcpu_nvcsi_intr: tstamp:19413280579 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:0 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811062: rtcpu_nvcsi_intr: tstamp:19413280579 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000010
     kworker/2:4-5998  [002] ....   610.811062: rtcpu_nvcsi_intr: tstamp:19413280579 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:0 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811062: rtcpu_nvcsi_intr: tstamp:19413280579 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:1 st:0 vc:0 status:0x00000110
     kworker/2:4-5998  [002] ....   610.811062: rtcpu_nvcsi_intr: tstamp:19413281469 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000002
     kworker/2:4-5998  [002] ....   610.811062: rtcpu_nvcsi_intr: tstamp:19413281469 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000100
     kworker/2:4-5998  [002] ....   610.811062: rtcpu_nvcsi_intr: tstamp:19413281469 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000006
     kworker/2:4-5998  [002] ....   610.811063: rtcpu_nvcsi_intr: tstamp:19413281469 class:CORRECTABLE_ERR type:PHY_INTR phy:0 cil:1 st:0 vc:0 status:0x00000100
     kworker/2:4-5998  [002] ....   610.811063: rtcpu_nvcsi_intr: tstamp:19413282356 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:1 vc:1 status:0x00000004
     kworker/2:4-5998  [002] ....   610.811063: rtcpu_nvcsi_intr: tstamp:19413282356 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00000010
     kworker/2:4-5998  [002] ....   610.811063: rtcpu_nvcsi_intr: tstamp:19413282356 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:1 vc:0 status:0x00000006

ShaneCCC · September 28, 2021, 1:47am

I mean trace log instead of kernel log.

Thanks

cloundliu · September 28, 2021, 3:19am

trace log update :MIPI CSI VC problem - #6 by cloundliu

ShaneCCC · September 28, 2021, 5:30am

There have ECC and CRC error for the package. Have a check the REG NVCSI_STREAM_0_INTR_STATUS_VC0_0 in TRM.

If each sensor working individual it could be the TI chip configure problem or signal interference.

cloundliu · September 28, 2021, 5:53am

I have checked it before.

The script just open/close camera loop, and it will not config SerDes and camera due to the sensor data is always stream on.
I think it maybe not TI chip configure problem, because the serdes driver is normaly used on the IMX8 platform.

Is it possible that driver problem about MIPI CSI or somthing else?

ShaneCCC · September 28, 2021, 6:03am

I can make sure NVCSI/VI driver been verified the virtual channel simultaneously.

cloundliu · September 29, 2021, 8:07am

update test result:

Stream on/off in start_stream/stop_stream callback

1. test one of the camera in the two using following script, no error, always good.
2. test both of the two camera at the same time, sometimes hang out, can't recovery.

Always stream on

 test one of the camera in the two using follow script, sometimes hang out, can't recovery.

From the test result, I think if there are more than one vc stream on, It sometimes will hang out, can’t recovery.
Is it could be the TI chip configure problem or signal interference??
What else reason could it be ??

test scripts:

#!/bin/bash

count=0

while [ 1 ]
do
	v4l2-ctl -d /dev/video$1 --set-fmt-video=width=1280,height=960,pixelformat=UYVY --set-ctrl bypass_mode=0 --stream-mmap --stream-count=5
	let count=$count+1
	echo "################count=$count###################################"
	sleep 0.1
done

thanks

ShaneCCC · September 29, 2021, 11:24am

Looks like TI chip configure problem. Check configure TI output test pattern to eliminate the sensor configure.