Camera not working on 1924x720

As you said, it means that when i set the format to 1984x720 and execute gst-launch, i can only see the first frame.

here is the results of v4l2-ctl -d /dev/video0 --list-formats-ext.

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

[0]: 'UYVY' (UYVY 4:2:2)
Size: Discrete 1924x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1920x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1984x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 800x480
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1024x768
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1024x1920
Interval: Discrete 0.017s (60.000 fps)
[1]: 'NV16' (Y/CbCr 4:2:2)
Size: Discrete 1924x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1920x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1984x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 800x480
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1024x768
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1024x1920
Interval: Discrete 0.017s (60.000 fps)
[2]: 'UYVY' (UYVY 4:2:2)
Size: Discrete 1924x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1920x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1984x720
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 800x480
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1024x768
Interval: Discrete 0.017s (60.000 fps)
Size: Discrete 1024x1920
Interval: Discrete 0.017s (60.000 fps)

And that scene is from Mystery of the Nile, riding a boat.
Thanks.

Thanks

hello ghdlghdl0558,

thanks for sharing format dumps.

let’s check it’s not streaming, or very low frame-rate,
you may try below commands to disable preview and shows frame-rate only for confirmation.
for example,
$ gst-launch-1.0 v4l2src device=/dev/video0 ! "video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720" ! fpsdisplaysink text-overlay=0 video-sink=fakesink sync=0 -v

I tried command, but nothing happened.
below is output of command.

nvidia@nvidia-desktop:~/Tx2DeviceDriver/ds90ub940n_orin$ gst-launch-1.0 v4l2src device=/dev/video0 ! "video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720" ! fpsdisplaysink text-overlay=0 video-sink=fakesink sync=0 -v
Setting pipeline to PAUSED ...
Pipeline is live and does not need PREROLL ...
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0/GstFakeSink:fakesink0: sync = false
Setting pipeline to PLAYING ...
/GstPipeline:pipeline0/GstV4l2Src:v4l2src0.GstPad:src: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstCapsFilter:capsfilter0.GstPad:src: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0.GstGhostPad:sink.GstProxyPad:proxypad0: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
New clock: GstSystemClock
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0/GstFakeSink:fakesink0.GstPad:sink: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0.GstGhostPad:sink: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstCapsFilter:capsfilter0.GstPad:sink: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0/GstFakeSink:fakesink0: sync = false

and kernel log

Jan  4 15:11:10 nvidia-desktop kernel: [  145.347626] ds90ub940n 8-002c: ds90ub940n_power_on: power on
Jan  4 15:11:10 nvidia-desktop kernel: [  145.352623] ds90ub940n 8-002c: ds90ub940n_power_on: power complete
Jan  4 15:11:10 nvidia-desktop kernel: [  145.360698] bwmgr API not supported
Jan  4 15:11:10 nvidia-desktop kernel: [  145.361304] ds90ub940n 8-002c: ds90ub940n_set_mode: mode_prop_idx=1
Jan  4 15:11:10 nvidia-desktop kernel: [  145.364935] ko_frmfmt = (1984,720)
Jan  4 15:11:10 nvidia-desktop kernel: [  145.370149] tegra-camrtc-capture-vi tegra-capture-vi: corr_err: discarding frame 0, flags: 0, err_data 512
Jan  4 15:11:10 nvidia-desktop kernel: [  145.375008] ds90ub940n 8-002c: ds90ub940n_start_streaming: mode_prop_idx=1
Jan  4 15:11:10 nvidia-desktop kernel: [  145.382785] [RCE] ERROR: camera-ip/nvcsi/nvcsi.c:1927 [nvcsi_stream_set_config] "MIPI clock rate not known. Using 250000 kHz
Jan  4 15:11:10 nvidia-desktop kernel: [  145.382795] [RCE] "
Jan  4 15:11:10 nvidia-desktop kernel: [  145.386809] tegra-camrtc-capture-vi tegra-capture-vi: corr_err: discarding frame 0, flags: 0, err_data 512
Jan  4 15:11:10 nvidia-desktop kernel: [  145.403477] tegra-camrtc-capture-vi tegra-capture-vi: corr_err: discarding frame 0, flags: 0, err_data 512
Jan  4 15:11:10 nvidia-desktop kernel: [  145.420135] tegra-camrtc-capture-vi tegra-capture-vi: corr_err: discarding frame 0, flags: 0, err_data 512

and gtksink output for “gst-launch-1.0 v4l2src device=/dev/video0 ! “video/x-raw,format=(string)UYVY,width=1984,height=720” ! videoconvert ! gtksink sync=false” is below (first frame only)

I don’t think it’s low framerate.
Because when i try to use 1984x720 setting for 1924x720 input, sometimes it comes out like the above capture, but sometimes it does not work and whole system freezes(needs to reboot).
And when i use 1924x720 setting, it moves smoothly(60fps), although it come out strangely like below capture.

Thanks

hello ghdlghdl0558,

that’s due to below, those buffers has dropped by discarding frame.

I also notice there’s log to indicate MIPI clock rate not known.
could you please try below commands to boost all the VI/CSI/ISP clocks. it’ll ignore clock settings.

sudo su
echo 1 > /sys/kernel/debug/bpmp/debug/clk/vi/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/isp/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/nvcsi/mrq_rate_locked
echo 1 > /sys/kernel/debug/bpmp/debug/clk/emc/mrq_rate_locked
cat /sys/kernel/debug/bpmp/debug/clk/vi/max_rate |tee /sys/kernel/debug/bpmp/debug/clk/vi/rate
cat /sys/kernel/debug/bpmp/debug/clk/isp/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/isp/rate
cat /sys/kernel/debug/bpmp/debug/clk/nvcsi/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/nvcsi/rate
cat /sys/kernel/debug/bpmp/debug/clk/emc/max_rate | tee /sys/kernel/debug/bpmp/debug/clk/emc/rate

i already applied boost command before gst-launch & v4l2-ctl.

But "[RCE] ERROR: camera-ip/nvcsi/nvcsi.c:1927 [nvcsi_stream_set_config] “MIPI clock rate not known. Using 250000 kHz” is still shown on kernel log.

Thanks

hello ghdlghdl0558,

that’s weird. it’s NVCSI to set stream config; this reported while you’re using invalid mipi clock rate.

could you please refer to Topic 226574 to download the JetPack-5.0.2/l4t-r35.1 camera firmware with debug flag enabled.
please also apply that debug firmware to gather more details.
thanks

i enabled debug flag, and tested gst-launch-1.0 v4l2src device=/dev/video0 ! "video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720" ! fpsdisplaysink text-overlay=0 video-sink=fakesink sync=0 -v

below is result kernel log. (1984x720)

[  273.749039] ds90ub940n 8-002c: ds90ub940n_power_on: power on
[  273.750418] ds90ub940n 8-002c: ds90ub940n_power_on: power complete
[  273.758593] bwmgr API not supported
[  273.759351] ds90ub940n 8-002c: ds90ub940n_set_mode: mode_prop_idx=1
[  273.762968] ko_frmfmt = (1984,720) 
[  273.766537] tegra-camrtc-capture-vi tegra-capture-vi: corr_err: discarding frame 0, flags: 0, err_data 512
[  273.773012] ds90ub940n 8-002c: ds90ub940n_start_streaming: mode_prop_idx=1
[  273.773845] [RCE] VM0 deactivating.VM0 activating.NVCSILP clock rate = 408000000 Hz.
[  273.773848] [RCE] VI5: tegra_vi_channel_alloc()
[  273.773849] [RCE] VI5: unit 0 stream ID table:
[  273.773850] [RCE] VI5: unit 0 stream ID table from THI:
[  273.773851] [RCE] VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00
[  273.773853] [RCE] VI5: vi5_trace_enable: configuring trace buffer at iova=0x8375dda00 size=32768
[  273.773854] [RCE] VI5: tegra_vi_channel_alloc: ch=35
[  273.773856] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  273.773857] [RCE] VI5: channel_submit(35, bfebd000)
[  273.773858] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  273.773860] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  273.773861] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  273.773863] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  273.773864] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  273.773866] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  273.773867] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  273.773868] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  273.773869] [RCE] VI5: enabling stream 0
[  273.773872] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  273.773873] [RCE] VI5: channel_submit(35, bfebd180)
[  273.773874] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  273.773876] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  273.773877] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  273.773879] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  273.773880] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  273.773881] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  273.773883] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  273.773884] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  273.773885] [RCE] tegra_nvcsi_stream_set_config(vm0, stream=0, csi=0)
[  273.773887] [RCE] ERROR: camera-ip/nvcsi/nvcsi.c:1927 [nvcsi_stream_set_config] "MIPI clock rate not known. Using 250000 kHz
[  273.773888] [RCE] "
[  273.773890] [RCE] MIPI clock = 250000 kHz, tHS-SETTLE = 0, tCLK-SETTLE = 0
[  273.773891] [RCE] tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
[  273.773892] [RCE] nvcsi_calc_ths_settle ths_settle 71
[  273.773893] [RCE] nvcsi_calc_ths_settle ths_settle 71
[  273.773894] [RCE] nvcsi_calc_ths_settle ths_settle 71
[  273.773895] [RCE] nvcsi_calc_tclk_settle tclk_settle 75
[  273.773899] [RCE] VI5: vi5_process_error_fifo: tag=0x03 ch=0 frame=0 ts=297879495392 data=0x00000001 ext_data=0x00000000
[  273.773901] [RCE] VI5: error_to_notify_bits CSIMUX_STREAM error detected
[  273.773902] [RCE] VI5: vi5_notify_error(35) status 0x3 tag 0x3, data 0x1
[  273.773904] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  273.773905] [RCE] VI5: vi5_handle_sof(35)
[  273.773906] [RCE] VI5: vi5_irq_process_channel_event: ch35 engine status=0x00000200 task 1
[  273.773908] [RCE] VI5: error_to_notify_bits Channel FALCON_ERROR identified
[  273.773909] [RCE] VI5: vi5_notify_error(35) status 0xe tag 0x0, data 0x200
[  273.773911] [RCE] VI5: Error 14 delivered to capture channel 0.
[  273.773912] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x10
[  273.773914] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  273.773915] [RCE] VI5: channel_submit(35, bfebd300)
[  273.773917] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  273.773918] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  273.773920] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  273.773921] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  273.773922] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  273.773924] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  273.773925] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  273.773926] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  273.783200] tegra-camrtc-capture-vi tegra-capture-vi: corr_err: discarding frame 0, flags: 0, err_data 512
[  273.799868] tegra-camrtc-capture-vi tegra-capture-vi: corr_err: discarding frame 0, flags: 0, err_data 512
[  273.816527] tegra-camrtc-capture-vi tegra-capture-vi: corr_err: discarding frame 0, flags: 0, err_data 512
[  273.829845] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  273.829849] [RCE] VI5: vi5_handle_sof(35)
[  273.829851] [RCE] VI5: vi5_irq_process_channel_event: ch35 engine status=0x00000200 task 1
[  273.829854] [RCE] VI5: error_to_notify_bits Channel FALCON_ERROR identified
[  273.829856] [RCE] VI5: vi5_notify_error(35) status 0xe tag 0x0, data 0x200
[  273.829857] [RCE] VI5: Error 14 delivered to capture channel 0.
[  273.829859] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x10
[  273.829863] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  273.829870] [RCE] VI5: channel_submit(35, bfebd480)
[  273.829878] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  273.829885] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  273.829889] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  273.829891] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  273.829893] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  273.829895] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  273.829898] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  273.829899] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  273.829903] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  273.829904] [RCE] VI5: vi5_handle_sof(35)
[  273.829906] [RCE] VI5: vi5_irq_process_channel_event: ch35 engine status=0x00000200 task 1
[  273.829908] [RCE] VI5: error_to_notify_bits Channel FALCON_ERROR identified
[  273.829910] [RCE] VI5: vi5_notify_error(35) status 0xe tag 0x0, data 0x200
[  273.829914] [RCE] VI5: Error 14 delivered to capture channel 0.
[  273.829919] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x10
[  273.829925] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  273.829928] [RCE] VI5: vi5_handle_sof(35)
[  273.829932] [RCE] VI5: vi5_irq_process_channel_event: ch35 engine status=0x00000200 task 1
[  273.829936] [RCE] VI5: error_to_notify_bits Channel FALCON_ERROR identified
[  273.829938] [RCE] VI5: vi5_notify_error(35) status 0xe tag 0x0, data 0x200
[  273.829940] [RCE] VI5: Error 14 delivered to capture channel 0.
[  273.829941] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x10
[  273.885839] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=297953252096 data=0x000003c9 ext_data=0x00000000
[  273.885843] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  273.885846] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  273.885849] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=297969918176 data=0x000003c9 ext_data=0x00000000
[  273.885851] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  273.885852] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  273.885855] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=297986584224 data=0x000003c9 ext_data=0x00000000
[  273.885857] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  273.885859] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  273.885862] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=298003250304 data=0x000003c9 ext_data=0x00000000
[  273.885864] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  273.885865] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  273.941832] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=298019916384 data=0x000003c9 ext_data=0x00000000
[  273.941836] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  273.941839] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  273.941844] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=298036582432 data=0x000003c9 ext_data=0x00000000
[  273.941847] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  273.941850] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  273.941855] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=298053248512 data=0x000003c9 ext_data=0x00000000
[  273.941858] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  273.941860] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  273.997824] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=298069914560 data=0x000003c9 ext_data=0x00000000
[  273.997826] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified

