Why is the camera not displaying images? Please help me find out what the reason is, thank you!

wxsrite · August 15, 2024, 1:20pm

Hi All：
Why is the camera not displaying images? Please help me find out what the reason is, thank you!

soft version：

root@tegra:/sys/kernel/debug/dynamic_debug# cat /etc/nv_tegra_release
# R35 (release), REVISION: 4.1, GCID: 33958178, BOARD: t186ref, EABI: aarch64, DATE: Tue Aug  1 19:57:35 UTC 2023

trace.log

root@tegra:/sys/kernel/debug/dynamic_debug# cat /sys/kernel/debug/tracing/trace
# tracer: nop
#
# entries-in-buffer/entries-written: 30/30   #P:8
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
        v4l2-ctl-27292   [007] ....  2320.607348: tegra_channel_open: vi-output, ar0233 31-001b
        v4l2-ctl-27292   [007] ....  2320.607487: tegra_channel_close: vi-output, ar0233 31-001b
        v4l2-ctl-27294   [007] ....  2320.609712: tegra_channel_open: vi-output, ar0233 31-001b
        v4l2-ctl-27294   [007] ....  2320.609840: tegra_channel_close: vi-output, ar0233 31-001b
        v4l2-ctl-27296   [007] ....  2320.619185: tegra_channel_open: vi-output, ar0233 31-001b
        v4l2-ctl-27296   [007] ....  2320.626933: tegra_channel_set_power: ar0233 31-001b : 0x1
        v4l2-ctl-27296   [007] ....  2320.626948: camera_common_s_power: status : 0x1
        v4l2-ctl-27296   [007] ....  2320.627363: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-27296   [007] ....  2320.627365: csi_s_power: enable : 0x1
        v4l2-ctl-27296   [007] ....  2320.630475: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-27296   [007] ....  2320.636260: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-27296   [007] ....  2320.637520: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-27296   [007] ....  2320.637522: csi_s_stream: enable : 0x1
        v4l2-ctl-27296   [007] ....  2320.637835: tegra_channel_set_stream: ar0233 31-001b : 0x1
     kworker/4:0-7108    [004] ....  2320.649553: rtcpu_string: tstamp:73236009074 id:0x04010000 str:"VM0 activating."
     kworker/4:0-7108    [004] ....  2320.649556: rtcpu_vinotify_event: tstamp:73236455690 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:2343556078432 data:0x759d580010000000
     kworker/4:0-7108    [004] ....  2320.649556: rtcpu_vinotify_event: tstamp:73236455826 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:2343556084896 data:0x0000000031000001
     kworker/4:0-7108    [004] ....  2320.649557: rtcpu_vinotify_event: tstamp:73236455982 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:2343556125632 data:0x759d550010000000
     kworker/4:0-7108    [004] ....  2320.649557: rtcpu_vinotify_event: tstamp:73236456117 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:2343556132224 data:0x0000000031000002
        v4l2-ctl-27296   [007] ....  2322.541325: tegra_channel_close: vi-output, ar0233 31-001b
 vi-output, ar02-27299   [001] ....  2323.221244: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-27296   [007] ....  2323.230837: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-27296   [007] ....  2323.230840: tegra_channel_set_stream: ar0233 31-001b : 0x0
        v4l2-ctl-27296   [007] ....  2323.230857: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-27296   [007] ....  2323.230859: csi_s_stream: enable : 0x0
        v4l2-ctl-27296   [007] ....  2323.232522: tegra_channel_set_power: ar0233 31-001b : 0x0
        v4l2-ctl-27296   [007] ....  2323.232527: camera_common_s_power: status : 0x0
        v4l2-ctl-27296   [007] ....  2323.236303: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-27296   [007] ....  2323.236304: csi_s_power: enable : 0x0
     kworker/4:0-7108    [004] ....  2329.445524: rtcpu_string: tstamp:73510225922 id:0x04010000 str:"VM0 deactivating."
root@tegra:/sys/kernel/debug/dynamic_debug#

dmesg:

 2978.478054] debug:[223, ar0233_power_on].
[ 2978.478059] debug:[238, ar0233_power_on, 0x0].
[ 2978.495863] bwmgr API not supported
[ 2978.496425] debug:[486, ar0233_set_mode].
[ 2978.500202] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[ 2978.500204] debug:[139, ar0233_write_table].
[ 2978.524593] debug:[502, ar0233_set_mode].
[ 2978.524605] debug:[511, ar0233_start_streaming].
[ 2978.524606] debug:[139, ar0233_write_table].
[ 2978.552584] debug:[520, ar0233_start_streaming].
[ 2981.016594] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 2981.025738] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 2981.035543] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2981.043260] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=1, csi_port=1
[ 2981.053910] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2981.061617] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 1 vc- 0
[ 2981.072363] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 2981.081880] debug:[529, ar0233_stop_streaming].
[ 2981.081883] debug:[139, ar0233_write_table].
[ 2981.081885] debug:[537, ar0233_stop_streaming, 0x0].
[ 2981.083015] bwmgr API not supported
[ 2981.083696] debug:[248, ar0233_power_off].
[ 2981.087314] debug:[265, ar0233_power_off, 0x0].

dts:
dts.txt (470.8 KB)

JerryChang · August 16, 2024, 5:43am

hello wxsrite,

