Camera not working on 1924x720

Hi,
I am trying to receive video through CSI and output it with gstreamer.

I tested it on Jetpack 5.0.1 and Jetpack 5.0.2 and confirmed that the video is output well at 1920x720 and 1024x1920.

However, when I receive a 1924x720 video, the output comes out strangely.

We used yuv_uyvy16 as the pixel format.
The gstreamer command we used is shown below.

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

full output video

Original Image
image

Output Image
image

kernel log is below.

Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.107613] ds90ub940n 8-002c: ds90ub940n_set_mode: mode_prop_idx=0
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.111228] ko_frmfmt = (1924,720)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.121267] ds90ub940n 8-002c: ds90ub940n_start_streaming: mode_prop_idx=0
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134350] [RCE] VM0 deactivating.VM0 activating.NVCSILP clock rate = 408000000 Hz.
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134354] [RCE] VI5: tegra_vi_channel_alloc()
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134356] [RCE] VI5: unit 0 stream ID table:
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134357] [RCE] VI5: unit 0 stream ID table from THI:
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134358] [RCE] VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134360] [RCE] VI5: vi5_trace_enable: configuring trace buffer at iova=0x8375dda00 size=32768
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134361] [RCE] VI5: tegra_vi_channel_alloc: ch=35
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134363] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134364] [RCE] VI5: channel_submit(35, bfebf000)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134366] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134367] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134369] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134370] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134372] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134373] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134375] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134376] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134377] [RCE] VI5: enabling stream 0
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134379] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134380] [RCE] VI5: channel_submit(35, bfebf180)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134382] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134383] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134385] [RCE] VI5: completion_actions[2] size = 14, next offset = 359
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134386] [RCE] VI5: completion_actions[1] size = 30, next offset = 389
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134388] [RCE] VI5: completion_actions[4] size = 14, next offset = 403
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134390] [RCE] VI5: completion_actions[3] size = 14, next offset = 417
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134391] [RCE] VI5: completion_actions[5] size = 2, next offset = 419
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134392] [RCE] VI5: vi5_update_streams: ch=35 stream_mask=0x01
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134394] [RCE] tegra_nvcsi_stream_set_config(vm0, stream=0, csi=0)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134395] [RCE] MIPI clock = 188540 kHz, tHS-SETTLE = 0, tCLK-SETTLE = 0
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134397] [RCE] tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134399] [RCE] nvcsi_calc_ths_settle ths_settle 80
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134400] [RCE] nvcsi_calc_ths_settle ths_settle 80
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134402] [RCE] nvcsi_calc_ths_settle ths_settle 80
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134403] [RCE] nvcsi_calc_tclk_settle tclk_settle 75
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134407] [RCE] VI5: vi5_process_error_fifo: tag=0x03 ch=0 frame=0 ts=1740177401792 data=0x00000001 ext_data=0x00000000
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134408] [RCE] VI5: error_to_notify_bits CSIMUX_STREAM error detected
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134410] [RCE] VI5: vi5_notify_error(35) status 0x3 tag 0x3, data 0x1
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134411] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134412] [RCE] VI5: vi5_handle_sof(35)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134417] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134418] [RCE] VI5: vi5_handle_eof(35)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134420] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x1
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.134421] [RCE] VI5: vi5_handle_sof(35)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194342] [RCE] VI5: vi5_irq_process_channel_event invoked: CH35_INT_STATUS = 0x2
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194345] [RCE] VI5: vi5_handle_eof(35)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194347] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=1740218579040 data=0x000003c9 ext_data=0x00000000
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194349] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194350] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194353] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=1740235288576 data=0x000003c9 ext_data=0x00000000
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194356] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194358] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194360] [RCE] VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame=0 ts=1740251998176 data=0x000003c9 ext_data=0x00000000
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194362] [RCE] VI5: error_to_notify_bits CHANSEL_NOMATCH identified
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194364] [RCE] VI5: vi5_notify_error(35) status 0xf tag 0xb, data 0x3c9
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194365] [RCE] VI5: PFSD enabled for channel: NO, expected_count. 0
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194366] [RCE] VI5: channel_submit(35, bfebf000)
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194367] [RCE] VI5: completion_actions[8] size = 30, next offset = 314
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194369] [RCE] VI5: completion_actions[0] size = 31, next offset = 345
Jan 3 15:40:26 nvidia-desktop kernel: [ 1717.194370] [RCE] VI5: completion_actions[2] size = 14, next offset = 359

trace log is below.

# tracer: nop
#
# entries-in-buffer/entries-written: 43009/43009   #P:12
#
#                                _-----=> irqs-off
#                               / _----=> need-resched
#                              | / _---=> hardirq/softirq
#                              || / _--=> preempt-depth
#                              ||| /     delay
#           TASK-PID     CPU#  ||||   TIMESTAMP  FUNCTION
#              | |         |   ||||      |         |
     kworker/5:1-2349    [005] ....  1031.293544: rtcpu_string: tstamp:32947568462 id:0x04010000 str:"NVCSILP clock rate = 408000000 Hz.
"
     kworker/5:1-2349    [005] ....  1031.293574: rtcpu_dbg_set_loglevel: tstamp:32947714157 old:0 new:2
     kworker/5:1-2349    [005] ....  1037.241146: rtcpu_string: tstamp:33134217417 id:0x04010000 str:"VM0 deactivating."
     kworker/5:1-2349    [005] ....  1717.134344: rtcpu_string: tstamp:54380351320 id:0x04010000 str:"VM0 activating."
     kworker/5:1-2349    [005] ....  1717.134346: rtcpu_string: tstamp:54380423865 id:0x04010000 str:"NVCSILP clock rate = 408000000 Hz.
"
     kworker/5:1-2349    [005] ....  1717.134354: rtcpu_string: tstamp:54380462386 id:0x04010000 str:"VI5: tegra_vi_channel_alloc()
"
     kworker/5:1-2349    [005] ....  1717.134355: rtcpu_string: tstamp:54380467772 id:0x04010000 str:"VI5: unit 0 stream ID table:
"
     kworker/5:1-2349    [005] ....  1717.134357: rtcpu_string: tstamp:54380468015 id:0x04010000 str:"VI5: unit 0 stream ID table from THI:
"
     kworker/5:1-2349    [005] ....  1717.134358: rtcpu_string: tstamp:54380468296 id:0x04010000 str:"VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00
"
     kworker/5:1-2349    [005] ....  1717.134359: rtcpu_string: tstamp:54380473746 id:0x04010000 str:"VI5: vi5_trace_enable: configuring trace buffer "
     kworker/5:1-2349    [005] ....  1717.134360: rtcpu_string: tstamp:54380473849 id:0x04010000 str:"at iova=0x8375dda00 size=32768
"
     kworker/5:1-2349    [005] ....  1717.134361: rtcpu_string: tstamp:54380475048 id:0x04010000 str:"VI5: tegra_vi_channel_alloc: ch=35
