GStreamer-devel

v4l2src gstv4l2bufferpool error

classic Classic list List threaded Threaded
♦
♦
 4 messages Options
Alfredo Salvarani
Reply | Threaded
Open this post in threaded view
♦
♦
|

v4l2src gstv4l2bufferpool error

Alfredo Salvarani
Hi,

I have a board with a adv7482 and I get video from it with v4l2src.
When something happen on adv7482 HDMI input (a disconnection for instance),
the adv7482 goes to free run mode with a blue screen with the same video
configuration that was before.
But the gstreamer pipeline crash's with v4l2src error "Could not read from
resource."
I think in this kind of transition there some frames lost or invalid.
Is there any way to avoid it? Maybe a buffer on v4l2src?

Thanks



--
Sent from: http://gstreamer-devel.966125.n4.nabble.com/
_______________________________________________
gstreamer-devel mailing list
[hidden email]
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
Nicolas Dufresne-5
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: v4l2src gstv4l2bufferpool error

Nicolas Dufresne-5


Le ven. 13 nov. 2020 20 h 30, Alfredo Salvarani <[hidden email]> a écrit :
Hi,

I have a board with a adv7482 and I get video from it with v4l2src.
When something happen on adv7482 HDMI input (a disconnection for instance),
the adv7482 goes to free run mode with a blue screen with the same video
configuration that was before.
But the gstreamer pipeline crash's with v4l2src error "Could not read from
resource."
I think in this kind of transition there some frames lost or invalid.
Is there any way to avoid it? Maybe a buffer on v4l2src?

We already handle frame lost or corrupted buffer reported by drivers. Perhaps your driver is reporting then the wrong way?

You may want to share somr more logs, as it's not clear what error your driver reported. Consider looking at kernel trace first (out of scope for GST ML) and the GST_DEBUG="v4l*:7,2"



Thanks



--
Sent from: http://gstreamer-devel.966125.n4.nabble.com/
_______________________________________________
gstreamer-devel mailing list
[hidden email]
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel

_______________________________________________
gstreamer-devel mailing list
[hidden email]
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
Alfredo Salvarani
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: v4l2src gstv4l2bufferpool error

Alfredo Salvarani
HI, Thanks for you replay.
I'm afraid that the adv7482 driver might not be the only one doing someting
wrog.
This is the DMESG log when the error happen:

[ 5574.501147] tegra-vi4 15700000.vi: Status:  7 channel:00 frame:0001
[ 5574.507639] tegra-vi4 15700000.vi:      timestamp sof 5585857446016 eof
5585862300544 data 0x00000001
[ 5574.522908] tegra-vi4 15700000.vi:      capture_id 257 stream  4 vchan  0
[ 5574.719479] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 5574.726039] tegra-vi4 15700000.vi: tegra_channel_error_recovery:
attempting to reset the capture channel
[ 5574.736208] nvcsi 150c0000.nvcsi: csi4_stream_check_status (4)
INTR_STATUS 0x00000008
[ 5574.744130] nvcsi 150c0000.nvcsi: csi4_stream_check_status (4)
ERR_INTR_STATUS 0x00000008
[ 5574.756003] nvcsi 150c0000.nvcsi: csi4_stream_check_status (4)
ERROR_STATUS2VI_VC0 = 0x00000004

And this is the dmesg msg when the drivers load:

[    2.528174] adv7180 6-0021: chip found @ 0x21 (31c0000.i2c)
[    2.528953] adv7180 6-0021: tegracam sensor driver:adv7280-m_v2.0.6
[    2.550377] adv7180 6-0021: adv7180_set_field_mode: disable I2P
[    2.635126] adv748x 1-0071: Endpoint ffffffc1f70854b8 on port 8
[    2.635209] adv748x 1-0071: Endpoint ffffffc1f7085af0 on port 10
[    2.642363] adv748x 1-0071: chip found @ 0xe2 revision 2143
[    2.767506] adv748x 1-0070: Endpoint ffffffc1f7086888 on port 8
[    2.767555] adv748x 1-0070: Endpoint ffffffc1f7086ec0 on port 10
[    2.768652] adv748x 1-0070: chip found @ 0xe0 revision 2143
[    3.419536] tegra-vi4 15700000.vi: subdev adv7180 6-0021 bound
[    3.420198] tegra-vi4 15700000.vi: subdev adv748x 1-0071 txa bound
[    3.420203] tegra-vi4 15700000.vi: Entity type for entity adv748x 1-0071
txa was not initialized!
[    3.421359] tegra-vi4 15700000.vi: subdev adv748x 1-0070 txa bound
[    3.421361] tegra-vi4 15700000.vi: Entity type for entity adv748x 1-0070
txa was not initialized!

