Hi,
I am debugging my camera drivers, by enabling tracing like so:
echo 1 > /sys/kernel/debug/tracing/tracing_on
echo 30720 > /sys/kernel/debug/tracing/buffer_size_kb
echo 1 > /sys/kernel/debug/tracing/events/tegra_rtcpu/enable
echo 1 > /sys/kernel/debug/tracing/events/freertos/enable
echo 2 > /sys/kernel/debug/camrtc/log-level
echo 1 > /sys/kernel/debug/tracing/events/camera_common/enable
echo > /sys/kernel/debug/tracing/trace
When I have my camera feed running, this outputs the following in dmesg constantly:
May 18 08:23:11 nvidia-desktop kernel: [ 407.032651] rtcpu_trace_isp_event event id 20 cannot be found
May 18 08:23:11 nvidia-desktop kernel: [ 407.038426] rtcpu_trace_isp_event event id 20 cannot be found
May 18 08:23:11 nvidia-desktop kernel: [ 407.044205] rtcpu_trace_isp_event event id 20 cannot be found
May 18 08:23:11 nvidia-desktop kernel: [ 407.049741] rtcpu_trace_isp_event event id 20 cannot be found
May 18 08:23:11 nvidia-desktop kernel: [ 407.055498] rtcpu_trace_isp_event event id 20 cannot be found
May 18 08:23:11 nvidia-desktop kernel: [ 407.061290] rtcpu_trace_isp_event event id 20 cannot be found
...
The actual tracing buffer seems pretty normal
kworker/1:2-1721 [001] .... 280.300565: rtos_queue_send_from_isr_failed: tstamp:8892240445 queue:0x0bcba5e0
kworker/1:2-1721 [001] .... 280.300566: rtos_queue_send_from_isr_failed: tstamp:8892240603 queue:0x0bcbb3a0
kworker/1:2-1721 [001] .... 280.300568: rtos_queue_send_from_isr_failed: tstamp:8892240757 queue:0x0bcbc160
kworker/1:2-1721 [001] .... 280.300569: rtcpu_vinotify_event: tstamp:8892261473 tag:FS channel:0x01 frame:2394 vi_tstamp:8892071068 data:0x00000012
kworker/1:2-1721 [001] .... 280.300570: rtcpu_vinotify_event: tstamp:8892261631 tag:ATOMP_FS channel:0x00 frame:2394 vi_tstamp:8892071068 data:0x20000000
kworker/1:2-1721 [001] .... 280.300572: rtcpu_vinotify_event: tstamp:8892261810 tag:CHANSEL_PXL_SOF channel:0x1d frame:2394 vi_tstamp:8892082980 data:0x00000001
kworker/1:2-1721 [001] .... 280.300573: rtcpu_vinotify_event: tstamp:8892261980 tag:RESERVED_19 channel:0x1d frame:90 vi_tstamp:9668748960 data:0x08020897
kworker/1:2-1721 [001] .... 280.300574: rtcpu_vinotify_event: tstamp:8892262151 tag:RESERVED_18 channel:0x1d frame:0 vi_tstamp:9668807392 data:0x10000000
kworker/1:2-1721 [001] .... 280.300576: rtcpu_vinotify_event: tstamp:8892262297 tag:RESERVED_18 channel:0x1d frame:0 vi_tstamp:9668811744 data:0x31000898
kworker/1:2-1721 [001] .... 280.300577: rtcpu_vinotify_event: tstamp:8892262468 tag:CHANSEL_PXL_EOF channel:0x21 frame:2409 vi_tstamp:8892183038 data:0x031f0002
kworker/1:2-1721 [001] .... 280.300578: rtcpu_vinotify_event: tstamp:8892262613 tag:ATOMP_FRAME_DONE channel:0x21 frame:2409 vi_tstamp:8892183054 data:0x00000000
kworker/1:2-1721 [001] .... 280.300580: rtcpu_vinotify_event: tstamp:8892262782 tag:RESERVED_19 channel:0x21 frame:105 vi_tstamp:9671951232 data:0x0202089a
kworker/1:2-1721 [001] .... 280.300581: rtcpu_vinotify_event: tstamp:8892262929 tag:FE channel:0x03 frame:2409 vi_tstamp:8892183141 data:0x00000020
kworker/1:2-1721 [001] .... 280.300582: rtcpu_vinotify_event: tstamp:8892263103 tag:ATOMP_FE channel:0x00 frame:2409 vi_tstamp:8892183141 data:0x00000000
kworker/1:2-1721 [001] .... 280.300583: rtcpu_vinotify_event: tstamp:8892263247 tag:RESERVED_19 channel:0x21 frame:105 vi_tstamp:9671955296 data:0x0002089a
kworker/1:2-1721 [001] .... 280.300585: rtcpu_vinotify_event: tstamp:8892263416 tag:RESERVED_19 channel:0x21 frame:0 vi_tstamp:9671958080 data:0x0702089b
kworker/1:2-1721 [001] .... 280.300586: rtcpu_vinotify_event: tstamp:8892263562 tag:CHANSEL_PXL_EOF channel:0x20 frame:2346 vi_tstamp:8892260456 data:0x031f0002
kworker/1:2-1721 [001] .... 280.300587: rtcpu_vinotify_event: tstamp:8892263737 tag:ATOMP_FRAME_DONE channel:0x20 frame:2346 vi_tstamp:8892260471 data:0x00000000
kworker/1:2-1721 [001] .... 280.300589: rtcpu_vinotify_event: tstamp:8892263881 tag:RESERVED_19 channel:0x20 frame:42 vi_tstamp:9674428576 data:0x0202089e
kworker/1:2-1721 [001] .... 280.300591: rtos_queue_send_from_isr_failed: tstamp:8892301060 queue:0x0bcb41f8
kworker/1:2-1721 [001] .... 280.300592: rtos_queue_send_from_isr_failed: tstamp:8892301224 queue:0x0bcb8a60
kworker/1:2-1721 [001] .... 280.300593: rtos_queue_send_from_isr_failed: tstamp:8892301386 queue:0x0bcba5e0
kworker/1:2-1721 [001] .... 280.300595: rtos_queue_send_from_isr_failed: tstamp:8892301542 queue:0x0bcbb3a0
If the fps of the video stream is high enough, enabling tracing even halts the videostream, seeming that some resources are drained to to enabling this.
My questions:
- Am I enabling the correct traces?
- Should I be worried about the rtcpu trace event id 20? Or should I just disable rtcpu tracing?
- Is it normal that enabling tracing can kill a video stream?
Thanks for your feedback