Frame Start/Frame End come but seems no ISP processing after a couple of frames

Robotics & Edge Computing Jetson & Embedded Systems Jetson AGX Xavier
1

Hi,

I’m integrating a new sensor over GMSL in JetPack 5.1.2/L4T 35.4.1. Jetson AGX Xavier.

I managed to make sensor streaming through all the GMSL path (sensor->MAX ser->MAX deser->Jetson AGX Xavier) but cannot see any visual stream by using the Arguscam (it starts, stays active for a couple of seconds and then exits with some standard unhelpful logs):

user@jetson:~$ gst-launch-1.0 nvarguscamerasrc ! nvvidconv ! autovideosink

I have enabled the traces and I see that FS/FE markers come at the expected framerate (30fps → ~33ms between two consequent FS markers). I see even ISP started processing some a couple of frames - e.g. “tiles” are being processed:

     kworker/2:3-139     [002] ....   136.768897: rtcpu_isp_falcon_tile_start: tstamp:589043451 ch:0 seq:1 tile_x:0 tile_y:0 tile_w:798 tile_h:540
     kworker/2:3-139     [002] ....   136.768899: rtcpu_isp_falcon_tile_end: tstamp:589100409 ch:0 seq:1 tile_x:0 tile_y:0
     kworker/2:3-139     [002] ....   136.768899: rtcpu_isp_falcon_task_start: tstamp:589100528 ch:0 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768900: rtcpu_isp_falcon_tile_start: tstamp:589101191 ch:0 seq:1 tile_x:1 tile_y:0 tile_w:802 tile_h:540
     kworker/2:3-139     [002] ....   136.768900: rtcpu_isp_falcon_task_end: tstamp:589101296 task:SCHED_FINISH_TILE

but seems then the ISP activity stops for some reason and I only see FS/FE continuing (obviously sensor and CSI are still active).

Can you please suggest what else to check in the log below and do you see any issues with it?