and result of cat /sys/kernel/debug/tracing/trace (1984x720)

     kworker/7:2-159     [007] ....   273.773837: rtcpu_string: tstamp:9308538899 id:0x04010000 str:"VM0 activating."
     kworker/7:2-159     [007] ....   273.773840: rtcpu_string: tstamp:9308621678 id:0x04010000 str:"NVCSILP clock rate = 408000000 Hz.
"
     kworker/7:2-159     [007] ....   273.773847: rtcpu_string: tstamp:9308665213 id:0x04010000 str:"VI5: tegra_vi_channel_alloc()
"
     kworker/7:2-159     [007] ....   273.773849: rtcpu_string: tstamp:9308670591 id:0x04010000 str:"VI5: unit 0 stream ID table:
"
     kworker/7:2-159     [007] ....   273.773850: rtcpu_string: tstamp:9308670832 id:0x04010000 str:"VI5: unit 0 stream ID table from THI:
"
     kworker/7:2-159     [007] ....   273.773851: rtcpu_string: tstamp:9308671115 id:0x04010000 str:"VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00
"
     kworker/7:2-159     [007] ....   273.773852: rtcpu_string: tstamp:9308676319 id:0x04010000 str:"VI5: vi5_trace_enable: configuring trace buffer "
     kworker/7:2-159     [007] ....   273.773853: rtcpu_string: tstamp:9308676438 id:0x04010000 str:"at iova=0x8375dda00 size=32768
"
     kworker/7:2-159     [007] ....   273.773854: rtcpu_string: tstamp:9308677636 id:0x04010000 str:"VI5: tegra_vi_channel_alloc: ch=35
"
     kworker/7:2-159     [007] ....   273.773855: rtcpu_string: tstamp:9308681962 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/7:2-159     [007] ....   273.773856: rtcpu_string: tstamp:9308682097 id:0x04010000 str:"t. 0
"
     kworker/7:2-159     [007] ....   273.773857: rtcpu_string: tstamp:9308682333 id:0x04010000 str:"VI5: channel_submit(35, bfebd000)
"
     kworker/7:2-159     [007] ....   273.773858: rtcpu_string: tstamp:9308682837 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/7:2-159     [007] ....   273.773858: rtcpu_string: tstamp:9308682938 id:0x04010000 str:"t = 314
"
     kworker/7:2-159     [007] ....   273.773859: rtcpu_string: tstamp:9308683243 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/7:2-159     [007] ....   273.773860: rtcpu_string: tstamp:9308683344 id:0x04010000 str:"t = 345
"
     kworker/7:2-159     [007] ....   273.773861: rtcpu_string: tstamp:9308683642 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773861: rtcpu_string: tstamp:9308683744 id:0x04010000 str:"t = 359
"
     kworker/7:2-159     [007] ....   273.773862: rtcpu_string: tstamp:9308684043 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/7:2-159     [007] ....   273.773863: rtcpu_string: tstamp:9308684143 id:0x04010000 str:"t = 389
"
     kworker/7:2-159     [007] ....   273.773864: rtcpu_string: tstamp:9308684439 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773864: rtcpu_string: tstamp:9308684540 id:0x04010000 str:"t = 403
"
     kworker/7:2-159     [007] ....   273.773865: rtcpu_string: tstamp:9308684837 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773865: rtcpu_string: tstamp:9308684938 id:0x04010000 str:"t = 417
"
     kworker/7:2-159     [007] ....   273.773866: rtcpu_string: tstamp:9308685232 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/7:2-159     [007] ....   273.773867: rtcpu_string: tstamp:9308685332 id:0x04010000 str:" = 419
"
     kworker/7:2-159     [007] ....   273.773868: rtcpu_string: tstamp:9308685725 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/7:2-159     [007] ....   273.773869: rtcpu_string: tstamp:9308685917 id:0x04010000 str:"VI5: enabling stream 0
"
     kworker/7:2-159     [007] ....   273.773870: rtcpu_string: tstamp:9308687547 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/7:2-159     [007] ....   273.773871: rtcpu_string: tstamp:9308687649 id:0x04010000 str:"t. 0
"
     kworker/7:2-159     [007] ....   273.773873: rtcpu_string: tstamp:9308687881 id:0x04010000 str:"VI5: channel_submit(35, bfebd180)
"
     kworker/7:2-159     [007] ....   273.773874: rtcpu_string: tstamp:9308688275 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/7:2-159     [007] ....   273.773874: rtcpu_string: tstamp:9308688378 id:0x04010000 str:"t = 314
"
     kworker/7:2-159     [007] ....   273.773875: rtcpu_string: tstamp:9308688684 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/7:2-159     [007] ....   273.773875: rtcpu_string: tstamp:9308688785 id:0x04010000 str:"t = 345
"
     kworker/7:2-159     [007] ....   273.773876: rtcpu_string: tstamp:9308689082 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773877: rtcpu_string: tstamp:9308689182 id:0x04010000 str:"t = 359
"
     kworker/7:2-159     [007] ....   273.773878: rtcpu_string: tstamp:9308689480 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/7:2-159     [007] ....   273.773878: rtcpu_string: tstamp:9308689580 id:0x04010000 str:"t = 389
"
     kworker/7:2-159     [007] ....   273.773879: rtcpu_string: tstamp:9308689876 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773880: rtcpu_string: tstamp:9308689978 id:0x04010000 str:"t = 403
"
     kworker/7:2-159     [007] ....   273.773881: rtcpu_string: tstamp:9308690274 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773881: rtcpu_string: tstamp:9308690376 id:0x04010000 str:"t = 417
"
     kworker/7:2-159     [007] ....   273.773882: rtcpu_string: tstamp:9308690669 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/7:2-159     [007] ....   273.773883: rtcpu_string: tstamp:9308690770 id:0x04010000 str:" = 419
"
     kworker/7:2-159     [007] ....   273.773884: rtcpu_string: tstamp:9308691144 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/7:2-159     [007] ....   273.773885: rtcpu_string: tstamp:9308723274 id:0x04010000 str:"tegra_nvcsi_stream_set_config(vm0, stream=0, csi"
     kworker/7:2-159     [007] ....   273.773885: rtcpu_string: tstamp:9308723374 id:0x04010000 str:"=0)
"
     kworker/7:2-159     [007] ....   273.773886: rtcpu_string: tstamp:9308723725 id:0x04010000 str:"ERROR: camera-ip/nvcsi/nvcsi.c:1927 [nvcsi_strea"
     kworker/7:2-159     [007] ....   273.773887: rtcpu_string: tstamp:9308723827 id:0x04010000 str:"m_set_config] ""
     kworker/7:2-159     [007] ....   273.773887: rtcpu_string: tstamp:9308724070 id:0x04010000 str:"MIPI clock rate not known. Using 250000 kHz
"
     kworker/7:2-159     [007] ....   273.773888: rtcpu_string: tstamp:9308724209 id:0x04010000 str:""
"
     kworker/7:2-159     [007] ....   273.773889: rtcpu_string: tstamp:9308724533 id:0x04010000 str:"MIPI clock = 250000 kHz, tHS-SETTLE = 0, tCLK-SE"
     kworker/7:2-159     [007] ....   273.773890: rtcpu_string: tstamp:9308724633 id:0x04010000 str:"TTLE = 0
"
     kworker/7:2-159     [007] ....   273.773891: rtcpu_string: tstamp:9308726158 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
"
     kworker/7:2-159     [007] ....   273.773892: rtcpu_string: tstamp:9308733026 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 71
"
     kworker/7:2-159     [007] ....   273.773893: rtcpu_string: tstamp:9308733291 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 71
"
     kworker/7:2-159     [007] ....   273.773894: rtcpu_string: tstamp:9308733550 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 71
"
     kworker/7:2-159     [007] ....   273.773895: rtcpu_string: tstamp:9308733803 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 75
"
     kworker/7:2-159     [007] ....   273.773898: rtcpu_vinotify_error: tstamp:9308734887 cch:-1 vi:0 tag:CSIMUX_STREAM channel:0x00 frame:0 vi_tstamp:297879495392 data:0x0000000000000001
     kworker/7:2-159     [007] ....   273.773898: rtcpu_string: tstamp:9308735388 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x03 ch=0 frame"
     kworker/7:2-159     [007] ....   273.773898: rtcpu_string: tstamp:9308735490 id:0x04010000 str:"=0 ts=297879495392 data=0x00000001 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.773899: rtcpu_string: tstamp:9308735594 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.773900: rtcpu_string: tstamp:9308735932 id:0x04010000 str:"VI5: error_to_notify_bits CSIMUX_STREAM error de"
     kworker/7:2-159     [007] ....   273.773900: rtcpu_string: tstamp:9308736032 id:0x04010000 str:"tected
"
     kworker/7:2-159     [007] ....   273.773902: rtcpu_string: tstamp:9308736360 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0x3 tag 0x3, da"
     kworker/7:2-159     [007] ....   273.773902: rtcpu_string: tstamp:9308736461 id:0x04010000 str:"ta 0x1
