T194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=2, csi_port=2

Env: Xavier NX Series + 9296 + 9295 + ov5640
Describe:
Running “v4l2-ctl -d /dev/video0 --verbose --set-fmt-video=width=1920,height=1080,pixelformat=UYVY --set-ctrl=sensor_mode=0 --stream-mmap --stream-count=100”
couldn’t get data. The oscilloscope can measure the mipi output, and I don’t know how to locate the problem.

[  386.496397] z_ov5640 10-001c: z_ov5640_power_on: power on
[  386.501432] z_ov5640 10-001c: zuojisi : z_ov5640_set_mode, mode=0
[  386.501441] z_ov5640 10-001c: zuojisi : z_ov5640_write_table
[  386.501449] z_ov5640 10-001c: zuojisi:ov5640 (0x3103)->0x11
[  386.501454] z_ov5640 10-001c: zuojisi:ov5640 (0x3008)->0x82
[  386.501459] z_ov5640 10-001c: zuojisi:ov5640 (0x3008)->0x42
[  386.501464] z_ov5640 10-001c: zuojisi:ov5640 (0x3103)->0x03
[  386.501468] z_ov5640 10-001c: zuojisi:ov5640  more...
[  386.567411] z_ov5640 10-001c: zuojisi : z_ov5640_start_streaming
[  386.567420] max9295 10-0062: zuojisi: 9295 setup streaming - enter
[  386.567425] max9295 10-0062: zuojisi:9295 port=B
[  386.567780] max9295 10-0062: zuojisi:9295:w addr(0x0330)->0x00
[  386.568129] max9295 10-0062: zuojisi:9295:w addr(0x0331)->0x10
[  386.568495] max9295 10-0062: zuojisi:9295:w addr(0x0332)->0xe0
[  386.568854] max9295 10-0062: zuojisi:9295:w addr(0x0333)->0x04
[  386.569204] max9295 10-0062: zuojisi:9295:w addr(0x0334)->0x70
[  386.569551] max9295 10-0062: zuojisi:9295:w addr(0x0335)->0x07
[  386.569557] max9295 10-0062: zuojisi:9295: cnt=0,stream_type=YUV16
[  386.569903] max9295 10-0062: zuojisi:9295:w addr(0x031a)->0x5e
[  386.569911] max9295 10-0062: zuojisi:9295: routing data_type=0x1e to PipelineU, with stream_id=3
[  386.569916] max9295 10-0062: zuojisi: 9295 start pipeline...:
[  386.571436] max9295 10-0062: zuojisi:9295:w addr(0x0311)->0xf0
[  386.571804] max9295 10-0062: zuojisi:9295:w addr(0x0308)->0x68
[  386.572156] max9295 10-0062: zuojisi:9295:w addr(0x0002)->0xf3
[  386.572163] max9295 10-0062: zuojisi: 9295 setup streaming - exit
[  386.572169] max9296 10-0048: zuojisi: 9296 setup streaming - enter
[  386.572205] max9296 10-0048: zuojisi:9296: num_streams=1
[  386.572210] max9296 10-0048: zuojisi:9296: stream 0 type is YUV16
[  386.572216] max9296 10-0048: zuojisi:rx_0 receive stream_id=3, datatype=0x1e
[  386.572578] max9296 10-0048: zuojisi:9296:w addr(0x040b)->0x07
[  386.573117] max9296 10-0048: zuojisi:9296:w addr(0x042d)->0x15
[  386.573506] max9296 10-0048: zuojisi:9296:w addr(0x040d)->0x1e
[  386.573887] max9296 10-0048: zuojisi:9296:w addr(0x040e)->0x5e
[  386.574270] max9296 10-0048: zuojisi:9296:w addr(0x040f)->0x00
[  386.574670] max9296 10-0048: zuojisi:9296:w addr(0x0410)->0x40
[  386.575060] max9296 10-0048: zuojisi:9296:w addr(0x0411)->0x01
[  386.575464] max9296 10-0048: zuojisi:9296:w addr(0x0412)->0x41
[  386.575848] max9296 10-0048: zuojisi:9296:w addr(0x044a)->0x40
[  386.575858] max9296 10-0048: zuojisi:9296 num_csi_lanes=2
[  386.576240] max9296 10-0048: zuojisi:9296:w addr(0x0330)->0x04
[  386.576633] max9296 10-0048: zuojisi:9296:w addr(0x0333)->0x4e
[  386.577041] max9296 10-0048: zuojisi:9296:w addr(0x0334)->0xe4
[  386.577439] max9296 10-0048: zuojisi:9296:w addr(0x0320)->0x2c
[  386.577450] max9296 10-0048: zuojisi: 9296 setup streaming - exit
[  386.577458] max9296 10-0048: zuojisi: 9296 start streaming - enter
[  386.577465] max9296 10-0048: zuojisi: trigger skew calibration
[  386.577842] max9296 10-0048: zuojisi:9296:w addr(0x0443)->0x10
[  386.578222] max9296 10-0048: zuojisi:9296:w addr(0x0483)->0x10
[  386.578601] max9296 10-0048: zuojisi:9296:w addr(0x0443)->0x33
[  386.578977] max9296 10-0048: zuojisi:9296:w addr(0x0483)->0x33
[  386.579429] max9296 10-0048: zuojisi:9296:w addr(0x0050)->0x03
[  386.579439] max9296 10-0048: zuojisi: 9296 start streaming - exit
[  386.579447] z_ov5640 10-001c: zuojisi : z_ov5640_write_table
[  386.579454] z_ov5640 10-001c: zuojisi:ov5640 (0x3008)->0x02
[  389.091245] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  389.091535] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  389.092740] (NULL device *): vi_capture_control_message: NULL VI channel received
[  389.092986] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=2, csi_port=2
[  389.093239] (NULL device *): vi_capture_control_message: NULL VI channel received
[  389.093535] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 2 vc- 1
[  389.094604] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  391.651296] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  391.651630] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  391.652749] (NULL device *): vi_capture_control_message: NULL VI channel received
[  391.652949] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=2, csi_port=2
[  391.653282] (NULL device *): vi_capture_control_message: NULL VI channel received
[  391.653458] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 2 vc- 1
[  391.654278] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  394.211301] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  394.211598] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  394.212736] (NULL device *): vi_capture_control_message: NULL VI channel received
[  394.212913] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=2, csi_port=2
[  394.213134] (NULL device *): vi_capture_control_message: NULL VI channel received
[  394.213292] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 2 vc- 1
[  394.214347] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  396.771336] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  396.771615] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  396.772706] (NULL device *): vi_capture_control_message: NULL VI channel received
[  396.772918] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=2, csi_port=2
[  396.773113] (NULL device *): vi_capture_control_message: NULL VI channel received
[  396.773276] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 2 vc- 1
[  396.774143] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  399.299320] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  399.299571] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  399.300765] (NULL device *): vi_capture_control_message: NULL VI channel received
[  399.300941] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=2, csi_port=2
[  399.301182] (NULL device *): vi_capture_control_message: NULL VI channel received
[  399.301451] t194-nvcsi 13e10000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 2 vc- 1
[  399.302003] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  399.302390] z_ov5640 10-001c: zuojisi : z_ov5640_stop_streaming
[  399.302404] max9296 10-0048: zuojisi: 9296 stop streaming - enter
[  399.302862] max9296 10-0048: zuojisi:9296:w addr(0x0050)->0x00
[  399.302873] max9296 10-0048: zuojisi: 9296 stop streaming - exit
[  399.302881] z_ov5640 10-001c: zuojisi : z_ov5640_write_table
[  399.302890] z_ov5640 10-001c: zuojisi:ov5640 (0x3008)->0x42
[  399.358729] z_ov5640 10-001c: z_ov5640_power_off: power off

