Use 4 CPU for Multithreading using C in xavier NX

Could you enable the trace log the check SOF interval.

echo 1 > /sys/kernel/debug/tracing/tracing_on
echo 30720 > /sys/kernel/debug/tracing/buffer_size_kb
echo 1 > /sys/kernel/debug/tracing/events/tegra_rtcpu/enable
echo 1 > /sys/kernel/debug/tracing/events/freertos/enable
echo 2 > /sys/kernel/debug/camrtc/log-level
echo 1 > /sys/kernel/debug/tracing/events/camera_common/enable
echo > /sys/kernel/debug/tracing/trace
cat /sys/kernel/debug/tracing/trace

Hi ShaneCC,
Thanks for your support, Here I have attached my trace log.

Thanks for your timetrace.log (190.2 KB)

The trace log show the SOF interval is 0.32xx seconds that tell output just about 3 -4 fps only.

Hi ShaneCC,
Thanks for your support, How to increase it speed. Could you suggest any Idea.

Here I have attached my clock detais
sudo /usr/bin/jetson_clocks --show
SOC family:tegra194 Machine:NVIDIA Jetson Xavier NX Developer Kit
Online CPUs: 0-5
CPU Cluster Switching: Disabled
cpu0: Online=1 Governor=schedutil MinFreq=1420800 MaxFreq=1420800 CurrentFreq=1420800 IdleStates: C1=0 c6=0
cpu1: Online=1 Governor=schedutil MinFreq=1420800 MaxFreq=1420800 CurrentFreq=1420800 IdleStates: C1=0 c6=0
cpu2: Online=1 Governor=schedutil MinFreq=1420800 MaxFreq=1420800 CurrentFreq=1420800 IdleStates: C1=0 c6=0
cpu3: Online=1 Governor=schedutil MinFreq=1420800 MaxFreq=1420800 CurrentFreq=1420800 IdleStates: C1=0 c6=0
cpu4: Online=1 Governor=schedutil MinFreq=1420800 MaxFreq=1420800 CurrentFreq=1420800 IdleStates: C1=0 c6=0
cpu5: Online=1 Governor=schedutil MinFreq=1420800 MaxFreq=1420800 CurrentFreq=1420800 IdleStates: C1=0 c6=0
GPU MinFreq=1109250000 MaxFreq=1109250000 CurrentFreq=1109250000
EMC MinFreq=204000000 MaxFreq=1600000000 CurrentFreq=1600000000 FreqOverride=1
Fan: speed=0
NV Power Mode: MODE_15W_6CORE

Thanks for your time

I think it couldn’t be the system performance cause the problem.
From the trace I think it could be the sensor output problem.

Hi ShaneCC,
Thanks for your support, I will check with my team, Could please give any suggestion for this Multi Virtual Channel Implementation in a CSI port using Xavier NX - #59 by JerryChang.

Thanks for your time

Hi ShaneCCC,
Actually I missed to enable echo 2 > /sys/kernel/debug/camrtc/log-level. After enabling it, it’s showing some trace could you please check it and give some idea. sorry
Here I have attached trace.logtrace.log (75.2 KB)
Thanks for your time.

Do you set embedded_metadata_height = 1 in device tree?
And have a confirm the sensor have output embedded data line.

Hi ShaneCC,
Thanks for your support, After adding embedded_metadata_height = 1, it’s not reading anything . Here I have attached my latest trace.log FYI. I will check with my team about whether it’s sending metadata or not and update you.

Thanks for your timeEmbedded_trace.log (44.0 KB)

Hi ShaneCCC,
I have Checked with my team, That the sensor not sending any embedded data line. My sensor send data as a chirp per trigger which contain 32frames and 8 frames per channel. In that I can read 1 frames per VC and others I can’t when am reading in parallel.
Use 4 CPU for Multithreading using C in xavier NX - #28 by PaulEnoch , This trace log is when am reading only one channel, And it’s reading 4frames/2frames out of 8 in a chirp.

Here I have attached trace when am reading data in parallel4VCtrace.log (102.0 KB) .

Could you please suggest any idea

Thanks for your time

This log show the FS interval for the same channel like 0.000064, does this capture have frame drop too?

 kworker/0:2-10294 [000] ....  9338.911928: rtcpu_vinotify_event: tstamp:292245506141 tag:FS channel:0x00 frame:0 vi_tstamp:292245499130 data:0x00000010

kworker/0:2-10294 [000] … 9338.911992: rtcpu_vinotify_event: tstamp:292245528589 tag:FS channel:0x00 frame:0 vi_tstamp:292245518947 data:0x00000010

Hi ShaneCC,
Thanks for your support, Yes it have frame drop. In 8 frame I can read only 2 or 3 frames, others get dropped. Could you please suggest any solution.