"
     kworker/7:2-159     [007] ....   273.773903: rtcpu_string: tstamp:9308957430 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/7:2-159     [007] ....   273.773903: rtcpu_string: tstamp:9308957531 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/7:2-159     [007] ....   273.773904: rtcpu_string: tstamp:9308957785 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/7:2-159     [007] ....   273.773906: rtcpu_string: tstamp:9308958344 id:0x04010000 str:"VI5: vi5_irq_process_channel_event: ch35 engine "
     kworker/7:2-159     [007] ....   273.773906: rtcpu_string: tstamp:9308958445 id:0x04010000 str:"status=0x00000200 task 1
"
     kworker/7:2-159     [007] ....   273.773907: rtcpu_string: tstamp:9308958721 id:0x04010000 str:"VI5: error_to_notify_bits Channel FALCON_ERROR i"
     kworker/7:2-159     [007] ....   273.773908: rtcpu_string: tstamp:9308958823 id:0x04010000 str:"dentified
"
     kworker/7:2-159     [007] ....   273.773909: rtcpu_string: tstamp:9308959149 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xe tag 0x0, da"
     kworker/7:2-159     [007] ....   273.773909: rtcpu_string: tstamp:9308959253 id:0x04010000 str:"ta 0x200
"
     kworker/7:2-159     [007] ....   273.773910: rtcpu_string: tstamp:9308959590 id:0x04010000 str:"VI5: Error 14 delivered to capture channel 0.
"
     kworker/7:2-159     [007] ....   273.773911: rtcpu_string: tstamp:9308960066 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/7:2-159     [007] ....   273.773912: rtcpu_string: tstamp:9308960170 id:0x04010000 str:"_INT_STATUS = 0x10
"
     kworker/7:2-159     [007] ....   273.773913: rtcpu_string: tstamp:9308961083 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/7:2-159     [007] ....   273.773913: rtcpu_string: tstamp:9308961185 id:0x04010000 str:"t. 0
"
     kworker/7:2-159     [007] ....   273.773914: rtcpu_string: tstamp:9308961421 id:0x04010000 str:"VI5: channel_submit(35, bfebd300)
"
     kworker/7:2-159     [007] ....   273.773915: rtcpu_string: tstamp:9308961814 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/7:2-159     [007] ....   273.773916: rtcpu_string: tstamp:9308961915 id:0x04010000 str:"t = 314
"
     kworker/7:2-159     [007] ....   273.773917: rtcpu_string: tstamp:9308962239 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/7:2-159     [007] ....   273.773918: rtcpu_string: tstamp:9308962342 id:0x04010000 str:"t = 345
"
     kworker/7:2-159     [007] ....   273.773919: rtcpu_string: tstamp:9308962640 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773919: rtcpu_string: tstamp:9308962741 id:0x04010000 str:"t = 359
"
     kworker/7:2-159     [007] ....   273.773920: rtcpu_string: tstamp:9308963041 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/7:2-159     [007] ....   273.773921: rtcpu_string: tstamp:9308963142 id:0x04010000 str:"t = 389
"
     kworker/7:2-159     [007] ....   273.773922: rtcpu_string: tstamp:9308963469 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773922: rtcpu_string: tstamp:9308963574 id:0x04010000 str:"t = 403
"
     kworker/7:2-159     [007] ....   273.773923: rtcpu_string: tstamp:9308963873 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.773923: rtcpu_string: tstamp:9308963976 id:0x04010000 str:"t = 417
"
     kworker/7:2-159     [007] ....   273.773925: rtcpu_string: tstamp:9308964271 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/7:2-159     [007] ....   273.773925: rtcpu_string: tstamp:9308964374 id:0x04010000 str:" = 419
"
     kworker/7:2-159     [007] ....   273.773926: rtcpu_string: tstamp:9308964750 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/7:2-159     [007] ....   273.773928: rtcpu_vinotify_event: tstamp:9309009118 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297877972640 data:0x375cff0010000000
     kworker/7:2-159     [007] ....   273.773928: rtcpu_vinotify_event: tstamp:9309009257 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297877979232 data:0x0000000031000001
     kworker/7:2-159     [007] ....   273.773929: rtcpu_vinotify_event: tstamp:9309009410 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297878138048 data:0x375cfc0010000000
     kworker/7:2-159     [007] ....   273.773929: rtcpu_vinotify_event: tstamp:9309009542 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297878144736 data:0x0000000031000002
     kworker/7:2-159     [007] ....   273.773929: rtcpu_vinotify_event: tstamp:9309009695 cch:-1 vi:0 tag:CSIMUX_STREAM channel:0x00 frame:0 vi_tstamp:297879495392 data:0x0000000000000001
     kworker/7:2-159     [007] ....   273.773929: rtcpu_vinotify_event: tstamp:9309009829 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:297886051520 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.773929: rtcpu_vinotify_event: tstamp:9309009983 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:297886051520 data:0x0000000800000000
     kworker/7:2-159     [007] ....   273.773929: rtcpu_vinotify_event: tstamp:9309010119 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:297886587840 data:0x0000000000000001
     kworker/7:2-159     [007] ....   273.773930: rtcpu_vinotify_event: tstamp:9309010272 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:297886589088 data:0x0000000008020001
     kworker/7:2-159     [007] ....   273.773930: rtcpu_vinotify_event: tstamp:9309010406 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:0 vi_tstamp:297886599456 data:0x0000000000000200
     kworker/7:2-159     [007] ....   273.773930: rtcpu_vinotify_event: tstamp:9309010559 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:297886605440 data:0x0000000001020001
     kworker/7:2-159     [007] ....   273.829835: rtcpu_vinotify_event: tstamp:9309460634 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:0 vi_tstamp:297902387232 data:0x0000000002cf0202
     kworker/7:2-159     [007] ....   273.829837: rtcpu_vinotify_event: tstamp:9309460787 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:297902387808 data:0x0000000000000000
     kworker/7:2-159     [007] ....   273.829837: rtcpu_vinotify_event: tstamp:9309460919 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:297902716800 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.829837: rtcpu_vinotify_event: tstamp:9309461074 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:297902716800 data:0x0000000800000000
     kworker/7:2-159     [007] ....   273.829837: rtcpu_vinotify_event: tstamp:9309461206 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:297902717600 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.829838: rtcpu_string: tstamp:9309478242 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/7:2-159     [007] ....   273.829839: rtcpu_string: tstamp:9309478344 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/7:2-159     [007] ....   273.829848: rtcpu_string: tstamp:9309478598 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/7:2-159     [007] ....   273.829850: rtcpu_string: tstamp:9309479169 id:0x04010000 str:"VI5: vi5_irq_process_channel_event: ch35 engine "
     kworker/7:2-159     [007] ....   273.829851: rtcpu_string: tstamp:9309479271 id:0x04010000 str:"status=0x00000200 task 1
"
     kworker/7:2-159     [007] ....   273.829853: rtcpu_string: tstamp:9309479547 id:0x04010000 str:"VI5: error_to_notify_bits Channel FALCON_ERROR i"
     kworker/7:2-159     [007] ....   273.829853: rtcpu_string: tstamp:9309479650 id:0x04010000 str:"dentified
"
     kworker/7:2-159     [007] ....   273.829855: rtcpu_string: tstamp:9309479978 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xe tag 0x0, da"
     kworker/7:2-159     [007] ....   273.829855: rtcpu_string: tstamp:9309480079 id:0x04010000 str:"ta 0x200
"
     kworker/7:2-159     [007] ....   273.829857: rtcpu_string: tstamp:9309480420 id:0x04010000 str:"VI5: Error 14 delivered to capture channel 0.
"
     kworker/7:2-159     [007] ....   273.829858: rtcpu_string: tstamp:9309480900 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/7:2-159     [007] ....   273.829859: rtcpu_string: tstamp:9309481005 id:0x04010000 str:"_INT_STATUS = 0x10
"
     kworker/7:2-159     [007] ....   273.829860: rtcpu_string: tstamp:9309481915 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/7:2-159     [007] ....   273.829861: rtcpu_string: tstamp:9309482020 id:0x04010000 str:"t. 0
"
     kworker/7:2-159     [007] ....   273.829868: rtcpu_string: tstamp:9309482258 id:0x04010000 str:"VI5: channel_submit(35, bfebd480)
"
     kworker/7:2-159     [007] ....   273.829876: rtcpu_string: tstamp:9309482653 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/7:2-159     [007] ....   273.829876: rtcpu_string: tstamp:9309482754 id:0x04010000 str:"t = 314
"
     kworker/7:2-159     [007] ....   273.829883: rtcpu_string: tstamp:9309483061 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/7:2-159     [007] ....   273.829884: rtcpu_string: tstamp:9309483165 id:0x04010000 str:"t = 345
"
     kworker/7:2-159     [007] ....   273.829888: rtcpu_string: tstamp:9309483466 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.829889: rtcpu_string: tstamp:9309483568 id:0x04010000 str:"t = 359
"
     kworker/7:2-159     [007] ....   273.829891: rtcpu_string: tstamp:9309483869 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/7:2-159     [007] ....   273.829891: rtcpu_string: tstamp:9309483973 id:0x04010000 str:"t = 389
"
     kworker/7:2-159     [007] ....   273.829892: rtcpu_string: tstamp:9309484271 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.829893: rtcpu_string: tstamp:9309484375 id:0x04010000 str:"t = 403
"
     kworker/7:2-159     [007] ....   273.829894: rtcpu_string: tstamp:9309484682 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/7:2-159     [007] ....   273.829895: rtcpu_string: tstamp:9309484783 id:0x04010000 str:"t = 417
"
     kworker/7:2-159     [007] ....   273.829896: rtcpu_string: tstamp:9309485079 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/7:2-159     [007] ....   273.829897: rtcpu_string: tstamp:9309485180 id:0x04010000 str:" = 419
"
     kworker/7:2-159     [007] ....   273.829899: rtcpu_string: tstamp:9309485546 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/7:2-159     [007] ....   273.829901: rtcpu_vinotify_event: tstamp:9309786756 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:297902717600 data:0x0000000800000000
     kworker/7:2-159     [007] ....   273.829901: rtcpu_vinotify_event: tstamp:9309786893 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297902745504 data:0x375cff0010000000
     kworker/7:2-159     [007] ....   273.829901: rtcpu_vinotify_event: tstamp:9309787046 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297902752224 data:0x0000000031000003
     kworker/7:2-159     [007] ....   273.829901: rtcpu_vinotify_event: tstamp:9309787177 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:297903253888 data:0x0000000000000001
     kworker/7:2-159     [007] ....   273.829901: rtcpu_vinotify_event: tstamp:9309787330 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:297903255168 data:0x0000000008020002
     kworker/7:2-159     [007] ....   273.829902: rtcpu_vinotify_event: tstamp:9309787461 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:0 vi_tstamp:297903265536 data:0x0000000000000200
     kworker/7:2-159     [007] ....   273.829902: rtcpu_vinotify_event: tstamp:9309787610 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:297903271520 data:0x0000000001020002
     kworker/7:2-159     [007] ....   273.829902: rtcpu_string: tstamp:9309999209 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/7:2-159     [007] ....   273.829902: rtcpu_string: tstamp:9309999310 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/7:2-159     [007] ....   273.829904: rtcpu_string: tstamp:9309999564 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/7:2-159     [007] ....   273.829906: rtcpu_string: tstamp:9310000136 id:0x04010000 str:"VI5: vi5_irq_process_channel_event: ch35 engine "
     kworker/7:2-159     [007] ....   273.829906: rtcpu_string: tstamp:9310000241 id:0x04010000 str:"status=0x00000200 task 1