"
     kworker/5:1-2349    [005] ....  1717.134362: rtcpu_string: tstamp:54380479239 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/5:1-2349    [005] ....  1717.134363: rtcpu_string: tstamp:54380479341 id:0x04010000 str:"t. 0
"
     kworker/5:1-2349    [005] ....  1717.134364: rtcpu_string: tstamp:54380479577 id:0x04010000 str:"VI5: channel_submit(35, bfebf000)
"
     kworker/5:1-2349    [005] ....  1717.134365: rtcpu_string: tstamp:54380480070 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.134366: rtcpu_string: tstamp:54380480171 id:0x04010000 str:"t = 314
"
     kworker/5:1-2349    [005] ....  1717.134367: rtcpu_string: tstamp:54380480476 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/5:1-2349    [005] ....  1717.134367: rtcpu_string: tstamp:54380480577 id:0x04010000 str:"t = 345
"
     kworker/5:1-2349    [005] ....  1717.134368: rtcpu_string: tstamp:54380480874 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.134369: rtcpu_string: tstamp:54380480974 id:0x04010000 str:"t = 359
"
     kworker/5:1-2349    [005] ....  1717.134370: rtcpu_string: tstamp:54380481272 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.134370: rtcpu_string: tstamp:54380481373 id:0x04010000 str:"t = 389
"
     kworker/5:1-2349    [005] ....  1717.134371: rtcpu_string: tstamp:54380481670 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.134371: rtcpu_string: tstamp:54380481771 id:0x04010000 str:"t = 403
"
     kworker/5:1-2349    [005] ....  1717.134373: rtcpu_string: tstamp:54380482068 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.134373: rtcpu_string: tstamp:54380482168 id:0x04010000 str:"t = 417
"
     kworker/5:1-2349    [005] ....  1717.134374: rtcpu_string: tstamp:54380482460 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/5:1-2349    [005] ....  1717.134375: rtcpu_string: tstamp:54380482558 id:0x04010000 str:" = 419
"
     kworker/5:1-2349    [005] ....  1717.134376: rtcpu_string: tstamp:54380482929 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/5:1-2349    [005] ....  1717.134377: rtcpu_string: tstamp:54380483124 id:0x04010000 str:"VI5: enabling stream 0
"
     kworker/5:1-2349    [005] ....  1717.134378: rtcpu_string: tstamp:54380484793 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/5:1-2349    [005] ....  1717.134379: rtcpu_string: tstamp:54380484895 id:0x04010000 str:"t. 0
"
     kworker/5:1-2349    [005] ....  1717.134380: rtcpu_string: tstamp:54380485130 id:0x04010000 str:"VI5: channel_submit(35, bfebf180)
"
     kworker/5:1-2349    [005] ....  1717.134381: rtcpu_string: tstamp:54380485525 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.134381: rtcpu_string: tstamp:54380485626 id:0x04010000 str:"t = 314
"
     kworker/5:1-2349    [005] ....  1717.134383: rtcpu_string: tstamp:54380485932 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/5:1-2349    [005] ....  1717.134383: rtcpu_string: tstamp:54380486033 id:0x04010000 str:"t = 345
"
     kworker/5:1-2349    [005] ....  1717.134385: rtcpu_string: tstamp:54380486333 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.134385: rtcpu_string: tstamp:54380486434 id:0x04010000 str:"t = 359
"
     kworker/5:1-2349    [005] ....  1717.134386: rtcpu_string: tstamp:54380486731 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.134386: rtcpu_string: tstamp:54380486832 id:0x04010000 str:"t = 389
"
     kworker/5:1-2349    [005] ....  1717.134387: rtcpu_string: tstamp:54380487129 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.134388: rtcpu_string: tstamp:54380487230 id:0x04010000 str:"t = 403
"
     kworker/5:1-2349    [005] ....  1717.134389: rtcpu_string: tstamp:54380487526 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.134389: rtcpu_string: tstamp:54380487627 id:0x04010000 str:"t = 417
"
     kworker/5:1-2349    [005] ....  1717.134391: rtcpu_string: tstamp:54380487921 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/5:1-2349    [005] ....  1717.134391: rtcpu_string: tstamp:54380488021 id:0x04010000 str:" = 419
"
     kworker/5:1-2349    [005] ....  1717.134392: rtcpu_string: tstamp:54380488390 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/5:1-2349    [005] ....  1717.134393: rtcpu_string: tstamp:54380533107 id:0x04010000 str:"tegra_nvcsi_stream_set_config(vm0, stream=0, csi"
     kworker/5:1-2349    [005] ....  1717.134394: rtcpu_string: tstamp:54380533208 id:0x04010000 str:"=0)
"
     kworker/5:1-2349    [005] ....  1717.134395: rtcpu_string: tstamp:54380533576 id:0x04010000 str:"MIPI clock = 188540 kHz, tHS-SETTLE = 0, tCLK-SE"
     kworker/5:1-2349    [005] ....  1717.134395: rtcpu_string: tstamp:54380533677 id:0x04010000 str:"TTLE = 0
"
     kworker/5:1-2349    [005] ....  1717.134397: rtcpu_string: tstamp:54380535318 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=0, csi=0)
"
     kworker/5:1-2349    [005] ....  1717.134399: rtcpu_string: tstamp:54380542175 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 80
"
     kworker/5:1-2349    [005] ....  1717.134400: rtcpu_string: tstamp:54380542439 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 80
"
     kworker/5:1-2349    [005] ....  1717.134402: rtcpu_string: tstamp:54380542697 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 80
"
     kworker/5:1-2349    [005] ....  1717.134403: rtcpu_string: tstamp:54380542950 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 75
"
     kworker/5:1-2349    [005] ....  1717.134405: rtcpu_vinotify_error: tstamp:54380544458 cch:-1 vi:0 tag:CSIMUX_STREAM channel:0x00 frame:0 vi_tstamp:1740177401792 data:0x0000000000000001
     kworker/5:1-2349    [005] ....  1717.134406: rtcpu_string: tstamp:54380544973 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x03 ch=0 frame"
     kworker/5:1-2349    [005] ....  1717.134406: rtcpu_string: tstamp:54380545074 id:0x04010000 str:"=0 ts=1740177401792 data=0x00000001 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.134406: rtcpu_string: tstamp:54380545175 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.134408: rtcpu_string: tstamp:54380545525 id:0x04010000 str:"VI5: error_to_notify_bits CSIMUX_STREAM error de"
     kworker/5:1-2349    [005] ....  1717.134408: rtcpu_string: tstamp:54380545626 id:0x04010000 str:"tected
"
     kworker/5:1-2349    [005] ....  1717.134409: rtcpu_string: tstamp:54380545953 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0x3 tag 0x3, da"
     kworker/5:1-2349    [005] ....  1717.134409: rtcpu_string: tstamp:54380546054 id:0x04010000 str:"ta 0x1
