Tegra-capture-vi: uncorr_err: request timed out after 5000 ms

hi when i run my camera in my board, i get an error.
The information of error is as follows:
[四 8月 15 16:03:52 2024] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 5000 ms
[四 8月 15 16:03:52 2024] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[四 8月 15 16:03:52 2024] (NULL device *): vi_capture_control_message: NULL VI channel received
[四 8月 15 16:03:52 2024] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=5, csi_port=6
[四 8月 15 16:03:52 2024] (NULL device *): vi_capture_control_message: NULL VI channel received
[四 8月 15 16:03:52 2024] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 5 vc- 0
[四 8月 15 16:03:52 2024] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[四 8月 15 16:03:52 2024] tegra-camrtc-capture-vi tegra-capture-vi: vi5_capture_dequeue: allen 0-tegra194-vi5

I am not sure if there is a problem with the csi port configuration. Could you please help me confirm the configuration information of my device tree?

the port binding:

/ {
tegra-capture-vi {
num-channels = <8>;
ports {
#address-cells = <1>;
#size-cells = <0>;
port@0 {
reg = <0>;
imx390_vi_in0: endpoint {
vc-id = <0>;
port-index = <0>;
bus-width = <4>;
remote-endpoint = <&imx390_csi_out0>;
};
};
port@1 {
reg = <1>;
imx390_vi_in1: endpoint {
vc-id = <1>;
port-index = <0>;
bus-width = <4>;
remote-endpoint = <&imx390_csi_out1>;
};
};
port@2 {
reg = <2>;
imx390_vi_in2: endpoint {
vc-id = <0>;
port-index = <4>;
bus-width = <4>;
remote-endpoint = <&imx390_csi_out2>;
};
};
port@3 {
reg = <3>;
imx390_vi_in3: endpoint {
vc-id = <1>;
port-index = <4>;
bus-width = <4>;
remote-endpoint = <&imx390_csi_out3>;
};
};
port@4 {
reg = <4>;
imx390_vi_in4: endpoint {
vc-id = <0>;
port-index = <2>;
bus-width = <4>;
remote-endpoint = <&imx390_csi_out4>;
};
};
port@5 {
reg = <5>;
imx390_vi_in5: endpoint {
vc-id = <1>;
port-index = <2>;
bus-width = <4>;
remote-endpoint = <&imx390_csi_out5>;
};
};
port@6 {
reg = <6>;
status = “okay”;
imx390_vi_in6: endpoint {
status = “okay”;
vc-id = <0>;
port-index = <5>;
bus-width = <4>;
remote-endpoint = <&imx390_csi_out6>;
};
};
port@7 {
reg = <7>;
status = “okay”;
imx390_vi_in7: endpoint {
status = “okay”;
vc-id = <1>;
port-index = <5>;
bus-width = <4>;
remote-endpoint = <&imx390_csi_out7>;
};
};
};
};

host1x@13e00000 {
	nvcsi@15a00000 {
		num-channels = <8>;
		#address-cells = <1>;
		#size-cells = <0>;
		channel@0 {
			reg = <0>;
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					imx390_csi_in0: endpoint@0 {
						port-index = <0>;
						bus-width = <4>;
						remote-endpoint = <&imx390_imx390_out0>;
					};
				};
				port@1 {
					reg = <1>;
					imx390_csi_out0: endpoint@1 {
						remote-endpoint = <&imx390_vi_in0>;
					};
				};
			};
		};
		channel@1 {
			reg = <1>;
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					imx390_csi_in1: endpoint@2 {
						port-index = <0>;
						bus-width = <4>;
						remote-endpoint = <&imx390_imx390_out1>;
					};
				};
				port@1 {
					reg = <1>;
					imx390_csi_out1: endpoint@3 {
						remote-endpoint = <&imx390_vi_in1>;
					};
				};
			};
		};
		channel@2 {
			reg = <2>;
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					imx390_csi_in2: endpoint@4 {
						port-index = <4>;
						bus-width = <4>;
						remote-endpoint = <&imx390_imx390_out2>;
					};
				};
				port@1 {
					reg = <1>;
					imx390_csi_out2: endpoint@5 {
						remote-endpoint = <&imx390_vi_in2>;
					};
				};
			};
		};
		channel@3 {
			reg = <3>;
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					imx390_csi_in3: endpoint@6 {
						port-index = <4>;
						bus-width = <4>;
						remote-endpoint = <&imx390_imx390_out3>;
					};
				};
				port@1 {
					reg = <1>;
					imx390_csi_out3: endpoint@7 {
						remote-endpoint = <&imx390_vi_in3>;
					};
				};
			};
		};
		channel@4 {
			reg = <4>;
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					imx390_csi_in4: endpoint@8 {
						port-index = <2>;
						bus-width = <4>;
						remote-endpoint = <&imx390_imx390_out4>;
					};
				};
				port@1 {
					reg = <1>;
					imx390_csi_out4: endpoint@9 {
						remote-endpoint = <&imx390_vi_in4>;
					};
				};
			};
		};
		channel@5 {
			reg = <5>;
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					imx390_csi_in5: endpoint@10 {
						port-index = <2>;
						bus-width = <4>;
						remote-endpoint = <&imx390_imx390_out5>;
					};
				};
				port@1 {
					reg = <1>;
					imx390_csi_out5: endpoint@11 {
						remote-endpoint = <&imx390_vi_in5>;
					};
				};
			};
		};
		channel@6 {
			reg = <6>;
			status = "okay";
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					status = "okay";
					imx390_csi_in6: endpoint@12 {
						status = "okay";
						port-index = <6>;
						bus-width = <4>;
						remote-endpoint = <&imx390_imx390_out6>;
					};
				};
				port@1 {
					reg = <1>;
					status = "okay";
					imx390_csi_out6: endpoint@13 {
						status = "okay";
						remote-endpoint = <&imx390_vi_in6>;
					};
				};
			};
		};
		channel@7 {
			reg = <7>;
			status = "okay";
			ports {
				#address-cells = <1>;
				#size-cells = <0>;
				port@0 {
					reg = <0>;
					status = "okay";
					imx390_csi_in7: endpoint@14 {
						status = "okay";
						port-index = <6>;
						bus-width = <4>;
						remote-endpoint = <&imx390_imx390_out7>;
					};
				};
				port@1 {
					reg = <1>;
					status = "okay";
					imx390_csi_out7: endpoint@15 {
						status = "okay";
						remote-endpoint = <&imx390_vi_in7>;
					};
				};
			};
		};
	};
};