(if you need more linux debug msg, please give-me some directions on how to
get it)

And it's the gstreamer GST_DEBUG="v4l*:7,2" log:

Setting pipeline to PAUSED ...
Pipeline is live and does not need PREROLL ...
Setting pipeline to PLAYING ...
New clock: GstSystemClock
/GstPipeline:pipeline0/GstV4l2Src:v4l2src0.GstPad:src: caps = video/x-raw,
width=(int)1024, height=(int)768, framerate=(fraction)120/1,
format=(string)BGRA, colorimetry=(string)sRGB,
interlace-mode=(string)progressive
/GstPipeline:pipeline0/GstVideoConvert:videoconvert0.GstPad:src: caps =
video/x-raw, width=(int)1024, height=(int)768, framerate=(fraction)120/1,
interlace-mode=(string)progressive, format=(string)YUY2
/GstPipeline:pipeline0/GstXvImageSink:xvimagesink0.GstPad:sink: caps =
video/x-raw, width=(int)1024, height=(int)768, framerate=(fraction)120/1,
interlace-mode=(string)progressive, format=(string)YUY2
/GstPipeline:pipeline0/GstVideoConvert:videoconvert0.GstPad:sink: caps =
video/x-raw, width=(int)1024, height=(int)768, framerate=(fraction)120/1,
format=(string)BGRA, colorimetry=(string)sRGB,
interlace-mode=(string)progressive
WARNING: from element /GstPipeline:pipeline0/GstXvImageSink:xvimagesink0: A
lot of buffers are being dropped.
Additional debug info:
gstbasesink.c(2902): gst_base_sink_is_too_late ():
/GstPipeline:pipeline0/GstXvImageSink:xvimagesink0:
There may be a timestamping problem, or this computer is too slow.
WARNING: from element /GstPipeline:pipeline0/GstXvImageSink:xvimagesink0: A
lot of buffers are being dropped.
Additional debug info:
gstbasesink.c(2902): gst_base_sink_is_too_late ():
/GstPipeline:pipeline0/GstXvImageSink:xvimagesink0:
There may be a timestamping problem, or this computer is too slow.
WARNING: from element /GstPipeline:pipeline0/GstXvImageSink:xvimagesink0: A
lot of buffers are being dropped.
Additional debug info:
gstbasesink.c(2902): gst_base_sink_is_too_late ():
/GstPipeline:pipeline0/GstXvImageSink:xvimagesink0:
There may be a timestamping problem, or this computer is too slow.
WARNING: from element /GstPipeline:pipeline0/GstXvImageSink:xvimagesink0: A
lot of buffers are being dropped.
Additional debug info:
gstbasesink.c(2902): gst_base_sink_is_too_late ():
/GstPipeline:pipeline0/GstXvImageSink:xvimagesink0:
There may be a timestamping problem, or this computer is too slow.
Execution ended after 0:00:05.263746872
Setting pipeline to PAUSED ...
Setting pipeline to READY ...
Setting pipeline to NULL ...
Freeing pipeline ...


