Tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms

Hi NV teams,
When I try run

v4l2-ctl -c bypass_mode=0 --stream-mmap

I got error

[  370.158079] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  370.167232] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  370.177539] (NULL device *): vi_capture_control_message: NULL VI channel received
[  370.185256] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=1, csi_port=1
[  370.195937] (NULL device *): vi_capture_control_message: NULL VI channel received
[  370.203651] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 1 vc- 0
[  370.214497] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel

Some info

cat /proc/version 
Linux version 5.10.120-tegra (root@kim) (aarch64-buildroot-linux-gnu-gcc.br_real (Buildroot 2020.08) 9.3.0, GNU ld (GNU Binutils) 2.33.1) #1 SMP PREEMPT Tue Jul 2 15:36:07 CST 2024

sudo media-ctl -p -d /dev/media0
Media controller API version 5.10.120

Media device information
------------------------
driver          tegra-camrtc-ca
model           NVIDIA Tegra Video Input Device
serial          
bus info        
hw revision     0x3
driver version  5.10.120

Device topology
- entity 1: 13e40000.host1x:nvcsi@15a00000- (2 pads, 2 links)
            type V4L2 subdev subtype Unknown flags 0
            device node name /dev/v4l-subdev0
	pad0: Sink
		<- "zedx 2-0018":0 [ENABLED]
	pad1: Source
		-> "vi-output, zedx 2-0018":0 [ENABLED]

- entity 4: 13e40000.host1x:nvcsi@15a00000- (2 pads, 2 links)
            type V4L2 subdev subtype Unknown flags 0
            device node name /dev/v4l-subdev1
	pad0: Sink
		<- "zedx 2-0010":0 [ENABLED]
	pad1: Source
		-> "vi-output, zedx 2-0010":0 [ENABLED]

- entity 7: zedx 2-0018 (1 pad, 1 link)
            type V4L2 subdev subtype Sensor flags 0
            device node name /dev/v4l-subdev2
	pad0: Source
		[fmt:SGRBG10_1X10/1920x1200 field:none colorspace:srgb]
		-> "13e40000.host1x:nvcsi@15a00000-":0 [ENABLED]

- entity 9: vi-output, zedx 2-0018 (1 pad, 1 link)
            type Node subtype V4L flags 0
            device node name /dev/video0
	pad0: Sink
		<- "13e40000.host1x:nvcsi@15a00000-":1 [ENABLED]

- entity 23: zedx 2-0010 (1 pad, 1 link)
             type V4L2 subdev subtype Sensor flags 0
             device node name /dev/v4l-subdev3
	pad0: Source
		[fmt:SGRBG10_1X10/1920x1200 field:none colorspace:srgb]
		-> "13e40000.host1x:nvcsi@15a00000-":0 [ENABLED]

- entity 25: vi-output, zedx 2-0010 (1 pad, 1 link)
             type Node subtype V4L flags 0
             device node name /dev/video1
	pad0: Sink
		<- "13e40000.host1x:nvcsi@15a00000-":1 [ENABLED]

Here is dts one of mode setting(Full dts too big)

zedx_left_0@18 {
			compatible = "stereolabs,zedx";

			devnode = "video0";
			reg = <0x18>;
			physical_w = "15.0";
			physical_h = "12.5";

			vertical-flip = "true";
			sensor_model ="zedx";
			sync_sensor = "ZED_CAM1";
			sync_sensor_index = <1>;
			post_crop_frame_drop = "0";

                        mode0 {/*mode ZEDX_MODE_1920X1200_60FPS*/
				mclk_khz = "24000";
				num_lanes = "2";
				tegra_sinterface = "serial_b";
				lane_polarity = "6";
				vc_id = "0";
				discontinuous_clk = "no";
				dpcm_enable = "false";
				cil_settletime = "0";
				dynamic_pixel_bit_depth = "10";
				csi_pixel_bit_depth = "10";
				mode_type = "bayer";
				pixel_phase = "grbg";
				active_w = "1920";
				active_h = "1200";
				readout_orientation = "0";
				line_length = "12496";
				inherent_gain = "1";
				mclk_multiplier = "3.01";
				pix_clk_hz = "229500000";
				serdes_pix_clk_hz = "299000000";
				gain_factor = "100";
				min_gain_val = "100"; /* dB */
				max_gain_val = "1600"; /* dB */
				step_gain_val = "1"; /* 0.1 */
				default_gain = "100";
				min_hdr_ratio = "1";
				max_hdr_ratio = "1";
				framerate_factor = "1000000";
				min_framerate = "15000000";
				max_framerate = "60000000";
				step_framerate = "15000000";
				default_framerate = "30000000";
				exposure_factor = "1000000";
				min_exp_time = "28"; /*us, 2 lines*/
				max_exp_time = "66000";
				step_exp_time = "1";
				default_exp_time = "13000";/* us */
				embedded_metadata_height = "0";
			};
};