The following device information is in use:
imx390_c_0@1d {
compatible = “sony,imx390”;

				reg = <0x32>; /* 9295 will translate to this i2c address */

				/* Physical dimensions of sensor */
				physical_w = "15.0";
				physical_h = "12.5";

				sensor_model ="imx390";

				/* Defines number of frames to be dropped by driver internally after applying */
				/* sensor crop settings. Some sensors send corrupt frames after applying */
				/* crop co-ordinates */
				post_crop_frame_drop = "0";

				/* Convert Gain to unit of dB (decibel) befor passing to kernel driver */
				use_decibel_gain = "true";

				/* enable CID_SENSOR_MODE_ID for sensor modes selection */
				use_sensor_mode_id = "true";

				mode0 {/*mode IMX390_MODE_1920X1080_CROP_30FPS*/
					mclk_khz = "24000";
					num_lanes = "2";
					tegra_sinterface = "serial_c";
					vc_id = "0";
					discontinuous_clk = "no";
					dpcm_enable = "false";
					cil_settletime = "0";
					dynamic_pixel_bit_depth = "16";
					csi_pixel_bit_depth = "16";
					mode_type = "yuv";
					pixel_phase = "uyvy";

					active_w = "2592";
					active_h = "1944";
					readout_orientation = "0";
					line_length = "2600";
					inherent_gain = "1";
					pix_clk_hz = "74250000";
					serdes_pix_clk_hz = "800000000";

					gain_factor = "10";
					min_gain_val = "0"; /* dB */
					max_gain_val = "420"; /* dB */
					step_gain_val = "3"; /* 0.3 */
					default_gain = "150";
					min_hdr_ratio = "1";
					max_hdr_ratio = "1";
					framerate_factor = "1000000";
					min_framerate = "30000000";
					max_framerate = "30000000";
					step_framerate = "1";
					default_framerate = "30000000";
					exposure_factor = "1000000";
					min_exp_time = "59"; /*us, 2 lines*/
					max_exp_time = "33333";
					step_exp_time = "1";
					default_exp_time = "33333";/* us */
					embedded_metadata_height = "0";
				};
				ports {
					#address-cells = <1>;
					#size-cells = <0>;
					port@0 {
						reg = <0>;
						imx390_imx390_out6: endpoint {
							vc-id = <0>;
							port-index = <6>;
							bus-width = <4>;
							remote-endpoint = <&imx390_csi_in6>;
						};
					};
				};
				gmsl-link {
					num-ser-lanes = <4>;
					streams = "yuv16";
				};
			};