0:00:04.813280882  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.813316145  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.813355887  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.235757000 now
0:04:58.286263807 delay 0:00:00.050506807
0:00:04.813400077  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.749999930 out
ts 0:00:04.681026340
0:00:04.813479657  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e8f0
0:00:04.813513928  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 2
0:00:04.813553990  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 2 (flags 0x2003)
0:00:04.813586853  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:04.813609444  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:04.829697555  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:04.829759440  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 0 (flags 0x2001)
0:00:04.829820877  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:04.829884427  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e6d0 seq:210 (ix=0), mem 0x5588553de0 used 3145728,
plane=0, flags 00002001, ts 0:04:58.252422000, pool-queued=2,
buffer=0x558855e6d0
0:00:04.829930665  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.829970823  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.830047460  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.252422000 now
0:04:58.302947956 delay 0:00:00.050525956
0:00:04.830097089  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.758333263 out
ts 0:00:04.697690924
0:00:04.830319448  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e6d0
0:00:04.830370166  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 0
0:00:04.830433971  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 0 (flags 0x2003)
0:00:04.830469457  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:04.830504144  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:04.846319146  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:04.846441477  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 1 (flags 0x2001)
0:00:04.846542369  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:04.846612382  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e7e0 seq:211 (ix=1), mem 0x7fa002a060 used 3145728,
plane=0, flags 00002001, ts 0:04:58.285753000, pool-queued=2,
buffer=0x558855e7e0
0:00:04.846670076  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.846709914  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.846785879  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.285753000 now
0:04:58.319681959 delay 0:00:00.033928959
0:00:04.846852756  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.766666596 out
ts 0:00:04.731021380
0:00:04.867632650  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e7e0
0:00:04.867668616  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 1
0:00:04.867700519  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 1 (flags 0x2003)
0:00:04.867793955  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:04.867814146  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:04.879553396  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:04.879598674  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 2 (flags 0x2001)
0:00:04.879630001  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:04.879671855  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e8f0 seq:212 (ix=2), mem 0x7fa002a210 used 3145728,
plane=0, flags 00002001, ts 0:04:58.302418000, pool-queued=2,
buffer=0x558855e8f0
0:00:04.879702990  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.879728844  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.879767787  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.302418000 now
0:04:58.352675163 delay 0:00:00.050257163
0:00:04.879801449  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.774999929 out
ts 0:00:04.747687276
0:00:04.879867399  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e8f0
0:00:04.879898437  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 2
0:00:04.879938884  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 2 (flags 0x2003)
0:00:04.879971106  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:04.879994401  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:04.896228502  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:04.896281075  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 0 (flags 0x2001)
0:00:04.896319826  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:04.896374799  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e6d0 seq:213 (ix=0), mem 0x5588553de0 used 3145728,
plane=0, flags 00002001, ts 0:04:58.319084000, pool-queued=2,
buffer=0x558855e6d0
0:00:04.896411086  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.896444333  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.896493994  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.319084000 now
0:04:58.369395994 delay 0:00:00.050311994
0:00:04.896537481  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.783333262 out
ts 0:00:04.764352989
0:00:04.896624645  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e6d0
0:00:04.896663459  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 0
0:00:04.896711809  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 0 (flags 0x2003)
0:00:04.896752736  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:04.896782878  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:04.912934390  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:04.913076240  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 1 (flags 0x2001)
0:00:04.913172684  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:04.913293927  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e7e0 seq:214 (ix=1), mem 0x7fa002a060 used 3145728,
plane=0, flags 00002001, ts 0:04:58.352414000, pool-queued=2,
buffer=0x558855e7e0
0:00:04.913366564  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.913430977  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.913528733  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.352414000 now
0:04:58.386406958 delay 0:00:00.033992958
0:00:04.913611418  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.791666595 out
ts 0:00:04.797682060
0:00:04.935432931  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e7e0
0:00:04.935468673  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 1
0:00:04.935500192  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 1 (flags 0x2003)
0:00:04.935593180  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:04.935612027  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:04.946277914  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:04.946414964  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 2 (flags 0x2001)
0:00:04.946514448  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:04.946555406  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e8f0 seq:215 (ix=2), mem 0x7fa002a210 used 3145728,
plane=0, flags 00002001, ts 0:04:58.369080000, pool-queued=2,
buffer=0x558855e8f0
0:00:04.946591885  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.946611532  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.946649642  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.369080000 now
0:04:58.419558426 delay 0:00:00.050478426
0:00:04.946679401  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.799999928 out
ts 0:00:04.814348988
0:00:04.946744070  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e8f0
0:00:04.946770085  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 2
0:00:04.946810787  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 2 (flags 0x2003)
0:00:04.946838466  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:04.946860929  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:04.962931549  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:04.963058583  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 0 (flags 0x2001)
0:00:04.963238448  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:04.963344203  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e6d0 seq:216 (ix=0), mem 0x5588553de0 used 3145728,
plane=0, flags 00002001, ts 0:04:58.385745000, pool-queued=2,
buffer=0x558855e6d0
0:00:04.963415816  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.963479558  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.963575841  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.385745000 now
0:04:58.436454770 delay 0:00:00.050709770
0:00:04.963663902  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.808333261 out
ts 0:00:04.831012869
0:00:04.963816759  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e6d0
0:00:04.963890932  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 0
0:00:04.963983472  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 0 (flags 0x2003)
0:00:04.964091596  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:04.964156809  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:04.979594911  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:04.979735065  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 1 (flags 0x2001)
0:00:04.979831413  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:04.979940560  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e7e0 seq:217 (ix=1), mem 0x7fa002a060 used 3145728,
plane=0, flags 00002001, ts 0:04:58.419076000, pool-queued=2,
buffer=0x558855e7e0
0:00:04.979997038  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:04.980038156  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:04.980099114  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.419076000 now
0:04:58.452995738 delay 0:00:00.033919738
0:00:04.980151527  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.816666594 out
ts 0:00:04.864344796
0:00:05.001674237  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e7e0
0:00:05.001757306  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 1
0:00:05.001792024  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 1 (flags 0x2003)
0:00:05.001890996  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.001911539  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.012884453  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.013012416  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 2 (flags 0x2001)
0:00:05.013117819  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.013241238  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e8f0 seq:218 (ix=2), mem 0x7fa002a210 used 3145728,
plane=0, flags 00002001, ts 0:04:58.435741000, pool-queued=2,
buffer=0x558855e8f0
0:00:05.013328882  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.013395311  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.013492779  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.435741000 now
0:04:58.486372188 delay 0:00:00.050631188
0:00:05.013577320  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.824999927 out
ts 0:00:04.881008612
0:00:05.013744321  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e8f0
0:00:05.013775903  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 2
0:00:05.013816478  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 2 (flags 0x2003)
0:00:05.013844349  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.013864348  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.029566534  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.029729728  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 0 (flags 0x2001)
0:00:05.029851802  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.029963606  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e6d0 seq:219 (ix=0), mem 0x5588553de0 used 3145728,
plane=0, flags 00002001, ts 0:04:58.452406000, pool-queued=2,
buffer=0x558855e6d0
0:00:05.030013716  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.030050290  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.030102000  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.452406000 now
0:04:58.502994656 delay 0:00:00.050588656
0:00:05.030137678  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.833333260 out
ts 0:00:04.897675180
0:00:05.030230954  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e6d0
0:00:05.030284328  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 0
0:00:05.030327814  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 0 (flags 0x2003)
0:00:05.030362757  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.030389572  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.046246696  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.046368355  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 1 (flags 0x2001)
0:00:05.046464607  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.046591161  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e7e0 seq:220 (ix=1), mem 0x7fa002a060 used 3145728,
plane=0, flags 00002001, ts 0:04:58.485737000, pool-queued=2,
buffer=0x558855e7e0
0:00:05.046679062  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.046745779  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.046844783  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.485737000 now
0:04:58.519722784 delay 0:00:00.033985784
0:00:05.046939787  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.841666593 out
ts 0:00:04.931005028
0:00:05.067456523  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e7e0
0:00:05.067489193  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 1
0:00:05.067521960  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 1 (flags 0x2003)
0:00:05.067618756  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.067640483  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.079551213  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.079611755  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 2 (flags 0x2001)
0:00:05.079641226  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.079680872  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e8f0 seq:221 (ix=2), mem 0x7fa002a210 used 3145728,
plane=0, flags 00002001, ts 0:04:58.502402000, pool-queued=2,
buffer=0x558855e8f0
0:00:05.079709031  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.079733702  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.079771972  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.502402000 now
0:04:58.552680244 delay 0:00:00.050278244
0:00:05.079803203  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.849999926 out
ts 0:00:04.947670700
0:00:05.079881184  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e8f0
0:00:05.079911422  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 2
0:00:05.079947357  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 2 (flags 0x2003)
0:00:05.079978779  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.080000283  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.096257198  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.096364457  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 0 (flags 0x2001)
0:00:05.096475205  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.096568673  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e6d0 seq:222 (ix=0), mem 0x5588553de0 used 3145728,
plane=0, flags 00002001, ts 0:04:58.519068000, pool-queued=2,
buffer=0x558855e6d0
0:00:05.096619807  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.096655229  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.096718171  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.519068000 now
0:04:58.569607883 delay 0:00:00.050539883
0:00:05.096763033  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.858333259 out
ts 0:00:04.964336604
0:00:05.096865780  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e6d0
0:00:05.096921330  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 0
0:00:05.096971856  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 0 (flags 0x2003)
0:00:05.097027725  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.097072588  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.112904049  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.113047755  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 1 (flags 0x2001)
0:00:05.113144231  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.113267586  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e7e0 seq:223 (ix=1), mem 0x7fa002a060 used 3145728,
plane=0, flags 00002001, ts 0:04:58.552398000, pool-queued=2,
buffer=0x558855e7e0
0:00:05.113312864  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.113367325  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.113441178  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.552398000 now
0:04:58.586324043 delay 0:00:00.033926043
0:00:05.113508215  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.866666592 out
ts 0:00:04.997666764
0:00:05.135278211  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e7e0
0:00:05.135316545  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 1
0:00:05.135354239  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 1 (flags 0x2003)
0:00:05.135460507  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.135479898  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.146206294  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.146343505  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 2 (flags 0x2001)
0:00:05.146433229  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.146552936  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e8f0 seq:224 (ix=2), mem 0x7fa002a210 used 3145728,
plane=0, flags 00002001, ts 0:04:58.569064000, pool-queued=2,
buffer=0x558855e8f0
0:00:05.146607046  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.146630437  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.146672259  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.569064000 now
0:04:58.619580883 delay 0:00:00.050516883
0:00:05.146701538  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.874999925 out
ts 0:00:05.014333052
0:00:05.146765791  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e8f0
0:00:05.146792638  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 2
0:00:05.146829020  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 2 (flags 0x2003)
0:00:05.146857179  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.146878810  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.162929494  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.163024946  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 0 (flags 0x2001)
0:00:05.163060017  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.163179852  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e6d0 seq:225 (ix=0), mem 0x5588553de0 used 3145728,
plane=0, flags 00002001, ts 0:04:58.585729000, pool-queued=2,
buffer=0x558855e6d0
0:00:05.163209706  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.163239401  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.163308902  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.585729000 now
0:04:58.636203927 delay 0:00:00.050474927
0:00:05.163343269  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.883333258 out
ts 0:00:05.030997956
0:00:05.163427169  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e6d0
0:00:05.163456096  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 0
0:00:05.163499902  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 0 (flags 0x2003)
0:00:05.163535133  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.163571963  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.179568537  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.179710995  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 1 (flags 0x2001)
0:00:05.179804784  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.179917515  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e7e0 seq:226 (ix=1), mem 0x7fa002a060 used 3145728,
plane=0, flags 00002001, ts 0:04:58.619060000, pool-queued=2,
buffer=0x558855e7e0
0:00:05.179963529  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.180017895  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.180091971  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.619060000 now
0:04:58.652974644 delay 0:00:00.033914644
0:00:05.180159041  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.891666591 out
ts 0:00:05.064328828
0:00:05.200213172  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e7e0
0:00:05.200249395  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 1
0:00:05.200282929  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 1 (flags 0x2003)
0:00:05.200380685  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.200400876  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.212864927  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.213011609  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 2 (flags 0x2001)
0:00:05.213105813  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.213204881  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e8f0 seq:227 (ix=2), mem 0x7fa002a210 used 3145728,
plane=0, flags 00002001, ts 0:04:58.635725000, pool-queued=2,
buffer=0x558855e8f0
0:00:05.213232880  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.213263215  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.213318732  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.635725000 now
0:04:58.686228060 delay 0:00:00.050503060
0:00:05.213353963  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.899999924 out
ts 0:00:05.080994052
0:00:05.213429032  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e8f0
0:00:05.213456614  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 2
0:00:05.213491269  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 2 (flags 0x2003)
0:00:05.213517860  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.213538371  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.229539489  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.229659612  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 0 (flags 0x2001)
0:00:05.229821045  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.229968207  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e6d0 seq:228 (ix=0), mem 0x5588553de0 used 3145728,
plane=0, flags 00002001, ts 0:04:58.652390000, pool-queued=2,
buffer=0x558855e6d0
0:00:05.230034668  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.230064971  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.230136968  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.652390000 now
0:04:58.703031000 delay 0:00:00.050641000
0:00:05.230193222  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.908333257 out
ts 0:00:05.097658956
0:00:05.230291201  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e6d0
0:00:05.230337247  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 0
0:00:05.230397405  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 0 (flags 0x2003)
0:00:05.230445819  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.230473242  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.246217443  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.246336190  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 1 (flags 0x2001)
0:00:05.246534197  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.246613202  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e7e0 seq:229 (ix=1), mem 0x7fa002a060 used 3145728,
plane=0, flags 00002001, ts 0:04:58.685721000, pool-queued=2,
buffer=0x558855e7e0
0:00:05.246683375  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.246736429  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.246811210  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.685721000 now
0:04:58.719695099 delay 0:00:00.033974099
0:00:05.246865895  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.916666590 out
ts 0:00:05.130989540
0:00:05.267165393  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e7e0
0:00:05.267199631  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 1
0:00:05.267235022  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 1 (flags 0x2003)
0:00:05.267339081  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.267355305  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.279531112  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.279677538  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 2 (flags 0x2001)
0:00:05.279773758  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.279881817  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e8f0 seq:230 (ix=2), mem 0x7fa002a210 used 3145728,
plane=0, flags 00002001, ts 0:04:58.702386000, pool-queued=2,
buffer=0x558855e8f0
0:00:05.279908632  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.279932023  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.279977717  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.702386000 now
0:04:58.752886405 delay 0:00:00.050500405
0:00:05.280007572  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.924999923 out
ts 0:00:05.147655276
0:00:05.280084529  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e8f0
0:00:05.280121487  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 2
0:00:05.280159821  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 2 (flags 0x2003)
0:00:05.280187980  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.280208299  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.296192330  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.296324293  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 0 (flags 0x2001)
0:00:05.296424512  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.296528540  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e6d0 seq:231 (ix=0), mem 0x5588553de0 used 3145728,
plane=0, flags 00002001, ts 0:04:58.719052000, pool-queued=2,
buffer=0x558855e6d0
0:00:05.296562203  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.296603705  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.296648183  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.719052000 now
0:04:58.769553191 delay 0:00:00.050501191
0:00:05.296696149  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.933333256 out
ts 0:00:05.164321116
0:00:05.296779601  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e6d0
0:00:05.296813648  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 0
0:00:05.296858702  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 0 (flags 0x2003)
0:00:05.296895597  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.296921579  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.312880811  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1137:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeueing a buffer
0:00:05.312997958  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1310:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
dequeued buffer 1 (flags 0x2001)
0:00:05.313074435  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1339:gst_v4l2_allocator_dqbuf:<v4l2src0:pool:src:allocator>
Dequeued capture buffer, length: 3145728 bytesused: 3145728 data_offset: 0
0:00:05.313176863  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1170:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src>
dequeued buffer 0x558855e7e0 seq:232 (ix=1), mem 0x7fa002a060 used 3145728,
plane=0, flags 00002001, ts 0:04:58.752382000, pool-queued=2,
buffer=0x558855e7e0
0:00:05.313248668  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1699:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
process buffer 0x7fad7af758
0:00:05.313312281  8367   0x55885584a0 TRACE         v4l2bufferpool
gstv4l2bufferpool.c:1733:gst_v4l2_buffer_pool_process:<v4l2src0:pool:src>
Only 2 buffer left in the capture queue.
0:00:05.313427604  8367   0x55885584a0 DEBUG                v4l2src
gstv4l2src.c:921:gst_v4l2src_create:<v4l2src0> ts: 0:04:58.752382000 now
0:04:58.786304709 delay 0:00:00.033922709
0:00:05.313514129  8367   0x55885584a0 INFO                 v4l2src
gstv4l2src.c:957:gst_v4l2src_create:<v4l2src0> sync to 0:00:01.941666589 out
ts 0:00:05.197649965
0:00:05.334013393  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1374:gst_v4l2_buffer_pool_release_buffer:<v4l2src0:pool:src>
release buffer 0x558855e7e0
0:00:05.334054096  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1063:gst_v4l2_buffer_pool_qbuf:<v4l2src0:pool:src>
queuing buffer 1
0:00:05.334108589  8367   0x55885584a0 LOG            v4l2allocator
gstv4l2allocator.c:1263:gst_v4l2_allocator_qbuf:<v4l2src0:pool:src:allocator>
queued buffer 1 (flags 0x2003)
0:00:05.334211017  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1296:gst_v4l2_buffer_pool_acquire_buffer:<v4l2src0:pool:src>
acquire
0:00:05.334233224  8367   0x55885584a0 LOG           v4l2bufferpool
gstv4l2bufferpool.c:1003:gst_v4l2_buffer_pool_poll:<v4l2src0:pool:src>
polling device
0:00:05.345345460  8367   0x55885584a0 WARN          v4l2bufferpool
gstv4l2bufferpool.c:1040:gst_v4l2_buffer_pool_poll:<v4l2src0> error: poll
error 1: Resource temporarily unavailable (11)
0:00:05.345517901  8367   0x55885584a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:1268:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src> poll
error error
0:00:05.345562155  8367   0x55885584a0 WARN                 v4l2src
gstv4l2src.c:998:gst_v4l2src_create:<v4l2src0> error: Failed to allocate a
buffer
ERROR: from element /GstPipeline:pipeline0/GstV4l2Src:v4l2src0: Could not
read from resource.
0:00:05.345679686  8367   0x55885584a0 WARN                 basesrc
gstbasesrc.c:3055:gst_base_src_loop:<v4l2src0> error: Internal data stream
error.
Additional debug info:
gstv4l2bufferpool.c(1040): gst_v4l2_buffer_pool_poll ():
/GstPipeline:pipeline0/GstV4l2Src:v4l2src0:
poll error 1: Resource temporarily unavailable (11)
0:00:05.345730692  8367   0x55885584a0 WARN                 basesrc
gstbasesrc.c:3055:gst_base_src_loop:<v4l2src0> error: streaming stopped,
reason error (-5)
0:00:05.346382633  8367   0x55885316a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:960:gst_v4l2_buffer_pool_flush_start:<v4l2src0:pool:src>
start flushing
0:00:05.346439142  8367   0x55885316a0 LOG                     v4l2
gstv4l2object.c:4117:gst_v4l2_object_unlock:<v4l2src0:src> start flushing
0:00:05.346582048  8367   0x55885316a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:978:gst_v4l2_buffer_pool_flush_stop:<v4l2src0:pool:src>
stop flushing
0:00:05.346634078  8367   0x55885316a0 LOG                     v4l2
gstv4l2object.c:4130:gst_v4l2_object_unlock_stop:<v4l2src0:src> stop
flushing
0:00:05.346653213  8367   0x55885316a0 DEBUG                   v4l2
gstv4l2object.c:4141:gst_v4l2_object_stop:<v4l2src0:src> stopping
0:00:05.346668508  8367   0x55885316a0 DEBUG                   v4l2
gstv4l2object.c:4149:gst_v4l2_object_stop:<v4l2src0:src> deactivating pool
0:00:05.346684284  8367   0x55885316a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:960:gst_v4l2_buffer_pool_flush_start:<v4l2src0:pool:src>
start flushing
0:00:05.346702203  8367   0x55885316a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:923:gst_v4l2_buffer_pool_stop:<v4l2src0:pool:src>
stopping pool
0:00:05.577156562  8367   0x55885316a0 DEBUG         v4l2bufferpool
gstv4l2bufferpool.c:705:gst_v4l2_buffer_pool_streamoff:<v4l2src0:pool:src>
Stopped streaming
0:00:05.577477093  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:356:gst_v4l2_allocator_release:<v4l2src0:pool:src:allocator>
plane 0 of buffer 0 released
0:00:05.577604831  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:372:gst_v4l2_allocator_release:<v4l2src0:pool:src:allocator>
buffer 0 released
0:00:05.577790423  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:356:gst_v4l2_allocator_release:<v4l2src0:pool:src:allocator>
plane 0 of buffer 1 released
0:00:05.577904115  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:372:gst_v4l2_allocator_release:<v4l2src0:pool:src:allocator>
buffer 1 released
0:00:05.578062572  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:356:gst_v4l2_allocator_release:<v4l2src0:pool:src:allocator>
plane 0 of buffer 2 released
0:00:05.578171527  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:372:gst_v4l2_allocator_release:<v4l2src0:pool:src:allocator>
buffer 2 released
0:00:05.578300258  8367   0x55885316a0 DEBUG          v4l2allocator
gstv4l2allocator.c:744:gst_v4l2_allocator_stop:<v4l2src0:pool:src:allocator>
stop allocator
0:00:05.578424445  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:392:gst_v4l2_allocator_free:<v4l2src0:pool:src:allocator>
freeing plane 0 of buffer 0
0:00:05.578632628  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:392:gst_v4l2_allocator_free:<v4l2src0:pool:src:allocator>
freeing plane 0 of buffer 1
0:00:05.578791341  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:392:gst_v4l2_allocator_free:<v4l2src0:pool:src:allocator>
freeing plane 0 of buffer 2
0:00:05.582650667  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:413:gst_v4l2_allocator_dispose:<v4l2src0:pool:src:allocator>
called
0:00:05.584361891  8367   0x55885316a0 LOG            v4l2allocator
gstv4l2allocator.c:430:gst_v4l2_allocator_finalize:<v4l2src0:pool:src:allocator>
called
0:00:05.645611439  8367   0x55885316a0 DEBUG                   v4l2
v4l2_calls.c:715:gst_v4l2_close:<v4l2src0:src> Trying to close /dev/video2
0:00:05.646552264  8367   0x55885316a0 DEBUG                   v4l2
v4l2_calls.c:464:gst_v4l2_empty_lists:<v4l2src0:src> deleting enumerations