"
     kworker/5:1-2349    [005] ....  1717.134411: rtcpu_string: tstamp:54380787182 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.134411: rtcpu_string: tstamp:54380787286 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/5:1-2349    [005] ....  1717.134412: rtcpu_string: tstamp:54380787540 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/5:1-2349    [005] ....  1717.134414: rtcpu_vinotify_event: tstamp:54380806301 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740175482464 data:0x375cff0010000000
     kworker/5:1-2349    [005] ....  1717.134415: rtcpu_vinotify_event: tstamp:54380806438 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740175489056 data:0x0000000031000001
     kworker/5:1-2349    [005] ....  1717.134415: rtcpu_vinotify_event: tstamp:54380806591 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740175647872 data:0x375cfc0010000000
     kworker/5:1-2349    [005] ....  1717.134415: rtcpu_vinotify_event: tstamp:54380806723 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740175654528 data:0x0000000031000002
     kworker/5:1-2349    [005] ....  1717.134415: rtcpu_vinotify_event: tstamp:54380806879 cch:-1 vi:0 tag:CSIMUX_STREAM channel:0x00 frame:0 vi_tstamp:1740177401792 data:0x0000000000000001
     kworker/5:1-2349    [005] ....  1717.134415: rtcpu_vinotify_event: tstamp:54380807011 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740185035296 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.134415: rtcpu_vinotify_event: tstamp:54380807164 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:1740185035328 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.134416: rtcpu_vinotify_event: tstamp:54380807296 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:1740185159808 data:0x0000000000000001
     kworker/5:1-2349    [005] ....  1717.134416: rtcpu_vinotify_event: tstamp:54380807452 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740185161056 data:0x0000000008020001
     kworker/5:1-2349    [005] ....  1717.134416: rtcpu_string: tstamp:54381305808 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.134416: rtcpu_string: tstamp:54381305910 id:0x04010000 str:"_INT_STATUS = 0x2
"
     kworker/5:1-2349    [005] ....  1717.134418: rtcpu_string: tstamp:54381306180 id:0x04010000 str:"VI5: vi5_handle_eof(35)
"
     kworker/5:1-2349    [005] ....  1717.134419: rtcpu_vinotify_event: tstamp:54381307103 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:1740201675104 data:0x0000000002cf0002
     kworker/5:1-2349    [005] ....  1717.134419: rtcpu_vinotify_event: tstamp:54381307256 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:1740201675680 data:0x0000000000000000
     kworker/5:1-2349    [005] ....  1717.134419: rtcpu_vinotify_event: tstamp:54381307388 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740201677504 data:0x0000000002020001
     kworker/5:1-2349    [005] ....  1717.134419: rtcpu_vinotify_event: tstamp:54381307540 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740201744064 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.134420: rtcpu_vinotify_event: tstamp:54381307679 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:1740201744064 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.134420: rtcpu_vinotify_event: tstamp:54381307828 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740201748544 data:0x0000000000020001
     kworker/5:1-2349    [005] ....  1717.134420: rtcpu_vinotify_event: tstamp:54381307959 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740201744896 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.134420: rtcpu_string: tstamp:54381309354 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.134420: rtcpu_string: tstamp:54381309457 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/5:1-2349    [005] ....  1717.134421: rtcpu_string: tstamp:54381309721 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/5:1-2349    [005] ....  1717.194337: rtcpu_vinotify_event: tstamp:54381617626 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:1740201744928 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.194339: rtcpu_vinotify_event: tstamp:54381617763 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:1740201869376 data:0x0000000000000001
     kworker/5:1-2349    [005] ....  1717.194339: rtcpu_vinotify_event: tstamp:54381617915 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740201870656 data:0x0000000008020002
     kworker/5:1-2349    [005] ....  1717.194340: rtcpu_string: tstamp:54381827732 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.194340: rtcpu_string: tstamp:54381827835 id:0x04010000 str:"_INT_STATUS = 0x2
"
     kworker/5:1-2349    [005] ....  1717.194345: rtcpu_string: tstamp:54381828108 id:0x04010000 str:"VI5: vi5_handle_eof(35)
"
     kworker/5:1-2349    [005] ....  1717.194346: rtcpu_vinotify_error: tstamp:54381831316 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740218579040 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.194346: rtcpu_string: tstamp:54381831831 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.194347: rtcpu_string: tstamp:54381831935 id:0x04010000 str:"=0 ts=1740218579040 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.194347: rtcpu_string: tstamp:54381832040 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.194348: rtcpu_string: tstamp:54381832378 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.194349: rtcpu_string: tstamp:54381832479 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.194350: rtcpu_string: tstamp:54381832811 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.194350: rtcpu_string: tstamp:54381832914 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.194352: rtcpu_vinotify_event: tstamp:54381955705 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:1740218384704 data:0x0000000002cf0002
     kworker/5:1-2349    [005] ....  1717.194352: rtcpu_vinotify_event: tstamp:54381955865 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:1740218385280 data:0x0000000000000000
     kworker/5:1-2349    [005] ....  1717.194352: rtcpu_vinotify_event: tstamp:54381955997 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740218387072 data:0x0000000002020002
     kworker/5:1-2349    [005] ....  1717.194352: rtcpu_vinotify_event: tstamp:54381956146 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740218453696 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.194352: rtcpu_vinotify_event: tstamp:54381956280 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:1740218453728 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.194352: rtcpu_vinotify_event: tstamp:54381956433 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740218458176 data:0x0000000000020002
     kworker/5:1-2349    [005] ....  1717.194352: rtcpu_vinotify_event: tstamp:54381956565 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740218454560 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.194352: rtcpu_vinotify_event: tstamp:54381956713 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740218579040 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.194353: rtcpu_vinotify_error: tstamp:54382353489 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740235288576 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.194353: rtcpu_string: tstamp:54382354000 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.194353: rtcpu_string: tstamp:54382354103 id:0x04010000 str:"=0 ts=1740235288576 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.194353: rtcpu_string: tstamp:54382354206 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.194356: rtcpu_string: tstamp:54382354542 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.194356: rtcpu_string: tstamp:54382354642 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.194357: rtcpu_string: tstamp:54382354974 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.194358: rtcpu_string: tstamp:54382355074 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.194359: rtcpu_vinotify_event: tstamp:54382631818 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740235163264 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.194359: rtcpu_vinotify_event: tstamp:54382631970 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740235164096 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.194359: rtcpu_vinotify_event: tstamp:54382632101 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740235288576 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.194359: rtcpu_vinotify_event: tstamp:54382871596 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740251872864 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.194359: rtcpu_vinotify_event: tstamp:54382871727 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740251873696 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.194360: rtcpu_vinotify_error: tstamp:54382875661 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740251998176 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.194360: rtcpu_string: tstamp:54382876173 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.194360: rtcpu_string: tstamp:54382876277 id:0x04010000 str:"=0 ts=1740251998176 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.194360: rtcpu_string: tstamp:54382876381 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.194362: rtcpu_string: tstamp:54382876715 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.194362: rtcpu_string: tstamp:54382876815 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.194363: rtcpu_string: tstamp:54382877149 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.194363: rtcpu_string: tstamp:54382877251 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.194364: rtcpu_string: tstamp:54383102278 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/5:1-2349    [005] ....  1717.194365: rtcpu_string: tstamp:54383102381 id:0x04010000 str:"t. 0