user@jetson:~$ cat FS_FE_come_log_7.txt 
# tracer: nop
#
# entries-in-buffer/entries-written: 25547/25547   #P:4
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/2:2-130     [002] ....   116.521740: rtcpu_string: tstamp:4252138545 id:0x04010000 str:"VM0 deactivating."
     kworker/2:3-139     [002] ....   131.329010: rtcpu_string: tstamp:4714479756 id:0x04010000 str:"VM0 activating."
  nvargus-daemon-3363    [003] ....   131.539352: tegra_channel_open: vi-output, s5k 30-0010
  nvargus-daemon-3363    [003] ....   131.539542: tegra_channel_close: vi-output, s5k 30-0010
  nvargus-daemon-3363    [003] ....   131.539592: tegra_channel_open: vi-output, s5k 31-0010
  nvargus-daemon-3363    [003] ....   131.539738: tegra_channel_close: vi-output, s5k 31-0010
  nvargus-daemon-3363    [003] ....   131.539758: tegra_channel_open: vi-output, s5k 32-0010
  nvargus-daemon-3363    [003] ....   131.539903: tegra_channel_close: vi-output, s5k 32-0010
  nvargus-daemon-3363    [003] ....   131.539922: tegra_channel_open: vi-output, s5k 33-0010
  nvargus-daemon-3363    [003] ....   131.540065: tegra_channel_close: vi-output, s5k 33-0010
  nvargus-daemon-3363    [003] ....   131.543773: tegra_channel_open: vi-output, s5k 30-0010
  nvargus-daemon-3363    [003] ....   131.543930: tegra_channel_close: vi-output, s5k 30-0010
  nvargus-daemon-3363    [003] ....   131.543952: tegra_channel_open: vi-output, s5k 31-0010
  nvargus-daemon-3363    [003] ....   131.544089: tegra_channel_close: vi-output, s5k 31-0010
  nvargus-daemon-3363    [003] ....   131.544109: tegra_channel_open: vi-output, s5k 32-0010
  nvargus-daemon-3363    [003] ....   131.544246: tegra_channel_close: vi-output, s5k 32-0010
  nvargus-daemon-3363    [003] ....   131.544263: tegra_channel_open: vi-output, s5k 33-0010
  nvargus-daemon-3363    [003] ....   131.544400: tegra_channel_close: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.569076: tegra_channel_open: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.569118: tegra_channel_close: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.569144: tegra_channel_open: vi-output, s5k 32-0010
  nvargus-daemon-3363    [001] ....   131.569163: tegra_channel_close: vi-output, s5k 32-0010
  nvargus-daemon-3363    [001] ....   131.569181: tegra_channel_open: vi-output, s5k 31-0010
  nvargus-daemon-3363    [001] ....   131.569199: tegra_channel_close: vi-output, s5k 31-0010
  nvargus-daemon-3363    [001] ....   131.569217: tegra_channel_open: vi-output, s5k 30-0010
  nvargus-daemon-3363    [001] ....   131.569233: tegra_channel_close: vi-output, s5k 30-0010
  nvargus-daemon-3363    [001] ....   131.569260: tegra_channel_open: vi-output, s5k 30-0010
  nvargus-daemon-3363    [001] ....   131.569433: tegra_channel_close: vi-output, s5k 30-0010
  nvargus-daemon-3363    [001] ....   131.569482: tegra_channel_open: vi-output, s5k 30-0010
  nvargus-daemon-3363    [001] ....   131.569620: tegra_channel_close: vi-output, s5k 30-0010
  nvargus-daemon-3363    [000] ....   131.638981: tegra_channel_open: vi-output, s5k 33-0010
  nvargus-daemon-3363    [000] ....   131.639024: tegra_channel_close: vi-output, s5k 33-0010
  nvargus-daemon-3363    [000] ....   131.639048: tegra_channel_open: vi-output, s5k 32-0010
  nvargus-daemon-3363    [000] ....   131.639066: tegra_channel_close: vi-output, s5k 32-0010
  nvargus-daemon-3363    [000] ....   131.639084: tegra_channel_open: vi-output, s5k 31-0010
  nvargus-daemon-3363    [000] ....   131.639101: tegra_channel_close: vi-output, s5k 31-0010
  nvargus-daemon-3363    [000] ....   131.639142: tegra_channel_open: vi-output, s5k 31-0010
  nvargus-daemon-3363    [000] ....   131.639300: tegra_channel_close: vi-output, s5k 31-0010
  nvargus-daemon-3363    [000] ....   131.639326: tegra_channel_open: vi-output, s5k 31-0010
  nvargus-daemon-3363    [000] ....   131.639443: tegra_channel_close: vi-output, s5k 31-0010
  nvargus-daemon-3363    [001] ....   131.684103: tegra_channel_open: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.684145: tegra_channel_close: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.684168: tegra_channel_open: vi-output, s5k 32-0010
  nvargus-daemon-3363    [001] ....   131.684186: tegra_channel_close: vi-output, s5k 32-0010
  nvargus-daemon-3363    [001] ....   131.684213: tegra_channel_open: vi-output, s5k 32-0010
  nvargus-daemon-3363    [001] ....   131.684382: tegra_channel_close: vi-output, s5k 32-0010
  nvargus-daemon-3363    [001] ....   131.684409: tegra_channel_open: vi-output, s5k 32-0010
  nvargus-daemon-3363    [001] ....   131.684526: tegra_channel_close: vi-output, s5k 32-0010
  nvargus-daemon-3363    [001] ....   131.722601: tegra_channel_open: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.722645: tegra_channel_close: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.722677: tegra_channel_open: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.722841: tegra_channel_close: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.722869: tegra_channel_open: vi-output, s5k 33-0010
  nvargus-daemon-3363    [001] ....   131.723017: tegra_channel_close: vi-output, s5k 33-0010
 CaptureSchedule-3379    [002] ....   133.742875: tegra_channel_open: vi-output, s5k 30-0010
 CaptureSchedule-3379    [002] ....   133.743328: tegra_channel_set_power: s5k 30-0010 : 0x1
 CaptureSchedule-3379    [002] ....   133.743333: camera_common_s_power: status : 0x1
 CaptureSchedule-3379    [002] ....   133.744343: tegra_channel_set_power: 13e10000.host1x:nvcsi@15a00000- : 0x1
 CaptureSchedule-3379    [002] ....   133.744346: csi_s_power: enable : 0x1
 CaptureSchedule-3379    [002] ....   133.744567: tegra_channel_set_stream: enable : 0x1
 CaptureSchedule-3379    [002] ....   133.748246: tegra_channel_set_stream: 13e10000.host1x:nvcsi@15a00000- : 0x1
 CaptureSchedule-3379    [002] ....   133.748250: csi_s_stream: enable : 0x1
 CaptureSchedule-3379    [002] ....   133.748251: tegra_channel_set_stream: s5k 30-0010 : 0x1
     kworker/2:3-139     [002] ....   136.656831: rtcpu_vinotify_event: tstamp:4880917962 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156188099584 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   136.656835: rtcpu_vinotify_event: tstamp:4880918156 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156188142400 data:0x0000000031000001
     kworker/2:3-139     [002] ....   136.656841: rtcpu_vinotify_event: tstamp:4881460290 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156201509280 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.656842: rtcpu_vinotify_event: tstamp:4881460463 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156201509344 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.656843: rtcpu_vinotify_event: tstamp:4881460656 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156201620384 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.656844: rtcpu_vinotify_event: tstamp:4881460818 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156201631200 data:0x0000000008020001
     kworker/2:3-139     [002] ....   136.656845: rtcpu_vinotify_event: tstamp:4881784109 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156207935296 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.656846: rtcpu_vinotify_event: tstamp:4881784275 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156207945376 data:0x0000000002020001
     kworker/2:3-139     [002] ....   136.656846: rtcpu_vinotify_event: tstamp:4881784462 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156214252960 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.656847: rtcpu_vinotify_event: tstamp:4881784627 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156214263136 data:0x0000000002020001
     kworker/2:3-139     [002] ....   136.656848: rtcpu_vinotify_event: tstamp:4881784813 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156216825888 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.656848: rtcpu_vinotify_event: tstamp:4881784974 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156216826592 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.656849: rtcpu_vinotify_event: tstamp:4881785163 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156216826688 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.656850: rtcpu_vinotify_event: tstamp:4881785326 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156216827136 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.656880: rtcpu_vinotify_event: tstamp:4881785511 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156216879168 data:0x0000000002020001
     kworker/2:3-139     [002] ....   136.656881: rtcpu_vinotify_event: tstamp:4881785673 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156216895616 data:0x0000000000020001
     kworker/2:3-139     [002] ....   136.712865: rtcpu_vinotify_event: tstamp:4882273550 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156221862432 data:0xcd9ce20010000000
     kworker/2:3-139     [002] ....   136.712869: rtcpu_vinotify_event: tstamp:4882273723 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156221905280 data:0x0000000031000002
     kworker/2:3-139     [002] ....   136.712870: rtcpu_vinotify_event: tstamp:4882273913 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156222491424 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   136.712871: rtcpu_vinotify_event: tstamp:4882274078 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156222534848 data:0x0000000031000003
     kworker/2:3-139     [002] ....   136.712877: rtcpu_vinotify_event: tstamp:4882820114 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156234665440 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.712878: rtcpu_vinotify_event: tstamp:4882820287 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156234665536 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.712879: rtcpu_vinotify_event: tstamp:4882820477 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156234776576 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.712880: rtcpu_vinotify_event: tstamp:4882820643 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156234787360 data:0x0000000008020002
     kworker/2:3-139     [002] ....   136.712881: rtcpu_vinotify_event: tstamp:4882820830 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156241091424 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.712882: rtcpu_vinotify_event: tstamp:4882820993 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156241101536 data:0x0000000002020002
     kworker/2:3-139     [002] ....   136.712883: rtcpu_vinotify_event: tstamp:4882821177 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156247409120 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.712884: rtcpu_vinotify_event: tstamp:4882821340 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156247419168 data:0x0000000002020002
     kworker/2:3-139     [002] ....   136.712885: rtcpu_vinotify_event: tstamp:4882821526 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156249982080 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.712915: rtcpu_vinotify_event: tstamp:4882821688 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156249982752 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.712916: rtcpu_vinotify_event: tstamp:4882821879 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156249982848 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.712917: rtcpu_vinotify_event: tstamp:4882822041 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156249983008 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.712918: rtcpu_vinotify_event: tstamp:4883304822 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156250044000 data:0x0000000002020002
     kworker/2:3-139     [002] ....   136.712919: rtcpu_vinotify_event: tstamp:4883304988 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156250060704 data:0x0000000000020002
     kworker/2:3-139     [002] ....   136.768846: rtcpu_vinotify_event: tstamp:4883847350 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156267821600 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.768850: rtcpu_vinotify_event: tstamp:4883847527 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156267821664 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.768850: rtcpu_vinotify_event: tstamp:4883847719 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156267932736 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.768851: rtcpu_vinotify_event: tstamp:4883847883 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156267943488 data:0x0000000008020003
     kworker/2:3-139     [002] ....   136.768851: rtcpu_vinotify_event: tstamp:4883848073 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156268185984 data:0xcd9ce20010000000
     kworker/2:3-139     [002] ....   136.768852: rtcpu_vinotify_event: tstamp:4883848235 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156268232288 data:0x0000000031000004
     kworker/2:3-139     [002] ....   136.768852: rtcpu_vinotify_event: tstamp:4883848422 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156274247616 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.768853: rtcpu_vinotify_event: tstamp:4883848584 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156274257728 data:0x0000000002020003
     kworker/2:3-139     [002] ....   136.768853: rtcpu_vinotify_event: tstamp:4883848769 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156280565280 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.768854: rtcpu_vinotify_event: tstamp:4883848932 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156280575456 data:0x0000000002020003
     kworker/2:3-139     [002] ....   136.768859: rtcpu_vinotify_event: tstamp:4883856268 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156283138208 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.768859: rtcpu_vinotify_event: tstamp:4883856434 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156283138912 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.768860: rtcpu_vinotify_event: tstamp:4883856627 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156283138976 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.768860: rtcpu_vinotify_event: tstamp:4883856792 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156283139488 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.768887: rtcpu_isp_falcon_task_start: tstamp:589037931 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768889: rtcpu_isp_falcon_task_end: tstamp:589038057 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768890: rtcpu_isp_falcon_task_start: tstamp:589038198 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768890: rtcpu_isp_falcon_task_end: tstamp:589038303 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768891: rtcpu_isp_falcon_task_start: tstamp:589038444 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768892: rtcpu_isp_falcon_task_end: tstamp:589038551 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768892: rtcpu_isp_falcon_task_start: tstamp:589038691 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768893: rtcpu_isp_falcon_task_end: tstamp:589038897 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768894: rtcpu_isp_falcon: tag:0xa tstamp:589039472 ch:0 seq:0 data:0x10000000 ext_data:0xcc27ec00
     kworker/2:3-139     [002] ....   136.768895: rtcpu_isp_falcon: tag:0xa tstamp:589040693 ch:0 seq:1 data:0x21000001 ext_data:0xcc286880
     kworker/2:3-139     [002] ....   136.768896: rtcpu_isp_falcon: tag:0xa tstamp:589041970 ch:0 seq:1 data:0x32000001 ext_data:0x00000000
     kworker/2:3-139     [002] ....   136.768897: rtcpu_isp_falcon_tile_start: tstamp:589043451 ch:0 seq:1 tile_x:0 tile_y:0 tile_w:798 tile_h:540
     kworker/2:3-139     [002] ....   136.768899: rtcpu_isp_falcon_tile_end: tstamp:589100409 ch:0 seq:1 tile_x:0 tile_y:0
     kworker/2:3-139     [002] ....   136.768899: rtcpu_isp_falcon_task_start: tstamp:589100528 ch:0 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768900: rtcpu_isp_falcon_tile_start: tstamp:589101191 ch:0 seq:1 tile_x:1 tile_y:0 tile_w:802 tile_h:540
     kworker/2:3-139     [002] ....   136.768900: rtcpu_isp_falcon_task_end: tstamp:589101296 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768901: rtcpu_isp_falcon_tile_end: tstamp:589157708 ch:0 seq:1 tile_x:1 tile_y:0
     kworker/2:3-139     [002] ....   136.768902: rtcpu_isp_falcon_task_start: tstamp:589157827 ch:0 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768902: rtcpu_isp_falcon_tile_start: tstamp:589159291 ch:0 seq:1 tile_x:0 tile_y:1 tile_w:798 tile_h:540
     kworker/2:3-139     [002] ....   136.768903: rtcpu_isp_falcon_task_end: tstamp:589159395 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768903: rtcpu_isp_falcon_task_start: tstamp:589170815 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768904: rtcpu_isp_falcon_task_end: tstamp:589171772 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768904: rtcpu_isp_falcon_tile_end: tstamp:589217287 ch:0 seq:1 tile_x:0 tile_y:1
     kworker/2:3-139     [002] ....   136.768905: rtcpu_isp_falcon_task_start: tstamp:589217405 ch:0 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768905: rtcpu_isp_falcon_tile_start: tstamp:589218142 ch:0 seq:1 tile_x:1 tile_y:1 tile_w:802 tile_h:540
     kworker/2:3-139     [002] ....   136.768906: rtcpu_isp_falcon_task_end: tstamp:589218246 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768906: rtcpu_isp_falcon_task_start: tstamp:589229728 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768907: rtcpu_isp_falcon_task_end: tstamp:589230685 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768908: rtcpu_isp_falcon_task_start: tstamp:589231195 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768908: rtcpu_isp_falcon_task_end: tstamp:589232917 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768909: rtcpu_isp_falcon_tile_end: tstamp:589276571 ch:0 seq:1 tile_x:1 tile_y:1
     kworker/2:3-139     [002] ....   136.768924: rtcpu_isp_falcon_task_start: tstamp:589276690 ch:0 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768924: rtcpu_isp_falcon_tile_start: tstamp:589279186 ch:0 seq:1 tile_x:0 tile_y:2 tile_w:798 tile_h:220
     kworker/2:3-139     [002] ....   136.768925: rtcpu_isp_falcon_task_end: tstamp:589279291 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768926: rtcpu_isp_falcon_task_start: tstamp:589300715 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768926: rtcpu_isp_falcon_task_end: tstamp:589301673 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768927: rtcpu_isp_falcon_tile_end: tstamp:589304893 ch:0 seq:1 tile_x:0 tile_y:2
     kworker/2:3-139     [002] ....   136.768927: rtcpu_isp_falcon_task_start: tstamp:589305012 ch:0 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768928: rtcpu_isp_falcon_tile_start: tstamp:589305688 ch:0 seq:1 tile_x:1 tile_y:2 tile_w:802 tile_h:220
     kworker/2:3-139     [002] ....   136.768928: rtcpu_isp_falcon_task_end: tstamp:589305793 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768929: rtcpu_isp_falcon_task_start: tstamp:589327289 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768929: rtcpu_isp_falcon_task_end: tstamp:589328311 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768930: rtcpu_isp_falcon_task_start: tstamp:589328504 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768930: rtcpu_isp_falcon_task_end: tstamp:589329742 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768931: rtcpu_isp_falcon_task_start: tstamp:589329936 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768932: rtcpu_isp_falcon_task_end: tstamp:589333645 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768933: rtcpu_isp_falcon_task_start: tstamp:589333840 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768934: rtcpu_isp_falcon_task_end: tstamp:589335562 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768935: rtcpu_vinotify_event: tstamp:4884390189 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156283200192 data:0x0000000002020003
     kworker/2:3-139     [002] ....   136.768935: rtcpu_vinotify_event: tstamp:4884390358 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156283216896 data:0x0000000000020003
     kworker/2:3-139     [002] ....   136.768936: rtcpu_vinotify_event: tstamp:4884892409 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156300977760 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.768937: rtcpu_vinotify_event: tstamp:4884892601 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156300977856 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.768938: rtcpu_vinotify_event: tstamp:4884892790 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156301088928 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.768938: rtcpu_vinotify_event: tstamp:4884892953 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156301099744 data:0x0000000008020004
     kworker/2:3-139     [002] ....   136.768939: rtcpu_vinotify_event: tstamp:4884893139 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156301337664 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   136.768939: rtcpu_vinotify_event: tstamp:4884893303 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156301381088 data:0x0000000031000005
     kworker/2:3-139     [002] ....   136.768940: rtcpu_vinotify_event: tstamp:4884893488 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156307403776 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.768940: rtcpu_vinotify_event: tstamp:4884893649 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156307413952 data:0x0000000002020004
     kworker/2:3-139     [002] ....   136.768941: rtcpu_vinotify_event: tstamp:4884893833 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156313721472 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.768942: rtcpu_vinotify_event: tstamp:4884893993 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156313731584 data:0x0000000002020004
     kworker/2:3-139     [002] ....   136.768942: rtcpu_vinotify_event: tstamp:4884894178 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156316294400 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.768943: rtcpu_vinotify_event: tstamp:4884894339 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156316295072 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.768943: rtcpu_vinotify_event: tstamp:4884894528 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156316295168 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.768944: rtcpu_vinotify_event: tstamp:4884894689 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156316295680 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.768945: rtcpu_isp_falcon_tile_end: tstamp:589337458 ch:0 seq:1 tile_x:1 tile_y:2
     kworker/2:3-139     [002] ....   136.768945: rtcpu_isp_falcon_task_start: tstamp:589337577 ch:0 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768946: rtcpu_isp_falcon: tag:0x12 tstamp:589338443 ch:0 seq:0 data:0x00000000 ext_data:0x00000000
     kworker/2:3-139     [002] ....   136.768947: rtcpu_isp_falcon_task_end: tstamp:589340830 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768947: rtcpu_isp_falcon_task_start: tstamp:589340969 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768948: rtcpu_isp_falcon_task_end: tstamp:589341213 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768948: rtcpu_isp_falcon_task_start: tstamp:589984202 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768949: rtcpu_isp_falcon_task_end: tstamp:589984327 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768950: rtcpu_isp_falcon_task_start: tstamp:589984469 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768950: rtcpu_isp_falcon_task_end: tstamp:589984573 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768964: rtcpu_isp_falcon_task_start: tstamp:589984715 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768964: rtcpu_isp_falcon_task_end: tstamp:589984821 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768965: rtcpu_isp_falcon_task_start: tstamp:589984961 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768965: rtcpu_isp_falcon_task_end: tstamp:589985169 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.768966: rtcpu_isp_falcon: tag:0xa tstamp:589985800 ch:0 seq:0 data:0x10000000 ext_data:0xcc27e800
     kworker/2:3-139     [002] ....   136.768967: rtcpu_isp_falcon: tag:0xa tstamp:589987021 ch:0 seq:2 data:0x21000002 ext_data:0xcc286800
     kworker/2:3-139     [002] ....   136.768967: rtcpu_isp_falcon: tag:0xa tstamp:589990266 ch:0 seq:2 data:0x32000002 ext_data:0x00000000
     kworker/2:3-139     [002] ....   136.768968: rtcpu_isp_falcon_tile_start: tstamp:589991726 ch:0 seq:2 tile_x:0 tile_y:0 tile_w:798 tile_h:540
     kworker/2:3-139     [002] ....   136.768968: rtcpu_isp_falcon_tile_end: tstamp:590048671 ch:0 seq:2 tile_x:0 tile_y:0
     kworker/2:3-139     [002] ....   136.768969: rtcpu_isp_falcon_task_start: tstamp:590048790 ch:0 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768970: rtcpu_isp_falcon_tile_start: tstamp:590049452 ch:0 seq:2 tile_x:1 tile_y:0 tile_w:802 tile_h:540
     kworker/2:3-139     [002] ....   136.768970: rtcpu_isp_falcon_task_end: tstamp:590049557 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768971: rtcpu_isp_falcon_tile_end: tstamp:590105969 ch:0 seq:2 tile_x:1 tile_y:0
     kworker/2:3-139     [002] ....   136.768971: rtcpu_isp_falcon_task_start: tstamp:590106089 ch:0 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768972: rtcpu_isp_falcon_tile_start: tstamp:590107559 ch:0 seq:2 tile_x:0 tile_y:1 tile_w:798 tile_h:540
     kworker/2:3-139     [002] ....   136.768973: rtcpu_isp_falcon_task_end: tstamp:590107664 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768973: rtcpu_isp_falcon_task_start: tstamp:590119112 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768974: rtcpu_isp_falcon_task_end: tstamp:590120069 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768974: rtcpu_isp_falcon_tile_end: tstamp:590165582 ch:0 seq:2 tile_x:0 tile_y:1
     kworker/2:3-139     [002] ....   136.768975: rtcpu_isp_falcon_task_start: tstamp:590165701 ch:0 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768975: rtcpu_isp_falcon_tile_start: tstamp:590166437 ch:0 seq:2 tile_x:1 tile_y:1 tile_w:802 tile_h:540
     kworker/2:3-139     [002] ....   136.768976: rtcpu_isp_falcon_task_end: tstamp:590166542 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768977: rtcpu_isp_falcon_task_start: tstamp:590178024 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768977: rtcpu_isp_falcon_task_end: tstamp:590178981 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768978: rtcpu_isp_falcon_task_start: tstamp:590179488 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768978: rtcpu_isp_falcon_task_end: tstamp:590181211 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768979: rtcpu_isp_falcon_tile_end: tstamp:590224865 ch:0 seq:2 tile_x:1 tile_y:1
     kworker/2:3-139     [002] ....   136.768979: rtcpu_isp_falcon_task_start: tstamp:590224984 ch:0 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768980: rtcpu_isp_falcon_tile_start: tstamp:590226270 ch:0 seq:2 tile_x:0 tile_y:2 tile_w:798 tile_h:220
     kworker/2:3-139     [002] ....   136.768981: rtcpu_isp_falcon_task_end: tstamp:590226375 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.768981: rtcpu_isp_falcon_task_start: tstamp:590247801 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768982: rtcpu_isp_falcon_task_end: tstamp:590248759 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768982: rtcpu_isp_falcon_tile_end: tstamp:590251978 ch:0 seq:2 tile_x:0 tile_y:2
     kworker/2:3-139     [002] ....   136.768983: rtcpu_isp_falcon_task_start: tstamp:590252097 ch:0 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768984: rtcpu_isp_falcon_tile_start: tstamp:590252773 ch:0 seq:2 tile_x:1 tile_y:2 tile_w:802 tile_h:220
     kworker/2:3-139     [002] ....   136.768984: rtcpu_isp_falcon_task_end: tstamp:590252878 task:SCHED_FINISH_TILE
     kworker/2:3-139     [002] ....   136.768985: rtcpu_isp_falcon_task_start: tstamp:590274374 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768985: rtcpu_isp_falcon_task_end: tstamp:590275396 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.768986: rtcpu_vinotify_event: tstamp:4885420102 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156316356352 data:0x0000000002020004
     kworker/2:3-139     [002] ....   136.768987: rtcpu_vinotify_event: tstamp:4885420271 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156316373152 data:0x0000000000020004
     kworker/2:3-139     [002] ....   136.824866: rtcpu_vinotify_event: tstamp:4885928493 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156334133952 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.824870: rtcpu_vinotify_event: tstamp:4885928667 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156334134016 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.824872: rtcpu_vinotify_event: tstamp:4885928856 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156334245088 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.824873: rtcpu_vinotify_event: tstamp:4885929021 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156334255840 data:0x0000000008020005
     kworker/2:3-139     [002] ....   136.824874: rtcpu_vinotify_event: tstamp:4885929205 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156334510080 data:0xcd9ce20010000000
     kworker/2:3-139     [002] ....   136.824875: rtcpu_vinotify_event: tstamp:4885929367 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156334553536 data:0x0000000031000006
     kworker/2:3-139     [002] ....   136.824876: rtcpu_vinotify_event: tstamp:4885929551 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156340559968 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.824877: rtcpu_vinotify_event: tstamp:4885929712 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156340570080 data:0x0000000002020005
     kworker/2:3-139     [002] ....   136.824878: rtcpu_vinotify_event: tstamp:4885929896 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156346877600 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.824879: rtcpu_vinotify_event: tstamp:4885930057 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156346887744 data:0x0000000002020005
     kworker/2:3-139     [002] ....   136.824880: rtcpu_vinotify_event: tstamp:4885930245 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156349450592 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.824881: rtcpu_vinotify_event: tstamp:4885930420 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156349451296 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.824882: rtcpu_vinotify_event: tstamp:4885930609 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156349451360 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.824883: rtcpu_vinotify_event: tstamp:4885930773 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156349451552 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.824890: rtcpu_vinotify_event: tstamp:4886451137 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156349512512 data:0x0000000002020005
     kworker/2:3-139     [002] ....   136.824923: rtcpu_vinotify_event: tstamp:4886451303 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156349529216 data:0x0000000000020005
     kworker/2:3-139     [002] ....   136.824925: rtcpu_vinotify_event: tstamp:4886964632 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156367290080 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.824926: rtcpu_vinotify_event: tstamp:4886964803 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156367290176 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.824927: rtcpu_vinotify_event: tstamp:4886964993 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156367401216 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.824928: rtcpu_vinotify_event: tstamp:4886965156 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156367412032 data:0x0000000008020006
     kworker/2:3-139     [002] ....   136.824929: rtcpu_vinotify_event: tstamp:4886965342 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156367646752 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   136.824930: rtcpu_vinotify_event: tstamp:4886965506 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156367690176 data:0x0000000031000007
     kworker/2:3-139     [002] ....   136.824932: rtcpu_vinotify_event: tstamp:4886965691 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156373716096 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.824933: rtcpu_vinotify_event: tstamp:4886965853 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156373726208 data:0x0000000002020006
     kworker/2:3-139     [002] ....   136.824934: rtcpu_vinotify_event: tstamp:4886966041 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156380033792 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.824935: rtcpu_vinotify_event: tstamp:4886966205 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156380043872 data:0x0000000002020006
     kworker/2:3-139     [002] ....   136.824936: rtcpu_vinotify_event: tstamp:4886966390 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156382606720 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.824959: rtcpu_vinotify_event: tstamp:4886966551 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156382607392 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.824960: rtcpu_vinotify_event: tstamp:4886966740 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156382607520 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.824961: rtcpu_vinotify_event: tstamp:4886966902 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156382607680 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.824963: rtcpu_isp_falcon_task_start: tstamp:590276971 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.824966: rtcpu_isp_falcon_task_end: tstamp:590281358 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.824967: rtcpu_isp_falcon_task_start: tstamp:590281553 ch:0 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.824968: rtcpu_isp_falcon_task_end: tstamp:590283275 task:SCHED_HANDLE_STAT
     kworker/2:3-139     [002] ....   136.824970: rtcpu_isp_falcon_tile_end: tstamp:590283963 ch:0 seq:2 tile_x:1 tile_y:2
     kworker/2:3-139     [002] ....   136.824972: rtcpu_isp_falcon_task_start: tstamp:590284083 ch:0 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.824974: rtcpu_isp_falcon: tag:0x12 tstamp:590284949 ch:0 seq:0 data:0x00000000 ext_data:0x00000000
     kworker/2:3-139     [002] ....   136.824975: rtcpu_isp_falcon_task_end: tstamp:590287336 task:SCHED_FINISH_SLICE
     kworker/2:3-139     [002] ....   136.824976: rtcpu_isp_falcon_task_start: tstamp:590287474 ch:0 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.824977: rtcpu_isp_falcon_task_end: tstamp:590287718 task:HANDLE_EVENT
     kworker/2:3-139     [002] ....   136.880852: rtcpu_vinotify_event: tstamp:4887482137 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156382668672 data:0x0000000002020006
     kworker/2:3-139     [002] ....   136.880855: rtcpu_vinotify_event: tstamp:4887482308 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156382685376 data:0x0000000000020006
     kworker/2:3-139     [002] ....   136.880859: rtcpu_vinotify_event: tstamp:4888000743 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156400446272 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.880861: rtcpu_vinotify_event: tstamp:4888000918 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156400446368 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.880863: rtcpu_vinotify_event: tstamp:4888001129 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156400557408 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.880864: rtcpu_vinotify_event: tstamp:4888001294 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156400568192 data:0x0000000008020007
     kworker/2:3-139     [002] ....   136.880865: rtcpu_vinotify_event: tstamp:4888001481 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156400817312 data:0xcd9ce20010000000
     kworker/2:3-139     [002] ....   136.880898: rtcpu_vinotify_event: tstamp:4888001644 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156400860768 data:0x0000000031000008
     kworker/2:3-139     [002] ....   136.880899: rtcpu_vinotify_event: tstamp:4888001830 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156406872288 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.880900: rtcpu_vinotify_event: tstamp:4888001997 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156406882432 data:0x0000000002020007
     kworker/2:3-139     [002] ....   136.880901: rtcpu_vinotify_event: tstamp:4888002182 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156413189952 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.880902: rtcpu_vinotify_event: tstamp:4888002344 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156413200064 data:0x0000000002020007
     kworker/2:3-139     [002] ....   136.880904: rtcpu_vinotify_event: tstamp:4888002531 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156415762912 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.880905: rtcpu_vinotify_event: tstamp:4888002693 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156415763616 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.880906: rtcpu_vinotify_event: tstamp:4888002885 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156415763680 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.880907: rtcpu_vinotify_event: tstamp:4888003052 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156415763840 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.880908: rtcpu_vinotify_event: tstamp:4888486107 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156415824896 data:0x0000000002020007
     kworker/2:3-139     [002] ....   136.880909: rtcpu_vinotify_event: tstamp:4888486276 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156415841568 data:0x0000000000020007
     kworker/2:3-139     [002] ....   136.936833: rtcpu_vinotify_event: tstamp:4889028629 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156433602432 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.936836: rtcpu_vinotify_event: tstamp:4889028810 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156433602496 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.936837: rtcpu_vinotify_event: tstamp:4889029003 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156433713568 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.936838: rtcpu_vinotify_event: tstamp:4889029166 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156433724320 data:0x0000000008020008
     kworker/2:3-139     [002] ....   136.936839: rtcpu_vinotify_event: tstamp:4889029353 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156433987424 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   136.936841: rtcpu_vinotify_event: tstamp:4889029516 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156434033696 data:0x0000000031000009
     kworker/2:3-139     [002] ....   136.936842: rtcpu_vinotify_event: tstamp:4889029703 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156440028480 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.936843: rtcpu_vinotify_event: tstamp:4889029865 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156440038592 data:0x0000000002020008
     kworker/2:3-139     [002] ....   136.936844: rtcpu_vinotify_event: tstamp:4889030052 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156446346112 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.936871: rtcpu_vinotify_event: tstamp:4889030214 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156446356256 data:0x0000000002020008
     kworker/2:3-139     [002] ....   136.936874: rtcpu_vinotify_event: tstamp:4889036879 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156448919072 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.936875: rtcpu_vinotify_event: tstamp:4889037047 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156448919744 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.936877: rtcpu_vinotify_event: tstamp:4889037242 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156448919840 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.936878: rtcpu_vinotify_event: tstamp:4889037404 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156448919968 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.936879: rtcpu_vinotify_event: tstamp:4889571486 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156448980992 data:0x0000000002020008
     kworker/2:3-139     [002] ....   136.936880: rtcpu_vinotify_event: tstamp:4889571652 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156448997696 data:0x0000000000020008
     kworker/2:3-139     [002] ....   136.936882: rtcpu_vinotify_event: tstamp:4890072998 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156466758592 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.936883: rtcpu_vinotify_event: tstamp:4890073169 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156466758688 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.936884: rtcpu_vinotify_event: tstamp:4890073360 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156466869760 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.936885: rtcpu_vinotify_event: tstamp:4890073558 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156466880544 data:0x0000000008020009
     kworker/2:3-139     [002] ....   136.936886: rtcpu_vinotify_event: tstamp:4890073745 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156467139008 data:0xcd9ce20010000000
     kworker/2:3-139     [002] ....   136.936887: rtcpu_vinotify_event: tstamp:4890073908 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156467182496 data:0x000000003100000a
     kworker/2:3-139     [002] ....   136.936888: rtcpu_vinotify_event: tstamp:4890074094 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156473184608 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.936889: rtcpu_vinotify_event: tstamp:4890074256 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156473194784 data:0x0000000002020009
     kworker/2:3-139     [002] ....   136.936890: rtcpu_vinotify_event: tstamp:4890074441 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156479502304 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.936891: rtcpu_vinotify_event: tstamp:4890074606 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156479512416 data:0x0000000002020009
     kworker/2:3-139     [002] ....   136.936892: rtcpu_vinotify_event: tstamp:4890074790 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156482075200 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.936893: rtcpu_vinotify_event: tstamp:4890074951 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156482075904 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.936894: rtcpu_vinotify_event: tstamp:4890075140 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156482076000 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.936895: rtcpu_vinotify_event: tstamp:4890075302 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156482076160 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.936896: rtcpu_vinotify_event: tstamp:4890601399 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156482137216 data:0x0000000002020009
     kworker/2:3-139     [002] ....   136.936897: rtcpu_vinotify_event: tstamp:4890601565 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156482153888 data:0x0000000000020009
     kworker/2:3-139     [002] ....   136.992849: rtcpu_vinotify_event: tstamp:4891109223 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156499914784 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.992852: rtcpu_vinotify_event: tstamp:4891109401 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156499914848 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.992853: rtcpu_vinotify_event: tstamp:4891109593 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156500025888 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.992854: rtcpu_vinotify_event: tstamp:4891109756 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156500036704 data:0x000000000802000a
     kworker/2:3-139     [002] ....   136.992855: rtcpu_vinotify_event: tstamp:4891109943 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156500275712 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   136.992856: rtcpu_vinotify_event: tstamp:4891110106 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156500319200 data:0x000000003100000b
     kworker/2:3-139     [002] ....   136.992857: rtcpu_vinotify_event: tstamp:4891110293 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156506340800 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.992888: rtcpu_vinotify_event: tstamp:4891110459 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156506350880 data:0x000000000202000a
     kworker/2:3-139     [002] ....   136.992889: rtcpu_vinotify_event: tstamp:4891110644 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156512658464 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.992890: rtcpu_vinotify_event: tstamp:4891110808 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156512668544 data:0x000000000202000a
     kworker/2:3-139     [002] ....   136.992891: rtcpu_vinotify_event: tstamp:4891110993 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156515231424 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.992892: rtcpu_vinotify_event: tstamp:4891111155 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156515232128 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.992893: rtcpu_vinotify_event: tstamp:4891111365 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156515232192 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.992894: rtcpu_vinotify_event: tstamp:4891111529 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156515232320 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.992895: rtcpu_vinotify_event: tstamp:4891632434 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156515293376 data:0x000000000202000a
     kworker/2:3-139     [002] ....   136.992896: rtcpu_vinotify_event: tstamp:4891632600 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156515310080 data:0x000000000002000a
     kworker/2:3-139     [002] ....   136.992898: rtcpu_vinotify_event: tstamp:4892145265 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156533070912 data:0x0000000000000010
     kworker/2:3-139     [002] ....   136.992900: rtcpu_vinotify_event: tstamp:4892145438 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156533071008 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.992901: rtcpu_vinotify_event: tstamp:4892145629 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156533182080 data:0x0000000000000001
     kworker/2:3-139     [002] ....   136.992923: rtcpu_vinotify_event: tstamp:4892145791 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156533192832 data:0x000000000802000b
     kworker/2:3-139     [002] ....   136.992924: rtcpu_vinotify_event: tstamp:4892145975 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156533448128 data:0xcd9ce20010000000
     kworker/2:3-139     [002] ....   136.992925: rtcpu_vinotify_event: tstamp:4892146141 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156533491648 data:0x000000003100000c
     kworker/2:3-139     [002] ....   136.992926: rtcpu_vinotify_event: tstamp:4892146324 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156539496960 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.992927: rtcpu_vinotify_event: tstamp:4892146485 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156539507072 data:0x000000000202000b
     kworker/2:3-139     [002] ....   136.992928: rtcpu_vinotify_event: tstamp:4892146670 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156545814656 data:0x0000000000000000
     kworker/2:3-139     [002] ....   136.992929: rtcpu_vinotify_event: tstamp:4892146831 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156545824736 data:0x000000000202000b
     kworker/2:3-139     [002] ....   136.992930: rtcpu_vinotify_event: tstamp:4892147015 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156548387584 data:0x0000000005130002
     kworker/2:3-139     [002] ....   136.992931: rtcpu_vinotify_event: tstamp:4892147176 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156548388256 data:0x0000000000000020
     kworker/2:3-139     [002] ....   136.992932: rtcpu_vinotify_event: tstamp:4892147365 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156548388352 data:0x0000000800000000
     kworker/2:3-139     [002] ....   136.992933: rtcpu_vinotify_event: tstamp:4892147525 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156548388512 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.048826: rtcpu_vinotify_event: tstamp:4892663480 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156548449536 data:0x000000000202000b
     kworker/2:3-139     [002] ....   137.048828: rtcpu_vinotify_event: tstamp:4892663654 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156548466272 data:0x000000000002000b
     kworker/2:3-139     [002] ....   137.048830: rtcpu_vinotify_event: tstamp:4893181425 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156566227104 data:0x0000000000000010
     kworker/2:3-139     [002] ....   137.048848: rtcpu_vinotify_event: tstamp:4893181599 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156566227200 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.048849: rtcpu_vinotify_event: tstamp:4893181791 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156566338240 data:0x0000000000000001
     kworker/2:3-139     [002] ....   137.048850: rtcpu_vinotify_event: tstamp:4893181967 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156566348960 data:0x000000000802000c
     kworker/2:3-139     [002] ....   137.048850: rtcpu_vinotify_event: tstamp:4893182154 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156566584896 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   137.048851: rtcpu_vinotify_event: tstamp:4893182320 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156566628352 data:0x000000003100000d
     kworker/2:3-139     [002] ....   137.048851: rtcpu_vinotify_event: tstamp:4893182505 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156572653120 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.048852: rtcpu_vinotify_event: tstamp:4893182667 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156572663168 data:0x000000000202000c
     kworker/2:3-139     [002] ....   137.048852: rtcpu_vinotify_event: tstamp:4893182872 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156578970784 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.048853: rtcpu_vinotify_event: tstamp:4893183034 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156578980960 data:0x000000000202000c
     kworker/2:3-139     [002] ....   137.048854: rtcpu_vinotify_event: tstamp:4893183220 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156581543744 data:0x0000000005130002
     kworker/2:3-139     [002] ....   137.048854: rtcpu_vinotify_event: tstamp:4893183387 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156581544416 data:0x0000000000000020
     kworker/2:3-139     [002] ....   137.048855: rtcpu_vinotify_event: tstamp:4893183576 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156581544512 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.048855: rtcpu_vinotify_event: tstamp:4893183738 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156581544640 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.048869: rtcpu_vinotify_event: tstamp:4893667443 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156581605664 data:0x000000000202000c
     kworker/2:3-139     [002] ....   137.048869: rtcpu_vinotify_event: tstamp:4893667612 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156581622368 data:0x000000000002000c
     kworker/2:3-139     [002] ....   137.048870: rtcpu_vinotify_event: tstamp:4894209930 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156599383264 data:0x0000000000000010
     kworker/2:3-139     [002] ....   137.048871: rtcpu_vinotify_event: tstamp:4894210101 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156599383360 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.048872: rtcpu_vinotify_event: tstamp:4894210293 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156599494400 data:0x0000000000000001
     kworker/2:3-139     [002] ....   137.048872: rtcpu_vinotify_event: tstamp:4894210455 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156599505216 data:0x000000000802000d
     kworker/2:3-139     [002] ....   137.048873: rtcpu_vinotify_event: tstamp:4894210642 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156599753152 data:0xcd9ce20010000000
     kworker/2:3-139     [002] ....   137.048873: rtcpu_vinotify_event: tstamp:4894210804 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156599796576 data:0x000000003100000e
     kworker/2:3-139     [002] ....   137.048874: rtcpu_vinotify_event: tstamp:4894210987 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156605809280 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.048874: rtcpu_vinotify_event: tstamp:4894211148 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156605819392 data:0x000000000202000d
     kworker/2:3-139     [002] ....   137.048875: rtcpu_vinotify_event: tstamp:4894211333 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156612126976 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.048876: rtcpu_vinotify_event: tstamp:4894211493 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156612137056 data:0x000000000202000d
     kworker/2:3-139     [002] ....   137.048877: rtcpu_vinotify_event: tstamp:4894217985 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156614699904 data:0x0000000005130002
     kworker/2:3-139     [002] ....   137.048877: rtcpu_vinotify_event: tstamp:4894218148 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156614700608 data:0x0000000000000020
     kworker/2:3-139     [002] ....   137.048878: rtcpu_vinotify_event: tstamp:4894218342 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156614700672 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.048878: rtcpu_vinotify_event: tstamp:4894218504 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156614700832 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.104844: rtcpu_vinotify_event: tstamp:4894752743 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156614775712 data:0x000000000202000d
     kworker/2:3-139     [002] ....   137.104846: rtcpu_vinotify_event: tstamp:4894752912 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156614792448 data:0x000000000002000d
     kworker/2:3-139     [002] ....   137.104850: rtcpu_vinotify_event: tstamp:4895253664 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156632539424 data:0x0000000000000010
     kworker/2:3-139     [002] ....   137.104851: rtcpu_vinotify_event: tstamp:4895253837 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156632539552 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.104852: rtcpu_vinotify_event: tstamp:4895254029 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156632650592 data:0x0000000000000001
     kworker/2:3-139     [002] ....   137.104853: rtcpu_vinotify_event: tstamp:4895254192 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156632661408 data:0x000000000802000e
     kworker/2:3-139     [002] ....   137.104854: rtcpu_vinotify_event: tstamp:4895254379 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156632903712 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   137.104855: rtcpu_vinotify_event: tstamp:4895254542 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156632947136 data:0x000000003100000f
     kworker/2:3-139     [002] ....   137.104856: rtcpu_vinotify_event: tstamp:4895254725 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156638965440 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.104858: rtcpu_vinotify_event: tstamp:4895254887 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156638975520 data:0x000000000202000e
     kworker/2:3-139     [002] ....   137.104859: rtcpu_vinotify_event: tstamp:4895255073 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156645283136 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.104860: rtcpu_vinotify_event: tstamp:4895255240 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156645293216 data:0x000000000202000e
     kworker/2:3-139     [002] ....   137.104861: rtcpu_vinotify_event: tstamp:4895255425 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156647856064 data:0x0000000005130002
     kworker/2:3-139     [002] ....   137.104891: rtcpu_vinotify_event: tstamp:4895255586 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156647856768 data:0x0000000000000020
     kworker/2:3-139     [002] ....   137.104892: rtcpu_vinotify_event: tstamp:4895255777 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156647856832 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.104893: rtcpu_vinotify_event: tstamp:4895255940 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156647856992 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.104894: rtcpu_vinotify_event: tstamp:4895782654 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156647918048 data:0x000000000202000e
     kworker/2:3-139     [002] ....   137.104895: rtcpu_vinotify_event: tstamp:4895782824 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156647934752 data:0x000000000002000e
     kworker/2:3-139     [002] ....   137.160845: rtcpu_vinotify_event: tstamp:4896289795 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156665695616 data:0x0000000000000010
     kworker/2:3-139     [002] ....   137.160847: rtcpu_vinotify_event: tstamp:4896289969 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156665695680 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.160849: rtcpu_vinotify_event: tstamp:4896290160 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156665806752 data:0x0000000000000001
     kworker/2:3-139     [002] ....   137.160850: rtcpu_vinotify_event: tstamp:4896290323 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156665817504 data:0x000000000802000f
     kworker/2:3-139     [002] ....   137.160881: rtcpu_vinotify_event: tstamp:4896290511 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156666076064 data:0xcd9ce20010000000
     kworker/2:3-139     [002] ....   137.160882: rtcpu_vinotify_event: tstamp:4896290674 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156666119488 data:0x0000000031000010
     kworker/2:3-139     [002] ....   137.160883: rtcpu_vinotify_event: tstamp:4896290860 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156672121632 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.160884: rtcpu_vinotify_event: tstamp:4896291022 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156672131744 data:0x000000000202000f
     kworker/2:3-139     [002] ....   137.160885: rtcpu_vinotify_event: tstamp:4896291209 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156678439296 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.160886: rtcpu_vinotify_event: tstamp:4896291371 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156678449408 data:0x000000000202000f
     kworker/2:3-139     [002] ....   137.160887: rtcpu_vinotify_event: tstamp:4896291556 cch:1 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:156681012256 data:0x0000000005130002
     kworker/2:3-139     [002] ....   137.160888: rtcpu_vinotify_event: tstamp:4896291718 cch:1 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:156681012960 data:0x0000000000000020
     kworker/2:3-139     [002] ....   137.160890: rtcpu_vinotify_event: tstamp:4896291906 cch:1 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:156681013024 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.160891: rtcpu_vinotify_event: tstamp:4896292072 cch:1 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:156681013152 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.160892: rtcpu_vinotify_event: tstamp:4896813698 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156681074144 data:0x000000000202000f
     kworker/2:3-139     [002] ....   137.160893: rtcpu_vinotify_event: tstamp:4896813864 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156681090880 data:0x000000000002000f
     kworker/2:3-139     [002] ....   137.160922: rtcpu_vinotify_event: tstamp:4897326100 cch:1 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:156698851776 data:0x0000000000000010
     kworker/2:3-139     [002] ....   137.160923: rtcpu_vinotify_event: tstamp:4897326272 cch:1 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:156698851872 data:0x0000000800000000
     kworker/2:3-139     [002] ....   137.160924: rtcpu_vinotify_event: tstamp:4897326460 cch:1 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:156698962912 data:0x0000000000000001
     kworker/2:3-139     [002] ....   137.160925: rtcpu_vinotify_event: tstamp:4897326621 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156698973696 data:0x0000000008020010
     kworker/2:3-139     [002] ....   137.160926: rtcpu_vinotify_event: tstamp:4897326805 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156699212864 data:0xcd9ce50010000000
     kworker/2:3-139     [002] ....   137.160927: rtcpu_vinotify_event: tstamp:4897326970 cch:1 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:156699256288 data:0x0000000031000011
     kworker/2:3-139     [002] ....   137.160928: rtcpu_vinotify_event: tstamp:4897327155 cch:1 vi:0 tag:ATOMP_FRAME_NLINES_DONE channel:0x23 frame:0 vi_tstamp:156705277760 data:0x0000000000000000
     kworker/2:3-139     [002] ....   137.160929: rtcpu_vinotify_event: tstamp:4897327316 cch:1 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:156705287904 data:0x0000000002020010