"
     kworker/7:2-159     [007] ....   273.829907: rtcpu_string: tstamp:9310000530 id:0x04010000 str:"VI5: error_to_notify_bits Channel FALCON_ERROR i"
     kworker/7:2-159     [007] ....   273.829908: rtcpu_string: tstamp:9310000634 id:0x04010000 str:"dentified
"
     kworker/7:2-159     [007] ....   273.829909: rtcpu_string: tstamp:9310000963 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xe tag 0x0, da"
     kworker/7:2-159     [007] ....   273.829910: rtcpu_string: tstamp:9310001067 id:0x04010000 str:"ta 0x200
"
     kworker/7:2-159     [007] ....   273.829913: rtcpu_string: tstamp:9310001408 id:0x04010000 str:"VI5: Error 14 delivered to capture channel 0.
"
     kworker/7:2-159     [007] ....   273.829918: rtcpu_string: tstamp:9310001884 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/7:2-159     [007] ....   273.829918: rtcpu_string: tstamp:9310001989 id:0x04010000 str:"_INT_STATUS = 0x10
"
     kworker/7:2-159     [007] ....   273.829922: rtcpu_vinotify_event: tstamp:9310002850 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:0 vi_tstamp:297919053312 data:0x0000000002cf0202
     kworker/7:2-159     [007] ....   273.829922: rtcpu_vinotify_event: tstamp:9310003008 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:297919053856 data:0x0000000000000000
     kworker/7:2-159     [007] ....   273.829922: rtcpu_vinotify_event: tstamp:9310003143 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:297919382848 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.829922: rtcpu_vinotify_event: tstamp:9310003296 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:297919382880 data:0x0000000800000000
     kworker/7:2-159     [007] ....   273.829922: rtcpu_vinotify_event: tstamp:9310003427 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:297919383648 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.829922: rtcpu_vinotify_event: tstamp:9310003580 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:297919383648 data:0x0000000800000000
     kworker/7:2-159     [007] ....   273.829923: rtcpu_vinotify_event: tstamp:9310003715 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297919402848 data:0x375cfc0010000000
     kworker/7:2-159     [007] ....   273.829923: rtcpu_vinotify_event: tstamp:9310003869 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:297919409536 data:0x0000000031000004
     kworker/7:2-159     [007] ....   273.829923: rtcpu_vinotify_event: tstamp:9310004000 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:297919920000 data:0x0000000000000001
     kworker/7:2-159     [007] ....   273.829923: rtcpu_vinotify_event: tstamp:9310327481 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:297919922400 data:0x0000000008020003
     kworker/7:2-159     [007] ....   273.829923: rtcpu_vinotify_event: tstamp:9310327613 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:0 vi_tstamp:297919931616 data:0x0000000000000200
     kworker/7:2-159     [007] ....   273.829923: rtcpu_vinotify_event: tstamp:9310327763 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:297919940160 data:0x0000000001020003
     kworker/7:2-159     [007] ....   273.829924: rtcpu_string: tstamp:9310519870 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/7:2-159     [007] ....   273.829924: rtcpu_string: tstamp:9310519974 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/7:2-159     [007] ....   273.829927: rtcpu_string: tstamp:9310520228 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/7:2-159     [007] ....   273.829931: rtcpu_string: tstamp:9310520800 id:0x04010000 str:"VI5: vi5_irq_process_channel_event: ch35 engine "
     kworker/7:2-159     [007] ....   273.829931: rtcpu_string: tstamp:9310520901 id:0x04010000 str:"status=0x00000200 task 1
"
     kworker/7:2-159     [007] ....   273.829935: rtcpu_string: tstamp:9310521177 id:0x04010000 str:"VI5: error_to_notify_bits Channel FALCON_ERROR i"
     kworker/7:2-159     [007] ....   273.829936: rtcpu_string: tstamp:9310521280 id:0x04010000 str:"dentified
"
     kworker/7:2-159     [007] ....   273.829937: rtcpu_string: tstamp:9310521609 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xe tag 0x0, da"
     kworker/7:2-159     [007] ....   273.829938: rtcpu_string: tstamp:9310521710 id:0x04010000 str:"ta 0x200
"
     kworker/7:2-159     [007] ....   273.829939: rtcpu_string: tstamp:9310522050 id:0x04010000 str:"VI5: Error 14 delivered to capture channel 0.
"
     kworker/7:2-159     [007] ....   273.829941: rtcpu_string: tstamp:9310522487 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/7:2-159     [007] ....   273.829941: rtcpu_string: tstamp:9310522591 id:0x04010000 str:"_INT_STATUS = 0x10
