Vi corr_err on very first capture

When I capture video frames, the very first capture results in the following error reported to the serial console and the dmesg log:

[12088.136800] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 0, err_data 131072

As far as I can tell, the capture occurs just fine. The captured image data appears as I expect. Note that subsequent captures do not cause this error to be emitted again – it only happens a single time after initialization. If I enable kernel tracing (see link below), I do not see any errors logged.

What causes this error to appear? Is there anything that I can do to address it?

Here’s my setup info:

  • 2x AR0233-based cameras, each equipped with a Maxim 9295A GMSL2 serializer
  • 1x Maxim 9296A GMSL2 Deserializer
  • AR0233 sensors output RGB888 @ 1920x1080
  • vi/isp/nvcsi rates are set to their max_rate. (See when get raw16 data through vc-id, corr_err: discarding frame 0, flags: 32, err_data 160 - #2 by ShaneCCC)
  • Tracing is enabled. (Reference same link as above)
  • I’m using the following command to capture data frames: v4l2-ctl -d /dev/video2 --set-fmt-video=width=1920,height=1080,pixelformat=RGB3 --stream-mmap --stream-count=1 --stream-to=ar0233-0.raw

Thanks!
–tim

Follow this topic after below two topics.

Note that removing embedded_metadata_height from my device tree did not eliminate this error. Just wanted to mention that for completeness.

Please check the trace log for more information.

ShaneCCC,

Thanks for the reply! I’ve got two traces included below: a snippet of the dmesg output and kernel debug.

Reiterating the symptoms:

  • This only happens on the first capture. Subsequent captures without a camera power cycle do not emit the error.
  • I get data on the first capture so it seems to be working in spite of the error
  • Happens regardless of whether I have two cameras connected to my deserializer or just one. So, I do not think it’s related to my virtual channel configuration.

Here’s the snippet of dmesg:

[  509.120889] brt9296 2-0048: brt9296_reg_write, slave = 0x48, 0x04d3 0x24 
[  509.121085] brt9296 2-0048: brt9296_reg_write, slave = 0x48, 0x04d4 0x64 
[  509.121540] brt9296 2-0048: brt9296_reg_write, slave = 0x48, 0x0313 0x02 
[  509.121556] jbar0233 2-0010: ar0233_event_callback:1694: Camera will be re-initialized on next capture!
[  509.121562] jbar0233 2-0020: ar0233_event_callback:1694: Camera will be re-initialized on next capture!
[  510.656417] jbar0233 2-0010: ar0233_power_on++ 
[  510.656438] jbar0233 2-0010: ar0233_power_on-- 
[  510.658106] jbar0233 2-0010: BRT: camera_common_try_fmt: size 1920 x 1080
[  510.658137] jbar0233 2-0010: BRT: camera_common_s_fmt(4106) size 1920 x 1080
[  510.658146] jbar0233 2-0010: BRT: camera_common_try_fmt: size 1920 x 1080
[  510.670496] misc tegra_camera_ctrl: tegra_camera_update_isobw: Warning, Requested ISO BW 5859375 has been capped to VI's max BW 4687500
[  510.671669] jbar0233 2-0010: ar0233_set_mode++ 
[  510.671676] jbar0233 2-0010: ar0233_set_mode: mode(0) mode_prop_idx(0) 
[  510.671680] jbar0233 2-0010: ar0233_set_mode-- 
[  510.671690] jbar0233 2-0010: ar0233_start_streaming++
[  510.671716] static_is_hw_trigger_timer_started = 0
[  510.671720] cam_hw_trigger_timer_init+-  HZ(1000)
[  510.671758] jbar0233 2-0010: ar0233_start_streaming--
[  510.685109] [RCE] Configuring VI GoS.
[  510.685120] [RCE] VM GOS[#0] addr=0xe4900000
[  510.685125] [RCE] VM GOS[#1] addr=0xe4901000
[  510.685129] [RCE] VM GOS[#2] addr=0xe4902000
[  510.685133] [RCE] VM GOS[#3] addr=0xe4903000
[  510.685137] [RCE] VM GOS[#4] addr=0xe4904000
[  510.685141] [RCE] VM GOS[#5] addr=0xe4905000
[  510.777999] cam_hw_trigger_timer_handler+= jiffies(4295177848) 
[  510.824499] tegra194-vi5 15c10000.vi: corr_err: discarding frame 0, flags: 0, err_data 131072

Here’s the kernel debug trace log. I don’t see any error occurrences in it.

# tracer: nop
#
# entries-in-buffer/entries-written: 146/146   #P:4
#
#                              _-----=> irqs-off
#                             / _----=> need-resched
#                            | / _---=> hardirq/softirq
#                            || / _--=> preempt-depth
#                            ||| /     delay
#           TASK-PID   CPU#  ||||    TIMESTAMP  FUNCTION
#              | |       |   ||||       |         |
     kworker/0:2-1619  [000] ....   509.767118: rtos_queue_peek_from_isr_failed: tstamp:16065153663 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   509.920155: rtos_queue_peek_from_isr_failed: tstamp:16070153663 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   510.073082: rtos_queue_peek_from_isr_failed: tstamp:16075153663 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   510.226045: rtos_queue_peek_from_isr_failed: tstamp:16080153659 queue:0x0bcbcf78
     kworker/0:4-2175  [000] ....   510.379044: rtos_queue_peek_from_isr_failed: tstamp:16085153658 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   510.532029: rtos_queue_peek_from_isr_failed: tstamp:16090153700 queue:0x0bcbcf78
        v4l2-ctl-9297  [001] ....   510.654104: tegra_channel_open: vi-output, jbar0233 2-0010
        v4l2-ctl-9297  [001] ....   510.654137: tegra_channel_set_power: jbar0233 2-0010 : 0x1
        v4l2-ctl-9297  [001] ....   510.654167: camera_common_s_power: status : 0x1
        v4l2-ctl-9297  [000] ....   510.656444: tegra_channel_set_power: 15a00000.nvcsi--6 : 0x1
        v4l2-ctl-9297  [000] ....   510.656454: csi_s_power: enable : 0x1
        v4l2-ctl-9297  [000] ....   510.669226: tegra_channel_set_stream: enable : 0x1
        v4l2-ctl-9297  [000] ....   510.671593: tegra_channel_set_stream: 15a00000.nvcsi--6 : 0x1
        v4l2-ctl-9297  [000] ....   510.671598: csi_s_stream: enable : 0x1
        v4l2-ctl-9297  [000] ....   510.671622: tegra_channel_set_stream: jbar0233 2-0010 : 0x1
        v4l2-ctl-9297  [000] ....   510.671923: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt a
     kworker/0:2-1619  [000] ....   510.685048: rtos_queue_send_from_isr_failed: tstamp:16094813641 queue:0x0bcb41f8
     kworker/0:2-1619  [000] ....   510.685054: rtos_queue_send_from_isr_failed: tstamp:16094813803 queue:0x0bcb8a60
     kworker/0:2-1619  [000] ....   510.685056: rtos_queue_send_from_isr_failed: tstamp:16094813965 queue:0x0bcba5e0
     kworker/0:2-1619  [000] ....   510.685057: rtos_queue_send_from_isr_failed: tstamp:16094814123 queue:0x0bcbb3a0
     kworker/0:2-1619  [000] ....   510.685059: rtos_queue_send_from_isr_failed: tstamp:16094814280 queue:0x0bcbc160
     kworker/0:2-1619  [000] ....   510.685060: rtos_queue_send_from_isr_failed: tstamp:16094831383 queue:0x0bcb41f8
     kworker/0:2-1619  [000] ....   510.685092: rtos_queue_send_from_isr_failed: tstamp:16094831541 queue:0x0bcb8a60
     kworker/0:2-1619  [000] ....   510.685093: rtos_queue_send_from_isr_failed: tstamp:16094831702 queue:0x0bcba5e0
     kworker/0:2-1619  [000] ....   510.685095: rtos_queue_send_from_isr_failed: tstamp:16094831859 queue:0x0bcbb3a0
     kworker/0:2-1619  [000] ....   510.685096: rtos_queue_send_from_isr_failed: tstamp:16094832017 queue:0x0bcbc160
     kworker/0:2-1619  [000] ....   510.685099: rtcpu_string: tstamp:16094832471 id:0x04010000 str:"Configuring VI GoS.
"
     kworker/0:2-1619  [000] ....   510.685118: rtcpu_string: tstamp:16094832662 id:0x04010000 str:"VM GOS[#0] addr=0xe4900000
"
     kworker/0:2-1619  [000] ....   510.685123: rtcpu_string: tstamp:16094832929 id:0x04010000 str:"VM GOS[#1] addr=0xe4901000
"
     kworker/0:2-1619  [000] ....   510.685128: rtcpu_string: tstamp:16094833210 id:0x04010000 str:"VM GOS[#2] addr=0xe4902000
"
     kworker/0:2-1619  [000] ....   510.685132: rtcpu_string: tstamp:16094833453 id:0x04010000 str:"VM GOS[#3] addr=0xe4903000
"
     kworker/0:2-1619  [000] ....   510.685136: rtcpu_string: tstamp:16094833694 id:0x04010000 str:"VM GOS[#4] addr=0xe4904000
"
     kworker/0:2-1619  [000] ....   510.685140: rtcpu_string: tstamp:16094833935 id:0x04010000 str:"VM GOS[#5] addr=0xe4905000
"
     kworker/0:2-1619  [000] ....   510.685144: rtos_queue_send_from_isr_failed: tstamp:16094862694 queue:0x0bcb41f8
     kworker/0:2-1619  [000] ....   510.685145: rtos_queue_send_from_isr_failed: tstamp:16094862856 queue:0x0bcb8a60
     kworker/0:2-1619  [000] ....   510.685146: rtos_queue_send_from_isr_failed: tstamp:16094863020 queue:0x0bcba5e0
     kworker/0:2-1619  [000] ....   510.685148: rtos_queue_send_from_isr_failed: tstamp:16094863179 queue:0x0bcbb3a0
     kworker/0:2-1619  [000] ....   510.685149: rtos_queue_send_from_isr_failed: tstamp:16094863336 queue:0x0bcbc160
     kworker/0:2-1619  [000] ....   510.685152: rtos_queue_peek_from_isr_failed: tstamp:16095153614 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   510.685155: rtcpu_vinotify_event: tstamp:16095194538 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:16819469856 data:0x10000000
     kworker/0:2-1619  [000] ....   510.685156: rtcpu_vinotify_event: tstamp:16095194694 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:16819474112 data:0x31000001
     kworker/0:2-1619  [000] ....   510.685158: rtcpu_vinotify_event: tstamp:16095194870 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:16819475168 data:0x07020001
     kworker/0:2-1619  [000] ....   510.685159: rtcpu_vinotify_event: tstamp:16095195015 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:16819512000 data:0x10000000
     kworker/0:2-1619  [000] ....   510.685160: rtcpu_vinotify_event: tstamp:16095195185 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:16819516224 data:0x31000002
     kworker/0:2-1619  [000] ....   510.838009: rtcpu_vinotify_event: tstamp:16098892222 tag:FS channel:0x00 frame:0 vi_tstamp:16098890591 data:0x00000014
     kworker/0:2-1619  [000] ....   510.838015: rtcpu_vinotify_event: tstamp:16098892404 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:16098890592 data:0x00000000
     kworker/0:2-1619  [000] ....   510.838017: rtcpu_vinotify_event: tstamp:16098892554 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:16098891280 data:0x00000001
     kworker/0:2-1619  [000] ....   510.838023: rtcpu_vinotify_event: tstamp:16099217773 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:16948315936 data:0x08020001
     kworker/0:2-1619  [000] ....   510.838027: rtos_queue_send_from_isr_failed: tstamp:16099597874 queue:0x0bcb41f8
     kworker/0:2-1619  [000] ....   510.838029: rtos_queue_send_from_isr_failed: tstamp:16099598039 queue:0x0bcb8a60
     kworker/0:2-1619  [000] ....   510.838030: rtos_queue_send_from_isr_failed: tstamp:16099598205 queue:0x0bcba5e0
     kworker/0:2-1619  [000] ....   510.838066: rtos_queue_send_from_isr_failed: tstamp:16099598362 queue:0x0bcbb3a0
     kworker/0:2-1619  [000] ....   510.838068: rtos_queue_send_from_isr_failed: tstamp:16099598550 queue:0x0bcbc160
     kworker/0:2-1619  [000] ....   510.838069: rtcpu_vinotify_event: tstamp:16099905533 tag:FE channel:0x00 frame:0 vi_tstamp:16099585717 data:0x00000024
     kworker/0:2-1619  [000] ....   510.838070: rtcpu_vinotify_event: tstamp:16099905708 tag:CHANSEL_SHORT_FRAME channel:0x10 frame:0 vi_tstamp:16099585717 data:0x01000000
     kworker/0:2-1619  [000] ....   510.838072: rtcpu_vinotify_event: tstamp:16099905858 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:16970538880 data:0x01020001
     kworker/0:2-1619  [000] ....   510.838073: rtcpu_vinotify_event: tstamp:16099906051 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:16099585717 data:0x00000000
     kworker/0:2-1619  [000] ....   510.838074: rtcpu_vinotify_event: tstamp:16099906200 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:16970543328 data:0x07020002
     kworker/0:2-1619  [000] ....   510.838076: rtcpu_vinotify_event: tstamp:16099906391 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:16970604192 data:0x10000000
     kworker/0:2-1619  [000] ....   510.838077: rtcpu_vinotify_event: tstamp:16099906536 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:16970608448 data:0x31000003
     kworker/0:2-1619  [000] ....   510.888991: rtos_queue_peek_from_isr_failed: tstamp:16100153634 queue:0x0bcbcf78
 vi-output, jbar-9300  [003] ....   510.928479: tegra_channel_capture_frame: sof:515.266960160
 vi-output, jbar-9300  [003] ....   510.928486: tegra_channel_capture_frame: eof:515.290723360
     kworker/0:2-1619  [000] ....   510.940015: rtcpu_vinotify_event: tstamp:16102312485 tag:FS channel:0x00 frame:0 vi_tstamp:16102063031 data:0x00000014
     kworker/0:2-1619  [000] ....   510.940019: rtcpu_vinotify_event: tstamp:16102312642 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:16102063031 data:0x00000000
     kworker/0:2-1619  [000] ....   510.940021: rtcpu_vinotify_event: tstamp:16102312819 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:16102092505 data:0x00000001
     kworker/0:2-1619  [000] ....   510.940022: rtcpu_vinotify_event: tstamp:16102312968 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:17050754400 data:0x08020002
     kworker/0:2-1619  [000] ....   510.940023: rtcpu_vinotify_event: tstamp:16102836169 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:16102835083 data:0x04370002
     kworker/0:2-1619  [000] ....   510.940059: rtcpu_vinotify_event: tstamp:16102836322 tag:FE channel:0x00 frame:0 vi_tstamp:16102835101 data:0x00000024
     kworker/0:2-1619  [000] ....   510.940060: rtcpu_vinotify_event: tstamp:16102836536 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:16102835101 data:0x00000000
     kworker/0:2-1619  [000] ....   510.940061: rtcpu_vinotify_event: tstamp:16102836684 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:16102835105 data:0x00000000
     kworker/0:2-1619  [000] ....   510.940064: rtcpu_vinotify_event: tstamp:16103172208 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:17074519776 data:0x02020002
     kworker/0:2-1619  [000] ....   510.940065: rtcpu_vinotify_event: tstamp:16103172357 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:17074521376 data:0x00020002
     kworker/0:2-1619  [000] ....   510.940066: rtcpu_vinotify_event: tstamp:16103172548 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:17074524480 data:0x07020003
 vi-output, jbar-9300  [003] ....   511.032528: tegra_channel_capture_frame: sof:515.370969696
 vi-output, jbar-9300  [003] ....   511.032533: tegra_channel_capture_frame: eof:515.394732864
        v4l2-ctl-9297  [003] ....   511.032595: tegra_channel_set_stream: enable : 0x0
        v4l2-ctl-9297  [003] ....   511.032597: tegra_channel_set_stream: jbar0233 2-0010 : 0x0
        v4l2-ctl-9297  [003] ....   511.032702: tegra_channel_set_stream: 15a00000.nvcsi--6 : 0x0
        v4l2-ctl-9297  [003] ....   511.032705: csi_s_stream: enable : 0x0
     kworker/0:2-1619  [000] ....   511.042012: rtos_queue_peek_from_isr_failed: tstamp:16105153632 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   511.042031: rtos_queue_send_from_isr_failed: tstamp:16105360004 queue:0x0bcb41f8
     kworker/0:2-1619  [000] ....   511.042033: rtos_queue_send_from_isr_failed: tstamp:16105360171 queue:0x0bcb8a60
     kworker/0:2-1619  [000] ....   511.042034: rtos_queue_send_from_isr_failed: tstamp:16105360334 queue:0x0bcba5e0
     kworker/0:2-1619  [000] ....   511.042036: rtos_queue_send_from_isr_failed: tstamp:16105360491 queue:0x0bcbb3a0
     kworker/0:2-1619  [000] ....   511.042037: rtos_queue_send_from_isr_failed: tstamp:16105360648 queue:0x0bcbc160
     kworker/0:2-1619  [000] ....   511.042045: rtcpu_vinotify_event: tstamp:16105579245 tag:FS channel:0x00 frame:0 vi_tstamp:16105313329 data:0x00000014
     kworker/0:2-1619  [000] ....   511.042080: rtcpu_vinotify_event: tstamp:16105579421 tag:ATOMP_FS channel:0x00 frame:0 vi_tstamp:16105313329 data:0x00000000
     kworker/0:2-1619  [000] ....   511.042081: rtcpu_vinotify_event: tstamp:16105579573 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:16105342803 data:0x00000001
     kworker/0:2-1619  [000] ....   511.042082: rtcpu_vinotify_event: tstamp:16105579784 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:17154763936 data:0x08020003
     kworker/0:2-1619  [000] ....   511.042084: rtcpu_vinotify_event: tstamp:16105579931 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:17154828000 data:0x10000000
     kworker/0:2-1619  [000] ....   511.042085: rtcpu_vinotify_event: tstamp:16105580119 tag:RESERVED_18 channel:0x23 frame:0 vi_tstamp:17154832288 data:0x31000004
     kworker/0:2-1619  [000] ....   511.042087: rtcpu_vinotify_event: tstamp:16106086490 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:16106085381 data:0x04370002
     kworker/0:2-1619  [000] ....   511.042088: rtcpu_vinotify_event: tstamp:16106086666 tag:FE channel:0x00 frame:0 vi_tstamp:16106085399 data:0x00000024
     kworker/0:2-1619  [000] ....   511.042089: rtcpu_vinotify_event: tstamp:16106086819 tag:ATOMP_FE channel:0x00 frame:0 vi_tstamp:16106085400 data:0x00000000
     kworker/0:2-1619  [000] ....   511.042090: rtcpu_vinotify_event: tstamp:16106087010 tag:ATOMP_FRAME_DONE channel:0x23 frame:0 vi_tstamp:16106085402 data:0x00000000
     kworker/0:2-1619  [000] ....   511.042092: rtcpu_vinotify_event: tstamp:16106087156 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:17178528576 data:0x02020003
     kworker/0:2-1619  [000] ....   511.042093: rtcpu_vinotify_event: tstamp:16106087344 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:17178530112 data:0x00020003
     kworker/0:2-1619  [000] ....   511.042094: rtcpu_vinotify_event: tstamp:16106087491 tag:RESERVED_19 channel:0x23 frame:0 vi_tstamp:17178533152 data:0x07020004
     kworker/0:2-1619  [000] ....   511.042097: rtos_queue_send_from_isr_failed: tstamp:16106097903 queue:0x0bcb41f8
     kworker/0:2-1619  [000] ....   511.042098: rtos_queue_send_from_isr_failed: tstamp:16106098069 queue:0x0bcb8a60
     kworker/0:2-1619  [000] ....   511.042099: rtos_queue_send_from_isr_failed: tstamp:16106098235 queue:0x0bcba5e0
     kworker/0:2-1619  [000] ....   511.042101: rtos_queue_send_from_isr_failed: tstamp:16106098392 queue:0x0bcbb3a0
     kworker/0:2-1619  [000] ....   511.042102: rtos_queue_send_from_isr_failed: tstamp:16106098550 queue:0x0bcbc160
        v4l2-ctl-9297  [000] ....   511.051928: tegra_channel_close: vi-output, jbar0233 2-0010
        v4l2-ctl-9297  [000] ....   511.055103: tegra_channel_set_power: jbar0233 2-0010 : 0x0
        v4l2-ctl-9297  [000] ....   511.055170: camera_common_s_power: status : 0x0
        v4l2-ctl-9297  [000] ....   511.055306: tegra_channel_set_power: 15a00000.nvcsi--6 : 0x0
        v4l2-ctl-9297  [000] ....   511.055311: csi_s_power: enable : 0x0
     kworker/0:2-1619  [000] ....   511.093022: rtos_queue_send_from_isr_failed: tstamp:16106408573 queue:0x0bcb41f8
     kworker/0:2-1619  [000] ....   511.093029: rtos_queue_send_from_isr_failed: tstamp:16106408799 queue:0x0bcb8a60
     kworker/0:2-1619  [000] ....   511.093030: rtos_queue_send_from_isr_failed: tstamp:16106409023 queue:0x0bcba5e0
     kworker/0:2-1619  [000] ....   511.093072: rtos_queue_send_from_isr_failed: tstamp:16106409245 queue:0x0bcbb3a0
     kworker/0:2-1619  [000] ....   511.093074: rtos_queue_send_from_isr_failed: tstamp:16106409464 queue:0x0bcbc160
     kworker/0:2-1619  [000] ....   511.194977: rtos_queue_peek_from_isr_failed: tstamp:16110153711 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   511.347961: rtos_queue_peek_from_isr_failed: tstamp:16115153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   511.500955: rtos_queue_peek_from_isr_failed: tstamp:16120153660 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   511.653935: rtos_queue_peek_from_isr_failed: tstamp:16125153702 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   511.806921: rtos_queue_peek_from_isr_failed: tstamp:16130153704 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   512.010973: rtos_queue_peek_from_isr_failed: tstamp:16135153660 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   512.163900: rtos_queue_peek_from_isr_failed: tstamp:16140153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   512.316917: rtos_queue_peek_from_isr_failed: tstamp:16145153662 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   512.469876: rtos_queue_peek_from_isr_failed: tstamp:16150153662 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   512.622899: rtos_queue_peek_from_isr_failed: tstamp:16155153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   512.775876: rtos_queue_peek_from_isr_failed: tstamp:16160153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   512.928828: rtos_queue_peek_from_isr_failed: tstamp:16165153765 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   513.132876: rtos_queue_peek_from_isr_failed: tstamp:16170153629 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   513.285819: rtos_queue_peek_from_isr_failed: tstamp:16175153660 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   513.438790: rtos_queue_peek_from_isr_failed: tstamp:16180153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   513.591797: rtos_queue_peek_from_isr_failed: tstamp:16185153663 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   513.744775: rtos_queue_peek_from_isr_failed: tstamp:16190153662 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   513.897763: rtos_queue_peek_from_isr_failed: tstamp:16195153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   514.050746: rtos_queue_peek_from_isr_failed: tstamp:16200153704 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   514.203776: rtos_queue_peek_from_isr_failed: tstamp:16205153704 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   514.407786: rtos_queue_peek_from_isr_failed: tstamp:16210153660 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   514.560718: rtos_queue_peek_from_isr_failed: tstamp:16215153660 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   514.713746: rtos_queue_peek_from_isr_failed: tstamp:16220153662 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   514.866695: rtos_queue_peek_from_isr_failed: tstamp:16225153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   515.019677: rtos_queue_peek_from_isr_failed: tstamp:16230153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   515.172660: rtos_queue_peek_from_isr_failed: tstamp:16235153662 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   515.325647: rtos_queue_peek_from_isr_failed: tstamp:16240153704 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   515.529667: rtos_queue_peek_from_isr_failed: tstamp:16245153633 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   515.682629: rtos_queue_peek_from_isr_failed: tstamp:16250153663 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   515.835609: rtos_queue_peek_from_isr_failed: tstamp:16255153664 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   515.988600: rtos_queue_peek_from_isr_failed: tstamp:16260153660 queue:0x0bcbcf78
     kworker/0:2-1619  [000] ....   516.090575: rtos_queue_peek_from_isr_failed: tstamp:16262814529 queue:0x0bcbcf78

One more observation: with two cameras connected to the deserializer, if I capture a single frame on one and then follow that with capturing a frame capture on the other camera, I still only see one “corr_err” reported. I wanted to see if I got an initial “err_data 131072” for each camera input, but that does not appear to be the case.

–tim

What generates the CHANSEL_PXL_SOF & CHANSEL_PXL_EOF tags for “channel:0x23” in the trace? I see that there appears to be a missing CHANSEL_PXL_EOF tag when the error is emitted. Here’s a grep on SOF/EOF in a trace after power-cycling where the error appears:

tescott@linux-black-beast:/tmp$ grep -E "SOF|EOF" trace1.txt 
     kworker/0:0-8657  [000] ....  1237.100431: rtcpu_vinotify_event: tstamp:38796384078 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:38796238644 data:0x00000001
     kworker/0:0-8657  [000] ....  1237.202449: rtcpu_vinotify_event: tstamp:38799444575 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:38799441704 data:0x00000001
     kworker/0:0-8657  [000] ....  1237.253460: rtcpu_vinotify_event: tstamp:38800475773 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:38800184282 data:0x04370002
     kworker/0:0-8657  [000] ....  1237.304426: rtcpu_vinotify_event: tstamp:38802695026 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:38802692004 data:0x00000001
     kworker/0:0-8657  [000] ....  1237.355435: rtcpu_vinotify_event: tstamp:38803685564 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:38803434582 data:0x04370002

Here’s a grep on SOF/EOF in a trace from capturing another frame after the trace above (ie no power-cycle and no error emission):

tescott@linux-black-beast:/tmp$ grep -E "SOF|EOF" trace2.txt 
     kworker/0:3-9069  [000] ....  1546.713678: rtcpu_vinotify_event: tstamp:48470774986 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:48470574612 data:0x00000001
     kworker/0:3-9069  [000] ....  1546.713680: rtcpu_vinotify_event: tstamp:48471318307 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:48471317191 data:0x04370002
     kworker/0:3-9069  [000] ....  1546.815767: rtcpu_vinotify_event: tstamp:48474144568 tag:CHANSEL_PXL_SOF channel:0x23 frame:0 vi_tstamp:48473825374 data:0x00000001
     kworker/0:3-9069  [000] ....  1546.815826: rtcpu_vinotify_event: tstamp:48474810493 tag:CHANSEL_PXL_EOF channel:0x23 frame:0 vi_tstamp:48474567952 data:0x04370002

In the second trace, each SOF has a matching EOF. The first trace doesn’t have this.

–tim

From the trace I saw the CHANSEL_SHORT_FRAME at first frame capture but didn’t see it at later captures.
It could be the first frame output didn’t correct. Try if can have a delay after xxx_s_stream of the sensor/serdes driver.