And the port binding

/ {
	tegra-capture-vi {
		status = "okay";
		num-channels = <4>;
		ports {
			#address-cells = <1>;
			#size-cells = <0>;
			status = "okay";
			port@0 {
				reg = <0>;
				ar0234_vi_in0: endpoint {
					status = "okay";
					vc-id = <0>;
					port-index = <1>;
					bus-width = <2>;
					remote-endpoint = <&ar0234_csi_out0>;
				};
			};
			port@1 {
				reg = <1>;
				ar0234_vi_in1: endpoint {
				status = "okay";
				vc-id = <1>;
				port-index = <1>;
				bus-width = <2>;
				remote-endpoint = <&ar0234_csi_out1>;
				};
			};
		};
	};

	host1x@13e00000 {
		nvcsi@15a00000 {
			status = "okay";
			num-channels = <2>;
			#address-cells = <1>;
			#size-cells = <0>;
			channel@0 {
				reg = <0>;
				ports {
				#address-cells = <1>;
				#size-cells = <0>;
					port@0 {
						reg = <0>;
						ar0234_csi_in0: endpoint@0 {
							status = "okay";
							port-index = <1>;
							bus-width = <2>;
							remote-endpoint = <&ar0234_ar0234_out0>;
						};
					};
					port@1 {
						reg = <1>;
						ar0234_csi_out0: endpoint@1 {
							status = "okay";
							remote-endpoint = <&ar0234_vi_in0>;
						};
					};
				};
			};
			channel@1 {
				reg = <1>;
				ports {
				#address-cells = <1>;
				#size-cells = <0>;
					port@0 {
						reg = <0>;
						ar0234_csi_in1: endpoint@2 {
						status = "okay";
						port-index = <1>;
						bus-width = <2>;
						remote-endpoint = <&ar0234_ar0234_out1>;
						};
					};
					port@1 {
						reg = <1>;
						ar0234_csi_out1: endpoint@3 {
						status = "okay";
						remote-endpoint = <&ar0234_vi_in1>;
						};
					};
				};
			};
		};
	};

	i2c@3180000 {
		/* ZEDX 0 left camera sensor */
		zedx_left_0@18 {
			mode0 {
				vc_id = "0";
			};
			mode1 {
				vc_id = "0";
			};
			mode2 {
				vc_id = "0";
			};
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					ar0234_ar0234_out0: endpoint {
						vc-id = <0>;
						port-index = <1>;
						bus-width = <2>;
						remote-endpoint = <&ar0234_csi_in0>;
					};
				};
			};
		};

		/* ZEDX 0 right camera sensor */
		zedx_right_0@10 {
			mode0 {
				vc_id = "1";
			};
			mode1 {
				vc_id = "1";
			};
			mode2 {
				vc_id = "1";
			};
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					ar0234_ar0234_out1: endpoint {
						vc-id = <1>;
						port-index = <1>;
						bus-width = <2>;
						remote-endpoint = <&ar0234_csi_in1>;
					};
				};
			};
		};
	};
};
/ {

	tegra-camera-platform {
		compatible = "nvidia, tegra-camera-platform";
		
		num_csi_lanes = <4>;
		max_lane_speed = <4000000>;
		min_bits_per_pixel = <10>;
		vi_peak_byte_per_pixel = <2>;
		vi_bw_margin_pct = <25>;
		isp_peak_byte_per_pixel = <5>;
		isp_bw_margin_pct = <25>;

		modules {
			module0 {
				badge = "zedx_ar0234";
				position = "bottomleft";
				orientation = "1";
				drivernode0 {
					status = "okay";
					/* Declare PCL support driver (classically known as guid)  */
					pcl_id = "v4l2_sensor";
					/* Driver v4l2 device name */
					devname = "zedx 2-0018";
					/* Declare the device-tree hierarchy to driver instance */
					proc-device-tree = "/proc/device-tree/i2c@3180000/zedx_left_0@18";
				};
			};
			module1 {
				badge = "zedx_ar0234";
				position = "bottomright";
				orientation = "1";
				drivernode0 {
				status = "okay";
					/* Declare PCL support driver (classically known as guid)  */
					pcl_id = "v4l2_sensor";
					/* Driver v4l2 device name */
					devname = "zedx 2-0010";
					/* Declare the device-tree hierarchy to driver instance */
					proc-device-tree = "/proc/device-tree/i2c@3180000/zedx_right_0@10";
				};
			};
		};
	};
};