"
     kworker/7:2-159     [007] ....   273.829943: rtcpu_vinotify_event: tstamp:9310665595 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:0 vi_tstamp:297935719328 data:0x0000000002cf0202
     kworker/7:2-159     [007] ....   273.829943: rtcpu_vinotify_event: tstamp:9310665755 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:297935719936 data:0x0000000000000000
     kworker/7:2-159     [007] ....   273.829943: rtcpu_vinotify_event: tstamp:9310665888 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:297936048928 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.829943: rtcpu_vinotify_event: tstamp:9310666041 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:297936048928 data:0x0000000800000000
     kworker/7:2-159     [007] ....   273.829943: rtcpu_vinotify_event: tstamp:9310666172 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:297936049696 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.829943: rtcpu_vinotify_event: tstamp:9310666325 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:297936049728 data:0x0000000800000000
     kworker/7:2-159     [007] ....   273.829944: rtcpu_vinotify_event: tstamp:9310666460 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:297936586016 data:0x0000000000000001
     kworker/7:2-159     [007] ....   273.829944: rtcpu_vinotify_event: tstamp:9310666612 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:297936587264 data:0x0000000008020004
     kworker/7:2-159     [007] ....   273.829944: rtcpu_vinotify_event: tstamp:9310666745 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:0 vi_tstamp:297936597664 data:0x0000000000000200
     kworker/7:2-159     [007] ....   273.829944: rtcpu_vinotify_event: tstamp:9310666898 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:297936603680 data:0x0000000001020004
     kworker/7:2-159     [007] ....   273.885832: rtcpu_vinotify_event: tstamp:9311022981 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:0 vi_tstamp:297952385440 data:0x0000000002cf0202
     kworker/7:2-159     [007] ....   273.885833: rtcpu_vinotify_event: tstamp:9311023141 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:297952386016 data:0x0000000000000000
     kworker/7:2-159     [007] ....   273.885833: rtcpu_vinotify_event: tstamp:9311023277 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:297952714976 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.885834: rtcpu_vinotify_error: tstamp:9311039852 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:297953252096 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.885835: rtcpu_string: tstamp:9311040377 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.885835: rtcpu_string: tstamp:9311040481 id:0x04010000 str:"=0 ts=297953252096 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.885836: rtcpu_string: tstamp:9311040585 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.885842: rtcpu_string: tstamp:9311040923 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.885842: rtcpu_string: tstamp:9311041024 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.885845: rtcpu_string: tstamp:9311041377 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.885845: rtcpu_string: tstamp:9311041480 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.885847: rtcpu_vinotify_event: tstamp:9311341690 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:297952714976 data:0x0000000800000000
     kworker/7:2-159     [007] ....   273.885847: rtcpu_vinotify_event: tstamp:9311341823 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:297952715808 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.885847: rtcpu_vinotify_event: tstamp:9311341976 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:297953252096 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.885847: rtcpu_vinotify_error: tstamp:9311560660 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:297969918176 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.885848: rtcpu_string: tstamp:9311561167 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.885848: rtcpu_string: tstamp:9311561268 id:0x04010000 str:"=0 ts=297969918176 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.885848: rtcpu_string: tstamp:9311561369 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.885850: rtcpu_string: tstamp:9311561705 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.885850: rtcpu_string: tstamp:9311561805 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.885852: rtcpu_string: tstamp:9311562138 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.885852: rtcpu_string: tstamp:9311562239 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.885853: rtcpu_vinotify_event: tstamp:9311679690 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:297969381056 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.885854: rtcpu_vinotify_event: tstamp:9311679839 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:297969381824 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.885854: rtcpu_vinotify_event: tstamp:9311679970 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:297969918176 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.885854: rtcpu_vinotify_error: tstamp:9312081478 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:297986584224 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.885854: rtcpu_string: tstamp:9312081980 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.885854: rtcpu_string: tstamp:9312082082 id:0x04010000 str:"=0 ts=297986584224 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.885855: rtcpu_string: tstamp:9312082186 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.885856: rtcpu_string: tstamp:9312082525 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.885857: rtcpu_string: tstamp:9312082624 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.885858: rtcpu_string: tstamp:9312082955 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.885858: rtcpu_string: tstamp:9312083055 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.885860: rtcpu_vinotify_event: tstamp:9312355805 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:297986047136 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.885860: rtcpu_vinotify_event: tstamp:9312355936 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:297986047904 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.885860: rtcpu_vinotify_event: tstamp:9312356085 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:297986584224 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.885860: rtcpu_vinotify_error: tstamp:9312602295 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298003250304 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.885861: rtcpu_string: tstamp:9312602806 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.885861: rtcpu_string: tstamp:9312602907 id:0x04010000 str:"=0 ts=298003250304 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.885861: rtcpu_string: tstamp:9312603008 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.885863: rtcpu_string: tstamp:9312603344 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.885864: rtcpu_string: tstamp:9312603444 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.885865: rtcpu_string: tstamp:9312603773 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.885865: rtcpu_string: tstamp:9312603873 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.941826: rtcpu_vinotify_event: tstamp:9312693801 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298002713184 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.941827: rtcpu_vinotify_event: tstamp:9312693956 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298002713984 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.941827: rtcpu_vinotify_event: tstamp:9312694090 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298003250304 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.941828: rtcpu_vinotify_error: tstamp:9313123107 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298019916384 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.941828: rtcpu_string: tstamp:9313123610 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.941829: rtcpu_string: tstamp:9313123711 id:0x04010000 str:"=0 ts=298019916384 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.941829: rtcpu_string: tstamp:9313123814 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.941834: rtcpu_string: tstamp:9313124152 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.941835: rtcpu_string: tstamp:9313124256 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.941837: rtcpu_string: tstamp:9313124592 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.941838: rtcpu_string: tstamp:9313124694 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.941840: rtcpu_vinotify_event: tstamp:9313369939 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298019379264 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.941841: rtcpu_vinotify_event: tstamp:9313370073 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298019380032 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.941841: rtcpu_vinotify_event: tstamp:9313370222 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298019916384 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.941841: rtcpu_vinotify_event: tstamp:9313626959 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298036045344 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.941842: rtcpu_vinotify_error: tstamp:9313643921 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298036582432 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.941842: rtcpu_string: tstamp:9313644428 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.941842: rtcpu_string: tstamp:9313644531 id:0x04010000 str:"=0 ts=298036582432 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.941843: rtcpu_string: tstamp:9313644634 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.941846: rtcpu_string: tstamp:9313644974 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.941846: rtcpu_string: tstamp:9313645074 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.941849: rtcpu_string: tstamp:9313645459 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.941849: rtcpu_string: tstamp:9313645561 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.941852: rtcpu_vinotify_event: tstamp:9313944711 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298036046112 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.941852: rtcpu_vinotify_event: tstamp:9313944856 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298036582432 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.941852: rtcpu_vinotify_error: tstamp:9314164734 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298053248512 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.941853: rtcpu_string: tstamp:9314165244 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.941854: rtcpu_string: tstamp:9314165345 id:0x04010000 str:"=0 ts=298053248512 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.941854: rtcpu_string: tstamp:9314165447 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.941857: rtcpu_string: tstamp:9314165784 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.941857: rtcpu_string: tstamp:9314165884 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.941859: rtcpu_string: tstamp:9314166216 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.941859: rtcpu_string: tstamp:9314166316 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.941861: rtcpu_vinotify_event: tstamp:9314282710 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298052711392 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.941861: rtcpu_vinotify_event: tstamp:9314282842 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298052712192 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.941861: rtcpu_vinotify_event: tstamp:9314282994 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298053248512 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.997821: rtcpu_vinotify_error: tstamp:9314685554 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298069914560 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.997821: rtcpu_string: tstamp:9314686067 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.997822: rtcpu_string: tstamp:9314686172 id:0x04010000 str:"=0 ts=298069914560 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.997822: rtcpu_string: tstamp:9314686277 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.997825: rtcpu_string: tstamp:9314686618 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.997826: rtcpu_string: tstamp:9314686717 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.997827: rtcpu_string: tstamp:9314687046 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.997827: rtcpu_string: tstamp:9314687146 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.997829: rtcpu_vinotify_event: tstamp:9314958825 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298069377440 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.997829: rtcpu_vinotify_event: tstamp:9314958974 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298069378240 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.997829: rtcpu_vinotify_event: tstamp:9314959105 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298069914560 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.997829: rtcpu_vinotify_error: tstamp:9315206364 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298086580640 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.997830: rtcpu_string: tstamp:9315206871 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.997830: rtcpu_string: tstamp:9315206971 id:0x04010000 str:"=0 ts=298086580640 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.997830: rtcpu_string: tstamp:9315207072 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.997832: rtcpu_string: tstamp:9315207407 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.997832: rtcpu_string: tstamp:9315207505 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.997834: rtcpu_string: tstamp:9315207836 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.997834: rtcpu_string: tstamp:9315207934 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.997835: rtcpu_vinotify_event: tstamp:9315296818 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298086043520 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.997836: rtcpu_vinotify_event: tstamp:9315296952 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298086044320 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.997836: rtcpu_vinotify_event: tstamp:9315297099 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298086580640 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.997836: rtcpu_vinotify_error: tstamp:9315727181 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298103246720 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   273.997836: rtcpu_string: tstamp:9315727685 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   273.997837: rtcpu_string: tstamp:9315727789 id:0x04010000 str:"=0 ts=298103246720 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   273.997837: rtcpu_string: tstamp:9315727893 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   273.997839: rtcpu_string: tstamp:9315728230 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   273.997839: rtcpu_string: tstamp:9315728331 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   273.997840: rtcpu_string: tstamp:9315728663 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   273.997841: rtcpu_string: tstamp:9315728764 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   273.997842: rtcpu_vinotify_event: tstamp:9315972929 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298102709568 data:0x0000000000000020
     kworker/7:2-159     [007] ....   273.997842: rtcpu_vinotify_event: tstamp:9315973078 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298102710368 data:0x0000000000000010
     kworker/7:2-159     [007] ....   273.997842: rtcpu_vinotify_event: tstamp:9315973209 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298103246720 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.053825: rtcpu_vinotify_event: tstamp:9316231065 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298119375680 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.053825: rtcpu_vinotify_event: tstamp:9316231203 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298119376448 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.053826: rtcpu_vinotify_error: tstamp:9316247997 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298119912768 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.053826: rtcpu_string: tstamp:9316248509 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.053827: rtcpu_string: tstamp:9316248611 id:0x04010000 str:"=0 ts=298119912768 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.053827: rtcpu_string: tstamp:9316248715 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.053831: rtcpu_string: tstamp:9316249052 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.053831: rtcpu_string: tstamp:9316249152 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.053833: rtcpu_string: tstamp:9316249484 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.053833: rtcpu_string: tstamp:9316249585 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.053834: rtcpu_vinotify_event: tstamp:9316547579 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298119912768 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.053835: rtcpu_vinotify_error: tstamp:9316768808 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298136578816 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.053835: rtcpu_string: tstamp:9316769310 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.053835: rtcpu_string: tstamp:9316769411 id:0x04010000 str:"=0 ts=298136578816 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.053835: rtcpu_string: tstamp:9316769514 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.053837: rtcpu_string: tstamp:9316769848 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.053837: rtcpu_string: tstamp:9316769947 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.053839: rtcpu_string: tstamp:9316770276 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.053840: rtcpu_string: tstamp:9316770376 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.053841: rtcpu_vinotify_event: tstamp:9316885581 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298136041696 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.053841: rtcpu_vinotify_event: tstamp:9316885734 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298136042528 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.053842: rtcpu_vinotify_event: tstamp:9316885866 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298136578816 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.053842: rtcpu_vinotify_error: tstamp:9317289625 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298153244896 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.053842: rtcpu_string: tstamp:9317290128 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.053842: rtcpu_string: tstamp:9317290250 id:0x04010000 str:"=0 ts=298153244896 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.053843: rtcpu_string: tstamp:9317290355 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.053844: rtcpu_string: tstamp:9317290695 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.053844: rtcpu_string: tstamp:9317290796 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.053846: rtcpu_string: tstamp:9317291128 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.053846: rtcpu_string: tstamp:9317291229 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.053848: rtcpu_vinotify_event: tstamp:9317561693 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298152707776 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.053848: rtcpu_vinotify_event: tstamp:9317561828 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298152708576 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.053848: rtcpu_vinotify_event: tstamp:9317561980 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298153244896 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.053848: rtcpu_vinotify_error: tstamp:9317810438 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298169910944 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.053848: rtcpu_string: tstamp:9317810947 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.053849: rtcpu_string: tstamp:9317811053 id:0x04010000 str:"=0 ts=298169910944 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.053849: rtcpu_string: tstamp:9317811157 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.053850: rtcpu_string: tstamp:9317811495 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.053851: rtcpu_string: tstamp:9317811595 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.053852: rtcpu_string: tstamp:9317811928 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.053853: rtcpu_string: tstamp:9317812029 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.053854: rtcpu_vinotify_event: tstamp:9317899682 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298169373856 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.053854: rtcpu_vinotify_event: tstamp:9317899834 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298169374656 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.053854: rtcpu_vinotify_event: tstamp:9317899965 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298169910944 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.109819: rtcpu_vinotify_error: tstamp:9318331254 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298186577056 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.109820: rtcpu_string: tstamp:9318331762 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.109820: rtcpu_string: tstamp:9318331866 id:0x04010000 str:"=0 ts=298186577056 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.109821: rtcpu_string: tstamp:9318331971 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.109824: rtcpu_string: tstamp:9318332311 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.109824: rtcpu_string: tstamp:9318332413 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.109825: rtcpu_string: tstamp:9318332746 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.109826: rtcpu_string: tstamp:9318332850 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.109827: rtcpu_vinotify_event: tstamp:9318575805 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298186039936 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.109827: rtcpu_vinotify_event: tstamp:9318575940 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298186040704 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.109828: rtcpu_vinotify_event: tstamp:9318576089 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298186577056 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.109828: rtcpu_vinotify_error: tstamp:9318852058 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298203243104 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.109828: rtcpu_string: tstamp:9318852567 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.109828: rtcpu_string: tstamp:9318852671 id:0x04010000 str:"=0 ts=298203243104 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.109829: rtcpu_string: tstamp:9318852776 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.109830: rtcpu_string: tstamp:9318853113 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.109830: rtcpu_string: tstamp:9318853217 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.109832: rtcpu_string: tstamp:9318853549 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.109832: rtcpu_string: tstamp:9318853650 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.109834: rtcpu_vinotify_event: tstamp:9318853996 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298202705984 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.109834: rtcpu_vinotify_event: tstamp:9318854149 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298202706784 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.109834: rtcpu_vinotify_event: tstamp:9318854282 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298203243104 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.109835: rtcpu_vinotify_error: tstamp:9319372881 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298219909152 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.109835: rtcpu_string: tstamp:9319373391 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.109835: rtcpu_string: tstamp:9319373496 id:0x04010000 str:"=0 ts=298219909152 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.109835: rtcpu_string: tstamp:9319373602 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.109837: rtcpu_string: tstamp:9319373943 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.109837: rtcpu_string: tstamp:9319374046 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.109839: rtcpu_string: tstamp:9319374380 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.109839: rtcpu_string: tstamp:9319374481 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.109840: rtcpu_vinotify_event: tstamp:9319387089 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298219372032 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.109841: rtcpu_vinotify_event: tstamp:9319387224 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298219372864 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.109841: rtcpu_vinotify_event: tstamp:9319387373 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298219909152 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.165814: rtcpu_vinotify_error: tstamp:9319893701 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298236575232 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.165815: rtcpu_string: tstamp:9319894208 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.165816: rtcpu_string: tstamp:9319894313 id:0x04010000 str:"=0 ts=298236575232 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.165816: rtcpu_string: tstamp:9319894418 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.165819: rtcpu_string: tstamp:9319894758 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.165819: rtcpu_string: tstamp:9319894861 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.165821: rtcpu_string: tstamp:9319895196 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.165821: rtcpu_string: tstamp:9319895298 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.165822: rtcpu_vinotify_event: tstamp:9320063205 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298236038112 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.165823: rtcpu_vinotify_event: tstamp:9320063361 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298236038912 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.165823: rtcpu_vinotify_event: tstamp:9320063492 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298236575232 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.165823: rtcpu_vinotify_event: tstamp:9320401221 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298252704192 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.165823: rtcpu_vinotify_event: tstamp:9320401356 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298252704992 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.165823: rtcpu_vinotify_error: tstamp:9320414511 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298253241312 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.165824: rtcpu_string: tstamp:9320415022 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.165824: rtcpu_string: tstamp:9320415123 id:0x04010000 str:"=0 ts=298253241312 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.165824: rtcpu_string: tstamp:9320415226 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.165826: rtcpu_string: tstamp:9320415564 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.165826: rtcpu_string: tstamp:9320415664 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.165828: rtcpu_string: tstamp:9320415997 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.165828: rtcpu_string: tstamp:9320416098 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.165829: rtcpu_vinotify_event: tstamp:9320739319 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298253241312 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.165830: rtcpu_vinotify_error: tstamp:9320935329 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298269907360 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.165830: rtcpu_string: tstamp:9320935836 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.165830: rtcpu_string: tstamp:9320935944 id:0x04010000 str:"=0 ts=298269907360 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.165830: rtcpu_string: tstamp:9320936049 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.165832: rtcpu_string: tstamp:9320936390 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.165832: rtcpu_string: tstamp:9320936494 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.165834: rtcpu_string: tstamp:9320936827 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.165834: rtcpu_string: tstamp:9320936929 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.165835: rtcpu_vinotify_event: tstamp:9321077320 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298269370240 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.165835: rtcpu_vinotify_event: tstamp:9321077471 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298269371072 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.165836: rtcpu_vinotify_event: tstamp:9321077605 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298269907360 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.221822: rtcpu_vinotify_error: tstamp:9321456146 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298286573440 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.221825: rtcpu_string: tstamp:9321456657 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.221826: rtcpu_string: tstamp:9321456762 id:0x04010000 str:"=0 ts=298286573440 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.221826: rtcpu_string: tstamp:9321456867 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.221834: rtcpu_string: tstamp:9321457206 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.221834: rtcpu_string: tstamp:9321457308 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.221836: rtcpu_string: tstamp:9321457640 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.221836: rtcpu_string: tstamp:9321457741 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.221839: rtcpu_vinotify_event: tstamp:9321753436 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298286036320 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.221839: rtcpu_vinotify_event: tstamp:9321753570 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298286037120 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.221839: rtcpu_vinotify_event: tstamp:9321753718 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298286573440 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.221839: rtcpu_vinotify_event: tstamp:9321959991 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298302702400 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.221839: rtcpu_vinotify_error: tstamp:9321976954 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298303239488 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.221840: rtcpu_string: tstamp:9321977457 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.221840: rtcpu_string: tstamp:9321977561 id:0x04010000 str:"=0 ts=298303239488 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.221840: rtcpu_string: tstamp:9321977663 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.221842: rtcpu_string: tstamp:9321978003 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.221842: rtcpu_string: tstamp:9321978103 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.221844: rtcpu_string: tstamp:9321978435 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.221844: rtcpu_string: tstamp:9321978535 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.221846: rtcpu_vinotify_event: tstamp:9322294349 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298302703200 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.221846: rtcpu_vinotify_event: tstamp:9322294485 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298303239488 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.221846: rtcpu_vinotify_error: tstamp:9322497774 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298319905568 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.221846: rtcpu_string: tstamp:9322498286 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.221846: rtcpu_string: tstamp:9322498390 id:0x04010000 str:"=0 ts=298319905568 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.221847: rtcpu_string: tstamp:9322498495 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.221848: rtcpu_string: tstamp:9322498834 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.221849: rtcpu_string: tstamp:9322498935 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.221851: rtcpu_string: tstamp:9322499270 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.221852: rtcpu_string: tstamp:9322499371 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.221853: rtcpu_vinotify_event: tstamp:9322632348 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298319368448 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.221853: rtcpu_vinotify_event: tstamp:9322632483 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298319369248 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.221853: rtcpu_vinotify_event: tstamp:9322632632 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298319905568 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.221854: rtcpu_vinotify_error: tstamp:9323018593 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298336571616 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.221854: rtcpu_string: tstamp:9323019099 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.221854: rtcpu_string: tstamp:9323019203 id:0x04010000 str:"=0 ts=298336571616 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.221854: rtcpu_string: tstamp:9323019309 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.221856: rtcpu_string: tstamp:9323019650 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.221856: rtcpu_string: tstamp:9323019747 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.221858: rtcpu_string: tstamp:9323020077 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.221858: rtcpu_string: tstamp:9323020178 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.277818: rtcpu_vinotify_event: tstamp:9323308491 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298336034528 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.277819: rtcpu_vinotify_event: tstamp:9323308642 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298336035296 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.277819: rtcpu_vinotify_event: tstamp:9323308773 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298336571616 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.277820: rtcpu_vinotify_error: tstamp:9323539398 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298353237696 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.277820: rtcpu_string: tstamp:9323539900 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.277821: rtcpu_string: tstamp:9323540005 id:0x04010000 str:"=0 ts=298353237696 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.277821: rtcpu_string: tstamp:9323540108 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.277826: rtcpu_string: tstamp:9323540446 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.277826: rtcpu_string: tstamp:9323540546 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.277829: rtcpu_string: tstamp:9323540879 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.277829: rtcpu_string: tstamp:9323540980 id:0x04010000 str:"ta 0x3c9
