Porting camera driver from Jetson Nano to Orin NX

Autonomous Machines Jetson & Embedded Systems Jetson Orin NX
1

Hello,

I am trying to port my current imx214 sensor driver which is working fine in a Jetson Nano to the Orin NX module + Orin Nano carrier.

The OS was built from SDK Manager using JetPack 5.1.2 and L4T 35.4.1

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

uname -a
Linux orinnx-desktop 5.10.120-tegra #15 SMP PREEMPT Tue Feb 6 08:07:22 CET 2024 aarch64 aarch64 aarch64 GNU/Linux

The device node is created and NVCSI bound:

orinnx@orinnx-desktop:~$ sudo dmesg -wHT | grep imx
[vie may 24 06:07:31 2024] imx214 7-0010: tegracam sensor driver:imx214_v2.0.6
[vie may 24 06:07:31 2024] imx214 7-0010: IMX214 sensor found
[vie may 24 06:07:31 2024] imx214 7-0010: tegracam_v4l2subdev_register
[vie may 24 06:07:31 2024] tegra-camrtc-capture-vi tegra-capture-vi: subdev imx214 7-0010 bound
[vie may 24 06:07:31 2024] imx214 7-0010: detected imx214 sensor

Media-ctl output looks like this:

orinnx@orinnx-desktop:~$ sudo media-ctl -p -d /dev/media0
[sudo] password for orinnx: 
Media controller API version 5.10.120

Media device information
------------------------
driver          tegra-camrtc-ca
model           NVIDIA Tegra Video Input Device
serial          
bus info        
hw revision     0x3
driver version  5.10.120

Device topology
- entity 1: 13e40000.host1x:nvcsi@15a00000- (2 pads, 2 links)
            type V4L2 subdev subtype Unknown flags 0
            device node name /dev/v4l-subdev0
	pad0: Sink
		<- "imx214 7-0010":0 [ENABLED]
	pad1: Source
		-> "vi-output, imx214 7-0010":0 [ENABLED]

- entity 4: imx214 7-0010 (1 pad, 1 link)
            type V4L2 subdev subtype Sensor flags 0
            device node name /dev/v4l-subdev1
	pad0: Source
		[fmt:SRGGB10_1X10/4160x3120 field:none colorspace:srgb]
		-> "13e40000.host1x:nvcsi@15a00000-":0 [ENABLED]

- entity 6: vi-output, imx214 7-0010 (1 pad, 1 link)
            type Node subtype V4L flags 0
            device node name /dev/video0
	pad0: Sink
		<- "13e40000.host1x:nvcsi@15a00000-":1 [ENABLED]

For testing purposes, I boost clocks and enable trace logs.

When I trigger my capture command, I get next strange video stream:

orinnx@orinnx-desktop:~$ gst-launch-1.0 nvarguscamerasrc ! queue ! nv3dsink
Setting pipeline to PAUSED ...
Pipeline is live and does not need PREROLL ...
Setting pipeline to PLAYING ...
New clock: GstSystemClock
GST_ARGUS: Creating output stream
CONSUMER: Waiting until producer is connected...
GST_ARGUS: Available Sensor modes :
GST_ARGUS: 4160 x 3120 FR = 33,000000 fps Duration = 30303030 ; Analog Gain range min 0,062500, max 63,937500; Exposure Range min 25000, max 683709000;

GST_ARGUS: Running with following settings:
   Camera index = 0 
   Camera mode  = 0 
   Output Stream W = 4160 H = 3120 
   seconds to Run    = 0 
   Frame Rate = 33,000000 
GST_ARGUS: Setup Complete, Starting captures for 0 seconds
GST_ARGUS: Starting repeat capture requests.
CONSUMER: Producer has connected; continuing.

image
image1920×1119 290 KB

Next logs are shown in dmesg:

[vie may 24 07:39:07 2024] NVRM nvAssertFailedNoLog: Assertion failed: minRequiredIsoBandwidthKBPS <= clientBwValues[DISPLAY_ICC_BW_CLIENT_EXT].minRequiredIsoBandwidthKBPS @ kern_disp_0402.c:111
[vie may 24 07:40:59 2024] bwmgr API not supported
[vie may 24 07:40:59 2024] bwmgr API not supported
[vie may 24 07:40:59 2024] bwmgr API not supported
[vie may 24 07:40:59 2024] bwmgr API not supported
[vie may 24 07:41:10 2024] bwmgr API not supported

And this is in the trace:

# tracer: nop
#
# entries-in-buffer/entries-written: 37465/37465   #P:8
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/4:1-55      [004] ....   234.839478: rtcpu_string: tstamp:7861263916 id:0x04010000 str:"VM0 deactivating."
 gst-plugin-scan-3372    [000] ....   313.615613: tegra_channel_open: vi-output, imx214 7-0010
 gst-plugin-scan-3372    [000] ....   313.615632: tegra_channel_close: vi-output, imx214 7-0010
     kworker/4:1-55      [004] ....   313.785008: rtcpu_string: tstamp:10327161333 id:0x04010000 str:"VM0 activating."
  nvargus-daemon-3374    [004] ....   313.844830: tegra_channel_open: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.844901: tegra_channel_close: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.845649: tegra_channel_open: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.845680: tegra_channel_close: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.865245: tegra_channel_open: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.865260: tegra_channel_close: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.865269: tegra_channel_open: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.865298: tegra_channel_close: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.865313: tegra_channel_open: vi-output, imx214 7-0010
  nvargus-daemon-3374    [004] ....   313.865348: tegra_channel_close: vi-output, imx214 7-0010
 CaptureSchedule-3390    [001] ....   314.054572: tegra_channel_open: vi-output, imx214 7-0010
 CaptureSchedule-3390    [001] ....   314.054733: tegra_channel_set_power: imx214 7-0010 : 0x1
 CaptureSchedule-3390    [001] ....   314.054737: camera_common_s_power: status : 0x1
 CaptureSchedule-3390    [001] ....   314.077873: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
 CaptureSchedule-3390    [001] ....   314.077878: csi_s_power: enable : 0x1
 CaptureSchedule-3390    [001] ....   314.078152: tegra_channel_set_stream: enable : 0x1
 CaptureSchedule-3390    [000] ....   314.079724: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
 CaptureSchedule-3390    [000] ....   314.079727: csi_s_stream: enable : 0x1
 CaptureSchedule-3390    [000] ....   314.079728: tegra_channel_set_stream: imx214 7-0010 : 0x1
     kworker/4:1-55      [004] ....   314.128994: rtcpu_vinotify_event: tstamp:10338689170 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:330837144224 data:0x759e300010000000
     kworker/4:1-55      [004] ....   314.128998: rtcpu_vinotify_event: tstamp:10338689353 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:330837150688 data:0x0000000031000001
     kworker/4:1-55      [004] ....   314.128998: rtcpu_vinotify_event: tstamp:10339031417 cch:1 vi:1 tag:FS channel:0x00 frame:1 vi_tstamp:330838100064 data:0x0000000100000012
     kworker/4:1-55      [004] ....   314.128999: rtcpu_vinotify_event: tstamp:10339031595 cch:1 vi:1 tag:ATOMP_FS channel:0x00 frame:1 vi_tstamp:330838100096 data:0x0000000800000000
     kworker/4:1-55      [004] ....   314.128999: rtcpu_vinotify_event: tstamp:10339031835 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:330838101120 data:0x0000000000004004
     kworker/4:1-55      [004] ....   314.128999: rtcpu_vinotify_event: tstamp:10339032009 cch:1 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:330838110368 data:0x0000000001020001
     kworker/4:1-55      [004] ....   314.129000: rtcpu_vinotify_event: tstamp:10339032239 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:330838209440 data:0x0000000000004001
     kworker/4:1-55      [004] ....   314.129000: rtcpu_vinotify_event: tstamp:10339032417 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:1 vi_tstamp:330846737280 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.129000: rtcpu_vinotify_event: tstamp:10339373802 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:1 vi_tstamp:330855269024 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.185019: rtcpu_vinotify_event: tstamp:10339716187 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:1 vi_tstamp:330863800832 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.185022: rtcpu_vinotify_event: tstamp:10339716408 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:330867545696 data:0x759e2d0010000000
     kworker/4:1-55      [004] ....   314.185023: rtcpu_vinotify_event: tstamp:10339716578 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:330867552320 data:0x0000000031000002
     kworker/4:1-55      [004] ....   314.185024: rtcpu_vinotify_event: tstamp:10340058374 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:1 vi_tstamp:330872332544 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.185025: rtcpu_vinotify_event: tstamp:10340058545 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:1 vi_tstamp:330880864224 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.185025: rtcpu_vinotify_event: tstamp:10340235755 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:330887499136 data:0x000000000c2f4002
     kworker/4:1-55      [004] ....   314.185026: rtcpu_vinotify_event: tstamp:10340235927 cch:1 vi:1 tag:FE channel:0x00 frame:1 vi_tstamp:330887499680 data:0x0000000100000022
     kworker/4:1-55      [004] ....   314.185027: rtcpu_vinotify_event: tstamp:10340571588 cch:1 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:1 vi_tstamp:330887499680 data:0x0004200000000000
     kworker/4:1-55      [004] ....   314.185027: rtcpu_vinotify_event: tstamp:10340571765 cch:1 vi:1 tag:ATOMP_FE channel:0x00 frame:1 vi_tstamp:330887499680 data:0x0000000800000000
     kworker/4:1-55      [004] ....   314.185028: rtcpu_vinotify_event: tstamp:10340571968 cch:1 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:1 vi_tstamp:330887500032 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.185029: rtcpu_vinotify_event: tstamp:10340572137 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:330887542144 data:0x759e300010000000
     kworker/4:1-55      [004] ....   314.185029: rtcpu_vinotify_event: tstamp:10340572337 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:330887548768 data:0x0000000031000003
     kworker/4:1-55      [004] ....   314.185030: rtcpu_vinotify_event: tstamp:10340572507 cch:1 vi:1 tag:FS channel:0x00 frame:2 vi_tstamp:330888026560 data:0x0000000200000012
     kworker/4:1-55      [004] ....   314.185030: rtcpu_vinotify_event: tstamp:10340572710 cch:1 vi:1 tag:ATOMP_FS channel:0x00 frame:2 vi_tstamp:330888026592 data:0x0000000800000000
     kworker/4:1-55      [004] ....   314.185031: rtcpu_vinotify_event: tstamp:10340572879 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:330888027648 data:0x0000000000004004
     kworker/4:1-55      [004] ....   314.185032: rtcpu_vinotify_event: tstamp:10340573077 cch:1 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:330888036896 data:0x0000000001020002
     kworker/4:1-55      [004] ....   314.185033: rtcpu_vinotify_event: tstamp:10340573244 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:330888135968 data:0x0000000000004001
     kworker/4:1-55      [004] ....   314.185033: rtcpu_vinotify_event: tstamp:10340573447 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:2 vi_tstamp:330896663776 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.185034: rtcpu_vinotify_event: tstamp:10340913545 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:2 vi_tstamp:330905195552 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.245007: rtcpu_vinotify_event: tstamp:10341255555 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:2 vi_tstamp:330913727296 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.245010: rtcpu_vinotify_event: tstamp:10341597849 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:2 vi_tstamp:330922259040 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.245011: rtcpu_vinotify_event: tstamp:10341598055 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:2 vi_tstamp:330930790720 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.245011: rtcpu_vinotify_event: tstamp:10341795904 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:330937425600 data:0x000000000c2f4002
     kworker/4:1-55      [004] ....   314.245014: rtcpu_isp_falcon_task_start: tstamp:1752030689 ch:0 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   314.245015: rtcpu_isp_falcon_task_end: tstamp:1752030705 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   314.245016: rtcpu_isp_falcon_task_start: tstamp:1752030723 ch:0 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   314.245016: rtcpu_isp_falcon_task_end: tstamp:1752030736 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   314.245017: rtcpu_isp_falcon_task_start: tstamp:1752030754 ch:0 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   314.245017: rtcpu_isp_falcon_task_end: tstamp:1752030768 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   314.245018: rtcpu_isp_falcon_task_start: tstamp:1752030786 ch:0 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   314.245018: rtcpu_isp_falcon_task_end: tstamp:1752030812 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   314.245020: rtcpu_isp_falcon: tag:0xa tstamp:1752030866 ch:0 seq:0 data:0x10000000 ext_data:0x7427f000
     kworker/4:1-55      [004] ....   314.245020: rtcpu_isp_falcon: tag:0xa tstamp:1752031022 ch:0 seq:1 data:0x21000001 ext_data:0x74286c80
     kworker/4:1-55      [004] ....   314.245021: rtcpu_isp_falcon: tag:0xa tstamp:1752031201 ch:0 seq:1 data:0x32000001 ext_data:0x00000000
     kworker/4:1-55      [004] ....   314.245022: rtcpu_isp_falcon_tile_start: tstamp:1752031374 ch:0 seq:1 tile_x:0 tile_y:0 tile_w:830 tile_h:540
     kworker/4:1-55      [004] ....   314.245024: rtcpu_isp_falcon_tile_end: tstamp:1752039005 ch:0 seq:1 tile_x:0 tile_y:0
     kworker/4:1-55      [004] ....   314.245024: rtcpu_isp_falcon_task_start: tstamp:1752039020 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245024: rtcpu_isp_falcon_tile_start: tstamp:1752039100 ch:0 seq:1 tile_x:1 tile_y:0 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245025: rtcpu_isp_falcon_task_end: tstamp:1752039114 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245026: rtcpu_isp_falcon_tile_end: tstamp:1752046671 ch:0 seq:1 tile_x:1 tile_y:0
     kworker/4:1-55      [004] ....   314.245026: rtcpu_isp_falcon_task_start: tstamp:1752046685 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245026: rtcpu_isp_falcon_tile_start: tstamp:1752046765 ch:0 seq:1 tile_x:2 tile_y:0 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245027: rtcpu_isp_falcon_task_end: tstamp:1752046778 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245027: rtcpu_isp_falcon_tile_end: tstamp:1752054331 ch:0 seq:1 tile_x:2 tile_y:0
     kworker/4:1-55      [004] ....   314.245027: rtcpu_isp_falcon_task_start: tstamp:1752054346 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245028: rtcpu_isp_falcon_tile_start: tstamp:1752054426 ch:0 seq:1 tile_x:3 tile_y:0 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245028: rtcpu_isp_falcon_task_end: tstamp:1752054440 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245029: rtcpu_isp_falcon_tile_end: tstamp:1752061995 ch:0 seq:1 tile_x:3 tile_y:0
     kworker/4:1-55      [004] ....   314.245029: rtcpu_isp_falcon_task_start: tstamp:1752062009 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245029: rtcpu_isp_falcon_tile_start: tstamp:1752062090 ch:0 seq:1 tile_x:4 tile_y:0 tile_w:834 tile_h:540
     kworker/4:1-55      [004] ....   314.245030: rtcpu_isp_falcon_task_end: tstamp:1752062103 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245030: rtcpu_isp_falcon_tile_end: tstamp:1752069543 ch:0 seq:1 tile_x:4 tile_y:0
     kworker/4:1-55      [004] ....   314.245031: rtcpu_isp_falcon_task_start: tstamp:1752069557 ch:0 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   314.245031: rtcpu_isp_falcon_tile_start: tstamp:1752069753 ch:0 seq:1 tile_x:0 tile_y:1 tile_w:830 tile_h:540
     kworker/4:1-55      [004] ....   314.245031: rtcpu_isp_falcon_task_end: tstamp:1752069767 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   314.245032: rtcpu_isp_falcon_tile_end: tstamp:1752077423 ch:0 seq:1 tile_x:0 tile_y:1
     kworker/4:1-55      [004] ....   314.245032: rtcpu_isp_falcon_task_start: tstamp:1752077438 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245032: rtcpu_isp_falcon_tile_start: tstamp:1752077525 ch:0 seq:1 tile_x:1 tile_y:1 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245033: rtcpu_isp_falcon_task_end: tstamp:1752077538 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245033: rtcpu_isp_falcon_tile_end: tstamp:1752085335 ch:0 seq:1 tile_x:1 tile_y:1
     kworker/4:1-55      [004] ....   314.245033: rtcpu_isp_falcon_task_start: tstamp:1752085351 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245034: rtcpu_isp_falcon_tile_start: tstamp:1752085438 ch:0 seq:1 tile_x:2 tile_y:1 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245034: rtcpu_isp_falcon_task_end: tstamp:1752085452 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245035: rtcpu_isp_falcon_tile_end: tstamp:1752093249 ch:0 seq:1 tile_x:2 tile_y:1
     kworker/4:1-55      [004] ....   314.245035: rtcpu_isp_falcon_task_start: tstamp:1752093264 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245035: rtcpu_isp_falcon_tile_start: tstamp:1752093351 ch:0 seq:1 tile_x:3 tile_y:1 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245036: rtcpu_isp_falcon_task_end: tstamp:1752093365 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245036: rtcpu_isp_falcon_tile_end: tstamp:1752101162 ch:0 seq:1 tile_x:3 tile_y:1
     kworker/4:1-55      [004] ....   314.245036: rtcpu_isp_falcon_task_start: tstamp:1752101178 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245037: rtcpu_isp_falcon_tile_start: tstamp:1752101265 ch:0 seq:1 tile_x:4 tile_y:1 tile_w:834 tile_h:540
     kworker/4:1-55      [004] ....   314.245037: rtcpu_isp_falcon_task_end: tstamp:1752101279 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245037: rtcpu_isp_falcon_tile_end: tstamp:1752108983 ch:0 seq:1 tile_x:4 tile_y:1
     kworker/4:1-55      [004] ....   314.245038: rtcpu_isp_falcon_task_start: tstamp:1752108998 ch:0 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   314.245038: rtcpu_isp_falcon_tile_start: tstamp:1752109194 ch:0 seq:1 tile_x:0 tile_y:2 tile_w:830 tile_h:540
     kworker/4:1-55      [004] ....   314.245039: rtcpu_isp_falcon_task_end: tstamp:1752109207 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   314.245039: rtcpu_isp_falcon_tile_end: tstamp:1752116863 ch:0 seq:1 tile_x:0 tile_y:2
     kworker/4:1-55      [004] ....   314.245039: rtcpu_isp_falcon_task_start: tstamp:1752116878 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245040: rtcpu_isp_falcon_tile_start: tstamp:1752116965 ch:0 seq:1 tile_x:1 tile_y:2 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245040: rtcpu_isp_falcon_task_end: tstamp:1752116978 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245040: rtcpu_isp_falcon_tile_end: tstamp:1752124779 ch:0 seq:1 tile_x:1 tile_y:2
     kworker/4:1-55      [004] ....   314.245041: rtcpu_isp_falcon_task_start: tstamp:1752124793 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245041: rtcpu_isp_falcon_tile_start: tstamp:1752124881 ch:0 seq:1 tile_x:2 tile_y:2 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245042: rtcpu_isp_falcon_task_end: tstamp:1752124894 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245042: rtcpu_isp_falcon_task_start: tstamp:1752131086 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   314.245042: rtcpu_isp_falcon_task_end: tstamp:1752131226 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   314.245043: rtcpu_isp_falcon_task_start: tstamp:1752131637 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   314.245043: rtcpu_isp_falcon_task_end: tstamp:1752131776 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   314.245043: rtcpu_isp_falcon_tile_end: tstamp:1752132699 ch:0 seq:1 tile_x:2 tile_y:2
     kworker/4:1-55      [004] ....   314.245044: rtcpu_isp_falcon_task_start: tstamp:1752132714 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245044: rtcpu_isp_falcon_tile_start: tstamp:1752132802 ch:0 seq:1 tile_x:3 tile_y:2 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245045: rtcpu_isp_falcon_task_end: tstamp:1752132815 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245045: rtcpu_isp_falcon_tile_end: tstamp:1752140613 ch:0 seq:1 tile_x:3 tile_y:2
     kworker/4:1-55      [004] ....   314.245045: rtcpu_isp_falcon_task_start: tstamp:1752140627 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245046: rtcpu_isp_falcon_tile_start: tstamp:1752140715 ch:0 seq:1 tile_x:4 tile_y:2 tile_w:834 tile_h:540
     kworker/4:1-55      [004] ....   314.245046: rtcpu_isp_falcon_task_end: tstamp:1752140729 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245046: rtcpu_isp_falcon_task_start: tstamp:1752146802 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   314.245047: rtcpu_isp_falcon_task_end: tstamp:1752146941 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   314.245047: rtcpu_isp_falcon_task_start: tstamp:1752147341 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   314.245050: rtcpu_isp_falcon_task_end: tstamp:1752147480 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   314.245050: rtcpu_isp_falcon_tile_end: tstamp:1752148407 ch:0 seq:1 tile_x:4 tile_y:2
     kworker/4:1-55      [004] ....   314.245051: rtcpu_isp_falcon_task_start: tstamp:1752148422 ch:0 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   314.245051: rtcpu_isp_falcon_tile_start: tstamp:1752148618 ch:0 seq:1 tile_x:0 tile_y:3 tile_w:830 tile_h:540
     kworker/4:1-55      [004] ....   314.245052: rtcpu_isp_falcon_task_end: tstamp:1752148631 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   314.245052: rtcpu_vinotify_event: tstamp:10342111302 cch:1 vi:1 tag:FE channel:0x00 frame:2 vi_tstamp:330937426176 data:0x0000000200000022
     kworker/4:1-55      [004] ....   314.245053: rtcpu_vinotify_event: tstamp:10342111480 cch:1 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:2 vi_tstamp:330937426176 data:0x0004200000000000
     kworker/4:1-55      [004] ....   314.245053: rtcpu_vinotify_event: tstamp:10342111688 cch:1 vi:1 tag:ATOMP_FE channel:0x00 frame:2 vi_tstamp:330937426176 data:0x0000000800000000
     kworker/4:1-55      [004] ....   314.245053: rtcpu_vinotify_event: tstamp:10342111862 cch:1 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:2 vi_tstamp:330937426528 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.245054: rtcpu_vinotify_event: tstamp:10342112059 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:330937469600 data:0x759e2d0010000000
     kworker/4:1-55      [004] ....   314.245054: rtcpu_vinotify_event: tstamp:10342112230 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:330937476192 data:0x0000000031000004
     kworker/4:1-55      [004] ....   314.245055: rtcpu_vinotify_event: tstamp:10342112426 cch:1 vi:1 tag:FS channel:0x00 frame:3 vi_tstamp:330937953056 data:0x0000000300000012
     kworker/4:1-55      [004] ....   314.245055: rtcpu_vinotify_event: tstamp:10342112601 cch:1 vi:1 tag:ATOMP_FS channel:0x00 frame:3 vi_tstamp:330937953056 data:0x0000000800000000
     kworker/4:1-55      [004] ....   314.245055: rtcpu_vinotify_event: tstamp:10342112798 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:3 vi_tstamp:330937954144 data:0x0000000000004004
     kworker/4:1-55      [004] ....   314.245056: rtcpu_vinotify_event: tstamp:10342112972 cch:1 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:330937963392 data:0x0000000001020003
     kworker/4:1-55      [004] ....   314.245056: rtcpu_vinotify_event: tstamp:10342113165 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:3 vi_tstamp:330938062432 data:0x0000000000004001
     kworker/4:1-55      [004] ....   314.245056: rtcpu_vinotify_event: tstamp:10342113333 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:3 vi_tstamp:330946590240 data:0x0000000000000000
     kworker/4:1-55      [004] ....   314.245057: rtcpu_isp_falcon_tile_end: tstamp:1752156303 ch:0 seq:1 tile_x:0 tile_y:3
     kworker/4:1-55      [004] ....   314.245057: rtcpu_isp_falcon_task_start: tstamp:1752156317 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245058: rtcpu_isp_falcon_tile_start: tstamp:1752156405 ch:0 seq:1 tile_x:1 tile_y:3 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245058: rtcpu_isp_falcon_task_end: tstamp:1752156418 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245058: rtcpu_isp_falcon_tile_end: tstamp:1752164223 ch:0 seq:1 tile_x:1 tile_y:3
     kworker/4:1-55      [004] ....   314.245059: rtcpu_isp_falcon_task_start: tstamp:1752164237 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245059: rtcpu_isp_falcon_tile_start: tstamp:1752164324 ch:0 seq:1 tile_x:2 tile_y:3 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245060: rtcpu_isp_falcon_task_end: tstamp:1752164338 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245060: rtcpu_isp_falcon_tile_end: tstamp:1752172148 ch:0 seq:1 tile_x:2 tile_y:3
     kworker/4:1-55      [004] ....   314.245060: rtcpu_isp_falcon_task_start: tstamp:1752172163 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245061: rtcpu_isp_falcon_tile_start: tstamp:1752172250 ch:0 seq:1 tile_x:3 tile_y:3 tile_w:832 tile_h:540
     kworker/4:1-55      [004] ....   314.245061: rtcpu_isp_falcon_task_end: tstamp:1752172263 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245061: rtcpu_isp_falcon_tile_end: tstamp:1752180068 ch:0 seq:1 tile_x:3 tile_y:3
     kworker/4:1-55      [004] ....   314.245062: rtcpu_isp_falcon_task_start: tstamp:1752180083 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   314.245062: rtcpu_isp_falcon_tile_start: tstamp:1752180171 ch:0 seq:1 tile_x:4 tile_y:3 tile_w:834 tile_h:540

