HI ALL:
I want to know how to dump jetson orin nvcsi5’s register?
Why do you need to dump the NVCSI REG? Current you may need to dump it from the csi5_fops.c otherwise it could cause the system hang.
I debug CPHY 2lane camera, it is not output image on jetson orin.
There is error, I dont what is means?
kworker/4:0-35 [004] .... 8167.328234: rtcpu_nvcsi_intr: tstamp:255936888784 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328234: rtcpu_nvcsi_intr: tstamp:255936889300 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328235: rtcpu_nvcsi_intr: tstamp:255936889706 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328235: rtcpu_nvcsi_intr: tstamp:255936890231 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328236: rtcpu_nvcsi_intr: tstamp:255936890641 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328236: rtcpu_nvcsi_intr: tstamp:255936891153 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328236: rtcpu_nvcsi_intr: tstamp:255936891559 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328237: rtcpu_nvcsi_intr: tstamp:255936892080 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328237: rtcpu_nvcsi_intr: tstamp:255936892495 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328237: rtcpu_nvcsi_intr: tstamp:255936893006 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328238: rtcpu_nvcsi_intr: tstamp:255936893413 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328238: rtcpu_nvcsi_intr: tstamp:255936893929 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328238: rtcpu_nvcsi_intr: tstamp:255936894340 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328239: rtcpu_nvcsi_intr: tstamp:255936894856 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328239: rtcpu_nvcsi_intr: tstamp:255936895267 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328239: rtcpu_nvcsi_intr: tstamp:255936895786 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
kworker/4:0-35 [004] .... 8167.328240: rtcpu_nvcsi_intr: tstamp:255936896197 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000080
my-camera-trace1.txt (5.9 MB)
It’s “err_intr_cil_data_lane_ctrl_err1_a” of REG NVCSI_PHY_0_CILA_INTR_0_STATUS_CILA_0
Maybe confirm the lane configure like port-index/bus-width … in device tree.
HI ShaneCCC:
if I do not do brick_config.lane_polarity[1] = NVCSI_CPHY_POLARITY_BCA;
the err msg is diff, like this
kworker/2:2-115 [002] .... 95.612827: rtcpu_nvcsi_intr: tstamp:3695781495 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612828: rtcpu_nvcsi_intr: tstamp:3695781495 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612828: rtcpu_nvcsi_intr: tstamp:3695781495 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612828: rtcpu_nvcsi_intr: tstamp:3695782204 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612829: rtcpu_nvcsi_intr: tstamp:3695782204 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612829: rtcpu_nvcsi_intr: tstamp:3695782204 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612830: rtcpu_nvcsi_intr: tstamp:3695782938 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612830: rtcpu_nvcsi_intr: tstamp:3695782938 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612830: rtcpu_nvcsi_intr: tstamp:3695783739 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612831: rtcpu_nvcsi_intr: tstamp:3695783739 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612831: rtcpu_nvcsi_intr: tstamp:3695784381 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612831: rtcpu_nvcsi_intr: tstamp:3695784381 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612832: rtcpu_nvcsi_intr: tstamp:3695785343 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612832: rtcpu_nvcsi_intr: tstamp:3695785343 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612833: rtcpu_nvcsi_intr: tstamp:3695786304 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612833: rtcpu_nvcsi_intr: tstamp:3695786304 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612833: rtcpu_nvcsi_intr: tstamp:3695786946 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612834: rtcpu_nvcsi_intr: tstamp:3695786946 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612834: rtcpu_nvcsi_intr: tstamp:3695787908 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612835: rtcpu_nvcsi_intr: tstamp:3695787908 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612835: rtcpu_nvcsi_intr: tstamp:3695788869 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612835: rtcpu_nvcsi_intr: tstamp:3695788869 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612836: rtcpu_nvcsi_intr: tstamp:3695789511 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612836: rtcpu_nvcsi_intr: tstamp:3695789511 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612837: rtcpu_nvcsi_intr: tstamp:3695790154 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612837: rtcpu_nvcsi_intr: tstamp:3695790154 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612837: rtcpu_nvcsi_intr: tstamp:3695790154 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612838: rtcpu_nvcsi_intr: tstamp:3695790854 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612838: rtcpu_nvcsi_intr: tstamp:3695790854 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612839: rtcpu_nvcsi_intr: tstamp:3695791408 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612839: rtcpu_nvcsi_intr: tstamp:3695791771 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612839: rtcpu_nvcsi_intr: tstamp:3695791771 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612840: rtcpu_nvcsi_intr: tstamp:3695792396 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612840: rtcpu_nvcsi_intr: tstamp:3695792396 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:1 status:0x00000004
kworker/2:2-115 [002] .... 95.612840: rtcpu_nvcsi_intr: tstamp:3695792947 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612841: rtcpu_nvcsi_intr: tstamp:3695792947 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612841: rtcpu_nvcsi_intr: tstamp:3695792947 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612842: rtcpu_nvcsi_intr: tstamp:3695792947 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:3 status:0x00000004
kworker/2:2-115 [002] .... 95.612842: rtcpu_nvcsi_intr: tstamp:3695793804 class:GLOBAL type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
kworker/2:2-115 [002] .... 95.612843: rtcpu_nvcsi_intr: tstamp:3695793804 class:CORRECTABLE_ERR type:STREAM_VC phy:0 cil:0 st:0 vc:2 status:0x00000004
vi-output, ar02-2384 [006] … 98.108125: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/2:2-115 [002] … 98.152024: rtcpu_vinotify_event: tstamp:3774977982 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:120795247328 data:0x379d580010000000
kworker/2:2-115 [002] … 98.152026: rtcpu_vinotify_event: tstamp:3774978138 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:120795253760 data:0x0000000031000001
kworker/2:2-115 [002] … 98.152027: rtcpu_vinotify_event: tstamp:3774978270 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:120795294144 data:0x379d550010000000
kworker/2:2-115 [002] … 98.152027: rtcpu_vinotify_event: tstamp:3774978422 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:120795300672 data:0x0000000031000002
vi-output, ar02-2384 [004] … 100.905820: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
my-camera-trace2.txt (316.9 KB)
This error tell “err_intr_cil_data_lane_sot_mb_err0_a” that could be the settle time cause the capture error. Please confirm the timing as MIPI spec.
HI ShaneCCC:
what is means this err msg
tracer: nop
entries-in-buffer/entries-written: 57077/57077 #P:8
_-----=> irqs-off
/ _----=> need-resched
| / _—=> hardirq/softirq
|| / _–=> preempt-depth
||| / delay
TASK-PID CPU# |||| TIMESTAMP FUNCTION
| | | |||| | |
kworker/4:2-121 [004] .... 260.675737: rtcpu_string: tstamp:8854354335 id:0x04010000 str:"VM0 deactivating."
gst-plugin-scan-2417 [002] … 270.867812: tegra_channel_open: vi-output, ar0233 30-0050
gst-plugin-scan-2417 [002] … 270.867897: tegra_channel_close: vi-output, ar0233 30-0050
gst-plugin-scan-2417 [002] … 270.867907: tegra_channel_open: vi-output, ar0233 30-0051
gst-plugin-scan-2417 [002] … 270.867977: tegra_channel_close: vi-output, ar0233 30-0051
gst-plugin-scan-2417 [002] … 270.867985: tegra_channel_open: vi-output, ar0233 30-0052
gst-plugin-scan-2417 [002] … 270.868055: tegra_channel_close: vi-output, ar0233 30-0052
gst-plugin-scan-2417 [002] … 270.868063: tegra_channel_open: vi-output, ar0233 30-0053
gst-plugin-scan-2417 [002] … 270.868074: tegra_channel_close: vi-output, ar0233 30-0053
gst-launch-1.0-2416 [003] … 270.930895: tegra_channel_open: vi-output, ar0233 30-0050
gst-launch-1.0-2416 [003] … 270.930979: tegra_channel_close: vi-output, ar0233 30-0050
gst-launch-1.0-2416 [003] … 270.930988: tegra_channel_open: vi-output, ar0233 30-0051
gst-launch-1.0-2416 [003] … 270.931055: tegra_channel_close: vi-output, ar0233 30-0051
gst-launch-1.0-2416 [003] … 270.931064: tegra_channel_open: vi-output, ar0233 30-0052
gst-launch-1.0-2416 [003] … 270.931131: tegra_channel_close: vi-output, ar0233 30-0052
gst-launch-1.0-2416 [003] … 270.931139: tegra_channel_open: vi-output, ar0233 30-0053
gst-launch-1.0-2416 [003] … 270.931149: tegra_channel_close: vi-output, ar0233 30-0053
gst-launch-1.0-2416 [003] … 270.952255: tegra_channel_open: vi-output, ar0233 30-0051
v4l2src0:src-2419 [004] … 270.963588: tegra_channel_set_power: ar0233 30-0051 : 0x1
v4l2src0:src-2419 [004] … 270.963614: camera_common_s_power: status : 0x1
v4l2src0:src-2419 [004] … 270.964609: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2src0:src-2419 [004] … 270.964614: csi_s_power: enable : 0x1
v4l2src0:src-2419 [004] … 270.965220: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
v4l2src0:src-2419 [005] … 270.972115: tegra_channel_set_stream: enable : 0x1
v4l2src0:src-2419 [005] … 270.984289: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2src0:src-2419 [005] … 270.984292: csi_s_stream: enable : 0x1
v4l2src0:src-2419 [005] … 270.988403: tegra_channel_set_stream: ar0233 30-0051 : 0x1
kworker/4:2-121 [004] … 270.999710: rtcpu_string: tstamp:9176964580 id:0x04010000 str:“VM0 activating.”
kworker/4:2-121 [004] … 270.999712: rtcpu_string: tstamp:9177056526 id:0x04010000 str:“NVCSILP clock rate = 408000000 Hz.
"
kworker/4:2-121 [004] … 270.999718: rtcpu_vinotify_event: tstamp:9177429773 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:293667481184 data:0x379d580010000000
kworker/4:2-121 [004] … 270.999719: rtcpu_vinotify_event: tstamp:9177429991 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:293667487648 data:0x0000000031000001
kworker/4:2-121 [004] … 270.999719: rtcpu_vinotify_event: tstamp:9177430258 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:293667548320 data:0x379d550010000000
kworker/4:2-121 [004] … 270.999719: rtcpu_vinotify_event: tstamp:9177430473 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:293667554880 data:0x0000000031000002
kworker/4:2-121 [004] … 270.999720: rtcpu_string: tstamp:9177603574 id:0x04010000 str:“tegra_nvcsi_stream_set_config(vm0, stream=0, csi”
kworker/4:2-121 [004] … 270.999720: rtcpu_string: tstamp:9177603676 id:0x04010000 str:”=0)
"
kworker/4:2-121 [004] … 270.999723: rtcpu_string: tstamp:9177604047 id:0x04010000 str:“MIPI clock = 656250 kHz, tHS-SETTLE = 0, tCLK-SE”
kworker/4:2-121 [004] … 270.999723: rtcpu_string: tstamp:9177604148 id:0x04010000 str:“TTLE = 0
"
kworker/4:2-121 [004] … 270.999726: rtcpu_string: tstamp:9177604360 id:0x04010000 str:”===== NVCSI Stream Configuration =====
"
kworker/4:2-121 [004] … 270.999729: rtcpu_string: tstamp:9177604607 id:0x04010000 str:"stream_id: PP 0, csi_port: PORT A
"
kworker/4:2-121 [004] … 270.999731: rtcpu_string: tstamp:9177604865 id:0x04010000 str:"Brick: PHY 0, Mode: C-PHY
"
kworker/4:2-121 [004] … 270.999734: rtcpu_string: tstamp:9177605153 id:0x04010000 str:"Partition: CIL A, LP bypass: Disabled, Lanes: 2
"
kworker/4:2-121 [004] … 270.999736: rtcpu_string: tstamp:9177605320 id:0x04010000 str:"Clock information:
"
kworker/4:2-121 [004] … 270.999738: rtcpu_string: tstamp:9177605625 id:0x04010000 str:"MIPI symbol rate: 656.25 MHz
"
kworker/4:2-121 [004] … 270.999740: rtcpu_string: tstamp:9177605872 id:0x04010000 str:“T_HS settle: 0, T_CLK settle: 0
"
kworker/4:2-121 [004] … 270.999741: rtcpu_string: tstamp:9177606098 id:0x04010000 str:”======================================
"
kworker/4:2-121 [004] … 270.999743: rtcpu_string: tstamp:9177607998 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
"
kworker/4:2-121 [004] … 270.999744: rtcpu_string: tstamp:9177614244 id:0x04010000 str:"calibration status1 1ed84010 status2 1e07ce11
"
kworker/4:2-121 [004] … 270.999745: rtcpu_string: tstamp:9177614625 id:0x04010000 str:"calibration status1 1ed84210 status2 1f07ce31
"
kworker/4:2-121 [004] … 270.999747: rtcpu_string: tstamp:9177615267 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:2-121 [004] … 270.999748: rtcpu_string: tstamp:9177615510 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 39
"
kworker/4:2-121 [004] … 270.999750: rtcpu_string: tstamp:9177615778 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:2-121 [004] … 270.999751: rtcpu_string: tstamp:9177616017 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 39
"
kworker/4:2-121 [004] … 270.999752: rtcpu_string: tstamp:9177616270 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:2-121 [004] … 271.055707: rtcpu_string: tstamp:9178368831 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055715: rtcpu_nvcsi_intr: tstamp:9178368602 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055716: rtcpu_string: tstamp:9178375301 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055718: rtcpu_nvcsi_intr: tstamp:9178375082 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055718: rtcpu_string: tstamp:9178377145 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055719: rtcpu_nvcsi_intr: tstamp:9178376932 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055720: rtcpu_string: tstamp:9178378995 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055721: rtcpu_nvcsi_intr: tstamp:9178378783 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055721: rtcpu_string: tstamp:9178382701 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055722: rtcpu_nvcsi_intr: tstamp:9178382487 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055723: rtcpu_string: tstamp:9178390109 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055724: rtcpu_nvcsi_intr: tstamp:9178389895 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055724: rtcpu_string: tstamp:9178391958 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055726: rtcpu_nvcsi_intr: tstamp:9178391747 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055726: rtcpu_string: tstamp:9178399366 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055727: rtcpu_nvcsi_intr: tstamp:9178399156 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055727: rtcpu_string: tstamp:9178402145 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055729: rtcpu_nvcsi_intr: tstamp:9178401932 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055729: rtcpu_string: tstamp:9178403072 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055730: rtcpu_nvcsi_intr: tstamp:9178402859 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055731: rtcpu_string: tstamp:9178403995 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055732: rtcpu_nvcsi_intr: tstamp:9178403785 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055732: rtcpu_string: tstamp:9178405848 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055733: rtcpu_nvcsi_intr: tstamp:9178405637 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055734: rtcpu_string: tstamp:9178409553 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055735: rtcpu_nvcsi_intr: tstamp:9178409340 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055735: rtcpu_string: tstamp:9178412330 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055736: rtcpu_nvcsi_intr: tstamp:9178412119 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055737: rtcpu_string: tstamp:9178418807 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055738: rtcpu_nvcsi_intr: tstamp:9178418597 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055738: rtcpu_string: tstamp:9178425293 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
"
kworker/4:2-121 [004] … 271.055739: rtcpu_nvcsi_intr: tstamp:9178425082 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000004
kworker/4:2-121 [004] … 271.055740: rtcpu_string: tstamp:9178426219 id:0x04010000 str:"ISR PHY 0 CIL_A 0x4
It’s err_intr_cil_data_lane_sot_mb_err0_a in NVCSI_PHY_0_CILA_INTR_0_STATUS_CILA_0 REG.
My previous comment is incorrect. It should be err_intr_stat_pd_crc_err_vc0 in NVCSI_STREAM_0_INTR_STATUS_VC0_0
HI ShaneCCC:
how to adjust to fix err like this
he@he-desktop:~$ sudo cat /sys/kernel/debug/tracing/trace
[sudo] password for he:
tracer: nop
entries-in-buffer/entries-written: 126/126 #P:8
_-----=> irqs-off
/ _----=> need-resched
| / _—=> hardirq/softirq
|| / _–=> preempt-depth
||| / delay
TASK-PID CPU# |||| TIMESTAMP FUNCTION
| | | |||| | |
kworker/4:3-114 [004] .... 64.832430: rtcpu_string: tstamp:2733571121 id:0x04010000 str:"VM0 deactivating."
gst-plugin-scan-2365 [005] … 73.105846: tegra_channel_open: vi-output, ar0233 30-0050
gst-plugin-scan-2365 [005] … 73.105927: tegra_channel_close: vi-output, ar0233 30-0050
gst-plugin-scan-2365 [005] … 73.105936: tegra_channel_open: vi-output, ar0233 30-0051
gst-plugin-scan-2365 [005] … 73.106003: tegra_channel_close: vi-output, ar0233 30-0051
gst-plugin-scan-2365 [005] … 73.106010: tegra_channel_open: vi-output, ar0233 30-0052
gst-plugin-scan-2365 [005] … 73.106078: tegra_channel_close: vi-output, ar0233 30-0052
gst-plugin-scan-2365 [005] … 73.106086: tegra_channel_open: vi-output, ar0233 30-0053
gst-plugin-scan-2365 [005] … 73.106096: tegra_channel_close: vi-output, ar0233 30-0053
gst-launch-1.0-2364 [003] … 73.175301: tegra_channel_open: vi-output, ar0233 30-0050
gst-launch-1.0-2364 [003] … 73.175381: tegra_channel_close: vi-output, ar0233 30-0050
gst-launch-1.0-2364 [003] … 73.175391: tegra_channel_open: vi-output, ar0233 30-0051
gst-launch-1.0-2364 [003] … 73.175458: tegra_channel_close: vi-output, ar0233 30-0051
gst-launch-1.0-2364 [003] … 73.175466: tegra_channel_open: vi-output, ar0233 30-0052
gst-launch-1.0-2364 [003] … 73.175532: tegra_channel_close: vi-output, ar0233 30-0052
gst-launch-1.0-2364 [003] … 73.175540: tegra_channel_open: vi-output, ar0233 30-0053
gst-launch-1.0-2364 [003] … 73.175550: tegra_channel_close: vi-output, ar0233 30-0053
gst-launch-1.0-2364 [003] … 73.194423: tegra_channel_open: vi-output, ar0233 30-0053
v4l2src0:src-2367 [004] … 73.205207: tegra_channel_set_power: ar0233 30-0053 : 0x1
v4l2src0:src-2367 [004] … 73.205230: camera_common_s_power: status : 0x1
v4l2src0:src-2367 [004] … 73.206241: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2src0:src-2367 [004] … 73.206245: csi_s_power: enable : 0x1
v4l2src0:src-2367 [004] … 73.206851: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
v4l2src0:src-2367 [003] … 73.214910: tegra_channel_set_stream: enable : 0x1
v4l2src0:src-2367 [000] … 73.227584: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2src0:src-2367 [000] … 73.227586: csi_s_stream: enable : 0x1
v4l2src0:src-2367 [000] … 73.231684: tegra_channel_set_stream: ar0233 30-0053 : 0x1
kworker/4:3-114 [004] … 73.252193: rtcpu_string: tstamp:2996998310 id:0x04010000 str:“VM0 activating.”
kworker/4:3-114 [004] … 73.252195: rtcpu_string: tstamp:2997101155 id:0x04010000 str:“NVCSILP clock rate = 408000000 Hz.
"
kworker/4:3-114 [004] … 73.252200: rtcpu_vinotify_event: tstamp:2997492811 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:95909512800 data:0x379d580010000000
kworker/4:3-114 [004] … 73.252201: rtcpu_vinotify_event: tstamp:2997493021 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:95909519648 data:0x0000000031000001
kworker/4:3-114 [004] … 73.252201: rtcpu_vinotify_event: tstamp:2997493270 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:95909574944 data:0x379d550010000000
kworker/4:3-114 [004] … 73.252201: rtcpu_vinotify_event: tstamp:2997493476 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:95909581472 data:0x0000000031000002
kworker/4:3-114 [004] … 73.252201: rtcpu_string: tstamp:2997682288 id:0x04010000 str:“tegra_nvcsi_stream_set_config(vm0, stream=0, csi”
kworker/4:3-114 [004] … 73.252202: rtcpu_string: tstamp:2997682391 id:0x04010000 str:”=0)
"
kworker/4:3-114 [004] … 73.252203: rtcpu_string: tstamp:2997682759 id:0x04010000 str:“MIPI clock = 900200 kHz, tHS-SETTLE = 0, tCLK-SE”
kworker/4:3-114 [004] … 73.252204: rtcpu_string: tstamp:2997682859 id:0x04010000 str:“TTLE = 0
"
kworker/4:3-114 [004] … 73.252205: rtcpu_string: tstamp:2997683069 id:0x04010000 str:”===== NVCSI Stream Configuration =====
"
kworker/4:3-114 [004] … 73.252207: rtcpu_string: tstamp:2997683314 id:0x04010000 str:"stream_id: PP 0, csi_port: PORT A
"
kworker/4:3-114 [004] … 73.252208: rtcpu_string: tstamp:2997683568 id:0x04010000 str:"Brick: PHY 0, Mode: C-PHY
"
kworker/4:3-114 [004] … 73.252210: rtcpu_string: tstamp:2997683855 id:0x04010000 str:"Partition: CIL A, LP bypass: Disabled, Lanes: 2
"
kworker/4:3-114 [004] … 73.252211: rtcpu_string: tstamp:2997684021 id:0x04010000 str:"Clock information:
"
kworker/4:3-114 [004] … 73.252212: rtcpu_string: tstamp:2997684324 id:0x04010000 str:"MIPI symbol rate: 900.20 MHz
"
kworker/4:3-114 [004] … 73.252214: rtcpu_string: tstamp:2997684569 id:0x04010000 str:“T_HS settle: 0, T_CLK settle: 0
"
kworker/4:3-114 [004] … 73.252215: rtcpu_string: tstamp:2997684795 id:0x04010000 str:”======================================
"
kworker/4:3-114 [004] … 73.252217: rtcpu_string: tstamp:2997686710 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
"
kworker/4:3-114 [004] … 73.252218: rtcpu_string: tstamp:2997692947 id:0x04010000 str:"calibration status1 1ed84210 status2 1f07ca11
"
kworker/4:3-114 [004] … 73.252219: rtcpu_string: tstamp:2997693589 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:3-114 [004] … 73.252221: rtcpu_string: tstamp:2997693831 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 33
"
kworker/4:3-114 [004] … 73.252222: rtcpu_string: tstamp:2997694101 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:3-114 [004] … 73.252223: rtcpu_string: tstamp:2997694340 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 33
"
kworker/4:3-114 [004] … 73.252225: rtcpu_string: tstamp:2997694591 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:3-114 [004] … 73.252226: rtcpu_string: tstamp:2998028677 id:0x04010000 str:"ISR PHY 0 CIL_A 0x20000000
"
kworker/4:3-114 [004] … 73.252229: rtcpu_nvcsi_intr: tstamp:2998028415 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x20000000
vi-output, ar02-2370 [004] … 75.837117: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-114 [004] … 75.846798: rtcpu_vinotify_event: tstamp:3079365421 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:98531885184 data:0x379d580010000000
kworker/4:3-114 [004] … 75.846799: rtcpu_vinotify_event: tstamp:3079365556 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:98531891648 data:0x0000000031000001
kworker/4:3-114 [004] … 75.846799: rtcpu_vinotify_event: tstamp:3079365709 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:98531932032 data:0x379d550010000000
kworker/4:3-114 [004] … 75.846799: rtcpu_vinotify_event: tstamp:3079365839 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:98531938592 data:0x0000000031000002
kworker/4:3-114 [004] … 78.048217: rtcpu_string: tstamp:3147838881 id:0x04010000 str:"ISR PHY 0 CIL_A 0x10
"
kworker/4:3-114 [004] … 78.048240: rtcpu_nvcsi_intr: tstamp:3147838601 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000010
vi-output, ar02-2370 [004] … 78.653064: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-114 [004] … 78.668188: rtcpu_vinotify_event: tstamp:3167322269 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:101349823392 data:0x379d580010000000
kworker/4:3-114 [004] … 78.668188: rtcpu_vinotify_event: tstamp:3167322404 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:101349829856 data:0x0000000031000001
kworker/4:3-114 [004] … 78.724215: rtcpu_vinotify_event: tstamp:3167660601 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:101361018688 data:0x379d550010000000
kworker/4:3-114 [004] … 78.724217: rtcpu_vinotify_event: tstamp:3167660740 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:101361025632 data:0x0000000031000002
vi-output, ar02-2370 [004] … 81.469085: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-114 [004] … 81.524207: rtcpu_vinotify_event: tstamp:3255130399 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:104164101888 data:0x379d580010000000
kworker/4:3-114 [004] … 81.524210: rtcpu_vinotify_event: tstamp:3255130534 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:104164108320 data:0x0000000031000001
kworker/4:3-114 [004] … 81.524210: rtcpu_vinotify_event: tstamp:3255130683 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:104164148704 data:0x379d550010000000
kworker/4:3-114 [004] … 81.524211: rtcpu_vinotify_event: tstamp:3255130813 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:104164155264 data:0x0000000031000002
vi-output, ar02-2370 [004] … 84.285346: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-114 [004] … 84.328191: rtcpu_vinotify_event: tstamp:3343129429 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:106980091968 data:0x379d580010000000
kworker/4:3-114 [004] … 84.328192: rtcpu_vinotify_event: tstamp:3343129564 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:106980099040 data:0x0000000031000001
kworker/4:3-114 [004] … 84.328193: rtcpu_vinotify_event: tstamp:3343467003 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:106980148960 data:0x379d550010000000
kworker/4:3-114 [004] … 84.328193: rtcpu_vinotify_event: tstamp:3343467138 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:106980155488 data:0x0000000031000002
vi-output, ar02-2370 [004] … 87.101056: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-114 [004] … 87.140190: rtcpu_vinotify_event: tstamp:3431426252 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:109795803904 data:0x379d580010000000
kworker/4:3-114 [004] … 87.140191: rtcpu_vinotify_event: tstamp:3431426390 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:109795810368 data:0x0000000031000001
kworker/4:3-114 [004] … 87.140192: rtcpu_vinotify_event: tstamp:3431426544 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:109795850752 data:0x379d550010000000
kworker/4:3-114 [004] … 87.140192: rtcpu_vinotify_event: tstamp:3431426675 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:109795857280 data:0x0000000031000002
Check the pix_clk_hz or serdes_pix_clk_hz for below notice.
Skew calibration is required if sensor or deserializer is using DPHY, and the output data rate is > 1.5Gbps.
An initiation deskew signal should be sent by sensor or deserializer to perform the skew calibration. If the deskew signals is not sent, the receiver will stall, and the capture will time out.
You can calculate the output data rate with the following equation:
Output data rate = (sensor or deserializer pixel clock in hertz) * (bits per pixel) / (number of CSI lanes)
Hi Shane
As we use CPHY with 2 lanes(trios)
The camera resoultion: 1920x1080, 30Hz, YUV422. Blanking 1.2
The datarate=1920x1080x1.2x30x16 / 2 =597.196Mbps
CPHY: 2.28bits/symbol So we need divide 2.28. The data rate = 597.196/2.28=261.928Mbps
Is it correct?
Thanks
Yutai
Try boost the clocks to try.
sudo su
echo 1 > /sys/kernel/debug/bpmp/debug/clk/vi/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/isp/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/nvcsi/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/emc/mrq_rate_locked
cat /sys/kernel/debug/bpmp/debug/clk/vi/max_rate |tee /sys/kernel/debug/bpmp/debug/clk/vi/rate
cat /sys/kernel/debug/bpmp/debug/clk/isp/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/isp/rate
cat /sys/kernel/debug/bpmp/debug/clk/nvcsi/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/nvcsi/rate
cat /sys/kernel/debug/bpmp/debug/clk/emc/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/emc/rate
hi ShaneCCC:
after boost the clocks, the msg, what is err?
tracer: nop
entries-in-buffer/entries-written: 172/172 #P:8
_-----=> irqs-off
/ _----=> need-resched
| / _—=> hardirq/softirq
|| / _–=> preempt-depth
||| / delay
TASK-PID CPU# |||| TIMESTAMP FUNCTION
| | | |||| | |
kworker/4:3-126 [004] .... 225.588090: rtcpu_string: tstamp:7757475524 id:0x04010000 str:"VM0 deactivating."
gst-launch-1.0-2521 [001] … 269.563154: tegra_channel_open: vi-output, ar0233 30-0050
gst-launch-1.0-2521 [001] … 269.563231: tegra_channel_close: vi-output, ar0233 30-0050
gst-launch-1.0-2521 [001] … 269.563238: tegra_channel_open: vi-output, ar0233 30-0051
gst-launch-1.0-2521 [001] … 269.563303: tegra_channel_close: vi-output, ar0233 30-0051
gst-launch-1.0-2521 [001] … 269.563309: tegra_channel_open: vi-output, ar0233 30-0052
gst-launch-1.0-2521 [001] … 269.563374: tegra_channel_close: vi-output, ar0233 30-0052
gst-launch-1.0-2521 [001] … 269.563380: tegra_channel_open: vi-output, ar0233 30-0053
gst-launch-1.0-2521 [001] … 269.563389: tegra_channel_close: vi-output, ar0233 30-0053
gst-launch-1.0-2521 [001] … 269.586174: tegra_channel_open: vi-output, ar0233 30-0051
v4l2src0:src-2523 [004] … 269.593642: tegra_channel_set_power: ar0233 30-0051 : 0x1
v4l2src0:src-2523 [004] … 269.593652: camera_common_s_power: status : 0x1
v4l2src0:src-2523 [004] … 269.594420: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2src0:src-2523 [004] … 269.594422: csi_s_power: enable : 0x1
v4l2src0:src-2523 [004] … 269.595242: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
v4l2src0:src-2523 [004] … 269.601018: tegra_channel_set_stream: enable : 0x1
v4l2src0:src-2523 [004] … 269.602709: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2src0:src-2523 [004] … 269.602711: csi_s_stream: enable : 0x1
v4l2src0:src-2523 [004] … 269.606824: tegra_channel_set_stream: ar0233 30-0051 : 0x1
kworker/4:3-126 [004] … 269.632069: rtcpu_string: tstamp:9134266390 id:0x04010000 str:“VM0 activating.”
kworker/4:3-126 [004] … 269.632071: rtcpu_string: tstamp:9134338473 id:0x04010000 str:“NVCSILP clock rate = 408000000 Hz.
"
kworker/4:3-126 [004] … 269.632080: rtcpu_string: tstamp:9134546016 id:0x04010000 str:“tegra_nvcsi_stream_set_config(vm0, stream=0, csi”
kworker/4:3-126 [004] … 269.632081: rtcpu_string: tstamp:9134546118 id:0x04010000 str:”=0)
"
kworker/4:3-126 [004] … 269.632083: rtcpu_string: tstamp:9134546489 id:0x04010000 str:“MIPI clock = 900200 kHz, tHS-SETTLE = 0, tCLK-SE”
kworker/4:3-126 [004] … 269.632083: rtcpu_string: tstamp:9134546590 id:0x04010000 str:“TTLE = 0
"
kworker/4:3-126 [004] … 269.632085: rtcpu_string: tstamp:9134546800 id:0x04010000 str:”===== NVCSI Stream Configuration =====
"
kworker/4:3-126 [004] … 269.632086: rtcpu_string: tstamp:9134547045 id:0x04010000 str:"stream_id: PP 0, csi_port: PORT A
"
kworker/4:3-126 [004] … 269.632088: rtcpu_string: tstamp:9134547303 id:0x04010000 str:"Brick: PHY 0, Mode: C-PHY
"
kworker/4:3-126 [004] … 269.632090: rtcpu_string: tstamp:9134547596 id:0x04010000 str:"Partition: CIL A, LP bypass: Disabled, Lanes: 2
"
kworker/4:3-126 [004] … 269.632091: rtcpu_string: tstamp:9134547762 id:0x04010000 str:"Clock information:
"
kworker/4:3-126 [004] … 269.632093: rtcpu_string: tstamp:9134548064 id:0x04010000 str:"MIPI symbol rate: 900.20 MHz
"
kworker/4:3-126 [004] … 269.632094: rtcpu_string: tstamp:9134548308 id:0x04010000 str:“T_HS settle: 0, T_CLK settle: 0
"
kworker/4:3-126 [004] … 269.632096: rtcpu_string: tstamp:9134548543 id:0x04010000 str:”======================================
"
kworker/4:3-126 [004] … 269.632097: rtcpu_string: tstamp:9134550492 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
"
kworker/4:3-126 [004] … 269.632099: rtcpu_string: tstamp:9134556741 id:0x04010000 str:"calibration status1 1ed84010 status2 1f07ce10
"
kworker/4:3-126 [004] … 269.632100: rtcpu_string: tstamp:9134557126 id:0x04010000 str:"calibration status1 1ed84210 status2 1f07ce10
"
kworker/4:3-126 [004] … 269.632101: rtcpu_string: tstamp:9134557768 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:3-126 [004] … 269.632103: rtcpu_string: tstamp:9134558010 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 33
"
kworker/4:3-126 [004] … 269.632104: rtcpu_string: tstamp:9134558277 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:3-126 [004] … 269.632106: rtcpu_string: tstamp:9134558514 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 33
"
kworker/4:3-126 [004] … 269.632107: rtcpu_string: tstamp:9134558767 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 16
"
kworker/4:3-126 [004] … 269.632109: rtcpu_vinotify_event: tstamp:9134707639 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:292300151328 data:0x379d580010000000
kworker/4:3-126 [004] … 269.632110: rtcpu_vinotify_event: tstamp:9134707783 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:292300158176 data:0x0000000031000001
kworker/4:3-126 [004] … 269.632110: rtcpu_vinotify_event: tstamp:9134707940 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:292300193600 data:0x379d550010000000
kworker/4:3-126 [004] … 269.632110: rtcpu_vinotify_event: tstamp:9134708071 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:292300200128 data:0x0000000031000002
kworker/4:3-126 [004] … 269.632110: rtcpu_string: tstamp:9134868805 id:0x04010000 str:"ISR PHY 0 CIL_A 0x20000000
"
kworker/4:3-126 [004] … 269.632113: rtcpu_nvcsi_intr: tstamp:9134868544 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x20000000
vi-output, ar02-2526 [000] … 272.189944: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 272.216072: rtcpu_vinotify_event: tstamp:9215570986 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:294893475680 data:0x379d580010000000
kworker/4:3-126 [004] … 272.216075: rtcpu_vinotify_event: tstamp:9215571121 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:294893482112 data:0x0000000031000001
kworker/4:3-126 [004] … 272.216075: rtcpu_vinotify_event: tstamp:9215909457 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:294900990688 data:0x379d550010000000
kworker/4:3-126 [004] … 272.216075: rtcpu_vinotify_event: tstamp:9215909592 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:294900997248 data:0x0000000031000002
vi-output, ar02-2526 [002] … 275.004472: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 275.076076: rtcpu_vinotify_event: tstamp:9303882218 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297719165312 data:0x379d580010000000
kworker/4:3-126 [004] … 275.076079: rtcpu_vinotify_event: tstamp:9303882355 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297719171744 data:0x0000000031000001
kworker/4:3-126 [004] … 275.076080: rtcpu_vinotify_event: tstamp:9304559037 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297742693248 data:0x379d550010000000
kworker/4:3-126 [004] … 275.076080: rtcpu_vinotify_event: tstamp:9304559173 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297742699808 data:0x0000000031000002
vi-output, ar02-2526 [001] … 277.802160: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 277.876077: rtcpu_vinotify_event: tstamp:9391242402 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300519690560 data:0x379d580010000000
kworker/4:3-126 [004] … 277.876079: rtcpu_vinotify_event: tstamp:9391242539 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300519697024 data:0x0000000031000001
kworker/4:3-126 [004] … 277.876080: rtcpu_vinotify_event: tstamp:9391242692 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300519732864 data:0x379d550010000000
kworker/4:3-126 [004] … 277.876080: rtcpu_vinotify_event: tstamp:9391242826 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300519739424 data:0x0000000031000002
vi-output, ar02-2526 [002] … 280.636489: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 280.680071: rtcpu_vinotify_event: tstamp:9479253937 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:303335789792 data:0x379d580010000000
kworker/4:3-126 [004] … 280.680074: rtcpu_vinotify_event: tstamp:9479254076 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:303335796224 data:0x0000000031000001
kworker/4:3-126 [004] … 280.680075: rtcpu_vinotify_event: tstamp:9479592316 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:303345338048 data:0x379d550010000000
kworker/4:3-126 [004] … 280.680075: rtcpu_vinotify_event: tstamp:9479592451 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:303345344576 data:0x0000000031000002
vi-output, ar02-2526 [002] … 283.452450: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 283.492082: rtcpu_vinotify_event: tstamp:9567559951 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:306151718656 data:0x379d580010000000
kworker/4:3-126 [004] … 283.492084: rtcpu_vinotify_event: tstamp:9567560086 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:306151725120 data:0x0000000031000001
kworker/4:3-126 [004] … 283.492085: rtcpu_vinotify_event: tstamp:9567560239 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:306151765504 data:0x379d550010000000
kworker/4:3-126 [004] … 283.492086: rtcpu_vinotify_event: tstamp:9567560369 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:306151772064 data:0x0000000031000002
vi-output, ar02-2526 [003] … 286.268430: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 286.312092: rtcpu_vinotify_event: tstamp:9655532259 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:308967697600 data:0x379d580010000000
kworker/4:3-126 [004] … 286.312095: rtcpu_vinotify_event: tstamp:9655532398 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:308967704064 data:0x0000000031000001
kworker/4:3-126 [004] … 286.312095: rtcpu_vinotify_event: tstamp:9655532554 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:308967744896 data:0x379d550010000000
kworker/4:3-126 [004] … 286.312096: rtcpu_vinotify_event: tstamp:9655532687 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:308967751456 data:0x0000000031000002
vi-output, ar02-2526 [003] … 289.084424: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 289.124081: rtcpu_vinotify_event: tstamp:9743242665 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:311783694016 data:0x379d580010000000
kworker/4:3-126 [004] … 289.124083: rtcpu_vinotify_event: tstamp:9743242803 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:311783700448 data:0x0000000031000001
kworker/4:3-126 [004] … 289.124084: rtcpu_vinotify_event: tstamp:9743242958 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:311783741248 data:0x379d550010000000
kworker/4:3-126 [004] … 289.124084: rtcpu_vinotify_event: tstamp:9743243089 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:311783747840 data:0x0000000031000002
vi-output, ar02-2526 [003] … 291.900490: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 291.980086: rtcpu_vinotify_event: tstamp:9833305297 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:314664760160 data:0x379d580010000000
kworker/4:3-126 [004] … 291.980089: rtcpu_vinotify_event: tstamp:9833305434 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:314664766624 data:0x0000000031000001
kworker/4:3-126 [004] … 291.980090: rtcpu_vinotify_event: tstamp:9833643675 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:314676378240 data:0x379d550010000000
kworker/4:3-126 [004] … 291.980091: rtcpu_vinotify_event: tstamp:9833643820 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:314676384800 data:0x0000000031000002
vi-output, ar02-2526 [003] … 294.717400: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 294.780079: rtcpu_vinotify_event: tstamp:9919585195 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:317416862720 data:0x379d580010000000
kworker/4:3-126 [004] … 294.780082: rtcpu_vinotify_event: tstamp:9919585334 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:317416869184 data:0x0000000031000001
kworker/4:3-126 [004] … 294.780082: rtcpu_vinotify_event: tstamp:9919585492 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:317416909568 data:0x379d550010000000
kworker/4:3-126 [004] … 294.780083: rtcpu_vinotify_event: tstamp:9919585624 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:317416916096 data:0x0000000031000002
vi-output, ar02-2526 [002] … 297.532551: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 297.580078: rtcpu_vinotify_event: tstamp:10007546578 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:320231822144 data:0x379d580010000000
kworker/4:3-126 [004] … 297.580080: rtcpu_vinotify_event: tstamp:10007546716 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:320231828608 data:0x0000000031000001
kworker/4:3-126 [004] … 297.580081: rtcpu_vinotify_event: tstamp:10007546868 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:320231869376 data:0x379d550010000000
kworker/4:3-126 [004] … 297.580081: rtcpu_vinotify_event: tstamp:10007547000 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:320231875936 data:0x0000000031000002
vi-output, ar02-2526 [001] … 300.348633: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 300.440078: rtcpu_vinotify_event: tstamp:10097195790 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:323108897344 data:0x379d580010000000
kworker/4:3-126 [004] … 300.440082: rtcpu_vinotify_event: tstamp:10097195925 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:323108903776 data:0x0000000031000001
kworker/4:3-126 [004] … 300.440083: rtcpu_vinotify_event: tstamp:10097516575 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:323120506624 data:0x379d550010000000
kworker/4:3-126 [004] … 300.440083: rtcpu_vinotify_event: tstamp:10097516712 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:323120513152 data:0x0000000031000002
vi-output, ar02-2526 [001] … 303.164469: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 303.244228: rtcpu_vinotify_event: tstamp:10185080153 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:325921854976 data:0x379d580010000000
kworker/4:3-126 [004] … 303.244231: rtcpu_vinotify_event: tstamp:10185080290 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:325921861440 data:0x0000000031000001
kworker/4:3-126 [004] … 303.244232: rtcpu_vinotify_event: tstamp:10185418613 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:325933181248 data:0x379d550010000000
kworker/4:3-126 [004] … 303.244232: rtcpu_vinotify_event: tstamp:10185418752 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:325933187776 data:0x0000000031000002
vi-output, ar02-2526 [002] … 305.980504: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 306.044081: rtcpu_vinotify_event: tstamp:10273043422 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:328737232832 data:0x379d580010000000
kworker/4:3-126 [004] … 306.044084: rtcpu_vinotify_event: tstamp:10273043565 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:328737239296 data:0x0000000031000001
kworker/4:3-126 [004] … 306.100436: rtcpu_vinotify_event: tstamp:10273720249 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:328748629760 data:0x379d550010000000
kworker/4:3-126 [004] … 306.100437: rtcpu_vinotify_event: tstamp:10273720393 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:328748636320 data:0x0000000031000002
vi-output, ar02-2526 [003] … 308.796402: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 308.904092: rtcpu_vinotify_event: tstamp:10361344326 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:331552803136 data:0x379d580010000000
kworker/4:3-126 [004] … 308.904095: rtcpu_vinotify_event: tstamp:10361344466 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:331552809600 data:0x0000000031000001
kworker/4:3-126 [004] … 308.904096: rtcpu_vinotify_event: tstamp:10361682640 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:331564898336 data:0x379d550010000000
kworker/4:3-126 [004] … 308.904097: rtcpu_vinotify_event: tstamp:10361682778 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:331564904896 data:0x0000000031000002
vi-output, ar02-2526 [003] … 311.612435: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 311.704094: rtcpu_vinotify_event: tstamp:10448977578 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:334365520288 data:0x379d580010000000
kworker/4:3-126 [004] … 311.704097: rtcpu_vinotify_event: tstamp:10448977718 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:334365526752 data:0x0000000031000001
kworker/4:3-126 [004] … 311.704098: rtcpu_vinotify_event: tstamp:10449275034 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:334376776736 data:0x379d550010000000
kworker/4:3-126 [004] … 311.704098: rtcpu_vinotify_event: tstamp:10449275170 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:334376783296 data:0x0000000031000002
vi-output, ar02-2526 [003] … 314.428455: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 314.512081: rtcpu_vinotify_event: tstamp:10536945572 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:337182131840 data:0x379d580010000000
kworker/4:3-126 [004] … 314.512084: rtcpu_vinotify_event: tstamp:10536945710 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:337182138304 data:0x0000000031000001
kworker/4:3-126 [004] … 314.512085: rtcpu_vinotify_event: tstamp:10537622215 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:337194599904 data:0x379d550010000000
kworker/4:3-126 [004] … 314.512086: rtcpu_vinotify_event: tstamp:10537622358 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:337194606432 data:0x0000000031000002
vi-output, ar02-2526 [003] … 317.244450: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 317.256088: rtcpu_vinotify_event: tstamp:10623549741 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:339943725184 data:0x379d580010000000
kworker/4:3-126 [004] … 317.256091: rtcpu_vinotify_event: tstamp:10623549877 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:339943731616 data:0x0000000031000001
kworker/4:3-126 [004] … 317.256092: rtcpu_vinotify_event: tstamp:10623550034 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:339943772032 data:0x379d550010000000
kworker/4:3-126 [004] … 317.256093: rtcpu_vinotify_event: tstamp:10623550165 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:339943778592 data:0x0000000031000002
vi-output, ar02-2526 [003] … 320.060393: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 320.112090: rtcpu_vinotify_event: tstamp:10711501984 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:342759686112 data:0x379d580010000000
kworker/4:3-126 [004] … 320.112093: rtcpu_vinotify_event: tstamp:10711502123 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:342759692576 data:0x0000000031000001
kworker/4:3-126 [004] … 320.112094: rtcpu_vinotify_event: tstamp:10711502280 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:342759713088 data:0x379d550010000000
kworker/4:3-126 [004] … 320.112095: rtcpu_vinotify_event: tstamp:10711502412 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:342759719648 data:0x0000000031000002
vi-output, ar02-2526 [003] … 322.876500: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 322.912082: rtcpu_vinotify_event: tstamp:10799244750 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:345575761056 data:0x379d580010000000
kworker/4:3-126 [004] … 322.912084: rtcpu_vinotify_event: tstamp:10799244886 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:345575767520 data:0x0000000031000001
kworker/4:3-126 [004] … 322.912085: rtcpu_vinotify_event: tstamp:10799245042 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:345575807968 data:0x379d550010000000
kworker/4:3-126 [004] … 322.912085: rtcpu_vinotify_event: tstamp:10799245177 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:345575814496 data:0x0000000031000002
vi-output, ar02-2526 [003] … 325.692944: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 325.712082: rtcpu_vinotify_event: tstamp:10887281244 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:348392243168 data:0x379d580010000000
kworker/4:3-126 [004] … 325.712084: rtcpu_vinotify_event: tstamp:10887281380 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:348392249632 data:0x0000000031000001
kworker/4:3-126 [004] … 325.712085: rtcpu_vinotify_event: tstamp:10887281531 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:348392290368 data:0x379d550010000000
kworker/4:3-126 [004] … 325.712085: rtcpu_vinotify_event: tstamp:10887281668 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:348392296928 data:0x0000000031000002
vi-output, ar02-2526 [003] … 328.508542: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 328.520088: rtcpu_vinotify_event: tstamp:10975577231 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:351207810240 data:0x379d580010000000
kworker/4:3-126 [004] … 328.520091: rtcpu_vinotify_event: tstamp:10975577372 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:351207816704 data:0x0000000031000001
kworker/4:3-126 [004] … 328.520092: rtcpu_vinotify_event: tstamp:10975577528 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:351207857120 data:0x379d550010000000
kworker/4:3-126 [004] … 328.520093: rtcpu_vinotify_event: tstamp:10975577661 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:351207864064 data:0x0000000031000002
vi-output, ar02-2526 [003] … 331.324459: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 331.432230: rtcpu_vinotify_event: tstamp:11065224658 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:354080484480 data:0x379d580010000000
kworker/4:3-126 [004] … 331.432233: rtcpu_vinotify_event: tstamp:11065224798 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:354080490912 data:0x0000000031000001
kworker/4:3-126 [004] … 331.432234: rtcpu_vinotify_event: tstamp:11065562969 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:354092015808 data:0x379d550010000000
kworker/4:3-126 [004] … 331.432234: rtcpu_vinotify_event: tstamp:11065563106 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:354092022368 data:0x0000000031000002
vi-output, ar02-2526 [003] … 334.140365: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 334.232280: rtcpu_vinotify_event: tstamp:11153187744 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:356897393152 data:0x379d580010000000
kworker/4:3-126 [004] … 334.232284: rtcpu_vinotify_event: tstamp:11153187880 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:356897399616 data:0x0000000031000001
kworker/4:3-126 [004] … 334.232284: rtcpu_vinotify_event: tstamp:11153415222 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:356909263392 data:0x379d550010000000
kworker/4:3-126 [004] … 334.232285: rtcpu_vinotify_event: tstamp:11153415361 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:356909269952 data:0x0000000031000002
vi-output, ar02-2526 [003] … 336.956530: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 337.044078: rtcpu_vinotify_event: tstamp:11241086595 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:359714390112 data:0x379d580010000000
kworker/4:3-126 [004] … 337.044080: rtcpu_vinotify_event: tstamp:11241086734 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:359714396576 data:0x0000000031000001
kworker/4:3-126 [004] … 337.044081: rtcpu_vinotify_event: tstamp:11241763260 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:359727083232 data:0x379d550010000000
kworker/4:3-126 [004] … 337.044081: rtcpu_vinotify_event: tstamp:11241763408 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:359727089792 data:0x0000000031000002
vi-output, ar02-2526 [003] … 339.772655: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 10
kworker/4:3-126 [004] … 339.792081: rtcpu_vinotify_event: tstamp:11327368565 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:362471907424 data:0x379d580010000000
kworker/4:3-126 [004] … 339.792083: rtcpu_vinotify_event: tstamp:11327368701 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:362471913856 data:0x0000000031000001
kworker/4:3-126 [004] … 339.792083: rtcpu_vinotify_event: tstamp:11327368855 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:362471949696 data:0x379d550010000000
kworker/4:3-126 [004] … 339.792084: rtcpu_vinotify_event: tstamp:11327368989 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:362471956224 data:0x0000000031000002
dmesg log :
[ 244.805663] start_addr=(0x20000), end_addr=(0x40000), buffer_size=(0x20000), smp_number_max=(16384)
[ 269.593661] ar0233 30-0051: ar0233_power_on: power on
[ 269.602445] bwmgr API not supported
[ 269.602716] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 269.606345] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
[ 269.606834] ar0233 30-0051: ar0233_start_streaming:
[ 269.607071] max9295 30-0042: max9295_read_reg:i2c read, 0x0 = 84 err=0
[ 269.607398] max9295 30-0042: max9295_read_reg:i2c read, 0x10 = 1 err=0
[ 269.607514] max9295 30-0042: max9295_setup_streaming: g_ctx->csi_mode=4 serdes_csi_link=2 dst_csi_port=0
[ 269.607516] max9295 30-0042: max9295_setup_streaming:csi mode=1 port=6a0c1580 lane_map1=869d0578 lane_map2=9c738998 num_streams=1
[ 269.607518] max9295 30-0042: max9295_setup_streaming:map_pipe_dtype[0].addr=316 map_pipe_dtype[0].val=5e st_en=40
[ 269.607521] max9295 30-0042: max9295_setup_streaming:csi st_pipe=f0 port_sel=62 pipe_en=f3 src_csi_port=1 serdes_csi_link=2
[ 269.607524] max96712 30-006b: max96712_get_available_pipe:priv->pipe[0].dt_type=5e priv->pipe[0].dst_csi_ctrl=1 priv->pipe[0].st_count=0 dst_csi_port=0 st_data_type=5e
[ 269.607526] max96712 30-006b: max96712_get_available_pipe:st_data_type=5e dst_csi_port=0 i=0
[ 269.607528] max96712 30-006b: max96712_setup_pipeline: pipe_id=1
[ 269.607529] max96712 30-006b: max96712_setup_pipeline: pipe_id=1 des_pipe=51 st_id_sel=1 dst_vc=1
[ 269.607699] max96712 30-006b: max96712_write_reg:i2c write, 0x40b = 2 err=0
[ 269.607976] max96712 30-006b: max96712_write_reg:i2c write, 0x8a0 = 81 err=0
[ 269.610237] max9295 30-0042: max9295_write_reg:i2c write, 0x2d3 = 14 err=0
[ 269.610356] max9295 30-0042: max9295_start_streaming: reg0x102=0 reg0x10A=0 err=0
[ 269.632076] [RCE] VM0 deactivating.VM0 activating.NVCSILP clock rate = 408000000 Hz.
[ 269.632081] [RCE] tegra_nvcsi_stream_set_config(vm0, stream=0, csi=0)
[ 269.632084] [RCE] MIPI clock = 900200 kHz, tHS-SETTLE = 0, tCLK-SETTLE = 0
[ 269.632085] [RCE] ===== NVCSI Stream Configuration =====
[ 269.632087] [RCE] stream_id: PP 0, csi_port: PORT A
[ 269.632088] [RCE] Brick: PHY 0, Mode: C-PHY
[ 269.632090] [RCE] Partition: CIL A, LP bypass: Disabled, Lanes: 2
[ 269.632091] [RCE] Clock information:
[ 269.632093] [RCE] MIPI symbol rate: 900.20 MHz
[ 269.632094] [RCE] T_HS settle: 0, T_CLK settle: 0
[ 269.632096] [RCE] ======================================
[ 269.632097] [RCE] tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
[ 269.632099] [RCE] calibration status1 1ed84010 status2 1f07ce10
[ 269.632100] [RCE] calibration status1 1ed84210 status2 1f07ce10
[ 269.632102] [RCE] nvcsi_calc_tclk_settle tclk_settle 16
[ 269.632103] [RCE] nvcsi_calc_ths_settle ths_settle 33
[ 269.632104] [RCE] nvcsi_calc_tclk_settle tclk_settle 16
[ 269.632106] [RCE] nvcsi_calc_ths_settle ths_settle 33
[ 269.632107] [RCE] nvcsi_calc_tclk_settle tclk_settle 16
[ 269.632110] [RCE] ISR PHY 0 CIL_A 0x20000000
[ 272.132733] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 272.141897] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 272.152662] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 272.160697] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[ 272.171522] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 272.171524] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
[ 272.171527] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 272.179260] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 1
[ 272.190092] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 274.948107] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 274.957301] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 274.967656] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 274.975392] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[ 274.986077] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 274.986085] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
[ 274.986087] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 274.993818] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 1
[ 275.004582] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 277.764250] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 277.773443] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 277.783664] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 277.791412] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[ 277.802079] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 277.802086] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
[ 277.802087] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 277.802089] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 1
[ 277.820532] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 280.580100] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 280.589299] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 280.599668] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 280.607410] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[ 280.618102] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 280.618105] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
[ 280.618107] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 280.625836] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 1
[ 280.636629] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 283.396103] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 283.405299] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 283.415656] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 283.423411] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[ 283.434081] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 283.434083] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
[ 283.434086] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 283.441807] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 1
[ 283.452562] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 286.212233] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 286.221405] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 286.231648] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 286.239381] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[ 286.250054] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 286.250057] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
[ 286.250060] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 286.257778] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 1
[ 286.268541] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 289.028084] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 289.037242] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 289.047634] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 289.055366] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[ 289.066037] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 289.066041] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
[ 289.066043] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 289.073774] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 1
[ 289.084536] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[ 291.844251] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[ 291.853441] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[ 291.863696] (NULL device *): vi_capture_control_message: NULL VI channel received
[ 291.871448] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[ 291.882119] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: stream_id=0, csi_port=0 is_cphy=1
[ 291.882121] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_set_config: phy_mode=1, lane_polarity=0 2 numlanes=2
Still didn’t see NVCSI/VI received any validate data from sensor.
Maybe configure the sensor to output discontinuous clock to try.
This topic was automatically closed 14 days after the last reply. New replies are no longer allowed.