Files
libva-multiplanar/phase0_evidence/2026-05-04-kernel-trace/ftrace.txt
T
marfrit 365764fffb Phase 0 amendment: hantro writes zeros, sentinel test cache-buggy
Re-baselined libva-v4l2-request decode path with kernel-side
observability (ftrace v4l2/vb2/dma_fence + dmesg + dynamic_debug)
and visual disambiguator (mpv --vo=gpu in operator's live Plasma
session).

Findings:

1. Kernel reports successful CAPTURE buffer write every frame:
   ftrace vb2_buf_done shows bytesused=3655712 (full NV12 1920x1088
   + hantro tile padding). dmesg completely silent — no
   hantro/vpu/decode/error/warn messages.

2. Visual disambiguator: mpv --hwdec=vaapi-copy --vo=gpu shows a
   solid GREEN frame; --hwdec=vaapi --vo=gpu shows solid BLUE.
   Neither shows the sentinel mid-beige (NV12 Y=0xab,UV=0xab would
   render cream). Both colors are consistent with the kernel
   writing all-zero NV12 (Y=0,UV=0 → green via BT.709 limited; same
   buffer GL-imported as DMA-BUF with different colorspace → blue).

3. Patch 0011 sentinel test has a cache-coherency bug: writes
   0xab via cached surface_object->destination_map[0] mmap, never
   invalidates cache before readback. So the readback always
   shows the stale sentinel even when kernel DMA-overwrote it
   with zeros. vaapi-copy and Mesa DMA-BUF GL import correctly
   invalidate cache and see the real (zero) contents.

This corrects the previous Phase 0 verdicts twice in one day:
- Original commit f15ba8b ("the 2026-04-26 picture holds") was
  wrong: clean contract trace, never checked pixel content.