root@pcai-desktop:/home/pcai# cat /sys/kernel/debug/tracing/trace
# tracer: nop
#
# entries-in-buffer/entries-written: 40/40   #P:4
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/0:0-5       [000] ....   455.795551: rtcpu_dbg_set_loglevel: tstamp:15326564148 old:0 new:2
        v4l2-ctl-5778    [002] ....   460.750853: tegra_channel_open: vi-output, z_ov5640 10-001c
        v4l2-ctl-5778    [002] ....   460.760901: tegra_channel_set_power: z_ov5640 10-001c : 0x1
        v4l2-ctl-5778    [002] ....   460.760917: camera_common_s_power: status : 0x1
        v4l2-ctl-5778    [002] ....   460.760945: tegra_channel_set_power: 13e10000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-5778    [002] ....   460.760948: csi_s_power: enable : 0x1
        v4l2-ctl-5778    [002] ....   460.761357: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13
        v4l2-ctl-5778    [003] ....   460.762349: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-5778    [001] ....   460.765721: tegra_channel_set_stream: 13e10000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-5778    [001] ....   460.765725: csi_s_stream: enable : 0x1
        v4l2-ctl-5778    [001] ....   460.766140: tegra_channel_set_stream: z_ov5640 10-001c : 0x1
     kworker/0:0-5       [000] ....   460.783517: rtcpu_vinotify_event: tstamp:15483974164 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:495472722592 data:0xcd9ce50010000000
     kworker/0:0-5       [000] ....   460.783521: rtcpu_vinotify_event: tstamp:15483974338 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:495472734368 data:0x0000000031000001
     kworker/0:0-5       [000] ....   460.783522: rtcpu_vinotify_event: tstamp:15483974517 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:495472780512 data:0xcd9ce20010000000
     kworker/0:0-5       [000] ....   460.783522: rtcpu_vinotify_event: tstamp:15483974668 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:495472792416 data:0x0000000031000002
 vi-output, z_ov-5780    [001] ....   463.333929: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13
     kworker/0:0-5       [000] ....   463.367586: rtcpu_vinotify_event: tstamp:15564052949 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:498044697472 data:0xcd9ce50010000000
     kworker/0:0-5       [000] ....   463.367590: rtcpu_vinotify_event: tstamp:15564053105 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:498044735968 data:0x0000000031000001
     kworker/0:0-5       [000] ....   463.367592: rtcpu_vinotify_event: tstamp:15564053280 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:498044752704 data:0xcd9ce20010000000
     kworker/0:0-5       [000] ....   463.367593: rtcpu_vinotify_event: tstamp:15564053431 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:498044807040 data:0x0000000031000002
 vi-output, z_ov-5780    [000] ....   465.893754: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13
     kworker/0:0-5       [000] ....   465.907488: rtcpu_vinotify_event: tstamp:15644132692 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:500604517856 data:0xcd9ce50010000000
     kworker/0:0-5       [000] ....   465.907492: rtcpu_vinotify_event: tstamp:15644132865 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:500604556352 data:0x0000000031000001
     kworker/0:0-5       [000] ....   465.907493: rtcpu_vinotify_event: tstamp:15644133041 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:500604573088 data:0xcd9ce20010000000
     kworker/0:0-5       [000] ....   465.907493: rtcpu_vinotify_event: tstamp:15644133190 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:500604627424 data:0x0000000031000002
 vi-output, z_ov-5780    [000] ....   468.453701: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13
     kworker/0:0-5       [000] ....   468.495527: rtcpu_vinotify_event: tstamp:15723895656 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:503164467680 data:0xcd9ce50010000000
     kworker/0:0-5       [000] ....   468.495530: rtcpu_vinotify_event: tstamp:15723895813 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:503164508736 data:0x0000000031000001
     kworker/0:0-5       [000] ....   468.495531: rtcpu_vinotify_event: tstamp:15723895990 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:503164525472 data:0xcd9ce20010000000
     kworker/0:0-5       [000] ....   468.495531: rtcpu_vinotify_event: tstamp:15723896140 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:503164579840 data:0x0000000031000002
        v4l2-ctl-5778    [001] ....   468.801078: tegra_channel_close: vi-output, z_ov5640 10-001c
 vi-output, z_ov-5780    [000] ....   471.013608: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13
        v4l2-ctl-5778    [001] ....   471.014106: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-5778    [001] ....   471.014110: tegra_channel_set_stream: z_ov5640 10-001c : 0x0
        v4l2-ctl-5778    [001] ....   471.065915: tegra_channel_set_stream: 13e10000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-5778    [001] ....   471.065921: csi_s_stream: enable : 0x0
        v4l2-ctl-5778    [001] ....   471.070727: tegra_channel_set_power: z_ov5640 10-001c : 0x0
        v4l2-ctl-5778    [001] ....   471.070739: camera_common_s_power: status : 0x0
        v4l2-ctl-5778    [001] ....   471.070765: tegra_channel_set_power: 13e10000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-5778    [001] ....   471.070768: csi_s_power: enable : 0x0

The trace log tell didn’t receive any validate data from the sensor.

This topic was automatically closed 14 days after the last reply. New replies are no longer allowed.