"
     kworker/5:1-2349    [005] ....  1717.194366: rtcpu_string: tstamp:54383102618 id:0x04010000 str:"VI5: channel_submit(35, bfebf000)
"
     kworker/5:1-2349    [005] ....  1717.194367: rtcpu_string: tstamp:54383103015 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.194367: rtcpu_string: tstamp:54383103120 id:0x04010000 str:"t = 314
"
     kworker/5:1-2349    [005] ....  1717.194368: rtcpu_string: tstamp:54383103427 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/5:1-2349    [005] ....  1717.194369: rtcpu_string: tstamp:54383103530 id:0x04010000 str:"t = 345
"
     kworker/5:1-2349    [005] ....  1717.194370: rtcpu_string: tstamp:54383103830 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.194370: rtcpu_string: tstamp:54383103939 id:0x04010000 str:"t = 359
"
     kworker/5:1-2349    [005] ....  1717.194371: rtcpu_string: tstamp:54383104243 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.194371: rtcpu_string: tstamp:54383104346 id:0x04010000 str:"t = 389
"
     kworker/5:1-2349    [005] ....  1717.194372: rtcpu_string: tstamp:54383104646 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.194373: rtcpu_string: tstamp:54383104747 id:0x04010000 str:"t = 403
"
     kworker/5:1-2349    [005] ....  1717.194374: rtcpu_string: tstamp:54383105045 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.194374: rtcpu_string: tstamp:54383105148 id:0x04010000 str:"t = 417
"
     kworker/5:1-2349    [005] ....  1717.194375: rtcpu_string: tstamp:54383105443 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/5:1-2349    [005] ....  1717.194375: rtcpu_string: tstamp:54383105547 id:0x04010000 str:" = 419
"
     kworker/5:1-2349    [005] ....  1717.194376: rtcpu_string: tstamp:54383105918 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/5:1-2349    [005] ....  1717.194377: rtcpu_vinotify_event: tstamp:54383206499 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740251998176 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.194378: rtcpu_vinotify_event: tstamp:54383206636 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740259409280 data:0x375cff0010000000
     kworker/5:1-2349    [005] ....  1717.194378: rtcpu_vinotify_event: tstamp:54383206793 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740259415904 data:0x0000000031000003
     kworker/5:1-2349    [005] ....  1717.254334: rtcpu_string: tstamp:54383398054 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.254335: rtcpu_string: tstamp:54383398157 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/5:1-2349    [005] ....  1717.254339: rtcpu_string: tstamp:54383398410 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/5:1-2349    [005] ....  1717.254341: rtcpu_vinotify_event: tstamp:54383544497 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740268582432 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.254341: rtcpu_vinotify_event: tstamp:54383544647 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740268583264 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.254341: rtcpu_vinotify_event: tstamp:54383544782 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:1740268583264 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.254342: rtcpu_vinotify_event: tstamp:54383544935 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:1740268707744 data:0x0000000000000001
     kworker/5:1-2349    [005] ....  1717.254342: rtcpu_vinotify_event: tstamp:54383545069 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740268708992 data:0x0000000008020003
     kworker/5:1-2349    [005] ....  1717.254342: rtcpu_string: tstamp:54383916422 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.254342: rtcpu_string: tstamp:54383916526 id:0x04010000 str:"_INT_STATUS = 0x2
"
     kworker/5:1-2349    [005] ....  1717.254343: rtcpu_string: tstamp:54383916801 id:0x04010000 str:"VI5: vi5_handle_eof(35)
"
     kworker/5:1-2349    [005] ....  1717.254345: rtcpu_vinotify_error: tstamp:54383920005 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740285417344 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.254345: rtcpu_string: tstamp:54383920517 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.254345: rtcpu_string: tstamp:54383920625 id:0x04010000 str:"=0 ts=1740285417344 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.254345: rtcpu_string: tstamp:54383920731 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.254347: rtcpu_string: tstamp:54383921070 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.254347: rtcpu_string: tstamp:54383921172 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.254348: rtcpu_string: tstamp:54383921507 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.254349: rtcpu_string: tstamp:54383921609 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.254350: rtcpu_vinotify_event: tstamp:54383921959 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:1740285223072 data:0x0000000002cf0002
     kworker/5:1-2349    [005] ....  1717.254350: rtcpu_vinotify_event: tstamp:54383922095 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:1740285223616 data:0x0000000000000000
     kworker/5:1-2349    [005] ....  1717.254351: rtcpu_vinotify_event: tstamp:54383922252 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740285225440 data:0x0000000002020003
     kworker/5:1-2349    [005] ....  1717.254351: rtcpu_vinotify_event: tstamp:54383922383 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740285292000 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.254351: rtcpu_vinotify_event: tstamp:54383922539 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:1740285292032 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.254351: rtcpu_vinotify_event: tstamp:54383922671 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740285296480 data:0x0000000000020003
     kworker/5:1-2349    [005] ....  1717.254351: rtcpu_vinotify_event: tstamp:54383922823 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740285292832 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.254352: rtcpu_vinotify_event: tstamp:54383922955 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740285417344 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.254352: rtcpu_vinotify_error: tstamp:54384442189 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740302126976 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.254352: rtcpu_string: tstamp:54384442704 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.254352: rtcpu_string: tstamp:54384442818 id:0x04010000 str:"=0 ts=1740302126976 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.254353: rtcpu_string: tstamp:54384442920 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.254354: rtcpu_string: tstamp:54384443253 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.254354: rtcpu_string: tstamp:54384443353 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.254355: rtcpu_string: tstamp:54384443685 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.254356: rtcpu_string: tstamp:54384443785 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.254357: rtcpu_vinotify_event: tstamp:54384457226 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740302001632 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.254357: rtcpu_vinotify_event: tstamp:54384457361 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740302002496 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.254357: rtcpu_vinotify_event: tstamp:54384457509 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740302126976 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.254357: rtcpu_string: tstamp:54384864707 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/5:1-2349    [005] ....  1717.254358: rtcpu_string: tstamp:54384864813 id:0x04010000 str:"t. 0
"
     kworker/5:1-2349    [005] ....  1717.254359: rtcpu_string: tstamp:54384865053 id:0x04010000 str:"VI5: channel_submit(35, bfebf180)
"
     kworker/5:1-2349    [005] ....  1717.254360: rtcpu_string: tstamp:54384865450 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.254360: rtcpu_string: tstamp:54384865551 id:0x04010000 str:"t = 314
"
     kworker/5:1-2349    [005] ....  1717.254362: rtcpu_string: tstamp:54384865860 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/5:1-2349    [005] ....  1717.254362: rtcpu_string: tstamp:54384865984 id:0x04010000 str:"t = 345