... repeated lines ...

     kworker/4:1-55      [004] ....   324.980636: rtcpu_isp_falcon_tile_start: tstamp:2087588792 ch:0 seq:216 tile_x:0 tile_y:5 tile_w:830 tile_h:420
     kworker/4:1-55      [004] ....   324.980637: rtcpu_isp_falcon_task_end: tstamp:2087588805 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   324.980637: rtcpu_isp_falcon_tile_end: tstamp:2087594859 ch:0 seq:216 tile_x:0 tile_y:5
     kworker/4:1-55      [004] ....   324.980638: rtcpu_isp_falcon_task_start: tstamp:2087594874 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   324.980638: rtcpu_isp_falcon_tile_start: tstamp:2087594955 ch:0 seq:216 tile_x:1 tile_y:5 tile_w:832 tile_h:420
     kworker/4:1-55      [004] ....   324.980638: rtcpu_isp_falcon_task_end: tstamp:2087594968 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   324.980639: rtcpu_isp_falcon_tile_end: tstamp:2087601134 ch:0 seq:216 tile_x:1 tile_y:5
     kworker/4:1-55      [004] ....   324.980639: rtcpu_isp_falcon_task_start: tstamp:2087601149 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   324.980639: rtcpu_isp_falcon_tile_start: tstamp:2087601230 ch:0 seq:216 tile_x:2 tile_y:5 tile_w:832 tile_h:420
     kworker/4:1-55      [004] ....   324.980640: rtcpu_isp_falcon_task_end: tstamp:2087601243 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   324.980640: rtcpu_isp_falcon_task_start: tstamp:2087606309 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980641: rtcpu_isp_falcon_task_end: tstamp:2087606448 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980641: rtcpu_isp_falcon_task_start: tstamp:2087606855 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980641: rtcpu_isp_falcon_task_end: tstamp:2087606995 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980642: rtcpu_isp_falcon_tile_end: tstamp:2087607409 ch:0 seq:216 tile_x:2 tile_y:5
     kworker/4:1-55      [004] ....   324.980642: rtcpu_isp_falcon_task_start: tstamp:2087607424 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   324.980642: rtcpu_isp_falcon_tile_start: tstamp:2087607506 ch:0 seq:216 tile_x:3 tile_y:5 tile_w:832 tile_h:420
     kworker/4:1-55      [004] ....   324.980643: rtcpu_isp_falcon_task_end: tstamp:2087607519 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   324.980643: rtcpu_isp_falcon_tile_end: tstamp:2087613682 ch:0 seq:216 tile_x:3 tile_y:5
     kworker/4:1-55      [004] ....   324.980644: rtcpu_isp_falcon_task_start: tstamp:2087613697 ch:0 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   324.980644: rtcpu_isp_falcon_tile_start: tstamp:2087613778 ch:0 seq:216 tile_x:4 tile_y:5 tile_w:834 tile_h:420
     kworker/4:1-55      [004] ....   324.980644: rtcpu_isp_falcon_task_end: tstamp:2087613791 task:SCHED_FINISH_TILE
     kworker/4:1-55      [004] ....   324.980645: rtcpu_isp_falcon_task_start: tstamp:2087618765 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980645: rtcpu_isp_falcon_task_end: tstamp:2087618918 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980646: rtcpu_isp_falcon_task_start: tstamp:2087618943 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980646: rtcpu_isp_falcon_task_end: tstamp:2087619082 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980646: rtcpu_isp_falcon_task_start: tstamp:2087619307 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980647: rtcpu_isp_falcon_task_end: tstamp:2087619447 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980647: rtcpu_isp_falcon_task_start: tstamp:2087619642 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980648: rtcpu_isp_falcon_task_end: tstamp:2087619954 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980648: rtcpu_isp_falcon_task_start: tstamp:2087619978 ch:0 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980648: rtcpu_isp_falcon_task_end: tstamp:2087620117 task:SCHED_HANDLE_STAT
     kworker/4:1-55      [004] ....   324.980649: rtcpu_isp_falcon_tile_end: tstamp:2087620206 ch:0 seq:216 tile_x:4 tile_y:5
     kworker/4:1-55      [004] ....   324.980649: rtcpu_isp_falcon_task_start: tstamp:2087620220 ch:0 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   324.980650: rtcpu_isp_falcon: tag:0x12 tstamp:2087620286 ch:0 seq:0 data:0x00000000 ext_data:0x00000000
     kworker/4:1-55      [004] ....   324.980650: rtcpu_isp_falcon_task_end: tstamp:2087620605 task:SCHED_FINISH_SLICE
     kworker/4:1-55      [004] ....   324.980650: rtcpu_isp_falcon_task_start: tstamp:2087620623 ch:0 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   324.980651: rtcpu_isp_falcon_task_end: tstamp:2087620654 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   324.980651: rtcpu_vinotify_event: tstamp:10677595883 cch:1 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:218 vi_tstamp:341672160288 data:0x00000000010200da
     kworker/4:1-55      [004] ....   324.980652: rtcpu_vinotify_event: tstamp:10677596058 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:218 vi_tstamp:341672258272 data:0x0000000000004001
     kworker/4:1-55      [004] ....   324.980652: rtcpu_vinotify_event: tstamp:10677596259 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:218 vi_tstamp:341680786080 data:0x0000000000000000
     kworker/4:1-55      [004] ....   324.980652: rtcpu_vinotify_event: tstamp:10677938012 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:218 vi_tstamp:341689317856 data:0x0000000000000000
     kworker/4:1-55      [004] ....   324.980653: rtcpu_vinotify_event: tstamp:10678280136 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:218 vi_tstamp:341697849600 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.036558: rtcpu_vinotify_event: tstamp:10678622258 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:218 vi_tstamp:341706381312 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.036562: rtcpu_vinotify_event: tstamp:10678622474 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:218 vi_tstamp:341714913024 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.036562: rtcpu_vinotify_event: tstamp:10678817268 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:218 vi_tstamp:341721547936 data:0x000000000c2f4002
     kworker/4:1-55      [004] ....   325.036563: rtcpu_vinotify_event: tstamp:10678817466 cch:1 vi:1 tag:FE channel:0x00 frame:218 vi_tstamp:341721548480 data:0x000000da00000022
     kworker/4:1-55      [004] ....   325.036564: rtcpu_vinotify_event: tstamp:10678817641 cch:1 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:218 vi_tstamp:341721548480 data:0x0004200000000000
     kworker/4:1-55      [004] ....   325.036564: rtcpu_vinotify_event: tstamp:10678817844 cch:1 vi:1 tag:ATOMP_FE channel:0x00 frame:218 vi_tstamp:341721548512 data:0x0000000800000000
     kworker/4:1-55      [004] ....   325.036565: rtcpu_vinotify_event: tstamp:10678818012 cch:1 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:218 vi_tstamp:341721548864 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.036566: rtcpu_vinotify_event: tstamp:10678818207 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:341721577088 data:0x759e2d0010000000
     kworker/4:1-55      [004] ....   325.036566: rtcpu_vinotify_event: tstamp:10678818375 cch:1 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:341721583680 data:0x00000000310000dc
     kworker/4:1-55      [004] ....   325.036567: rtcpu_vinotify_event: tstamp:10678818574 cch:1 vi:1 tag:FS channel:0x00 frame:219 vi_tstamp:341722075392 data:0x000000db00000012
     kworker/4:1-55      [004] ....   325.036568: rtcpu_vinotify_event: tstamp:10678818745 cch:1 vi:1 tag:ATOMP_FS channel:0x00 frame:219 vi_tstamp:341722075392 data:0x0000000800000000
     kworker/4:1-55      [004] ....   325.036568: rtcpu_vinotify_event: tstamp:10679135313 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:219 vi_tstamp:341722076448 data:0x0000000000004004
     kworker/4:1-55      [004] ....   325.036569: rtcpu_vinotify_event: tstamp:10679135484 cch:1 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:219 vi_tstamp:341722087456 data:0x00000000010200db
     kworker/4:1-55      [004] ....   325.036570: rtcpu_vinotify_event: tstamp:10679135678 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:219 vi_tstamp:341722184768 data:0x0000000000004001
     kworker/4:1-55      [004] ....   325.036570: rtcpu_vinotify_event: tstamp:10679135851 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:219 vi_tstamp:341730712608 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.036571: rtcpu_vinotify_event: tstamp:10679477080 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:219 vi_tstamp:341739244320 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.036572: rtcpu_vinotify_event: tstamp:10679818584 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:219 vi_tstamp:341747776128 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.036572: rtcpu_vinotify_event: tstamp:10680160010 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:219 vi_tstamp:341756307840 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.036573: rtcpu_vinotify_event: tstamp:10680160183 cch:1 vi:1 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:219 vi_tstamp:341764839584 data:0x0000000000000000
 CaptureSchedule-3390    [003] ....   325.039392: tegra_channel_set_stream: enable : 0x0
 CaptureSchedule-3390    [003] ....   325.039396: tegra_channel_set_stream: imx214 7-0010 : 0x0
 CaptureSchedule-3390    [003] ....   325.039584: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
 CaptureSchedule-3390    [003] ....   325.039588: csi_s_stream: enable : 0x0
 CaptureSchedule-3390    [003] ....   325.045426: tegra_channel_set_power: imx214 7-0010 : 0x0
 CaptureSchedule-3390    [003] ....   325.045433: camera_common_s_power: status : 0x0
 CaptureSchedule-3390    [003] ....   325.045473: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
 CaptureSchedule-3390    [003] ....   325.045476: csi_s_power: enable : 0x0
     kworker/4:1-55      [004] ....   325.092569: rtcpu_vinotify_event: tstamp:10680377316 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:219 vi_tstamp:341771474432 data:0x000000000c2f4002
     kworker/4:1-55      [004] ....   325.092572: rtcpu_vinotify_event: tstamp:10680377488 cch:1 vi:1 tag:FE channel:0x00 frame:219 vi_tstamp:341771474976 data:0x000000db00000022
     kworker/4:1-55      [004] ....   325.092572: rtcpu_vinotify_event: tstamp:10680377689 cch:1 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:219 vi_tstamp:341771474976 data:0x0004200000000000
     kworker/4:1-55      [004] ....   325.092573: rtcpu_vinotify_event: tstamp:10680377864 cch:1 vi:1 tag:ATOMP_FE channel:0x00 frame:219 vi_tstamp:341771474976 data:0x0000000800000000
     kworker/4:1-55      [004] ....   325.092576: rtcpu_vinotify_event: tstamp:10680378062 cch:1 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:219 vi_tstamp:341771475360 data:0x0000000000000000
     kworker/4:1-55      [004] ....   325.092576: rtcpu_vinotify_event: tstamp:10680378231 cch:1 vi:1 tag:FS channel:0x00 frame:220 vi_tstamp:341772001856 data:0x000000dc00000012
     kworker/4:1-55      [004] ....   325.092577: rtcpu_vinotify_event: tstamp:10680378435 cch:1 vi:1 tag:ATOMP_FS channel:0x00 frame:220 vi_tstamp:341772001888 data:0x0000000800000000
     kworker/4:1-55      [004] ....   325.092577: rtcpu_vinotify_event: tstamp:10680378606 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:220 vi_tstamp:341772002944 data:0x0000000000004004
     kworker/4:1-55      [004] ....   325.092578: rtcpu_vinotify_event: tstamp:10680706490 cch:1 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:220 vi_tstamp:341772013312 data:0x00000000010200dc
     kworker/4:1-55      [004] ....   325.092578: rtcpu_vinotify_event: tstamp:10680706665 cch:1 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:220 vi_tstamp:341772111264 data:0x0000000000004001
     kworker/4:1-55      [004] ....   325.092579: rtcpu_vinotify_event: tstamp:10680706867 cch:1 vi:1 tag:FE channel:0x00 frame:220 vi_tstamp:341772328160 data:0x000000dc00000022
     kworker/4:1-55      [004] ....   325.092579: rtcpu_vinotify_event: tstamp:10680707041 cch:1 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:220 vi_tstamp:341772328160 data:0x000e200001000000
     kworker/4:1-55      [004] ....   325.092579: rtcpu_vinotify_event: tstamp:10680707242 cch:1 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:220 vi_tstamp:341772328160 data:0x0004200000000000
     kworker/4:1-55      [004] ....   325.092580: rtcpu_vinotify_event: tstamp:10680707413 cch:1 vi:1 tag:ATOMP_FE channel:0x00 frame:220 vi_tstamp:341772328160 data:0x0000000800000000
  nvargus-daemon-3374    [007] ....   330.434401: tegra_channel_close: vi-output, imx214 7-0010
     kworker/4:1-55      [004] ....   330.448364: rtcpu_isp_falcon_task_start: tstamp:2259002533 ch:0 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   330.448370: rtcpu_isp_falcon_task_end: tstamp:2259002576 task:HANDLE_EVENT
     kworker/4:1-55      [004] ....   336.732187: rtcpu_string: tstamp:11044154678 id:0x04010000 str:"VM0 deactivating."

Any idea of what the problem might be or which way to go?

Thank you!

3

hello embedx,

it looks promising. you’ve correct port bindings, and there’re available sensor modes reported by nvarguscamerasrc plugin.

here’re several approaches for issue narrow down.
(1) please refer to Applications Using V4L2 IOCTL Directly.
you may use V4L2 IOCTL to verify basic camera functionality.
for instance,
$ v4l2-ctl -d /dev/video0 --set-fmt-video=width=1920,height=1080,pixelformat=RG10 --set-ctrl bypass_mode=0 --stream-mmap --stream-count=100

(2) since you’re using camera with 4160x3120@33-fps.
are you using a 4K@60HZ monitor for display rendering?
if no… you may try adding video converter to lower the resolution for testing.
for instance,
$ gst-launch-1.0 nvarguscamerasrc ! 'video/x-raw(memory:NVMM),framerate=30/1,format=NV12' ! nvvidconv ! 'video/x-raw, format=(string)I420, width=640, height=480' ! queue ! xvimagesink -e

(3) assume you’re able to fetch the stream via v4l2-ctl in step-(1),
you may further exclude display elements, here’s another sample pipeline to disable preview and shows frame-rate only.
for instance,
$ gst-launch-1.0 nvarguscamerasrc sensor-id=0 sensor-mode=0 ! 'video/x-raw(memory:NVMM),width=1920, height=1080, framerate=30/1, format=NV12' ! nvvidconv ! fpsdisplaysink text-overlay=0 name=sink_0 video-sink=fakesink sync=0 -v

4

Hello Jerrychang,

Thanks for your fast and good explanation.

Following your advise, I use the command V4L2 and I get next traces:

v4l2-ctl -d /dev/video0 --set-fmt-video=width=1920,height=1080,pixelformat=RG10 --set-ctrl bypass_mode=0 --stream-mmap --stream-count=100