I’ve went though the code quickly,
may I confirm which is not working? for instance, which is the connection of your port-3.

		tca9546@70 {
			i2c@0 {
				ar0233_a@1b {
				ar0233_b@1c {

			i2c@1 {
				ar0233_a@1b {
				ar0233_b@1c {

			i2c@2 {
				ar0233_a@1b {
				ar0233_b@1c {

wxsrite · August 16, 2024, 10:51am

The interface i2c@1：
Sorry, I said it wrong, it was port index=1 that had a problem.

JerryChang · August 19, 2024, 2:11am

hello wxsrite,

it looks your i2c@1 cameras connected to port-index=<0x1>, which is CSI-B.
hence, please also updating tegra_sinterface = "serial_b"; within your ar0233_a@1b camera nodes.

please see-also [Figure 10-1. CSI 2-Lane Connection Options] in the Jetson Orin NX Series and Orin Nano Series Design Guide for polarity swaps.
besides, you may see-also reference driver for descriptions of lane_polarity settings.
re-cap as below…
$public_sources/kernel_src/hardware/nvidia/platform/t23x/p3768/kernel-dts/cvb/tegra234-camera-rbpcv2-imx219.dtsi

                                * Program a different readout order for this mode
                                *   
                                * lane_polarity
                                * Based on the camera connector pin.
                                * CSIx_D0 | CSIx_D1 | CSI(X+1)_D0 | CSI(X+1)CSIx_D1
                                *    LSB  |   BIT1  |     BIT2    |      MSB 
                                * if there is a polarity swap on any lane, the bit corrsponding
                                * to the lane should be set 
                                * e.g. polarity swap on CSIx_D0 only -> lane_polarity = "1"; 0001
                                * e.g. polarity swap on CSIx_D1 and CSI(X+1)_D0 -> lane_polarity = "6"; 0110

for instance,
since you’ve 2-lane camera connected to CSI-B, it’s only CSI1_D0_(P/N) need to configure polarity swap.
you should use lane_polarity = "4"; in your device tree settings.

wxsrite · August 19, 2024, 2:53am

hi Jerry,
The same version, another device does not have the problem. lan is equal to 2, lane_polarity = "6"; will there be a problem?

JerryChang · August 19, 2024, 3:07am

hello wxsrite,

let’s using correct settings since it’s device tree to represent hardware configuration.

wxsrite · August 19, 2024, 10:57am

hi JerryChang,

I use the new method, port-index=3, which does not require lane_polarity configuration, but there is still a problem.

dts:
dts.txt (485.1 KB)

root@tegra:/sys/kernel/debug/dynamic_debug# cat /sys/kernel/debug/tracing/trace
# tracer: nop
#
# entries-in-buffer/entries-written: 31/31   #P:8
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/4:0-35      [004] ....  4110.018156: rtcpu_string: tstamp:129095479278 id:0x04010000 str:"VM0 deactivating."
        v4l2-ctl-27552   [007] ....  4142.201821: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-27552   [007] ....  4142.201958: tegra_channel_close: vi-output, ar0233 33-001b
        v4l2-ctl-27554   [007] ....  4142.204143: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-27554   [007] ....  4142.204285: tegra_channel_close: vi-output, ar0233 33-001b
        v4l2-ctl-27556   [007] ....  4142.211830: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-27556   [007] ....  4142.215377: tegra_channel_set_power: ar0233 33-001b : 0x1
        v4l2-ctl-27556   [007] ....  4142.215387: camera_common_s_power: status : 0x1
        v4l2-ctl-27556   [007] ....  4142.226304: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-27556   [007] ....  4142.226307: csi_s_power: enable : 0x1
        v4l2-ctl-27556   [007] ....  4142.226831: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-27556   [007] ....  4142.232477: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-27556   [007] ....  4142.233739: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-27556   [007] ....  4142.233741: csi_s_stream: enable : 0x1
        v4l2-ctl-27556   [007] ....  4142.234036: tegra_channel_set_stream: ar0233 33-001b : 0x1
     kworker/4:0-35      [004] ....  4142.254117: rtcpu_string: tstamp:130102564719 id:0x04010000 str:"VM0 activating."
     kworker/4:0-35      [004] ....  4142.254121: rtcpu_vinotify_event: tstamp:130103005411 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:4163285684256 data:0x759e300010000000
     kworker/4:0-35      [004] ....  4142.254121: rtcpu_vinotify_event: tstamp:130103005564 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:4163285691104 data:0x0000000031000001
     kworker/4:0-35      [004] ....  4142.254122: rtcpu_vinotify_event: tstamp:130103005716 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:4163285731488 data:0x759e2d0010000000
     kworker/4:0-35      [004] ....  4142.254122: rtcpu_vinotify_event: tstamp:130103005848 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:4163285738016 data:0x0000000031000002
        v4l2-ctl-27556   [007] ....  4144.044709: tegra_channel_close: vi-output, ar0233 33-001b
 vi-output, ar02-27559   [002] ....  4144.798169: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-27556   [007] ....  4144.807776: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-27556   [007] ....  4144.807778: tegra_channel_set_stream: ar0233 33-001b : 0x0
        v4l2-ctl-27556   [007] ....  4144.807799: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-27556   [007] ....  4144.807801: csi_s_stream: enable : 0x0
        v4l2-ctl-27556   [007] ....  4144.809400: tegra_channel_set_power: ar0233 33-001b : 0x0
        v4l2-ctl-27556   [007] ....  4144.809405: camera_common_s_power: status : 0x0
        v4l2-ctl-27556   [007] ....  4144.813433: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-27556   [007] ....  4144.813435: csi_s_power: enable : 0x0
     kworker/4:0-35      [004] ....  4149.882097: rtcpu_string: tstamp:130340983178 id:0x04010000 str:"VM0 deactivating."

[ 3112.024020] debug:[560, ar0233_open].
[ 3112.024026] debug:[560, ar0233_open].
[ 3112.024032] debug:[560, ar0233_open].
[ 3112.024037] debug:[560, ar0233_open].
[ 3112.024042] debug:[560, ar0233_open].
[ 3112.024049] debug:[560, ar0233_open].
[ 3314.739414] debug:[223, ar0233_power_on].
[ 3314.739419] debug:[238, ar0233_power_on, 0x0].
[ 3314.747037] bwmgr API not supported
[ 3314.747607] debug:[486, ar0233_set_mode].
[ 3314.751209] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[ 3314.751211] debug:[139, ar0233_write_table].
[ 3314.775243] debug:[502, ar0233_set_mode].
[ 3314.775251] debug:[511, ar0233_start_streaming].
[ 3314.775252] debug:[139, ar0233_write_table].
[ 3314.803243] debug:[520, ar0233_start_streaming].
[ 3317.255251] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 3317.264397] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 3317.274422] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 3317.282134] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[ 3317.292785] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 3317.300498] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 1
[ 3317.311211] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 3317.320730] debug:[529, ar0233_stop_streaming].
[ 3317.320733] debug:[139, ar0233_write_table].
[ 3317.320735] debug:[537, ar0233_stop_streaming, 0x0].
[ 3317.321835] bwmgr API not supported
[ 3317.322579] debug:[248, ar0233_power_off].
[ 3317.326184] debug:[265, ar0233_power_off, 0x0].
[ 4142.215394] debug:[223, ar0233_power_on].
[ 4142.215399] debug:[238, ar0233_power_on, 0x0].
[ 4142.233466] bwmgr API not supported
[ 4142.234047] debug:[486, ar0233_set_mode].
[ 4142.237822] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[ 4142.237825] debug:[139, ar0233_write_table].
[ 4142.262118] debug:[502, ar0233_set_mode].
[ 4142.262130] debug:[511, ar0233_start_streaming].
[ 4142.262131] debug:[139, ar0233_write_table].
[ 4142.290109] debug:[520, ar0233_start_streaming].
[ 4144.742115] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 4144.751256] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 4144.761422] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 4144.769127] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[ 4144.779794] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 4144.787501] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[ 4144.798262] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 4144.807792] debug:[529, ar0233_stop_streaming].
[ 4144.807795] debug:[139, ar0233_write_table].
[ 4144.807797] debug:[537, ar0233_stop_streaming, 0x0].
[ 4144.808926] bwmgr API not supported
[ 4144.809582] debug:[248, ar0233_power_off].
[ 4144.813431] debug:[265, ar0233_power_off, 0

JerryChang · August 20, 2024, 1:59am

hello wxsrite,

it’s both of your ar0233_a@1b and ar0233_b@1c connected to port-index=3 as 2-lane configuration, and using virtual channel to separate them.
however, you’ve incorrect tegra_sinterface settings for ar0233_a@1b.
for instance, it should be tegra_sinterface = "serial_d"; when utilize port-index=3.

wxsrite · August 21, 2024, 1:18am

hi JerryChang.
I did the following test:

Set the camera parameters of port-index=0 and port-index=1 to:
lane_polarity = “4”;
num_lanes = “2”;
Test conclusion:
The camera with port-index=0 and port-index=1 cannot be lit, only lane_polarity = “6”; can be used.
Change the tegra_sinterface of port-index=3 to serial_d, the problem is solved. At the same time, I have checked and modified it. Please help me check whether the tegra_sinterface settings corresponding to my other port-index are correct. Are there any other errors in this dts?
tegra234-camera-2lan-15G-8rgb.txt (97.0 KB)
Thank you.

JerryChang · August 21, 2024, 6:22am

hello wxsrite,

(1) please double check reference driver for descriptions of lane_polarity settings.
anyways, it’s lane_polarity = "6"; to configure polarity swap on CSIx_D1 and CSI(X+1)_D0.

(2) I’ve only checking port-index=3, and it seems you should also revise gmsl-link
especially, the dst-csi-port, which is the destination CSI port on the Jetson side connected at deserializer.

wxsrite · August 22, 2024, 1:23am

Hi JerryChang,
After multiple verifications, there is still a problem. The log is as follows. Please help me take a look. Thank you!

[ 2196.818388] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 2196.818402] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 2196.827526] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 2196.846282] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 2196.846345] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2196.856166] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2196.863553] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[ 2196.863556] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2196.863559] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[ 2196.871270] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[ 2196.882260] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 2196.889670] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2196.928046] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 1
[ 2196.938997] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel

[ 2274.959115] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: flags 2, err_data 0
[ 2274.970742] [RCE] ERROR: camera-ip/vi5/vi5.c:745 [vi5_handle_eof] "General error queue is out of sync with frame queue. ts=2297463428768 sof_ts=2297466279232 gerror_code=2 gerror_data=400062 notify_bits=220000"
[ 2277.458313] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 2277.458315] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 2277.458322] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 2277.467481] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 2277.495830] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2277.496174] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2277.503533] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[ 2277.511282] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[ 2277.521893] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2277.532524] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2277.540224] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[ 2277.558575] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 2277.558591] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 1
[ 2277.579105] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 2277.718310] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 2277.727451] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 2277.738349] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2277.746053] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=2, csi_port=2
[ 2277.756710] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 2277.764420] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 2 vc- 0
[ 2277.775339] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel

dts:
dts.txt (485.1 KB)
dmesg:
dmesg.txt (138.4 KB)
trace:
trace.txt (1.0 MB)

wxsrite · August 22, 2024, 1:56am

Hi, JerryChang,

Another phenomenon