3

The trace looks like fine could be the pipeline element problem.
Please check with below pipeline.

export DISPLy=:0
gst-launch-1.0 nvarguscamerasrc sensor-id=1 ! 'video/x-raw(memory:NVMM), width=(int)3280, height=(int)2464, format=(string)NV12, framerate=(fraction)21/1' ! queue ! nvvidconv ! xvimagesink -e
4

Thanks for pointing that out! Here is the output, unfortunately it exits with some error:

user@jetson:~$ export DISPLy=:0
user@jetson:~$ 
user@jetson:~$ gst-launch-1.0 nvarguscamerasrc sensor-id=1 ! 'video/x-raw(memory:NVMM), width=(int)1600, height=(int)1300, format=(string)GB10, framerate=(fraction)30/1' ! queue ! nvvidconv ! xvimagesink -e
WARNING: erroneous pipeline: could not link nvarguscamerasrc0 to queue0, nvarguscamerasrc0 can't handle caps video/x-raw(memory:NVMM), width=(int)1600, height=(int)1300, format=(string)GB10, framerate=(fraction)30/1
user@jetson:~$ 
user@jetson:~$ 
user@jetson:~$

Alternately I tried with V4L like that:

user@jetson:~$ v4l2-ctl -d /dev/video0 --set-ctrl bypass_mode=0 --stream-mmap --stream-count=1 --stream-to=test.raw --verbose --set-fmt-video=width=1600,height=1300