"
     kworker/5:1-2349    [005] ....  1717.254363: rtcpu_string: tstamp:54384866285 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.254364: rtcpu_string: tstamp:54384866385 id:0x04010000 str:"t = 359
"
     kworker/5:1-2349    [005] ....  1717.254365: rtcpu_string: tstamp:54384866683 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.254365: rtcpu_string: tstamp:54384866783 id:0x04010000 str:"t = 389
"
     kworker/5:1-2349    [005] ....  1717.254366: rtcpu_string: tstamp:54384867079 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.254366: rtcpu_string: tstamp:54384867180 id:0x04010000 str:"t = 403
"
     kworker/5:1-2349    [005] ....  1717.254367: rtcpu_string: tstamp:54384867481 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.254368: rtcpu_string: tstamp:54384867584 id:0x04010000 str:"t = 417
"
     kworker/5:1-2349    [005] ....  1717.254369: rtcpu_string: tstamp:54384867882 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/5:1-2349    [005] ....  1717.254369: rtcpu_string: tstamp:54384867985 id:0x04010000 str:" = 419
"
     kworker/5:1-2349    [005] ....  1717.254370: rtcpu_string: tstamp:54384868362 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/5:1-2349    [005] ....  1717.254371: rtcpu_string: tstamp:54384964582 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.254372: rtcpu_string: tstamp:54384964686 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/5:1-2349    [005] ....  1717.254373: rtcpu_string: tstamp:54384964940 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/5:1-2349    [005] ....  1717.314329: rtcpu_vinotify_event: tstamp:54385133309 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740315809280 data:0x375cfc0010000000
     kworker/5:1-2349    [005] ....  1717.314330: rtcpu_vinotify_event: tstamp:54385133467 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740315815872 data:0x0000000031000004
     kworker/5:1-2349    [005] ....  1717.314330: rtcpu_vinotify_event: tstamp:54385133600 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740318711200 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.314330: rtcpu_vinotify_event: tstamp:54385133749 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740318712064 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.314330: rtcpu_vinotify_event: tstamp:54385133884 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:1740318712064 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.314331: rtcpu_vinotify_event: tstamp:54385134035 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:1740318836544 data:0x0000000000000001
     kworker/5:1-2349    [005] ....  1717.314331: rtcpu_vinotify_event: tstamp:54385134166 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740318837824 data:0x0000000008020004
     kworker/5:1-2349    [005] ....  1717.314331: rtcpu_string: tstamp:54385483135 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.314331: rtcpu_string: tstamp:54385483243 id:0x04010000 str:"_INT_STATUS = 0x2
"
     kworker/5:1-2349    [005] ....  1717.314334: rtcpu_string: tstamp:54385483523 id:0x04010000 str:"VI5: vi5_handle_eof(35)
"
     kworker/5:1-2349    [005] ....  1717.314336: rtcpu_vinotify_event: tstamp:54385484476 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:1740335351808 data:0x0000000002cf0002
     kworker/5:1-2349    [005] ....  1717.314336: rtcpu_vinotify_event: tstamp:54385484612 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:1740335352384 data:0x0000000000000000
     kworker/5:1-2349    [005] ....  1717.314336: rtcpu_vinotify_event: tstamp:54385484765 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740335354176 data:0x0000000002020004
     kworker/5:1-2349    [005] ....  1717.314336: rtcpu_vinotify_event: tstamp:54385484896 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740335420800 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.314336: rtcpu_vinotify_event: tstamp:54385485048 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:1740335420832 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.314336: rtcpu_vinotify_event: tstamp:54385485179 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740335425280 data:0x0000000000020004
     kworker/5:1-2349    [005] ....  1717.314337: rtcpu_vinotify_error: tstamp:54385486539 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740335546144 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.314337: rtcpu_string: tstamp:54385487051 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.314337: rtcpu_string: tstamp:54385487158 id:0x04010000 str:"=0 ts=1740335546144 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.314337: rtcpu_string: tstamp:54385487261 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.314339: rtcpu_string: tstamp:54385487597 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.314339: rtcpu_string: tstamp:54385487698 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.314340: rtcpu_string: tstamp:54385488030 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.314341: rtcpu_string: tstamp:54385488130 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.314342: rtcpu_vinotify_event: tstamp:54385809507 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740335421632 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.314343: rtcpu_vinotify_event: tstamp:54385809643 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740335546144 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.314343: rtcpu_vinotify_error: tstamp:54386008710 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740352255680 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.314343: rtcpu_string: tstamp:54386009222 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.314343: rtcpu_string: tstamp:54386009324 id:0x04010000 str:"=0 ts=1740352255680 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.314343: rtcpu_string: tstamp:54386009428 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.314345: rtcpu_string: tstamp:54386009764 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.314345: rtcpu_string: tstamp:54386009864 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.314346: rtcpu_string: tstamp:54386010197 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.314346: rtcpu_string: tstamp:54386010296 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.314348: rtcpu_vinotify_event: tstamp:54386147540 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740352130368 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.314348: rtcpu_vinotify_event: tstamp:54386147740 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740352131200 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.314348: rtcpu_vinotify_event: tstamp:54386147960 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740352255680 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.314348: rtcpu_vinotify_error: tstamp:54386530886 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740368965280 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.314348: rtcpu_string: tstamp:54386531395 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.314348: rtcpu_string: tstamp:54386531496 id:0x04010000 str:"=0 ts=1740368965280 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.314349: rtcpu_string: tstamp:54386531599 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.314350: rtcpu_string: tstamp:54386531933 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.314350: rtcpu_string: tstamp:54386532033 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.314352: rtcpu_string: tstamp:54386532364 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.314352: rtcpu_string: tstamp:54386532464 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.314353: rtcpu_string: tstamp:54386633113 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/5:1-2349    [005] ....  1717.314353: rtcpu_string: tstamp:54386633213 id:0x04010000 str:"t. 0
"
     kworker/5:1-2349    [005] ....  1717.314354: rtcpu_string: tstamp:54386633445 id:0x04010000 str:"VI5: channel_submit(35, bfebf000)
"
     kworker/5:1-2349    [005] ....  1717.314356: rtcpu_string: tstamp:54386633846 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.314356: rtcpu_string: tstamp:54386633947 id:0x04010000 str:"t = 314
"
     kworker/5:1-2349    [005] ....  1717.314357: rtcpu_string: tstamp:54386634253 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/5:1-2349    [005] ....  1717.314357: rtcpu_string: tstamp:54386634356 id:0x04010000 str:"t = 345
"
     kworker/5:1-2349    [005] ....  1717.314359: rtcpu_string: tstamp:54386634655 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.314359: rtcpu_string: tstamp:54386634755 id:0x04010000 str:"t = 359
"
     kworker/5:1-2349    [005] ....  1717.314360: rtcpu_string: tstamp:54386635053 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.314360: rtcpu_string: tstamp:54386635154 id:0x04010000 str:"t = 389