By the way, where I can fine serial_b means? which in tegra_sinterface = “serial_b”;
is b mean hs_csi1 in DG-10931-001_v1.2.pdf Figure 10-1.?

Here is debug trace output

sudo su
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 3 > /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
v4l2-ctl --stream-mmap -c bypass_mode=0
root@test-desktop:/home/test# cat /sys/kernel/debug/tracing/trace
# tracer: nop
#
# entries-in-buffer/entries-written: 35/35   #P:6
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
        v4l2-ctl-4383    [004] ....  3404.373146: tegra_channel_open: vi-output, zedx 2-0018
        v4l2-ctl-4383    [004] ....  3404.379704: tegra_channel_set_power: zedx 2-0018 : 0x1
        v4l2-ctl-4383    [004] ....  3404.379717: camera_common_s_power: status : 0x1
        v4l2-ctl-4383    [004] ....  3404.380182: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-4383    [004] ....  3404.380185: csi_s_power: enable : 0x1
        v4l2-ctl-4383    [004] ....  3404.380573: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1200 fmt c4
        v4l2-ctl-4383    [004] ....  3404.382005: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-4383    [004] ....  3404.388862: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-4383    [004] ....  3404.388864: csi_s_stream: enable : 0x1
        v4l2-ctl-4383    [004] ....  3404.389242: tegra_channel_set_stream: zedx 2-0018 : 0x1
     kworker/1:9-146     [001] ....  3404.412123: rtcpu_vinotify_event: tstamp:106962285824 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3422777843872 data:0x759d580010000000
     kworker/1:9-146     [001] ....  3404.412125: rtcpu_vinotify_event: tstamp:106962286082 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3422777853632 data:0x0000000031000001
     kworker/1:9-146     [001] ....  3404.412126: rtcpu_vinotify_event: tstamp:106962286371 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3422777945152 data:0x759d550010000000
     kworker/1:9-146     [001] ....  3404.412126: rtcpu_vinotify_event: tstamp:106962286616 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3422777955072 data:0x0000000031000002
 vi-output, zedx-4385    [000] ....  3407.060519: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1200 fmt c4
     kworker/1:9-146     [001] ....  3407.104049: rtcpu_vinotify_event: tstamp:107045837118 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3425456850656 data:0x759d580010000000
     kworker/1:9-146     [001] ....  3407.104051: rtcpu_vinotify_event: tstamp:107045837380 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3425456908224 data:0x0000000031000001
     kworker/1:9-146     [001] ....  3407.104051: rtcpu_vinotify_event: tstamp:107045837668 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3425456925888 data:0x759d550010000000
     kworker/1:9-146     [001] ....  3407.104051: rtcpu_vinotify_event: tstamp:107045837914 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3425456986240 data:0x0000000031000002
 vi-output, zedx-4385    [000] ....  3409.876384: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1200 fmt c4
     kworker/1:9-146     [001] ....  3409.911985: rtcpu_vinotify_event: tstamp:107133728398 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3428272744832 data:0x759d580010000000
     kworker/1:9-146     [001] ....  3409.911986: rtcpu_vinotify_event: tstamp:107133728659 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3428272787456 data:0x0000000031000001
     kworker/1:9-146     [001] ....  3409.911986: rtcpu_vinotify_event: tstamp:107133729013 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3428272805184 data:0x759d550010000000
     kworker/1:9-146     [001] ....  3409.911987: rtcpu_vinotify_event: tstamp:107133729267 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3428272865536 data:0x0000000031000002
        v4l2-ctl-4383    [004] ....  3411.628639: tegra_channel_close: vi-output, zedx 2-0018
 vi-output, zedx-4385    [000] ....  3412.468558: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1200 fmt c4
        v4l2-ctl-4383    [005] ....  3412.478259: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-4383    [005] ....  3412.478263: tegra_channel_set_stream: zedx 2-0018 : 0x0
        v4l2-ctl-4383    [000] ....  3412.583929: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-4383    [000] ....  3412.583932: csi_s_stream: enable : 0x0
        v4l2-ctl-4383    [000] ....  3412.587492: tegra_channel_set_power: zedx 2-0018 : 0x0
        v4l2-ctl-4383    [000] ....  3412.587505: camera_common_s_power: status : 0x0
        v4l2-ctl-4383    [000] ....  3412.587691: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-4383    [000] ....  3412.587694: csi_s_power: enable : 0x0
     kworker/1:9-146     [001] ....  3418.591944: rtcpu_string: tstamp:107404545377 id:0x04010000 str:"VM0 deactivating."