and here is the log:

tracer: nop
#
# entries-in-buffer/entries-written: 553/553   #P:4
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/0:3-401     [000] ....   127.878883: rtcpu_dbg_set_loglevel: tstamp:4610433756 old:0 new:2
        v4l2-ctl-3299    [001] ....   131.549053: tegra_channel_open: vi-output, s5k 30-0010
        v4l2-ctl-3299    [000] ....   131.559719: tegra_channel_set_power: s5k 30-0010 : 0x1
        v4l2-ctl-3299    [000] ....   131.559742: camera_common_s_power: status : 0x1
        v4l2-ctl-3299    [000] ....   131.564315: tegra_channel_set_power: 13e10000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-3299    [000] ....   131.564323: csi_s_power: enable : 0x1
        v4l2-ctl-3299    [000] ....   131.571532: tegra_channel_capture_setup: vnc_id 0 W 1600 H 1300 fmt c4
        v4l2-ctl-3299    [000] ....   131.572398: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-3299    [001] ....   131.575815: tegra_channel_set_stream: 13e10000.host1x:nvcsi@15a00000- : 0x1
        v4l2-ctl-3299    [001] ....   131.575818: csi_s_stream: enable : 0x1
        v4l2-ctl-3299    [001] ....   131.576200: tegra_channel_set_stream: s5k 30-0010 : 0x1
     kworker/0:0-5       [000] ....   131.630881: rtcpu_vinotify_event: tstamp:4727798524 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:151273383648 data:0xcd9ce50010000000
     kworker/0:0-5       [000] ....   131.630885: rtcpu_vinotify_event: tstamp:4727798695 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:151273389152 data:0x0000000031000001
     kworker/0:0-5       [000] ....   131.630886: rtcpu_vinotify_event: tstamp:4727798886 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:151273452096 data:0xcd9ce20010000000
     kworker/0:0-5       [000] ....   131.630887: rtcpu_vinotify_event: tstamp:4727799049 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:151273457664 data:0x0000000031000002
     kworker/0:0-5       [000] ....   131.854920: rtcpu_vinotify_event: tstamp:4734852449 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151514431712 data:0x0000000000000010
     kworker/0:0-5       [000] ....   131.854925: rtcpu_vinotify_event: tstamp:4734852619 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:151514431776 data:0x0000000800000000
     kworker/0:0-5       [000] ....   131.854927: rtcpu_vinotify_event: tstamp:4734852807 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:151514443520 data:0x0000000000000001
     kworker/0:0-5       [000] ....   131.854928: rtcpu_vinotify_event: tstamp:4734852969 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151514473920 data:0x0000000008020001
     kworker/0:0-5       [000] ....   131.854929: rtcpu_vinotify_event: tstamp:4735394728 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151528257472 data:0x0000000000000020
     kworker/0:0-5       [000] ....   131.854930: rtcpu_vinotify_event: tstamp:4735394900 cch:0 vi:0 tag:CHANSEL_SHORT_FRAME channel:0x01 frame:0 vi_tstamp:151528257472 data:0x0000200001000000
     kworker/0:0-5       [000] ....   131.854931: rtcpu_vinotify_event: tstamp:4735395089 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151528323104 data:0x0000000001020001
     kworker/0:0-5       [000] ....   131.854932: rtcpu_vinotify_event: tstamp:4735395257 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:151528257536 data:0x0000000800000000
     kworker/0:0-5       [000] ....   131.854933: rtcpu_vinotify_event: tstamp:4735395446 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:151528620320 data:0xcd9ce50010000000
     kworker/0:0-5       [000] ....   131.854960: rtcpu_vinotify_event: tstamp:4735395608 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:151528663776 data:0x0000000031000003
     kworker/0:0-5       [000] ....   131.854961: rtcpu_vinotify_event: tstamp:4735819227 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151546096288 data:0x0000000000000010
     kworker/0:0-5       [000] ....   131.854962: rtcpu_vinotify_event: tstamp:4735819394 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:151546096384 data:0x0000000800000000
 vi-output, s5k2-3301    [001] ....   131.860687: tegra_channel_capture_frame: sof:151.546207456
 vi-output, s5k2-3301    [001] ....   131.860691: tegra_channel_capture_frame: eof:151.561413824
 vi-output, s5k2-3301    [001] ....   131.893905: tegra_channel_capture_frame: sof:151.579363616
 vi-output, s5k2-3301    [001] ....   131.893907: tegra_channel_capture_frame: eof:151.594569984
     kworker/0:0-5       [000] ....   131.910862: rtcpu_vinotify_event: tstamp:4736317039 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:151546207456 data:0x0000000000000001
     kworker/0:0-5       [000] ....   131.910864: rtcpu_vinotify_event: tstamp:4736317207 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151546222112 data:0x0000000008020002
     kworker/0:0-5       [000] ....   131.910866: rtcpu_vinotify_event: tstamp:4736317396 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:151561412928 data:0x0000000005130002
     kworker/0:0-5       [000] ....   131.910867: rtcpu_vinotify_event: tstamp:4736317558 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151561413632 data:0x0000000000000020
     kworker/0:0-5       [000] ....   131.910868: rtcpu_vinotify_event: tstamp:4736317747 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:151561413728 data:0x0000000800000000
     kworker/0:0-5       [000] ....   131.910869: rtcpu_vinotify_event: tstamp:4736317912 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:151561413824 data:0x0000000000000000
     kworker/0:0-5       [000] ....   131.910870: rtcpu_vinotify_event: tstamp:4736318098 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151561466240 data:0x0000000002020002
     kworker/0:0-5       [000] ....   131.910870: rtcpu_vinotify_event: tstamp:4736318261 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151561482688 data:0x0000000000020002
     kworker/0:0-5       [000] ....   131.910904: rtcpu_vinotify_event: tstamp:4736863716 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151579252480 data:0x0000000000000010
     kworker/0:0-5       [000] ....   131.910905: rtcpu_vinotify_event: tstamp:4736863889 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:151579252576 data:0x0000000800000000
     kworker/0:0-5       [000] ....   131.910906: rtcpu_vinotify_event: tstamp:4736864079 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:151579363616 data:0x0000000000000001
     kworker/0:0-5       [000] ....   131.910907: rtcpu_vinotify_event: tstamp:4736864241 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151579374368 data:0x0000000008020003
     kworker/0:0-5       [000] ....   131.910908: rtcpu_vinotify_event: tstamp:4737338975 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:151579695840 data:0xcd9ce20010000000
     kworker/0:0-5       [000] ....   131.910909: rtcpu_vinotify_event: tstamp:4737339144 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:151579739264 data:0x0000000031000004
     kworker/0:0-5       [000] ....   131.910910: rtcpu_vinotify_event: tstamp:4737339332 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:151594569088 data:0x0000000005130002
     kworker/0:0-5       [000] ....   131.910911: rtcpu_vinotify_event: tstamp:4737339494 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151594569792 data:0x0000000000000020
     kworker/0:0-5       [000] ....   131.910912: rtcpu_vinotify_event: tstamp:4737836003 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:151594569920 data:0x0000000800000000
     kworker/0:0-5       [000] ....   131.910913: rtcpu_vinotify_event: tstamp:4737836171 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:151594569984 data:0x0000000000000000
     kworker/0:0-5       [000] ....   131.910914: rtcpu_vinotify_event: tstamp:4737836360 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151594635200 data:0x0000000002020003
     kworker/0:0-5       [000] ....   131.910915: rtcpu_vinotify_event: tstamp:4737836522 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151594651936 data:0x0000000000020003
 vi-output, s5k2-3301    [001] ....   131.926988: tegra_channel_capture_frame: sof:151.612519776
 vi-output, s5k2-3301    [001] ....   131.926990: tegra_channel_capture_frame: eof:151.627726176
     kworker/0:0-5       [000] ....   131.966874: rtcpu_vinotify_event: tstamp:4738378663 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151612408640 data:0x0000000000000010
     kworker/0:0-5       [000] ....   131.966877: rtcpu_vinotify_event: tstamp:4738378834 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:151612408736 data:0x0000000800000000
     kworker/0:0-5       [000] ....   131.966879: rtcpu_vinotify_event: tstamp:4738379027 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:151612519776 data:0x0000000000000001
     kworker/0:0-5       [000] ....   131.966880: rtcpu_vinotify_event: tstamp:4738379189 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151612530592 data:0x0000000008020004
     kworker/0:0-5       [000] ....   131.966881: rtcpu_vinotify_event: tstamp:4738379375 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:151627725312 data:0x0000000005130002
     kworker/0:0-5       [000] ....   131.966882: rtcpu_vinotify_event: tstamp:4738379537 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151627726016 data:0x0000000000000020
     kworker/0:0-5       [000] ....   131.966883: rtcpu_vinotify_event: tstamp:4738379726 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:151627726080 data:0x0000000800000000
     kworker/0:0-5       [000] ....   131.966884: rtcpu_vinotify_event: tstamp:4738379888 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:151627726176 data:0x0000000000000000
     kworker/0:0-5       [000] ....   131.966885: rtcpu_vinotify_event: tstamp:4738380072 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151627778592 data:0x0000000002020004
     kworker/0:0-5       [000] ....   131.966886: rtcpu_vinotify_event: tstamp:4738380235 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151627795008 data:0x0000000000020004
     kworker/0:0-5       [000] ....   131.966888: rtcpu_vinotify_error: tstamp:4738931670 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151645675936 data:0x0000000000000569
     kworker/0:0-5       [000] ....   131.966889: rtcpu_vinotify_event: tstamp:4738932465 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151645564832 data:0x0000000000000010
     kworker/0:0-5       [000] ....   131.966890: rtcpu_vinotify_event: tstamp:4738932629 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151645675936 data:0x0000000000000569
     kworker/0:0-5       [000] ....   131.966891: rtcpu_vinotify_event: tstamp:4739463205 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151660882144 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.022945: rtcpu_vinotify_error: tstamp:4739967576 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151678832160 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.022948: rtcpu_vinotify_event: tstamp:4740006592 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151678720992 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.022950: rtcpu_vinotify_event: tstamp:4740006784 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151678832160 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.022951: rtcpu_vinotify_event: tstamp:4740548327 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151694038336 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.022952: rtcpu_vinotify_error: tstamp:4741003764 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151711988320 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.022953: rtcpu_vinotify_event: tstamp:4741090630 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151711877184 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.022954: rtcpu_vinotify_event: tstamp:4741090796 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151711988320 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.078863: rtcpu_vinotify_event: tstamp:4741633462 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151727194528 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.078866: rtcpu_vinotify_error: tstamp:4742039838 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151745144480 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.078868: rtcpu_vinotify_event: tstamp:4742175748 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151745033344 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.078869: rtcpu_vinotify_event: tstamp:4742175939 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151745144480 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.078870: rtcpu_vinotify_event: tstamp:4742718585 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151760350656 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.078871: rtcpu_vinotify_error: tstamp:4743075966 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151778300672 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.134905: rtcpu_vinotify_event: tstamp:4743260871 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151778189504 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.134909: rtcpu_vinotify_event: tstamp:4743261041 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151778300672 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.134910: rtcpu_vinotify_event: tstamp:4743803703 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151793506848 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.134912: rtcpu_vinotify_error: tstamp:4744112095 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151811456832 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.134913: rtcpu_vinotify_event: tstamp:4744345979 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151811345696 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.134914: rtcpu_vinotify_event: tstamp:4744346169 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151811456832 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.134915: rtcpu_vinotify_event: tstamp:4744586195 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151826663040 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.190844: rtcpu_vinotify_error: tstamp:4745148231 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151844612992 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.190847: rtcpu_vinotify_event: tstamp:4745160578 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151844501888 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.190848: rtcpu_vinotify_event: tstamp:4745160744 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151844612992 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.190849: rtcpu_vinotify_event: tstamp:4745702278 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151859819200 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.190871: rtcpu_vinotify_error: tstamp:4746184357 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151877769184 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.190872: rtcpu_vinotify_event: tstamp:4746245666 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151877658048 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.190873: rtcpu_vinotify_event: tstamp:4746245856 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151877769184 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.246862: rtcpu_vinotify_event: tstamp:4746787397 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151892975360 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.246865: rtcpu_vinotify_error: tstamp:4747220489 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151910925344 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.246867: rtcpu_vinotify_event: tstamp:4747330779 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151910814208 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.246868: rtcpu_vinotify_event: tstamp:4747330946 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151910925344 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.246869: rtcpu_vinotify_event: tstamp:4747872513 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151926131552 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.246870: rtcpu_vinotify_error: tstamp:4748256614 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151944081536 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.302881: rtcpu_vinotify_event: tstamp:4748415900 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151943970400 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.302884: rtcpu_vinotify_event: tstamp:4748416094 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151944081536 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.302885: rtcpu_vinotify_event: tstamp:4748957637 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151959287712 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.302887: rtcpu_vinotify_error: tstamp:4749292749 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151977237696 data:0x0000000000000569