robot@tegra:~$ more cam_7.txt
[  745.875237] debug:[223, ar0233_power_on].
[  745.875242] debug:[238, ar0233_power_on, 0x0].
[  745.892901] bwmgr API not supported
[  745.893467] debug:[486, ar0233_set_mode].
[  745.897071] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  745.897073] debug:[139, ar0233_write_table].
[  745.921407] debug:[502, ar0233_set_mode].
[  745.921418] debug:[511, ar0233_start_streaming].
[  745.921419] debug:[139, ar0233_write_table].
[  745.949401] debug:[520, ar0233_start_streaming].
[  748.401411] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  748.410568] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  748.420383] (NULL device *): vi_capture_control_message: NULL VI channel received
[  748.428085] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  748.438735] (NULL device *): vi_capture_control_message: NULL VI channel received
[  748.446446] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[  748.457163] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  748.466701] debug:[529, ar0233_stop_streaming].
[  748.466705] debug:[139, ar0233_write_table].
[  748.466706] debug:[537, ar0233_stop_streaming, 0x0].
[  748.467807] bwmgr API not supported
[  748.468539] debug:[248, ar0233_power_off].
[  748.472277] debug:[265, ar0233_power_off, 0x0].
[  751.359187] debug:[223, ar0233_power_on].
[  751.359193] debug:[238, ar0233_power_on, 0x0].
[  751.363322] bwmgr API not supported
[  751.363657] debug:[486, ar0233_set_mode].
[  751.367393] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  751.367395] debug:[139, ar0233_write_table].
[  751.393412] debug:[502, ar0233_set_mode].
[  751.393424] debug:[511, ar0233_start_streaming].
[  751.393425] debug:[139, ar0233_write_table].
[  751.421396] debug:[520, ar0233_start_streaming].
[  753.873403] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  753.882547] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  753.892375] (NULL device *): vi_capture_control_message: NULL VI channel received
[  753.900079] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  753.910731] (NULL device *): vi_capture_control_message: NULL VI channel received
[  753.918444] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[  753.929169] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  753.938682] debug:[529, ar0233_stop_streaming].
[  753.938686] debug:[139, ar0233_write_table].
[  753.938688] debug:[537, ar0233_stop_streaming, 0x0].
[  753.940350] bwmgr API not supported
[  753.941545] debug:[248, ar0233_power_off].
[  753.945139] debug:[265, ar0233_power_off, 0x0].
[  756.779489] debug:[223, ar0233_power_on].
[  756.779494] debug:[238, ar0233_power_on, 0x0].
[  756.791462] bwmgr API not supported
[  756.792110] debug:[486, ar0233_set_mode].
[  756.795893] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  756.795895] debug:[139, ar0233_write_table].
[  756.821408] debug:[502, ar0233_set_mode].
[  756.821420] debug:[511, ar0233_start_streaming].
[  756.821421] debug:[139, ar0233_write_table].
[  756.849401] debug:[520, ar0233_start_streaming].
[  757.010764] debug:[529, ar0233_stop_streaming].
[  757.010770] debug:[139, ar0233_write_table].
[  757.010771] debug:[537, ar0233_stop_streaming, 0x0].
[  757.011930] bwmgr API not supported
[  757.013728] debug:[248, ar0233_power_off].
[  757.017325] debug:[265, ar0233_power_off, 0x0].
[  757.163564] debug:[223, ar0233_power_on].
[  757.163570] debug:[238, ar0233_power_on, 0x0].
[  757.165261] bwmgr API not supported
[  757.166035] debug:[486, ar0233_set_mode].
[  757.169647] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  757.169648] debug:[139, ar0233_write_table].
[  757.197404] debug:[502, ar0233_set_mode].
[  757.197417] debug:[511, ar0233_start_streaming].
[  757.197419] debug:[139, ar0233_write_table].
[  757.225422] debug:[520, ar0233_start_streaming].
[  757.370763] debug:[529, ar0233_stop_streaming].
[  757.370767] debug:[139, ar0233_write_table].
[  757.370769] debug:[537, ar0233_stop_streaming, 0x0].
[  757.371934] bwmgr API not supported
[  757.373565] debug:[248, ar0233_power_off].
[  757.377166] debug:[265, ar0233_power_off, 0x0].
[  764.879427] debug:[223, ar0233_power_on].
[  764.879432] debug:[238, ar0233_power_on, 0x0].
[  764.896560] bwmgr API not supported
[  764.897116] debug:[486, ar0233_set_mode].
[  764.900902] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  764.900904] debug:[139, ar0233_write_table].
[  764.925388] debug:[502, ar0233_set_mode].
[  764.925400] debug:[511, ar0233_start_streaming].
[  764.925402] debug:[139, ar0233_write_table].
[  764.953391] debug:[520, ar0233_start_streaming].
[  765.130013] debug:[529, ar0233_stop_streaming].
[  765.130018] debug:[139, ar0233_write_table].
[  765.130020] debug:[537, ar0233_stop_streaming, 0x0].
[  765.131136] bwmgr API not supported
[  765.132545] debug:[248, ar0233_power_off].
[  765.136399] debug:[265, ar0233_power_off, 0x0].
[  765.267311] debug:[223, ar0233_power_on].
[  765.267316] debug:[238, ar0233_power_on, 0x0].
[  765.282502] bwmgr API not supported
[  765.283051] debug:[486, ar0233_set_mode].
[  765.286901] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  765.286902] debug:[139, ar0233_write_table].
[  765.313389] debug:[502, ar0233_set_mode].
[  765.313402] debug:[511, ar0233_start_streaming].
[  765.313403] debug:[139, ar0233_write_table].
[  765.346131] debug:[520, ar0233_start_streaming].
[  765.489962] debug:[529, ar0233_stop_streaming].
[  765.489967] debug:[139, ar0233_write_table].
[  765.489968] debug:[537, ar0233_stop_streaming, 0x0].
[  765.491144] bwmgr API not supported
[  765.492545] debug:[248, ar0233_power_off].
[  765.496361] debug:[265, ar0233_power_off, 0x0].
[  773.731502] debug:[223, ar0233_power_on].
[  773.731508] debug:[238, ar0233_power_on, 0x0].
[  773.749108] bwmgr API not supported
[  773.749702] debug:[486, ar0233_set_mode].
[  773.753306] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  773.753308] debug:[139, ar0233_write_table].
[  773.777375] debug:[502, ar0233_set_mode].
[  773.777387] debug:[511, ar0233_start_streaming].
[  773.777389] debug:[139, ar0233_write_table].
[  773.805369] debug:[520, ar0233_start_streaming].
[  776.273378] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  776.282538] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  776.292375] (NULL device *): vi_capture_control_message: NULL VI channel received
[  776.300090] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  776.310745] (NULL device *): vi_capture_control_message: NULL VI channel received
[  776.318446] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[  776.329189] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  776.338700] debug:[529, ar0233_stop_streaming].
[  776.338704] debug:[139, ar0233_write_table].
[  776.338705] debug:[537, ar0233_stop_streaming, 0x0].
[  776.339829] bwmgr API not supported
[  776.340535] debug:[248, ar0233_power_off].
[  776.344140] debug:[265, ar0233_power_off, 0x0].
[  779.231418] debug:[223, ar0233_power_on].
[  779.231423] debug:[238, ar0233_power_on, 0x0].
[  779.243077] bwmgr API not supported
[  779.243425] debug:[486, ar0233_set_mode].
[  779.247162] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  779.247165] debug:[139, ar0233_write_table].
[  779.273416] debug:[502, ar0233_set_mode].
[  779.273428] debug:[511, ar0233_start_streaming].
[  779.273429] debug:[139, ar0233_write_table].
[  779.301376] debug:[520, ar0233_start_streaming].
[  781.745365] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  781.754500] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  781.764378] (NULL device *): vi_capture_control_message: NULL VI channel received
[  781.772094] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  781.782745] (NULL device *): vi_capture_control_message: NULL VI channel received
[  781.790454] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[  781.801170] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  781.810672] debug:[529, ar0233_stop_streaming].
[  781.810676] debug:[139, ar0233_write_table].
[  781.810677] debug:[537, ar0233_stop_streaming, 0x0].
[  781.811784] bwmgr API not supported
[  781.812527] debug:[248, ar0233_power_off].
[  781.816128] debug:[265, ar0233_power_off, 0x0].
[  844.746526] debug:[223, ar0233_power_on].
[  844.746531] debug:[238, ar0233_power_on, 0x0].
[  844.764799] bwmgr API not supported
[  844.765371] debug:[486, ar0233_set_mode].
[  844.768972] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  844.768973] debug:[139, ar0233_write_table].
[  844.793303] debug:[502, ar0233_set_mode].
[  844.793315] debug:[511, ar0233_start_streaming].
[  844.793316] debug:[139, ar0233_write_table].
[  844.821302] debug:[520, ar0233_start_streaming].
[  847.281315] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  847.290454] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  847.300383] (NULL device *): vi_capture_control_message: NULL VI channel received
[  847.308086] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  847.318739] (NULL device *): vi_capture_control_message: NULL VI channel received
[  847.326451] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[  847.337201] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  847.346720] debug:[529, ar0233_stop_streaming].
[  847.346723] debug:[139, ar0233_write_table].
[  847.346724] debug:[537, ar0233_stop_streaming, 0x0].
[  847.347842] bwmgr API not supported
[  847.348533] debug:[248, ar0233_power_off].
[  847.352133] debug:[265, ar0233_power_off, 0x0].

robot@tegra:~$ more trace_cam7.txt
# tracer: nop
#
# entries-in-buffer/entries-written: 95/95   #P:8
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/4:2-247     [004] ....   828.749324: rtcpu_string: tstamp:27364055381 id:0x04010000 str:"VM0 deactivating."
        v4l2-ctl-12450   [007] ....   844.733816: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-12450   [007] ....   844.733954: tegra_channel_close: vi-output, ar0233 33-001b
        v4l2-ctl-12452   [007] ....   844.736047: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-12452   [007] ....   844.736179: tegra_channel_close: vi-output, ar0233 33-001b
        v4l2-ctl-12454   [007] ....   844.742945: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-12454   [007] ....   844.746510: tegra_channel_set_power: ar0233 33-001b : 0x1
        v4l2-ctl-12454   [007] ....   844.746519: camera_common_s_power: status : 0x1
        v4l2-ctl-12454   [007] ....   844.757483: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-12454   [007] ....   844.757486: csi_s_power: enable : 0x1
        v4l2-ctl-12454   [007] ....   844.757997: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-12454   [007] ....   844.763808: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-12454   [007] ....   844.765062: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-12454   [007] ....   844.765064: csi_s_stream: enable : 0x1
        v4l2-ctl-12454   [007] ....   844.765359: tegra_channel_set_stream: ar0233 33-001b : 0x1
     kworker/4:2-247     [004] ....   844.769305: rtcpu_string: tstamp:27865515328 id:0x04010000 str:"VM0 activating."
     kworker/4:2-247     [004] ....   844.825301: rtcpu_vinotify_event: tstamp:27865962511 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:89170029
6640 data:0x759e300010000000
     kworker/4:2-247     [004] ....   844.825301: rtcpu_vinotify_event: tstamp:27865962651 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:89170030
3072 data:0x0000000031000001
     kworker/4:2-247     [004] ....   844.825301: rtcpu_vinotify_event: tstamp:27865962806 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:89170033
8880 data:0x759e2d0010000000
     kworker/4:2-247     [004] ....   844.825302: rtcpu_vinotify_event: tstamp:27865962939 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:89170034