--
Sent from: http://gstreamer-devel.966125.n4.nabble.com/
_______________________________________________
gstreamer-devel mailing list
[hidden email]
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
Nicolas Dufresne-5
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: v4l2src gstv4l2bufferpool error

Nicolas Dufresne-5
Le lundi 16 novembre 2020 à 14:50 -0600, Alfredo Salvarani a écrit :

> HI, Thanks for you replay.
> I'm afraid that the adv7482 driver might not be the only one doing someting
> wrog.
> This is the DMESG log when the error happen:
>
> [ 5574.501147] tegra-vi4 15700000.vi: Status:  7 channel:00 frame:0001
> [ 5574.507639] tegra-vi4 15700000.vi:      timestamp sof 5585857446016 eof
> 5585862300544 data 0x00000001
> [ 5574.522908] tegra-vi4 15700000.vi:      capture_id 257 stream  4 vchan  0
> [ 5574.719479] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
> [ 5574.726039] tegra-vi4 15700000.vi: tegra_channel_error_recovery:
> attempting to reset the capture channel
> [ 5574.736208] nvcsi 150c0000.nvcsi: csi4_stream_check_status (4)
> INTR_STATUS 0x00000008
> [ 5574.744130] nvcsi 150c0000.nvcsi: csi4_stream_check_status (4)
> ERR_INTR_STATUS 0x00000008
> [ 5574.756003] nvcsi 150c0000.nvcsi: csi4_stream_check_status (4)
> ERROR_STATUS2VI_VC0 = 0x00000004