"
     kworker/5:1-2349    [005] ....  1717.314362: rtcpu_string: tstamp:54386635451 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.314362: rtcpu_string: tstamp:54386635552 id:0x04010000 str:"t = 403
"
     kworker/5:1-2349    [005] ....  1717.314363: rtcpu_string: tstamp:54386635849 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.314363: rtcpu_string: tstamp:54386635949 id:0x04010000 str:"t = 417
"
     kworker/5:1-2349    [005] ....  1717.314365: rtcpu_string: tstamp:54386636243 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/5:1-2349    [005] ....  1717.314365: rtcpu_string: tstamp:54386636346 id:0x04010000 str:" = 419
"
     kworker/5:1-2349    [005] ....  1717.314366: rtcpu_string: tstamp:54386636714 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/5:1-2349    [005] ....  1717.314367: rtcpu_vinotify_event: tstamp:54386823579 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740368839968 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.314367: rtcpu_vinotify_event: tstamp:54386823733 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740368840800 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.314367: rtcpu_vinotify_event: tstamp:54386823863 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740368965280 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.314368: rtcpu_vinotify_event: tstamp:54386824017 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740372394336 data:0x375cff0010000000
     kworker/5:1-2349    [005] ....  1717.314368: rtcpu_vinotify_event: tstamp:54386824148 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740372400928 data:0x0000000031000005
     kworker/5:1-2349    [005] ....  1717.370334: rtcpu_string: tstamp:54387053279 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.370336: rtcpu_string: tstamp:54387053383 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/5:1-2349    [005] ....  1717.370341: rtcpu_string: tstamp:54387053640 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/5:1-2349    [005] ....  1717.370343: rtcpu_vinotify_event: tstamp:54387161578 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740385549600 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.370343: rtcpu_vinotify_event: tstamp:54387161708 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740385550432 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.370343: rtcpu_vinotify_event: tstamp:54387161865 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:1740385550464 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.370343: rtcpu_vinotify_event: tstamp:54387161997 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:1740385674912 data:0x0000000000000001
     kworker/5:1-2349    [005] ....  1717.370343: rtcpu_vinotify_event: tstamp:54387162146 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740385676192 data:0x0000000008020005
     kworker/5:1-2349    [005] ....  1717.370344: rtcpu_vinotify_event: tstamp:54387569053 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:1740402190176 data:0x0000000002cf0002
     kworker/5:1-2349    [005] ....  1717.370344: rtcpu_string: tstamp:54387571636 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.370344: rtcpu_string: tstamp:54387571738 id:0x04010000 str:"_INT_STATUS = 0x2
"
     kworker/5:1-2349    [005] ....  1717.370345: rtcpu_string: tstamp:54387572011 id:0x04010000 str:"VI5: vi5_handle_eof(35)
"
     kworker/5:1-2349    [005] ....  1717.370347: rtcpu_vinotify_error: tstamp:54387575236 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740402384480 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.370347: rtcpu_string: tstamp:54387575765 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.370348: rtcpu_string: tstamp:54387575866 id:0x04010000 str:"=0 ts=1740402384480 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.370348: rtcpu_string: tstamp:54387575969 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.370349: rtcpu_string: tstamp:54387576304 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.370350: rtcpu_string: tstamp:54387576404 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.370351: rtcpu_string: tstamp:54387576735 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.370351: rtcpu_string: tstamp:54387576836 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.370352: rtcpu_vinotify_event: tstamp:54387905320 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:1740402190720 data:0x0000000000000000
     kworker/5:1-2349    [005] ....  1717.370352: rtcpu_vinotify_event: tstamp:54387905455 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740402193728 data:0x0000000002020005
     kworker/5:1-2349    [005] ....  1717.370352: rtcpu_vinotify_event: tstamp:54387905604 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740402259168 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.370352: rtcpu_vinotify_event: tstamp:54387905742 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:1740402259168 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.370353: rtcpu_vinotify_event: tstamp:54387905897 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740402263648 data:0x0000000000020005
     kworker/5:1-2349    [005] ....  1717.370353: rtcpu_vinotify_event: tstamp:54387906028 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740402260032 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.370353: rtcpu_vinotify_event: tstamp:54387906179 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740402384480 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.370353: rtcpu_vinotify_error: tstamp:54388097411 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740419094080 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.370353: rtcpu_string: tstamp:54388097929 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.370354: rtcpu_string: tstamp:54388098032 id:0x04010000 str:"=0 ts=1740419094080 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.370354: rtcpu_string: tstamp:54388098135 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.370355: rtcpu_string: tstamp:54388098471 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.370355: rtcpu_string: tstamp:54388098571 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.370356: rtcpu_string: tstamp:54388098903 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.370357: rtcpu_string: tstamp:54388099004 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.370358: rtcpu_vinotify_event: tstamp:54388243318 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740418968736 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.370358: rtcpu_vinotify_event: tstamp:54388243471 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740418969600 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.370358: rtcpu_vinotify_event: tstamp:54388243602 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740419094080 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.370358: rtcpu_string: tstamp:54388378261 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/5:1-2349    [005] ....  1717.370358: rtcpu_string: tstamp:54388378366 id:0x04010000 str:"t. 0
"
     kworker/5:1-2349    [005] ....  1717.370360: rtcpu_string: tstamp:54388378602 id:0x04010000 str:"VI5: channel_submit(35, bfebf180)
"
     kworker/5:1-2349    [005] ....  1717.370361: rtcpu_string: tstamp:54388379000 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.370361: rtcpu_string: tstamp:54388379105 id:0x04010000 str:"t = 314
"
     kworker/5:1-2349    [005] ....  1717.370362: rtcpu_string: tstamp:54388379414 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/5:1-2349    [005] ....  1717.370363: rtcpu_string: tstamp:54388379518 id:0x04010000 str:"t = 345
"
     kworker/5:1-2349    [005] ....  1717.370364: rtcpu_string: tstamp:54388379820 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.370364: rtcpu_string: tstamp:54388379922 id:0x04010000 str:"t = 359
"
     kworker/5:1-2349    [005] ....  1717.370365: rtcpu_string: tstamp:54388380223 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.370365: rtcpu_string: tstamp:54388380325 id:0x04010000 str:"t = 389
"
     kworker/5:1-2349    [005] ....  1717.370366: rtcpu_string: tstamp:54388380625 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.370367: rtcpu_string: tstamp:54388380726 id:0x04010000 str:"t = 403
"
     kworker/5:1-2349    [005] ....  1717.370368: rtcpu_string: tstamp:54388381023 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.370368: rtcpu_string: tstamp:54388381124 id:0x04010000 str:"t = 417
"
     kworker/5:1-2349    [005] ....  1717.370369: rtcpu_string: tstamp:54388381420 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/5:1-2349    [005] ....  1717.370369: rtcpu_string: tstamp:54388381522 id:0x04010000 str:" = 419