5408 data:0x0000000031000002
     kworker/4:2-247     [004] ....   844.825303: rtcpu_nvcsi_intr: tstamp:27866806685 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   844.881296: rtcpu_nvcsi_intr: tstamp:27868056658 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   844.937292: rtcpu_nvcsi_intr: tstamp:27869306632 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   844.937293: rtcpu_nvcsi_intr: tstamp:27870556609 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   844.993293: rtcpu_nvcsi_intr: tstamp:27871806583 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.049297: rtcpu_nvcsi_intr: tstamp:27873056557 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.049297: rtcpu_nvcsi_intr: tstamp:27874306531 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.105295: rtcpu_nvcsi_intr: tstamp:27875556504 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.161302: rtcpu_nvcsi_intr: tstamp:27876806478 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.217296: rtcpu_nvcsi_intr: tstamp:27878056452 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.217297: rtcpu_nvcsi_intr: tstamp:27879306426 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.273294: rtcpu_nvcsi_intr: tstamp:27880556400 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.329294: rtcpu_nvcsi_intr: tstamp:27881806374 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.329294: rtcpu_nvcsi_intr: tstamp:27883056348 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.385293: rtcpu_nvcsi_intr: tstamp:27884306321 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.441292: rtcpu_nvcsi_intr: tstamp:27885554971 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.497293: rtcpu_nvcsi_intr: tstamp:27886804945 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.497294: rtcpu_nvcsi_intr: tstamp:27888054922 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.553292: rtcpu_nvcsi_intr: tstamp:27889304552 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.609292: rtcpu_nvcsi_intr: tstamp:27890554525 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.609292: rtcpu_nvcsi_intr: tstamp:27891804499 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.665293: rtcpu_nvcsi_intr: tstamp:27893054473 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.725291: rtcpu_nvcsi_intr: tstamp:27894304447 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.725291: rtcpu_nvcsi_intr: tstamp:27895554421 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.781295: rtcpu_nvcsi_intr: tstamp:27896804395 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.837293: rtcpu_nvcsi_intr: tstamp:27898054368 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.893295: rtcpu_nvcsi_intr: tstamp:27899304342 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.893295: rtcpu_nvcsi_intr: tstamp:27900554319 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   845.949291: rtcpu_nvcsi_intr: tstamp:27901804320 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.005292: rtcpu_nvcsi_intr: tstamp:27903054267 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.005292: rtcpu_nvcsi_intr: tstamp:27904304241 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.061291: rtcpu_nvcsi_intr: tstamp:27905554214 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.117295: rtcpu_nvcsi_intr: tstamp:27906804189 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.173295: rtcpu_nvcsi_intr: tstamp:27908052847 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.173295: rtcpu_nvcsi_intr: tstamp:27909302821 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.229292: rtcpu_nvcsi_intr: tstamp:27910552798 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.285293: rtcpu_nvcsi_intr: tstamp:27911802772 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.285294: rtcpu_nvcsi_intr: tstamp:27913052392 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.345295: rtcpu_nvcsi_intr: tstamp:27914302427 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.401295: rtcpu_nvcsi_intr: tstamp:27915552340 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.401296: rtcpu_nvcsi_intr: tstamp:27916802314 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.457291: rtcpu_nvcsi_intr: tstamp:27918052287 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.513291: rtcpu_nvcsi_intr: tstamp:27919302261 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.569290: rtcpu_nvcsi_intr: tstamp:27920552235 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.569290: rtcpu_nvcsi_intr: tstamp:27921802209 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.625289: rtcpu_nvcsi_intr: tstamp:27923052183 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.681296: rtcpu_nvcsi_intr: tstamp:27924302157 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.681298: rtcpu_nvcsi_intr: tstamp:27925552130 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.737291: rtcpu_nvcsi_intr: tstamp:27926802107 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.797300: rtcpu_nvcsi_intr: tstamp:27928052080 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
        v4l2-ctl-12454   [007] ....   846.823963: tegra_channel_close: vi-output, ar0233 33-001b
     kworker/4:2-247     [004] ....   846.853294: rtcpu_nvcsi_intr: tstamp:27929302055 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.853295: rtcpu_nvcsi_intr: tstamp:27930550723 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.909301: rtcpu_nvcsi_intr: tstamp:27931800697 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.965299: rtcpu_nvcsi_intr: tstamp:27933050674 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   846.965300: rtcpu_nvcsi_intr: tstamp:27934300649 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   847.021317: rtcpu_nvcsi_intr: tstamp:27935550255 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   847.081292: rtcpu_nvcsi_intr: tstamp:27936800231 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   847.081293: rtcpu_nvcsi_intr: tstamp:27938050207 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   847.137302: rtcpu_nvcsi_intr: tstamp:27939300180 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   847.193314: rtcpu_nvcsi_intr: tstamp:27940550154 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   847.249296: rtcpu_nvcsi_intr: tstamp:27941800128 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   847.249297: rtcpu_nvcsi_intr: tstamp:27943050102 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   847.309298: rtcpu_nvcsi_intr: tstamp:27944300075 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
 vi-output, ar02-12457   [003] ....   847.337103: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-12454   [007] ....   847.346704: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-12454   [007] ....   847.346707: tegra_channel_set_stream: ar0233 33-001b : 0x0
        v4l2-ctl-12454   [007] ....   847.346726: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-12454   [007] ....   847.346728: csi_s_stream: enable : 0x0
        v4l2-ctl-12454   [007] ....   847.348354: tegra_channel_set_power: ar0233 33-001b : 0x0
        v4l2-ctl-12454   [007] ....   847.348359: camera_common_s_power: status : 0x0
        v4l2-ctl-12454   [007] ....   847.352135: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-12454   [007] ....   847.352137: csi_s_power: enable : 0x0
     kworker/4:2-247     [004] ....   847.365292: rtcpu_nvcsi_intr: tstamp:27945550049 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000080
     kworker/4:2-247     [004] ....   852.949283: rtcpu_string: tstamp:28120314713 id:0x04010000 str:"VM0 deactivating."
robot@tegra:~$

dts:
dts.txt (485.1 KB)

JerryChang · August 22, 2024, 7:22am

it’s known failure,
please refer to Topic 268833 to apply camera firmware, to update deskew algorithm, and also stability fixes.

wxsrite · August 23, 2024, 9:53am

Hi JerryChang,
Why report this error?

[  182.573589] debug:[529, ar0233_stop_streaming].
[  182.573592] debug:[139, ar0233_write_table].
[  182.573593] debug:[537, ar0233_stop_streaming, 0x0].
[  182.574398] bwmgr API not supported
[  182.575570] debug:[248, ar0233_power_off].
[  182.579525] debug:[265, ar0233_power_off, 0x0].
[  182.598210] [RCE] tegra_nvcsi_stream_close(vm0, stream=2, csi=2)
[  182.613580] debug:[529, ar0233_stop_streaming].
[  182.613582] debug:[139, ar0233_write_table].
[  182.613583] debug:[537, ar0233_stop_streaming, 0x0].
[  182.614423] bwmgr API not supported
[  182.615572] debug:[248, ar0233_power_off].
[  182.619187] debug:[265, ar0233_power_off, 0x0].
[  182.658209] [RCE] tegra_nvcsi_stream_close(vm0, stream=2, csi=2)
[  182.658211] [RCE] nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_A_SW_RESET_0 = 00000003
[  182.658213] [RCE] nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_B_SW_RESET_0 = 00000000
[  182.658214] [RCE] nvcsi_reset_lane_merger: NVCSI_PHY_1_LM_SW_RESET_0 = 00000001
[  185.054230] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  185.063364] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  185.073597] (NULL device *): vi_capture_control_message: NULL VI channel received
[  185.081307] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  185.091975] (NULL device *): vi_capture_control_message: NULL VI channel received
[  185.099695] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 1
[  185.110494] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  185.120017] debug:[529, ar0233_stop_streaming].
[  185.120019] debug:[139, ar0233_write_table].
[  185.120020] debug:[537, ar0233_stop_streaming, 0x0].
[  185.121455] bwmgr API not supported
[  185.122568] debug:[248, ar0233_power_off].
[  185.126158] debug:[265, ar0233_power_off, 0x0].
[  185.126240] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  185.135375] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  185.145560] (NULL device *): vi_capture_control_message: NULL VI channel received
[  185.153274] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  185.163929] (NULL device *): vi_capture_control_message: NULL VI channel received
[  185.171639] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[  185.182318] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  185.191828] debug:[529, ar0233_stop_streaming].
[  185.191830] debug:[139, ar0233_write_table].
[  185.191831] debug:[537, ar0233_stop_streaming, 0x0].
[  185.192611] bwmgr API not supported
[  185.193557] debug:[248, ar0233_power_off].
[  185.197150] debug:[265, ar0233_power_off, 0x0].
[  185.197190] debug:[529, ar0233_stop_streaming].
[  185.197191] debug:[139, ar0233_write_table].
[  185.197192] debug:[537, ar0233_stop_streaming, 0x0].
[  185.197935] bwmgr API not supported
[  185.198203] [RCE] tegra_nvcsi_stream_close(vm0, stream=0, csi=0)
[  185.198573] debug:[248, ar0233_power_off].
[  185.201788] debug:[265, ar0233_power_off, 0x0].
[  185.201838] debug:[529, ar0233_stop_streaming].
[  185.201839] debug:[139, ar0233_write_table].
[  185.201840] debug:[537, ar0233_stop_streaming, 0x0].
[  185.202948] bwmgr API not supported
[  185.203731] debug:[248, ar0233_power_off].
[  185.207335] debug:[265, ar0233_power_off, 0x0].
[  185.258210] [RCE] tegra_nvcsi_stream_close(vm0, stream=0, csi=0)
[  185.258214] [RCE] nvcsi_reset_data_lanes: NVCSI_PHY_0_NVCSI_CIL_A_SW_RESET_0 = 00000003
[  185.258216] [RCE] nvcsi_reset_data_lanes: NVCSI_PHY_0_NVCSI_CIL_B_SW_RESET_0 = 00000000
[  185.258217] [RCE] nvcsi_reset_lane_merger: NVCSI_PHY_0_LM_SW_RESET_0 = 00000001
[  196.002190] [RCE] IDAC CIL A params:
[  196.002195] [RCE]    ab_trio0 0
                ab_trio1 0
                bc_trio0 0
                bc_trio1 0
[  196.002196] [RCE]    ca_trio0 0
                ca_trio1 0
[  196.002198] [RCE] IDAC CIL B params:
[  196.002199] [RCE]    ab_trio0 0
                ab_trio1 0
                bc_trio0 0
                bc_trio1 0
[  196.002200] [RCE]    ca_trio0 0
                ca_trio1 0
[  196.002202] [RCE] IDACADJ calibration timeout, use default static config
[  196.002204] [RCE] IDAC CIL A params:
[  196.002205] [RCE]    ab_trio0 0
                ab_trio1 0
                bc_trio0 0
                bc_trio1 0