# tracer: nop
#
# entries-in-buffer/entries-written: 1342/1342   #P:8
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/3:2-98      [003] ....   121.531008: rtcpu_string: tstamp:4353963068 id:0x04010000 str:"VM0 deactivating."
        v4l2-ctl-3168    [005] ....   124.469941: tegra_channel_open: vi-output, imx214 7-0010
        v4l2-ctl-3168    [005] ....   124.494708: tegra_channel_set_power: imx214 7-0010 : 0x1
        v4l2-ctl-3168    [005] ....   124.494722: camera_common_s_power: status : 0x1
        v4l2-ctl-3168    [005] ....   124.517868: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-3168    [005] ....   124.517871: csi_s_power: enable : 0x1
        v4l2-ctl-3168    [005] ....   124.518913: tegra_channel_capture_setup: vnc_id 0 W 4160 H 3120 fmt c4
        v4l2-ctl-3168    [006] ....   124.526951: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-3168    [006] ....   124.531137: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-3168    [006] ....   124.531142: csi_s_stream: enable : 0x1
        v4l2-ctl-3168    [006] ....   124.531492: tegra_channel_set_stream: imx214 7-0010 : 0x1
     kworker/3:2-98      [003] ....   124.538986: rtcpu_string: tstamp:4448759603 id:0x04010000 str:"VM0 activating."
     kworker/3:2-98      [003] ....   124.538991: rtcpu_vinotify_event: tstamp:4449243063 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:142365659232 data:0x759e300010000000
     kworker/3:2-98      [003] ....   124.538992: rtcpu_vinotify_event: tstamp:4449243240 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:142365665792 data:0x0000000031000001
     kworker/3:2-98      [003] ....   124.538993: rtcpu_vinotify_event: tstamp:4449243443 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:142365711648 data:0x759e2d0010000000
     kworker/3:2-98      [003] ....   124.538993: rtcpu_vinotify_event: tstamp:4449243615 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:142365718240 data:0x0000000031000002
     kworker/3:2-98      [003] ....   124.650980: rtcpu_vinotify_event: tstamp:4451637811 cch:0 vi:1 tag:FS channel:0x00 frame:1 vi_tstamp:142445554720 data:0x0000000100000012
     kworker/3:2-98      [003] ....   124.650982: rtcpu_vinotify_event: tstamp:4451637993 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:1 vi_tstamp:142445554720 data:0x0000000800000000
     kworker/3:2-98      [003] ....   124.650983: rtcpu_vinotify_event: tstamp:4451638198 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:142445555808 data:0x0000000000004004
     kworker/3:2-98      [003] ....   124.650983: rtcpu_vinotify_event: tstamp:4451638370 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:142445565056 data:0x0000000001020001
     kworker/3:2-98      [003] ....   124.650983: rtcpu_vinotify_event: tstamp:4451638568 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:142445664160 data:0x0000000000004001
     kworker/3:2-98      [003] ....   124.706987: rtcpu_vinotify_event: tstamp:4452969209 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:142494954176 data:0x000000000c2f4002
     kworker/3:2-98      [003] ....   124.706991: rtcpu_vinotify_event: tstamp:4452969410 cch:0 vi:1 tag:FE channel:0x00 frame:1 vi_tstamp:142494954720 data:0x0000000100000022
     kworker/3:2-98      [003] ....   124.706992: rtcpu_vinotify_event: tstamp:4452969582 cch:0 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:1 vi_tstamp:142494954720 data:0x0004200000000000
     kworker/3:2-98      [003] ....   124.706992: rtcpu_vinotify_event: tstamp:4452969786 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:1 vi_tstamp:142494954720 data:0x0000000800000000
     kworker/3:2-98      [003] ....   124.706993: rtcpu_vinotify_event: tstamp:4452969953 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:1 vi_tstamp:142494955104 data:0x0000000000000000
     kworker/3:2-98      [003] ....   124.706994: rtcpu_vinotify_event: tstamp:4452970157 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:142494985984 data:0x759e300010000000
     kworker/3:2-98      [003] ....   124.706994: rtcpu_vinotify_event: tstamp:4452970325 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:142494992576 data:0x0000000031000003
     kworker/3:2-98      [003] ....   124.706995: rtcpu_vinotify_event: tstamp:4453282570 cch:0 vi:1 tag:FS channel:0x00 frame:2 vi_tstamp:142495481568 data:0x0000000200000012
     kworker/3:2-98      [003] ....   124.706996: rtcpu_vinotify_event: tstamp:4453282745 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:2 vi_tstamp:142495481568 data:0x0000000800000000
     kworker/3:2-98      [003] ....   124.706996: rtcpu_vinotify_event: tstamp:4453282942 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:142495482624 data:0x0000000000004004
     kworker/3:2-98      [003] ....   124.706997: rtcpu_vinotify_event: tstamp:4453283111 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:142495491904 data:0x0000000001020002
     kworker/3:2-98      [003] ....   124.706998: rtcpu_vinotify_event: tstamp:4453283310 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:142495591008 data:0x0000000000004001
     kworker/3:2-98      [003] ....   124.706998: rtcpu_vinotify_event: tstamp:4454546251 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:142544880992 data:0x000000000c2f4002
     kworker/3:2-98      [003] ....   124.706999: rtcpu_vinotify_event: tstamp:4454546448 cch:0 vi:1 tag:FE channel:0x00 frame:2 vi_tstamp:142544881568 data:0x0000000200000022
     kworker/3:2-98      [003] ....   124.707000: rtcpu_vinotify_event: tstamp:4454546622 cch:0 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:2 vi_tstamp:142544881568 data:0x0004200000000000
     kworker/3:2-98      [003] ....   124.707000: rtcpu_vinotify_event: tstamp:4454546827 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:2 vi_tstamp:142544881568 data:0x0000000800000000
     kworker/3:2-98      [003] ....   124.707001: rtcpu_vinotify_event: tstamp:4454546997 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:2 vi_tstamp:142544881920 data:0x0000000000000000
     kworker/3:2-98      [003] ....   124.707001: rtcpu_vinotify_event: tstamp:4454547195 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:142544913216 data:0x759e2d0010000000
     kworker/3:2-98      [003] ....   124.707002: rtcpu_vinotify_event: tstamp:4454547366 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:142544919840 data:0x0000000031000004
     kworker/3:2-98      [003] ....   124.707002: rtcpu_vinotify_event: tstamp:4454547562 cch:0 vi:1 tag:FS channel:0x00 frame:3 vi_tstamp:142545408416 data:0x0000000300000012
     kworker/3:2-98      [003] ....   124.707003: rtcpu_vinotify_event: tstamp:4454547736 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:3 vi_tstamp:142545408416 data:0x0000000800000000
     kworker/3:2-98      [003] ....   124.707003: rtcpu_vinotify_event: tstamp:4454547933 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:3 vi_tstamp:142545409440 data:0x0000000000004004
     kworker/3:2-98      [003] ....   124.707004: rtcpu_vinotify_event: tstamp:4454548108 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:142545418720 data:0x0000000001020003
     kworker/3:2-98      [003] ....   124.766964: rtcpu_vinotify_event: tstamp:4454859428 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:3 vi_tstamp:142545517824 data:0x0000000000004001
     kworker/3:2-98      [003] ....   124.766966: rtcpu_vinotify_event: tstamp:4456230679 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:3 vi_tstamp:142594807840 data:0x000000000c2f4002
     kworker/3:2-98      [003] ....   124.766966: rtcpu_vinotify_event: tstamp:4456230884 cch:0 vi:1 tag:FE channel:0x00 frame:3 vi_tstamp:142594808384 data:0x0000000300000022
     kworker/3:2-98      [003] ....   124.766966: rtcpu_vinotify_event: tstamp:4456231062 cch:0 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:3 vi_tstamp:142594808416 data:0x0004200000000000
     kworker/3:2-98      [003] ....   124.766966: rtcpu_vinotify_event: tstamp:4456231268 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:3 vi_tstamp:142594808416 data:0x0000000800000000
     kworker/3:2-98      [003] ....   124.766967: rtcpu_vinotify_event: tstamp:4456231443 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:3 vi_tstamp:142594808800 data:0x0000000000000000
     kworker/3:2-98      [003] ....   124.766967: rtcpu_vinotify_event: tstamp:4456231642 cch:0 vi:1 tag:FS channel:0x00 frame:4 vi_tstamp:142595335232 data:0x0000000400000012
     kworker/3:2-98      [003] ....   124.766967: rtcpu_vinotify_event: tstamp:4456231819 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:4 vi_tstamp:142595335232 data:0x0000000800000000
     kworker/3:2-98      [003] ....   124.766967: rtcpu_vinotify_event: tstamp:4456232020 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:4 vi_tstamp:142595336288 data:0x0000000000004004
     kworker/3:2-98      [003] ....   124.766967: rtcpu_vinotify_event: tstamp:4456232191 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:4 vi_tstamp:142595345536 data:0x0000000001020004
     kworker/3:2-98      [003] ....   124.766967: rtcpu_vinotify_event: tstamp:4456232392 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:4 vi_tstamp:142595444640 data:0x0000000000004001
     kworker/3:2-98      [003] ....   124.822973: rtcpu_vinotify_event: tstamp:4457649271 cch:0 vi:1 tag:CHANSEL_FAULT channel:0x23 frame:4 vi_tstamp:142644734688 data:0x000000000c2f4002
     kworker/3:2-98      [003] ....   124.822975: rtcpu_vinotify_event: tstamp:4457649479 cch:0 vi:1 tag:FE channel:0x00 frame:4 vi_tstamp:142644735232 data:0x0000000400000022
     kworker/3:2-98      [003] ....   124.822975: rtcpu_vinotify_event: tstamp:4457649652 cch:0 vi:1 tag:CHANSEL_SHORT_FRAME channel:0x04 frame:4 vi_tstamp:142644735232 data:0x0004200000000000
     kworker/3:2-98      [003] ....   124.822976: rtcpu_vinotify_event: tstamp:4457649853 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:4 vi_tstamp:142644735232 data:0x0000000800000000
     kworker/3:2-98      [003] ....   124.822976: rtcpu_vinotify_event: tstamp:4457650023 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:4 vi_tstamp:142644735616 data:0x0000000000000000
     kworker/3:2-98      [003] ....   124.822979: rtcpu_vinotify_error: tstamp:4457665277 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:5 vi_tstamp:142645263104 data:0x0000000000000249
     kworker/3:2-98      [003] ....   124.822979: rtcpu_vinotify_event: tstamp:4457977269 cch:0 vi:1 tag:FS channel:0x00 frame:5 vi_tstamp:142645262048 data:0x0000000500000012
     kworker/3:2-98      [003] ....   124.822980: rtcpu_vinotify_event: tstamp:4457977439 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:5 vi_tstamp:142645263104 data:0x0000000000000249
     kworker/3:2-98      [003] ....   124.879165: rtcpu_vinotify_error: tstamp:4459225493 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:6 vi_tstamp:142695189952 data:0x0000000000000249
     kworker/3:2-98      [003] ....   124.879170: rtcpu_vinotify_event: tstamp:4459345551 cch:0 vi:1 tag:FE channel:0x00 frame:5 vi_tstamp:142694662048 data:0x0000000500000022
     kworker/3:2-98      [003] ....   124.879170: rtcpu_vinotify_event: tstamp:4459345721 cch:0 vi:1 tag:FS channel:0x00 frame:6 vi_tstamp:142695188864 data:0x0000000600000012
     kworker/3:2-98      [003] ....   124.879171: rtcpu_vinotify_event: tstamp:4459345918 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:6 vi_tstamp:142695189952 data:0x0000000000000249
     kworker/3:2-98      [003] ....   124.934974: rtcpu_vinotify_error: tstamp:4460785705 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:7 vi_tstamp:142745116768 data:0x0000000000000249
     kworker/3:2-98      [003] ....   124.934975: rtcpu_vinotify_event: tstamp:4461056105 cch:0 vi:1 tag:FE channel:0x00 frame:6 vi_tstamp:142744588864 data:0x0000000600000022
     kworker/3:2-98      [003] ....   124.934976: rtcpu_vinotify_event: tstamp:4461056303 cch:0 vi:1 tag:FS channel:0x00 frame:7 vi_tstamp:142745115712 data:0x0000000700000012
     kworker/3:2-98      [003] ....   124.934976: rtcpu_vinotify_event: tstamp:4461056487 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:7 vi_tstamp:142745116768 data:0x0000000000000249
     kworker/3:2-98      [003] ....   124.991241: rtcpu_vinotify_error: tstamp:4462345919 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:8 vi_tstamp:142795043616 data:0x0000000000000249
     kworker/3:2-98      [003] ....   124.991245: rtcpu_vinotify_event: tstamp:4462424296 cch:0 vi:1 tag:FE channel:0x00 frame:7 vi_tstamp:142794515712 data:0x0000000700000022
     kworker/3:2-98      [003] ....   124.991245: rtcpu_vinotify_event: tstamp:4462424467 cch:0 vi:1 tag:FS channel:0x00 frame:8 vi_tstamp:142795042528 data:0x0000000800000012
     kworker/3:2-98      [003] ....   124.991246: rtcpu_vinotify_event: tstamp:4462424665 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:8 vi_tstamp:142795043616 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.047163: rtcpu_vinotify_error: tstamp:4463906137 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:9 vi_tstamp:142844970432 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.047167: rtcpu_vinotify_event: tstamp:4464135150 cch:0 vi:1 tag:FE channel:0x00 frame:8 vi_tstamp:142844442528 data:0x0000000800000022
     kworker/3:2-98      [003] ....   125.047167: rtcpu_vinotify_event: tstamp:4464135353 cch:0 vi:1 tag:FS channel:0x00 frame:9 vi_tstamp:142844969376 data:0x0000000900000012
     kworker/3:2-98      [003] ....   125.047168: rtcpu_vinotify_event: tstamp:4464135525 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:9 vi_tstamp:142844970432 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.102983: rtcpu_vinotify_error: tstamp:4465466328 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:10 vi_tstamp:142894897248 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.102989: rtcpu_vinotify_event: tstamp:4465466828 cch:0 vi:1 tag:FE channel:0x00 frame:9 vi_tstamp:142894369376 data:0x0000000900000022
     kworker/3:2-98      [003] ....   125.102989: rtcpu_vinotify_event: tstamp:4465467001 cch:0 vi:1 tag:FS channel:0x00 frame:10 vi_tstamp:142894896224 data:0x0000000a00000012
     kworker/3:2-98      [003] ....   125.102989: rtcpu_vinotify_event: tstamp:4465777684 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:10 vi_tstamp:142894897248 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.158969: rtcpu_vinotify_error: tstamp:4467026561 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:11 vi_tstamp:142944824096 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.158970: rtcpu_vinotify_event: tstamp:4467147550 cch:0 vi:1 tag:FE channel:0x00 frame:10 vi_tstamp:142944296192 data:0x0000000a00000022
     kworker/3:2-98      [003] ....   125.158970: rtcpu_vinotify_event: tstamp:4467147755 cch:0 vi:1 tag:FS channel:0x00 frame:11 vi_tstamp:142944823040 data:0x0000000b00000012
     kworker/3:2-98      [003] ....   125.158971: rtcpu_vinotify_event: tstamp:4467147929 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:11 vi_tstamp:142944824096 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.158973: rtcpu_vinotify_error: tstamp:4468586771 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:12 vi_tstamp:142994750944 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.214966: rtcpu_vinotify_event: tstamp:4468858938 cch:0 vi:1 tag:FE channel:0x00 frame:11 vi_tstamp:142994223040 data:0x0000000b00000022
     kworker/3:2-98      [003] ....   125.214966: rtcpu_vinotify_event: tstamp:4468859113 cch:0 vi:1 tag:FS channel:0x00 frame:12 vi_tstamp:142994749856 data:0x0000000c00000012
     kworker/3:2-98      [003] ....   125.214967: rtcpu_vinotify_event: tstamp:4468859311 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:12 vi_tstamp:142994750944 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.214967: rtcpu_vinotify_error: tstamp:4470146990 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:13 vi_tstamp:143044677760 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.214968: rtcpu_vinotify_event: tstamp:4470227898 cch:0 vi:1 tag:FE channel:0x00 frame:12 vi_tstamp:143044149856 data:0x0000000c00000022
     kworker/3:2-98      [003] ....   125.214968: rtcpu_vinotify_event: tstamp:4470228095 cch:0 vi:1 tag:FS channel:0x00 frame:13 vi_tstamp:143044676672 data:0x0000000d00000012
     kworker/3:2-98      [003] ....   125.214968: rtcpu_vinotify_event: tstamp:4470228264 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:13 vi_tstamp:143044677760 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.271162: rtcpu_vinotify_error: tstamp:4471707203 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:14 vi_tstamp:143094604576 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.271166: rtcpu_vinotify_event: tstamp:4471939353 cch:0 vi:1 tag:FE channel:0x00 frame:13 vi_tstamp:143094076672 data:0x0000000d00000022
     kworker/3:2-98      [003] ....   125.271167: rtcpu_vinotify_event: tstamp:4471939524 cch:0 vi:1 tag:FS channel:0x00 frame:14 vi_tstamp:143094603520 data:0x0000000e00000012
     kworker/3:2-98      [003] ....   125.271167: rtcpu_vinotify_event: tstamp:4471939720 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:14 vi_tstamp:143094604576 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.327159: rtcpu_vinotify_error: tstamp:4473267394 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:15 vi_tstamp:143144531424 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.327163: rtcpu_vinotify_event: tstamp:4473267894 cch:0 vi:1 tag:FE channel:0x00 frame:14 vi_tstamp:143144003520 data:0x0000000e00000022
     kworker/3:2-98      [003] ....   125.327164: rtcpu_vinotify_event: tstamp:4473268087 cch:0 vi:1 tag:FS channel:0x00 frame:15 vi_tstamp:143144530368 data:0x0000000f00000012
     kworker/3:2-98      [003] ....   125.327164: rtcpu_vinotify_event: tstamp:4473268261 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:15 vi_tstamp:143144531424 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.382982: rtcpu_vinotify_event: tstamp:4474814402 cch:0 vi:1 tag:FE channel:0x00 frame:15 vi_tstamp:143193930368 data:0x0000000f00000022
     kworker/3:2-98      [003] ....   125.382988: rtcpu_vinotify_error: tstamp:4474827624 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:16 vi_tstamp:143194458240 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.382989: rtcpu_vinotify_event: tstamp:4475156680 cch:0 vi:1 tag:FS channel:0x00 frame:16 vi_tstamp:143194457184 data:0x0000001000000012
     kworker/3:2-98      [003] ....   125.382990: rtcpu_vinotify_event: tstamp:4475156880 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:16 vi_tstamp:143194458240 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.438970: rtcpu_vinotify_error: tstamp:4476387838 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:17 vi_tstamp:143244385056 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.438975: rtcpu_vinotify_event: tstamp:4476525917 cch:0 vi:1 tag:FE channel:0x00 frame:16 vi_tstamp:143243857184 data:0x0000001000000022
     kworker/3:2-98      [003] ....   125.438975: rtcpu_vinotify_event: tstamp:4476526114 cch:0 vi:1 tag:FS channel:0x00 frame:17 vi_tstamp:143244384000 data:0x0000001100000012
     kworker/3:2-98      [003] ....   125.438976: rtcpu_vinotify_event: tstamp:4476526284 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:17 vi_tstamp:143244385056 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.494964: rtcpu_vinotify_error: tstamp:4477948055 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:18 vi_tstamp:143294311904 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.494964: rtcpu_vinotify_event: tstamp:4478237426 cch:0 vi:1 tag:FE channel:0x00 frame:17 vi_tstamp:143293784000 data:0x0000001100000022
     kworker/3:2-98      [003] ....   125.494964: rtcpu_vinotify_event: tstamp:4478237598 cch:0 vi:1 tag:FS channel:0x00 frame:18 vi_tstamp:143294310848 data:0x0000001200000012
     kworker/3:2-98      [003] ....   125.494965: rtcpu_vinotify_event: tstamp:4478237805 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:18 vi_tstamp:143294311904 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.550964: rtcpu_vinotify_error: tstamp:4479508270 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:19 vi_tstamp:143344238752 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.550965: rtcpu_vinotify_event: tstamp:4479605743 cch:0 vi:1 tag:FE channel:0x00 frame:18 vi_tstamp:143343710816 data:0x0000001200000022
     kworker/3:2-98      [003] ....   125.550966: rtcpu_vinotify_event: tstamp:4479605949 cch:0 vi:1 tag:FS channel:0x00 frame:19 vi_tstamp:143344237664 data:0x0000001300000012
     kworker/3:2-98      [003] ....   125.550966: rtcpu_vinotify_event: tstamp:4479606123 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:19 vi_tstamp:143344238752 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.606966: rtcpu_vinotify_error: tstamp:4481068482 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:20 vi_tstamp:143394165568 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.606966: rtcpu_vinotify_event: tstamp:4481316081 cch:0 vi:1 tag:FE channel:0x00 frame:19 vi_tstamp:143393637664 data:0x0000001300000022
     kworker/3:2-98      [003] ....   125.606967: rtcpu_vinotify_event: tstamp:4481316256 cch:0 vi:1 tag:FS channel:0x00 frame:20 vi_tstamp:143394164512 data:0x0000001400000012
     kworker/3:2-98      [003] ....   125.606968: rtcpu_vinotify_event: tstamp:4481316460 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:20 vi_tstamp:143394165568 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.606969: rtcpu_vinotify_event: tstamp:4482612101 cch:0 vi:1 tag:FE channel:0x00 frame:20 vi_tstamp:143443564512 data:0x0000001400000022
     kworker/3:2-98      [003] ....   125.606969: rtcpu_vinotify_error: tstamp:4482628692 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:21 vi_tstamp:143444092384 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.662979: rtcpu_vinotify_event: tstamp:4482923869 cch:0 vi:1 tag:FS channel:0x00 frame:21 vi_tstamp:143444091328 data:0x0000001500000012
     kworker/3:2-98      [003] ....   125.662981: rtcpu_vinotify_event: tstamp:4482924042 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:21 vi_tstamp:143444092384 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.662983: rtcpu_vinotify_error: tstamp:4484188907 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:22 vi_tstamp:143494019200 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.662983: rtcpu_vinotify_event: tstamp:4484292215 cch:0 vi:1 tag:FE channel:0x00 frame:21 vi_tstamp:143493491328 data:0x0000001500000022
     kworker/3:2-98      [003] ....   125.662984: rtcpu_vinotify_event: tstamp:4484292385 cch:0 vi:1 tag:FS channel:0x00 frame:22 vi_tstamp:143494018176 data:0x0000001600000012
     kworker/3:2-98      [003] ....   125.662985: rtcpu_vinotify_event: tstamp:4484292587 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:22 vi_tstamp:143494019200 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.718978: rtcpu_vinotify_error: tstamp:4485749123 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:23 vi_tstamp:143543946048 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.718982: rtcpu_vinotify_event: tstamp:4486003611 cch:0 vi:1 tag:FE channel:0x00 frame:22 vi_tstamp:143543418144 data:0x0000001600000022
     kworker/3:2-98      [003] ....   125.718983: rtcpu_vinotify_event: tstamp:4486003812 cch:0 vi:1 tag:FS channel:0x00 frame:23 vi_tstamp:143543944992 data:0x0000001700000012
     kworker/3:2-98      [003] ....   125.718984: rtcpu_vinotify_event: tstamp:4486003986 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:23 vi_tstamp:143543946048 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.775159: rtcpu_vinotify_error: tstamp:4487309332 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:24 vi_tstamp:143593872896 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.775164: rtcpu_vinotify_event: tstamp:4487372325 cch:0 vi:1 tag:FE channel:0x00 frame:23 vi_tstamp:143593344960 data:0x0000001700000022
     kworker/3:2-98      [003] ....   125.775165: rtcpu_vinotify_event: tstamp:4487372493 cch:0 vi:1 tag:FS channel:0x00 frame:24 vi_tstamp:143593871808 data:0x0000001800000012
     kworker/3:2-98      [003] ....   125.775166: rtcpu_vinotify_event: tstamp:4487372694 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:24 vi_tstamp:143593872896 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.830963: rtcpu_vinotify_error: tstamp:4488869547 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:25 vi_tstamp:143643799712 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.830964: rtcpu_vinotify_event: tstamp:4489083085 cch:0 vi:1 tag:FE channel:0x00 frame:24 vi_tstamp:143643271808 data:0x0000001800000022
     kworker/3:2-98      [003] ....   125.830965: rtcpu_vinotify_event: tstamp:4489083288 cch:0 vi:1 tag:FS channel:0x00 frame:25 vi_tstamp:143643798624 data:0x0000001900000012
     kworker/3:2-98      [003] ....   125.830965: rtcpu_vinotify_event: tstamp:4489083458 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:25 vi_tstamp:143643799712 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.887158: rtcpu_vinotify_error: tstamp:4490429738 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:26 vi_tstamp:143693726528 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.887164: rtcpu_vinotify_event: tstamp:4490430235 cch:0 vi:1 tag:FE channel:0x00 frame:25 vi_tstamp:143693198624 data:0x0000001900000022
     kworker/3:2-98      [003] ....   125.887165: rtcpu_vinotify_event: tstamp:4490430403 cch:0 vi:1 tag:FS channel:0x00 frame:26 vi_tstamp:143693725472 data:0x0000001a00000012
     kworker/3:2-98      [003] ....   125.887165: rtcpu_vinotify_event: tstamp:4490759389 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:26 vi_tstamp:143693726528 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.943158: rtcpu_vinotify_error: tstamp:4491989974 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:27 vi_tstamp:143743653344 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.943162: rtcpu_vinotify_event: tstamp:4492127781 cch:0 vi:1 tag:FE channel:0x00 frame:26 vi_tstamp:143743125472 data:0x0000001a00000022
     kworker/3:2-98      [003] ....   125.943163: rtcpu_vinotify_event: tstamp:4492127986 cch:0 vi:1 tag:FS channel:0x00 frame:27 vi_tstamp:143743652320 data:0x0000001b00000012
     kworker/3:2-98      [003] ....   125.943164: rtcpu_vinotify_event: tstamp:4492128160 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:27 vi_tstamp:143743653344 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.999161: rtcpu_vinotify_error: tstamp:4493550188 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:28 vi_tstamp:143793580192 data:0x0000000000000249
     kworker/3:2-98      [003] ....   125.999166: rtcpu_vinotify_event: tstamp:4493838336 cch:0 vi:1 tag:FE channel:0x00 frame:27 vi_tstamp:143793052288 data:0x0000001b00000022
     kworker/3:2-98      [003] ....   125.999167: rtcpu_vinotify_event: tstamp:4493838510 cch:0 vi:1 tag:FS channel:0x00 frame:28 vi_tstamp:143793579136 data:0x0000001c00000012
     kworker/3:2-98      [003] ....   125.999167: rtcpu_vinotify_event: tstamp:4493838711 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:28 vi_tstamp:143793580192 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.055158: rtcpu_vinotify_error: tstamp:4495110403 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:29 vi_tstamp:143843507008 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.055163: rtcpu_vinotify_event: tstamp:4495206845 cch:0 vi:1 tag:FE channel:0x00 frame:28 vi_tstamp:143842979104 data:0x0000001c00000022
     kworker/3:2-98      [003] ....   126.055164: rtcpu_vinotify_event: tstamp:4495207051 cch:0 vi:1 tag:FS channel:0x00 frame:29 vi_tstamp:143843505952 data:0x0000001d00000012
     kworker/3:2-98      [003] ....   126.055164: rtcpu_vinotify_event: tstamp:4495207222 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:29 vi_tstamp:143843507008 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.055165: rtcpu_vinotify_error: tstamp:4496670613 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:30 vi_tstamp:143893433856 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.110981: rtcpu_vinotify_event: tstamp:4496917438 cch:0 vi:1 tag:FE channel:0x00 frame:29 vi_tstamp:143892905952 data:0x0000001d00000022
     kworker/3:2-98      [003] ....   126.110984: rtcpu_vinotify_event: tstamp:4496917610 cch:0 vi:1 tag:FS channel:0x00 frame:30 vi_tstamp:143893432768 data:0x0000001e00000012
     kworker/3:2-98      [003] ....   126.110985: rtcpu_vinotify_event: tstamp:4496917816 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:30 vi_tstamp:143893433856 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.110988: rtcpu_vinotify_error: tstamp:4498230810 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:31 vi_tstamp:143943360672 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.110988: rtcpu_vinotify_event: tstamp:4498231311 cch:0 vi:1 tag:FE channel:0x00 frame:30 vi_tstamp:143942832768 data:0x0000001e00000022
     kworker/3:2-98      [003] ....   126.110990: rtcpu_vinotify_event: tstamp:4498231517 cch:0 vi:1 tag:FS channel:0x00 frame:31 vi_tstamp:143943359616 data:0x0000001f00000012
     kworker/3:2-98      [003] ....   126.110992: rtcpu_vinotify_event: tstamp:4498231690 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:31 vi_tstamp:143943360672 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.166970: rtcpu_vinotify_error: tstamp:4499790989 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:32 vi_tstamp:143993287488 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.166972: rtcpu_vinotify_event: tstamp:4499791494 cch:0 vi:1 tag:FE channel:0x00 frame:31 vi_tstamp:143992759616 data:0x0000001f00000022
     kworker/3:2-98      [003] ....   126.166972: rtcpu_vinotify_event: tstamp:4500133042 cch:0 vi:1 tag:FS channel:0x00 frame:32 vi_tstamp:143993286432 data:0x0000002000000012
     kworker/3:2-98      [003] ....   126.166973: rtcpu_vinotify_event: tstamp:4500133248 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:32 vi_tstamp:143993287488 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.222979: rtcpu_vinotify_error: tstamp:4501351284 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:33 vi_tstamp:144043214304 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.222983: rtcpu_vinotify_event: tstamp:4501501295 cch:0 vi:1 tag:FE channel:0x00 frame:32 vi_tstamp:144042686432 data:0x0000002000000022
     kworker/3:2-98      [003] ....   126.222984: rtcpu_vinotify_event: tstamp:4501501499 cch:0 vi:1 tag:FS channel:0x00 frame:33 vi_tstamp:144043213280 data:0x0000002100000012
     kworker/3:2-98      [003] ....   126.222984: rtcpu_vinotify_event: tstamp:4501501670 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:33 vi_tstamp:144043214304 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.278983: rtcpu_vinotify_error: tstamp:4502911468 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:34 vi_tstamp:144093141152 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.278988: rtcpu_vinotify_event: tstamp:4503212093 cch:0 vi:1 tag:FE channel:0x00 frame:33 vi_tstamp:144092613248 data:0x0000002100000022
     kworker/3:2-98      [003] ....   126.278989: rtcpu_vinotify_event: tstamp:4503212261 cch:0 vi:1 tag:FS channel:0x00 frame:34 vi_tstamp:144093140096 data:0x0000002200000012
     kworker/3:2-98      [003] ....   126.278989: rtcpu_vinotify_event: tstamp:4503212462 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:34 vi_tstamp:144093141152 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.335154: rtcpu_vinotify_error: tstamp:4504471680 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:35 vi_tstamp:144143068000 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.335158: rtcpu_vinotify_event: tstamp:4504581075 cch:0 vi:1 tag:FE channel:0x00 frame:34 vi_tstamp:144142540064 data:0x0000002200000022
     kworker/3:2-98      [003] ....   126.335159: rtcpu_vinotify_event: tstamp:4504581278 cch:0 vi:1 tag:FS channel:0x00 frame:35 vi_tstamp:144143066912 data:0x0000002300000012
     kworker/3:2-98      [003] ....   126.335159: rtcpu_vinotify_event: tstamp:4504581452 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:35 vi_tstamp:144143068000 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.391163: rtcpu_vinotify_error: tstamp:4506031893 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:36 vi_tstamp:144192994816 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.391168: rtcpu_vinotify_event: tstamp:4506291957 cch:0 vi:1 tag:FE channel:0x00 frame:35 vi_tstamp:144192466912 data:0x0000002300000022
     kworker/3:2-98      [003] ....   126.391169: rtcpu_vinotify_event: tstamp:4506292130 cch:0 vi:1 tag:FS channel:0x00 frame:36 vi_tstamp:144192993728 data:0x0000002400000012
     kworker/3:2-98      [003] ....   126.391169: rtcpu_vinotify_event: tstamp:4506292333 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:36 vi_tstamp:144192994816 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.446973: rtcpu_vinotify_event: tstamp:4507575512 cch:0 vi:1 tag:FE channel:0x00 frame:36 vi_tstamp:144242393728 data:0x0000002400000022
     kworker/3:2-98      [003] ....   126.446977: rtcpu_vinotify_error: tstamp:4507592108 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:37 vi_tstamp:144242921632 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.446977: rtcpu_vinotify_event: tstamp:4507900354 cch:0 vi:1 tag:FS channel:0x00 frame:37 vi_tstamp:144242920576 data:0x0000002500000012
     kworker/3:2-98      [003] ....   126.446977: rtcpu_vinotify_event: tstamp:4507900526 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:37 vi_tstamp:144242921632 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.502976: rtcpu_vinotify_error: tstamp:4509152326 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:38 vi_tstamp:144292848448 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.502980: rtcpu_vinotify_event: tstamp:4509269288 cch:0 vi:1 tag:FE channel:0x00 frame:37 vi_tstamp:144292320576 data:0x0000002500000022
     kworker/3:2-98      [003] ....   126.502980: rtcpu_vinotify_event: tstamp:4509269458 cch:0 vi:1 tag:FS channel:0x00 frame:38 vi_tstamp:144292847392 data:0x0000002600000012
     kworker/3:2-98      [003] ....   126.502981: rtcpu_vinotify_event: tstamp:4509269660 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:38 vi_tstamp:144292848448 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.558962: rtcpu_vinotify_error: tstamp:4510712538 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:39 vi_tstamp:144342775264 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.558963: rtcpu_vinotify_event: tstamp:4510980998 cch:0 vi:1 tag:FE channel:0x00 frame:38 vi_tstamp:144342247392 data:0x0000002600000022
     kworker/3:2-98      [003] ....   126.558963: rtcpu_vinotify_event: tstamp:4510981207 cch:0 vi:1 tag:FS channel:0x00 frame:39 vi_tstamp:144342774208 data:0x0000002700000012
     kworker/3:2-98      [003] ....   126.558964: rtcpu_vinotify_event: tstamp:4510981395 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:39 vi_tstamp:144342775264 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.558964: rtcpu_vinotify_error: tstamp:4512272748 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:40 vi_tstamp:144392702112 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.558965: rtcpu_vinotify_event: tstamp:4512349328 cch:0 vi:1 tag:FE channel:0x00 frame:39 vi_tstamp:144392174208 data:0x0000002700000022
     kworker/3:2-98      [003] ....   126.558965: rtcpu_vinotify_event: tstamp:4512349498 cch:0 vi:1 tag:FS channel:0x00 frame:40 vi_tstamp:144392701056 data:0x0000002800000012
     kworker/3:2-98      [003] ....   126.558966: rtcpu_vinotify_event: tstamp:4512349697 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:40 vi_tstamp:144392702112 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.614960: rtcpu_vinotify_error: tstamp:4513832960 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:41 vi_tstamp:144442628960 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.614961: rtcpu_vinotify_event: tstamp:4514060159 cch:0 vi:1 tag:FE channel:0x00 frame:40 vi_tstamp:144442101024 data:0x0000002800000022
     kworker/3:2-98      [003] ....   126.614961: rtcpu_vinotify_event: tstamp:4514060362 cch:0 vi:1 tag:FS channel:0x00 frame:41 vi_tstamp:144442627872 data:0x0000002900000012
     kworker/3:2-98      [003] ....   126.614962: rtcpu_vinotify_event: tstamp:4514060530 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:41 vi_tstamp:144442628960 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.670977: rtcpu_vinotify_error: tstamp:4515393152 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:42 vi_tstamp:144492555776 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.670979: rtcpu_vinotify_event: tstamp:4515393653 cch:0 vi:1 tag:FE channel:0x00 frame:41 vi_tstamp:144492027872 data:0x0000002900000022
     kworker/3:2-98      [003] ....   126.670980: rtcpu_vinotify_event: tstamp:4515393823 cch:0 vi:1 tag:FS channel:0x00 frame:42 vi_tstamp:144492554720 data:0x0000002a00000012
     kworker/3:2-98      [003] ....   126.670980: rtcpu_vinotify_event: tstamp:4515702470 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:42 vi_tstamp:144492555776 data:0x0000000000000249
     kworker/3:2-98      [003] ....   126.726965: rtcpu_vinotify_error: tstamp:4516953388 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:43 vi_tstamp:144542482592 data:0x0000000000000249

	... repeated logs ...


     kworker/3:2-98      [003] ....   140.466909: rtcpu_vinotify_event: tstamp:4946240963 cch:0 vi:1 tag:FE channel:0x00 frame:63 vi_tstamp:158271826336 data:0x0000003f00000022
     kworker/3:2-98      [003] ....   140.466909: rtcpu_vinotify_event: tstamp:4946241137 cch:0 vi:1 tag:FS channel:0x00 frame:64 vi_tstamp:158272353184 data:0x0000004000000012
     kworker/3:2-98      [003] ....   140.466910: rtcpu_vinotify_event: tstamp:4946241339 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:64 vi_tstamp:158272354240 data:0x0000000000000249
     kworker/3:2-98      [003] ....   140.522892: rtcpu_vinotify_error: tstamp:4947572073 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:65 vi_tstamp:158322281024 data:0x0000000000000249
     kworker/3:2-98      [003] ....   140.522893: rtcpu_vinotify_event: tstamp:4947572575 cch:0 vi:1 tag:FE channel:0x00 frame:64 vi_tstamp:158321753120 data:0x0000004000000022
     kworker/3:2-98      [003] ....   140.522894: rtcpu_vinotify_event: tstamp:4947572784 cch:0 vi:1 tag:FS channel:0x00 frame:65 vi_tstamp:158322279968 data:0x0000004100000012
     kworker/3:2-98      [003] ....   140.522894: rtcpu_vinotify_event: tstamp:4947572956 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:65 vi_tstamp:158322281024 data:0x0000000000000249
     kworker/3:2-98      [003] ....   140.578884: rtcpu_vinotify_error: tstamp:4949132335 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:66 vi_tstamp:158372207808 data:0x0000000000000249
     kworker/3:2-98      [003] ....   140.578885: rtcpu_vinotify_event: tstamp:4949149432 cch:0 vi:1 tag:FE channel:0x00 frame:65 vi_tstamp:158371679904 data:0x0000004100000022
     kworker/3:2-98      [003] ....   140.578885: rtcpu_vinotify_event: tstamp:4949149606 cch:0 vi:1 tag:FS channel:0x00 frame:66 vi_tstamp:158372206752 data:0x0000004200000012
     kworker/3:2-98      [003] ....   140.578886: rtcpu_vinotify_event: tstamp:4949149804 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:66 vi_tstamp:158372207808 data:0x0000000000000249
     kworker/3:2-98      [003] ....   140.634877: rtcpu_vinotify_error: tstamp:4950692514 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:67 vi_tstamp:158422134592 data:0x0000000000000249
     kworker/3:2-98      [003] ....   140.634878: rtcpu_vinotify_event: tstamp:4950859857 cch:0 vi:1 tag:FE channel:0x00 frame:66 vi_tstamp:158421606720 data:0x0000004200000022
     kworker/3:2-98      [003] ....   140.634878: rtcpu_vinotify_event: tstamp:4950860058 cch:0 vi:1 tag:FS channel:0x00 frame:67 vi_tstamp:158422133536 data:0x0000004300000012
     kworker/3:2-98      [003] ....   140.634879: rtcpu_vinotify_event: tstamp:4950860229 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:67 vi_tstamp:158422134592 data:0x0000000000000249
     kworker/3:2-98      [003] ....   140.634879: rtcpu_vinotify_error: tstamp:4952252726 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:68 vi_tstamp:158472061408 data:0x0000000000000249
        v4l2-ctl-3168    [003] ....   140.655407: tegra_channel_close: vi-output, imx214 7-0010
        v4l2-ctl-3168    [003] ....   140.655459: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-3168    [003] ....   140.655461: tegra_channel_set_stream: imx214 7-0010 : 0x0
        v4l2-ctl-3168    [003] ....   140.655664: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-3168    [003] ....   140.655667: csi_s_stream: enable : 0x0
        v4l2-ctl-3168    [003] ....   140.659026: tegra_channel_set_power: imx214 7-0010 : 0x0
        v4l2-ctl-3168    [003] ....   140.659035: camera_common_s_power: status : 0x0
        v4l2-ctl-3168    [003] ....   140.659081: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-3168    [003] ....   140.659084: csi_s_power: enable : 0x0
     kworker/3:2-98      [003] ....   140.690876: rtcpu_vinotify_event: tstamp:4952570778 cch:0 vi:1 tag:FE channel:0x00 frame:67 vi_tstamp:158471533472 data:0x0000004300000022
     kworker/3:2-98      [003] ....   140.690879: rtcpu_vinotify_event: tstamp:4952570949 cch:0 vi:1 tag:FS channel:0x00 frame:68 vi_tstamp:158472060320 data:0x0000004400000012
     kworker/3:2-98      [003] ....   140.690880: rtcpu_vinotify_event: tstamp:4952571150 cch:0 vi:1 tag:CHANSEL_NOMATCH channel:0x04 frame:68 vi_tstamp:158472061408 data:0x0000000000000249
     kworker/3:2-98      [003] ....   140.690880: rtcpu_vinotify_event: tstamp:4953255262 cch:-1 vi:1 tag:FE channel:0x00 frame:68 vi_tstamp:158494728640 data:0x0000004400000022
     kworker/3:2-98      [003] ....   146.682496: rtcpu_string: tstamp:5139925499 id:0x04010000 str:"VM0 deactivating."