So this is NVidia driver, not a mainline one. If I'm reading this
correctly, there is an internal error and it resets the driver behind
userspace back. Please raise this driver issue to them, GStreamer won't
be able to cope with such a driver failure.

> 0:00:05.345345460  8367   0x55885584a0 WARN          v4l2bufferpool
> gstv4l2bufferpool.c:1040:gst_v4l2_buffer_pool_poll:<v4l2src0> error: poll
> error 1: Resource temporarily unavailable (11)
> 0:00:05.345517901  8367   0x55885584a0 DEBUG         v4l2bufferpool
> gstv4l2bufferpool.c:1268:gst_v4l2_buffer_pool_dqbuf:<v4l2src0:pool:src> poll
> error error
> 0:00:05.345562155  8367   0x55885584a0 WARN                 v4l2src
> gstv4l2src.c:998:gst_v4l2src_create:<v4l2src0> error: Failed to allocate a
> buffer
> ERROR: from element /GstPipeline:pipeline0/GstV4l2Src:v4l2src0: Could not
> read from resource.
> 0:00:05.345679686  8367   0x55885584a0 WARN                 basesrc
> gstbasesrc.c:3055:gst_base_src_loop:<v4l2src0> error: Internal data stream
> error.
> Additional debug info:
> gstv4l2bufferpool.c(1040): gst_v4l2_buffer_pool_poll ():
> /GstPipeline:pipeline0/GstV4l2Src:v4l2src0:
> poll error 1: Resource temporarily unavailable (11)
> 0:00:05.345730692  8367   0x55885584a0 WARN                 basesrc
> gstbasesrc.c:3055:gst_base_src_loop:<v4l2src0> error: streaming stopped,
> reason error (-5)

As you can read here, all operation on the driver FD returns EBUSY. To
make things worst, the error get caught and mangled by a libv4l2 plugin
(notice the lack of context prefix near the poll error.

If you fix the problem with the driver / libv4l2 plugin, GStreamer
should work as expected.

Nicolas

_______________________________________________
gstreamer-devel mailing list
[hidden email]
https://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
Free forum by Nabble Edit this page