thanks for your time

Can configure output only one channel to clarify?

Hi ShaneCC,
Thanks for your support, When I configure for one VC, Its showing following trace

Here I have attached trace log.
Kindly give any suggestion to over come.
kworker/1:0-9413 [001] … 2202.218212: rtos_queue_send_from_isr_failed: tstamp:69222809672 queue:0x0bcbb3a0
kworker/1:0-9413 [001] … 2202.218213: rtos_queue_send_from_isr_failed: tstamp:69222809817 queue:0x0bcbc160
kworker/1:3-9733 [001] … 2202.273978: rtcpu_vinotify_event: tstamp:69223343837 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:67646406304 data:0x10000000
kworker/1:3-9733 [001] … 2202.273982: rtcpu_vinotify_event: tstamp:69223344004 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:67646435744 data:0x31000001
kworker/1:3-9733 [001] … 2202.273984: rtcpu_vinotify_event: tstamp:69223344143 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:67646445952 data:0x10000000
kworker/1:3-9733 [001] … 2202.273985: rtcpu_vinotify_event: tstamp:69223344299 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:67646454400 data:0x07020001
kworker/1:3-9733 [001] … 2202.273986: rtcpu_vinotify_event: tstamp:69223344435 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:67646484832 data:0x31000002
kworker/1:3-9733 [001] … 2202.273989: rtos_queue_peek_from_isr_failed: tstamp:69224461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2202.441976: rtos_queue_peek_from_isr_failed: tstamp:69229461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2202.609963: rtos_queue_peek_from_isr_failed: tstamp:69234461551 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2202.777972: rtos_queue_peek_from_isr_failed: tstamp:69239461549 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2202.945963: rtos_queue_peek_from_isr_failed: tstamp:69244461546 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2203.057970: rtos_queue_peek_from_isr_failed: tstamp:69249461546 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2203.225975: rtos_queue_peek_from_isr_failed: tstamp:69254461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2203.393987: rtos_queue_peek_from_isr_failed: tstamp:69259461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2203.561963: rtos_queue_peek_from_isr_failed: tstamp:69264461546 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2203.729959: rtos_queue_peek_from_isr_failed: tstamp:69269461543 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2203.897969: rtos_queue_peek_from_isr_failed: tstamp:69274461546 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2204.065962: rtos_queue_peek_from_isr_failed: tstamp:69279461549 queue:0x0bcbcf78
vi-output, imx2-10209 [000] … 2204.073913: tegra_channel_capture_frame: sof:2216.998944224
vi-output, imx2-10209 [000] … 2204.073916: tegra_channel_capture_frame: eof:2216.999101472
vi-output, imx2-10209 [001] … 2204.074609: tegra_channel_capture_frame: sof:2216.999578400
vi-output, imx2-10209 [001] … 2204.074612: tegra_channel_capture_frame: eof:2216.999735616
kworker/1:3-9733 [001] … 2204.121967: rtcpu_vinotify_event: tstamp:69281221171 tag:FS channel:0x00 frame:0 vi_tstamp:69281216979 data:0x00000010
kworker/1:3-9733 [001] … 2204.121972: rtcpu_vinotify_event: tstamp:69281221322 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:69281216982 data:0x00000000
kworker/1:3-9733 [001] … 2204.121980: rtcpu_vinotify_error: tstamp:69281229157 tag:CHANSEL_NOMATCH channel:0x41 frame:0 vi_tstamp:69281221961 data:0x00000549
kworker/1:3-9733 [001] … 2204.121981: rtcpu_vinotify_error: tstamp:69281230725 tag:CHANSEL_NOMATCH channel:0x81 frame:0 vi_tstamp:69281226916 data:0x00000549
kworker/1:3-9733 [001] … 2204.121983: rtcpu_vinotify_error: tstamp:69281234886 tag:CHANSEL_NOMATCH channel:0xc1 frame:0 vi_tstamp:69281231870 data:0x00000549
kworker/1:3-9733 [001] … 2204.121984: rtcpu_vinotify_event: tstamp:69281235245 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:69281217007 data:0x00000001
kworker/1:3-9733 [001] … 2204.121985: rtcpu_vinotify_event: tstamp:69281235388 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:69515318304 data:0x08020001
kworker/1:3-9733 [001] … 2204.121986: rtcpu_vinotify_event: tstamp:69281235546 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:69281221882 data:0x00000002
kworker/1:3-9733 [001] … 2204.121987: rtcpu_vinotify_event: tstamp:69281235685 tag:FE channel:0x00 frame:0 vi_tstamp:69281221906 data:0x00000020
kworker/1:3-9733 [001] … 2204.121988: rtcpu_vinotify_event: tstamp:69281235843 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:69281221909 data:0x00000000
kworker/1:3-9733 [001] … 2204.121989: rtcpu_vinotify_event: tstamp:69281235978 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:69281221921 data:0x00000000
kworker/1:3-9733 [001] … 2204.121990: rtcpu_vinotify_event: tstamp:69281236134 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:69515473376 data:0x02020001
kworker/1:3-9733 [001] … 2204.121992: rtcpu_vinotify_event: tstamp:69281236270 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:69515485632 data:0x00020001
kworker/1:3-9733 [001] … 2204.121993: rtcpu_vinotify_event: tstamp:69281236427 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:69515507488 data:0x07020002
kworker/1:3-9733 [001] … 2204.121994: rtcpu_vinotify_event: tstamp:69281236564 tag:FS channel:0x01 frame:0 vi_tstamp:69281221934 data:0x00000010
kworker/1:3-9733 [001] … 2204.121995: rtcpu_vinotify_event: tstamp:69281236718 tag:CHANSEL_NOMATCH channel:0x41 frame:0 vi_tstamp:69281221961 data:0x00000549
kworker/1:3-9733 [001] … 2204.121996: rtcpu_vinotify_event: tstamp:69281236853 tag:FE channel:0x01 frame:0 vi_tstamp:69281226861 data:0x00000020
kworker/1:3-9733 [001] … 2204.121997: rtcpu_vinotify_event: tstamp:69281237009 tag:FS channel:0x02 frame:0 vi_tstamp:69281226887 data:0x00000010
kworker/1:3-9733 [001] … 2204.121998: rtcpu_vinotify_event: tstamp:69281237144 tag:CHANSEL_NOMATCH channel:0x81 frame:0 vi_tstamp:69281226916 data:0x00000549
kworker/1:3-9733 [001] … 2204.121999: rtcpu_vinotify_event: tstamp:69281237298 tag:FE channel:0x02 frame:0 vi_tstamp:69281231815 data:0x00000020
kworker/1:3-9733 [001] … 2204.122000: rtcpu_vinotify_event: tstamp:69281237430 tag:FS channel:0x03 frame:0 vi_tstamp:69281231843 data:0x00000010
kworker/1:3-9733 [001] … 2204.122002: rtcpu_vinotify_error: tstamp:69281249178 tag:CHANSEL_NOMATCH channel:0x41 frame:0 vi_tstamp:69281241779 data:0x00000549
kworker/1:3-9733 [001] … 2204.122004: rtcpu_vinotify_error: tstamp:69281250688 tag:CHANSEL_NOMATCH channel:0x81 frame:0 vi_tstamp:69281246733 data:0x00000549
kworker/1:3-9733 [001] … 2204.122006: rtcpu_vinotify_event: tstamp:69281251066 tag:CHANSEL_NOMATCH channel:0xc1 frame:0 vi_tstamp:69281231870 data:0x00000549
kworker/1:3-9733 [001] … 2204.122007: rtcpu_vinotify_event: tstamp:69281251202 tag:FE channel:0x03 frame:0 vi_tstamp:69281236770 data:0x00000020
kworker/1:3-9733 [001] … 2204.122008: rtcpu_vinotify_event: tstamp:69281251359 tag:FS channel:0x00 frame:0 vi_tstamp:69281236797 data:0x00000010
kworker/1:3-9733 [001] … 2204.122009: rtcpu_vinotify_event: tstamp:69281251499 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:69281236799 data:0x00000000
kworker/1:3-9733 [001] … 2204.122010: rtcpu_vinotify_event: tstamp:69281251661 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:69281236825 data:0x00000001
kworker/1:3-9733 [001] … 2204.122011: rtcpu_vinotify_event: tstamp:69281251799 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:69515947328 data:0x08020002
kworker/1:3-9733 [001] … 2204.122012: rtcpu_vinotify_event: tstamp:69281251956 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:69281241701 data:0x00000002
kworker/1:3-9733 [001] … 2204.122014: rtcpu_vinotify_event: tstamp:69281253584 tag:FE channel:0x00 frame:0 vi_tstamp:69281241725 data:0x00000020
kworker/1:3-9733 [001] … 2204.122015: rtcpu_vinotify_event: tstamp:69281253742 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:69281241727 data:0x00000000
kworker/1:3-9733 [001] … 2204.122016: rtcpu_vinotify_event: tstamp:69281253877 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:69281241738 data:0x00000000
kworker/1:3-9733 [001] … 2204.122017: rtcpu_vinotify_event: tstamp:69281254037 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:69516107616 data:0x02020002
kworker/1:3-9733 [001] … 2204.122018: rtcpu_vinotify_event: tstamp:69281254172 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:69516119840 data:0x00020002
kworker/1:3-9733 [001] … 2204.122019: rtcpu_vinotify_event: tstamp:69281254330 tag:FS channel:0x01 frame:0 vi_tstamp:69281241751 data:0x00000010
kworker/1:3-9733 [001] … 2204.122020: rtcpu_vinotify_event: tstamp:69281254466 tag:CHANSEL_NOMATCH channel:0x41 frame:0 vi_tstamp:69281241779 data:0x00000549
kworker/1:3-9733 [001] … 2204.122021: rtcpu_vinotify_event: tstamp:69281254620 tag:FE channel:0x01 frame:0 vi_tstamp:69281246679 data:0x00000020
kworker/1:3-9733 [001] … 2204.122022: rtcpu_vinotify_event: tstamp:69281254755 tag:FS channel:0x02 frame:0 vi_tstamp:69281246706 data:0x00000010
kworker/1:3-9733 [001] … 2204.122024: rtcpu_vinotify_error: tstamp:69281255821 tag:CHANSEL_NOMATCH channel:0xc1 frame:0 vi_tstamp:69281251688 data:0x00000549
kworker/1:3-9733 [001] … 2204.122027: rtos_queue_send_from_isr_failed: tstamp:69281305825 queue:0x0bcb41f8
kworker/1:3-9733 [001] … 2204.122028: rtos_queue_send_from_isr_failed: tstamp:69281305979 queue:0x0bcb8a60
kworker/1:3-9733 [001] … 2204.122029: rtos_queue_send_from_isr_failed: tstamp:69281306132 queue:0x0bcba5e0
kworker/1:3-9733 [001] … 2204.122031: rtos_queue_send_from_isr_failed: tstamp:69281306279 queue:0x0bcbb3a0
kworker/1:3-9733 [001] … 2204.122032: rtos_queue_send_from_isr_failed: tstamp:69281306424 queue:0x0bcbc160
kworker/1:3-9733 [001] … 2204.122033: rtos_queue_send_from_isr_failed: tstamp:69281359326 queue:0x0bcb41f8
kworker/1:3-9733 [001] … 2204.122034: rtos_queue_send_from_isr_failed: tstamp:69281359488 queue:0x0bcb8a60
kworker/1:3-9733 [001] … 2204.122035: rtos_queue_send_from_isr_failed: tstamp:69281359637 queue:0x0bcba5e0
kworker/1:3-9733 [001] … 2204.122036: rtos_queue_send_from_isr_failed: tstamp:69281359784 queue:0x0bcbb3a0
kworker/1:3-9733 [001] … 2204.122038: rtos_queue_send_from_isr_failed: tstamp:69281359930 queue:0x0bcbc160
kworker/1:3-9733 [001] … 2204.122039: rtcpu_vinotify_event: tstamp:69281775630 tag:CHANSEL_NOMATCH channel:0x81 frame:0 vi_tstamp:69281246733 data:0x00000549
kworker/1:3-9733 [001] … 2204.122040: rtcpu_vinotify_event: tstamp:69281775773 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:69516403808 data:0x10000000
kworker/1:3-9733 [001] … 2204.122041: rtcpu_vinotify_event: tstamp:69281775935 tag:FE channel:0x02 frame:0 vi_tstamp:69281251633 data:0x00000020
kworker/1:3-9733 [001] … 2204.122042: rtcpu_vinotify_event: tstamp:69281776070 tag:FS channel:0x03 frame:0 vi_tstamp:69281251660 data:0x00000010
kworker/1:3-9733 [001] … 2204.122043: rtcpu_vinotify_event: tstamp:69281776224 tag:CHANSEL_NOMATCH channel:0xc1 frame:0 vi_tstamp:69281251688 data:0x00000549
kworker/1:3-9733 [001] … 2204.122044: rtcpu_vinotify_event: tstamp:69281776363 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:69516488896 data:0x31000003
kworker/1:3-9733 [001] … 2204.122045: rtcpu_vinotify_event: tstamp:69281776523 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:69516497536 data:0x07020003
kworker/1:3-9733 [001] … 2204.122046: rtcpu_vinotify_event: tstamp:69281776659 tag:FE channel:0x03 frame:0 vi_tstamp:69281256588 data:0x00000020
kworker/1:3-9733 [001] … 2204.178004: rtos_queue_peek_from_isr_failed: tstamp:69284461550 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2204.345989: rtos_queue_peek_from_isr_failed: tstamp:69289461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2204.513981: rtos_queue_peek_from_isr_failed: tstamp:69294461550 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2204.681963: rtos_queue_peek_from_isr_failed: tstamp:69299461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2204.849985: rtos_queue_peek_from_isr_failed: tstamp:69304461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2205.017976: rtos_queue_peek_from_isr_failed: tstamp:69309461546 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2205.185966: rtos_queue_peek_from_isr_failed: tstamp:69314461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2205.297970: rtos_queue_peek_from_isr_failed: tstamp:69319461546 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2205.466005: rtos_queue_peek_from_isr_failed: tstamp:69324461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2205.633962: rtos_queue_peek_from_isr_failed: tstamp:69329461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2205.801973: rtos_queue_peek_from_isr_failed: tstamp:69334461547 queue:0x0bcbcf78
kworker/1:3-9733 [001] … 2205.969966: rtos_queue_peek_from_isr_failed: tstamp:69339461547 queue:0x0bcbcf78