Just for information about the sensor:

orinnx@orinnx-desktop:~$ v4l2-ctl -d /dev/video0 --list-formats-ext
ioctl: VIDIOC_ENUM_FMT
	Type: Video Capture

	[0]: 'RG10' (10-bit Bayer RGRG/GBGB)
		Size: Discrete 4160x3120
			Interval: Discrete 0.050s (20.000 fps)

Looking another similar cases, they reported as a solution that this could be due to a embedded metadata height problem, but I tried some options and nothing changed. Anyway, it is working with embedded_metadata_height=0 in Jetson Nano.

Any suggestion to try?

Thanks!

5

hello embedx,

just an FYI, compare to Nano series, Orin is more critical (especially those device tree settings) to MIPI signaling,

is your sensor output embedded metadata actually?
please see-also similar thread, Topic 293730.
there’s CHANSEL_FAULT reported with error code 0x04004. this is failure related the embedded data. please confirm whether you’re outputting embedded metadata. you may also review your device tree property, embedded_metadata_height.

BTW,
here’s one thing it worth a try by revise the v4l2 cmd as same as your sensor capability.
$ v4l2-ctl -d /dev/video0 --set-fmt-video=width=4160,height=3120,pixelformat=RG10 --set-ctrl bypass_mode=0 --stream-mmap --stream-count=100

