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.
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
Hi Shane,
Now I can see max96724 reg : 0x8D1 has mipi count.
But I still got same error
[242649.796230] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[242649.805478] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[242649.816337] (NULL device *): vi_capture_control_message: NULL VI channel received
[242649.824137] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=1, csi_port=1
[242649.834893] (NULL device *): vi_capture_control_message: NULL VI channel received
[242649.842690] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 1 vc- 0
[242649.853642] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[242652.608173] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[242652.617419] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[242652.628005] (NULL device *): vi_capture_control_message: NULL VI channel received
[242652.635820] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=1, csi_port=1
[242652.646571] (NULL device *): vi_capture_control_message: NULL VI channel received
[242652.654371] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 1 vc- 0
[242652.665331] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
We connect max96724 CSI2 to orin nano pin csi 2.
Hi Shane,
We try some different vi seting, now we didn’t get “NULL VI channel received” error.
But still no any image out put.
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 > /sys/kernel/debug/tracing/trace
v4l2-ctl -d /dev/video0 --set-ctrl bypass_mode=0 --stream-mmap --stream-count=1 --stream-to=test.raw
sudo cat /sys/kernel/debug/tracing/trace > log.txt
# tracer: nop
#
# entries-in-buffer/entries-written: 1450/1450 #P:6
#
# _-----=> irqs-off
# / _----=> need-resched
# | / _---=> hardirq/softirq
# || / _--=> preempt-depth
# ||| / delay
# TASK-PID CPU# |||| TIMESTAMP FUNCTION
# | | | |||| | |
kworker/1:0-4781 [001] .... 657.637381: rtcpu_string: tstamp:21076595563 id:0x04010000 str:"VM0 deactivating."
kworker/1:0-4781 [001] .... 664.953154: rtcpu_string: tstamp:21305949793 id:0x04010000 str:"VM0 activating."
kworker/1:0-4781 [001] .... 664.953160: rtcpu_vinotify_event: tstamp:21306579117 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:681795501312 data:0x759e300010000000
kworker/1:0-4781 [001] .... 664.953160: rtcpu_vinotify_event: tstamp:21306579375 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:681795511072 data:0x0000000031000001
kworker/1:0-4781 [001] .... 664.953161: rtcpu_vinotify_event: tstamp:21306579668 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:681795602144 data:0x759e2d0010000000
kworker/1:0-4781 [001] .... 664.953161: rtcpu_vinotify_event: tstamp:21306579915 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:681795612032 data:0x0000000031000002
kworker/1:0-4781 [001] .... 665.345344: rtcpu_nvcsi_intr: tstamp:21318678112 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
kworker/1:0-4781 [001] .... 665.401144: rtcpu_vinotify_error: tstamp:21319468859 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:682222734016 data:0x0000000000000569
kworker/1:0-4781 [001] .... 665.401150: rtcpu_vinotify_event: tstamp:21319599282 cch:0 vi:1 tag:FS channel:0x00 frame:0 vi_tstamp:682222718528 data:0x0000000000000012
kworker/1:0-4781 [001] .... 665.401151: rtcpu_vinotify_event: tstamp:21319599545 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:682222718624 data:0x0000000800000000
kworker/1:0-4781 [001] .... 665.401151: rtcpu_vinotify_event: tstamp:21319599828 cch:-1 vi:1 tag:FS channel:0x01 frame:0 vi_tstamp:682222719136 data:0x0000000000000012
kworker/1:0-4781 [001] .... 665.401152: rtcpu_vinotify_event: tstamp:21319600081 cch:0 vi:1 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:682222728640 data:0x0000000000000001
kworker/1:0-4781 [001] .... 665.401152: rtcpu_vinotify_event: tstamp:21319600363 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:682222768192 data:0x0000000008020001
kworker/1:0-4781 [001] .... 665.401152: rtcpu_vinotify_event: tstamp:21319600607 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:682222734016 data:0x0000000000000569
kworker/1:0-4781 [001] .... 665.401155: rtcpu_nvcsi_intr: tstamp:21319859244 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
kworker/1:0-4781 [001] .... 665.401155: rtcpu_vinotify_error: tstamp:21319881293 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:682235876704 data:0x0000000000000569
kworker/1:0-4781 [001] .... 665.401156: rtcpu_vinotify_event: tstamp:21319882780 cch:-1 vi:1 tag:FE channel:0x01 frame:0 vi_tstamp:682235491488 data:0x0000000000000022
kworker/1:0-4781 [001] .... 665.401156: rtcpu_vinotify_event: tstamp:21319883032 cch:0 vi:1 tag:FE channel:0x00 frame:0 vi_tstamp:682235493280 data:0x0000000000000022
kworker/1:0-4781 [001] .... 665.401156: rtcpu_vinotify_event: tstamp:21319883318 cch:0 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:0 vi_tstamp:682235493280 data:0x04a0200001000000
kworker/1:0-4781 [001] .... 665.401157: rtcpu_vinotify_event: tstamp:21319883571 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:682235564128 data:0x0000000001020001
kworker/1:0-4781 [001] .... 665.401157: rtcpu_vinotify_event: tstamp:21319883859 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:682235493344 data:0x0000000800000000
kworker/1:0-4781 [001] .... 665.401158: rtcpu_vinotify_event: tstamp:21319884100 cch:0 vi:1 tag:FS channel:0x00 frame:0 vi_tstamp:682235861184 data:0x0000000000000012
kworker/1:0-4781 [001] .... 665.401158: rtcpu_nvcsi_intr: tstamp:21320269954 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
kworker/1:0-4781 [001] .... 665.401158: rtcpu_vinotify_error: tstamp:21320291541 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:682249019360 data:0x0000000000000569
kworker/1:0-4781 [001] .... 665.401159: rtcpu_vinotify_event: tstamp:21320293701 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:682235861280 data:0x0000000800000000
kworker/1:0-4781 [001] .... 665.401159: rtcpu_vinotify_event: tstamp:21320293951 cch:-1 vi:1 tag:FS channel:0x01 frame:0 vi_tstamp:682235861792 data:0x0000000000000012
kworker/1:0-4781 [001] .... 665.401160: rtcpu_vinotify_event: tstamp:21320294244 cch:0 vi:1 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:682235871328 data:0x0000000000000001
kworker/1:0-4781 [001] .... 665.401160: rtcpu_vinotify_event: tstamp:21320294493 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:682235931936 data:0x0000000008020002
kworker/1:0-4781 [001] .... 665.401160: rtcpu_vinotify_event: tstamp:21320294777 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:682235876704 data:0x0000000000000569
kworker/1:0-4781 [001] .... 665.401161: rtcpu_vinotify_event: tstamp:21320295026 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:682236154944 data:0x759e300010000000
kworker/1:0-4781 [001] .... 665.401161: rtcpu_vinotify_event: tstamp:21320295310 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:682236245280 data:0x0000000031000003
kworker/1:0-4781 [001] .... 665.401162: rtcpu_vinotify_event: tstamp:21320295555 cch:-1 vi:1 tag:FE channel:0x01 frame:0 vi_tstamp:682248634144 data:0x0000000000000022
kworker/1:0-4781 [001] .... 665.401162: rtcpu_vinotify_event: tstamp:21320295829 cch:0 vi:1 tag:FE channel:0x00 frame:0 vi_tstamp:682248635936 data:0x0000000000000022
kworker/1:0-4781 [001] .... 665.401162: rtcpu_vinotify_event: tstamp:21320296080 cch:0 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:0 vi_tstamp:682248635936 data:0x04a0200001000000
kworker/1:0-4781 [001] .... 665.401163: rtcpu_vinotify_event: tstamp:21320296359 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:682248706816 data:0x0000000001020002
Full log please see log.txt
log.txt (256.5 KB)
Looks like short frame in the trace log. That tell the output line less than expected. Boost the clocks to try. If didn’t help you need to confirm the output size from the sensor configuration.
echo 1 > /sys/kernel/debug/bpmp/debug/clk/vi/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/isp/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/nvcsi/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/emc/mrq_rate_locked
cat /sys/kernel/debug/bpmp/debug/clk/vi/max_rate |tee /sys/kernel/debug/bpmp/debug/clk/vi/rate
cat /sys/kernel/debug/bpmp/debug/clk/isp/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/isp/rate
cat /sys/kernel/debug/bpmp/debug/clk/nvcsi/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/nvcsi/rate
cat /sys/kernel/debug/bpmp/debug/clk/emc/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/emc/rate
Hi Shane,
Here is after boost the clocks outout
kworker/2:1-44 [002] .... 12529.412492: rtcpu_nvcsi_intr: tstamp:392042842541 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
kworker/2:1-44 [002] .... 12529.412497: rtcpu_vinotify_error: tstamp:392042928904 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:12545373666048 data:0x0000000000000569
kworker/2:1-44 [002] .... 12529.412497: rtcpu_vinotify_error: tstamp:392042929510 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:0 vi_tstamp:12545373671456 data:0x0000000000000569
kworker/2:1-44 [002] .... 12529.412499: rtcpu_vinotify_event: tstamp:392042930443 cch:0 vi:1 tag:FE channel:0x00 frame:0 vi_tstamp:12545370956928 data:0x0000000000000022
kworker/2:1-44 [002] .... 12529.412500: rtcpu_vinotify_event: tstamp:392042930688 cch:-1 vi:1 tag:FE channel:0x01 frame:0 vi_tstamp:12545370958688 data:0x0000000000000022
kworker/2:1-44 [002] .... 12529.412500: rtcpu_vinotify_event: tstamp:392042930963 cch:-1 vi:1 tag:FS channel:0x01 frame:0 vi_tstamp:12545373656000 data:0x0000000000000012
kworker/2:1-44 [002] .... 12529.412500: rtcpu_vinotify_event: tstamp:392042931219 cch:0 vi:1 tag:FS channel:0x00 frame:0 vi_tstamp:12545373656544 data:0x0000000000000012
kworker/2:1-44 [002] .... 12529.412501: rtcpu_vinotify_event: tstamp:392043238774 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:12545373666048 data:0x0000000000000569
kworker/2:1-44 [002] .... 12529.412501: rtcpu_vinotify_event: tstamp:392043239031 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:0 vi_tstamp:12545373671456 data:0x0000000000000569
kworker/2:1-44 [002] .... 12529.468359: rtcpu_nvcsi_intr: tstamp:392044925804 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
kworker/2:1-44 [002] .... 12529.468364: rtcpu_vinotify_event: tstamp:392044942148 cch:0 vi:1 tag:FE channel:0x00 frame:0 vi_tstamp:12545437621312 data:0x0000000000000022
kworker/2:1-44 [002] .... 12529.468365: rtcpu_vinotify_event: tstamp:392044942397 cch:-1 vi:1 tag:FE channel:0x01 frame:0 vi_tstamp:12545437623104 data:0x0000000000000022
kworker/2:1-44 [002] .... 12529.468366: rtcpu_vinotify_error: tstamp:392045011794 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:12545440330464 data:0x0000000000000569
kworker/2:1-44 [002] .... 12529.468367: rtcpu_vinotify_error: tstamp:392045012404 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:0 vi_tstamp:12545440335840 data:0x0000000000000569
kworker/2:1-44 [002] .... 12529.468368: rtcpu_vinotify_event: tstamp:392045282672 cch:-1 vi:1 tag:FS channel:0x01 frame:0 vi_tstamp:12545440320352 data:0x0000000000000012
kworker/2:1-44 [002] .... 12529.468368: rtcpu_vinotify_event: tstamp:392045282925 cch:0 vi:1 tag:FS channel:0x00 frame:0 vi_tstamp:12545440320928 data:0x0000000000000012
kworker/2:1-44 [002] .... 12529.468368: rtcpu_vinotify_event: tstamp:392045283203 cch:-1 vi:1 tag:CHANSEL_NOMATCH channel:0x44 frame:0 vi_tstamp:12545440330464 data:0x0000000000000569
kworker/2:1-44 [002] .... 12529.468369: rtcpu_vinotify_event: tstamp:392045283450 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:0 vi_tstamp:12545440335840 data:0x0000000000000569
kworker/2:1-44 [002] .... 12529.580348: rtcpu_vinotify_event: tstamp:392048689345 cch:-1 vi:1 tag:FE channel:0x00 frame:0 vi_tstamp:12545553602432 data:0x0000000000000022
kworker/2:1-44 [002] .... 12534.620391: rtcpu_string: tstamp:392205597784 id:0x04010000 str:"VM0 deactivating.
So you think may be is GMSL clock problem? if yes we will try to fix on this way.
Looks like cause by deskew calibration error. That could be the output data rate > 1.5G and sensor didn’t send deskew words.
What’s the output size and frame rate?
Skew calibration is required if sensor or deserializer is using DPHY, and the output data rate is > 1.5Gbps.
An initiation deskew signal should be sent by sensor or deserializer to perform the skew calibration. If the deskew signals is not sent, the receiver will stall, and the capture will time out.
You can calculate the output data rate with the following equation:
Output data rate = (sensor or deserializer pixel clock in hertz) * (bits per pixel) / (number of CSI lanes)
Hi Shane,
All size and frame rate is from zed setting.
We don’t sure current seting in ar0234 since we don’t have that datasheet(We only make our own deserializer board.)
By Zed dts, there has three mode
1920X1200_60FPS
960X600_120FPS
1920X1080_CROP_60FPS
When we use cmd
sudo v4l2-ctl -c bypass_mode=0 --stream-mmap
Deserializer will set to 15FPS
Reg : 0x04A5 = 0x68, Reg : 0x04A6 = 0x6E, Reg : 0x04A7 = 0x19
The rate in dts provide by zed is
pix_clk_hz = "229500000";
serdes_pix_clk_hz = "299000000";
What’s the num of lanes and data format?
Hi Shane,
It’s 2 lane,
What is data format? Is Raw10? or someting?
You can check by v4l2-ctl --list-formats-ext.
If 10bits the output rate didn’t > 1.5G. Maybe can ignore the deskew error.
kworker/2:1-44 [002] .... 12529.468359: rtcpu_nvcsi_intr: tstamp:392044925804 class:GLOBAL type:PHY_INTR0 phy:1 cil:0 st:0 vc:0 status:0x0e000000
However still have lots of CHANSEL_NOMATCH could be incorrect vc-id data cause it.
Hi Shane,
$ v4l2-ctl --list-formats-ext
ioctl: VIDIOC_ENUM_FMT
Type: Video Capture
[0]: 'BA10' (10-bit Bayer GRGR/BGBG)
Size: Discrete 1920x1200
Interval: Discrete 0.033s (30.000 fps)
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 960x600
Interval: Discrete 0.017s (60.000 fps)
Interval: Discrete 0.008s (120.000 fps)
Size: Discrete 1920x1080
Interval: Discrete 0.033s (30.000 fps)
Interval: Discrete 0.017s (60.000 fps)
Hi Shane,
After some config(clock) change, we can see frame, but will got error when change fps
[ 297.470882] misc tegra_camera_ctrl: tegra_camera_update_isobw: Warning, Requested ISO BW 18446744073709478618 has been capped to VI's max BW 2000000
[ 301.405782] ------------[ cut here ]------------
[ 301.405806] WARNING: CPU: 2 PID: 2527 at kernel/module.c:1162 module_put+0x18c/0x1a0
[ 301.413788] Modules linked in: st_zedx(OE) fuse(E) nvidia_modeset(OE) lzo_rle(E) lzo_compress(E) zram(E) ramoops(E) reed_solomon(E) loop(E) hid_logitech_hidpp(E) input_leds(E) snd_soc_tegra186_dspk(E) snd_soc_tegra186_asrc(E) snd_soc_tegra210_ope(E) snd_soc_tegra186_arad(E) snd_soc_tegra210_iqc(E) snd_soc_tegra210_mvc(E) snd_soc_tegra210_adx(E) snd_soc_tegra210_afc(E) snd_soc_tegra210_dmic(E) snd_soc_tegra210_admaif(E) snd_soc_tegra_pcm(E) snd_soc_tegra210_amx(E) snd_soc_tegra210_mixer(E) snd_soc_tegra210_i2s(E) snd_soc_tegra210_sfc(E) aes_ce_blk(E) crypto_simd(E) cryptd(E) aes_ce_cipher(E) ghash_ce(E) sha2_ce(E) sha256_arm64(E) sha1_ce(E) hid_logitech_dj(E) snd_soc_spdif_tx(E) snd_soc_tegra_machine_driver(E) snd_soc_tegra210_adsp(E) snd_soc_tegra210_ahub(E) snd_hda_codec_hdmi(E) snd_soc_tegra_utils(E) userspace_alert(E) snd_soc_simple_card_utils(E) tegra_bpmp_thermal(E) fusb301(E) nvadsp(E) snd_hda_tegra(E) tegra210_adma(E) bmi088(E) snd_hda_codec(E) kfifo_buf(E) snd_hda_core(E)
[ 301.413915] spi_tegra114(E) igc(E) r8168(E) nvidia(OE) binfmt_misc(E) ina3221(E) pwm_fan(E) nvgpu(E) nvmap(E) ip_tables(E) x_tables(E) [last unloaded: st_zedx]
[ 301.413952] CPU: 2 PID: 2527 Comm: CaptureSchedule Tainted: G OE 5.10.120-tegra #1
[ 301.413955] Hardware name: Unknown NVIDIA Orin Nano Developer Kit/NVIDIA Orin Nano Developer Kit, BIOS 4.1-33958178 08/01/2023
[ 301.413962] pstate: 80400009 (Nzcv daif +PAN -UAO -TCO BTYPE=--)
[ 301.413967] pc : module_put+0x18c/0x1a0
[ 301.413971] lr : module_put+0x20/0x1a0
[ 301.413974] sp : ffff80001a30b990
[ 301.413977] x29: ffff80001a30b990 x28: ffff1928cea11700
[ 301.413984] x27: ffff192952de28b8 x26: 0000000000000000
[ 301.413990] x25: ffffd2918f024028 x24: ffff19297c180158
[ 301.413996] x23: ffff19297c181160 x22: ffff192945775820
[ 301.414002] x21: ffff192979f52c80 x20: ffffd291c29713f8
[ 301.414008] x19: ffffd2918f025380 x18: 0000000000000010
[ 301.414014] x17: 0000000000000000 x16: ffffd291c1c85220
[ 301.414020] x15: ffff192957e622f0 x14: ffffffffffffffff
[ 301.414026] x13: ffff80009a30b687 x12: ffff80001a30b68f
[ 301.414031] x11: 0000000000000008 x10: 0000000000000ab0
[ 301.414037] x9 : 00000000fffffffe x8 : 6d61657274735f70
[ 301.414043] x7 : 6f74735f7864657a x6 : c0000000ffffefff
[ 301.414049] x5 : 0000000000000000 x4 : 0000000000000000
[ 301.414055] x3 : ffffd2918f0256c0 x2 : 00000000ffffffff
[ 301.414060] x1 : ffff192957e61d80 x0 : 0000000000000001
[ 301.414067] Call trace:
[ 301.414072] module_put+0x18c/0x1a0
[ 301.414080] v4l2sd_stream+0xb8/0x230
[ 301.414087] tegra_channel_set_stream+0x3d4/0x580
[ 301.414092] vi5_channel_stop_streaming+0x34/0xe0
[ 301.414097] tegra_channel_stop_streaming+0x3c/0x70
[ 301.414104] __vb2_queue_cancel+0x40/0x220
[ 301.414109] vb2_core_streamoff+0x34/0xd0
[ 301.414113] vb2_streamoff+0x34/0x80
[ 301.414116] vb2_ioctl_streamoff+0x58/0x70
[ 301.414122] v4l_streamoff+0x40/0x50
[ 301.414127] __video_do_ioctl+0x188/0x400
[ 301.414132] video_usercopy+0x280/0x790
[ 301.414137] video_ioctl2+0x40/0x180
[ 301.414140] v4l2_ioctl+0x68/0x90
[ 301.414148] __arm64_sys_ioctl+0xac/0xf0
[ 301.414157] el0_svc_common.constprop.0+0x80/0x1d0
[ 301.414162] do_el0_svc+0x38/0xb0
[ 301.414168] el0_svc+0x1c/0x30
[ 301.414172] el0_sync_handler+0xa8/0xb0
[ 301.414176] el0_sync+0x16c/0x180
[ 301.414180] ---[ end trace 768ef14cc7ee579b ]---
[ 301.421532] bwmgr API not supported
[ 301.421690] zedx_power_off
[ 301.674145] zedx_power_on
[ 301.931994] bwmgr API not supported
[ 301.934235] bwmgr API not supported
[ 301.956373] bwmgr API not supported
[ 472.336068] NVRM rpcRmApiControl_dce: NVRM_RPC_DCE: Failed RM ctrl call cmd:0x731341 result 0xffff:
[ 472.414807] NVRM nvAssertFailedNoLog: Assertion failed: minRequiredIsoBandwidthKBPS <= clientBwValues[DISPLAY_ICC_BW_CLIENT_EXT].minRequiredIsoBandwidthKBPS @ kern_disp_0402.c:111
Get the trace log to check if any clue.
Thanks