Thanks for your time

Looks like still output 4 channel data. I saw the FS for channel 0 1 2 3

FS channel:0x00/FS channel:0x01/FS channel:0x02/FS channel:0x03

Hi ShaneCC,
That I configure Xavier for one VC and sensor sending 4VC data, Sorry mis understand. Now I tried xavier and sensor enable 1VC config. Here I have attached trace log. thanks

 kworker/0:4-1751  [000] ....   935.097068: rtos_queue_send_from_isr_failed: tstamp:29625081120 queue:0x0bcbc160
 kworker/0:4-1751  [000] ....   935.151394: rtcpu_vinotify_event: tstamp:29625232209 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:28872231968 data:0x10000000
 kworker/0:4-1751  [000] ....   935.151397: rtcpu_vinotify_event: tstamp:29625232357 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:28872247040 data:0x31000001
 kworker/0:4-1751  [000] ....   935.151399: rtcpu_vinotify_event: tstamp:29625232519 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:28872249152 data:0x07020001
 kworker/0:4-1751  [000] ....   935.151400: rtcpu_vinotify_event: tstamp:29625232655 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:28872356384 data:0x10000000
 kworker/0:4-1751  [000] ....   935.151401: rtcpu_vinotify_event: tstamp:29625232812 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:28872364768 data:0x31000002
 kworker/0:4-1751  [000] ....   935.263409: rtos_queue_peek_from_isr_failed: tstamp:29629630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   935.431385: rtos_queue_peek_from_isr_failed: tstamp:29634630669 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   935.599390: rtos_queue_peek_from_isr_failed: tstamp:29639630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   935.767390: rtos_queue_peek_from_isr_failed: tstamp:29644630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   935.879394: rtos_queue_peek_from_isr_failed: tstamp:29649630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   936.047387: rtos_queue_peek_from_isr_failed: tstamp:29654630673 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   936.215397: rtos_queue_peek_from_isr_failed: tstamp:29659630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   936.383384: rtos_queue_peek_from_isr_failed: tstamp:29664630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   936.551377: rtos_queue_peek_from_isr_failed: tstamp:29669630671 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   936.719392: rtos_queue_peek_from_isr_failed: tstamp:29674630671 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   936.887384: rtos_queue_peek_from_isr_failed: tstamp:29679630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   936.999379: rtos_queue_peek_from_isr_failed: tstamp:29684630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   937.167401: rtos_queue_peek_from_isr_failed: tstamp:29689630669 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   937.335392: rtos_queue_peek_from_isr_failed: tstamp:29694630670 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   937.503373: rtos_queue_peek_from_isr_failed: tstamp:29699630669 queue:0x0bcbcf78
 kworker/0:4-1751  [000] ....   937.671377: rtos_queue_peek_from_isr_failed: tstamp:29704630671 queue:0x0bcbcf78