6

Hello JerryChang,

I was checking and the sensor is outputting 4 metadata lines, so I modified embedded_metadata_height to 4 in the DTS.

image
image1300×1210 59 KB

Now when I test with V4L2 command I get next logs and traces:

orinnx@orinnx-desktop:~$ v4l2-ctl -d /dev/video0 --set-fmt-video=width=4160,height=3120,pixelformat=RG10 --set-ctrl bypass_mode=0 --stream-mmap --stream-count=100
<<<<<<<<<<<<<<<<<<<<<< 20.03 fps
<<<<<<<<<<<<<<<<<<<< 20.03 fps
<<<<<<<<<<<<<<<<<<<< 20.03 fps
<<<<<<<<<<<<<<<<<<<< 20.03 fps
<<<<<<<<<<<<<<<<<<

Traces:

# tracer: nop
#
# entries-in-buffer/entries-written: 1842/1842   #P:8
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/3:2-99      [003] ....    44.982212: rtcpu_string: tstamp:1928518089 id:0x04010000 str:"VM0 deactivating."
        v4l2-ctl-3071    [005] ....    77.837159: tegra_channel_open: vi-output, imx214 7-0010
        v4l2-ctl-3071    [005] ....    77.861907: tegra_channel_set_power: imx214 7-0010 : 0x1
        v4l2-ctl-3071    [005] ....    77.861921: camera_common_s_power: status : 0x1
        v4l2-ctl-3071    [005] ....    77.885055: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-3071    [005] ....    77.885058: csi_s_power: enable : 0x1
        v4l2-ctl-3071    [005] ....    77.886161: tegra_channel_capture_setup: vnc_id 0 W 4160 H 3120 fmt c4
        v4l2-ctl-3071    [006] ....    77.893310: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-3071    [006] ....    77.896395: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-3071    [006] ....    77.896401: csi_s_stream: enable : 0x1
        v4l2-ctl-3071    [006] ....    77.896731: tegra_channel_set_stream: imx214 7-0010 : 0x1
     kworker/3:2-99      [003] ....    77.910006: rtcpu_string: tstamp:2957668455 id:0x04010000 str:"VM0 activating."
     kworker/3:2-99      [003] ....    77.910012: rtcpu_vinotify_event: tstamp:2958131021 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94649898720 data:0x759e300010000000
     kworker/3:2-99      [003] ....    77.910013: rtcpu_vinotify_event: tstamp:2958131218 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94649905216 data:0x0000000031000001
     kworker/3:2-99      [003] ....    77.910013: rtcpu_vinotify_event: tstamp:2958131413 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94649950976 data:0x759e2d0010000000
     kworker/3:2-99      [003] ....    77.910014: rtcpu_vinotify_event: tstamp:2958131581 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94649957600 data:0x0000000031000002
     kworker/3:2-99      [003] ....    78.021991: rtcpu_vinotify_event: tstamp:2960861187 cch:0 vi:1 tag:FS channel:0x00 frame:1 vi_tstamp:94737501600 data:0x0000000100000012
     kworker/3:2-99      [003] ....    78.021993: rtcpu_vinotify_event: tstamp:2960861358 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:1 vi_tstamp:94737501600 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.021993: rtcpu_vinotify_event: tstamp:2960861562 cch:0 vi:1 tag:CHANSEL_EMBED_SOF channel:0x23 frame:1 vi_tstamp:94737502624 data:0x0000000000000004
     kworker/3:2-99      [003] ....    78.021994: rtcpu_vinotify_event: tstamp:2960861729 cch:0 vi:1 tag:CHANSEL_EMBED_EOF channel:0x23 frame:1 vi_tstamp:94737556896 data:0x0000000000030008
     kworker/3:2-99      [003] ....    78.021994: rtcpu_vinotify_event: tstamp:2960861930 cch:0 vi:1 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:1 vi_tstamp:94737557728 data:0x0000000000000000
     kworker/3:2-99      [003] ....    78.021995: rtcpu_vinotify_event: tstamp:2960862104 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:94737559968 data:0x0000000003020001
     kworker/3:2-99      [003] ....    78.021995: rtcpu_vinotify_event: tstamp:2960862305 cch:0 vi:1 tag:CHANSEL_PXL_SOF channel:0x23 frame:1 vi_tstamp:94737611008 data:0x0000000000000001
     kworker/3:2-99      [003] ....    78.021995: rtcpu_vinotify_event: tstamp:2960862473 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:94737612576 data:0x0000000008020001
 vi-output, imx2-3073    [005] ....    78.030379: tegra_channel_capture_frame: sof:94.737611008
 vi-output, imx2-3073    [005] ....    78.030382: tegra_channel_capture_frame: eof:94.786901664
 vi-output, imx2-3073    [005] ....    78.080294: tegra_channel_capture_frame: sof:94.787537568
 vi-output, imx2-3073    [005] ....    78.080296: tegra_channel_capture_frame: eof:94.836828192
     kworker/3:2-99      [003] ....    78.081976: rtcpu_vinotify_event: tstamp:2962092983 cch:0 vi:1 tag:CHANSEL_PXL_EOF channel:0x23 frame:1 vi_tstamp:94786900736 data:0x000000000c2f0002
     kworker/3:2-99      [003] ....    78.081978: rtcpu_vinotify_event: tstamp:2962093154 cch:0 vi:1 tag:FE channel:0x00 frame:1 vi_tstamp:94786901280 data:0x0000000100000022
     kworker/3:2-99      [003] ....    78.081978: rtcpu_vinotify_event: tstamp:2962093358 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:1 vi_tstamp:94786901280 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.081978: rtcpu_vinotify_event: tstamp:2962093530 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:1 vi_tstamp:94786901664 data:0x0000000000000000
     kworker/3:2-99      [003] ....    78.081979: rtcpu_vinotify_event: tstamp:2962431852 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:94786909440 data:0x0000000002020001
     kworker/3:2-99      [003] ....    78.081979: rtcpu_vinotify_event: tstamp:2962432026 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:94786911744 data:0x0000000000020001
     kworker/3:2-99      [003] ....    78.081979: rtcpu_vinotify_event: tstamp:2962432223 cch:0 vi:1 tag:FS channel:0x00 frame:2 vi_tstamp:94787428096 data:0x0000000200000012
     kworker/3:2-99      [003] ....    78.081979: rtcpu_vinotify_event: tstamp:2962432396 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:2 vi_tstamp:94787428128 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.081980: rtcpu_vinotify_event: tstamp:2962432594 cch:0 vi:1 tag:CHANSEL_EMBED_SOF channel:0x23 frame:2 vi_tstamp:94787429184 data:0x0000000000000004
     kworker/3:2-99      [003] ....    78.081980: rtcpu_vinotify_event: tstamp:2962432764 cch:0 vi:1 tag:CHANSEL_EMBED_EOF channel:0x23 frame:2 vi_tstamp:94787483456 data:0x0000000000030008
     kworker/3:2-99      [003] ....    78.081980: rtcpu_vinotify_event: tstamp:2962432958 cch:0 vi:1 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:2 vi_tstamp:94787484288 data:0x0000000000000000
     kworker/3:2-99      [003] ....    78.081980: rtcpu_vinotify_event: tstamp:2962433131 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:94787486496 data:0x0000000003020002
     kworker/3:2-99      [003] ....    78.081981: rtcpu_vinotify_event: tstamp:2962433324 cch:0 vi:1 tag:CHANSEL_PXL_SOF channel:0x23 frame:2 vi_tstamp:94787537568 data:0x0000000000000001
     kworker/3:2-99      [003] ....    78.081981: rtcpu_vinotify_event: tstamp:2962433490 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:94787539136 data:0x0000000008020002
     kworker/3:2-99      [003] ....    78.081981: rtcpu_vinotify_event: tstamp:2962433682 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94787612416 data:0x759e300010000000
     kworker/3:2-99      [003] ....    78.081982: rtcpu_vinotify_event: tstamp:2962433849 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94787619008 data:0x0000000031000003
     kworker/3:2-99      [003] ....    78.081982: rtcpu_vinotify_event: tstamp:2963653146 cch:0 vi:1 tag:CHANSEL_PXL_EOF channel:0x23 frame:2 vi_tstamp:94836827232 data:0x000000000c2f0002
     kworker/3:2-99      [003] ....    78.081982: rtcpu_vinotify_event: tstamp:2963653316 cch:0 vi:1 tag:FE channel:0x00 frame:2 vi_tstamp:94836827808 data:0x0000000200000022
     kworker/3:2-99      [003] ....    78.081982: rtcpu_vinotify_event: tstamp:2963653515 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:2 vi_tstamp:94836827808 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.081983: rtcpu_vinotify_event: tstamp:2963653683 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:2 vi_tstamp:94836828192 data:0x0000000000000000
 vi-output, imx2-3073    [005] ....    78.130217: tegra_channel_capture_frame: sof:94.837464064
 vi-output, imx2-3073    [005] ....    78.130219: tegra_channel_capture_frame: eof:94.886754752
     kworker/3:2-99      [003] ....    78.137992: rtcpu_vinotify_event: tstamp:2963968504 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:94836835936 data:0x0000000002020002
     kworker/3:2-99      [003] ....    78.137994: rtcpu_vinotify_event: tstamp:2963968674 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:94836838272 data:0x0000000000020002
     kworker/3:2-99      [003] ....    78.137995: rtcpu_vinotify_event: tstamp:2963968871 cch:0 vi:1 tag:FS channel:0x00 frame:3 vi_tstamp:94837354624 data:0x0000000300000012
     kworker/3:2-99      [003] ....    78.137995: rtcpu_vinotify_event: tstamp:2963969042 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:3 vi_tstamp:94837354624 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.137995: rtcpu_vinotify_event: tstamp:2963969242 cch:0 vi:1 tag:CHANSEL_EMBED_SOF channel:0x23 frame:3 vi_tstamp:94837355712 data:0x0000000000000004
     kworker/3:2-99      [003] ....    78.137996: rtcpu_vinotify_event: tstamp:2963969413 cch:0 vi:1 tag:CHANSEL_EMBED_EOF channel:0x23 frame:3 vi_tstamp:94837409952 data:0x0000000000030008
     kworker/3:2-99      [003] ....    78.137996: rtcpu_vinotify_event: tstamp:2963969617 cch:0 vi:1 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:3 vi_tstamp:94837410816 data:0x0000000000000000
     kworker/3:2-99      [003] ....    78.137997: rtcpu_vinotify_event: tstamp:2963969787 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:94837412992 data:0x0000000003020003
     kworker/3:2-99      [003] ....    78.137997: rtcpu_vinotify_event: tstamp:2963969990 cch:0 vi:1 tag:CHANSEL_PXL_SOF channel:0x23 frame:3 vi_tstamp:94837464064 data:0x0000000000000001
     kworker/3:2-99      [003] ....    78.137998: rtcpu_vinotify_event: tstamp:2963970160 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:94837465632 data:0x0000000008020003
     kworker/3:2-99      [003] ....    78.137998: rtcpu_vinotify_event: tstamp:2963970357 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94837539392 data:0x759e2d0010000000
     kworker/3:2-99      [003] ....    78.137999: rtcpu_vinotify_event: tstamp:2963970528 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94837545984 data:0x0000000031000004
     kworker/3:2-99      [003] ....    78.138000: rtcpu_vinotify_event: tstamp:2965213378 cch:0 vi:1 tag:CHANSEL_PXL_EOF channel:0x23 frame:3 vi_tstamp:94886753792 data:0x000000000c2f0002
     kworker/3:2-99      [003] ....    78.138000: rtcpu_vinotify_event: tstamp:2965213550 cch:0 vi:1 tag:FE channel:0x00 frame:3 vi_tstamp:94886754336 data:0x0000000300000022
     kworker/3:2-99      [003] ....    78.138000: rtcpu_vinotify_event: tstamp:2965213756 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:3 vi_tstamp:94886754368 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.138001: rtcpu_vinotify_event: tstamp:2965213927 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:3 vi_tstamp:94886754752 data:0x0000000000000000
 vi-output, imx2-3073    [005] ....    78.180134: tegra_channel_capture_frame: sof:94.887390592
 vi-output, imx2-3073    [005] ....    78.180135: tegra_channel_capture_frame: eof:94.936681280
     kworker/3:2-99      [003] ....    78.193995: rtcpu_vinotify_event: tstamp:2965539523 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:94886762496 data:0x0000000002020003
     kworker/3:2-99      [003] ....    78.193997: rtcpu_vinotify_event: tstamp:2965539708 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:94886764800 data:0x0000000000020003
     kworker/3:2-99      [003] ....    78.193998: rtcpu_vinotify_event: tstamp:2965539915 cch:0 vi:1 tag:FS channel:0x00 frame:4 vi_tstamp:94887281152 data:0x0000000400000012
     kworker/3:2-99      [003] ....    78.193999: rtcpu_vinotify_event: tstamp:2965540102 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:4 vi_tstamp:94887281184 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.193999: rtcpu_vinotify_event: tstamp:2965540300 cch:0 vi:1 tag:CHANSEL_EMBED_SOF channel:0x23 frame:4 vi_tstamp:94887282208 data:0x0000000000000004
     kworker/3:2-99      [003] ....    78.194000: rtcpu_vinotify_event: tstamp:2965540471 cch:0 vi:1 tag:CHANSEL_EMBED_EOF channel:0x23 frame:4 vi_tstamp:94887336480 data:0x0000000000030008
     kworker/3:2-99      [003] ....    78.194001: rtcpu_vinotify_event: tstamp:2965540671 cch:0 vi:1 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:4 vi_tstamp:94887337344 data:0x0000000000000000
     kworker/3:2-99      [003] ....    78.194001: rtcpu_vinotify_event: tstamp:2965540842 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:4 vi_tstamp:94887339552 data:0x0000000003020004
     kworker/3:2-99      [003] ....    78.194002: rtcpu_vinotify_event: tstamp:2965541044 cch:0 vi:1 tag:CHANSEL_PXL_SOF channel:0x23 frame:4 vi_tstamp:94887390592 data:0x0000000000000001
     kworker/3:2-99      [003] ....    78.194003: rtcpu_vinotify_event: tstamp:2965541214 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:4 vi_tstamp:94887392160 data:0x0000000008020004
     kworker/3:2-99      [003] ....    78.194003: rtcpu_vinotify_event: tstamp:2965541420 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94887462880 data:0x759e300010000000
     kworker/3:2-99      [003] ....    78.194004: rtcpu_vinotify_event: tstamp:2965541590 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94887469472 data:0x0000000031000005
     kworker/3:2-99      [003] ....    78.194005: rtcpu_vinotify_event: tstamp:2966773570 cch:0 vi:1 tag:CHANSEL_PXL_EOF channel:0x23 frame:4 vi_tstamp:94936680320 data:0x000000000c2f0002
     kworker/3:2-99      [003] ....    78.194006: rtcpu_vinotify_event: tstamp:2966773739 cch:0 vi:1 tag:FE channel:0x00 frame:4 vi_tstamp:94936680864 data:0x0000000400000022
     kworker/3:2-99      [003] ....    78.194007: rtcpu_vinotify_event: tstamp:2966773949 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:4 vi_tstamp:94936680864 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.194007: rtcpu_vinotify_event: tstamp:2966774118 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:4 vi_tstamp:94936681280 data:0x0000000000000000
     kworker/3:2-99      [003] ....    78.194008: rtcpu_vinotify_event: tstamp:2967110327 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:4 vi_tstamp:94936689056 data:0x0000000002020004
     kworker/3:2-99      [003] ....    78.194009: rtcpu_vinotify_event: tstamp:2967110502 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:4 vi_tstamp:94936691360 data:0x0000000000020004
     kworker/3:2-99      [003] ....    78.194010: rtcpu_vinotify_event: tstamp:2967110706 cch:0 vi:1 tag:FS channel:0x00 frame:5 vi_tstamp:94937207680 data:0x0000000500000012
     kworker/3:2-99      [003] ....    78.194010: rtcpu_vinotify_event: tstamp:2967110878 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:5 vi_tstamp:94937207712 data:0x0000000800000000
     kworker/3:2-99      [003] ....    78.194011: rtcpu_vinotify_event: tstamp:2967111082 cch:0 vi:1 tag:CHANSEL_EMBED_SOF channel:0x23 frame:5 vi_tstamp:94937208768 data:0x0000000000000004
     kworker/3:2-99      [003] ....    78.194011: rtcpu_vinotify_event: tstamp:2967111252 cch:0 vi:1 tag:CHANSEL_EMBED_EOF channel:0x23 frame:5 vi_tstamp:94937263040 data:0x0000000000030008
     kworker/3:2-99      [003] ....    78.194012: rtcpu_vinotify_event: tstamp:2967111454 cch:0 vi:1 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:5 vi_tstamp:94937263872 data:0x0000000000000000
     kworker/3:2-99      [003] ....    78.194013: rtcpu_vinotify_event: tstamp:2967111624 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:5 vi_tstamp:94937266080 data:0x0000000003020005
     kworker/3:2-99      [003] ....    78.194013: rtcpu_vinotify_event: tstamp:2967111826 cch:0 vi:1 tag:CHANSEL_PXL_SOF channel:0x23 frame:5 vi_tstamp:94937317152 data:0x0000000000000001
     kworker/3:2-99      [003] ....    78.194014: rtcpu_vinotify_event: tstamp:2967111996 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:5 vi_tstamp:94937318688 data:0x0000000008020005
     kworker/3:2-99      [003] ....    78.194015: rtcpu_vinotify_event: tstamp:2967112195 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94937392704 data:0x759e2d0010000000
     kworker/3:2-99      [003] ....    78.194018: rtcpu_vinotify_event: tstamp:2967112367 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:94937399296 data:0x0000000031000006
 vi-output, imx2-3073    [005] ....    78.230064: tegra_channel_capture_frame: sof:94.937317152
 vi-output, imx2-3073    [005] ....    78.230066: tegra_channel_capture_frame: eof:94.986607776

	... repeated traces ...

     kworker/3:2-99      [003] ....    83.021692: rtcpu_vinotify_event: tstamp:3116553145 cch:0 vi:1 tag:CHANSEL_PXL_EOF channel:0x23 frame:100 vi_tstamp:99729627136 data:0x000000000c2f0002
     kworker/3:2-99      [003] ....    83.021694: rtcpu_vinotify_event: tstamp:3116553314 cch:0 vi:1 tag:FE channel:0x00 frame:100 vi_tstamp:99729627680 data:0x0000006400000022
     kworker/3:2-99      [003] ....    83.021694: rtcpu_vinotify_event: tstamp:3116553511 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:100 vi_tstamp:99729627712 data:0x0000000800000000
     kworker/3:2-99      [003] ....    83.021695: rtcpu_vinotify_event: tstamp:3116553679 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:100 vi_tstamp:99729628096 data:0x0000000000000000
     kworker/3:2-99      [003] ....    83.021695: rtcpu_vinotify_event: tstamp:3116881148 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:100 vi_tstamp:99729635872 data:0x0000000002020064
     kworker/3:2-99      [003] ....    83.021696: rtcpu_vinotify_event: tstamp:3116881327 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:100 vi_tstamp:99729638176 data:0x0000000000020064
     kworker/3:2-99      [003] ....    83.021696: rtcpu_vinotify_event: tstamp:3116881523 cch:0 vi:1 tag:FS channel:0x00 frame:101 vi_tstamp:99730154528 data:0x0000006500000012
     kworker/3:2-99      [003] ....    83.021697: rtcpu_vinotify_event: tstamp:3116881695 cch:0 vi:1 tag:ATOMP_FS channel:0x00 frame:101 vi_tstamp:99730154528 data:0x0000000800000000
     kworker/3:2-99      [003] ....    83.021697: rtcpu_vinotify_event: tstamp:3116881891 cch:0 vi:1 tag:CHANSEL_EMBED_SOF channel:0x23 frame:101 vi_tstamp:99730155584 data:0x0000000000000004
     kworker/3:2-99      [003] ....    83.021698: rtcpu_vinotify_event: tstamp:3116882061 cch:0 vi:1 tag:CHANSEL_EMBED_EOF channel:0x23 frame:101 vi_tstamp:99730209856 data:0x0000000000030008
     kworker/3:2-99      [003] ....    83.021698: rtcpu_vinotify_event: tstamp:3116882256 cch:0 vi:1 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:101 vi_tstamp:99730210720 data:0x0000000000000000
     kworker/3:2-99      [003] ....    83.021698: rtcpu_vinotify_event: tstamp:3116882428 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:101 vi_tstamp:99730212896 data:0x0000000003020065
     kworker/3:2-99      [003] ....    83.021699: rtcpu_vinotify_event: tstamp:3116882621 cch:0 vi:1 tag:CHANSEL_PXL_SOF channel:0x23 frame:101 vi_tstamp:99730263968 data:0x0000000000000001
     kworker/3:2-99      [003] ....    83.021699: rtcpu_vinotify_event: tstamp:3116882791 cch:0 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:101 vi_tstamp:99730265504 data:0x0000000008020065
     kworker/3:2-99      [003] ....    83.021699: rtcpu_vinotify_event: tstamp:3116882988 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:99730344224 data:0x759e2d0010000000
     kworker/3:2-99      [003] ....    83.021700: rtcpu_vinotify_event: tstamp:3116883158 cch:0 vi:1 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:99730350848 data:0x0000000031000066
 vi-output, imx2-3073    [000] ....    83.022817: tegra_channel_capture_frame: sof:99.730263968
 vi-output, imx2-3073    [000] ....    83.022820: tegra_channel_capture_frame: eof:99.779554624
        v4l2-ctl-3071    [003] ....    83.022844: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-3071    [003] ....    83.022847: tegra_channel_set_stream: imx214 7-0010 : 0x0
        v4l2-ctl-3071    [003] ....    83.023045: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-3071    [003] ....    83.023048: csi_s_stream: enable : 0x0
        v4l2-ctl-3071    [003] ....    83.028649: tegra_channel_set_power: imx214 7-0010 : 0x0
        v4l2-ctl-3071    [003] ....    83.028659: camera_common_s_power: status : 0x0
        v4l2-ctl-3071    [000] ....    83.028715: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0
        v4l2-ctl-3071    [000] ....    83.028719: csi_s_power: enable : 0x0
     kworker/3:2-99      [003] ....    83.077675: rtcpu_vinotify_event: tstamp:3118113373 cch:0 vi:1 tag:CHANSEL_PXL_EOF channel:0x23 frame:101 vi_tstamp:99779553664 data:0x000000000c2f0002
     kworker/3:2-99      [003] ....    83.077676: rtcpu_vinotify_event: tstamp:3118113544 cch:0 vi:1 tag:FE channel:0x00 frame:101 vi_tstamp:99779554208 data:0x0000006500000022
     kworker/3:2-99      [003] ....    83.077677: rtcpu_vinotify_event: tstamp:3118113748 cch:0 vi:1 tag:ATOMP_FE channel:0x00 frame:101 vi_tstamp:99779554240 data:0x0000000800000000
     kworker/3:2-99      [003] ....    83.077677: rtcpu_vinotify_event: tstamp:3118113919 cch:0 vi:1 tag:ATOMP_FRAME_DONE channel:0x23 frame:101 vi_tstamp:99779554624 data:0x0000000000000000
     kworker/3:2-99      [003] ....    83.077677: rtcpu_vinotify_event: tstamp:3118451799 cch:-1 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:101 vi_tstamp:99779562368 data:0x0000000002020065
     kworker/3:2-99      [003] ....    83.077678: rtcpu_vinotify_event: tstamp:3118451968 cch:-1 vi:1 tag:VIFALC_ACTIONLST channel:0x23 frame:101 vi_tstamp:99779564704 data:0x0000000000020065
        v4l2-ctl-3071    [000] ....    83.300083: tegra_channel_close: vi-output, imx214 7-0010
     kworker/3:3-100     [003] ....    89.269457: rtcpu_string: tstamp:3313321360 id:0x04010000 str:"VM0 deactivating."