"
     kworker/5:1-2349    [005] ....  1717.370371: rtcpu_string: tstamp:54388381893 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"
     kworker/5:1-2349    [005] ....  1717.370372: rtcpu_vinotify_event: tstamp:54388581452 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740428242176 data:0x375cfc0010000000
     kworker/5:1-2349    [005] ....  1717.370372: rtcpu_vinotify_event: tstamp:54388581591 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:1740428248768 data:0x0000000031000006
     kworker/5:1-2349    [005] ....  1717.370372: rtcpu_string: tstamp:54388619956 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.370372: rtcpu_string: tstamp:54388620058 id:0x04010000 str:"_INT_STATUS = 0x1
"
     kworker/5:1-2349    [005] ....  1717.370373: rtcpu_string: tstamp:54388620315 id:0x04010000 str:"VI5: vi5_handle_sof(35)
"
     kworker/5:1-2349    [005] ....  1717.370374: rtcpu_vinotify_event: tstamp:54388621072 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740435678336 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.370374: rtcpu_vinotify_event: tstamp:54388621206 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740435679168 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.370375: rtcpu_vinotify_event: tstamp:54388621360 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:1740435679168 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.370375: rtcpu_vinotify_event: tstamp:54388621494 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:1740435803648 data:0x0000000000000001
     kworker/5:1-2349    [005] ....  1717.426333: rtcpu_vinotify_event: tstamp:54388953226 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740435806080 data:0x0000000008020006
     kworker/5:1-2349    [005] ....  1717.426334: rtcpu_string: tstamp:54389138164 id:0x04010000 str:"VI5: vi5_irq_process_channel_event invoked: CH35"
     kworker/5:1-2349    [005] ....  1717.426335: rtcpu_string: tstamp:54389138268 id:0x04010000 str:"_INT_STATUS = 0x2
"
     kworker/5:1-2349    [005] ....  1717.426340: rtcpu_string: tstamp:54389138543 id:0x04010000 str:"VI5: vi5_handle_eof(35)
"
     kworker/5:1-2349    [005] ....  1717.426342: rtcpu_vinotify_error: tstamp:54389141758 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740452513216 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.426342: rtcpu_string: tstamp:54389142269 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.426342: rtcpu_string: tstamp:54389142372 id:0x04010000 str:"=0 ts=1740452513216 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.426343: rtcpu_string: tstamp:54389142479 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.426345: rtcpu_string: tstamp:54389142819 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.426345: rtcpu_string: tstamp:54389142920 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.426346: rtcpu_string: tstamp:54389143252 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.426347: rtcpu_string: tstamp:54389143353 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.426348: rtcpu_vinotify_event: tstamp:54389291272 cch:0 vi:0 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:1740452318944 data:0x0000000002cf0002
     kworker/5:1-2349    [005] ....  1717.426348: rtcpu_vinotify_event: tstamp:54389291432 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:1740452319552 data:0x0000000000000000
     kworker/5:1-2349    [005] ....  1717.426348: rtcpu_vinotify_event: tstamp:54389291570 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740452321344 data:0x0000000002020006
     kworker/5:1-2349    [005] ....  1717.426348: rtcpu_vinotify_event: tstamp:54389291725 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740452387872 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.426348: rtcpu_vinotify_event: tstamp:54389291861 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:1740452387904 data:0x0000000800000000
     kworker/5:1-2349    [005] ....  1717.426349: rtcpu_vinotify_event: tstamp:54389292016 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:1740452392352 data:0x0000000000020006
     kworker/5:1-2349    [005] ....  1717.426349: rtcpu_vinotify_event: tstamp:54389292151 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740452388736 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.426349: rtcpu_vinotify_event: tstamp:54389292303 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740452513216 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.426349: rtcpu_vinotify_error: tstamp:54389663935 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740469222848 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.426349: rtcpu_string: tstamp:54389664451 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x0b ch=1 frame"
     kworker/5:1-2349    [005] ....  1717.426349: rtcpu_string: tstamp:54389664559 id:0x04010000 str:"=0 ts=1740469222848 data=0x000003c9 ext_data=0x0"
     kworker/5:1-2349    [005] ....  1717.426350: rtcpu_string: tstamp:54389664667 id:0x04010000 str:"0000000
"
     kworker/5:1-2349    [005] ....  1717.426351: rtcpu_string: tstamp:54389665005 id:0x04010000 str:"VI5: error_to_notify_bits CHANSEL_NOMATCH identi"
     kworker/5:1-2349    [005] ....  1717.426351: rtcpu_string: tstamp:54389665106 id:0x04010000 str:"fied
"
     kworker/5:1-2349    [005] ....  1717.426352: rtcpu_string: tstamp:54389665442 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0xf tag 0xb, da"
     kworker/5:1-2349    [005] ....  1717.426353: rtcpu_string: tstamp:54389665547 id:0x04010000 str:"ta 0x3c9
"
     kworker/5:1-2349    [005] ....  1717.426354: rtcpu_vinotify_event: tstamp:54389967387 cch:0 vi:0 tag:FE channel:0x00 frame:0 vi_tstamp:1740469097536 data:0x0000000000000020
     kworker/5:1-2349    [005] ....  1717.426354: rtcpu_vinotify_event: tstamp:54389967540 cch:0 vi:0 tag:FS channel:0x00 frame:0 vi_tstamp:1740469098368 data:0x0000000000000010
     kworker/5:1-2349    [005] ....  1717.426354: rtcpu_vinotify_event: tstamp:54389967672 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:0 vi_tstamp:1740469222848 data:0x00000000000003c9
     kworker/5:1-2349    [005] ....  1717.426354: rtcpu_string: tstamp:54390109747 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun"
     kworker/5:1-2349    [005] ....  1717.426354: rtcpu_string: tstamp:54390109852 id:0x04010000 str:"t. 0
"
     kworker/5:1-2349    [005] ....  1717.426355: rtcpu_string: tstamp:54390110089 id:0x04010000 str:"VI5: channel_submit(35, bfebf000)
"
     kworker/5:1-2349    [005] ....  1717.426357: rtcpu_string: tstamp:54390110490 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.426357: rtcpu_string: tstamp:54390110594 id:0x04010000 str:"t = 314
"
     kworker/5:1-2349    [005] ....  1717.426358: rtcpu_string: tstamp:54390110902 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse"
     kworker/5:1-2349    [005] ....  1717.426358: rtcpu_string: tstamp:54390111006 id:0x04010000 str:"t = 345
"
     kworker/5:1-2349    [005] ....  1717.426359: rtcpu_string: tstamp:54390111307 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.426359: rtcpu_string: tstamp:54390111411 id:0x04010000 str:"t = 359
"
     kworker/5:1-2349    [005] ....  1717.426360: rtcpu_string: tstamp:54390111709 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse"
     kworker/5:1-2349    [005] ....  1717.426361: rtcpu_string: tstamp:54390111810 id:0x04010000 str:"t = 389
"
     kworker/5:1-2349    [005] ....  1717.426362: rtcpu_string: tstamp:54390112108 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.426362: rtcpu_string: tstamp:54390112210 id:0x04010000 str:"t = 403