"
     kworker/7:2-159     [007] ....   274.277832: rtcpu_vinotify_event: tstamp:9323646456 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:298352700576 data:0x0000000000000020
     kworker/7:2-159     [007] ....   274.277832: rtcpu_vinotify_event: tstamp:9323646592 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:298352701376 data:0x0000000000000010
     kworker/7:2-159     [007] ....   274.277832: rtcpu_vinotify_event: tstamp:9323646744 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298353237696 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.277833: rtcpu_vinotify_error: tstamp:9324060214 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:298369903776 data:0x00000000000003c9
     kworker/7:2-159     [007] ....   274.277833: rtcpu_string: tstamp:9324060722 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/7:2-159     [007] ....   274.277834: rtcpu_string: tstamp:9324060823 id:0x04010000 str:"=0 ts=298369903776 data=0x000003c9 ext_data=0x00"
     kworker/7:2-159     [007] ....   274.277834: rtcpu_string: tstamp:9324060927 id:0x04010000 str:"000000
"
     kworker/7:2-159     [007] ....   274.277837: rtcpu_string: tstamp:9324061264 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/7:2-159     [007] ....   274.277837: rtcpu_string: tstamp:9324061364 id:0x04010000 str:"fied
"
     kworker/7:2-159     [007] ....   274.277840: rtcpu_string: tstamp:9324061696 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/7:2-159     [007] ....   274.277840: rtcpu_string: tstamp:9324061796 id:0x04010000 str:"ta 0x3c9

and kernel log for 1924x720 setting. (for comparision)

[  683.698148] ds90ub940n 8-002c: ds90ub940n_power_on: power on
[  683.699527] ds90ub940n 8-002c: ds90ub940n_power_on: power complete
[  683.707242] bwmgr API not supported
[  683.707976] ds90ub940n 8-002c: ds90ub940n_set_mode: mode_prop_idx=0
[  683.709625] [RCE] VM0 deactivating.VM0 activating.NVCSILP clock rate = 408000000 Hz.
[  683.711581] [RCE] VI5: tegra_vi_channel_alloc()
[  683.711582] [RCE] VI5: unit 0 stream ID table:
[  683.711584] [RCE] VI5: unit 0 stream ID table from THI:
[  683.711586] [RCE] VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00
[  683.711588] [RCE] VI5: vi5_trace_enable: configuring trace buffer at iova=0x8375dda00 size=32768
[  683.711590] [RCE] VI5: tegra_vi_channel_alloc: ch=35
[  683.711596] ko_frmfmt = (1924,720) 
[  683.711598] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  683.711602] [RCE] VI5: channel_submit(35, bfebc000)
[  683.711609] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  683.711614] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  683.711619] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  683.711623] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  683.711633] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  683.711634] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  683.711636] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  683.711638] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  683.711639] [RCE] VI5: enabling stream 0
[  683.711641] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  683.711642] [RCE] VI5: channel_submit(35, bfebc180)
[  683.711644] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  683.711646] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  683.711648] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  683.711650] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  683.711651] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  683.711653] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  683.711655] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  683.711656] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  683.711658] [RCE] tegra_nvcsi_stream_set_config(vm0, stream=0, csi=0)
[  683.711660] [RCE] MIPI clock = 188540 kHz, tHS-SETTLE = 0, tCLK-SETTLE = 0
[  683.711661] [RCE] tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
[  683.711663] [RCE] nvcsi_calc_ths_settle ths_settle 80
[  683.711664] [RCE] nvcsi_calc_ths_settle ths_settle 80
[  683.711666] [RCE] nvcsi_calc_ths_settle ths_settle 80
[  683.711667] [RCE] nvcsi_calc_tclk_settle tclk_settle 75
[  683.711671] [RCE] VI5: vi5_process_error_fifo: tag=0x03 ch=0 frame=0 ts=707828116128 data=0x00000001 ext_data=0x00000000
[  683.711672] [RCE] VI5: error_to_notify_bits CSIMUX_STREAM error detected
[  683.711674] [RCE] VI5: vi5_notify_error(35) status 0x3 tag 0x3, data 0x1
[  683.721665] ds90ub940n 8-002c: ds90ub940n_start_streaming: mode_prop_idx=0
[  683.769614] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  683.769618] [RCE] VI5: vi5_handle_sof(35)
[  683.769623] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  683.769625] [RCE] VI5: vi5_handle_eof(35)
[  683.769628] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  683.769630] [RCE] VI5: vi5_handle_sof(35)
[  683.769632] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  683.769634] [RCE] VI5: vi5_handle_eof(35)
[  683.769637] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=707871834400 data=0x000003c9 ext_data=0x00000000
[  683.769639] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.769641] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.769645] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=707888500448 data=0x000003c9 ext_data=0x00000000
[  683.769647] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.769649] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.829612] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=707905166528 data=0x000003c9 ext_data=0x00000000
[  683.829618] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.829620] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.829622] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=707921832608 data=0x000003c9 ext_data=0x00000000
[  683.829624] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.829628] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.829630] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  683.829632] [RCE] VI5: channel_submit(35, bfebc000)
[  683.829633] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  683.829635] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  683.829637] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  683.829639] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  683.829641] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  683.829643] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  683.829644] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  683.829646] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  683.829649] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  683.829650] [RCE] VI5: vi5_handle_sof(35)
[  683.889604] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  683.889610] [RCE] VI5: vi5_handle_eof(35)
[  683.889615] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=707955164736 data=0x000003c9 ext_data=0x00000000
[  683.889617] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.889619] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.889622] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=707971830784 data=0x000003c9 ext_data=0x00000000
[  683.889624] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.889626] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.889630] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=707988496864 data=0x000003c9 ext_data=0x00000000
[  683.889632] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.889634] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.889637] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=708005162944 data=0x000003c9 ext_data=0x00000000
[  683.889639] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.889641] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.889643] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  683.889644] [RCE] VI5: channel_submit(35, bfebc180)
[  683.889646] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  683.889648] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  683.889650] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  683.889652] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  683.889653] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  683.889655] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  683.889657] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  683.889658] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  683.945623] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  683.945629] [RCE] VI5: vi5_handle_sof(35)
[  683.945632] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  683.945634] [RCE] VI5: vi5_handle_eof(35)
[  683.945640] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=708038495072 data=0x000003c9 ext_data=0x00000000
[  683.945642] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.945644] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  683.945649] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=708055161120 data=0x000003c9 ext_data=0x00000000
[  683.945651] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  683.945654] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  684.005601] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=708071827200 data=0x000003c9 ext_data=0x00000000
[  684.005607] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  684.005609] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  684.005611] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  684.005612] [RCE] VI5: channel_submit(35, bfebc000)

Thanks

hello ghdlghdl0558,

this is indicate capture channel encountered an uncorrectable error, and it must be reset.
but… am wondering why this is reported before your start_stream.

there’s 2500ms as an internal timeout limit, is your sensor took a short while for output streaming?

could you please refer to developer guide, Sensor Software Driver Programming.
you may try adding set_mode_delay_ms into device tree to configure maximum waiting time for the 1st frame after capture starts, the unit is in milliseconds.

I did set_mode_delay_ms=2500 on dtb, and log changed on 1984x720.
However, in most cases the system freezes on 1984x720 setting. (I got this log after trying several times.)

command log