It seems that the problem with embedded metada was solved.

Regarding the second point that you mentioned above, my monitor is 4K@60HZ, but I tested with lower resolution with the same result. I changed in the command the bitrate from 30 to 20 because it was implemented in that way in the Jetson Nano DTS.

orinnx@orinnx-desktop:~$ gst-launch-1.0 nvarguscamerasrc ! 'video/x-raw(memory:NVMM),framerate=20/1,format=NV12' ! nvvidconv ! 'video/x-raw, format=(string)I420, width=640, height=480' ! queue ! xvimagesink -e
Setting pipeline to PAUSED ...
Pipeline is live and does not need PREROLL ...
Setting pipeline to PLAYING ...
New clock: GstSystemClock
GST_ARGUS: Creating output stream
CONSUMER: Waiting until producer is connected...
GST_ARGUS: Available Sensor modes :
GST_ARGUS: 4160 x 3120 FR = 33,000000 fps Duration = 30303030 ; Analog Gain range min 0,062500, max 63,937500; Exposure Range min 25000, max 683709000;

GST_ARGUS: Running with following settings:
   Camera index = 0 
   Camera mode  = 0 
   Output Stream W = 4160 H = 3120 
   seconds to Run    = 0 
   Frame Rate = 33,000000 
GST_ARGUS: Setup Complete, Starting captures for 0 seconds
GST_ARGUS: Starting repeat capture requests.
CONSUMER: Producer has connected; continuing.