[  196.002206] [RCE]    ca_trio0 0
                ca_trio1 0
[  196.002208] [RCE] IDAC CIL B params:
[  196.002209] [RCE]    ab_trio0 0
                ab_trio1 0
                bc_trio0 0
                bc_trio1 0
[  196.002210] [RCE]    ca_trio0 0
                ca_trio1 0
[  196.002212] [RCE] IDACADJ calibration timeout, use default static config
[  196.002214] [RCE] calibration status1 233ace52 status2 27384e11
[  196.002215] [RCE] calibration status1 23384e91 status2 2f4bce75
[  196.002216] [RCE] NVCSILP clock rate = 408000000 Hz.
[  208.511446] debug:[223, ar0233_power_on].
[  208.511450] debug:[238, ar0233_power_on, 0x0].
[  208.528645] bwmgr API not supported
[  208.529429] debug:[486, ar0233_set_mode].
[  208.533366] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  208.533369] debug:[139, ar0233_write_table].
[  208.538172] [RCE] VM0 deactivating.VM0 activating.IDAC CIL A params:
[  208.538174] [RCE]    ab_trio0 0
                ab_trio1 0
                bc_trio0 0
                bc_trio1 0
[  208.538176] [RCE]    ca_trio0 0
                ca_trio1 0
[  208.538177] [RCE] IDAC CIL B params:
[  208.538178] [RCE]    ab_trio0 0
                ab_trio1 0
                bc_trio0 0
                bc_trio1 0
[  208.538179] [RCE]    ca_trio0 0
                ca_trio1 0
[  208.538181] [RCE] IDACADJ calibration timeout, use default static config
[  208.538182] [RCE] IDAC CIL A params:
[  208.538183] [RCE]    ab_trio0 0
                ab_trio1 0
                bc_trio0 0
                bc_trio1 0
[  208.538184] [RCE]    ca_trio0 0
                ca_trio1 0
[  208.538186] [RCE] IDAC CIL B params:
[  208.538187] [RCE]    ab_trio0 0
                ab_trio1 0
                bc_trio0 0
                bc_trio1 0
[  208.538188] [RCE]    ca_trio0 0
                ca_trio1 0
[  208.538190] [RCE] IDACADJ calibration timeout, use default static config
[  208.538191] [RCE] calibration status1 221a8421 status2 2108421
[  208.538192] [RCE] calibration status1 23484e92 status2 2f4bce76
[  208.538193] [RCE] NVCSILP clock rate = 408000000 Hz.
[  208.538195] [RCE] tegra_nvcsi_stream_set_config(vm0, stream=3, csi=3)
[  208.538196] [RCE] MIPI clock = 750000 kHz, tHS-SETTLE = 0, tCLK-SETTLE = 0
[  208.538198] [RCE] ===== NVCSI Stream Configuration =====
[  208.538199] [RCE] stream_id: PP 3, csi_port: PORT D
[  208.538200] [RCE] Brick: PHY 1, Mode: D-PHY
[  208.538201] [RCE] Partition: CIL B, LP bypass: Enabled, Lanes: 2
[  208.538203] [RCE] Clock information:
[  208.538204] [RCE] MIPI clock rate: 750.00 MHz
[  208.538205] [RCE] T_HS settle: 0, T_CLK settle: 0
[  208.538206] [RCE] ======================================
[  208.538207] [RCE] tegra_nvcsi_stream_open(vm0, stream=3, csi=3)
[  208.538209] [RCE] nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_A_SW_RESET_0 = 00000000
[  208.538210] [RCE] nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_B_SW_RESET_0 = 00000003
[  208.538212] [RCE] nvcsi_reset_lane_merger: NVCSI_PHY_1_LM_SW_RESET_0 = 00000002
[  208.538214] [RCE] nvcsi_reset_lane_merger: NVCSI_PHY_1_LM_SW_RESET_0 = 00000000
[  208.538215] [RCE] nvcsi_calc_ths_settle ths_settle 53
[  208.538216] [RCE] nvcsi_calc_ths_settle ths_settle 53
[  208.538217] [RCE] nvcsi_calc_tclk_settle tclk_settle 75
[  208.538219] [RCE] nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_A_SW_RESET_0 = 00000000
[  208.538220] [RCE] nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_B_SW_RESET_0 = 00000000
[  208.558170] debug:[502, ar0233_set_mode].
[  208.558180] debug:[511, ar0233_start_streaming].
[  208.558182] debug:[139, ar0233_write_table].
[  208.586163] debug:[520, ar0233_start_streaming].
[  211.038166] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  211.047309] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  211.057590] (NULL device *): vi_capture_control_message: NULL VI channel received
[  211.065300] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  211.075955] (NULL device *): vi_capture_control_message: NULL VI channel received
[  211.083666] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[  211.094403] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  211.103925] debug:[529, ar0233_stop_streaming].
[  211.103929] debug:[139, ar0233_write_table].
[  211.103931] debug:[537, ar0233_stop_streaming, 0x0].
[  211.105047] bwmgr API not supported
[  211.105747] debug:[248, ar0233_power_off].
[  211.109350] debug:[265, ar0233_power_off, 0x0].
[  213.995476] debug:[223, ar0233_power_on].
[  213.995481] debug:[238, ar0233_power_on, 0x0].
[  214.000110] bwmgr API not supported
[  214.000479] debug:[486, ar0233_set_mode].
[  214.004295] debug:[494, ar0233_set_mode, mode_prop_idx:3].
[  214.004297] debug:[139, ar0233_write_table].
[  214.030180] debug:[502, ar0233_set_mode].
[  214.030192] debug:[511, ar0233_start_streaming].
[  214.030194] debug:[139, ar0233_write_table].
[  214.054172] [RCE] ISR PHY 1 CIL_B 0x88
[  214.054180] [RCE] tegra_nvcsi_stream_set_config(vm0, stream=3, csi=3)
[  214.054181] [RCE] tegra_nvcsi_stream_open(vm0, stream=3, csi=3)
[  214.058323] debug:[520, ar0233_start_streaming].
[  216.510159] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  216.519319] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  216.529592] (NULL device *): vi_capture_control_message: NULL VI channel received
[  216.537290] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=3, csi_port=3
[  216.547951] (NULL device *): vi_capture_control_message: NULL VI channel received
[  216.555658] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 3 vc- 0
[  216.566391] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  216.575918] debug:[529, ar0233_stop_streaming].
[  216.575921] debug:[139, ar0233_write_table].
[  216.575923] debug:[537, ar0233_stop_streaming, 0x0].
[  216.577033] bwmgr API not supported
[  216.577754] debug:[248, ar0233_power_off].
[  216.581362] debug:[265, ar0233_power_off, 0x0].

root@tegra:/sys/kernel/debug/dynamic_debug# cat /sys/kernel/debug/tracing/trace
# tracer: nop
#
# entries-in-buffer/entries-written: 111/111   #P:8
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/2:1-110     [002] ....   202.186172: rtcpu_string: tstamp:6967818756 id:0x04010000 str:"VM0 deactivating."
        v4l2-ctl-5499    [007] ....   208.494893: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-5499    [007] ....   208.495029: tegra_channel_close: vi-output, ar0233 33-001b
        v4l2-ctl-5501    [007] ....   208.497236: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-5501    [007] ....   208.497369: tegra_channel_close: vi-output, ar0233 33-001b
        v4l2-ctl-5503    [007] ....   208.507909: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-5503    [007] ....   208.511428: tegra_channel_set_power: ar0233 33-001b : 0x1
        v4l2-ctl-5503    [007] ....   208.511439: camera_common_s_power: status : 0x1
        v4l2-ctl-5503    [007] ....   208.518183: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-5503    [007] ....   208.518187: csi_s_power: enable : 0x1
        v4l2-ctl-5503    [007] ....   208.518700: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-5503    [007] ....   208.527636: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-5503    [007] ....   208.528906: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-5503    [007] ....   208.528908: csi_s_stream: enable : 0x1
        v4l2-ctl-5503    [007] ....   208.529416: tegra_channel_set_stream: ar0233 33-001b : 0x1
     kworker/2:1-110     [002] ....   208.538166: rtcpu_string: tstamp:7166274109 id:0x04010000 str:"VM0 activating."
     kworker/2:1-110     [002] ....   208.538168: rtcpu_string: tstamp:7166390198 id:0x04010000 str:"IDAC CIL A params:
"
     kworker/2:1-110     [002] ....   208.538174: rtcpu_string: tstamp:7166390599 id:0x04010000 str:"   ab_trio0 0
        ab_trio1 0
        bc_trio0 0
        bc_trio1 0
"
     kworker/2:1-110     [002] ....   208.538175: rtcpu_string: tstamp:7166390701 id:0x04010000 str:"   ca_trio0 0
        ca_trio1 0
"
     kworker/2:1-110     [002] ....   208.538177: rtcpu_string: tstamp:7166390884 id:0x04010000 str:"IDAC CIL B params:
"
     kworker/2:1-110     [002] ....   208.538178: rtcpu_string: tstamp:7166391277 id:0x04010000 str:"   ab_trio0 0
        ab_trio1 0
        bc_trio0 0
        bc_trio1 0
"
     kworker/2:1-110     [002] ....   208.538179: rtcpu_string: tstamp:7166391408 id:0x04010000 str:"   ca_trio0 0
        ca_trio1 0
"
     kworker/2:1-110     [002] ....   208.538180: rtcpu_string: tstamp:7166391670 id:0x04010000 str:"IDACADJ calibration timeout, use default static "
     kworker/2:1-110     [002] ....   208.538181: rtcpu_string: tstamp:7166391771 id:0x04010000 str:"config
"
     kworker/2:1-110     [002] ....   208.538182: rtcpu_string: tstamp:7166434205 id:0x04010000 str:"IDAC CIL A params:
"
     kworker/2:1-110     [002] ....   208.538183: rtcpu_string: tstamp:7166434595 id:0x04010000 str:"   ab_trio0 0
        ab_trio1 0
        bc_trio0 0
        bc_trio1 0
"
     kworker/2:1-110     [002] ....   208.538184: rtcpu_string: tstamp:7166434695 id:0x04010000 str:"   ca_trio0 0
        ca_trio1 0
"
     kworker/2:1-110     [002] ....   208.538185: rtcpu_string: tstamp:7166434876 id:0x04010000 str:"IDAC CIL B params:
"
     kworker/2:1-110     [002] ....   208.538187: rtcpu_string: tstamp:7166435267 id:0x04010000 str:"   ab_trio0 0
        ab_trio1 0
        bc_trio0 0
        bc_trio1 0
"
     kworker/2:1-110     [002] ....   208.538188: rtcpu_string: tstamp:7166435369 id:0x04010000 str:"   ca_trio0 0
        ca_trio1 0
"
     kworker/2:1-110     [002] ....   208.538189: rtcpu_string: tstamp:7166435630 id:0x04010000 str:"IDACADJ calibration timeout, use default static "
     kworker/2:1-110     [002] ....   208.538189: rtcpu_string: tstamp:7166435731 id:0x04010000 str:"config
"
     kworker/2:1-110     [002] ....   208.538191: rtcpu_string: tstamp:7166436168 id:0x04010000 str:"calibration status1 221a8421 status2 2108421
"
     kworker/2:1-110     [002] ....   208.538192: rtcpu_string: tstamp:7166436613 id:0x04010000 str:"calibration status1 23484e92 status2 2f4bce76
"
     kworker/2:1-110     [002] ....   208.538193: rtcpu_string: tstamp:7166446848 id:0x04010000 str:"NVCSILP clock rate = 408000000 Hz.
"
     kworker/2:1-110     [002] ....   208.538194: rtcpu_string: tstamp:7166529718 id:0x04010000 str:"tegra_nvcsi_stream_set_config(vm0, stream=3, csi"
     kworker/2:1-110     [002] ....   208.538195: rtcpu_string: tstamp:7166529839 id:0x04010000 str:"=3)
"
     kworker/2:1-110     [002] ....   208.538196: rtcpu_string: tstamp:7166530210 id:0x04010000 str:"MIPI clock = 750000 kHz, tHS-SETTLE = 0, tCLK-SE"
     kworker/2:1-110     [002] ....   208.538196: rtcpu_string: tstamp:7166530312 id:0x04010000 str:"TTLE = 0
"
     kworker/2:1-110     [002] ....   208.538197: rtcpu_string: tstamp:7166530526 id:0x04010000 str:"===== NVCSI Stream Configuration =====
"
     kworker/2:1-110     [002] ....   208.538199: rtcpu_string: tstamp:7166530774 id:0x04010000 str:"stream_id: PP 3, csi_port: PORT D
"
     kworker/2:1-110     [002] ....   208.538200: rtcpu_string: tstamp:7166531029 id:0x04010000 str:"Brick: PHY 1, Mode: D-PHY
"
     kworker/2:1-110     [002] ....   208.538201: rtcpu_string: tstamp:7166531316 id:0x04010000 str:"Partition: CIL B, LP bypass: Enabled, Lanes: 2
"
     kworker/2:1-110     [002] ....   208.538202: rtcpu_string: tstamp:7166531495 id:0x04010000 str:"Clock information:
"
     kworker/2:1-110     [002] ....   208.538203: rtcpu_string: tstamp:7166531794 id:0x04010000 str:"MIPI clock rate: 750.00 MHz
"
     kworker/2:1-110     [002] ....   208.538205: rtcpu_string: tstamp:7166532042 id:0x04010000 str:"T_HS settle: 0, T_CLK settle: 0
"
     kworker/2:1-110     [002] ....   208.538206: rtcpu_string: tstamp:7166532265 id:0x04010000 str:"======================================
"
     kworker/2:1-110     [002] ....   208.538207: rtcpu_string: tstamp:7166533878 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=3, csi=3)
"
     kworker/2:1-110     [002] ....   208.538208: rtcpu_string: tstamp:7166534464 id:0x04010000 str:"nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_A_"
     kworker/2:1-110     [002] ....   208.538209: rtcpu_string: tstamp:7166534568 id:0x04010000 str:"SW_RESET_0 = 00000000
"
     kworker/2:1-110     [002] ....   208.538210: rtcpu_string: tstamp:7166534929 id:0x04010000 str:"nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_B_"
     kworker/2:1-110     [002] ....   208.538210: rtcpu_string: tstamp:7166535031 id:0x04010000 str:"SW_RESET_0 = 00000003
"
     kworker/2:1-110     [002] ....   208.538211: rtcpu_string: tstamp:7166535567 id:0x04010000 str:"nvcsi_reset_lane_merger: NVCSI_PHY_1_LM_SW_RESET"
     kworker/2:1-110     [002] ....   208.538212: rtcpu_string: tstamp:7166535669 id:0x04010000 str:"_0 = 00000002
"
     kworker/2:1-110     [002] ....   208.538213: rtcpu_string: tstamp:7166541653 id:0x04010000 str:"nvcsi_reset_lane_merger: NVCSI_PHY_1_LM_SW_RESET"
     kworker/2:1-110     [002] ....   208.538213: rtcpu_string: tstamp:7166541754 id:0x04010000 str:"_0 = 00000000
"
     kworker/2:1-110     [002] ....   208.538214: rtcpu_string: tstamp:7166542190 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 53
"
     kworker/2:1-110     [002] ....   208.538216: rtcpu_string: tstamp:7166542453 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 53
"
     kworker/2:1-110     [002] ....   208.538217: rtcpu_string: tstamp:7166542711 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 75
"
     kworker/2:1-110     [002] ....   208.538218: rtcpu_string: tstamp:7166543152 id:0x04010000 str:"nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_A_"
     kworker/2:1-110     [002] ....   208.538218: rtcpu_string: tstamp:7166543252 id:0x04010000 str:"SW_RESET_0 = 00000000
"
     kworker/2:1-110     [002] ....   208.538220: rtcpu_string: tstamp:7166543610 id:0x04010000 str:"nvcsi_reset_data_lanes: NVCSI_PHY_1_NVCSI_CIL_B_"
     kworker/2:1-110     [002] ....   208.538220: rtcpu_string: tstamp:7166543711 id:0x04010000 str:"SW_RESET_0 = 00000000
"
     kworker/2:1-110     [002] ....   208.594163: rtcpu_vinotify_event: tstamp:7166818282 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:229327690912 data:0x759e300010000000
     kworker/2:1-110     [002] ....   208.594163: rtcpu_vinotify_event: tstamp:7166818419 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:229327697344 data:0x0000000031000001
     kworker/2:1-110     [002] ....   208.594163: rtcpu_vinotify_event: tstamp:7166818574 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:229327738112 data:0x759e2d0010000000
     kworker/2:1-110     [002] ....   208.594164: rtcpu_vinotify_event: tstamp:7166818707 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:229327744640 data:0x0000000031000002
        v4l2-ctl-5503    [007] ....   210.588822: tegra_channel_close: vi-output, ar0233 33-001b
 vi-output, ar02-5506    [002] ....   211.094318: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-5503    [007] ....   211.103909: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-5503    [007] ....   211.103911: tegra_channel_set_stream: ar0233 33-001b : 0x0
        v4l2-ctl-5503    [007] ....   211.103933: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-5503    [007] ....   211.103935: csi_s_stream: enable : 0x0
        v4l2-ctl-5503    [007] ....   211.105570: tegra_channel_set_power: ar0233 33-001b : 0x0
        v4l2-ctl-5503    [007] ....   211.105575: camera_common_s_power: status : 0x0
        v4l2-ctl-5503    [007] ....   211.109351: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-5503    [007] ....   211.109353: csi_s_power: enable : 0x0
        v4l2-ctl-5570    [007] ....   213.975564: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-5570    [007] ....   213.975700: tegra_channel_close: vi-output, ar0233 33-001b
        v4l2-ctl-5572    [007] ....   213.977855: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-5572    [007] ....   213.977986: tegra_channel_close: vi-output, ar0233 33-001b
        v4l2-ctl-5574    [007] ....   213.991918: tegra_channel_open: vi-output, ar0233 33-001b
        v4l2-ctl-5574    [007] ....   213.995458: tegra_channel_set_power: ar0233 33-001b : 0x1
        v4l2-ctl-5574    [007] ....   213.995469: camera_common_s_power: status : 0x1
        v4l2-ctl-5574    [007] ....   213.998581: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-5574    [007] ....   213.998584: csi_s_power: enable : 0x1
        v4l2-ctl-5574    [007] ....   213.998906: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-5574    [007] ....   213.999099: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-5574    [007] ....   214.000360: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-5574    [007] ....   214.000362: csi_s_stream: enable : 0x1
        v4l2-ctl-5574    [007] ....   214.000469: tegra_channel_set_stream: ar0233 33-001b : 0x1
     kworker/2:1-110     [002] ....   214.054165: rtcpu_string: tstamp:7337456185 id:0x04010000 str:"ISR PHY 1 CIL_B 0x88
"
     kworker/2:1-110     [002] ....   214.054178: rtcpu_nvcsi_intr: tstamp:7337455949 class:GLOBAL type:PHY_INTR0 phy:1 cil:1 st:0 vc:0 status:0x00000088
     kworker/2:1-110     [002] ....   214.054179: rtcpu_string: tstamp:7337512618 id:0x04010000 str:"tegra_nvcsi_stream_set_config(vm0, stream=3, csi"
     kworker/2:1-110     [002] ....   214.054179: rtcpu_string: tstamp:7337512722 id:0x04010000 str:"=3)
