“CHANSEL_NOMATCH channel:0x10 ” error and failed to capture image on Xavier

Hi, Guys,

I’m blocked by the issue of “CHANSEL_NOMATCH”, and couldn’t capture image from mipi csi port on Xavier with the below trace log.
I’m confuse of the value of “0x10” for “channel:”, is it means “virtual channel”? but i’m set vc as 0 only.
And i’m also don’t understand the means of value “0x00000489” for “data:”, I guess the value should be read from someone VI status register, but I chouldn’t found it from the TRM, can you help that?
Can you give any advice to debug it? Thank you in advance!

kworker/0:0-14093 [000] … 10777.694660: rtcpu_vinotify_event: tstamp:337161010390 tag:FS channel:0x00 frame:0 vi_tstamp:337160489545 data:0x00000014
kworker/0:0-14093 [000] … 10777.694660: rtcpu_vinotify_event: tstamp:337161010564 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337160490507 data:0x00000489
kworker/0:0-14093 [000] … 10777.694661: rtcpu_vinotify_event: tstamp:337161553197 tag:FE channel:0x00 frame:0 vi_tstamp:337161500444 data:0x00000024
kworker/0:0-14093 [000] … 10777.694661: rtcpu_vinotify_error: tstamp:337161582690 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337161579871 data:0x00000489
kworker/0:0-14093 [000] … 10777.694662: rtos_queue_peek_from_isr_failed: tstamp:337161896207 queue:0x0bcbcf78
kworker/0:0-14093 [000] … 10777.694663: rtcpu_vinotify_event: tstamp:337162096133 tag:FS channel:0x00 frame:0 vi_tstamp:337161578910 data:0x00000014
kworker/0:0-14093 [000] … 10777.694664: rtcpu_vinotify_event: tstamp:337162096281 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337161579871 data:0x00000489
kworker/0:0-14093 [000] … 10777.750662: rtcpu_vinotify_event: tstamp:337162638147 tag:FE channel:0x00 frame:0 vi_tstamp:337162589808 data:0x00000024
kworker/0:0-14093 [000] … 10777.750662: rtcpu_vinotify_error: tstamp:337162672058 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337162669235 data:0x00000489
kworker/0:0-14093 [000] … 10777.750663: rtcpu_vinotify_event: tstamp:337163181081 tag:FS channel:0x00 frame:0 vi_tstamp:337162668274 data:0x00000014
kworker/0:0-14093 [000] … 10777.750663: rtcpu_vinotify_event: tstamp:337163181252 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337162669235 data:0x00000489
kworker/0:0-14093 [000] … 10777.750663: rtcpu_vinotify_event: tstamp:337163723090 tag:FE channel:0x00 frame:0 vi_tstamp:337163679173 data:0x00000024
kworker/0:0-14093 [000] … 10777.750663: rtcpu_vinotify_error: tstamp:337163761562 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337163758600 data:0x00000489
kworker/0:0-14093 [000] … 10777.750664: rtcpu_vinotify_event: tstamp:337163761960 tag:FS channel:0x00 frame:0 vi_tstamp:337163757639 data:0x00000014
kworker/0:0-14093 [000] … 10777.750664: rtcpu_vinotify_event: tstamp:337163762108 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337163758600 data:0x00000489
kworker/0:0-14093 [000] … 10777.806661: rtcpu_vinotify_event: tstamp:337164808332 tag:FE channel:0x00 frame:0 vi_tstamp:337164768536 data:0x00000024
kworker/0:0-14093 [000] … 10777.806662: rtcpu_vinotify_error: tstamp:337164850786 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337164847964 data:0x00000489
kworker/0:0-14093 [000] … 10777.806662: rtcpu_vinotify_event: tstamp:337165351208 tag:FS channel:0x00 frame:0 vi_tstamp:337164847002 data:0x00000014
kworker/0:0-14093 [000] … 10777.806662: rtcpu_vinotify_event: tstamp:337165351382 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337164847964 data:0x00000489
kworker/0:0-14093 [000] … 10777.806663: rtcpu_vinotify_event: tstamp:337165893218 tag:FE channel:0x00 frame:0 vi_tstamp:337165857902 data:0x00000024
kworker/0:0-14093 [000] … 10777.806663: rtcpu_vinotify_error: tstamp:337165940150 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337165937329 data:0x00000489
kworker/0:0-14093 [000] … 10777.862671: rtcpu_vinotify_event: tstamp:337166436154 tag:FS channel:0x00 frame:0 vi_tstamp:337165936367 data:0x00000014
kworker/0:0-14093 [000] … 10777.862671: rtcpu_vinotify_event: tstamp:337166436304 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337165937329 data:0x00000489
kworker/0:0-14093 [000] … 10777.862673: rtos_queue_peek_from_isr_failed: tstamp:337166896208 queue:0x0bcbcf78
kworker/0:0-14093 [000] … 10777.862674: rtcpu_vinotify_event: tstamp:337166978961 tag:FE channel:0x00 frame:0 vi_tstamp:337166947266 data:0x00000024
kworker/0:0-14093 [000] … 10777.862674: rtcpu_vinotify_error: tstamp:337167029512 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337167026693 data:0x00000489
kworker/0:0-14093 [000] … 10777.862675: rtcpu_vinotify_event: tstamp:337167521093 tag:FS channel:0x00 frame:0 vi_tstamp:337167025732 data:0x00000014
kworker/0:0-14093 [000] … 10777.862675: rtcpu_vinotify_event: tstamp:337167521264 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337167026693 data:0x00000489
kworker/0:0-14093 [000] … 10777.918662: rtcpu_vinotify_event: tstamp:337168063909 tag:FE channel:0x00 frame:0 vi_tstamp:337168036630 data:0x00000024
kworker/0:0-14093 [000] … 10777.918663: rtcpu_vinotify_error: tstamp:337168118878 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337168116058 data:0x00000489
kworker/0:0-14093 [000] … 10777.918663: rtcpu_vinotify_event: tstamp:337168606042 tag:FS channel:0x00 frame:0 vi_tstamp:337168115096 data:0x00000014
kworker/0:0-14093 [000] … 10777.918664: rtcpu_vinotify_event: tstamp:337168606190 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337168116058 data:0x00000489
kworker/0:0-14093 [000] … 10777.918664: rtcpu_vinotify_event: tstamp:337169148851 tag:FE channel:0x00 frame:0 vi_tstamp:337169125995 data:0x00000024
kworker/0:0-14093 [000] … 10777.918664: rtcpu_vinotify_error: tstamp:337169208240 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337169205422 data:0x00000489
kworker/0:0-14093 [000] … 10777.974664: rtcpu_vinotify_event: tstamp:337169690990 tag:FS channel:0x00 frame:0 vi_tstamp:337169204461 data:0x00000014
kworker/0:0-14093 [000] … 10777.974665: rtcpu_vinotify_event: tstamp:337169691168 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337169205422 data:0x00000489
kworker/0:0-14093 [000] … 10777.974665: rtcpu_vinotify_event: tstamp:337170217807 tag:FE channel:0x00 frame:0 vi_tstamp:337170215359 data:0x00000024
kworker/0:0-14093 [000] … 10777.974666: rtcpu_vinotify_error: tstamp:337170297615 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337170294787 data:0x00000489
kworker/0:0-14093 [000] … 10777.974666: rtcpu_vinotify_event: tstamp:337170721946 tag:FS channel:0x00 frame:0 vi_tstamp:337170293825 data:0x00000014
kworker/0:0-14093 [000] … 10777.974666: rtcpu_vinotify_event: tstamp:337170722091 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337170294787 data:0x00000489
kworker/0:0-14093 [000] … 10778.030665: rtcpu_vinotify_error: tstamp:337171386968 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337171384151 data:0x00000489
kworker/0:0-14093 [000] … 10778.030668: rtcpu_vinotify_event: tstamp:337171806893 tag:FE channel:0x00 frame:0 vi_tstamp:337171304724 data:0x00000024
kworker/0:0-14093 [000] … 10778.030669: rtcpu_vinotify_event: tstamp:337171807041 tag:FS channel:0x00 frame:0 vi_tstamp:337171383190 data:0x00000014
kworker/0:0-14093 [000] … 10778.030669: rtcpu_vinotify_event: tstamp:337171807213 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337171384151 data:0x00000489
kworker/0:0-14093 [000] … 10778.030672: rtos_queue_peek_from_isr_failed: tstamp:337171896204 queue:0x0bcbcf78
kworker/0:0-14093 [000] … 10778.030676: rtcpu_vinotify_error: tstamp:337172476335 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337172473516 data:0x00000489
kworker/0:0-14093 [000] … 10778.030676: rtcpu_vinotify_event: tstamp:337172892641 tag:FE channel:0x00 frame:0 vi_tstamp:337172394088 data:0x00000024
kworker/0:0-14093 [000] … 10778.030676: rtcpu_vinotify_event: tstamp:337172892814 tag:FS channel:0x00 frame:0 vi_tstamp:337172472554 data:0x00000014
kworker/0:0-14093 [000] … 10778.030676: rtcpu_vinotify_event: tstamp:337172892964 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337172473516 data:0x00000489
kworker/0:0-14093 [000] … 10778.086676: rtcpu_vinotify_error: tstamp:337173565713 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337173562886 data:0x00000489
kworker/0:0-14093 [000] … 10778.086676: rtcpu_vinotify_event: tstamp:337173977593 tag:FE channel:0x00 frame:0 vi_tstamp:337173483453 data:0x00000024
kworker/0:0-14093 [000] … 10778.086677: rtcpu_vinotify_event: tstamp:337173977742 tag:FS channel:0x00 frame:0 vi_tstamp:337173561919 data:0x00000014
kworker/0:0-14093 [000] … 10778.086677: rtcpu_vinotify_event: tstamp:337173977916 tag:CHANSEL_NOMATCH channel:0x10 frame:0 vi_tstamp:337173562886 data:0x00000489