the trace is as follows:

tracer: nop

entries-in-buffer/entries-written: 18/18 #P:8

_-----=> irqs-off

/ _----=> need-resched

| / _—=> hardirq/softirq

|| / _–=> preempt-depth

||| / delay

TASK-PID CPU# |||| TIMESTAMP FUNCTION

| | | |||| | |

 kworker/3:3-222     [003] ....  2984.086670: rtcpu_string: tstamp:94411632094 id:0x04010000 str:"VM0 deactivating."

gst-launch-1.0-4803 [003] … 3056.779644: tegra_channel_open: vi-output, imx390 31-0032
gst-launch-1.0-4803 [003] … 3056.779671: tegra_channel_close: vi-output, imx390 31-0032
gst-launch-1.0-4803 [006] … 3056.805876: tegra_channel_open: vi-output, imx390 31-0032
v4l2src0:src-4817 [000] … 3056.824164: tegra_channel_set_power: imx390 31-0032 : 0x1
v4l2src0:src-4817 [000] … 3056.824184: camera_common_s_power: status : 0x1
v4l2src0:src-4817 [000] … 3056.825286: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2src0:src-4817 [000] … 3056.825290: csi_s_power: enable : 0x1
v4l2src0:src-4817 [000] … 3056.825969: tegra_channel_capture_setup: vnc_id 0 W 2592 H 1944 fmt 13
v4l2src0:src-4817 [002] … 3056.833075: tegra_channel_set_stream: enable : 0x1
kworker/3:3-222 [003] … 3056.842315: rtcpu_string: tstamp:96686311748 id:0x04010000 str:“VM0 activating.”
v4l2src0:src-4817 [002] … 3056.848470: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2src0:src-4817 [002] … 3056.848473: csi_s_stream: enable : 0x1
v4l2src0:src-4817 [002] … 3056.848788: tegra_channel_set_stream: imx390 31-0032 : 0x1
kworker/3:3-222 [003] … 3056.898313: rtcpu_vinotify_event: tstamp:96686900213 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3093966643712 data:0x399e300010000000
kworker/3:3-222 [003] … 3056.898314: rtcpu_vinotify_event: tstamp:96686900351 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3093966650176 data:0x0000000031000001
kworker/3:3-222 [003] … 3056.898315: rtcpu_vinotify_event: tstamp:96686900502 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3093966710816 data:0x399e2d0010000000
kworker/3:3-222 [003] … 3056.898315: rtcpu_vinotify_event: tstamp:96686900631 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:3093966717344 data:0x0000000031000002