- Revised commit e892cea ("kernel produces no decoded pixel
  output, sentinel survives") was half right: kernel does write,
  writes zeros, and the sentinel test was reading stale cache.
- Now: kernel writes ALL ZEROS to the CAPTURE buffer. Hantro is
  silently failing the bitstream parse or some control validation.

This is consistent with patch 0011's own commit message hypothesis:
"All zeros → kernel did write 0x00s (overwriting our sentinel),
and the apparent 'no picture' output is the kernel-side decode
actually producing zeros (e.g. parser rejected the bitstream)."
That hypothesis was right; we just couldn't confirm it via the
sentinel test (cache bug) and went down the wrong rabbit hole.

Phase 6 direction sharpens substantially. Bug isn't "we can't
engage hantro" — it's "hantro engages but its parser produces
zeros." Bisect the control submission: VIDIOC_G_EXT_CTRLS
readback to verify writes stick, diff against FFmpeg's
v4l2_request_h264.c (proven working on hantro), verify SPS
completeness, resolve patch 0008's slice_header bit_size open
question, dyndbg the hantro module, etc. Phase 1 boolean-
correctness criterion needs a working pixel-content check before
lock; fix patch 0011's cache sync first.

Co-Authored-By: Claude Opus 4.7 (1M context) <noreply@anthropic.com>
2026-05-04 11:39:42 +00:00

133 lines
29 KiB
Plaintext

# tracer: nop
#
# entries-in-buffer/entries-written: 120/120 #P:4
#
# _-----=> irqs-off/BH-disabled
# / _----=> need-resched
# | / _---=> hardirq/softirq
# || / _--=> preempt-depth
# ||| / _-=> migrate-disable
# |||| / delay
# TASK-PID CPU# ||||| TIMESTAMP FUNCTION
# | | | ||||| | |
av:h264:df0-148779 [001] ..... 164409.395851: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df0-148779 [001] ..... 164409.395858: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 0, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df0-148779 [001] ..... 164409.395869: v4l2_qbuf: minor = 1, index = 0, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = ANY, timestamp = 0, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df0-148779 [001] ..... 164409.395920: v4l2_qbuf: minor = 1, index = 0, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893356955599000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df0-148779 [001] ..... 164409.395934: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 10, bytesused = 6272, timestamp = 1777893356955599000
av:h264:df0-148779 [001] ..... 164409.395935: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 0, type = 10, bytesused = 6272, timestamp = 1777893356955599000
systemd-journal-2348 [000] d.h1. 164409.396156: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 9, bytesused = 3655712, timestamp = 1777893356955599000
systemd-journal-2348 [000] d.h1. 164409.396200: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 10, bytesused = 6272, timestamp = 1777893356955599000
av:h264:df0-148779 [001] ..... 164409.396268: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 0, type = 10, bytesused = 6272, timestamp = 1777893356955599000
av:h264:df0-148779 [001] ..... 164409.396273: v4l2_dqbuf: minor = 1, index = 0, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893356955599000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df0-148779 [001] ..... 164409.396279: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 0, type = 9, bytesused = 3655712, timestamp = 1777893356955599000
av:h264:df0-148779 [001] ..... 164409.396281: v4l2_dqbuf: minor = 1, index = 0, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356955599000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df1-148780 [003] ..... 164409.402228: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df1-148780 [003] ..... 164409.402236: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 1, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df1-148780 [003] ..... 164409.402246: v4l2_qbuf: minor = 1, index = 1, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = ANY, timestamp = 0, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df1-148780 [003] ..... 164409.402285: v4l2_qbuf: minor = 1, index = 1, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893356962057000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df1-148780 [003] ..... 164409.402297: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 10, bytesused = 108, timestamp = 1777893356962057000
av:h264:df1-148780 [003] ..... 164409.402298: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 1, type = 10, bytesused = 108, timestamp = 1777893356962057000
<idle>-0 [000] d.h2. 164409.402441: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 9, bytesused = 3655712, timestamp = 1777893356962057000
<idle>-0 [000] d.h2. 164409.402495: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 10, bytesused = 108, timestamp = 1777893356962057000
av:h264:df1-148780 [003] ..... 164409.402552: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 1, type = 10, bytesused = 108, timestamp = 1777893356962057000
av:h264:df1-148780 [003] ..... 164409.402556: v4l2_dqbuf: minor = 1, index = 1, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893356962057000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 1
av:h264:df1-148780 [003] ..... 164409.402561: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 1, type = 9, bytesused = 3655712, timestamp = 1777893356962057000
av:h264:df1-148780 [003] ..... 164409.402562: v4l2_dqbuf: minor = 1, index = 1, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356962057000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 1
av:h264:df2-148781 [001] ..... 164409.408246: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 2, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df2-148781 [001] ..... 164409.408254: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 2, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df2-148781 [001] ..... 164409.408266: v4l2_qbuf: minor = 1, index = 2, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = ANY, timestamp = 0, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df2-148781 [001] ..... 164409.408312: v4l2_qbuf: minor = 1, index = 2, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893356968082000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df2-148781 [001] ..... 164409.408324: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 2, type = 10, bytesused = 109, timestamp = 1777893356968082000
av:h264:df2-148781 [001] ..... 164409.408324: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 2, type = 10, bytesused = 109, timestamp = 1777893356968082000
<idle>-0 [000] d.h2. 164409.408476: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 2, type = 9, bytesused = 3655712, timestamp = 1777893356968082000
<idle>-0 [000] d.h2. 164409.408527: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 2, type = 10, bytesused = 109, timestamp = 1777893356968082000
av:h264:df2-148781 [001] ..... 164409.408585: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 2, type = 10, bytesused = 109, timestamp = 1777893356968082000
av:h264:df2-148781 [001] ..... 164409.408588: v4l2_dqbuf: minor = 1, index = 2, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893356968082000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 2
av:h264:df2-148781 [001] ..... 164409.408595: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 2, type = 9, bytesused = 3655712, timestamp = 1777893356968082000
av:h264:df2-148781 [001] ..... 164409.408596: v4l2_dqbuf: minor = 1, index = 2, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356968082000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 2
av:h264:df3-148782 [003] ..... 164409.415179: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 3, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df3-148782 [003] ..... 164409.415184: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 3, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df3-148782 [003] ..... 164409.415193: v4l2_qbuf: minor = 1, index = 3, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = ANY, timestamp = 0, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df3-148782 [003] ..... 164409.415233: v4l2_qbuf: minor = 1, index = 3, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893356975051000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df3-148782 [003] ..... 164409.415242: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 3, type = 10, bytesused = 144299, timestamp = 1777893356975051000
av:h264:df3-148782 [003] ..... 164409.415243: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 3, type = 10, bytesused = 144299, timestamp = 1777893356975051000
<idle>-0 [000] d.h2. 164409.415359: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 3, type = 9, bytesused = 3655712, timestamp = 1777893356975051000
<idle>-0 [000] d.h2. 164409.415385: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 3, type = 10, bytesused = 144299, timestamp = 1777893356975051000
av:h264:df3-148782 [003] ..... 164409.415437: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 3, type = 10, bytesused = 144299, timestamp = 1777893356975051000
av:h264:df3-148782 [003] ..... 164409.415440: v4l2_dqbuf: minor = 1, index = 3, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893356975051000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 3
av:h264:df3-148782 [003] ..... 164409.415446: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 3, type = 9, bytesused = 3655712, timestamp = 1777893356975051000
av:h264:df3-148782 [003] ..... 164409.415447: v4l2_dqbuf: minor = 1, index = 3, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356975051000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 3
av:h264:df0-148779 [001] ..... 164409.420516: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 4, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df0-148779 [001] ..... 164409.420521: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 4, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df0-148779 [001] ..... 164409.420530: v4l2_qbuf: minor = 1, index = 4, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = ANY, timestamp = 0, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df0-148779 [001] ..... 164409.420565: v4l2_qbuf: minor = 1, index = 0, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893356980390000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df0-148779 [001] ..... 164409.420574: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 10, bytesused = 248054, timestamp = 1777893356980390000
av:h264:df0-148779 [001] ..... 164409.420575: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 0, type = 10, bytesused = 248054, timestamp = 1777893356980390000
<idle>-0 [000] d.h2. 164409.420687: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 4, type = 9, bytesused = 3655712, timestamp = 1777893356980390000
<idle>-0 [000] d.h2. 164409.420713: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 10, bytesused = 248054, timestamp = 1777893356980390000
av:h264:df0-148779 [001] ..... 164409.420806: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 0, type = 10, bytesused = 248054, timestamp = 1777893356980390000
av:h264:df0-148779 [001] ..... 164409.420810: v4l2_dqbuf: minor = 1, index = 0, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893356980390000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 4
av:h264:df0-148779 [001] ..... 164409.420815: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 4, type = 9, bytesused = 3655712, timestamp = 1777893356980390000
av:h264:df0-148779 [001] ..... 164409.420816: v4l2_dqbuf: minor = 1, index = 4, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356980390000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 4
av:h264:df1-148780 [002] ..... 164409.426981: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 5, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df1-148780 [002] ..... 164409.426987: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 5, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df1-148780 [002] ..... 164409.426995: v4l2_qbuf: minor = 1, index = 5, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = ANY, timestamp = 0, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df1-148780 [002] ..... 164409.427030: v4l2_qbuf: minor = 1, index = 1, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893356986851000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df1-148780 [002] ..... 164409.427039: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 10, bytesused = 95876, timestamp = 1777893356986851000
av:h264:df1-148780 [002] ..... 164409.427040: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 1, type = 10, bytesused = 95876, timestamp = 1777893356986851000
mpv-148764 [000] d.h1. 164409.427153: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 5, type = 9, bytesused = 3655712, timestamp = 1777893356986851000
mpv-148764 [000] d.h1. 164409.427184: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 10, bytesused = 95876, timestamp = 1777893356986851000
av:h264:df1-148780 [002] ..... 164409.427230: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 1, type = 10, bytesused = 95876, timestamp = 1777893356986851000
av:h264:df1-148780 [002] ..... 164409.427233: v4l2_dqbuf: minor = 1, index = 1, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893356986851000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 5
av:h264:df1-148780 [002] ..... 164409.427239: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 5, type = 9, bytesused = 3655712, timestamp = 1777893356986851000
av:h264:df1-148780 [002] ..... 164409.427240: v4l2_dqbuf: minor = 1, index = 5, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356986851000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 5
av:h264:df2-148781 [001] ..... 164409.432195: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 6, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df2-148781 [001] ..... 164409.432201: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 6, type = 9, bytesused = 3655712, timestamp = 0
av:h264:df2-148781 [001] ..... 164409.432210: v4l2_qbuf: minor = 1, index = 6, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = ANY, timestamp = 0, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df2-148781 [001] ..... 164409.432245: v4l2_qbuf: minor = 1, index = 2, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893356992059000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df2-148781 [001] ..... 164409.432255: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 2, type = 10, bytesused = 49777, timestamp = 1777893356992059000
av:h264:df2-148781 [001] ..... 164409.432256: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 2, type = 10, bytesused = 49777, timestamp = 1777893356992059000
mpv-148764 [000] d.h1. 164409.432371: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 6, type = 9, bytesused = 3655712, timestamp = 1777893356992059000
mpv-148764 [000] d.h1. 164409.432423: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 2, type = 10, bytesused = 49777, timestamp = 1777893356992059000
av:h264:df2-148781 [001] ..... 164409.432483: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 2, type = 10, bytesused = 49777, timestamp = 1777893356992059000
av:h264:df2-148781 [001] ..... 164409.432486: v4l2_dqbuf: minor = 1, index = 2, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893356992059000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 6
av:h264:df2-148781 [001] ..... 164409.432492: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 6, type = 9, bytesused = 3655712, timestamp = 1777893356992059000
av:h264:df2-148781 [001] ..... 164409.432493: v4l2_dqbuf: minor = 1, index = 6, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356992059000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 6
av:h264:df3-148782 [001] ..... 164409.458237: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 9, bytesused = 3655712, timestamp = 1777893356955599000
av:h264:df3-148782 [001] ..... 164409.458243: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 0, type = 9, bytesused = 3655712, timestamp = 1777893356955599000
av:h264:df3-148782 [001] ..... 164409.458253: v4l2_qbuf: minor = 1, index = 0, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356955599000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df3-148782 [001] ..... 164409.458289: v4l2_qbuf: minor = 1, index = 3, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893357018098000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df3-148782 [001] ..... 164409.458300: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 3, type = 10, bytesused = 60348, timestamp = 1777893357018098000
av:h264:df3-148782 [001] ..... 164409.458301: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 3, type = 10, bytesused = 60348, timestamp = 1777893357018098000
mpv-148764 [000] d.h1. 164409.458420: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 9, bytesused = 3655712, timestamp = 1777893357018098000
mpv-148764 [000] d.h1. 164409.458446: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 3, type = 10, bytesused = 60348, timestamp = 1777893357018098000
av:h264:df3-148782 [001] ..... 164409.458517: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 3, type = 10, bytesused = 60348, timestamp = 1777893357018098000
av:h264:df3-148782 [001] ..... 164409.458521: v4l2_dqbuf: minor = 1, index = 3, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893357018098000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 7
av:h264:df3-148782 [001] ..... 164409.458526: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 0, type = 9, bytesused = 3655712, timestamp = 1777893357018098000
av:h264:df3-148782 [001] ..... 164409.458528: v4l2_dqbuf: minor = 1, index = 0, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893357018098000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 7
av:h264:df0-148779 [003] ..... 164409.483579: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 2, type = 9, bytesused = 3655712, timestamp = 1777893356968082000
av:h264:df0-148779 [003] ..... 164409.483584: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 2, type = 9, bytesused = 3655712, timestamp = 1777893356968082000
av:h264:df0-148779 [003] ..... 164409.483593: v4l2_qbuf: minor = 1, index = 2, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356968082000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df0-148779 [003] ..... 164409.483630: v4l2_qbuf: minor = 1, index = 0, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893357043499000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df0-148779 [003] ..... 164409.483639: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 10, bytesused = 9557, timestamp = 1777893357043499000
av:h264:df0-148779 [003] ..... 164409.483641: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 0, type = 10, bytesused = 9557, timestamp = 1777893357043499000
systemd-journal-2348 [000] d.h1. 164409.483817: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 2, type = 9, bytesused = 3655712, timestamp = 1777893357043499000
systemd-journal-2348 [000] d.h1. 164409.483824: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 0, type = 10, bytesused = 9557, timestamp = 1777893357043499000
av:h264:df0-148779 [003] ..... 164409.483877: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 0, type = 10, bytesused = 9557, timestamp = 1777893357043499000
av:h264:df0-148779 [003] ..... 164409.483881: v4l2_dqbuf: minor = 1, index = 0, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893357043499000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 8
av:h264:df0-148779 [003] ..... 164409.483887: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 2, type = 9, bytesused = 3655712, timestamp = 1777893357043499000
av:h264:df0-148779 [003] ..... 164409.483888: v4l2_dqbuf: minor = 1, index = 2, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893357043499000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 8
av:h264:df1-148780 [003] ..... 164409.508537: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 9, bytesused = 3655712, timestamp = 1777893356962057000
av:h264:df1-148780 [003] ..... 164409.508543: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 1, type = 9, bytesused = 3655712, timestamp = 1777893356962057000
av:h264:df1-148780 [003] ..... 164409.508552: v4l2_qbuf: minor = 1, index = 1, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|QUEUED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893356962057000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df1-148780 [003] ..... 164409.508602: v4l2_qbuf: minor = 1, index = 1, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800080, field = NONE, timestamp = 1777893357067976000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 0
av:h264:df1-148780 [003] ..... 164409.508614: vb2_qbuf: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 10, bytesused = 59825, timestamp = 1777893357067976000
av:h264:df1-148780 [003] ..... 164409.508615: vb2_buf_queue: owner = 0000000000000000, queued = 1, owned_by_drv = 1, index = 1, type = 10, bytesused = 59825, timestamp = 1777893357067976000
<idle>-0 [000] d.h2. 164409.508726: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 9, bytesused = 3655712, timestamp = 1777893357067976000
<idle>-0 [000] d.h2. 164409.508730: vb2_buf_done: owner = 0000000000000000, queued = 1, owned_by_drv = 0, index = 1, type = 10, bytesused = 59825, timestamp = 1777893357067976000
av:h264:df1-148780 [003] ..... 164409.508783: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 1, type = 10, bytesused = 59825, timestamp = 1777893357067976000
av:h264:df1-148780 [003] ..... 164409.508961: v4l2_dqbuf: minor = 1, index = 1, type = VIDEO_OUTPUT_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY|0x800000, field = NONE, timestamp = 1777893357067976000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 9
av:h264:df1-148780 [003] ..... 164409.508973: vb2_dqbuf: owner = 0000000000000000, queued = 0, owned_by_drv = 0, index = 1, type = 9, bytesused = 3655712, timestamp = 1777893357067976000
av:h264:df1-148780 [003] ..... 164409.508974: v4l2_dqbuf: minor = 1, index = 1, type = VIDEO_CAPTURE_MPLANE, bytesused = 0, flags = MAPPED|TIMESTAMP_UNKNOWN|TIMESTAMP_COPY, field = NONE, timestamp = 1777893357067976000, timecode = { type = 0x0, flags = , frames = 0, seconds = 0, minutes = 0, hours = 0, userbits = { 0 0 0 0 } }, sequence = 9