At the same time, the syslog as below:
[10478.670682] tegra194-vi5 15c10000.vi: no reply from camera processor
[10478.670848] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[10478.670994] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[10478.673875] tegra194-vi5 15c10000.vi: err_rec: successfully reset the capture channel
[10478.740271] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 128, err_data 131072
[10481.486682] tegra194-vi5 15c10000.vi: no reply from camera processor
[10481.486903] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[10481.487043] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[10481.490522] tegra194-vi5 15c10000.vi: err_rec: successfully reset the capture channel
[10481.529039] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 128, err_data 131072
[10484.046684] tegra194-vi5 15c10000.vi: no reply from camera processor
[10484.046849] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[10484.046985] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[10484.050687] tegra194-vi5 15c10000.vi: err_rec: successfully reset the capture channel
[10484.108686] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 128, err_data 131072
[10486.862675] tegra194-vi5 15c10000.vi: no reply from camera processor
[10486.862836] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[10486.862970] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[10486.864835] tegra194-vi5 15c10000.vi: err_rec: successfully reset the capture channel
[10486.932342] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 128, err_data 131072
[10489.678686] tegra194-vi5 15c10000.vi: no reply from camera processor
[10489.678853] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[10489.678987] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[10489.681875] tegra194-vi5 15c10000.vi: err_rec: successfully reset the capture channel
[10489.721092] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 128, err_data 131072
[10492.238694] tegra194-vi5 15c10000.vi: no reply from camera processor
[10492.238862] tegra194-vi5 15c10000.vi: uncorr_err: request timed out after 2500 ms
[10492.238996] tegra194-vi5 15c10000.vi: err_rec: attempting to reset the capture channel
[10492.242500] tegra194-vi5 15c10000.vi: err_rec: successfully reset the capture channel
[10492.300676] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 128, err_data 131072