Does below connect to CSI-C?
The port-index should be 2 if using CSI-C(CSI-2)
And what’s the BSP version?

						imx390_imx390_out6: endpoint {
							vc-id = <0>;
							port-index = <6>;
							bus-width = <4>;
							remote-endpoint = <&imx390_csi_in6>;
						};
					};
				};
				gmsl-link {

I set port-index = <2>

but the problem is still exist.

BSP version is :
my sdk is R35.2.1, jetPack is 5.1-b147

the new log is :
[六 8月 17 17:44:42 2024] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 5000 ms
[六 8月 17 17:44:42 2024] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[六 8月 17 17:44:42 2024] (NULL device *): vi_capture_control_message: NULL VI channel received
[六 8月 17 17:44:42 2024] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=2, csi_port=2
[六 8月 17 17:44:42 2024] (NULL device *): vi_capture_control_message: NULL VI channel received
[六 8月 17 17:44:42 2024] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 2 vc- 0
[六 8月 17 17:44:42 2024] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[六 8月 17 17:44:42 2024] tegra-camrtc-capture-vi tegra-capture-vi: vi5_capture_dequeue: allen 0-tegra194-vi5

trace log is:

  vi-output, imx3-3349    [004] ....   293.712976: tegra_channel_capture_setup: vnc_id 0 W 2592 H 1944 fmt 13
 kworker/1:6-218     [001] ....   293.756893: rtcpu_vinotify_event: tstamp:10354836269 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:331345400352 data:0x399e300010000000
 kworker/1:6-218     [001] ....   293.756895: rtcpu_vinotify_event: tstamp:10354836404 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:331345443040 data:0x0000000031000001
 kworker/1:6-218     [001] ....   293.812893: rtcpu_vinotify_event: tstamp:10355922007 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:331383534720 data:0x399e2d0010000000
 kworker/1:6-218     [001] ....   293.812894: rtcpu_vinotify_event: tstamp:10355922146 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:331383578048 data:0x0000000031000002

vi-output, imx3-3349 [004] … 298.804082: tegra_channel_capture_setup: vnc_id 0 W 2592 H 1944 fmt 13
kworker/1:6-218 [001] … 298.856893: rtcpu_vinotify_event: tstamp:10514885451 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:336465677696 data:0x399e300010000000
kworker/1:6-218 [001] … 298.856896: rtcpu_vinotify_event: tstamp:10514885583 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:336465720384 data:0x0000000031000001
kworker/1:6-218 [001] … 298.912899: rtcpu_vinotify_event: tstamp:10515979009 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:336511198656 data:0x399e2d0010000000
kworker/1:6-218 [001] … 298.912901: rtcpu_vinotify_event: tstamp:10515979144 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:336511241984 data:0x0000000031000002

The trace log shows doesn’t receive any data from the sensor.

So what tests should I do to help me solve this problem? Another phenomenon is that I can see a green screen through the gst-lanch command, why is this?

Please probe the output signal to confirm.
Green screen tell empty buffer(all zero)

hi ShaneCCC:

Through the PCLKDET register on the serializer(max96717) side and the VID_LOCK[bit6] and VPRBS register [bit0] on the deserializer(max96712) side, we can know that valid mipi data has been transmitted to the deserializer, but the agx orin side has not received valid mipi data. Can you support me in solving this problem?

root@desktop:/# i2ctransfer -f -y 31 w2@0x44 0x01 0x12 r1 ---->read PCLKDET
0x8a
root@magna-desktop:/# i2ctransfer -f -y 31 w2@0x29 0x01 0x2c r1 ------>read VID_LOCK
0x62
root@desktop:/# i2ctransfer -f -y 31 w2@0x29 0x02 0x1c r1 ------->read VPRBS
0x81

The trace log is the same as before:

root@magna-desktop:/# cat /sys/kernel/debug/tracing/trace

tracer: nop

entries-in-buffer/entries-written: 20/20 #P:8

_-----=> irqs-off

/ _----=> need-resched

| / _—=> hardirq/softirq

|| / _–=> preempt-depth

||| / delay

TASK-PID CPU# |||| TIMESTAMP FUNCTION

| | | |||| | |

vi-output, imx3-3155 [003] … 242.713168: tegra_channel_capture_setup: vnc_id 0 W 2592 H 1944 fmt 13
kworker/1:2-119 [001] … 242.729420: rtcpu_vinotify_event: tstamp:8745591812 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:279857778304 data:0x399e300010000000
kworker/1:2-119 [001] … 242.729422: rtcpu_vinotify_event: tstamp:8745591948 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:279857820992 data:0x0000000031000001
kworker/1:2-119 [001] … 242.785416: rtcpu_vinotify_event: tstamp:8747219435 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:279898298784 data:0x399e2d0010000000
kworker/1:2-119 [001] … 242.785418: rtcpu_vinotify_event: tstamp:8747219573 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:279898342144 data:0x0000000031000002
vi-output, imx3-3155 [001] … 247.833180: tegra_channel_capture_setup: vnc_id 0 W 2592 H 1944 fmt 13
kworker/1:2-119 [001] … 247.845933: rtcpu_vinotify_event: tstamp:8905641739 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:284973117344 data:0x399e300010000000
kworker/1:2-119 [001] … 247.845934: rtcpu_vinotify_event: tstamp:8905641875 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:284973160032 data:0x0000000031000001
kworker/1:2-119 [001] … 247.901420: rtcpu_vinotify_event: tstamp:8907269199 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:285024428224 data:0x399e2d0010000000
kworker/1:2-119 [001] … 247.901421: rtcpu_vinotify_event: tstamp:8907269339 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:285024471584 data:0x0000000031000002
vi-output, imx3-3155 [001] … 252.957218: tegra_channel_capture_setup: vnc_id 0 W 2592 H 1944 fmt 13
kworker/1:2-119 [001] … 253.001422: rtcpu_vinotify_event: tstamp:9065691056 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:290097141824 data:0x399e300010000000
kworker/1:2-119 [001] … 253.001424: rtcpu_vinotify_event: tstamp:9065691196 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:290097184512 data:0x0000000031000001
kworker/1:2-119 [001] … 253.001425: rtcpu_vinotify_event: tstamp:9066775782 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:290131389792 data:0x399e2d0010000000
kworker/1:2-119 [001] … 253.001425: rtcpu_vinotify_event: tstamp:9066775921 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:290131433152 data:0x0000000031000002
vi-output, imx3-3155 [001] … 258.073175: tegra_channel_capture_setup: vnc_id 0 W 2592 H 1944 fmt 13
kworker/1:2-119 [001] … 258.089413: rtcpu_vinotify_event: tstamp:9225740537 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:295213112320 data:0x399e300010000000
kworker/1:2-119 [001] … 258.089415: rtcpu_vinotify_event: tstamp:9225740693 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:295213159648 data:0x0000000031000001
kworker/1:2-119 [001] … 258.145411: rtcpu_vinotify_event: tstamp:9226844221 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:295258884384 data:0x399e2d0010000000
kworker/1:2-119 [001] … 258.145413: rtcpu_vinotify_event: tstamp:9226844360 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:295258927712 data:0x0000000031000002

run the media ctl command:

Media device information

driver tegra-camrtc-ca
model NVIDIA Tegra Video Input Device
serial
bus info
hw revision 0x3
driver version 5.10.104

Device topology

  • entity 1: 13e40000.host1x:nvcsi@15a00000- (2 pads, 0 link)
    type V4L2 subdev subtype Unknown flags 0
    device node name /dev/v4l-subdev0
    pad0: Sink
    pad1: Source

  • entity 4: 13e40000.host1x:nvcsi@15a00000- (2 pads, 0 link)
    type V4L2 subdev subtype Unknown flags 0
    device node name /dev/v4l-subdev1
    pad0: Sink
    pad1: Source

  • entity 7: 13e40000.host1x:nvcsi@15a00000- (2 pads, 0 link)
    type V4L2 subdev subtype Unknown flags 0
    device node name /dev/v4l-subdev2
    pad0: Sink
    pad1: Source

  • entity 10: 13e40000.host1x:nvcsi@15a00000- (2 pads, 0 link)
    type V4L2 subdev subtype Unknown flags 0
    device node name /dev/v4l-subdev3
    pad0: Sink
    pad1: Source

  • entity 13: 13e40000.host1x:nvcsi@15a00000- (2 pads, 0 link)
    type V4L2 subdev subtype Unknown flags 0
    device node name /dev/v4l-subdev4
    pad0: Sink
    pad1: Source

  • entity 16: 13e40000.host1x:nvcsi@15a00000- (2 pads, 0 link)
    type V4L2 subdev subtype Unknown flags 0
    device node name /dev/v4l-subdev5
    pad0: Sink
    pad1: Source

  • entity 19: 13e40000.host1x:nvcsi@15a00000- (2 pads, 2 links)
    type V4L2 subdev subtype Unknown flags 0
    device node name /dev/v4l-subdev6
    pad0: Sink
    ← “imx390 31-0032”:0 [ENABLED]
    pad1: Source
    → “vi-output, imx390 31-0032”:0 [ENABLED]

  • entity 22: 13e40000.host1x:nvcsi@15a00000- (2 pads, 0 link)
    type V4L2 subdev subtype Unknown flags 0
    device node name /dev/v4l-subdev7
    pad0: Sink
    pad1: Source

  • entity 25: imx390 31-0032 (1 pad, 1 link)
    type V4L2 subdev subtype Sensor flags 0
    device node name /dev/v4l-subdev8
    pad0: Source
    [fmt:UYVY8_1X16/2592x1944 field:none colorspace:srgb]
    → “13e40000.host1x:nvcsi@15a00000-”:0 [ENABLED]

  • entity 27: vi-output, imx390 31-0032 (1 pad, 1 link)
    type Node subtype V4L flags 0
    device node name /dev/video0
    pad0: Sink
    ← “13e40000.host1x:nvcsi@15a00000-”:1 [ENABLED]

The trace log are the same.
Maybe check the port-index/bus-width is match HW connection.

Thanks

The tegra_sinterface in camera mode in the device tree should correlate with the port index in mode, independent of the port index in vi and nvcsi, right?

Yes, if confirm the HW connection still the same.
I would suggest checking the MIPI signal.

From the current debugging situation, we can see that mipi data is transmitted to the deserializer. Is there any way to confirm whether agx orin has received the signal? I understand that VI Receive NULL is also possible for other reasons, right? What I mean is whether the signal can be observed at the first entry point to obtain mipi data from agx orin?

I have uploaded a device tree file that I am using now, could you please help me check port_index and bus_width in the config of vi and nvcsi and imx390?
tegra234-p3737-0000-camera-zuojisi-imx390.txt (22.8 KB)

hi ShaneCCC, I change port index in mode, the trace have a new change.

the latest new trace is as follows:
kworker/2:4-200 [002] … 305.884720: rtcpu_vinotify_event: tstamp:10726685302 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343252882656 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884720: rtcpu_vinotify_event: tstamp:10726685450 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343253728672 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884720: rtcpu_vinotify_event: tstamp:10726685581 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343253728672 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884720: rtcpu_vinotify_error: tstamp:10726691447 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343254010656 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884720: rtcpu_vinotify_error: tstamp:10726722260 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343254997632 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884721: rtcpu_vinotify_error: tstamp:10726731073 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343255279648 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884721: rtcpu_vinotify_error: tstamp:10726757511 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343256125632 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884721: rtcpu_vinotify_error: tstamp:10726797167 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343257394592 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884721: rtcpu_vinotify_error: tstamp:10726806110 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343257676608 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884721: rtcpu_vinotify_error: tstamp:10726836822 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343258663584 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884721: rtcpu_vinotify_error: tstamp:10726846001 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343258945568 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884721: rtcpu_vinotify_event: tstamp:10726846546 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343254010656 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884722: rtcpu_vinotify_event: tstamp:10726846677 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343254010656 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884722: rtcpu_vinotify_event: tstamp:10726846826 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343254997632 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884722: rtcpu_vinotify_event: tstamp:10726846957 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343254997632 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884722: rtcpu_vinotify_event: tstamp:10726847105 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343255279648 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884722: rtcpu_vinotify_event: tstamp:10726847233 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343255279648 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884722: rtcpu_vinotify_event: tstamp:10726847382 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343256125632 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884723: rtcpu_vinotify_event: tstamp:10726847511 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343256125632 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884723: rtcpu_vinotify_event: tstamp:10726847657 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343257394592 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884723: rtcpu_vinotify_event: tstamp:10726847786 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343257394624 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884723: rtcpu_vinotify_event: tstamp:10726847936 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343257676608 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884723: rtcpu_vinotify_event: tstamp:10726848064 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343257676608 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884723: rtcpu_vinotify_event: tstamp:10726848213 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343258663584 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884724: rtcpu_vinotify_event: tstamp:10726848343 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343258663616 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884724: rtcpu_vinotify_event: tstamp:10726848492 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343258945568 data:0x00002b2b000000a2
kworker/2:4-200 [002] … 305.884724: rtcpu_vinotify_event: tstamp:10726848623 cch:-1 vi:1 tag:FS channel:0x00 frame:11051 vi_tstamp:343258945568 data:0x00002b2b00000012
kworker/2:4-200 [002] … 305.884724: rtcpu_vinotify_error: tstamp:10726872072 cch:-1 vi:1 tag:CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343259791584 data:0x00002b2b000000a2

This error tell lost the FE(Frame End) package.

CSIMUX_FRAME channel:0x00 frame:11051 vi_tstamp:343259791584 data:0x00002b2b000000a2

I don’t really understand. It means there’s no end signal in the frame data being sent, right?
What does cch = -1 mean? Does it matter?