"
     kworker/2:1-110     [002] ....   214.054181: rtcpu_string: tstamp:7337514344 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=3, csi=3)
"
     kworker/2:1-110     [002] ....   214.054184: rtcpu_vinotify_event: tstamp:7337530650 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:234799154976 data:0x759e300010000000
     kworker/2:1-110     [002] ....   214.054184: rtcpu_vinotify_event: tstamp:7337530786 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:234799161440 data:0x0000000031000001
     kworker/2:1-110     [002] ....   214.054184: rtcpu_vinotify_event: tstamp:7337530940 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:234799201792 data:0x759e2d0010000000
     kworker/2:1-110     [002] ....   214.054184: rtcpu_vinotify_event: tstamp:7337531072 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:234799208320 data:0x0000000031000002
        v4l2-ctl-5574    [007] ....   215.436379: tegra_channel_close: vi-output, ar0233 33-001b
 vi-output, ar02-5577    [002] ....   216.566301: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1380 fmt 10
        v4l2-ctl-5574    [007] ....   216.575906: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-5574    [007] ....   216.575908: tegra_channel_set_stream: ar0233 33-001b : 0x0
        v4l2-ctl-5574    [007] ....   216.575925: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-5574    [007] ....   216.575926: csi_s_stream: enable : 0x0
        v4l2-ctl-5574    [007] ....   216.577575: tegra_channel_set_power: ar0233 33-001b : 0x0
        v4l2-ctl-5574    [007] ....   216.577581: camera_common_s_power: status : 0x0
        v4l2-ctl-5574    [007] ....   216.581364: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-5574    [007] ....   216.581366: csi_s_power: enable : 0x0

wxsrite · August 23, 2024, 12:23pm

Hi, JerryChang,

After flashing RCE, there is still a problem. There are two D430s, but one of them still has a problem. Please help me find where the error is. Thank you!

Log：
dmesg.txt (135.6 KB)
dts.txt (478.1 KB)
trace.log (16.4 MB)

JerryChang · August 26, 2024, 2:03am

hello wxsrite,

just double confirm the RCE firmware you’d applied.
I saw some RCE debug messages, did you used the RCE firmware package, Topic268833_JP-512_rce-fw.7z (250.9 KB)?

wxsrite · August 27, 2024, 12:20pm

Hi, JerryChang,

I found that I didn’t seem to upgrade successfully. Please help me, thank you.

Compilation environment:

w@w:~/L4T-public-source $ cp Debug_camera-rtcpu-t234-rce.img ./bootloader/camera-rtcpu-t234-rce.img
w@w:~/L4T-public-source $ cd out/
w@w:~/L4T-public-source/out $ tar xf ota_tools_R35.4.1_aarch64.tbz2
w@w:~/L4T-public-source/out $ export BASE_BSP=/home/w/L4T-public-source/out/Linux_for_Tegra
w@w:~/L4T-public-source/out $ export TARGET_BSP=/home/w/L4T-public-source/out/Linux_for_Tegra
w@w:~/L4T-public-source/out $ cd ${TARGET_BSP}
w@w:~/L4T-public-source/out/Linux_for_Tegra $ sudo -E ./tools/ota_tools/version_upgrade/l4t_generate_ota_package.sh --external-device nvme0n1 -b jetson-orin-nano-devkit R35-4

device（orin nx）：