"
     kworker/5:1-2349    [005] ....  1717.426363: rtcpu_string: tstamp:54390112508 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse"
     kworker/5:1-2349    [005] ....  1717.426363: rtcpu_string: tstamp:54390112609 id:0x04010000 str:"t = 417
"
     kworker/5:1-2349    [005] ....  1717.426364: rtcpu_string: tstamp:54390112903 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset"
     kworker/5:1-2349    [005] ....  1717.426365: rtcpu_string: tstamp:54390113004 id:0x04010000 str:" = 419
"
     kworker/5:1-2349    [005] ....  1717.426366: rtcpu_string: tstamp:54390113374 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01
"

When I did the same work on Jetson TX2, 1924x720 works well .
What should I do to solve this?

Thank you.

hello ghdlghdl0558,

it looks like stride alignment issue. we should have follow VI’s 64 byte aligned to set the correct stride, set the width alignment to 64.
please try v4l2-ctl command to adjust the stride by --preferred_stride=<>.

Hi, Thank you for answer.
i tried v4l2-ctl -d /dev/video0 --set-ctrl preferred_stride=4,
but it still not working.

i also tried stride value 1, 1924, 3848, etc. for the stride, but none of them work.

below is kernel log when i tried preferred_stride.

Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801423] video0: VIDIOC_QUERYCAP: driver=tegra-video, card=vi-output, ds90ub940n 8-002c, bus=platform:tegra-capture-vi:0, version=0x00050a41, capabilities=0x84200001, device_caps=0x04200001
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801437] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a0001, type=6, name=Camera Controls, min/max=0/0, step=0, default=0, flags=0x00000044, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801447] video0: VIDIOC_TRY_EXT_CTRLS: which=0x0, count=0, error_idx=0, request_fd=0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801456] video0: VIDIOC_QUERYCTRL: id=0x9a0001, type=6, name=Camera Controls, min/max=0/0, step=0, default=0, flags=0x00000044
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801462] video0: VIDIOC_G_SELECTION: error -25: type=vid-cap, target=0, flags=0x0, wxh=0x0, x,y=0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801471] video0: VIDIOC_QUERYCAP: driver=tegra-video, card=vi-output, ds90ub940n 8-002c, bus=platform:tegra-capture-vi:0, version=0x00050a41, capabilities=0x84200001, device_caps=0x04200001
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801519] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a0001, type=6, name=Camera Controls, min/max=0/0, step=0, default=0, flags=0x00000044, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801525] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a0001, type=6, name=Camera Controls, min/max=0/0, step=0, default=0, flags=0x00000044, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801529] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2003, type=2, name=Group Hold, min/max=0/1, step=1, default=0, flags=0x00000200, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801536] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2009, type=5, name=Gain, min/max=0/48, step=1, default=10, flags=0x00000020, elem_size=8, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801541] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a200a, type=5, name=Exposure, min/max=30/660000, step=1, default=16667, flags=0x00000020, elem_size=8, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801546] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2032, type=258, name=Sensor configuration, min/max=0/4294967295, step=1, default=0, flags=0x00000184, elem_size=4, elems=22, nr_of_dims=1, dims=22,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801552] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2033, type=258, name=Sensor mode I2C packet, min/max=0/4294967295, step=1, default=0, flags=0x00000184, elem_size=4, elems=1026, nr_of_dims=1, dims=1026,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801557] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2034, type=258, name=Sensor control I2C packet, min/max=0/4294967295, step=1, default=0, flags=0x00000184, elem_size=4, elems=1026, nr_of_dims=1, dims=1026,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801562] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2064, type=9, name=Bypass Mode, min/max=0/1, step=1, default=0, flags=0x00000000, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801567] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2065, type=9, name=Override Enable, min/max=0/1, step=1, default=0, flags=0x00000000, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801571] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2066, type=1, name=Height Align, min/max=1/16, step=1, default=1, flags=0x00000000, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801576] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2067, type=9, name=Size Align, min/max=0/2, step=1, default=0, flags=0x00000000, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801582] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2068, type=1, name=Write ISP format, min/max=1/1, step=1, default=1, flags=0x00000000, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801586] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2069, type=258, name=Sensor Signal Properties, min/max=0/4294967295, step=1, default=0, flags=0x00000104, elem_size=4, elems=18, nr_of_dims=2, dims=30,18,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801591] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a206a, type=258, name=Sensor Image Properties, min/max=0/4294967295, step=1, default=0, flags=0x00000104, elem_size=4, elems=16, nr_of_dims=2, dims=30,16,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801596] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a206b, type=258, name=Sensor Control Properties, min/max=0/4294967295, step=1, default=0, flags=0x00000104, elem_size=4, elems=36, nr_of_dims=2, dims=30,36,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801601] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a206c, type=258, name=Sensor DV Timings, min/max=0/4294967295, step=1, default=0, flags=0x00000104, elem_size=4, elems=16, nr_of_dims=2, dims=30,16,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801605] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a206d, type=2, name=Low Latency Mode, min/max=0/1, step=1, default=0, flags=0x00000000, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801610] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a206e, type=1, name=Preferred Stride, min/max=0/65535, step=1, default=0, flags=0x00000000, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801614] video0: VIDIOC_QUERY_EXT_CTRL: id=0x9a2082, type=1, name=Sensor Modes, min/max=0/30, step=1, default=30, flags=0x00000004, elem_size=4, elems=1, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801618] video0: VIDIOC_QUERY_EXT_CTRL: error -22: id=0xc09a2082, type=0, name=, min/max=0/0, step=0, default=0, flags=0x00000000, elem_size=0, elems=0, nr_of_dims=0, dims=0,0,0,0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801637] video0: VIDIOC_S_EXT_CTRLS: which=0x9a0000, count=1, error_idx=0, request_fd=0, id/val=0x9a206e/0x4
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801665] video0: VIDIOC_CROPCAP: error -25: type=vid-cap, bounds wxh=0x0, x,y=0,0, defrect wxh=0x0, x,y=0,0, pixelaspect 0/0
Jan 4 10:55:17 nvidia-desktop kernel: [ 199.801670] video0: VIDIOC_CROPCAP: error -25: type=vid-out, bounds wxh=0x0, x,y=0,0, defrect wxh=0x0, x,y=0,0, pixelaspect 0/0

When I set 1920x720, 1984x720 in the 1924x720 video input, the video comes out clearly, so it seems to be a stride problem.

However, in 1920x720, 1984x720 settings, only the first frame is captured and the video does not move.

Is there any way I could try something different?

Thanks

hello ghdlghdl0558,

I am not quite understand this part.
are you saying you allocate the buffer as 1984x720 (as rounding up of 64) to receive 1924x720 video stream, however, you only see 1st frame in such approach.

may I know what’s the format dumps,
could you please check and share the results. # v4l2-ctl -d /dev/video0 --list-formats-ext

BTW,
just for curious, is it the scene of go-kart track?

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!!