The trace log tell didn’t receive any validate data from the sensor.
Maybe probe the signal to confirm.

Hi Shane,
Where I can fine serial_b means? which in tegra_sinterface = “serial_b”;
Is b mean hs_csi1 in DG-10931-001_v1.2.pdf Figure 10-1?

     Also we'll check signal latter, just make sure I set correct csi port in dts.

Yes, serial_b = CSI1

Hi Shane,

   I notice that nomatter is /dev/video0 or video1,The log always be v1:1.
   Since we have two tegra-capture-vi port withe different vi-id in DTS, Is it normal?
# tracer: nop
#
# entries-in-buffer/entries-written: 49/49   #P:6
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/2:1-25257   [002] .... 17676.118122: rtcpu_string: tstamp:552878077041 id:0x04010000 str:"VM0 deactivating."
     kworker/2:0-2988    [002] .... 17677.689722: rtcpu_string: tstamp:552926025941 id:0x04010000 str:"VM0 activating."
     kworker/2:0-2988    [002] .... 17677.689727: rtcpu_vinotify_event: tstamp:552926486735 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17693637174816 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 17677.689727: rtcpu_vinotify_event: tstamp:552926486998 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17693637184544 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 17677.689728: rtcpu_vinotify_event: tstamp:552926487292 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17693637275616 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 17677.689728: rtcpu_vinotify_event: tstamp:552926487541 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17693637285504 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 17678.081711: rtcpu_nvcsi_intr: tstamp:552938793196 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
     kworker/2:0-2988    [002] .... 17678.809691: rtcpu_nvcsi_intr: tstamp:552960463612 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
     kworker/2:0-2988    [002] .... 17680.437633: rtcpu_vinotify_event: tstamp:553013015468 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17696411324416 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 17680.437636: rtcpu_vinotify_event: tstamp:553013015764 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17696411334144 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 17680.437637: rtcpu_vinotify_event: tstamp:553013016050 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17696411410816 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 17680.437637: rtcpu_vinotify_event: tstamp:553013016299 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17696411420704 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 17683.289561: rtcpu_vinotify_event: tstamp:553100907068 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17699227236256 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 17683.289565: rtcpu_vinotify_event: tstamp:553100907336 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17699227245952 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 17683.289566: rtcpu_vinotify_event: tstamp:553100907620 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17699227329568 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 17683.289566: rtcpu_vinotify_event: tstamp:553100907869 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:17699227340032 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 17691.749505: rtcpu_string: tstamp:553364818955 id:0x04010000 str:"VM0 deactivating."
     kworker/2:0-2988    [002] .... 18028.171370: rtcpu_string: tstamp:563878722965 id:0x04010000 str:"VM0 activating."
     kworker/2:0-2988    [002] .... 18028.171376: rtcpu_vinotify_event: tstamp:563879179661 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18044123363872 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 18028.171377: rtcpu_vinotify_event: tstamp:563879179951 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18044123373664 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 18028.171377: rtcpu_vinotify_event: tstamp:563879180246 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18044123464672 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 18028.171378: rtcpu_vinotify_event: tstamp:563879180495 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18044123474560 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 18028.563347: rtcpu_nvcsi_intr: tstamp:563891185771 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
     kworker/2:0-2988    [002] .... 18029.291354: rtcpu_nvcsi_intr: tstamp:563913131236 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
     kworker/2:0-2988    [002] .... 18030.919275: rtcpu_vinotify_event: tstamp:563964686627 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18046865018560 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 18030.919279: rtcpu_vinotify_event: tstamp:563964686886 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18046865028320 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 18030.919280: rtcpu_vinotify_event: tstamp:563964687178 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18046865119328 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 18030.919280: rtcpu_vinotify_event: tstamp:563964687427 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18046865129216 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 18039.891243: rtcpu_string: tstamp:564245865923 id:0x04010000 str:"VM0 deactivating."
     kworker/2:0-2988    [002] .... 18091.321494: rtcpu_string: tstamp:565851778352 id:0x04010000 str:"VM0 activating."
     kworker/2:0-2988    [002] .... 18091.321499: rtcpu_vinotify_event: tstamp:565852234341 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18107261091136 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 18091.321499: rtcpu_vinotify_event: tstamp:565852234632 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18107261100896 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 18091.321499: rtcpu_vinotify_event: tstamp:565852234918 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18107261191968 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 18091.321500: rtcpu_vinotify_event: tstamp:565852235167 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18107261201824 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 18091.713479: rtcpu_nvcsi_intr: tstamp:565864262703 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
     kworker/2:0-2988    [002] .... 18092.385653: rtcpu_nvcsi_intr: tstamp:565886073931 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
     kworker/2:0-2988    [002] .... 18093.897424: rtcpu_vinotify_event: tstamp:565932631177 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18109839274560 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 18093.897428: rtcpu_vinotify_event: tstamp:565932631437 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18109839284288 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 18093.897428: rtcpu_vinotify_event: tstamp:565932631724 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18109839360320 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 18093.897429: rtcpu_vinotify_event: tstamp:565932631972 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18109839370208 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 18096.697348: rtcpu_vinotify_event: tstamp:566020522846 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18112655240352 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 18096.697352: rtcpu_vinotify_event: tstamp:566020523104 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18112655250112 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 18096.697352: rtcpu_vinotify_event: tstamp:566020523397 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18112655326144 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 18096.697352: rtcpu_vinotify_event: tstamp:566020523647 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18112655336032 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 18099.497260: rtcpu_vinotify_event: tstamp:566108454307 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18115470393280 data:0x759e300010000000
     kworker/2:0-2988    [002] .... 18099.497264: rtcpu_vinotify_event: tstamp:566108454572 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18115470403008 data:0x0000000031000001
     kworker/2:0-2988    [002] .... 18099.497264: rtcpu_vinotify_event: tstamp:566108454866 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18115470472096 data:0x759e2d0010000000
     kworker/2:0-2988    [002] .... 18099.497264: rtcpu_vinotify_event: tstamp:566108455115 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:18115470482016 data:0x0000000031000002
     kworker/2:0-2988    [002] .... 18108.065197: rtcpu_string: tstamp:566375427867 id:0x04010000 str:"VM0 deactivating."

Suppose it’s normal. It’s follow the vi-mapping in device tree to route the vi id.

Below is the vi-mapping. You can see the streaming 0/1 using vi-0 and streaming 2/3 using vi-1 and streaming 4 using 0 streaming 5 using 1

        tegra-capture-vi {
                nvidia,vi-max-channels = <0x48>;
                nvidia,vi-mapping-size = <0x06>;
                nvidia,vi-devices = <0x1f4 0x1f6>;
                nvidia,vi-mapping = <0x00 0x00 0x01 0x00 0x02 0x01 0x03 0x01 0x04 0x00 0x05 0x01>;
                num-channels = <0x02>;
                compatible = "nvidia,tegra-camrtc-capture-vi";
                nvidia,vi-mapping-names = "csi-stream-id\0vi-unit-id";
                phandle = <0x352>;

1 Like