vi-output, imx2-8864 [000] … 937.809044: tegra_channel_capture_setup: vnc_id 0 W 32776 H 1 fmt 5
kworker/0:4-1751 [000] … 937.839372: rtos_queue_peek_from_isr_failed: tstamp:29709630670 queue:0x0bcbcf78
kworker/0:4-1751 [000] … 937.839377: rtos_queue_send_from_isr_failed: tstamp:29709800204 queue:0x0bcb41f8
kworker/0:4-1751 [000] … 937.839379: rtos_queue_send_from_isr_failed: tstamp:29709800356 queue:0x0bcb8a60
kworker/0:4-1751 [000] … 937.839380: rtos_queue_send_from_isr_failed: tstamp:29709800509 queue:0x0bcba5e0
kworker/0:4-1751 [000] … 937.839381: rtos_queue_send_from_isr_failed: tstamp:29709800657 queue:0x0bcbb3a0
kworker/0:4-1751 [000] … 937.839382: rtos_queue_send_from_isr_failed: tstamp:29709800814 queue:0x0bcbc160
kworker/0:4-1751 [000] … 937.839384: rtos_queue_send_from_isr_failed: tstamp:29709958528 queue:0x0bcb41f8
kworker/0:4-1751 [000] … 937.839385: rtos_queue_send_from_isr_failed: tstamp:29709958678 queue:0x0bcb8a60
kworker/0:4-1751 [000] … 937.839386: rtos_queue_send_from_isr_failed: tstamp:29709958830 queue:0x0bcba5e0
kworker/0:4-1751 [000] … 937.839387: rtos_queue_send_from_isr_failed: tstamp:29709958978 queue:0x0bcbb3a0
kworker/0:4-1751 [000] … 937.839388: rtos_queue_send_from_isr_failed: tstamp:29709959141 queue:0x0bcbc160
kworker/0:4-1751 [000] … 937.839391: rtcpu_string: tstamp:29709964885 id:0x04010000 str:"Configuring VI GoS.
"
kworker/0:4-1751 [000] … 937.839411: rtcpu_string: tstamp:29709965067 id:0x04010000 str:"VM GOS[#0] addr=0xc2100000
"
kworker/0:4-1751 [000] … 937.839446: rtcpu_string: tstamp:29709965319 id:0x04010000 str:"VM GOS[#1] addr=0xc2101000
"
kworker/0:4-1751 [000] … 937.839452: rtcpu_string: tstamp:29709965583 id:0x04010000 str:"VM GOS[#2] addr=0xc2102000
"
kworker/0:4-1751 [000] … 937.839459: rtcpu_string: tstamp:29709965814 id:0x04010000 str:"VM GOS[#3] addr=0xc2103000
"
kworker/0:4-1751 [000] … 937.839466: rtcpu_string: tstamp:29709966046 id:0x04010000 str:"VM GOS[#4] addr=0xc2104000
"
kworker/0:4-1751 [000] … 937.839472: rtcpu_string: tstamp:29709966277 id:0x04010000 str:"VM GOS[#5] addr=0xc2105000
"
kworker/0:4-1751 [000] … 937.839479: rtos_queue_send_from_isr_failed: tstamp:29709966633 queue:0x0bcb41f8
kworker/0:4-1751 [000] … 937.839480: rtos_queue_send_from_isr_failed: tstamp:29709966793 queue:0x0bcb8a60
kworker/0:4-1751 [000] … 937.839481: rtos_queue_send_from_isr_failed: tstamp:29709966951 queue:0x0bcba5e0
kworker/0:4-1751 [000] … 937.839482: rtos_queue_send_from_isr_failed: tstamp:29709967104 queue:0x0bcbb3a0
kworker/0:4-1751 [000] … 937.839484: rtos_queue_send_from_isr_failed: tstamp:29709967253 queue:0x0bcbc160
kworker/0:4-1751 [000] … 937.839485: rtos_queue_send_from_isr_failed: tstamp:29709971241 queue:0x0bcb41f8
kworker/0:4-1751 [000] … 937.839486: rtos_queue_send_from_isr_failed: tstamp:29709971390 queue:0x0bcb8a60
kworker/0:4-1751 [000] … 937.839487: rtos_queue_send_from_isr_failed: tstamp:29709971541 queue:0x0bcba5e0
kworker/0:4-1751 [000] … 937.839489: rtos_queue_send_from_isr_failed: tstamp:29709971689 queue:0x0bcbb3a0
kworker/0:4-1751 [000] … 937.839490: rtos_queue_send_from_isr_failed: tstamp:29709971834 queue:0x0bcbc160
kworker/0:4-1751 [000] … 937.839492: rtcpu_vinotify_event: tstamp:29710410370 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:31596252800 data:0x10000000
kworker/0:4-1751 [000] … 937.839494: rtcpu_vinotify_event: tstamp:29710410540 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:31596282176 data:0x31000001
kworker/0:4-1751 [000] … 937.839495: rtcpu_vinotify_event: tstamp:29710410680 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:31596292352 data:0x10000000
kworker/0:4-1751 [000] … 937.839523: rtcpu_vinotify_event: tstamp:29710410839 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:31596300800 data:0x07020001
kworker/0:4-1751 [000] … 937.839524: rtcpu_vinotify_event: tstamp:29710410976 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:31596331264 data:0x31000002
kworker/0:4-1751 [000] … 938.007426: rtos_queue_peek_from_isr_failed: tstamp:29714630671 queue:0x0bcbcf78
kworker/0:4-1751 [000] … 938.119371: rtos_queue_peek_from_isr_failed: tstamp:29719630671 queue:0x0bcbcf78
vi-output, imx2-8864 [001] … 938.267109: tegra_channel_capture_frame: sof:951.175757728
vi-output, imx2-8864 [001] … 938.267115: tegra_channel_capture_frame: eof:951.175914976
vi-output, imx2-8864 [001] … 938.267132: tegra_channel_capture_frame: sof:951.175916256
vi-output, imx2-8864 [001] … 938.267134: tegra_channel_capture_frame: eof:951.176073504
kworker/0:4-1751 [000] … 938.287378: rtcpu_vinotify_event: tstamp:29724260230 tag:FS channel:0x00 frame:0 vi_tstamp:29724242401 data:0x00000010
kworker/0:4-1751 [000] … 938.287384: rtcpu_vinotify_event: tstamp:29724260382 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:29724242404 data:0x00000000
kworker/0:4-1751 [000] … 938.287385: rtcpu_vinotify_event: tstamp:29724260548 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:29724242429 data:0x00000001
kworker/0:4-1751 [000] … 938.287386: rtcpu_vinotify_event: tstamp:29724260687 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:27757806912 data:0x08020001
kworker/0:4-1751 [000] … 938.287387: rtcpu_vinotify_event: tstamp:29724260845 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:29724247305 data:0x00000002
kworker/0:4-1751 [000] … 938.287388: rtcpu_vinotify_event: tstamp:29724260981 tag:FE channel:0x00 frame:0 vi_tstamp:29724247328 data:0x00000020
kworker/0:4-1751 [000] … 938.287391: rtcpu_vinotify_event: tstamp:29724261159 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:29724247331 data:0x00000000
kworker/0:4-1751 [000] … 938.287392: rtcpu_vinotify_event: tstamp:29724261294 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:29724247343 data:0x00000000
kworker/0:4-1751 [000] … 938.287393: rtcpu_vinotify_event: tstamp:29724261452 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:27757972160 data:0x02020001
kworker/0:4-1751 [000] … 938.287394: rtcpu_vinotify_event: tstamp:29724261585 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:27757984736 data:0x00020001
kworker/0:4-1751 [000] … 938.287395: rtcpu_vinotify_event: tstamp:29724261744 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:27758007040 data:0x07020002
kworker/0:4-1751 [000] … 938.287396: rtcpu_vinotify_event: tstamp:29724261880 tag:FS channel:0x00 frame:0 vi_tstamp:29724247356 data:0x00000010
kworker/0:4-1751 [000] … 938.287397: rtcpu_vinotify_event: tstamp:29724262037 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:29724247358 data:0x00000000
kworker/0:4-1751 [000] … 938.287398: rtcpu_vinotify_event: tstamp:29724262172 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:29724247383 data:0x00000001
kworker/0:4-1751 [000] … 938.287400: rtcpu_vinotify_event: tstamp:29724262330 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:27758026240 data:0x08020002
kworker/0:4-1751 [000] … 938.287401: rtcpu_vinotify_event: tstamp:29724262463 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:29724252260 data:0x00000002
kworker/0:4-1751 [000] … 938.287402: rtcpu_vinotify_event: tstamp:29724262619 tag:FE channel:0x00 frame:0 vi_tstamp:29724252283 data:0x00000020
kworker/0:4-1751 [000] … 938.287403: rtcpu_vinotify_event: tstamp:29724262759 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:29724252286 data:0x00000000
kworker/0:4-1751 [000] … 938.287404: rtcpu_vinotify_event: tstamp:29724262917 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:29724252297 data:0x00000000
kworker/0:4-1751 [000] … 938.287405: rtcpu_vinotify_event: tstamp:29724263052 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:27758129920 data:0x02020002
kworker/0:4-1751 [000] … 938.287406: rtcpu_vinotify_event: tstamp:29724263208 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:27758144992 data:0x00020002
kworker/0:4-1751 [000] … 938.287407: rtcpu_vinotify_event: tstamp:29724263342 tag:FS channel:0x00 frame:0 vi_tstamp:29724252310 data:0x00000010
kworker/0:4-1751 [000] … 938.287411: rtcpu_vinotify_error: tstamp:29724266758 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724252338 data:0x00000549
kworker/0:4-1751 [000] … 938.287412: rtcpu_vinotify_error: tstamp:29724267749 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724257292 data:0x00000549
kworker/0:4-1751 [000] … 938.287413: rtcpu_vinotify_error: tstamp:29724268411 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724262247 data:0x00000549
kworker/0:4-1751 [000] … 938.287414: rtcpu_vinotify_error: tstamp:29724271458 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724267201 data:0x00000549
kworker/0:4-1751 [000] … 938.287415: rtcpu_vinotify_error: tstamp:29724276278 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724272156 data:0x00000549
kworker/0:4-1751 [000] … 938.287416: rtcpu_vinotify_error: tstamp:29724280153 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724277111 data:0x00000549
kworker/0:4-1751 [000] … 938.287418: rtcpu_vinotify_event: tstamp:29724280614 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724252338 data:0x00000549
kworker/0:4-1751 [000] … 938.287419: rtcpu_vinotify_event: tstamp:29724280754 tag:FE channel:0x00 frame:0 vi_tstamp:29724257238 data:0x00000020
kworker/0:4-1751 [000] … 938.287420: rtcpu_vinotify_event: tstamp:29724280911 tag:FS channel:0x00 frame:0 vi_tstamp:29724257265 data:0x00000010
kworker/0:4-1751 [000] … 938.287421: rtcpu_vinotify_event: tstamp:29724281047 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724257292 data:0x00000549
kworker/0:4-1751 [000] … 938.287422: rtcpu_vinotify_event: tstamp:29724281203 tag:FE channel:0x00 frame:0 vi_tstamp:29724262192 data:0x00000020
kworker/0:4-1751 [000] … 938.287423: rtcpu_vinotify_event: tstamp:29724281338 tag:FS channel:0x00 frame:0 vi_tstamp:29724262219 data:0x00000010
kworker/0:4-1751 [000] … 938.287424: rtcpu_vinotify_event: tstamp:29724281493 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724262247 data:0x00000549
kworker/0:4-1751 [000] … 938.287425: rtcpu_vinotify_event: tstamp:29724281628 tag:FE channel:0x00 frame:0 vi_tstamp:29724267147 data:0x00000020
kworker/0:4-1751 [000] … 938.287426: rtcpu_vinotify_event: tstamp:29724281785 tag:FS channel:0x00 frame:0 vi_tstamp:29724267173 data:0x00000010
kworker/0:4-1751 [000] … 938.287428: rtcpu_vinotify_event: tstamp:29724281920 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724267201 data:0x00000549
kworker/0:4-1751 [000] … 938.287429: rtcpu_vinotify_event: tstamp:29724282074 tag:FE channel:0x00 frame:0 vi_tstamp:29724272101 data:0x00000020
kworker/0:4-1751 [000] … 938.287430: rtcpu_vinotify_event: tstamp:29724282209 tag:FS channel:0x00 frame:0 vi_tstamp:29724272129 data:0x00000010
kworker/0:4-1751 [000] … 938.287431: rtcpu_vinotify_event: tstamp:29724282365 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724272156 data:0x00000549
kworker/0:4-1751 [000] … 938.287432: rtcpu_vinotify_event: tstamp:29724282498 tag:FE channel:0x00 frame:0 vi_tstamp:29724277055 data:0x00000020
kworker/0:4-1751 [000] … 938.287433: rtcpu_vinotify_event: tstamp:29724282653 tag:FS channel:0x00 frame:0 vi_tstamp:29724277082 data:0x00000010
kworker/0:4-1751 [000] … 938.287434: rtcpu_vinotify_event: tstamp:29724282787 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:29724277111 data:0x00000549
kworker/0:4-1751 [000] … 938.287437: rtos_queue_send_from_isr_failed: tstamp:29724310811 queue:0x0bcb41f8
kworker/0:4-1751 [000] … 938.287439: rtos_queue_send_from_isr_failed: tstamp:29724310967 queue:0x0bcb8a60
kworker/0:4-1751 [000] … 938.287451: rtos_queue_send_from_isr_failed: tstamp:29724311117 queue:0x0bcba5e0
kworker/0:4-1751 [000] … 938.287453: rtos_queue_send_from_isr_failed: tstamp:29724311265 queue:0x0bcbb3a0

Could you please give some idea

One more doubt that, I saw CHANSEL_NOMATCH channel:0x01 in trace log, is this mean that sensor sending channel 1 data.

Thanks for your time

nomatch log shows VI received pixels with dataType=RAW8 (bit[10:5] on data field: 0x00000549), and no channel to match this, please confirm if RAW8 is expected input format, and if a VI channel has been programmed to catch that

Hi ShaneCC,
Thanks for your support , Yes RAW8 is the expected Input. Could you please suggest any Idea. I see LS(Line Start) in CTYPE bit[4:1] is enabled in data field: 0x00000549 what it’s showing , This LS is coming from long packet or short packet.

Thanks for your time

if RAW8 is expected format, may need to confirm VI channel has been programmed correct to catch that.

Hello ShaneCCC,
Thanks for your support, I am using imx219 dtsi file as reference and I have changed only two parameters in VI section. min_bits_per_pixel = <8>, vi_peak_byte_per_pixel = <1>;. Here I have attached my dtsi file all. Could you please give some Idea to over come it.

Thanks for your time.customDtsi.txt (15.0 KB)