[uvch264src in 1.1.1.1] "Got data flow before segment event" warnings until crash
Tim-Philipp Müller
t.i.m at zen.co.uk
Thu Jul 11 09:08:33 PDT 2013
On Thu, 2013-07-11 at 11:55 -0400, Robert Krakora wrote:
> I filed a bug report for this a couple of months ago. Myself and
> another engineer struggled with this error for a few weeks. This
> plugin works fine on GStreamer 0.10. I think that it has something to
> do with v4l2src on GStreamer 1.x. I know I saw that there was an
> open issue that Wim and others have been struggling to solve. They
> may be related...
It's just a warning. It doesn't indicate a problem in your application,
but indicates something-that's-not-quite-right in some element. We are
trying to get the order right here for some advanced use cases.
You can ignore those warnings. They shouldn't have any side effects
generally other than being annoying.
It's not a 0.10 vs. 1.x thing, more of a 'unstable 1.1.x series vs.
stable release series' thing. The warnings will go away in 1.2.0, even
if we don't manage to fix them all (which is usually not too hard, just
tedious).
Cheers
-Tim
> On Thu, Jul 11, 2013 at 11:51 AM, Peter Rennert
> <p.rennert at cs.ucl.ac.uk> wrote:
> I just want to add that I get the same problem even with a
> very reduced pipeline of:
>
> gst-launch-1.0 uvch264src device=/dev/video1 name=src
> auto-start=true src.vfsrc ! queue ! fakesink src.vidsrc !
> queue ! video/x-h264 ! fakesink
>
>
> ........
>
> (gst-launch-1.0:5463): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<fakesink1:sink>
> Got data flow before segment event
>
> (gst-launch-1.0:5463): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<capsfilter0:src> Got data
> flow before segment event
>
> (gst-launch-1.0:5463): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<fakesink1:sink>
> Got data flow before segment event
> ERROR: from
> element /GstPipeline:pipeline0/GstUvcH264Src:src/GstUvcH264MjpgDemux:uvch264mjpgdemux0: Not enough data to read marker content
> Additional debug info:
> gstuvch264_mjpgdemux.c(507): gst_uvc_h264_mjpg_demux_chain
> (): /GstPipeline:pipeline0/GstUvcH264Src:src/GstUvcH264MjpgDemux:uvch264mjpgdemux0
> Execution ended after 0:00:34.640728375
> Setting pipeline to PAUSED ...
> Setting pipeline to READY ...
> Setting pipeline to NULL ...
> Freeing pipeline .
>
> On 07/11/2013 04:23 PM, Peter Rennert wrote:
> Hello,
>
> I am trying to get the uvch264src to work in gstreamer
> 1.1.1.1 (git head compiled yesterday) with the
> following pipeline:
>
> gst-launch-1.0 uvch264src device=/dev/video1 name=src
> auto-start=true src.vfsrc ! queue !
> video/x-raw,width=320,height=240,format="YUY2",framerate=15/1 ! xvimagesink src.vidsrc ! queue ! video/x-h264,width=1920,height=1080,framerate=30/1,profile=constrained-baseline ! h264parse ! avdec_h264 ! xvimagesink
>
> It runs as expected (showing both the 1920x1080 and
> 320x240 windows). But it constantly prints warnings
> (see below). Then stops it . You can find the
> GST_DEBUG output below the warnings below.
>
> It works stable with gstreamer 0.10 (an older
> compilation) and
>
> gst-launch uvch264_src device=/dev/video1 name=src
> auto-start=true src.vfsrc ! queue !
> video/x-raw-yuv,width=320,height=240,framerate=15/1 !
> xvimagesink src.vidsrc ! queue !
> video/x-h264,width=1920,height=1080,framerate=30/1,profile=constrained-baseline ! h264parse ! ffdec_h264 ! xvimagesink
>
> I am using a C920.
>
> I found a (probably) related problem here:
> http://comments.gmane.org/gmane.comp.video.gstreamer.bugs/107399
>
>
> Do I do something wrong in gstreamer 1 or is this a
> bug?
>
> -----------------
>
> Warnings (i get loads of them, this is just an
> excerpt):
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<capsfilter1:src> Got
> data flow before segment event
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<h264parse0:sink> Got data flow before segment event
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<h264parse0:src> Got
> data flow before segment event
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<avdec_h264-0:sink> Got data flow before segment event
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<capsfilter0:src> Got
> data flow before segment event
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<xvimagesink0:sink> Got data flow before segment event
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<capsfilter1:src> Got
> data flow before segment event
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<h264parse0:sink> Got data flow before segment event
>
> (gst-launch-1.0:2962): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<h264parse0:src> Got
> data flow before segment event
>
> ==================================================================
>
> GST_DEBUG=5 :
>
> (only the interesting bit, shortly before the error
> gets reported until the moment the error gets
> reported)
>
> 0:00:35.394998922 3694 0x1e171e0 DEBUG GST_CLOCK
> gstclock.c:922:gst_clock_get_internal_time:<GstSystemClock> internal time 26:55:06.762023021
> 0:00:35.395022245 3694 0x1e171e0 DEBUG GST_CLOCK
> gstclock.c:967:gst_clock_get_time:<GstSystemClock>
> adjusted time 26:55:06.762023021
> 0:00:35.395034653 3694 0x1e171e0 DEBUG v4l2src
> gstv4l2src.c:823:gst_v4l2src_fill:<v4l2src0> ts:
> 26:55:06.771552000 now 26:55:06.762058860 delay
> 0:00:00.000000000
> 0:00:35.395050068 3694 0x1e171e0 INFO v4l2src
> gstv4l2src.c:861:gst_v4l2src_fill:<v4l2src0> sync to
> 0:00:34.433332989 out ts 0:00:34.768634907
> 0:00:35.395062230 3694 0x1e171e0 DEBUG basesrc
> gstbasesrc.c:2216:gst_base_src_do_sync:<v4l2src0> no
> sync needed
> 0:00:35.395069279 3694 0x1e171e0 DEBUG basesrc
> gstbasesrc.c:2414:gst_base_src_get_range:<v4l2src0>
> buffer ok
> 0:00:35.395080250 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:1958:gst_base_transform_handle_buffer:<capsfilter5> handling buffer 0x1de7720 of size 166785 and offset 1032
> 0:00:35.395090539 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2042:gst_base_transform_handle_buffer:<capsfilter5> calling prepare buffer
> 0:00:35.395099383 3694 0x1e171e0 DEBUG GST_CAPS
> gstpad.c:2408:gst_pad_has_current_caps:<capsfilter5:sink> check current pad caps image/jpeg, width=(int)320, height=(int)240, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1
> 0:00:35.395123772 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2049:gst_base_transform_handle_buffer:<capsfilter5> using allocated buffer in 0x1de7720, out 0x1de7720
> 0:00:35.395131630 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2066:gst_base_transform_handle_buffer:<capsfilter5> doing inplace transform
> 0:00:35.395150881 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:513:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Found APP4 marker (12215). JPG: 0-8 - APP4: 8 - 12225
> 0:00:35.395163919 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f77291e63d0, maxsize:1440014 offset:0 size:8
> 0:00:35.395174000 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f7728009720 -> 0x7f77291e63d0
> 0:00:35.395566373 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:550:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> New auxiliary stream : v1 - 22 bytes - H264 1920x1080 -- 333333 *100ns -- 27 ms -- 56960032
> 0:00:35.395589065 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:552:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Auxiliary stream size : 12187 bytes
> 0:00:35.395598711 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f7728587db0, maxsize:1440014 offset:38 size:12187
> 0:00:35.395609310 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f7728009720 -> 0x7f7728587db0
> 0:00:35.395854659 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:659:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Pushing H264 auxiliary buffer video/x-h264, width=(int)1920, height=(int)1080, framerate=(fraction)30/1
> 0:00:35.395885190 3694 0x1e171e0 DEBUG GST_PADS
> gstpad.c:3224:do_probe_callbacks:<src:vidsrc> data is
> passed
> 0:00:35.395901512 3694 0x1e171e0 DEBUG GST_PADS
> gstpad.c:3088:probe_hook_marshal:<src:vidsrc> probe
> returned OK
> 0:00:35.395918777 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:513:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Found APP4 marker (65533). JPG: 12225-12225 - APP4: 12225 - 77760
> 0:00:35.395929092 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:550:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> New auxiliary stream : v1 - 22 bytes - YUY2 320x240 -- 333333 *100ns -- 27 ms -- 56960032
> 0:00:35.395940742 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:552:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Auxiliary stream size : 153600 bytes
> 0:00:35.396005741 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f7728c67d10, maxsize:1440014 offset:12255
> size:65505
> 0:00:35.395934221 3694 0x1e2b0a0 DEBUG
> queue_dataflow gstqueue.c:1240:gst_queue_loop:<queue1>
> queue is not empty
> 0:00:35.396055314 3694 0x1e2b0a0 DEBUG
> basetransform
> gstbasetransform.c:1962:gst_base_transform_handle_buffer:<capsfilter1> handling buffer 0x7f77380050b0 of size 12187 and offset NONE
> 0:00:35.396076643 3694 0x1e2b0a0 DEBUG
> basetransform
> gstbasetransform.c:2042:gst_base_transform_handle_buffer:<capsfilter1> calling prepare buffer
> 0:00:35.396089336 3694 0x1e2b0a0 DEBUG GST_CAPS
> gstpad.c:2408:gst_pad_has_current_caps:<capsfilter1:sink> check current pad caps video/x-h264, width=(int)1920, height=(int)1080, framerate=(fraction)30/1
> 0:00:35.396116373 3694 0x1e2b0a0 DEBUG
> basetransform
> gstbasetransform.c:2049:gst_base_transform_handle_buffer:<capsfilter1> using allocated buffer in 0x7f77380050b0, out 0x7f77380050b0
> 0:00:35.396128931 3694 0x1e2b0a0 DEBUG
> basetransform
> gstbasetransform.c:2066:gst_base_transform_handle_buffer:<capsfilter1> doing inplace transform
>
> (gst-launch-1.0:3694): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<capsfilter1:src> Got
> data flow before segment event
>
> (gst-launch-1.0:3694): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<h264parse0:sink> Got data flow before segment event
> 0:00:35.396169900 3694 0x1e2b0a0 DEBUG GST_CAPS
> gstpad.c:2408:gst_pad_has_current_caps:<h264parse0:src> check current pad caps video/x-h264, width=(int)1920, height=(int)1080, framerate=(fraction)30/1, profile=(string)constrained-baseline, parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au
> 0:00:35.396202328 3694 0x1e2b0a0 DEBUG h264parse
> gsth264parse.c:165:gst_h264_parse_reset_frame:<h264parse0> reset frame
>
> (gst-launch-1.0:3694): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<h264parse0:src> Got
> data flow before segment event
>
> (gst-launch-1.0:3694): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<avdec_h264-0:sink> Got data flow before segment event
> 0:00:35.396241398 3694 0x1e2b0a0 DEBUG default
> gstsegment.c:481:gst_segment_to_running_time: invalid
> position (-1)
> 0:00:35.396281397 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:935:gst_ffmpegviddec_do_qos:<avdec_h264-0> decoding time 2562047:47:16.854775807
> 0:00:35.396314138 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:1100:gst_ffmpegviddec_video_frame:<avdec_h264-0> stored opaque values idx 1032
> 0:00:35.396349848 3694 0x1e2b0a0 DEBUG
> libav :0:: Frame num gap 60 58
> 0:00:35.396378922 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:704:gst_ffmpegviddec_release_buffer:<avdec_h264-0> release frame 1030
> 0:00:35.396414061 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:538:gst_ffmpegviddec_get_buffer:<avdec_h264-0> getting buffer picture 0x7f773c726040
> 0:00:35.396437146 3694 0x1e2b0a0 DEBUG
> videodecoder
> gstvideodecoder.c:2883:gst_video_decoder_get_frame:<avdec_h264-0> frame_number : 1032
> 0:00:35.396466024 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:557:gst_ffmpegviddec_get_buffer:<avdec_h264-0> storing opaque 0x7f77300c4130
> 0:00:35.397593960 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:704:gst_ffmpegviddec_release_buffer:<avdec_h264-0> release frame 1031
> 0:00:35.397631322 3694 0x1e2b0a0 DEBUG default
> gstvideoutils.c:40:_gst_video_codec_frame_free: free
> frame 0x7f7728848080
> 0:00:35.397643694 3694 0x1e2b0a0 DEBUG
> GST_MEMORY gstmemory.c:88:_gst_memory_free: free
> memory 0x7f7728849000
> 0:00:35.397666794 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:538:gst_ffmpegviddec_get_buffer:<avdec_h264-0> getting buffer picture 0x7f773c7264c0
> 0:00:35.398284117 3694 0x1e2b0a0 DEBUG
> videodecoder
> gstvideodecoder.c:2883:gst_video_decoder_get_frame:<avdec_h264-0> frame_number : 1032
> 0:00:35.398304138 3694 0x1e2b0a0 WARN libav
> gstavviddec.c:632:gst_ffmpegviddec_get_buffer:<avdec_h264-0> already alloc'ed output buffer for frame
> 0:00:35.398316802 3694 0x1e2b0a0 ERROR
> libav :0:: get_buffer() failed (-1 2 (nil))
> 0:00:35.398331690 3694 0x1e2b0a0 ERROR
> libav :0:: decode_slice_header error
> 0:00:35.398348752 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:538:gst_ffmpegviddec_get_buffer:<avdec_h264-0> getting buffer picture 0x7f773c7264c0
> 0:00:35.398356353 3694 0x1e2b0a0 DEBUG
> videodecoder
> gstvideodecoder.c:2883:gst_video_decoder_get_frame:<avdec_h264-0> frame_number : 1032
> 0:00:35.398363340 3694 0x1e2b0a0 WARN libav
> gstavviddec.c:632:gst_ffmpegviddec_get_buffer:<avdec_h264-0> already alloc'ed output buffer for frame
> 0:00:35.398370450 3694 0x1e2b0a0 ERROR
> libav :0:: get_buffer() failed (-1 2 (nil))
> 0:00:35.398377837 3694 0x1e2b0a0 ERROR
> libav :0:: decode_slice_header error
> 0:00:35.398386031 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:538:gst_ffmpegviddec_get_buffer:<avdec_h264-0> getting buffer picture 0x7f773c7264c0
> 0:00:35.398392697 3694 0x1e2b0a0 DEBUG
> videodecoder
> gstvideodecoder.c:2883:gst_video_decoder_get_frame:<avdec_h264-0> frame_number : 1032
> 0:00:35.398399053 3694 0x1e2b0a0 WARN libav
> gstavviddec.c:632:gst_ffmpegviddec_get_buffer:<avdec_h264-0> already alloc'ed output buffer for frame
> 0:00:35.398404717 3694 0x1e2b0a0 ERROR
> libav :0:: get_buffer() failed (-1 2 (nil))
> 0:00:35.398411627 3694 0x1e2b0a0 ERROR
> libav :0:: decode_slice_header error
> 0:00:35.398418838 3694 0x1e2b0a0 DEBUG libav
> gstavviddec.c:538:gst_ffmpegviddec_get_buffer:<avdec_h264-0> getting buffer picture 0x7f773c7264c0
> 0:00:35.398425798 3694 0x1e2b0a0 DEBUG
> videodecoder
> gstvideodecoder.c:2883:gst_video_decoder_get_frame:<avdec_h264-0> frame_number : 1032
> 0:00:35.398432465 3694 0x1e2b0a0 WARN libav
> gstavviddec.c:632:gst_ffmpegviddec_get_buffer:<avdec_h264-0> already alloc'ed output buffer for frame
> 0:00:35.398438066 3694 0x1e2b0a0 ERROR
> libav :0:: get_buffer() failed (-1 2 (nil))
> 0:00:35.398445219 3694 0x1e2b0a0 ERROR
> libav :0:: decode_slice_header error
> 0:00:35.398451851 3694 0x1e2b0a0 ERROR
> libav :0:: mmco: unref short failure
> 0:00:35.398627695 3694 0x1e2b0a0 INFO libav :0::
> concealing 8160 DC, 8160 AC, 8160 MV errors
> 0:00:35.398828963 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f7728009720 -> 0x7f7728c67d10
> 0:00:35.399319058 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:513:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Found APP4 marker (65533). JPG: 77760-77760 - APP4: 77760 - 143295
> 0:00:35.399353615 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f7728849000, maxsize:1440014 offset:77764
> size:65531
> 0:00:35.399368422 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f7728009720 -> 0x7f7728849000
> 0:00:35.399658333 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:513:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Found APP4 marker (22566). JPG: 143295-143295 - APP4: 143295 - 165863
> 0:00:35.399677140 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f7728dc76c0, maxsize:1440014 offset:143299
> size:22564
> 0:00:35.399690113 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f7728009720 -> 0x7f7728dc76c0
> 0:00:35.399982333 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:659:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Pushing YUY2 auxiliary buffer video/x-raw, format=(string)YUY2, width=(int)320, height=(int)240, framerate=(fraction)15/1
> 0:00:35.400035247 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:1962:gst_base_transform_handle_buffer:<videoconvert4> handling buffer 0x7f7730007550 of size 153600 and offset NONE
> 0:00:35.400047757 3694 0x1e171e0 DEBUG default
> gstsegment.c:481:gst_segment_to_running_time: invalid
> position (-1)
> 0:00:35.400057009 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2042:gst_base_transform_handle_buffer:<videoconvert4> calling prepare buffer
> 0:00:35.400066028 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:1537:default_prepare_output_buffer:<videoconvert4> passthrough: reusing input buffer
> 0:00:35.400074785 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2049:gst_base_transform_handle_buffer:<videoconvert4> using allocated buffer in 0x7f7730007550, out 0x7f7730007550
> 0:00:35.400085953 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2060:gst_base_transform_handle_buffer:<videoconvert4> element is in passthrough
> 0:00:35.400100607 3694 0x1e171e0 DEBUG GST_PADS
> gstpad.c:3224:do_probe_callbacks:<src:vfsrc> data is
> passed
> 0:00:35.400125786 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:694:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> SOS marker wasn't found. MJPG is container only
> 0:00:35.400136546 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:88:_gst_memory_free: free
> memory 0x7f77291e63d0
> 0:00:35.400153414 3694 0x1e171e0 DEBUG v4l2
> gstv4l2bufferpool.c:933:gst_v4l2_buffer_pool_release_buffer:<v4l2bufferpool0> release buffer 0x1de7720
> 0:00:35.400178526 3694 0x1e171e0 DEBUG basesrc
> gstbasesrc.c:2351:gst_base_src_get_range:<v4l2src0>
> calling create offset 18446744073709551615 length
> 4096, time 0
> 0:00:35.400194362 3694 0x1e171e0 DEBUG v4l2
> gstv4l2bufferpool.c:832:gst_v4l2_buffer_pool_acquire_buffer:<v4l2bufferpool0> acquire
> 0:00:35.400205021 3694 0x1e171e0 DEBUG GST_POLL
> gstpoll.c:1200:gst_poll_wait:
> timeout :99:99:99.999999999
> 0:00:35.400235182 3694 0x1e2b1e0 DEBUG
> queue_dataflow gstqueue.c:1240:gst_queue_loop:<queue0>
> queue is not empty
> 0:00:35.402142734 3694 0x1e2b1e0 DEBUG
> basetransform
> gstbasetransform.c:1962:gst_base_transform_handle_buffer:<capsfilter0> handling buffer 0x7f7730007550 of size 153600 and offset NONE
> 0:00:35.402168640 3694 0x1e2b1e0 DEBUG
> basetransform
> gstbasetransform.c:2042:gst_base_transform_handle_buffer:<capsfilter0> calling prepare buffer
> 0:00:35.402178987 3694 0x1e2b1e0 DEBUG GST_CAPS
> gstpad.c:2408:gst_pad_has_current_caps:<capsfilter0:sink> check current pad caps video/x-raw, format=(string)YUY2, width=(int)320, height=(int)240, framerate=(fraction)15/1
> 0:00:35.402210753 3694 0x1e2b1e0 DEBUG
> basetransform
> gstbasetransform.c:2049:gst_base_transform_handle_buffer:<capsfilter0> using allocated buffer in 0x7f7730007550, out 0x7f7730007550
> 0:00:35.402218850 3694 0x1e2b1e0 DEBUG
> basetransform
> gstbasetransform.c:2066:gst_base_transform_handle_buffer:<capsfilter0> doing inplace transform
>
> (gst-launch-1.0:3694): GStreamer-WARNING **:
> gstpad.c:3923:gst_pad_push_data:<capsfilter0:src> Got
> data flow before segment event
>
> (gst-launch-1.0:3694): GStreamer-WARNING **:
> gstpad.c:3692:gst_pad_chain_data_unchecked:<xvimagesink0:sink> Got data flow before segment event
> 0:00:35.402247697 3694 0x1e2b1e0 DEBUG basesink
> gstbasesink.c:3283:gst_base_sink_chain_unlocked:<xvimagesink0> got times start: 99:99:99.999999999, end: 99:99:99.999999999
> 0:00:35.402263093 3694 0x1e2b1e0 DEBUG basesink
> gstbasesink.c:1899:gst_base_sink_get_sync_times:<xvimagesink0> got times start: 99:99:99.999999999, stop: 99:99:99.999999999, do_sync 0
> 0:00:35.402275683 3694 0x1e2b1e0 DEBUG default
> gstsegment.c:481:gst_segment_to_running_time: invalid
> position (-1)
> 0:00:35.402281951 3694 0x1e2b1e0 DEBUG default
> gstsegment.c:481:gst_segment_to_running_time: invalid
> position (-1)
> 0:00:35.402289418 3694 0x1e2b1e0 DEBUG basesink
> gstbasesink.c:3354:gst_base_sink_chain_unlocked:<xvimagesink0> rendering object 0x7f7730007550
> 0:00:35.402297939 3694 0x1e2b1e0 DEBUG basesink
> gstbasesink.c:938:gst_base_sink_set_last_buffer_unlocked:<xvimagesink0> setting last buffer to 0x7f7730007550
> 0:00:35.402309005 3694 0x1e2b1e0 DEBUG
> GST_MEMORY gstmemory.c:88:_gst_memory_free: free
> memory 0x7f7728f27070
> 0:00:35.402322365 3694 0x1e2b1e0 DEBUG
> GST_MEMORY gstmemory.c:88:_gst_memory_free: free
> memory 0x7f7729086a20
> 0:00:35.402329259 3694 0x1e2b1e0 DEBUG
> GST_MEMORY gstmemory.c:88:_gst_memory_free: free
> memory 0x7f7729345d80
> 0:00:35.402748981 3694 0x1e2b1e0 DEBUG
> bufferpool
> gstbufferpool.c:479:gst_buffer_pool_set_active:<xvimagebufferpool1> pool was in the right state
> 0:00:35.402773116 3694 0x1e2b1e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f7738006800, maxsize:153607 offset:0 size:153600
> 0:00:35.402785810 3694 0x1e2b1e0 DEBUG
> GST_PERFORMANCE gstbuffer.c:244:_get_merged_memory:
> memcpy 65505 bytes for merge 0x7f7738006800 from
> memory 0x7f7728c67d10
> 0:00:35.402805953 3694 0x1e2b1e0 DEBUG
> GST_PERFORMANCE gstbuffer.c:244:_get_merged_memory:
> memcpy 65531 bytes for merge 0x7f7738006800 from
> memory 0x7f7728849000
> 0:00:35.402825501 3694 0x1e2b1e0 DEBUG
> GST_PERFORMANCE gstbuffer.c:244:_get_merged_memory:
> memcpy 22564 bytes for merge 0x7f7738006800 from
> memory 0x7f7728dc76c0
> 0:00:35.402843541 3694 0x1e2b1e0 DEBUG
> GST_PERFORMANCE gstbuffer.c:1509:gst_buffer_map_range:
> temporary mapping for memory 0x7f7738006800 in buffer
> 0x7f7730007550
> 0:00:35.402930602 3694 0x1e2b1e0 DEBUG
> GST_PERFORMANCE
> video-frame.c:245:gst_video_frame_copy_plane: copy
> plane 0, w:640 h:240
> 0:00:35.402963361 3694 0x1e2b1e0 DEBUG
> GST_MEMORY gstmemory.c:88:_gst_memory_free: free
> memory 0x7f7738006800
> 0:00:35.402993223 3694 0x1e2b1e0 DEBUG videosink
> gstvideosink.c:117:gst_video_sink_center_rect: source
> is 320x240 dest is 320x240, result is 320x240 with x,y
> 0x0
> 0:00:35.403172767 3694 0x1e2b1e0 DEBUG GST_QOS
> gstbasesink.c:2855:gst_base_sink_do_render_stats:<xvimagesink0> avg_render: 0:00:00.000771911
> 0:00:35.403185342 3694 0x1e2b1e0 DEBUG basesink
> gstbasesink.c:3393:gst_base_sink_chain_unlocked:<xvimagesink0> object unref after render 0x7f7730007550
> 0:00:35.403196545 3694 0x1e2b1e0 DEBUG
> queue_dataflow gstqueue.c:1228:gst_queue_loop:<queue0>
> queue is empty
> 0:00:35.426997236 3694 0x1e171e0 DEBUG v4l2
> gstv4l2bufferpool.c:1167:gst_v4l2_buffer_pool_process:<v4l2bufferpool0> process buffer 0x1de7830
> 0:00:35.427041303 3694 0x1e171e0 DEBUG GST_CLOCK
> gstclock.c:922:gst_clock_get_internal_time:<GstSystemClock> internal time 26:55:06.794065247
> 0:00:35.427056137 3694 0x1e171e0 DEBUG GST_CLOCK
> gstclock.c:967:gst_clock_get_time:<GstSystemClock>
> adjusted time 26:55:06.794065247
> 0:00:35.427067629 3694 0x1e171e0 DEBUG v4l2src
> gstv4l2src.c:823:gst_v4l2src_fill:<v4l2src0> ts:
> 26:55:06.800990000 now 26:55:06.794092266 delay
> 0:00:00.000000000
> 0:00:35.427080729 3694 0x1e171e0 INFO v4l2src
> gstv4l2src.c:861:gst_v4l2src_fill:<v4l2src0> sync to
> 0:00:34.466666322 out ts 0:00:34.800677133
> 0:00:35.427092788 3694 0x1e171e0 DEBUG basesrc
> gstbasesrc.c:2216:gst_base_src_do_sync:<v4l2src0> no
> sync needed
> 0:00:35.427100101 3694 0x1e171e0 DEBUG basesrc
> gstbasesrc.c:2414:gst_base_src_get_range:<v4l2src0>
> buffer ok
> 0:00:35.427112183 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:1958:gst_base_transform_handle_buffer:<capsfilter5> handling buffer 0x1de7830 of size 165490 and offset 1033
> 0:00:35.427122893 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2042:gst_base_transform_handle_buffer:<capsfilter5> calling prepare buffer
> 0:00:35.427131748 3694 0x1e171e0 DEBUG GST_CAPS
> gstpad.c:2408:gst_pad_has_current_caps:<capsfilter5:sink> check current pad caps image/jpeg, width=(int)320, height=(int)240, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1
> 0:00:35.427157133 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2049:gst_base_transform_handle_buffer:<capsfilter5> using allocated buffer in 0x1de7830, out 0x1de7830
> 0:00:35.427164745 3694 0x1e171e0 DEBUG
> basetransform
> gstbasetransform.c:2066:gst_base_transform_handle_buffer:<capsfilter5> doing inplace transform
> 0:00:35.427176735 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:513:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Found APP4 marker (12182). JPG: 0-8 - APP4: 8 - 12192
> 0:00:35.427197573 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f7728f27070, maxsize:1440014 offset:0 size:8
> 0:00:35.427207769 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f77281690c0 -> 0x7f7728f27070
> 0:00:35.427908444 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:550:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> New auxiliary stream : v1 - 22 bytes - H264 1920x1080 -- 333333 *100ns -- 27 ms -- 67059032
> 0:00:35.427927470 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:552:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Auxiliary stream size : 12154 bytes
> 0:00:35.427939077 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f7729086a20, maxsize:1440014 offset:38 size:12154
> 0:00:35.427948111 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f77281690c0 -> 0x7f7729086a20
> 0:00:35.428626317 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:659:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Pushing H264 auxiliary buffer video/x-h264, width=(int)1920, height=(int)1080, framerate=(fraction)30/1
> 0:00:35.428658303 3694 0x1e171e0 DEBUG GST_PADS
> gstpad.c:3224:do_probe_callbacks:<src:vidsrc> data is
> passed
> 0:00:35.428666953 3694 0x1e171e0 DEBUG GST_PADS
> gstpad.c:3088:probe_hook_marshal:<src:vidsrc> probe
> returned OK
> 0:00:35.428679400 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:513:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Found APP4 marker (65533). JPG: 12192-12192 - APP4: 12192 - 77727
> 0:00:35.428688152 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:550:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> New auxiliary stream : v1 - 22 bytes - YUY2 320x240 -- 333333 *100ns -- 27 ms -- 67059032
> 0:00:35.428697950 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:552:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Auxiliary stream size : 153600 bytes
> 0:00:35.428708335 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f77291e63d0, maxsize:1440014 offset:12222
> size:65505
> 0:00:35.428720262 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f77281690c0 -> 0x7f77291e63d0
> 0:00:35.429419395 3694 0x1e171e0 DEBUG
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:513:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> Found APP4 marker (65533). JPG: 77727-77727 - APP4: 77727 - 143262
> 0:00:35.429437926 3694 0x1e171e0 DEBUG
> GST_MEMORY gstmemory.c:138:gst_memory_init: new memory
> 0x7f7729345d80, maxsize:1440014 offset:77731
> size:65531
> 0:00:35.429446663 3694 0x1e171e0 DEBUG
> GST_PERFORMANCE gstallocator.c:466:_sysmem_copy:
> memcpy 1440007 memory 0x7f77281690c0 -> 0x7f7729345d80
> 0:00:35.430148554 3694 0x1e171e0 WARN
> uvch264mjpgdemux
> gstuvch264_mjpgdemux.c:507:gst_uvc_h264_mjpg_demux_chain:<uvch264mjpgdemux0> error: Not enough data to read marker content
> 0:00:35.430172000 3694 0x1e171e0 DEBUG
> GST_MESSAGE
> gstelement.c:1807:gst_element_message_full:<uvch264mjpgdemux0> start
> 0:00:35.430202374 3694 0x1e171e0 INFO
> GST_ERROR_SYSTEM
> gstelement.c:1838:gst_element_message_full:<uvch264mjpgdemux0> posting message: Not enough data to read marker content
> 0:00:35.430223343 3694 0x1e171e0 DEBUG GST_BUS
> gstbus.c:312:gst_bus_post:<bus0> [msg 0x1e3c5a0]
> posting on bus error message: 0x1e3c5a0, time
> 99:99:99.999999999, seq-num 378, element
> 'uvch264mjpgdemux0', GstMessageError,
> gerror=(GError)NULL,
> debug=(string)"gstuvch264_mjpgdemux.c\(507\):\
> gst_uvc_h264_mjpg_demux_chain\ \(\):
> \ /GstPipeline:pipeline0/GstUvcH264Src:src/GstUvcH264MjpgDemux:uvch264mjpgdemux0";
>
> ... now it continues with with and EOS that stops the
> pipeline
>
> _______________________________________________
> gstreamer-devel mailing list
> gstreamer-devel at lists.freedesktop.org
> http://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
>
>
>
> --
> Rob Krakora
> MessageNet Systems
> 101 East Carmel Dr. Suite 105
> Carmel, IN 46032
> (317)566-1677 Ext 212
> (317)663-0808 Fax
> _______________________________________________
> gstreamer-devel mailing list
> gstreamer-devel at lists.freedesktop.org
> http://lists.freedesktop.org/mailman/listinfo/gstreamer-devel
More information about the gstreamer-devel
mailing list