nvidia@nvidia-desktop:~/Tx2DeviceDriver/ds90ub940n_orin$ gst-launch-1.0 v4l2src device=/dev/video0 ! "video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720" ! fpsdisplaysink text-overlay=0 video-sink=fakesink sync=0 -v
Setting pipeline to PAUSED ...
Pipeline is live and does not need PREROLL ...
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0/GstFakeSink:fakesink0: sync = false
Setting pipeline to PLAYING ...
New clock: GstSystemClock
/GstPipeline:pipeline0/GstV4l2Src:v4l2src0.GstPad:src: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstCapsFilter:capsfilter0.GstPad:src: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0.GstGhostPad:sink.GstProxyPad:proxypad0: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0/GstFakeSink:fakesink0.GstPad:sink: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0.GstGhostPad:sink: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstCapsFilter:capsfilter0.GstPad:sink: caps = video/x-raw, format=(string)UYVY, width=(int)1984, height=(int)720, framerate=(fraction)60/1, colorimetry=(string)2:4:7:1, interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0/GstFakeSink:fakesink0: sync = false
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0: last-message = rendered: 5, dropped: 0, current: 1.78, average: 1.78
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0: last-message = rendered: 9, dropped: 0, current: 1.42, average: 1.60
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0: last-message = rendered: 13, dropped: 0, current: 1.56, average: 1.59
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0: last-message = rendered: 17, dropped: 0, current: 1.42, average: 1.54
^Chandling interrupt.
Interrupt: Stopping pipeline ...
Execution ended after 0:00:14.463227992
Setting pipeline to NULL ...
Freeing pipeline ...

kernel log

[   82.655382] [RCE] VM0 deactivating.VM0 activating.NVCSILP clock rate = 408000000 Hz.
[   82.689229] nvgpu: 17000000.ga10b             railgate_enable_store:323  [INFO]  railgate is disabled.
[   91.759499] ds90ub940n 8-002c: ds90ub940n_power_on: power on
[   91.760887] ds90ub940n 8-002c: ds90ub940n_power_on: power complete
[   91.770100] bwmgr API not supported
[   91.770779] ds90ub940n 8-002c: ds90ub940n_set_mode: mode_prop_idx=1
[   91.774404] ko_frmfmt = (1984,720) 
[   91.782807] [RCE] VM0 deactivating.VM0 activating.NVCSILP clock rate = 408000000 Hz.
[   91.782810] [RCE] VI5: tegra_vi_channel_alloc()
[   91.782812] [RCE] VI5: unit 0 stream ID table:
[   91.782814] [RCE] VI5: unit 0 stream ID table from THI:
[   91.782816] [RCE] VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00
[   91.782819] [RCE] VI5: vi5_trace_enable: configuring trace buffer at iova=0x8375dda00 size=32768
[   91.782821] [RCE] VI5: tegra_vi_channel_alloc: ch=35
[   91.782823] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[   91.782825] [RCE] VI5: channel_submit(35, bfebf000)
[   91.782827] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[   91.782830] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[   91.782832] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[   91.782834] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[   91.782835] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[   91.782837] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[   91.782839] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[   91.782841] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[   91.782842] [RCE] VI5: enabling stream 0
[   91.782844] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[   91.782846] [RCE] VI5: channel_submit(35, bfebf180)
[   91.782847] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[   91.782849] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[   91.782851] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[   91.782853] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[   91.782854] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[   91.782856] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[   91.782858] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[   91.782859] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[   91.782862] [RCE] tegra_nvcsi_stream_set_config(vm0, stream=0, csi=0)
[   91.782864] [RCE] MIPI clock = 3231417376 kHz, tHS-SETTLE = 0, tCLK-SETTLE = 0
[   91.782865] [RCE] tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
[   91.782867] [RCE] nvcsi_calc_ths_settle ths_settle 43
[   91.782868] [RCE] nvcsi_calc_ths_settle ths_settle 43
[   91.782870] [RCE] nvcsi_calc_ths_settle ths_settle 43
[   91.782871] [RCE] nvcsi_calc_tclk_settle tclk_settle 75
[   91.782873] [RCE] Deskew setup message sent for port 0 num_lane 4
[   91.784462] ds90ub940n 8-002c: ds90ub940n_start_streaming: mode_prop_idx=1
[   94.494692] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[   94.503881] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[   94.514029] (NULL device *): vi_capture_control_message: NULL VI channel received
[   94.521761] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[   94.526694] [RCE] VI5: tegra_vi_channel_release(35)
[   94.532475] (NULL device *): vi_capture_control_message: NULL VI channel received
[   94.532839] [RCE] VI5: vi5_channel_disable(35)
[   94.540212] [RCE] VI5: vi5 reset completed in 1 retries
[   94.540214] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x00
[   94.540217] [RCE] VI5: disabling stream 0
[   94.540224] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 0
[   94.551032] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[   94.594695] [RCE] VI5: tegra_vi_channel_alloc()
[   94.594700] [RCE] VI5: tegra_vi_channel_alloc: ch=35
[   94.594702] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[   94.594704] [RCE] VI5: channel_submit(35, bfebe000)
[   94.594706] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[   94.594707] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[   94.594709] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[   94.594711] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[   94.594713] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[   94.594714] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[   94.594716] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[   94.594718] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[   94.594719] [RCE] VI5: enabling stream 0
[   94.594721] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[   94.594722] [RCE] VI5: channel_submit(35, bfebe180)
[   94.594724] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[   94.594726] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[   94.594728] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[   94.594729] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[   94.594731] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[   94.594733] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[   94.594736] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[   94.594737] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[   97.310602] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[   97.319769] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[   97.330041] (NULL device *): vi_capture_control_message: NULL VI channel received
[   97.337782] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[   97.338594] [RCE] VI5: tegra_vi_channel_release(35)
[   97.348470] [RCE] VI5: vi5_channel_disable(35)
[   97.348474] [RCE] VI5: vi5 reset completed in 1 retries
[   97.348476] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x00
[   97.348478] [RCE] VI5: disabling stream 0
[   97.348502] (NULL device *): vi_capture_control_message: NULL VI channel received
[   97.356246] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 0
[   97.367027] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[   97.402584] [RCE] VI5: tegra_vi_channel_alloc()
[   97.402588] [RCE] VI5: tegra_vi_channel_alloc: ch=35
[   97.402591] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[   97.402593] [RCE] VI5: channel_submit(35, bfebd000)
[   97.402597] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[   97.402600] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[   97.402603] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[   97.402606] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[   97.402609] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[   97.402611] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[   97.402613] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[   97.402615] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[   97.402616] [RCE] VI5: enabling stream 0
[   97.402618] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[   97.402619] [RCE] VI5: channel_submit(35, bfebd180)
[   97.402621] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[   97.402622] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[   97.402624] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[   97.402626] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[   97.402627] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[   97.402629] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[   97.402630] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[   97.402632] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  100.126518] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  100.135713] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  100.146027] (NULL device *): vi_capture_control_message: NULL VI channel received
[  100.146528] [RCE] VI5: tegra_vi_channel_release(35)
[  100.153771] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[  100.154149] [RCE] VI5: vi5_channel_disable(35)
[  100.164459] (NULL device *): vi_capture_control_message: NULL VI channel received
[  100.164462] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 0
[  100.164776] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  100.172200] [RCE] VI5: vi5 reset completed in 1 retries
[  100.172202] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x00
[  100.192328] [RCE] VI5: disabling stream 0
[  100.246512] [RCE] VI5: tegra_vi_channel_alloc()
[  100.246517] [RCE] VI5: tegra_vi_channel_alloc: ch=35
[  100.246520] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  100.246521] [RCE] VI5: channel_submit(35, bfebc000)
[  100.246523] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  100.246525] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  100.246527] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  100.246529] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  100.246530] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  100.246532] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  100.246534] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  100.246536] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  100.246537] [RCE] VI5: enabling stream 0
[  100.246539] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  100.246540] [RCE] VI5: channel_submit(35, bfebc180)
[  100.246542] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  100.246544] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  100.246546] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  100.246548] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  100.246549] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  100.246551] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  100.246553] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  100.246554] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  102.686458] tegra-camrtc-capture-vi tegra-capture-vi: uncorr_err: request timed out after 2500 ms
[  102.695640] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: attempting to reset the capture channel
[  102.706018] (NULL device *): vi_capture_control_message: NULL VI channel received
[  102.710454] [RCE] VI5: tegra_vi_channel_release(35)
[  102.713731] [RCE] VI5: vi5_channel_disable(35)
[  102.713733] [RCE] VI5: vi5 reset completed in 1 retries
[  102.713736] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x00
[  102.713737] [RCE] VI5: disabling stream 0
[  102.713743] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_close: Error in closing stream_id=0, csi_port=0
[  102.724424] (NULL device *): vi_capture_control_message: NULL VI channel received
[  102.732155] t194-nvcsi 13e40000.host1x:nvcsi@15a00000: csi5_stream_open: VI channel not found for stream- 0 vc- 0
[  102.742905] tegra-camrtc-capture-vi tegra-capture-vi: err_rec: successfully reset the capture channel
[  102.766442] [RCE] VI5: tegra_vi_channel_alloc()
[  102.766445] [RCE] VI5: tegra_vi_channel_alloc: ch=35
[  102.766450] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  102.766452] [RCE] VI5: channel_submit(35, bfebb000)
[  102.766455] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  102.766458] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  102.766462] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  102.766465] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  102.766467] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  102.766469] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  102.766471] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  102.766473] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  102.766474] [RCE] VI5: enabling stream 0
[  102.766476] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  102.766477] [RCE] VI5: channel_submit(35, bfebb180)
[  102.766479] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  102.766481] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  102.766482] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  102.766484] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  102.766486] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  102.766487] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  102.766489] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  102.766491] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01

By the way, in the 1924x720 format, is it not possible to match the stride with the preferred_stride setting?
Why did the previous v4l2-ctl -d /dev/video0 --set-ctrl preferred_stride=4 not work?

Thanks

hello ghdlghdl0558,

according to the logs,

/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0: last-message = rendered: 5, dropped: 0, current: 1.78, average: 1.78
/GstPipeline:pipeline0/GstFPSDisplaySink:fpsdisplaysink0: last-message = rendered: 9, dropped: 0, current: 1.42, average: 1.60

the received frame rate is very slow, roughly 1-fps reported by fpsdisplaysink. kernel message also show error recovery mechanism to reset capture channel.
this may due to the stream stability on sensor side.

could you please dig into kernel driver,
for example, $public_sources/kernel_src/kernel/nvidia/drivers/media/platform/tegra/camera/vi/channel.c
please examine the bytesperline settings of this function call.