So as you can see some error started popping-up at some point - is it a crucial error and what is caused by?

     kworker/0:0-5       [000] ....   131.966885: rtcpu_vinotify_event: tstamp:4738380072 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151627778592 data:0x0000000002020004
     kworker/0:0-5       [000] ....   131.966886: rtcpu_vinotify_event: tstamp:4738380235 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:151627795008 data:0x0000000000020004
     kworker/0:0-5       [000] ....   131.966888: rtcpu_vinotify_error: tstamp:4738931670 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151645675936 data:0x0000000000000569
     kworker/0:0-5       [000] ....   131.966889: rtcpu_vinotify_event: tstamp:4738932465 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151645564832 data:0x0000000000000010
     kworker/0:0-5       [000] ....   131.966890: rtcpu_vinotify_event: tstamp:4738932629 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151645675936 data:0x0000000000000569
     kworker/0:0-5       [000] ....   131.966891: rtcpu_vinotify_event: tstamp:4739463205 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151660882144 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.022945: rtcpu_vinotify_error: tstamp:4739967576 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151678832160 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.022948: rtcpu_vinotify_event: tstamp:4740006592 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151678720992 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.022950: rtcpu_vinotify_event: tstamp:4740006784 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151678832160 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.022951: rtcpu_vinotify_event: tstamp:4740548327 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151694038336 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.022952: rtcpu_vinotify_error: tstamp:4741003764 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151711988320 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.022953: rtcpu_vinotify_event: tstamp:4741090630 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:151711877184 data:0x0000000000000010
     kworker/0:0-5       [000] ....   132.022954: rtcpu_vinotify_event: tstamp:4741090796 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151711988320 data:0x0000000000000569
     kworker/0:0-5       [000] ....   132.078863: rtcpu_vinotify_event: tstamp:4741633462 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:151727194528 data:0x0000000000000020
     kworker/0:0-5       [000] ....   132.078866: rtcpu_vinotify_error: tstamp:4742039838 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:151745144480 data:0x0000000000000569
5

Sorry for the typo
should be export DISPLAY=:0

1 Like
6

Hi, I’m here just to confirm that all the issues are now resolved by simply export DISPLAY=:0. I can see the video stream from the camera. Thanks for your support!

7

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