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.
My configuration utilizes virtual channels. I’ve noticed errors in the kernel debug log when I capture on only one of the two configured virtual channels available, relating to CHANSEL_NOMATCH. If I launch two captures at the same time for both of the channels, this error does NOT seem to appear. Is this something that can be safely ignored?
Capturing on /dev/video2 (virtual channel 0):
nvidia@tegra-ubuntu:/tmp/video$ sudo cat /sys/kernel/debug/tracing/trace | grep -i error
nvidia@tegra-ubu…
I’m noticing an intermittent error with video capture that only seems to go away when I reinitialize my camera devices. About 80% of the time when I capture, it is successful. When it is successful, subsequent captures do not have any issues and I’m able to confirm my video input is what I expect it to be. However, when it fails, it’s a hard fail and I need to reinitialize my device in order to make another capture attempt. In the times that it fails, the errors in the kernel debug log inclu…
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.