I attach here the traces for second comand (it is too big to copy-paste here):
trace_logs_cmd2.log (7.3 MB)

And the capture image:

image

Then, I tested as well with the third comand that you suggested excluding the display element. The result was:

orinnx@orinnx-desktop:~$ gst-launch-1.0 nvarguscamerasrc sensor-id=0 sensor-mode=0 ! 'video/x-raw(memory:NVMM),width=1920, height=1080, framerate=20/1, format=NV12' ! nvvidconv ! fpsdisplaysink text-overlay=0 name=sink_0 video-sink=fakesink sync=0 -v
Setting pipeline to PAUSED ...
Pipeline is live and does not need PREROLL ...
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0/GstFakeSink:fakesink0: sync = false
Setting pipeline to PLAYING ...
New clock: GstSystemClock
/GstPipeline:pipeline0/GstNvArgusCameraSrc:nvarguscamerasrc0.GstPad:src: caps = video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, format=(string)NV12, framerate=(fraction)20/1
/GstPipeline:pipeline0/GstCapsFilter:capsfilter0.GstPad:src: caps = video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, format=(string)NV12, framerate=(fraction)20/1
/GstPipeline:pipeline0/Gstnvvconv:nvvconv0.GstPad:src: caps = video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, format=(string)NV12, framerate=(fraction)20/1
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0.GstGhostPad:sink.GstProxyPad:proxypad0: caps = video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, format=(string)NV12, framerate=(fraction)20/1
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0/GstFakeSink:fakesink0.GstPad:sink: caps = video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, format=(string)NV12, framerate=(fraction)20/1
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0.GstGhostPad:sink: caps = video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, format=(string)NV12, framerate=(fraction)20/1
/GstPipeline:pipeline0/Gstnvvconv:nvvconv0.GstPad:sink: caps = video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, format=(string)NV12, framerate=(fraction)20/1
/GstPipeline:pipeline0/GstCapsFilter:capsfilter0.GstPad:sink: caps = video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, format=(string)NV12, framerate=(fraction)20/1
GST_ARGUS: Creating output stream
CONSUMER: Waiting until producer is connected...
GST_ARGUS: Available Sensor modes :
GST_ARGUS: 4160 x 3120 FR = 33,000000 fps Duration = 30303030 ; Analog Gain range min 0,062500, max 63,937500; Exposure Range min 25000, max 683709000;

GST_ARGUS: Running with following settings:
   Camera index = 0 
   Camera mode  = 0 
   Output Stream W = 4160 H = 3120 
   seconds to Run    = 0 
   Frame Rate = 33,000000 
GST_ARGUS: Setup Complete, Starting captures for 0 seconds
GST_ARGUS: Starting repeat capture requests.
CONSUMER: Producer has connected; continuing.
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0/GstFakeSink:fakesink0: sync = false
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 12, dropped: 0, current: 22,52, average: 22,52
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 23, dropped: 0, current: 20,05, average: 21,27
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 34, dropped: 0, current: 20,02, average: 20,85
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 44, dropped: 0, current: 20,00, average: 20,65
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 55, dropped: 0, current: 20,05, average: 20,53
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 66, dropped: 0, current: 20,04, average: 20,44
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 77, dropped: 0, current: 20,01, average: 20,38
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 88, dropped: 0, current: 20,00, average: 20,33
/GstPipeline:pipeline0/GstFPSDisplaySink:sink_0: last-message = rendered: 99, dropped: 0, current: 20,02, average: 20,30

You can find attached the trace logs (too big for copy-paste here):
traces_cmd3.log (2.5 MB)

I will investigate now with new traces why I don’t have a good captured frame. What do you think that could be the reason, and how should I continue debugging?

Thanks so much for your big support!!

7

hello embedx,

it looks you’re now able to fetch sensor streaming with v4l2-ctl and nvarguscamerasrc plugin as well.
is it true that your sensor actually outputting such noisy frames?

let’s examine the frame content.
here’s couple ways to dump the sensor frames for confirmation,
for instance,
(1) you may dump the sensor bayer image with v4l2 command.
after you dumped the raw file, please use the 3rdparty tool, such as 7yuv to view the raw content.
i.e. $ v4l2-ctl -d /dev/video0 --set-fmt-video=width=1920,height=1080,pixelformat=RG10 --set-ctrl bypass_mode=0 --stream-mmap --stream-count=1 --stream-to=test.raw
(2) you may refer to developer guide, Argus NvRaw Tool,
here’s a sample command-line to capture raw and also jpg files concurrently for checking.
i.e. $ sudo nvargus_nvraw --c 0 --mode 0 --format "raw, jpg" --file /home/nvidia/output

8

Hello JerryChang,

Thanks again for your detailed explanation.

I tested with nvargus_nvraw but something is wrong:

orinnx@orinnx-desktop:/home$ sudo nvargus_nvraw --c 0 --mode 0 --format "raw, jpg" --file /home/nvidia/output
nvargus_nvraw version 1.14.0
capture: Total captured frame count 90
("nvargus_nvraw") Error BadParameter (0x04): Embedded data size (16.384) is not a perfect multiple of
     row width (8.320)
 (in capture_nvraw/src/mobile/ArgusNvRawInterface.cpp, func getPrivateMetadata(),
     line 337)
("nvargus_nvraw") Error BadParameter (0x04):  (propagating from capture_nvraw/src/mobile/ArgusNvRawInterface.cpp,
     func addFrame(), line 244)
("nvargus_nvraw") Error BadParameter (0x04):  (propagating from capture_nvraw/src/mobile/ArgusNvRawCapture.cpp,
     func acquireFrames(), line 872)
("nvargus_nvraw") Error BadParameter (0x04):  (propagating from capture_nvraw/src/mobile/ArgusNvRawCapture.cpp,
     func capture(), line 817)
("nvargus_nvraw") Error BadParameter (0x04): Unable to capture (propagating from capture_nvraw/src/mobile/main.cpp,
     func main(), line 97)

I attach the trace logs:
trace_nvargus_nvraw.log (2.2 MB)

The good new is that the captured frame that I posted before correspond to a real frame, but it seems that the exposure is zero. I realized it when testing the camera was focused to a window office:

window_frame
window_frame1265×986 204 KB

If I move the camera, the stream reacts fast. The color is very poor.

Now I will check that the hardware side is correct. Related to ISP, maybe do I need some type of ISP configuration?

Thanks a lot!!!

9

hello embedx,

that’s known issue that nvargus_nvraw did not fully support embedded data size != image width.
let’s omit nvargus_nvraw utility since you’re now able to capture raw files.

so, that noisy frame due to very high gain settings.
I doubt why the exposure is zero. is it something wrong in the exposure’s CID control implementation?

here’s an approach to specify sensor controls settings to v4l command-line.
please update the settings to your sensor driver accordingly.
for instance,
$ v4l2-ctl --set-fmt-video=width=1920,height=1200,pixelformat=BA10 --set-ctrl bypass_mode=0 --stream-mmap --stream-skip=9 --stream-count=1 --set-ctrl=gain=200 --set-ctrl=exposure=2800 --stream-to=test.raw -d /dev/video0

you may see-also Module Properties, it’s badge property in device tree for loading ISP configuration file.
unfortunately, we do not support image tuning via public forum discussion thread.
please contact with sensor vendor for further supports.

10

Hello JerryChang,

I solved the problem related to exposure. It was a wrong operation inside the driver code.

Everything is working great now as you can see in the image below:

image
image1016×988 43.2 KB

Thank you very much for being so patient with me and guiding me through the debugging process =)

Best regards!!

1 Like
12

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