[uvch264src in 1.1.1.1] "Got data flow before segment event" warnings until crash
Robert Krakora
rob.krakora at messagenetsystems.com
Thu Jul 11 10:23:14 PDT 2013
Hi Peter and Tim,
I tried several experiments and came to the conclusion that the problem
lies in v4l2src as the port of uvch264_src from 0.10 to uvch264src for 1.x
looked really straight forward IMHO when I did a file comparison and went
over the porting guide. Whoever did it did a great job. v4l2src 0.10 to
1.x changed significantly...I really did not have a chance to go over the
differences with the porting guide in hand for v4l2src (0.10 uvch264_src
works for me and I had to move on to some other issues in my application).
However, I did notice on a blog a conversation between Wim and other
developers on a v4l2src architectural issue that seemed like it might
pertain to this issue.
Best Regards,
Rob
On Thu, Jul 11, 2013 at 1:11 PM, Peter Rennert <p.rennert at cs.ucl.ac.uk>wrote:
> Thanks Tim,
>
> I was a bit suspicious about the warnings as the cause for the crash, but
> since they relate to data flow, I thought there might a buffer filling up
> behind the scenes or something.
>
> If I can ignore the warnings, the error interest is probably:
>
>
> 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
>
>
>
>
> On 07/11/2013 05:08 PM, Tim-Philipp Müller wrote:
>
>> 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<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<gstreamer-devel at lists.freedesktop.org>
>>> http://lists.freedesktop.org/**mailman/listinfo/gstreamer-**
>>> devel <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<gstreamer-devel at lists.freedesktop.org>
>>> http://lists.freedesktop.org/**mailman/listinfo/gstreamer-**devel<http://lists.freedesktop.org/mailman/listinfo/gstreamer-devel>
>>>
>>
>> ______________________________**_________________
>> gstreamer-devel mailing list
>> gstreamer-devel at lists.**freedesktop.org<gstreamer-devel at lists.freedesktop.org>
>> http://lists.freedesktop.org/**mailman/listinfo/gstreamer-**devel<http://lists.freedesktop.org/mailman/listinfo/gstreamer-devel>
>>
>
> ______________________________**_________________
> gstreamer-devel mailing list
> gstreamer-devel at lists.**freedesktop.org<gstreamer-devel at lists.freedesktop.org>
> http://lists.freedesktop.org/**mailman/listinfo/gstreamer-**devel<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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freedesktop.org/archives/gstreamer-devel/attachments/20130711/3131c766/attachment-0001.html>
More information about the gstreamer-devel
mailing list