The data:0x00000489 have check the CHANSEL_NOMATCH from the https://elinux.org/Jetson_TX2_Camera_BringUp

channel:0x10 here is the csi_stream number.

Hi, ShaneCCC,

Thanks you for reply!
But the article of this link should be for TX2, are you sure it’s available for Xavier also?

As the description for the issue in this article, the 0x00000489 should be indicated LS package’s data type is no match RGB888? But the data from camera is yuv422 8bit,and Xavier platform set as yuv 422 8bit also, I’m confuse why VI have captured rgb888 data for the LS short package ? If any idea for it? Thanks!

CHANSEL_NOMATCH
bit 0 no_match
bit 1-4 CTYPE
bit 5-10 DTYPE
CTYPE:
FS = 0x8, FE = 0x1
LS = 0x4, LE = 0x2
LS_LE = 0x6, DATA = 0x9
DTYPE:
NvCsiDataType_Unspecified = 0,
NvCsiDataType_YUV420_10 = 25,
NvCsiDataType_LEG_YUV420_8 = 26,
NvCsiDataType_YUV420_8 = 24,
NvCsiDataType_YUV420CSPS_8 = 28,
NvCsiDataType_YUV420CSPS_10 = 29,
NvCsiDataType_YUV422_8 = 30,
NvCsiDataType_YUV422_10 = 31,
NvCsiDataType_RGB444 = 32,
NvCsiDataType_RGB555 = 33,
NvCsiDataType_RGB565 = 34,
NvCsiDataType_RGB666 = 35,
NvCsiDataType_RGB888 = 36,
NvCsiDataType_RAW6 = 40,
NvCsiDataType_RAW7 = 41,
NvCsiDataType_RAW8 = 42,
NvCsiDataType_RAW10 = 43,
NvCsiDataType_RAW12 = 44,
NvCsiDataType_RAW14 = 45,
NvCsiDataType_RAW16 = 46,
NvCsiDataType_RAW20 = 47,
NvCsiDataType_User_1 = 48,
NvCsiDataType_User_2 = 49,
NvCsiDataType_User_3 = 50,
NvCsiDataType_User_4 = 51,
NvCsiDataType_User_5 = 52,
NvCsiDataType_User_6 = 53,
NvCsiDataType_User_7 = 54,
NvCsiDataType_User_8 = 55,
NvCsiDataType_Unknown = 64,

Yes, Xavier VI notify is the same with TX2.

Thank you Shane!
All the issue have been fixed, and now my GMSL2 camera with max96712 and max9295 can work well on Xavier.