static void tegra_channel_update_format() {
...
        /* Calculate the sizeimage per plane */
        chan->format.sizeimage = get_aligned_buffer_size(chan,
                        chan->format.bytesperline, chan->format.height);

Hi, JerryChang

It doesn’t seem to be a problem on the sensor side.
When I ran the same 1924x720 CSI on Jetson TX2, it worked just as well as 1920x720.
Also, I checked that 800x480 video is not output as well as 1924x720.
1920x720, 1024x1920, and 1280x768 were all output normally.
So I wonder if the horizontal resolution is not a multiple of 64 or it doesn’t work normally.

I’ll check the kernel driver side you mentioned.

Thanks.

had you also test 1984x720 setting on TX2 series?

No. In Jetson TX2, 1924x720 itself worked well, so I did not test 1984x720.

I tested 1920x720, 1924x720, 1024x1920, and 1024x768 on the Jetson TX2 and all worked fine.
I used Jetpack 4.6.2 on Jetson TX2.

Just in case there’s any confusion,

This is the case at jetson orin.

Everything worked fine on the Jetson TX2.
1924x720 and 800x480 did not work on Jetson Orin.

I set the 1924x720 input to be processed as 1984x720 in the device driver to check if the cause of the 1924x720 CSI input not working is because of the stride.
(I didn’t use 1984x720 CSI input)

Thanks

Hi,

I add debug on$public_sources/kernel_src/kernel/nvidia/drivers/media/platform/tegra/camera/vi/channel.c you mentioned.

/* Calculate the sizeimage per plane */
chan->format.sizeimage = get_aligned_buffer_size(chan,
chan->format.bytesperline, chan->format.height);

dev_info(&chan->video->dev,
"%s: sizeimage= %d bytesperline=%d\n",
__func__, chan->format.sizeimage, chan->format.bytesperline);

and below is the kernel log on gst-launch-1.0 v4l2src device=/dev/video0 ! "video/x-raw,format=(string)UYVY,width=1924,height=720" ! videoconvert ! gtksink sync=false

[   98.637534] ds90ub940n 8-002c: ds90ub940n: start probe
[   98.637582] ds90ub940n 8-002c: ds90ub940n_parse_dt
[   98.637584] ds90ub940n 8-002c: ds90ub940n_parse_dt: parse clocks
[   98.637592] ds90ub940n 8-002c: pwdn gpios not in DT
[   98.642939] ds90ub940n 8-002c: toggle gpio
[   98.809272] ds90ub940n 8-002c: toggle done
[   98.809279] ds90ub940n 8-002c: ds90ub940n_power_get: power get
[   98.809511] ds90ub940n 8-002c: tegracam sensor driver:ub940n_v2.0.6
[   98.809546] tegra-camrtc-capture-vi tegra-capture-vi: subdev ds90ub940n 8-002c bound
[   98.809772] video4linux video0: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[   98.809847] video4linux video0: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[   98.809849] video4linux video0: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[   98.809851] video4linux video0: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[   98.917601] ds90ub940n 8-002c: ds90ub940n: input is YUV422 8bit
[   99.025300] ds90ub940n 8-002c: ds90ub940n: probed
[   99.025495] ds90ub940n 8-002e: ds90ub940n: start probe
[   99.025527] ds90ub940n 8-002e: ds90ub940n_parse_dt
[   99.025529] ds90ub940n 8-002e: ds90ub940n_parse_dt: parse clocks
[   99.025535] ds90ub940n 8-002e: pwdn gpios not in DT
[   99.030884] ds90ub940n 8-002e: toggle gpio
[   99.197265] ds90ub940n 8-002e: toggle done
[   99.197273] ds90ub940n 8-002e: ds90ub940n_power_get: power get
[   99.197528] ds90ub940n 8-002e: tegracam sensor driver:ub940n_v2.0.6
[   99.197557] tegra-camrtc-capture-vi tegra-capture-vi: subdev ds90ub940n 8-002e bound
[   99.197670] video4linux video1: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[   99.197774] video4linux video1: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[   99.197777] video4linux video1: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[   99.197779] video4linux video1: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[   99.305587] ds90ub940n 8-002e: ds90ub940n: input is YUV422 8bit
[   99.413290] ds90ub940n 8-002e: ds90ub940n: probed
[  165.326884] video4linux video0: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848
[  165.328466] ds90ub940n 8-002c: ds90ub940n_power_on: power on
[  165.329865] ds90ub940n 8-002c: ds90ub940n_power_on: power complete
[  165.338925] bwmgr API not supported
[  165.340207] ds90ub940n 8-002c: ds90ub940n_set_mode: mode_prop_idx=0
[  165.343840] ko_frmfmt = (1924,720) 
[  165.353882] ds90ub940n 8-002c: ds90ub940n_start_streaming: mode_prop_idx=0
[  165.380829] [RCE] NVCSILP clock rate = 408000000 Hz.
[  165.380833] [RCE] VI5: tegra_vi_channel_alloc()
[  165.380834] [RCE] VI5: unit 0 stream ID table:
[  165.380835] [RCE] VI5: unit 0 stream ID table from THI:
[  165.380836] [RCE] VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00
[  165.380838] [RCE] VI5: vi5_trace_enable: configuring trace buffer at iova=0x8375dda00 size=32768
[  165.380839] [RCE] VI5: tegra_vi_channel_alloc: ch=35
[  165.380840] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  165.380841] [RCE] VI5: channel_submit(35, bfebf000)
[  165.380842] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  165.380843] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  165.380844] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  165.380845] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  165.380846] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  165.380847] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  165.380848] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  165.380849] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  165.380850] [RCE] VI5: enabling stream 0
[  165.380851] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  165.380852] [RCE] VI5: channel_submit(35, bfebf180)
[  165.380853] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  165.380854] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  165.380855] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  165.380856] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  165.380857] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  165.380858] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  165.380859] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  165.380860] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  165.380861] [RCE] tegra_nvcsi_stream_set_config(vm0, stream=0, csi=0)
[  165.380862] [RCE] MIPI clock = 206400 kHz, tHS-SETTLE = 0, tCLK-SETTLE = 0
[  165.380863] [RCE] tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
[  165.380864] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380865] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380866] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380867] [RCE] nvcsi_calc_tclk_settle tclk_settle 75
[  165.380868] [RCE] ISR PHY 0 CIL_A 0x110
[  165.380869] [RCE] ISR PHY 0 CIL_B 0x10
[  165.380870] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380871] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380872] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380873] [RCE] nvcsi_calc_tclk_settle tclk_settle 75
[  165.380874] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380875] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380876] [RCE] nvcsi_calc_ths_settle ths_settle 77
[  165.380877] [RCE] nvcsi_calc_tclk_settle tclk_settle 75
[  165.380878] [RCE] VI5: vi5_process_error_fifo: tag=0x03 ch=0 frame=0 ts=192141376384 data=0x00000001 ext_data=0x00000000
[  165.380879] [RCE] VI5: error_to_notify_bits CSIMUX_STREAM error detected
[  165.380880] [RCE] VI5: vi5_notify_error(35) status 0x3 tag 0x3, data 0x1
[  165.380881] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  165.380882] [RCE] VI5: vi5_handle_sof(35)
[  165.380883] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  165.380884] [RCE] VI5: vi5_handle_eof(35)
[  165.380885] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  165.380886] [RCE] VI5: vi5_handle_sof(35)
[  165.436833] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  165.436838] [RCE] VI5: vi5_handle_eof(35)
[  165.436840] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192186302368 data=0x000003c9 ext_data=0x00000000
[  165.436841] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.436842] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.436843] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192202968512 data=0x000003c9 ext_data=0x00000000
[  165.436844] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.436845] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.436846] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192219634624 data=0x000003c9 ext_data=0x00000000
[  165.436847] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.436848] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.436849] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  165.436850] [RCE] VI5: channel_submit(35, bfebf000)
[  165.436852] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  165.436853] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  165.436854] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  165.436855] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  165.436856] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  165.436857] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  165.436858] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  165.436859] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  165.436860] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  165.436861] [RCE] VI5: vi5_handle_sof(35)
[  165.492824] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  165.492830] [RCE] VI5: vi5_handle_eof(35)
[  165.492832] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192252966848 data=0x000003c9 ext_data=0x00000000
[  165.492833] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.492834] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.492835] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192269632960 data=0x000003c9 ext_data=0x00000000
[  165.492836] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.492837] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.492838] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192286299072 data=0x000003c9 ext_data=0x00000000
[  165.492839] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.492841] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.552829] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  165.552833] [RCE] VI5: channel_submit(35, bfebf180)
[  165.552834] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  165.552835] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  165.552836] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  165.552837] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  165.552838] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  165.552839] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  165.552840] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  165.552842] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  165.552843] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  165.552844] [RCE] VI5: vi5_handle_sof(35)
[  165.552845] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  165.552846] [RCE] VI5: vi5_handle_eof(35)
[  165.552847] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192319631328 data=0x000003c9 ext_data=0x00000000
[  165.552848] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.552849] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.552850] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192336297440 data=0x000003c9 ext_data=0x00000000
[  165.552851] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.552852] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.552853] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192352963520 data=0x000003c9 ext_data=0x00000000
[  165.552854] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.552855] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.612831] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  165.612836] [RCE] VI5: channel_submit(35, bfebf000)
[  165.612838] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  165.612839] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  165.612840] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  165.612841] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  165.612842] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  165.612843] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  165.612844] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  165.612845] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  165.612846] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  165.612847] [RCE] VI5: vi5_handle_sof(35)
[  165.612848] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  165.612849] [RCE] VI5: vi5_handle_eof(35)
[  165.612850] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192386295776 data=0x000003c9 ext_data=0x00000000
[  165.612851] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.612852] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.612853] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192402961888 data=0x000003c9 ext_data=0x00000000
[  165.612854] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.612855] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.668829] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  165.668835] [RCE] VI5: channel_submit(35, bfebf180)
[  165.668836] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  165.668837] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  165.668838] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  165.668839] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  165.668840] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  165.668841] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  165.668842] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  165.668843] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
[  165.668844] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
[  165.668845] [RCE] VI5: vi5_handle_sof(35)
[  165.668846] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
[  165.668847] [RCE] VI5: vi5_handle_eof(35)
[  165.668848] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192436294112 data=0x000003c9 ext_data=0x00000000
[  165.668849] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.668850] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.668851] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192452960224 data=0x000003c9 ext_data=0x00000000
[  165.668852] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.668853] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.668854] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=192469626336 data=0x000003c9 ext_data=0x00000000
[  165.668855] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
[  165.668857] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
[  165.724834] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
[  165.724840] [RCE] VI5: channel_submit(35, bfebf000)
[  165.724841] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
[  165.724842] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
[  165.724843] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
[  165.724844] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
[  165.724845] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
[  165.724845] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
[  165.724847] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
[  165.724848] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
...

and kernel log of v4l2-ctl -d /dev/video0 --set-ctrl preferred_stride=4

[  556.793476] video4linux video0: tegra_channel_update_format: sizeimage= 2770560 bytesperline=3848

I think sizeimage & byteperline have no problem.
What more can i do ?

Thanks.

hello ghdlghdl0558,

please try hacking bytesperline as 3904 for your 1924x720 sensor stream.

Hi, JerryChang.

It worked.
Thank you for your help!!

hello ghdlghdl0558,

this may be a corner case to hit this bug.
according to below, bytesperline should be overwritten by v4l control.

static void tegra_channel_update_format()
...
   chan->format.bytesperline = preferred_stride ?: bytesperline;

   ...
   tegra_channel_fmt_align(chan, chan->fmtinfo,
   			&chan->format.width,
   			&chan->format.height,
   			&chan->format.bytesperline);

   /* Calculate the sizeimage per plane */
   chan->format.sizeimage = get_aligned_buffer_size(chan,
   		chan->format.bytesperline, chan->format.height);

could you please check you’re also able to resolve the issue by… --set-ctrl preferred_stride=3904.
thanks

Hi, JerryChang.

Even if i do not modify the kernel, 1924x720 works normally after execute v4l2-ctl -d /dev/video0 --set-ctrl preferred_stride=3904

Thanks.

hello ghdlghdl0558,

thanks for your test results, it’s a heads up of preferred_stride settings should also follow 64-alignment.

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