root@tegra:/home/robot# ls
bootup.svg  bsp-ota-cam4dts_1.0.0_arm64.deb  Desktop  ota_payload_package.tar.gz  ota_tools_R35.4.1_aarch64.tbz2  rcu_selftest
root@tegra:/home/robot# exit
robot@tegra:~$ export WORKDIR=/home/robot
robot@tegra:~$ tar xf ota_tools_R35.4.1_aarch64.tbz2
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_jaxi_R32x_R35i.xml: time stamp 2023-08-02 04:02:27.070295064 is 12025832.120458456 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_t194_sdmmc_rootfs_ab_R35A_R35i.xml: time stamp 2023-08-02 04:02:27.070295064 is 12025832.119201592 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_p3668_R32i.xml: time stamp 2023-08-02 04:02:27.074294964 is 12025832.121885556 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_p3668_rootfs_ab_R35A_R35i.xml: time stamp 2023-08-02 04:02:27.074294964 is 12025832.120993428 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_p3668_rootfs_ab_R32i.xml: time stamp 2023-08-02 04:02:27.074294964 is 12025832.120060308 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_t194_sdmmc_rootfs_ab_R32x_R35i.xml: time stamp 2023-08-02 04:02:27.070295064 is 12025832.11507564 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_p3668_R32x_R35i.xml: time stamp 2023-08-02 04:02:27.074294964 is 12025832.118141428 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_p3668_rootfs_ab_R32x_R35i.xml: time stamp 2023-08-02 04:02:27.074294964 is 12025832.117269428 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_t194_sdmmc_R32x_R35i.xml: time stamp 2023-08-02 04:02:27.070295064 is 12025832.112244344 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_jaxi_R35A_R35i.xml: time stamp 2023-08-02 04:02:27.070295064 is 12025832.1114574 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_jaxi_rootfs_ab_R35A_R35i.xml: time stamp 2023-08-02 04:02:27.074294964 is 12025832.114888916 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_t194_sdmmc_R35A_R35i.xml: time stamp 2023-08-02 04:02:27.070295064 is 12025832.110128184 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_p3668_R35A_R35i.xml: time stamp 2023-08-02 04:02:27.074294964 is 12025832.11299778 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg/flash_l4t_t194_spi_emmc_jaxi_rootfs_ab_R32x_R35i.xml: time stamp 2023-08-02 04:02:27.074294964 is 12025832.11196562 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/cfg: time stamp 2023-08-02 04:02:27.074294964 is 12025832.111628596 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/BCT/tegra186-mb1-bct-misc-si-l4t-r32i.cfg: time stamp 2023-08-02 04:02:27.082294763 is 12025832.119489035 s in the future
tar: Linux_for_Tegra/bootloader/t186ref/BCT: time stamp 2023-08-02 04:02:27.082294763 is 12025832.119455979 s in the future
tar: Linux_for_Tegra/bootloader/t186ref: time stamp 2023-08-02 04:02:27.082294763 is 12025832.119437419 s in the future
tar: Linux_for_Tegra/bootloader: time stamp 2023-08-02 04:02:27.070295064 is 12025832.107422008 s in the future
tar: Linux_for_Tegra/p3509-0000+p3668-0001-qspi-emmc-R35A-R35i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025832.115380849 s in the future
tar: Linux_for_Tegra/p2822-0000+p2888-0008-R35A-R35i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025832.115308817 s in the future
tar: Linux_for_Tegra/Tegra_Software_License_Agreement-Tegra-Linux.txt: time stamp 2023-08-02 04:02:27.098294362 is 12025832.13504553 s in the future
tar: Linux_for_Tegra/jetson-agx-xavier-devkit-R32x-R35i.conf: time stamp 2023-08-02 04:02:27.074294964 is 12025832.111001908 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/ota_backup_files_list.txt: time stamp 2023-08-02 04:02:27.094294461 is 12025832.130738813 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_update.sh: time stamp 2023-08-02 04:02:27.090294563 is 12025832.126674307 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_log.sh: time stamp 2023-08-02 04:02:27.086294663 is 12025832.122482087 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/l4t_update_partitions.sh: time stamp 2023-08-02 04:02:27.082294763 is 12025832.117811275 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/BOOTAA64_r35_1.efi: time stamp 2023-08-02 04:02:27.082294763 is 12025832.116980491 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_update_alt_part.func: time stamp 2023-08-02 04:02:27.090294563 is 12025832.124742787 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_customer.conf: time stamp 2023-08-02 04:02:27.086294663 is 12025832.120688967 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_update_with_layout_change.sh: time stamp 2023-08-02 04:02:27.090294563 is 12025832.124517251 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_start.sh: time stamp 2023-08-02 04:02:27.090294563 is 12025832.124463811 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/kernel_bootctrl.bin.reset: time stamp 2023-08-02 04:02:27.098294362 is 12025832.132417114 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/ota_multi_board_specs.sh: time stamp 2023-08-02 04:02:27.094294461 is 12025832.090099197 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_update_alt_part.sh: time stamp 2023-08-02 04:02:27.094294461 is 12025832.089971421 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/kernel_bootctrl.bin.update: time stamp 2023-08-02 04:02:27.098294362 is 12025832.093912826 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/ota_board_specs.conf: time stamp 2023-08-02 04:02:27.094294461 is 12025832.089843293 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_preserve_data.sh: time stamp 2023-08-02 04:02:27.086294663 is 12025832.081789927 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/ota_validate_params.sh: time stamp 2023-08-02 04:02:27.094294461 is 12025832.089743165 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/l4t_generate_ota_package.sh: time stamp 2023-08-02 04:02:27.082294763 is 12025832.076940523 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_decompress_package.sh: time stamp 2023-08-02 04:02:27.086294663 is 12025832.080849575 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_run_tasks.sh: time stamp 2023-08-02 04:02:27.086294663 is 12025832.080793831 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/upgradetasklist.txt.jetson-agx-xavier-industrial.R32x_to_R35-ToT_emmc: time stamp 2023-08-02 04:02:27.094294461 is 12025832.088660221 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_common.func: time stamp 2023-08-02 04:02:27.082294763 is 12025832.076207019 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/NOTICE.uefi: time stamp 2023-08-02 04:02:27.082294763 is 12025832.076141483 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/upgradetasklist.txt.jetson-xavier-nx-devkit-emmc.R32x_to_R35-ToT_emmc: time stamp 2023-08-02 04:02:27.098294362 is 12025832.092006074 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_validate.sh: time stamp 2023-08-02 04:02:27.090294563 is 12025832.083890563 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_update_without_layout_change.sh: time stamp 2023-08-02 04:02:27.090294563 is 12025832.083669795 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_update_rootfs_in_recovery.sh: time stamp 2023-08-02 04:02:27.090294563 is 12025832.083535619 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/ota_check_partitions.sh: time stamp 2023-08-02 04:02:27.094294461 is 12025832.087445437 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_common_utils.func: time stamp 2023-08-02 04:02:27.086294663 is 12025832.079351879 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/kernel_bootctrl.bin.normal: time stamp 2023-08-02 04:02:27.098294362 is 12025832.091301818 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/Image_based_OTA_Examples.txt: time stamp 2023-08-02 04:02:27.082294763 is 12025832.075232907 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_update_all_in_recovery.sh: time stamp 2023-08-02 04:02:27.090294563 is 12025832.083097059 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/build_base_recovery_image.sh: time stamp 2023-08-02 04:02:27.082294763 is 12025832.074743755 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/uefi_jetson_r35_1.bin: time stamp 2023-08-02 04:02:27.086294663 is 12025831.871490951 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_check_version.sh: time stamp 2023-08-02 04:02:27.082294763 is 12025831.867254027 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/upgradetasklist.txt.jetson-agx-xavier-devkit.R32x_to_R35-ToT_emmc: time stamp 2023-08-02 04:02:27.094294461 is 12025831.879146781 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade/nv_ota_rootfs_updater.sh: time stamp 2023-08-02 04:02:27.086294663 is 12025831.871067271 s in the future
tar: Linux_for_Tegra/tools/ota_tools/version_upgrade: time stamp 2023-08-02 04:02:27.10229426 is 12025831.887036596 s in the future
tar: Linux_for_Tegra/tools/ota_tools: time stamp 2023-08-02 04:02:27.082294763 is 12025831.867018507 s in the future
tar: Linux_for_Tegra/tools: time stamp 2023-08-02 04:02:27.082294763 is 12025831.867005003 s in the future
tar: Linux_for_Tegra/p3509-0000+p3668-0001-qspi-emmc-R32x-R35i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025831.862962865 s in the future
tar: Linux_for_Tegra/jetson-xnx-devkit-emmc-R32x-R35i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025831.862916945 s in the future
tar: Linux_for_Tegra/jaxi-R35A-R35i.conf: time stamp 2023-08-02 04:02:27.074294964 is 12025831.858884404 s in the future
tar: Linux_for_Tegra/jetson-xnx-devkit-emmc-R32i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025831.862849585 s in the future
tar: Linux_for_Tegra/jetson-agx-xavier-devkit-R35A-R35i.conf: time stamp 2023-08-02 04:02:27.074294964 is 12025831.8588165 s in the future
tar: Linux_for_Tegra/p2822-0000+p2888-0008-R32x-R35i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025831.862773585 s in the future
tar: Linux_for_Tegra/p3509-0000+p3668-0001-qspi-emmc-R32i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025831.862726225 s in the future
tar: Linux_for_Tegra/jaxi-R32x-R35i.conf: time stamp 2023-08-02 04:02:27.074294964 is 12025831.858687828 s in the future
tar: Linux_for_Tegra/p2822-0000+p2888-0004-R35A-R35i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025831.862646545 s in the future
tar: Linux_for_Tegra/p2822-0000+p2888-0004-R32x-R35i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025831.862595281 s in the future
tar: Linux_for_Tegra/jetson-xnx-devkit-emmc-R35A-R35i.conf: time stamp 2023-08-02 04:02:27.078294865 is 12025831.862559089 s in the future
tar: Linux_for_Tegra: time stamp 2023-08-02 04:02:27.098294362 is 12025831.88249705 s in the future
robot@tegra:~$ sudo mkdir /ota
[sudo] password for robot:
robot@tegra:~$ sudo mv ota_payload_package.tar.gz /ota/
robot@tegra:~$ cd ${WORKDIR}/Linux_for_Tegra/tools/ota_tools/version_upgrade
robot@tegra:~/Linux_for_Tegra/tools/ota_tools/version_upgrade$ sudo ./nv_ota_start.sh /ota/ota_payload_package.tar.gz
Command: ./nv_ota_start.sh /ota/ota_payload_package.tar.gz
Current rootfs is on /dev/nvme0n1
init_ota_log /ota_log
Creating log dir at /ota_log
Create log file at /ota_log/ota_20230315-233216.log
OTA_LOG_FILE=/ota_log/ota_20230315-233216.log
Extract /ota/ota_payload_package.tar.gz
update_nv_boot_control_in_rootfs /ota_work
3767-000-0000--1--jetson-orin-nano-devkit-
Info. Installing mtdblock.
Info. Active boot storage: nvme0n1
Info. Legacy mode: false
TNSPEC 3767-300-0000-M.1-1-0-jetson-orin-nano-devkit-nvme-
COMPATIBLE_SPEC 3767-000-0000--1--jetson-orin-nano-devkit-
TEGRA_LEGACY_UPDATE false
TEGRA_BOOT_STORAGE nvme0n1
TEGRA_EMMC_ONLY false
TEGRA_CHIPID 0x23
TEGRA_OTA_BOOT_DEVICE /dev/mtdblock0
TEGRA_OTA_GPT_DEVICE /dev/mtdblock0
Info: Write TegraPlatformCompatSpec with 3767-000-0000--1--jetson-orin-nano-devkit-.
Info. Uninstalling mtdblock.
check_prerequisites
decompress_ota_package ota_package.tar /ota_work
decompress_ota_package: start at Wed 15 Mar 2023 11:32:22 PM CST
Sha1 checksum for /ota_work/ota_package.tar (a71c0a62f9ad5d0a3505032aa844af0f5f3f5a67) matches
decompress_ota_package: end at Wed 15 Mar 2023 11:32:24 PM CST
nv_ota_update_without_layout_change.sh
Command: nv_ota_update_without_layout_change.sh
check_target_board /ota_work TARGET_BOARD
get_chip_id CHIP_ID
ota_choose_images /ota_work
COMPATIBLE_SPEC=3767-000-0000--1--jetson-orin-nano-devkit-
TEGRA_CHIPID=0x23
_BOARD_SPEC_NAME=3767-000-0000-
Copy files from ./images-R35-ToT/3767-000-0000-/ to ./images-R35-ToT/
get_rootfs_a_b_enabled ROOTFS_AB_ENABLED ROOTFS_CURRENT_SLOT
ROOTFS_AB_ENABLED=0
ROOTFS_CURRENT_SLOT=0
get_update_slot UPDATE_SLOT
UPDATE_SLOT=B
get_update_control /ota_work UPDATE_BOOTLOADER UPDATE_ROOTFS
UPDATE_BOOTLOADER=1, UPDATE_ROOTFS=0
select_uefi_capsule /ota_work
check_bootloader_version /ota_work
update_bootloader /ota_work
trigger_uefi_capsule_update /ota_work jetson-orin-nano-devkit
Mount esp partition on /opt/nvidia/esp
Copying /ota_work/TEGRA_BL.Cap into /opt/nvidia/esp/EFI/UpdateCapsule
Triggering UEFI capsule update by writing \x07\x00\x00\x00\x04\x00\x00\x00\x00\x00\x00\x00 to UEFI variable OsIndications-8be4df61-93ca-11d2-aa0d-00e098032b8c
dd if=/tmp/var_tmp.bin of=OsIndications-8be4df61-93ca-11d2-aa0d-00e098032b8c bs=12
1+0 records in
1+0 records out
12 bytes copied, 0.00593203 s, 2.0 kB/s
Bootloader on non-current slot(B) is to be updated once device is rebooted
robot@tegra:~/Linux_for_Tegra/tools/ota_tools/version_upgrade$

After the device is up, the MD5 value of rce has not changed. Did I fail to upgrade? Please help me find out what is wrong. Thank you.

[    6.041272] tegra186-cam-rtcpu bc00000.rtcpu: firmware version cpu=rce cmd=6 sha1=3f5896e7fe6940b914d3eeab7660ff15767c552c

JerryChang · August 28, 2024, 3:00am

hello wxsrite,

you should update RCE firmware correctly since there’re RCE debug logs within your kernel logs.
however, it’s the one only toggling the debug flag, you should using the RCE firmware package, Topic268833_JP-512_rce-fw.7z (250.9 KB) to include the fixes.

system · September 11, 2024, 3:00am

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

Topic		Replies	Views
Capture image error Jetson Orin Nano camera	20	140	December 3, 2024
Camera is unable to produce images, could you please help to see what the reason is? Jetson Orin NX camera	23	416	April 10, 2024
Camera Porting Issues Jetson AGX Xavier camera	16	653	August 8, 2023
Why is the camera not displaying images? Is there something wrong with vi? Jetson Orin NX camera , jetson	4	17	January 13, 2025
AGX Orin 100% CPU usage when multiple video devices are enabled at the same time, one of the camera devices stops streaming data unexpectedly Jetson AGX Orin camera	30	1794	October 3, 2023
When the camera driver was upgraded from r35.4.1 to r35.5.0, a kernel crash occurred when vi could not receive data Jetson AGX Orin camera	9	238	September 20, 2024
No frame from IMX415 on orin devkit Jetson AGX Orin camera	3	530	November 6, 2023
Open camera occur system reboot on jetpack 4.4 Jetson AGX Xavier camera	18	873	February 1, 2023
Jetson_Linux 34.1 : vi_capture_control_message: NULL VI channel received Jetson AGX Xavier camera	47	2790	June 13, 2023
Jetpack 5.0.2 : vi_capture_control_message: NULL VI channel received Jetson Xavier NX camera , kernel , gstreamer	18	3745	December 14, 2022

Why is the camera not displaying images? Please help me find out what the reason is, thank you!

Related topics